[09:11:28.411] <TB0> INFO: *** Welcome to pxar ***
[09:11:28.411] <TB0> INFO: *** Today: 2016/10/04
[09:11:29.783] <TB0> INFO: *** Version: 0b00-dirty
[09:11:29.783] <TB0> INFO: readRocDacs: /usr/local/coldboxDATA/M2348_FullQualification_2016-10-04_11h02m_1475571737//000_FulltestPxar_m20//dacParameters_C0.dat .. /usr/local/coldboxDATA/M2348_FullQualification_2016-10-04_11h02m_1475571737//000_FulltestPxar_m20//dacParameters_C15.dat
[09:11:29.784] <TB0> INFO: readTbmDacs: /usr/local/coldboxDATA/M2348_FullQualification_2016-10-04_11h02m_1475571737//000_FulltestPxar_m20//tbmParameters_C0a.dat .. /usr/local/coldboxDATA/M2348_FullQualification_2016-10-04_11h02m_1475571737//000_FulltestPxar_m20//tbmParameters_C0b.dat
[09:11:29.784] <TB0> INFO: readMaskFile: /usr/local/coldboxDATA/M2348_FullQualification_2016-10-04_11h02m_1475571737//000_FulltestPxar_m20//defaultMaskFile.dat
[09:11:29.784] <TB0> INFO: readTrimFile: /usr/local/coldboxDATA/M2348_FullQualification_2016-10-04_11h02m_1475571737//000_FulltestPxar_m20//trimParameters_C0.dat .. /usr/local/coldboxDATA/M2348_FullQualification_2016-10-04_11h02m_1475571737//000_FulltestPxar_m20//trimParameters_C15.dat
[09:11:29.849] <TB0> INFO: clk: 4
[09:11:29.849] <TB0> INFO: ctr: 4
[09:11:29.849] <TB0> INFO: sda: 19
[09:11:29.849] <TB0> INFO: tin: 9
[09:11:29.849] <TB0> INFO: level: 15
[09:11:29.849] <TB0> INFO: triggerdelay: 0
[09:11:29.849] <TB0> QUIET: Instanciating API for pxar v2.7.6+55~gafdbfd9
[09:11:29.849] <TB0> INFO: Log level: INFO
[09:11:29.857] <TB0> INFO: Found DTB DTB_WS6AYH
[09:11:29.870] <TB0> QUIET: Connection to board DTB_WS6AYH opened.
[09:11:29.872] <TB0> INFO: DTB startup information
--- DTB info------------------------------------------
Board id: 73
HW version: DTB1.2
FW version: 4.6
SW version: 4.7
Options:
USB id: DTB_WS6AYH
MAC address: 40D855118049
Hostname: pixelDTB073
Comment:
------------------------------------------------------
[09:11:29.875] <TB0> INFO: RPC call hashes of host and DTB match: 486171790
[09:11:31.374] <TB0> INFO: DUT info:
[09:11:31.374] <TB0> INFO: The DUT currently contains the following objects:
[09:11:31.374] <TB0> INFO: 2 TBM Cores tbm09c (2 ON)
[09:11:31.374] <TB0> INFO: TBM Core alpha (0): 7 registers set
[09:11:31.374] <TB0> INFO: TBM Core beta (1): 7 registers set
[09:11:31.374] <TB0> INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[09:11:31.374] <TB0> INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[09:11:31.374] <TB0> INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[09:11:31.374] <TB0> INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[09:11:31.374] <TB0> INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[09:11:31.374] <TB0> INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[09:11:31.374] <TB0> INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[09:11:31.374] <TB0> INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[09:11:31.374] <TB0> INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[09:11:31.374] <TB0> INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[09:11:31.374] <TB0> INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[09:11:31.374] <TB0> INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[09:11:31.374] <TB0> INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[09:11:31.374] <TB0> INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[09:11:31.374] <TB0> INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[09:11:31.374] <TB0> INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[09:11:31.374] <TB0> INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[09:11:31.775] <TB0> INFO: enter 'restricted' command line mode
[09:11:31.775] <TB0> INFO: enter test to run
[09:11:31.775] <TB0> INFO: test: pretest no parameter change
[09:11:31.775] <TB0> INFO: running: pretest
[09:11:31.779] <TB0> INFO: ----------------------------------------------------------------------
[09:11:31.779] <TB0> INFO: PixTestPretest::programROC()
[09:11:31.779] <TB0> INFO: ----------------------------------------------------------------------
[09:11:49.794] <TB0> INFO: PixTestPretest::programROC() done: ROCs are all programmable
[09:11:49.794] <TB0> INFO: IA differences per ROC: 17.7 16.1 17.7 18.5 17.7 18.5 17.7 16.9 20.1 19.3 19.3 18.5 17.7 19.3 17.7 18.5
[09:11:49.895] <TB0> INFO: enter test to run
[09:11:49.895] <TB0> INFO: test: pretest no parameter change
[09:11:49.895] <TB0> INFO: running: pretest
[09:11:49.897] <TB0> INFO: ----------------------------------------------------------------------
[09:11:49.897] <TB0> INFO: PixTestPretest::setVana() target Ia = 24 mA/ROC
[09:11:49.897] <TB0> INFO: ----------------------------------------------------------------------
[09:12:11.150] <TB0> INFO: PixTestPretest::setVana() done, Module Ia 389.9 mA = 24.3688 mA/ROC
[09:12:11.150] <TB0> INFO: i(loss) [mA/ROC]: 20.1 19.3 19.3 19.3 19.3 19.3 19.3 19.3 20.1 19.3 19.3 19.3 19.3 18.5 19.3 18.5
[09:12:11.178] <TB0> INFO: enter test to run
[09:12:11.178] <TB0> INFO: test: pretest no parameter change
[09:12:11.178] <TB0> INFO: running: pretest
[09:12:11.178] <TB0> INFO: ----------------------------------------------------------------------
[09:12:11.178] <TB0> INFO: PixTestPretest::findTiming()
[09:12:11.178] <TB0> INFO: ----------------------------------------------------------------------
[09:12:11.178] <TB0> INFO: PixTestCmd::init()

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


[09:13:48.942] <TB0> INFO: 231680 events read in total (8697ms).
[09:13:48.948] <TB0> INFO: Test took 9348ms.
[09:13:49.152] <TB0> INFO: Found working pixel in all ROCs: col/row = 12/22
[09:13:49.222] <TB0> INFO: enter test to run
[09:13:49.222] <TB0> INFO: test: pretest no parameter change
[09:13:49.222] <TB0> INFO: running: pretest
[09:13:49.224] <TB0> INFO: ----------------------------------------------------------------------
[09:13:49.224] <TB0> INFO: PixTestPretest::setVthrCompCalDel()
[09:13:49.224] <TB0> INFO: ----------------------------------------------------------------------
[09:13:49.316] <TB0> INFO: Expecting 231680 events.
[09:13:58.551] <TB0> INFO: 231680 events read in total (8678ms).
[09:13:58.557] <TB0> INFO: Test took 9330ms.
[09:13:58.785] <TB0> INFO: PixTestPretest::setVthrCompCalDel() done
[09:13:58.785] <TB0> INFO: CalDel: 124 124 138 157 147 145 132 140 148 141 134 125 143 137 131 141
[09:13:58.785] <TB0> INFO: VthrComp: 51 51 51 51 51 51 51 51 51 51 51 51 51 51 51 51
[09:13:58.833] <TB0> INFO: enter test to run
[09:13:58.833] <TB0> INFO: test: pretest no parameter change
[09:13:58.833] <TB0> INFO: running: pretest
[09:13:58.833] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2348_FullQualification_2016-10-04_11h02m_1475571737//000_FulltestPxar_m20//dacParameters_C0.dat
[09:13:58.833] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2348_FullQualification_2016-10-04_11h02m_1475571737//000_FulltestPxar_m20//dacParameters_C1.dat
[09:13:58.833] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2348_FullQualification_2016-10-04_11h02m_1475571737//000_FulltestPxar_m20//dacParameters_C2.dat
[09:13:58.834] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2348_FullQualification_2016-10-04_11h02m_1475571737//000_FulltestPxar_m20//dacParameters_C3.dat
[09:13:58.834] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2348_FullQualification_2016-10-04_11h02m_1475571737//000_FulltestPxar_m20//dacParameters_C4.dat
[09:13:58.834] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2348_FullQualification_2016-10-04_11h02m_1475571737//000_FulltestPxar_m20//dacParameters_C5.dat
[09:13:58.834] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2348_FullQualification_2016-10-04_11h02m_1475571737//000_FulltestPxar_m20//dacParameters_C6.dat
[09:13:58.834] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2348_FullQualification_2016-10-04_11h02m_1475571737//000_FulltestPxar_m20//dacParameters_C7.dat
[09:13:58.834] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2348_FullQualification_2016-10-04_11h02m_1475571737//000_FulltestPxar_m20//dacParameters_C8.dat
[09:13:58.834] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2348_FullQualification_2016-10-04_11h02m_1475571737//000_FulltestPxar_m20//dacParameters_C9.dat
[09:13:58.834] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2348_FullQualification_2016-10-04_11h02m_1475571737//000_FulltestPxar_m20//dacParameters_C10.dat
[09:13:58.835] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2348_FullQualification_2016-10-04_11h02m_1475571737//000_FulltestPxar_m20//dacParameters_C11.dat
[09:13:58.835] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2348_FullQualification_2016-10-04_11h02m_1475571737//000_FulltestPxar_m20//dacParameters_C12.dat
[09:13:58.835] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2348_FullQualification_2016-10-04_11h02m_1475571737//000_FulltestPxar_m20//dacParameters_C13.dat
[09:13:58.835] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2348_FullQualification_2016-10-04_11h02m_1475571737//000_FulltestPxar_m20//dacParameters_C14.dat
[09:13:58.835] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2348_FullQualification_2016-10-04_11h02m_1475571737//000_FulltestPxar_m20//dacParameters_C15.dat
[09:13:58.835] <TB0> INFO: enter test to run
[09:13:58.835] <TB0> INFO: test: fulltest no parameter change
[09:13:58.835] <TB0> INFO: running: fulltest
[09:13:58.836] <TB0> INFO: ######################################################################
[09:13:58.836] <TB0> INFO: PixTestFullTest::doTest()
[09:13:58.836] <TB0> INFO: ######################################################################
[09:13:58.837] <TB0> INFO: ######################################################################
[09:13:58.837] <TB0> INFO: PixTestAlive::doTest()
[09:13:58.837] <TB0> INFO: ######################################################################
[09:13:58.838] <TB0> INFO: ----------------------------------------------------------------------
[09:13:58.838] <TB0> INFO: PixTestAlive::aliveTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[09:13:58.838] <TB0> INFO: ----------------------------------------------------------------------
[09:13:59.110] <TB0> INFO: Expecting 41600 events.
[09:14:02.525] <TB0> INFO: 41600 events read in total (2859ms).
[09:14:02.526] <TB0> INFO: Test took 3687ms.
[09:14:02.748] <TB0> INFO: PixTestAlive::aliveTest() done
[09:14:02.748] <TB0> INFO: number of dead pixels (per ROC): 0 0 0 0 1 4 0 0 0 0 2 0 0 0 0 0
[09:14:02.749] <TB0> INFO: ----------------------------------------------------------------------
[09:14:02.749] <TB0> INFO: PixTestAlive::maskTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[09:14:02.749] <TB0> INFO: ----------------------------------------------------------------------
[09:14:03.011] <TB0> INFO: Expecting 41600 events.
[09:14:05.740] <TB0> INFO: 41600 events read in total (2173ms).
[09:14:05.742] <TB0> INFO: Test took 2992ms.
[09:14:05.752] <TB0> INFO: mask vs. old pixelAlive PixelAlive_C0_V0 .. PixelAlive_C15_V0
[09:14:05.977] <TB0> INFO: PixTestAlive::maskTest() done
[09:14:05.977] <TB0> INFO: number of mask-defect pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[09:14:05.978] <TB0> INFO: ----------------------------------------------------------------------
[09:14:05.978] <TB0> INFO: PixTestAlive::addressDecodingTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[09:14:05.978] <TB0> INFO: ----------------------------------------------------------------------
[09:14:06.242] <TB0> INFO: Expecting 41600 events.
[09:14:09.691] <TB0> INFO: 41600 events read in total (2893ms).
[09:14:09.692] <TB0> INFO: Test took 3713ms.
[09:14:09.904] <TB0> INFO: PixTestAlive::addressDecodingTest() done
[09:14:09.904] <TB0> INFO: number of address-decoding pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[09:14:09.904] <TB0> INFO: PixTestAlive::doTest() done, duration: 11 seconds
[09:14:09.904] <TB0> INFO: Decoding statistics:
[09:14:09.904] <TB0> INFO: General information:
[09:14:09.904] <TB0> INFO: 16bit words read: 0
[09:14:09.904] <TB0> INFO: valid events total: 0
[09:14:09.904] <TB0> INFO: empty events: 0
[09:14:09.904] <TB0> INFO: valid events with pixels: 0
[09:14:09.904] <TB0> INFO: valid pixel hits: 0
[09:14:09.904] <TB0> INFO: Event errors: 0
[09:14:09.904] <TB0> INFO: start marker: 0
[09:14:09.904] <TB0> INFO: stop marker: 0
[09:14:09.904] <TB0> INFO: overflow: 0
[09:14:09.904] <TB0> INFO: invalid 5bit words: 0
[09:14:09.904] <TB0> INFO: invalid XOR eye diagram: 0
[09:14:09.904] <TB0> INFO: frame (failed synchr.): 0
[09:14:09.904] <TB0> INFO: idle data (no TBM trl): 0
[09:14:09.904] <TB0> INFO: no data (only TBM hdr): 0
[09:14:09.904] <TB0> INFO: TBM errors: 0
[09:14:09.904] <TB0> INFO: flawed TBM headers: 0
[09:14:09.904] <TB0> INFO: flawed TBM trailers: 0
[09:14:09.904] <TB0> INFO: event ID mismatches: 0
[09:14:09.904] <TB0> INFO: ROC errors: 0
[09:14:09.904] <TB0> INFO: missing ROC header(s): 0
[09:14:09.905] <TB0> INFO: misplaced readback start: 0
[09:14:09.905] <TB0> INFO: Pixel decoding errors: 0
[09:14:09.905] <TB0> INFO: pixel data incomplete: 0
[09:14:09.905] <TB0> INFO: pixel address: 0
[09:14:09.905] <TB0> INFO: pulse height fill bit: 0
[09:14:09.905] <TB0> INFO: buffer corruption: 0
[09:14:09.916] <TB0> INFO: ######################################################################
[09:14:09.916] <TB0> INFO: PixTestBBMap::doTest() Ntrig = 5, VcalS = 250 (high range)
[09:14:09.916] <TB0> INFO: ######################################################################
[09:14:09.918] <TB0> INFO: ---> dac: VthrComp name: calSMap ntrig: 5 dacrange: 0 .. 149 (-1/-1) hits flags = 514 (plus default)
[09:14:09.928] <TB0> INFO: dacScan split into 1 runs with ntrig = 5
[09:14:09.928] <TB0> INFO: run 1 of 1
[09:14:10.193] <TB0> INFO: Expecting 3120000 events.
[09:14:46.021] <TB0> INFO: 820810 events read in total (35272ms).
[09:15:21.406] <TB0> INFO: 1638530 events read in total (70658ms).
[09:15:58.926] <TB0> INFO: 2475225 events read in total (108177ms).
[09:16:27.254] <TB0> INFO: 3120000 events read in total (136505ms).
[09:16:27.345] <TB0> INFO: Test took 137417ms.
[09:16:51.044] <TB0> INFO: PixTestBBMap::doTest() done, duration: 161 seconds
[09:16:51.044] <TB0> INFO: number of dead bumps (per ROC): 3 0 0 1 1 1 0 0 0 3 0 1 0 0 0 0
[09:16:51.044] <TB0> INFO: separation cut (per ROC): 89 88 99 85 73 82 87 80 86 77 95 80 80 86 81 87
[09:16:51.044] <TB0> INFO: Decoding statistics:
[09:16:51.044] <TB0> INFO: General information:
[09:16:51.044] <TB0> INFO: 16bit words read: 0
[09:16:51.044] <TB0> INFO: valid events total: 0
[09:16:51.044] <TB0> INFO: empty events: 0
[09:16:51.044] <TB0> INFO: valid events with pixels: 0
[09:16:51.044] <TB0> INFO: valid pixel hits: 0
[09:16:51.044] <TB0> INFO: Event errors: 0
[09:16:51.044] <TB0> INFO: start marker: 0
[09:16:51.044] <TB0> INFO: stop marker: 0
[09:16:51.044] <TB0> INFO: overflow: 0
[09:16:51.044] <TB0> INFO: invalid 5bit words: 0
[09:16:51.044] <TB0> INFO: invalid XOR eye diagram: 0
[09:16:51.044] <TB0> INFO: frame (failed synchr.): 0
[09:16:51.044] <TB0> INFO: idle data (no TBM trl): 0
[09:16:51.044] <TB0> INFO: no data (only TBM hdr): 0
[09:16:51.044] <TB0> INFO: TBM errors: 0
[09:16:51.044] <TB0> INFO: flawed TBM headers: 0
[09:16:51.044] <TB0> INFO: flawed TBM trailers: 0
[09:16:51.044] <TB0> INFO: event ID mismatches: 0
[09:16:51.044] <TB0> INFO: ROC errors: 0
[09:16:51.044] <TB0> INFO: missing ROC header(s): 0
[09:16:51.044] <TB0> INFO: misplaced readback start: 0
[09:16:51.044] <TB0> INFO: Pixel decoding errors: 0
[09:16:51.044] <TB0> INFO: pixel data incomplete: 0
[09:16:51.044] <TB0> INFO: pixel address: 0
[09:16:51.044] <TB0> INFO: pulse height fill bit: 0
[09:16:51.044] <TB0> INFO: buffer corruption: 0
[09:16:51.104] <TB0> INFO: ######################################################################
[09:16:51.104] <TB0> INFO: PixTestScurves::fullTest() ntrig = 50, dacs/step = -1, ntrig/step = -1
[09:16:51.104] <TB0> INFO: ######################################################################
[09:16:51.105] <TB0> INFO: ----------------------------------------------------------------------
[09:16:51.105] <TB0> INFO: PixTestScurves::scurves(Vcal), ntrig = 50, dacs/step = -1, ntrig/step = -1
[09:16:51.105] <TB0> INFO: ----------------------------------------------------------------------
[09:16:51.105] <TB0> INFO: ---> dac: Vcal name: scurveVcal ntrig: 50 dacrange: 25 .. 200 (-1/-1) hits flags = 528 (plus default)
[09:16:51.113] <TB0> INFO: dacScan split into 1 runs with ntrig = 50
[09:16:51.113] <TB0> INFO: run 1 of 1
[09:16:51.374] <TB0> INFO: Expecting 36608000 events.
[09:17:20.398] <TB0> INFO: 815750 events read in total (28468ms).
[09:17:48.641] <TB0> INFO: 1616350 events read in total (56711ms).
[09:18:16.780] <TB0> INFO: 2417800 events read in total (84850ms).
[09:18:44.708] <TB0> INFO: 3217750 events read in total (112778ms).
[09:19:12.755] <TB0> INFO: 4018150 events read in total (140825ms).
[09:19:40.894] <TB0> INFO: 4817850 events read in total (168964ms).
[09:20:08.970] <TB0> INFO: 5617150 events read in total (197040ms).
[09:20:37.340] <TB0> INFO: 6416050 events read in total (225410ms).
[09:21:05.738] <TB0> INFO: 7214250 events read in total (253808ms).
[09:21:34.396] <TB0> INFO: 8013900 events read in total (282466ms).
[09:22:03.002] <TB0> INFO: 8812250 events read in total (311072ms).
[09:22:31.831] <TB0> INFO: 9608500 events read in total (339901ms).
[09:23:00.592] <TB0> INFO: 10406750 events read in total (368662ms).
[09:23:29.649] <TB0> INFO: 11204350 events read in total (397719ms).
[09:23:58.612] <TB0> INFO: 12002300 events read in total (426682ms).
[09:24:26.958] <TB0> INFO: 12800950 events read in total (455028ms).
[09:24:55.901] <TB0> INFO: 13599750 events read in total (483971ms).
[09:25:23.975] <TB0> INFO: 14394400 events read in total (512045ms).
[09:25:52.433] <TB0> INFO: 15191350 events read in total (540503ms).
[09:26:20.747] <TB0> INFO: 15986700 events read in total (568817ms).
[09:26:49.002] <TB0> INFO: 16782550 events read in total (597072ms).
[09:27:17.510] <TB0> INFO: 17579000 events read in total (625580ms).
[09:27:46.372] <TB0> INFO: 18373250 events read in total (654442ms).
[09:28:14.515] <TB0> INFO: 19164550 events read in total (682585ms).
[09:28:42.752] <TB0> INFO: 19956200 events read in total (710822ms).
[09:29:10.892] <TB0> INFO: 20746550 events read in total (738962ms).
[09:29:39.282] <TB0> INFO: 21537300 events read in total (767352ms).
[09:30:07.507] <TB0> INFO: 22328600 events read in total (795577ms).
[09:30:35.933] <TB0> INFO: 23117400 events read in total (824003ms).
[09:31:03.983] <TB0> INFO: 23909000 events read in total (852053ms).
[09:31:32.056] <TB0> INFO: 24700800 events read in total (880126ms).
[09:32:00.465] <TB0> INFO: 25491150 events read in total (908535ms).
[09:32:28.690] <TB0> INFO: 26282000 events read in total (936760ms).
[09:32:56.784] <TB0> INFO: 27069450 events read in total (964854ms).
[09:33:24.743] <TB0> INFO: 27859300 events read in total (992813ms).
[09:33:52.884] <TB0> INFO: 28648500 events read in total (1020954ms).
[09:34:20.964] <TB0> INFO: 29437400 events read in total (1049034ms).
[09:34:49.046] <TB0> INFO: 30227400 events read in total (1077116ms).
[09:35:17.006] <TB0> INFO: 31016150 events read in total (1105076ms).
[09:35:45.369] <TB0> INFO: 31806000 events read in total (1133439ms).
[09:36:13.618] <TB0> INFO: 32595750 events read in total (1161688ms).
[09:36:41.808] <TB0> INFO: 33385600 events read in total (1189878ms).
[09:37:09.921] <TB0> INFO: 34175600 events read in total (1217991ms).
[09:37:37.927] <TB0> INFO: 34966650 events read in total (1245997ms).
[09:38:06.152] <TB0> INFO: 35757250 events read in total (1274222ms).
[09:38:35.082] <TB0> INFO: 36562050 events read in total (1303152ms).
[09:38:37.305] <TB0> INFO: 36608000 events read in total (1305375ms).
[09:38:37.391] <TB0> INFO: Test took 1306278ms.
[09:38:38.016] <TB0> INFO: dumping ASCII scurve output file: SCurveData
[09:38:39.906] <TB0> INFO: dumping ASCII scurve output file: SCurveData
[09:38:41.750] <TB0> INFO: dumping ASCII scurve output file: SCurveData
[09:38:43.835] <TB0> INFO: dumping ASCII scurve output file: SCurveData
[09:38:45.962] <TB0> INFO: dumping ASCII scurve output file: SCurveData
[09:38:47.911] <TB0> INFO: dumping ASCII scurve output file: SCurveData
[09:38:50.052] <TB0> INFO: dumping ASCII scurve output file: SCurveData
[09:38:51.966] <TB0> INFO: dumping ASCII scurve output file: SCurveData
[09:38:53.656] <TB0> INFO: dumping ASCII scurve output file: SCurveData
[09:38:55.473] <TB0> INFO: dumping ASCII scurve output file: SCurveData
[09:38:57.215] <TB0> INFO: dumping ASCII scurve output file: SCurveData
[09:38:59.075] <TB0> INFO: dumping ASCII scurve output file: SCurveData
[09:39:00.829] <TB0> INFO: dumping ASCII scurve output file: SCurveData
[09:39:02.517] <TB0> INFO: dumping ASCII scurve output file: SCurveData
[09:39:04.178] <TB0> INFO: dumping ASCII scurve output file: SCurveData
[09:39:05.854] <TB0> INFO: dumping ASCII scurve output file: SCurveData
[09:39:07.734] <TB0> INFO: PixTestScurves::scurves() done
[09:39:07.734] <TB0> INFO: Vcal mean: 79.99 81.46 96.51 86.72 77.25 82.44 84.78 82.99 83.85 91.90 85.69 81.28 86.69 79.26 80.56 83.03
[09:39:07.734] <TB0> INFO: Vcal RMS: 4.19 4.22 5.02 4.77 4.25 5.86 4.41 4.37 5.42 5.39 5.23 4.27 4.69 4.35 4.01 4.98
[09:39:07.734] <TB0> INFO: PixTestScurves::fullTest() done, duration: 1336 seconds
[09:39:07.734] <TB0> INFO: Decoding statistics:
[09:39:07.734] <TB0> INFO: General information:
[09:39:07.734] <TB0> INFO: 16bit words read: 0
[09:39:07.734] <TB0> INFO: valid events total: 0
[09:39:07.734] <TB0> INFO: empty events: 0
[09:39:07.734] <TB0> INFO: valid events with pixels: 0
[09:39:07.734] <TB0> INFO: valid pixel hits: 0
[09:39:07.734] <TB0> INFO: Event errors: 0
[09:39:07.734] <TB0> INFO: start marker: 0
[09:39:07.734] <TB0> INFO: stop marker: 0
[09:39:07.734] <TB0> INFO: overflow: 0
[09:39:07.734] <TB0> INFO: invalid 5bit words: 0
[09:39:07.734] <TB0> INFO: invalid XOR eye diagram: 0
[09:39:07.734] <TB0> INFO: frame (failed synchr.): 0
[09:39:07.734] <TB0> INFO: idle data (no TBM trl): 0
[09:39:07.734] <TB0> INFO: no data (only TBM hdr): 0
[09:39:07.734] <TB0> INFO: TBM errors: 0
[09:39:07.734] <TB0> INFO: flawed TBM headers: 0
[09:39:07.734] <TB0> INFO: flawed TBM trailers: 0
[09:39:07.734] <TB0> INFO: event ID mismatches: 0
[09:39:07.734] <TB0> INFO: ROC errors: 0
[09:39:07.734] <TB0> INFO: missing ROC header(s): 0
[09:39:07.734] <TB0> INFO: misplaced readback start: 0
[09:39:07.734] <TB0> INFO: Pixel decoding errors: 0
[09:39:07.734] <TB0> INFO: pixel data incomplete: 0
[09:39:07.734] <TB0> INFO: pixel address: 0
[09:39:07.734] <TB0> INFO: pulse height fill bit: 0
[09:39:07.734] <TB0> INFO: buffer corruption: 0
[09:39:07.806] <TB0> INFO: ######################################################################
[09:39:07.806] <TB0> INFO: PixTestTrim::doTest()
[09:39:07.806] <TB0> INFO: ######################################################################
[09:39:07.807] <TB0> INFO: ----------------------------------------------------------------------
[09:39:07.807] <TB0> INFO: PixTestTrim::trimTest() ntrig = 10, vcal = 35
[09:39:07.807] <TB0> INFO: ----------------------------------------------------------------------
[09:39:07.889] <TB0> INFO: ---> VthrComp thr map (minimal VthrComp)
[09:39:07.889] <TB0> INFO: ---> dac: vthrcomp name: TrimThr0 ntrig: 10 dacrange: 10 .. 160 (-1/-1) hits flags = 528 (plus default)
[09:39:07.898] <TB0> INFO: dacScan split into 1 runs with ntrig = 10
[09:39:07.898] <TB0> INFO: run 1 of 1
[09:39:08.160] <TB0> INFO: Expecting 6281600 events.
[09:39:46.844] <TB0> INFO: 1084040 events read in total (38126ms).
[09:40:23.302] <TB0> INFO: 2163070 events read in total (74585ms).
[09:40:59.287] <TB0> INFO: 3235290 events read in total (110569ms).
[09:41:34.445] <TB0> INFO: 4313610 events read in total (145727ms).
[09:42:10.178] <TB0> INFO: 5396080 events read in total (181460ms).
[09:42:39.528] <TB0> INFO: 6281600 events read in total (210810ms).
[09:42:39.590] <TB0> INFO: Test took 211693ms.
[09:43:00.859] <TB0> INFO: ROC 0 VthrComp = 90
[09:43:00.859] <TB0> INFO: ROC 1 VthrComp = 90
[09:43:00.859] <TB0> INFO: ROC 2 VthrComp = 106
[09:43:00.859] <TB0> INFO: ROC 3 VthrComp = 95
[09:43:00.859] <TB0> INFO: ROC 4 VthrComp = 85
[09:43:00.859] <TB0> INFO: ROC 5 VthrComp = 90
[09:43:00.860] <TB0> INFO: ROC 6 VthrComp = 95
[09:43:00.860] <TB0> INFO: ROC 7 VthrComp = 93
[09:43:00.860] <TB0> INFO: ROC 8 VthrComp = 86
[09:43:00.860] <TB0> INFO: ROC 9 VthrComp = 98
[09:43:00.860] <TB0> INFO: ROC 10 VthrComp = 95
[09:43:00.860] <TB0> INFO: ROC 11 VthrComp = 89
[09:43:00.860] <TB0> INFO: ROC 12 VthrComp = 94
[09:43:00.860] <TB0> INFO: ROC 13 VthrComp = 87
[09:43:00.860] <TB0> INFO: ROC 14 VthrComp = 89
[09:43:00.860] <TB0> INFO: ROC 15 VthrComp = 89
[09:43:00.860] <TB0> INFO: ---> Vcal thr map (pixel with maximum Vcal thr)
[09:43:00.860] <TB0> INFO: ---> dac: vcal name: TrimThr1 ntrig: 10 dacrange: 10 .. 160 (-1/-1) hits flags = 528 (plus default)
[09:43:00.869] <TB0> INFO: dacScan split into 1 runs with ntrig = 10
[09:43:00.869] <TB0> INFO: run 1 of 1
[09:43:01.134] <TB0> INFO: Expecting 6281600 events.
[09:43:33.506] <TB0> INFO: 758300 events read in total (31816ms).
[09:44:04.697] <TB0> INFO: 1514480 events read in total (63007ms).
[09:44:35.814] <TB0> INFO: 2270920 events read in total (94124ms).
[09:45:06.470] <TB0> INFO: 3026810 events read in total (124780ms).
[09:45:37.307] <TB0> INFO: 3777980 events read in total (155617ms).
[09:46:07.925] <TB0> INFO: 4526980 events read in total (186235ms).
[09:46:38.083] <TB0> INFO: 5273720 events read in total (216393ms).
[09:47:08.807] <TB0> INFO: 6019930 events read in total (247117ms).
[09:47:19.714] <TB0> INFO: 6281600 events read in total (258024ms).
[09:47:19.775] <TB0> INFO: Test took 258906ms.
[09:47:45.315] <TB0> INFO: roc 0 with ID = 0 has maximal Vcal 56.8254 for pixel 0/3 mean/min/max = 45.0473/33.1938/56.9008
[09:47:45.315] <TB0> INFO: roc 1 with ID = 1 has maximal Vcal 55.5223 for pixel 46/7 mean/min/max = 44.5622/33.0356/56.0887
[09:47:45.315] <TB0> INFO: roc 2 with ID = 2 has maximal Vcal 58.5585 for pixel 40/7 mean/min/max = 45.8336/33.0769/58.5902
[09:47:45.316] <TB0> INFO: roc 3 with ID = 3 has maximal Vcal 56.7367 for pixel 19/1 mean/min/max = 44.2392/31.6808/56.7976
[09:47:45.316] <TB0> INFO: roc 4 with ID = 4 has maximal Vcal 55.4959 for pixel 0/79 mean/min/max = 43.6575/31.7565/55.5586
[09:47:45.316] <TB0> INFO: roc 5 with ID = 5 has maximal Vcal 60.1938 for pixel 23/55 mean/min/max = 45.8054/31.3684/60.2424
[09:47:45.316] <TB0> INFO: roc 6 with ID = 6 has maximal Vcal 55.1123 for pixel 8/77 mean/min/max = 44.0151/32.6504/55.3798
[09:47:45.317] <TB0> INFO: roc 7 with ID = 7 has maximal Vcal 56.3181 for pixel 12/76 mean/min/max = 44.3872/32.4394/56.335
[09:47:45.317] <TB0> INFO: roc 8 with ID = 8 has maximal Vcal 61.0349 for pixel 0/54 mean/min/max = 46.096/31.0044/61.1876
[09:47:45.317] <TB0> INFO: roc 9 with ID = 9 has maximal Vcal 59.8824 for pixel 17/0 mean/min/max = 45.7964/31.6361/59.9567
[09:47:45.318] <TB0> INFO: roc 10 with ID = 10 has maximal Vcal 57.4306 for pixel 20/75 mean/min/max = 44.523/31.5936/57.4525
[09:47:45.318] <TB0> INFO: roc 11 with ID = 11 has maximal Vcal 57.4875 for pixel 18/50 mean/min/max = 44.9012/32.3078/57.4946
[09:47:45.318] <TB0> INFO: roc 12 with ID = 12 has maximal Vcal 56.3968 for pixel 51/8 mean/min/max = 44.3831/32.337/56.4292
[09:47:45.318] <TB0> INFO: roc 13 with ID = 13 has maximal Vcal 57.4249 for pixel 0/32 mean/min/max = 44.7887/32.1372/57.4403
[09:47:45.318] <TB0> INFO: roc 14 with ID = 14 has maximal Vcal 56.5383 for pixel 0/0 mean/min/max = 44.685/32.7626/56.6075
[09:47:45.319] <TB0> INFO: roc 15 with ID = 15 has maximal Vcal 60.1167 for pixel 4/3 mean/min/max = 46.2134/32.2004/60.2264
[09:47:45.319] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[09:47:45.408] <TB0> INFO: Expecting 514560 events.
[09:47:56.371] <TB0> INFO: 514560 events read in total (10407ms).
[09:47:56.378] <TB0> INFO: Expecting 514560 events.
[09:48:07.176] <TB0> INFO: 514560 events read in total (10396ms).
[09:48:07.186] <TB0> INFO: Expecting 514560 events.
[09:48:17.845] <TB0> INFO: 514560 events read in total (10257ms).
[09:48:17.857] <TB0> INFO: Expecting 514560 events.
[09:48:28.626] <TB0> INFO: 514560 events read in total (10368ms).
[09:48:28.640] <TB0> INFO: Expecting 514560 events.
[09:48:39.238] <TB0> INFO: 514560 events read in total (10196ms).
[09:48:39.254] <TB0> INFO: Expecting 514560 events.
[09:48:50.029] <TB0> INFO: 514560 events read in total (10373ms).
[09:48:50.050] <TB0> INFO: Expecting 514560 events.
[09:49:00.756] <TB0> INFO: 514560 events read in total (10305ms).
[09:49:00.779] <TB0> INFO: Expecting 514560 events.
[09:49:11.565] <TB0> INFO: 514560 events read in total (10384ms).
[09:49:11.592] <TB0> INFO: Expecting 514560 events.
[09:49:22.290] <TB0> INFO: 514560 events read in total (10296ms).
[09:49:22.328] <TB0> INFO: Expecting 514560 events.
[09:49:33.096] <TB0> INFO: 514560 events read in total (10366ms).
[09:49:33.127] <TB0> INFO: Expecting 514560 events.
[09:49:43.863] <TB0> INFO: 514560 events read in total (10334ms).
[09:49:43.910] <TB0> INFO: Expecting 514560 events.
[09:49:54.650] <TB0> INFO: 514560 events read in total (10339ms).
[09:49:54.687] <TB0> INFO: Expecting 514560 events.
[09:50:05.364] <TB0> INFO: 514560 events read in total (10276ms).
[09:50:05.405] <TB0> INFO: Expecting 514560 events.
[09:50:16.182] <TB0> INFO: 514560 events read in total (10375ms).
[09:50:16.225] <TB0> INFO: Expecting 514560 events.
[09:50:26.896] <TB0> INFO: 514560 events read in total (10270ms).
[09:50:26.941] <TB0> INFO: Expecting 514560 events.
[09:50:37.601] <TB0> INFO: 514560 events read in total (10259ms).
[09:50:37.652] <TB0> INFO: Test took 172333ms.
[09:50:38.761] <TB0> INFO: ---> dac: vcal name: TrimThr2 ntrig: 10 dacrange: 0 .. 150 (-1/-1) hits flags = 528 (plus default)
[09:50:38.770] <TB0> INFO: dacScan split into 1 runs with ntrig = 10
[09:50:38.770] <TB0> INFO: run 1 of 1
[09:50:39.044] <TB0> INFO: Expecting 6281600 events.
[09:51:10.722] <TB0> INFO: 746810 events read in total (31121ms).
[09:51:41.396] <TB0> INFO: 1492090 events read in total (61795ms).
[09:52:12.224] <TB0> INFO: 2237340 events read in total (92623ms).
[09:52:42.680] <TB0> INFO: 2982520 events read in total (123079ms).
[09:53:13.576] <TB0> INFO: 3722690 events read in total (153975ms).
[09:53:44.144] <TB0> INFO: 4458940 events read in total (184543ms).
[09:54:14.827] <TB0> INFO: 5193240 events read in total (215226ms).
[09:54:45.265] <TB0> INFO: 5926170 events read in total (245664ms).
[09:55:00.452] <TB0> INFO: 6281600 events read in total (260851ms).
[09:55:00.524] <TB0> INFO: Test took 261754ms.
[09:55:24.886] <TB0> INFO: ---> TrimStepCorr4 extremal thresholds: 8.675904 .. 80.247218
[09:55:25.152] <TB0> INFO: Expecting 208000 events.
[09:55:35.150] <TB0> INFO: 208000 events read in total (9441ms).
[09:55:35.151] <TB0> INFO: Test took 10262ms.
[09:55:35.240] <TB0> INFO: ---> dac: vcal name: trimStepCorr4 ntrig: 10 dacrange: 8 .. 90 (-1/-1) hits flags = 528 (plus default)
[09:55:35.248] <TB0> INFO: dacScan split into 1 runs with ntrig = 10
[09:55:35.248] <TB0> INFO: run 1 of 1
[09:55:35.517] <TB0> INFO: Expecting 3452800 events.
[09:56:07.941] <TB0> INFO: 802630 events read in total (31868ms).
[09:56:39.436] <TB0> INFO: 1605880 events read in total (63363ms).
[09:57:11.365] <TB0> INFO: 2406240 events read in total (95292ms).
[09:57:42.831] <TB0> INFO: 3200860 events read in total (126758ms).
[09:57:53.365] <TB0> INFO: 3452800 events read in total (137292ms).
[09:57:53.406] <TB0> INFO: Test took 138156ms.
[09:58:12.227] <TB0> INFO: ---> TrimStepCorr2 extremal thresholds: 20.575657 .. 68.360659
[09:58:12.493] <TB0> INFO: Expecting 208000 events.
[09:58:22.353] <TB0> INFO: 208000 events read in total (9304ms).
[09:58:22.354] <TB0> INFO: Test took 10126ms.
[09:58:22.444] <TB0> INFO: ---> dac: vcal name: trimStepCorr2 ntrig: 10 dacrange: 10 .. 78 (-1/-1) hits flags = 528 (plus default)
[09:58:22.452] <TB0> INFO: dacScan split into 1 runs with ntrig = 10
[09:58:22.452] <TB0> INFO: run 1 of 1
[09:58:22.736] <TB0> INFO: Expecting 2870400 events.
[09:58:55.715] <TB0> INFO: 818620 events read in total (32423ms).
[09:59:27.167] <TB0> INFO: 1637270 events read in total (63875ms).
[09:59:58.158] <TB0> INFO: 2454280 events read in total (94866ms).
[10:00:14.668] <TB0> INFO: 2870400 events read in total (111376ms).
[10:00:14.713] <TB0> INFO: Test took 112260ms.
[10:00:32.730] <TB0> INFO: ---> TrimStepCorr1a extremal thresholds: 21.927260 .. 40.001018
[10:00:32.995] <TB0> INFO: Expecting 208000 events.
[10:00:42.860] <TB0> INFO: 208000 events read in total (9309ms).
[10:00:42.861] <TB0> INFO: Test took 10130ms.
[10:00:42.949] <TB0> INFO: ---> dac: vcal name: trimStepCorr1a ntrig: 10 dacrange: 11 .. 50 (-1/-1) hits flags = 528 (plus default)
[10:00:42.958] <TB0> INFO: dacScan split into 1 runs with ntrig = 10
[10:00:42.958] <TB0> INFO: run 1 of 1
[10:00:43.220] <TB0> INFO: Expecting 1664000 events.
[10:01:18.126] <TB0> INFO: 967800 events read in total (34350ms).
[10:01:43.414] <TB0> INFO: 1664000 events read in total (59638ms).
[10:01:43.433] <TB0> INFO: Test took 60475ms.
[10:01:56.386] <TB0> INFO: ---> TrimStepCorr1b extremal thresholds: 22.703772 .. 39.154975
[10:01:56.658] <TB0> INFO: Expecting 208000 events.
[10:02:06.613] <TB0> INFO: 208000 events read in total (9399ms).
[10:02:06.613] <TB0> INFO: Test took 10225ms.
[10:02:06.700] <TB0> INFO: ---> dac: vcal name: trimStepCorr1b ntrig: 10 dacrange: 12 .. 49 (-1/-1) hits flags = 528 (plus default)
[10:02:06.709] <TB0> INFO: dacScan split into 1 runs with ntrig = 10
[10:02:06.709] <TB0> INFO: run 1 of 1
[10:02:06.970] <TB0> INFO: Expecting 1580800 events.
[10:02:42.427] <TB0> INFO: 973470 events read in total (34901ms).
[10:03:04.291] <TB0> INFO: 1580800 events read in total (56765ms).
[10:03:04.308] <TB0> INFO: Test took 57600ms.
[10:03:17.244] <TB0> INFO: ---> TrimThrFinal extremal thresholds: 15 .. 55
[10:03:17.244] <TB0> INFO: ---> dac: vcal name: TrimThrFinal ntrig: 10 dacrange: 15 .. 55 (-1/-1) hits flags = 528 (plus default)
[10:03:17.252] <TB0> INFO: dacScan split into 1 runs with ntrig = 10
[10:03:17.252] <TB0> INFO: run 1 of 1
[10:03:17.518] <TB0> INFO: Expecting 1705600 events.
[10:03:51.496] <TB0> INFO: 894010 events read in total (33422ms).
[10:04:22.253] <TB0> INFO: 1705600 events read in total (64179ms).
[10:04:22.276] <TB0> INFO: Test took 65023ms.
[10:04:35.680] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2348_FullQualification_2016-10-04_11h02m_1475571737//000_FulltestPxar_m20//dacParameters35_C0.dat
[10:04:35.680] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2348_FullQualification_2016-10-04_11h02m_1475571737//000_FulltestPxar_m20//dacParameters35_C1.dat
[10:04:35.680] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2348_FullQualification_2016-10-04_11h02m_1475571737//000_FulltestPxar_m20//dacParameters35_C2.dat
[10:04:35.681] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2348_FullQualification_2016-10-04_11h02m_1475571737//000_FulltestPxar_m20//dacParameters35_C3.dat
[10:04:35.681] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2348_FullQualification_2016-10-04_11h02m_1475571737//000_FulltestPxar_m20//dacParameters35_C4.dat
[10:04:35.681] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2348_FullQualification_2016-10-04_11h02m_1475571737//000_FulltestPxar_m20//dacParameters35_C5.dat
[10:04:35.681] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2348_FullQualification_2016-10-04_11h02m_1475571737//000_FulltestPxar_m20//dacParameters35_C6.dat
[10:04:35.681] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2348_FullQualification_2016-10-04_11h02m_1475571737//000_FulltestPxar_m20//dacParameters35_C7.dat
[10:04:35.681] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2348_FullQualification_2016-10-04_11h02m_1475571737//000_FulltestPxar_m20//dacParameters35_C8.dat
[10:04:35.681] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2348_FullQualification_2016-10-04_11h02m_1475571737//000_FulltestPxar_m20//dacParameters35_C9.dat
[10:04:35.681] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2348_FullQualification_2016-10-04_11h02m_1475571737//000_FulltestPxar_m20//dacParameters35_C10.dat
[10:04:35.681] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2348_FullQualification_2016-10-04_11h02m_1475571737//000_FulltestPxar_m20//dacParameters35_C11.dat
[10:04:35.682] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2348_FullQualification_2016-10-04_11h02m_1475571737//000_FulltestPxar_m20//dacParameters35_C12.dat
[10:04:35.682] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2348_FullQualification_2016-10-04_11h02m_1475571737//000_FulltestPxar_m20//dacParameters35_C13.dat
[10:04:35.682] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2348_FullQualification_2016-10-04_11h02m_1475571737//000_FulltestPxar_m20//dacParameters35_C14.dat
[10:04:35.682] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2348_FullQualification_2016-10-04_11h02m_1475571737//000_FulltestPxar_m20//dacParameters35_C15.dat
[10:04:35.682] <TB0> INFO: write trim parameters into /usr/local/coldboxDATA/M2348_FullQualification_2016-10-04_11h02m_1475571737//000_FulltestPxar_m20//trimParameters35_C0.dat
[10:04:35.689] <TB0> INFO: write trim parameters into /usr/local/coldboxDATA/M2348_FullQualification_2016-10-04_11h02m_1475571737//000_FulltestPxar_m20//trimParameters35_C1.dat
[10:04:35.696] <TB0> INFO: write trim parameters into /usr/local/coldboxDATA/M2348_FullQualification_2016-10-04_11h02m_1475571737//000_FulltestPxar_m20//trimParameters35_C2.dat
[10:04:35.702] <TB0> INFO: write trim parameters into /usr/local/coldboxDATA/M2348_FullQualification_2016-10-04_11h02m_1475571737//000_FulltestPxar_m20//trimParameters35_C3.dat
[10:04:35.709] <TB0> INFO: write trim parameters into /usr/local/coldboxDATA/M2348_FullQualification_2016-10-04_11h02m_1475571737//000_FulltestPxar_m20//trimParameters35_C4.dat
[10:04:35.716] <TB0> INFO: write trim parameters into /usr/local/coldboxDATA/M2348_FullQualification_2016-10-04_11h02m_1475571737//000_FulltestPxar_m20//trimParameters35_C5.dat
[10:04:35.722] <TB0> INFO: write trim parameters into /usr/local/coldboxDATA/M2348_FullQualification_2016-10-04_11h02m_1475571737//000_FulltestPxar_m20//trimParameters35_C6.dat
[10:04:35.729] <TB0> INFO: write trim parameters into /usr/local/coldboxDATA/M2348_FullQualification_2016-10-04_11h02m_1475571737//000_FulltestPxar_m20//trimParameters35_C7.dat
[10:04:35.736] <TB0> INFO: write trim parameters into /usr/local/coldboxDATA/M2348_FullQualification_2016-10-04_11h02m_1475571737//000_FulltestPxar_m20//trimParameters35_C8.dat
[10:04:35.743] <TB0> INFO: write trim parameters into /usr/local/coldboxDATA/M2348_FullQualification_2016-10-04_11h02m_1475571737//000_FulltestPxar_m20//trimParameters35_C9.dat
[10:04:35.750] <TB0> INFO: write trim parameters into /usr/local/coldboxDATA/M2348_FullQualification_2016-10-04_11h02m_1475571737//000_FulltestPxar_m20//trimParameters35_C10.dat
[10:04:35.756] <TB0> INFO: write trim parameters into /usr/local/coldboxDATA/M2348_FullQualification_2016-10-04_11h02m_1475571737//000_FulltestPxar_m20//trimParameters35_C11.dat
[10:04:35.763] <TB0> INFO: write trim parameters into /usr/local/coldboxDATA/M2348_FullQualification_2016-10-04_11h02m_1475571737//000_FulltestPxar_m20//trimParameters35_C12.dat
[10:04:35.770] <TB0> INFO: write trim parameters into /usr/local/coldboxDATA/M2348_FullQualification_2016-10-04_11h02m_1475571737//000_FulltestPxar_m20//trimParameters35_C13.dat
[10:04:35.777] <TB0> INFO: write trim parameters into /usr/local/coldboxDATA/M2348_FullQualification_2016-10-04_11h02m_1475571737//000_FulltestPxar_m20//trimParameters35_C14.dat
[10:04:35.784] <TB0> INFO: write trim parameters into /usr/local/coldboxDATA/M2348_FullQualification_2016-10-04_11h02m_1475571737//000_FulltestPxar_m20//trimParameters35_C15.dat
[10:04:35.790] <TB0> INFO: PixTestTrim::trimTest() done
[10:04:35.790] <TB0> INFO: vtrim: 85 98 109 95 90 117 103 101 113 108 104 113 94 98 95 104
[10:04:35.790] <TB0> INFO: vthrcomp: 90 90 106 95 85 90 95 93 86 98 95 89 94 87 89 89
[10:04:35.790] <TB0> INFO: vcal mean: 34.97 34.99 34.95 34.96 34.95 34.92 34.99 34.98 34.98 34.99 34.98 34.99 34.98 34.96 34.99 34.97
[10:04:35.790] <TB0> INFO: vcal RMS: 0.77 0.78 0.82 0.86 0.94 1.35 0.81 0.80 0.91 0.82 1.12 0.80 0.80 0.82 0.78 0.82
[10:04:35.790] <TB0> INFO: bits mean: 9.23 9.47 9.46 9.92 9.73 9.75 9.84 9.96 9.23 9.48 9.76 9.79 9.49 9.50 9.44 9.05
[10:04:35.790] <TB0> INFO: bits RMS: 2.67 2.63 2.52 2.58 2.68 2.61 2.50 2.49 2.93 2.73 2.63 2.49 2.68 2.77 2.64 2.79
[10:04:35.797] <TB0> INFO: ----------------------------------------------------------------------
[10:04:35.797] <TB0> INFO: PixTestTrim::trimBitTest() ntrig = 5, vtrims = 254 126 63 32
[10:04:35.797] <TB0> INFO: ----------------------------------------------------------------------
[10:04:35.800] <TB0> INFO: ---> dac: Vcal name: TrimBitsThr0 ntrig: 5 dacrange: 0 .. 199 (-1/-1) hits flags = 528 (plus default)
[10:04:35.808] <TB0> INFO: dacScan split into 1 runs with ntrig = 5
[10:04:35.808] <TB0> INFO: run 1 of 1
[10:04:36.073] <TB0> INFO: Expecting 4160000 events.
[10:05:13.488] <TB0> INFO: 912515 events read in total (36859ms).
[10:05:50.321] <TB0> INFO: 1819085 events read in total (73692ms).
[10:06:27.289] <TB0> INFO: 2719285 events read in total (110660ms).
[10:07:05.181] <TB0> INFO: 3616880 events read in total (148552ms).
[10:07:27.581] <TB0> INFO: 4160000 events read in total (170952ms).
[10:07:27.645] <TB0> INFO: Test took 171836ms.
[10:07:56.561] <TB0> INFO: ---> dac: Vcal name: TrimThr_trim14 ntrig: 5 dacrange: 0 .. 156 (-1/-1) hits flags = 528 (plus default)
[10:07:56.570] <TB0> INFO: dacScan split into 1 runs with ntrig = 5
[10:07:56.570] <TB0> INFO: run 1 of 1
[10:07:56.859] <TB0> INFO: Expecting 3265600 events.
[10:08:36.385] <TB0> INFO: 973015 events read in total (38970ms).
[10:09:17.593] <TB0> INFO: 1934160 events read in total (80178ms).
[10:09:56.931] <TB0> INFO: 2887450 events read in total (119516ms).
[10:10:12.196] <TB0> INFO: 3265600 events read in total (134781ms).
[10:10:12.263] <TB0> INFO: Test took 135693ms.
[10:10:37.827] <TB0> INFO: ---> dac: Vcal name: TrimThr_trim13 ntrig: 5 dacrange: 0 .. 144 (-1/-1) hits flags = 528 (plus default)
[10:10:37.836] <TB0> INFO: dacScan split into 1 runs with ntrig = 5
[10:10:37.836] <TB0> INFO: run 1 of 1
[10:10:38.102] <TB0> INFO: Expecting 3016000 events.
[10:11:18.251] <TB0> INFO: 1009730 events read in total (39593ms).
[10:11:57.308] <TB0> INFO: 2004415 events read in total (78650ms).
[10:12:36.204] <TB0> INFO: 2994405 events read in total (117546ms).
[10:12:37.400] <TB0> INFO: 3016000 events read in total (118742ms).
[10:12:37.444] <TB0> INFO: Test took 119609ms.
[10:13:00.863] <TB0> INFO: ---> dac: Vcal name: TrimThr_trim11 ntrig: 5 dacrange: 0 .. 145 (-1/-1) hits flags = 528 (plus default)
[10:13:00.871] <TB0> INFO: dacScan split into 1 runs with ntrig = 5
[10:13:00.871] <TB0> INFO: run 1 of 1
[10:13:01.140] <TB0> INFO: Expecting 3036800 events.
[10:13:40.803] <TB0> INFO: 1004825 events read in total (39106ms).
[10:14:20.061] <TB0> INFO: 1994550 events read in total (78364ms).
[10:15:00.399] <TB0> INFO: 2978370 events read in total (118702ms).
[10:15:03.086] <TB0> INFO: 3036800 events read in total (121389ms).
[10:15:03.132] <TB0> INFO: Test took 122261ms.
[10:15:29.850] <TB0> INFO: ---> dac: Vcal name: TrimThr_trim7 ntrig: 5 dacrange: 0 .. 143 (-1/-1) hits flags = 528 (plus default)
[10:15:29.860] <TB0> INFO: dacScan split into 1 runs with ntrig = 5
[10:15:29.860] <TB0> INFO: run 1 of 1
[10:15:30.126] <TB0> INFO: Expecting 2995200 events.
[10:16:09.603] <TB0> INFO: 1011570 events read in total (38921ms).
[10:16:48.976] <TB0> INFO: 2007350 events read in total (78294ms).
[10:17:28.081] <TB0> INFO: 2995200 events read in total (117400ms).
[10:17:28.139] <TB0> INFO: Test took 118280ms.
[10:17:51.155] <TB0> INFO: PixTestTrim::trimBitTest() done
[10:17:51.156] <TB0> INFO: PixTestTrim::doTest() done, duration: 2323 seconds
[10:17:51.156] <TB0> INFO: Decoding statistics:
[10:17:51.156] <TB0> INFO: General information:
[10:17:51.156] <TB0> INFO: 16bit words read: 0
[10:17:51.156] <TB0> INFO: valid events total: 0
[10:17:51.157] <TB0> INFO: empty events: 0
[10:17:51.157] <TB0> INFO: valid events with pixels: 0
[10:17:51.157] <TB0> INFO: valid pixel hits: 0
[10:17:51.157] <TB0> INFO: Event errors: 0
[10:17:51.157] <TB0> INFO: start marker: 0
[10:17:51.157] <TB0> INFO: stop marker: 0
[10:17:51.157] <TB0> INFO: overflow: 0
[10:17:51.157] <TB0> INFO: invalid 5bit words: 0
[10:17:51.157] <TB0> INFO: invalid XOR eye diagram: 0
[10:17:51.157] <TB0> INFO: frame (failed synchr.): 0
[10:17:51.157] <TB0> INFO: idle data (no TBM trl): 0
[10:17:51.157] <TB0> INFO: no data (only TBM hdr): 0
[10:17:51.157] <TB0> INFO: TBM errors: 0
[10:17:51.157] <TB0> INFO: flawed TBM headers: 0
[10:17:51.157] <TB0> INFO: flawed TBM trailers: 0
[10:17:51.157] <TB0> INFO: event ID mismatches: 0
[10:17:51.157] <TB0> INFO: ROC errors: 0
[10:17:51.157] <TB0> INFO: missing ROC header(s): 0
[10:17:51.157] <TB0> INFO: misplaced readback start: 0
[10:17:51.157] <TB0> INFO: Pixel decoding errors: 0
[10:17:51.157] <TB0> INFO: pixel data incomplete: 0
[10:17:51.157] <TB0> INFO: pixel address: 0
[10:17:51.157] <TB0> INFO: pulse height fill bit: 0
[10:17:51.157] <TB0> INFO: buffer corruption: 0
[10:17:51.840] <TB0> INFO: ######################################################################
[10:17:51.840] <TB0> INFO: PixTestPhOptimization::doTest() Ntrig = 10
[10:17:51.840] <TB0> INFO: ######################################################################
[10:17:52.102] <TB0> INFO: Expecting 41600 events.
[10:17:55.529] <TB0> INFO: 41600 events read in total (2870ms).
[10:17:55.530] <TB0> INFO: Test took 3689ms.
[10:17:56.011] <TB0> INFO: Expecting 41600 events.
[10:17:59.488] <TB0> INFO: 41600 events read in total (2920ms).
[10:17:59.489] <TB0> INFO: Test took 3764ms.
[10:17:59.775] <TB0> INFO: Expecting 41600 events.
[10:18:03.211] <TB0> INFO: 41600 events read in total (2880ms).
[10:18:03.212] <TB0> INFO: Test took 3701ms.
[10:18:03.467] <TB0> INFO: Expecting 2560 events.
[10:18:04.321] <TB0> INFO: 2560 events read in total (298ms).
[10:18:04.321] <TB0> INFO: Test took 1097ms.
[10:18:04.626] <TB0> INFO: Expecting 2560 events.
[10:18:05.478] <TB0> INFO: 2560 events read in total (296ms).
[10:18:05.478] <TB0> INFO: Test took 1157ms.
[10:18:05.783] <TB0> INFO: Expecting 2560 events.
[10:18:06.635] <TB0> INFO: 2560 events read in total (296ms).
[10:18:06.635] <TB0> INFO: Test took 1157ms.
[10:18:06.941] <TB0> INFO: Expecting 2560 events.
[10:18:07.801] <TB0> INFO: 2560 events read in total (304ms).
[10:18:07.801] <TB0> INFO: Test took 1165ms.
[10:18:08.105] <TB0> INFO: Expecting 2560 events.
[10:18:08.964] <TB0> INFO: 2560 events read in total (303ms).
[10:18:08.964] <TB0> INFO: Test took 1163ms.
[10:18:09.269] <TB0> INFO: Expecting 2560 events.
[10:18:10.126] <TB0> INFO: 2560 events read in total (301ms).
[10:18:10.126] <TB0> INFO: Test took 1161ms.
[10:18:10.430] <TB0> INFO: Expecting 2560 events.
[10:18:11.282] <TB0> INFO: 2560 events read in total (296ms).
[10:18:11.282] <TB0> INFO: Test took 1155ms.
[10:18:11.586] <TB0> INFO: Expecting 2560 events.
[10:18:12.442] <TB0> INFO: 2560 events read in total (299ms).
[10:18:12.442] <TB0> INFO: Test took 1159ms.
[10:18:12.747] <TB0> INFO: Expecting 2560 events.
[10:18:13.604] <TB0> INFO: 2560 events read in total (301ms).
[10:18:13.605] <TB0> INFO: Test took 1163ms.
[10:18:13.909] <TB0> INFO: Expecting 2560 events.
[10:18:14.761] <TB0> INFO: 2560 events read in total (296ms).
[10:18:14.761] <TB0> INFO: Test took 1156ms.
[10:18:15.066] <TB0> INFO: Expecting 2560 events.
[10:18:15.917] <TB0> INFO: 2560 events read in total (295ms).
[10:18:15.918] <TB0> INFO: Test took 1157ms.
[10:18:16.223] <TB0> INFO: Expecting 2560 events.
[10:18:17.078] <TB0> INFO: 2560 events read in total (299ms).
[10:18:17.078] <TB0> INFO: Test took 1160ms.
[10:18:17.383] <TB0> INFO: Expecting 2560 events.
[10:18:18.235] <TB0> INFO: 2560 events read in total (296ms).
[10:18:18.235] <TB0> INFO: Test took 1157ms.
[10:18:18.540] <TB0> INFO: Expecting 2560 events.
[10:18:19.397] <TB0> INFO: 2560 events read in total (301ms).
[10:18:19.397] <TB0> INFO: Test took 1162ms.
[10:18:19.701] <TB0> INFO: Expecting 2560 events.
[10:18:20.554] <TB0> INFO: 2560 events read in total (297ms).
[10:18:20.555] <TB0> INFO: Test took 1156ms.
[10:18:20.860] <TB0> INFO: Expecting 2560 events.
[10:18:21.711] <TB0> INFO: 2560 events read in total (295ms).
[10:18:21.711] <TB0> INFO: Test took 1156ms.
[10:18:21.715] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[10:18:22.017] <TB0> INFO: Expecting 655360 events.
[10:18:35.950] <TB0> INFO: 655360 events read in total (13376ms).
[10:18:35.964] <TB0> INFO: Expecting 655360 events.
[10:18:49.737] <TB0> INFO: 655360 events read in total (13371ms).
[10:18:49.753] <TB0> INFO: Expecting 655360 events.
[10:19:03.497] <TB0> INFO: 655360 events read in total (13342ms).
[10:19:03.517] <TB0> INFO: Expecting 655360 events.
[10:19:17.293] <TB0> INFO: 655360 events read in total (13374ms).
[10:19:17.321] <TB0> INFO: Expecting 655360 events.
[10:19:31.135] <TB0> INFO: 655360 events read in total (13412ms).
[10:19:31.165] <TB0> INFO: Expecting 655360 events.
[10:19:44.905] <TB0> INFO: 655360 events read in total (13338ms).
[10:19:44.937] <TB0> INFO: Expecting 655360 events.
[10:19:58.705] <TB0> INFO: 655360 events read in total (13366ms).
[10:19:58.741] <TB0> INFO: Expecting 655360 events.
[10:20:12.475] <TB0> INFO: 655360 events read in total (13332ms).
[10:20:12.516] <TB0> INFO: Expecting 655360 events.
[10:20:26.294] <TB0> INFO: 655360 events read in total (13376ms).
[10:20:26.344] <TB0> INFO: Expecting 655360 events.
[10:20:40.198] <TB0> INFO: 655360 events read in total (13452ms).
[10:20:40.247] <TB0> INFO: Expecting 655360 events.
[10:20:53.966] <TB0> INFO: 655360 events read in total (13318ms).
[10:20:54.023] <TB0> INFO: Expecting 655360 events.
[10:21:07.816] <TB0> INFO: 655360 events read in total (13392ms).
[10:21:07.880] <TB0> INFO: Expecting 655360 events.
[10:21:21.871] <TB0> INFO: 655360 events read in total (13589ms).
[10:21:21.935] <TB0> INFO: Expecting 655360 events.
[10:21:35.670] <TB0> INFO: 655360 events read in total (13333ms).
[10:21:35.736] <TB0> INFO: Expecting 655360 events.
[10:21:49.535] <TB0> INFO: 655360 events read in total (13397ms).
[10:21:49.623] <TB0> INFO: Expecting 655360 events.
[10:22:03.412] <TB0> INFO: 655360 events read in total (13388ms).
[10:22:03.489] <TB0> INFO: Test took 221774ms.
[10:22:03.576] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[10:22:03.805] <TB0> INFO: Expecting 655360 events.
[10:22:17.750] <TB0> INFO: 655360 events read in total (13389ms).
[10:22:17.762] <TB0> INFO: Expecting 655360 events.
[10:22:31.448] <TB0> INFO: 655360 events read in total (13284ms).
[10:22:31.463] <TB0> INFO: Expecting 655360 events.
[10:22:45.201] <TB0> INFO: 655360 events read in total (13336ms).
[10:22:45.222] <TB0> INFO: Expecting 655360 events.
[10:22:59.068] <TB0> INFO: 655360 events read in total (13444ms).
[10:22:59.092] <TB0> INFO: Expecting 655360 events.
[10:23:12.819] <TB0> INFO: 655360 events read in total (13326ms).
[10:23:12.847] <TB0> INFO: Expecting 655360 events.
[10:23:26.628] <TB0> INFO: 655360 events read in total (13379ms).
[10:23:26.661] <TB0> INFO: Expecting 655360 events.
[10:23:40.436] <TB0> INFO: 655360 events read in total (13373ms).
[10:23:40.483] <TB0> INFO: Expecting 655360 events.
[10:23:54.156] <TB0> INFO: 655360 events read in total (13271ms).
[10:23:54.202] <TB0> INFO: Expecting 655360 events.
[10:24:08.032] <TB0> INFO: 655360 events read in total (13428ms).
[10:24:08.087] <TB0> INFO: Expecting 655360 events.
[10:24:21.830] <TB0> INFO: 655360 events read in total (13341ms).
[10:24:21.883] <TB0> INFO: Expecting 655360 events.
[10:24:35.711] <TB0> INFO: 655360 events read in total (13427ms).
[10:24:35.768] <TB0> INFO: Expecting 655360 events.
[10:24:49.653] <TB0> INFO: 655360 events read in total (13484ms).
[10:24:49.720] <TB0> INFO: Expecting 655360 events.
[10:25:03.485] <TB0> INFO: 655360 events read in total (13363ms).
[10:25:03.547] <TB0> INFO: Expecting 655360 events.
[10:25:17.363] <TB0> INFO: 655360 events read in total (13414ms).
[10:25:17.431] <TB0> INFO: Expecting 655360 events.
[10:25:31.297] <TB0> INFO: 655360 events read in total (13464ms).
[10:25:31.381] <TB0> INFO: Expecting 655360 events.
[10:25:45.437] <TB0> INFO: 655360 events read in total (13654ms).
[10:25:45.516] <TB0> INFO: Test took 221940ms.
[10:25:45.738] <TB0> INFO: safety margin for low PH: adding 0, margin is now 20
[10:25:45.746] <TB0> INFO: safety margin for low PH: adding 0, margin is now 20
[10:25:45.753] <TB0> INFO: safety margin for low PH: adding 0, margin is now 20
[10:25:45.762] <TB0> INFO: safety margin for low PH: adding 0, margin is now 20
[10:25:45.769] <TB0> INFO: safety margin for low PH: adding 0, margin is now 20
[10:25:45.777] <TB0> INFO: safety margin for low PH: adding 1, margin is now 21
[10:25:45.784] <TB0> INFO: safety margin for low PH: adding 2, margin is now 22
[10:25:45.791] <TB0> INFO: safety margin for low PH: adding 0, margin is now 20
[10:25:45.798] <TB0> INFO: safety margin for low PH: adding 0, margin is now 20
[10:25:45.805] <TB0> INFO: safety margin for low PH: adding 0, margin is now 20
[10:25:45.812] <TB0> INFO: safety margin for low PH: adding 0, margin is now 20
[10:25:45.819] <TB0> INFO: safety margin for low PH: adding 0, margin is now 20
[10:25:45.826] <TB0> INFO: safety margin for low PH: adding 1, margin is now 21
[10:25:45.834] <TB0> INFO: safety margin for low PH: adding 2, margin is now 22
[10:25:45.842] <TB0> INFO: safety margin for low PH: adding 3, margin is now 23
[10:25:45.850] <TB0> INFO: safety margin for low PH: adding 4, margin is now 24
[10:25:45.857] <TB0> INFO: safety margin for low PH: adding 5, margin is now 25
[10:25:45.864] <TB0> INFO: safety margin for low PH: adding 0, margin is now 20
[10:25:45.871] <TB0> INFO: safety margin for low PH: adding 0, margin is now 20
[10:25:45.878] <TB0> INFO: safety margin for low PH: adding 0, margin is now 20
[10:25:45.885] <TB0> INFO: safety margin for low PH: adding 0, margin is now 20
[10:25:45.893] <TB0> INFO: safety margin for low PH: adding 0, margin is now 20
[10:25:45.900] <TB0> INFO: safety margin for low PH: adding 0, margin is now 20
[10:25:45.938] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2348_FullQualification_2016-10-04_11h02m_1475571737//000_FulltestPxar_m20//dacParameters35_C0.dat
[10:25:45.938] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2348_FullQualification_2016-10-04_11h02m_1475571737//000_FulltestPxar_m20//dacParameters35_C1.dat
[10:25:45.939] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2348_FullQualification_2016-10-04_11h02m_1475571737//000_FulltestPxar_m20//dacParameters35_C2.dat
[10:25:45.939] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2348_FullQualification_2016-10-04_11h02m_1475571737//000_FulltestPxar_m20//dacParameters35_C3.dat
[10:25:45.939] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2348_FullQualification_2016-10-04_11h02m_1475571737//000_FulltestPxar_m20//dacParameters35_C4.dat
[10:25:45.939] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2348_FullQualification_2016-10-04_11h02m_1475571737//000_FulltestPxar_m20//dacParameters35_C5.dat
[10:25:45.939] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2348_FullQualification_2016-10-04_11h02m_1475571737//000_FulltestPxar_m20//dacParameters35_C6.dat
[10:25:45.940] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2348_FullQualification_2016-10-04_11h02m_1475571737//000_FulltestPxar_m20//dacParameters35_C7.dat
[10:25:45.940] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2348_FullQualification_2016-10-04_11h02m_1475571737//000_FulltestPxar_m20//dacParameters35_C8.dat
[10:25:45.940] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2348_FullQualification_2016-10-04_11h02m_1475571737//000_FulltestPxar_m20//dacParameters35_C9.dat
[10:25:45.940] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2348_FullQualification_2016-10-04_11h02m_1475571737//000_FulltestPxar_m20//dacParameters35_C10.dat
[10:25:45.940] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2348_FullQualification_2016-10-04_11h02m_1475571737//000_FulltestPxar_m20//dacParameters35_C11.dat
[10:25:45.940] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2348_FullQualification_2016-10-04_11h02m_1475571737//000_FulltestPxar_m20//dacParameters35_C12.dat
[10:25:45.941] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2348_FullQualification_2016-10-04_11h02m_1475571737//000_FulltestPxar_m20//dacParameters35_C13.dat
[10:25:45.941] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2348_FullQualification_2016-10-04_11h02m_1475571737//000_FulltestPxar_m20//dacParameters35_C14.dat
[10:25:45.941] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2348_FullQualification_2016-10-04_11h02m_1475571737//000_FulltestPxar_m20//dacParameters35_C15.dat
[10:25:46.213] <TB0> INFO: Expecting 41600 events.
[10:25:49.341] <TB0> INFO: 41600 events read in total (2572ms).
[10:25:49.341] <TB0> INFO: Test took 3397ms.
[10:25:49.781] <TB0> INFO: Expecting 41600 events.
[10:25:52.898] <TB0> INFO: 41600 events read in total (2561ms).
[10:25:52.900] <TB0> INFO: Test took 3384ms.
[10:25:53.357] <TB0> INFO: Expecting 41600 events.
[10:25:56.496] <TB0> INFO: 41600 events read in total (2583ms).
[10:25:56.497] <TB0> INFO: Test took 3415ms.
[10:25:56.684] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[10:25:56.774] <TB0> INFO: Expecting 2560 events.
[10:25:57.627] <TB0> INFO: 2560 events read in total (297ms).
[10:25:57.627] <TB0> INFO: Test took 943ms.
[10:25:57.628] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[10:25:57.933] <TB0> INFO: Expecting 2560 events.
[10:25:58.787] <TB0> INFO: 2560 events read in total (298ms).
[10:25:58.788] <TB0> INFO: Test took 1160ms.
[10:25:58.790] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[10:25:59.092] <TB0> INFO: Expecting 2560 events.
[10:25:59.945] <TB0> INFO: 2560 events read in total (297ms).
[10:25:59.945] <TB0> INFO: Test took 1155ms.
[10:25:59.947] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[10:26:00.251] <TB0> INFO: Expecting 2560 events.
[10:26:01.104] <TB0> INFO: 2560 events read in total (297ms).
[10:26:01.105] <TB0> INFO: Test took 1158ms.
[10:26:01.107] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[10:26:01.410] <TB0> INFO: Expecting 2560 events.
[10:26:02.269] <TB0> INFO: 2560 events read in total (303ms).
[10:26:02.269] <TB0> INFO: Test took 1162ms.
[10:26:02.273] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[10:26:02.572] <TB0> INFO: Expecting 2560 events.
[10:26:03.428] <TB0> INFO: 2560 events read in total (300ms).
[10:26:03.428] <TB0> INFO: Test took 1155ms.
[10:26:03.431] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[10:26:03.732] <TB0> INFO: Expecting 2560 events.
[10:26:04.588] <TB0> INFO: 2560 events read in total (300ms).
[10:26:04.588] <TB0> INFO: Test took 1157ms.
[10:26:04.595] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[10:26:04.893] <TB0> INFO: Expecting 2560 events.
[10:26:05.754] <TB0> INFO: 2560 events read in total (305ms).
[10:26:05.755] <TB0> INFO: Test took 1160ms.
[10:26:05.758] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[10:26:06.060] <TB0> INFO: Expecting 2560 events.
[10:26:06.915] <TB0> INFO: 2560 events read in total (299ms).
[10:26:06.916] <TB0> INFO: Test took 1158ms.
[10:26:06.917] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[10:26:07.222] <TB0> INFO: Expecting 2560 events.
[10:26:08.074] <TB0> INFO: 2560 events read in total (296ms).
[10:26:08.074] <TB0> INFO: Test took 1157ms.
[10:26:08.076] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[10:26:08.381] <TB0> INFO: Expecting 2560 events.
[10:26:09.241] <TB0> INFO: 2560 events read in total (304ms).
[10:26:09.241] <TB0> INFO: Test took 1165ms.
[10:26:09.244] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[10:26:09.546] <TB0> INFO: Expecting 2560 events.
[10:26:10.400] <TB0> INFO: 2560 events read in total (297ms).
[10:26:10.400] <TB0> INFO: Test took 1156ms.
[10:26:10.403] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[10:26:10.704] <TB0> INFO: Expecting 2560 events.
[10:26:11.558] <TB0> INFO: 2560 events read in total (297ms).
[10:26:11.558] <TB0> INFO: Test took 1155ms.
[10:26:11.560] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[10:26:11.863] <TB0> INFO: Expecting 2560 events.
[10:26:12.724] <TB0> INFO: 2560 events read in total (304ms).
[10:26:12.725] <TB0> INFO: Test took 1165ms.
[10:26:12.727] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[10:26:13.030] <TB0> INFO: Expecting 2560 events.
[10:26:13.893] <TB0> INFO: 2560 events read in total (307ms).
[10:26:13.893] <TB0> INFO: Test took 1166ms.
[10:26:13.896] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[10:26:14.197] <TB0> INFO: Expecting 2560 events.
[10:26:15.048] <TB0> INFO: 2560 events read in total (295ms).
[10:26:15.048] <TB0> INFO: Test took 1152ms.
[10:26:15.050] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[10:26:15.354] <TB0> INFO: Expecting 2560 events.
[10:26:16.209] <TB0> INFO: 2560 events read in total (299ms).
[10:26:16.209] <TB0> INFO: Test took 1159ms.
[10:26:16.212] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[10:26:16.513] <TB0> INFO: Expecting 2560 events.
[10:26:17.374] <TB0> INFO: 2560 events read in total (305ms).
[10:26:17.375] <TB0> INFO: Test took 1164ms.
[10:26:17.377] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[10:26:17.679] <TB0> INFO: Expecting 2560 events.
[10:26:18.542] <TB0> INFO: 2560 events read in total (307ms).
[10:26:18.543] <TB0> INFO: Test took 1166ms.
[10:26:18.546] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[10:26:18.847] <TB0> INFO: Expecting 2560 events.
[10:26:19.698] <TB0> INFO: 2560 events read in total (295ms).
[10:26:19.698] <TB0> INFO: Test took 1153ms.
[10:26:19.700] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[10:26:20.005] <TB0> INFO: Expecting 2560 events.
[10:26:20.856] <TB0> INFO: 2560 events read in total (295ms).
[10:26:20.856] <TB0> INFO: Test took 1156ms.
[10:26:20.859] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[10:26:21.163] <TB0> INFO: Expecting 2560 events.
[10:26:22.016] <TB0> INFO: 2560 events read in total (297ms).
[10:26:22.017] <TB0> INFO: Test took 1158ms.
[10:26:22.020] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[10:26:22.321] <TB0> INFO: Expecting 2560 events.
[10:26:23.173] <TB0> INFO: 2560 events read in total (296ms).
[10:26:23.173] <TB0> INFO: Test took 1154ms.
[10:26:23.175] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[10:26:23.479] <TB0> INFO: Expecting 2560 events.
[10:26:24.341] <TB0> INFO: 2560 events read in total (306ms).
[10:26:24.341] <TB0> INFO: Test took 1166ms.
[10:26:24.344] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[10:26:24.647] <TB0> INFO: Expecting 2560 events.
[10:26:25.505] <TB0> INFO: 2560 events read in total (302ms).
[10:26:25.505] <TB0> INFO: Test took 1161ms.
[10:26:25.508] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[10:26:25.811] <TB0> INFO: Expecting 2560 events.
[10:26:26.664] <TB0> INFO: 2560 events read in total (296ms).
[10:26:26.664] <TB0> INFO: Test took 1156ms.
[10:26:26.677] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[10:26:26.970] <TB0> INFO: Expecting 2560 events.
[10:26:27.825] <TB0> INFO: 2560 events read in total (299ms).
[10:26:27.825] <TB0> INFO: Test took 1148ms.
[10:26:27.827] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[10:26:28.131] <TB0> INFO: Expecting 2560 events.
[10:26:28.984] <TB0> INFO: 2560 events read in total (297ms).
[10:26:28.984] <TB0> INFO: Test took 1157ms.
[10:26:28.987] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[10:26:29.290] <TB0> INFO: Expecting 2560 events.
[10:26:30.146] <TB0> INFO: 2560 events read in total (300ms).
[10:26:30.146] <TB0> INFO: Test took 1159ms.
[10:26:30.149] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[10:26:30.451] <TB0> INFO: Expecting 2560 events.
[10:26:31.306] <TB0> INFO: 2560 events read in total (299ms).
[10:26:31.306] <TB0> INFO: Test took 1158ms.
[10:26:31.309] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[10:26:31.610] <TB0> INFO: Expecting 2560 events.
[10:26:32.465] <TB0> INFO: 2560 events read in total (298ms).
[10:26:32.465] <TB0> INFO: Test took 1157ms.
[10:26:32.467] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[10:26:32.770] <TB0> INFO: Expecting 2560 events.
[10:26:33.625] <TB0> INFO: 2560 events read in total (298ms).
[10:26:33.625] <TB0> INFO: Test took 1158ms.
[10:26:34.092] <TB0> INFO: PixTestPhOptimization::doTest() done, duration: 522 seconds
[10:26:34.092] <TB0> INFO: PH scale (per ROC): 92 98 84 88 87 81 88 91 81 80 105 86 87 101 87 78
[10:26:34.092] <TB0> INFO: PH offset (per ROC): 158 150 159 150 144 154 145 149 149 144 161 148 142 144 153 155
[10:26:34.099] <TB0> INFO: Decoding statistics:
[10:26:34.099] <TB0> INFO: General information:
[10:26:34.099] <TB0> INFO: 16bit words read: 86930
[10:26:34.099] <TB0> INFO: valid events total: 10240
[10:26:34.099] <TB0> INFO: empty events: 7735
[10:26:34.099] <TB0> INFO: valid events with pixels: 2505
[10:26:34.099] <TB0> INFO: valid pixel hits: 2505
[10:26:34.099] <TB0> INFO: Event errors: 0
[10:26:34.099] <TB0> INFO: start marker: 0
[10:26:34.099] <TB0> INFO: stop marker: 0
[10:26:34.099] <TB0> INFO: overflow: 0
[10:26:34.099] <TB0> INFO: invalid 5bit words: 0
[10:26:34.099] <TB0> INFO: invalid XOR eye diagram: 0
[10:26:34.099] <TB0> INFO: frame (failed synchr.): 0
[10:26:34.099] <TB0> INFO: idle data (no TBM trl): 0
[10:26:34.099] <TB0> INFO: no data (only TBM hdr): 0
[10:26:34.099] <TB0> INFO: TBM errors: 0
[10:26:34.099] <TB0> INFO: flawed TBM headers: 0
[10:26:34.099] <TB0> INFO: flawed TBM trailers: 0
[10:26:34.099] <TB0> INFO: event ID mismatches: 0
[10:26:34.099] <TB0> INFO: ROC errors: 0
[10:26:34.099] <TB0> INFO: missing ROC header(s): 0
[10:26:34.099] <TB0> INFO: misplaced readback start: 0
[10:26:34.099] <TB0> INFO: Pixel decoding errors: 0
[10:26:34.099] <TB0> INFO: pixel data incomplete: 0
[10:26:34.099] <TB0> INFO: pixel address: 0
[10:26:34.099] <TB0> INFO: pulse height fill bit: 0
[10:26:34.100] <TB0> INFO: buffer corruption: 0
[10:26:34.269] <TB0> INFO: ######################################################################
[10:26:34.269] <TB0> INFO: PixTestGainPedestal::fullTest() ntrig = 10
[10:26:34.269] <TB0> INFO: ######################################################################
[10:26:34.279] <TB0> INFO: scanning low vcal = 10
[10:26:34.539] <TB0> INFO: Expecting 41600 events.
[10:26:37.945] <TB0> INFO: 41600 events read in total (2850ms).
[10:26:37.945] <TB0> INFO: Test took 3666ms.
[10:26:37.946] <TB0> INFO: scanning low vcal = 20
[10:26:38.244] <TB0> INFO: Expecting 41600 events.
[10:26:41.630] <TB0> INFO: 41600 events read in total (2830ms).
[10:26:41.630] <TB0> INFO: Test took 3684ms.
[10:26:41.631] <TB0> INFO: scanning low vcal = 30
[10:26:41.933] <TB0> INFO: Expecting 41600 events.
[10:26:45.362] <TB0> INFO: 41600 events read in total (2872ms).
[10:26:45.363] <TB0> INFO: Test took 3732ms.
[10:26:45.364] <TB0> INFO: scanning low vcal = 40
[10:26:45.655] <TB0> INFO: Expecting 41600 events.
[10:26:49.511] <TB0> INFO: 41600 events read in total (3300ms).
[10:26:49.512] <TB0> INFO: Test took 4148ms.
[10:26:49.515] <TB0> INFO: scanning low vcal = 50
[10:26:49.778] <TB0> INFO: Expecting 41600 events.
[10:26:53.715] <TB0> INFO: 41600 events read in total (3381ms).
[10:26:53.715] <TB0> INFO: Test took 4200ms.
[10:26:53.718] <TB0> INFO: scanning low vcal = 60
[10:26:53.982] <TB0> INFO: Expecting 41600 events.
[10:26:57.944] <TB0> INFO: 41600 events read in total (3406ms).
[10:26:57.944] <TB0> INFO: Test took 4226ms.
[10:26:57.947] <TB0> INFO: scanning low vcal = 70
[10:26:58.212] <TB0> INFO: Expecting 41600 events.
[10:27:02.155] <TB0> INFO: 41600 events read in total (3387ms).
[10:27:02.156] <TB0> INFO: Test took 4208ms.
[10:27:02.158] <TB0> INFO: scanning low vcal = 80
[10:27:02.421] <TB0> INFO: Expecting 41600 events.
[10:27:06.372] <TB0> INFO: 41600 events read in total (3395ms).
[10:27:06.372] <TB0> INFO: Test took 4214ms.
[10:27:06.375] <TB0> INFO: scanning low vcal = 90
[10:27:06.654] <TB0> INFO: Expecting 41600 events.
[10:27:10.618] <TB0> INFO: 41600 events read in total (3408ms).
[10:27:10.619] <TB0> INFO: Test took 4244ms.
[10:27:10.622] <TB0> INFO: scanning low vcal = 100
[10:27:10.894] <TB0> INFO: Expecting 41600 events.
[10:27:14.848] <TB0> INFO: 41600 events read in total (3398ms).
[10:27:14.849] <TB0> INFO: Test took 4227ms.
[10:27:14.852] <TB0> INFO: scanning low vcal = 110
[10:27:15.124] <TB0> INFO: Expecting 41600 events.
[10:27:19.077] <TB0> INFO: 41600 events read in total (3397ms).
[10:27:19.078] <TB0> INFO: Test took 4225ms.
[10:27:19.080] <TB0> INFO: scanning low vcal = 120
[10:27:19.342] <TB0> INFO: Expecting 41600 events.
[10:27:23.276] <TB0> INFO: 41600 events read in total (3377ms).
[10:27:23.277] <TB0> INFO: Test took 4196ms.
[10:27:23.279] <TB0> INFO: scanning low vcal = 130
[10:27:23.540] <TB0> INFO: Expecting 41600 events.
[10:27:27.499] <TB0> INFO: 41600 events read in total (3403ms).
[10:27:27.500] <TB0> INFO: Test took 4220ms.
[10:27:27.502] <TB0> INFO: scanning low vcal = 140
[10:27:27.774] <TB0> INFO: Expecting 41600 events.
[10:27:31.722] <TB0> INFO: 41600 events read in total (3392ms).
[10:27:31.723] <TB0> INFO: Test took 4221ms.
[10:27:31.725] <TB0> INFO: scanning low vcal = 150
[10:27:32.004] <TB0> INFO: Expecting 41600 events.
[10:27:35.953] <TB0> INFO: 41600 events read in total (3393ms).
[10:27:35.954] <TB0> INFO: Test took 4229ms.
[10:27:35.956] <TB0> INFO: scanning low vcal = 160
[10:27:36.219] <TB0> INFO: Expecting 41600 events.
[10:27:40.195] <TB0> INFO: 41600 events read in total (3420ms).
[10:27:40.196] <TB0> INFO: Test took 4240ms.
[10:27:40.198] <TB0> INFO: scanning low vcal = 170
[10:27:40.474] <TB0> INFO: Expecting 41600 events.
[10:27:44.451] <TB0> INFO: 41600 events read in total (3421ms).
[10:27:44.451] <TB0> INFO: Test took 4253ms.
[10:27:44.455] <TB0> INFO: scanning low vcal = 180
[10:27:44.728] <TB0> INFO: Expecting 41600 events.
[10:27:48.709] <TB0> INFO: 41600 events read in total (3424ms).
[10:27:48.710] <TB0> INFO: Test took 4255ms.
[10:27:48.713] <TB0> INFO: scanning low vcal = 190
[10:27:48.972] <TB0> INFO: Expecting 41600 events.
[10:27:52.910] <TB0> INFO: 41600 events read in total (3382ms).
[10:27:52.911] <TB0> INFO: Test took 4198ms.
[10:27:52.914] <TB0> INFO: scanning low vcal = 200
[10:27:53.191] <TB0> INFO: Expecting 41600 events.
[10:27:57.114] <TB0> INFO: 41600 events read in total (3367ms).
[10:27:57.115] <TB0> INFO: Test took 4201ms.
[10:27:57.118] <TB0> INFO: scanning low vcal = 210
[10:27:57.383] <TB0> INFO: Expecting 41600 events.
[10:28:01.316] <TB0> INFO: 41600 events read in total (3377ms).
[10:28:01.316] <TB0> INFO: Test took 4198ms.
[10:28:01.319] <TB0> INFO: scanning low vcal = 220
[10:28:01.596] <TB0> INFO: Expecting 41600 events.
[10:28:05.525] <TB0> INFO: 41600 events read in total (3373ms).
[10:28:05.525] <TB0> INFO: Test took 4206ms.
[10:28:05.528] <TB0> INFO: scanning low vcal = 230
[10:28:05.791] <TB0> INFO: Expecting 41600 events.
[10:28:09.708] <TB0> INFO: 41600 events read in total (3361ms).
[10:28:09.708] <TB0> INFO: Test took 4180ms.
[10:28:09.711] <TB0> INFO: scanning low vcal = 240
[10:28:09.989] <TB0> INFO: Expecting 41600 events.
[10:28:13.956] <TB0> INFO: 41600 events read in total (3411ms).
[10:28:13.956] <TB0> INFO: Test took 4245ms.
[10:28:13.959] <TB0> INFO: scanning low vcal = 250
[10:28:14.232] <TB0> INFO: Expecting 41600 events.
[10:28:18.179] <TB0> INFO: 41600 events read in total (3391ms).
[10:28:18.180] <TB0> INFO: Test took 4221ms.
[10:28:18.184] <TB0> INFO: scanning high vcal = 30 (= 210 in low range)
[10:28:18.487] <TB0> INFO: Expecting 41600 events.
[10:28:22.476] <TB0> INFO: 41600 events read in total (3433ms).
[10:28:22.477] <TB0> INFO: Test took 4293ms.
[10:28:22.479] <TB0> INFO: scanning high vcal = 50 (= 350 in low range)
[10:28:22.741] <TB0> INFO: Expecting 41600 events.
[10:28:26.661] <TB0> INFO: 41600 events read in total (3364ms).
[10:28:26.661] <TB0> INFO: Test took 4182ms.
[10:28:26.664] <TB0> INFO: scanning high vcal = 70 (= 490 in low range)
[10:28:26.930] <TB0> INFO: Expecting 41600 events.
[10:28:30.845] <TB0> INFO: 41600 events read in total (3359ms).
[10:28:30.846] <TB0> INFO: Test took 4182ms.
[10:28:30.848] <TB0> INFO: scanning high vcal = 90 (= 630 in low range)
[10:28:31.112] <TB0> INFO: Expecting 41600 events.
[10:28:35.023] <TB0> INFO: 41600 events read in total (3355ms).
[10:28:35.024] <TB0> INFO: Test took 4176ms.
[10:28:35.026] <TB0> INFO: scanning high vcal = 200 (= 1400 in low range)
[10:28:35.289] <TB0> INFO: Expecting 41600 events.
[10:28:39.263] <TB0> INFO: 41600 events read in total (3418ms).
[10:28:39.264] <TB0> INFO: Test took 4238ms.
[10:28:39.925] <TB0> INFO: PixTestGainPedestal::measure() done
[10:29:11.693] <TB0> INFO: PixTestGainPedestal::fit() done
[10:29:11.693] <TB0> INFO: non-linearity mean: 0.945 0.949 0.950 0.957 0.955 0.947 0.954 0.959 0.949 0.955 0.956 0.955 0.955 0.951 0.958 0.945
[10:29:11.693] <TB0> INFO: non-linearity RMS: 0.006 0.006 0.007 0.005 0.006 0.007 0.005 0.005 0.006 0.005 0.006 0.006 0.006 0.005 0.005 0.006
[10:29:11.693] <TB0> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2348_FullQualification_2016-10-04_11h02m_1475571737//000_FulltestPxar_m20//phCalibrationFitErr35_C0.dat
[10:29:11.712] <TB0> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2348_FullQualification_2016-10-04_11h02m_1475571737//000_FulltestPxar_m20//phCalibrationFitErr35_C1.dat
[10:29:11.731] <TB0> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2348_FullQualification_2016-10-04_11h02m_1475571737//000_FulltestPxar_m20//phCalibrationFitErr35_C2.dat
[10:29:11.749] <TB0> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2348_FullQualification_2016-10-04_11h02m_1475571737//000_FulltestPxar_m20//phCalibrationFitErr35_C3.dat
[10:29:11.768] <TB0> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2348_FullQualification_2016-10-04_11h02m_1475571737//000_FulltestPxar_m20//phCalibrationFitErr35_C4.dat
[10:29:11.786] <TB0> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2348_FullQualification_2016-10-04_11h02m_1475571737//000_FulltestPxar_m20//phCalibrationFitErr35_C5.dat
[10:29:11.828] <TB0> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2348_FullQualification_2016-10-04_11h02m_1475571737//000_FulltestPxar_m20//phCalibrationFitErr35_C6.dat
[10:29:11.847] <TB0> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2348_FullQualification_2016-10-04_11h02m_1475571737//000_FulltestPxar_m20//phCalibrationFitErr35_C7.dat
[10:29:11.865] <TB0> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2348_FullQualification_2016-10-04_11h02m_1475571737//000_FulltestPxar_m20//phCalibrationFitErr35_C8.dat
[10:29:11.884] <TB0> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2348_FullQualification_2016-10-04_11h02m_1475571737//000_FulltestPxar_m20//phCalibrationFitErr35_C9.dat
[10:29:11.903] <TB0> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2348_FullQualification_2016-10-04_11h02m_1475571737//000_FulltestPxar_m20//phCalibrationFitErr35_C10.dat
[10:29:11.922] <TB0> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2348_FullQualification_2016-10-04_11h02m_1475571737//000_FulltestPxar_m20//phCalibrationFitErr35_C11.dat
[10:29:11.941] <TB0> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2348_FullQualification_2016-10-04_11h02m_1475571737//000_FulltestPxar_m20//phCalibrationFitErr35_C12.dat
[10:29:11.960] <TB0> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2348_FullQualification_2016-10-04_11h02m_1475571737//000_FulltestPxar_m20//phCalibrationFitErr35_C13.dat
[10:29:11.979] <TB0> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2348_FullQualification_2016-10-04_11h02m_1475571737//000_FulltestPxar_m20//phCalibrationFitErr35_C14.dat
[10:29:11.998] <TB0> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2348_FullQualification_2016-10-04_11h02m_1475571737//000_FulltestPxar_m20//phCalibrationFitErr35_C15.dat
[10:29:12.016] <TB0> INFO: PixTestGainPedestal::fullTest() done, duration: 157 seconds
[10:29:12.016] <TB0> INFO: Decoding statistics:
[10:29:12.016] <TB0> INFO: General information:
[10:29:12.016] <TB0> INFO: 16bit words read: 2662280
[10:29:12.016] <TB0> INFO: valid events total: 166400
[10:29:12.016] <TB0> INFO: empty events: 0
[10:29:12.016] <TB0> INFO: valid events with pixels: 166400
[10:29:12.016] <TB0> INFO: valid pixel hits: 665540
[10:29:12.016] <TB0> INFO: Event errors: 0
[10:29:12.016] <TB0> INFO: start marker: 0
[10:29:12.016] <TB0> INFO: stop marker: 0
[10:29:12.016] <TB0> INFO: overflow: 0
[10:29:12.016] <TB0> INFO: invalid 5bit words: 0
[10:29:12.016] <TB0> INFO: invalid XOR eye diagram: 0
[10:29:12.016] <TB0> INFO: frame (failed synchr.): 0
[10:29:12.016] <TB0> INFO: idle data (no TBM trl): 0
[10:29:12.017] <TB0> INFO: no data (only TBM hdr): 0
[10:29:12.017] <TB0> INFO: TBM errors: 0
[10:29:12.017] <TB0> INFO: flawed TBM headers: 0
[10:29:12.017] <TB0> INFO: flawed TBM trailers: 0
[10:29:12.017] <TB0> INFO: event ID mismatches: 0
[10:29:12.017] <TB0> INFO: ROC errors: 0
[10:29:12.017] <TB0> INFO: missing ROC header(s): 0
[10:29:12.017] <TB0> INFO: misplaced readback start: 0
[10:29:12.017] <TB0> INFO: Pixel decoding errors: 0
[10:29:12.017] <TB0> INFO: pixel data incomplete: 0
[10:29:12.017] <TB0> INFO: pixel address: 0
[10:29:12.017] <TB0> INFO: pulse height fill bit: 0
[10:29:12.017] <TB0> INFO: buffer corruption: 0
[10:29:12.024] <TB0> INFO: readReadbackCal: /usr/local/coldboxDATA/M2348_FullQualification_2016-10-04_11h02m_1475571737//000_FulltestPxar_m20//readbackCal_C0.dat .. /usr/local/coldboxDATA/M2348_FullQualification_2016-10-04_11h02m_1475571737//000_FulltestPxar_m20//readbackCal_C15.dat
[10:29:12.044] <TB0> INFO: ######################################################################
[10:29:12.044] <TB0> INFO: PixTestReadback::doTest()
[10:29:12.044] <TB0> INFO: ######################################################################
[10:29:12.044] <TB0> INFO: ----------------------------------------------------------------------
[10:29:12.044] <TB0> INFO: PixTestReadback::CalibrateVd()
[10:29:12.044] <TB0> INFO: ----------------------------------------------------------------------
[10:29:21.543] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2348_FullQualification_2016-10-04_11h02m_1475571737//000_FulltestPxar_m20//readbackCal_C0.dat
[10:29:21.543] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2348_FullQualification_2016-10-04_11h02m_1475571737//000_FulltestPxar_m20//readbackCal_C1.dat
[10:29:21.543] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2348_FullQualification_2016-10-04_11h02m_1475571737//000_FulltestPxar_m20//readbackCal_C2.dat
[10:29:21.543] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2348_FullQualification_2016-10-04_11h02m_1475571737//000_FulltestPxar_m20//readbackCal_C3.dat
[10:29:21.543] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2348_FullQualification_2016-10-04_11h02m_1475571737//000_FulltestPxar_m20//readbackCal_C4.dat
[10:29:21.543] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2348_FullQualification_2016-10-04_11h02m_1475571737//000_FulltestPxar_m20//readbackCal_C5.dat
[10:29:21.543] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2348_FullQualification_2016-10-04_11h02m_1475571737//000_FulltestPxar_m20//readbackCal_C6.dat
[10:29:21.543] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2348_FullQualification_2016-10-04_11h02m_1475571737//000_FulltestPxar_m20//readbackCal_C7.dat
[10:29:21.543] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2348_FullQualification_2016-10-04_11h02m_1475571737//000_FulltestPxar_m20//readbackCal_C8.dat
[10:29:21.543] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2348_FullQualification_2016-10-04_11h02m_1475571737//000_FulltestPxar_m20//readbackCal_C9.dat
[10:29:21.543] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2348_FullQualification_2016-10-04_11h02m_1475571737//000_FulltestPxar_m20//readbackCal_C10.dat
[10:29:21.543] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2348_FullQualification_2016-10-04_11h02m_1475571737//000_FulltestPxar_m20//readbackCal_C11.dat
[10:29:21.544] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2348_FullQualification_2016-10-04_11h02m_1475571737//000_FulltestPxar_m20//readbackCal_C12.dat
[10:29:21.544] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2348_FullQualification_2016-10-04_11h02m_1475571737//000_FulltestPxar_m20//readbackCal_C13.dat
[10:29:21.544] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2348_FullQualification_2016-10-04_11h02m_1475571737//000_FulltestPxar_m20//readbackCal_C14.dat
[10:29:21.544] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2348_FullQualification_2016-10-04_11h02m_1475571737//000_FulltestPxar_m20//readbackCal_C15.dat
[10:29:21.567] <TB0> INFO: PixTestPattern:: pg_setup set to default.
[10:29:21.567] <TB0> INFO: ----------------------------------------------------------------------
[10:29:21.567] <TB0> INFO: PixTestReadback::CalibrateVa()
[10:29:21.567] <TB0> INFO: ----------------------------------------------------------------------
[10:29:31.059] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2348_FullQualification_2016-10-04_11h02m_1475571737//000_FulltestPxar_m20//readbackCal_C0.dat
[10:29:31.059] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2348_FullQualification_2016-10-04_11h02m_1475571737//000_FulltestPxar_m20//readbackCal_C1.dat
[10:29:31.059] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2348_FullQualification_2016-10-04_11h02m_1475571737//000_FulltestPxar_m20//readbackCal_C2.dat
[10:29:31.059] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2348_FullQualification_2016-10-04_11h02m_1475571737//000_FulltestPxar_m20//readbackCal_C3.dat
[10:29:31.059] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2348_FullQualification_2016-10-04_11h02m_1475571737//000_FulltestPxar_m20//readbackCal_C4.dat
[10:29:31.059] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2348_FullQualification_2016-10-04_11h02m_1475571737//000_FulltestPxar_m20//readbackCal_C5.dat
[10:29:31.059] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2348_FullQualification_2016-10-04_11h02m_1475571737//000_FulltestPxar_m20//readbackCal_C6.dat
[10:29:31.059] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2348_FullQualification_2016-10-04_11h02m_1475571737//000_FulltestPxar_m20//readbackCal_C7.dat
[10:29:31.059] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2348_FullQualification_2016-10-04_11h02m_1475571737//000_FulltestPxar_m20//readbackCal_C8.dat
[10:29:31.059] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2348_FullQualification_2016-10-04_11h02m_1475571737//000_FulltestPxar_m20//readbackCal_C9.dat
[10:29:31.060] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2348_FullQualification_2016-10-04_11h02m_1475571737//000_FulltestPxar_m20//readbackCal_C10.dat
[10:29:31.060] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2348_FullQualification_2016-10-04_11h02m_1475571737//000_FulltestPxar_m20//readbackCal_C11.dat
[10:29:31.060] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2348_FullQualification_2016-10-04_11h02m_1475571737//000_FulltestPxar_m20//readbackCal_C12.dat
[10:29:31.060] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2348_FullQualification_2016-10-04_11h02m_1475571737//000_FulltestPxar_m20//readbackCal_C13.dat
[10:29:31.060] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2348_FullQualification_2016-10-04_11h02m_1475571737//000_FulltestPxar_m20//readbackCal_C14.dat
[10:29:31.060] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2348_FullQualification_2016-10-04_11h02m_1475571737//000_FulltestPxar_m20//readbackCal_C15.dat
[10:29:31.083] <TB0> INFO: PixTestPattern:: pg_setup set to default.
[10:29:31.083] <TB0> INFO: ----------------------------------------------------------------------
[10:29:31.083] <TB0> INFO: PixTestReadback::readbackVbg()
[10:29:31.083] <TB0> INFO: ----------------------------------------------------------------------
[10:29:38.389] <TB0> INFO: PixTestPattern:: pg_setup set to default.
[10:29:38.390] <TB0> INFO: ----------------------------------------------------------------------
[10:29:38.390] <TB0> INFO: PixTestReadback::getCalibratedVbg()
[10:29:38.390] <TB0> INFO: ----------------------------------------------------------------------
[10:29:38.390] <TB0> INFO: Vbg will be calibrated using Vd calibration
[10:29:38.390] <TB0> INFO: /*/*/*/*::: ROC 0: uncalibrated Vbg = 162.4calibrated Vbg = 1.21205 :::*/*/*/*/
[10:29:38.390] <TB0> INFO: /*/*/*/*::: ROC 1: uncalibrated Vbg = 158.3calibrated Vbg = 1.20579 :::*/*/*/*/
[10:29:38.390] <TB0> INFO: /*/*/*/*::: ROC 2: uncalibrated Vbg = 149.8calibrated Vbg = 1.22231 :::*/*/*/*/
[10:29:38.390] <TB0> INFO: /*/*/*/*::: ROC 3: uncalibrated Vbg = 156.9calibrated Vbg = 1.21972 :::*/*/*/*/
[10:29:38.390] <TB0> INFO: /*/*/*/*::: ROC 4: uncalibrated Vbg = 147.8calibrated Vbg = 1.22386 :::*/*/*/*/
[10:29:38.390] <TB0> INFO: /*/*/*/*::: ROC 5: uncalibrated Vbg = 150.1calibrated Vbg = 1.22187 :::*/*/*/*/
[10:29:38.390] <TB0> INFO: /*/*/*/*::: ROC 6: uncalibrated Vbg = 144.8calibrated Vbg = 1.22585 :::*/*/*/*/
[10:29:38.390] <TB0> INFO: /*/*/*/*::: ROC 7: uncalibrated Vbg = 152.9calibrated Vbg = 1.22535 :::*/*/*/*/
[10:29:38.390] <TB0> INFO: /*/*/*/*::: ROC 8: uncalibrated Vbg = 156.8calibrated Vbg = 1.23318 :::*/*/*/*/
[10:29:38.390] <TB0> INFO: /*/*/*/*::: ROC 9: uncalibrated Vbg = 162.8calibrated Vbg = 1.2368 :::*/*/*/*/
[10:29:38.390] <TB0> INFO: /*/*/*/*::: ROC 10: uncalibrated Vbg = 149.7calibrated Vbg = 1.23126 :::*/*/*/*/
[10:29:38.390] <TB0> INFO: /*/*/*/*::: ROC 11: uncalibrated Vbg = 151.9calibrated Vbg = 1.22435 :::*/*/*/*/
[10:29:38.390] <TB0> INFO: /*/*/*/*::: ROC 12: uncalibrated Vbg = 160.3calibrated Vbg = 1.20759 :::*/*/*/*/
[10:29:38.390] <TB0> INFO: /*/*/*/*::: ROC 13: uncalibrated Vbg = 162.5calibrated Vbg = 1.21385 :::*/*/*/*/
[10:29:38.390] <TB0> INFO: /*/*/*/*::: ROC 14: uncalibrated Vbg = 143.5calibrated Vbg = 1.22197 :::*/*/*/*/
[10:29:38.390] <TB0> INFO: /*/*/*/*::: ROC 15: uncalibrated Vbg = 154.5calibrated Vbg = 1.21272 :::*/*/*/*/
[10:29:38.394] <TB0> INFO: ----------------------------------------------------------------------
[10:29:38.394] <TB0> INFO: PixTestReadback::CalibrateIa()
[10:29:38.394] <TB0> INFO: ----------------------------------------------------------------------
[10:32:13.598] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2348_FullQualification_2016-10-04_11h02m_1475571737//000_FulltestPxar_m20//readbackCal_C0.dat
[10:32:13.598] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2348_FullQualification_2016-10-04_11h02m_1475571737//000_FulltestPxar_m20//readbackCal_C1.dat
[10:32:13.598] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2348_FullQualification_2016-10-04_11h02m_1475571737//000_FulltestPxar_m20//readbackCal_C2.dat
[10:32:13.598] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2348_FullQualification_2016-10-04_11h02m_1475571737//000_FulltestPxar_m20//readbackCal_C3.dat
[10:32:13.598] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2348_FullQualification_2016-10-04_11h02m_1475571737//000_FulltestPxar_m20//readbackCal_C4.dat
[10:32:13.598] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2348_FullQualification_2016-10-04_11h02m_1475571737//000_FulltestPxar_m20//readbackCal_C5.dat
[10:32:13.598] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2348_FullQualification_2016-10-04_11h02m_1475571737//000_FulltestPxar_m20//readbackCal_C6.dat
[10:32:13.598] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2348_FullQualification_2016-10-04_11h02m_1475571737//000_FulltestPxar_m20//readbackCal_C7.dat
[10:32:13.598] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2348_FullQualification_2016-10-04_11h02m_1475571737//000_FulltestPxar_m20//readbackCal_C8.dat
[10:32:13.598] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2348_FullQualification_2016-10-04_11h02m_1475571737//000_FulltestPxar_m20//readbackCal_C9.dat
[10:32:13.598] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2348_FullQualification_2016-10-04_11h02m_1475571737//000_FulltestPxar_m20//readbackCal_C10.dat
[10:32:13.598] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2348_FullQualification_2016-10-04_11h02m_1475571737//000_FulltestPxar_m20//readbackCal_C11.dat
[10:32:13.598] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2348_FullQualification_2016-10-04_11h02m_1475571737//000_FulltestPxar_m20//readbackCal_C12.dat
[10:32:13.598] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2348_FullQualification_2016-10-04_11h02m_1475571737//000_FulltestPxar_m20//readbackCal_C13.dat
[10:32:13.599] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2348_FullQualification_2016-10-04_11h02m_1475571737//000_FulltestPxar_m20//readbackCal_C14.dat
[10:32:13.599] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2348_FullQualification_2016-10-04_11h02m_1475571737//000_FulltestPxar_m20//readbackCal_C15.dat
[10:32:13.625] <TB0> INFO: PixTestPattern:: pg_setup set to default.
[10:32:13.626] <TB0> INFO: PixTestReadback::doTest() done
[10:32:13.626] <TB0> INFO: Decoding statistics:
[10:32:13.626] <TB0> INFO: General information:
[10:32:13.626] <TB0> INFO: 16bit words read: 1024
[10:32:13.626] <TB0> INFO: valid events total: 128
[10:32:13.626] <TB0> INFO: empty events: 128
[10:32:13.626] <TB0> INFO: valid events with pixels: 0
[10:32:13.626] <TB0> INFO: valid pixel hits: 0
[10:32:13.626] <TB0> INFO: Event errors: 0
[10:32:13.626] <TB0> INFO: start marker: 0
[10:32:13.626] <TB0> INFO: stop marker: 0
[10:32:13.626] <TB0> INFO: overflow: 0
[10:32:13.626] <TB0> INFO: invalid 5bit words: 0
[10:32:13.626] <TB0> INFO: invalid XOR eye diagram: 0
[10:32:13.626] <TB0> INFO: frame (failed synchr.): 0
[10:32:13.626] <TB0> INFO: idle data (no TBM trl): 0
[10:32:13.626] <TB0> INFO: no data (only TBM hdr): 0
[10:32:13.626] <TB0> INFO: TBM errors: 0
[10:32:13.626] <TB0> INFO: flawed TBM headers: 0
[10:32:13.626] <TB0> INFO: flawed TBM trailers: 0
[10:32:13.626] <TB0> INFO: event ID mismatches: 0
[10:32:13.626] <TB0> INFO: ROC errors: 0
[10:32:13.626] <TB0> INFO: missing ROC header(s): 0
[10:32:13.626] <TB0> INFO: misplaced readback start: 0
[10:32:13.626] <TB0> INFO: Pixel decoding errors: 0
[10:32:13.626] <TB0> INFO: pixel data incomplete: 0
[10:32:13.626] <TB0> INFO: pixel address: 0
[10:32:13.626] <TB0> INFO: pulse height fill bit: 0
[10:32:13.626] <TB0> INFO: buffer corruption: 0
[10:32:13.646] <TB0> INFO: Decoding statistics:
[10:32:13.646] <TB0> INFO: General information:
[10:32:13.646] <TB0> INFO: 16bit words read: 2750234
[10:32:13.646] <TB0> INFO: valid events total: 176768
[10:32:13.646] <TB0> INFO: empty events: 7863
[10:32:13.646] <TB0> INFO: valid events with pixels: 168905
[10:32:13.646] <TB0> INFO: valid pixel hits: 668045
[10:32:13.646] <TB0> INFO: Event errors: 0
[10:32:13.646] <TB0> INFO: start marker: 0
[10:32:13.646] <TB0> INFO: stop marker: 0
[10:32:13.646] <TB0> INFO: overflow: 0
[10:32:13.646] <TB0> INFO: invalid 5bit words: 0
[10:32:13.646] <TB0> INFO: invalid XOR eye diagram: 0
[10:32:13.646] <TB0> INFO: frame (failed synchr.): 0
[10:32:13.646] <TB0> INFO: idle data (no TBM trl): 0
[10:32:13.646] <TB0> INFO: no data (only TBM hdr): 0
[10:32:13.646] <TB0> INFO: TBM errors: 0
[10:32:13.646] <TB0> INFO: flawed TBM headers: 0
[10:32:13.646] <TB0> INFO: flawed TBM trailers: 0
[10:32:13.646] <TB0> INFO: event ID mismatches: 0
[10:32:13.646] <TB0> INFO: ROC errors: 0
[10:32:13.646] <TB0> INFO: missing ROC header(s): 0
[10:32:13.646] <TB0> INFO: misplaced readback start: 0
[10:32:13.646] <TB0> INFO: Pixel decoding errors: 0
[10:32:13.646] <TB0> INFO: pixel data incomplete: 0
[10:32:13.646] <TB0> INFO: pixel address: 0
[10:32:13.646] <TB0> INFO: pulse height fill bit: 0
[10:32:13.646] <TB0> INFO: buffer corruption: 0
[10:32:13.646] <TB0> INFO: enter test to run
[10:32:13.646] <TB0> INFO: test: exit no parameter change
[10:32:13.840] <TB0> QUIET: Connection to board 73 closed.
[10:32:13.919] <TB0> INFO: pXar: this is the end, my friend