[22:23:27.422] <TB0> INFO: *** Welcome to pxar ***
[22:23:27.422] <TB0> INFO: *** Today: 2016/10/19
[22:23:27.427] <TB0> INFO: *** Version: c8ba-dirty
[22:23:27.427] <TB0> INFO: readRocDacs: /home/l_tester/david/FullQualification/data/M1024_FullQualification_2016-10-19_19h52m_1476899523//003_Fulltest_p17//dacParameters_C0.dat .. /home/l_tester/david/FullQualification/data/M1024_FullQualification_2016-10-19_19h52m_1476899523//003_Fulltest_p17//dacParameters_C15.dat
[22:23:27.428] <TB0> INFO: readTbmDacs: /home/l_tester/david/FullQualification/data/M1024_FullQualification_2016-10-19_19h52m_1476899523//003_Fulltest_p17//tbmParameters_C0a.dat .. /home/l_tester/david/FullQualification/data/M1024_FullQualification_2016-10-19_19h52m_1476899523//003_Fulltest_p17//tbmParameters_C1b.dat
[22:23:27.428] <TB0> INFO: readMaskFile: /home/l_tester/david/FullQualification/data/M1024_FullQualification_2016-10-19_19h52m_1476899523//003_Fulltest_p17//defaultMaskFile.dat
[22:23:27.428] <TB0> INFO: readTrimFile: /home/l_tester/david/FullQualification/data/M1024_FullQualification_2016-10-19_19h52m_1476899523//003_Fulltest_p17//trimParameters_C0.dat .. /home/l_tester/david/FullQualification/data/M1024_FullQualification_2016-10-19_19h52m_1476899523//003_Fulltest_p17//trimParameters_C15.dat
[22:23:27.487] <TB0> INFO: clk: 4
[22:23:27.487] <TB0> INFO: ctr: 4
[22:23:27.487] <TB0> INFO: sda: 19
[22:23:27.487] <TB0> INFO: tin: 9
[22:23:27.487] <TB0> INFO: level: 15
[22:23:27.487] <TB0> INFO: triggerdelay: 0
[22:23:27.487] <TB0> QUIET: Instanciating API for pxar v2.7.6+55~gafdbfd9
[22:23:27.487] <TB0> INFO: Log level: INFO
[22:23:27.496] <TB0> INFO: Found DTB DTB_WRQ4OZ
[22:23:27.506] <TB0> QUIET: Connection to board DTB_WRQ4OZ opened.
[22:23:27.508] <TB0> INFO: DTB startup information
--- DTB info------------------------------------------
Board id: 71
HW version: DTB1.2
FW version: 4.6
SW version: 4.7
Options:
USB id: DTB_WRQ4OZ
MAC address: 40D855118047
Hostname: pixelDTB071
Comment:
------------------------------------------------------
[22:23:27.510] <TB0> INFO: RPC call hashes of host and DTB match: 486171790
[22:23:28.996] <TB0> INFO: DUT info:
[22:23:28.996] <TB0> INFO: The DUT currently contains the following objects:
[22:23:28.996] <TB0> INFO: 4 TBM Cores tbm10c (4 ON)
[22:23:28.996] <TB0> INFO: TBM Core alpha (0): 7 registers set
[22:23:28.996] <TB0> INFO: TBM Core beta (1): 7 registers set
[22:23:28.996] <TB0> INFO: TBM Core alpha (2): 7 registers set
[22:23:28.996] <TB0> INFO: TBM Core beta (3): 7 registers set
[22:23:28.996] <TB0> INFO: 16 ROCs proc600 (16 ON) with 4160 pixelConfigs
[22:23:28.996] <TB0> INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[22:23:28.996] <TB0> INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[22:23:28.996] <TB0> INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[22:23:28.996] <TB0> INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[22:23:28.996] <TB0> INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[22:23:28.996] <TB0> INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[22:23:28.996] <TB0> INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[22:23:28.996] <TB0> INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[22:23:28.996] <TB0> INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[22:23:28.996] <TB0> INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[22:23:28.996] <TB0> INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[22:23:28.996] <TB0> INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[22:23:28.996] <TB0> INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[22:23:28.996] <TB0> INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[22:23:28.996] <TB0> INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[22:23:28.996] <TB0> INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[22:23:29.397] <TB0> INFO: enter 'restricted' command line mode
[22:23:29.397] <TB0> INFO: enter test to run
[22:23:29.397] <TB0> INFO: test: pretest no parameter change
[22:23:29.397] <TB0> INFO: running: pretest
[22:23:29.402] <TB0> INFO: ######################################################################
[22:23:29.402] <TB0> INFO: PixTestPretest::doTest()
[22:23:29.402] <TB0> INFO: ######################################################################
[22:23:29.403] <TB0> INFO: ----------------------------------------------------------------------
[22:23:29.403] <TB0> INFO: PixTestPretest::programROC()
[22:23:29.403] <TB0> INFO: ----------------------------------------------------------------------
[22:23:47.417] <TB0> INFO: PixTestPretest::programROC() done: ROCs are all programmable
[22:23:47.417] <TB0> INFO: IA differences per ROC: 19.3 18.5 20.9 19.3 16.9 20.1 19.3 17.7 20.1 20.1 19.3 17.7 18.5 20.1 22.5 19.3
[22:23:47.473] <TB0> INFO: ----------------------------------------------------------------------
[22:23:47.473] <TB0> INFO: PixTestPretest::setVana() target Ia = 24 mA/ROC
[22:23:47.473] <TB0> INFO: ----------------------------------------------------------------------
[22:23:54.454] <TB0> INFO: PixTestPretest::setVana() done, Module Ia 397.1 mA = 24.8188 mA/ROC
[22:23:54.454] <TB0> INFO: i(loss) [mA/ROC]: 19.3 20.1 20.1 20.1 20.1 20.1 20.1 20.9 20.1 19.3 20.1 19.3 20.1 19.3 20.1 19.3
[22:23:54.484] <TB0> INFO: ----------------------------------------------------------------------
[22:23:54.484] <TB0> INFO: PixTestPretest::findTiming()
[22:23:54.484] <TB0> INFO: ----------------------------------------------------------------------
[22:23:54.485] <TB0> INFO: PixTestCmd::init()

[22:24:26.291] <TB0> INFO: TBM phases: 160MHz: 7, 400MHz: 2, TBM delays: ROC(0/1):5, header/trailer: 1, token: 1
[22:24:26.291] <TB0> INFO: (success/tries = 100/100), width = 3
[22:24:27.791] <TB0> INFO: ----------------------------------------------------------------------
[22:24:27.791] <TB0> INFO: PixTestPretest::findWorkingPixel()
[22:24:27.791] <TB0> INFO: ----------------------------------------------------------------------
[22:24:27.887] <TB0> INFO: Expecting 231680 events.
[22:24:37.986] <TB0> INFO: 231680 events read in total (9507ms).
[22:24:37.993] <TB0> INFO: Test took 10196ms.
[22:24:38.244] <TB0> INFO: Found working pixel in all ROCs: col/row = 12/22
[22:24:38.276] <TB0> INFO: ----------------------------------------------------------------------
[22:24:38.276] <TB0> INFO: PixTestPretest::setVthrCompCalDel()
[22:24:38.276] <TB0> INFO: ----------------------------------------------------------------------
[22:24:38.371] <TB0> INFO: Expecting 231680 events.
[22:24:48.398] <TB0> INFO: 231680 events read in total (9435ms).
[22:24:48.408] <TB0> INFO: Test took 10126ms.
[22:24:48.674] <TB0> INFO: PixTestPretest::setVthrCompCalDel() done
[22:24:48.675] <TB0> INFO: CalDel: 75 75 83 86 71 80 92 77 90 90 90 93 80 87 90 101
[22:24:48.675] <TB0> INFO: VthrComp: 51 51 51 51 51 51 51 51 51 51 51 51 51 51 51 51
[22:24:48.678] <TB0> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1024_FullQualification_2016-10-19_19h52m_1476899523//003_Fulltest_p17//dacParameters_C0.dat
[22:24:48.679] <TB0> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1024_FullQualification_2016-10-19_19h52m_1476899523//003_Fulltest_p17//dacParameters_C1.dat
[22:24:48.679] <TB0> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1024_FullQualification_2016-10-19_19h52m_1476899523//003_Fulltest_p17//dacParameters_C2.dat
[22:24:48.679] <TB0> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1024_FullQualification_2016-10-19_19h52m_1476899523//003_Fulltest_p17//dacParameters_C3.dat
[22:24:48.679] <TB0> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1024_FullQualification_2016-10-19_19h52m_1476899523//003_Fulltest_p17//dacParameters_C4.dat
[22:24:48.679] <TB0> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1024_FullQualification_2016-10-19_19h52m_1476899523//003_Fulltest_p17//dacParameters_C5.dat
[22:24:48.680] <TB0> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1024_FullQualification_2016-10-19_19h52m_1476899523//003_Fulltest_p17//dacParameters_C6.dat
[22:24:48.680] <TB0> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1024_FullQualification_2016-10-19_19h52m_1476899523//003_Fulltest_p17//dacParameters_C7.dat
[22:24:48.680] <TB0> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1024_FullQualification_2016-10-19_19h52m_1476899523//003_Fulltest_p17//dacParameters_C8.dat
[22:24:48.680] <TB0> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1024_FullQualification_2016-10-19_19h52m_1476899523//003_Fulltest_p17//dacParameters_C9.dat
[22:24:48.680] <TB0> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1024_FullQualification_2016-10-19_19h52m_1476899523//003_Fulltest_p17//dacParameters_C10.dat
[22:24:48.680] <TB0> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1024_FullQualification_2016-10-19_19h52m_1476899523//003_Fulltest_p17//dacParameters_C11.dat
[22:24:48.680] <TB0> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1024_FullQualification_2016-10-19_19h52m_1476899523//003_Fulltest_p17//dacParameters_C12.dat
[22:24:48.680] <TB0> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1024_FullQualification_2016-10-19_19h52m_1476899523//003_Fulltest_p17//dacParameters_C13.dat
[22:24:48.680] <TB0> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1024_FullQualification_2016-10-19_19h52m_1476899523//003_Fulltest_p17//dacParameters_C14.dat
[22:24:48.680] <TB0> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1024_FullQualification_2016-10-19_19h52m_1476899523//003_Fulltest_p17//dacParameters_C15.dat
[22:24:48.681] <TB0> INFO: write tbm parameters into /home/l_tester/david/FullQualification/data/M1024_FullQualification_2016-10-19_19h52m_1476899523//003_Fulltest_p17//tbmParameters_C0a.dat
[22:24:48.681] <TB0> INFO: write tbm parameters into /home/l_tester/david/FullQualification/data/M1024_FullQualification_2016-10-19_19h52m_1476899523//003_Fulltest_p17//tbmParameters_C0b.dat
[22:24:48.681] <TB0> INFO: write tbm parameters into /home/l_tester/david/FullQualification/data/M1024_FullQualification_2016-10-19_19h52m_1476899523//003_Fulltest_p17//tbmParameters_C1a.dat
[22:24:48.681] <TB0> INFO: write tbm parameters into /home/l_tester/david/FullQualification/data/M1024_FullQualification_2016-10-19_19h52m_1476899523//003_Fulltest_p17//tbmParameters_C1b.dat
[22:24:48.681] <TB0> INFO: PixTestPretest::doTest() done, duration: 79 seconds
[22:24:48.733] <TB0> INFO: enter test to run
[22:24:48.733] <TB0> INFO: test: FullTest no parameter change
[22:24:48.733] <TB0> INFO: running: fulltest
[22:24:48.733] <TB0> INFO: ######################################################################
[22:24:48.733] <TB0> INFO: PixTestFullTest::doTest()
[22:24:48.733] <TB0> INFO: ######################################################################
[22:24:48.735] <TB0> INFO: ######################################################################
[22:24:48.735] <TB0> INFO: PixTestAlive::doTest()
[22:24:48.735] <TB0> INFO: ######################################################################
[22:24:48.736] <TB0> INFO: ----------------------------------------------------------------------
[22:24:48.736] <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)
[22:24:48.736] <TB0> INFO: ----------------------------------------------------------------------
[22:24:48.978] <TB0> INFO: Expecting 41600 events.
[22:24:52.740] <TB0> INFO: 41600 events read in total (3171ms).
[22:24:52.741] <TB0> INFO: Test took 4004ms.
[22:24:52.974] <TB0> INFO: PixTestAlive::aliveTest() done
[22:24:52.974] <TB0> INFO: number of dead pixels (per ROC): 0 1 0 1 0 0 0 3 0 0 0 0 0 0 0 0
[22:24:52.976] <TB0> INFO: ----------------------------------------------------------------------
[22:24:52.976] <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)
[22:24:52.976] <TB0> INFO: ----------------------------------------------------------------------
[22:24:53.222] <TB0> INFO: Expecting 41600 events.
[22:24:56.191] <TB0> INFO: 41600 events read in total (2377ms).
[22:24:56.192] <TB0> INFO: Test took 3214ms.
[22:24:56.192] <TB0> INFO: mask vs. old pixelAlive PixelAlive_C0_V0 .. PixelAlive_C15_V0
[22:24:56.429] <TB0> INFO: PixTestAlive::maskTest() done
[22:24:56.429] <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
[22:24:56.431] <TB0> INFO: ----------------------------------------------------------------------
[22:24:56.431] <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)
[22:24:56.431] <TB0> INFO: ----------------------------------------------------------------------
[22:24:56.711] <TB0> INFO: Expecting 41600 events.
[22:25:00.300] <TB0> INFO: 41600 events read in total (2997ms).
[22:25:00.300] <TB0> INFO: Test took 3867ms.
[22:25:00.532] <TB0> INFO: PixTestAlive::addressDecodingTest() done
[22:25:00.532] <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
[22:25:00.532] <TB0> INFO: PixTestAlive::doTest() done, duration: 11 seconds
[22:25:00.533] <TB0> INFO: Decoding statistics:
[22:25:00.533] <TB0> INFO: General information:
[22:25:00.533] <TB0> INFO: 16bit words read: 0
[22:25:00.533] <TB0> INFO: valid events total: 0
[22:25:00.533] <TB0> INFO: empty events: 0
[22:25:00.533] <TB0> INFO: valid events with pixels: 0
[22:25:00.533] <TB0> INFO: valid pixel hits: 0
[22:25:00.533] <TB0> INFO: Event errors: 0
[22:25:00.533] <TB0> INFO: start marker: 0
[22:25:00.533] <TB0> INFO: stop marker: 0
[22:25:00.533] <TB0> INFO: overflow: 0
[22:25:00.533] <TB0> INFO: invalid 5bit words: 0
[22:25:00.533] <TB0> INFO: invalid XOR eye diagram: 0
[22:25:00.533] <TB0> INFO: frame (failed synchr.): 0
[22:25:00.533] <TB0> INFO: idle data (no TBM trl): 0
[22:25:00.533] <TB0> INFO: no data (only TBM hdr): 0
[22:25:00.533] <TB0> INFO: TBM errors: 0
[22:25:00.533] <TB0> INFO: flawed TBM headers: 0
[22:25:00.533] <TB0> INFO: flawed TBM trailers: 0
[22:25:00.533] <TB0> INFO: event ID mismatches: 0
[22:25:00.533] <TB0> INFO: ROC errors: 0
[22:25:00.533] <TB0> INFO: missing ROC header(s): 0
[22:25:00.533] <TB0> INFO: misplaced readback start: 0
[22:25:00.533] <TB0> INFO: Pixel decoding errors: 0
[22:25:00.533] <TB0> INFO: pixel data incomplete: 0
[22:25:00.533] <TB0> INFO: pixel address: 0
[22:25:00.533] <TB0> INFO: pulse height fill bit: 0
[22:25:00.533] <TB0> INFO: buffer corruption: 0
[22:25:00.540] <TB0> INFO: readReadbackCal: /home/l_tester/david/FullQualification/data/M1024_FullQualification_2016-10-19_19h52m_1476899523//003_Fulltest_p17//readbackCal_C0.dat .. /home/l_tester/david/FullQualification/data/M1024_FullQualification_2016-10-19_19h52m_1476899523//003_Fulltest_p17//readbackCal_C15.dat
[22:25:00.541] <TB0> INFO: readGainPedestalParameters /home/l_tester/david/FullQualification/data/M1024_FullQualification_2016-10-19_19h52m_1476899523//003_Fulltest_p17//phCalibrationFitErr_C0.dat .. /home/l_tester/david/FullQualification/data/M1024_FullQualification_2016-10-19_19h52m_1476899523//003_Fulltest_p17//phCalibrationFitErr_C15.dat

[22:25:00.541] <TB0> INFO: ######################################################################
[22:25:00.541] <TB0> INFO: PixTestReadback::doTest()
[22:25:00.541] <TB0> INFO: ######################################################################
[22:25:00.541] <TB0> INFO: ----------------------------------------------------------------------
[22:25:00.541] <TB0> INFO: PixTestReadback::CalibrateVd()
[22:25:00.541] <TB0> INFO: ----------------------------------------------------------------------
[22:25:10.506] <TB0> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1024_FullQualification_2016-10-19_19h52m_1476899523//003_Fulltest_p17//readbackCal_C0.dat
[22:25:10.506] <TB0> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1024_FullQualification_2016-10-19_19h52m_1476899523//003_Fulltest_p17//readbackCal_C1.dat
[22:25:10.506] <TB0> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1024_FullQualification_2016-10-19_19h52m_1476899523//003_Fulltest_p17//readbackCal_C2.dat
[22:25:10.506] <TB0> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1024_FullQualification_2016-10-19_19h52m_1476899523//003_Fulltest_p17//readbackCal_C3.dat
[22:25:10.506] <TB0> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1024_FullQualification_2016-10-19_19h52m_1476899523//003_Fulltest_p17//readbackCal_C4.dat
[22:25:10.507] <TB0> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1024_FullQualification_2016-10-19_19h52m_1476899523//003_Fulltest_p17//readbackCal_C5.dat
[22:25:10.507] <TB0> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1024_FullQualification_2016-10-19_19h52m_1476899523//003_Fulltest_p17//readbackCal_C6.dat
[22:25:10.507] <TB0> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1024_FullQualification_2016-10-19_19h52m_1476899523//003_Fulltest_p17//readbackCal_C7.dat
[22:25:10.507] <TB0> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1024_FullQualification_2016-10-19_19h52m_1476899523//003_Fulltest_p17//readbackCal_C8.dat
[22:25:10.507] <TB0> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1024_FullQualification_2016-10-19_19h52m_1476899523//003_Fulltest_p17//readbackCal_C9.dat
[22:25:10.507] <TB0> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1024_FullQualification_2016-10-19_19h52m_1476899523//003_Fulltest_p17//readbackCal_C10.dat
[22:25:10.507] <TB0> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1024_FullQualification_2016-10-19_19h52m_1476899523//003_Fulltest_p17//readbackCal_C11.dat
[22:25:10.507] <TB0> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1024_FullQualification_2016-10-19_19h52m_1476899523//003_Fulltest_p17//readbackCal_C12.dat
[22:25:10.507] <TB0> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1024_FullQualification_2016-10-19_19h52m_1476899523//003_Fulltest_p17//readbackCal_C13.dat
[22:25:10.507] <TB0> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1024_FullQualification_2016-10-19_19h52m_1476899523//003_Fulltest_p17//readbackCal_C14.dat
[22:25:10.507] <TB0> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1024_FullQualification_2016-10-19_19h52m_1476899523//003_Fulltest_p17//readbackCal_C15.dat
[22:25:10.542] <TB0> INFO: PixTestPattern:: pg_setup set to default.
[22:25:10.542] <TB0> INFO: ----------------------------------------------------------------------
[22:25:10.542] <TB0> INFO: PixTestReadback::CalibrateVa()
[22:25:10.542] <TB0> INFO: ----------------------------------------------------------------------
[22:25:20.458] <TB0> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1024_FullQualification_2016-10-19_19h52m_1476899523//003_Fulltest_p17//readbackCal_C0.dat
[22:25:20.458] <TB0> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1024_FullQualification_2016-10-19_19h52m_1476899523//003_Fulltest_p17//readbackCal_C1.dat
[22:25:20.458] <TB0> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1024_FullQualification_2016-10-19_19h52m_1476899523//003_Fulltest_p17//readbackCal_C2.dat
[22:25:20.458] <TB0> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1024_FullQualification_2016-10-19_19h52m_1476899523//003_Fulltest_p17//readbackCal_C3.dat
[22:25:20.459] <TB0> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1024_FullQualification_2016-10-19_19h52m_1476899523//003_Fulltest_p17//readbackCal_C4.dat
[22:25:20.459] <TB0> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1024_FullQualification_2016-10-19_19h52m_1476899523//003_Fulltest_p17//readbackCal_C5.dat
[22:25:20.459] <TB0> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1024_FullQualification_2016-10-19_19h52m_1476899523//003_Fulltest_p17//readbackCal_C6.dat
[22:25:20.459] <TB0> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1024_FullQualification_2016-10-19_19h52m_1476899523//003_Fulltest_p17//readbackCal_C7.dat
[22:25:20.459] <TB0> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1024_FullQualification_2016-10-19_19h52m_1476899523//003_Fulltest_p17//readbackCal_C8.dat
[22:25:20.459] <TB0> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1024_FullQualification_2016-10-19_19h52m_1476899523//003_Fulltest_p17//readbackCal_C9.dat
[22:25:20.459] <TB0> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1024_FullQualification_2016-10-19_19h52m_1476899523//003_Fulltest_p17//readbackCal_C10.dat
[22:25:20.459] <TB0> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1024_FullQualification_2016-10-19_19h52m_1476899523//003_Fulltest_p17//readbackCal_C11.dat
[22:25:20.459] <TB0> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1024_FullQualification_2016-10-19_19h52m_1476899523//003_Fulltest_p17//readbackCal_C12.dat
[22:25:20.459] <TB0> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1024_FullQualification_2016-10-19_19h52m_1476899523//003_Fulltest_p17//readbackCal_C13.dat
[22:25:20.459] <TB0> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1024_FullQualification_2016-10-19_19h52m_1476899523//003_Fulltest_p17//readbackCal_C14.dat
[22:25:20.459] <TB0> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1024_FullQualification_2016-10-19_19h52m_1476899523//003_Fulltest_p17//readbackCal_C15.dat
[22:25:20.488] <TB0> INFO: PixTestPattern:: pg_setup set to default.
[22:25:20.488] <TB0> INFO: ----------------------------------------------------------------------
[22:25:20.488] <TB0> INFO: PixTestReadback::readbackVbg()
[22:25:20.488] <TB0> INFO: ----------------------------------------------------------------------
[22:25:28.151] <TB0> INFO: PixTestPattern:: pg_setup set to default.
[22:25:28.151] <TB0> INFO: ----------------------------------------------------------------------
[22:25:28.151] <TB0> INFO: PixTestReadback::getCalibratedVbg()
[22:25:28.151] <TB0> INFO: ----------------------------------------------------------------------
[22:25:28.151] <TB0> INFO: Vbg will be calibrated using Vd calibration
[22:25:28.151] <TB0> INFO: /*/*/*/*::: ROC 0: uncalibrated Vbg = 155calibrated Vbg = 1.19067 :::*/*/*/*/
[22:25:28.151] <TB0> INFO: /*/*/*/*::: ROC 1: uncalibrated Vbg = 148.5calibrated Vbg = 1.18589 :::*/*/*/*/
[22:25:28.151] <TB0> INFO: /*/*/*/*::: ROC 2: uncalibrated Vbg = 152.7calibrated Vbg = 1.18292 :::*/*/*/*/
[22:25:28.151] <TB0> INFO: /*/*/*/*::: ROC 3: uncalibrated Vbg = 160.1calibrated Vbg = 1.17355 :::*/*/*/*/
[22:25:28.151] <TB0> INFO: /*/*/*/*::: ROC 4: uncalibrated Vbg = 156.9calibrated Vbg = 1.17205 :::*/*/*/*/
[22:25:28.151] <TB0> INFO: /*/*/*/*::: ROC 5: uncalibrated Vbg = 148.8calibrated Vbg = 1.18848 :::*/*/*/*/
[22:25:28.151] <TB0> INFO: /*/*/*/*::: ROC 6: uncalibrated Vbg = 137.8calibrated Vbg = 1.18914 :::*/*/*/*/
[22:25:28.151] <TB0> INFO: /*/*/*/*::: ROC 7: uncalibrated Vbg = 150.6calibrated Vbg = 1.18574 :::*/*/*/*/
[22:25:28.151] <TB0> INFO: /*/*/*/*::: ROC 8: uncalibrated Vbg = 149.8calibrated Vbg = 1.18137 :::*/*/*/*/
[22:25:28.151] <TB0> INFO: /*/*/*/*::: ROC 9: uncalibrated Vbg = 150.3calibrated Vbg = 1.18283 :::*/*/*/*/
[22:25:28.151] <TB0> INFO: /*/*/*/*::: ROC 10: uncalibrated Vbg = 147calibrated Vbg = 1.17695 :::*/*/*/*/
[22:25:28.151] <TB0> INFO: /*/*/*/*::: ROC 11: uncalibrated Vbg = 149calibrated Vbg = 1.17448 :::*/*/*/*/
[22:25:28.151] <TB0> INFO: /*/*/*/*::: ROC 12: uncalibrated Vbg = 156.8calibrated Vbg = 1.17735 :::*/*/*/*/
[22:25:28.151] <TB0> INFO: /*/*/*/*::: ROC 13: uncalibrated Vbg = 153calibrated Vbg = 1.18619 :::*/*/*/*/
[22:25:28.151] <TB0> INFO: /*/*/*/*::: ROC 14: uncalibrated Vbg = 149.4calibrated Vbg = 1.18792 :::*/*/*/*/
[22:25:28.151] <TB0> INFO: /*/*/*/*::: ROC 15: uncalibrated Vbg = 149.1calibrated Vbg = 1.18582 :::*/*/*/*/
[22:25:28.154] <TB0> INFO: ----------------------------------------------------------------------
[22:25:28.154] <TB0> INFO: PixTestReadback::CalibrateIa()
[22:25:28.154] <TB0> INFO: ----------------------------------------------------------------------


[22:28:08.999] <TB0> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1024_FullQualification_2016-10-19_19h52m_1476899523//003_Fulltest_p17//readbackCal_C0.dat
[22:28:08.999] <TB0> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1024_FullQualification_2016-10-19_19h52m_1476899523//003_Fulltest_p17//readbackCal_C1.dat
[22:28:08.999] <TB0> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1024_FullQualification_2016-10-19_19h52m_1476899523//003_Fulltest_p17//readbackCal_C2.dat
[22:28:08.999] <TB0> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1024_FullQualification_2016-10-19_19h52m_1476899523//003_Fulltest_p17//readbackCal_C3.dat
[22:28:08.999] <TB0> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1024_FullQualification_2016-10-19_19h52m_1476899523//003_Fulltest_p17//readbackCal_C4.dat
[22:28:08.000] <TB0> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1024_FullQualification_2016-10-19_19h52m_1476899523//003_Fulltest_p17//readbackCal_C5.dat
[22:28:08.000] <TB0> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1024_FullQualification_2016-10-19_19h52m_1476899523//003_Fulltest_p17//readbackCal_C6.dat
[22:28:08.000] <TB0> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1024_FullQualification_2016-10-19_19h52m_1476899523//003_Fulltest_p17//readbackCal_C7.dat
[22:28:08.000] <TB0> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1024_FullQualification_2016-10-19_19h52m_1476899523//003_Fulltest_p17//readbackCal_C8.dat
[22:28:08.000] <TB0> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1024_FullQualification_2016-10-19_19h52m_1476899523//003_Fulltest_p17//readbackCal_C9.dat
[22:28:08.000] <TB0> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1024_FullQualification_2016-10-19_19h52m_1476899523//003_Fulltest_p17//readbackCal_C10.dat
[22:28:08.000] <TB0> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1024_FullQualification_2016-10-19_19h52m_1476899523//003_Fulltest_p17//readbackCal_C11.dat
[22:28:08.000] <TB0> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1024_FullQualification_2016-10-19_19h52m_1476899523//003_Fulltest_p17//readbackCal_C12.dat
[22:28:08.000] <TB0> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1024_FullQualification_2016-10-19_19h52m_1476899523//003_Fulltest_p17//readbackCal_C13.dat
[22:28:08.000] <TB0> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1024_FullQualification_2016-10-19_19h52m_1476899523//003_Fulltest_p17//readbackCal_C14.dat
[22:28:08.000] <TB0> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1024_FullQualification_2016-10-19_19h52m_1476899523//003_Fulltest_p17//readbackCal_C15.dat
[22:28:09.030] <TB0> INFO: PixTestPattern:: pg_setup set to default.
[22:28:09.031] <TB0> INFO: PixTestReadback::doTest() done
[22:28:09.031] <TB0> INFO: Decoding statistics:
[22:28:09.031] <TB0> INFO: General information:
[22:28:09.031] <TB0> INFO: 16bit words read: 1536
[22:28:09.031] <TB0> INFO: valid events total: 256
[22:28:09.031] <TB0> INFO: empty events: 256
[22:28:09.031] <TB0> INFO: valid events with pixels: 0
[22:28:09.031] <TB0> INFO: valid pixel hits: 0
[22:28:09.031] <TB0> INFO: Event errors: 0
[22:28:09.032] <TB0> INFO: start marker: 0
[22:28:09.032] <TB0> INFO: stop marker: 0
[22:28:09.032] <TB0> INFO: overflow: 0
[22:28:09.032] <TB0> INFO: invalid 5bit words: 0
[22:28:09.032] <TB0> INFO: invalid XOR eye diagram: 0
[22:28:09.032] <TB0> INFO: frame (failed synchr.): 0
[22:28:09.032] <TB0> INFO: idle data (no TBM trl): 0
[22:28:09.032] <TB0> INFO: no data (only TBM hdr): 0
[22:28:09.032] <TB0> INFO: TBM errors: 0
[22:28:09.032] <TB0> INFO: flawed TBM headers: 0
[22:28:09.032] <TB0> INFO: flawed TBM trailers: 0
[22:28:09.032] <TB0> INFO: event ID mismatches: 0
[22:28:09.032] <TB0> INFO: ROC errors: 0
[22:28:09.032] <TB0> INFO: missing ROC header(s): 0
[22:28:09.032] <TB0> INFO: misplaced readback start: 0
[22:28:09.032] <TB0> INFO: Pixel decoding errors: 0
[22:28:09.032] <TB0> INFO: pixel data incomplete: 0
[22:28:09.032] <TB0> INFO: pixel address: 0
[22:28:09.032] <TB0> INFO: pulse height fill bit: 0
[22:28:09.032] <TB0> INFO: buffer corruption: 0
[22:28:09.087] <TB0> INFO: ######################################################################
[22:28:09.087] <TB0> INFO: PixTestBBMap::doTest() Ntrig = 5, VcalS = 250 (high range)
[22:28:09.087] <TB0> INFO: ######################################################################
[22:28:09.090] <TB0> INFO: ---> dac: VthrComp name: calSMap ntrig: 5 dacrange: 0 .. 149 (-1/-1) hits flags = 514 (plus default)
[22:28:09.103] <TB0> INFO: dacScan split into 1 runs with ntrig = 5
[22:28:09.103] <TB0> INFO: run 1 of 1
[22:28:09.340] <TB0> INFO: Expecting 3120000 events.
[22:28:41.399] <TB0> INFO: 666175 events read in total (31467ms).











[22:29:11.419] <TB0> INFO: 1325225 events read in total (61487ms).











[22:29:41.232] <TB0> INFO: 1980280 events read in total (91300ms).











[22:30:11.313] <TB0> INFO: 2637810 events read in total (121381ms).











[22:30:33.379] <TB0> INFO: 3120000 events read in total (143447ms).
[22:30:33.459] <TB0> INFO: Test took 144356ms.
[22:30:55.190] <TB0> INFO: PixTestBBMap::doTest() done with 1 decoding errors: , duration: 166 seconds
[22:30:55.190] <TB0> INFO: number of dead bumps (per ROC): 243 54 4 5 0 0 0 0 0 0 0 0 3 0 2 14
[22:30:55.190] <TB0> INFO: separation cut (per ROC): 99 102 117 114 108 108 107 110 110 110 111 111 105 110 117 119
[22:30:55.190] <TB0> INFO: Decoding statistics:
[22:30:55.190] <TB0> INFO: General information:
[22:30:55.190] <TB0> INFO: 16bit words read: 0
[22:30:55.190] <TB0> INFO: valid events total: 0
[22:30:55.190] <TB0> INFO: empty events: 0
[22:30:55.190] <TB0> INFO: valid events with pixels: 0
[22:30:55.190] <TB0> INFO: valid pixel hits: 0
[22:30:55.190] <TB0> INFO: Event errors: 0
[22:30:55.190] <TB0> INFO: start marker: 0
[22:30:55.190] <TB0> INFO: stop marker: 0
[22:30:55.190] <TB0> INFO: overflow: 0
[22:30:55.190] <TB0> INFO: invalid 5bit words: 0
[22:30:55.191] <TB0> INFO: invalid XOR eye diagram: 0
[22:30:55.191] <TB0> INFO: frame (failed synchr.): 0
[22:30:55.191] <TB0> INFO: idle data (no TBM trl): 0
[22:30:55.191] <TB0> INFO: no data (only TBM hdr): 0
[22:30:55.191] <TB0> INFO: TBM errors: 0
[22:30:55.191] <TB0> INFO: flawed TBM headers: 0
[22:30:55.191] <TB0> INFO: flawed TBM trailers: 0
[22:30:55.191] <TB0> INFO: event ID mismatches: 0
[22:30:55.191] <TB0> INFO: ROC errors: 0
[22:30:55.191] <TB0> INFO: missing ROC header(s): 0
[22:30:55.191] <TB0> INFO: misplaced readback start: 0
[22:30:55.191] <TB0> INFO: Pixel decoding errors: 0
[22:30:55.191] <TB0> INFO: pixel data incomplete: 0
[22:30:55.191] <TB0> INFO: pixel address: 0
[22:30:55.191] <TB0> INFO: pulse height fill bit: 0
[22:30:55.191] <TB0> INFO: buffer corruption: 0
[22:30:55.258] <TB0> INFO: ######################################################################
[22:30:55.258] <TB0> INFO: PixTestScurves::fullTest() ntrig = 50, dacs/step = -1, ntrig/step = -1
[22:30:55.258] <TB0> INFO: ######################################################################
[22:30:55.258] <TB0> INFO: ----------------------------------------------------------------------
[22:30:55.258] <TB0> INFO: PixTestScurves::scurves(Vcal), ntrig = 50, dacs/step = -1, ntrig/step = -1
[22:30:55.258] <TB0> INFO: ----------------------------------------------------------------------
[22:30:55.258] <TB0> INFO: ---> dac: Vcal name: scurveVcal ntrig: 50 dacrange: 25 .. 200 (-1/-1) hits flags = 528 (plus default)
[22:30:55.272] <TB0> INFO: dacScan split into 1 runs with ntrig = 50
[22:30:55.272] <TB0> INFO: run 1 of 1
[22:30:55.597] <TB0> INFO: Expecting 36608000 events.
[22:31:21.067] <TB0> INFO: 692150 events read in total (24878ms).
[22:31:44.223] <TB0> INFO: 1371100 events read in total (48034ms).
[22:32:07.152] <TB0> INFO: 2047200 events read in total (70963ms).
[22:32:29.765] <TB0> INFO: 2724150 events read in total (93576ms).
[22:32:52.445] <TB0> INFO: 3397600 events read in total (116256ms).
[22:33:15.493] <TB0> INFO: 4073550 events read in total (139304ms).
[22:33:38.795] <TB0> INFO: 4748650 events read in total (162606ms).
[22:34:01.446] <TB0> INFO: 5422000 events read in total (185257ms).
[22:34:24.809] <TB0> INFO: 6095850 events read in total (208620ms).
[22:34:47.554] <TB0> INFO: 6769600 events read in total (231365ms).
[22:35:10.289] <TB0> INFO: 7443600 events read in total (254100ms).
[22:35:33.390] <TB0> INFO: 8118850 events read in total (277201ms).
[22:35:56.835] <TB0> INFO: 8792850 events read in total (300646ms).
[22:36:19.900] <TB0> INFO: 9465750 events read in total (323711ms).
[22:36:42.880] <TB0> INFO: 10136800 events read in total (346691ms).
[22:37:06.104] <TB0> INFO: 10809350 events read in total (369915ms).
[22:37:29.453] <TB0> INFO: 11480050 events read in total (393264ms).
[22:37:52.477] <TB0> INFO: 12149900 events read in total (416288ms).
[22:38:15.665] <TB0> INFO: 12820900 events read in total (439476ms).
[22:38:38.843] <TB0> INFO: 13492100 events read in total (462654ms).
[22:39:02.014] <TB0> INFO: 14163850 events read in total (485825ms).
[22:39:25.069] <TB0> INFO: 14833400 events read in total (508880ms).
[22:39:48.185] <TB0> INFO: 15503950 events read in total (531996ms).
[22:40:11.082] <TB0> INFO: 16172200 events read in total (554893ms).
[22:40:33.925] <TB0> INFO: 16840450 events read in total (577736ms).
[22:40:56.738] <TB0> INFO: 17508250 events read in total (600549ms).
[22:41:19.764] <TB0> INFO: 18175100 events read in total (623575ms).
[22:41:42.722] <TB0> INFO: 18842650 events read in total (646533ms).
[22:42:05.695] <TB0> INFO: 19509000 events read in total (669506ms).
[22:42:28.384] <TB0> INFO: 20174950 events read in total (692195ms).
[22:42:50.818] <TB0> INFO: 20837900 events read in total (714629ms).
[22:43:13.438] <TB0> INFO: 21502250 events read in total (737249ms).
[22:43:36.133] <TB0> INFO: 22164650 events read in total (759944ms).
[22:43:58.924] <TB0> INFO: 22827400 events read in total (782735ms).
[22:44:21.740] <TB0> INFO: 23491700 events read in total (805551ms).
[22:44:44.340] <TB0> INFO: 24154150 events read in total (828151ms).
[22:45:07.118] <TB0> INFO: 24815950 events read in total (850929ms).
[22:45:29.959] <TB0> INFO: 25480350 events read in total (873770ms).
[22:45:52.696] <TB0> INFO: 26142700 events read in total (896507ms).
[22:46:15.398] <TB0> INFO: 26804800 events read in total (919209ms).
[22:46:38.117] <TB0> INFO: 27468650 events read in total (941928ms).
[22:47:01.181] <TB0> INFO: 28131150 events read in total (964992ms).
[22:47:23.609] <TB0> INFO: 28791500 events read in total (987420ms).
[22:47:46.415] <TB0> INFO: 29453400 events read in total (1010226ms).
[22:48:09.063] <TB0> INFO: 30114200 events read in total (1032874ms).
[22:48:31.911] <TB0> INFO: 30776850 events read in total (1055722ms).
[22:48:54.417] <TB0> INFO: 31439150 events read in total (1078228ms).
[22:49:17.345] <TB0> INFO: 32101400 events read in total (1101156ms).
[22:49:40.248] <TB0> INFO: 32763600 events read in total (1124059ms).
[22:50:02.003] <TB0> INFO: 33426850 events read in total (1146814ms).
[22:50:25.604] <TB0> INFO: 34090300 events read in total (1169415ms).
[22:50:48.450] <TB0> INFO: 34754300 events read in total (1192261ms).
[22:51:11.192] <TB0> INFO: 35419100 events read in total (1215003ms).
[22:51:34.096] <TB0> INFO: 36086550 events read in total (1237907ms).
[22:51:51.957] <TB0> INFO: 36608000 events read in total (1255768ms).
[22:51:52.053] <TB0> INFO: Test took 1256781ms.
[22:51:52.565] <TB0> INFO: dumping ASCII scurve output file: SCurveData
[22:51:54.224] <TB0> INFO: dumping ASCII scurve output file: SCurveData
[22:51:55.786] <TB0> INFO: dumping ASCII scurve output file: SCurveData
[22:51:57.397] <TB0> INFO: dumping ASCII scurve output file: SCurveData
[22:51:58.935] <TB0> INFO: dumping ASCII scurve output file: SCurveData
[22:52:00.443] <TB0> INFO: dumping ASCII scurve output file: SCurveData
[22:52:02.171] <TB0> INFO: dumping ASCII scurve output file: SCurveData
[22:52:03.768] <TB0> INFO: dumping ASCII scurve output file: SCurveData
[22:52:05.504] <TB0> INFO: dumping ASCII scurve output file: SCurveData
[22:52:06.890] <TB0> INFO: dumping ASCII scurve output file: SCurveData
[22:52:08.305] <TB0> INFO: dumping ASCII scurve output file: SCurveData
[22:52:10.150] <TB0> INFO: dumping ASCII scurve output file: SCurveData
[22:52:11.739] <TB0> INFO: dumping ASCII scurve output file: SCurveData
[22:52:13.638] <TB0> INFO: dumping ASCII scurve output file: SCurveData
[22:52:15.583] <TB0> INFO: dumping ASCII scurve output file: SCurveData
[22:52:17.697] <TB0> INFO: dumping ASCII scurve output file: SCurveData
[22:52:19.547] <TB0> INFO: PixTestScurves::scurves() done
[22:52:19.547] <TB0> INFO: Vcal mean: 111.75 117.10 126.13 132.54 122.92 116.94 106.71 123.01 122.46 121.88 115.28 122.56 122.88 128.82 116.68 124.68
[22:52:19.547] <TB0> INFO: Vcal RMS: 6.87 5.72 6.74 8.08 6.50 5.11 4.68 6.61 6.33 5.52 5.69 5.55 5.89 5.72 5.75 6.52
[22:52:19.547] <TB0> INFO: PixTestScurves::fullTest() done, duration: 1284 seconds
[22:52:19.547] <TB0> INFO: Decoding statistics:
[22:52:19.547] <TB0> INFO: General information:
[22:52:19.547] <TB0> INFO: 16bit words read: 0
[22:52:19.547] <TB0> INFO: valid events total: 0
[22:52:19.547] <TB0> INFO: empty events: 0
[22:52:19.547] <TB0> INFO: valid events with pixels: 0
[22:52:19.547] <TB0> INFO: valid pixel hits: 0
[22:52:19.547] <TB0> INFO: Event errors: 0
[22:52:19.547] <TB0> INFO: start marker: 0
[22:52:19.547] <TB0> INFO: stop marker: 0
[22:52:19.547] <TB0> INFO: overflow: 0
[22:52:19.547] <TB0> INFO: invalid 5bit words: 0
[22:52:19.547] <TB0> INFO: invalid XOR eye diagram: 0
[22:52:19.547] <TB0> INFO: frame (failed synchr.): 0
[22:52:19.547] <TB0> INFO: idle data (no TBM trl): 0
[22:52:19.547] <TB0> INFO: no data (only TBM hdr): 0
[22:52:19.547] <TB0> INFO: TBM errors: 0
[22:52:19.547] <TB0> INFO: flawed TBM headers: 0
[22:52:19.547] <TB0> INFO: flawed TBM trailers: 0
[22:52:19.547] <TB0> INFO: event ID mismatches: 0
[22:52:19.547] <TB0> INFO: ROC errors: 0
[22:52:19.547] <TB0> INFO: missing ROC header(s): 0
[22:52:19.547] <TB0> INFO: misplaced readback start: 0
[22:52:19.547] <TB0> INFO: Pixel decoding errors: 0
[22:52:19.547] <TB0> INFO: pixel data incomplete: 0
[22:52:19.547] <TB0> INFO: pixel address: 0
[22:52:19.547] <TB0> INFO: pulse height fill bit: 0
[22:52:19.547] <TB0> INFO: buffer corruption: 0
[22:52:19.615] <TB0> INFO: ######################################################################
[22:52:19.615] <TB0> INFO: PixTestTrim::doTest()
[22:52:19.615] <TB0> INFO: ######################################################################
[22:52:19.616] <TB0> INFO: ----------------------------------------------------------------------
[22:52:19.616] <TB0> INFO: PixTestTrim::trimTest() ntrig = 8, vcal = 35
[22:52:19.616] <TB0> INFO: ----------------------------------------------------------------------
[22:52:19.659] <TB0> INFO: ---> VthrComp thr map (minimal VthrComp)
[22:52:19.659] <TB0> INFO: ---> dac: vthrcomp name: TrimThr0 ntrig: 8 dacrange: 10 .. 160 (-1/-1) hits flags = 528 (plus default)
[22:52:19.672] <TB0> INFO: dacScan split into 1 runs with ntrig = 8
[22:52:19.672] <TB0> INFO: run 1 of 1
[22:52:19.942] <TB0> INFO: Expecting 5025280 events.
[22:52:51.151] <TB0> INFO: 833312 events read in total (30608ms).
[22:53:21.159] <TB0> INFO: 1663056 events read in total (60617ms).
[22:53:50.982] <TB0> INFO: 2489568 events read in total (90439ms).
[22:54:21.125] <TB0> INFO: 3309984 events read in total (120582ms).
[22:54:51.430] <TB0> INFO: 4125224 events read in total (150887ms).
[22:55:21.275] <TB0> INFO: 4940480 events read in total (180732ms).
[22:55:24.702] <TB0> INFO: 5025280 events read in total (184159ms).
[22:55:24.759] <TB0> INFO: Test took 185087ms.
[22:55:40.417] <TB0> INFO: ROC 0 VthrComp = 127
[22:55:40.418] <TB0> INFO: ROC 1 VthrComp = 129
[22:55:40.418] <TB0> INFO: ROC 2 VthrComp = 130
[22:55:40.418] <TB0> INFO: ROC 3 VthrComp = 129
[22:55:40.418] <TB0> INFO: ROC 4 VthrComp = 128
[22:55:40.418] <TB0> INFO: ROC 5 VthrComp = 129
[22:55:40.418] <TB0> INFO: ROC 6 VthrComp = 122
[22:55:40.418] <TB0> INFO: ROC 7 VthrComp = 134
[22:55:40.418] <TB0> INFO: ROC 8 VthrComp = 127
[22:55:40.419] <TB0> INFO: ROC 9 VthrComp = 130
[22:55:40.419] <TB0> INFO: ROC 10 VthrComp = 124
[22:55:40.419] <TB0> INFO: ROC 11 VthrComp = 127
[22:55:40.419] <TB0> INFO: ROC 12 VthrComp = 127
[22:55:40.419] <TB0> INFO: ROC 13 VthrComp = 131
[22:55:40.419] <TB0> INFO: ROC 14 VthrComp = 131
[22:55:40.420] <TB0> INFO: ROC 15 VthrComp = 131
[22:55:40.658] <TB0> INFO: Expecting 41600 events.
[22:55:44.199] <TB0> INFO: 41600 events read in total (2950ms).
[22:55:44.200] <TB0> INFO: Test took 3779ms.
[22:55:44.209] <TB0> INFO: ---> Vcal thr map (pixel with maximum Vcal thr)
[22:55:44.209] <TB0> INFO: ---> dac: vcal name: TrimThr1 ntrig: 8 dacrange: 10 .. 160 (-1/-1) hits flags = 528 (plus default)
[22:55:44.221] <TB0> INFO: dacScan split into 1 runs with ntrig = 8
[22:55:44.221] <TB0> INFO: run 1 of 1
[22:55:44.499] <TB0> INFO: Expecting 5025280 events.
[22:56:10.706] <TB0> INFO: 589584 events read in total (25615ms).
[22:56:36.225] <TB0> INFO: 1178088 events read in total (51134ms).
[22:57:01.960] <TB0> INFO: 1765784 events read in total (76869ms).
[22:57:28.064] <TB0> INFO: 2353080 events read in total (102973ms).
[22:57:54.457] <TB0> INFO: 2938480 events read in total (129366ms).
[22:58:20.456] <TB0> INFO: 3522096 events read in total (155365ms).
[22:58:45.886] <TB0> INFO: 4105000 events read in total (180795ms).
[22:59:11.778] <TB0> INFO: 4687608 events read in total (206687ms).
[22:59:26.733] <TB0> INFO: 5025280 events read in total (221642ms).
[22:59:26.820] <TB0> INFO: Test took 222599ms.
[22:59:50.694] <TB0> INFO: roc 0 with ID = 0 has maximal Vcal 55.9983 for pixel 18/8 mean/min/max = 43.7005/31.3853/56.0157
[22:59:50.695] <TB0> INFO: roc 1 with ID = 1 has maximal Vcal 57.0339 for pixel 1/11 mean/min/max = 45.0365/32.9614/57.1116
[22:59:50.695] <TB0> INFO: roc 2 with ID = 2 has maximal Vcal 63.174 for pixel 17/4 mean/min/max = 48.0908/32.9456/63.236
[22:59:50.696] <TB0> INFO: roc 3 with ID = 3 has maximal Vcal 69.1879 for pixel 15/75 mean/min/max = 50.6242/31.9905/69.2579
[22:59:50.697] <TB0> INFO: roc 4 with ID = 4 has maximal Vcal 61.3322 for pixel 0/30 mean/min/max = 46.6002/31.7462/61.4541
[22:59:50.697] <TB0> INFO: roc 5 with ID = 5 has maximal Vcal 55.6792 for pixel 5/6 mean/min/max = 43.6664/31.6202/55.7127
[22:59:50.698] <TB0> INFO: roc 6 with ID = 6 has maximal Vcal 53.8766 for pixel 46/79 mean/min/max = 43.7716/33.1705/54.3727
[22:59:50.698] <TB0> INFO: roc 7 with ID = 7 has maximal Vcal 57.5593 for pixel 0/7 mean/min/max = 45.8773/34.1661/57.5886
[22:59:50.699] <TB0> INFO: roc 8 with ID = 8 has maximal Vcal 60.037 for pixel 4/4 mean/min/max = 46.0951/31.8719/60.3184
[22:59:50.699] <TB0> INFO: roc 9 with ID = 9 has maximal Vcal 56.617 for pixel 0/0 mean/min/max = 44.073/31.1247/57.0214
[22:59:50.700] <TB0> INFO: roc 10 with ID = 10 has maximal Vcal 57.813 for pixel 5/77 mean/min/max = 45.0605/32.1375/57.9835
[22:59:50.700] <TB0> INFO: roc 11 with ID = 11 has maximal Vcal 58.521 for pixel 11/2 mean/min/max = 45.4423/32.3562/58.5284
[22:59:50.701] <TB0> INFO: roc 12 with ID = 12 has maximal Vcal 58.2175 for pixel 3/45 mean/min/max = 44.6078/30.7557/58.46
[22:59:50.701] <TB0> INFO: roc 13 with ID = 13 has maximal Vcal 57.687 for pixel 21/75 mean/min/max = 45.314/32.8961/57.7319
[22:59:50.702] <TB0> INFO: roc 14 with ID = 14 has maximal Vcal 57.8453 for pixel 51/6 mean/min/max = 45.2469/32.6304/57.8635
[22:59:50.702] <TB0> INFO: roc 15 with ID = 15 has maximal Vcal 58.0246 for pixel 0/41 mean/min/max = 45.5024/32.9506/58.0541
[22:59:50.704] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[22:59:50.793] <TB0> INFO: Expecting 411648 events.
[23:00:00.166] <TB0> INFO: 411648 events read in total (8782ms).
[23:00:00.173] <TB0> INFO: Expecting 411648 events.
[23:00:09.392] <TB0> INFO: 411648 events read in total (8815ms).
[23:00:09.403] <TB0> INFO: Expecting 411648 events.
[23:00:18.772] <TB0> INFO: 411648 events read in total (8966ms).
[23:00:18.790] <TB0> INFO: Expecting 411648 events.
[23:00:27.840] <TB0> INFO: 411648 events read in total (8647ms).
[23:00:27.857] <TB0> INFO: Expecting 411648 events.
[23:00:37.146] <TB0> INFO: 411648 events read in total (8886ms).
[23:00:37.168] <TB0> INFO: Expecting 411648 events.
[23:00:46.245] <TB0> INFO: 411648 events read in total (8674ms).
[23:00:46.268] <TB0> INFO: Expecting 411648 events.
[23:00:55.377] <TB0> INFO: 411648 events read in total (8706ms).
[23:00:55.404] <TB0> INFO: Expecting 411648 events.
[23:01:04.761] <TB0> INFO: 411648 events read in total (8954ms).
[23:01:04.792] <TB0> INFO: Expecting 411648 events.
[23:01:14.143] <TB0> INFO: 411648 events read in total (8948ms).
[23:01:14.174] <TB0> INFO: Expecting 411648 events.
[23:01:23.453] <TB0> INFO: 411648 events read in total (8876ms).
[23:01:23.486] <TB0> INFO: Expecting 411648 events.
[23:01:32.808] <TB0> INFO: 411648 events read in total (8919ms).
[23:01:32.845] <TB0> INFO: Expecting 411648 events.
[23:01:42.178] <TB0> INFO: 411648 events read in total (8930ms).
[23:01:42.217] <TB0> INFO: Expecting 411648 events.
[23:01:51.596] <TB0> INFO: 411648 events read in total (8976ms).
[23:01:51.655] <TB0> INFO: Expecting 411648 events.
[23:02:00.991] <TB0> INFO: 411648 events read in total (8933ms).
[23:02:01.036] <TB0> INFO: Expecting 411648 events.
[23:02:10.405] <TB0> INFO: 411648 events read in total (8966ms).
[23:02:10.453] <TB0> INFO: Expecting 411648 events.
[23:02:19.866] <TB0> INFO: 411648 events read in total (9010ms).
[23:02:19.918] <TB0> INFO: Test took 149214ms.
[23:02:20.703] <TB0> INFO: ---> dac: vcal name: TrimThr2 ntrig: 8 dacrange: 0 .. 150 (-1/-1) hits flags = 528 (plus default)
[23:02:20.717] <TB0> INFO: dacScan split into 1 runs with ntrig = 8
[23:02:20.717] <TB0> INFO: run 1 of 1
[23:02:20.954] <TB0> INFO: Expecting 5025280 events.
[23:02:47.466] <TB0> INFO: 584504 events read in total (25920ms).
[23:03:13.204] <TB0> INFO: 1167832 events read in total (51658ms).
[23:03:38.767] <TB0> INFO: 1751024 events read in total (77222ms).
[23:04:04.943] <TB0> INFO: 2333680 events read in total (103397ms).
[23:04:31.062] <TB0> INFO: 2914592 events read in total (129516ms).
[23:04:57.194] <TB0> INFO: 3495296 events read in total (155648ms).
[23:05:22.885] <TB0> INFO: 4074680 events read in total (181339ms).
[23:05:48.575] <TB0> INFO: 4654960 events read in total (207029ms).
[23:06:05.213] <TB0> INFO: 5025280 events read in total (223667ms).
[23:06:05.349] <TB0> INFO: Test took 224633ms.
[23:06:28.941] <TB0> INFO: ---> TrimStepCorr4 extremal thresholds: 10.814924 .. 138.501021
[23:06:29.181] <TB0> INFO: Expecting 208000 events.
[23:06:39.468] <TB0> INFO: 208000 events read in total (9696ms).
[23:06:39.469] <TB0> INFO: Test took 10527ms.
[23:06:39.549] <TB0> INFO: ---> dac: vcal name: trimStepCorr4 ntrig: 8 dacrange: 0 .. 148 (-1/-1) hits flags = 528 (plus default)
[23:06:39.564] <TB0> INFO: dacScan split into 1 runs with ntrig = 8
[23:06:39.564] <TB0> INFO: run 1 of 1
[23:06:39.889] <TB0> INFO: Expecting 4958720 events.
[23:07:06.862] <TB0> INFO: 587648 events read in total (26382ms).
[23:07:32.583] <TB0> INFO: 1175736 events read in total (52103ms).
[23:07:58.141] <TB0> INFO: 1764040 events read in total (77661ms).
[23:08:24.152] <TB0> INFO: 2352440 events read in total (103673ms).
[23:08:49.915] <TB0> INFO: 2940464 events read in total (129436ms).
[23:09:15.583] <TB0> INFO: 3527696 events read in total (155103ms).
[23:09:41.545] <TB0> INFO: 4114336 events read in total (181065ms).
[23:10:07.374] <TB0> INFO: 4700864 events read in total (206894ms).
[23:10:18.652] <TB0> INFO: 4958720 events read in total (218172ms).
[23:10:18.739] <TB0> INFO: Test took 219174ms.
[23:10:41.080] <TB0> INFO: ---> TrimStepCorr2 extremal thresholds: 26.564712 .. 45.845399
[23:10:41.320] <TB0> INFO: Expecting 208000 events.
[23:10:51.191] <TB0> INFO: 208000 events read in total (9280ms).
[23:10:51.193] <TB0> INFO: Test took 10111ms.
[23:10:51.246] <TB0> INFO: ---> dac: vcal name: trimStepCorr2 ntrig: 8 dacrange: 16 .. 55 (-1/-1) hits flags = 528 (plus default)
[23:10:51.263] <TB0> INFO: dacScan split into 1 runs with ntrig = 8
[23:10:51.263] <TB0> INFO: run 1 of 1
[23:10:51.544] <TB0> INFO: Expecting 1331200 events.
[23:11:19.814] <TB0> INFO: 663488 events read in total (27678ms).
[23:11:47.857] <TB0> INFO: 1324752 events read in total (55722ms).
[23:11:48.581] <TB0> INFO: 1331200 events read in total (56446ms).
[23:11:48.612] <TB0> INFO: Test took 57349ms.
[23:12:03.790] <TB0> INFO: ---> TrimStepCorr1a extremal thresholds: 25.486202 .. 47.257956
[23:12:04.069] <TB0> INFO: Expecting 208000 events.
[23:12:13.859] <TB0> INFO: 208000 events read in total (9198ms).
[23:12:13.860] <TB0> INFO: Test took 10068ms.
[23:12:13.906] <TB0> INFO: ---> dac: vcal name: trimStepCorr1a ntrig: 8 dacrange: 15 .. 57 (-1/-1) hits flags = 528 (plus default)
[23:12:13.920] <TB0> INFO: dacScan split into 1 runs with ntrig = 8
[23:12:13.920] <TB0> INFO: run 1 of 1
[23:12:14.198] <TB0> INFO: Expecting 1431040 events.
[23:12:42.726] <TB0> INFO: 659776 events read in total (27937ms).
[23:13:10.068] <TB0> INFO: 1318296 events read in total (55279ms).
[23:13:15.168] <TB0> INFO: 1431040 events read in total (60379ms).
[23:13:15.207] <TB0> INFO: Test took 61288ms.
[23:13:27.410] <TB0> INFO: ---> TrimStepCorr1b extremal thresholds: 24.319893 .. 45.247293
[23:13:27.649] <TB0> INFO: Expecting 208000 events.
[23:13:37.444] <TB0> INFO: 208000 events read in total (9203ms).
[23:13:37.445] <TB0> INFO: Test took 10033ms.
[23:13:37.494] <TB0> INFO: ---> dac: vcal name: trimStepCorr1b ntrig: 8 dacrange: 14 .. 55 (-1/-1) hits flags = 528 (plus default)
[23:13:37.507] <TB0> INFO: dacScan split into 1 runs with ntrig = 8
[23:13:37.507] <TB0> INFO: run 1 of 1
[23:13:37.786] <TB0> INFO: Expecting 1397760 events.
[23:14:05.613] <TB0> INFO: 672048 events read in total (27236ms).
[23:14:33.765] <TB0> INFO: 1343264 events read in total (55389ms).
[23:14:36.385] <TB0> INFO: 1397760 events read in total (58008ms).
[23:14:36.414] <TB0> INFO: Test took 58908ms.
[23:14:49.050] <TB0> INFO: ---> TrimThrFinal extremal thresholds: 15 .. 55
[23:14:49.050] <TB0> INFO: ---> dac: vcal name: TrimThrFinal ntrig: 8 dacrange: 15 .. 55 (-1/-1) hits flags = 528 (plus default)
[23:14:49.066] <TB0> INFO: dacScan split into 1 runs with ntrig = 8
[23:14:49.066] <TB0> INFO: run 1 of 1
[23:14:49.348] <TB0> INFO: Expecting 1364480 events.
[23:15:17.857] <TB0> INFO: 668344 events read in total (27918ms).
[23:15:45.887] <TB0> INFO: 1335624 events read in total (55948ms).
[23:15:47.541] <TB0> INFO: 1364480 events read in total (57602ms).
[23:15:47.567] <TB0> INFO: Test took 58501ms.
[23:15:59.516] <TB0> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1024_FullQualification_2016-10-19_19h52m_1476899523//003_Fulltest_p17//dacParameters35_C0.dat
[23:15:59.516] <TB0> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1024_FullQualification_2016-10-19_19h52m_1476899523//003_Fulltest_p17//dacParameters35_C1.dat
[23:15:59.516] <TB0> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1024_FullQualification_2016-10-19_19h52m_1476899523//003_Fulltest_p17//dacParameters35_C2.dat
[23:15:59.517] <TB0> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1024_FullQualification_2016-10-19_19h52m_1476899523//003_Fulltest_p17//dacParameters35_C3.dat
[23:15:59.517] <TB0> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1024_FullQualification_2016-10-19_19h52m_1476899523//003_Fulltest_p17//dacParameters35_C4.dat
[23:15:59.517] <TB0> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1024_FullQualification_2016-10-19_19h52m_1476899523//003_Fulltest_p17//dacParameters35_C5.dat
[23:15:59.517] <TB0> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1024_FullQualification_2016-10-19_19h52m_1476899523//003_Fulltest_p17//dacParameters35_C6.dat
[23:15:59.517] <TB0> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1024_FullQualification_2016-10-19_19h52m_1476899523//003_Fulltest_p17//dacParameters35_C7.dat
[23:15:59.517] <TB0> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1024_FullQualification_2016-10-19_19h52m_1476899523//003_Fulltest_p17//dacParameters35_C8.dat
[23:15:59.517] <TB0> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1024_FullQualification_2016-10-19_19h52m_1476899523//003_Fulltest_p17//dacParameters35_C9.dat
[23:15:59.517] <TB0> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1024_FullQualification_2016-10-19_19h52m_1476899523//003_Fulltest_p17//dacParameters35_C10.dat
[23:15:59.517] <TB0> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1024_FullQualification_2016-10-19_19h52m_1476899523//003_Fulltest_p17//dacParameters35_C11.dat
[23:15:59.518] <TB0> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1024_FullQualification_2016-10-19_19h52m_1476899523//003_Fulltest_p17//dacParameters35_C12.dat
[23:15:59.518] <TB0> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1024_FullQualification_2016-10-19_19h52m_1476899523//003_Fulltest_p17//dacParameters35_C13.dat
[23:15:59.518] <TB0> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1024_FullQualification_2016-10-19_19h52m_1476899523//003_Fulltest_p17//dacParameters35_C14.dat
[23:15:59.518] <TB0> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1024_FullQualification_2016-10-19_19h52m_1476899523//003_Fulltest_p17//dacParameters35_C15.dat
[23:15:59.518] <TB0> INFO: write trim parameters into /home/l_tester/david/FullQualification/data/M1024_FullQualification_2016-10-19_19h52m_1476899523//003_Fulltest_p17//trimParameters35_C0.dat
[23:15:59.526] <TB0> INFO: write trim parameters into /home/l_tester/david/FullQualification/data/M1024_FullQualification_2016-10-19_19h52m_1476899523//003_Fulltest_p17//trimParameters35_C1.dat
[23:15:59.532] <TB0> INFO: write trim parameters into /home/l_tester/david/FullQualification/data/M1024_FullQualification_2016-10-19_19h52m_1476899523//003_Fulltest_p17//trimParameters35_C2.dat
[23:15:59.537] <TB0> INFO: write trim parameters into /home/l_tester/david/FullQualification/data/M1024_FullQualification_2016-10-19_19h52m_1476899523//003_Fulltest_p17//trimParameters35_C3.dat
[23:15:59.542] <TB0> INFO: write trim parameters into /home/l_tester/david/FullQualification/data/M1024_FullQualification_2016-10-19_19h52m_1476899523//003_Fulltest_p17//trimParameters35_C4.dat
[23:15:59.546] <TB0> INFO: write trim parameters into /home/l_tester/david/FullQualification/data/M1024_FullQualification_2016-10-19_19h52m_1476899523//003_Fulltest_p17//trimParameters35_C5.dat
[23:15:59.551] <TB0> INFO: write trim parameters into /home/l_tester/david/FullQualification/data/M1024_FullQualification_2016-10-19_19h52m_1476899523//003_Fulltest_p17//trimParameters35_C6.dat
[23:15:59.556] <TB0> INFO: write trim parameters into /home/l_tester/david/FullQualification/data/M1024_FullQualification_2016-10-19_19h52m_1476899523//003_Fulltest_p17//trimParameters35_C7.dat
[23:15:59.560] <TB0> INFO: write trim parameters into /home/l_tester/david/FullQualification/data/M1024_FullQualification_2016-10-19_19h52m_1476899523//003_Fulltest_p17//trimParameters35_C8.dat
[23:15:59.565] <TB0> INFO: write trim parameters into /home/l_tester/david/FullQualification/data/M1024_FullQualification_2016-10-19_19h52m_1476899523//003_Fulltest_p17//trimParameters35_C9.dat
[23:15:59.570] <TB0> INFO: write trim parameters into /home/l_tester/david/FullQualification/data/M1024_FullQualification_2016-10-19_19h52m_1476899523//003_Fulltest_p17//trimParameters35_C10.dat
[23:15:59.574] <TB0> INFO: write trim parameters into /home/l_tester/david/FullQualification/data/M1024_FullQualification_2016-10-19_19h52m_1476899523//003_Fulltest_p17//trimParameters35_C11.dat
[23:15:59.579] <TB0> INFO: write trim parameters into /home/l_tester/david/FullQualification/data/M1024_FullQualification_2016-10-19_19h52m_1476899523//003_Fulltest_p17//trimParameters35_C12.dat
[23:15:59.583] <TB0> INFO: write trim parameters into /home/l_tester/david/FullQualification/data/M1024_FullQualification_2016-10-19_19h52m_1476899523//003_Fulltest_p17//trimParameters35_C13.dat
[23:15:59.588] <TB0> INFO: write trim parameters into /home/l_tester/david/FullQualification/data/M1024_FullQualification_2016-10-19_19h52m_1476899523//003_Fulltest_p17//trimParameters35_C14.dat
[23:15:59.593] <TB0> INFO: write trim parameters into /home/l_tester/david/FullQualification/data/M1024_FullQualification_2016-10-19_19h52m_1476899523//003_Fulltest_p17//trimParameters35_C15.dat
[23:15:59.597] <TB0> INFO: PixTestTrim::trimTest() done
[23:15:59.597] <TB0> INFO: vtrim: 130 127 163 152 128 118 115 121 130 110 122 119 115 117 140 119
[23:15:59.597] <TB0> INFO: vthrcomp: 127 129 130 129 128 129 122 134 127 130 124 127 127 131 131 131
[23:15:59.597] <TB0> INFO: vcal mean: 34.93 35.00 35.02 35.21 34.98 34.94 34.97 34.96 35.04 34.95 34.99 34.98 34.95 34.97 34.99 35.01
[23:15:59.597] <TB0> INFO: vcal RMS: 1.00 1.08 1.05 1.40 1.00 1.05 0.87 1.37 1.05 1.00 0.98 1.07 1.19 0.98 0.92 0.94
[23:15:59.597] <TB0> INFO: bits mean: 10.48 9.39 9.52 9.00 8.90 10.18 9.63 8.66 9.34 9.78 9.54 9.64 10.04 9.52 9.35 8.69
[23:15:59.597] <TB0> INFO: bits RMS: 2.41 2.71 2.41 2.66 2.94 2.61 2.53 2.78 2.82 2.86 2.71 2.65 2.74 2.60 2.71 2.93
[23:15:59.605] <TB0> INFO: ----------------------------------------------------------------------
[23:15:59.605] <TB0> INFO: PixTestTrim::trimBitTest() ntrig = 5, vtrims = 254 126 63 32
[23:15:59.605] <TB0> INFO: ----------------------------------------------------------------------
[23:15:59.607] <TB0> INFO: ---> dac: Vcal name: TrimBitsThr0 ntrig: 5 dacrange: 0 .. 199 (-1/-1) hits flags = 528 (plus default)
[23:15:59.621] <TB0> INFO: dacScan split into 1 runs with ntrig = 5
[23:15:59.621] <TB0> INFO: run 1 of 1
[23:15:59.865] <TB0> INFO: Expecting 4160000 events.
[23:16:32.430] <TB0> INFO: 758425 events read in total (31974ms).
[23:17:03.901] <TB0> INFO: 1509835 events read in total (63445ms).
[23:17:35.708] <TB0> INFO: 2255620 events read in total (95252ms).
[23:18:07.283] <TB0> INFO: 2994055 events read in total (126827ms).
[23:18:39.253] <TB0> INFO: 3730365 events read in total (158797ms).
[23:18:58.037] <TB0> INFO: 4160000 events read in total (177581ms).
[23:18:58.102] <TB0> INFO: Test took 178480ms.
[23:19:21.442] <TB0> INFO: ---> dac: Vcal name: TrimThr_trim14 ntrig: 5 dacrange: 0 .. 213 (-1/-1) hits flags = 528 (plus default)
[23:19:21.455] <TB0> INFO: dacScan split into 1 runs with ntrig = 5
[23:19:21.455] <TB0> INFO: run 1 of 1
[23:19:21.691] <TB0> INFO: Expecting 4451200 events.
[23:19:54.682] <TB0> INFO: 717165 events read in total (32399ms).
[23:20:25.926] <TB0> INFO: 1429145 events read in total (63643ms).
[23:20:56.886] <TB0> INFO: 2136830 events read in total (94603ms).
[23:21:27.827] <TB0> INFO: 2839895 events read in total (125544ms).
[23:21:58.675] <TB0> INFO: 3540735 events read in total (156392ms).
[23:22:29.604] <TB0> INFO: 4241440 events read in total (187321ms).
[23:22:38.990] <TB0> INFO: 4451200 events read in total (196707ms).
[23:22:39.146] <TB0> INFO: Test took 197691ms.
[23:23:07.390] <TB0> INFO: ---> dac: Vcal name: TrimThr_trim13 ntrig: 5 dacrange: 0 .. 202 (-1/-1) hits flags = 528 (plus default)
[23:23:07.405] <TB0> INFO: dacScan split into 1 runs with ntrig = 5
[23:23:07.405] <TB0> INFO: run 1 of 1
[23:23:07.644] <TB0> INFO: Expecting 4222400 events.
[23:23:39.619] <TB0> INFO: 730855 events read in total (31384ms).
[23:24:10.703] <TB0> INFO: 1456465 events read in total (62468ms).
[23:24:41.774] <TB0> INFO: 2176690 events read in total (93539ms).
[23:25:13.453] <TB0> INFO: 2891350 events read in total (125218ms).
[23:25:45.099] <TB0> INFO: 3604370 events read in total (156864ms).
[23:26:12.417] <TB0> INFO: 4222400 events read in total (184182ms).
[23:26:12.484] <TB0> INFO: Test took 185079ms.
[23:26:39.378] <TB0> INFO: ---> dac: Vcal name: TrimThr_trim11 ntrig: 5 dacrange: 0 .. 202 (-1/-1) hits flags = 528 (plus default)
[23:26:39.392] <TB0> INFO: dacScan split into 1 runs with ntrig = 5
[23:26:39.392] <TB0> INFO: run 1 of 1
[23:26:39.668] <TB0> INFO: Expecting 4222400 events.
[23:27:11.818] <TB0> INFO: 731000 events read in total (31559ms).
[23:27:43.167] <TB0> INFO: 1456470 events read in total (62908ms).
[23:28:14.503] <TB0> INFO: 2176795 events read in total (94244ms).
[23:28:45.724] <TB0> INFO: 2891390 events read in total (125465ms).
[23:29:16.806] <TB0> INFO: 3604510 events read in total (156547ms).
[23:29:43.782] <TB0> INFO: 4222400 events read in total (183523ms).
[23:29:43.909] <TB0> INFO: Test took 184516ms.
[23:30:10.506] <TB0> INFO: ---> dac: Vcal name: TrimThr_trim7 ntrig: 5 dacrange: 0 .. 203 (-1/-1) hits flags = 528 (plus default)
[23:30:10.519] <TB0> INFO: dacScan split into 1 runs with ntrig = 5
[23:30:10.519] <TB0> INFO: run 1 of 1
[23:30:10.805] <TB0> INFO: Expecting 4243200 events.
[23:30:42.867] <TB0> INFO: 730155 events read in total (31470ms).
[23:31:14.525] <TB0> INFO: 1454450 events read in total (63128ms).
[23:31:46.063] <TB0> INFO: 2173545 events read in total (94666ms).
[23:32:16.604] <TB0> INFO: 2887450 events read in total (125207ms).
[23:32:48.014] <TB0> INFO: 3599830 events read in total (156617ms).
[23:33:16.157] <TB0> INFO: 4243200 events read in total (184760ms).
[23:33:16.258] <TB0> INFO: Test took 185739ms.
[23:33:45.077] <TB0> INFO: PixTestTrim::trimBitTest() done
[23:33:45.078] <TB0> INFO: PixTestTrim::doTest() done, duration: 2485 seconds
[23:33:45.078] <TB0> INFO: Decoding statistics:
[23:33:45.078] <TB0> INFO: General information:
[23:33:45.078] <TB0> INFO: 16bit words read: 0
[23:33:45.078] <TB0> INFO: valid events total: 0
[23:33:45.078] <TB0> INFO: empty events: 0
[23:33:45.078] <TB0> INFO: valid events with pixels: 0
[23:33:45.078] <TB0> INFO: valid pixel hits: 0
[23:33:45.078] <TB0> INFO: Event errors: 0
[23:33:45.078] <TB0> INFO: start marker: 0
[23:33:45.078] <TB0> INFO: stop marker: 0
[23:33:45.078] <TB0> INFO: overflow: 0
[23:33:45.078] <TB0> INFO: invalid 5bit words: 0
[23:33:45.078] <TB0> INFO: invalid XOR eye diagram: 0
[23:33:45.078] <TB0> INFO: frame (failed synchr.): 0
[23:33:45.078] <TB0> INFO: idle data (no TBM trl): 0
[23:33:45.078] <TB0> INFO: no data (only TBM hdr): 0
[23:33:45.078] <TB0> INFO: TBM errors: 0
[23:33:45.078] <TB0> INFO: flawed TBM headers: 0
[23:33:45.078] <TB0> INFO: flawed TBM trailers: 0
[23:33:45.078] <TB0> INFO: event ID mismatches: 0
[23:33:45.078] <TB0> INFO: ROC errors: 0
[23:33:45.078] <TB0> INFO: missing ROC header(s): 0
[23:33:45.078] <TB0> INFO: misplaced readback start: 0
[23:33:45.078] <TB0> INFO: Pixel decoding errors: 0
[23:33:45.078] <TB0> INFO: pixel data incomplete: 0
[23:33:45.078] <TB0> INFO: pixel address: 0
[23:33:45.078] <TB0> INFO: pulse height fill bit: 0
[23:33:45.078] <TB0> INFO: buffer corruption: 0
[23:33:45.680] <TB0> INFO: ######################################################################
[23:33:45.680] <TB0> INFO: PixTestPhOptimization::doTest() Ntrig = 10
[23:33:45.680] <TB0> INFO: ######################################################################
[23:33:45.917] <TB0> INFO: Expecting 41600 events.
[23:33:49.363] <TB0> INFO: 41600 events read in total (2855ms).
[23:33:49.363] <TB0> INFO: Test took 3682ms.
[23:33:49.802] <TB0> INFO: Expecting 41600 events.
[23:33:53.361] <TB0> INFO: 41600 events read in total (2967ms).
[23:33:53.362] <TB0> INFO: Test took 3796ms.
[23:33:53.651] <TB0> INFO: Expecting 41600 events.
[23:33:57.241] <TB0> INFO: 41600 events read in total (2999ms).
[23:33:57.242] <TB0> INFO: Test took 3856ms.
[23:33:57.531] <TB0> INFO: Expecting 41600 events.
[23:34:01.107] <TB0> INFO: 41600 events read in total (2984ms).
[23:34:01.108] <TB0> INFO: Test took 3842ms.
[23:34:01.397] <TB0> INFO: Expecting 41600 events.
[23:34:04.969] <TB0> INFO: 41600 events read in total (2980ms).
[23:34:04.970] <TB0> INFO: Test took 3838ms.
[23:34:05.260] <TB0> INFO: Expecting 41600 events.
[23:34:08.809] <TB0> INFO: 41600 events read in total (2958ms).
[23:34:08.810] <TB0> INFO: Test took 3815ms.
[23:34:09.099] <TB0> INFO: Expecting 41600 events.
[23:34:12.716] <TB0> INFO: 41600 events read in total (3025ms).
[23:34:12.717] <TB0> INFO: Test took 3883ms.
[23:34:12.006] <TB0> INFO: Expecting 41600 events.
[23:34:16.927] <TB0> INFO: 41600 events read in total (3330ms).
[23:34:16.928] <TB0> INFO: Test took 4187ms.
[23:34:17.217] <TB0> INFO: Expecting 41600 events.
[23:34:20.703] <TB0> INFO: 41600 events read in total (2895ms).
[23:34:20.704] <TB0> INFO: Test took 3752ms.
[23:34:20.992] <TB0> INFO: Expecting 41600 events.
[23:34:24.562] <TB0> INFO: 41600 events read in total (2978ms).
[23:34:24.563] <TB0> INFO: Test took 3835ms.
[23:34:24.852] <TB0> INFO: Expecting 41600 events.
[23:34:28.341] <TB0> INFO: 41600 events read in total (2898ms).
[23:34:28.341] <TB0> INFO: Test took 3754ms.
[23:34:28.630] <TB0> INFO: Expecting 41600 events.
[23:34:32.156] <TB0> INFO: 41600 events read in total (2934ms).
[23:34:32.157] <TB0> INFO: Test took 3792ms.
[23:34:32.449] <TB0> INFO: Expecting 41600 events.
[23:34:35.950] <TB0> INFO: 41600 events read in total (2910ms).
[23:34:35.951] <TB0> INFO: Test took 3767ms.
[23:34:36.241] <TB0> INFO: Expecting 41600 events.
[23:34:39.782] <TB0> INFO: 41600 events read in total (2950ms).
[23:34:39.783] <TB0> INFO: Test took 3808ms.
[23:34:40.073] <TB0> INFO: Expecting 41600 events.
[23:34:43.626] <TB0> INFO: 41600 events read in total (2961ms).
[23:34:43.627] <TB0> INFO: Test took 3819ms.
[23:34:43.917] <TB0> INFO: Expecting 41600 events.
[23:34:47.469] <TB0> INFO: 41600 events read in total (2960ms).
[23:34:47.470] <TB0> INFO: Test took 3819ms.
[23:34:47.759] <TB0> INFO: Expecting 41600 events.
[23:34:51.295] <TB0> INFO: 41600 events read in total (2944ms).
[23:34:51.296] <TB0> INFO: Test took 3802ms.
[23:34:51.612] <TB0> INFO: Expecting 41600 events.
[23:34:55.185] <TB0> INFO: 41600 events read in total (2982ms).
[23:34:55.186] <TB0> INFO: Test took 3864ms.
[23:34:55.475] <TB0> INFO: Expecting 41600 events.
[23:34:59.009] <TB0> INFO: 41600 events read in total (2943ms).
[23:34:59.010] <TB0> INFO: Test took 3800ms.
[23:34:59.299] <TB0> INFO: Expecting 41600 events.
[23:35:02.793] <TB0> INFO: 41600 events read in total (2902ms).
[23:35:02.794] <TB0> INFO: Test took 3759ms.
[23:35:03.083] <TB0> INFO: Expecting 41600 events.
[23:35:06.692] <TB0> INFO: 41600 events read in total (3017ms).
[23:35:06.692] <TB0> INFO: Test took 3874ms.
[23:35:06.981] <TB0> INFO: Expecting 41600 events.
[23:35:10.624] <TB0> INFO: 41600 events read in total (3051ms).
[23:35:10.625] <TB0> INFO: Test took 3909ms.
[23:35:10.917] <TB0> INFO: Expecting 41600 events.
[23:35:14.547] <TB0> INFO: 41600 events read in total (3038ms).
[23:35:14.548] <TB0> INFO: Test took 3897ms.
[23:35:14.838] <TB0> INFO: Expecting 41600 events.
[23:35:18.404] <TB0> INFO: 41600 events read in total (2975ms).
[23:35:18.405] <TB0> INFO: Test took 3833ms.
[23:35:18.716] <TB0> INFO: Expecting 41600 events.
[23:35:22.291] <TB0> INFO: 41600 events read in total (2983ms).
[23:35:22.291] <TB0> INFO: Test took 3863ms.
[23:35:22.645] <TB0> INFO: Expecting 41600 events.
[23:35:26.127] <TB0> INFO: 41600 events read in total (2891ms).
[23:35:26.127] <TB0> INFO: Test took 3811ms.
[23:35:26.422] <TB0> INFO: Expecting 41600 events.
[23:35:29.920] <TB0> INFO: 41600 events read in total (2906ms).
[23:35:29.921] <TB0> INFO: Test took 3764ms.
[23:35:30.211] <TB0> INFO: Expecting 2560 events.
[23:35:31.096] <TB0> INFO: 2560 events read in total (294ms).
[23:35:31.096] <TB0> INFO: Test took 1162ms.
[23:35:31.404] <TB0> INFO: Expecting 2560 events.
[23:35:32.290] <TB0> INFO: 2560 events read in total (294ms).
[23:35:32.290] <TB0> INFO: Test took 1193ms.
[23:35:32.599] <TB0> INFO: Expecting 2560 events.
[23:35:33.482] <TB0> INFO: 2560 events read in total (292ms).
[23:35:33.482] <TB0> INFO: Test took 1191ms.
[23:35:33.791] <TB0> INFO: Expecting 2560 events.
[23:35:34.679] <TB0> INFO: 2560 events read in total (297ms).
[23:35:34.680] <TB0> INFO: Test took 1197ms.
[23:35:34.988] <TB0> INFO: Expecting 2560 events.
[23:35:35.870] <TB0> INFO: 2560 events read in total (291ms).
[23:35:35.870] <TB0> INFO: Test took 1190ms.
[23:35:36.179] <TB0> INFO: Expecting 2560 events.
[23:35:37.061] <TB0> INFO: 2560 events read in total (290ms).
[23:35:37.061] <TB0> INFO: Test took 1190ms.
[23:35:37.370] <TB0> INFO: Expecting 2560 events.
[23:35:38.252] <TB0> INFO: 2560 events read in total (291ms).
[23:35:38.252] <TB0> INFO: Test took 1191ms.
[23:35:38.559] <TB0> INFO: Expecting 2560 events.
[23:35:39.439] <TB0> INFO: 2560 events read in total (290ms).
[23:35:39.440] <TB0> INFO: Test took 1187ms.
[23:35:39.750] <TB0> INFO: Expecting 2560 events.
[23:35:40.639] <TB0> INFO: 2560 events read in total (297ms).
[23:35:40.639] <TB0> INFO: Test took 1198ms.
[23:35:40.947] <TB0> INFO: Expecting 2560 events.
[23:35:41.837] <TB0> INFO: 2560 events read in total (298ms).
[23:35:41.837] <TB0> INFO: Test took 1197ms.
[23:35:42.144] <TB0> INFO: Expecting 2560 events.
[23:35:43.022] <TB0> INFO: 2560 events read in total (287ms).
[23:35:43.022] <TB0> INFO: Test took 1184ms.
[23:35:43.330] <TB0> INFO: Expecting 2560 events.
[23:35:44.211] <TB0> INFO: 2560 events read in total (290ms).
[23:35:44.212] <TB0> INFO: Test took 1189ms.
[23:35:44.519] <TB0> INFO: Expecting 2560 events.
[23:35:45.413] <TB0> INFO: 2560 events read in total (302ms).
[23:35:45.413] <TB0> INFO: Test took 1201ms.
[23:35:45.721] <TB0> INFO: Expecting 2560 events.
[23:35:46.608] <TB0> INFO: 2560 events read in total (295ms).
[23:35:46.608] <TB0> INFO: Test took 1194ms.
[23:35:46.915] <TB0> INFO: Expecting 2560 events.
[23:35:47.811] <TB0> INFO: 2560 events read in total (304ms).
[23:35:47.811] <TB0> INFO: Test took 1202ms.
[23:35:48.119] <TB0> INFO: Expecting 2560 events.
[23:35:48.004] <TB0> INFO: 2560 events read in total (293ms).
[23:35:48.005] <TB0> INFO: Test took 1192ms.
[23:35:49.009] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[23:35:49.312] <TB0> INFO: Expecting 655360 events.
[23:36:04.080] <TB0> INFO: 655360 events read in total (14177ms).
[23:36:04.092] <TB0> INFO: Expecting 655360 events.
[23:36:18.433] <TB0> INFO: 655360 events read in total (13938ms).
[23:36:18.449] <TB0> INFO: Expecting 655360 events.
[23:36:33.058] <TB0> INFO: 655360 events read in total (14206ms).
[23:36:33.079] <TB0> INFO: Expecting 655360 events.
[23:36:47.894] <TB0> INFO: 655360 events read in total (14412ms).
[23:36:47.919] <TB0> INFO: Expecting 655360 events.
[23:37:02.650] <TB0> INFO: 655360 events read in total (14328ms).
[23:37:02.687] <TB0> INFO: Expecting 655360 events.
[23:37:17.375] <TB0> INFO: 655360 events read in total (14285ms).
[23:37:17.417] <TB0> INFO: Expecting 655360 events.
[23:37:31.944] <TB0> INFO: 655360 events read in total (14124ms).
[23:37:31.984] <TB0> INFO: Expecting 655360 events.
[23:37:46.644] <TB0> INFO: 655360 events read in total (14257ms).
[23:37:46.687] <TB0> INFO: Expecting 655360 events.
[23:38:01.283] <TB0> INFO: 655360 events read in total (14193ms).
[23:38:01.344] <TB0> INFO: Expecting 655360 events.
[23:38:15.969] <TB0> INFO: 655360 events read in total (14222ms).
[23:38:16.021] <TB0> INFO: Expecting 655360 events.
[23:38:30.845] <TB0> INFO: 655360 events read in total (14421ms).
[23:38:30.900] <TB0> INFO: Expecting 655360 events.
[23:38:45.539] <TB0> INFO: 655360 events read in total (14236ms).
[23:38:45.599] <TB0> INFO: Expecting 655360 events.
[23:39:00.264] <TB0> INFO: 655360 events read in total (14262ms).
[23:39:00.330] <TB0> INFO: Expecting 655360 events.
[23:39:15.109] <TB0> INFO: 655360 events read in total (14376ms).
[23:39:15.237] <TB0> INFO: Expecting 655360 events.
[23:39:29.821] <TB0> INFO: 655360 events read in total (14181ms).
[23:39:29.896] <TB0> INFO: Expecting 655360 events.
[23:39:44.500] <TB0> INFO: 655360 events read in total (14201ms).
[23:39:44.677] <TB0> INFO: Test took 235668ms.
[23:39:44.771] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[23:39:45.029] <TB0> INFO: Expecting 655360 events.
[23:39:59.816] <TB0> INFO: 655360 events read in total (14194ms).
[23:39:59.832] <TB0> INFO: Expecting 655360 events.
[23:40:14.611] <TB0> INFO: 655360 events read in total (14375ms).
[23:40:14.627] <TB0> INFO: Expecting 655360 events.
[23:40:29.306] <TB0> INFO: 655360 events read in total (14276ms).
[23:40:29.327] <TB0> INFO: Expecting 655360 events.
[23:40:44.029] <TB0> INFO: 655360 events read in total (14299ms).
[23:40:44.060] <TB0> INFO: Expecting 655360 events.
[23:40:58.528] <TB0> INFO: 655360 events read in total (14065ms).
[23:40:58.558] <TB0> INFO: Expecting 655360 events.
[23:41:13.028] <TB0> INFO: 655360 events read in total (14067ms).
[23:41:13.062] <TB0> INFO: Expecting 655360 events.
[23:41:27.652] <TB0> INFO: 655360 events read in total (14187ms).
[23:41:27.702] <TB0> INFO: Expecting 655360 events.
[23:41:42.263] <TB0> INFO: 655360 events read in total (14158ms).
[23:41:42.318] <TB0> INFO: Expecting 655360 events.
[23:41:56.000] <TB0> INFO: 655360 events read in total (14278ms).
[23:41:57.048] <TB0> INFO: Expecting 655360 events.
[23:42:11.255] <TB0> INFO: 655360 events read in total (13803ms).
[23:42:11.305] <TB0> INFO: Expecting 655360 events.
[23:42:25.876] <TB0> INFO: 655360 events read in total (14168ms).
[23:42:25.929] <TB0> INFO: Expecting 655360 events.
[23:42:40.505] <TB0> INFO: 655360 events read in total (14172ms).
[23:42:40.564] <TB0> INFO: Expecting 655360 events.
[23:42:54.989] <TB0> INFO: 655360 events read in total (14022ms).
[23:42:55.051] <TB0> INFO: Expecting 655360 events.
[23:43:09.518] <TB0> INFO: 655360 events read in total (14064ms).
[23:43:09.644] <TB0> INFO: Expecting 655360 events.
[23:43:24.319] <TB0> INFO: 655360 events read in total (14272ms).
[23:43:24.417] <TB0> INFO: Expecting 655360 events.
[23:43:38.005] <TB0> INFO: 655360 events read in total (14185ms).
[23:43:39.096] <TB0> INFO: Test took 234325ms.
[23:43:39.261] <TB0> INFO: safety margin for low PH: adding 0, margin is now 20
[23:43:39.267] <TB0> INFO: safety margin for low PH: adding 1, margin is now 21
[23:43:39.273] <TB0> INFO: safety margin for low PH: adding 2, margin is now 22
[23:43:39.279] <TB0> INFO: safety margin for low PH: adding 3, margin is now 23
[23:43:39.285] <TB0> INFO: safety margin for low PH: adding 4, margin is now 24
[23:43:39.291] <TB0> INFO: safety margin for low PH: adding 5, margin is now 25
[23:43:39.297] <TB0> INFO: safety margin for low PH: adding 0, margin is now 20
[23:43:39.302] <TB0> INFO: safety margin for low PH: adding 0, margin is now 20
[23:43:39.308] <TB0> INFO: safety margin for low PH: adding 0, margin is now 20
[23:43:39.314] <TB0> INFO: safety margin for low PH: adding 0, margin is now 20
[23:43:39.319] <TB0> INFO: safety margin for low PH: adding 1, margin is now 21
[23:43:39.325] <TB0> INFO: safety margin for low PH: adding 2, margin is now 22
[23:43:39.331] <TB0> INFO: safety margin for low PH: adding 3, margin is now 23
[23:43:39.337] <TB0> INFO: safety margin for low PH: adding 4, margin is now 24
[23:43:39.343] <TB0> INFO: safety margin for low PH: adding 5, margin is now 25
[23:43:39.350] <TB0> INFO: safety margin for low PH: adding 6, margin is now 26
[23:43:39.357] <TB0> INFO: safety margin for low PH: adding 7, margin is now 27
[23:43:39.364] <TB0> INFO: safety margin for low PH: adding 8, margin is now 28
[23:43:39.371] <TB0> INFO: safety margin for low PH: adding 9, margin is now 29
[23:43:39.379] <TB0> INFO: safety margin for low PH: adding 10, margin is now 30
[23:43:39.386] <TB0> INFO: safety margin for low PH: adding 11, margin is now 31
[23:43:39.392] <TB0> INFO: safety margin for low PH: adding 12, margin is now 32
[23:43:39.400] <TB0> INFO: safety margin for low PH: adding 13, margin is now 33
[23:43:39.407] <TB0> INFO: safety margin for low PH: adding 0, margin is now 20
[23:43:39.414] <TB0> INFO: safety margin for low PH: adding 0, margin is now 20
[23:43:39.420] <TB0> INFO: safety margin for low PH: adding 1, margin is now 21
[23:43:39.428] <TB0> INFO: safety margin for low PH: adding 2, margin is now 22
[23:43:39.435] <TB0> INFO: safety margin for low PH: adding 0, margin is now 20
[23:43:39.443] <TB0> INFO: safety margin for low PH: adding 1, margin is now 21
[23:43:39.449] <TB0> INFO: safety margin for low PH: adding 0, margin is now 20
[23:43:39.455] <TB0> INFO: safety margin for low PH: adding 1, margin is now 21
[23:43:39.461] <TB0> INFO: safety margin for low PH: adding 2, margin is now 22
[23:43:39.467] <TB0> INFO: safety margin for low PH: adding 3, margin is now 23
[23:43:39.473] <TB0> INFO: safety margin for low PH: adding 4, margin is now 24
[23:43:39.480] <TB0> INFO: safety margin for low PH: adding 0, margin is now 20
[23:43:39.485] <TB0> INFO: safety margin for low PH: adding 0, margin is now 20
[23:43:39.491] <TB0> INFO: safety margin for low PH: adding 0, margin is now 20
[23:43:39.497] <TB0> INFO: safety margin for low PH: adding 1, margin is now 21
[23:43:39.502] <TB0> INFO: safety margin for low PH: adding 2, margin is now 22
[23:43:39.508] <TB0> INFO: safety margin for low PH: adding 3, margin is now 23
[23:43:39.514] <TB0> INFO: safety margin for low PH: adding 4, margin is now 24
[23:43:39.520] <TB0> INFO: safety margin for low PH: adding 0, margin is now 20
[23:43:39.526] <TB0> INFO: safety margin for low PH: adding 0, margin is now 20
[23:43:39.533] <TB0> INFO: safety margin for low PH: adding 0, margin is now 20
[23:43:39.539] <TB0> INFO: safety margin for low PH: adding 0, margin is now 20
[23:43:39.545] <TB0> INFO: safety margin for low PH: adding 1, margin is now 21
[23:43:39.579] <TB0> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1024_FullQualification_2016-10-19_19h52m_1476899523//003_Fulltest_p17//dacParameters35_C0.dat
[23:43:39.579] <TB0> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1024_FullQualification_2016-10-19_19h52m_1476899523//003_Fulltest_p17//dacParameters35_C1.dat
[23:43:39.579] <TB0> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1024_FullQualification_2016-10-19_19h52m_1476899523//003_Fulltest_p17//dacParameters35_C2.dat
[23:43:39.579] <TB0> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1024_FullQualification_2016-10-19_19h52m_1476899523//003_Fulltest_p17//dacParameters35_C3.dat
[23:43:39.579] <TB0> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1024_FullQualification_2016-10-19_19h52m_1476899523//003_Fulltest_p17//dacParameters35_C4.dat
[23:43:39.579] <TB0> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1024_FullQualification_2016-10-19_19h52m_1476899523//003_Fulltest_p17//dacParameters35_C5.dat
[23:43:39.579] <TB0> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1024_FullQualification_2016-10-19_19h52m_1476899523//003_Fulltest_p17//dacParameters35_C6.dat
[23:43:39.579] <TB0> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1024_FullQualification_2016-10-19_19h52m_1476899523//003_Fulltest_p17//dacParameters35_C7.dat
[23:43:39.579] <TB0> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1024_FullQualification_2016-10-19_19h52m_1476899523//003_Fulltest_p17//dacParameters35_C8.dat
[23:43:39.580] <TB0> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1024_FullQualification_2016-10-19_19h52m_1476899523//003_Fulltest_p17//dacParameters35_C9.dat
[23:43:39.580] <TB0> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1024_FullQualification_2016-10-19_19h52m_1476899523//003_Fulltest_p17//dacParameters35_C10.dat
[23:43:39.580] <TB0> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1024_FullQualification_2016-10-19_19h52m_1476899523//003_Fulltest_p17//dacParameters35_C11.dat
[23:43:39.580] <TB0> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1024_FullQualification_2016-10-19_19h52m_1476899523//003_Fulltest_p17//dacParameters35_C12.dat
[23:43:39.580] <TB0> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1024_FullQualification_2016-10-19_19h52m_1476899523//003_Fulltest_p17//dacParameters35_C13.dat
[23:43:39.580] <TB0> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1024_FullQualification_2016-10-19_19h52m_1476899523//003_Fulltest_p17//dacParameters35_C14.dat
[23:43:39.580] <TB0> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1024_FullQualification_2016-10-19_19h52m_1476899523//003_Fulltest_p17//dacParameters35_C15.dat
[23:43:39.834] <TB0> INFO: Expecting 41600 events.
[23:43:42.954] <TB0> INFO: 41600 events read in total (2528ms).
[23:43:42.955] <TB0> INFO: Test took 3371ms.
[23:43:43.403] <TB0> INFO: Expecting 41600 events.
[23:43:46.494] <TB0> INFO: 41600 events read in total (2499ms).
[23:43:46.495] <TB0> INFO: Test took 3329ms.
[23:43:46.951] <TB0> INFO: Expecting 41600 events.
[23:43:50.107] <TB0> INFO: 41600 events read in total (2564ms).
[23:43:50.108] <TB0> INFO: Test took 3402ms.
[23:43:50.324] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[23:43:50.414] <TB0> INFO: Expecting 2560 events.
[23:43:51.303] <TB0> INFO: 2560 events read in total (298ms).
[23:43:51.304] <TB0> INFO: Test took 980ms.
[23:43:51.307] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[23:43:51.612] <TB0> INFO: Expecting 2560 events.
[23:43:52.502] <TB0> INFO: 2560 events read in total (299ms).
[23:43:52.503] <TB0> INFO: Test took 1196ms.
[23:43:52.505] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[23:43:52.811] <TB0> INFO: Expecting 2560 events.
[23:43:53.701] <TB0> INFO: 2560 events read in total (298ms).
[23:43:53.702] <TB0> INFO: Test took 1197ms.
[23:43:53.704] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[23:43:54.010] <TB0> INFO: Expecting 2560 events.
[23:43:54.901] <TB0> INFO: 2560 events read in total (300ms).
[23:43:54.901] <TB0> INFO: Test took 1197ms.
[23:43:54.903] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[23:43:55.210] <TB0> INFO: Expecting 2560 events.
[23:43:56.100] <TB0> INFO: 2560 events read in total (298ms).
[23:43:56.100] <TB0> INFO: Test took 1197ms.
[23:43:56.104] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[23:43:56.408] <TB0> INFO: Expecting 2560 events.
[23:43:57.299] <TB0> INFO: 2560 events read in total (299ms).
[23:43:57.299] <TB0> INFO: Test took 1195ms.
[23:43:57.302] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[23:43:57.607] <TB0> INFO: Expecting 2560 events.
[23:43:58.497] <TB0> INFO: 2560 events read in total (298ms).
[23:43:58.497] <TB0> INFO: Test took 1195ms.
[23:43:58.501] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[23:43:58.805] <TB0> INFO: Expecting 2560 events.
[23:43:59.698] <TB0> INFO: 2560 events read in total (301ms).
[23:43:59.698] <TB0> INFO: Test took 1197ms.
[23:43:59.701] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[23:44:00.007] <TB0> INFO: Expecting 2560 events.
[23:44:00.894] <TB0> INFO: 2560 events read in total (295ms).
[23:44:00.895] <TB0> INFO: Test took 1194ms.
[23:44:00.898] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[23:44:01.203] <TB0> INFO: Expecting 2560 events.
[23:44:02.089] <TB0> INFO: 2560 events read in total (294ms).
[23:44:02.089] <TB0> INFO: Test took 1191ms.
[23:44:02.092] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[23:44:02.398] <TB0> INFO: Expecting 2560 events.
[23:44:03.280] <TB0> INFO: 2560 events read in total (291ms).
[23:44:03.280] <TB0> INFO: Test took 1188ms.
[23:44:03.284] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[23:44:03.588] <TB0> INFO: Expecting 2560 events.
[23:44:04.479] <TB0> INFO: 2560 events read in total (299ms).
[23:44:04.480] <TB0> INFO: Test took 1196ms.
[23:44:04.483] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[23:44:04.788] <TB0> INFO: Expecting 2560 events.
[23:44:05.674] <TB0> INFO: 2560 events read in total (294ms).
[23:44:05.674] <TB0> INFO: Test took 1191ms.
[23:44:05.677] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[23:44:05.982] <TB0> INFO: Expecting 2560 events.
[23:44:06.863] <TB0> INFO: 2560 events read in total (289ms).
[23:44:06.864] <TB0> INFO: Test took 1187ms.
[23:44:06.867] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[23:44:07.171] <TB0> INFO: Expecting 2560 events.
[23:44:08.061] <TB0> INFO: 2560 events read in total (298ms).
[23:44:08.061] <TB0> INFO: Test took 1194ms.
[23:44:08.064] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[23:44:08.370] <TB0> INFO: Expecting 2560 events.
[23:44:09.252] <TB0> INFO: 2560 events read in total (290ms).
[23:44:09.252] <TB0> INFO: Test took 1188ms.
[23:44:09.254] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[23:44:09.561] <TB0> INFO: Expecting 2560 events.
[23:44:10.446] <TB0> INFO: 2560 events read in total (293ms).
[23:44:10.447] <TB0> INFO: Test took 1193ms.
[23:44:10.449] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[23:44:10.754] <TB0> INFO: Expecting 2560 events.
[23:44:11.634] <TB0> INFO: 2560 events read in total (288ms).
[23:44:11.635] <TB0> INFO: Test took 1186ms.
[23:44:11.637] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[23:44:11.944] <TB0> INFO: Expecting 2560 events.
[23:44:12.825] <TB0> INFO: 2560 events read in total (290ms).
[23:44:12.826] <TB0> INFO: Test took 1189ms.
[23:44:12.830] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[23:44:13.134] <TB0> INFO: Expecting 2560 events.
[23:44:14.021] <TB0> INFO: 2560 events read in total (296ms).
[23:44:14.021] <TB0> INFO: Test took 1191ms.
[23:44:14.024] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[23:44:14.330] <TB0> INFO: Expecting 2560 events.
[23:44:15.224] <TB0> INFO: 2560 events read in total (303ms).
[23:44:15.224] <TB0> INFO: Test took 1200ms.
[23:44:15.227] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[23:44:15.532] <TB0> INFO: Expecting 2560 events.
[23:44:16.419] <TB0> INFO: 2560 events read in total (295ms).
[23:44:16.420] <TB0> INFO: Test took 1193ms.
[23:44:16.422] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[23:44:16.729] <TB0> INFO: Expecting 2560 events.
[23:44:17.613] <TB0> INFO: 2560 events read in total (292ms).
[23:44:17.614] <TB0> INFO: Test took 1192ms.
[23:44:17.617] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[23:44:17.922] <TB0> INFO: Expecting 2560 events.
[23:44:18.806] <TB0> INFO: 2560 events read in total (292ms).
[23:44:18.806] <TB0> INFO: Test took 1189ms.
[23:44:18.810] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[23:44:19.114] <TB0> INFO: Expecting 2560 events.
[23:44:20.010] <TB0> INFO: 2560 events read in total (304ms).
[23:44:20.011] <TB0> INFO: Test took 1201ms.
[23:44:20.014] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[23:44:20.318] <TB0> INFO: Expecting 2560 events.
[23:44:21.205] <TB0> INFO: 2560 events read in total (295ms).
[23:44:21.206] <TB0> INFO: Test took 1193ms.
[23:44:21.209] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[23:44:21.513] <TB0> INFO: Expecting 2560 events.
[23:44:22.407] <TB0> INFO: 2560 events read in total (302ms).
[23:44:22.407] <TB0> INFO: Test took 1198ms.
[23:44:22.411] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[23:44:22.714] <TB0> INFO: Expecting 2560 events.
[23:44:23.607] <TB0> INFO: 2560 events read in total (301ms).
[23:44:23.607] <TB0> INFO: Test took 1196ms.
[23:44:23.612] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[23:44:23.915] <TB0> INFO: Expecting 2560 events.
[23:44:24.807] <TB0> INFO: 2560 events read in total (300ms).
[23:44:24.808] <TB0> INFO: Test took 1197ms.
[23:44:24.811] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[23:44:25.116] <TB0> INFO: Expecting 2560 events.
[23:44:26.008] <TB0> INFO: 2560 events read in total (300ms).
[23:44:26.008] <TB0> INFO: Test took 1197ms.
[23:44:26.011] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[23:44:26.316] <TB0> INFO: Expecting 2560 events.
[23:44:27.211] <TB0> INFO: 2560 events read in total (303ms).
[23:44:27.211] <TB0> INFO: Test took 1200ms.
[23:44:27.214] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[23:44:27.518] <TB0> INFO: Expecting 2560 events.
[23:44:28.407] <TB0> INFO: 2560 events read in total (297ms).
[23:44:28.407] <TB0> INFO: Test took 1193ms.
[23:44:28.880] <TB0> INFO: PixTestPhOptimization::doTest() done, duration: 643 seconds
[23:44:28.880] <TB0> INFO: PH scale (per ROC): 44 54 45 45 44 46 48 47 33 41 41 38 48 48 56 33
[23:44:28.880] <TB0> INFO: PH offset (per ROC): 110 116 100 110 112 89 94 115 90 102 110 94 122 107 123 99
[23:44:28.887] <TB0> INFO: Decoding statistics:
[23:44:28.887] <TB0> INFO: General information:
[23:44:28.887] <TB0> INFO: 16bit words read: 127896
[23:44:28.887] <TB0> INFO: valid events total: 20480
[23:44:28.887] <TB0> INFO: empty events: 17972
[23:44:28.887] <TB0> INFO: valid events with pixels: 2508
[23:44:28.887] <TB0> INFO: valid pixel hits: 2508
[23:44:28.887] <TB0> INFO: Event errors: 0
[23:44:28.887] <TB0> INFO: start marker: 0
[23:44:28.887] <TB0> INFO: stop marker: 0
[23:44:28.887] <TB0> INFO: overflow: 0
[23:44:28.887] <TB0> INFO: invalid 5bit words: 0
[23:44:28.887] <TB0> INFO: invalid XOR eye diagram: 0
[23:44:28.887] <TB0> INFO: frame (failed synchr.): 0
[23:44:28.887] <TB0> INFO: idle data (no TBM trl): 0
[23:44:28.887] <TB0> INFO: no data (only TBM hdr): 0
[23:44:28.887] <TB0> INFO: TBM errors: 0
[23:44:28.887] <TB0> INFO: flawed TBM headers: 0
[23:44:28.887] <TB0> INFO: flawed TBM trailers: 0
[23:44:28.887] <TB0> INFO: event ID mismatches: 0
[23:44:28.887] <TB0> INFO: ROC errors: 0
[23:44:28.887] <TB0> INFO: missing ROC header(s): 0
[23:44:28.887] <TB0> INFO: misplaced readback start: 0
[23:44:28.887] <TB0> INFO: Pixel decoding errors: 0
[23:44:28.887] <TB0> INFO: pixel data incomplete: 0
[23:44:28.887] <TB0> INFO: pixel address: 0
[23:44:28.887] <TB0> INFO: pulse height fill bit: 0
[23:44:28.887] <TB0> INFO: buffer corruption: 0
[23:44:29.046] <TB0> INFO: ######################################################################
[23:44:29.046] <TB0> INFO: PixTestGainPedestal::fullTest() ntrig = 10
[23:44:29.046] <TB0> INFO: ######################################################################
[23:44:29.060] <TB0> INFO: scanning low vcal = 10
[23:44:29.299] <TB0> INFO: Expecting 41600 events.
[23:44:32.893] <TB0> INFO: 41600 events read in total (3002ms).
[23:44:32.893] <TB0> INFO: Test took 3833ms.
[23:44:32.895] <TB0> INFO: scanning low vcal = 20
[23:44:33.193] <TB0> INFO: Expecting 41600 events.
[23:44:36.782] <TB0> INFO: 41600 events read in total (2997ms).
[23:44:36.782] <TB0> INFO: Test took 3887ms.
[23:44:36.784] <TB0> INFO: scanning low vcal = 30
[23:44:37.079] <TB0> INFO: Expecting 41600 events.
[23:44:40.764] <TB0> INFO: 41600 events read in total (3094ms).
[23:44:40.765] <TB0> INFO: Test took 3981ms.
[23:44:40.768] <TB0> INFO: scanning low vcal = 40
[23:44:41.045] <TB0> INFO: Expecting 41600 events.
[23:44:45.031] <TB0> INFO: 41600 events read in total (3394ms).
[23:44:45.032] <TB0> INFO: Test took 4264ms.
[23:44:45.035] <TB0> INFO: scanning low vcal = 50
[23:44:45.312] <TB0> INFO: Expecting 41600 events.
[23:44:49.393] <TB0> INFO: 41600 events read in total (3489ms).
[23:44:49.394] <TB0> INFO: Test took 4359ms.
[23:44:49.398] <TB0> INFO: scanning low vcal = 60
[23:44:49.675] <TB0> INFO: Expecting 41600 events.
[23:44:53.648] <TB0> INFO: 41600 events read in total (3381ms).
[23:44:53.649] <TB0> INFO: Test took 4251ms.
[23:44:53.652] <TB0> INFO: scanning low vcal = 70
[23:44:53.930] <TB0> INFO: Expecting 41600 events.
[23:44:57.938] <TB0> INFO: 41600 events read in total (3417ms).
[23:44:57.939] <TB0> INFO: Test took 4287ms.
[23:44:57.941] <TB0> INFO: scanning low vcal = 80
[23:44:58.218] <TB0> INFO: Expecting 41600 events.
[23:45:02.243] <TB0> INFO: 41600 events read in total (3433ms).
[23:45:02.244] <TB0> INFO: Test took 4302ms.
[23:45:02.247] <TB0> INFO: scanning low vcal = 90
[23:45:02.525] <TB0> INFO: Expecting 41600 events.
[23:45:06.508] <TB0> INFO: 41600 events read in total (3392ms).
[23:45:06.509] <TB0> INFO: Test took 4262ms.
[23:45:06.512] <TB0> INFO: scanning low vcal = 100
[23:45:06.788] <TB0> INFO: Expecting 41600 events.
[23:45:10.804] <TB0> INFO: 41600 events read in total (3424ms).
[23:45:10.805] <TB0> INFO: Test took 4292ms.
[23:45:10.808] <TB0> INFO: scanning low vcal = 110
[23:45:11.085] <TB0> INFO: Expecting 41600 events.
[23:45:15.083] <TB0> INFO: 41600 events read in total (3407ms).
[23:45:15.084] <TB0> INFO: Test took 4276ms.
[23:45:15.087] <TB0> INFO: scanning low vcal = 120
[23:45:15.365] <TB0> INFO: Expecting 41600 events.
[23:45:19.391] <TB0> INFO: 41600 events read in total (3434ms).
[23:45:19.392] <TB0> INFO: Test took 4305ms.
[23:45:19.395] <TB0> INFO: scanning low vcal = 130
[23:45:19.672] <TB0> INFO: Expecting 41600 events.
[23:45:23.664] <TB0> INFO: 41600 events read in total (3400ms).
[23:45:23.665] <TB0> INFO: Test took 4270ms.
[23:45:23.668] <TB0> INFO: scanning low vcal = 140
[23:45:23.945] <TB0> INFO: Expecting 41600 events.
[23:45:27.929] <TB0> INFO: 41600 events read in total (3392ms).
[23:45:27.930] <TB0> INFO: Test took 4262ms.
[23:45:27.934] <TB0> INFO: scanning low vcal = 150
[23:45:28.211] <TB0> INFO: Expecting 41600 events.
[23:45:32.212] <TB0> INFO: 41600 events read in total (3409ms).
[23:45:32.213] <TB0> INFO: Test took 4279ms.
[23:45:32.216] <TB0> INFO: scanning low vcal = 160
[23:45:32.494] <TB0> INFO: Expecting 41600 events.
[23:45:36.483] <TB0> INFO: 41600 events read in total (3397ms).
[23:45:36.483] <TB0> INFO: Test took 4267ms.
[23:45:36.486] <TB0> INFO: scanning low vcal = 170
[23:45:36.764] <TB0> INFO: Expecting 41600 events.
[23:45:40.762] <TB0> INFO: 41600 events read in total (3406ms).
[23:45:40.762] <TB0> INFO: Test took 4275ms.
[23:45:40.768] <TB0> INFO: scanning low vcal = 180
[23:45:41.042] <TB0> INFO: Expecting 41600 events.
[23:45:45.042] <TB0> INFO: 41600 events read in total (3408ms).
[23:45:45.043] <TB0> INFO: Test took 4275ms.
[23:45:45.046] <TB0> INFO: scanning low vcal = 190
[23:45:45.323] <TB0> INFO: Expecting 41600 events.
[23:45:49.346] <TB0> INFO: 41600 events read in total (3431ms).
[23:45:49.347] <TB0> INFO: Test took 4301ms.
[23:45:49.350] <TB0> INFO: scanning low vcal = 200
[23:45:49.627] <TB0> INFO: Expecting 41600 events.
[23:45:53.600] <TB0> INFO: 41600 events read in total (3382ms).
[23:45:53.600] <TB0> INFO: Test took 4250ms.
[23:45:53.603] <TB0> INFO: scanning low vcal = 210
[23:45:53.880] <TB0> INFO: Expecting 41600 events.
[23:45:57.848] <TB0> INFO: 41600 events read in total (3376ms).
[23:45:57.848] <TB0> INFO: Test took 4245ms.
[23:45:57.851] <TB0> INFO: scanning low vcal = 220
[23:45:58.129] <TB0> INFO: Expecting 41600 events.
[23:46:02.132] <TB0> INFO: 41600 events read in total (3411ms).
[23:46:02.133] <TB0> INFO: Test took 4282ms.
[23:46:02.136] <TB0> INFO: scanning low vcal = 230
[23:46:02.413] <TB0> INFO: Expecting 41600 events.
[23:46:06.391] <TB0> INFO: 41600 events read in total (3386ms).
[23:46:06.392] <TB0> INFO: Test took 4256ms.
[23:46:06.395] <TB0> INFO: scanning low vcal = 240
[23:46:06.672] <TB0> INFO: Expecting 41600 events.
[23:46:10.636] <TB0> INFO: 41600 events read in total (3372ms).
[23:46:10.637] <TB0> INFO: Test took 4241ms.
[23:46:10.640] <TB0> INFO: scanning low vcal = 250
[23:46:10.917] <TB0> INFO: Expecting 41600 events.
[23:46:14.910] <TB0> INFO: 41600 events read in total (3401ms).
[23:46:14.911] <TB0> INFO: Test took 4271ms.
[23:46:14.915] <TB0> INFO: scanning high vcal = 30 (= 210 in low range)
[23:46:15.192] <TB0> INFO: Expecting 41600 events.
[23:46:19.178] <TB0> INFO: 41600 events read in total (3394ms).
[23:46:19.179] <TB0> INFO: Test took 4264ms.
[23:46:19.182] <TB0> INFO: scanning high vcal = 50 (= 350 in low range)
[23:46:19.465] <TB0> INFO: Expecting 41600 events.
[23:46:23.443] <TB0> INFO: 41600 events read in total (3387ms).
[23:46:23.444] <TB0> INFO: Test took 4262ms.
[23:46:23.447] <TB0> INFO: scanning high vcal = 70 (= 490 in low range)
[23:46:23.724] <TB0> INFO: Expecting 41600 events.
[23:46:27.708] <TB0> INFO: 41600 events read in total (3392ms).
[23:46:27.709] <TB0> INFO: Test took 4262ms.
[23:46:27.713] <TB0> INFO: scanning high vcal = 90 (= 630 in low range)
[23:46:27.990] <TB0> INFO: Expecting 41600 events.
[23:46:31.000] <TB0> INFO: 41600 events read in total (3419ms).
[23:46:31.000] <TB0> INFO: Test took 4287ms.
[23:46:31.003] <TB0> INFO: scanning high vcal = 200 (= 1400 in low range)
[23:46:32.281] <TB0> INFO: Expecting 41600 events.
[23:46:36.247] <TB0> INFO: 41600 events read in total (3374ms).
[23:46:36.248] <TB0> INFO: Test took 4245ms.
[23:46:36.671] <TB0> INFO: PixTestGainPedestal::measure() done
[23:47:09.254] <TB0> INFO: PixTestGainPedestal::fit() done
[23:47:09.254] <TB0> INFO: non-linearity mean: 0.929 0.978 0.857 0.942 0.923 0.935 0.936 0.949 0.952 0.937 0.919 0.895 0.980 0.959 0.981 0.932
[23:47:09.254] <TB0> INFO: non-linearity RMS: 0.090 0.009 0.134 0.068 0.075 0.068 0.050 0.044 0.204 0.074 0.104 0.160 0.004 0.036 0.004 0.196
[23:47:09.254] <TB0> INFO: write gain/ped parameters into /home/l_tester/david/FullQualification/data/M1024_FullQualification_2016-10-19_19h52m_1476899523//003_Fulltest_p17//phCalibrationFitErr35_C0.dat
[23:47:09.267] <TB0> INFO: write gain/ped parameters into /home/l_tester/david/FullQualification/data/M1024_FullQualification_2016-10-19_19h52m_1476899523//003_Fulltest_p17//phCalibrationFitErr35_C1.dat
[23:47:09.280] <TB0> INFO: write gain/ped parameters into /home/l_tester/david/FullQualification/data/M1024_FullQualification_2016-10-19_19h52m_1476899523//003_Fulltest_p17//phCalibrationFitErr35_C2.dat
[23:47:09.293] <TB0> INFO: write gain/ped parameters into /home/l_tester/david/FullQualification/data/M1024_FullQualification_2016-10-19_19h52m_1476899523//003_Fulltest_p17//phCalibrationFitErr35_C3.dat
[23:47:09.306] <TB0> INFO: write gain/ped parameters into /home/l_tester/david/FullQualification/data/M1024_FullQualification_2016-10-19_19h52m_1476899523//003_Fulltest_p17//phCalibrationFitErr35_C4.dat
[23:47:09.319] <TB0> INFO: write gain/ped parameters into /home/l_tester/david/FullQualification/data/M1024_FullQualification_2016-10-19_19h52m_1476899523//003_Fulltest_p17//phCalibrationFitErr35_C5.dat
[23:47:09.332] <TB0> INFO: write gain/ped parameters into /home/l_tester/david/FullQualification/data/M1024_FullQualification_2016-10-19_19h52m_1476899523//003_Fulltest_p17//phCalibrationFitErr35_C6.dat
[23:47:09.345] <TB0> INFO: write gain/ped parameters into /home/l_tester/david/FullQualification/data/M1024_FullQualification_2016-10-19_19h52m_1476899523//003_Fulltest_p17//phCalibrationFitErr35_C7.dat
[23:47:09.358] <TB0> INFO: write gain/ped parameters into /home/l_tester/david/FullQualification/data/M1024_FullQualification_2016-10-19_19h52m_1476899523//003_Fulltest_p17//phCalibrationFitErr35_C8.dat
[23:47:09.371] <TB0> INFO: write gain/ped parameters into /home/l_tester/david/FullQualification/data/M1024_FullQualification_2016-10-19_19h52m_1476899523//003_Fulltest_p17//phCalibrationFitErr35_C9.dat
[23:47:09.384] <TB0> INFO: write gain/ped parameters into /home/l_tester/david/FullQualification/data/M1024_FullQualification_2016-10-19_19h52m_1476899523//003_Fulltest_p17//phCalibrationFitErr35_C10.dat
[23:47:09.397] <TB0> INFO: write gain/ped parameters into /home/l_tester/david/FullQualification/data/M1024_FullQualification_2016-10-19_19h52m_1476899523//003_Fulltest_p17//phCalibrationFitErr35_C11.dat
[23:47:09.411] <TB0> INFO: write gain/ped parameters into /home/l_tester/david/FullQualification/data/M1024_FullQualification_2016-10-19_19h52m_1476899523//003_Fulltest_p17//phCalibrationFitErr35_C12.dat
[23:47:09.425] <TB0> INFO: write gain/ped parameters into /home/l_tester/david/FullQualification/data/M1024_FullQualification_2016-10-19_19h52m_1476899523//003_Fulltest_p17//phCalibrationFitErr35_C13.dat
[23:47:09.439] <TB0> INFO: write gain/ped parameters into /home/l_tester/david/FullQualification/data/M1024_FullQualification_2016-10-19_19h52m_1476899523//003_Fulltest_p17//phCalibrationFitErr35_C14.dat
[23:47:09.452] <TB0> INFO: write gain/ped parameters into /home/l_tester/david/FullQualification/data/M1024_FullQualification_2016-10-19_19h52m_1476899523//003_Fulltest_p17//phCalibrationFitErr35_C15.dat
[23:47:09.465] <TB0> INFO: PixTestGainPedestal::fullTest() done, duration: 160 seconds
[23:47:09.466] <TB0> INFO: Decoding statistics:
[23:47:09.466] <TB0> INFO: General information:
[23:47:09.466] <TB0> INFO: 16bit words read: 3324448
[23:47:09.466] <TB0> INFO: valid events total: 332800
[23:47:09.466] <TB0> INFO: empty events: 16
[23:47:09.466] <TB0> INFO: valid events with pixels: 332784
[23:47:09.466] <TB0> INFO: valid pixel hits: 663824
[23:47:09.466] <TB0> INFO: Event errors: 0
[23:47:09.466] <TB0> INFO: start marker: 0
[23:47:09.466] <TB0> INFO: stop marker: 0
[23:47:09.466] <TB0> INFO: overflow: 0
[23:47:09.466] <TB0> INFO: invalid 5bit words: 0
[23:47:09.466] <TB0> INFO: invalid XOR eye diagram: 0
[23:47:09.466] <TB0> INFO: frame (failed synchr.): 0
[23:47:09.466] <TB0> INFO: idle data (no TBM trl): 0
[23:47:09.466] <TB0> INFO: no data (only TBM hdr): 0
[23:47:09.466] <TB0> INFO: TBM errors: 0
[23:47:09.466] <TB0> INFO: flawed TBM headers: 0
[23:47:09.466] <TB0> INFO: flawed TBM trailers: 0
[23:47:09.466] <TB0> INFO: event ID mismatches: 0
[23:47:09.466] <TB0> INFO: ROC errors: 0
[23:47:09.466] <TB0> INFO: missing ROC header(s): 0
[23:47:09.466] <TB0> INFO: misplaced readback start: 0
[23:47:09.466] <TB0> INFO: Pixel decoding errors: 0
[23:47:09.466] <TB0> INFO: pixel data incomplete: 0
[23:47:09.466] <TB0> INFO: pixel address: 0
[23:47:09.466] <TB0> INFO: pulse height fill bit: 0
[23:47:09.466] <TB0> INFO: buffer corruption: 0
[23:47:09.481] <TB0> INFO: Decoding statistics:
[23:47:09.481] <TB0> INFO: General information:
[23:47:09.481] <TB0> INFO: 16bit words read: 3453880
[23:47:09.481] <TB0> INFO: valid events total: 353536
[23:47:09.481] <TB0> INFO: empty events: 18244
[23:47:09.481] <TB0> INFO: valid events with pixels: 335292
[23:47:09.481] <TB0> INFO: valid pixel hits: 666332
[23:47:09.481] <TB0> INFO: Event errors: 0
[23:47:09.481] <TB0> INFO: start marker: 0
[23:47:09.481] <TB0> INFO: stop marker: 0
[23:47:09.481] <TB0> INFO: overflow: 0
[23:47:09.481] <TB0> INFO: invalid 5bit words: 0
[23:47:09.481] <TB0> INFO: invalid XOR eye diagram: 0
[23:47:09.481] <TB0> INFO: frame (failed synchr.): 0
[23:47:09.481] <TB0> INFO: idle data (no TBM trl): 0
[23:47:09.481] <TB0> INFO: no data (only TBM hdr): 0
[23:47:09.481] <TB0> INFO: TBM errors: 0
[23:47:09.481] <TB0> INFO: flawed TBM headers: 0
[23:47:09.481] <TB0> INFO: flawed TBM trailers: 0
[23:47:09.481] <TB0> INFO: event ID mismatches: 0
[23:47:09.481] <TB0> INFO: ROC errors: 0
[23:47:09.481] <TB0> INFO: missing ROC header(s): 0
[23:47:09.481] <TB0> INFO: misplaced readback start: 0
[23:47:09.482] <TB0> INFO: Pixel decoding errors: 0
[23:47:09.482] <TB0> INFO: pixel data incomplete: 0
[23:47:09.482] <TB0> INFO: pixel address: 0
[23:47:09.482] <TB0> INFO: pulse height fill bit: 0
[23:47:09.482] <TB0> INFO: buffer corruption: 0
[23:47:09.482] <TB0> INFO: enter test to run
[23:47:09.482] <TB0> INFO: test: exit no parameter change
[23:47:09.615] <TB0> QUIET: Connection to board 71 closed.
[23:47:09.617] <TB0> INFO: pXar: this is the end, my friend