[18:11:23.817] <TB0> INFO: *** Welcome to pxar ***
[18:11:23.817] <TB0> INFO: *** Today: 2016/11/04
[18:11:23.823] <TB0> INFO: *** Version: c8ba-dirty
[18:11:23.823] <TB0> INFO: readRocDacs: /home/l_tester/david/FullQualification/data/M1116_FullQualification_2016-11-04_17h17m_1478276226//001_Fulltest_m20//dacParameters_C0.dat .. /home/l_tester/david/FullQualification/data/M1116_FullQualification_2016-11-04_17h17m_1478276226//001_Fulltest_m20//dacParameters_C15.dat
[18:11:23.824] <TB0> INFO: readTbmDacs: /home/l_tester/david/FullQualification/data/M1116_FullQualification_2016-11-04_17h17m_1478276226//001_Fulltest_m20//tbmParameters_C0a.dat .. /home/l_tester/david/FullQualification/data/M1116_FullQualification_2016-11-04_17h17m_1478276226//001_Fulltest_m20//tbmParameters_C1b.dat
[18:11:23.824] <TB0> INFO: readMaskFile: /home/l_tester/david/FullQualification/data/M1116_FullQualification_2016-11-04_17h17m_1478276226//001_Fulltest_m20//defaultMaskFile.dat
[18:11:23.824] <TB0> INFO: readTrimFile: /home/l_tester/david/FullQualification/data/M1116_FullQualification_2016-11-04_17h17m_1478276226//001_Fulltest_m20//trimParameters_C0.dat .. /home/l_tester/david/FullQualification/data/M1116_FullQualification_2016-11-04_17h17m_1478276226//001_Fulltest_m20//trimParameters_C15.dat
[18:11:23.883] <TB0> INFO: clk: 4
[18:11:23.883] <TB0> INFO: ctr: 4
[18:11:23.883] <TB0> INFO: sda: 19
[18:11:23.883] <TB0> INFO: tin: 9
[18:11:23.883] <TB0> INFO: level: 15
[18:11:23.883] <TB0> INFO: triggerdelay: 0
[18:11:23.883] <TB0> QUIET: Instanciating API for pxar v2.7.6+61~g7f4a123
[18:11:23.883] <TB0> INFO: Log level: INFO
[18:11:23.892] <TB0> INFO: Found DTB DTB_WRQ4OZ
[18:11:23.911] <TB0> QUIET: Connection to board DTB_WRQ4OZ opened.
[18:11:23.912] <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:
------------------------------------------------------
[18:11:23.914] <TB0> INFO: RPC call hashes of host and DTB match: 486171790
[18:11:25.403] <TB0> INFO: DUT info:
[18:11:25.403] <TB0> INFO: The DUT currently contains the following objects:
[18:11:25.403] <TB0> INFO: 4 TBM Cores tbm10c (4 ON)
[18:11:25.403] <TB0> INFO: TBM Core alpha (0): 7 registers set
[18:11:25.403] <TB0> INFO: TBM Core beta (1): 7 registers set
[18:11:25.403] <TB0> INFO: TBM Core alpha (2): 7 registers set
[18:11:25.403] <TB0> INFO: TBM Core beta (3): 7 registers set
[18:11:25.403] <TB0> INFO: 16 ROCs proc600 (16 ON) with 4160 pixelConfigs
[18:11:25.403] <TB0> INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[18:11:25.403] <TB0> INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[18:11:25.403] <TB0> INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[18:11:25.403] <TB0> INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[18:11:25.403] <TB0> INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[18:11:25.403] <TB0> INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[18:11:25.403] <TB0> INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[18:11:25.403] <TB0> INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[18:11:25.403] <TB0> INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[18:11:25.403] <TB0> INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[18:11:25.403] <TB0> INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[18:11:25.403] <TB0> INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[18:11:25.403] <TB0> INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[18:11:25.403] <TB0> INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[18:11:25.403] <TB0> INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[18:11:25.403] <TB0> INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[18:11:25.804] <TB0> INFO: enter 'restricted' command line mode
[18:11:25.804] <TB0> INFO: enter test to run
[18:11:25.804] <TB0> INFO: test: pretest no parameter change
[18:11:25.804] <TB0> INFO: running: pretest
[18:11:25.809] <TB0> INFO: ######################################################################
[18:11:25.809] <TB0> INFO: PixTestPretest::doTest()
[18:11:25.809] <TB0> INFO: ######################################################################
[18:11:25.810] <TB0> INFO: ----------------------------------------------------------------------
[18:11:25.810] <TB0> INFO: PixTestPretest::programROC()
[18:11:25.810] <TB0> INFO: ----------------------------------------------------------------------
[18:11:43.824] <TB0> INFO: PixTestPretest::programROC() done: ROCs are all programmable
[18:11:43.824] <TB0> INFO: IA differences per ROC: 21.7 17.7 19.3 20.9 18.5 20.1 21.7 20.1 20.9 19.3 17.7 19.3 20.1 20.1 20.9 18.5
[18:11:43.881] <TB0> INFO: ----------------------------------------------------------------------
[18:11:43.881] <TB0> INFO: PixTestPretest::setVana() target Ia = 24 mA/ROC
[18:11:43.881] <TB0> INFO: ----------------------------------------------------------------------
[18:11:53.776] <TB0> INFO: PixTestPretest::setVana() done, Module Ia 389.9 mA = 24.3688 mA/ROC
[18:11:53.776] <TB0> INFO: i(loss) [mA/ROC]: 19.3 18.5 18.5 19.3 19.3 19.3 19.3 18.5 18.5 18.5 19.3 20.1 18.5 19.3 19.3 19.3
[18:11:53.807] <TB0> INFO: ----------------------------------------------------------------------
[18:11:53.807] <TB0> INFO: PixTestPretest::findTiming()
[18:11:53.807] <TB0> INFO: ----------------------------------------------------------------------
[18:11:53.807] <TB0> INFO: PixTestCmd::init()

[18:12:25.341] <TB0> INFO: TBM phases: 160MHz: 4, 400MHz: 2, TBM delays: ROC(0/1):3, header/trailer: 1, token: 0
[18:12:25.341] <TB0> INFO: (success/tries = 100/100), width = 5
[18:12:26.840] <TB0> INFO: ----------------------------------------------------------------------
[18:12:26.840] <TB0> INFO: PixTestPretest::findWorkingPixel()
[18:12:26.840] <TB0> INFO: ----------------------------------------------------------------------
[18:12:26.937] <TB0> INFO: Expecting 231680 events.
[18:12:36.903] <TB0> INFO: 231680 events read in total (9374ms).
[18:12:36.912] <TB0> INFO: Test took 10066ms.
[18:12:37.163] <TB0> INFO: Found working pixel in all ROCs: col/row = 12/22
[18:12:37.199] <TB0> INFO: ----------------------------------------------------------------------
[18:12:37.199] <TB0> INFO: PixTestPretest::setVthrCompCalDel()
[18:12:37.199] <TB0> INFO: ----------------------------------------------------------------------
[18:12:37.295] <TB0> INFO: Expecting 231680 events.
[18:12:47.275] <TB0> INFO: 231680 events read in total (9388ms).
[18:12:47.283] <TB0> INFO: Test took 10078ms.
[18:12:47.553] <TB0> INFO: PixTestPretest::setVthrCompCalDel() done
[18:12:47.553] <TB0> INFO: CalDel: 92 82 98 119 80 77 104 95 90 96 126 114 106 106 105 93
[18:12:47.553] <TB0> INFO: VthrComp: 51 51 51 51 51 51 51 51 51 52 51 51 51 51 51 52
[18:12:47.556] <TB0> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1116_FullQualification_2016-11-04_17h17m_1478276226//001_Fulltest_m20//dacParameters_C0.dat
[18:12:47.557] <TB0> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1116_FullQualification_2016-11-04_17h17m_1478276226//001_Fulltest_m20//dacParameters_C1.dat
[18:12:47.557] <TB0> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1116_FullQualification_2016-11-04_17h17m_1478276226//001_Fulltest_m20//dacParameters_C2.dat
[18:12:47.557] <TB0> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1116_FullQualification_2016-11-04_17h17m_1478276226//001_Fulltest_m20//dacParameters_C3.dat
[18:12:47.557] <TB0> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1116_FullQualification_2016-11-04_17h17m_1478276226//001_Fulltest_m20//dacParameters_C4.dat
[18:12:47.557] <TB0> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1116_FullQualification_2016-11-04_17h17m_1478276226//001_Fulltest_m20//dacParameters_C5.dat
[18:12:47.558] <TB0> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1116_FullQualification_2016-11-04_17h17m_1478276226//001_Fulltest_m20//dacParameters_C6.dat
[18:12:47.558] <TB0> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1116_FullQualification_2016-11-04_17h17m_1478276226//001_Fulltest_m20//dacParameters_C7.dat
[18:12:47.558] <TB0> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1116_FullQualification_2016-11-04_17h17m_1478276226//001_Fulltest_m20//dacParameters_C8.dat
[18:12:47.558] <TB0> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1116_FullQualification_2016-11-04_17h17m_1478276226//001_Fulltest_m20//dacParameters_C9.dat
[18:12:47.558] <TB0> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1116_FullQualification_2016-11-04_17h17m_1478276226//001_Fulltest_m20//dacParameters_C10.dat
[18:12:47.558] <TB0> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1116_FullQualification_2016-11-04_17h17m_1478276226//001_Fulltest_m20//dacParameters_C11.dat
[18:12:47.559] <TB0> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1116_FullQualification_2016-11-04_17h17m_1478276226//001_Fulltest_m20//dacParameters_C12.dat
[18:12:47.559] <TB0> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1116_FullQualification_2016-11-04_17h17m_1478276226//001_Fulltest_m20//dacParameters_C13.dat
[18:12:47.559] <TB0> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1116_FullQualification_2016-11-04_17h17m_1478276226//001_Fulltest_m20//dacParameters_C14.dat
[18:12:47.559] <TB0> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1116_FullQualification_2016-11-04_17h17m_1478276226//001_Fulltest_m20//dacParameters_C15.dat
[18:12:47.559] <TB0> INFO: write tbm parameters into /home/l_tester/david/FullQualification/data/M1116_FullQualification_2016-11-04_17h17m_1478276226//001_Fulltest_m20//tbmParameters_C0a.dat
[18:12:47.559] <TB0> INFO: write tbm parameters into /home/l_tester/david/FullQualification/data/M1116_FullQualification_2016-11-04_17h17m_1478276226//001_Fulltest_m20//tbmParameters_C0b.dat
[18:12:47.559] <TB0> INFO: write tbm parameters into /home/l_tester/david/FullQualification/data/M1116_FullQualification_2016-11-04_17h17m_1478276226//001_Fulltest_m20//tbmParameters_C1a.dat
[18:12:47.560] <TB0> INFO: write tbm parameters into /home/l_tester/david/FullQualification/data/M1116_FullQualification_2016-11-04_17h17m_1478276226//001_Fulltest_m20//tbmParameters_C1b.dat
[18:12:47.560] <TB0> INFO: PixTestPretest::doTest() done, duration: 81 seconds
[18:12:47.619] <TB0> INFO: enter test to run
[18:12:47.619] <TB0> INFO: test: FullTest no parameter change
[18:12:47.619] <TB0> INFO: running: fulltest
[18:12:47.619] <TB0> INFO: ######################################################################
[18:12:47.619] <TB0> INFO: PixTestFullTest::doTest()
[18:12:47.619] <TB0> INFO: ######################################################################
[18:12:47.620] <TB0> INFO: ######################################################################
[18:12:47.620] <TB0> INFO: PixTestAlive::doTest()
[18:12:47.620] <TB0> INFO: ######################################################################
[18:12:47.621] <TB0> INFO: ----------------------------------------------------------------------
[18:12:47.621] <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)
[18:12:47.621] <TB0> INFO: ----------------------------------------------------------------------
[18:12:47.861] <TB0> INFO: Expecting 41600 events.
[18:12:51.468] <TB0> INFO: 41600 events read in total (3015ms).
[18:12:51.469] <TB0> INFO: Test took 3846ms.
[18:12:51.702] <TB0> INFO: PixTestAlive::aliveTest() done
[18:12:51.702] <TB0> INFO: number of dead pixels (per ROC): 0 0 2 0 0 0 0 0 0 0 0 0 0 0 0 0
[18:12:51.704] <TB0> INFO: ----------------------------------------------------------------------
[18:12:51.704] <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)
[18:12:51.704] <TB0> INFO: ----------------------------------------------------------------------
[18:12:51.991] <TB0> INFO: Expecting 41600 events.
[18:12:55.041] <TB0> INFO: 41600 events read in total (2458ms).
[18:12:55.041] <TB0> INFO: Test took 3335ms.
[18:12:55.042] <TB0> INFO: mask vs. old pixelAlive PixelAlive_C0_V0 .. PixelAlive_C15_V0
[18:12:55.281] <TB0> INFO: PixTestAlive::maskTest() done
[18:12:55.281] <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
[18:12:55.283] <TB0> INFO: ----------------------------------------------------------------------
[18:12:55.283] <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)
[18:12:55.283] <TB0> INFO: ----------------------------------------------------------------------
[18:12:55.529] <TB0> INFO: Expecting 41600 events.
[18:12:59.079] <TB0> INFO: 41600 events read in total (2959ms).
[18:12:59.080] <TB0> INFO: Test took 3796ms.
[18:12:59.316] <TB0> INFO: PixTestAlive::addressDecodingTest() done
[18:12:59.316] <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
[18:12:59.316] <TB0> INFO: PixTestAlive::doTest() done, duration: 11 seconds
[18:12:59.316] <TB0> INFO: Decoding statistics:
[18:12:59.316] <TB0> INFO: General information:
[18:12:59.316] <TB0> INFO: 16bit words read: 0
[18:12:59.316] <TB0> INFO: valid events total: 0
[18:12:59.316] <TB0> INFO: empty events: 0
[18:12:59.316] <TB0> INFO: valid events with pixels: 0
[18:12:59.316] <TB0> INFO: valid pixel hits: 0
[18:12:59.316] <TB0> INFO: Event errors: 0
[18:12:59.316] <TB0> INFO: start marker: 0
[18:12:59.316] <TB0> INFO: stop marker: 0
[18:12:59.316] <TB0> INFO: overflow: 0
[18:12:59.316] <TB0> INFO: invalid 5bit words: 0
[18:12:59.316] <TB0> INFO: invalid XOR eye diagram: 0
[18:12:59.316] <TB0> INFO: frame (failed synchr.): 0
[18:12:59.316] <TB0> INFO: idle data (no TBM trl): 0
[18:12:59.316] <TB0> INFO: no data (only TBM hdr): 0
[18:12:59.316] <TB0> INFO: TBM errors: 0
[18:12:59.316] <TB0> INFO: flawed TBM headers: 0
[18:12:59.317] <TB0> INFO: flawed TBM trailers: 0
[18:12:59.317] <TB0> INFO: event ID mismatches: 0
[18:12:59.317] <TB0> INFO: ROC errors: 0
[18:12:59.317] <TB0> INFO: missing ROC header(s): 0
[18:12:59.317] <TB0> INFO: misplaced readback start: 0
[18:12:59.317] <TB0> INFO: Pixel decoding errors: 0
[18:12:59.317] <TB0> INFO: pixel data incomplete: 0
[18:12:59.317] <TB0> INFO: pixel address: 0
[18:12:59.317] <TB0> INFO: pulse height fill bit: 0
[18:12:59.317] <TB0> INFO: buffer corruption: 0
[18:12:59.325] <TB0> INFO: readReadbackCal: /home/l_tester/david/FullQualification/data/M1116_FullQualification_2016-11-04_17h17m_1478276226//001_Fulltest_m20//readbackCal_C0.dat .. /home/l_tester/david/FullQualification/data/M1116_FullQualification_2016-11-04_17h17m_1478276226//001_Fulltest_m20//readbackCal_C15.dat
[18:12:59.326] <TB0> INFO: readGainPedestalParameters /home/l_tester/david/FullQualification/data/M1116_FullQualification_2016-11-04_17h17m_1478276226//001_Fulltest_m20//phCalibrationFitErr_C0.dat .. /home/l_tester/david/FullQualification/data/M1116_FullQualification_2016-11-04_17h17m_1478276226//001_Fulltest_m20//phCalibrationFitErr_C15.dat

[18:12:59.326] <TB0> INFO: ######################################################################
[18:12:59.326] <TB0> INFO: PixTestReadback::doTest()
[18:12:59.326] <TB0> INFO: ######################################################################
[18:12:59.326] <TB0> INFO: ----------------------------------------------------------------------
[18:12:59.326] <TB0> INFO: PixTestReadback::CalibrateVd()
[18:12:59.326] <TB0> INFO: ----------------------------------------------------------------------
[18:13:09.287] <TB0> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1116_FullQualification_2016-11-04_17h17m_1478276226//001_Fulltest_m20//readbackCal_C0.dat
[18:13:09.287] <TB0> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1116_FullQualification_2016-11-04_17h17m_1478276226//001_Fulltest_m20//readbackCal_C1.dat
[18:13:09.287] <TB0> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1116_FullQualification_2016-11-04_17h17m_1478276226//001_Fulltest_m20//readbackCal_C2.dat
[18:13:09.287] <TB0> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1116_FullQualification_2016-11-04_17h17m_1478276226//001_Fulltest_m20//readbackCal_C3.dat
[18:13:09.287] <TB0> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1116_FullQualification_2016-11-04_17h17m_1478276226//001_Fulltest_m20//readbackCal_C4.dat
[18:13:09.288] <TB0> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1116_FullQualification_2016-11-04_17h17m_1478276226//001_Fulltest_m20//readbackCal_C5.dat
[18:13:09.288] <TB0> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1116_FullQualification_2016-11-04_17h17m_1478276226//001_Fulltest_m20//readbackCal_C6.dat
[18:13:09.288] <TB0> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1116_FullQualification_2016-11-04_17h17m_1478276226//001_Fulltest_m20//readbackCal_C7.dat
[18:13:09.288] <TB0> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1116_FullQualification_2016-11-04_17h17m_1478276226//001_Fulltest_m20//readbackCal_C8.dat
[18:13:09.288] <TB0> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1116_FullQualification_2016-11-04_17h17m_1478276226//001_Fulltest_m20//readbackCal_C9.dat
[18:13:09.288] <TB0> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1116_FullQualification_2016-11-04_17h17m_1478276226//001_Fulltest_m20//readbackCal_C10.dat
[18:13:09.288] <TB0> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1116_FullQualification_2016-11-04_17h17m_1478276226//001_Fulltest_m20//readbackCal_C11.dat
[18:13:09.288] <TB0> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1116_FullQualification_2016-11-04_17h17m_1478276226//001_Fulltest_m20//readbackCal_C12.dat
[18:13:09.288] <TB0> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1116_FullQualification_2016-11-04_17h17m_1478276226//001_Fulltest_m20//readbackCal_C13.dat
[18:13:09.288] <TB0> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1116_FullQualification_2016-11-04_17h17m_1478276226//001_Fulltest_m20//readbackCal_C14.dat
[18:13:09.288] <TB0> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1116_FullQualification_2016-11-04_17h17m_1478276226//001_Fulltest_m20//readbackCal_C15.dat
[18:13:09.317] <TB0> INFO: PixTestPattern:: pg_setup set to default.
[18:13:09.318] <TB0> INFO: ----------------------------------------------------------------------
[18:13:09.318] <TB0> INFO: PixTestReadback::CalibrateVa()
[18:13:09.318] <TB0> INFO: ----------------------------------------------------------------------
[18:13:19.240] <TB0> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1116_FullQualification_2016-11-04_17h17m_1478276226//001_Fulltest_m20//readbackCal_C0.dat
[18:13:19.241] <TB0> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1116_FullQualification_2016-11-04_17h17m_1478276226//001_Fulltest_m20//readbackCal_C1.dat
[18:13:19.241] <TB0> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1116_FullQualification_2016-11-04_17h17m_1478276226//001_Fulltest_m20//readbackCal_C2.dat
[18:13:19.241] <TB0> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1116_FullQualification_2016-11-04_17h17m_1478276226//001_Fulltest_m20//readbackCal_C3.dat
[18:13:19.241] <TB0> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1116_FullQualification_2016-11-04_17h17m_1478276226//001_Fulltest_m20//readbackCal_C4.dat
[18:13:19.241] <TB0> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1116_FullQualification_2016-11-04_17h17m_1478276226//001_Fulltest_m20//readbackCal_C5.dat
[18:13:19.241] <TB0> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1116_FullQualification_2016-11-04_17h17m_1478276226//001_Fulltest_m20//readbackCal_C6.dat
[18:13:19.241] <TB0> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1116_FullQualification_2016-11-04_17h17m_1478276226//001_Fulltest_m20//readbackCal_C7.dat
[18:13:19.241] <TB0> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1116_FullQualification_2016-11-04_17h17m_1478276226//001_Fulltest_m20//readbackCal_C8.dat
[18:13:19.241] <TB0> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1116_FullQualification_2016-11-04_17h17m_1478276226//001_Fulltest_m20//readbackCal_C9.dat
[18:13:19.241] <TB0> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1116_FullQualification_2016-11-04_17h17m_1478276226//001_Fulltest_m20//readbackCal_C10.dat
[18:13:19.241] <TB0> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1116_FullQualification_2016-11-04_17h17m_1478276226//001_Fulltest_m20//readbackCal_C11.dat
[18:13:19.241] <TB0> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1116_FullQualification_2016-11-04_17h17m_1478276226//001_Fulltest_m20//readbackCal_C12.dat
[18:13:19.241] <TB0> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1116_FullQualification_2016-11-04_17h17m_1478276226//001_Fulltest_m20//readbackCal_C13.dat
[18:13:19.242] <TB0> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1116_FullQualification_2016-11-04_17h17m_1478276226//001_Fulltest_m20//readbackCal_C14.dat
[18:13:19.242] <TB0> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1116_FullQualification_2016-11-04_17h17m_1478276226//001_Fulltest_m20//readbackCal_C15.dat
[18:13:19.268] <TB0> INFO: PixTestPattern:: pg_setup set to default.
[18:13:19.268] <TB0> INFO: ----------------------------------------------------------------------
[18:13:19.268] <TB0> INFO: PixTestReadback::readbackVbg()
[18:13:19.268] <TB0> INFO: ----------------------------------------------------------------------
[18:13:26.932] <TB0> INFO: PixTestPattern:: pg_setup set to default.
[18:13:26.932] <TB0> INFO: ----------------------------------------------------------------------
[18:13:26.932] <TB0> INFO: PixTestReadback::getCalibratedVbg()
[18:13:26.932] <TB0> INFO: ----------------------------------------------------------------------
[18:13:26.932] <TB0> INFO: Vbg will be calibrated using Vd calibration
[18:13:26.932] <TB0> INFO: /*/*/*/*::: ROC 0: uncalibrated Vbg = 151calibrated Vbg = 1.17165 :::*/*/*/*/
[18:13:26.932] <TB0> INFO: /*/*/*/*::: ROC 1: uncalibrated Vbg = 149.6calibrated Vbg = 1.15962 :::*/*/*/*/
[18:13:26.932] <TB0> INFO: /*/*/*/*::: ROC 2: uncalibrated Vbg = 150.7calibrated Vbg = 1.15754 :::*/*/*/*/
[18:13:26.932] <TB0> INFO: /*/*/*/*::: ROC 3: uncalibrated Vbg = 151.8calibrated Vbg = 1.16565 :::*/*/*/*/
[18:13:26.932] <TB0> INFO: /*/*/*/*::: ROC 4: uncalibrated Vbg = 151.2calibrated Vbg = 1.16644 :::*/*/*/*/
[18:13:26.932] <TB0> INFO: /*/*/*/*::: ROC 5: uncalibrated Vbg = 154.9calibrated Vbg = 1.16238 :::*/*/*/*/
[18:13:26.932] <TB0> INFO: /*/*/*/*::: ROC 6: uncalibrated Vbg = 148.1calibrated Vbg = 1.16906 :::*/*/*/*/
[18:13:26.932] <TB0> INFO: /*/*/*/*::: ROC 7: uncalibrated Vbg = 150.4calibrated Vbg = 1.17151 :::*/*/*/*/
[18:13:26.932] <TB0> INFO: /*/*/*/*::: ROC 8: uncalibrated Vbg = 157.9calibrated Vbg = 1.1647 :::*/*/*/*/
[18:13:26.933] <TB0> INFO: /*/*/*/*::: ROC 9: uncalibrated Vbg = 161.3calibrated Vbg = 1.16678 :::*/*/*/*/
[18:13:26.933] <TB0> INFO: /*/*/*/*::: ROC 10: uncalibrated Vbg = 151calibrated Vbg = 1.16026 :::*/*/*/*/
[18:13:26.933] <TB0> INFO: /*/*/*/*::: ROC 11: uncalibrated Vbg = 159.3calibrated Vbg = 1.15574 :::*/*/*/*/
[18:13:26.933] <TB0> INFO: /*/*/*/*::: ROC 12: uncalibrated Vbg = 150.2calibrated Vbg = 1.16439 :::*/*/*/*/
[18:13:26.933] <TB0> INFO: /*/*/*/*::: ROC 13: uncalibrated Vbg = 154.6calibrated Vbg = 1.16705 :::*/*/*/*/
[18:13:26.933] <TB0> INFO: /*/*/*/*::: ROC 14: uncalibrated Vbg = 152.3calibrated Vbg = 1.16557 :::*/*/*/*/
[18:13:26.933] <TB0> INFO: /*/*/*/*::: ROC 15: uncalibrated Vbg = 159.3calibrated Vbg = 1.15705 :::*/*/*/*/
[18:13:26.935] <TB0> INFO: ----------------------------------------------------------------------
[18:13:26.935] <TB0> INFO: PixTestReadback::CalibrateIa()
[18:13:26.935] <TB0> INFO: ----------------------------------------------------------------------
[18:16:07.787] <TB0> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1116_FullQualification_2016-11-04_17h17m_1478276226//001_Fulltest_m20//readbackCal_C0.dat
[18:16:07.788] <TB0> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1116_FullQualification_2016-11-04_17h17m_1478276226//001_Fulltest_m20//readbackCal_C1.dat
[18:16:07.788] <TB0> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1116_FullQualification_2016-11-04_17h17m_1478276226//001_Fulltest_m20//readbackCal_C2.dat
[18:16:07.788] <TB0> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1116_FullQualification_2016-11-04_17h17m_1478276226//001_Fulltest_m20//readbackCal_C3.dat
[18:16:07.788] <TB0> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1116_FullQualification_2016-11-04_17h17m_1478276226//001_Fulltest_m20//readbackCal_C4.dat
[18:16:07.788] <TB0> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1116_FullQualification_2016-11-04_17h17m_1478276226//001_Fulltest_m20//readbackCal_C5.dat
[18:16:07.788] <TB0> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1116_FullQualification_2016-11-04_17h17m_1478276226//001_Fulltest_m20//readbackCal_C6.dat
[18:16:07.788] <TB0> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1116_FullQualification_2016-11-04_17h17m_1478276226//001_Fulltest_m20//readbackCal_C7.dat
[18:16:07.788] <TB0> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1116_FullQualification_2016-11-04_17h17m_1478276226//001_Fulltest_m20//readbackCal_C8.dat
[18:16:07.788] <TB0> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1116_FullQualification_2016-11-04_17h17m_1478276226//001_Fulltest_m20//readbackCal_C9.dat
[18:16:07.788] <TB0> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1116_FullQualification_2016-11-04_17h17m_1478276226//001_Fulltest_m20//readbackCal_C10.dat
[18:16:07.788] <TB0> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1116_FullQualification_2016-11-04_17h17m_1478276226//001_Fulltest_m20//readbackCal_C11.dat
[18:16:07.788] <TB0> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1116_FullQualification_2016-11-04_17h17m_1478276226//001_Fulltest_m20//readbackCal_C12.dat
[18:16:07.788] <TB0> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1116_FullQualification_2016-11-04_17h17m_1478276226//001_Fulltest_m20//readbackCal_C13.dat
[18:16:07.788] <TB0> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1116_FullQualification_2016-11-04_17h17m_1478276226//001_Fulltest_m20//readbackCal_C14.dat
[18:16:07.788] <TB0> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1116_FullQualification_2016-11-04_17h17m_1478276226//001_Fulltest_m20//readbackCal_C15.dat
[18:16:07.817] <TB0> INFO: PixTestPattern:: pg_setup set to default.
[18:16:07.819] <TB0> INFO: PixTestReadback::doTest() done
[18:16:07.819] <TB0> INFO: Decoding statistics:
[18:16:07.819] <TB0> INFO: General information:
[18:16:07.819] <TB0> INFO: 16bit words read: 1536
[18:16:07.819] <TB0> INFO: valid events total: 256
[18:16:07.819] <TB0> INFO: empty events: 256
[18:16:07.819] <TB0> INFO: valid events with pixels: 0
[18:16:07.819] <TB0> INFO: valid pixel hits: 0
[18:16:07.819] <TB0> INFO: Event errors: 0
[18:16:07.819] <TB0> INFO: start marker: 0
[18:16:07.819] <TB0> INFO: stop marker: 0
[18:16:07.819] <TB0> INFO: overflow: 0
[18:16:07.819] <TB0> INFO: invalid 5bit words: 0
[18:16:07.819] <TB0> INFO: invalid XOR eye diagram: 0
[18:16:07.819] <TB0> INFO: frame (failed synchr.): 0
[18:16:07.819] <TB0> INFO: idle data (no TBM trl): 0
[18:16:07.819] <TB0> INFO: no data (only TBM hdr): 0
[18:16:07.819] <TB0> INFO: TBM errors: 0
[18:16:07.819] <TB0> INFO: flawed TBM headers: 0
[18:16:07.819] <TB0> INFO: flawed TBM trailers: 0
[18:16:07.819] <TB0> INFO: event ID mismatches: 0
[18:16:07.819] <TB0> INFO: ROC errors: 0
[18:16:07.819] <TB0> INFO: missing ROC header(s): 0
[18:16:07.819] <TB0> INFO: misplaced readback start: 0
[18:16:07.819] <TB0> INFO: Pixel decoding errors: 0
[18:16:07.819] <TB0> INFO: pixel data incomplete: 0
[18:16:07.819] <TB0> INFO: pixel address: 0
[18:16:07.819] <TB0> INFO: pulse height fill bit: 0
[18:16:07.820] <TB0> INFO: buffer corruption: 0
[18:16:07.872] <TB0> INFO: ######################################################################
[18:16:07.872] <TB0> INFO: PixTestBBMap::doTest() Ntrig = 5, VcalS = 250 (high range)
[18:16:07.872] <TB0> INFO: ######################################################################
[18:16:07.875] <TB0> INFO: ---> dac: VthrComp name: calSMap ntrig: 5 dacrange: 0 .. 149 (-1/-1) hits flags = 514 (plus default)
[18:16:07.892] <TB0> INFO: dacScan split into 1 runs with ntrig = 5
[18:16:07.892] <TB0> INFO: run 1 of 1
[18:16:08.129] <TB0> INFO: Expecting 3120000 events.
[18:16:40.333] <TB0> INFO: 673755 events read in total (31612ms).











[18:17:10.782] <TB0> INFO: 1342395 events read in total (62061ms).











[18:17:41.088] <TB0> INFO: 2006555 events read in total (92367ms).











[18:18:11.268] <TB0> INFO: 2671300 events read in total (122547ms).
[18:18:31.846] <TB0> INFO: 3120000 events read in total (143125ms).
[18:18:31.928] <TB0> INFO: Test took 144037ms.
[18:18:53.994] <TB0> INFO: PixTestBBMap::doTest() done with 1 decoding errors: , duration: 166 seconds
[18:18:53.994] <TB0> INFO: number of dead bumps (per ROC): 0 1 2 0 0 0 0 0 0 0 0 0 0 0 1 0
[18:18:53.994] <TB0> INFO: separation cut (per ROC): 108 105 110 109 110 123 107 110 112 131 115 126 109 105 116 112
[18:18:53.994] <TB0> INFO: Decoding statistics:
[18:18:53.994] <TB0> INFO: General information:
[18:18:53.994] <TB0> INFO: 16bit words read: 0
[18:18:53.994] <TB0> INFO: valid events total: 0
[18:18:53.994] <TB0> INFO: empty events: 0
[18:18:53.994] <TB0> INFO: valid events with pixels: 0
[18:18:53.994] <TB0> INFO: valid pixel hits: 0
[18:18:53.994] <TB0> INFO: Event errors: 0
[18:18:53.994] <TB0> INFO: start marker: 0
[18:18:53.994] <TB0> INFO: stop marker: 0
[18:18:53.994] <TB0> INFO: overflow: 0
[18:18:53.994] <TB0> INFO: invalid 5bit words: 0
[18:18:53.994] <TB0> INFO: invalid XOR eye diagram: 0
[18:18:53.994] <TB0> INFO: frame (failed synchr.): 0
[18:18:53.994] <TB0> INFO: idle data (no TBM trl): 0
[18:18:53.994] <TB0> INFO: no data (only TBM hdr): 0
[18:18:53.994] <TB0> INFO: TBM errors: 0
[18:18:53.994] <TB0> INFO: flawed TBM headers: 0
[18:18:53.994] <TB0> INFO: flawed TBM trailers: 0
[18:18:53.994] <TB0> INFO: event ID mismatches: 0
[18:18:53.994] <TB0> INFO: ROC errors: 0
[18:18:53.994] <TB0> INFO: missing ROC header(s): 0
[18:18:53.994] <TB0> INFO: misplaced readback start: 0
[18:18:53.994] <TB0> INFO: Pixel decoding errors: 0
[18:18:53.994] <TB0> INFO: pixel data incomplete: 0
[18:18:53.994] <TB0> INFO: pixel address: 0
[18:18:53.994] <TB0> INFO: pulse height fill bit: 0
[18:18:53.994] <TB0> INFO: buffer corruption: 0
[18:18:54.041] <TB0> INFO: ######################################################################
[18:18:54.041] <TB0> INFO: PixTestScurves::fullTest() ntrig = 50, dacs/step = -1, ntrig/step = -1
[18:18:54.041] <TB0> INFO: ######################################################################
[18:18:54.041] <TB0> INFO: ----------------------------------------------------------------------
[18:18:54.041] <TB0> INFO: PixTestScurves::scurves(Vcal), ntrig = 50, dacs/step = -1, ntrig/step = -1
[18:18:54.041] <TB0> INFO: ----------------------------------------------------------------------
[18:18:54.042] <TB0> INFO: ---> dac: Vcal name: scurveVcal ntrig: 50 dacrange: 25 .. 200 (-1/-1) hits flags = 528 (plus default)
[18:18:54.055] <TB0> INFO: dacScan split into 1 runs with ntrig = 50
[18:18:54.056] <TB0> INFO: run 1 of 1
[18:18:54.297] <TB0> INFO: Expecting 36608000 events.
[18:19:19.076] <TB0> INFO: 677850 events read in total (24187ms).
[18:19:42.387] <TB0> INFO: 1343250 events read in total (47498ms).
[18:20:05.411] <TB0> INFO: 2008800 events read in total (70522ms).
[18:20:28.063] <TB0> INFO: 2673450 events read in total (93174ms).
[18:20:50.707] <TB0> INFO: 3335650 events read in total (115818ms).
[18:21:13.680] <TB0> INFO: 3998800 events read in total (138791ms).
[18:21:36.910] <TB0> INFO: 4660400 events read in total (162021ms).
[18:21:59.673] <TB0> INFO: 5321850 events read in total (184784ms).
[18:22:22.353] <TB0> INFO: 5985050 events read in total (207464ms).
[18:22:45.392] <TB0> INFO: 6649300 events read in total (230503ms).
[18:23:08.079] <TB0> INFO: 7311400 events read in total (253190ms).
[18:23:31.384] <TB0> INFO: 7974400 events read in total (276495ms).
[18:23:54.257] <TB0> INFO: 8637000 events read in total (299368ms).
[18:24:17.367] <TB0> INFO: 9300150 events read in total (322478ms).
[18:24:40.437] <TB0> INFO: 9962050 events read in total (345548ms).
[18:25:03.293] <TB0> INFO: 10624000 events read in total (368404ms).
[18:25:26.413] <TB0> INFO: 11286400 events read in total (391524ms).
[18:25:49.420] <TB0> INFO: 11947350 events read in total (414531ms).
[18:26:12.316] <TB0> INFO: 12608150 events read in total (437427ms).
[18:26:35.516] <TB0> INFO: 13267450 events read in total (460627ms).
[18:26:58.475] <TB0> INFO: 13928250 events read in total (483586ms).
[18:27:21.644] <TB0> INFO: 14589100 events read in total (506755ms).
[18:27:44.760] <TB0> INFO: 15250300 events read in total (529871ms).
[18:28:07.643] <TB0> INFO: 15911450 events read in total (552754ms).
[18:28:30.501] <TB0> INFO: 16571300 events read in total (575612ms).
[18:28:53.750] <TB0> INFO: 17231700 events read in total (598861ms).
[18:29:16.799] <TB0> INFO: 17889850 events read in total (621910ms).
[18:29:39.804] <TB0> INFO: 18548650 events read in total (644915ms).
[18:30:02.389] <TB0> INFO: 19205500 events read in total (667500ms).
[18:30:25.207] <TB0> INFO: 19861700 events read in total (690318ms).
[18:30:48.142] <TB0> INFO: 20518350 events read in total (713253ms).
[18:31:11.198] <TB0> INFO: 21174250 events read in total (736309ms).
[18:31:34.243] <TB0> INFO: 21831650 events read in total (759354ms).
[18:31:56.942] <TB0> INFO: 22488450 events read in total (782053ms).
[18:32:19.561] <TB0> INFO: 23144100 events read in total (804672ms).
[18:32:42.568] <TB0> INFO: 23800400 events read in total (827679ms).
[18:33:05.389] <TB0> INFO: 24455500 events read in total (850500ms).
[18:33:28.100] <TB0> INFO: 25111750 events read in total (873211ms).
[18:33:50.892] <TB0> INFO: 25766350 events read in total (896003ms).
[18:34:13.624] <TB0> INFO: 26423400 events read in total (918735ms).
[18:34:36.623] <TB0> INFO: 27078750 events read in total (941734ms).
[18:34:59.390] <TB0> INFO: 27733350 events read in total (964501ms).
[18:35:22.273] <TB0> INFO: 28386950 events read in total (987384ms).
[18:35:45.071] <TB0> INFO: 29041300 events read in total (1010182ms).
[18:36:07.843] <TB0> INFO: 29694550 events read in total (1032954ms).
[18:36:30.469] <TB0> INFO: 30347850 events read in total (1055580ms).
[18:36:53.412] <TB0> INFO: 31000300 events read in total (1078523ms).
[18:37:15.945] <TB0> INFO: 31652900 events read in total (1101056ms).
[18:37:38.806] <TB0> INFO: 32307000 events read in total (1123917ms).
[18:38:01.574] <TB0> INFO: 32962550 events read in total (1146685ms).
[18:38:24.282] <TB0> INFO: 33618050 events read in total (1169393ms).
[18:38:47.128] <TB0> INFO: 34273250 events read in total (1192239ms).
[18:39:09.960] <TB0> INFO: 34926600 events read in total (1215071ms).
[18:39:32.513] <TB0> INFO: 35582350 events read in total (1237624ms).
[18:39:55.424] <TB0> INFO: 36242350 events read in total (1260535ms).
[18:40:08.010] <TB0> INFO: 36608000 events read in total (1273121ms).
[18:40:08.130] <TB0> INFO: Test took 1274074ms.
[18:40:08.716] <TB0> INFO: dumping ASCII scurve output file: SCurveData
[18:40:10.350] <TB0> INFO: dumping ASCII scurve output file: SCurveData
[18:40:12.214] <TB0> INFO: dumping ASCII scurve output file: SCurveData
[18:40:13.755] <TB0> INFO: dumping ASCII scurve output file: SCurveData
[18:40:15.563] <TB0> INFO: dumping ASCII scurve output file: SCurveData
[18:40:17.553] <TB0> INFO: dumping ASCII scurve output file: SCurveData
[18:40:19.681] <TB0> INFO: dumping ASCII scurve output file: SCurveData
[18:40:21.355] <TB0> INFO: dumping ASCII scurve output file: SCurveData
[18:40:22.857] <TB0> INFO: dumping ASCII scurve output file: SCurveData
[18:40:24.524] <TB0> INFO: dumping ASCII scurve output file: SCurveData
[18:40:26.320] <TB0> INFO: dumping ASCII scurve output file: SCurveData
[18:40:28.129] <TB0> INFO: dumping ASCII scurve output file: SCurveData
[18:40:29.866] <TB0> INFO: dumping ASCII scurve output file: SCurveData
[18:40:31.669] <TB0> INFO: dumping ASCII scurve output file: SCurveData
[18:40:33.761] <TB0> INFO: dumping ASCII scurve output file: SCurveData
[18:40:35.697] <TB0> INFO: dumping ASCII scurve output file: SCurveData
[18:40:37.902] <TB0> INFO: PixTestScurves::scurves() done
[18:40:37.902] <TB0> INFO: Vcal mean: 114.95 104.00 116.20 108.88 109.69 127.06 110.92 112.55 112.59 131.84 119.40 120.36 115.25 105.14 120.04 113.64
[18:40:37.902] <TB0> INFO: Vcal RMS: 4.98 5.28 6.44 7.69 5.73 6.31 4.36 4.81 4.63 7.52 5.87 5.81 5.63 5.07 6.62 5.57
[18:40:37.902] <TB0> INFO: PixTestScurves::fullTest() done, duration: 1303 seconds
[18:40:37.902] <TB0> INFO: Decoding statistics:
[18:40:37.902] <TB0> INFO: General information:
[18:40:37.902] <TB0> INFO: 16bit words read: 0
[18:40:37.902] <TB0> INFO: valid events total: 0
[18:40:37.902] <TB0> INFO: empty events: 0
[18:40:37.902] <TB0> INFO: valid events with pixels: 0
[18:40:37.902] <TB0> INFO: valid pixel hits: 0
[18:40:37.902] <TB0> INFO: Event errors: 0
[18:40:37.902] <TB0> INFO: start marker: 0
[18:40:37.902] <TB0> INFO: stop marker: 0
[18:40:37.902] <TB0> INFO: overflow: 0
[18:40:37.902] <TB0> INFO: invalid 5bit words: 0
[18:40:37.902] <TB0> INFO: invalid XOR eye diagram: 0
[18:40:37.902] <TB0> INFO: frame (failed synchr.): 0
[18:40:37.902] <TB0> INFO: idle data (no TBM trl): 0
[18:40:37.902] <TB0> INFO: no data (only TBM hdr): 0
[18:40:37.902] <TB0> INFO: TBM errors: 0
[18:40:37.902] <TB0> INFO: flawed TBM headers: 0
[18:40:37.902] <TB0> INFO: flawed TBM trailers: 0
[18:40:37.902] <TB0> INFO: event ID mismatches: 0
[18:40:37.902] <TB0> INFO: ROC errors: 0
[18:40:37.902] <TB0> INFO: missing ROC header(s): 0
[18:40:37.903] <TB0> INFO: misplaced readback start: 0
[18:40:37.903] <TB0> INFO: Pixel decoding errors: 0
[18:40:37.903] <TB0> INFO: pixel data incomplete: 0
[18:40:37.903] <TB0> INFO: pixel address: 0
[18:40:37.903] <TB0> INFO: pulse height fill bit: 0
[18:40:37.903] <TB0> INFO: buffer corruption: 0
[18:40:37.971] <TB0> INFO: ######################################################################
[18:40:37.971] <TB0> INFO: PixTestTrim::doTest()
[18:40:37.971] <TB0> INFO: ######################################################################
[18:40:37.973] <TB0> INFO: ----------------------------------------------------------------------
[18:40:37.973] <TB0> INFO: PixTestTrim::trimTest() ntrig = 8, vcal = 35
[18:40:37.973] <TB0> INFO: ----------------------------------------------------------------------
[18:40:38.015] <TB0> INFO: ---> VthrComp thr map (minimal VthrComp)
[18:40:38.015] <TB0> INFO: ---> dac: vthrcomp name: TrimThr0 ntrig: 8 dacrange: 10 .. 160 (-1/-1) hits flags = 528 (plus default)
[18:40:38.030] <TB0> INFO: dacScan split into 1 runs with ntrig = 8
[18:40:38.030] <TB0> INFO: run 1 of 1
[18:40:38.337] <TB0> INFO: Expecting 5025280 events.
[18:41:09.870] <TB0> INFO: 821336 events read in total (30928ms).
[18:41:40.045] <TB0> INFO: 1639976 events read in total (61104ms).
[18:42:09.996] <TB0> INFO: 2457592 events read in total (91054ms).
[18:42:39.949] <TB0> INFO: 3269176 events read in total (121007ms).
[18:43:10.126] <TB0> INFO: 4079376 events read in total (151185ms).
[18:43:40.185] <TB0> INFO: 4887328 events read in total (181243ms).
[18:43:45.564] <TB0> INFO: 5025280 events read in total (186622ms).
[18:43:45.633] <TB0> INFO: Test took 187603ms.
[18:44:04.280] <TB0> INFO: ROC 0 VthrComp = 125
[18:44:04.281] <TB0> INFO: ROC 1 VthrComp = 109
[18:44:04.281] <TB0> INFO: ROC 2 VthrComp = 122
[18:44:04.281] <TB0> INFO: ROC 3 VthrComp = 108
[18:44:04.281] <TB0> INFO: ROC 4 VthrComp = 116
[18:44:04.281] <TB0> INFO: ROC 5 VthrComp = 132
[18:44:04.281] <TB0> INFO: ROC 6 VthrComp = 122
[18:44:04.281] <TB0> INFO: ROC 7 VthrComp = 121
[18:44:04.281] <TB0> INFO: ROC 8 VthrComp = 123
[18:44:04.281] <TB0> INFO: ROC 9 VthrComp = 131
[18:44:04.282] <TB0> INFO: ROC 10 VthrComp = 122
[18:44:04.282] <TB0> INFO: ROC 11 VthrComp = 126
[18:44:04.282] <TB0> INFO: ROC 12 VthrComp = 118
[18:44:04.282] <TB0> INFO: ROC 13 VthrComp = 106
[18:44:04.282] <TB0> INFO: ROC 14 VthrComp = 124
[18:44:04.282] <TB0> INFO: ROC 15 VthrComp = 117
[18:44:04.522] <TB0> INFO: Expecting 41600 events.
[18:44:08.156] <TB0> INFO: 41600 events read in total (3042ms).
[18:44:08.157] <TB0> INFO: Test took 3872ms.
[18:44:08.166] <TB0> INFO: ---> Vcal thr map (pixel with maximum Vcal thr)
[18:44:08.166] <TB0> INFO: ---> dac: vcal name: TrimThr1 ntrig: 8 dacrange: 10 .. 160 (-1/-1) hits flags = 528 (plus default)
[18:44:08.180] <TB0> INFO: dacScan split into 1 runs with ntrig = 8
[18:44:08.180] <TB0> INFO: run 1 of 1
[18:44:08.458] <TB0> INFO: Expecting 5025280 events.
[18:44:35.160] <TB0> INFO: 589632 events read in total (26110ms).
[18:45:00.753] <TB0> INFO: 1177544 events read in total (51703ms).
[18:45:26.735] <TB0> INFO: 1766104 events read in total (77685ms).
[18:45:52.958] <TB0> INFO: 2353960 events read in total (103908ms).
[18:46:18.940] <TB0> INFO: 2940336 events read in total (129890ms).
[18:46:45.161] <TB0> INFO: 3525648 events read in total (156111ms).
[18:47:10.987] <TB0> INFO: 4110456 events read in total (181937ms).
[18:47:36.444] <TB0> INFO: 4694968 events read in total (207394ms).
[18:47:51.437] <TB0> INFO: 5025280 events read in total (222387ms).
[18:47:51.574] <TB0> INFO: Test took 223394ms.
[18:48:17.125] <TB0> INFO: roc 0 with ID = 0 has maximal Vcal 59.7762 for pixel 0/50 mean/min/max = 45.8195/31.8212/59.8177
[18:48:17.126] <TB0> INFO: roc 1 with ID = 1 has maximal Vcal 59.2747 for pixel 20/10 mean/min/max = 47.0616/34.8136/59.3095
[18:48:17.127] <TB0> INFO: roc 2 with ID = 2 has maximal Vcal 60.3628 for pixel 4/19 mean/min/max = 46.1307/31.8088/60.4526
[18:48:17.127] <TB0> INFO: roc 3 with ID = 3 has maximal Vcal 70.8171 for pixel 2/3 mean/min/max = 50.8658/30.5774/71.1543
[18:48:17.128] <TB0> INFO: roc 4 with ID = 4 has maximal Vcal 60.8645 for pixel 0/23 mean/min/max = 46.2213/31.4685/60.9741
[18:48:17.128] <TB0> INFO: roc 5 with ID = 5 has maximal Vcal 61.9613 for pixel 0/0 mean/min/max = 47.7786/33.5692/61.9881
[18:48:17.129] <TB0> INFO: roc 6 with ID = 6 has maximal Vcal 56.4261 for pixel 47/4 mean/min/max = 44.6506/32.7984/56.5028
[18:48:17.129] <TB0> INFO: roc 7 with ID = 7 has maximal Vcal 58.3418 for pixel 2/4 mean/min/max = 45.1968/32.0431/58.3505
[18:48:17.130] <TB0> INFO: roc 8 with ID = 8 has maximal Vcal 56.6639 for pixel 0/13 mean/min/max = 44.435/32.0304/56.8397
[18:48:17.131] <TB0> INFO: roc 9 with ID = 9 has maximal Vcal 66.5737 for pixel 0/2 mean/min/max = 49.3705/31.8173/66.9238
[18:48:17.131] <TB0> INFO: roc 10 with ID = 10 has maximal Vcal 59.8067 for pixel 0/42 mean/min/max = 45.9669/31.86/60.0738
[18:48:17.132] <TB0> INFO: roc 11 with ID = 11 has maximal Vcal 58.598 for pixel 14/13 mean/min/max = 44.9362/30.9572/58.9153
[18:48:17.132] <TB0> INFO: roc 12 with ID = 12 has maximal Vcal 61.8188 for pixel 20/46 mean/min/max = 46.4722/31.0224/61.9219
[18:48:17.133] <TB0> INFO: roc 13 with ID = 13 has maximal Vcal 60.9837 for pixel 6/0 mean/min/max = 47.8031/34.5081/61.0982
[18:48:17.133] <TB0> INFO: roc 14 with ID = 14 has maximal Vcal 62.9385 for pixel 10/6 mean/min/max = 46.4233/29.8467/62.9999
[18:48:17.134] <TB0> INFO: roc 15 with ID = 15 has maximal Vcal 59.9037 for pixel 12/2 mean/min/max = 45.8628/31.6449/60.0808
[18:48:17.134] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[18:48:17.224] <TB0> INFO: Expecting 411648 events.
[18:48:26.980] <TB0> INFO: 411648 events read in total (9161ms).
[18:48:26.989] <TB0> INFO: Expecting 411648 events.
[18:48:36.570] <TB0> INFO: 411648 events read in total (9177ms).
[18:48:36.581] <TB0> INFO: Expecting 411648 events.
[18:48:45.770] <TB0> INFO: 411648 events read in total (8786ms).
[18:48:45.788] <TB0> INFO: Expecting 411648 events.
[18:48:55.050] <TB0> INFO: 411648 events read in total (8859ms).
[18:48:55.067] <TB0> INFO: Expecting 411648 events.
[18:49:04.371] <TB0> INFO: 411648 events read in total (8901ms).
[18:49:04.390] <TB0> INFO: Expecting 411648 events.
[18:49:13.649] <TB0> INFO: 411648 events read in total (8856ms).
[18:49:13.671] <TB0> INFO: Expecting 411648 events.
[18:49:23.040] <TB0> INFO: 411648 events read in total (8967ms).
[18:49:23.064] <TB0> INFO: Expecting 411648 events.
[18:49:32.368] <TB0> INFO: 411648 events read in total (8901ms).
[18:49:32.396] <TB0> INFO: Expecting 411648 events.
[18:49:41.665] <TB0> INFO: 411648 events read in total (8866ms).
[18:49:41.695] <TB0> INFO: Expecting 411648 events.
[18:49:50.972] <TB0> INFO: 411648 events read in total (8874ms).
[18:49:51.004] <TB0> INFO: Expecting 411648 events.
[18:50:00.296] <TB0> INFO: 411648 events read in total (8889ms).
[18:50:00.338] <TB0> INFO: Expecting 411648 events.
[18:50:09.587] <TB0> INFO: 411648 events read in total (8845ms).
[18:50:09.629] <TB0> INFO: Expecting 411648 events.
[18:50:18.905] <TB0> INFO: 411648 events read in total (8873ms).
[18:50:18.954] <TB0> INFO: Expecting 411648 events.
[18:50:28.244] <TB0> INFO: 411648 events read in total (8886ms).
[18:50:28.289] <TB0> INFO: Expecting 411648 events.
[18:50:37.602] <TB0> INFO: 411648 events read in total (8911ms).
[18:50:37.659] <TB0> INFO: Expecting 411648 events.
[18:50:46.939] <TB0> INFO: 411648 events read in total (8877ms).
[18:50:46.990] <TB0> INFO: Test took 149856ms.
[18:50:47.728] <TB0> INFO: ---> dac: vcal name: TrimThr2 ntrig: 8 dacrange: 0 .. 150 (-1/-1) hits flags = 528 (plus default)
[18:50:47.742] <TB0> INFO: dacScan split into 1 runs with ntrig = 8
[18:50:47.742] <TB0> INFO: run 1 of 1
[18:50:47.984] <TB0> INFO: Expecting 5025280 events.
[18:51:14.719] <TB0> INFO: 586288 events read in total (26143ms).
[18:51:40.791] <TB0> INFO: 1170112 events read in total (52215ms).
[18:52:06.629] <TB0> INFO: 1752656 events read in total (78053ms).
[18:52:32.669] <TB0> INFO: 2335856 events read in total (104093ms).
[18:52:58.728] <TB0> INFO: 2917208 events read in total (130152ms).
[18:53:25.113] <TB0> INFO: 3499016 events read in total (156537ms).
[18:53:51.479] <TB0> INFO: 4080976 events read in total (182903ms).
[18:54:18.066] <TB0> INFO: 4664912 events read in total (209490ms).
[18:54:34.750] <TB0> INFO: 5025280 events read in total (226174ms).
[18:54:34.886] <TB0> INFO: Test took 227146ms.
[18:54:56.240] <TB0> INFO: ---> TrimStepCorr4 extremal thresholds: 2.077844 .. 147.610635
[18:54:56.487] <TB0> INFO: Expecting 208000 events.
[18:55:06.584] <TB0> INFO: 208000 events read in total (9505ms).
[18:55:06.586] <TB0> INFO: Test took 10345ms.
[18:55:06.666] <TB0> INFO: ---> dac: vcal name: trimStepCorr4 ntrig: 8 dacrange: 2 .. 157 (-1/-1) hits flags = 528 (plus default)
[18:55:06.680] <TB0> INFO: dacScan split into 1 runs with ntrig = 8
[18:55:06.680] <TB0> INFO: run 1 of 1
[18:55:07.005] <TB0> INFO: Expecting 5191680 events.
[18:55:33.650] <TB0> INFO: 582144 events read in total (26053ms).
[18:55:59.865] <TB0> INFO: 1164192 events read in total (52268ms).
[18:56:25.742] <TB0> INFO: 1746600 events read in total (78145ms).
[18:56:51.436] <TB0> INFO: 2328592 events read in total (103840ms).
[18:57:17.359] <TB0> INFO: 2911096 events read in total (129762ms).
[18:57:42.959] <TB0> INFO: 3492688 events read in total (155362ms).
[18:58:08.953] <TB0> INFO: 4074272 events read in total (181357ms).
[18:58:35.206] <TB0> INFO: 4655272 events read in total (207609ms).
[18:58:58.881] <TB0> INFO: 5191680 events read in total (231284ms).
[18:58:58.996] <TB0> INFO: Test took 232317ms.
[18:59:22.968] <TB0> INFO: ---> TrimStepCorr2 extremal thresholds: 27.500000 .. 47.026160
[18:59:23.254] <TB0> INFO: Expecting 208000 events.
[18:59:33.318] <TB0> INFO: 208000 events read in total (9472ms).
[18:59:33.320] <TB0> INFO: Test took 10351ms.
[18:59:33.402] <TB0> INFO: ---> dac: vcal name: trimStepCorr2 ntrig: 8 dacrange: 17 .. 57 (-1/-1) hits flags = 528 (plus default)
[18:59:33.416] <TB0> INFO: dacScan split into 1 runs with ntrig = 8
[18:59:33.416] <TB0> INFO: run 1 of 1
[18:59:33.748] <TB0> INFO: Expecting 1364480 events.
[19:00:02.430] <TB0> INFO: 649928 events read in total (28090ms).
[19:00:30.549] <TB0> INFO: 1299424 events read in total (56210ms).
[19:00:33.653] <TB0> INFO: 1364480 events read in total (59313ms).
[19:00:33.684] <TB0> INFO: Test took 60269ms.
[19:00:45.596] <TB0> INFO: ---> TrimStepCorr1a extremal thresholds: 26.308687 .. 47.174902
[19:00:45.837] <TB0> INFO: Expecting 208000 events.
[19:00:55.837] <TB0> INFO: 208000 events read in total (9408ms).
[19:00:55.838] <TB0> INFO: Test took 10240ms.
[19:00:55.907] <TB0> INFO: ---> dac: vcal name: trimStepCorr1a ntrig: 8 dacrange: 16 .. 57 (-1/-1) hits flags = 528 (plus default)
[19:00:55.921] <TB0> INFO: dacScan split into 1 runs with ntrig = 8
[19:00:55.921] <TB0> INFO: run 1 of 1
[19:00:56.200] <TB0> INFO: Expecting 1397760 events.
[19:01:24.907] <TB0> INFO: 655032 events read in total (28116ms).
[19:01:52.592] <TB0> INFO: 1309200 events read in total (55801ms).
[19:01:56.681] <TB0> INFO: 1397760 events read in total (59890ms).
[19:01:56.725] <TB0> INFO: Test took 60804ms.
[19:02:09.558] <TB0> INFO: ---> TrimStepCorr1b extremal thresholds: 23.912796 .. 45.402499
[19:02:09.826] <TB0> INFO: Expecting 208000 events.
[19:02:19.769] <TB0> INFO: 208000 events read in total (9351ms).
[19:02:19.770] <TB0> INFO: Test took 10211ms.
[19:02:19.829] <TB0> INFO: ---> dac: vcal name: trimStepCorr1b ntrig: 8 dacrange: 13 .. 55 (-1/-1) hits flags = 528 (plus default)
[19:02:19.844] <TB0> INFO: dacScan split into 1 runs with ntrig = 8
[19:02:19.844] <TB0> INFO: run 1 of 1
[19:02:20.167] <TB0> INFO: Expecting 1431040 events.
[19:02:49.544] <TB0> INFO: 674712 events read in total (28784ms).
[19:03:17.883] <TB0> INFO: 1349792 events read in total (57124ms).
[19:03:21.647] <TB0> INFO: 1431040 events read in total (60887ms).
[19:03:21.676] <TB0> INFO: Test took 61832ms.
[19:03:34.045] <TB0> INFO: ---> TrimThrFinal extremal thresholds: 15 .. 55
[19:03:34.045] <TB0> INFO: ---> dac: vcal name: TrimThrFinal ntrig: 8 dacrange: 15 .. 55 (-1/-1) hits flags = 528 (plus default)
[19:03:34.059] <TB0> INFO: dacScan split into 1 runs with ntrig = 8
[19:03:34.059] <TB0> INFO: run 1 of 1
[19:03:34.342] <TB0> INFO: Expecting 1364480 events.
[19:04:04.003] <TB0> INFO: 667280 events read in total (29069ms).
[19:04:31.970] <TB0> INFO: 1334056 events read in total (57036ms).
[19:04:33.647] <TB0> INFO: 1364480 events read in total (58714ms).
[19:04:33.674] <TB0> INFO: Test took 59616ms.
[19:04:45.784] <TB0> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1116_FullQualification_2016-11-04_17h17m_1478276226//001_Fulltest_m20//dacParameters35_C0.dat
[19:04:45.785] <TB0> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1116_FullQualification_2016-11-04_17h17m_1478276226//001_Fulltest_m20//dacParameters35_C1.dat
[19:04:45.785] <TB0> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1116_FullQualification_2016-11-04_17h17m_1478276226//001_Fulltest_m20//dacParameters35_C2.dat
[19:04:45.785] <TB0> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1116_FullQualification_2016-11-04_17h17m_1478276226//001_Fulltest_m20//dacParameters35_C3.dat
[19:04:45.785] <TB0> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1116_FullQualification_2016-11-04_17h17m_1478276226//001_Fulltest_m20//dacParameters35_C4.dat
[19:04:45.785] <TB0> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1116_FullQualification_2016-11-04_17h17m_1478276226//001_Fulltest_m20//dacParameters35_C5.dat
[19:04:45.785] <TB0> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1116_FullQualification_2016-11-04_17h17m_1478276226//001_Fulltest_m20//dacParameters35_C6.dat
[19:04:45.785] <TB0> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1116_FullQualification_2016-11-04_17h17m_1478276226//001_Fulltest_m20//dacParameters35_C7.dat
[19:04:45.785] <TB0> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1116_FullQualification_2016-11-04_17h17m_1478276226//001_Fulltest_m20//dacParameters35_C8.dat
[19:04:45.785] <TB0> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1116_FullQualification_2016-11-04_17h17m_1478276226//001_Fulltest_m20//dacParameters35_C9.dat
[19:04:45.785] <TB0> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1116_FullQualification_2016-11-04_17h17m_1478276226//001_Fulltest_m20//dacParameters35_C10.dat
[19:04:45.785] <TB0> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1116_FullQualification_2016-11-04_17h17m_1478276226//001_Fulltest_m20//dacParameters35_C11.dat
[19:04:45.786] <TB0> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1116_FullQualification_2016-11-04_17h17m_1478276226//001_Fulltest_m20//dacParameters35_C12.dat
[19:04:45.786] <TB0> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1116_FullQualification_2016-11-04_17h17m_1478276226//001_Fulltest_m20//dacParameters35_C13.dat
[19:04:45.786] <TB0> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1116_FullQualification_2016-11-04_17h17m_1478276226//001_Fulltest_m20//dacParameters35_C14.dat
[19:04:45.786] <TB0> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1116_FullQualification_2016-11-04_17h17m_1478276226//001_Fulltest_m20//dacParameters35_C15.dat
[19:04:45.786] <TB0> INFO: write trim parameters into /home/l_tester/david/FullQualification/data/M1116_FullQualification_2016-11-04_17h17m_1478276226//001_Fulltest_m20//trimParameters35_C0.dat
[19:04:45.791] <TB0> INFO: write trim parameters into /home/l_tester/david/FullQualification/data/M1116_FullQualification_2016-11-04_17h17m_1478276226//001_Fulltest_m20//trimParameters35_C1.dat
[19:04:45.795] <TB0> INFO: write trim parameters into /home/l_tester/david/FullQualification/data/M1116_FullQualification_2016-11-04_17h17m_1478276226//001_Fulltest_m20//trimParameters35_C2.dat
[19:04:45.800] <TB0> INFO: write trim parameters into /home/l_tester/david/FullQualification/data/M1116_FullQualification_2016-11-04_17h17m_1478276226//001_Fulltest_m20//trimParameters35_C3.dat
[19:04:45.805] <TB0> INFO: write trim parameters into /home/l_tester/david/FullQualification/data/M1116_FullQualification_2016-11-04_17h17m_1478276226//001_Fulltest_m20//trimParameters35_C4.dat
[19:04:45.810] <TB0> INFO: write trim parameters into /home/l_tester/david/FullQualification/data/M1116_FullQualification_2016-11-04_17h17m_1478276226//001_Fulltest_m20//trimParameters35_C5.dat
[19:04:45.815] <TB0> INFO: write trim parameters into /home/l_tester/david/FullQualification/data/M1116_FullQualification_2016-11-04_17h17m_1478276226//001_Fulltest_m20//trimParameters35_C6.dat
[19:04:45.819] <TB0> INFO: write trim parameters into /home/l_tester/david/FullQualification/data/M1116_FullQualification_2016-11-04_17h17m_1478276226//001_Fulltest_m20//trimParameters35_C7.dat
[19:04:45.824] <TB0> INFO: write trim parameters into /home/l_tester/david/FullQualification/data/M1116_FullQualification_2016-11-04_17h17m_1478276226//001_Fulltest_m20//trimParameters35_C8.dat
[19:04:45.829] <TB0> INFO: write trim parameters into /home/l_tester/david/FullQualification/data/M1116_FullQualification_2016-11-04_17h17m_1478276226//001_Fulltest_m20//trimParameters35_C9.dat
[19:04:45.833] <TB0> INFO: write trim parameters into /home/l_tester/david/FullQualification/data/M1116_FullQualification_2016-11-04_17h17m_1478276226//001_Fulltest_m20//trimParameters35_C10.dat
[19:04:45.838] <TB0> INFO: write trim parameters into /home/l_tester/david/FullQualification/data/M1116_FullQualification_2016-11-04_17h17m_1478276226//001_Fulltest_m20//trimParameters35_C11.dat
[19:04:45.843] <TB0> INFO: write trim parameters into /home/l_tester/david/FullQualification/data/M1116_FullQualification_2016-11-04_17h17m_1478276226//001_Fulltest_m20//trimParameters35_C12.dat
[19:04:45.848] <TB0> INFO: write trim parameters into /home/l_tester/david/FullQualification/data/M1116_FullQualification_2016-11-04_17h17m_1478276226//001_Fulltest_m20//trimParameters35_C13.dat
[19:04:45.853] <TB0> INFO: write trim parameters into /home/l_tester/david/FullQualification/data/M1116_FullQualification_2016-11-04_17h17m_1478276226//001_Fulltest_m20//trimParameters35_C14.dat
[19:04:45.858] <TB0> INFO: write trim parameters into /home/l_tester/david/FullQualification/data/M1116_FullQualification_2016-11-04_17h17m_1478276226//001_Fulltest_m20//trimParameters35_C15.dat
[19:04:45.862] <TB0> INFO: PixTestTrim::trimTest() done
[19:04:45.862] <TB0> INFO: vtrim: 127 143 128 157 120 124 127 131 112 150 113 117 122 125 131 122
[19:04:45.862] <TB0> INFO: vthrcomp: 125 109 122 108 116 132 122 121 123 131 122 126 118 106 124 117
[19:04:45.862] <TB0> INFO: vcal mean: 34.99 34.98 34.96 35.00 34.94 35.05 34.97 34.98 34.90 35.37 34.94 34.93 34.99 35.05 34.95 34.95
[19:04:45.862] <TB0> INFO: vcal RMS: 1.03 1.01 1.28 1.09 1.00 1.04 0.98 1.02 0.99 1.51 1.06 1.09 1.23 0.99 1.23 1.08
[19:04:45.862] <TB0> INFO: bits mean: 9.28 9.23 9.42 8.94 8.71 7.98 9.67 9.50 9.73 9.28 9.45 9.92 9.75 8.67 9.87 9.77
[19:04:45.862] <TB0> INFO: bits RMS: 2.88 2.39 2.69 2.77 3.07 2.97 2.55 2.74 2.69 2.72 2.74 2.67 2.66 2.61 2.73 2.64
[19:04:45.870] <TB0> INFO: ----------------------------------------------------------------------
[19:04:45.870] <TB0> INFO: PixTestTrim::trimBitTest() ntrig = 5, vtrims = 254 126 63 32
[19:04:45.870] <TB0> INFO: ----------------------------------------------------------------------
[19:04:45.873] <TB0> INFO: ---> dac: Vcal name: TrimBitsThr0 ntrig: 5 dacrange: 0 .. 199 (-1/-1) hits flags = 528 (plus default)
[19:04:45.885] <TB0> INFO: dacScan split into 1 runs with ntrig = 5
[19:04:45.885] <TB0> INFO: run 1 of 1
[19:04:46.122] <TB0> INFO: Expecting 4160000 events.
[19:05:28.182] <TB0> INFO: 744975 events read in total (41469ms).
[19:06:31.347] <TB0> INFO: 1485760 events read in total (104634ms).
[19:07:46.633] <TB0> INFO: 2223795 events read in total (179920ms).
[19:08:18.571] <TB0> INFO: 2957975 events read in total (211858ms).
[19:08:50.440] <TB0> INFO: 3688745 events read in total (243727ms).
[19:09:11.242] <TB0> INFO: 4160000 events read in total (264529ms).
[19:09:11.328] <TB0> INFO: Test took 265443ms.
[19:09:34.762] <TB0> INFO: ---> dac: Vcal name: TrimThr_trim14 ntrig: 5 dacrange: 0 .. 205 (-1/-1) hits flags = 528 (plus default)
[19:09:34.776] <TB0> INFO: dacScan split into 1 runs with ntrig = 5
[19:09:34.776] <TB0> INFO: run 1 of 1
[19:09:35.076] <TB0> INFO: Expecting 4284800 events.
[19:10:08.149] <TB0> INFO: 713965 events read in total (32481ms).
[19:10:39.747] <TB0> INFO: 1424785 events read in total (64079ms).
[19:11:11.368] <TB0> INFO: 2133765 events read in total (95700ms).
[19:11:43.159] <TB0> INFO: 2838405 events read in total (127491ms).
[19:12:14.573] <TB0> INFO: 3540560 events read in total (158905ms).
[19:12:46.387] <TB0> INFO: 4243275 events read in total (190719ms).
[19:12:48.694] <TB0> INFO: 4284800 events read in total (193026ms).
[19:12:48.803] <TB0> INFO: Test took 194027ms.
[19:13:18.017] <TB0> INFO: ---> dac: Vcal name: TrimThr_trim13 ntrig: 5 dacrange: 0 .. 207 (-1/-1) hits flags = 528 (plus default)
[19:13:18.032] <TB0> INFO: dacScan split into 1 runs with ntrig = 5
[19:13:18.032] <TB0> INFO: run 1 of 1
[19:13:18.318] <TB0> INFO: Expecting 4326400 events.
[19:13:50.947] <TB0> INFO: 712080 events read in total (32038ms).
[19:14:22.344] <TB0> INFO: 1421000 events read in total (63435ms).
[19:14:53.610] <TB0> INFO: 2127740 events read in total (94701ms).
[19:15:24.953] <TB0> INFO: 2830645 events read in total (126045ms).
[19:15:55.987] <TB0> INFO: 3530670 events read in total (157078ms).
[19:16:27.238] <TB0> INFO: 4230595 events read in total (188329ms).
[19:16:31.716] <TB0> INFO: 4326400 events read in total (192807ms).
[19:16:31.804] <TB0> INFO: Test took 193772ms.
[19:17:07.795] <TB0> INFO: ---> dac: Vcal name: TrimThr_trim11 ntrig: 5 dacrange: 0 .. 207 (-1/-1) hits flags = 528 (plus default)
[19:17:07.810] <TB0> INFO: dacScan split into 1 runs with ntrig = 5
[19:17:07.810] <TB0> INFO: run 1 of 1
[19:17:08.059] <TB0> INFO: Expecting 4326400 events.
[19:18:25.538] <TB0> INFO: 712090 events read in total (76880ms).
[19:19:30.056] <TB0> INFO: 1421050 events read in total (141398ms).
[19:20:10.023] <TB0> INFO: 2127930 events read in total (181365ms).
[19:21:16.779] <TB0> INFO: 2831220 events read in total (248121ms).
[19:21:49.917] <TB0> INFO: 3531465 events read in total (281259ms).
[19:23:14.245] <TB0> INFO: 4231540 events read in total (365587ms).
[19:23:18.923] <TB0> INFO: 4326400 events read in total (370265ms).
[19:23:19.041] <TB0> INFO: Test took 371229ms.
[19:24:24.379] <TB0> INFO: ---> dac: Vcal name: TrimThr_trim7 ntrig: 5 dacrange: 0 .. 194 (-1/-1) hits flags = 528 (plus default)
[19:24:24.407] <TB0> INFO: dacScan split into 1 runs with ntrig = 5
[19:24:24.415] <TB0> INFO: run 1 of 1
[19:24:25.463] <TB0> INFO: Expecting 4056000 events.
[19:24:58.178] <TB0> INFO: 728630 events read in total (32015ms).
[19:26:15.473] <TB0> INFO: 1453165 events read in total (109310ms).
[19:27:15.656] <TB0> INFO: 2175170 events read in total (169494ms).
[19:27:49.301] <TB0> INFO: 2893125 events read in total (203138ms).
[19:29:11.978] <TB0> INFO: 3607925 events read in total (285815ms).
[19:29:35.426] <TB0> INFO: 4056000 events read in total (309263ms).
[19:29:35.561] <TB0> INFO: Test took 311138ms.
[19:30:39.682] <TB0> INFO: PixTestTrim::trimBitTest() done
[19:30:39.683] <TB0> INFO: PixTestTrim::doTest() done, duration: 3001 seconds
[19:30:39.683] <TB0> INFO: Decoding statistics:
[19:30:39.683] <TB0> INFO: General information:
[19:30:39.683] <TB0> INFO: 16bit words read: 0
[19:30:39.683] <TB0> INFO: valid events total: 0
[19:30:39.683] <TB0> INFO: empty events: 0
[19:30:39.683] <TB0> INFO: valid events with pixels: 0
[19:30:39.683] <TB0> INFO: valid pixel hits: 0
[19:30:39.683] <TB0> INFO: Event errors: 0
[19:30:39.683] <TB0> INFO: start marker: 0
[19:30:39.683] <TB0> INFO: stop marker: 0
[19:30:39.684] <TB0> INFO: overflow: 0
[19:30:39.684] <TB0> INFO: invalid 5bit words: 0
[19:30:39.684] <TB0> INFO: invalid XOR eye diagram: 0
[19:30:39.684] <TB0> INFO: frame (failed synchr.): 0
[19:30:39.684] <TB0> INFO: idle data (no TBM trl): 0
[19:30:39.684] <TB0> INFO: no data (only TBM hdr): 0
[19:30:39.684] <TB0> INFO: TBM errors: 0
[19:30:39.684] <TB0> INFO: flawed TBM headers: 0
[19:30:39.684] <TB0> INFO: flawed TBM trailers: 0
[19:30:39.684] <TB0> INFO: event ID mismatches: 0
[19:30:39.684] <TB0> INFO: ROC errors: 0
[19:30:39.684] <TB0> INFO: missing ROC header(s): 0
[19:30:39.684] <TB0> INFO: misplaced readback start: 0
[19:30:39.684] <TB0> INFO: Pixel decoding errors: 0
[19:30:39.684] <TB0> INFO: pixel data incomplete: 0
[19:30:39.684] <TB0> INFO: pixel address: 0
[19:30:39.684] <TB0> INFO: pulse height fill bit: 0
[19:30:39.684] <TB0> INFO: buffer corruption: 0
[19:30:40.406] <TB0> INFO: ######################################################################
[19:30:40.406] <TB0> INFO: PixTestPhOptimization::doTest() Ntrig = 10
[19:30:40.406] <TB0> INFO: ######################################################################
[19:30:40.649] <TB0> INFO: Expecting 41600 events.
[19:30:44.189] <TB0> INFO: 41600 events read in total (2948ms).
[19:30:44.191] <TB0> INFO: Test took 3784ms.
[19:30:44.636] <TB0> INFO: Expecting 41600 events.
[19:30:48.370] <TB0> INFO: 41600 events read in total (3142ms).
[19:30:48.372] <TB0> INFO: Test took 3975ms.
[19:30:48.695] <TB0> INFO: Expecting 41600 events.
[19:30:52.327] <TB0> INFO: 41600 events read in total (3041ms).
[19:30:52.327] <TB0> INFO: Test took 3926ms.
[19:30:52.617] <TB0> INFO: Expecting 41600 events.
[19:30:56.287] <TB0> INFO: 41600 events read in total (3078ms).
[19:30:56.287] <TB0> INFO: Test took 3935ms.
[19:30:56.608] <TB0> INFO: Expecting 41600 events.
[19:31:00.164] <TB0> INFO: 41600 events read in total (2965ms).
[19:31:00.165] <TB0> INFO: Test took 3853ms.
[19:31:00.461] <TB0> INFO: Expecting 41600 events.
[19:31:04.199] <TB0> INFO: 41600 events read in total (3146ms).
[19:31:04.201] <TB0> INFO: Test took 4011ms.
[19:31:04.795] <TB0> INFO: Expecting 41600 events.
[19:31:13.444] <TB0> INFO: 41600 events read in total (8009ms).
[19:31:13.446] <TB0> INFO: Test took 9197ms.
[19:31:13.787] <TB0> INFO: Expecting 41600 events.
[19:31:21.168] <TB0> INFO: 41600 events read in total (6789ms).
[19:31:21.176] <TB0> INFO: Test took 7701ms.
[19:31:22.619] <TB0> INFO: Expecting 41600 events.
[19:31:41.228] <TB0> INFO: 41600 events read in total (17869ms).
[19:31:41.230] <TB0> INFO: Test took 19610ms.
[19:31:41.522] <TB0> INFO: Expecting 41600 events.
[19:31:53.799] <TB0> INFO: 41600 events read in total (11685ms).
[19:31:53.806] <TB0> INFO: Test took 12549ms.
[19:31:54.599] <TB0> INFO: Expecting 41600 events.
[19:32:06.685] <TB0> INFO: 41600 events read in total (11394ms).
[19:32:06.687] <TB0> INFO: Test took 12784ms.
[19:32:06.983] <TB0> INFO: Expecting 41600 events.
[19:32:10.471] <TB0> INFO: 41600 events read in total (2896ms).
[19:32:10.471] <TB0> INFO: Test took 3755ms.
[19:32:10.762] <TB0> INFO: Expecting 41600 events.
[19:32:14.316] <TB0> INFO: 41600 events read in total (2962ms).
[19:32:14.317] <TB0> INFO: Test took 3821ms.
[19:32:14.631] <TB0> INFO: Expecting 41600 events.
[19:32:18.196] <TB0> INFO: 41600 events read in total (2974ms).
[19:32:18.197] <TB0> INFO: Test took 3838ms.
[19:32:18.486] <TB0> INFO: Expecting 41600 events.
[19:32:22.095] <TB0> INFO: 41600 events read in total (3017ms).
[19:32:22.096] <TB0> INFO: Test took 3875ms.
[19:32:22.422] <TB0> INFO: Expecting 41600 events.
[19:32:25.961] <TB0> INFO: 41600 events read in total (2947ms).
[19:32:25.961] <TB0> INFO: Test took 3836ms.
[19:32:26.252] <TB0> INFO: Expecting 41600 events.
[19:32:29.823] <TB0> INFO: 41600 events read in total (2980ms).
[19:32:29.824] <TB0> INFO: Test took 3837ms.
[19:32:30.114] <TB0> INFO: Expecting 41600 events.
[19:32:41.032] <TB0> INFO: 41600 events read in total (10326ms).
[19:32:41.033] <TB0> INFO: Test took 11184ms.
[19:32:41.386] <TB0> INFO: Expecting 41600 events.
[19:32:48.245] <TB0> INFO: 41600 events read in total (6198ms).
[19:32:48.252] <TB0> INFO: Test took 7190ms.
[19:32:49.927] <TB0> INFO: Expecting 41600 events.
[19:33:06.729] <TB0> INFO: 41600 events read in total (16110ms).
[19:33:06.730] <TB0> INFO: Test took 17875ms.
[19:33:07.212] <TB0> INFO: Expecting 41600 events.
[19:33:10.918] <TB0> INFO: 41600 events read in total (3108ms).
[19:33:10.919] <TB0> INFO: Test took 4156ms.
[19:33:11.212] <TB0> INFO: Expecting 41600 events.
[19:33:30.573] <TB0> INFO: 41600 events read in total (18769ms).
[19:33:30.584] <TB0> INFO: Test took 19637ms.
[19:33:31.849] <TB0> INFO: Expecting 41600 events.
[19:33:35.619] <TB0> INFO: 41600 events read in total (3178ms).
[19:33:35.620] <TB0> INFO: Test took 4453ms.
[19:33:35.975] <TB0> INFO: Expecting 41600 events.
[19:33:39.642] <TB0> INFO: 41600 events read in total (3076ms).
[19:33:39.643] <TB0> INFO: Test took 3997ms.
[19:33:39.942] <TB0> INFO: Expecting 41600 events.
[19:33:43.479] <TB0> INFO: 41600 events read in total (2945ms).
[19:33:43.480] <TB0> INFO: Test took 3812ms.
[19:33:43.770] <TB0> INFO: Expecting 41600 events.
[19:33:47.316] <TB0> INFO: 41600 events read in total (2954ms).
[19:33:47.317] <TB0> INFO: Test took 3812ms.
[19:33:47.607] <TB0> INFO: Expecting 41600 events.
[19:33:51.135] <TB0> INFO: 41600 events read in total (2936ms).
[19:33:51.136] <TB0> INFO: Test took 3794ms.
[19:33:51.454] <TB0> INFO: Expecting 41600 events.
[19:33:55.028] <TB0> INFO: 41600 events read in total (2982ms).
[19:33:55.029] <TB0> INFO: Test took 3865ms.
[19:33:55.359] <TB0> INFO: Expecting 41600 events.
[19:33:58.889] <TB0> INFO: 41600 events read in total (2938ms).
[19:33:58.889] <TB0> INFO: Test took 3808ms.
[19:33:59.181] <TB0> INFO: Expecting 41600 events.
[19:34:02.723] <TB0> INFO: 41600 events read in total (2951ms).
[19:34:02.724] <TB0> INFO: Test took 3808ms.
[19:34:03.021] <TB0> INFO: Expecting 2560 events.
[19:34:03.905] <TB0> INFO: 2560 events read in total (292ms).
[19:34:03.905] <TB0> INFO: Test took 1161ms.
[19:34:04.214] <TB0> INFO: Expecting 2560 events.
[19:34:07.168] <TB0> INFO: 2560 events read in total (2358ms).
[19:34:07.179] <TB0> INFO: Test took 3273ms.
[19:34:07.743] <TB0> INFO: Expecting 2560 events.
[19:34:08.925] <TB0> INFO: 2560 events read in total (488ms).
[19:34:08.926] <TB0> INFO: Test took 1731ms.
[19:34:09.234] <TB0> INFO: Expecting 2560 events.
[19:34:12.335] <TB0> INFO: 2560 events read in total (2476ms).
[19:34:12.343] <TB0> INFO: Test took 3416ms.
[19:34:12.855] <TB0> INFO: Expecting 2560 events.
[19:34:13.846] <TB0> INFO: 2560 events read in total (344ms).
[19:34:13.846] <TB0> INFO: Test took 1486ms.
[19:34:14.154] <TB0> INFO: Expecting 2560 events.
[19:34:15.037] <TB0> INFO: 2560 events read in total (291ms).
[19:34:15.044] <TB0> INFO: Test took 1197ms.
[19:34:15.345] <TB0> INFO: Expecting 2560 events.
[19:34:16.286] <TB0> INFO: 2560 events read in total (349ms).
[19:34:16.294] <TB0> INFO: Test took 1247ms.
[19:34:16.590] <TB0> INFO: Expecting 2560 events.
[19:34:17.555] <TB0> INFO: 2560 events read in total (307ms).
[19:34:17.555] <TB0> INFO: Test took 1257ms.
[19:34:17.911] <TB0> INFO: Expecting 2560 events.
[19:34:20.114] <TB0> INFO: 2560 events read in total (1518ms).
[19:34:20.115] <TB0> INFO: Test took 2560ms.
[19:34:20.424] <TB0> INFO: Expecting 2560 events.
[19:34:23.592] <TB0> INFO: 2560 events read in total (2547ms).
[19:34:23.606] <TB0> INFO: Test took 3488ms.
[19:34:24.211] <TB0> INFO: Expecting 2560 events.
[19:34:27.036] <TB0> INFO: 2560 events read in total (2121ms).
[19:34:27.047] <TB0> INFO: Test took 3434ms.
[19:34:27.659] <TB0> INFO: Expecting 2560 events.
[19:34:30.584] <TB0> INFO: 2560 events read in total (2213ms).
[19:34:30.585] <TB0> INFO: Test took 3526ms.
[19:34:31.091] <TB0> INFO: Expecting 2560 events.
[19:34:33.624] <TB0> INFO: 2560 events read in total (1825ms).
[19:34:33.635] <TB0> INFO: Test took 3037ms.
[19:34:34.056] <TB0> INFO: Expecting 2560 events.
[19:34:37.056] <TB0> INFO: 2560 events read in total (2399ms).
[19:34:37.067] <TB0> INFO: Test took 3416ms.
[19:34:37.467] <TB0> INFO: Expecting 2560 events.
[19:34:39.008] <TB0> INFO: 2560 events read in total (899ms).
[19:34:39.019] <TB0> INFO: Test took 1941ms.
[19:34:39.595] <TB0> INFO: Expecting 2560 events.
[19:34:40.927] <TB0> INFO: 2560 events read in total (612ms).
[19:34:40.927] <TB0> INFO: Test took 1884ms.
[19:34:40.930] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[19:34:41.291] <TB0> INFO: Expecting 655360 events.
[19:35:11.999] <TB0> INFO: 655360 events read in total (30113ms).
[19:35:12.015] <TB0> INFO: Expecting 655360 events.
[19:35:26.450] <TB0> INFO: 655360 events read in total (14031ms).
[19:35:26.474] <TB0> INFO: Expecting 655360 events.
[19:36:12.213] <TB0> INFO: 655360 events read in total (45336ms).
[19:36:12.244] <TB0> INFO: Expecting 655360 events.
[19:36:40.822] <TB0> INFO: 655360 events read in total (28173ms).
[19:36:40.864] <TB0> INFO: Expecting 655360 events.
[19:36:55.339] <TB0> INFO: 655360 events read in total (14071ms).
[19:36:55.380] <TB0> INFO: Expecting 655360 events.
[19:37:40.613] <TB0> INFO: 655360 events read in total (44830ms).
[19:37:40.664] <TB0> INFO: Expecting 655360 events.
[19:38:11.269] <TB0> INFO: 655360 events read in total (30202ms).
[19:38:11.311] <TB0> INFO: Expecting 655360 events.
[19:38:25.763] <TB0> INFO: 655360 events read in total (14049ms).
[19:38:25.812] <TB0> INFO: Expecting 655360 events.
[19:38:58.997] <TB0> INFO: 655360 events read in total (32782ms).
[19:38:59.114] <TB0> INFO: Expecting 655360 events.
[19:39:32.037] <TB0> INFO: 655360 events read in total (32514ms).
[19:39:32.150] <TB0> INFO: Expecting 655360 events.
[19:39:46.512] <TB0> INFO: 655360 events read in total (13959ms).
[19:39:46.608] <TB0> INFO: Expecting 655360 events.
[19:40:33.089] <TB0> INFO: 655360 events read in total (46078ms).
[19:40:33.232] <TB0> INFO: Expecting 655360 events.
[19:41:02.731] <TB0> INFO: 655360 events read in total (29066ms).
[19:41:02.826] <TB0> INFO: Expecting 655360 events.
[19:41:17.306] <TB0> INFO: 655360 events read in total (14077ms).
[19:41:17.466] <TB0> INFO: Expecting 655360 events.
[19:42:18.730] <TB0> INFO: 655360 events read in total (60861ms).
[19:42:18.880] <TB0> INFO: Expecting 655360 events.
[19:42:33.316] <TB0> INFO: 655360 events read in total (14027ms).
[19:42:33.485] <TB0> INFO: Test took 472555ms.
[19:42:33.599] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[19:42:33.847] <TB0> INFO: Expecting 655360 events.
[19:42:48.034] <TB0> INFO: 655360 events read in total (13595ms).
[19:42:48.051] <TB0> INFO: Expecting 655360 events.
[19:43:45.982] <TB0> INFO: 655360 events read in total (57528ms).
[19:43:46.004] <TB0> INFO: Expecting 655360 events.
[19:44:00.540] <TB0> INFO: 655360 events read in total (14133ms).
[19:44:00.563] <TB0> INFO: Expecting 655360 events.
[19:44:18.500] <TB0> INFO: 655360 events read in total (17534ms).
[19:44:18.601] <TB0> INFO: Expecting 655360 events.
[19:44:54.203] <TB0> INFO: 655360 events read in total (35088ms).
[19:44:54.235] <TB0> INFO: Expecting 655360 events.
[19:45:21.934] <TB0> INFO: 655360 events read in total (27239ms).
[19:45:21.973] <TB0> INFO: Expecting 655360 events.
[19:45:36.065] <TB0> INFO: 655360 events read in total (13689ms).
[19:45:36.105] <TB0> INFO: Expecting 655360 events.
[19:46:21.673] <TB0> INFO: 655360 events read in total (45159ms).
[19:46:21.719] <TB0> INFO: Expecting 655360 events.
[19:46:52.831] <TB0> INFO: 655360 events read in total (30709ms).
[19:46:52.932] <TB0> INFO: Expecting 655360 events.
[19:47:07.355] <TB0> INFO: 655360 events read in total (14016ms).
[19:47:07.430] <TB0> INFO: Expecting 655360 events.
[19:48:09.679] <TB0> INFO: 655360 events read in total (61846ms).
[19:48:09.804] <TB0> INFO: Expecting 655360 events.
[19:48:23.927] <TB0> INFO: 655360 events read in total (13719ms).
[19:48:24.020] <TB0> INFO: Expecting 655360 events.
[19:48:38.332] <TB0> INFO: 655360 events read in total (13909ms).
[19:48:38.422] <TB0> INFO: Expecting 655360 events.
[19:49:26.811] <TB0> INFO: 655360 events read in total (47986ms).
[19:49:27.007] <TB0> INFO: Expecting 655360 events.
[19:49:50.411] <TB0> INFO: 655360 events read in total (22984ms).
[19:49:50.508] <TB0> INFO: Expecting 655360 events.
[19:50:04.934] <TB0> INFO: 655360 events read in total (14023ms).
[19:50:05.048] <TB0> INFO: Test took 451449ms.
[19:50:05.312] <TB0> INFO: safety margin for low PH: adding 0, margin is now 20
[19:50:05.323] <TB0> INFO: safety margin for low PH: adding 0, margin is now 20
[19:50:05.334] <TB0> INFO: safety margin for low PH: adding 1, margin is now 21
[19:50:05.345] <TB0> INFO: safety margin for low PH: adding 2, margin is now 22
[19:50:05.357] <TB0> INFO: safety margin for low PH: adding 3, margin is now 23
[19:50:05.368] <TB0> INFO: safety margin for low PH: adding 0, margin is now 20
[19:50:05.378] <TB0> INFO: safety margin for low PH: adding 0, margin is now 20
[19:50:05.389] <TB0> INFO: safety margin for low PH: adding 0, margin is now 20
[19:50:05.399] <TB0> INFO: safety margin for low PH: adding 0, margin is now 20
[19:50:05.410] <TB0> INFO: safety margin for low PH: adding 0, margin is now 20
[19:50:05.421] <TB0> INFO: safety margin for low PH: adding 0, margin is now 20
[19:50:05.432] <TB0> INFO: safety margin for low PH: adding 0, margin is now 20
[19:50:05.444] <TB0> INFO: safety margin for low PH: adding 0, margin is now 20
[19:50:05.456] <TB0> INFO: safety margin for low PH: adding 1, margin is now 21
[19:50:05.466] <TB0> INFO: safety margin for low PH: adding 0, margin is now 20
[19:50:05.477] <TB0> INFO: safety margin for low PH: adding 0, margin is now 20
[19:50:05.488] <TB0> INFO: safety margin for low PH: adding 0, margin is now 20
[19:50:05.499] <TB0> INFO: safety margin for low PH: adding 0, margin is now 20
[19:50:05.509] <TB0> INFO: safety margin for low PH: adding 1, margin is now 21
[19:50:05.520] <TB0> INFO: safety margin for low PH: adding 0, margin is now 20
[19:50:05.532] <TB0> INFO: safety margin for low PH: adding 0, margin is now 20
[19:50:05.574] <TB0> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1116_FullQualification_2016-11-04_17h17m_1478276226//001_Fulltest_m20//dacParameters35_C0.dat
[19:50:05.574] <TB0> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1116_FullQualification_2016-11-04_17h17m_1478276226//001_Fulltest_m20//dacParameters35_C1.dat
[19:50:05.575] <TB0> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1116_FullQualification_2016-11-04_17h17m_1478276226//001_Fulltest_m20//dacParameters35_C2.dat
[19:50:05.575] <TB0> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1116_FullQualification_2016-11-04_17h17m_1478276226//001_Fulltest_m20//dacParameters35_C3.dat
[19:50:05.575] <TB0> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1116_FullQualification_2016-11-04_17h17m_1478276226//001_Fulltest_m20//dacParameters35_C4.dat
[19:50:05.575] <TB0> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1116_FullQualification_2016-11-04_17h17m_1478276226//001_Fulltest_m20//dacParameters35_C5.dat
[19:50:05.575] <TB0> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1116_FullQualification_2016-11-04_17h17m_1478276226//001_Fulltest_m20//dacParameters35_C6.dat
[19:50:05.575] <TB0> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1116_FullQualification_2016-11-04_17h17m_1478276226//001_Fulltest_m20//dacParameters35_C7.dat
[19:50:05.575] <TB0> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1116_FullQualification_2016-11-04_17h17m_1478276226//001_Fulltest_m20//dacParameters35_C8.dat
[19:50:05.576] <TB0> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1116_FullQualification_2016-11-04_17h17m_1478276226//001_Fulltest_m20//dacParameters35_C9.dat
[19:50:05.576] <TB0> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1116_FullQualification_2016-11-04_17h17m_1478276226//001_Fulltest_m20//dacParameters35_C10.dat
[19:50:05.576] <TB0> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1116_FullQualification_2016-11-04_17h17m_1478276226//001_Fulltest_m20//dacParameters35_C11.dat
[19:50:05.576] <TB0> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1116_FullQualification_2016-11-04_17h17m_1478276226//001_Fulltest_m20//dacParameters35_C12.dat
[19:50:05.576] <TB0> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1116_FullQualification_2016-11-04_17h17m_1478276226//001_Fulltest_m20//dacParameters35_C13.dat
[19:50:05.576] <TB0> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1116_FullQualification_2016-11-04_17h17m_1478276226//001_Fulltest_m20//dacParameters35_C14.dat
[19:50:05.576] <TB0> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1116_FullQualification_2016-11-04_17h17m_1478276226//001_Fulltest_m20//dacParameters35_C15.dat
[19:50:05.849] <TB0> INFO: Expecting 41600 events.
[19:50:08.977] <TB0> INFO: 41600 events read in total (2537ms).
[19:50:08.978] <TB0> INFO: Test took 3398ms.
[19:50:09.444] <TB0> INFO: Expecting 41600 events.
[19:50:16.126] <TB0> INFO: 41600 events read in total (6091ms).
[19:50:16.127] <TB0> INFO: Test took 6939ms.
[19:50:18.555] <TB0> INFO: Expecting 41600 events.
[19:50:22.485] <TB0> INFO: 41600 events read in total (3233ms).
[19:50:22.486] <TB0> INFO: Test took 4947ms.
[19:50:23.091] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[19:50:23.203] <TB0> INFO: Expecting 2560 events.
[19:50:25.582] <TB0> INFO: 2560 events read in total (1775ms).
[19:50:25.582] <TB0> INFO: Test took 2491ms.
[19:50:25.585] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[19:50:25.959] <TB0> INFO: Expecting 2560 events.
[19:50:27.253] <TB0> INFO: 2560 events read in total (650ms).
[19:50:27.255] <TB0> INFO: Test took 1670ms.
[19:50:27.257] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[19:50:27.589] <TB0> INFO: Expecting 2560 events.
[19:50:28.499] <TB0> INFO: 2560 events read in total (310ms).
[19:50:28.500] <TB0> INFO: Test took 1243ms.
[19:50:28.515] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[19:50:28.871] <TB0> INFO: Expecting 2560 events.
[19:50:29.920] <TB0> INFO: 2560 events read in total (449ms).
[19:50:29.923] <TB0> INFO: Test took 1408ms.
[19:50:29.939] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[19:50:30.315] <TB0> INFO: Expecting 2560 events.
[19:50:31.208] <TB0> INFO: 2560 events read in total (301ms).
[19:50:31.208] <TB0> INFO: Test took 1269ms.
[19:50:31.210] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[19:50:31.516] <TB0> INFO: Expecting 2560 events.
[19:50:32.420] <TB0> INFO: 2560 events read in total (305ms).
[19:50:32.421] <TB0> INFO: Test took 1211ms.
[19:50:32.424] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[19:50:32.731] <TB0> INFO: Expecting 2560 events.
[19:50:33.708] <TB0> INFO: 2560 events read in total (353ms).
[19:50:33.711] <TB0> INFO: Test took 1287ms.
[19:50:33.727] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[19:50:34.071] <TB0> INFO: Expecting 2560 events.
[19:50:35.032] <TB0> INFO: 2560 events read in total (358ms).
[19:50:35.039] <TB0> INFO: Test took 1312ms.
[19:50:35.084] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[19:50:35.347] <TB0> INFO: Expecting 2560 events.
[19:50:36.247] <TB0> INFO: 2560 events read in total (305ms).
[19:50:36.253] <TB0> INFO: Test took 1169ms.
[19:50:36.256] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[19:50:36.557] <TB0> INFO: Expecting 2560 events.
[19:50:37.570] <TB0> INFO: 2560 events read in total (413ms).
[19:50:37.571] <TB0> INFO: Test took 1315ms.
[19:50:37.595] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[19:50:37.905] <TB0> INFO: Expecting 2560 events.
[19:50:39.288] <TB0> INFO: 2560 events read in total (785ms).
[19:50:39.289] <TB0> INFO: Test took 1694ms.
[19:50:39.323] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[19:50:39.893] <TB0> INFO: Expecting 2560 events.
[19:50:41.517] <TB0> INFO: 2560 events read in total (1022ms).
[19:50:41.518] <TB0> INFO: Test took 2195ms.
[19:50:41.536] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[19:50:42.011] <TB0> INFO: Expecting 2560 events.
[19:50:42.992] <TB0> INFO: 2560 events read in total (365ms).
[19:50:42.994] <TB0> INFO: Test took 1458ms.
[19:50:43.047] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[19:50:43.489] <TB0> INFO: Expecting 2560 events.
[19:50:44.372] <TB0> INFO: 2560 events read in total (290ms).
[19:50:44.372] <TB0> INFO: Test took 1325ms.
[19:50:44.378] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[19:50:44.691] <TB0> INFO: Expecting 2560 events.
[19:50:45.569] <TB0> INFO: 2560 events read in total (286ms).
[19:50:45.570] <TB0> INFO: Test took 1192ms.
[19:50:45.572] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[19:50:45.976] <TB0> INFO: Expecting 2560 events.
[19:50:46.948] <TB0> INFO: 2560 events read in total (343ms).
[19:50:46.948] <TB0> INFO: Test took 1376ms.
[19:50:46.949] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[19:50:47.322] <TB0> INFO: Expecting 2560 events.
[19:50:48.985] <TB0> INFO: 2560 events read in total (1046ms).
[19:50:48.986] <TB0> INFO: Test took 2037ms.
[19:50:49.031] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[19:50:49.347] <TB0> INFO: Expecting 2560 events.
[19:50:50.504] <TB0> INFO: 2560 events read in total (509ms).
[19:50:50.512] <TB0> INFO: Test took 1481ms.
[19:50:50.603] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[19:50:51.123] <TB0> INFO: Expecting 2560 events.
[19:50:54.044] <TB0> INFO: 2560 events read in total (2205ms).
[19:50:54.055] <TB0> INFO: Test took 3452ms.
[19:50:54.243] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[19:50:54.691] <TB0> INFO: Expecting 2560 events.
[19:50:57.872] <TB0> INFO: 2560 events read in total (2497ms).
[19:50:57.875] <TB0> INFO: Test took 3632ms.
[19:50:57.963] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[19:50:58.527] <TB0> INFO: Expecting 2560 events.
[19:51:00.988] <TB0> INFO: 2560 events read in total (1737ms).
[19:51:00.999] <TB0> INFO: Test took 3036ms.
[19:51:01.127] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[19:51:01.619] <TB0> INFO: Expecting 2560 events.
[19:51:04.204] <TB0> INFO: 2560 events read in total (1869ms).
[19:51:04.204] <TB0> INFO: Test took 3077ms.
[19:51:04.206] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[19:51:04.592] <TB0> INFO: Expecting 2560 events.
[19:51:05.542] <TB0> INFO: 2560 events read in total (309ms).
[19:51:05.543] <TB0> INFO: Test took 1337ms.
[19:51:05.545] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[19:51:05.863] <TB0> INFO: Expecting 2560 events.
[19:51:06.761] <TB0> INFO: 2560 events read in total (306ms).
[19:51:06.761] <TB0> INFO: Test took 1216ms.
[19:51:06.766] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[19:51:07.069] <TB0> INFO: Expecting 2560 events.
[19:51:07.956] <TB0> INFO: 2560 events read in total (295ms).
[19:51:07.961] <TB0> INFO: Test took 1195ms.
[19:51:07.964] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[19:51:08.265] <TB0> INFO: Expecting 2560 events.
[19:51:09.153] <TB0> INFO: 2560 events read in total (297ms).
[19:51:09.153] <TB0> INFO: Test took 1189ms.
[19:51:09.156] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[19:51:09.461] <TB0> INFO: Expecting 2560 events.
[19:51:10.359] <TB0> INFO: 2560 events read in total (306ms).
[19:51:10.360] <TB0> INFO: Test took 1205ms.
[19:51:10.362] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[19:51:10.668] <TB0> INFO: Expecting 2560 events.
[19:51:11.555] <TB0> INFO: 2560 events read in total (295ms).
[19:51:11.555] <TB0> INFO: Test took 1193ms.
[19:51:11.558] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[19:51:11.864] <TB0> INFO: Expecting 2560 events.
[19:51:12.755] <TB0> INFO: 2560 events read in total (300ms).
[19:51:12.755] <TB0> INFO: Test took 1197ms.
[19:51:12.757] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[19:51:13.064] <TB0> INFO: Expecting 2560 events.
[19:51:13.956] <TB0> INFO: 2560 events read in total (301ms).
[19:51:13.957] <TB0> INFO: Test took 1200ms.
[19:51:13.959] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[19:51:14.265] <TB0> INFO: Expecting 2560 events.
[19:51:15.149] <TB0> INFO: 2560 events read in total (293ms).
[19:51:15.150] <TB0> INFO: Test took 1191ms.
[19:51:15.152] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[19:51:15.458] <TB0> INFO: Expecting 2560 events.
[19:51:16.342] <TB0> INFO: 2560 events read in total (292ms).
[19:51:16.342] <TB0> INFO: Test took 1190ms.
[19:51:16.812] <TB0> INFO: PixTestPhOptimization::doTest() done, duration: 1236 seconds
[19:51:16.812] <TB0> INFO: PH scale (per ROC): 63 63 57 44 59 71 41 42 53 59 60 30 54 53 60 74
[19:51:16.812] <TB0> INFO: PH offset (per ROC): 129 128 124 99 126 125 88 110 130 111 137 92 122 133 123 114
[19:51:16.821] <TB0> INFO: Decoding statistics:
[19:51:16.821] <TB0> INFO: General information:
[19:51:16.821] <TB0> INFO: 16bit words read: 127888
[19:51:16.821] <TB0> INFO: valid events total: 20480
[19:51:16.821] <TB0> INFO: empty events: 17976
[19:51:16.821] <TB0> INFO: valid events with pixels: 2504
[19:51:16.821] <TB0> INFO: valid pixel hits: 2504
[19:51:16.821] <TB0> INFO: Event errors: 0
[19:51:16.821] <TB0> INFO: start marker: 0
[19:51:16.821] <TB0> INFO: stop marker: 0
[19:51:16.822] <TB0> INFO: overflow: 0
[19:51:16.822] <TB0> INFO: invalid 5bit words: 0
[19:51:16.822] <TB0> INFO: invalid XOR eye diagram: 0
[19:51:16.822] <TB0> INFO: frame (failed synchr.): 0
[19:51:16.822] <TB0> INFO: idle data (no TBM trl): 0
[19:51:16.822] <TB0> INFO: no data (only TBM hdr): 0
[19:51:16.822] <TB0> INFO: TBM errors: 0
[19:51:16.822] <TB0> INFO: flawed TBM headers: 0
[19:51:16.822] <TB0> INFO: flawed TBM trailers: 0
[19:51:16.822] <TB0> INFO: event ID mismatches: 0
[19:51:16.822] <TB0> INFO: ROC errors: 0
[19:51:16.822] <TB0> INFO: missing ROC header(s): 0
[19:51:16.822] <TB0> INFO: misplaced readback start: 0
[19:51:16.822] <TB0> INFO: Pixel decoding errors: 0
[19:51:16.822] <TB0> INFO: pixel data incomplete: 0
[19:51:16.822] <TB0> INFO: pixel address: 0
[19:51:16.822] <TB0> INFO: pulse height fill bit: 0
[19:51:16.822] <TB0> INFO: buffer corruption: 0
[19:51:16.987] <TB0> INFO: ######################################################################
[19:51:16.987] <TB0> INFO: PixTestGainPedestal::fullTest() ntrig = 10
[19:51:16.987] <TB0> INFO: ######################################################################
[19:51:16.003] <TB0> INFO: scanning low vcal = 10
[19:51:17.240] <TB0> INFO: Expecting 41600 events.
[19:51:20.803] <TB0> INFO: 41600 events read in total (2971ms).
[19:51:20.803] <TB0> INFO: Test took 3800ms.
[19:51:20.806] <TB0> INFO: scanning low vcal = 20
[19:51:21.105] <TB0> INFO: Expecting 41600 events.
[19:51:24.674] <TB0> INFO: 41600 events read in total (2977ms).
[19:51:24.674] <TB0> INFO: Test took 3868ms.
[19:51:24.677] <TB0> INFO: scanning low vcal = 30
[19:51:24.971] <TB0> INFO: Expecting 41600 events.
[19:51:28.632] <TB0> INFO: 41600 events read in total (3070ms).
[19:51:28.633] <TB0> INFO: Test took 3956ms.
[19:51:28.636] <TB0> INFO: scanning low vcal = 40
[19:51:28.913] <TB0> INFO: Expecting 41600 events.
[19:51:32.842] <TB0> INFO: 41600 events read in total (3337ms).
[19:51:32.844] <TB0> INFO: Test took 4208ms.
[19:51:32.847] <TB0> INFO: scanning low vcal = 50
[19:51:33.124] <TB0> INFO: Expecting 41600 events.
[19:51:40.361] <TB0> INFO: 41600 events read in total (6645ms).
[19:51:40.362] <TB0> INFO: Test took 7515ms.
[19:51:40.367] <TB0> INFO: scanning low vcal = 60
[19:51:40.642] <TB0> INFO: Expecting 41600 events.
[19:51:49.012] <TB0> INFO: 41600 events read in total (7778ms).
[19:51:49.014] <TB0> INFO: Test took 8647ms.
[19:51:49.017] <TB0> INFO: scanning low vcal = 70
[19:51:49.294] <TB0> INFO: Expecting 41600 events.
[19:52:11.005] <TB0> INFO: 41600 events read in total (21100ms).
[19:52:11.008] <TB0> INFO: Test took 21990ms.
[19:52:11.017] <TB0> INFO: scanning low vcal = 80
[19:52:11.453] <TB0> INFO: Expecting 41600 events.
[19:52:15.674] <TB0> INFO: 41600 events read in total (3629ms).
[19:52:15.675] <TB0> INFO: Test took 4657ms.
[19:52:15.680] <TB0> INFO: scanning low vcal = 90
[19:52:15.978] <TB0> INFO: Expecting 41600 events.
[19:52:20.108] <TB0> INFO: 41600 events read in total (3535ms).
[19:52:20.114] <TB0> INFO: Test took 4432ms.
[19:52:20.121] <TB0> INFO: scanning low vcal = 100
[19:52:20.462] <TB0> INFO: Expecting 41600 events.
[19:52:29.743] <TB0> INFO: 41600 events read in total (8682ms).
[19:52:29.760] <TB0> INFO: Test took 9637ms.
[19:52:29.899] <TB0> INFO: scanning low vcal = 110
[19:52:30.894] <TB0> INFO: Expecting 41600 events.
[19:52:41.098] <TB0> INFO: 41600 events read in total (9511ms).
[19:52:41.101] <TB0> INFO: Test took 11190ms.
[19:52:41.114] <TB0> INFO: scanning low vcal = 120
[19:52:41.437] <TB0> INFO: Expecting 41600 events.
[19:52:45.423] <TB0> INFO: 41600 events read in total (3394ms).
[19:52:45.424] <TB0> INFO: Test took 4309ms.
[19:52:45.427] <TB0> INFO: scanning low vcal = 130
[19:52:45.703] <TB0> INFO: Expecting 41600 events.
[19:52:49.755] <TB0> INFO: 41600 events read in total (3460ms).
[19:52:49.756] <TB0> INFO: Test took 4329ms.
[19:52:49.759] <TB0> INFO: scanning low vcal = 140
[19:52:50.042] <TB0> INFO: Expecting 41600 events.
[19:52:54.038] <TB0> INFO: 41600 events read in total (3404ms).
[19:52:54.039] <TB0> INFO: Test took 4280ms.
[19:52:54.042] <TB0> INFO: scanning low vcal = 150
[19:52:54.318] <TB0> INFO: Expecting 41600 events.
[19:52:58.280] <TB0> INFO: 41600 events read in total (3370ms).
[19:52:58.281] <TB0> INFO: Test took 4239ms.
[19:52:58.284] <TB0> INFO: scanning low vcal = 160
[19:52:58.569] <TB0> INFO: Expecting 41600 events.
[19:53:02.555] <TB0> INFO: 41600 events read in total (3394ms).
[19:53:02.556] <TB0> INFO: Test took 4272ms.
[19:53:02.561] <TB0> INFO: scanning low vcal = 170
[19:53:02.836] <TB0> INFO: Expecting 41600 events.
[19:53:10.501] <TB0> INFO: 41600 events read in total (7074ms).
[19:53:10.502] <TB0> INFO: Test took 7942ms.
[19:53:10.508] <TB0> INFO: scanning low vcal = 180
[19:53:10.809] <TB0> INFO: Expecting 41600 events.
[19:53:15.585] <TB0> INFO: 41600 events read in total (4185ms).
[19:53:15.588] <TB0> INFO: Test took 5079ms.
[19:53:15.592] <TB0> INFO: scanning low vcal = 190
[19:53:15.911] <TB0> INFO: Expecting 41600 events.
[19:53:22.351] <TB0> INFO: 41600 events read in total (5817ms).
[19:53:22.353] <TB0> INFO: Test took 6761ms.
[19:53:22.372] <TB0> INFO: scanning low vcal = 200
[19:53:23.131] <TB0> INFO: Expecting 41600 events.
[19:53:42.522] <TB0> INFO: 41600 events read in total (18716ms).
[19:53:42.530] <TB0> INFO: Test took 20158ms.
[19:53:42.536] <TB0> INFO: scanning low vcal = 210
[19:53:42.872] <TB0> INFO: Expecting 41600 events.
[19:53:46.829] <TB0> INFO: 41600 events read in total (3365ms).
[19:53:46.829] <TB0> INFO: Test took 4292ms.
[19:53:46.833] <TB0> INFO: scanning low vcal = 220
[19:53:47.109] <TB0> INFO: Expecting 41600 events.
[19:54:07.636] <TB0> INFO: 41600 events read in total (19934ms).
[19:54:07.643] <TB0> INFO: Test took 20810ms.
[19:54:07.647] <TB0> INFO: scanning low vcal = 230
[19:54:07.968] <TB0> INFO: Expecting 41600 events.
[19:54:11.910] <TB0> INFO: 41600 events read in total (3350ms).
[19:54:11.911] <TB0> INFO: Test took 4263ms.
[19:54:11.915] <TB0> INFO: scanning low vcal = 240
[19:54:12.191] <TB0> INFO: Expecting 41600 events.
[19:54:16.157] <TB0> INFO: 41600 events read in total (3374ms).
[19:54:16.157] <TB0> INFO: Test took 4242ms.
[19:54:16.161] <TB0> INFO: scanning low vcal = 250
[19:54:16.437] <TB0> INFO: Expecting 41600 events.
[19:54:20.426] <TB0> INFO: 41600 events read in total (3397ms).
[19:54:20.427] <TB0> INFO: Test took 4266ms.
[19:54:20.431] <TB0> INFO: scanning high vcal = 30 (= 210 in low range)
[19:54:20.707] <TB0> INFO: Expecting 41600 events.
[19:54:24.685] <TB0> INFO: 41600 events read in total (3386ms).
[19:54:24.686] <TB0> INFO: Test took 4255ms.
[19:54:24.693] <TB0> INFO: scanning high vcal = 50 (= 350 in low range)
[19:54:24.970] <TB0> INFO: Expecting 41600 events.
[19:54:28.925] <TB0> INFO: 41600 events read in total (3363ms).
[19:54:28.926] <TB0> INFO: Test took 4233ms.
[19:54:28.929] <TB0> INFO: scanning high vcal = 70 (= 490 in low range)
[19:54:29.205] <TB0> INFO: Expecting 41600 events.
[19:54:33.189] <TB0> INFO: 41600 events read in total (3392ms).
[19:54:33.190] <TB0> INFO: Test took 4261ms.
[19:54:33.195] <TB0> INFO: scanning high vcal = 90 (= 630 in low range)
[19:54:33.470] <TB0> INFO: Expecting 41600 events.
[19:54:37.477] <TB0> INFO: 41600 events read in total (3415ms).
[19:54:37.478] <TB0> INFO: Test took 4283ms.
[19:54:37.482] <TB0> INFO: scanning high vcal = 200 (= 1400 in low range)
[19:54:37.758] <TB0> INFO: Expecting 41600 events.
[19:54:43.520] <TB0> INFO: 41600 events read in total (5170ms).
[19:54:43.522] <TB0> INFO: Test took 6041ms.
[19:54:44.480] <TB0> INFO: PixTestGainPedestal::measure() done
[19:55:47.949] <TB0> INFO: PixTestGainPedestal::fit() done
[19:55:47.949] <TB0> INFO: non-linearity mean: 0.985 0.982 0.979 0.910 0.986 0.987 0.907 0.909 0.972 0.979 0.984 1.081 0.983 0.982 0.984 0.988
[19:55:47.949] <TB0> INFO: non-linearity RMS: 0.003 0.004 0.004 0.126 0.003 0.003 0.107 0.160 0.006 0.009 0.004 0.112 0.003 0.005 0.004 0.003
[19:55:47.949] <TB0> INFO: write gain/ped parameters into /home/l_tester/david/FullQualification/data/M1116_FullQualification_2016-11-04_17h17m_1478276226//001_Fulltest_m20//phCalibrationFitErr35_C0.dat
[19:55:47.963] <TB0> INFO: write gain/ped parameters into /home/l_tester/david/FullQualification/data/M1116_FullQualification_2016-11-04_17h17m_1478276226//001_Fulltest_m20//phCalibrationFitErr35_C1.dat
[19:55:47.977] <TB0> INFO: write gain/ped parameters into /home/l_tester/david/FullQualification/data/M1116_FullQualification_2016-11-04_17h17m_1478276226//001_Fulltest_m20//phCalibrationFitErr35_C2.dat
[19:55:47.991] <TB0> INFO: write gain/ped parameters into /home/l_tester/david/FullQualification/data/M1116_FullQualification_2016-11-04_17h17m_1478276226//001_Fulltest_m20//phCalibrationFitErr35_C3.dat
[19:55:48.005] <TB0> INFO: write gain/ped parameters into /home/l_tester/david/FullQualification/data/M1116_FullQualification_2016-11-04_17h17m_1478276226//001_Fulltest_m20//phCalibrationFitErr35_C4.dat
[19:55:48.019] <TB0> INFO: write gain/ped parameters into /home/l_tester/david/FullQualification/data/M1116_FullQualification_2016-11-04_17h17m_1478276226//001_Fulltest_m20//phCalibrationFitErr35_C5.dat
[19:55:48.033] <TB0> INFO: write gain/ped parameters into /home/l_tester/david/FullQualification/data/M1116_FullQualification_2016-11-04_17h17m_1478276226//001_Fulltest_m20//phCalibrationFitErr35_C6.dat
[19:55:48.047] <TB0> INFO: write gain/ped parameters into /home/l_tester/david/FullQualification/data/M1116_FullQualification_2016-11-04_17h17m_1478276226//001_Fulltest_m20//phCalibrationFitErr35_C7.dat
[19:55:48.061] <TB0> INFO: write gain/ped parameters into /home/l_tester/david/FullQualification/data/M1116_FullQualification_2016-11-04_17h17m_1478276226//001_Fulltest_m20//phCalibrationFitErr35_C8.dat
[19:55:48.075] <TB0> INFO: write gain/ped parameters into /home/l_tester/david/FullQualification/data/M1116_FullQualification_2016-11-04_17h17m_1478276226//001_Fulltest_m20//phCalibrationFitErr35_C9.dat
[19:55:48.089] <TB0> INFO: write gain/ped parameters into /home/l_tester/david/FullQualification/data/M1116_FullQualification_2016-11-04_17h17m_1478276226//001_Fulltest_m20//phCalibrationFitErr35_C10.dat
[19:55:48.103] <TB0> INFO: write gain/ped parameters into /home/l_tester/david/FullQualification/data/M1116_FullQualification_2016-11-04_17h17m_1478276226//001_Fulltest_m20//phCalibrationFitErr35_C11.dat
[19:55:48.117] <TB0> INFO: write gain/ped parameters into /home/l_tester/david/FullQualification/data/M1116_FullQualification_2016-11-04_17h17m_1478276226//001_Fulltest_m20//phCalibrationFitErr35_C12.dat
[19:55:48.131] <TB0> INFO: write gain/ped parameters into /home/l_tester/david/FullQualification/data/M1116_FullQualification_2016-11-04_17h17m_1478276226//001_Fulltest_m20//phCalibrationFitErr35_C13.dat
[19:55:48.145] <TB0> INFO: write gain/ped parameters into /home/l_tester/david/FullQualification/data/M1116_FullQualification_2016-11-04_17h17m_1478276226//001_Fulltest_m20//phCalibrationFitErr35_C14.dat
[19:55:48.159] <TB0> INFO: write gain/ped parameters into /home/l_tester/david/FullQualification/data/M1116_FullQualification_2016-11-04_17h17m_1478276226//001_Fulltest_m20//phCalibrationFitErr35_C15.dat
[19:55:48.173] <TB0> INFO: PixTestGainPedestal::fullTest() done, duration: 271 seconds
[19:55:48.173] <TB0> INFO: Decoding statistics:
[19:55:48.173] <TB0> INFO: General information:
[19:55:48.173] <TB0> INFO: 16bit words read: 3327710
[19:55:48.173] <TB0> INFO: valid events total: 332800
[19:55:48.173] <TB0> INFO: empty events: 0
[19:55:48.174] <TB0> INFO: valid events with pixels: 332800
[19:55:48.174] <TB0> INFO: valid pixel hits: 665455
[19:55:48.174] <TB0> INFO: Event errors: 0
[19:55:48.174] <TB0> INFO: start marker: 0
[19:55:48.174] <TB0> INFO: stop marker: 0
[19:55:48.174] <TB0> INFO: overflow: 0
[19:55:48.174] <TB0> INFO: invalid 5bit words: 0
[19:55:48.174] <TB0> INFO: invalid XOR eye diagram: 0
[19:55:48.174] <TB0> INFO: frame (failed synchr.): 0
[19:55:48.174] <TB0> INFO: idle data (no TBM trl): 0
[19:55:48.174] <TB0> INFO: no data (only TBM hdr): 0
[19:55:48.174] <TB0> INFO: TBM errors: 0
[19:55:48.174] <TB0> INFO: flawed TBM headers: 0
[19:55:48.174] <TB0> INFO: flawed TBM trailers: 0
[19:55:48.174] <TB0> INFO: event ID mismatches: 0
[19:55:48.174] <TB0> INFO: ROC errors: 0
[19:55:48.174] <TB0> INFO: missing ROC header(s): 0
[19:55:48.174] <TB0> INFO: misplaced readback start: 0
[19:55:48.174] <TB0> INFO: Pixel decoding errors: 0
[19:55:48.174] <TB0> INFO: pixel data incomplete: 0
[19:55:48.174] <TB0> INFO: pixel address: 0
[19:55:48.174] <TB0> INFO: pulse height fill bit: 0
[19:55:48.174] <TB0> INFO: buffer corruption: 0
[19:55:48.193] <TB0> INFO: Decoding statistics:
[19:55:48.193] <TB0> INFO: General information:
[19:55:48.193] <TB0> INFO: 16bit words read: 3457134
[19:55:48.193] <TB0> INFO: valid events total: 353536
[19:55:48.193] <TB0> INFO: empty events: 18232
[19:55:48.193] <TB0> INFO: valid events with pixels: 335304
[19:55:48.193] <TB0> INFO: valid pixel hits: 667959
[19:55:48.193] <TB0> INFO: Event errors: 0
[19:55:48.193] <TB0> INFO: start marker: 0
[19:55:48.193] <TB0> INFO: stop marker: 0
[19:55:48.193] <TB0> INFO: overflow: 0
[19:55:48.193] <TB0> INFO: invalid 5bit words: 0
[19:55:48.193] <TB0> INFO: invalid XOR eye diagram: 0
[19:55:48.193] <TB0> INFO: frame (failed synchr.): 0
[19:55:48.193] <TB0> INFO: idle data (no TBM trl): 0
[19:55:48.193] <TB0> INFO: no data (only TBM hdr): 0
[19:55:48.193] <TB0> INFO: TBM errors: 0
[19:55:48.193] <TB0> INFO: flawed TBM headers: 0
[19:55:48.193] <TB0> INFO: flawed TBM trailers: 0
[19:55:48.193] <TB0> INFO: event ID mismatches: 0
[19:55:48.193] <TB0> INFO: ROC errors: 0
[19:55:48.193] <TB0> INFO: missing ROC header(s): 0
[19:55:48.193] <TB0> INFO: misplaced readback start: 0
[19:55:48.193] <TB0> INFO: Pixel decoding errors: 0
[19:55:48.193] <TB0> INFO: pixel data incomplete: 0
[19:55:48.193] <TB0> INFO: pixel address: 0
[19:55:48.193] <TB0> INFO: pulse height fill bit: 0
[19:55:48.193] <TB0> INFO: buffer corruption: 0
[19:55:48.194] <TB0> INFO: enter test to run
[19:55:48.194] <TB0> INFO: test: exit no parameter change
[19:55:48.353] <TB0> QUIET: Connection to board 71 closed.
[19:55:48.356] <TB0> INFO: pXar: this is the end, my friend