[10:11:55.883] <TB3> INFO: *** Welcome to pxar ***
[10:11:55.883] <TB3> INFO: *** Today: 2016/08/29
[10:11:55.972] <TB3> INFO: *** Version: fe94
[10:11:55.972] <TB3> INFO: readRocDacs: /usr/local/coldboxDATA/M2302_FullQualification_2016-08-29_11h59m_1472464790//000_FulltestPxar_m20//dacParameters_C0.dat .. /usr/local/coldboxDATA/M2302_FullQualification_2016-08-29_11h59m_1472464790//000_FulltestPxar_m20//dacParameters_C15.dat
[10:11:55.972] <TB3> INFO: readTbmDacs: /usr/local/coldboxDATA/M2302_FullQualification_2016-08-29_11h59m_1472464790//000_FulltestPxar_m20//tbmParameters_C0a.dat .. /usr/local/coldboxDATA/M2302_FullQualification_2016-08-29_11h59m_1472464790//000_FulltestPxar_m20//tbmParameters_C0b.dat
[10:11:55.973] <TB3> INFO: readMaskFile: /usr/local/coldboxDATA/M2302_FullQualification_2016-08-29_11h59m_1472464790//000_FulltestPxar_m20//defaultMaskFile.dat
[10:11:55.973] <TB3> INFO: readTrimFile: /usr/local/coldboxDATA/M2302_FullQualification_2016-08-29_11h59m_1472464790//000_FulltestPxar_m20//trimParameters_C0.dat .. /usr/local/coldboxDATA/M2302_FullQualification_2016-08-29_11h59m_1472464790//000_FulltestPxar_m20//trimParameters_C15.dat
[10:11:56.042] <TB3> INFO: clk: 4
[10:11:56.042] <TB3> INFO: ctr: 4
[10:11:56.042] <TB3> INFO: sda: 19
[10:11:56.042] <TB3> INFO: tin: 9
[10:11:56.042] <TB3> INFO: level: 15
[10:11:56.042] <TB3> INFO: triggerdelay: 0
[10:11:56.043] <TB3> QUIET: Instanciating API for pxar v2.7.5+16~g4df2ad8
[10:11:56.043] <TB3> INFO: Log level: INFO
[10:11:56.050] <TB3> INFO: Found DTB DTB_WZ4I6J
[10:11:56.064] <TB3> QUIET: Connection to board DTB_WZ4I6J opened.
[10:11:56.066] <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:
------------------------------------------------------
[10:11:56.068] <TB3> INFO: RPC call hashes of host and DTB match: 484264910
[10:11:57.617] <TB3> INFO: DUT info:
[10:11:57.618] <TB3> INFO: The DUT currently contains the following objects:
[10:11:57.618] <TB3> INFO: 2 TBM Cores tbm09c (2 ON)
[10:11:57.618] <TB3> INFO: TBM Core alpha (0): 7 registers set
[10:11:57.618] <TB3> INFO: TBM Core beta (1): 7 registers set
[10:11:57.618] <TB3> INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[10:11:57.618] <TB3> INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[10:11:57.618] <TB3> INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[10:11:57.618] <TB3> INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[10:11:57.618] <TB3> INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[10:11:57.618] <TB3> INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[10:11:57.618] <TB3> INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[10:11:57.618] <TB3> INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[10:11:57.618] <TB3> INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[10:11:57.618] <TB3> INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[10:11:57.618] <TB3> INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[10:11:57.618] <TB3> INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[10:11:57.618] <TB3> INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[10:11:57.618] <TB3> INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[10:11:57.618] <TB3> INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[10:11:57.618] <TB3> INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[10:11:57.618] <TB3> INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[10:11:58.019] <TB3> INFO: enter 'restricted' command line mode
[10:11:58.019] <TB3> INFO: enter test to run
[10:11:58.019] <TB3> INFO: test: pretest no parameter change
[10:11:58.019] <TB3> INFO: running: pretest
[10:11:58.023] <TB3> INFO: ----------------------------------------------------------------------
[10:11:58.023] <TB3> INFO: PixTestPretest::programROC()
[10:11:58.024] <TB3> INFO: ----------------------------------------------------------------------
[10:12:16.037] <TB3> INFO: PixTestPretest::programROC() done: ROCs are all programmable
[10:12:16.037] <TB3> INFO: IA differences per ROC: 18.5 18.5 20.1 17.7 19.3 19.3 18.5 20.1 19.3 17.7 19.3 19.3 20.9 16.9 20.1 19.3
[10:12:16.152] <TB3> INFO: enter test to run
[10:12:16.152] <TB3> INFO: test: pretest no parameter change
[10:12:16.152] <TB3> INFO: running: pretest
[10:12:16.153] <TB3> INFO: ----------------------------------------------------------------------
[10:12:16.153] <TB3> INFO: PixTestPretest::setVana() target Ia = 24 mA/ROC
[10:12:16.153] <TB3> INFO: ----------------------------------------------------------------------
[10:12:21.626] <TB3> INFO: PixTestPretest::setVana() done, Module Ia 385.1 mA = 24.0688 mA/ROC
[10:12:21.626] <TB3> INFO: i(loss) [mA/ROC]: 19.3 18.5 19.3 19.3 19.3 19.3 18.5 19.3 19.3 19.3 19.3 18.5 19.3 19.3 18.5 18.5
[10:12:21.651] <TB3> INFO: enter test to run
[10:12:21.651] <TB3> INFO: test: pretest no parameter change
[10:12:21.651] <TB3> INFO: running: pretest
[10:12:21.652] <TB3> INFO: ----------------------------------------------------------------------
[10:12:21.652] <TB3> INFO: PixTestPretest::findTiming()
[10:12:21.652] <TB3> INFO: ----------------------------------------------------------------------
[10:12:21.652] <TB3> INFO: PixTestCmd::init()
[10:12:22.611] <TB3> WARNING: Not unmasking DUT, not setting Calibrate bits!
[10:13:50.639] <TB3> INFO: TBM phases: 160MHz: 7, 400MHz: 2, TBM delays: ROC(0/1):4, header/trailer: 1, token: 1
[10:13:50.639] <TB3> INFO: (success/tries = 100/100), width = 5
[10:13:50.640] <TB3> INFO: enter test to run
[10:13:50.640] <TB3> INFO: test: pretest no parameter change
[10:13:50.640] <TB3> INFO: running: pretest
[10:13:50.641] <TB3> INFO: ----------------------------------------------------------------------
[10:13:50.641] <TB3> INFO: PixTestPretest::findWorkingPixel()
[10:13:50.641] <TB3> INFO: ----------------------------------------------------------------------
[10:13:50.731] <TB3> INFO: Expecting 231680 events.
[10:13:56.508] <TB3> ERROR: <datapipe.cc/CheckEventValidity:L497> Channel 0 Number of ROCs (1) != Token Chain Length (4)
[10:13:56.590] <TB3> ERROR: <datapipe.cc/CheckEventID:L459> Channel 0 Event ID mismatch: local ID (21) != TBM ID (2)
[10:14:00.916] <TB3> INFO: 231680 events read in total (9629ms).
[10:14:00.920] <TB3> INFO: Test took 10277ms.
[10:14:01.146] <TB3> INFO: Found working pixel in all ROCs: col/row = 12/22
[10:14:01.206] <TB3> INFO: enter test to run
[10:14:01.206] <TB3> INFO: test: pretest no parameter change
[10:14:01.206] <TB3> INFO: running: pretest
[10:14:01.207] <TB3> INFO: ----------------------------------------------------------------------
[10:14:01.207] <TB3> INFO: PixTestPretest::setVthrCompCalDel()
[10:14:01.207] <TB3> INFO: ----------------------------------------------------------------------
[10:14:01.299] <TB3> INFO: Expecting 231680 events.
[10:14:11.469] <TB3> INFO: 231680 events read in total (9614ms).
[10:14:11.472] <TB3> INFO: Test took 10261ms.
[10:14:11.710] <TB3> INFO: PixTestPretest::setVthrCompCalDel() done
[10:14:11.710] <TB3> INFO: CalDel: 144 159 136 143 142 144 150 155 150 148 147 138 133 157 144 157
[10:14:11.710] <TB3> INFO: VthrComp: 51 51 51 51 51 51 51 51 51 51 51 51 51 51 51 51
[10:14:11.747] <TB3> INFO: enter test to run
[10:14:11.747] <TB3> INFO: test: pretest no parameter change
[10:14:11.747] <TB3> INFO: running: pretest
[10:14:11.748] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2302_FullQualification_2016-08-29_11h59m_1472464790//000_FulltestPxar_m20//dacParameters_C0.dat
[10:14:11.748] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2302_FullQualification_2016-08-29_11h59m_1472464790//000_FulltestPxar_m20//dacParameters_C1.dat
[10:14:11.748] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2302_FullQualification_2016-08-29_11h59m_1472464790//000_FulltestPxar_m20//dacParameters_C2.dat
[10:14:11.748] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2302_FullQualification_2016-08-29_11h59m_1472464790//000_FulltestPxar_m20//dacParameters_C3.dat
[10:14:11.748] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2302_FullQualification_2016-08-29_11h59m_1472464790//000_FulltestPxar_m20//dacParameters_C4.dat
[10:14:11.748] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2302_FullQualification_2016-08-29_11h59m_1472464790//000_FulltestPxar_m20//dacParameters_C5.dat
[10:14:11.748] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2302_FullQualification_2016-08-29_11h59m_1472464790//000_FulltestPxar_m20//dacParameters_C6.dat
[10:14:11.748] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2302_FullQualification_2016-08-29_11h59m_1472464790//000_FulltestPxar_m20//dacParameters_C7.dat
[10:14:11.748] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2302_FullQualification_2016-08-29_11h59m_1472464790//000_FulltestPxar_m20//dacParameters_C8.dat
[10:14:11.749] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2302_FullQualification_2016-08-29_11h59m_1472464790//000_FulltestPxar_m20//dacParameters_C9.dat
[10:14:11.749] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2302_FullQualification_2016-08-29_11h59m_1472464790//000_FulltestPxar_m20//dacParameters_C10.dat
[10:14:11.749] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2302_FullQualification_2016-08-29_11h59m_1472464790//000_FulltestPxar_m20//dacParameters_C11.dat
[10:14:11.749] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2302_FullQualification_2016-08-29_11h59m_1472464790//000_FulltestPxar_m20//dacParameters_C12.dat
[10:14:11.749] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2302_FullQualification_2016-08-29_11h59m_1472464790//000_FulltestPxar_m20//dacParameters_C13.dat
[10:14:11.749] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2302_FullQualification_2016-08-29_11h59m_1472464790//000_FulltestPxar_m20//dacParameters_C14.dat
[10:14:11.749] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2302_FullQualification_2016-08-29_11h59m_1472464790//000_FulltestPxar_m20//dacParameters_C15.dat
[10:14:11.749] <TB3> INFO: enter test to run
[10:14:11.749] <TB3> INFO: test: fulltest no parameter change
[10:14:11.749] <TB3> INFO: running: fulltest
[10:14:11.749] <TB3> INFO: ######################################################################
[10:14:11.749] <TB3> INFO: PixTestFullTest::doTest()
[10:14:11.749] <TB3> INFO: ######################################################################
[10:14:11.750] <TB3> INFO: ######################################################################
[10:14:11.751] <TB3> INFO: PixTestAlive::doTest()
[10:14:11.751] <TB3> INFO: ######################################################################
[10:14:11.752] <TB3> INFO: ----------------------------------------------------------------------
[10:14:11.752] <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)
[10:14:11.752] <TB3> INFO: ----------------------------------------------------------------------
[10:14:12.017] <TB3> INFO: Expecting 41600 events.
[10:14:15.792] <TB3> INFO: 41600 events read in total (3219ms).
[10:14:15.792] <TB3> INFO: Test took 4039ms.
[10:14:16.025] <TB3> INFO: PixTestAlive::aliveTest() done
[10:14:16.025] <TB3> INFO: number of dead pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[10:14:16.027] <TB3> INFO: ----------------------------------------------------------------------
[10:14:16.027] <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)
[10:14:16.027] <TB3> INFO: ----------------------------------------------------------------------
[10:14:16.317] <TB3> INFO: Expecting 41600 events.
[10:14:19.301] <TB3> INFO: 41600 events read in total (2427ms).
[10:14:19.301] <TB3> INFO: Test took 3273ms.
[10:14:19.302] <TB3> INFO: mask vs. old pixelAlive PixelAlive_C0_V0 .. PixelAlive_C15_V0
[10:14:19.552] <TB3> INFO: PixTestAlive::maskTest() done
[10:14:19.552] <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
[10:14:19.554] <TB3> INFO: ----------------------------------------------------------------------
[10:14:19.554] <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)
[10:14:19.554] <TB3> INFO: ----------------------------------------------------------------------
[10:14:19.825] <TB3> INFO: Expecting 41600 events.
[10:14:23.668] <TB3> INFO: 41600 events read in total (3287ms).
[10:14:23.668] <TB3> INFO: Test took 4112ms.
[10:14:23.897] <TB3> INFO: PixTestAlive::addressDecodingTest() done
[10:14:23.897] <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
[10:14:23.898] <TB3> INFO: PixTestAlive::doTest() done, duration: 12 seconds
[10:14:23.898] <TB3> INFO: Decoding statistics:
[10:14:23.898] <TB3> INFO: General information:
[10:14:23.898] <TB3> INFO: 16bit words read: 0
[10:14:23.898] <TB3> INFO: valid events total: 0
[10:14:23.898] <TB3> INFO: empty events: 0
[10:14:23.898] <TB3> INFO: valid events with pixels: 0
[10:14:23.898] <TB3> INFO: valid pixel hits: 0
[10:14:23.898] <TB3> INFO: Event errors: 0
[10:14:23.898] <TB3> INFO: start marker: 0
[10:14:23.898] <TB3> INFO: stop marker: 0
[10:14:23.898] <TB3> INFO: overflow: 0
[10:14:23.898] <TB3> INFO: invalid 5bit words: 0
[10:14:23.898] <TB3> INFO: invalid XOR eye diagram: 0
[10:14:23.898] <TB3> INFO: frame (failed synchr.): 0
[10:14:23.898] <TB3> INFO: idle data (no TBM trl): 0
[10:14:23.898] <TB3> INFO: no data (only TBM hdr): 0
[10:14:23.898] <TB3> INFO: TBM errors: 0
[10:14:23.898] <TB3> INFO: flawed TBM headers: 0
[10:14:23.898] <TB3> INFO: flawed TBM trailers: 0
[10:14:23.898] <TB3> INFO: event ID mismatches: 0
[10:14:23.898] <TB3> INFO: ROC errors: 0
[10:14:23.898] <TB3> INFO: missing ROC header(s): 0
[10:14:23.898] <TB3> INFO: misplaced readback start: 0
[10:14:23.898] <TB3> INFO: Pixel decoding errors: 0
[10:14:23.898] <TB3> INFO: pixel data incomplete: 0
[10:14:23.898] <TB3> INFO: pixel address: 0
[10:14:23.898] <TB3> INFO: pulse height fill bit: 0
[10:14:23.898] <TB3> INFO: buffer corruption: 0
[10:14:23.910] <TB3> INFO: ######################################################################
[10:14:23.910] <TB3> INFO: PixTestBBMap::doTest() Ntrig = 5, VcalS = 250 (high range)
[10:14:23.910] <TB3> INFO: ######################################################################
[10:14:23.912] <TB3> INFO: ---> dac: VthrComp name: calSMap ntrig: 5 dacrange: 0 .. 149 (-1/-1) hits flags = 514 (plus default)
[10:14:23.923] <TB3> INFO: dacScan split into 1 runs with ntrig = 5
[10:14:23.923] <TB3> INFO: run 1 of 1
[10:14:24.187] <TB3> INFO: Expecting 3120000 events.
[10:15:04.989] <TB3> INFO: 848300 events read in total (40245ms).
[10:15:45.276] <TB3> INFO: 1688140 events read in total (80532ms).
[10:16:25.166] <TB3> INFO: 2538240 events read in total (120422ms).
[10:16:53.005] <TB3> INFO: 3120000 events read in total (148261ms).
[10:16:53.050] <TB3> INFO: Test took 149127ms.
[10:17:16.918] <TB3> INFO: PixTestBBMap::doTest() done, duration: 173 seconds
[10:17:16.918] <TB3> INFO: number of dead bumps (per ROC): 2 0 0 3 0 1 1 2 0 0 1 1 10 0 2 2
[10:17:16.918] <TB3> INFO: separation cut (per ROC): 72 82 88 76 78 85 70 80 91 72 85 80 83 69 89 65
[10:17:16.918] <TB3> INFO: Decoding statistics:
[10:17:16.918] <TB3> INFO: General information:
[10:17:16.918] <TB3> INFO: 16bit words read: 0
[10:17:16.918] <TB3> INFO: valid events total: 0
[10:17:16.918] <TB3> INFO: empty events: 0
[10:17:16.918] <TB3> INFO: valid events with pixels: 0
[10:17:16.918] <TB3> INFO: valid pixel hits: 0
[10:17:16.918] <TB3> INFO: Event errors: 0
[10:17:16.918] <TB3> INFO: start marker: 0
[10:17:16.918] <TB3> INFO: stop marker: 0
[10:17:16.918] <TB3> INFO: overflow: 0
[10:17:16.918] <TB3> INFO: invalid 5bit words: 0
[10:17:16.918] <TB3> INFO: invalid XOR eye diagram: 0
[10:17:16.918] <TB3> INFO: frame (failed synchr.): 0
[10:17:16.918] <TB3> INFO: idle data (no TBM trl): 0
[10:17:16.918] <TB3> INFO: no data (only TBM hdr): 0
[10:17:16.918] <TB3> INFO: TBM errors: 0
[10:17:16.918] <TB3> INFO: flawed TBM headers: 0
[10:17:16.918] <TB3> INFO: flawed TBM trailers: 0
[10:17:16.918] <TB3> INFO: event ID mismatches: 0
[10:17:16.918] <TB3> INFO: ROC errors: 0
[10:17:16.918] <TB3> INFO: missing ROC header(s): 0
[10:17:16.918] <TB3> INFO: misplaced readback start: 0
[10:17:16.918] <TB3> INFO: Pixel decoding errors: 0
[10:17:16.918] <TB3> INFO: pixel data incomplete: 0
[10:17:16.918] <TB3> INFO: pixel address: 0
[10:17:16.918] <TB3> INFO: pulse height fill bit: 0
[10:17:16.918] <TB3> INFO: buffer corruption: 0
[10:17:16.991] <TB3> INFO: ######################################################################
[10:17:16.991] <TB3> INFO: PixTestScurves::fullTest() ntrig = 50, dacs/step = -1, ntrig/step = -1
[10:17:16.991] <TB3> INFO: ######################################################################
[10:17:16.991] <TB3> INFO: ----------------------------------------------------------------------
[10:17:16.991] <TB3> INFO: PixTestScurves::scurves(Vcal), ntrig = 50, dacs/step = -1, ntrig/step = -1
[10:17:16.991] <TB3> INFO: ----------------------------------------------------------------------
[10:17:16.991] <TB3> INFO: ---> dac: Vcal name: scurveVcal ntrig: 50 dacrange: 25 .. 150 (-1/-1) hits flags = 528 (plus default)
[10:17:16.999] <TB3> INFO: dacScan split into 1 runs with ntrig = 50
[10:17:16.999] <TB3> INFO: run 1 of 1
[10:17:17.259] <TB3> INFO: Expecting 26208000 events.
[10:17:51.048] <TB3> INFO: 873100 events read in total (33233ms).
[10:18:23.911] <TB3> INFO: 1732300 events read in total (66096ms).
[10:18:56.838] <TB3> INFO: 2593150 events read in total (99023ms).
[10:19:29.588] <TB3> INFO: 3451050 events read in total (131773ms).
[10:20:02.241] <TB3> INFO: 4307950 events read in total (164426ms).
[10:20:35.232] <TB3> INFO: 5163450 events read in total (197417ms).
[10:21:07.999] <TB3> INFO: 6019600 events read in total (230184ms).
[10:21:40.552] <TB3> INFO: 6874400 events read in total (262737ms).
[10:22:13.428] <TB3> INFO: 7729300 events read in total (295613ms).
[10:22:46.418] <TB3> INFO: 8584350 events read in total (328603ms).
[10:23:19.570] <TB3> INFO: 9438500 events read in total (361755ms).
[10:23:52.507] <TB3> INFO: 10291450 events read in total (394692ms).
[10:24:25.537] <TB3> INFO: 11144100 events read in total (427722ms).
[10:24:58.481] <TB3> INFO: 11995050 events read in total (460666ms).
[10:25:31.522] <TB3> INFO: 12846900 events read in total (493707ms).
[10:26:04.362] <TB3> INFO: 13693000 events read in total (526547ms).
[10:26:37.167] <TB3> INFO: 14536300 events read in total (559352ms).
[10:27:10.073] <TB3> INFO: 15379000 events read in total (592258ms).
[10:27:42.565] <TB3> INFO: 16220900 events read in total (624750ms).
[10:28:15.680] <TB3> INFO: 17062800 events read in total (657865ms).
[10:28:47.931] <TB3> INFO: 17903400 events read in total (690116ms).
[10:29:20.523] <TB3> INFO: 18745200 events read in total (722708ms).
[10:29:52.868] <TB3> INFO: 19583600 events read in total (755053ms).
[10:30:25.033] <TB3> INFO: 20423100 events read in total (787218ms).
[10:30:57.563] <TB3> INFO: 21264900 events read in total (819748ms).
[10:31:29.928] <TB3> INFO: 22103350 events read in total (852113ms).
[10:32:02.473] <TB3> INFO: 22943400 events read in total (884658ms).
[10:32:35.088] <TB3> INFO: 23785850 events read in total (917273ms).
[10:33:07.387] <TB3> INFO: 24625100 events read in total (949572ms).
[10:33:40.682] <TB3> INFO: 25467400 events read in total (982867ms).
[10:34:11.147] <TB3> INFO: 26208000 events read in total (1013332ms).
[10:34:11.183] <TB3> INFO: Test took 1014183ms.
[10:34:11.620] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[10:34:13.225] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[10:34:14.646] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[10:34:16.069] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[10:34:17.654] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[10:34:19.420] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[10:34:21.158] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[10:34:22.618] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[10:34:23.996] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[10:34:25.382] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[10:34:26.805] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[10:34:28.251] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[10:34:29.803] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[10:34:31.500] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[10:34:33.076] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[10:34:34.604] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[10:34:36.142] <TB3> INFO: PixTestScurves::scurves() done
[10:34:36.142] <TB3> INFO: Vcal mean: 78.70 84.40 80.92 79.83 79.58 82.02 74.85 85.87 89.08 75.17 76.68 80.89 84.65 81.57 96.11 75.55
[10:34:36.142] <TB3> INFO: Vcal RMS: 4.65 4.44 4.50 3.95 3.72 4.42 4.36 4.86 5.65 4.18 3.90 4.05 4.52 4.93 5.33 4.09
[10:34:36.142] <TB3> INFO: PixTestScurves::fullTest() done, duration: 1039 seconds
[10:34:36.142] <TB3> INFO: Decoding statistics:
[10:34:36.142] <TB3> INFO: General information:
[10:34:36.142] <TB3> INFO: 16bit words read: 0
[10:34:36.142] <TB3> INFO: valid events total: 0
[10:34:36.142] <TB3> INFO: empty events: 0
[10:34:36.142] <TB3> INFO: valid events with pixels: 0
[10:34:36.142] <TB3> INFO: valid pixel hits: 0
[10:34:36.142] <TB3> INFO: Event errors: 0
[10:34:36.142] <TB3> INFO: start marker: 0
[10:34:36.142] <TB3> INFO: stop marker: 0
[10:34:36.142] <TB3> INFO: overflow: 0
[10:34:36.142] <TB3> INFO: invalid 5bit words: 0
[10:34:36.142] <TB3> INFO: invalid XOR eye diagram: 0
[10:34:36.142] <TB3> INFO: frame (failed synchr.): 0
[10:34:36.142] <TB3> INFO: idle data (no TBM trl): 0
[10:34:36.142] <TB3> INFO: no data (only TBM hdr): 0
[10:34:36.142] <TB3> INFO: TBM errors: 0
[10:34:36.142] <TB3> INFO: flawed TBM headers: 0
[10:34:36.142] <TB3> INFO: flawed TBM trailers: 0
[10:34:36.142] <TB3> INFO: event ID mismatches: 0
[10:34:36.142] <TB3> INFO: ROC errors: 0
[10:34:36.142] <TB3> INFO: missing ROC header(s): 0
[10:34:36.142] <TB3> INFO: misplaced readback start: 0
[10:34:36.142] <TB3> INFO: Pixel decoding errors: 0
[10:34:36.142] <TB3> INFO: pixel data incomplete: 0
[10:34:36.142] <TB3> INFO: pixel address: 0
[10:34:36.142] <TB3> INFO: pulse height fill bit: 0
[10:34:36.142] <TB3> INFO: buffer corruption: 0
[10:34:36.215] <TB3> INFO: ######################################################################
[10:34:36.215] <TB3> INFO: PixTestTrim::doTest()
[10:34:36.215] <TB3> INFO: ######################################################################
[10:34:36.216] <TB3> INFO: ----------------------------------------------------------------------
[10:34:36.216] <TB3> INFO: PixTestTrim::trimTest() ntrig = 10, vcal = 35
[10:34:36.216] <TB3> INFO: ----------------------------------------------------------------------
[10:34:36.304] <TB3> INFO: ---> VthrComp thr map (minimal VthrComp)
[10:34:36.304] <TB3> INFO: ---> dac: vthrcomp name: TrimThr0 ntrig: 10 dacrange: 10 .. 160 (-1/-1) hits flags = 528 (plus default)
[10:34:36.313] <TB3> INFO: dacScan split into 1 runs with ntrig = 10
[10:34:36.313] <TB3> INFO: run 1 of 1
[10:34:36.585] <TB3> INFO: Expecting 6281600 events.
[10:35:16.174] <TB3> INFO: 1057360 events read in total (39033ms).
[10:35:55.225] <TB3> INFO: 2110960 events read in total (78084ms).
[10:36:33.976] <TB3> INFO: 3158300 events read in total (116835ms).
[10:37:13.236] <TB3> INFO: 4212290 events read in total (156095ms).
[10:37:51.532] <TB3> INFO: 5269380 events read in total (194391ms).
[10:38:29.249] <TB3> INFO: 6281600 events read in total (232108ms).
[10:38:29.286] <TB3> INFO: Test took 232974ms.
[10:38:48.832] <TB3> INFO: ROC 0 VthrComp = 85
[10:38:48.833] <TB3> INFO: ROC 1 VthrComp = 91
[10:38:48.833] <TB3> INFO: ROC 2 VthrComp = 90
[10:38:48.833] <TB3> INFO: ROC 3 VthrComp = 88
[10:38:48.833] <TB3> INFO: ROC 4 VthrComp = 89
[10:38:48.833] <TB3> INFO: ROC 5 VthrComp = 90
[10:38:48.833] <TB3> INFO: ROC 6 VthrComp = 80
[10:38:48.833] <TB3> INFO: ROC 7 VthrComp = 94
[10:38:48.833] <TB3> INFO: ROC 8 VthrComp = 94
[10:38:48.833] <TB3> INFO: ROC 9 VthrComp = 82
[10:38:48.833] <TB3> INFO: ROC 10 VthrComp = 86
[10:38:48.834] <TB3> INFO: ROC 11 VthrComp = 89
[10:38:48.834] <TB3> INFO: ROC 12 VthrComp = 94
[10:38:48.834] <TB3> INFO: ROC 13 VthrComp = 85
[10:38:48.834] <TB3> INFO: ROC 14 VthrComp = 99
[10:38:48.834] <TB3> INFO: ROC 15 VthrComp = 81
[10:38:48.834] <TB3> INFO: ---> Vcal thr map (pixel with maximum Vcal thr)
[10:38:48.834] <TB3> INFO: ---> dac: vcal name: TrimThr1 ntrig: 10 dacrange: 10 .. 160 (-1/-1) hits flags = 528 (plus default)
[10:38:48.842] <TB3> INFO: dacScan split into 1 runs with ntrig = 10
[10:38:48.842] <TB3> INFO: run 1 of 1
[10:38:49.101] <TB3> INFO: Expecting 6281600 events.
[10:39:23.840] <TB3> INFO: 754750 events read in total (34183ms).
[10:39:58.691] <TB3> INFO: 1507540 events read in total (69034ms).
[10:40:33.061] <TB3> INFO: 2260150 events read in total (103404ms).
[10:41:07.737] <TB3> INFO: 3012850 events read in total (138080ms).
[10:41:43.017] <TB3> INFO: 3760440 events read in total (173360ms).
[10:42:17.229] <TB3> INFO: 4505020 events read in total (207572ms).
[10:42:51.518] <TB3> INFO: 5247940 events read in total (241861ms).
[10:43:26.707] <TB3> INFO: 5990510 events read in total (277050ms).
[10:43:40.906] <TB3> INFO: 6281600 events read in total (291249ms).
[10:43:40.958] <TB3> INFO: Test took 292117ms.
[10:44:06.158] <TB3> INFO: roc 0 with ID = 0 has maximal Vcal 58.3117 for pixel 2/34 mean/min/max = 44.9702/31.595/58.3453
[10:44:06.159] <TB3> INFO: roc 1 with ID = 1 has maximal Vcal 55.9011 for pixel 26/16 mean/min/max = 44.8165/33.7279/55.9051
[10:44:06.159] <TB3> INFO: roc 2 with ID = 2 has maximal Vcal 56.7822 for pixel 7/2 mean/min/max = 44.499/32.0812/56.9169
[10:44:06.159] <TB3> INFO: roc 3 with ID = 3 has maximal Vcal 56.7351 for pixel 2/5 mean/min/max = 45.0726/33.3237/56.8216
[10:44:06.160] <TB3> INFO: roc 4 with ID = 4 has maximal Vcal 55.1442 for pixel 12/19 mean/min/max = 44.4897/33.6772/55.3021
[10:44:06.160] <TB3> INFO: roc 5 with ID = 5 has maximal Vcal 56.2986 for pixel 0/59 mean/min/max = 44.5551/32.7829/56.3273
[10:44:06.160] <TB3> INFO: roc 6 with ID = 6 has maximal Vcal 56.4049 for pixel 24/12 mean/min/max = 44.492/32.3061/56.6779
[10:44:06.161] <TB3> INFO: roc 7 with ID = 7 has maximal Vcal 56.6805 for pixel 51/4 mean/min/max = 44.3576/31.9632/56.752
[10:44:06.161] <TB3> INFO: roc 8 with ID = 8 has maximal Vcal 60.7022 for pixel 0/77 mean/min/max = 46.2715/31.8144/60.7286
[10:44:06.161] <TB3> INFO: roc 9 with ID = 9 has maximal Vcal 56.1202 for pixel 0/1 mean/min/max = 44.0993/32.0462/56.1524
[10:44:06.162] <TB3> INFO: roc 10 with ID = 10 has maximal Vcal 55.2426 for pixel 17/0 mean/min/max = 43.8252/32.2486/55.4017
[10:44:06.162] <TB3> INFO: roc 11 with ID = 11 has maximal Vcal 55.9829 for pixel 2/68 mean/min/max = 44.3778/32.5258/56.2298
[10:44:06.163] <TB3> INFO: roc 12 with ID = 12 has maximal Vcal 56.5482 for pixel 11/3 mean/min/max = 44.54/32.1852/56.8947
[10:44:06.163] <TB3> INFO: roc 13 with ID = 13 has maximal Vcal 59.7458 for pixel 21/5 mean/min/max = 45.6761/31.5895/59.7627
[10:44:06.163] <TB3> INFO: roc 14 with ID = 14 has maximal Vcal 59.5814 for pixel 21/21 mean/min/max = 45.7294/31.7641/59.6947
[10:44:06.164] <TB3> INFO: roc 15 with ID = 15 has maximal Vcal 55.9394 for pixel 22/2 mean/min/max = 44.215/32.1861/56.2439
[10:44:06.164] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[10:44:06.252] <TB3> INFO: Expecting 514560 events.
[10:44:19.173] <TB3> INFO: 514560 events read in total (12365ms).
[10:44:19.179] <TB3> INFO: Expecting 514560 events.
[10:44:31.977] <TB3> INFO: 514560 events read in total (12382ms).
[10:44:31.984] <TB3> INFO: Expecting 514560 events.
[10:44:44.918] <TB3> INFO: 514560 events read in total (12517ms).
[10:44:44.927] <TB3> INFO: Expecting 514560 events.
[10:44:57.841] <TB3> INFO: 514560 events read in total (12502ms).
[10:44:57.853] <TB3> INFO: Expecting 514560 events.
[10:45:10.723] <TB3> INFO: 514560 events read in total (12458ms).
[10:45:10.737] <TB3> INFO: Expecting 514560 events.
[10:45:23.696] <TB3> INFO: 514560 events read in total (12553ms).
[10:45:23.711] <TB3> INFO: Expecting 514560 events.
[10:45:36.662] <TB3> INFO: 514560 events read in total (12542ms).
[10:45:36.681] <TB3> INFO: Expecting 514560 events.
[10:45:49.520] <TB3> INFO: 514560 events read in total (12434ms).
[10:45:49.540] <TB3> INFO: Expecting 514560 events.
[10:46:02.449] <TB3> INFO: 514560 events read in total (12504ms).
[10:46:02.470] <TB3> INFO: Expecting 514560 events.
[10:46:15.282] <TB3> INFO: 514560 events read in total (12410ms).
[10:46:15.307] <TB3> INFO: Expecting 514560 events.
[10:46:28.242] <TB3> INFO: 514560 events read in total (12533ms).
[10:46:28.272] <TB3> INFO: Expecting 514560 events.
[10:46:41.020] <TB3> INFO: 514560 events read in total (12346ms).
[10:46:41.048] <TB3> INFO: Expecting 514560 events.
[10:46:53.930] <TB3> INFO: 514560 events read in total (12480ms).
[10:46:53.962] <TB3> INFO: Expecting 514560 events.
[10:47:06.802] <TB3> INFO: 514560 events read in total (12439ms).
[10:47:06.835] <TB3> INFO: Expecting 514560 events.
[10:47:19.793] <TB3> INFO: 514560 events read in total (12556ms).
[10:47:19.830] <TB3> INFO: Expecting 514560 events.
[10:47:32.565] <TB3> INFO: 514560 events read in total (12334ms).
[10:47:32.599] <TB3> INFO: Test took 206435ms.
[10:47:33.881] <TB3> INFO: ---> dac: vcal name: TrimThr2 ntrig: 10 dacrange: 0 .. 150 (-1/-1) hits flags = 528 (plus default)
[10:47:33.890] <TB3> INFO: dacScan split into 1 runs with ntrig = 10
[10:47:33.890] <TB3> INFO: run 1 of 1
[10:47:34.151] <TB3> INFO: Expecting 6281600 events.
[10:48:09.237] <TB3> INFO: 742580 events read in total (34530ms).
[10:48:43.784] <TB3> INFO: 1483550 events read in total (69077ms).
[10:49:18.159] <TB3> INFO: 2224880 events read in total (103452ms).
[10:49:52.978] <TB3> INFO: 2966050 events read in total (138271ms).
[10:50:27.407] <TB3> INFO: 3702430 events read in total (172700ms).
[10:51:01.882] <TB3> INFO: 4434490 events read in total (207175ms).
[10:51:36.625] <TB3> INFO: 5164860 events read in total (241918ms).
[10:52:11.009] <TB3> INFO: 5894540 events read in total (276302ms).
[10:52:29.632] <TB3> INFO: 6281600 events read in total (294925ms).
[10:52:29.684] <TB3> INFO: Test took 295794ms.
[10:52:53.194] <TB3> INFO: ---> TrimStepCorr4 extremal thresholds: 0.179848 .. 255.000000
[10:52:53.273] <TB3> INFO: ---> dac: vcal name: trimStepCorr4 ntrig: 10 dacrange: 0 .. 255 (-1/-1) hits flags = 528 (plus default)
[10:52:53.281] <TB3> INFO: dacScan split into 1 runs with ntrig = 10
[10:52:53.281] <TB3> INFO: run 1 of 1
[10:52:53.543] <TB3> INFO: Expecting 10649600 events.
[10:53:28.283] <TB3> INFO: 712110 events read in total (34183ms).
[10:54:02.208] <TB3> INFO: 1424740 events read in total (68108ms).
[10:54:36.469] <TB3> INFO: 2137090 events read in total (102369ms).
[10:55:10.385] <TB3> INFO: 2849960 events read in total (136285ms).
[10:55:44.185] <TB3> INFO: 3562400 events read in total (170085ms).
[10:56:18.256] <TB3> INFO: 4274940 events read in total (204156ms).
[10:56:52.162] <TB3> INFO: 4987910 events read in total (238062ms).
[10:57:26.397] <TB3> INFO: 5700420 events read in total (272297ms).
[10:58:00.486] <TB3> INFO: 6412670 events read in total (306386ms).
[10:58:34.862] <TB3> INFO: 7124030 events read in total (340762ms).
[10:59:09.235] <TB3> INFO: 7835240 events read in total (375135ms).
[10:59:43.913] <TB3> INFO: 8546020 events read in total (409813ms).
[11:00:17.694] <TB3> INFO: 9256510 events read in total (443594ms).
[11:00:52.001] <TB3> INFO: 9967210 events read in total (477901ms).
[11:01:25.405] <TB3> INFO: 10649600 events read in total (511305ms).
[11:01:25.500] <TB3> INFO: Test took 512218ms.
[11:01:55.050] <TB3> INFO: ---> TrimStepCorr2 extremal thresholds: 16.780562 .. 46.169582
[11:01:55.141] <TB3> INFO: ---> dac: vcal name: trimStepCorr2 ntrig: 10 dacrange: 6 .. 56 (-1/-1) hits flags = 528 (plus default)
[11:01:55.151] <TB3> INFO: dacScan split into 1 runs with ntrig = 10
[11:01:55.151] <TB3> INFO: run 1 of 1
[11:01:55.437] <TB3> INFO: Expecting 2121600 events.
[11:02:33.006] <TB3> INFO: 925340 events read in total (37013ms).
[11:03:10.320] <TB3> INFO: 1848680 events read in total (74327ms).
[11:03:21.851] <TB3> INFO: 2121600 events read in total (85858ms).
[11:03:21.868] <TB3> INFO: Test took 86718ms.
[11:03:36.283] <TB3> INFO: ---> TrimStepCorr1a extremal thresholds: 21.174565 .. 43.272336
[11:03:36.376] <TB3> INFO: ---> dac: vcal name: trimStepCorr1a ntrig: 10 dacrange: 11 .. 53 (-1/-1) hits flags = 528 (plus default)
[11:03:36.388] <TB3> INFO: dacScan split into 1 runs with ntrig = 10
[11:03:36.389] <TB3> INFO: run 1 of 1
[11:03:36.682] <TB3> INFO: Expecting 1788800 events.
[11:04:17.108] <TB3> INFO: 921760 events read in total (39870ms).
[11:04:52.775] <TB3> INFO: 1788800 events read in total (75537ms).
[11:04:52.793] <TB3> INFO: Test took 76404ms.
[11:05:06.204] <TB3> INFO: ---> TrimStepCorr1b extremal thresholds: 3.700669 .. 43.173379
[11:05:06.281] <TB3> INFO: ---> dac: vcal name: trimStepCorr1b ntrig: 10 dacrange: 3 .. 53 (-1/-1) hits flags = 528 (plus default)
[11:05:06.289] <TB3> INFO: dacScan split into 1 runs with ntrig = 10
[11:05:06.289] <TB3> INFO: run 1 of 1
[11:05:06.550] <TB3> INFO: Expecting 2121600 events.
[11:05:45.668] <TB3> INFO: 968000 events read in total (38562ms).
[11:06:24.546] <TB3> INFO: 1935350 events read in total (77440ms).
[11:06:32.113] <TB3> INFO: 2121600 events read in total (85007ms).
[11:06:32.127] <TB3> INFO: Test took 85838ms.
[11:06:45.456] <TB3> INFO: ---> TrimThrFinal extremal thresholds: 15 .. 55
[11:06:45.456] <TB3> INFO: ---> dac: vcal name: TrimThrFinal ntrig: 10 dacrange: 15 .. 55 (-1/-1) hits flags = 528 (plus default)
[11:06:45.464] <TB3> INFO: dacScan split into 1 runs with ntrig = 10
[11:06:45.464] <TB3> INFO: run 1 of 1
[11:06:45.727] <TB3> INFO: Expecting 1705600 events.
[11:07:25.258] <TB3> INFO: 879600 events read in total (38975ms).
[11:08:00.216] <TB3> INFO: 1705600 events read in total (73933ms).
[11:08:00.237] <TB3> INFO: Test took 74775ms.
[11:08:14.755] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2302_FullQualification_2016-08-29_11h59m_1472464790//000_FulltestPxar_m20//dacParameters35_C0.dat
[11:08:14.755] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2302_FullQualification_2016-08-29_11h59m_1472464790//000_FulltestPxar_m20//dacParameters35_C1.dat
[11:08:14.755] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2302_FullQualification_2016-08-29_11h59m_1472464790//000_FulltestPxar_m20//dacParameters35_C2.dat
[11:08:14.755] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2302_FullQualification_2016-08-29_11h59m_1472464790//000_FulltestPxar_m20//dacParameters35_C3.dat
[11:08:14.755] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2302_FullQualification_2016-08-29_11h59m_1472464790//000_FulltestPxar_m20//dacParameters35_C4.dat
[11:08:14.755] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2302_FullQualification_2016-08-29_11h59m_1472464790//000_FulltestPxar_m20//dacParameters35_C5.dat
[11:08:14.756] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2302_FullQualification_2016-08-29_11h59m_1472464790//000_FulltestPxar_m20//dacParameters35_C6.dat
[11:08:14.756] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2302_FullQualification_2016-08-29_11h59m_1472464790//000_FulltestPxar_m20//dacParameters35_C7.dat
[11:08:14.756] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2302_FullQualification_2016-08-29_11h59m_1472464790//000_FulltestPxar_m20//dacParameters35_C8.dat
[11:08:14.756] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2302_FullQualification_2016-08-29_11h59m_1472464790//000_FulltestPxar_m20//dacParameters35_C9.dat
[11:08:14.756] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2302_FullQualification_2016-08-29_11h59m_1472464790//000_FulltestPxar_m20//dacParameters35_C10.dat
[11:08:14.756] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2302_FullQualification_2016-08-29_11h59m_1472464790//000_FulltestPxar_m20//dacParameters35_C11.dat
[11:08:14.756] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2302_FullQualification_2016-08-29_11h59m_1472464790//000_FulltestPxar_m20//dacParameters35_C12.dat
[11:08:14.756] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2302_FullQualification_2016-08-29_11h59m_1472464790//000_FulltestPxar_m20//dacParameters35_C13.dat
[11:08:14.756] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2302_FullQualification_2016-08-29_11h59m_1472464790//000_FulltestPxar_m20//dacParameters35_C14.dat
[11:08:14.757] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2302_FullQualification_2016-08-29_11h59m_1472464790//000_FulltestPxar_m20//dacParameters35_C15.dat
[11:08:14.757] <TB3> INFO: write trim parameters into /usr/local/coldboxDATA/M2302_FullQualification_2016-08-29_11h59m_1472464790//000_FulltestPxar_m20//trimParameters35_C0.dat
[11:08:14.763] <TB3> INFO: write trim parameters into /usr/local/coldboxDATA/M2302_FullQualification_2016-08-29_11h59m_1472464790//000_FulltestPxar_m20//trimParameters35_C1.dat
[11:08:14.769] <TB3> INFO: write trim parameters into /usr/local/coldboxDATA/M2302_FullQualification_2016-08-29_11h59m_1472464790//000_FulltestPxar_m20//trimParameters35_C2.dat
[11:08:14.775] <TB3> INFO: write trim parameters into /usr/local/coldboxDATA/M2302_FullQualification_2016-08-29_11h59m_1472464790//000_FulltestPxar_m20//trimParameters35_C3.dat
[11:08:14.783] <TB3> INFO: write trim parameters into /usr/local/coldboxDATA/M2302_FullQualification_2016-08-29_11h59m_1472464790//000_FulltestPxar_m20//trimParameters35_C4.dat
[11:08:14.789] <TB3> INFO: write trim parameters into /usr/local/coldboxDATA/M2302_FullQualification_2016-08-29_11h59m_1472464790//000_FulltestPxar_m20//trimParameters35_C5.dat
[11:08:14.795] <TB3> INFO: write trim parameters into /usr/local/coldboxDATA/M2302_FullQualification_2016-08-29_11h59m_1472464790//000_FulltestPxar_m20//trimParameters35_C6.dat
[11:08:14.802] <TB3> INFO: write trim parameters into /usr/local/coldboxDATA/M2302_FullQualification_2016-08-29_11h59m_1472464790//000_FulltestPxar_m20//trimParameters35_C7.dat
[11:08:14.808] <TB3> INFO: write trim parameters into /usr/local/coldboxDATA/M2302_FullQualification_2016-08-29_11h59m_1472464790//000_FulltestPxar_m20//trimParameters35_C8.dat
[11:08:14.814] <TB3> INFO: write trim parameters into /usr/local/coldboxDATA/M2302_FullQualification_2016-08-29_11h59m_1472464790//000_FulltestPxar_m20//trimParameters35_C9.dat
[11:08:14.820] <TB3> INFO: write trim parameters into /usr/local/coldboxDATA/M2302_FullQualification_2016-08-29_11h59m_1472464790//000_FulltestPxar_m20//trimParameters35_C10.dat
[11:08:14.826] <TB3> INFO: write trim parameters into /usr/local/coldboxDATA/M2302_FullQualification_2016-08-29_11h59m_1472464790//000_FulltestPxar_m20//trimParameters35_C11.dat
[11:08:14.832] <TB3> INFO: write trim parameters into /usr/local/coldboxDATA/M2302_FullQualification_2016-08-29_11h59m_1472464790//000_FulltestPxar_m20//trimParameters35_C12.dat
[11:08:14.838] <TB3> INFO: write trim parameters into /usr/local/coldboxDATA/M2302_FullQualification_2016-08-29_11h59m_1472464790//000_FulltestPxar_m20//trimParameters35_C13.dat
[11:08:14.844] <TB3> INFO: write trim parameters into /usr/local/coldboxDATA/M2302_FullQualification_2016-08-29_11h59m_1472464790//000_FulltestPxar_m20//trimParameters35_C14.dat
[11:08:14.850] <TB3> INFO: write trim parameters into /usr/local/coldboxDATA/M2302_FullQualification_2016-08-29_11h59m_1472464790//000_FulltestPxar_m20//trimParameters35_C15.dat
[11:08:14.856] <TB3> INFO: PixTestTrim::trimTest() done
[11:08:14.856] <TB3> INFO: vtrim: 105 97 100 98 90 99 89 93 115 92 103 87 95 99 117 93
[11:08:14.856] <TB3> INFO: vthrcomp: 85 91 90 88 89 90 80 94 94 82 86 89 94 85 99 81
[11:08:14.856] <TB3> INFO: vcal mean: 35.01 34.97 34.99 35.00 35.01 35.01 35.00 34.96 34.98 34.99 34.96 34.99 34.94 34.98 34.98 34.97
[11:08:14.856] <TB3> INFO: vcal RMS: 0.84 0.81 0.76 0.80 0.79 0.77 0.78 0.81 0.89 0.80 0.78 0.88 0.82 0.88 0.89 0.80
[11:08:14.856] <TB3> INFO: bits mean: 9.64 9.89 9.65 9.51 9.80 9.54 9.37 9.15 9.32 9.78 9.79 9.52 9.57 9.33 9.68 9.79
[11:08:14.856] <TB3> INFO: bits RMS: 2.76 2.33 2.66 2.53 2.36 2.59 2.78 2.96 2.73 2.64 2.62 2.71 2.66 2.81 2.65 2.63
[11:08:14.862] <TB3> INFO: ----------------------------------------------------------------------
[11:08:14.862] <TB3> INFO: PixTestTrim::trimBitTest() ntrig = 5, vtrims = 254 126 63 32
[11:08:14.862] <TB3> INFO: ----------------------------------------------------------------------
[11:08:14.865] <TB3> INFO: ---> dac: Vcal name: TrimBitsThr0 ntrig: 5 dacrange: 0 .. 199 (-1/-1) hits flags = 528 (plus default)
[11:08:14.874] <TB3> INFO: dacScan split into 1 runs with ntrig = 5
[11:08:14.874] <TB3> INFO: run 1 of 1
[11:08:15.145] <TB3> INFO: Expecting 4160000 events.
[11:08:57.002] <TB3> INFO: 904880 events read in total (41301ms).
[11:09:37.737] <TB3> INFO: 1803745 events read in total (82036ms).
[11:10:18.412] <TB3> INFO: 2694905 events read in total (122712ms).
[11:10:59.271] <TB3> INFO: 3582765 events read in total (163570ms).
[11:11:25.647] <TB3> INFO: 4160000 events read in total (189947ms).
[11:11:25.687] <TB3> INFO: Test took 190813ms.
[11:11:54.432] <TB3> INFO: ---> dac: Vcal name: TrimThr_trim14 ntrig: 5 dacrange: 0 .. 153 (-1/-1) hits flags = 528 (plus default)
[11:11:54.440] <TB3> INFO: dacScan split into 1 runs with ntrig = 5
[11:11:54.440] <TB3> INFO: run 1 of 1
[11:11:54.700] <TB3> INFO: Expecting 3203200 events.
[11:12:38.049] <TB3> INFO: 968255 events read in total (42793ms).
[11:13:20.398] <TB3> INFO: 1923675 events read in total (85142ms).
[11:14:02.768] <TB3> INFO: 2868965 events read in total (127512ms).
[11:14:17.741] <TB3> INFO: 3203200 events read in total (142485ms).
[11:14:17.766] <TB3> INFO: Test took 143325ms.
[11:14:42.268] <TB3> INFO: ---> dac: Vcal name: TrimThr_trim13 ntrig: 5 dacrange: 0 .. 143 (-1/-1) hits flags = 528 (plus default)
[11:14:42.276] <TB3> INFO: dacScan split into 1 runs with ntrig = 5
[11:14:42.276] <TB3> INFO: run 1 of 1
[11:14:42.535] <TB3> INFO: Expecting 2995200 events.
[11:15:26.206] <TB3> INFO: 998965 events read in total (43115ms).
[11:16:09.427] <TB3> INFO: 1980735 events read in total (86336ms).
[11:16:52.109] <TB3> INFO: 2954970 events read in total (129018ms).
[11:16:54.180] <TB3> INFO: 2995200 events read in total (131089ms).
[11:16:54.206] <TB3> INFO: Test took 131930ms.
[11:17:18.407] <TB3> INFO: ---> dac: Vcal name: TrimThr_trim11 ntrig: 5 dacrange: 0 .. 142 (-1/-1) hits flags = 528 (plus default)
[11:17:18.416] <TB3> INFO: dacScan split into 1 runs with ntrig = 5
[11:17:18.416] <TB3> INFO: run 1 of 1
[11:17:18.694] <TB3> INFO: Expecting 2974400 events.
[11:18:02.529] <TB3> INFO: 1000820 events read in total (43279ms).
[11:18:44.681] <TB3> INFO: 1984025 events read in total (85432ms).
[11:19:27.216] <TB3> INFO: 2961075 events read in total (127967ms).
[11:19:28.194] <TB3> INFO: 2974400 events read in total (128944ms).
[11:19:28.221] <TB3> INFO: Test took 129805ms.
[11:19:50.808] <TB3> INFO: ---> dac: Vcal name: TrimThr_trim7 ntrig: 5 dacrange: 0 .. 142 (-1/-1) hits flags = 528 (plus default)
[11:19:50.816] <TB3> INFO: dacScan split into 1 runs with ntrig = 5
[11:19:50.816] <TB3> INFO: run 1 of 1
[11:19:51.078] <TB3> INFO: Expecting 2974400 events.
[11:20:34.520] <TB3> INFO: 1000185 events read in total (42885ms).
[11:21:17.264] <TB3> INFO: 1982910 events read in total (85629ms).
[11:21:59.881] <TB3> INFO: 2959145 events read in total (128246ms).
[11:22:00.909] <TB3> INFO: 2974400 events read in total (129274ms).
[11:22:00.933] <TB3> INFO: Test took 130116ms.
[11:22:25.446] <TB3> INFO: PixTestTrim::trimBitTest() done
[11:22:25.447] <TB3> INFO: PixTestTrim::doTest() done, duration: 2869 seconds
[11:22:25.447] <TB3> INFO: Decoding statistics:
[11:22:25.447] <TB3> INFO: General information:
[11:22:25.447] <TB3> INFO: 16bit words read: 0
[11:22:25.447] <TB3> INFO: valid events total: 0
[11:22:25.447] <TB3> INFO: empty events: 0
[11:22:25.447] <TB3> INFO: valid events with pixels: 0
[11:22:25.447] <TB3> INFO: valid pixel hits: 0
[11:22:25.447] <TB3> INFO: Event errors: 0
[11:22:25.447] <TB3> INFO: start marker: 0
[11:22:25.447] <TB3> INFO: stop marker: 0
[11:22:25.447] <TB3> INFO: overflow: 0
[11:22:25.447] <TB3> INFO: invalid 5bit words: 0
[11:22:25.447] <TB3> INFO: invalid XOR eye diagram: 0
[11:22:25.447] <TB3> INFO: frame (failed synchr.): 0
[11:22:25.447] <TB3> INFO: idle data (no TBM trl): 0
[11:22:25.447] <TB3> INFO: no data (only TBM hdr): 0
[11:22:25.447] <TB3> INFO: TBM errors: 0
[11:22:25.447] <TB3> INFO: flawed TBM headers: 0
[11:22:25.447] <TB3> INFO: flawed TBM trailers: 0
[11:22:25.447] <TB3> INFO: event ID mismatches: 0
[11:22:25.447] <TB3> INFO: ROC errors: 0
[11:22:25.447] <TB3> INFO: missing ROC header(s): 0
[11:22:25.447] <TB3> INFO: misplaced readback start: 0
[11:22:25.447] <TB3> INFO: Pixel decoding errors: 0
[11:22:25.447] <TB3> INFO: pixel data incomplete: 0
[11:22:25.447] <TB3> INFO: pixel address: 0
[11:22:25.447] <TB3> INFO: pulse height fill bit: 0
[11:22:25.447] <TB3> INFO: buffer corruption: 0
[11:22:26.165] <TB3> INFO: ######################################################################
[11:22:26.165] <TB3> INFO: PixTestPhOptimization::doTest() Ntrig = 10
[11:22:26.165] <TB3> INFO: ######################################################################
[11:22:26.448] <TB3> INFO: Expecting 41600 events.
[11:22:30.245] <TB3> INFO: 41600 events read in total (3241ms).
[11:22:30.245] <TB3> INFO: Test took 4078ms.
[11:22:30.724] <TB3> INFO: Expecting 41600 events.
[11:22:34.571] <TB3> INFO: 41600 events read in total (3291ms).
[11:22:34.572] <TB3> INFO: Test took 4124ms.
[11:22:34.883] <TB3> INFO: Expecting 41600 events.
[11:22:38.713] <TB3> INFO: 41600 events read in total (3274ms).
[11:22:38.714] <TB3> INFO: Test took 4119ms.
[11:22:38.973] <TB3> INFO: Expecting 2560 events.
[11:22:39.827] <TB3> INFO: 2560 events read in total (298ms).
[11:22:39.827] <TB3> INFO: Test took 1096ms.
[11:22:40.132] <TB3> INFO: Expecting 2560 events.
[11:22:40.987] <TB3> INFO: 2560 events read in total (299ms).
[11:22:40.987] <TB3> INFO: Test took 1160ms.
[11:22:41.293] <TB3> INFO: Expecting 2560 events.
[11:22:42.151] <TB3> INFO: 2560 events read in total (302ms).
[11:22:42.151] <TB3> INFO: Test took 1163ms.
[11:22:42.456] <TB3> INFO: Expecting 2560 events.
[11:22:43.309] <TB3> INFO: 2560 events read in total (297ms).
[11:22:43.309] <TB3> INFO: Test took 1157ms.
[11:22:43.614] <TB3> INFO: Expecting 2560 events.
[11:22:44.468] <TB3> INFO: 2560 events read in total (298ms).
[11:22:44.469] <TB3> INFO: Test took 1160ms.
[11:22:44.773] <TB3> INFO: Expecting 2560 events.
[11:22:45.629] <TB3> INFO: 2560 events read in total (299ms).
[11:22:45.629] <TB3> INFO: Test took 1160ms.
[11:22:45.933] <TB3> INFO: Expecting 2560 events.
[11:22:46.787] <TB3> INFO: 2560 events read in total (297ms).
[11:22:46.787] <TB3> INFO: Test took 1157ms.
[11:22:47.092] <TB3> INFO: Expecting 2560 events.
[11:22:47.944] <TB3> INFO: 2560 events read in total (296ms).
[11:22:47.945] <TB3> INFO: Test took 1158ms.
[11:22:48.250] <TB3> INFO: Expecting 2560 events.
[11:22:49.104] <TB3> INFO: 2560 events read in total (298ms).
[11:22:49.104] <TB3> INFO: Test took 1159ms.
[11:22:49.409] <TB3> INFO: Expecting 2560 events.
[11:22:50.262] <TB3> INFO: 2560 events read in total (297ms).
[11:22:50.262] <TB3> INFO: Test took 1152ms.
[11:22:50.567] <TB3> INFO: Expecting 2560 events.
[11:22:51.420] <TB3> INFO: 2560 events read in total (297ms).
[11:22:51.420] <TB3> INFO: Test took 1157ms.
[11:22:51.725] <TB3> INFO: Expecting 2560 events.
[11:22:52.582] <TB3> INFO: 2560 events read in total (301ms).
[11:22:52.582] <TB3> INFO: Test took 1161ms.
[11:22:52.888] <TB3> INFO: Expecting 2560 events.
[11:22:53.741] <TB3> INFO: 2560 events read in total (297ms).
[11:22:53.742] <TB3> INFO: Test took 1159ms.
[11:22:54.047] <TB3> INFO: Expecting 2560 events.
[11:22:54.904] <TB3> INFO: 2560 events read in total (301ms).
[11:22:54.904] <TB3> INFO: Test took 1162ms.
[11:22:55.209] <TB3> INFO: Expecting 2560 events.
[11:22:56.062] <TB3> INFO: 2560 events read in total (297ms).
[11:22:56.062] <TB3> INFO: Test took 1146ms.
[11:22:56.367] <TB3> INFO: Expecting 2560 events.
[11:22:57.226] <TB3> INFO: 2560 events read in total (303ms).
[11:22:57.226] <TB3> INFO: Test took 1164ms.
[11:22:57.229] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:22:57.531] <TB3> INFO: Expecting 655360 events.
[11:23:14.477] <TB3> INFO: 655360 events read in total (16390ms).
[11:23:14.487] <TB3> INFO: Expecting 655360 events.
[11:23:31.246] <TB3> INFO: 655360 events read in total (16358ms).
[11:23:31.258] <TB3> INFO: Expecting 655360 events.
[11:23:48.016] <TB3> INFO: 655360 events read in total (16357ms).
[11:23:48.031] <TB3> INFO: Expecting 655360 events.
[11:24:05.004] <TB3> INFO: 655360 events read in total (16571ms).
[11:24:05.023] <TB3> INFO: Expecting 655360 events.
[11:24:21.585] <TB3> INFO: 655360 events read in total (16161ms).
[11:24:21.608] <TB3> INFO: Expecting 655360 events.
[11:24:38.007] <TB3> INFO: 655360 events read in total (15998ms).
[11:24:38.033] <TB3> INFO: Expecting 655360 events.
[11:24:55.091] <TB3> INFO: 655360 events read in total (16656ms).
[11:24:55.120] <TB3> INFO: Expecting 655360 events.
[11:25:11.829] <TB3> INFO: 655360 events read in total (16307ms).
[11:25:11.863] <TB3> INFO: Expecting 655360 events.
[11:25:28.573] <TB3> INFO: 655360 events read in total (16308ms).
[11:25:28.611] <TB3> INFO: Expecting 655360 events.
[11:25:45.494] <TB3> INFO: 655360 events read in total (16482ms).
[11:25:45.540] <TB3> INFO: Expecting 655360 events.
[11:26:02.259] <TB3> INFO: 655360 events read in total (16317ms).
[11:26:02.300] <TB3> INFO: Expecting 655360 events.
[11:26:19.350] <TB3> INFO: 655360 events read in total (16648ms).
[11:26:19.396] <TB3> INFO: Expecting 655360 events.
[11:26:36.096] <TB3> INFO: 655360 events read in total (16298ms).
[11:26:36.146] <TB3> INFO: Expecting 655360 events.
[11:26:52.888] <TB3> INFO: 655360 events read in total (16341ms).
[11:26:52.948] <TB3> INFO: Expecting 655360 events.
[11:27:09.532] <TB3> INFO: 655360 events read in total (16182ms).
[11:27:09.589] <TB3> INFO: Expecting 655360 events.
[11:27:26.271] <TB3> INFO: 655360 events read in total (16281ms).
[11:27:26.339] <TB3> INFO: Test took 269110ms.
[11:27:26.416] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:27:26.647] <TB3> INFO: Expecting 655360 events.
[11:27:43.538] <TB3> INFO: 655360 events read in total (16335ms).
[11:27:43.546] <TB3> INFO: Expecting 655360 events.
[11:28:00.475] <TB3> INFO: 655360 events read in total (16527ms).
[11:28:00.488] <TB3> INFO: Expecting 655360 events.
[11:28:16.921] <TB3> INFO: 655360 events read in total (16032ms).
[11:28:16.937] <TB3> INFO: Expecting 655360 events.
[11:28:33.388] <TB3> INFO: 655360 events read in total (16050ms).
[11:28:33.407] <TB3> INFO: Expecting 655360 events.
[11:28:50.349] <TB3> INFO: 655360 events read in total (16540ms).
[11:28:50.374] <TB3> INFO: Expecting 655360 events.
[11:29:07.459] <TB3> INFO: 655360 events read in total (16683ms).
[11:29:07.488] <TB3> INFO: Expecting 655360 events.
[11:29:24.530] <TB3> INFO: 655360 events read in total (16640ms).
[11:29:24.564] <TB3> INFO: Expecting 655360 events.
[11:29:41.701] <TB3> INFO: 655360 events read in total (16736ms).
[11:29:41.734] <TB3> INFO: Expecting 655360 events.
[11:29:58.350] <TB3> INFO: 655360 events read in total (16214ms).
[11:29:58.385] <TB3> INFO: Expecting 655360 events.
[11:30:15.412] <TB3> INFO: 655360 events read in total (16625ms).
[11:30:15.453] <TB3> INFO: Expecting 655360 events.
[11:30:32.515] <TB3> INFO: 655360 events read in total (16660ms).
[11:30:32.561] <TB3> INFO: Expecting 655360 events.
[11:30:49.434] <TB3> INFO: 655360 events read in total (16471ms).
[11:30:49.479] <TB3> INFO: Expecting 655360 events.
[11:31:06.399] <TB3> INFO: 655360 events read in total (16518ms).
[11:31:06.456] <TB3> INFO: Expecting 655360 events.
[11:31:23.282] <TB3> INFO: 655360 events read in total (16424ms).
[11:31:23.335] <TB3> INFO: Expecting 655360 events.
[11:31:40.195] <TB3> INFO: 655360 events read in total (16458ms).
[11:31:40.250] <TB3> INFO: Expecting 655360 events.
[11:31:56.840] <TB3> INFO: 655360 events read in total (16188ms).
[11:31:56.901] <TB3> INFO: Test took 270485ms.
[11:31:57.084] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[11:31:57.090] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[11:31:57.097] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[11:31:57.105] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[11:31:57.113] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[11:31:57.120] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[11:31:57.127] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[11:31:57.133] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[11:31:57.140] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[11:31:57.147] <TB3> INFO: safety margin for low PH: adding 1, margin is now 21
[11:31:57.153] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[11:31:57.160] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[11:31:57.167] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[11:31:57.173] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[11:31:57.184] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[11:31:57.194] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[11:31:57.203] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[11:31:57.238] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2302_FullQualification_2016-08-29_11h59m_1472464790//000_FulltestPxar_m20//dacParameters35_C0.dat
[11:31:57.239] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2302_FullQualification_2016-08-29_11h59m_1472464790//000_FulltestPxar_m20//dacParameters35_C1.dat
[11:31:57.239] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2302_FullQualification_2016-08-29_11h59m_1472464790//000_FulltestPxar_m20//dacParameters35_C2.dat
[11:31:57.239] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2302_FullQualification_2016-08-29_11h59m_1472464790//000_FulltestPxar_m20//dacParameters35_C3.dat
[11:31:57.239] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2302_FullQualification_2016-08-29_11h59m_1472464790//000_FulltestPxar_m20//dacParameters35_C4.dat
[11:31:57.239] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2302_FullQualification_2016-08-29_11h59m_1472464790//000_FulltestPxar_m20//dacParameters35_C5.dat
[11:31:57.239] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2302_FullQualification_2016-08-29_11h59m_1472464790//000_FulltestPxar_m20//dacParameters35_C6.dat
[11:31:57.239] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2302_FullQualification_2016-08-29_11h59m_1472464790//000_FulltestPxar_m20//dacParameters35_C7.dat
[11:31:57.239] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2302_FullQualification_2016-08-29_11h59m_1472464790//000_FulltestPxar_m20//dacParameters35_C8.dat
[11:31:57.239] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2302_FullQualification_2016-08-29_11h59m_1472464790//000_FulltestPxar_m20//dacParameters35_C9.dat
[11:31:57.239] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2302_FullQualification_2016-08-29_11h59m_1472464790//000_FulltestPxar_m20//dacParameters35_C10.dat
[11:31:57.240] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2302_FullQualification_2016-08-29_11h59m_1472464790//000_FulltestPxar_m20//dacParameters35_C11.dat
[11:31:57.240] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2302_FullQualification_2016-08-29_11h59m_1472464790//000_FulltestPxar_m20//dacParameters35_C12.dat
[11:31:57.240] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2302_FullQualification_2016-08-29_11h59m_1472464790//000_FulltestPxar_m20//dacParameters35_C13.dat
[11:31:57.240] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2302_FullQualification_2016-08-29_11h59m_1472464790//000_FulltestPxar_m20//dacParameters35_C14.dat
[11:31:57.240] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2302_FullQualification_2016-08-29_11h59m_1472464790//000_FulltestPxar_m20//dacParameters35_C15.dat
[11:31:57.504] <TB3> INFO: Expecting 41600 events.
[11:32:01.047] <TB3> INFO: 41600 events read in total (2987ms).
[11:32:01.048] <TB3> INFO: Test took 3805ms.
[11:32:01.497] <TB3> INFO: Expecting 41600 events.
[11:32:05.013] <TB3> INFO: 41600 events read in total (2960ms).
[11:32:05.014] <TB3> INFO: Test took 3786ms.
[11:32:05.495] <TB3> INFO: Expecting 41600 events.
[11:32:09.054] <TB3> INFO: 41600 events read in total (3003ms).
[11:32:09.054] <TB3> INFO: Test took 3859ms.
[11:32:09.244] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:32:09.332] <TB3> INFO: Expecting 2560 events.
[11:32:10.188] <TB3> INFO: 2560 events read in total (300ms).
[11:32:10.188] <TB3> INFO: Test took 945ms.
[11:32:10.191] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:32:10.494] <TB3> INFO: Expecting 2560 events.
[11:32:11.348] <TB3> INFO: 2560 events read in total (298ms).
[11:32:11.348] <TB3> INFO: Test took 1158ms.
[11:32:11.350] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:32:11.654] <TB3> INFO: Expecting 2560 events.
[11:32:12.511] <TB3> INFO: 2560 events read in total (301ms).
[11:32:12.511] <TB3> INFO: Test took 1161ms.
[11:32:12.513] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:32:12.817] <TB3> INFO: Expecting 2560 events.
[11:32:13.674] <TB3> INFO: 2560 events read in total (301ms).
[11:32:13.674] <TB3> INFO: Test took 1161ms.
[11:32:13.676] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:32:13.980] <TB3> INFO: Expecting 2560 events.
[11:32:14.833] <TB3> INFO: 2560 events read in total (297ms).
[11:32:14.833] <TB3> INFO: Test took 1157ms.
[11:32:14.835] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:32:15.140] <TB3> INFO: Expecting 2560 events.
[11:32:15.995] <TB3> INFO: 2560 events read in total (299ms).
[11:32:15.996] <TB3> INFO: Test took 1161ms.
[11:32:15.998] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:32:16.301] <TB3> INFO: Expecting 2560 events.
[11:32:17.156] <TB3> INFO: 2560 events read in total (299ms).
[11:32:17.156] <TB3> INFO: Test took 1158ms.
[11:32:17.158] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:32:17.462] <TB3> INFO: Expecting 2560 events.
[11:32:18.318] <TB3> INFO: 2560 events read in total (300ms).
[11:32:18.319] <TB3> INFO: Test took 1161ms.
[11:32:18.322] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:32:18.623] <TB3> INFO: Expecting 2560 events.
[11:32:19.476] <TB3> INFO: 2560 events read in total (296ms).
[11:32:19.477] <TB3> INFO: Test took 1156ms.
[11:32:19.479] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:32:19.782] <TB3> INFO: Expecting 2560 events.
[11:32:20.638] <TB3> INFO: 2560 events read in total (299ms).
[11:32:20.638] <TB3> INFO: Test took 1159ms.
[11:32:20.641] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:32:20.943] <TB3> INFO: Expecting 2560 events.
[11:32:21.798] <TB3> INFO: 2560 events read in total (299ms).
[11:32:21.798] <TB3> INFO: Test took 1157ms.
[11:32:21.800] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:32:22.103] <TB3> INFO: Expecting 2560 events.
[11:32:22.957] <TB3> INFO: 2560 events read in total (297ms).
[11:32:22.958] <TB3> INFO: Test took 1158ms.
[11:32:22.960] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:32:23.265] <TB3> INFO: Expecting 2560 events.
[11:32:24.124] <TB3> INFO: 2560 events read in total (303ms).
[11:32:24.125] <TB3> INFO: Test took 1165ms.
[11:32:24.128] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:32:24.430] <TB3> INFO: Expecting 2560 events.
[11:32:25.289] <TB3> INFO: 2560 events read in total (303ms).
[11:32:25.289] <TB3> INFO: Test took 1162ms.
[11:32:25.291] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:32:25.596] <TB3> INFO: Expecting 2560 events.
[11:32:26.457] <TB3> INFO: 2560 events read in total (305ms).
[11:32:26.457] <TB3> INFO: Test took 1166ms.
[11:32:26.459] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:32:26.764] <TB3> INFO: Expecting 2560 events.
[11:32:27.627] <TB3> INFO: 2560 events read in total (306ms).
[11:32:27.627] <TB3> INFO: Test took 1168ms.
[11:32:27.629] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:32:27.934] <TB3> INFO: Expecting 2560 events.
[11:32:28.790] <TB3> INFO: 2560 events read in total (300ms).
[11:32:28.790] <TB3> INFO: Test took 1161ms.
[11:32:28.792] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:32:29.096] <TB3> INFO: Expecting 2560 events.
[11:32:29.952] <TB3> INFO: 2560 events read in total (300ms).
[11:32:29.952] <TB3> INFO: Test took 1160ms.
[11:32:29.954] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:32:30.258] <TB3> INFO: Expecting 2560 events.
[11:32:31.117] <TB3> INFO: 2560 events read in total (304ms).
[11:32:31.118] <TB3> INFO: Test took 1164ms.
[11:32:31.121] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:32:31.423] <TB3> INFO: Expecting 2560 events.
[11:32:32.280] <TB3> INFO: 2560 events read in total (301ms).
[11:32:32.280] <TB3> INFO: Test took 1160ms.
[11:32:32.282] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:32:32.587] <TB3> INFO: Expecting 2560 events.
[11:32:33.442] <TB3> INFO: 2560 events read in total (299ms).
[11:32:33.442] <TB3> INFO: Test took 1160ms.
[11:32:33.444] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:32:33.748] <TB3> INFO: Expecting 2560 events.
[11:32:34.604] <TB3> INFO: 2560 events read in total (300ms).
[11:32:34.604] <TB3> INFO: Test took 1160ms.
[11:32:34.607] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:32:34.910] <TB3> INFO: Expecting 2560 events.
[11:32:35.768] <TB3> INFO: 2560 events read in total (302ms).
[11:32:35.768] <TB3> INFO: Test took 1162ms.
[11:32:35.770] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:32:36.073] <TB3> INFO: Expecting 2560 events.
[11:32:36.928] <TB3> INFO: 2560 events read in total (298ms).
[11:32:36.929] <TB3> INFO: Test took 1159ms.
[11:32:36.931] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:32:37.234] <TB3> INFO: Expecting 2560 events.
[11:32:38.088] <TB3> INFO: 2560 events read in total (298ms).
[11:32:38.088] <TB3> INFO: Test took 1157ms.
[11:32:38.090] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:32:38.393] <TB3> INFO: Expecting 2560 events.
[11:32:39.249] <TB3> INFO: 2560 events read in total (300ms).
[11:32:39.249] <TB3> INFO: Test took 1159ms.
[11:32:39.251] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:32:39.556] <TB3> INFO: Expecting 2560 events.
[11:32:40.416] <TB3> INFO: 2560 events read in total (304ms).
[11:32:40.417] <TB3> INFO: Test took 1166ms.
[11:32:40.418] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:32:40.724] <TB3> INFO: Expecting 2560 events.
[11:32:41.588] <TB3> INFO: 2560 events read in total (308ms).
[11:32:41.588] <TB3> INFO: Test took 1170ms.
[11:32:41.591] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:32:41.895] <TB3> INFO: Expecting 2560 events.
[11:32:42.751] <TB3> INFO: 2560 events read in total (300ms).
[11:32:42.751] <TB3> INFO: Test took 1160ms.
[11:32:42.754] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:32:43.059] <TB3> INFO: Expecting 2560 events.
[11:32:43.917] <TB3> INFO: 2560 events read in total (302ms).
[11:32:43.917] <TB3> INFO: Test took 1164ms.
[11:32:43.929] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:32:44.224] <TB3> INFO: Expecting 2560 events.
[11:32:45.087] <TB3> INFO: 2560 events read in total (306ms).
[11:32:45.087] <TB3> INFO: Test took 1158ms.
[11:32:45.089] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:32:45.393] <TB3> INFO: Expecting 2560 events.
[11:32:46.247] <TB3> INFO: 2560 events read in total (298ms).
[11:32:46.247] <TB3> INFO: Test took 1158ms.
[11:32:46.711] <TB3> INFO: PixTestPhOptimization::doTest() done, duration: 620 seconds
[11:32:46.711] <TB3> INFO: PH scale (per ROC): 87 91 93 87 80 92 87 81 88 97 94 90 86 91 79 89
[11:32:46.711] <TB3> INFO: PH offset (per ROC): 145 138 151 137 151 150 155 149 144 146 153 144 157 166 153 151
[11:32:46.715] <TB3> INFO: Decoding statistics:
[11:32:46.715] <TB3> INFO: General information:
[11:32:46.715] <TB3> INFO: 16bit words read: 91920
[11:32:46.715] <TB3> INFO: valid events total: 10240
[11:32:46.715] <TB3> INFO: empty events: 7740
[11:32:46.715] <TB3> INFO: valid events with pixels: 2500
[11:32:46.715] <TB3> INFO: valid pixel hits: 2500
[11:32:46.715] <TB3> INFO: Event errors: 0
[11:32:46.715] <TB3> INFO: start marker: 0
[11:32:46.715] <TB3> INFO: stop marker: 0
[11:32:46.715] <TB3> INFO: overflow: 0
[11:32:46.715] <TB3> INFO: invalid 5bit words: 0
[11:32:46.715] <TB3> INFO: invalid XOR eye diagram: 0
[11:32:46.715] <TB3> INFO: frame (failed synchr.): 0
[11:32:46.715] <TB3> INFO: idle data (no TBM trl): 0
[11:32:46.715] <TB3> INFO: no data (only TBM hdr): 0
[11:32:46.715] <TB3> INFO: TBM errors: 0
[11:32:46.715] <TB3> INFO: flawed TBM headers: 0
[11:32:46.715] <TB3> INFO: flawed TBM trailers: 0
[11:32:46.715] <TB3> INFO: event ID mismatches: 0
[11:32:46.715] <TB3> INFO: ROC errors: 0
[11:32:46.715] <TB3> INFO: missing ROC header(s): 0
[11:32:46.715] <TB3> INFO: misplaced readback start: 0
[11:32:46.715] <TB3> INFO: Pixel decoding errors: 0
[11:32:46.715] <TB3> INFO: pixel data incomplete: 0
[11:32:46.715] <TB3> INFO: pixel address: 0
[11:32:46.715] <TB3> INFO: pulse height fill bit: 0
[11:32:46.715] <TB3> INFO: buffer corruption: 0
[11:32:46.887] <TB3> INFO: ######################################################################
[11:32:46.887] <TB3> INFO: PixTestGainPedestal::fullTest() ntrig = 10
[11:32:46.887] <TB3> INFO: ######################################################################
[11:32:46.896] <TB3> INFO: scanning low vcal = 10
[11:32:47.157] <TB3> INFO: Expecting 41600 events.
[11:32:50.681] <TB3> INFO: 41600 events read in total (2968ms).
[11:32:50.681] <TB3> INFO: Test took 3784ms.
[11:32:50.683] <TB3> INFO: scanning low vcal = 20
[11:32:50.986] <TB3> INFO: Expecting 41600 events.
[11:32:54.524] <TB3> INFO: 41600 events read in total (2982ms).
[11:32:54.524] <TB3> INFO: Test took 3841ms.
[11:32:54.526] <TB3> INFO: scanning low vcal = 30
[11:32:54.829] <TB3> INFO: Expecting 41600 events.
[11:32:58.357] <TB3> INFO: 41600 events read in total (2971ms).
[11:32:58.357] <TB3> INFO: Test took 3831ms.
[11:32:58.359] <TB3> INFO: scanning low vcal = 40
[11:32:58.659] <TB3> INFO: Expecting 41600 events.
[11:33:02.791] <TB3> INFO: 41600 events read in total (3576ms).
[11:33:02.791] <TB3> INFO: Test took 4432ms.
[11:33:02.794] <TB3> INFO: scanning low vcal = 50
[11:33:03.053] <TB3> INFO: Expecting 41600 events.
[11:33:07.219] <TB3> INFO: 41600 events read in total (3610ms).
[11:33:07.219] <TB3> INFO: Test took 4425ms.
[11:33:07.222] <TB3> INFO: scanning low vcal = 60
[11:33:07.491] <TB3> INFO: Expecting 41600 events.
[11:33:11.678] <TB3> INFO: 41600 events read in total (3631ms).
[11:33:11.679] <TB3> INFO: Test took 4457ms.
[11:33:11.682] <TB3> INFO: scanning low vcal = 70
[11:33:11.953] <TB3> INFO: Expecting 41600 events.
[11:33:16.114] <TB3> INFO: 41600 events read in total (3605ms).
[11:33:16.115] <TB3> INFO: Test took 4433ms.
[11:33:16.117] <TB3> INFO: scanning low vcal = 80
[11:33:16.380] <TB3> INFO: Expecting 41600 events.
[11:33:20.552] <TB3> INFO: 41600 events read in total (3616ms).
[11:33:20.552] <TB3> INFO: Test took 4435ms.
[11:33:20.555] <TB3> INFO: scanning low vcal = 90
[11:33:20.815] <TB3> INFO: Expecting 41600 events.
[11:33:25.006] <TB3> INFO: 41600 events read in total (3635ms).
[11:33:25.006] <TB3> INFO: Test took 4451ms.
[11:33:25.010] <TB3> INFO: scanning low vcal = 100
[11:33:25.301] <TB3> INFO: Expecting 41600 events.
[11:33:29.487] <TB3> INFO: 41600 events read in total (3630ms).
[11:33:29.487] <TB3> INFO: Test took 4477ms.
[11:33:29.490] <TB3> INFO: scanning low vcal = 110
[11:33:29.773] <TB3> INFO: Expecting 41600 events.
[11:33:33.980] <TB3> INFO: 41600 events read in total (3651ms).
[11:33:33.980] <TB3> INFO: Test took 4490ms.
[11:33:33.983] <TB3> INFO: scanning low vcal = 120
[11:33:34.287] <TB3> INFO: Expecting 41600 events.
[11:33:38.453] <TB3> INFO: 41600 events read in total (3610ms).
[11:33:38.453] <TB3> INFO: Test took 4470ms.
[11:33:38.459] <TB3> INFO: scanning low vcal = 130
[11:33:38.721] <TB3> INFO: Expecting 41600 events.
[11:33:42.891] <TB3> INFO: 41600 events read in total (3614ms).
[11:33:42.892] <TB3> INFO: Test took 4433ms.
[11:33:42.895] <TB3> INFO: scanning low vcal = 140
[11:33:43.155] <TB3> INFO: Expecting 41600 events.
[11:33:47.332] <TB3> INFO: 41600 events read in total (3621ms).
[11:33:47.332] <TB3> INFO: Test took 4437ms.
[11:33:47.335] <TB3> INFO: scanning low vcal = 150
[11:33:47.611] <TB3> INFO: Expecting 41600 events.
[11:33:51.796] <TB3> INFO: 41600 events read in total (3629ms).
[11:33:51.797] <TB3> INFO: Test took 4462ms.
[11:33:51.799] <TB3> INFO: scanning low vcal = 160
[11:33:52.060] <TB3> INFO: Expecting 41600 events.
[11:33:56.288] <TB3> INFO: 41600 events read in total (3671ms).
[11:33:56.289] <TB3> INFO: Test took 4490ms.
[11:33:56.292] <TB3> INFO: scanning low vcal = 170
[11:33:56.552] <TB3> INFO: Expecting 41600 events.
[11:34:00.741] <TB3> INFO: 41600 events read in total (3633ms).
[11:34:00.742] <TB3> INFO: Test took 4450ms.
[11:34:00.749] <TB3> INFO: scanning low vcal = 180
[11:34:01.014] <TB3> INFO: Expecting 41600 events.
[11:34:05.198] <TB3> INFO: 41600 events read in total (3628ms).
[11:34:05.198] <TB3> INFO: Test took 4448ms.
[11:34:05.201] <TB3> INFO: scanning low vcal = 190
[11:34:05.479] <TB3> INFO: Expecting 41600 events.
[11:34:09.685] <TB3> INFO: 41600 events read in total (3649ms).
[11:34:09.685] <TB3> INFO: Test took 4484ms.
[11:34:09.688] <TB3> INFO: scanning low vcal = 200
[11:34:09.948] <TB3> INFO: Expecting 41600 events.
[11:34:14.141] <TB3> INFO: 41600 events read in total (3637ms).
[11:34:14.142] <TB3> INFO: Test took 4454ms.
[11:34:14.144] <TB3> INFO: scanning low vcal = 210
[11:34:14.406] <TB3> INFO: Expecting 41600 events.
[11:34:18.587] <TB3> INFO: 41600 events read in total (3625ms).
[11:34:18.587] <TB3> INFO: Test took 4443ms.
[11:34:18.590] <TB3> INFO: scanning low vcal = 220
[11:34:18.850] <TB3> INFO: Expecting 41600 events.
[11:34:23.022] <TB3> INFO: 41600 events read in total (3616ms).
[11:34:23.023] <TB3> INFO: Test took 4433ms.
[11:34:23.026] <TB3> INFO: scanning low vcal = 230
[11:34:23.294] <TB3> INFO: Expecting 41600 events.
[11:34:27.485] <TB3> INFO: 41600 events read in total (3635ms).
[11:34:27.486] <TB3> INFO: Test took 4460ms.
[11:34:27.488] <TB3> INFO: scanning low vcal = 240
[11:34:27.781] <TB3> INFO: Expecting 41600 events.
[11:34:31.965] <TB3> INFO: 41600 events read in total (3628ms).
[11:34:31.966] <TB3> INFO: Test took 4478ms.
[11:34:31.980] <TB3> INFO: scanning low vcal = 250
[11:34:32.252] <TB3> INFO: Expecting 41600 events.
[11:34:36.438] <TB3> INFO: 41600 events read in total (3630ms).
[11:34:36.438] <TB3> INFO: Test took 4458ms.
[11:34:36.442] <TB3> INFO: scanning high vcal = 30 (= 210 in low range)
[11:34:36.699] <TB3> INFO: Expecting 41600 events.
[11:34:40.901] <TB3> INFO: 41600 events read in total (3646ms).
[11:34:40.902] <TB3> INFO: Test took 4460ms.
[11:34:40.904] <TB3> INFO: scanning high vcal = 50 (= 350 in low range)
[11:34:41.163] <TB3> INFO: Expecting 41600 events.
[11:34:45.338] <TB3> INFO: 41600 events read in total (3618ms).
[11:34:45.338] <TB3> INFO: Test took 4434ms.
[11:34:45.341] <TB3> INFO: scanning high vcal = 70 (= 490 in low range)
[11:34:45.600] <TB3> INFO: Expecting 41600 events.
[11:34:49.775] <TB3> INFO: 41600 events read in total (3619ms).
[11:34:49.775] <TB3> INFO: Test took 4434ms.
[11:34:49.778] <TB3> INFO: scanning high vcal = 90 (= 630 in low range)
[11:34:50.037] <TB3> INFO: Expecting 41600 events.
[11:34:54.218] <TB3> INFO: 41600 events read in total (3625ms).
[11:34:54.219] <TB3> INFO: Test took 4441ms.
[11:34:54.221] <TB3> INFO: scanning high vcal = 200 (= 1400 in low range)
[11:34:54.481] <TB3> INFO: Expecting 41600 events.
[11:34:58.682] <TB3> INFO: 41600 events read in total (3645ms).
[11:34:58.682] <TB3> INFO: Test took 4461ms.
[11:34:59.121] <TB3> INFO: PixTestGainPedestal::measure() done
[11:35:30.293] <TB3> INFO: PixTestGainPedestal::fit() done
[11:35:30.293] <TB3> INFO: non-linearity mean: 0.948 0.947 0.952 0.956 0.953 0.956 0.951 0.956 0.955 0.953 0.954 0.960 0.962 0.950 0.958 0.953
[11:35:30.293] <TB3> INFO: non-linearity RMS: 0.005 0.005 0.006 0.005 0.007 0.005 0.007 0.005 0.005 0.006 0.005 0.006 0.006 0.007 0.006 0.006
[11:35:30.294] <TB3> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2302_FullQualification_2016-08-29_11h59m_1472464790//000_FulltestPxar_m20//phCalibrationFitErr35_C0.dat
[11:35:30.312] <TB3> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2302_FullQualification_2016-08-29_11h59m_1472464790//000_FulltestPxar_m20//phCalibrationFitErr35_C1.dat
[11:35:30.330] <TB3> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2302_FullQualification_2016-08-29_11h59m_1472464790//000_FulltestPxar_m20//phCalibrationFitErr35_C2.dat
[11:35:30.348] <TB3> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2302_FullQualification_2016-08-29_11h59m_1472464790//000_FulltestPxar_m20//phCalibrationFitErr35_C3.dat
[11:35:30.366] <TB3> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2302_FullQualification_2016-08-29_11h59m_1472464790//000_FulltestPxar_m20//phCalibrationFitErr35_C4.dat
[11:35:30.384] <TB3> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2302_FullQualification_2016-08-29_11h59m_1472464790//000_FulltestPxar_m20//phCalibrationFitErr35_C5.dat
[11:35:30.402] <TB3> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2302_FullQualification_2016-08-29_11h59m_1472464790//000_FulltestPxar_m20//phCalibrationFitErr35_C6.dat
[11:35:30.420] <TB3> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2302_FullQualification_2016-08-29_11h59m_1472464790//000_FulltestPxar_m20//phCalibrationFitErr35_C7.dat
[11:35:30.438] <TB3> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2302_FullQualification_2016-08-29_11h59m_1472464790//000_FulltestPxar_m20//phCalibrationFitErr35_C8.dat
[11:35:30.456] <TB3> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2302_FullQualification_2016-08-29_11h59m_1472464790//000_FulltestPxar_m20//phCalibrationFitErr35_C9.dat
[11:35:30.474] <TB3> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2302_FullQualification_2016-08-29_11h59m_1472464790//000_FulltestPxar_m20//phCalibrationFitErr35_C10.dat
[11:35:30.492] <TB3> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2302_FullQualification_2016-08-29_11h59m_1472464790//000_FulltestPxar_m20//phCalibrationFitErr35_C11.dat
[11:35:30.510] <TB3> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2302_FullQualification_2016-08-29_11h59m_1472464790//000_FulltestPxar_m20//phCalibrationFitErr35_C12.dat
[11:35:30.528] <TB3> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2302_FullQualification_2016-08-29_11h59m_1472464790//000_FulltestPxar_m20//phCalibrationFitErr35_C13.dat
[11:35:30.546] <TB3> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2302_FullQualification_2016-08-29_11h59m_1472464790//000_FulltestPxar_m20//phCalibrationFitErr35_C14.dat
[11:35:30.564] <TB3> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2302_FullQualification_2016-08-29_11h59m_1472464790//000_FulltestPxar_m20//phCalibrationFitErr35_C15.dat
[11:35:30.582] <TB3> INFO: PixTestGainPedestal::fullTest() done, duration: 163 seconds
[11:35:30.582] <TB3> INFO: Decoding statistics:
[11:35:30.582] <TB3> INFO: General information:
[11:35:30.582] <TB3> INFO: 16bit words read: 2662400
[11:35:30.582] <TB3> INFO: valid events total: 166400
[11:35:30.582] <TB3> INFO: empty events: 0
[11:35:30.582] <TB3> INFO: valid events with pixels: 166400
[11:35:30.582] <TB3> INFO: valid pixel hits: 665585
[11:35:30.582] <TB3> INFO: Event errors: 0
[11:35:30.582] <TB3> INFO: start marker: 0
[11:35:30.582] <TB3> INFO: stop marker: 0
[11:35:30.582] <TB3> INFO: overflow: 0
[11:35:30.582] <TB3> INFO: invalid 5bit words: 0
[11:35:30.582] <TB3> INFO: invalid XOR eye diagram: 0
[11:35:30.582] <TB3> INFO: frame (failed synchr.): 0
[11:35:30.582] <TB3> INFO: idle data (no TBM trl): 0
[11:35:30.582] <TB3> INFO: no data (only TBM hdr): 0
[11:35:30.582] <TB3> INFO: TBM errors: 0
[11:35:30.582] <TB3> INFO: flawed TBM headers: 0
[11:35:30.582] <TB3> INFO: flawed TBM trailers: 0
[11:35:30.582] <TB3> INFO: event ID mismatches: 0
[11:35:30.582] <TB3> INFO: ROC errors: 0
[11:35:30.582] <TB3> INFO: missing ROC header(s): 0
[11:35:30.582] <TB3> INFO: misplaced readback start: 0
[11:35:30.582] <TB3> INFO: Pixel decoding errors: 0
[11:35:30.582] <TB3> INFO: pixel data incomplete: 0
[11:35:30.582] <TB3> INFO: pixel address: 0
[11:35:30.582] <TB3> INFO: pulse height fill bit: 0
[11:35:30.582] <TB3> INFO: buffer corruption: 0
[11:35:30.590] <TB3> INFO: readReadbackCal: /usr/local/coldboxDATA/M2302_FullQualification_2016-08-29_11h59m_1472464790//000_FulltestPxar_m20//readbackCal_C0.dat .. /usr/local/coldboxDATA/M2302_FullQualification_2016-08-29_11h59m_1472464790//000_FulltestPxar_m20//readbackCal_C15.dat
[11:35:30.591] <TB3> INFO: ######################################################################
[11:35:30.591] <TB3> INFO: PixTestReadback::doTest()
[11:35:30.591] <TB3> INFO: ######################################################################
[11:35:30.591] <TB3> INFO: ----------------------------------------------------------------------
[11:35:30.591] <TB3> INFO: PixTestReadback::CalibrateVd()
[11:35:30.591] <TB3> INFO: ----------------------------------------------------------------------
[11:35:40.070] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2302_FullQualification_2016-08-29_11h59m_1472464790//000_FulltestPxar_m20//readbackCal_C0.dat
[11:35:40.070] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2302_FullQualification_2016-08-29_11h59m_1472464790//000_FulltestPxar_m20//readbackCal_C1.dat
[11:35:40.070] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2302_FullQualification_2016-08-29_11h59m_1472464790//000_FulltestPxar_m20//readbackCal_C2.dat
[11:35:40.070] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2302_FullQualification_2016-08-29_11h59m_1472464790//000_FulltestPxar_m20//readbackCal_C3.dat
[11:35:40.070] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2302_FullQualification_2016-08-29_11h59m_1472464790//000_FulltestPxar_m20//readbackCal_C4.dat
[11:35:40.070] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2302_FullQualification_2016-08-29_11h59m_1472464790//000_FulltestPxar_m20//readbackCal_C5.dat
[11:35:40.070] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2302_FullQualification_2016-08-29_11h59m_1472464790//000_FulltestPxar_m20//readbackCal_C6.dat
[11:35:40.071] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2302_FullQualification_2016-08-29_11h59m_1472464790//000_FulltestPxar_m20//readbackCal_C7.dat
[11:35:40.071] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2302_FullQualification_2016-08-29_11h59m_1472464790//000_FulltestPxar_m20//readbackCal_C8.dat
[11:35:40.071] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2302_FullQualification_2016-08-29_11h59m_1472464790//000_FulltestPxar_m20//readbackCal_C9.dat
[11:35:40.071] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2302_FullQualification_2016-08-29_11h59m_1472464790//000_FulltestPxar_m20//readbackCal_C10.dat
[11:35:40.071] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2302_FullQualification_2016-08-29_11h59m_1472464790//000_FulltestPxar_m20//readbackCal_C11.dat
[11:35:40.071] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2302_FullQualification_2016-08-29_11h59m_1472464790//000_FulltestPxar_m20//readbackCal_C12.dat
[11:35:40.071] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2302_FullQualification_2016-08-29_11h59m_1472464790//000_FulltestPxar_m20//readbackCal_C13.dat
[11:35:40.071] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2302_FullQualification_2016-08-29_11h59m_1472464790//000_FulltestPxar_m20//readbackCal_C14.dat
[11:35:40.071] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2302_FullQualification_2016-08-29_11h59m_1472464790//000_FulltestPxar_m20//readbackCal_C15.dat
[11:35:40.099] <TB3> INFO: PixTestPattern:: pg_setup set to default.
[11:35:40.099] <TB3> INFO: ----------------------------------------------------------------------
[11:35:40.099] <TB3> INFO: PixTestReadback::CalibrateVa()
[11:35:40.099] <TB3> INFO: ----------------------------------------------------------------------
[11:35:49.577] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2302_FullQualification_2016-08-29_11h59m_1472464790//000_FulltestPxar_m20//readbackCal_C0.dat
[11:35:49.578] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2302_FullQualification_2016-08-29_11h59m_1472464790//000_FulltestPxar_m20//readbackCal_C1.dat
[11:35:49.578] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2302_FullQualification_2016-08-29_11h59m_1472464790//000_FulltestPxar_m20//readbackCal_C2.dat
[11:35:49.578] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2302_FullQualification_2016-08-29_11h59m_1472464790//000_FulltestPxar_m20//readbackCal_C3.dat
[11:35:49.578] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2302_FullQualification_2016-08-29_11h59m_1472464790//000_FulltestPxar_m20//readbackCal_C4.dat
[11:35:49.578] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2302_FullQualification_2016-08-29_11h59m_1472464790//000_FulltestPxar_m20//readbackCal_C5.dat
[11:35:49.578] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2302_FullQualification_2016-08-29_11h59m_1472464790//000_FulltestPxar_m20//readbackCal_C6.dat
[11:35:49.578] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2302_FullQualification_2016-08-29_11h59m_1472464790//000_FulltestPxar_m20//readbackCal_C7.dat
[11:35:49.578] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2302_FullQualification_2016-08-29_11h59m_1472464790//000_FulltestPxar_m20//readbackCal_C8.dat
[11:35:49.578] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2302_FullQualification_2016-08-29_11h59m_1472464790//000_FulltestPxar_m20//readbackCal_C9.dat
[11:35:49.578] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2302_FullQualification_2016-08-29_11h59m_1472464790//000_FulltestPxar_m20//readbackCal_C10.dat
[11:35:49.578] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2302_FullQualification_2016-08-29_11h59m_1472464790//000_FulltestPxar_m20//readbackCal_C11.dat
[11:35:49.579] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2302_FullQualification_2016-08-29_11h59m_1472464790//000_FulltestPxar_m20//readbackCal_C12.dat
[11:35:49.579] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2302_FullQualification_2016-08-29_11h59m_1472464790//000_FulltestPxar_m20//readbackCal_C13.dat
[11:35:49.579] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2302_FullQualification_2016-08-29_11h59m_1472464790//000_FulltestPxar_m20//readbackCal_C14.dat
[11:35:49.579] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2302_FullQualification_2016-08-29_11h59m_1472464790//000_FulltestPxar_m20//readbackCal_C15.dat
[11:35:49.607] <TB3> INFO: PixTestPattern:: pg_setup set to default.
[11:35:49.607] <TB3> INFO: ----------------------------------------------------------------------
[11:35:49.607] <TB3> INFO: PixTestReadback::readbackVbg()
[11:35:49.607] <TB3> INFO: ----------------------------------------------------------------------
[11:35:56.902] <TB3> INFO: PixTestPattern:: pg_setup set to default.
[11:35:56.902] <TB3> INFO: ----------------------------------------------------------------------
[11:35:56.902] <TB3> INFO: PixTestReadback::getCalibratedVbg()
[11:35:56.902] <TB3> INFO: ----------------------------------------------------------------------
[11:35:56.902] <TB3> INFO: Vbg will be calibrated using Vd calibration
[11:35:56.902] <TB3> INFO: /*/*/*/*::: ROC 0: uncalibrated Vbg = 157.8calibrated Vbg = 1.21268 :::*/*/*/*/
[11:35:56.902] <TB3> INFO: /*/*/*/*::: ROC 1: uncalibrated Vbg = 148.9calibrated Vbg = 1.20629 :::*/*/*/*/
[11:35:56.902] <TB3> INFO: /*/*/*/*::: ROC 2: uncalibrated Vbg = 148.7calibrated Vbg = 1.21434 :::*/*/*/*/
[11:35:56.902] <TB3> INFO: /*/*/*/*::: ROC 3: uncalibrated Vbg = 151.4calibrated Vbg = 1.2194 :::*/*/*/*/
[11:35:56.902] <TB3> INFO: /*/*/*/*::: ROC 4: uncalibrated Vbg = 150.5calibrated Vbg = 1.22176 :::*/*/*/*/
[11:35:56.902] <TB3> INFO: /*/*/*/*::: ROC 5: uncalibrated Vbg = 151.2calibrated Vbg = 1.21942 :::*/*/*/*/
[11:35:56.902] <TB3> INFO: /*/*/*/*::: ROC 6: uncalibrated Vbg = 147.4calibrated Vbg = 1.21977 :::*/*/*/*/
[11:35:56.902] <TB3> INFO: /*/*/*/*::: ROC 7: uncalibrated Vbg = 147.4calibrated Vbg = 1.2181 :::*/*/*/*/
[11:35:56.903] <TB3> INFO: /*/*/*/*::: ROC 8: uncalibrated Vbg = 155calibrated Vbg = 1.22555 :::*/*/*/*/
[11:35:56.903] <TB3> INFO: /*/*/*/*::: ROC 9: uncalibrated Vbg = 145.5calibrated Vbg = 1.22629 :::*/*/*/*/
[11:35:56.903] <TB3> INFO: /*/*/*/*::: ROC 10: uncalibrated Vbg = 162.9calibrated Vbg = 1.22512 :::*/*/*/*/
[11:35:56.903] <TB3> INFO: /*/*/*/*::: ROC 11: uncalibrated Vbg = 159.2calibrated Vbg = 1.22341 :::*/*/*/*/
[11:35:56.903] <TB3> INFO: /*/*/*/*::: ROC 12: uncalibrated Vbg = 153.2calibrated Vbg = 1.21455 :::*/*/*/*/
[11:35:56.903] <TB3> INFO: /*/*/*/*::: ROC 13: uncalibrated Vbg = 156.5calibrated Vbg = 1.21577 :::*/*/*/*/
[11:35:56.903] <TB3> INFO: /*/*/*/*::: ROC 14: uncalibrated Vbg = 158.7calibrated Vbg = 1.21159 :::*/*/*/*/
[11:35:56.903] <TB3> INFO: /*/*/*/*::: ROC 15: uncalibrated Vbg = 153calibrated Vbg = 1.21602 :::*/*/*/*/
[11:35:56.904] <TB3> INFO: ----------------------------------------------------------------------
[11:35:56.904] <TB3> INFO: PixTestReadback::CalibrateIa()
[11:35:56.904] <TB3> INFO: ----------------------------------------------------------------------
[11:38:32.219] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2302_FullQualification_2016-08-29_11h59m_1472464790//000_FulltestPxar_m20//readbackCal_C0.dat
[11:38:32.219] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2302_FullQualification_2016-08-29_11h59m_1472464790//000_FulltestPxar_m20//readbackCal_C1.dat
[11:38:32.219] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2302_FullQualification_2016-08-29_11h59m_1472464790//000_FulltestPxar_m20//readbackCal_C2.dat
[11:38:32.219] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2302_FullQualification_2016-08-29_11h59m_1472464790//000_FulltestPxar_m20//readbackCal_C3.dat
[11:38:32.220] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2302_FullQualification_2016-08-29_11h59m_1472464790//000_FulltestPxar_m20//readbackCal_C4.dat
[11:38:32.220] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2302_FullQualification_2016-08-29_11h59m_1472464790//000_FulltestPxar_m20//readbackCal_C5.dat
[11:38:32.220] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2302_FullQualification_2016-08-29_11h59m_1472464790//000_FulltestPxar_m20//readbackCal_C6.dat
[11:38:32.220] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2302_FullQualification_2016-08-29_11h59m_1472464790//000_FulltestPxar_m20//readbackCal_C7.dat
[11:38:32.220] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2302_FullQualification_2016-08-29_11h59m_1472464790//000_FulltestPxar_m20//readbackCal_C8.dat
[11:38:32.220] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2302_FullQualification_2016-08-29_11h59m_1472464790//000_FulltestPxar_m20//readbackCal_C9.dat
[11:38:32.220] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2302_FullQualification_2016-08-29_11h59m_1472464790//000_FulltestPxar_m20//readbackCal_C10.dat
[11:38:32.220] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2302_FullQualification_2016-08-29_11h59m_1472464790//000_FulltestPxar_m20//readbackCal_C11.dat
[11:38:32.220] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2302_FullQualification_2016-08-29_11h59m_1472464790//000_FulltestPxar_m20//readbackCal_C12.dat
[11:38:32.220] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2302_FullQualification_2016-08-29_11h59m_1472464790//000_FulltestPxar_m20//readbackCal_C13.dat
[11:38:32.220] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2302_FullQualification_2016-08-29_11h59m_1472464790//000_FulltestPxar_m20//readbackCal_C14.dat
[11:38:32.221] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2302_FullQualification_2016-08-29_11h59m_1472464790//000_FulltestPxar_m20//readbackCal_C15.dat
[11:38:32.250] <TB3> INFO: PixTestPattern:: pg_setup set to default.
[11:38:32.252] <TB3> INFO: PixTestReadback::doTest() done
[11:38:32.252] <TB3> INFO: Decoding statistics:
[11:38:32.252] <TB3> INFO: General information:
[11:38:32.252] <TB3> INFO: 16bit words read: 1024
[11:38:32.252] <TB3> INFO: valid events total: 128
[11:38:32.252] <TB3> INFO: empty events: 128
[11:38:32.252] <TB3> INFO: valid events with pixels: 0
[11:38:32.252] <TB3> INFO: valid pixel hits: 0
[11:38:32.252] <TB3> INFO: Event errors: 0
[11:38:32.252] <TB3> INFO: start marker: 0
[11:38:32.252] <TB3> INFO: stop marker: 0
[11:38:32.252] <TB3> INFO: overflow: 0
[11:38:32.252] <TB3> INFO: invalid 5bit words: 0
[11:38:32.252] <TB3> INFO: invalid XOR eye diagram: 0
[11:38:32.252] <TB3> INFO: frame (failed synchr.): 0
[11:38:32.252] <TB3> INFO: idle data (no TBM trl): 0
[11:38:32.252] <TB3> INFO: no data (only TBM hdr): 0
[11:38:32.252] <TB3> INFO: TBM errors: 0
[11:38:32.252] <TB3> INFO: flawed TBM headers: 0
[11:38:32.252] <TB3> INFO: flawed TBM trailers: 0
[11:38:32.252] <TB3> INFO: event ID mismatches: 0
[11:38:32.252] <TB3> INFO: ROC errors: 0
[11:38:32.252] <TB3> INFO: missing ROC header(s): 0
[11:38:32.252] <TB3> INFO: misplaced readback start: 0
[11:38:32.252] <TB3> INFO: Pixel decoding errors: 0
[11:38:32.252] <TB3> INFO: pixel data incomplete: 0
[11:38:32.252] <TB3> INFO: pixel address: 0
[11:38:32.252] <TB3> INFO: pulse height fill bit: 0
[11:38:32.252] <TB3> INFO: buffer corruption: 0
[11:38:32.276] <TB3> INFO: Decoding statistics:
[11:38:32.276] <TB3> INFO: General information:
[11:38:32.276] <TB3> INFO: 16bit words read: 2755344
[11:38:32.276] <TB3> INFO: valid events total: 176768
[11:38:32.276] <TB3> INFO: empty events: 7868
[11:38:32.276] <TB3> INFO: valid events with pixels: 168900
[11:38:32.276] <TB3> INFO: valid pixel hits: 668085
[11:38:32.276] <TB3> INFO: Event errors: 0
[11:38:32.276] <TB3> INFO: start marker: 0
[11:38:32.276] <TB3> INFO: stop marker: 0
[11:38:32.276] <TB3> INFO: overflow: 0
[11:38:32.276] <TB3> INFO: invalid 5bit words: 0
[11:38:32.276] <TB3> INFO: invalid XOR eye diagram: 0
[11:38:32.276] <TB3> INFO: frame (failed synchr.): 0
[11:38:32.276] <TB3> INFO: idle data (no TBM trl): 0
[11:38:32.276] <TB3> INFO: no data (only TBM hdr): 0
[11:38:32.276] <TB3> INFO: TBM errors: 0
[11:38:32.276] <TB3> INFO: flawed TBM headers: 0
[11:38:32.276] <TB3> INFO: flawed TBM trailers: 0
[11:38:32.276] <TB3> INFO: event ID mismatches: 0
[11:38:32.276] <TB3> INFO: ROC errors: 0
[11:38:32.276] <TB3> INFO: missing ROC header(s): 0
[11:38:32.276] <TB3> INFO: misplaced readback start: 0
[11:38:32.276] <TB3> INFO: Pixel decoding errors: 0
[11:38:32.276] <TB3> INFO: pixel data incomplete: 0
[11:38:32.276] <TB3> INFO: pixel address: 0
[11:38:32.276] <TB3> INFO: pulse height fill bit: 0
[11:38:32.276] <TB3> INFO: buffer corruption: 0
[11:38:32.276] <TB3> INFO: enter test to run
[11:38:32.276] <TB3> INFO: test: exit no parameter change
[11:38:32.487] <TB3> QUIET: Connection to board 170 closed.
[11:38:32.567] <TB3> INFO: pXar: this is the end, my friend