[16:10:12.459] <TB0> INFO: *** Welcome to pxar ***
[16:10:12.459] <TB0> INFO: *** Today: 2016/11/02
[16:10:12.465] <TB0> INFO: *** Version: c8ba-dirty
[16:10:12.465] <TB0> INFO: readRocDacs: /home/l_tester/david/FullQualification/data/M1108_FullQualification_2016-11-02_15h17m_1478096238//001_Fulltest_m20//dacParameters_C0.dat .. /home/l_tester/david/FullQualification/data/M1108_FullQualification_2016-11-02_15h17m_1478096238//001_Fulltest_m20//dacParameters_C15.dat
[16:10:12.466] <TB0> INFO: readTbmDacs: /home/l_tester/david/FullQualification/data/M1108_FullQualification_2016-11-02_15h17m_1478096238//001_Fulltest_m20//tbmParameters_C0a.dat .. /home/l_tester/david/FullQualification/data/M1108_FullQualification_2016-11-02_15h17m_1478096238//001_Fulltest_m20//tbmParameters_C1b.dat
[16:10:12.466] <TB0> INFO: readMaskFile: /home/l_tester/david/FullQualification/data/M1108_FullQualification_2016-11-02_15h17m_1478096238//001_Fulltest_m20//defaultMaskFile.dat
[16:10:12.466] <TB0> INFO: readTrimFile: /home/l_tester/david/FullQualification/data/M1108_FullQualification_2016-11-02_15h17m_1478096238//001_Fulltest_m20//trimParameters_C0.dat .. /home/l_tester/david/FullQualification/data/M1108_FullQualification_2016-11-02_15h17m_1478096238//001_Fulltest_m20//trimParameters_C15.dat
[16:10:12.526] <TB0> INFO: clk: 4
[16:10:12.526] <TB0> INFO: ctr: 4
[16:10:12.526] <TB0> INFO: sda: 19
[16:10:12.526] <TB0> INFO: tin: 9
[16:10:12.526] <TB0> INFO: level: 15
[16:10:12.526] <TB0> INFO: triggerdelay: 0
[16:10:12.526] <TB0> QUIET: Instanciating API for pxar v2.7.6+61~g7f4a123
[16:10:12.526] <TB0> INFO: Log level: INFO
[16:10:12.535] <TB0> INFO: Found DTB DTB_WRQ4OZ
[16:10:12.545] <TB0> QUIET: Connection to board DTB_WRQ4OZ opened.
[16:10:12.547] <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:
------------------------------------------------------
[16:10:12.549] <TB0> INFO: RPC call hashes of host and DTB match: 486171790
[16:10:14.037] <TB0> INFO: DUT info:
[16:10:14.037] <TB0> INFO: The DUT currently contains the following objects:
[16:10:14.037] <TB0> INFO: 4 TBM Cores tbm10c (4 ON)
[16:10:14.037] <TB0> INFO: TBM Core alpha (0): 7 registers set
[16:10:14.037] <TB0> INFO: TBM Core beta (1): 7 registers set
[16:10:14.037] <TB0> INFO: TBM Core alpha (2): 7 registers set
[16:10:14.037] <TB0> INFO: TBM Core beta (3): 7 registers set
[16:10:14.037] <TB0> INFO: 16 ROCs proc600 (16 ON) with 4160 pixelConfigs
[16:10:14.037] <TB0> INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[16:10:14.037] <TB0> INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[16:10:14.037] <TB0> INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[16:10:14.037] <TB0> INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[16:10:14.037] <TB0> INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[16:10:14.037] <TB0> INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[16:10:14.037] <TB0> INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[16:10:14.037] <TB0> INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[16:10:14.037] <TB0> INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[16:10:14.037] <TB0> INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[16:10:14.037] <TB0> INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[16:10:14.037] <TB0> INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[16:10:14.037] <TB0> INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[16:10:14.037] <TB0> INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[16:10:14.037] <TB0> INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[16:10:14.037] <TB0> INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[16:10:14.438] <TB0> INFO: enter 'restricted' command line mode
[16:10:14.438] <TB0> INFO: enter test to run
[16:10:14.438] <TB0> INFO: test: pretest no parameter change
[16:10:14.438] <TB0> INFO: running: pretest
[16:10:14.443] <TB0> INFO: ######################################################################
[16:10:14.443] <TB0> INFO: PixTestPretest::doTest()
[16:10:14.443] <TB0> INFO: ######################################################################
[16:10:14.444] <TB0> INFO: ----------------------------------------------------------------------
[16:10:14.444] <TB0> INFO: PixTestPretest::programROC()
[16:10:14.444] <TB0> INFO: ----------------------------------------------------------------------
[16:10:32.457] <TB0> INFO: PixTestPretest::programROC() done: ROCs are all programmable
[16:10:32.457] <TB0> INFO: IA differences per ROC: 21.7 18.5 17.7 16.9 19.3 20.1 20.9 17.7 19.3 21.7 21.7 20.1 20.9 20.1 18.5 17.7
[16:10:32.524] <TB0> INFO: ----------------------------------------------------------------------
[16:10:32.524] <TB0> INFO: PixTestPretest::setVana() target Ia = 24 mA/ROC
[16:10:32.524] <TB0> INFO: ----------------------------------------------------------------------
[16:10:39.709] <TB0> INFO: PixTestPretest::setVana() done, Module Ia 379.4 mA = 23.7125 mA/ROC
[16:10:39.709] <TB0> INFO: i(loss) [mA/ROC]: 19.3 19.3 20.1 19.3 20.1 20.1 19.3 19.3 19.3 19.3 20.1 19.3 19.3 19.3 18.5 19.3
[16:10:39.738] <TB0> INFO: ----------------------------------------------------------------------
[16:10:39.738] <TB0> INFO: PixTestPretest::findTiming()
[16:10:39.738] <TB0> INFO: ----------------------------------------------------------------------
[16:10:39.738] <TB0> INFO: PixTestCmd::init()

[16:11:12.187] <TB0> INFO: TBM phases: 160MHz: 0, 400MHz: 2, TBM delays: ROC(0/1):5, header/trailer: 1, token: 1
[16:11:12.187] <TB0> INFO: (success/tries = 100/100), width = 4
[16:11:13.689] <TB0> INFO: ----------------------------------------------------------------------
[16:11:13.689] <TB0> INFO: PixTestPretest::findWorkingPixel()
[16:11:13.689] <TB0> INFO: ----------------------------------------------------------------------
[16:11:13.785] <TB0> INFO: Expecting 231680 events.
[16:11:23.820] <TB0> INFO: 231680 events read in total (9443ms).
[16:11:23.829] <TB0> INFO: Test took 10135ms.
[16:11:24.082] <TB0> INFO: Found working pixel in all ROCs: col/row = 12/22
[16:11:24.119] <TB0> INFO: ----------------------------------------------------------------------
[16:11:24.119] <TB0> INFO: PixTestPretest::setVthrCompCalDel()
[16:11:24.119] <TB0> INFO: ----------------------------------------------------------------------
[16:11:24.213] <TB0> INFO: Expecting 231680 events.
[16:11:34.088] <TB0> INFO: 231680 events read in total (9283ms).
[16:11:34.098] <TB0> INFO: Test took 9974ms.
[16:11:34.352] <TB0> INFO: PixTestPretest::setVthrCompCalDel() done
[16:11:34.352] <TB0> INFO: CalDel: 93 105 93 102 102 94 92 94 83 102 92 90 109 95 89 87
[16:11:34.352] <TB0> INFO: VthrComp: 51 51 51 51 51 51 51 51 51 51 51 51 51 51 51 51
[16:11:34.354] <TB0> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1108_FullQualification_2016-11-02_15h17m_1478096238//001_Fulltest_m20//dacParameters_C0.dat
[16:11:34.354] <TB0> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1108_FullQualification_2016-11-02_15h17m_1478096238//001_Fulltest_m20//dacParameters_C1.dat
[16:11:34.354] <TB0> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1108_FullQualification_2016-11-02_15h17m_1478096238//001_Fulltest_m20//dacParameters_C2.dat
[16:11:34.355] <TB0> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1108_FullQualification_2016-11-02_15h17m_1478096238//001_Fulltest_m20//dacParameters_C3.dat
[16:11:34.355] <TB0> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1108_FullQualification_2016-11-02_15h17m_1478096238//001_Fulltest_m20//dacParameters_C4.dat
[16:11:34.355] <TB0> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1108_FullQualification_2016-11-02_15h17m_1478096238//001_Fulltest_m20//dacParameters_C5.dat
[16:11:34.355] <TB0> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1108_FullQualification_2016-11-02_15h17m_1478096238//001_Fulltest_m20//dacParameters_C6.dat
[16:11:34.355] <TB0> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1108_FullQualification_2016-11-02_15h17m_1478096238//001_Fulltest_m20//dacParameters_C7.dat
[16:11:34.355] <TB0> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1108_FullQualification_2016-11-02_15h17m_1478096238//001_Fulltest_m20//dacParameters_C8.dat
[16:11:34.355] <TB0> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1108_FullQualification_2016-11-02_15h17m_1478096238//001_Fulltest_m20//dacParameters_C9.dat
[16:11:34.355] <TB0> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1108_FullQualification_2016-11-02_15h17m_1478096238//001_Fulltest_m20//dacParameters_C10.dat
[16:11:34.355] <TB0> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1108_FullQualification_2016-11-02_15h17m_1478096238//001_Fulltest_m20//dacParameters_C11.dat
[16:11:34.355] <TB0> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1108_FullQualification_2016-11-02_15h17m_1478096238//001_Fulltest_m20//dacParameters_C12.dat
[16:11:34.355] <TB0> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1108_FullQualification_2016-11-02_15h17m_1478096238//001_Fulltest_m20//dacParameters_C13.dat
[16:11:34.355] <TB0> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1108_FullQualification_2016-11-02_15h17m_1478096238//001_Fulltest_m20//dacParameters_C14.dat
[16:11:34.355] <TB0> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1108_FullQualification_2016-11-02_15h17m_1478096238//001_Fulltest_m20//dacParameters_C15.dat
[16:11:34.356] <TB0> INFO: write tbm parameters into /home/l_tester/david/FullQualification/data/M1108_FullQualification_2016-11-02_15h17m_1478096238//001_Fulltest_m20//tbmParameters_C0a.dat
[16:11:34.356] <TB0> INFO: write tbm parameters into /home/l_tester/david/FullQualification/data/M1108_FullQualification_2016-11-02_15h17m_1478096238//001_Fulltest_m20//tbmParameters_C0b.dat
[16:11:34.356] <TB0> INFO: write tbm parameters into /home/l_tester/david/FullQualification/data/M1108_FullQualification_2016-11-02_15h17m_1478096238//001_Fulltest_m20//tbmParameters_C1a.dat
[16:11:34.356] <TB0> INFO: write tbm parameters into /home/l_tester/david/FullQualification/data/M1108_FullQualification_2016-11-02_15h17m_1478096238//001_Fulltest_m20//tbmParameters_C1b.dat
[16:11:34.356] <TB0> INFO: PixTestPretest::doTest() done, duration: 79 seconds
[16:11:34.406] <TB0> INFO: enter test to run
[16:11:34.407] <TB0> INFO: test: FullTest no parameter change
[16:11:34.407] <TB0> INFO: running: fulltest
[16:11:34.407] <TB0> INFO: ######################################################################
[16:11:34.407] <TB0> INFO: PixTestFullTest::doTest()
[16:11:34.407] <TB0> INFO: ######################################################################
[16:11:34.408] <TB0> INFO: ######################################################################
[16:11:34.408] <TB0> INFO: PixTestAlive::doTest()
[16:11:34.408] <TB0> INFO: ######################################################################
[16:11:34.409] <TB0> INFO: ----------------------------------------------------------------------
[16:11:34.409] <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)
[16:11:34.409] <TB0> INFO: ----------------------------------------------------------------------
[16:11:34.650] <TB0> INFO: Expecting 41600 events.
[16:11:38.267] <TB0> INFO: 41600 events read in total (3025ms).
[16:11:38.267] <TB0> INFO: Test took 3856ms.
[16:11:38.501] <TB0> INFO: PixTestAlive::aliveTest() done
[16:11:38.501] <TB0> INFO: number of dead pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 1 0 0 0 0 0
[16:11:38.503] <TB0> INFO: ----------------------------------------------------------------------
[16:11:38.503] <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)
[16:11:38.503] <TB0> INFO: ----------------------------------------------------------------------
[16:11:38.746] <TB0> INFO: Expecting 41600 events.
[16:11:41.705] <TB0> INFO: 41600 events read in total (2367ms).
[16:11:41.705] <TB0> INFO: Test took 3199ms.
[16:11:41.705] <TB0> INFO: mask vs. old pixelAlive PixelAlive_C0_V0 .. PixelAlive_C15_V0
[16:11:41.948] <TB0> INFO: PixTestAlive::maskTest() done
[16:11:41.948] <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
[16:11:41.949] <TB0> INFO: ----------------------------------------------------------------------
[16:11:41.949] <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)
[16:11:41.949] <TB0> INFO: ----------------------------------------------------------------------
[16:11:42.193] <TB0> INFO: Expecting 41600 events.
[16:11:45.779] <TB0> INFO: 41600 events read in total (2994ms).
[16:11:45.780] <TB0> INFO: Test took 3829ms.
[16:11:46.013] <TB0> INFO: PixTestAlive::addressDecodingTest() done
[16:11:46.013] <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
[16:11:46.013] <TB0> INFO: PixTestAlive::doTest() done, duration: 11 seconds
[16:11:46.013] <TB0> INFO: Decoding statistics:
[16:11:46.013] <TB0> INFO: General information:
[16:11:46.013] <TB0> INFO: 16bit words read: 0
[16:11:46.013] <TB0> INFO: valid events total: 0
[16:11:46.013] <TB0> INFO: empty events: 0
[16:11:46.013] <TB0> INFO: valid events with pixels: 0
[16:11:46.013] <TB0> INFO: valid pixel hits: 0
[16:11:46.013] <TB0> INFO: Event errors: 0
[16:11:46.013] <TB0> INFO: start marker: 0
[16:11:46.013] <TB0> INFO: stop marker: 0
[16:11:46.013] <TB0> INFO: overflow: 0
[16:11:46.013] <TB0> INFO: invalid 5bit words: 0
[16:11:46.013] <TB0> INFO: invalid XOR eye diagram: 0
[16:11:46.013] <TB0> INFO: frame (failed synchr.): 0
[16:11:46.013] <TB0> INFO: idle data (no TBM trl): 0
[16:11:46.013] <TB0> INFO: no data (only TBM hdr): 0
[16:11:46.013] <TB0> INFO: TBM errors: 0
[16:11:46.013] <TB0> INFO: flawed TBM headers: 0
[16:11:46.013] <TB0> INFO: flawed TBM trailers: 0
[16:11:46.013] <TB0> INFO: event ID mismatches: 0
[16:11:46.013] <TB0> INFO: ROC errors: 0
[16:11:46.013] <TB0> INFO: missing ROC header(s): 0
[16:11:46.013] <TB0> INFO: misplaced readback start: 0
[16:11:46.013] <TB0> INFO: Pixel decoding errors: 0
[16:11:46.013] <TB0> INFO: pixel data incomplete: 0
[16:11:46.013] <TB0> INFO: pixel address: 0
[16:11:46.013] <TB0> INFO: pulse height fill bit: 0
[16:11:46.013] <TB0> INFO: buffer corruption: 0
[16:11:46.018] <TB0> INFO: readReadbackCal: /home/l_tester/david/FullQualification/data/M1108_FullQualification_2016-11-02_15h17m_1478096238//001_Fulltest_m20//readbackCal_C0.dat .. /home/l_tester/david/FullQualification/data/M1108_FullQualification_2016-11-02_15h17m_1478096238//001_Fulltest_m20//readbackCal_C15.dat
[16:11:46.018] <TB0> INFO: readGainPedestalParameters /home/l_tester/david/FullQualification/data/M1108_FullQualification_2016-11-02_15h17m_1478096238//001_Fulltest_m20//phCalibrationFitErr_C0.dat .. /home/l_tester/david/FullQualification/data/M1108_FullQualification_2016-11-02_15h17m_1478096238//001_Fulltest_m20//phCalibrationFitErr_C15.dat

[16:11:46.018] <TB0> INFO: ######################################################################
[16:11:46.018] <TB0> INFO: PixTestReadback::doTest()
[16:11:46.018] <TB0> INFO: ######################################################################
[16:11:46.018] <TB0> INFO: ----------------------------------------------------------------------
[16:11:46.018] <TB0> INFO: PixTestReadback::CalibrateVd()
[16:11:46.018] <TB0> INFO: ----------------------------------------------------------------------
[16:11:55.973] <TB0> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1108_FullQualification_2016-11-02_15h17m_1478096238//001_Fulltest_m20//readbackCal_C0.dat
[16:11:55.974] <TB0> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1108_FullQualification_2016-11-02_15h17m_1478096238//001_Fulltest_m20//readbackCal_C1.dat
[16:11:55.974] <TB0> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1108_FullQualification_2016-11-02_15h17m_1478096238//001_Fulltest_m20//readbackCal_C2.dat
[16:11:55.974] <TB0> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1108_FullQualification_2016-11-02_15h17m_1478096238//001_Fulltest_m20//readbackCal_C3.dat
[16:11:55.974] <TB0> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1108_FullQualification_2016-11-02_15h17m_1478096238//001_Fulltest_m20//readbackCal_C4.dat
[16:11:55.974] <TB0> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1108_FullQualification_2016-11-02_15h17m_1478096238//001_Fulltest_m20//readbackCal_C5.dat
[16:11:55.974] <TB0> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1108_FullQualification_2016-11-02_15h17m_1478096238//001_Fulltest_m20//readbackCal_C6.dat
[16:11:55.974] <TB0> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1108_FullQualification_2016-11-02_15h17m_1478096238//001_Fulltest_m20//readbackCal_C7.dat
[16:11:55.974] <TB0> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1108_FullQualification_2016-11-02_15h17m_1478096238//001_Fulltest_m20//readbackCal_C8.dat
[16:11:55.974] <TB0> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1108_FullQualification_2016-11-02_15h17m_1478096238//001_Fulltest_m20//readbackCal_C9.dat
[16:11:55.975] <TB0> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1108_FullQualification_2016-11-02_15h17m_1478096238//001_Fulltest_m20//readbackCal_C10.dat
[16:11:55.975] <TB0> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1108_FullQualification_2016-11-02_15h17m_1478096238//001_Fulltest_m20//readbackCal_C11.dat
[16:11:55.975] <TB0> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1108_FullQualification_2016-11-02_15h17m_1478096238//001_Fulltest_m20//readbackCal_C12.dat
[16:11:55.975] <TB0> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1108_FullQualification_2016-11-02_15h17m_1478096238//001_Fulltest_m20//readbackCal_C13.dat
[16:11:55.977] <TB0> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1108_FullQualification_2016-11-02_15h17m_1478096238//001_Fulltest_m20//readbackCal_C14.dat
[16:11:55.977] <TB0> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1108_FullQualification_2016-11-02_15h17m_1478096238//001_Fulltest_m20//readbackCal_C15.dat
[16:11:56.007] <TB0> INFO: PixTestPattern:: pg_setup set to default.
[16:11:56.007] <TB0> INFO: ----------------------------------------------------------------------
[16:11:56.007] <TB0> INFO: PixTestReadback::CalibrateVa()
[16:11:56.007] <TB0> INFO: ----------------------------------------------------------------------
[16:12:05.930] <TB0> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1108_FullQualification_2016-11-02_15h17m_1478096238//001_Fulltest_m20//readbackCal_C0.dat
[16:12:05.930] <TB0> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1108_FullQualification_2016-11-02_15h17m_1478096238//001_Fulltest_m20//readbackCal_C1.dat
[16:12:05.930] <TB0> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1108_FullQualification_2016-11-02_15h17m_1478096238//001_Fulltest_m20//readbackCal_C2.dat
[16:12:05.930] <TB0> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1108_FullQualification_2016-11-02_15h17m_1478096238//001_Fulltest_m20//readbackCal_C3.dat
[16:12:05.930] <TB0> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1108_FullQualification_2016-11-02_15h17m_1478096238//001_Fulltest_m20//readbackCal_C4.dat
[16:12:05.930] <TB0> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1108_FullQualification_2016-11-02_15h17m_1478096238//001_Fulltest_m20//readbackCal_C5.dat
[16:12:05.930] <TB0> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1108_FullQualification_2016-11-02_15h17m_1478096238//001_Fulltest_m20//readbackCal_C6.dat
[16:12:05.930] <TB0> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1108_FullQualification_2016-11-02_15h17m_1478096238//001_Fulltest_m20//readbackCal_C7.dat
[16:12:05.931] <TB0> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1108_FullQualification_2016-11-02_15h17m_1478096238//001_Fulltest_m20//readbackCal_C8.dat
[16:12:05.931] <TB0> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1108_FullQualification_2016-11-02_15h17m_1478096238//001_Fulltest_m20//readbackCal_C9.dat
[16:12:05.931] <TB0> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1108_FullQualification_2016-11-02_15h17m_1478096238//001_Fulltest_m20//readbackCal_C10.dat
[16:12:05.931] <TB0> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1108_FullQualification_2016-11-02_15h17m_1478096238//001_Fulltest_m20//readbackCal_C11.dat
[16:12:05.931] <TB0> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1108_FullQualification_2016-11-02_15h17m_1478096238//001_Fulltest_m20//readbackCal_C12.dat
[16:12:05.931] <TB0> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1108_FullQualification_2016-11-02_15h17m_1478096238//001_Fulltest_m20//readbackCal_C13.dat
[16:12:05.931] <TB0> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1108_FullQualification_2016-11-02_15h17m_1478096238//001_Fulltest_m20//readbackCal_C14.dat
[16:12:05.931] <TB0> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1108_FullQualification_2016-11-02_15h17m_1478096238//001_Fulltest_m20//readbackCal_C15.dat
[16:12:05.962] <TB0> INFO: PixTestPattern:: pg_setup set to default.
[16:12:05.962] <TB0> INFO: ----------------------------------------------------------------------
[16:12:05.962] <TB0> INFO: PixTestReadback::readbackVbg()
[16:12:05.962] <TB0> INFO: ----------------------------------------------------------------------
[16:12:13.623] <TB0> INFO: PixTestPattern:: pg_setup set to default.
[16:12:13.623] <TB0> INFO: ----------------------------------------------------------------------
[16:12:13.623] <TB0> INFO: PixTestReadback::getCalibratedVbg()
[16:12:13.623] <TB0> INFO: ----------------------------------------------------------------------
[16:12:13.623] <TB0> INFO: Vbg will be calibrated using Vd calibration
[16:12:13.623] <TB0> INFO: /*/*/*/*::: ROC 0: uncalibrated Vbg = 152.2calibrated Vbg = 1.17833 :::*/*/*/*/
[16:12:13.624] <TB0> INFO: /*/*/*/*::: ROC 1: uncalibrated Vbg = 153.5calibrated Vbg = 1.16773 :::*/*/*/*/
[16:12:13.624] <TB0> INFO: /*/*/*/*::: ROC 2: uncalibrated Vbg = 153.7calibrated Vbg = 1.16297 :::*/*/*/*/
[16:12:13.624] <TB0> INFO: /*/*/*/*::: ROC 3: uncalibrated Vbg = 156.8calibrated Vbg = 1.16399 :::*/*/*/*/
[16:12:13.624] <TB0> INFO: /*/*/*/*::: ROC 4: uncalibrated Vbg = 153.2calibrated Vbg = 1.16546 :::*/*/*/*/
[16:12:13.624] <TB0> INFO: /*/*/*/*::: ROC 5: uncalibrated Vbg = 154.1calibrated Vbg = 1.17776 :::*/*/*/*/
[16:12:13.624] <TB0> INFO: /*/*/*/*::: ROC 6: uncalibrated Vbg = 147.8calibrated Vbg = 1.17328 :::*/*/*/*/
[16:12:13.624] <TB0> INFO: /*/*/*/*::: ROC 7: uncalibrated Vbg = 153.2calibrated Vbg = 1.17266 :::*/*/*/*/
[16:12:13.624] <TB0> INFO: /*/*/*/*::: ROC 8: uncalibrated Vbg = 150.3calibrated Vbg = 1.17285 :::*/*/*/*/
[16:12:13.624] <TB0> INFO: /*/*/*/*::: ROC 9: uncalibrated Vbg = 154.2calibrated Vbg = 1.16266 :::*/*/*/*/
[16:12:13.624] <TB0> INFO: /*/*/*/*::: ROC 10: uncalibrated Vbg = 150.4calibrated Vbg = 1.15951 :::*/*/*/*/
[16:12:13.624] <TB0> INFO: /*/*/*/*::: ROC 11: uncalibrated Vbg = 150.5calibrated Vbg = 1.15803 :::*/*/*/*/
[16:12:13.624] <TB0> INFO: /*/*/*/*::: ROC 12: uncalibrated Vbg = 147.2calibrated Vbg = 1.15743 :::*/*/*/*/
[16:12:13.624] <TB0> INFO: /*/*/*/*::: ROC 13: uncalibrated Vbg = 152.1calibrated Vbg = 1.16861 :::*/*/*/*/
[16:12:13.624] <TB0> INFO: /*/*/*/*::: ROC 14: uncalibrated Vbg = 153.2calibrated Vbg = 1.17153 :::*/*/*/*/
[16:12:13.624] <TB0> INFO: /*/*/*/*::: ROC 15: uncalibrated Vbg = 153calibrated Vbg = 1.17124 :::*/*/*/*/
[16:12:13.626] <TB0> INFO: ----------------------------------------------------------------------
[16:12:13.626] <TB0> INFO: PixTestReadback::CalibrateIa()
[16:12:13.627] <TB0> INFO: ----------------------------------------------------------------------
[16:14:54.477] <TB0> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1108_FullQualification_2016-11-02_15h17m_1478096238//001_Fulltest_m20//readbackCal_C0.dat
[16:14:54.477] <TB0> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1108_FullQualification_2016-11-02_15h17m_1478096238//001_Fulltest_m20//readbackCal_C1.dat
[16:14:54.477] <TB0> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1108_FullQualification_2016-11-02_15h17m_1478096238//001_Fulltest_m20//readbackCal_C2.dat
[16:14:54.477] <TB0> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1108_FullQualification_2016-11-02_15h17m_1478096238//001_Fulltest_m20//readbackCal_C3.dat
[16:14:54.477] <TB0> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1108_FullQualification_2016-11-02_15h17m_1478096238//001_Fulltest_m20//readbackCal_C4.dat
[16:14:54.478] <TB0> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1108_FullQualification_2016-11-02_15h17m_1478096238//001_Fulltest_m20//readbackCal_C5.dat
[16:14:54.478] <TB0> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1108_FullQualification_2016-11-02_15h17m_1478096238//001_Fulltest_m20//readbackCal_C6.dat
[16:14:54.478] <TB0> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1108_FullQualification_2016-11-02_15h17m_1478096238//001_Fulltest_m20//readbackCal_C7.dat
[16:14:54.478] <TB0> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1108_FullQualification_2016-11-02_15h17m_1478096238//001_Fulltest_m20//readbackCal_C8.dat
[16:14:54.478] <TB0> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1108_FullQualification_2016-11-02_15h17m_1478096238//001_Fulltest_m20//readbackCal_C9.dat
[16:14:54.478] <TB0> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1108_FullQualification_2016-11-02_15h17m_1478096238//001_Fulltest_m20//readbackCal_C10.dat
[16:14:54.478] <TB0> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1108_FullQualification_2016-11-02_15h17m_1478096238//001_Fulltest_m20//readbackCal_C11.dat
[16:14:54.478] <TB0> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1108_FullQualification_2016-11-02_15h17m_1478096238//001_Fulltest_m20//readbackCal_C12.dat
[16:14:54.478] <TB0> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1108_FullQualification_2016-11-02_15h17m_1478096238//001_Fulltest_m20//readbackCal_C13.dat
[16:14:54.478] <TB0> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1108_FullQualification_2016-11-02_15h17m_1478096238//001_Fulltest_m20//readbackCal_C14.dat
[16:14:54.478] <TB0> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1108_FullQualification_2016-11-02_15h17m_1478096238//001_Fulltest_m20//readbackCal_C15.dat
[16:14:54.509] <TB0> INFO: PixTestPattern:: pg_setup set to default.
[16:14:54.510] <TB0> INFO: PixTestReadback::doTest() done
[16:14:54.511] <TB0> INFO: Decoding statistics:
[16:14:54.511] <TB0> INFO: General information:
[16:14:54.511] <TB0> INFO: 16bit words read: 1536
[16:14:54.511] <TB0> INFO: valid events total: 256
[16:14:54.511] <TB0> INFO: empty events: 256
[16:14:54.511] <TB0> INFO: valid events with pixels: 0
[16:14:54.511] <TB0> INFO: valid pixel hits: 0
[16:14:54.511] <TB0> INFO: Event errors: 0
[16:14:54.511] <TB0> INFO: start marker: 0
[16:14:54.511] <TB0> INFO: stop marker: 0
[16:14:54.511] <TB0> INFO: overflow: 0
[16:14:54.511] <TB0> INFO: invalid 5bit words: 0
[16:14:54.511] <TB0> INFO: invalid XOR eye diagram: 0
[16:14:54.511] <TB0> INFO: frame (failed synchr.): 0
[16:14:54.511] <TB0> INFO: idle data (no TBM trl): 0
[16:14:54.511] <TB0> INFO: no data (only TBM hdr): 0
[16:14:54.511] <TB0> INFO: TBM errors: 0
[16:14:54.511] <TB0> INFO: flawed TBM headers: 0
[16:14:54.511] <TB0> INFO: flawed TBM trailers: 0
[16:14:54.511] <TB0> INFO: event ID mismatches: 0
[16:14:54.511] <TB0> INFO: ROC errors: 0
[16:14:54.511] <TB0> INFO: missing ROC header(s): 0
[16:14:54.511] <TB0> INFO: misplaced readback start: 0
[16:14:54.511] <TB0> INFO: Pixel decoding errors: 0
[16:14:54.511] <TB0> INFO: pixel data incomplete: 0
[16:14:54.511] <TB0> INFO: pixel address: 0
[16:14:54.511] <TB0> INFO: pulse height fill bit: 0
[16:14:54.511] <TB0> INFO: buffer corruption: 0
[16:14:54.566] <TB0> INFO: ######################################################################
[16:14:54.566] <TB0> INFO: PixTestBBMap::doTest() Ntrig = 5, VcalS = 250 (high range)
[16:14:54.566] <TB0> INFO: ######################################################################
[16:14:54.569] <TB0> INFO: ---> dac: VthrComp name: calSMap ntrig: 5 dacrange: 0 .. 149 (-1/-1) hits flags = 514 (plus default)
[16:14:54.625] <TB0> INFO: dacScan split into 1 runs with ntrig = 5
[16:14:54.625] <TB0> INFO: run 1 of 1
[16:14:54.861] <TB0> INFO: Expecting 3120000 events.
[16:15:26.169] <TB0> INFO: 654875 events read in total (30716ms).











[16:15:56.258] <TB0> INFO: 1308490 events read in total (60805ms).











[16:16:26.859] <TB0> INFO: 1958450 events read in total (91406ms).











[16:16:56.706] <TB0> INFO: 2607440 events read in total (121253ms).











[16:17:20.340] <TB0> INFO: 3120000 events read in total (144887ms).
[16:17:20.431] <TB0> INFO: Test took 145805ms.
[16:17:44.811] <TB0> INFO: PixTestBBMap::doTest() done with 2 decoding errors: , duration: 170 seconds
[16:17:44.811] <TB0> INFO: number of dead bumps (per ROC): 0 1 0 0 9 0 0 0 0 0 1 0 1 2 0 0
[16:17:44.811] <TB0> INFO: separation cut (per ROC): 101 98 100 104 102 101 104 96 108 99 109 104 95 97 102 108
[16:17:44.811] <TB0> INFO: Decoding statistics:
[16:17:44.811] <TB0> INFO: General information:
[16:17:44.811] <TB0> INFO: 16bit words read: 0
[16:17:44.811] <TB0> INFO: valid events total: 0
[16:17:44.811] <TB0> INFO: empty events: 0
[16:17:44.811] <TB0> INFO: valid events with pixels: 0
[16:17:44.811] <TB0> INFO: valid pixel hits: 0
[16:17:44.811] <TB0> INFO: Event errors: 0
[16:17:44.811] <TB0> INFO: start marker: 0
[16:17:44.811] <TB0> INFO: stop marker: 0
[16:17:44.811] <TB0> INFO: overflow: 0
[16:17:44.811] <TB0> INFO: invalid 5bit words: 0
[16:17:44.811] <TB0> INFO: invalid XOR eye diagram: 0
[16:17:44.812] <TB0> INFO: frame (failed synchr.): 0
[16:17:44.812] <TB0> INFO: idle data (no TBM trl): 0
[16:17:44.812] <TB0> INFO: no data (only TBM hdr): 0
[16:17:44.812] <TB0> INFO: TBM errors: 0
[16:17:44.812] <TB0> INFO: flawed TBM headers: 0
[16:17:44.812] <TB0> INFO: flawed TBM trailers: 0
[16:17:44.812] <TB0> INFO: event ID mismatches: 0
[16:17:44.812] <TB0> INFO: ROC errors: 0
[16:17:44.812] <TB0> INFO: missing ROC header(s): 0
[16:17:44.812] <TB0> INFO: misplaced readback start: 0
[16:17:44.812] <TB0> INFO: Pixel decoding errors: 0
[16:17:44.812] <TB0> INFO: pixel data incomplete: 0
[16:17:44.812] <TB0> INFO: pixel address: 0
[16:17:44.812] <TB0> INFO: pulse height fill bit: 0
[16:17:44.812] <TB0> INFO: buffer corruption: 0
[16:17:44.849] <TB0> INFO: ######################################################################
[16:17:44.849] <TB0> INFO: PixTestScurves::fullTest() ntrig = 50, dacs/step = -1, ntrig/step = -1
[16:17:44.849] <TB0> INFO: ######################################################################
[16:17:44.850] <TB0> INFO: ----------------------------------------------------------------------
[16:17:44.850] <TB0> INFO: PixTestScurves::scurves(Vcal), ntrig = 50, dacs/step = -1, ntrig/step = -1
[16:17:44.850] <TB0> INFO: ----------------------------------------------------------------------
[16:17:44.850] <TB0> INFO: ---> dac: Vcal name: scurveVcal ntrig: 50 dacrange: 25 .. 200 (-1/-1) hits flags = 528 (plus default)
[16:17:44.864] <TB0> INFO: dacScan split into 1 runs with ntrig = 50
[16:17:44.864] <TB0> INFO: run 1 of 1
[16:17:45.111] <TB0> INFO: Expecting 36608000 events.
[16:18:09.874] <TB0> INFO: 665000 events read in total (24162ms).
[16:18:32.736] <TB0> INFO: 1317050 events read in total (47024ms).
[16:18:55.513] <TB0> INFO: 1969200 events read in total (69801ms).
[16:19:18.141] <TB0> INFO: 2621850 events read in total (92429ms).
[16:19:41.080] <TB0> INFO: 3274300 events read in total (115368ms).
[16:20:03.841] <TB0> INFO: 3926400 events read in total (138129ms).
[16:20:26.628] <TB0> INFO: 4577550 events read in total (160916ms).
[16:20:49.309] <TB0> INFO: 5231150 events read in total (183597ms).
[16:21:12.098] <TB0> INFO: 5883500 events read in total (206386ms).
[16:21:34.718] <TB0> INFO: 6534500 events read in total (229006ms).
[16:21:57.465] <TB0> INFO: 7185750 events read in total (251753ms).
[16:22:19.951] <TB0> INFO: 7837300 events read in total (274239ms).
[16:22:42.598] <TB0> INFO: 8489200 events read in total (296886ms).
[16:23:05.296] <TB0> INFO: 9137800 events read in total (319584ms).
[16:23:28.462] <TB0> INFO: 9785550 events read in total (342750ms).
[16:23:51.356] <TB0> INFO: 10434800 events read in total (365644ms).
[16:24:14.037] <TB0> INFO: 11083550 events read in total (388325ms).
[16:24:36.790] <TB0> INFO: 11732550 events read in total (411078ms).
[16:24:59.344] <TB0> INFO: 12381450 events read in total (433632ms).
[16:25:21.933] <TB0> INFO: 13031700 events read in total (456221ms).
[16:25:44.460] <TB0> INFO: 13682200 events read in total (478748ms).
[16:26:07.098] <TB0> INFO: 14332100 events read in total (501386ms).
[16:26:29.926] <TB0> INFO: 14980800 events read in total (524214ms).
[16:26:52.557] <TB0> INFO: 15628350 events read in total (546845ms).
[16:27:15.207] <TB0> INFO: 16277400 events read in total (569495ms).
[16:27:37.777] <TB0> INFO: 16927250 events read in total (592065ms).
[16:28:00.120] <TB0> INFO: 17572300 events read in total (614408ms).
[16:28:22.568] <TB0> INFO: 18217150 events read in total (636856ms).
[16:28:45.392] <TB0> INFO: 18863600 events read in total (659680ms).
[16:29:07.829] <TB0> INFO: 19509950 events read in total (682117ms).
[16:29:30.387] <TB0> INFO: 20156100 events read in total (704675ms).
[16:29:52.998] <TB0> INFO: 20801150 events read in total (727286ms).
[16:30:15.577] <TB0> INFO: 21445600 events read in total (749865ms).
[16:30:38.203] <TB0> INFO: 22091700 events read in total (772491ms).
[16:31:00.691] <TB0> INFO: 22737000 events read in total (794979ms).
[16:31:23.173] <TB0> INFO: 23381400 events read in total (817461ms).
[16:31:45.751] <TB0> INFO: 24026450 events read in total (840039ms).
[16:32:08.132] <TB0> INFO: 24670850 events read in total (862420ms).
[16:32:30.685] <TB0> INFO: 25315500 events read in total (884973ms).
[16:32:52.948] <TB0> INFO: 25959600 events read in total (907236ms).
[16:33:15.648] <TB0> INFO: 26605550 events read in total (929936ms).
[16:33:38.273] <TB0> INFO: 27249850 events read in total (952561ms).
[16:34:00.750] <TB0> INFO: 27894750 events read in total (975038ms).
[16:34:23.786] <TB0> INFO: 28538450 events read in total (998074ms).
[16:34:46.354] <TB0> INFO: 29183000 events read in total (1020642ms).
[16:35:09.323] <TB0> INFO: 29826900 events read in total (1043611ms).
[16:35:31.875] <TB0> INFO: 30469700 events read in total (1066163ms).
[16:35:54.586] <TB0> INFO: 31110950 events read in total (1088874ms).
[16:36:17.224] <TB0> INFO: 31753550 events read in total (1111512ms).
[16:36:39.826] <TB0> INFO: 32397500 events read in total (1134114ms).
[16:37:02.412] <TB0> INFO: 33040100 events read in total (1156700ms).
[16:37:24.996] <TB0> INFO: 33683900 events read in total (1179284ms).
[16:37:47.481] <TB0> INFO: 34328700 events read in total (1201769ms).
[16:38:10.256] <TB0> INFO: 34974250 events read in total (1224544ms).
[16:38:33.045] <TB0> INFO: 35619150 events read in total (1247333ms).
[16:38:55.987] <TB0> INFO: 36269900 events read in total (1270275ms).
[16:39:07.884] <TB0> INFO: 36608000 events read in total (1282172ms).
[16:39:07.992] <TB0> INFO: Test took 1283128ms.
[16:39:08.462] <TB0> INFO: dumping ASCII scurve output file: SCurveData
[16:39:10.584] <TB0> INFO: dumping ASCII scurve output file: SCurveData
[16:39:12.111] <TB0> INFO: dumping ASCII scurve output file: SCurveData
[16:39:14.009] <TB0> INFO: dumping ASCII scurve output file: SCurveData
[16:39:15.780] <TB0> INFO: dumping ASCII scurve output file: SCurveData
[16:39:17.616] <TB0> INFO: dumping ASCII scurve output file: SCurveData
[16:39:19.561] <TB0> INFO: dumping ASCII scurve output file: SCurveData
[16:39:21.517] <TB0> INFO: dumping ASCII scurve output file: SCurveData
[16:39:23.606] <TB0> INFO: dumping ASCII scurve output file: SCurveData
[16:39:25.585] <TB0> INFO: dumping ASCII scurve output file: SCurveData
[16:39:27.694] <TB0> INFO: dumping ASCII scurve output file: SCurveData
[16:39:29.753] <TB0> INFO: dumping ASCII scurve output file: SCurveData
[16:39:31.822] <TB0> INFO: dumping ASCII scurve output file: SCurveData
[16:39:34.088] <TB0> INFO: dumping ASCII scurve output file: SCurveData
[16:39:35.669] <TB0> INFO: dumping ASCII scurve output file: SCurveData
[16:39:37.511] <TB0> INFO: dumping ASCII scurve output file: SCurveData
[16:39:39.598] <TB0> INFO: PixTestScurves::scurves() done
[16:39:39.598] <TB0> INFO: Vcal mean: 105.54 104.98 107.31 105.60 99.70 106.91 112.24 94.91 115.03 103.28 114.16 113.01 108.82 102.56 111.76 112.12
[16:39:39.598] <TB0> INFO: Vcal RMS: 4.93 4.70 4.70 5.24 5.21 4.83 5.04 4.64 5.67 5.44 5.44 5.35 4.74 5.34 5.36 5.17
[16:39:39.598] <TB0> INFO: PixTestScurves::fullTest() done, duration: 1314 seconds
[16:39:39.598] <TB0> INFO: Decoding statistics:
[16:39:39.598] <TB0> INFO: General information:
[16:39:39.598] <TB0> INFO: 16bit words read: 0
[16:39:39.598] <TB0> INFO: valid events total: 0
[16:39:39.598] <TB0> INFO: empty events: 0
[16:39:39.598] <TB0> INFO: valid events with pixels: 0
[16:39:39.598] <TB0> INFO: valid pixel hits: 0
[16:39:39.599] <TB0> INFO: Event errors: 0
[16:39:39.599] <TB0> INFO: start marker: 0
[16:39:39.599] <TB0> INFO: stop marker: 0
[16:39:39.599] <TB0> INFO: overflow: 0
[16:39:39.599] <TB0> INFO: invalid 5bit words: 0
[16:39:39.599] <TB0> INFO: invalid XOR eye diagram: 0
[16:39:39.599] <TB0> INFO: frame (failed synchr.): 0
[16:39:39.599] <TB0> INFO: idle data (no TBM trl): 0
[16:39:39.599] <TB0> INFO: no data (only TBM hdr): 0
[16:39:39.599] <TB0> INFO: TBM errors: 0
[16:39:39.599] <TB0> INFO: flawed TBM headers: 0
[16:39:39.599] <TB0> INFO: flawed TBM trailers: 0
[16:39:39.599] <TB0> INFO: event ID mismatches: 0
[16:39:39.599] <TB0> INFO: ROC errors: 0
[16:39:39.599] <TB0> INFO: missing ROC header(s): 0
[16:39:39.599] <TB0> INFO: misplaced readback start: 0
[16:39:39.599] <TB0> INFO: Pixel decoding errors: 0
[16:39:39.599] <TB0> INFO: pixel data incomplete: 0
[16:39:39.599] <TB0> INFO: pixel address: 0
[16:39:39.599] <TB0> INFO: pulse height fill bit: 0
[16:39:39.599] <TB0> INFO: buffer corruption: 0
[16:39:39.681] <TB0> INFO: ######################################################################
[16:39:39.681] <TB0> INFO: PixTestTrim::doTest()
[16:39:39.681] <TB0> INFO: ######################################################################
[16:39:39.682] <TB0> INFO: ----------------------------------------------------------------------
[16:39:39.682] <TB0> INFO: PixTestTrim::trimTest() ntrig = 8, vcal = 35
[16:39:39.682] <TB0> INFO: ----------------------------------------------------------------------
[16:39:39.733] <TB0> INFO: ---> VthrComp thr map (minimal VthrComp)
[16:39:39.733] <TB0> INFO: ---> dac: vthrcomp name: TrimThr0 ntrig: 8 dacrange: 10 .. 160 (-1/-1) hits flags = 528 (plus default)
[16:39:39.747] <TB0> INFO: dacScan split into 1 runs with ntrig = 8
[16:39:39.747] <TB0> INFO: run 1 of 1
[16:39:40.027] <TB0> INFO: Expecting 5025280 events.
[16:40:10.289] <TB0> INFO: 810912 events read in total (29657ms).
[16:40:40.204] <TB0> INFO: 1618632 events read in total (59572ms).
[16:41:10.227] <TB0> INFO: 2424200 events read in total (89596ms).
[16:41:39.982] <TB0> INFO: 3226312 events read in total (119350ms).
[16:42:10.007] <TB0> INFO: 4026136 events read in total (149375ms).
[16:42:39.732] <TB0> INFO: 4824016 events read in total (179100ms).
[16:42:47.448] <TB0> INFO: 5025280 events read in total (186816ms).
[16:42:47.504] <TB0> INFO: Test took 187758ms.
[16:43:09.585] <TB0> INFO: ROC 0 VthrComp = 117
[16:43:09.586] <TB0> INFO: ROC 1 VthrComp = 110
[16:43:09.586] <TB0> INFO: ROC 2 VthrComp = 114
[16:43:09.586] <TB0> INFO: ROC 3 VthrComp = 111
[16:43:09.586] <TB0> INFO: ROC 4 VthrComp = 104
[16:43:09.586] <TB0> INFO: ROC 5 VthrComp = 112
[16:43:09.586] <TB0> INFO: ROC 6 VthrComp = 121
[16:43:09.586] <TB0> INFO: ROC 7 VthrComp = 105
[16:43:09.586] <TB0> INFO: ROC 8 VthrComp = 124
[16:43:09.586] <TB0> INFO: ROC 9 VthrComp = 111
[16:43:09.586] <TB0> INFO: ROC 10 VthrComp = 126
[16:43:09.586] <TB0> INFO: ROC 11 VthrComp = 112
[16:43:09.587] <TB0> INFO: ROC 12 VthrComp = 114
[16:43:09.587] <TB0> INFO: ROC 13 VthrComp = 111
[16:43:09.587] <TB0> INFO: ROC 14 VthrComp = 113
[16:43:09.587] <TB0> INFO: ROC 15 VthrComp = 115
[16:43:09.830] <TB0> INFO: Expecting 41600 events.
[16:43:13.331] <TB0> INFO: 41600 events read in total (2909ms).
[16:43:13.332] <TB0> INFO: Test took 3743ms.
[16:43:13.341] <TB0> INFO: ---> Vcal thr map (pixel with maximum Vcal thr)
[16:43:13.341] <TB0> INFO: ---> dac: vcal name: TrimThr1 ntrig: 8 dacrange: 10 .. 160 (-1/-1) hits flags = 528 (plus default)
[16:43:13.352] <TB0> INFO: dacScan split into 1 runs with ntrig = 8
[16:43:13.352] <TB0> INFO: run 1 of 1
[16:43:13.630] <TB0> INFO: Expecting 5025280 events.
[16:43:40.169] <TB0> INFO: 589888 events read in total (25947ms).
[16:44:06.172] <TB0> INFO: 1178896 events read in total (51950ms).
[16:44:32.051] <TB0> INFO: 1768080 events read in total (77829ms).
[16:44:57.701] <TB0> INFO: 2356600 events read in total (103479ms).
[16:45:23.396] <TB0> INFO: 2943304 events read in total (129174ms).
[16:45:49.014] <TB0> INFO: 3528648 events read in total (154792ms).
[16:46:14.695] <TB0> INFO: 4113840 events read in total (180473ms).
[16:46:40.351] <TB0> INFO: 4698552 events read in total (206129ms).
[16:46:54.915] <TB0> INFO: 5025280 events read in total (220693ms).
[16:46:55.107] <TB0> INFO: Test took 221754ms.
[16:47:21.402] <TB0> INFO: roc 0 with ID = 0 has maximal Vcal 56.1559 for pixel 46/3 mean/min/max = 44.3501/31.9356/56.7646
[16:47:21.403] <TB0> INFO: roc 1 with ID = 1 has maximal Vcal 57.7858 for pixel 36/77 mean/min/max = 46.4511/35.031/57.8712
[16:47:21.403] <TB0> INFO: roc 2 with ID = 2 has maximal Vcal 57.2276 for pixel 11/2 mean/min/max = 44.8334/32.4119/57.2549
[16:47:21.403] <TB0> INFO: roc 3 with ID = 3 has maximal Vcal 60.0573 for pixel 51/62 mean/min/max = 46.937/33.7985/60.0755
[16:47:21.404] <TB0> INFO: roc 4 with ID = 4 has maximal Vcal 59.854 for pixel 12/63 mean/min/max = 46.9369/34.0086/59.8652
[16:47:21.404] <TB0> INFO: roc 5 with ID = 5 has maximal Vcal 58.1819 for pixel 17/1 mean/min/max = 45.4774/32.7415/58.2134
[16:47:21.405] <TB0> INFO: roc 6 with ID = 6 has maximal Vcal 57.5858 for pixel 6/53 mean/min/max = 44.6745/31.751/57.598
[16:47:21.405] <TB0> INFO: roc 7 with ID = 7 has maximal Vcal 56.5548 for pixel 15/63 mean/min/max = 45.6402/34.7138/56.5665
[16:47:21.406] <TB0> INFO: roc 8 with ID = 8 has maximal Vcal 59.1375 for pixel 19/2 mean/min/max = 45.3474/31.5076/59.1872
[16:47:21.406] <TB0> INFO: roc 9 with ID = 9 has maximal Vcal 58.9602 for pixel 0/39 mean/min/max = 46.9027/34.6772/59.1281
[16:47:21.406] <TB0> INFO: roc 10 with ID = 10 has maximal Vcal 57.6731 for pixel 22/12 mean/min/max = 44.9661/32.1742/57.758
[16:47:21.407] <TB0> INFO: roc 11 with ID = 11 has maximal Vcal 60.3153 for pixel 17/1 mean/min/max = 46.5603/32.6236/60.4969
[16:47:21.407] <TB0> INFO: roc 12 with ID = 12 has maximal Vcal 58.6274 for pixel 6/7 mean/min/max = 45.7737/32.8601/58.6872
[16:47:21.407] <TB0> INFO: roc 13 with ID = 13 has maximal Vcal 58.4241 for pixel 45/0 mean/min/max = 46.1794/33.8768/58.4819
[16:47:21.408] <TB0> INFO: roc 14 with ID = 14 has maximal Vcal 60.1784 for pixel 51/78 mean/min/max = 46.2281/32.2336/60.2227
[16:47:21.408] <TB0> INFO: roc 15 with ID = 15 has maximal Vcal 59.9998 for pixel 1/9 mean/min/max = 46.1787/32.3321/60.0253
[16:47:21.409] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[16:47:21.497] <TB0> INFO: Expecting 411648 events.
[16:47:31.103] <TB0> INFO: 411648 events read in total (9015ms).
[16:47:31.112] <TB0> INFO: Expecting 411648 events.
[16:47:40.429] <TB0> INFO: 411648 events read in total (8914ms).
[16:47:40.440] <TB0> INFO: Expecting 411648 events.
[16:47:49.688] <TB0> INFO: 411648 events read in total (8846ms).
[16:47:49.702] <TB0> INFO: Expecting 411648 events.
[16:47:59.027] <TB0> INFO: 411648 events read in total (8922ms).
[16:47:59.043] <TB0> INFO: Expecting 411648 events.
[16:48:08.435] <TB0> INFO: 411648 events read in total (8989ms).
[16:48:08.455] <TB0> INFO: Expecting 411648 events.
[16:48:17.892] <TB0> INFO: 411648 events read in total (9034ms).
[16:48:17.914] <TB0> INFO: Expecting 411648 events.
[16:48:27.256] <TB0> INFO: 411648 events read in total (8939ms).
[16:48:27.281] <TB0> INFO: Expecting 411648 events.
[16:48:36.657] <TB0> INFO: 411648 events read in total (8973ms).
[16:48:36.686] <TB0> INFO: Expecting 411648 events.
[16:48:46.114] <TB0> INFO: 411648 events read in total (9025ms).
[16:48:46.145] <TB0> INFO: Expecting 411648 events.
[16:48:55.540] <TB0> INFO: 411648 events read in total (8992ms).
[16:48:55.574] <TB0> INFO: Expecting 411648 events.
[16:49:05.027] <TB0> INFO: 411648 events read in total (9050ms).
[16:49:05.074] <TB0> INFO: Expecting 411648 events.
[16:49:14.476] <TB0> INFO: 411648 events read in total (8999ms).
[16:49:14.515] <TB0> INFO: Expecting 411648 events.
[16:49:23.860] <TB0> INFO: 411648 events read in total (8942ms).
[16:49:23.911] <TB0> INFO: Expecting 411648 events.
[16:49:33.342] <TB0> INFO: 411648 events read in total (9028ms).
[16:49:33.405] <TB0> INFO: Expecting 411648 events.
[16:49:42.762] <TB0> INFO: 411648 events read in total (8954ms).
[16:49:42.811] <TB0> INFO: Expecting 411648 events.
[16:49:52.164] <TB0> INFO: 411648 events read in total (8950ms).
[16:49:52.302] <TB0> INFO: Test took 150894ms.
[16:49:53.162] <TB0> INFO: ---> dac: vcal name: TrimThr2 ntrig: 8 dacrange: 0 .. 150 (-1/-1) hits flags = 528 (plus default)
[16:49:53.175] <TB0> INFO: dacScan split into 1 runs with ntrig = 8
[16:49:53.175] <TB0> INFO: run 1 of 1
[16:49:53.452] <TB0> INFO: Expecting 5025280 events.
[16:50:19.762] <TB0> INFO: 583360 events read in total (25718ms).
[16:50:45.732] <TB0> INFO: 1166368 events read in total (51688ms).
[16:51:11.802] <TB0> INFO: 1750016 events read in total (77758ms).
[16:51:37.735] <TB0> INFO: 2332648 events read in total (103691ms).
[16:52:03.772] <TB0> INFO: 2914632 events read in total (129728ms).
[16:52:30.460] <TB0> INFO: 3497224 events read in total (156416ms).
[16:52:56.673] <TB0> INFO: 4077856 events read in total (182629ms).
[16:53:23.224] <TB0> INFO: 4658320 events read in total (209180ms).
[16:53:39.827] <TB0> INFO: 5025280 events read in total (225783ms).
[16:53:39.959] <TB0> INFO: Test took 226784ms.
[16:54:03.696] <TB0> INFO: ---> TrimStepCorr4 extremal thresholds: 8.499984 .. 147.432853
[16:54:04.024] <TB0> INFO: Expecting 208000 events.
[16:54:14.125] <TB0> INFO: 208000 events read in total (9510ms).
[16:54:14.127] <TB0> INFO: Test took 10429ms.
[16:54:14.196] <TB0> INFO: ---> dac: vcal name: trimStepCorr4 ntrig: 8 dacrange: 8 .. 157 (-1/-1) hits flags = 528 (plus default)
[16:54:14.209] <TB0> INFO: dacScan split into 1 runs with ntrig = 8
[16:54:14.210] <TB0> INFO: run 1 of 1
[16:54:14.490] <TB0> INFO: Expecting 4992000 events.
[16:54:40.551] <TB0> INFO: 574272 events read in total (25469ms).
[16:55:05.914] <TB0> INFO: 1148768 events read in total (50832ms).
[16:55:31.483] <TB0> INFO: 1722864 events read in total (76401ms).
[16:55:57.074] <TB0> INFO: 2297576 events read in total (101993ms).
[16:56:22.883] <TB0> INFO: 2872216 events read in total (127801ms).
[16:56:48.384] <TB0> INFO: 3446248 events read in total (153303ms).
[16:57:14.366] <TB0> INFO: 4020288 events read in total (179284ms).
[16:57:39.893] <TB0> INFO: 4593480 events read in total (204811ms).
[16:57:57.763] <TB0> INFO: 4992000 events read in total (222681ms).
[16:57:57.870] <TB0> INFO: Test took 223660ms.
[16:58:22.153] <TB0> INFO: ---> TrimStepCorr2 extremal thresholds: 28.138761 .. 45.312591
[16:58:22.433] <TB0> INFO: Expecting 208000 events.
[16:58:32.638] <TB0> INFO: 208000 events read in total (9611ms).
[16:58:32.639] <TB0> INFO: Test took 10484ms.
[16:58:32.707] <TB0> INFO: ---> dac: vcal name: trimStepCorr2 ntrig: 8 dacrange: 18 .. 55 (-1/-1) hits flags = 528 (plus default)
[16:58:32.721] <TB0> INFO: dacScan split into 1 runs with ntrig = 8
[16:58:32.721] <TB0> INFO: run 1 of 1
[16:58:33.023] <TB0> INFO: Expecting 1264640 events.
[16:59:01.517] <TB0> INFO: 654688 events read in total (27902ms).
[16:59:27.372] <TB0> INFO: 1264640 events read in total (53757ms).
[16:59:27.411] <TB0> INFO: Test took 54690ms.
[16:59:43.032] <TB0> INFO: ---> TrimStepCorr1a extremal thresholds: 25.607215 .. 46.227624
[16:59:43.307] <TB0> INFO: Expecting 208000 events.
[16:59:53.193] <TB0> INFO: 208000 events read in total (9295ms).
[16:59:53.194] <TB0> INFO: Test took 10162ms.
[16:59:53.251] <TB0> INFO: ---> dac: vcal name: trimStepCorr1a ntrig: 8 dacrange: 15 .. 56 (-1/-1) hits flags = 528 (plus default)
[16:59:53.265] <TB0> INFO: dacScan split into 1 runs with ntrig = 8
[16:59:53.266] <TB0> INFO: run 1 of 1
[16:59:53.549] <TB0> INFO: Expecting 1397760 events.
[17:00:21.329] <TB0> INFO: 663792 events read in total (27189ms).
[17:00:48.992] <TB0> INFO: 1326552 events read in total (54852ms).
[17:00:52.422] <TB0> INFO: 1397760 events read in total (58282ms).
[17:00:52.452] <TB0> INFO: Test took 59186ms.
[17:01:07.810] <TB0> INFO: ---> TrimStepCorr1b extremal thresholds: 24.117213 .. 42.612751
[17:01:08.053] <TB0> INFO: Expecting 208000 events.
[17:01:17.921] <TB0> INFO: 208000 events read in total (9277ms).
[17:01:17.922] <TB0> INFO: Test took 10111ms.
[17:01:17.970] <TB0> INFO: ---> dac: vcal name: trimStepCorr1b ntrig: 8 dacrange: 14 .. 52 (-1/-1) hits flags = 528 (plus default)
[17:01:17.983] <TB0> INFO: dacScan split into 1 runs with ntrig = 8
[17:01:17.983] <TB0> INFO: run 1 of 1
[17:01:18.261] <TB0> INFO: Expecting 1297920 events.
[17:01:46.935] <TB0> INFO: 684848 events read in total (28082ms).
[17:02:12.265] <TB0> INFO: 1297920 events read in total (53412ms).
[17:02:12.308] <TB0> INFO: Test took 54326ms.
[17:02:25.402] <TB0> INFO: ---> TrimThrFinal extremal thresholds: 15 .. 55
[17:02:25.402] <TB0> INFO: ---> dac: vcal name: TrimThrFinal ntrig: 8 dacrange: 15 .. 55 (-1/-1) hits flags = 528 (plus default)
[17:02:25.415] <TB0> INFO: dacScan split into 1 runs with ntrig = 8
[17:02:25.415] <TB0> INFO: run 1 of 1
[17:02:25.654] <TB0> INFO: Expecting 1364480 events.
[17:02:53.816] <TB0> INFO: 667584 events read in total (27570ms).
[17:03:21.450] <TB0> INFO: 1334240 events read in total (55204ms).
[17:03:23.109] <TB0> INFO: 1364480 events read in total (56863ms).
[17:03:23.139] <TB0> INFO: Test took 57725ms.
[17:03:39.973] <TB0> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1108_FullQualification_2016-11-02_15h17m_1478096238//001_Fulltest_m20//dacParameters35_C0.dat
[17:03:39.973] <TB0> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1108_FullQualification_2016-11-02_15h17m_1478096238//001_Fulltest_m20//dacParameters35_C1.dat
[17:03:39.973] <TB0> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1108_FullQualification_2016-11-02_15h17m_1478096238//001_Fulltest_m20//dacParameters35_C2.dat
[17:03:39.973] <TB0> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1108_FullQualification_2016-11-02_15h17m_1478096238//001_Fulltest_m20//dacParameters35_C3.dat
[17:03:39.973] <TB0> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1108_FullQualification_2016-11-02_15h17m_1478096238//001_Fulltest_m20//dacParameters35_C4.dat
[17:03:39.973] <TB0> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1108_FullQualification_2016-11-02_15h17m_1478096238//001_Fulltest_m20//dacParameters35_C5.dat
[17:03:39.973] <TB0> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1108_FullQualification_2016-11-02_15h17m_1478096238//001_Fulltest_m20//dacParameters35_C6.dat
[17:03:39.973] <TB0> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1108_FullQualification_2016-11-02_15h17m_1478096238//001_Fulltest_m20//dacParameters35_C7.dat
[17:03:39.974] <TB0> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1108_FullQualification_2016-11-02_15h17m_1478096238//001_Fulltest_m20//dacParameters35_C8.dat
[17:03:39.974] <TB0> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1108_FullQualification_2016-11-02_15h17m_1478096238//001_Fulltest_m20//dacParameters35_C9.dat
[17:03:39.974] <TB0> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1108_FullQualification_2016-11-02_15h17m_1478096238//001_Fulltest_m20//dacParameters35_C10.dat
[17:03:39.974] <TB0> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1108_FullQualification_2016-11-02_15h17m_1478096238//001_Fulltest_m20//dacParameters35_C11.dat
[17:03:39.974] <TB0> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1108_FullQualification_2016-11-02_15h17m_1478096238//001_Fulltest_m20//dacParameters35_C12.dat
[17:03:39.974] <TB0> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1108_FullQualification_2016-11-02_15h17m_1478096238//001_Fulltest_m20//dacParameters35_C13.dat
[17:03:39.974] <TB0> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1108_FullQualification_2016-11-02_15h17m_1478096238//001_Fulltest_m20//dacParameters35_C14.dat
[17:03:39.974] <TB0> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1108_FullQualification_2016-11-02_15h17m_1478096238//001_Fulltest_m20//dacParameters35_C15.dat
[17:03:39.974] <TB0> INFO: write trim parameters into /home/l_tester/david/FullQualification/data/M1108_FullQualification_2016-11-02_15h17m_1478096238//001_Fulltest_m20//trimParameters35_C0.dat
[17:03:39.981] <TB0> INFO: write trim parameters into /home/l_tester/david/FullQualification/data/M1108_FullQualification_2016-11-02_15h17m_1478096238//001_Fulltest_m20//trimParameters35_C1.dat
[17:03:39.986] <TB0> INFO: write trim parameters into /home/l_tester/david/FullQualification/data/M1108_FullQualification_2016-11-02_15h17m_1478096238//001_Fulltest_m20//trimParameters35_C2.dat
[17:03:39.991] <TB0> INFO: write trim parameters into /home/l_tester/david/FullQualification/data/M1108_FullQualification_2016-11-02_15h17m_1478096238//001_Fulltest_m20//trimParameters35_C3.dat
[17:03:39.995] <TB0> INFO: write trim parameters into /home/l_tester/david/FullQualification/data/M1108_FullQualification_2016-11-02_15h17m_1478096238//001_Fulltest_m20//trimParameters35_C4.dat
[17:03:39.000] <TB0> INFO: write trim parameters into /home/l_tester/david/FullQualification/data/M1108_FullQualification_2016-11-02_15h17m_1478096238//001_Fulltest_m20//trimParameters35_C5.dat
[17:03:40.004] <TB0> INFO: write trim parameters into /home/l_tester/david/FullQualification/data/M1108_FullQualification_2016-11-02_15h17m_1478096238//001_Fulltest_m20//trimParameters35_C6.dat
[17:03:40.009] <TB0> INFO: write trim parameters into /home/l_tester/david/FullQualification/data/M1108_FullQualification_2016-11-02_15h17m_1478096238//001_Fulltest_m20//trimParameters35_C7.dat
[17:03:40.014] <TB0> INFO: write trim parameters into /home/l_tester/david/FullQualification/data/M1108_FullQualification_2016-11-02_15h17m_1478096238//001_Fulltest_m20//trimParameters35_C8.dat
[17:03:40.018] <TB0> INFO: write trim parameters into /home/l_tester/david/FullQualification/data/M1108_FullQualification_2016-11-02_15h17m_1478096238//001_Fulltest_m20//trimParameters35_C9.dat
[17:03:40.023] <TB0> INFO: write trim parameters into /home/l_tester/david/FullQualification/data/M1108_FullQualification_2016-11-02_15h17m_1478096238//001_Fulltest_m20//trimParameters35_C10.dat
[17:03:40.028] <TB0> INFO: write trim parameters into /home/l_tester/david/FullQualification/data/M1108_FullQualification_2016-11-02_15h17m_1478096238//001_Fulltest_m20//trimParameters35_C11.dat
[17:03:40.032] <TB0> INFO: write trim parameters into /home/l_tester/david/FullQualification/data/M1108_FullQualification_2016-11-02_15h17m_1478096238//001_Fulltest_m20//trimParameters35_C12.dat
[17:03:40.037] <TB0> INFO: write trim parameters into /home/l_tester/david/FullQualification/data/M1108_FullQualification_2016-11-02_15h17m_1478096238//001_Fulltest_m20//trimParameters35_C13.dat
[17:03:40.042] <TB0> INFO: write trim parameters into /home/l_tester/david/FullQualification/data/M1108_FullQualification_2016-11-02_15h17m_1478096238//001_Fulltest_m20//trimParameters35_C14.dat
[17:03:40.046] <TB0> INFO: write trim parameters into /home/l_tester/david/FullQualification/data/M1108_FullQualification_2016-11-02_15h17m_1478096238//001_Fulltest_m20//trimParameters35_C15.dat
[17:03:40.051] <TB0> INFO: PixTestTrim::trimTest() done
[17:03:40.051] <TB0> INFO: vtrim: 112 108 118 116 129 123 139 120 139 111 140 122 116 115 107 115
[17:03:40.051] <TB0> INFO: vthrcomp: 117 110 114 111 104 112 121 105 124 111 126 112 114 111 113 115
[17:03:40.051] <TB0> INFO: vcal mean: 34.93 34.98 34.99 34.92 34.98 34.99 34.95 35.00 34.93 34.98 34.94 34.98 34.98 34.97 34.99 34.97
[17:03:40.051] <TB0> INFO: vcal RMS: 0.95 0.93 0.94 0.96 0.94 0.96 1.02 0.86 1.07 0.94 1.18 1.12 1.06 0.93 1.01 1.06
[17:03:40.051] <TB0> INFO: bits mean: 9.97 8.94 9.84 8.78 9.09 9.37 9.70 9.27 9.76 8.25 9.68 9.38 9.65 8.87 9.06 8.99
[17:03:40.051] <TB0> INFO: bits RMS: 2.57 2.49 2.54 2.64 2.57 2.68 2.71 2.39 2.71 2.74 2.64 2.62 2.55 2.75 2.90 2.87
[17:03:40.059] <TB0> INFO: ----------------------------------------------------------------------
[17:03:40.059] <TB0> INFO: PixTestTrim::trimBitTest() ntrig = 5, vtrims = 254 126 63 32
[17:03:40.059] <TB0> INFO: ----------------------------------------------------------------------
[17:03:40.062] <TB0> INFO: ---> dac: Vcal name: TrimBitsThr0 ntrig: 5 dacrange: 0 .. 199 (-1/-1) hits flags = 528 (plus default)
[17:03:40.076] <TB0> INFO: dacScan split into 1 runs with ntrig = 5
[17:03:40.076] <TB0> INFO: run 1 of 1
[17:03:40.358] <TB0> INFO: Expecting 4160000 events.
[17:04:12.281] <TB0> INFO: 733100 events read in total (31331ms).
[17:04:43.891] <TB0> INFO: 1462870 events read in total (62941ms).
[17:05:15.473] <TB0> INFO: 2189235 events read in total (94523ms).
[17:05:46.737] <TB0> INFO: 2910135 events read in total (125787ms).
[17:06:17.957] <TB0> INFO: 3629255 events read in total (157007ms).
[17:06:41.154] <TB0> INFO: 4160000 events read in total (180204ms).
[17:06:41.248] <TB0> INFO: Test took 181171ms.
[17:07:11.149] <TB0> INFO: ---> dac: Vcal name: TrimThr_trim14 ntrig: 5 dacrange: 0 .. 188 (-1/-1) hits flags = 528 (plus default)
[17:07:11.162] <TB0> INFO: dacScan split into 1 runs with ntrig = 5
[17:07:11.162] <TB0> INFO: run 1 of 1
[17:07:11.398] <TB0> INFO: Expecting 3931200 events.
[17:07:43.728] <TB0> INFO: 722985 events read in total (31738ms).
[17:08:14.739] <TB0> INFO: 1441315 events read in total (62749ms).
[17:08:45.750] <TB0> INFO: 2157875 events read in total (93760ms).
[17:09:16.712] <TB0> INFO: 2871125 events read in total (124722ms).
[17:09:47.979] <TB0> INFO: 3582525 events read in total (155989ms).
[17:10:03.581] <TB0> INFO: 3931200 events read in total (171591ms).
[17:10:03.669] <TB0> INFO: Test took 172506ms.
[17:10:33.784] <TB0> INFO: ---> dac: Vcal name: TrimThr_trim13 ntrig: 5 dacrange: 0 .. 176 (-1/-1) hits flags = 528 (plus default)
[17:10:33.798] <TB0> INFO: dacScan split into 1 runs with ntrig = 5
[17:10:33.798] <TB0> INFO: run 1 of 1
[17:10:34.081] <TB0> INFO: Expecting 3681600 events.
[17:11:06.065] <TB0> INFO: 740745 events read in total (31393ms).
[17:11:37.715] <TB0> INFO: 1476790 events read in total (63043ms).
[17:12:09.362] <TB0> INFO: 2210040 events read in total (94690ms).
[17:12:40.911] <TB0> INFO: 2940295 events read in total (126239ms).
[17:13:12.351] <TB0> INFO: 3670840 events read in total (157679ms).
[17:13:13.203] <TB0> INFO: 3681600 events read in total (158531ms).
[17:13:13.273] <TB0> INFO: Test took 159474ms.
[17:13:35.498] <TB0> INFO: ---> dac: Vcal name: TrimThr_trim11 ntrig: 5 dacrange: 0 .. 175 (-1/-1) hits flags = 528 (plus default)
[17:13:35.512] <TB0> INFO: dacScan split into 1 runs with ntrig = 5
[17:13:35.512] <TB0> INFO: run 1 of 1
[17:13:35.793] <TB0> INFO: Expecting 3660800 events.
[17:14:08.935] <TB0> INFO: 742325 events read in total (32550ms).
[17:14:40.442] <TB0> INFO: 1479720 events read in total (64057ms).
[17:15:12.360] <TB0> INFO: 2214000 events read in total (95975ms).
[17:15:43.913] <TB0> INFO: 2945465 events read in total (127528ms).
[17:16:14.764] <TB0> INFO: 3660800 events read in total (158379ms).
[17:16:14.825] <TB0> INFO: Test took 159313ms.
[17:16:36.698] <TB0> INFO: ---> dac: Vcal name: TrimThr_trim7 ntrig: 5 dacrange: 0 .. 176 (-1/-1) hits flags = 528 (plus default)
[17:16:36.711] <TB0> INFO: dacScan split into 1 runs with ntrig = 5
[17:16:36.711] <TB0> INFO: run 1 of 1
[17:16:36.969] <TB0> INFO: Expecting 3681600 events.
[17:17:09.114] <TB0> INFO: 740535 events read in total (31553ms).
[17:17:41.007] <TB0> INFO: 1476080 events read in total (63446ms).
[17:18:12.499] <TB0> INFO: 2209265 events read in total (94938ms).
[17:18:44.289] <TB0> INFO: 2939085 events read in total (126728ms).
[17:19:16.011] <TB0> INFO: 3669345 events read in total (158450ms).
[17:19:16.990] <TB0> INFO: 3681600 events read in total (159429ms).
[17:19:17.047] <TB0> INFO: Test took 160337ms.
[17:19:39.170] <TB0> INFO: PixTestTrim::trimBitTest() done
[17:19:39.171] <TB0> INFO: PixTestTrim::doTest() done, duration: 2399 seconds
[17:19:39.171] <TB0> INFO: Decoding statistics:
[17:19:39.171] <TB0> INFO: General information:
[17:19:39.171] <TB0> INFO: 16bit words read: 0
[17:19:39.171] <TB0> INFO: valid events total: 0
[17:19:39.171] <TB0> INFO: empty events: 0
[17:19:39.171] <TB0> INFO: valid events with pixels: 0
[17:19:39.171] <TB0> INFO: valid pixel hits: 0
[17:19:39.171] <TB0> INFO: Event errors: 0
[17:19:39.171] <TB0> INFO: start marker: 0
[17:19:39.171] <TB0> INFO: stop marker: 0
[17:19:39.171] <TB0> INFO: overflow: 0
[17:19:39.171] <TB0> INFO: invalid 5bit words: 0
[17:19:39.171] <TB0> INFO: invalid XOR eye diagram: 0
[17:19:39.171] <TB0> INFO: frame (failed synchr.): 0
[17:19:39.171] <TB0> INFO: idle data (no TBM trl): 0
[17:19:39.171] <TB0> INFO: no data (only TBM hdr): 0
[17:19:39.171] <TB0> INFO: TBM errors: 0
[17:19:39.171] <TB0> INFO: flawed TBM headers: 0
[17:19:39.171] <TB0> INFO: flawed TBM trailers: 0
[17:19:39.171] <TB0> INFO: event ID mismatches: 0
[17:19:39.171] <TB0> INFO: ROC errors: 0
[17:19:39.171] <TB0> INFO: missing ROC header(s): 0
[17:19:39.171] <TB0> INFO: misplaced readback start: 0
[17:19:39.171] <TB0> INFO: Pixel decoding errors: 0
[17:19:39.171] <TB0> INFO: pixel data incomplete: 0
[17:19:39.171] <TB0> INFO: pixel address: 0
[17:19:39.171] <TB0> INFO: pulse height fill bit: 0
[17:19:39.171] <TB0> INFO: buffer corruption: 0
[17:19:39.783] <TB0> INFO: ######################################################################
[17:19:39.783] <TB0> INFO: PixTestPhOptimization::doTest() Ntrig = 10
[17:19:39.783] <TB0> INFO: ######################################################################
[17:19:40.021] <TB0> INFO: Expecting 41600 events.
[17:19:43.542] <TB0> INFO: 41600 events read in total (2929ms).
[17:19:43.543] <TB0> INFO: Test took 3759ms.
[17:19:43.988] <TB0> INFO: Expecting 41600 events.
[17:19:47.518] <TB0> INFO: 41600 events read in total (2938ms).
[17:19:47.519] <TB0> INFO: Test took 3770ms.
[17:19:47.864] <TB0> INFO: Expecting 41600 events.
[17:19:51.377] <TB0> INFO: 41600 events read in total (2922ms).
[17:19:51.378] <TB0> INFO: Test took 3831ms.
[17:19:51.731] <TB0> INFO: Expecting 41600 events.
[17:19:55.421] <TB0> INFO: 41600 events read in total (3098ms).
[17:19:55.421] <TB0> INFO: Test took 4015ms.
[17:19:55.726] <TB0> INFO: Expecting 41600 events.
[17:19:59.482] <TB0> INFO: 41600 events read in total (3165ms).
[17:19:59.483] <TB0> INFO: Test took 4037ms.
[17:19:59.773] <TB0> INFO: Expecting 41600 events.
[17:20:03.326] <TB0> INFO: 41600 events read in total (2962ms).
[17:20:03.327] <TB0> INFO: Test took 3820ms.
[17:20:03.620] <TB0> INFO: Expecting 41600 events.
[17:20:07.085] <TB0> INFO: 41600 events read in total (2873ms).
[17:20:07.086] <TB0> INFO: Test took 3730ms.
[17:20:07.375] <TB0> INFO: Expecting 41600 events.
[17:20:10.947] <TB0> INFO: 41600 events read in total (2980ms).
[17:20:10.948] <TB0> INFO: Test took 3837ms.
[17:20:11.237] <TB0> INFO: Expecting 41600 events.
[17:20:14.751] <TB0> INFO: 41600 events read in total (2922ms).
[17:20:14.752] <TB0> INFO: Test took 3780ms.
[17:20:15.044] <TB0> INFO: Expecting 41600 events.
[17:20:18.623] <TB0> INFO: 41600 events read in total (2987ms).
[17:20:18.624] <TB0> INFO: Test took 3845ms.
[17:20:18.913] <TB0> INFO: Expecting 41600 events.
[17:20:22.473] <TB0> INFO: 41600 events read in total (2968ms).
[17:20:22.474] <TB0> INFO: Test took 3826ms.
[17:20:22.764] <TB0> INFO: Expecting 41600 events.
[17:20:26.265] <TB0> INFO: 41600 events read in total (2909ms).
[17:20:26.266] <TB0> INFO: Test took 3767ms.
[17:20:26.555] <TB0> INFO: Expecting 41600 events.
[17:20:30.197] <TB0> INFO: 41600 events read in total (3050ms).
[17:20:30.198] <TB0> INFO: Test took 3908ms.
[17:20:30.487] <TB0> INFO: Expecting 41600 events.
[17:20:33.952] <TB0> INFO: 41600 events read in total (2874ms).
[17:20:33.953] <TB0> INFO: Test took 3731ms.
[17:20:34.242] <TB0> INFO: Expecting 41600 events.
[17:20:37.719] <TB0> INFO: 41600 events read in total (2885ms).
[17:20:37.720] <TB0> INFO: Test took 3743ms.
[17:20:38.012] <TB0> INFO: Expecting 41600 events.
[17:20:41.566] <TB0> INFO: 41600 events read in total (2962ms).
[17:20:41.567] <TB0> INFO: Test took 3820ms.
[17:20:41.857] <TB0> INFO: Expecting 41600 events.
[17:20:45.524] <TB0> INFO: 41600 events read in total (3076ms).
[17:20:45.525] <TB0> INFO: Test took 3933ms.
[17:20:45.826] <TB0> INFO: Expecting 41600 events.
[17:20:49.437] <TB0> INFO: 41600 events read in total (3019ms).
[17:20:49.437] <TB0> INFO: Test took 3888ms.
[17:20:49.727] <TB0> INFO: Expecting 41600 events.
[17:20:53.229] <TB0> INFO: 41600 events read in total (2911ms).
[17:20:53.230] <TB0> INFO: Test took 3768ms.
[17:20:53.521] <TB0> INFO: Expecting 41600 events.
[17:20:57.039] <TB0> INFO: 41600 events read in total (2927ms).
[17:20:57.040] <TB0> INFO: Test took 3785ms.
[17:20:57.330] <TB0> INFO: Expecting 41600 events.
[17:21:00.804] <TB0> INFO: 41600 events read in total (2883ms).
[17:21:00.805] <TB0> INFO: Test took 3740ms.
[17:21:01.095] <TB0> INFO: Expecting 41600 events.
[17:21:04.611] <TB0> INFO: 41600 events read in total (2925ms).
[17:21:04.611] <TB0> INFO: Test took 3781ms.
[17:21:04.902] <TB0> INFO: Expecting 41600 events.
[17:21:08.378] <TB0> INFO: 41600 events read in total (2885ms).
[17:21:08.379] <TB0> INFO: Test took 3743ms.
[17:21:08.671] <TB0> INFO: Expecting 41600 events.
[17:21:12.179] <TB0> INFO: 41600 events read in total (2916ms).
[17:21:12.180] <TB0> INFO: Test took 3774ms.
[17:21:12.472] <TB0> INFO: Expecting 41600 events.
[17:21:16.129] <TB0> INFO: 41600 events read in total (3066ms).
[17:21:16.130] <TB0> INFO: Test took 3923ms.
[17:21:16.483] <TB0> INFO: Expecting 41600 events.
[17:21:20.216] <TB0> INFO: 41600 events read in total (3142ms).
[17:21:20.217] <TB0> INFO: Test took 4059ms.
[17:21:20.509] <TB0> INFO: Expecting 41600 events.
[17:21:24.059] <TB0> INFO: 41600 events read in total (2959ms).
[17:21:24.059] <TB0> INFO: Test took 3815ms.
[17:21:24.349] <TB0> INFO: Expecting 41600 events.
[17:21:27.845] <TB0> INFO: 41600 events read in total (2905ms).
[17:21:27.846] <TB0> INFO: Test took 3762ms.
[17:21:28.136] <TB0> INFO: Expecting 41600 events.
[17:21:31.658] <TB0> INFO: 41600 events read in total (2930ms).
[17:21:31.659] <TB0> INFO: Test took 3788ms.
[17:21:31.951] <TB0> INFO: Expecting 41600 events.
[17:21:35.516] <TB0> INFO: 41600 events read in total (2974ms).
[17:21:35.517] <TB0> INFO: Test took 3832ms.
[17:21:35.808] <TB0> INFO: Expecting 2560 events.
[17:21:36.697] <TB0> INFO: 2560 events read in total (299ms).
[17:21:36.698] <TB0> INFO: Test took 1167ms.
[17:21:37.004] <TB0> INFO: Expecting 2560 events.
[17:21:37.900] <TB0> INFO: 2560 events read in total (304ms).
[17:21:37.900] <TB0> INFO: Test took 1201ms.
[17:21:38.207] <TB0> INFO: Expecting 2560 events.
[17:21:39.095] <TB0> INFO: 2560 events read in total (296ms).
[17:21:39.095] <TB0> INFO: Test took 1194ms.
[17:21:39.403] <TB0> INFO: Expecting 2560 events.
[17:21:40.287] <TB0> INFO: 2560 events read in total (293ms).
[17:21:40.288] <TB0> INFO: Test took 1192ms.
[17:21:40.596] <TB0> INFO: Expecting 2560 events.
[17:21:41.480] <TB0> INFO: 2560 events read in total (293ms).
[17:21:41.481] <TB0> INFO: Test took 1193ms.
[17:21:41.788] <TB0> INFO: Expecting 2560 events.
[17:21:42.678] <TB0> INFO: 2560 events read in total (298ms).
[17:21:42.678] <TB0> INFO: Test took 1194ms.
[17:21:42.987] <TB0> INFO: Expecting 2560 events.
[17:21:43.868] <TB0> INFO: 2560 events read in total (290ms).
[17:21:43.868] <TB0> INFO: Test took 1189ms.
[17:21:44.177] <TB0> INFO: Expecting 2560 events.
[17:21:45.065] <TB0> INFO: 2560 events read in total (296ms).
[17:21:45.065] <TB0> INFO: Test took 1196ms.
[17:21:45.374] <TB0> INFO: Expecting 2560 events.
[17:21:46.257] <TB0> INFO: 2560 events read in total (291ms).
[17:21:46.257] <TB0> INFO: Test took 1192ms.
[17:21:46.564] <TB0> INFO: Expecting 2560 events.
[17:21:47.451] <TB0> INFO: 2560 events read in total (296ms).
[17:21:47.452] <TB0> INFO: Test took 1194ms.
[17:21:47.759] <TB0> INFO: Expecting 2560 events.
[17:21:48.640] <TB0> INFO: 2560 events read in total (288ms).
[17:21:48.640] <TB0> INFO: Test took 1188ms.
[17:21:48.948] <TB0> INFO: Expecting 2560 events.
[17:21:49.840] <TB0> INFO: 2560 events read in total (300ms).
[17:21:49.840] <TB0> INFO: Test took 1198ms.
[17:21:50.148] <TB0> INFO: Expecting 2560 events.
[17:21:51.035] <TB0> INFO: 2560 events read in total (295ms).
[17:21:51.036] <TB0> INFO: Test took 1195ms.
[17:21:51.342] <TB0> INFO: Expecting 2560 events.
[17:21:52.228] <TB0> INFO: 2560 events read in total (294ms).
[17:21:52.229] <TB0> INFO: Test took 1193ms.
[17:21:52.535] <TB0> INFO: Expecting 2560 events.
[17:21:53.431] <TB0> INFO: 2560 events read in total (304ms).
[17:21:53.431] <TB0> INFO: Test took 1201ms.
[17:21:53.739] <TB0> INFO: Expecting 2560 events.
[17:21:54.628] <TB0> INFO: 2560 events read in total (298ms).
[17:21:54.628] <TB0> INFO: Test took 1196ms.
[17:21:54.633] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[17:21:54.938] <TB0> INFO: Expecting 655360 events.
[17:22:09.930] <TB0> INFO: 655360 events read in total (14402ms).
[17:22:09.943] <TB0> INFO: Expecting 655360 events.
[17:22:24.988] <TB0> INFO: 655360 events read in total (14642ms).
[17:22:25.009] <TB0> INFO: Expecting 655360 events.
[17:22:39.634] <TB0> INFO: 655360 events read in total (14222ms).
[17:22:39.654] <TB0> INFO: Expecting 655360 events.
[17:22:54.263] <TB0> INFO: 655360 events read in total (14206ms).
[17:22:54.288] <TB0> INFO: Expecting 655360 events.
[17:23:09.022] <TB0> INFO: 655360 events read in total (14332ms).
[17:23:09.052] <TB0> INFO: Expecting 655360 events.
[17:23:23.676] <TB0> INFO: 655360 events read in total (14221ms).
[17:23:23.710] <TB0> INFO: Expecting 655360 events.
[17:23:38.403] <TB0> INFO: 655360 events read in total (14290ms).
[17:23:38.447] <TB0> INFO: Expecting 655360 events.
[17:23:53.093] <TB0> INFO: 655360 events read in total (14243ms).
[17:23:53.136] <TB0> INFO: Expecting 655360 events.
[17:24:07.655] <TB0> INFO: 655360 events read in total (14117ms).
[17:24:07.780] <TB0> INFO: Expecting 655360 events.
[17:24:22.285] <TB0> INFO: 655360 events read in total (14102ms).
[17:24:22.340] <TB0> INFO: Expecting 655360 events.
[17:24:37.241] <TB0> INFO: 655360 events read in total (14498ms).
[17:24:37.366] <TB0> INFO: Expecting 655360 events.
[17:24:51.891] <TB0> INFO: 655360 events read in total (14121ms).
[17:24:51.954] <TB0> INFO: Expecting 655360 events.
[17:25:06.640] <TB0> INFO: 655360 events read in total (14283ms).
[17:25:06.777] <TB0> INFO: Expecting 655360 events.
[17:25:21.341] <TB0> INFO: 655360 events read in total (14161ms).
[17:25:21.428] <TB0> INFO: Expecting 655360 events.
[17:25:35.999] <TB0> INFO: 655360 events read in total (14168ms).
[17:25:36.164] <TB0> INFO: Expecting 655360 events.
[17:25:50.761] <TB0> INFO: 655360 events read in total (14194ms).
[17:25:50.858] <TB0> INFO: Test took 236225ms.
[17:25:50.955] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[17:25:51.210] <TB0> INFO: Expecting 655360 events.
[17:26:05.921] <TB0> INFO: 655360 events read in total (14119ms).
[17:26:05.933] <TB0> INFO: Expecting 655360 events.
[17:26:20.081] <TB0> INFO: 655360 events read in total (13745ms).
[17:26:20.097] <TB0> INFO: Expecting 655360 events.
[17:26:34.677] <TB0> INFO: 655360 events read in total (14177ms).
[17:26:34.697] <TB0> INFO: Expecting 655360 events.
[17:26:48.804] <TB0> INFO: 655360 events read in total (13704ms).
[17:26:48.827] <TB0> INFO: Expecting 655360 events.
[17:27:03.396] <TB0> INFO: 655360 events read in total (14166ms).
[17:27:03.426] <TB0> INFO: Expecting 655360 events.
[17:27:17.837] <TB0> INFO: 655360 events read in total (14008ms).
[17:27:17.869] <TB0> INFO: Expecting 655360 events.
[17:27:32.361] <TB0> INFO: 655360 events read in total (14089ms).
[17:27:32.402] <TB0> INFO: Expecting 655360 events.
[17:27:46.000] <TB0> INFO: 655360 events read in total (14195ms).
[17:27:47.041] <TB0> INFO: Expecting 655360 events.
[17:28:01.655] <TB0> INFO: 655360 events read in total (14211ms).
[17:28:01.717] <TB0> INFO: Expecting 655360 events.
[17:28:16.090] <TB0> INFO: 655360 events read in total (13969ms).
[17:28:16.143] <TB0> INFO: Expecting 655360 events.
[17:28:30.823] <TB0> INFO: 655360 events read in total (14277ms).
[17:28:30.947] <TB0> INFO: Expecting 655360 events.
[17:28:45.186] <TB0> INFO: 655360 events read in total (13836ms).
[17:28:45.249] <TB0> INFO: Expecting 655360 events.
[17:28:59.849] <TB0> INFO: 655360 events read in total (14197ms).
[17:28:59.927] <TB0> INFO: Expecting 655360 events.
[17:29:14.146] <TB0> INFO: 655360 events read in total (13816ms).
[17:29:14.231] <TB0> INFO: Expecting 655360 events.
[17:29:28.544] <TB0> INFO: 655360 events read in total (13909ms).
[17:29:28.655] <TB0> INFO: Expecting 655360 events.
[17:29:43.167] <TB0> INFO: 655360 events read in total (14108ms).
[17:29:43.306] <TB0> INFO: Test took 232351ms.
[17:29:43.483] <TB0> INFO: safety margin for low PH: adding 0, margin is now 20
[17:29:43.489] <TB0> INFO: safety margin for low PH: adding 1, margin is now 21
[17:29:43.495] <TB0> INFO: safety margin for low PH: adding 2, margin is now 22
[17:29:43.500] <TB0> INFO: safety margin for low PH: adding 3, margin is now 23
[17:29:43.505] <TB0> INFO: safety margin for low PH: adding 4, margin is now 24
[17:29:43.511] <TB0> INFO: safety margin for low PH: adding 5, margin is now 25
[17:29:43.517] <TB0> INFO: safety margin for low PH: adding 0, margin is now 20
[17:29:43.522] <TB0> INFO: safety margin for low PH: adding 0, margin is now 20
[17:29:43.528] <TB0> INFO: safety margin for low PH: adding 0, margin is now 20
[17:29:43.533] <TB0> INFO: safety margin for low PH: adding 1, margin is now 21
[17:29:43.539] <TB0> INFO: safety margin for low PH: adding 2, margin is now 22
[17:29:43.544] <TB0> INFO: safety margin for low PH: adding 0, margin is now 20
[17:29:43.550] <TB0> INFO: safety margin for low PH: adding 1, margin is now 21
[17:29:43.556] <TB0> INFO: safety margin for low PH: adding 2, margin is now 22
[17:29:43.561] <TB0> INFO: safety margin for low PH: adding 3, margin is now 23
[17:29:43.567] <TB0> INFO: safety margin for low PH: adding 4, margin is now 24
[17:29:43.572] <TB0> INFO: safety margin for low PH: adding 5, margin is now 25
[17:29:43.578] <TB0> INFO: safety margin for low PH: adding 6, margin is now 26
[17:29:43.584] <TB0> INFO: safety margin for low PH: adding 7, margin is now 27
[17:29:43.589] <TB0> INFO: safety margin for low PH: adding 8, margin is now 28
[17:29:43.595] <TB0> INFO: safety margin for low PH: adding 9, margin is now 29
[17:29:43.601] <TB0> INFO: safety margin for low PH: adding 10, margin is now 30
[17:29:43.607] <TB0> INFO: safety margin for low PH: adding 11, margin is now 31
[17:29:43.613] <TB0> INFO: safety margin for low PH: adding 0, margin is now 20
[17:29:43.619] <TB0> INFO: safety margin for low PH: adding 0, margin is now 20
[17:29:43.624] <TB0> INFO: safety margin for low PH: adding 0, margin is now 20
[17:29:43.630] <TB0> INFO: safety margin for low PH: adding 1, margin is now 21
[17:29:43.636] <TB0> INFO: safety margin for low PH: adding 0, margin is now 20
[17:29:43.642] <TB0> INFO: safety margin for low PH: adding 0, margin is now 20
[17:29:43.648] <TB0> INFO: safety margin for low PH: adding 1, margin is now 21
[17:29:43.654] <TB0> INFO: safety margin for low PH: adding 0, margin is now 20
[17:29:43.659] <TB0> INFO: safety margin for low PH: adding 0, margin is now 20
[17:29:43.665] <TB0> INFO: safety margin for low PH: adding 0, margin is now 20
[17:29:43.671] <TB0> INFO: safety margin for low PH: adding 1, margin is now 21
[17:29:43.677] <TB0> INFO: safety margin for low PH: adding 2, margin is now 22
[17:29:43.683] <TB0> INFO: safety margin for low PH: adding 3, margin is now 23
[17:29:43.688] <TB0> INFO: safety margin for low PH: adding 0, margin is now 20
[17:29:43.694] <TB0> INFO: safety margin for low PH: adding 0, margin is now 20
[17:29:43.700] <TB0> INFO: safety margin for low PH: adding 1, margin is now 21
[17:29:43.705] <TB0> INFO: safety margin for low PH: adding 2, margin is now 22
[17:29:43.711] <TB0> INFO: safety margin for low PH: adding 3, margin is now 23
[17:29:43.716] <TB0> INFO: safety margin for low PH: adding 4, margin is now 24
[17:29:43.722] <TB0> INFO: safety margin for low PH: adding 5, margin is now 25
[17:29:43.727] <TB0> INFO: safety margin for low PH: adding 6, margin is now 26
[17:29:43.733] <TB0> INFO: safety margin for low PH: adding 7, margin is now 27
[17:29:43.738] <TB0> INFO: safety margin for low PH: adding 8, margin is now 28
[17:29:43.744] <TB0> INFO: safety margin for low PH: adding 9, margin is now 29
[17:29:43.749] <TB0> INFO: safety margin for low PH: adding 10, margin is now 30
[17:29:43.755] <TB0> INFO: safety margin for low PH: adding 11, margin is now 31
[17:29:43.760] <TB0> INFO: safety margin for low PH: adding 12, margin is now 32
[17:29:43.766] <TB0> INFO: safety margin for low PH: adding 13, margin is now 33
[17:29:43.772] <TB0> INFO: safety margin for low PH: adding 14, margin is now 34
[17:29:43.778] <TB0> INFO: safety margin for low PH: adding 15, margin is now 35
[17:29:43.783] <TB0> INFO: safety margin for low PH: adding 16, margin is now 36
[17:29:43.789] <TB0> INFO: safety margin for low PH: adding 17, margin is now 37
[17:29:43.794] <TB0> INFO: safety margin for low PH: adding 18, margin is now 38
[17:29:43.800] <TB0> INFO: safety margin for low PH: adding 19, margin is now 39
[17:29:43.806] <TB0> INFO: safety margin for low PH: adding 0, margin is now 20
[17:29:43.812] <TB0> INFO: safety margin for low PH: adding 1, margin is now 21
[17:29:43.818] <TB0> INFO: safety margin for low PH: adding 2, margin is now 22
[17:29:43.824] <TB0> INFO: safety margin for low PH: adding 3, margin is now 23
[17:29:43.860] <TB0> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1108_FullQualification_2016-11-02_15h17m_1478096238//001_Fulltest_m20//dacParameters35_C0.dat
[17:29:43.860] <TB0> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1108_FullQualification_2016-11-02_15h17m_1478096238//001_Fulltest_m20//dacParameters35_C1.dat
[17:29:43.860] <TB0> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1108_FullQualification_2016-11-02_15h17m_1478096238//001_Fulltest_m20//dacParameters35_C2.dat
[17:29:43.860] <TB0> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1108_FullQualification_2016-11-02_15h17m_1478096238//001_Fulltest_m20//dacParameters35_C3.dat
[17:29:43.860] <TB0> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1108_FullQualification_2016-11-02_15h17m_1478096238//001_Fulltest_m20//dacParameters35_C4.dat
[17:29:43.860] <TB0> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1108_FullQualification_2016-11-02_15h17m_1478096238//001_Fulltest_m20//dacParameters35_C5.dat
[17:29:43.860] <TB0> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1108_FullQualification_2016-11-02_15h17m_1478096238//001_Fulltest_m20//dacParameters35_C6.dat
[17:29:43.861] <TB0> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1108_FullQualification_2016-11-02_15h17m_1478096238//001_Fulltest_m20//dacParameters35_C7.dat
[17:29:43.861] <TB0> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1108_FullQualification_2016-11-02_15h17m_1478096238//001_Fulltest_m20//dacParameters35_C8.dat
[17:29:43.861] <TB0> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1108_FullQualification_2016-11-02_15h17m_1478096238//001_Fulltest_m20//dacParameters35_C9.dat
[17:29:43.861] <TB0> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1108_FullQualification_2016-11-02_15h17m_1478096238//001_Fulltest_m20//dacParameters35_C10.dat
[17:29:43.861] <TB0> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1108_FullQualification_2016-11-02_15h17m_1478096238//001_Fulltest_m20//dacParameters35_C11.dat
[17:29:43.861] <TB0> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1108_FullQualification_2016-11-02_15h17m_1478096238//001_Fulltest_m20//dacParameters35_C12.dat
[17:29:43.861] <TB0> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1108_FullQualification_2016-11-02_15h17m_1478096238//001_Fulltest_m20//dacParameters35_C13.dat
[17:29:43.861] <TB0> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1108_FullQualification_2016-11-02_15h17m_1478096238//001_Fulltest_m20//dacParameters35_C14.dat
[17:29:43.861] <TB0> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1108_FullQualification_2016-11-02_15h17m_1478096238//001_Fulltest_m20//dacParameters35_C15.dat
[17:29:44.151] <TB0> INFO: Expecting 41600 events.
[17:29:47.291] <TB0> INFO: 41600 events read in total (2548ms).
[17:29:47.292] <TB0> INFO: Test took 3427ms.
[17:29:47.747] <TB0> INFO: Expecting 41600 events.
[17:29:50.818] <TB0> INFO: 41600 events read in total (2479ms).
[17:29:50.820] <TB0> INFO: Test took 3316ms.
[17:29:51.317] <TB0> INFO: Expecting 41600 events.
[17:29:54.447] <TB0> INFO: 41600 events read in total (2538ms).
[17:29:54.447] <TB0> INFO: Test took 3412ms.
[17:29:54.664] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[17:29:54.753] <TB0> INFO: Expecting 2560 events.
[17:29:55.647] <TB0> INFO: 2560 events read in total (302ms).
[17:29:55.648] <TB0> INFO: Test took 984ms.
[17:29:55.649] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[17:29:55.957] <TB0> INFO: Expecting 2560 events.
[17:29:56.842] <TB0> INFO: 2560 events read in total (294ms).
[17:29:56.843] <TB0> INFO: Test took 1194ms.
[17:29:56.846] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[17:29:57.151] <TB0> INFO: Expecting 2560 events.
[17:29:58.041] <TB0> INFO: 2560 events read in total (298ms).
[17:29:58.042] <TB0> INFO: Test took 1197ms.
[17:29:58.046] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[17:29:58.350] <TB0> INFO: Expecting 2560 events.
[17:29:59.247] <TB0> INFO: 2560 events read in total (305ms).
[17:29:59.247] <TB0> INFO: Test took 1201ms.
[17:29:59.251] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[17:29:59.555] <TB0> INFO: Expecting 2560 events.
[17:30:00.447] <TB0> INFO: 2560 events read in total (300ms).
[17:30:00.448] <TB0> INFO: Test took 1197ms.
[17:30:00.450] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[17:30:00.757] <TB0> INFO: Expecting 2560 events.
[17:30:01.648] <TB0> INFO: 2560 events read in total (300ms).
[17:30:01.649] <TB0> INFO: Test took 1199ms.
[17:30:01.651] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[17:30:01.957] <TB0> INFO: Expecting 2560 events.
[17:30:02.855] <TB0> INFO: 2560 events read in total (306ms).
[17:30:02.855] <TB0> INFO: Test took 1204ms.
[17:30:02.860] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[17:30:03.163] <TB0> INFO: Expecting 2560 events.
[17:30:04.057] <TB0> INFO: 2560 events read in total (303ms).
[17:30:04.058] <TB0> INFO: Test took 1198ms.
[17:30:04.064] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[17:30:04.366] <TB0> INFO: Expecting 2560 events.
[17:30:05.255] <TB0> INFO: 2560 events read in total (298ms).
[17:30:05.256] <TB0> INFO: Test took 1193ms.
[17:30:05.262] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[17:30:05.563] <TB0> INFO: Expecting 2560 events.
[17:30:06.449] <TB0> INFO: 2560 events read in total (294ms).
[17:30:06.450] <TB0> INFO: Test took 1188ms.
[17:30:06.453] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[17:30:06.758] <TB0> INFO: Expecting 2560 events.
[17:30:07.647] <TB0> INFO: 2560 events read in total (297ms).
[17:30:07.647] <TB0> INFO: Test took 1194ms.
[17:30:07.651] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[17:30:07.956] <TB0> INFO: Expecting 2560 events.
[17:30:08.836] <TB0> INFO: 2560 events read in total (288ms).
[17:30:08.837] <TB0> INFO: Test took 1186ms.
[17:30:08.840] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[17:30:09.145] <TB0> INFO: Expecting 2560 events.
[17:30:10.035] <TB0> INFO: 2560 events read in total (298ms).
[17:30:10.036] <TB0> INFO: Test took 1196ms.
[17:30:10.039] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[17:30:10.345] <TB0> INFO: Expecting 2560 events.
[17:30:11.227] <TB0> INFO: 2560 events read in total (291ms).
[17:30:11.227] <TB0> INFO: Test took 1188ms.
[17:30:11.231] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[17:30:11.535] <TB0> INFO: Expecting 2560 events.
[17:30:12.426] <TB0> INFO: 2560 events read in total (299ms).
[17:30:12.427] <TB0> INFO: Test took 1196ms.
[17:30:12.430] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[17:30:12.734] <TB0> INFO: Expecting 2560 events.
[17:30:13.617] <TB0> INFO: 2560 events read in total (291ms).
[17:30:13.617] <TB0> INFO: Test took 1187ms.
[17:30:13.620] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[17:30:13.926] <TB0> INFO: Expecting 2560 events.
[17:30:14.811] <TB0> INFO: 2560 events read in total (294ms).
[17:30:14.811] <TB0> INFO: Test took 1191ms.
[17:30:14.815] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[17:30:15.118] <TB0> INFO: Expecting 2560 events.
[17:30:15.003] <TB0> INFO: 2560 events read in total (293ms).
[17:30:16.003] <TB0> INFO: Test took 1188ms.
[17:30:16.006] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[17:30:16.311] <TB0> INFO: Expecting 2560 events.
[17:30:17.193] <TB0> INFO: 2560 events read in total (291ms).
[17:30:17.194] <TB0> INFO: Test took 1189ms.
[17:30:17.198] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[17:30:17.501] <TB0> INFO: Expecting 2560 events.
[17:30:18.388] <TB0> INFO: 2560 events read in total (295ms).
[17:30:18.389] <TB0> INFO: Test took 1191ms.
[17:30:18.391] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[17:30:18.697] <TB0> INFO: Expecting 2560 events.
[17:30:19.576] <TB0> INFO: 2560 events read in total (287ms).
[17:30:19.576] <TB0> INFO: Test took 1185ms.
[17:30:19.579] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[17:30:19.885] <TB0> INFO: Expecting 2560 events.
[17:30:20.766] <TB0> INFO: 2560 events read in total (289ms).
[17:30:20.766] <TB0> INFO: Test took 1187ms.
[17:30:20.768] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[17:30:21.075] <TB0> INFO: Expecting 2560 events.
[17:30:21.964] <TB0> INFO: 2560 events read in total (297ms).
[17:30:21.964] <TB0> INFO: Test took 1196ms.
[17:30:21.967] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[17:30:22.272] <TB0> INFO: Expecting 2560 events.
[17:30:23.151] <TB0> INFO: 2560 events read in total (287ms).
[17:30:23.151] <TB0> INFO: Test took 1184ms.
[17:30:23.153] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[17:30:23.461] <TB0> INFO: Expecting 2560 events.
[17:30:24.346] <TB0> INFO: 2560 events read in total (293ms).
[17:30:24.346] <TB0> INFO: Test took 1193ms.
[17:30:24.349] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[17:30:24.655] <TB0> INFO: Expecting 2560 events.
[17:30:25.551] <TB0> INFO: 2560 events read in total (304ms).
[17:30:25.551] <TB0> INFO: Test took 1202ms.
[17:30:25.554] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[17:30:25.860] <TB0> INFO: Expecting 2560 events.
[17:30:26.753] <TB0> INFO: 2560 events read in total (301ms).
[17:30:26.754] <TB0> INFO: Test took 1200ms.
[17:30:26.758] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[17:30:27.062] <TB0> INFO: Expecting 2560 events.
[17:30:27.955] <TB0> INFO: 2560 events read in total (301ms).
[17:30:27.955] <TB0> INFO: Test took 1197ms.
[17:30:27.957] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[17:30:28.265] <TB0> INFO: Expecting 2560 events.
[17:30:29.162] <TB0> INFO: 2560 events read in total (305ms).
[17:30:29.162] <TB0> INFO: Test took 1205ms.
[17:30:29.166] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[17:30:29.470] <TB0> INFO: Expecting 2560 events.
[17:30:30.357] <TB0> INFO: 2560 events read in total (295ms).
[17:30:30.357] <TB0> INFO: Test took 1191ms.
[17:30:30.360] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[17:30:30.664] <TB0> INFO: Expecting 2560 events.
[17:30:31.552] <TB0> INFO: 2560 events read in total (296ms).
[17:30:31.552] <TB0> INFO: Test took 1192ms.
[17:30:31.557] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[17:30:31.859] <TB0> INFO: Expecting 2560 events.
[17:30:32.746] <TB0> INFO: 2560 events read in total (295ms).
[17:30:32.746] <TB0> INFO: Test took 1189ms.
[17:30:33.225] <TB0> INFO: PixTestPhOptimization::doTest() done, duration: 653 seconds
[17:30:33.225] <TB0> INFO: PH scale (per ROC): 46 40 62 64 59 55 57 69 57 58 68 56 46 46 41 44
[17:30:33.225] <TB0> INFO: PH offset (per ROC): 91 86 121 108 112 138 129 130 99 117 122 124 121 109 112 111
[17:30:33.233] <TB0> INFO: Decoding statistics:
[17:30:33.233] <TB0> INFO: General information:
[17:30:33.233] <TB0> INFO: 16bit words read: 127884
[17:30:33.233] <TB0> INFO: valid events total: 20480
[17:30:33.233] <TB0> INFO: empty events: 17978
[17:30:33.233] <TB0> INFO: valid events with pixels: 2502
[17:30:33.233] <TB0> INFO: valid pixel hits: 2502
[17:30:33.233] <TB0> INFO: Event errors: 0
[17:30:33.234] <TB0> INFO: start marker: 0
[17:30:33.234] <TB0> INFO: stop marker: 0
[17:30:33.234] <TB0> INFO: overflow: 0
[17:30:33.234] <TB0> INFO: invalid 5bit words: 0
[17:30:33.234] <TB0> INFO: invalid XOR eye diagram: 0
[17:30:33.234] <TB0> INFO: frame (failed synchr.): 0
[17:30:33.234] <TB0> INFO: idle data (no TBM trl): 0
[17:30:33.234] <TB0> INFO: no data (only TBM hdr): 0
[17:30:33.234] <TB0> INFO: TBM errors: 0
[17:30:33.234] <TB0> INFO: flawed TBM headers: 0
[17:30:33.234] <TB0> INFO: flawed TBM trailers: 0
[17:30:33.234] <TB0> INFO: event ID mismatches: 0
[17:30:33.234] <TB0> INFO: ROC errors: 0
[17:30:33.234] <TB0> INFO: missing ROC header(s): 0
[17:30:33.234] <TB0> INFO: misplaced readback start: 0
[17:30:33.234] <TB0> INFO: Pixel decoding errors: 0
[17:30:33.234] <TB0> INFO: pixel data incomplete: 0
[17:30:33.234] <TB0> INFO: pixel address: 0
[17:30:33.234] <TB0> INFO: pulse height fill bit: 0
[17:30:33.234] <TB0> INFO: buffer corruption: 0
[17:30:33.435] <TB0> INFO: ######################################################################
[17:30:33.435] <TB0> INFO: PixTestGainPedestal::fullTest() ntrig = 10
[17:30:33.435] <TB0> INFO: ######################################################################
[17:30:33.455] <TB0> INFO: scanning low vcal = 10
[17:30:33.692] <TB0> INFO: Expecting 41600 events.
[17:30:37.278] <TB0> INFO: 41600 events read in total (2994ms).
[17:30:37.278] <TB0> INFO: Test took 3823ms.
[17:30:37.280] <TB0> INFO: scanning low vcal = 20
[17:30:37.577] <TB0> INFO: Expecting 41600 events.
[17:30:41.196] <TB0> INFO: 41600 events read in total (3028ms).
[17:30:41.197] <TB0> INFO: Test took 3917ms.
[17:30:41.199] <TB0> INFO: scanning low vcal = 30
[17:30:41.494] <TB0> INFO: Expecting 41600 events.
[17:30:45.141] <TB0> INFO: 41600 events read in total (3055ms).
[17:30:45.142] <TB0> INFO: Test took 3943ms.
[17:30:45.146] <TB0> INFO: scanning low vcal = 40
[17:30:45.424] <TB0> INFO: Expecting 41600 events.
[17:30:49.387] <TB0> INFO: 41600 events read in total (3371ms).
[17:30:49.389] <TB0> INFO: Test took 4243ms.
[17:30:49.392] <TB0> INFO: scanning low vcal = 50
[17:30:49.669] <TB0> INFO: Expecting 41600 events.
[17:30:53.632] <TB0> INFO: 41600 events read in total (3371ms).
[17:30:53.633] <TB0> INFO: Test took 4240ms.
[17:30:53.637] <TB0> INFO: scanning low vcal = 60
[17:30:53.914] <TB0> INFO: Expecting 41600 events.
[17:30:57.925] <TB0> INFO: 41600 events read in total (3420ms).
[17:30:57.926] <TB0> INFO: Test took 4289ms.
[17:30:57.929] <TB0> INFO: scanning low vcal = 70
[17:30:58.207] <TB0> INFO: Expecting 41600 events.
[17:31:02.177] <TB0> INFO: 41600 events read in total (3379ms).
[17:31:02.178] <TB0> INFO: Test took 4249ms.
[17:31:02.182] <TB0> INFO: scanning low vcal = 80
[17:31:02.458] <TB0> INFO: Expecting 41600 events.
[17:31:06.460] <TB0> INFO: 41600 events read in total (3410ms).
[17:31:06.461] <TB0> INFO: Test took 4279ms.
[17:31:06.464] <TB0> INFO: scanning low vcal = 90
[17:31:06.741] <TB0> INFO: Expecting 41600 events.
[17:31:10.727] <TB0> INFO: 41600 events read in total (3394ms).
[17:31:10.728] <TB0> INFO: Test took 4264ms.
[17:31:10.732] <TB0> INFO: scanning low vcal = 100
[17:31:11.008] <TB0> INFO: Expecting 41600 events.
[17:31:14.970] <TB0> INFO: 41600 events read in total (3370ms).
[17:31:14.971] <TB0> INFO: Test took 4239ms.
[17:31:14.974] <TB0> INFO: scanning low vcal = 110
[17:31:15.251] <TB0> INFO: Expecting 41600 events.
[17:31:19.218] <TB0> INFO: 41600 events read in total (3376ms).
[17:31:19.219] <TB0> INFO: Test took 4245ms.
[17:31:19.223] <TB0> INFO: scanning low vcal = 120
[17:31:19.500] <TB0> INFO: Expecting 41600 events.
[17:31:23.474] <TB0> INFO: 41600 events read in total (3383ms).
[17:31:23.475] <TB0> INFO: Test took 4252ms.
[17:31:23.478] <TB0> INFO: scanning low vcal = 130
[17:31:23.755] <TB0> INFO: Expecting 41600 events.
[17:31:27.759] <TB0> INFO: 41600 events read in total (3412ms).
[17:31:27.760] <TB0> INFO: Test took 4282ms.
[17:31:27.764] <TB0> INFO: scanning low vcal = 140
[17:31:28.040] <TB0> INFO: Expecting 41600 events.
[17:31:32.011] <TB0> INFO: 41600 events read in total (3379ms).
[17:31:32.012] <TB0> INFO: Test took 4248ms.
[17:31:32.015] <TB0> INFO: scanning low vcal = 150
[17:31:32.292] <TB0> INFO: Expecting 41600 events.
[17:31:36.291] <TB0> INFO: 41600 events read in total (3407ms).
[17:31:36.292] <TB0> INFO: Test took 4276ms.
[17:31:36.296] <TB0> INFO: scanning low vcal = 160
[17:31:36.573] <TB0> INFO: Expecting 41600 events.
[17:31:40.533] <TB0> INFO: 41600 events read in total (3368ms).
[17:31:40.534] <TB0> INFO: Test took 4238ms.
[17:31:40.538] <TB0> INFO: scanning low vcal = 170
[17:31:40.815] <TB0> INFO: Expecting 41600 events.
[17:31:44.788] <TB0> INFO: 41600 events read in total (3382ms).
[17:31:44.789] <TB0> INFO: Test took 4251ms.
[17:31:44.796] <TB0> INFO: scanning low vcal = 180
[17:31:45.070] <TB0> INFO: Expecting 41600 events.
[17:31:49.074] <TB0> INFO: 41600 events read in total (3412ms).
[17:31:49.075] <TB0> INFO: Test took 4279ms.
[17:31:49.078] <TB0> INFO: scanning low vcal = 190
[17:31:49.356] <TB0> INFO: Expecting 41600 events.
[17:31:53.393] <TB0> INFO: 41600 events read in total (3445ms).
[17:31:53.393] <TB0> INFO: Test took 4314ms.
[17:31:53.396] <TB0> INFO: scanning low vcal = 200
[17:31:53.673] <TB0> INFO: Expecting 41600 events.
[17:31:57.634] <TB0> INFO: 41600 events read in total (3370ms).
[17:31:57.635] <TB0> INFO: Test took 4239ms.
[17:31:57.638] <TB0> INFO: scanning low vcal = 210
[17:31:57.914] <TB0> INFO: Expecting 41600 events.
[17:32:01.858] <TB0> INFO: 41600 events read in total (3352ms).
[17:32:01.858] <TB0> INFO: Test took 4220ms.
[17:32:01.861] <TB0> INFO: scanning low vcal = 220
[17:32:02.138] <TB0> INFO: Expecting 41600 events.
[17:32:06.096] <TB0> INFO: 41600 events read in total (3366ms).
[17:32:06.097] <TB0> INFO: Test took 4235ms.
[17:32:06.102] <TB0> INFO: scanning low vcal = 230
[17:32:06.379] <TB0> INFO: Expecting 41600 events.
[17:32:10.342] <TB0> INFO: 41600 events read in total (3371ms).
[17:32:10.343] <TB0> INFO: Test took 4241ms.
[17:32:10.347] <TB0> INFO: scanning low vcal = 240
[17:32:10.623] <TB0> INFO: Expecting 41600 events.
[17:32:14.602] <TB0> INFO: 41600 events read in total (3387ms).
[17:32:14.603] <TB0> INFO: Test took 4256ms.
[17:32:14.606] <TB0> INFO: scanning low vcal = 250
[17:32:14.883] <TB0> INFO: Expecting 41600 events.
[17:32:18.825] <TB0> INFO: 41600 events read in total (3351ms).
[17:32:18.826] <TB0> INFO: Test took 4220ms.
[17:32:18.832] <TB0> INFO: scanning high vcal = 30 (= 210 in low range)
[17:32:19.106] <TB0> INFO: Expecting 41600 events.
[17:32:23.103] <TB0> INFO: 41600 events read in total (3405ms).
[17:32:23.104] <TB0> INFO: Test took 4272ms.
[17:32:23.108] <TB0> INFO: scanning high vcal = 50 (= 350 in low range)
[17:32:23.384] <TB0> INFO: Expecting 41600 events.
[17:32:27.343] <TB0> INFO: 41600 events read in total (3368ms).
[17:32:27.343] <TB0> INFO: Test took 4236ms.
[17:32:27.347] <TB0> INFO: scanning high vcal = 70 (= 490 in low range)
[17:32:27.623] <TB0> INFO: Expecting 41600 events.
[17:32:31.660] <TB0> INFO: 41600 events read in total (3445ms).
[17:32:31.661] <TB0> INFO: Test took 4314ms.
[17:32:31.665] <TB0> INFO: scanning high vcal = 90 (= 630 in low range)
[17:32:31.941] <TB0> INFO: Expecting 41600 events.
[17:32:35.966] <TB0> INFO: 41600 events read in total (3433ms).
[17:32:35.966] <TB0> INFO: Test took 4301ms.
[17:32:35.970] <TB0> INFO: scanning high vcal = 200 (= 1400 in low range)
[17:32:36.266] <TB0> INFO: Expecting 41600 events.
[17:32:40.263] <TB0> INFO: 41600 events read in total (3405ms).
[17:32:40.264] <TB0> INFO: Test took 4294ms.
[17:32:40.670] <TB0> INFO: PixTestGainPedestal::measure() done
[17:33:13.938] <TB0> INFO: PixTestGainPedestal::fit() done
[17:33:13.938] <TB0> INFO: non-linearity mean: 0.908 1.000 0.974 0.977 0.976 0.976 0.979 0.983 0.950 0.979 0.982 0.978 0.935 0.921 0.934 0.932
[17:33:13.938] <TB0> INFO: non-linearity RMS: 0.077 0.182 0.008 0.011 0.005 0.006 0.007 0.004 0.043 0.003 0.003 0.004 0.133 0.110 0.131 0.086
[17:33:13.938] <TB0> INFO: write gain/ped parameters into /home/l_tester/david/FullQualification/data/M1108_FullQualification_2016-11-02_15h17m_1478096238//001_Fulltest_m20//phCalibrationFitErr35_C0.dat
[17:33:13.952] <TB0> INFO: write gain/ped parameters into /home/l_tester/david/FullQualification/data/M1108_FullQualification_2016-11-02_15h17m_1478096238//001_Fulltest_m20//phCalibrationFitErr35_C1.dat
[17:33:13.965] <TB0> INFO: write gain/ped parameters into /home/l_tester/david/FullQualification/data/M1108_FullQualification_2016-11-02_15h17m_1478096238//001_Fulltest_m20//phCalibrationFitErr35_C2.dat
[17:33:13.978] <TB0> INFO: write gain/ped parameters into /home/l_tester/david/FullQualification/data/M1108_FullQualification_2016-11-02_15h17m_1478096238//001_Fulltest_m20//phCalibrationFitErr35_C3.dat
[17:33:13.991] <TB0> INFO: write gain/ped parameters into /home/l_tester/david/FullQualification/data/M1108_FullQualification_2016-11-02_15h17m_1478096238//001_Fulltest_m20//phCalibrationFitErr35_C4.dat
[17:33:14.005] <TB0> INFO: write gain/ped parameters into /home/l_tester/david/FullQualification/data/M1108_FullQualification_2016-11-02_15h17m_1478096238//001_Fulltest_m20//phCalibrationFitErr35_C5.dat
[17:33:14.018] <TB0> INFO: write gain/ped parameters into /home/l_tester/david/FullQualification/data/M1108_FullQualification_2016-11-02_15h17m_1478096238//001_Fulltest_m20//phCalibrationFitErr35_C6.dat
[17:33:14.031] <TB0> INFO: write gain/ped parameters into /home/l_tester/david/FullQualification/data/M1108_FullQualification_2016-11-02_15h17m_1478096238//001_Fulltest_m20//phCalibrationFitErr35_C7.dat
[17:33:14.044] <TB0> INFO: write gain/ped parameters into /home/l_tester/david/FullQualification/data/M1108_FullQualification_2016-11-02_15h17m_1478096238//001_Fulltest_m20//phCalibrationFitErr35_C8.dat
[17:33:14.057] <TB0> INFO: write gain/ped parameters into /home/l_tester/david/FullQualification/data/M1108_FullQualification_2016-11-02_15h17m_1478096238//001_Fulltest_m20//phCalibrationFitErr35_C9.dat
[17:33:14.070] <TB0> INFO: write gain/ped parameters into /home/l_tester/david/FullQualification/data/M1108_FullQualification_2016-11-02_15h17m_1478096238//001_Fulltest_m20//phCalibrationFitErr35_C10.dat
[17:33:14.083] <TB0> INFO: write gain/ped parameters into /home/l_tester/david/FullQualification/data/M1108_FullQualification_2016-11-02_15h17m_1478096238//001_Fulltest_m20//phCalibrationFitErr35_C11.dat
[17:33:14.096] <TB0> INFO: write gain/ped parameters into /home/l_tester/david/FullQualification/data/M1108_FullQualification_2016-11-02_15h17m_1478096238//001_Fulltest_m20//phCalibrationFitErr35_C12.dat
[17:33:14.110] <TB0> INFO: write gain/ped parameters into /home/l_tester/david/FullQualification/data/M1108_FullQualification_2016-11-02_15h17m_1478096238//001_Fulltest_m20//phCalibrationFitErr35_C13.dat
[17:33:14.123] <TB0> INFO: write gain/ped parameters into /home/l_tester/david/FullQualification/data/M1108_FullQualification_2016-11-02_15h17m_1478096238//001_Fulltest_m20//phCalibrationFitErr35_C14.dat
[17:33:14.136] <TB0> INFO: write gain/ped parameters into /home/l_tester/david/FullQualification/data/M1108_FullQualification_2016-11-02_15h17m_1478096238//001_Fulltest_m20//phCalibrationFitErr35_C15.dat
[17:33:14.149] <TB0> INFO: PixTestGainPedestal::fullTest() done, duration: 160 seconds
[17:33:14.149] <TB0> INFO: Decoding statistics:
[17:33:14.149] <TB0> INFO: General information:
[17:33:14.149] <TB0> INFO: 16bit words read: 3327992
[17:33:14.149] <TB0> INFO: valid events total: 332800
[17:33:14.149] <TB0> INFO: empty events: 0
[17:33:14.149] <TB0> INFO: valid events with pixels: 332800
[17:33:14.149] <TB0> INFO: valid pixel hits: 665596
[17:33:14.149] <TB0> INFO: Event errors: 0
[17:33:14.149] <TB0> INFO: start marker: 0
[17:33:14.149] <TB0> INFO: stop marker: 0
[17:33:14.149] <TB0> INFO: overflow: 0
[17:33:14.149] <TB0> INFO: invalid 5bit words: 0
[17:33:14.149] <TB0> INFO: invalid XOR eye diagram: 0
[17:33:14.149] <TB0> INFO: frame (failed synchr.): 0
[17:33:14.149] <TB0> INFO: idle data (no TBM trl): 0
[17:33:14.149] <TB0> INFO: no data (only TBM hdr): 0
[17:33:14.149] <TB0> INFO: TBM errors: 0
[17:33:14.149] <TB0> INFO: flawed TBM headers: 0
[17:33:14.149] <TB0> INFO: flawed TBM trailers: 0
[17:33:14.149] <TB0> INFO: event ID mismatches: 0
[17:33:14.149] <TB0> INFO: ROC errors: 0
[17:33:14.149] <TB0> INFO: missing ROC header(s): 0
[17:33:14.149] <TB0> INFO: misplaced readback start: 0
[17:33:14.149] <TB0> INFO: Pixel decoding errors: 0
[17:33:14.149] <TB0> INFO: pixel data incomplete: 0
[17:33:14.149] <TB0> INFO: pixel address: 0
[17:33:14.149] <TB0> INFO: pulse height fill bit: 0
[17:33:14.149] <TB0> INFO: buffer corruption: 0
[17:33:14.167] <TB0> INFO: Decoding statistics:
[17:33:14.167] <TB0> INFO: General information:
[17:33:14.167] <TB0> INFO: 16bit words read: 3457412
[17:33:14.167] <TB0> INFO: valid events total: 353536
[17:33:14.167] <TB0> INFO: empty events: 18234
[17:33:14.167] <TB0> INFO: valid events with pixels: 335302
[17:33:14.167] <TB0> INFO: valid pixel hits: 668098
[17:33:14.167] <TB0> INFO: Event errors: 0
[17:33:14.167] <TB0> INFO: start marker: 0
[17:33:14.167] <TB0> INFO: stop marker: 0
[17:33:14.167] <TB0> INFO: overflow: 0
[17:33:14.167] <TB0> INFO: invalid 5bit words: 0
[17:33:14.167] <TB0> INFO: invalid XOR eye diagram: 0
[17:33:14.167] <TB0> INFO: frame (failed synchr.): 0
[17:33:14.167] <TB0> INFO: idle data (no TBM trl): 0
[17:33:14.167] <TB0> INFO: no data (only TBM hdr): 0
[17:33:14.167] <TB0> INFO: TBM errors: 0
[17:33:14.167] <TB0> INFO: flawed TBM headers: 0
[17:33:14.167] <TB0> INFO: flawed TBM trailers: 0
[17:33:14.167] <TB0> INFO: event ID mismatches: 0
[17:33:14.167] <TB0> INFO: ROC errors: 0
[17:33:14.167] <TB0> INFO: missing ROC header(s): 0
[17:33:14.167] <TB0> INFO: misplaced readback start: 0
[17:33:14.167] <TB0> INFO: Pixel decoding errors: 0
[17:33:14.167] <TB0> INFO: pixel data incomplete: 0
[17:33:14.167] <TB0> INFO: pixel address: 0
[17:33:14.167] <TB0> INFO: pulse height fill bit: 0
[17:33:14.167] <TB0> INFO: buffer corruption: 0
[17:33:14.167] <TB0> INFO: enter test to run
[17:33:14.167] <TB0> INFO: test: exit no parameter change
[17:33:14.305] <TB0> QUIET: Connection to board 71 closed.
[17:33:14.307] <TB0> INFO: pXar: this is the end, my friend