[10:11:45.898] <TB2> INFO: *** Welcome to pxar ***
[10:11:45.898] <TB2> INFO: *** Today: 2016/08/29
[10:11:46.008] <TB2> INFO: *** Version: fe94
[10:11:46.008] <TB2> INFO: readRocDacs: /usr/local/coldboxDATA/M2301_FullQualification_2016-08-29_11h59m_1472464790//000_FulltestPxar_m20//dacParameters_C0.dat .. /usr/local/coldboxDATA/M2301_FullQualification_2016-08-29_11h59m_1472464790//000_FulltestPxar_m20//dacParameters_C15.dat
[10:11:46.010] <TB2> INFO: readTbmDacs: /usr/local/coldboxDATA/M2301_FullQualification_2016-08-29_11h59m_1472464790//000_FulltestPxar_m20//tbmParameters_C0a.dat .. /usr/local/coldboxDATA/M2301_FullQualification_2016-08-29_11h59m_1472464790//000_FulltestPxar_m20//tbmParameters_C0b.dat
[10:11:46.010] <TB2> INFO: readMaskFile: /usr/local/coldboxDATA/M2301_FullQualification_2016-08-29_11h59m_1472464790//000_FulltestPxar_m20//defaultMaskFile.dat
[10:11:46.010] <TB2> INFO: readTrimFile: /usr/local/coldboxDATA/M2301_FullQualification_2016-08-29_11h59m_1472464790//000_FulltestPxar_m20//trimParameters_C0.dat .. /usr/local/coldboxDATA/M2301_FullQualification_2016-08-29_11h59m_1472464790//000_FulltestPxar_m20//trimParameters_C15.dat
[10:11:46.082] <TB2> INFO: clk: 4
[10:11:46.082] <TB2> INFO: ctr: 4
[10:11:46.082] <TB2> INFO: sda: 19
[10:11:46.082] <TB2> INFO: tin: 9
[10:11:46.082] <TB2> INFO: level: 15
[10:11:46.082] <TB2> INFO: triggerdelay: 0
[10:11:46.082] <TB2> QUIET: Instanciating API for pxar v2.7.5+16~g4df2ad8
[10:11:46.082] <TB2> INFO: Log level: INFO
[10:11:46.089] <TB2> INFO: Found DTB DTB_WXC55Z
[10:11:46.101] <TB2> QUIET: Connection to board DTB_WXC55Z opened.
[10:11:46.103] <TB2> INFO: DTB startup information
--- DTB info------------------------------------------
Board id: 156
HW version: DTB1.2
FW version: 4.4
SW version: 4.6
Options:
USB id: DTB_WXC55Z
MAC address: 40D85511809C
Hostname: pixelDTB156
Comment:
------------------------------------------------------
[10:11:46.105] <TB2> INFO: RPC call hashes of host and DTB match: 484264910
[10:11:47.662] <TB2> INFO: DUT info:
[10:11:47.662] <TB2> INFO: The DUT currently contains the following objects:
[10:11:47.662] <TB2> INFO: 2 TBM Cores tbm09c (2 ON)
[10:11:47.662] <TB2> INFO: TBM Core alpha (0): 7 registers set
[10:11:47.662] <TB2> INFO: TBM Core beta (1): 7 registers set
[10:11:47.662] <TB2> INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[10:11:47.662] <TB2> INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[10:11:47.662] <TB2> INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[10:11:47.662] <TB2> INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[10:11:47.662] <TB2> INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[10:11:47.662] <TB2> INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[10:11:47.662] <TB2> INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[10:11:47.662] <TB2> INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[10:11:47.662] <TB2> INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[10:11:47.662] <TB2> INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[10:11:47.663] <TB2> INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[10:11:47.663] <TB2> INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[10:11:47.663] <TB2> INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[10:11:47.663] <TB2> INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[10:11:47.663] <TB2> INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[10:11:47.663] <TB2> INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[10:11:47.663] <TB2> INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[10:11:48.064] <TB2> INFO: enter 'restricted' command line mode
[10:11:48.064] <TB2> INFO: enter test to run
[10:11:48.064] <TB2> INFO: test: pretest no parameter change
[10:11:48.064] <TB2> INFO: running: pretest
[10:11:48.072] <TB2> INFO: ----------------------------------------------------------------------
[10:11:48.072] <TB2> INFO: PixTestPretest::programROC()
[10:11:48.073] <TB2> INFO: ----------------------------------------------------------------------
[10:12:06.088] <TB2> INFO: PixTestPretest::programROC() done: ROCs are all programmable
[10:12:06.088] <TB2> INFO: IA differences per ROC: 20.1 18.5 18.5 20.1 17.7 19.3 18.5 17.7 20.1 19.3 19.3 20.1 19.3 19.3 20.9 17.7
[10:12:06.190] <TB2> INFO: enter test to run
[10:12:06.190] <TB2> INFO: test: pretest no parameter change
[10:12:06.190] <TB2> INFO: running: pretest
[10:12:06.191] <TB2> INFO: ----------------------------------------------------------------------
[10:12:06.191] <TB2> INFO: PixTestPretest::setVana() target Ia = 24 mA/ROC
[10:12:06.191] <TB2> INFO: ----------------------------------------------------------------------
[10:12:13.189] <TB2> INFO: PixTestPretest::setVana() done, Module Ia 382.7 mA = 23.9187 mA/ROC
[10:12:13.189] <TB2> INFO: i(loss) [mA/ROC]: 19.3 19.3 20.1 19.3 19.3 19.3 19.3 20.1 19.3 18.5 19.3 19.3 19.3 19.3 19.3 19.3
[10:12:13.223] <TB2> INFO: enter test to run
[10:12:13.223] <TB2> INFO: test: pretest no parameter change
[10:12:13.223] <TB2> INFO: running: pretest
[10:12:13.223] <TB2> INFO: ----------------------------------------------------------------------
[10:12:13.223] <TB2> INFO: PixTestPretest::findTiming()
[10:12:13.223] <TB2> INFO: ----------------------------------------------------------------------
[10:12:13.223] <TB2> INFO: PixTestCmd::init()

[10:13:44.173] <TB2> INFO: TBM phases: 160MHz: 7, 400MHz: 6, TBM delays: ROC(0/1):5, header/trailer: 1, token: 1
[10:13:44.173] <TB2> INFO: (success/tries = 100/100), width = 4
[10:13:44.174] <TB2> INFO: enter test to run
[10:13:44.174] <TB2> INFO: test: pretest no parameter change
[10:13:44.174] <TB2> INFO: running: pretest
[10:13:44.176] <TB2> INFO: ----------------------------------------------------------------------
[10:13:44.176] <TB2> INFO: PixTestPretest::findWorkingPixel()
[10:13:44.176] <TB2> INFO: ----------------------------------------------------------------------
[10:13:44.270] <TB2> INFO: Expecting 231680 events.


[10:13:54.604] <TB2> INFO: 231680 events read in total (9778ms).
[10:13:54.608] <TB2> INFO: Test took 10427ms.
[10:13:54.840] <TB2> INFO: Found working pixel in all ROCs: col/row = 12/22
[10:13:54.895] <TB2> INFO: enter test to run
[10:13:54.895] <TB2> INFO: test: pretest no parameter change
[10:13:54.895] <TB2> INFO: running: pretest
[10:13:54.896] <TB2> INFO: ----------------------------------------------------------------------
[10:13:54.896] <TB2> INFO: PixTestPretest::setVthrCompCalDel()
[10:13:54.896] <TB2> INFO: ----------------------------------------------------------------------
[10:13:54.987] <TB2> INFO: Expecting 231680 events.
[10:14:05.317] <TB2> INFO: 231680 events read in total (9774ms).
[10:14:05.321] <TB2> INFO: Test took 10422ms.
[10:14:05.571] <TB2> INFO: PixTestPretest::setVthrCompCalDel() done
[10:14:05.571] <TB2> INFO: CalDel: 148 130 134 134 154 155 151 142 137 147 159 151 149 138 180 139
[10:14:05.571] <TB2> INFO: VthrComp: 51 51 51 51 51 51 51 51 51 51 51 51 51 51 51 51
[10:14:05.613] <TB2> INFO: enter test to run
[10:14:05.613] <TB2> INFO: test: pretest no parameter change
[10:14:05.613] <TB2> INFO: running: pretest
[10:14:05.613] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2301_FullQualification_2016-08-29_11h59m_1472464790//000_FulltestPxar_m20//dacParameters_C0.dat
[10:14:05.613] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2301_FullQualification_2016-08-29_11h59m_1472464790//000_FulltestPxar_m20//dacParameters_C1.dat
[10:14:05.613] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2301_FullQualification_2016-08-29_11h59m_1472464790//000_FulltestPxar_m20//dacParameters_C2.dat
[10:14:05.613] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2301_FullQualification_2016-08-29_11h59m_1472464790//000_FulltestPxar_m20//dacParameters_C3.dat
[10:14:05.614] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2301_FullQualification_2016-08-29_11h59m_1472464790//000_FulltestPxar_m20//dacParameters_C4.dat
[10:14:05.614] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2301_FullQualification_2016-08-29_11h59m_1472464790//000_FulltestPxar_m20//dacParameters_C5.dat
[10:14:05.614] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2301_FullQualification_2016-08-29_11h59m_1472464790//000_FulltestPxar_m20//dacParameters_C6.dat
[10:14:05.614] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2301_FullQualification_2016-08-29_11h59m_1472464790//000_FulltestPxar_m20//dacParameters_C7.dat
[10:14:05.614] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2301_FullQualification_2016-08-29_11h59m_1472464790//000_FulltestPxar_m20//dacParameters_C8.dat
[10:14:05.614] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2301_FullQualification_2016-08-29_11h59m_1472464790//000_FulltestPxar_m20//dacParameters_C9.dat
[10:14:05.614] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2301_FullQualification_2016-08-29_11h59m_1472464790//000_FulltestPxar_m20//dacParameters_C10.dat
[10:14:05.614] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2301_FullQualification_2016-08-29_11h59m_1472464790//000_FulltestPxar_m20//dacParameters_C11.dat
[10:14:05.615] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2301_FullQualification_2016-08-29_11h59m_1472464790//000_FulltestPxar_m20//dacParameters_C12.dat
[10:14:05.615] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2301_FullQualification_2016-08-29_11h59m_1472464790//000_FulltestPxar_m20//dacParameters_C13.dat
[10:14:05.615] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2301_FullQualification_2016-08-29_11h59m_1472464790//000_FulltestPxar_m20//dacParameters_C14.dat
[10:14:05.615] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2301_FullQualification_2016-08-29_11h59m_1472464790//000_FulltestPxar_m20//dacParameters_C15.dat
[10:14:05.615] <TB2> INFO: enter test to run
[10:14:05.615] <TB2> INFO: test: fulltest no parameter change
[10:14:05.615] <TB2> INFO: running: fulltest
[10:14:05.615] <TB2> INFO: ######################################################################
[10:14:05.615] <TB2> INFO: PixTestFullTest::doTest()
[10:14:05.615] <TB2> INFO: ######################################################################
[10:14:05.616] <TB2> INFO: ######################################################################
[10:14:05.616] <TB2> INFO: PixTestAlive::doTest()
[10:14:05.616] <TB2> INFO: ######################################################################
[10:14:05.618] <TB2> INFO: ----------------------------------------------------------------------
[10:14:05.618] <TB2> INFO: PixTestAlive::aliveTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[10:14:05.618] <TB2> INFO: ----------------------------------------------------------------------
[10:14:05.884] <TB2> INFO: Expecting 41600 events.
[10:14:09.689] <TB2> INFO: 41600 events read in total (3249ms).
[10:14:09.690] <TB2> INFO: Test took 4071ms.
[10:14:09.921] <TB2> INFO: PixTestAlive::aliveTest() done
[10:14:09.921] <TB2> INFO: number of dead pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 4 0 0
[10:14:09.922] <TB2> INFO: ----------------------------------------------------------------------
[10:14:09.922] <TB2> INFO: PixTestAlive::maskTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[10:14:09.923] <TB2> INFO: ----------------------------------------------------------------------
[10:14:10.199] <TB2> INFO: Expecting 41600 events.
[10:14:13.158] <TB2> INFO: 41600 events read in total (2403ms).
[10:14:13.158] <TB2> INFO: Test took 3234ms.
[10:14:13.159] <TB2> INFO: mask vs. old pixelAlive PixelAlive_C0_V0 .. PixelAlive_C15_V0
[10:14:13.408] <TB2> INFO: PixTestAlive::maskTest() done
[10:14:13.408] <TB2> INFO: number of mask-defect pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[10:14:13.410] <TB2> INFO: ----------------------------------------------------------------------
[10:14:13.410] <TB2> INFO: PixTestAlive::addressDecodingTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[10:14:13.410] <TB2> INFO: ----------------------------------------------------------------------
[10:14:13.678] <TB2> INFO: Expecting 41600 events.
[10:14:17.478] <TB2> INFO: 41600 events read in total (3244ms).
[10:14:17.479] <TB2> INFO: Test took 4068ms.
[10:14:17.704] <TB2> INFO: PixTestAlive::addressDecodingTest() done
[10:14:17.704] <TB2> INFO: number of address-decoding pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[10:14:17.704] <TB2> INFO: PixTestAlive::doTest() done, duration: 12 seconds
[10:14:17.704] <TB2> INFO: Decoding statistics:
[10:14:17.704] <TB2> INFO: General information:
[10:14:17.704] <TB2> INFO: 16bit words read: 0
[10:14:17.704] <TB2> INFO: valid events total: 0
[10:14:17.704] <TB2> INFO: empty events: 0
[10:14:17.704] <TB2> INFO: valid events with pixels: 0
[10:14:17.704] <TB2> INFO: valid pixel hits: 0
[10:14:17.704] <TB2> INFO: Event errors: 0
[10:14:17.704] <TB2> INFO: start marker: 0
[10:14:17.704] <TB2> INFO: stop marker: 0
[10:14:17.704] <TB2> INFO: overflow: 0
[10:14:17.704] <TB2> INFO: invalid 5bit words: 0
[10:14:17.704] <TB2> INFO: invalid XOR eye diagram: 0
[10:14:17.704] <TB2> INFO: frame (failed synchr.): 0
[10:14:17.704] <TB2> INFO: idle data (no TBM trl): 0
[10:14:17.704] <TB2> INFO: no data (only TBM hdr): 0
[10:14:17.704] <TB2> INFO: TBM errors: 0
[10:14:17.704] <TB2> INFO: flawed TBM headers: 0
[10:14:17.704] <TB2> INFO: flawed TBM trailers: 0
[10:14:17.704] <TB2> INFO: event ID mismatches: 0
[10:14:17.705] <TB2> INFO: ROC errors: 0
[10:14:17.705] <TB2> INFO: missing ROC header(s): 0
[10:14:17.705] <TB2> INFO: misplaced readback start: 0
[10:14:17.705] <TB2> INFO: Pixel decoding errors: 0
[10:14:17.705] <TB2> INFO: pixel data incomplete: 0
[10:14:17.705] <TB2> INFO: pixel address: 0
[10:14:17.705] <TB2> INFO: pulse height fill bit: 0
[10:14:17.705] <TB2> INFO: buffer corruption: 0
[10:14:17.719] <TB2> INFO: ######################################################################
[10:14:17.719] <TB2> INFO: PixTestBBMap::doTest() Ntrig = 5, VcalS = 250 (high range)
[10:14:17.719] <TB2> INFO: ######################################################################
[10:14:17.721] <TB2> INFO: ---> dac: VthrComp name: calSMap ntrig: 5 dacrange: 0 .. 149 (-1/-1) hits flags = 514 (plus default)
[10:14:17.732] <TB2> INFO: dacScan split into 1 runs with ntrig = 5
[10:14:17.732] <TB2> INFO: run 1 of 1
[10:14:17.996] <TB2> INFO: Expecting 3120000 events.
[10:14:58.598] <TB2> INFO: 846650 events read in total (40046ms).
[10:15:38.534] <TB2> INFO: 1682050 events read in total (79983ms).
[10:16:18.449] <TB2> INFO: 2524515 events read in total (119898ms).
[10:16:46.886] <TB2> INFO: 3120000 events read in total (148334ms).
[10:16:46.945] <TB2> INFO: Test took 149213ms.
[10:17:14.197] <TB2> INFO: PixTestBBMap::doTest() done, duration: 176 seconds
[10:17:14.197] <TB2> INFO: number of dead bumps (per ROC): 1 0 0 0 0 0 0 0 0 0 0 1 0 2 0 0
[10:17:14.197] <TB2> INFO: separation cut (per ROC): 72 82 106 77 84 78 83 95 86 81 68 91 76 73 69 76
[10:17:14.197] <TB2> INFO: Decoding statistics:
[10:17:14.197] <TB2> INFO: General information:
[10:17:14.197] <TB2> INFO: 16bit words read: 0
[10:17:14.197] <TB2> INFO: valid events total: 0
[10:17:14.197] <TB2> INFO: empty events: 0
[10:17:14.197] <TB2> INFO: valid events with pixels: 0
[10:17:14.197] <TB2> INFO: valid pixel hits: 0
[10:17:14.197] <TB2> INFO: Event errors: 0
[10:17:14.197] <TB2> INFO: start marker: 0
[10:17:14.197] <TB2> INFO: stop marker: 0
[10:17:14.197] <TB2> INFO: overflow: 0
[10:17:14.197] <TB2> INFO: invalid 5bit words: 0
[10:17:14.197] <TB2> INFO: invalid XOR eye diagram: 0
[10:17:14.197] <TB2> INFO: frame (failed synchr.): 0
[10:17:14.197] <TB2> INFO: idle data (no TBM trl): 0
[10:17:14.197] <TB2> INFO: no data (only TBM hdr): 0
[10:17:14.197] <TB2> INFO: TBM errors: 0
[10:17:14.198] <TB2> INFO: flawed TBM headers: 0
[10:17:14.198] <TB2> INFO: flawed TBM trailers: 0
[10:17:14.198] <TB2> INFO: event ID mismatches: 0
[10:17:14.198] <TB2> INFO: ROC errors: 0
[10:17:14.198] <TB2> INFO: missing ROC header(s): 0
[10:17:14.198] <TB2> INFO: misplaced readback start: 0
[10:17:14.198] <TB2> INFO: Pixel decoding errors: 0
[10:17:14.198] <TB2> INFO: pixel data incomplete: 0
[10:17:14.198] <TB2> INFO: pixel address: 0
[10:17:14.198] <TB2> INFO: pulse height fill bit: 0
[10:17:14.198] <TB2> INFO: buffer corruption: 0
[10:17:14.280] <TB2> INFO: ######################################################################
[10:17:14.280] <TB2> INFO: PixTestScurves::fullTest() ntrig = 50, dacs/step = -1, ntrig/step = -1
[10:17:14.280] <TB2> INFO: ######################################################################
[10:17:14.280] <TB2> INFO: ----------------------------------------------------------------------
[10:17:14.280] <TB2> INFO: PixTestScurves::scurves(Vcal), ntrig = 50, dacs/step = -1, ntrig/step = -1
[10:17:14.280] <TB2> INFO: ----------------------------------------------------------------------
[10:17:14.280] <TB2> INFO: ---> dac: Vcal name: scurveVcal ntrig: 50 dacrange: 25 .. 150 (-1/-1) hits flags = 528 (plus default)
[10:17:14.289] <TB2> INFO: dacScan split into 1 runs with ntrig = 50
[10:17:14.289] <TB2> INFO: run 1 of 1
[10:17:14.563] <TB2> INFO: Expecting 26208000 events.
[10:17:47.246] <TB2> INFO: 867250 events read in total (32127ms).
[10:18:19.148] <TB2> INFO: 1722550 events read in total (64029ms).
[10:18:50.984] <TB2> INFO: 2574650 events read in total (95865ms).
[10:19:23.082] <TB2> INFO: 3426400 events read in total (127963ms).
[10:19:54.732] <TB2> INFO: 4276400 events read in total (159613ms).
[10:20:26.362] <TB2> INFO: 5126750 events read in total (191243ms).
[10:20:58.263] <TB2> INFO: 5976500 events read in total (223144ms).
[10:21:30.411] <TB2> INFO: 6826250 events read in total (255292ms).
[10:22:02.468] <TB2> INFO: 7673200 events read in total (287349ms).
[10:22:34.698] <TB2> INFO: 8521000 events read in total (319579ms).
[10:23:06.962] <TB2> INFO: 9368650 events read in total (351843ms).
[10:23:38.843] <TB2> INFO: 10215100 events read in total (383724ms).
[10:24:10.618] <TB2> INFO: 11059200 events read in total (415499ms).
[10:24:42.340] <TB2> INFO: 11904100 events read in total (447221ms).
[10:25:14.547] <TB2> INFO: 12749650 events read in total (479428ms).
[10:25:46.081] <TB2> INFO: 13587250 events read in total (510962ms).
[10:26:17.926] <TB2> INFO: 14424150 events read in total (542807ms).
[10:26:49.659] <TB2> INFO: 15258600 events read in total (574540ms).
[10:27:21.817] <TB2> INFO: 16090400 events read in total (606698ms).
[10:27:53.391] <TB2> INFO: 16925200 events read in total (638272ms).
[10:28:24.626] <TB2> INFO: 17758150 events read in total (669507ms).
[10:28:56.300] <TB2> INFO: 18590350 events read in total (701181ms).
[10:29:28.055] <TB2> INFO: 19421300 events read in total (732936ms).
[10:29:59.576] <TB2> INFO: 20253850 events read in total (764457ms).
[10:30:31.130] <TB2> INFO: 21083850 events read in total (796011ms).
[10:31:03.032] <TB2> INFO: 21915450 events read in total (827913ms).
[10:31:34.835] <TB2> INFO: 22746000 events read in total (859716ms).
[10:32:06.097] <TB2> INFO: 23575400 events read in total (890978ms).
[10:32:37.959] <TB2> INFO: 24408400 events read in total (922840ms).
[10:33:09.319] <TB2> INFO: 25239450 events read in total (954200ms).
[10:33:41.802] <TB2> INFO: 26079350 events read in total (986683ms).
[10:33:46.957] <TB2> INFO: 26208000 events read in total (991838ms).
[10:33:46.984] <TB2> INFO: Test took 992695ms.
[10:33:47.274] <TB2> INFO: dumping ASCII scurve output file: SCurveData
[10:33:48.920] <TB2> INFO: dumping ASCII scurve output file: SCurveData
[10:33:50.393] <TB2> INFO: dumping ASCII scurve output file: SCurveData
[10:33:51.804] <TB2> INFO: dumping ASCII scurve output file: SCurveData
[10:33:53.449] <TB2> INFO: dumping ASCII scurve output file: SCurveData
[10:33:55.115] <TB2> INFO: dumping ASCII scurve output file: SCurveData
[10:33:56.790] <TB2> INFO: dumping ASCII scurve output file: SCurveData
[10:33:58.368] <TB2> INFO: dumping ASCII scurve output file: SCurveData
[10:33:59.762] <TB2> INFO: dumping ASCII scurve output file: SCurveData
[10:34:01.375] <TB2> INFO: dumping ASCII scurve output file: SCurveData
[10:34:03.054] <TB2> INFO: dumping ASCII scurve output file: SCurveData
[10:34:04.750] <TB2> INFO: dumping ASCII scurve output file: SCurveData
[10:34:06.559] <TB2> INFO: dumping ASCII scurve output file: SCurveData
[10:34:08.713] <TB2> INFO: dumping ASCII scurve output file: SCurveData
[10:34:10.533] <TB2> INFO: dumping ASCII scurve output file: SCurveData
[10:34:12.192] <TB2> INFO: dumping ASCII scurve output file: SCurveData
[10:34:14.400] <TB2> INFO: PixTestScurves::scurves() done
[10:34:14.400] <TB2> INFO: Vcal mean: 83.74 82.44 105.96 83.20 92.17 94.60 97.20 105.22 83.08 82.04 71.82 85.33 75.51 76.95 75.76 84.69
[10:34:14.400] <TB2> INFO: Vcal RMS: 4.61 4.43 5.52 4.99 5.29 6.69 5.10 5.84 4.50 4.36 4.93 5.05 4.52 4.96 3.92 5.18
[10:34:14.401] <TB2> INFO: PixTestScurves::fullTest() done, duration: 1020 seconds
[10:34:14.401] <TB2> INFO: Decoding statistics:
[10:34:14.401] <TB2> INFO: General information:
[10:34:14.401] <TB2> INFO: 16bit words read: 0
[10:34:14.401] <TB2> INFO: valid events total: 0
[10:34:14.401] <TB2> INFO: empty events: 0
[10:34:14.401] <TB2> INFO: valid events with pixels: 0
[10:34:14.401] <TB2> INFO: valid pixel hits: 0
[10:34:14.401] <TB2> INFO: Event errors: 0
[10:34:14.401] <TB2> INFO: start marker: 0
[10:34:14.401] <TB2> INFO: stop marker: 0
[10:34:14.401] <TB2> INFO: overflow: 0
[10:34:14.401] <TB2> INFO: invalid 5bit words: 0
[10:34:14.401] <TB2> INFO: invalid XOR eye diagram: 0
[10:34:14.401] <TB2> INFO: frame (failed synchr.): 0
[10:34:14.401] <TB2> INFO: idle data (no TBM trl): 0
[10:34:14.401] <TB2> INFO: no data (only TBM hdr): 0
[10:34:14.401] <TB2> INFO: TBM errors: 0
[10:34:14.401] <TB2> INFO: flawed TBM headers: 0
[10:34:14.401] <TB2> INFO: flawed TBM trailers: 0
[10:34:14.401] <TB2> INFO: event ID mismatches: 0
[10:34:14.401] <TB2> INFO: ROC errors: 0
[10:34:14.401] <TB2> INFO: missing ROC header(s): 0
[10:34:14.401] <TB2> INFO: misplaced readback start: 0
[10:34:14.401] <TB2> INFO: Pixel decoding errors: 0
[10:34:14.401] <TB2> INFO: pixel data incomplete: 0
[10:34:14.401] <TB2> INFO: pixel address: 0
[10:34:14.401] <TB2> INFO: pulse height fill bit: 0
[10:34:14.401] <TB2> INFO: buffer corruption: 0
[10:34:14.498] <TB2> INFO: ######################################################################
[10:34:14.498] <TB2> INFO: PixTestTrim::doTest()
[10:34:14.498] <TB2> INFO: ######################################################################
[10:34:14.500] <TB2> INFO: ----------------------------------------------------------------------
[10:34:14.500] <TB2> INFO: PixTestTrim::trimTest() ntrig = 10, vcal = 35
[10:34:14.500] <TB2> INFO: ----------------------------------------------------------------------
[10:34:14.624] <TB2> INFO: ---> VthrComp thr map (minimal VthrComp)
[10:34:14.624] <TB2> INFO: ---> dac: vthrcomp name: TrimThr0 ntrig: 10 dacrange: 10 .. 160 (-1/-1) hits flags = 528 (plus default)
[10:34:14.636] <TB2> INFO: dacScan split into 1 runs with ntrig = 10
[10:34:14.636] <TB2> INFO: run 1 of 1
[10:34:14.978] <TB2> INFO: Expecting 6281600 events.
[10:34:54.920] <TB2> INFO: 1063800 events read in total (39386ms).
[10:35:33.495] <TB2> INFO: 2120550 events read in total (77961ms).
[10:36:12.220] <TB2> INFO: 3171960 events read in total (116686ms).
[10:36:50.938] <TB2> INFO: 4229790 events read in total (155404ms).
[10:37:29.896] <TB2> INFO: 5290500 events read in total (194362ms).
[10:38:05.801] <TB2> INFO: 6281600 events read in total (230267ms).
[10:38:05.834] <TB2> INFO: Test took 231198ms.
[10:38:25.853] <TB2> INFO: ROC 0 VthrComp = 91
[10:38:25.853] <TB2> INFO: ROC 1 VthrComp = 91
[10:38:25.853] <TB2> INFO: ROC 2 VthrComp = 108
[10:38:25.853] <TB2> INFO: ROC 3 VthrComp = 90
[10:38:25.853] <TB2> INFO: ROC 4 VthrComp = 95
[10:38:25.853] <TB2> INFO: ROC 5 VthrComp = 93
[10:38:25.853] <TB2> INFO: ROC 6 VthrComp = 100
[10:38:25.853] <TB2> INFO: ROC 7 VthrComp = 104
[10:38:25.853] <TB2> INFO: ROC 8 VthrComp = 92
[10:38:25.854] <TB2> INFO: ROC 9 VthrComp = 87
[10:38:25.854] <TB2> INFO: ROC 10 VthrComp = 76
[10:38:25.854] <TB2> INFO: ROC 11 VthrComp = 92
[10:38:25.854] <TB2> INFO: ROC 12 VthrComp = 82
[10:38:25.854] <TB2> INFO: ROC 13 VthrComp = 81
[10:38:25.854] <TB2> INFO: ROC 14 VthrComp = 82
[10:38:25.854] <TB2> INFO: ROC 15 VthrComp = 90
[10:38:25.854] <TB2> INFO: ---> Vcal thr map (pixel with maximum Vcal thr)
[10:38:25.854] <TB2> INFO: ---> dac: vcal name: TrimThr1 ntrig: 10 dacrange: 10 .. 160 (-1/-1) hits flags = 528 (plus default)
[10:38:25.862] <TB2> INFO: dacScan split into 1 runs with ntrig = 10
[10:38:25.863] <TB2> INFO: run 1 of 1
[10:38:26.127] <TB2> INFO: Expecting 6281600 events.
[10:39:01.498] <TB2> INFO: 757900 events read in total (34814ms).
[10:39:35.818] <TB2> INFO: 1513650 events read in total (69134ms).
[10:40:10.492] <TB2> INFO: 2268860 events read in total (103808ms).
[10:40:45.082] <TB2> INFO: 3023650 events read in total (138398ms).
[10:41:19.836] <TB2> INFO: 3772840 events read in total (173152ms).
[10:41:54.641] <TB2> INFO: 4518870 events read in total (207957ms).
[10:42:29.067] <TB2> INFO: 5262880 events read in total (242383ms).
[10:43:03.690] <TB2> INFO: 6006310 events read in total (277006ms).
[10:43:16.814] <TB2> INFO: 6281600 events read in total (290130ms).
[10:43:16.857] <TB2> INFO: Test took 290994ms.
[10:43:40.909] <TB2> INFO: roc 0 with ID = 0 has maximal Vcal 57.2108 for pixel 0/14 mean/min/max = 45.0791/32.9362/57.222
[10:43:40.909] <TB2> INFO: roc 1 with ID = 1 has maximal Vcal 55.7323 for pixel 17/42 mean/min/max = 44.0634/32.1844/55.9423
[10:43:40.909] <TB2> INFO: roc 2 with ID = 2 has maximal Vcal 61.4009 for pixel 44/79 mean/min/max = 47.3858/33.3429/61.4287
[10:43:40.910] <TB2> INFO: roc 3 with ID = 3 has maximal Vcal 58.2053 for pixel 2/66 mean/min/max = 45.3356/32.4576/58.2136
[10:43:40.910] <TB2> INFO: roc 4 with ID = 4 has maximal Vcal 59.3806 for pixel 28/16 mean/min/max = 45.6764/31.9221/59.4307
[10:43:40.910] <TB2> INFO: roc 5 with ID = 5 has maximal Vcal 65.0419 for pixel 1/2 mean/min/max = 48.3752/31.3967/65.3536
[10:43:40.911] <TB2> INFO: roc 6 with ID = 6 has maximal Vcal 57.979 for pixel 13/63 mean/min/max = 45.0667/32.0115/58.1218
[10:43:40.911] <TB2> INFO: roc 7 with ID = 7 has maximal Vcal 63.3874 for pixel 12/4 mean/min/max = 48.5486/33.5296/63.5677
[10:43:40.911] <TB2> INFO: roc 8 with ID = 8 has maximal Vcal 56.8985 for pixel 23/70 mean/min/max = 44.4697/31.9182/57.0212
[10:43:40.911] <TB2> INFO: roc 9 with ID = 9 has maximal Vcal 57.1058 for pixel 0/23 mean/min/max = 44.5507/31.9352/57.1663
[10:43:40.912] <TB2> INFO: roc 10 with ID = 10 has maximal Vcal 59.6407 for pixel 11/7 mean/min/max = 47.1971/34.7399/59.6543
[10:43:40.912] <TB2> INFO: roc 11 with ID = 11 has maximal Vcal 58.7569 for pixel 0/4 mean/min/max = 45.6702/32.5296/58.8109
[10:43:40.913] <TB2> INFO: roc 12 with ID = 12 has maximal Vcal 56.9822 for pixel 0/46 mean/min/max = 44.6403/32.107/57.1737
[10:43:40.913] <TB2> INFO: roc 13 with ID = 13 has maximal Vcal 58.1101 for pixel 7/67 mean/min/max = 45.4898/32.8585/58.1211
[10:43:40.913] <TB2> INFO: roc 14 with ID = 14 has maximal Vcal 55.6824 for pixel 51/52 mean/min/max = 44.3997/32.9789/55.8206
[10:43:40.914] <TB2> INFO: roc 15 with ID = 15 has maximal Vcal 60.1841 for pixel 7/1 mean/min/max = 46.425/32.4769/60.3731
[10:43:40.914] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[10:43:41.003] <TB2> INFO: Expecting 514560 events.
[10:43:53.972] <TB2> INFO: 514560 events read in total (12413ms).
[10:43:53.977] <TB2> INFO: Expecting 514560 events.
[10:44:06.759] <TB2> INFO: 514560 events read in total (12359ms).
[10:44:06.766] <TB2> INFO: Expecting 514560 events.
[10:44:19.579] <TB2> INFO: 514560 events read in total (12400ms).
[10:44:19.588] <TB2> INFO: Expecting 514560 events.
[10:44:32.529] <TB2> INFO: 514560 events read in total (12528ms).
[10:44:32.541] <TB2> INFO: Expecting 514560 events.
[10:44:45.328] <TB2> INFO: 514560 events read in total (12377ms).
[10:44:45.342] <TB2> INFO: Expecting 514560 events.
[10:44:58.169] <TB2> INFO: 514560 events read in total (12413ms).
[10:44:58.185] <TB2> INFO: Expecting 514560 events.
[10:45:11.009] <TB2> INFO: 514560 events read in total (12409ms).
[10:45:11.025] <TB2> INFO: Expecting 514560 events.
[10:45:24.008] <TB2> INFO: 514560 events read in total (12569ms).
[10:45:24.026] <TB2> INFO: Expecting 514560 events.
[10:45:37.067] <TB2> INFO: 514560 events read in total (12632ms).
[10:45:37.089] <TB2> INFO: Expecting 514560 events.
[10:45:49.801] <TB2> INFO: 514560 events read in total (12310ms).
[10:45:49.822] <TB2> INFO: Expecting 514560 events.
[10:46:02.641] <TB2> INFO: 514560 events read in total (12413ms).
[10:46:02.665] <TB2> INFO: Expecting 514560 events.
[10:46:15.402] <TB2> INFO: 514560 events read in total (12336ms).
[10:46:15.428] <TB2> INFO: Expecting 514560 events.
[10:46:28.213] <TB2> INFO: 514560 events read in total (12382ms).
[10:46:28.255] <TB2> INFO: Expecting 514560 events.
[10:46:41.060] <TB2> INFO: 514560 events read in total (12403ms).
[10:46:41.089] <TB2> INFO: Expecting 514560 events.
[10:46:53.862] <TB2> INFO: 514560 events read in total (12371ms).
[10:46:53.895] <TB2> INFO: Expecting 514560 events.
[10:47:06.750] <TB2> INFO: 514560 events read in total (12453ms).
[10:47:06.784] <TB2> INFO: Test took 205870ms.
[10:47:07.778] <TB2> INFO: ---> dac: vcal name: TrimThr2 ntrig: 10 dacrange: 0 .. 150 (-1/-1) hits flags = 528 (plus default)
[10:47:07.786] <TB2> INFO: dacScan split into 1 runs with ntrig = 10
[10:47:07.786] <TB2> INFO: run 1 of 1
[10:47:08.055] <TB2> INFO: Expecting 6281600 events.
[10:47:43.369] <TB2> INFO: 745560 events read in total (34758ms).
[10:48:18.479] <TB2> INFO: 1488860 events read in total (69868ms).
[10:48:53.547] <TB2> INFO: 2231870 events read in total (104936ms).
[10:49:28.419] <TB2> INFO: 2974610 events read in total (139808ms).
[10:50:02.552] <TB2> INFO: 3711750 events read in total (173941ms).
[10:50:36.904] <TB2> INFO: 4444530 events read in total (208293ms).
[10:51:11.712] <TB2> INFO: 5175220 events read in total (243101ms).
[10:51:46.407] <TB2> INFO: 5905170 events read in total (277796ms).
[10:52:04.304] <TB2> INFO: 6281600 events read in total (295693ms).
[10:52:04.364] <TB2> INFO: Test took 296578ms.
[10:52:27.675] <TB2> INFO: ---> TrimStepCorr4 extremal thresholds: 0.048095 .. 255.000000
[10:52:27.751] <TB2> INFO: ---> dac: vcal name: trimStepCorr4 ntrig: 10 dacrange: 0 .. 255 (-1/-1) hits flags = 528 (plus default)
[10:52:27.759] <TB2> INFO: dacScan split into 1 runs with ntrig = 10
[10:52:27.759] <TB2> INFO: run 1 of 1
[10:52:28.021] <TB2> INFO: Expecting 10649600 events.
[10:53:04.679] <TB2> INFO: 712080 events read in total (36102ms).
[10:53:38.919] <TB2> INFO: 1424100 events read in total (70342ms).
[10:54:13.248] <TB2> INFO: 2136190 events read in total (104671ms).
[10:54:47.023] <TB2> INFO: 2848720 events read in total (138446ms).
[10:55:21.190] <TB2> INFO: 3561290 events read in total (172613ms).
[10:55:55.021] <TB2> INFO: 4273540 events read in total (206444ms).
[10:56:28.663] <TB2> INFO: 4985860 events read in total (240086ms).
[10:57:02.602] <TB2> INFO: 5698500 events read in total (274025ms).
[10:57:36.298] <TB2> INFO: 6410980 events read in total (307721ms).
[10:58:10.413] <TB2> INFO: 7122680 events read in total (341836ms).
[10:58:44.389] <TB2> INFO: 7834500 events read in total (375812ms).
[10:59:18.151] <TB2> INFO: 8545600 events read in total (409574ms).
[10:59:52.374] <TB2> INFO: 9256580 events read in total (443797ms).
[11:00:26.596] <TB2> INFO: 9967640 events read in total (478019ms).
[11:00:59.352] <TB2> INFO: 10649600 events read in total (510775ms).
[11:00:59.459] <TB2> INFO: Test took 511699ms.
[11:01:29.652] <TB2> INFO: ---> TrimStepCorr2 extremal thresholds: 17.069159 .. 165.246595
[11:01:29.727] <TB2> INFO: ---> dac: vcal name: trimStepCorr2 ntrig: 10 dacrange: 7 .. 175 (-1/-1) hits flags = 528 (plus default)
[11:01:29.735] <TB2> INFO: dacScan split into 1 runs with ntrig = 10
[11:01:29.735] <TB2> INFO: run 1 of 1
[11:01:29.998] <TB2> INFO: Expecting 7030400 events.
[11:02:04.550] <TB2> INFO: 723600 events read in total (33996ms).
[11:02:38.385] <TB2> INFO: 1447120 events read in total (67831ms).
[11:03:12.721] <TB2> INFO: 2170800 events read in total (102167ms).
[11:03:47.642] <TB2> INFO: 2894250 events read in total (137088ms).
[11:04:24.271] <TB2> INFO: 3617770 events read in total (173717ms).
[11:04:58.160] <TB2> INFO: 4341170 events read in total (207606ms).
[11:05:32.219] <TB2> INFO: 5064590 events read in total (241665ms).
[11:06:06.281] <TB2> INFO: 5787760 events read in total (275727ms).
[11:06:40.950] <TB2> INFO: 6510890 events read in total (310396ms).
[11:07:05.844] <TB2> INFO: 7030400 events read in total (335290ms).
[11:07:05.921] <TB2> INFO: Test took 336186ms.
[11:07:29.777] <TB2> INFO: ---> TrimStepCorr1a extremal thresholds: 13.010701 .. 43.528004
[11:07:29.857] <TB2> INFO: ---> dac: vcal name: trimStepCorr1a ntrig: 10 dacrange: 3 .. 53 (-1/-1) hits flags = 528 (plus default)
[11:07:29.866] <TB2> INFO: dacScan split into 1 runs with ntrig = 10
[11:07:29.866] <TB2> INFO: run 1 of 1
[11:07:30.126] <TB2> INFO: Expecting 2121600 events.
[11:08:08.583] <TB2> INFO: 968100 events read in total (37901ms).
[11:08:46.676] <TB2> INFO: 1935210 events read in total (75994ms).
[11:08:54.322] <TB2> INFO: 2121600 events read in total (83641ms).
[11:08:54.334] <TB2> INFO: Test took 84468ms.
[11:09:09.146] <TB2> INFO: ---> TrimStepCorr1b extremal thresholds: 16.582478 .. 43.528004
[11:09:09.224] <TB2> INFO: ---> dac: vcal name: trimStepCorr1b ntrig: 10 dacrange: 6 .. 53 (-1/-1) hits flags = 528 (plus default)
[11:09:09.232] <TB2> INFO: dacScan split into 1 runs with ntrig = 10
[11:09:09.232] <TB2> INFO: run 1 of 1
[11:09:09.495] <TB2> INFO: Expecting 1996800 events.
[11:09:48.014] <TB2> INFO: 951300 events read in total (37963ms).
[11:10:25.381] <TB2> INFO: 1902410 events read in total (75330ms).
[11:10:29.730] <TB2> INFO: 1996800 events read in total (79679ms).
[11:10:29.741] <TB2> INFO: Test took 80508ms.
[11:10:43.293] <TB2> INFO: ---> TrimThrFinal extremal thresholds: 15 .. 55
[11:10:43.293] <TB2> INFO: ---> dac: vcal name: TrimThrFinal ntrig: 10 dacrange: 15 .. 55 (-1/-1) hits flags = 528 (plus default)
[11:10:43.302] <TB2> INFO: dacScan split into 1 runs with ntrig = 10
[11:10:43.302] <TB2> INFO: run 1 of 1
[11:10:43.567] <TB2> INFO: Expecting 1705600 events.
[11:11:21.111] <TB2> INFO: 879040 events read in total (36988ms).
[11:11:56.227] <TB2> INFO: 1705600 events read in total (72104ms).
[11:11:56.246] <TB2> INFO: Test took 72944ms.
[11:12:10.096] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2301_FullQualification_2016-08-29_11h59m_1472464790//000_FulltestPxar_m20//dacParameters35_C0.dat
[11:12:10.096] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2301_FullQualification_2016-08-29_11h59m_1472464790//000_FulltestPxar_m20//dacParameters35_C1.dat
[11:12:10.096] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2301_FullQualification_2016-08-29_11h59m_1472464790//000_FulltestPxar_m20//dacParameters35_C2.dat
[11:12:10.096] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2301_FullQualification_2016-08-29_11h59m_1472464790//000_FulltestPxar_m20//dacParameters35_C3.dat
[11:12:10.096] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2301_FullQualification_2016-08-29_11h59m_1472464790//000_FulltestPxar_m20//dacParameters35_C4.dat
[11:12:10.096] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2301_FullQualification_2016-08-29_11h59m_1472464790//000_FulltestPxar_m20//dacParameters35_C5.dat
[11:12:10.096] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2301_FullQualification_2016-08-29_11h59m_1472464790//000_FulltestPxar_m20//dacParameters35_C6.dat
[11:12:10.097] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2301_FullQualification_2016-08-29_11h59m_1472464790//000_FulltestPxar_m20//dacParameters35_C7.dat
[11:12:10.097] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2301_FullQualification_2016-08-29_11h59m_1472464790//000_FulltestPxar_m20//dacParameters35_C8.dat
[11:12:10.097] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2301_FullQualification_2016-08-29_11h59m_1472464790//000_FulltestPxar_m20//dacParameters35_C9.dat
[11:12:10.097] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2301_FullQualification_2016-08-29_11h59m_1472464790//000_FulltestPxar_m20//dacParameters35_C10.dat
[11:12:10.097] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2301_FullQualification_2016-08-29_11h59m_1472464790//000_FulltestPxar_m20//dacParameters35_C11.dat
[11:12:10.097] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2301_FullQualification_2016-08-29_11h59m_1472464790//000_FulltestPxar_m20//dacParameters35_C12.dat
[11:12:10.097] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2301_FullQualification_2016-08-29_11h59m_1472464790//000_FulltestPxar_m20//dacParameters35_C13.dat
[11:12:10.097] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2301_FullQualification_2016-08-29_11h59m_1472464790//000_FulltestPxar_m20//dacParameters35_C14.dat
[11:12:10.098] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2301_FullQualification_2016-08-29_11h59m_1472464790//000_FulltestPxar_m20//dacParameters35_C15.dat
[11:12:10.098] <TB2> INFO: write trim parameters into /usr/local/coldboxDATA/M2301_FullQualification_2016-08-29_11h59m_1472464790//000_FulltestPxar_m20//trimParameters35_C0.dat
[11:12:10.106] <TB2> INFO: write trim parameters into /usr/local/coldboxDATA/M2301_FullQualification_2016-08-29_11h59m_1472464790//000_FulltestPxar_m20//trimParameters35_C1.dat
[11:12:10.115] <TB2> INFO: write trim parameters into /usr/local/coldboxDATA/M2301_FullQualification_2016-08-29_11h59m_1472464790//000_FulltestPxar_m20//trimParameters35_C2.dat
[11:12:10.122] <TB2> INFO: write trim parameters into /usr/local/coldboxDATA/M2301_FullQualification_2016-08-29_11h59m_1472464790//000_FulltestPxar_m20//trimParameters35_C3.dat
[11:12:10.131] <TB2> INFO: write trim parameters into /usr/local/coldboxDATA/M2301_FullQualification_2016-08-29_11h59m_1472464790//000_FulltestPxar_m20//trimParameters35_C4.dat
[11:12:10.139] <TB2> INFO: write trim parameters into /usr/local/coldboxDATA/M2301_FullQualification_2016-08-29_11h59m_1472464790//000_FulltestPxar_m20//trimParameters35_C5.dat
[11:12:10.146] <TB2> INFO: write trim parameters into /usr/local/coldboxDATA/M2301_FullQualification_2016-08-29_11h59m_1472464790//000_FulltestPxar_m20//trimParameters35_C6.dat
[11:12:10.155] <TB2> INFO: write trim parameters into /usr/local/coldboxDATA/M2301_FullQualification_2016-08-29_11h59m_1472464790//000_FulltestPxar_m20//trimParameters35_C7.dat
[11:12:10.163] <TB2> INFO: write trim parameters into /usr/local/coldboxDATA/M2301_FullQualification_2016-08-29_11h59m_1472464790//000_FulltestPxar_m20//trimParameters35_C8.dat
[11:12:10.171] <TB2> INFO: write trim parameters into /usr/local/coldboxDATA/M2301_FullQualification_2016-08-29_11h59m_1472464790//000_FulltestPxar_m20//trimParameters35_C9.dat
[11:12:10.178] <TB2> INFO: write trim parameters into /usr/local/coldboxDATA/M2301_FullQualification_2016-08-29_11h59m_1472464790//000_FulltestPxar_m20//trimParameters35_C10.dat
[11:12:10.187] <TB2> INFO: write trim parameters into /usr/local/coldboxDATA/M2301_FullQualification_2016-08-29_11h59m_1472464790//000_FulltestPxar_m20//trimParameters35_C11.dat
[11:12:10.194] <TB2> INFO: write trim parameters into /usr/local/coldboxDATA/M2301_FullQualification_2016-08-29_11h59m_1472464790//000_FulltestPxar_m20//trimParameters35_C12.dat
[11:12:10.202] <TB2> INFO: write trim parameters into /usr/local/coldboxDATA/M2301_FullQualification_2016-08-29_11h59m_1472464790//000_FulltestPxar_m20//trimParameters35_C13.dat
[11:12:10.211] <TB2> INFO: write trim parameters into /usr/local/coldboxDATA/M2301_FullQualification_2016-08-29_11h59m_1472464790//000_FulltestPxar_m20//trimParameters35_C14.dat
[11:12:10.219] <TB2> INFO: write trim parameters into /usr/local/coldboxDATA/M2301_FullQualification_2016-08-29_11h59m_1472464790//000_FulltestPxar_m20//trimParameters35_C15.dat
[11:12:10.227] <TB2> INFO: PixTestTrim::trimTest() done
[11:12:10.227] <TB2> INFO: vtrim: 86 91 104 120 97 98 92 115 119 91 106 94 94 100 85 103
[11:12:10.227] <TB2> INFO: vthrcomp: 91 91 108 90 95 93 100 104 92 87 76 92 82 81 82 90
[11:12:10.227] <TB2> INFO: vcal mean: 35.02 34.97 35.01 34.99 34.97 34.96 34.97 35.01 34.96 34.95 35.04 34.96 35.00 35.00 34.97 34.97
[11:12:10.227] <TB2> INFO: vcal RMS: 0.83 0.78 0.84 0.80 0.87 0.93 0.86 0.90 0.93 0.81 0.80 0.79 0.79 1.28 0.78 0.81
[11:12:10.227] <TB2> INFO: bits mean: 9.19 9.71 8.54 9.71 9.73 8.54 9.64 8.63 9.99 9.65 8.72 8.89 9.48 9.38 9.44 8.82
[11:12:10.228] <TB2> INFO: bits RMS: 2.75 2.62 2.82 2.56 2.57 2.96 2.68 2.64 2.56 2.65 2.50 2.87 2.81 2.65 2.61 2.89
[11:12:10.234] <TB2> INFO: ----------------------------------------------------------------------
[11:12:10.234] <TB2> INFO: PixTestTrim::trimBitTest() ntrig = 5, vtrims = 254 126 63 32
[11:12:10.234] <TB2> INFO: ----------------------------------------------------------------------
[11:12:10.237] <TB2> INFO: ---> dac: Vcal name: TrimBitsThr0 ntrig: 5 dacrange: 0 .. 199 (-1/-1) hits flags = 528 (plus default)
[11:12:10.245] <TB2> INFO: dacScan split into 1 runs with ntrig = 5
[11:12:10.245] <TB2> INFO: run 1 of 1
[11:12:10.520] <TB2> INFO: Expecting 4160000 events.
[11:12:51.894] <TB2> INFO: 898935 events read in total (40818ms).
[11:13:32.123] <TB2> INFO: 1791315 events read in total (81047ms).
[11:14:12.336] <TB2> INFO: 2675720 events read in total (121261ms).
[11:14:52.514] <TB2> INFO: 3556080 events read in total (161438ms).
[11:15:20.530] <TB2> INFO: 4160000 events read in total (189454ms).
[11:15:20.570] <TB2> INFO: Test took 190325ms.
[11:15:48.656] <TB2> INFO: ---> dac: Vcal name: TrimThr_trim14 ntrig: 5 dacrange: 0 .. 170 (-1/-1) hits flags = 528 (plus default)
[11:15:48.664] <TB2> INFO: dacScan split into 1 runs with ntrig = 5
[11:15:48.664] <TB2> INFO: run 1 of 1
[11:15:48.936] <TB2> INFO: Expecting 3556800 events.
[11:16:30.595] <TB2> INFO: 920495 events read in total (41103ms).
[11:17:11.418] <TB2> INFO: 1831720 events read in total (81926ms).
[11:17:52.186] <TB2> INFO: 2732485 events read in total (122695ms).
[11:18:29.448] <TB2> INFO: 3556800 events read in total (159956ms).
[11:18:29.491] <TB2> INFO: Test took 160826ms.
[11:18:55.893] <TB2> INFO: ---> dac: Vcal name: TrimThr_trim13 ntrig: 5 dacrange: 0 .. 160 (-1/-1) hits flags = 528 (plus default)
[11:18:55.901] <TB2> INFO: dacScan split into 1 runs with ntrig = 5
[11:18:55.901] <TB2> INFO: run 1 of 1
[11:18:56.172] <TB2> INFO: Expecting 3348800 events.
[11:19:37.674] <TB2> INFO: 941830 events read in total (40946ms).
[11:20:18.674] <TB2> INFO: 1872430 events read in total (81946ms).
[11:20:59.411] <TB2> INFO: 2792240 events read in total (122683ms).
[11:21:24.035] <TB2> INFO: 3348800 events read in total (147307ms).
[11:21:24.072] <TB2> INFO: Test took 148171ms.
[11:21:48.979] <TB2> INFO: ---> dac: Vcal name: TrimThr_trim11 ntrig: 5 dacrange: 0 .. 158 (-1/-1) hits flags = 528 (plus default)
[11:21:48.989] <TB2> INFO: dacScan split into 1 runs with ntrig = 5
[11:21:48.989] <TB2> INFO: run 1 of 1
[11:21:49.248] <TB2> INFO: Expecting 3307200 events.
[11:22:30.940] <TB2> INFO: 945785 events read in total (41136ms).
[11:23:12.248] <TB2> INFO: 1879805 events read in total (82444ms).
[11:23:53.192] <TB2> INFO: 2803030 events read in total (123389ms).
[11:24:15.542] <TB2> INFO: 3307200 events read in total (145738ms).
[11:24:15.577] <TB2> INFO: Test took 146588ms.
[11:24:43.291] <TB2> INFO: ---> dac: Vcal name: TrimThr_trim7 ntrig: 5 dacrange: 0 .. 158 (-1/-1) hits flags = 528 (plus default)
[11:24:43.299] <TB2> INFO: dacScan split into 1 runs with ntrig = 5
[11:24:43.299] <TB2> INFO: run 1 of 1
[11:24:43.576] <TB2> INFO: Expecting 3307200 events.
[11:25:25.960] <TB2> INFO: 945360 events read in total (41824ms).
[11:26:07.717] <TB2> INFO: 1879090 events read in total (83581ms).
[11:26:49.086] <TB2> INFO: 2801720 events read in total (124950ms).
[11:27:11.843] <TB2> INFO: 3307200 events read in total (147707ms).
[11:27:11.874] <TB2> INFO: Test took 148576ms.
[11:27:37.227] <TB2> INFO: PixTestTrim::trimBitTest() done
[11:27:37.228] <TB2> INFO: PixTestTrim::doTest() done, duration: 3202 seconds
[11:27:37.228] <TB2> INFO: Decoding statistics:
[11:27:37.228] <TB2> INFO: General information:
[11:27:37.228] <TB2> INFO: 16bit words read: 0
[11:27:37.228] <TB2> INFO: valid events total: 0
[11:27:37.228] <TB2> INFO: empty events: 0
[11:27:37.228] <TB2> INFO: valid events with pixels: 0
[11:27:37.228] <TB2> INFO: valid pixel hits: 0
[11:27:37.228] <TB2> INFO: Event errors: 0
[11:27:37.228] <TB2> INFO: start marker: 0
[11:27:37.228] <TB2> INFO: stop marker: 0
[11:27:37.228] <TB2> INFO: overflow: 0
[11:27:37.228] <TB2> INFO: invalid 5bit words: 0
[11:27:37.228] <TB2> INFO: invalid XOR eye diagram: 0
[11:27:37.228] <TB2> INFO: frame (failed synchr.): 0
[11:27:37.228] <TB2> INFO: idle data (no TBM trl): 0
[11:27:37.228] <TB2> INFO: no data (only TBM hdr): 0
[11:27:37.228] <TB2> INFO: TBM errors: 0
[11:27:37.228] <TB2> INFO: flawed TBM headers: 0
[11:27:37.228] <TB2> INFO: flawed TBM trailers: 0
[11:27:37.228] <TB2> INFO: event ID mismatches: 0
[11:27:37.228] <TB2> INFO: ROC errors: 0
[11:27:37.228] <TB2> INFO: missing ROC header(s): 0
[11:27:37.228] <TB2> INFO: misplaced readback start: 0
[11:27:37.228] <TB2> INFO: Pixel decoding errors: 0
[11:27:37.228] <TB2> INFO: pixel data incomplete: 0
[11:27:37.228] <TB2> INFO: pixel address: 0
[11:27:37.228] <TB2> INFO: pulse height fill bit: 0
[11:27:37.228] <TB2> INFO: buffer corruption: 0
[11:27:37.926] <TB2> INFO: ######################################################################
[11:27:37.926] <TB2> INFO: PixTestPhOptimization::doTest() Ntrig = 10
[11:27:37.926] <TB2> INFO: ######################################################################
[11:27:38.186] <TB2> INFO: Expecting 41600 events.
[11:27:41.979] <TB2> INFO: 41600 events read in total (3235ms).
[11:27:41.980] <TB2> INFO: Test took 4053ms.
[11:27:42.450] <TB2> INFO: Expecting 41600 events.
[11:27:46.320] <TB2> INFO: 41600 events read in total (3313ms).
[11:27:46.320] <TB2> INFO: Test took 4141ms.
[11:27:46.604] <TB2> INFO: Expecting 41600 events.
[11:27:50.391] <TB2> INFO: 41600 events read in total (3231ms).
[11:27:50.392] <TB2> INFO: Test took 4050ms.
[11:27:50.646] <TB2> INFO: Expecting 2560 events.
[11:27:51.501] <TB2> INFO: 2560 events read in total (299ms).
[11:27:51.501] <TB2> INFO: Test took 1097ms.
[11:27:51.806] <TB2> INFO: Expecting 2560 events.
[11:27:52.660] <TB2> INFO: 2560 events read in total (298ms).
[11:27:52.660] <TB2> INFO: Test took 1159ms.
[11:27:52.965] <TB2> INFO: Expecting 2560 events.
[11:27:53.817] <TB2> INFO: 2560 events read in total (296ms).
[11:27:53.817] <TB2> INFO: Test took 1156ms.
[11:27:54.123] <TB2> INFO: Expecting 2560 events.
[11:27:54.977] <TB2> INFO: 2560 events read in total (298ms).
[11:27:54.977] <TB2> INFO: Test took 1159ms.
[11:27:55.282] <TB2> INFO: Expecting 2560 events.
[11:27:56.141] <TB2> INFO: 2560 events read in total (303ms).
[11:27:56.141] <TB2> INFO: Test took 1163ms.
[11:27:56.446] <TB2> INFO: Expecting 2560 events.
[11:27:57.302] <TB2> INFO: 2560 events read in total (300ms).
[11:27:57.302] <TB2> INFO: Test took 1160ms.
[11:27:57.607] <TB2> INFO: Expecting 2560 events.
[11:27:58.459] <TB2> INFO: 2560 events read in total (296ms).
[11:27:58.460] <TB2> INFO: Test took 1157ms.
[11:27:58.765] <TB2> INFO: Expecting 2560 events.
[11:27:59.617] <TB2> INFO: 2560 events read in total (296ms).
[11:27:59.618] <TB2> INFO: Test took 1158ms.
[11:27:59.923] <TB2> INFO: Expecting 2560 events.
[11:28:00.778] <TB2> INFO: 2560 events read in total (299ms).
[11:28:00.778] <TB2> INFO: Test took 1160ms.
[11:28:01.084] <TB2> INFO: Expecting 2560 events.
[11:28:01.939] <TB2> INFO: 2560 events read in total (299ms).
[11:28:01.939] <TB2> INFO: Test took 1154ms.
[11:28:02.245] <TB2> INFO: Expecting 2560 events.
[11:28:03.098] <TB2> INFO: 2560 events read in total (297ms).
[11:28:03.098] <TB2> INFO: Test took 1158ms.
[11:28:03.404] <TB2> INFO: Expecting 2560 events.
[11:28:04.258] <TB2> INFO: 2560 events read in total (298ms).
[11:28:04.258] <TB2> INFO: Test took 1159ms.
[11:28:04.563] <TB2> INFO: Expecting 2560 events.
[11:28:05.419] <TB2> INFO: 2560 events read in total (300ms).
[11:28:05.419] <TB2> INFO: Test took 1161ms.
[11:28:05.723] <TB2> INFO: Expecting 2560 events.
[11:28:06.579] <TB2> INFO: 2560 events read in total (299ms).
[11:28:06.579] <TB2> INFO: Test took 1160ms.
[11:28:06.884] <TB2> INFO: Expecting 2560 events.
[11:28:07.738] <TB2> INFO: 2560 events read in total (298ms).
[11:28:07.739] <TB2> INFO: Test took 1160ms.
[11:28:08.043] <TB2> INFO: Expecting 2560 events.
[11:28:08.898] <TB2> INFO: 2560 events read in total (299ms).
[11:28:08.898] <TB2> INFO: Test took 1159ms.
[11:28:08.901] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:28:09.204] <TB2> INFO: Expecting 655360 events.
[11:28:25.772] <TB2> INFO: 655360 events read in total (16012ms).
[11:28:25.781] <TB2> INFO: Expecting 655360 events.
[11:28:42.403] <TB2> INFO: 655360 events read in total (16221ms).
[11:28:42.416] <TB2> INFO: Expecting 655360 events.
[11:28:59.435] <TB2> INFO: 655360 events read in total (16617ms).
[11:28:59.451] <TB2> INFO: Expecting 655360 events.
[11:29:16.527] <TB2> INFO: 655360 events read in total (16674ms).
[11:29:16.552] <TB2> INFO: Expecting 655360 events.
[11:29:33.594] <TB2> INFO: 655360 events read in total (16641ms).
[11:29:33.616] <TB2> INFO: Expecting 655360 events.
[11:29:50.610] <TB2> INFO: 655360 events read in total (16592ms).
[11:29:50.640] <TB2> INFO: Expecting 655360 events.
[11:30:07.626] <TB2> INFO: 655360 events read in total (16584ms).
[11:30:07.658] <TB2> INFO: Expecting 655360 events.
[11:30:24.666] <TB2> INFO: 655360 events read in total (16606ms).
[11:30:24.704] <TB2> INFO: Expecting 655360 events.
[11:30:41.631] <TB2> INFO: 655360 events read in total (16525ms).
[11:30:41.666] <TB2> INFO: Expecting 655360 events.
[11:30:58.416] <TB2> INFO: 655360 events read in total (16349ms).
[11:30:58.455] <TB2> INFO: Expecting 655360 events.
[11:31:15.182] <TB2> INFO: 655360 events read in total (16325ms).
[11:31:15.225] <TB2> INFO: Expecting 655360 events.
[11:31:31.947] <TB2> INFO: 655360 events read in total (16320ms).
[11:31:31.992] <TB2> INFO: Expecting 655360 events.
[11:31:49.039] <TB2> INFO: 655360 events read in total (16645ms).
[11:31:49.094] <TB2> INFO: Expecting 655360 events.
[11:32:05.803] <TB2> INFO: 655360 events read in total (16307ms).
[11:32:05.854] <TB2> INFO: Expecting 655360 events.
[11:32:22.428] <TB2> INFO: 655360 events read in total (16172ms).
[11:32:22.482] <TB2> INFO: Expecting 655360 events.
[11:32:39.116] <TB2> INFO: 655360 events read in total (16232ms).
[11:32:39.175] <TB2> INFO: Test took 270274ms.
[11:32:39.253] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:32:39.484] <TB2> INFO: Expecting 655360 events.
[11:32:56.532] <TB2> INFO: 655360 events read in total (16492ms).
[11:32:56.540] <TB2> INFO: Expecting 655360 events.
[11:33:13.363] <TB2> INFO: 655360 events read in total (16421ms).
[11:33:13.377] <TB2> INFO: Expecting 655360 events.
[11:33:29.858] <TB2> INFO: 655360 events read in total (16080ms).
[11:33:29.873] <TB2> INFO: Expecting 655360 events.
[11:33:46.361] <TB2> INFO: 655360 events read in total (16086ms).
[11:33:46.379] <TB2> INFO: Expecting 655360 events.
[11:34:02.894] <TB2> INFO: 655360 events read in total (16113ms).
[11:34:02.915] <TB2> INFO: Expecting 655360 events.
[11:34:19.476] <TB2> INFO: 655360 events read in total (16159ms).
[11:34:19.501] <TB2> INFO: Expecting 655360 events.
[11:34:35.947] <TB2> INFO: 655360 events read in total (16044ms).
[11:34:35.977] <TB2> INFO: Expecting 655360 events.
[11:34:52.746] <TB2> INFO: 655360 events read in total (16367ms).
[11:34:52.787] <TB2> INFO: Expecting 655360 events.
[11:35:09.515] <TB2> INFO: 655360 events read in total (16326ms).
[11:35:09.563] <TB2> INFO: Expecting 655360 events.
[11:35:26.052] <TB2> INFO: 655360 events read in total (16087ms).
[11:35:26.090] <TB2> INFO: Expecting 655360 events.
[11:35:43.045] <TB2> INFO: 655360 events read in total (16553ms).
[11:35:43.088] <TB2> INFO: Expecting 655360 events.
[11:35:59.898] <TB2> INFO: 655360 events read in total (16408ms).
[11:35:59.963] <TB2> INFO: Expecting 655360 events.
[11:36:16.917] <TB2> INFO: 655360 events read in total (16552ms).
[11:36:16.965] <TB2> INFO: Expecting 655360 events.
[11:36:33.952] <TB2> INFO: 655360 events read in total (16585ms).
[11:36:34.018] <TB2> INFO: Expecting 655360 events.
[11:36:50.982] <TB2> INFO: 655360 events read in total (16562ms).
[11:36:51.038] <TB2> INFO: Expecting 655360 events.
[11:37:07.820] <TB2> INFO: 655360 events read in total (16380ms).
[11:37:07.890] <TB2> INFO: Test took 268637ms.
[11:37:08.078] <TB2> INFO: safety margin for low PH: adding 0, margin is now 20
[11:37:08.085] <TB2> INFO: safety margin for low PH: adding 0, margin is now 20
[11:37:08.092] <TB2> INFO: safety margin for low PH: adding 1, margin is now 21
[11:37:08.099] <TB2> INFO: safety margin for low PH: adding 2, margin is now 22
[11:37:08.106] <TB2> INFO: safety margin for low PH: adding 3, margin is now 23
[11:37:08.112] <TB2> INFO: safety margin for low PH: adding 0, margin is now 20
[11:37:08.119] <TB2> INFO: safety margin for low PH: adding 0, margin is now 20
[11:37:08.126] <TB2> INFO: safety margin for low PH: adding 0, margin is now 20
[11:37:08.132] <TB2> INFO: safety margin for low PH: adding 0, margin is now 20
[11:37:08.139] <TB2> INFO: safety margin for low PH: adding 0, margin is now 20
[11:37:08.146] <TB2> INFO: safety margin for low PH: adding 0, margin is now 20
[11:37:08.152] <TB2> INFO: safety margin for low PH: adding 0, margin is now 20
[11:37:08.159] <TB2> INFO: safety margin for low PH: adding 0, margin is now 20
[11:37:08.165] <TB2> INFO: safety margin for low PH: adding 0, margin is now 20
[11:37:08.172] <TB2> INFO: safety margin for low PH: adding 0, margin is now 20
[11:37:08.179] <TB2> INFO: safety margin for low PH: adding 0, margin is now 20
[11:37:08.185] <TB2> INFO: safety margin for low PH: adding 0, margin is now 20
[11:37:08.192] <TB2> INFO: safety margin for low PH: adding 0, margin is now 20
[11:37:08.199] <TB2> INFO: safety margin for low PH: adding 0, margin is now 20
[11:37:08.236] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2301_FullQualification_2016-08-29_11h59m_1472464790//000_FulltestPxar_m20//dacParameters35_C0.dat
[11:37:08.236] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2301_FullQualification_2016-08-29_11h59m_1472464790//000_FulltestPxar_m20//dacParameters35_C1.dat
[11:37:08.236] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2301_FullQualification_2016-08-29_11h59m_1472464790//000_FulltestPxar_m20//dacParameters35_C2.dat
[11:37:08.236] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2301_FullQualification_2016-08-29_11h59m_1472464790//000_FulltestPxar_m20//dacParameters35_C3.dat
[11:37:08.237] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2301_FullQualification_2016-08-29_11h59m_1472464790//000_FulltestPxar_m20//dacParameters35_C4.dat
[11:37:08.237] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2301_FullQualification_2016-08-29_11h59m_1472464790//000_FulltestPxar_m20//dacParameters35_C5.dat
[11:37:08.237] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2301_FullQualification_2016-08-29_11h59m_1472464790//000_FulltestPxar_m20//dacParameters35_C6.dat
[11:37:08.237] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2301_FullQualification_2016-08-29_11h59m_1472464790//000_FulltestPxar_m20//dacParameters35_C7.dat
[11:37:08.237] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2301_FullQualification_2016-08-29_11h59m_1472464790//000_FulltestPxar_m20//dacParameters35_C8.dat
[11:37:08.237] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2301_FullQualification_2016-08-29_11h59m_1472464790//000_FulltestPxar_m20//dacParameters35_C9.dat
[11:37:08.237] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2301_FullQualification_2016-08-29_11h59m_1472464790//000_FulltestPxar_m20//dacParameters35_C10.dat
[11:37:08.237] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2301_FullQualification_2016-08-29_11h59m_1472464790//000_FulltestPxar_m20//dacParameters35_C11.dat
[11:37:08.237] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2301_FullQualification_2016-08-29_11h59m_1472464790//000_FulltestPxar_m20//dacParameters35_C12.dat
[11:37:08.238] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2301_FullQualification_2016-08-29_11h59m_1472464790//000_FulltestPxar_m20//dacParameters35_C13.dat
[11:37:08.238] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2301_FullQualification_2016-08-29_11h59m_1472464790//000_FulltestPxar_m20//dacParameters35_C14.dat
[11:37:08.238] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2301_FullQualification_2016-08-29_11h59m_1472464790//000_FulltestPxar_m20//dacParameters35_C15.dat
[11:37:08.501] <TB2> INFO: Expecting 41600 events.
[11:37:12.025] <TB2> INFO: 41600 events read in total (2967ms).
[11:37:12.026] <TB2> INFO: Test took 3785ms.
[11:37:12.462] <TB2> INFO: Expecting 41600 events.
[11:37:15.949] <TB2> INFO: 41600 events read in total (2931ms).
[11:37:15.950] <TB2> INFO: Test took 3746ms.
[11:37:16.423] <TB2> INFO: Expecting 41600 events.
[11:37:19.981] <TB2> INFO: 41600 events read in total (3002ms).
[11:37:19.982] <TB2> INFO: Test took 3850ms.
[11:37:20.174] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:37:20.263] <TB2> INFO: Expecting 2560 events.
[11:37:21.126] <TB2> INFO: 2560 events read in total (307ms).
[11:37:21.127] <TB2> INFO: Test took 953ms.
[11:37:21.129] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:37:21.434] <TB2> INFO: Expecting 2560 events.
[11:37:22.297] <TB2> INFO: 2560 events read in total (307ms).
[11:37:22.297] <TB2> INFO: Test took 1168ms.
[11:37:22.299] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:37:22.602] <TB2> INFO: Expecting 2560 events.
[11:37:23.460] <TB2> INFO: 2560 events read in total (301ms).
[11:37:23.460] <TB2> INFO: Test took 1161ms.
[11:37:23.468] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:37:23.765] <TB2> INFO: Expecting 2560 events.
[11:37:24.624] <TB2> INFO: 2560 events read in total (302ms).
[11:37:24.624] <TB2> INFO: Test took 1156ms.
[11:37:24.628] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:37:24.929] <TB2> INFO: Expecting 2560 events.
[11:37:25.787] <TB2> INFO: 2560 events read in total (302ms).
[11:37:25.787] <TB2> INFO: Test took 1159ms.
[11:37:25.790] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:37:26.093] <TB2> INFO: Expecting 2560 events.
[11:37:26.950] <TB2> INFO: 2560 events read in total (301ms).
[11:37:26.951] <TB2> INFO: Test took 1161ms.
[11:37:26.953] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:37:27.256] <TB2> INFO: Expecting 2560 events.
[11:37:28.118] <TB2> INFO: 2560 events read in total (306ms).
[11:37:28.118] <TB2> INFO: Test took 1165ms.
[11:37:28.123] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:37:28.424] <TB2> INFO: Expecting 2560 events.
[11:37:29.279] <TB2> INFO: 2560 events read in total (298ms).
[11:37:29.279] <TB2> INFO: Test took 1156ms.
[11:37:29.281] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:37:29.584] <TB2> INFO: Expecting 2560 events.
[11:37:30.438] <TB2> INFO: 2560 events read in total (297ms).
[11:37:30.439] <TB2> INFO: Test took 1158ms.
[11:37:30.441] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:37:30.745] <TB2> INFO: Expecting 2560 events.
[11:37:31.599] <TB2> INFO: 2560 events read in total (298ms).
[11:37:31.599] <TB2> INFO: Test took 1158ms.
[11:37:31.601] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:37:31.906] <TB2> INFO: Expecting 2560 events.
[11:37:32.764] <TB2> INFO: 2560 events read in total (302ms).
[11:37:32.764] <TB2> INFO: Test took 1163ms.
[11:37:32.766] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:37:33.070] <TB2> INFO: Expecting 2560 events.
[11:37:33.927] <TB2> INFO: 2560 events read in total (301ms).
[11:37:33.927] <TB2> INFO: Test took 1161ms.
[11:37:33.928] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:37:34.233] <TB2> INFO: Expecting 2560 events.
[11:37:35.088] <TB2> INFO: 2560 events read in total (299ms).
[11:37:35.089] <TB2> INFO: Test took 1161ms.
[11:37:35.091] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:37:35.394] <TB2> INFO: Expecting 2560 events.
[11:37:36.254] <TB2> INFO: 2560 events read in total (303ms).
[11:37:36.254] <TB2> INFO: Test took 1163ms.
[11:37:36.257] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:37:36.560] <TB2> INFO: Expecting 2560 events.
[11:37:37.419] <TB2> INFO: 2560 events read in total (302ms).
[11:37:37.420] <TB2> INFO: Test took 1163ms.
[11:37:37.422] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:37:37.727] <TB2> INFO: Expecting 2560 events.
[11:37:38.585] <TB2> INFO: 2560 events read in total (302ms).
[11:37:38.586] <TB2> INFO: Test took 1164ms.
[11:37:38.589] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:37:38.891] <TB2> INFO: Expecting 2560 events.
[11:37:39.747] <TB2> INFO: 2560 events read in total (299ms).
[11:37:39.747] <TB2> INFO: Test took 1158ms.
[11:37:39.749] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:37:40.054] <TB2> INFO: Expecting 2560 events.
[11:37:40.909] <TB2> INFO: 2560 events read in total (298ms).
[11:37:40.910] <TB2> INFO: Test took 1161ms.
[11:37:40.923] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:37:41.215] <TB2> INFO: Expecting 2560 events.
[11:37:42.069] <TB2> INFO: 2560 events read in total (298ms).
[11:37:42.069] <TB2> INFO: Test took 1147ms.
[11:37:42.073] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:37:42.376] <TB2> INFO: Expecting 2560 events.
[11:37:43.233] <TB2> INFO: 2560 events read in total (301ms).
[11:37:43.233] <TB2> INFO: Test took 1160ms.
[11:37:43.237] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:37:43.539] <TB2> INFO: Expecting 2560 events.
[11:37:44.396] <TB2> INFO: 2560 events read in total (300ms).
[11:37:44.397] <TB2> INFO: Test took 1160ms.
[11:37:44.398] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:37:44.702] <TB2> INFO: Expecting 2560 events.
[11:37:45.556] <TB2> INFO: 2560 events read in total (298ms).
[11:37:45.556] <TB2> INFO: Test took 1158ms.
[11:37:45.559] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:37:45.862] <TB2> INFO: Expecting 2560 events.
[11:37:46.720] <TB2> INFO: 2560 events read in total (301ms).
[11:37:46.721] <TB2> INFO: Test took 1162ms.
[11:37:46.723] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:37:47.027] <TB2> INFO: Expecting 2560 events.
[11:37:47.885] <TB2> INFO: 2560 events read in total (302ms).
[11:37:47.885] <TB2> INFO: Test took 1162ms.
[11:37:47.887] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:37:48.192] <TB2> INFO: Expecting 2560 events.
[11:37:49.048] <TB2> INFO: 2560 events read in total (300ms).
[11:37:49.048] <TB2> INFO: Test took 1161ms.
[11:37:49.050] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:37:49.354] <TB2> INFO: Expecting 2560 events.
[11:37:50.208] <TB2> INFO: 2560 events read in total (297ms).
[11:37:50.209] <TB2> INFO: Test took 1159ms.
[11:37:50.210] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:37:50.514] <TB2> INFO: Expecting 2560 events.
[11:37:51.368] <TB2> INFO: 2560 events read in total (297ms).
[11:37:51.368] <TB2> INFO: Test took 1158ms.
[11:37:51.370] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:37:51.675] <TB2> INFO: Expecting 2560 events.
[11:37:52.532] <TB2> INFO: 2560 events read in total (300ms).
[11:37:52.532] <TB2> INFO: Test took 1162ms.
[11:37:52.534] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:37:52.838] <TB2> INFO: Expecting 2560 events.
[11:37:53.697] <TB2> INFO: 2560 events read in total (302ms).
[11:37:53.697] <TB2> INFO: Test took 1163ms.
[11:37:53.699] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:37:54.004] <TB2> INFO: Expecting 2560 events.
[11:37:54.861] <TB2> INFO: 2560 events read in total (301ms).
[11:37:54.861] <TB2> INFO: Test took 1162ms.
[11:37:54.863] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:37:55.167] <TB2> INFO: Expecting 2560 events.
[11:37:56.028] <TB2> INFO: 2560 events read in total (304ms).
[11:37:56.028] <TB2> INFO: Test took 1165ms.
[11:37:56.030] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:37:56.335] <TB2> INFO: Expecting 2560 events.
[11:37:57.189] <TB2> INFO: 2560 events read in total (298ms).
[11:37:57.189] <TB2> INFO: Test took 1159ms.
[11:37:57.670] <TB2> INFO: PixTestPhOptimization::doTest() done, duration: 619 seconds
[11:37:57.670] <TB2> INFO: PH scale (per ROC): 85 99 82 81 79 79 80 84 89 85 78 80 98 88 85 90
[11:37:57.671] <TB2> INFO: PH offset (per ROC): 147 144 174 148 162 156 163 158 154 151 151 165 135 155 142 150
[11:37:57.681] <TB2> INFO: Decoding statistics:
[11:37:57.681] <TB2> INFO: General information:
[11:37:57.681] <TB2> INFO: 16bit words read: 91920
[11:37:57.681] <TB2> INFO: valid events total: 10240
[11:37:57.681] <TB2> INFO: empty events: 7740
[11:37:57.681] <TB2> INFO: valid events with pixels: 2500
[11:37:57.681] <TB2> INFO: valid pixel hits: 2500
[11:37:57.681] <TB2> INFO: Event errors: 0
[11:37:57.681] <TB2> INFO: start marker: 0
[11:37:57.681] <TB2> INFO: stop marker: 0
[11:37:57.681] <TB2> INFO: overflow: 0
[11:37:57.681] <TB2> INFO: invalid 5bit words: 0
[11:37:57.681] <TB2> INFO: invalid XOR eye diagram: 0
[11:37:57.681] <TB2> INFO: frame (failed synchr.): 0
[11:37:57.681] <TB2> INFO: idle data (no TBM trl): 0
[11:37:57.681] <TB2> INFO: no data (only TBM hdr): 0
[11:37:57.681] <TB2> INFO: TBM errors: 0
[11:37:57.681] <TB2> INFO: flawed TBM headers: 0
[11:37:57.681] <TB2> INFO: flawed TBM trailers: 0
[11:37:57.681] <TB2> INFO: event ID mismatches: 0
[11:37:57.681] <TB2> INFO: ROC errors: 0
[11:37:57.681] <TB2> INFO: missing ROC header(s): 0
[11:37:57.681] <TB2> INFO: misplaced readback start: 0
[11:37:57.681] <TB2> INFO: Pixel decoding errors: 0
[11:37:57.681] <TB2> INFO: pixel data incomplete: 0
[11:37:57.681] <TB2> INFO: pixel address: 0
[11:37:57.681] <TB2> INFO: pulse height fill bit: 0
[11:37:57.681] <TB2> INFO: buffer corruption: 0
[11:37:57.858] <TB2> INFO: ######################################################################
[11:37:57.858] <TB2> INFO: PixTestGainPedestal::fullTest() ntrig = 10
[11:37:57.858] <TB2> INFO: ######################################################################
[11:37:57.868] <TB2> INFO: scanning low vcal = 10
[11:37:58.154] <TB2> INFO: Expecting 41600 events.
[11:38:01.703] <TB2> INFO: 41600 events read in total (2993ms).
[11:38:01.704] <TB2> INFO: Test took 3836ms.
[11:38:01.706] <TB2> INFO: scanning low vcal = 20
[11:38:02.009] <TB2> INFO: Expecting 41600 events.
[11:38:05.556] <TB2> INFO: 41600 events read in total (2991ms).
[11:38:05.557] <TB2> INFO: Test took 3851ms.
[11:38:05.558] <TB2> INFO: scanning low vcal = 30
[11:38:05.861] <TB2> INFO: Expecting 41600 events.
[11:38:09.445] <TB2> INFO: 41600 events read in total (3027ms).
[11:38:09.445] <TB2> INFO: Test took 3886ms.
[11:38:09.447] <TB2> INFO: scanning low vcal = 40
[11:38:09.743] <TB2> INFO: Expecting 41600 events.
[11:38:13.977] <TB2> INFO: 41600 events read in total (3677ms).
[11:38:13.978] <TB2> INFO: Test took 4531ms.
[11:38:13.981] <TB2> INFO: scanning low vcal = 50
[11:38:14.250] <TB2> INFO: Expecting 41600 events.
[11:38:18.469] <TB2> INFO: 41600 events read in total (3663ms).
[11:38:18.469] <TB2> INFO: Test took 4488ms.
[11:38:18.472] <TB2> INFO: scanning low vcal = 60
[11:38:18.729] <TB2> INFO: Expecting 41600 events.
[11:38:23.009] <TB2> INFO: 41600 events read in total (3723ms).
[11:38:23.010] <TB2> INFO: Test took 4538ms.
[11:38:23.013] <TB2> INFO: scanning low vcal = 70
[11:38:23.306] <TB2> INFO: Expecting 41600 events.
[11:38:27.574] <TB2> INFO: 41600 events read in total (3711ms).
[11:38:27.575] <TB2> INFO: Test took 4562ms.
[11:38:27.578] <TB2> INFO: scanning low vcal = 80
[11:38:27.879] <TB2> INFO: Expecting 41600 events.
[11:38:32.064] <TB2> INFO: 41600 events read in total (3629ms).
[11:38:32.064] <TB2> INFO: Test took 4486ms.
[11:38:32.067] <TB2> INFO: scanning low vcal = 90
[11:38:32.329] <TB2> INFO: Expecting 41600 events.
[11:38:36.480] <TB2> INFO: 41600 events read in total (3595ms).
[11:38:36.481] <TB2> INFO: Test took 4414ms.
[11:38:36.483] <TB2> INFO: scanning low vcal = 100
[11:38:36.738] <TB2> INFO: Expecting 41600 events.
[11:38:40.913] <TB2> INFO: 41600 events read in total (3618ms).
[11:38:40.914] <TB2> INFO: Test took 4430ms.
[11:38:40.916] <TB2> INFO: scanning low vcal = 110
[11:38:41.169] <TB2> INFO: Expecting 41600 events.
[11:38:45.357] <TB2> INFO: 41600 events read in total (3631ms).
[11:38:45.358] <TB2> INFO: Test took 4442ms.
[11:38:45.360] <TB2> INFO: scanning low vcal = 120
[11:38:45.614] <TB2> INFO: Expecting 41600 events.
[11:38:49.764] <TB2> INFO: 41600 events read in total (3594ms).
[11:38:49.764] <TB2> INFO: Test took 4404ms.
[11:38:49.767] <TB2> INFO: scanning low vcal = 130
[11:38:50.021] <TB2> INFO: Expecting 41600 events.
[11:38:54.171] <TB2> INFO: 41600 events read in total (3594ms).
[11:38:54.171] <TB2> INFO: Test took 4404ms.
[11:38:54.174] <TB2> INFO: scanning low vcal = 140
[11:38:54.429] <TB2> INFO: Expecting 41600 events.
[11:38:58.580] <TB2> INFO: 41600 events read in total (3595ms).
[11:38:58.581] <TB2> INFO: Test took 4407ms.
[11:38:58.583] <TB2> INFO: scanning low vcal = 150
[11:38:58.837] <TB2> INFO: Expecting 41600 events.
[11:39:02.987] <TB2> INFO: 41600 events read in total (3594ms).
[11:39:02.988] <TB2> INFO: Test took 4405ms.
[11:39:02.990] <TB2> INFO: scanning low vcal = 160
[11:39:03.245] <TB2> INFO: Expecting 41600 events.
[11:39:07.395] <TB2> INFO: 41600 events read in total (3594ms).
[11:39:07.396] <TB2> INFO: Test took 4406ms.
[11:39:07.398] <TB2> INFO: scanning low vcal = 170
[11:39:07.653] <TB2> INFO: Expecting 41600 events.
[11:39:11.804] <TB2> INFO: 41600 events read in total (3595ms).
[11:39:11.804] <TB2> INFO: Test took 4405ms.
[11:39:11.808] <TB2> INFO: scanning low vcal = 180
[11:39:12.062] <TB2> INFO: Expecting 41600 events.
[11:39:16.214] <TB2> INFO: 41600 events read in total (3595ms).
[11:39:16.215] <TB2> INFO: Test took 4407ms.
[11:39:16.217] <TB2> INFO: scanning low vcal = 190
[11:39:16.473] <TB2> INFO: Expecting 41600 events.
[11:39:20.631] <TB2> INFO: 41600 events read in total (3602ms).
[11:39:20.632] <TB2> INFO: Test took 4415ms.
[11:39:20.634] <TB2> INFO: scanning low vcal = 200
[11:39:20.889] <TB2> INFO: Expecting 41600 events.
[11:39:25.041] <TB2> INFO: 41600 events read in total (3596ms).
[11:39:25.042] <TB2> INFO: Test took 4407ms.
[11:39:25.044] <TB2> INFO: scanning low vcal = 210
[11:39:25.299] <TB2> INFO: Expecting 41600 events.
[11:39:29.449] <TB2> INFO: 41600 events read in total (3594ms).
[11:39:29.450] <TB2> INFO: Test took 4406ms.
[11:39:29.452] <TB2> INFO: scanning low vcal = 220
[11:39:29.706] <TB2> INFO: Expecting 41600 events.
[11:39:33.859] <TB2> INFO: 41600 events read in total (3597ms).
[11:39:33.860] <TB2> INFO: Test took 4408ms.
[11:39:33.862] <TB2> INFO: scanning low vcal = 230
[11:39:34.117] <TB2> INFO: Expecting 41600 events.
[11:39:38.268] <TB2> INFO: 41600 events read in total (3595ms).
[11:39:38.268] <TB2> INFO: Test took 4406ms.
[11:39:38.271] <TB2> INFO: scanning low vcal = 240
[11:39:38.525] <TB2> INFO: Expecting 41600 events.
[11:39:42.677] <TB2> INFO: 41600 events read in total (3596ms).
[11:39:42.678] <TB2> INFO: Test took 4407ms.
[11:39:42.680] <TB2> INFO: scanning low vcal = 250
[11:39:42.934] <TB2> INFO: Expecting 41600 events.
[11:39:47.086] <TB2> INFO: 41600 events read in total (3595ms).
[11:39:47.087] <TB2> INFO: Test took 4406ms.
[11:39:47.090] <TB2> INFO: scanning high vcal = 30 (= 210 in low range)
[11:39:47.346] <TB2> INFO: Expecting 41600 events.
[11:39:51.497] <TB2> INFO: 41600 events read in total (3595ms).
[11:39:51.498] <TB2> INFO: Test took 4408ms.
[11:39:51.500] <TB2> INFO: scanning high vcal = 50 (= 350 in low range)
[11:39:51.754] <TB2> INFO: Expecting 41600 events.
[11:39:55.904] <TB2> INFO: 41600 events read in total (3594ms).
[11:39:55.904] <TB2> INFO: Test took 4404ms.
[11:39:55.907] <TB2> INFO: scanning high vcal = 70 (= 490 in low range)
[11:39:56.161] <TB2> INFO: Expecting 41600 events.
[11:40:00.314] <TB2> INFO: 41600 events read in total (3596ms).
[11:40:00.314] <TB2> INFO: Test took 4407ms.
[11:40:00.317] <TB2> INFO: scanning high vcal = 90 (= 630 in low range)
[11:40:00.571] <TB2> INFO: Expecting 41600 events.
[11:40:04.722] <TB2> INFO: 41600 events read in total (3595ms).
[11:40:04.723] <TB2> INFO: Test took 4406ms.
[11:40:04.725] <TB2> INFO: scanning high vcal = 200 (= 1400 in low range)
[11:40:04.979] <TB2> INFO: Expecting 41600 events.
[11:40:09.129] <TB2> INFO: 41600 events read in total (3594ms).
[11:40:09.130] <TB2> INFO: Test took 4405ms.
[11:40:09.552] <TB2> INFO: PixTestGainPedestal::measure() done
[11:40:39.069] <TB2> INFO: PixTestGainPedestal::fit() done
[11:40:39.069] <TB2> INFO: non-linearity mean: 0.949 0.960 0.956 0.947 0.953 0.956 0.960 0.959 0.952 0.958 0.952 0.959 0.952 0.955 0.952 0.952
[11:40:39.069] <TB2> INFO: non-linearity RMS: 0.006 0.004 0.006 0.007 0.006 0.006 0.006 0.006 0.006 0.006 0.006 0.006 0.006 0.005 0.006 0.006
[11:40:39.069] <TB2> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2301_FullQualification_2016-08-29_11h59m_1472464790//000_FulltestPxar_m20//phCalibrationFitErr35_C0.dat
[11:40:39.086] <TB2> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2301_FullQualification_2016-08-29_11h59m_1472464790//000_FulltestPxar_m20//phCalibrationFitErr35_C1.dat
[11:40:39.103] <TB2> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2301_FullQualification_2016-08-29_11h59m_1472464790//000_FulltestPxar_m20//phCalibrationFitErr35_C2.dat
[11:40:39.121] <TB2> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2301_FullQualification_2016-08-29_11h59m_1472464790//000_FulltestPxar_m20//phCalibrationFitErr35_C3.dat
[11:40:39.138] <TB2> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2301_FullQualification_2016-08-29_11h59m_1472464790//000_FulltestPxar_m20//phCalibrationFitErr35_C4.dat
[11:40:39.155] <TB2> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2301_FullQualification_2016-08-29_11h59m_1472464790//000_FulltestPxar_m20//phCalibrationFitErr35_C5.dat
[11:40:39.173] <TB2> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2301_FullQualification_2016-08-29_11h59m_1472464790//000_FulltestPxar_m20//phCalibrationFitErr35_C6.dat
[11:40:39.190] <TB2> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2301_FullQualification_2016-08-29_11h59m_1472464790//000_FulltestPxar_m20//phCalibrationFitErr35_C7.dat
[11:40:39.208] <TB2> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2301_FullQualification_2016-08-29_11h59m_1472464790//000_FulltestPxar_m20//phCalibrationFitErr35_C8.dat
[11:40:39.225] <TB2> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2301_FullQualification_2016-08-29_11h59m_1472464790//000_FulltestPxar_m20//phCalibrationFitErr35_C9.dat
[11:40:39.242] <TB2> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2301_FullQualification_2016-08-29_11h59m_1472464790//000_FulltestPxar_m20//phCalibrationFitErr35_C10.dat
[11:40:39.260] <TB2> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2301_FullQualification_2016-08-29_11h59m_1472464790//000_FulltestPxar_m20//phCalibrationFitErr35_C11.dat
[11:40:39.277] <TB2> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2301_FullQualification_2016-08-29_11h59m_1472464790//000_FulltestPxar_m20//phCalibrationFitErr35_C12.dat
[11:40:39.294] <TB2> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2301_FullQualification_2016-08-29_11h59m_1472464790//000_FulltestPxar_m20//phCalibrationFitErr35_C13.dat
[11:40:39.312] <TB2> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2301_FullQualification_2016-08-29_11h59m_1472464790//000_FulltestPxar_m20//phCalibrationFitErr35_C14.dat
[11:40:39.330] <TB2> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2301_FullQualification_2016-08-29_11h59m_1472464790//000_FulltestPxar_m20//phCalibrationFitErr35_C15.dat
[11:40:39.348] <TB2> INFO: PixTestGainPedestal::fullTest() done, duration: 161 seconds
[11:40:39.348] <TB2> INFO: Decoding statistics:
[11:40:39.348] <TB2> INFO: General information:
[11:40:39.348] <TB2> INFO: 16bit words read: 2662400
[11:40:39.348] <TB2> INFO: valid events total: 166400
[11:40:39.348] <TB2> INFO: empty events: 0
[11:40:39.348] <TB2> INFO: valid events with pixels: 166400
[11:40:39.348] <TB2> INFO: valid pixel hits: 665570
[11:40:39.348] <TB2> INFO: Event errors: 0
[11:40:39.348] <TB2> INFO: start marker: 0
[11:40:39.348] <TB2> INFO: stop marker: 0
[11:40:39.348] <TB2> INFO: overflow: 0
[11:40:39.348] <TB2> INFO: invalid 5bit words: 0
[11:40:39.348] <TB2> INFO: invalid XOR eye diagram: 0
[11:40:39.348] <TB2> INFO: frame (failed synchr.): 0
[11:40:39.348] <TB2> INFO: idle data (no TBM trl): 0
[11:40:39.348] <TB2> INFO: no data (only TBM hdr): 0
[11:40:39.348] <TB2> INFO: TBM errors: 0
[11:40:39.348] <TB2> INFO: flawed TBM headers: 0
[11:40:39.348] <TB2> INFO: flawed TBM trailers: 0
[11:40:39.348] <TB2> INFO: event ID mismatches: 0
[11:40:39.348] <TB2> INFO: ROC errors: 0
[11:40:39.348] <TB2> INFO: missing ROC header(s): 0
[11:40:39.348] <TB2> INFO: misplaced readback start: 0
[11:40:39.348] <TB2> INFO: Pixel decoding errors: 0
[11:40:39.348] <TB2> INFO: pixel data incomplete: 0
[11:40:39.348] <TB2> INFO: pixel address: 0
[11:40:39.348] <TB2> INFO: pulse height fill bit: 0
[11:40:39.348] <TB2> INFO: buffer corruption: 0
[11:40:39.355] <TB2> INFO: readReadbackCal: /usr/local/coldboxDATA/M2301_FullQualification_2016-08-29_11h59m_1472464790//000_FulltestPxar_m20//readbackCal_C0.dat .. /usr/local/coldboxDATA/M2301_FullQualification_2016-08-29_11h59m_1472464790//000_FulltestPxar_m20//readbackCal_C15.dat
[11:40:39.356] <TB2> INFO: ######################################################################
[11:40:39.356] <TB2> INFO: PixTestReadback::doTest()
[11:40:39.356] <TB2> INFO: ######################################################################
[11:40:39.356] <TB2> INFO: ----------------------------------------------------------------------
[11:40:39.356] <TB2> INFO: PixTestReadback::CalibrateVd()
[11:40:39.356] <TB2> INFO: ----------------------------------------------------------------------
[11:40:48.774] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2301_FullQualification_2016-08-29_11h59m_1472464790//000_FulltestPxar_m20//readbackCal_C0.dat
[11:40:48.774] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2301_FullQualification_2016-08-29_11h59m_1472464790//000_FulltestPxar_m20//readbackCal_C1.dat
[11:40:48.775] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2301_FullQualification_2016-08-29_11h59m_1472464790//000_FulltestPxar_m20//readbackCal_C2.dat
[11:40:48.775] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2301_FullQualification_2016-08-29_11h59m_1472464790//000_FulltestPxar_m20//readbackCal_C3.dat
[11:40:48.775] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2301_FullQualification_2016-08-29_11h59m_1472464790//000_FulltestPxar_m20//readbackCal_C4.dat
[11:40:48.775] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2301_FullQualification_2016-08-29_11h59m_1472464790//000_FulltestPxar_m20//readbackCal_C5.dat
[11:40:48.775] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2301_FullQualification_2016-08-29_11h59m_1472464790//000_FulltestPxar_m20//readbackCal_C6.dat
[11:40:48.775] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2301_FullQualification_2016-08-29_11h59m_1472464790//000_FulltestPxar_m20//readbackCal_C7.dat
[11:40:48.775] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2301_FullQualification_2016-08-29_11h59m_1472464790//000_FulltestPxar_m20//readbackCal_C8.dat
[11:40:48.775] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2301_FullQualification_2016-08-29_11h59m_1472464790//000_FulltestPxar_m20//readbackCal_C9.dat
[11:40:48.775] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2301_FullQualification_2016-08-29_11h59m_1472464790//000_FulltestPxar_m20//readbackCal_C10.dat
[11:40:48.775] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2301_FullQualification_2016-08-29_11h59m_1472464790//000_FulltestPxar_m20//readbackCal_C11.dat
[11:40:48.775] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2301_FullQualification_2016-08-29_11h59m_1472464790//000_FulltestPxar_m20//readbackCal_C12.dat
[11:40:48.775] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2301_FullQualification_2016-08-29_11h59m_1472464790//000_FulltestPxar_m20//readbackCal_C13.dat
[11:40:48.775] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2301_FullQualification_2016-08-29_11h59m_1472464790//000_FulltestPxar_m20//readbackCal_C14.dat
[11:40:48.776] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2301_FullQualification_2016-08-29_11h59m_1472464790//000_FulltestPxar_m20//readbackCal_C15.dat
[11:40:48.803] <TB2> INFO: PixTestPattern:: pg_setup set to default.
[11:40:48.803] <TB2> INFO: ----------------------------------------------------------------------
[11:40:48.803] <TB2> INFO: PixTestReadback::CalibrateVa()
[11:40:48.803] <TB2> INFO: ----------------------------------------------------------------------
[11:40:58.262] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2301_FullQualification_2016-08-29_11h59m_1472464790//000_FulltestPxar_m20//readbackCal_C0.dat
[11:40:58.262] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2301_FullQualification_2016-08-29_11h59m_1472464790//000_FulltestPxar_m20//readbackCal_C1.dat
[11:40:58.262] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2301_FullQualification_2016-08-29_11h59m_1472464790//000_FulltestPxar_m20//readbackCal_C2.dat
[11:40:58.262] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2301_FullQualification_2016-08-29_11h59m_1472464790//000_FulltestPxar_m20//readbackCal_C3.dat
[11:40:58.262] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2301_FullQualification_2016-08-29_11h59m_1472464790//000_FulltestPxar_m20//readbackCal_C4.dat
[11:40:58.262] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2301_FullQualification_2016-08-29_11h59m_1472464790//000_FulltestPxar_m20//readbackCal_C5.dat
[11:40:58.262] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2301_FullQualification_2016-08-29_11h59m_1472464790//000_FulltestPxar_m20//readbackCal_C6.dat
[11:40:58.262] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2301_FullQualification_2016-08-29_11h59m_1472464790//000_FulltestPxar_m20//readbackCal_C7.dat
[11:40:58.262] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2301_FullQualification_2016-08-29_11h59m_1472464790//000_FulltestPxar_m20//readbackCal_C8.dat
[11:40:58.262] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2301_FullQualification_2016-08-29_11h59m_1472464790//000_FulltestPxar_m20//readbackCal_C9.dat
[11:40:58.263] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2301_FullQualification_2016-08-29_11h59m_1472464790//000_FulltestPxar_m20//readbackCal_C10.dat
[11:40:58.263] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2301_FullQualification_2016-08-29_11h59m_1472464790//000_FulltestPxar_m20//readbackCal_C11.dat
[11:40:58.263] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2301_FullQualification_2016-08-29_11h59m_1472464790//000_FulltestPxar_m20//readbackCal_C12.dat
[11:40:58.263] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2301_FullQualification_2016-08-29_11h59m_1472464790//000_FulltestPxar_m20//readbackCal_C13.dat
[11:40:58.263] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2301_FullQualification_2016-08-29_11h59m_1472464790//000_FulltestPxar_m20//readbackCal_C14.dat
[11:40:58.263] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2301_FullQualification_2016-08-29_11h59m_1472464790//000_FulltestPxar_m20//readbackCal_C15.dat
[11:40:58.291] <TB2> INFO: PixTestPattern:: pg_setup set to default.
[11:40:58.291] <TB2> INFO: ----------------------------------------------------------------------
[11:40:58.291] <TB2> INFO: PixTestReadback::readbackVbg()
[11:40:58.291] <TB2> INFO: ----------------------------------------------------------------------
[11:41:05.590] <TB2> INFO: PixTestPattern:: pg_setup set to default.
[11:41:05.590] <TB2> INFO: ----------------------------------------------------------------------
[11:41:05.590] <TB2> INFO: PixTestReadback::getCalibratedVbg()
[11:41:05.590] <TB2> INFO: ----------------------------------------------------------------------
[11:41:05.590] <TB2> INFO: Vbg will be calibrated using Vd calibration
[11:41:05.590] <TB2> INFO: /*/*/*/*::: ROC 0: uncalibrated Vbg = 154.9calibrated Vbg = 1.19613 :::*/*/*/*/
[11:41:05.590] <TB2> INFO: /*/*/*/*::: ROC 1: uncalibrated Vbg = 151.2calibrated Vbg = 1.19666 :::*/*/*/*/
[11:41:05.590] <TB2> INFO: /*/*/*/*::: ROC 2: uncalibrated Vbg = 153.1calibrated Vbg = 1.19794 :::*/*/*/*/
[11:41:05.590] <TB2> INFO: /*/*/*/*::: ROC 3: uncalibrated Vbg = 147.9calibrated Vbg = 1.20805 :::*/*/*/*/
[11:41:05.590] <TB2> INFO: /*/*/*/*::: ROC 4: uncalibrated Vbg = 155.4calibrated Vbg = 1.20853 :::*/*/*/*/
[11:41:05.590] <TB2> INFO: /*/*/*/*::: ROC 5: uncalibrated Vbg = 156.8calibrated Vbg = 1.21105 :::*/*/*/*/
[11:41:05.590] <TB2> INFO: /*/*/*/*::: ROC 6: uncalibrated Vbg = 157.2calibrated Vbg = 1.20502 :::*/*/*/*/
[11:41:05.590] <TB2> INFO: /*/*/*/*::: ROC 7: uncalibrated Vbg = 149.9calibrated Vbg = 1.21017 :::*/*/*/*/
[11:41:05.590] <TB2> INFO: /*/*/*/*::: ROC 8: uncalibrated Vbg = 150.5calibrated Vbg = 1.20763 :::*/*/*/*/
[11:41:05.590] <TB2> INFO: /*/*/*/*::: ROC 9: uncalibrated Vbg = 149.8calibrated Vbg = 1.21126 :::*/*/*/*/
[11:41:05.590] <TB2> INFO: /*/*/*/*::: ROC 10: uncalibrated Vbg = 143.5calibrated Vbg = 1.21215 :::*/*/*/*/
[11:41:05.590] <TB2> INFO: /*/*/*/*::: ROC 11: uncalibrated Vbg = 154.2calibrated Vbg = 1.19698 :::*/*/*/*/
[11:41:05.590] <TB2> INFO: /*/*/*/*::: ROC 12: uncalibrated Vbg = 149.4calibrated Vbg = 1.20329 :::*/*/*/*/
[11:41:05.590] <TB2> INFO: /*/*/*/*::: ROC 13: uncalibrated Vbg = 148.4calibrated Vbg = 1.20278 :::*/*/*/*/
[11:41:05.590] <TB2> INFO: /*/*/*/*::: ROC 14: uncalibrated Vbg = 151.9calibrated Vbg = 1.21254 :::*/*/*/*/
[11:41:05.590] <TB2> INFO: /*/*/*/*::: ROC 15: uncalibrated Vbg = 154.1calibrated Vbg = 1.20128 :::*/*/*/*/
[11:41:05.592] <TB2> INFO: ----------------------------------------------------------------------
[11:41:05.592] <TB2> INFO: PixTestReadback::CalibrateIa()
[11:41:05.592] <TB2> INFO: ----------------------------------------------------------------------
[11:43:40.799] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2301_FullQualification_2016-08-29_11h59m_1472464790//000_FulltestPxar_m20//readbackCal_C0.dat
[11:43:40.799] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2301_FullQualification_2016-08-29_11h59m_1472464790//000_FulltestPxar_m20//readbackCal_C1.dat
[11:43:40.799] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2301_FullQualification_2016-08-29_11h59m_1472464790//000_FulltestPxar_m20//readbackCal_C2.dat
[11:43:40.799] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2301_FullQualification_2016-08-29_11h59m_1472464790//000_FulltestPxar_m20//readbackCal_C3.dat
[11:43:40.799] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2301_FullQualification_2016-08-29_11h59m_1472464790//000_FulltestPxar_m20//readbackCal_C4.dat
[11:43:40.799] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2301_FullQualification_2016-08-29_11h59m_1472464790//000_FulltestPxar_m20//readbackCal_C5.dat
[11:43:40.799] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2301_FullQualification_2016-08-29_11h59m_1472464790//000_FulltestPxar_m20//readbackCal_C6.dat
[11:43:40.799] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2301_FullQualification_2016-08-29_11h59m_1472464790//000_FulltestPxar_m20//readbackCal_C7.dat
[11:43:40.799] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2301_FullQualification_2016-08-29_11h59m_1472464790//000_FulltestPxar_m20//readbackCal_C8.dat
[11:43:40.799] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2301_FullQualification_2016-08-29_11h59m_1472464790//000_FulltestPxar_m20//readbackCal_C9.dat
[11:43:40.799] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2301_FullQualification_2016-08-29_11h59m_1472464790//000_FulltestPxar_m20//readbackCal_C10.dat
[11:43:40.799] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2301_FullQualification_2016-08-29_11h59m_1472464790//000_FulltestPxar_m20//readbackCal_C11.dat
[11:43:40.800] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2301_FullQualification_2016-08-29_11h59m_1472464790//000_FulltestPxar_m20//readbackCal_C12.dat
[11:43:40.800] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2301_FullQualification_2016-08-29_11h59m_1472464790//000_FulltestPxar_m20//readbackCal_C13.dat
[11:43:40.800] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2301_FullQualification_2016-08-29_11h59m_1472464790//000_FulltestPxar_m20//readbackCal_C14.dat
[11:43:40.800] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2301_FullQualification_2016-08-29_11h59m_1472464790//000_FulltestPxar_m20//readbackCal_C15.dat
[11:43:40.827] <TB2> INFO: PixTestPattern:: pg_setup set to default.
[11:43:40.828] <TB2> INFO: PixTestReadback::doTest() done
[11:43:40.828] <TB2> INFO: Decoding statistics:
[11:43:40.828] <TB2> INFO: General information:
[11:43:40.828] <TB2> INFO: 16bit words read: 1024
[11:43:40.828] <TB2> INFO: valid events total: 128
[11:43:40.828] <TB2> INFO: empty events: 128
[11:43:40.828] <TB2> INFO: valid events with pixels: 0
[11:43:40.828] <TB2> INFO: valid pixel hits: 0
[11:43:40.828] <TB2> INFO: Event errors: 0
[11:43:40.828] <TB2> INFO: start marker: 0
[11:43:40.828] <TB2> INFO: stop marker: 0
[11:43:40.828] <TB2> INFO: overflow: 0
[11:43:40.828] <TB2> INFO: invalid 5bit words: 0
[11:43:40.828] <TB2> INFO: invalid XOR eye diagram: 0
[11:43:40.828] <TB2> INFO: frame (failed synchr.): 0
[11:43:40.828] <TB2> INFO: idle data (no TBM trl): 0
[11:43:40.828] <TB2> INFO: no data (only TBM hdr): 0
[11:43:40.828] <TB2> INFO: TBM errors: 0
[11:43:40.829] <TB2> INFO: flawed TBM headers: 0
[11:43:40.829] <TB2> INFO: flawed TBM trailers: 0
[11:43:40.829] <TB2> INFO: event ID mismatches: 0
[11:43:40.829] <TB2> INFO: ROC errors: 0
[11:43:40.829] <TB2> INFO: missing ROC header(s): 0
[11:43:40.829] <TB2> INFO: misplaced readback start: 0
[11:43:40.829] <TB2> INFO: Pixel decoding errors: 0
[11:43:40.829] <TB2> INFO: pixel data incomplete: 0
[11:43:40.829] <TB2> INFO: pixel address: 0
[11:43:40.829] <TB2> INFO: pulse height fill bit: 0
[11:43:40.829] <TB2> INFO: buffer corruption: 0
[11:43:40.846] <TB2> INFO: Decoding statistics:
[11:43:40.846] <TB2> INFO: General information:
[11:43:40.846] <TB2> INFO: 16bit words read: 2755344
[11:43:40.846] <TB2> INFO: valid events total: 176768
[11:43:40.846] <TB2> INFO: empty events: 7868
[11:43:40.846] <TB2> INFO: valid events with pixels: 168900
[11:43:40.846] <TB2> INFO: valid pixel hits: 668070
[11:43:40.846] <TB2> INFO: Event errors: 0
[11:43:40.846] <TB2> INFO: start marker: 0
[11:43:40.846] <TB2> INFO: stop marker: 0
[11:43:40.846] <TB2> INFO: overflow: 0
[11:43:40.846] <TB2> INFO: invalid 5bit words: 0
[11:43:40.846] <TB2> INFO: invalid XOR eye diagram: 0
[11:43:40.846] <TB2> INFO: frame (failed synchr.): 0
[11:43:40.846] <TB2> INFO: idle data (no TBM trl): 0
[11:43:40.846] <TB2> INFO: no data (only TBM hdr): 0
[11:43:40.846] <TB2> INFO: TBM errors: 0
[11:43:40.846] <TB2> INFO: flawed TBM headers: 0
[11:43:40.846] <TB2> INFO: flawed TBM trailers: 0
[11:43:40.846] <TB2> INFO: event ID mismatches: 0
[11:43:40.846] <TB2> INFO: ROC errors: 0
[11:43:40.846] <TB2> INFO: missing ROC header(s): 0
[11:43:40.846] <TB2> INFO: misplaced readback start: 0
[11:43:40.846] <TB2> INFO: Pixel decoding errors: 0
[11:43:40.847] <TB2> INFO: pixel data incomplete: 0
[11:43:40.847] <TB2> INFO: pixel address: 0
[11:43:40.847] <TB2> INFO: pulse height fill bit: 0
[11:43:40.847] <TB2> INFO: buffer corruption: 0
[11:43:40.847] <TB2> INFO: enter test to run
[11:43:40.847] <TB2> INFO: test: exit no parameter change
[11:43:41.046] <TB2> QUIET: Connection to board 156 closed.
[11:43:41.125] <TB2> INFO: pXar: this is the end, my friend