[16:10:42.485] <TB3> INFO: *** Welcome to pxar ***
[16:10:42.485] <TB3> INFO: *** Today: 2016/11/02
[16:10:42.492] <TB3> INFO: *** Version: c8ba-dirty
[16:10:42.492] <TB3> INFO: readRocDacs: /home/l_tester/david/FullQualification/data/M1111_FullQualification_2016-11-02_15h17m_1478096238//001_Fulltest_m20//dacParameters_C0.dat .. /home/l_tester/david/FullQualification/data/M1111_FullQualification_2016-11-02_15h17m_1478096238//001_Fulltest_m20//dacParameters_C15.dat
[16:10:42.492] <TB3> INFO: readTbmDacs: /home/l_tester/david/FullQualification/data/M1111_FullQualification_2016-11-02_15h17m_1478096238//001_Fulltest_m20//tbmParameters_C0a.dat .. /home/l_tester/david/FullQualification/data/M1111_FullQualification_2016-11-02_15h17m_1478096238//001_Fulltest_m20//tbmParameters_C1b.dat
[16:10:42.492] <TB3> INFO: readMaskFile: /home/l_tester/david/FullQualification/data/M1111_FullQualification_2016-11-02_15h17m_1478096238//001_Fulltest_m20//defaultMaskFile.dat
[16:10:42.492] <TB3> INFO: readTrimFile: /home/l_tester/david/FullQualification/data/M1111_FullQualification_2016-11-02_15h17m_1478096238//001_Fulltest_m20//trimParameters_C0.dat .. /home/l_tester/david/FullQualification/data/M1111_FullQualification_2016-11-02_15h17m_1478096238//001_Fulltest_m20//trimParameters_C15.dat
[16:10:42.555] <TB3> INFO: clk: 4
[16:10:42.556] <TB3> INFO: ctr: 4
[16:10:42.556] <TB3> INFO: sda: 19
[16:10:42.556] <TB3> INFO: tin: 9
[16:10:42.556] <TB3> INFO: level: 15
[16:10:42.556] <TB3> INFO: triggerdelay: 0
[16:10:42.556] <TB3> QUIET: Instanciating API for pxar v2.7.6+61~g7f4a123
[16:10:42.556] <TB3> INFO: Log level: INFO
[16:10:42.564] <TB3> INFO: Found DTB DTB_WWVASW
[16:10:42.574] <TB3> QUIET: Connection to board DTB_WWVASW opened.
[16:10:42.576] <TB3> INFO: DTB startup information
--- DTB info------------------------------------------
Board id: 126
HW version: DTB1.2
FW version: 4.6
SW version: 4.7
Options:
USB id: DTB_WWVASW
MAC address: 40D85511807E
Hostname: pixelDTB126
Comment:
------------------------------------------------------
[16:10:42.577] <TB3> INFO: RPC call hashes of host and DTB match: 486171790
[16:10:44.075] <TB3> INFO: DUT info:
[16:10:44.075] <TB3> INFO: The DUT currently contains the following objects:
[16:10:44.075] <TB3> INFO: 4 TBM Cores tbm10c (4 ON)
[16:10:44.075] <TB3> INFO: TBM Core alpha (0): 7 registers set
[16:10:44.075] <TB3> INFO: TBM Core beta (1): 7 registers set
[16:10:44.075] <TB3> INFO: TBM Core alpha (2): 7 registers set
[16:10:44.075] <TB3> INFO: TBM Core beta (3): 7 registers set
[16:10:44.075] <TB3> INFO: 16 ROCs proc600 (16 ON) with 4160 pixelConfigs
[16:10:44.075] <TB3> INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[16:10:44.075] <TB3> INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[16:10:44.076] <TB3> INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[16:10:44.076] <TB3> INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[16:10:44.076] <TB3> INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[16:10:44.076] <TB3> INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[16:10:44.076] <TB3> INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[16:10:44.076] <TB3> INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[16:10:44.076] <TB3> INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[16:10:44.076] <TB3> INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[16:10:44.076] <TB3> INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[16:10:44.076] <TB3> INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[16:10:44.076] <TB3> INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[16:10:44.076] <TB3> INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[16:10:44.076] <TB3> INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[16:10:44.076] <TB3> INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[16:10:44.477] <TB3> INFO: enter 'restricted' command line mode
[16:10:44.477] <TB3> INFO: enter test to run
[16:10:44.477] <TB3> INFO: test: pretest no parameter change
[16:10:44.477] <TB3> INFO: running: pretest
[16:10:44.484] <TB3> INFO: ######################################################################
[16:10:44.484] <TB3> INFO: PixTestPretest::doTest()
[16:10:44.484] <TB3> INFO: ######################################################################
[16:10:44.485] <TB3> INFO: ----------------------------------------------------------------------
[16:10:44.485] <TB3> INFO: PixTestPretest::programROC()
[16:10:44.485] <TB3> INFO: ----------------------------------------------------------------------
[16:11:02.500] <TB3> INFO: PixTestPretest::programROC() done: ROCs are all programmable
[16:11:02.500] <TB3> INFO: IA differences per ROC: 20.1 20.1 18.5 20.1 18.5 19.3 19.3 19.3 19.3 24.1 17.7 18.5 17.7 19.3 20.9 21.7
[16:11:02.566] <TB3> INFO: ----------------------------------------------------------------------
[16:11:02.566] <TB3> INFO: PixTestPretest::setVana() target Ia = 24 mA/ROC
[16:11:02.566] <TB3> INFO: ----------------------------------------------------------------------
[16:11:10.073] <TB3> INFO: PixTestPretest::setVana() done, Module Ia 405.2 mA = 25.325 mA/ROC
[16:11:10.073] <TB3> INFO: i(loss) [mA/ROC]: 20.9 20.9 20.9 20.1 20.9 20.1 20.9 20.9 20.1 20.9 20.9 20.1 20.1 20.9 20.9 20.9
[16:11:10.108] <TB3> INFO: ----------------------------------------------------------------------
[16:11:10.108] <TB3> INFO: PixTestPretest::findTiming()
[16:11:10.108] <TB3> INFO: ----------------------------------------------------------------------
[16:11:10.109] <TB3> INFO: PixTestCmd::init()

[16:11:42.193] <TB3> INFO: TBM phases: 160MHz: 6, 400MHz: 6, TBM delays: ROC(0/1):5, header/trailer: 1, token: 1
[16:11:42.193] <TB3> INFO: (success/tries = 100/100), width = 4
[16:11:43.702] <TB3> INFO: ----------------------------------------------------------------------
[16:11:43.702] <TB3> INFO: PixTestPretest::findWorkingPixel()
[16:11:43.702] <TB3> INFO: ----------------------------------------------------------------------
[16:11:43.795] <TB3> INFO: Expecting 231680 events.
[16:11:53.861] <TB3> INFO: 231680 events read in total (9474ms).
[16:11:53.870] <TB3> INFO: Test took 10165ms.
[16:11:54.119] <TB3> INFO: Found working pixel in all ROCs: col/row = 12/22
[16:11:54.155] <TB3> INFO: ----------------------------------------------------------------------
[16:11:54.155] <TB3> INFO: PixTestPretest::setVthrCompCalDel()
[16:11:54.155] <TB3> INFO: ----------------------------------------------------------------------
[16:11:54.250] <TB3> INFO: Expecting 231680 events.
[16:12:04.388] <TB3> INFO: 231680 events read in total (9546ms).
[16:12:04.401] <TB3> INFO: Test took 10240ms.
[16:12:04.656] <TB3> INFO: PixTestPretest::setVthrCompCalDel() done
[16:12:04.656] <TB3> INFO: CalDel: 91 96 111 112 105 113 109 83 106 91 102 106 99 91 96 97
[16:12:04.656] <TB3> INFO: VthrComp: 51 51 51 51 51 51 51 51 51 51 51 51 51 51 51 52
[16:12:04.660] <TB3> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1111_FullQualification_2016-11-02_15h17m_1478096238//001_Fulltest_m20//dacParameters_C0.dat
[16:12:04.660] <TB3> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1111_FullQualification_2016-11-02_15h17m_1478096238//001_Fulltest_m20//dacParameters_C1.dat
[16:12:04.660] <TB3> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1111_FullQualification_2016-11-02_15h17m_1478096238//001_Fulltest_m20//dacParameters_C2.dat
[16:12:04.660] <TB3> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1111_FullQualification_2016-11-02_15h17m_1478096238//001_Fulltest_m20//dacParameters_C3.dat
[16:12:04.660] <TB3> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1111_FullQualification_2016-11-02_15h17m_1478096238//001_Fulltest_m20//dacParameters_C4.dat
[16:12:04.661] <TB3> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1111_FullQualification_2016-11-02_15h17m_1478096238//001_Fulltest_m20//dacParameters_C5.dat
[16:12:04.661] <TB3> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1111_FullQualification_2016-11-02_15h17m_1478096238//001_Fulltest_m20//dacParameters_C6.dat
[16:12:04.661] <TB3> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1111_FullQualification_2016-11-02_15h17m_1478096238//001_Fulltest_m20//dacParameters_C7.dat
[16:12:04.661] <TB3> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1111_FullQualification_2016-11-02_15h17m_1478096238//001_Fulltest_m20//dacParameters_C8.dat
[16:12:04.661] <TB3> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1111_FullQualification_2016-11-02_15h17m_1478096238//001_Fulltest_m20//dacParameters_C9.dat
[16:12:04.662] <TB3> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1111_FullQualification_2016-11-02_15h17m_1478096238//001_Fulltest_m20//dacParameters_C10.dat
[16:12:04.662] <TB3> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1111_FullQualification_2016-11-02_15h17m_1478096238//001_Fulltest_m20//dacParameters_C11.dat
[16:12:04.662] <TB3> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1111_FullQualification_2016-11-02_15h17m_1478096238//001_Fulltest_m20//dacParameters_C12.dat
[16:12:04.662] <TB3> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1111_FullQualification_2016-11-02_15h17m_1478096238//001_Fulltest_m20//dacParameters_C13.dat
[16:12:04.662] <TB3> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1111_FullQualification_2016-11-02_15h17m_1478096238//001_Fulltest_m20//dacParameters_C14.dat
[16:12:04.662] <TB3> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1111_FullQualification_2016-11-02_15h17m_1478096238//001_Fulltest_m20//dacParameters_C15.dat
[16:12:04.662] <TB3> INFO: write tbm parameters into /home/l_tester/david/FullQualification/data/M1111_FullQualification_2016-11-02_15h17m_1478096238//001_Fulltest_m20//tbmParameters_C0a.dat
[16:12:04.663] <TB3> INFO: write tbm parameters into /home/l_tester/david/FullQualification/data/M1111_FullQualification_2016-11-02_15h17m_1478096238//001_Fulltest_m20//tbmParameters_C0b.dat
[16:12:04.663] <TB3> INFO: write tbm parameters into /home/l_tester/david/FullQualification/data/M1111_FullQualification_2016-11-02_15h17m_1478096238//001_Fulltest_m20//tbmParameters_C1a.dat
[16:12:04.663] <TB3> INFO: write tbm parameters into /home/l_tester/david/FullQualification/data/M1111_FullQualification_2016-11-02_15h17m_1478096238//001_Fulltest_m20//tbmParameters_C1b.dat
[16:12:04.663] <TB3> INFO: PixTestPretest::doTest() done, duration: 80 seconds
[16:12:04.720] <TB3> INFO: enter test to run
[16:12:04.721] <TB3> INFO: test: FullTest no parameter change
[16:12:04.721] <TB3> INFO: running: fulltest
[16:12:04.721] <TB3> INFO: ######################################################################
[16:12:04.721] <TB3> INFO: PixTestFullTest::doTest()
[16:12:04.721] <TB3> INFO: ######################################################################
[16:12:04.722] <TB3> INFO: ######################################################################
[16:12:04.722] <TB3> INFO: PixTestAlive::doTest()
[16:12:04.722] <TB3> INFO: ######################################################################
[16:12:04.723] <TB3> INFO: ----------------------------------------------------------------------
[16:12:04.723] <TB3> 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:12:04.723] <TB3> INFO: ----------------------------------------------------------------------
[16:12:04.963] <TB3> INFO: Expecting 41600 events.
[16:12:08.477] <TB3> INFO: 41600 events read in total (2922ms).
[16:12:08.478] <TB3> INFO: Test took 3753ms.
[16:12:08.711] <TB3> INFO: PixTestAlive::aliveTest() done
[16:12:08.712] <TB3> INFO: number of dead pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 1 0 0 0 0 0
[16:12:08.713] <TB3> INFO: ----------------------------------------------------------------------
[16:12:08.714] <TB3> 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:12:08.714] <TB3> INFO: ----------------------------------------------------------------------
[16:12:08.961] <TB3> INFO: Expecting 41600 events.
[16:12:11.948] <TB3> INFO: 41600 events read in total (2395ms).
[16:12:11.948] <TB3> INFO: Test took 3232ms.
[16:12:11.949] <TB3> INFO: mask vs. old pixelAlive PixelAlive_C0_V0 .. PixelAlive_C15_V0
[16:12:12.187] <TB3> INFO: PixTestAlive::maskTest() done
[16:12:12.187] <TB3> 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:12:12.189] <TB3> INFO: ----------------------------------------------------------------------
[16:12:12.189] <TB3> 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:12:12.189] <TB3> INFO: ----------------------------------------------------------------------
[16:12:12.432] <TB3> INFO: Expecting 41600 events.
[16:12:15.969] <TB3> INFO: 41600 events read in total (2945ms).
[16:12:15.969] <TB3> INFO: Test took 3778ms.
[16:12:16.203] <TB3> INFO: PixTestAlive::addressDecodingTest() done
[16:12:16.204] <TB3> 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:12:16.204] <TB3> INFO: PixTestAlive::doTest() done, duration: 11 seconds
[16:12:16.204] <TB3> INFO: Decoding statistics:
[16:12:16.204] <TB3> INFO: General information:
[16:12:16.204] <TB3> INFO: 16bit words read: 0
[16:12:16.204] <TB3> INFO: valid events total: 0
[16:12:16.204] <TB3> INFO: empty events: 0
[16:12:16.204] <TB3> INFO: valid events with pixels: 0
[16:12:16.204] <TB3> INFO: valid pixel hits: 0
[16:12:16.204] <TB3> INFO: Event errors: 0
[16:12:16.204] <TB3> INFO: start marker: 0
[16:12:16.204] <TB3> INFO: stop marker: 0
[16:12:16.204] <TB3> INFO: overflow: 0
[16:12:16.204] <TB3> INFO: invalid 5bit words: 0
[16:12:16.204] <TB3> INFO: invalid XOR eye diagram: 0
[16:12:16.204] <TB3> INFO: frame (failed synchr.): 0
[16:12:16.204] <TB3> INFO: idle data (no TBM trl): 0
[16:12:16.204] <TB3> INFO: no data (only TBM hdr): 0
[16:12:16.204] <TB3> INFO: TBM errors: 0
[16:12:16.204] <TB3> INFO: flawed TBM headers: 0
[16:12:16.204] <TB3> INFO: flawed TBM trailers: 0
[16:12:16.204] <TB3> INFO: event ID mismatches: 0
[16:12:16.204] <TB3> INFO: ROC errors: 0
[16:12:16.204] <TB3> INFO: missing ROC header(s): 0
[16:12:16.205] <TB3> INFO: misplaced readback start: 0
[16:12:16.205] <TB3> INFO: Pixel decoding errors: 0
[16:12:16.205] <TB3> INFO: pixel data incomplete: 0
[16:12:16.205] <TB3> INFO: pixel address: 0
[16:12:16.205] <TB3> INFO: pulse height fill bit: 0
[16:12:16.205] <TB3> INFO: buffer corruption: 0
[16:12:16.212] <TB3> INFO: readReadbackCal: /home/l_tester/david/FullQualification/data/M1111_FullQualification_2016-11-02_15h17m_1478096238//001_Fulltest_m20//readbackCal_C0.dat .. /home/l_tester/david/FullQualification/data/M1111_FullQualification_2016-11-02_15h17m_1478096238//001_Fulltest_m20//readbackCal_C15.dat
[16:12:16.212] <TB3> INFO: readGainPedestalParameters /home/l_tester/david/FullQualification/data/M1111_FullQualification_2016-11-02_15h17m_1478096238//001_Fulltest_m20//phCalibrationFitErr_C0.dat .. /home/l_tester/david/FullQualification/data/M1111_FullQualification_2016-11-02_15h17m_1478096238//001_Fulltest_m20//phCalibrationFitErr_C15.dat

[16:12:16.212] <TB3> INFO: ######################################################################
[16:12:16.212] <TB3> INFO: PixTestReadback::doTest()
[16:12:16.212] <TB3> INFO: ######################################################################
[16:12:16.212] <TB3> INFO: ----------------------------------------------------------------------
[16:12:16.212] <TB3> INFO: PixTestReadback::CalibrateVd()
[16:12:16.212] <TB3> INFO: ----------------------------------------------------------------------
[16:12:26.197] <TB3> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1111_FullQualification_2016-11-02_15h17m_1478096238//001_Fulltest_m20//readbackCal_C0.dat
[16:12:26.197] <TB3> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1111_FullQualification_2016-11-02_15h17m_1478096238//001_Fulltest_m20//readbackCal_C1.dat
[16:12:26.197] <TB3> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1111_FullQualification_2016-11-02_15h17m_1478096238//001_Fulltest_m20//readbackCal_C2.dat
[16:12:26.198] <TB3> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1111_FullQualification_2016-11-02_15h17m_1478096238//001_Fulltest_m20//readbackCal_C3.dat
[16:12:26.198] <TB3> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1111_FullQualification_2016-11-02_15h17m_1478096238//001_Fulltest_m20//readbackCal_C4.dat
[16:12:26.198] <TB3> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1111_FullQualification_2016-11-02_15h17m_1478096238//001_Fulltest_m20//readbackCal_C5.dat
[16:12:26.198] <TB3> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1111_FullQualification_2016-11-02_15h17m_1478096238//001_Fulltest_m20//readbackCal_C6.dat
[16:12:26.198] <TB3> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1111_FullQualification_2016-11-02_15h17m_1478096238//001_Fulltest_m20//readbackCal_C7.dat
[16:12:26.198] <TB3> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1111_FullQualification_2016-11-02_15h17m_1478096238//001_Fulltest_m20//readbackCal_C8.dat
[16:12:26.198] <TB3> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1111_FullQualification_2016-11-02_15h17m_1478096238//001_Fulltest_m20//readbackCal_C9.dat
[16:12:26.198] <TB3> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1111_FullQualification_2016-11-02_15h17m_1478096238//001_Fulltest_m20//readbackCal_C10.dat
[16:12:26.198] <TB3> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1111_FullQualification_2016-11-02_15h17m_1478096238//001_Fulltest_m20//readbackCal_C11.dat
[16:12:26.198] <TB3> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1111_FullQualification_2016-11-02_15h17m_1478096238//001_Fulltest_m20//readbackCal_C12.dat
[16:12:26.198] <TB3> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1111_FullQualification_2016-11-02_15h17m_1478096238//001_Fulltest_m20//readbackCal_C13.dat
[16:12:26.198] <TB3> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1111_FullQualification_2016-11-02_15h17m_1478096238//001_Fulltest_m20//readbackCal_C14.dat
[16:12:26.198] <TB3> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1111_FullQualification_2016-11-02_15h17m_1478096238//001_Fulltest_m20//readbackCal_C15.dat
[16:12:26.234] <TB3> INFO: PixTestPattern:: pg_setup set to default.
[16:12:26.234] <TB3> INFO: ----------------------------------------------------------------------
[16:12:26.234] <TB3> INFO: PixTestReadback::CalibrateVa()
[16:12:26.234] <TB3> INFO: ----------------------------------------------------------------------
[16:12:36.173] <TB3> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1111_FullQualification_2016-11-02_15h17m_1478096238//001_Fulltest_m20//readbackCal_C0.dat
[16:12:36.173] <TB3> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1111_FullQualification_2016-11-02_15h17m_1478096238//001_Fulltest_m20//readbackCal_C1.dat
[16:12:36.173] <TB3> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1111_FullQualification_2016-11-02_15h17m_1478096238//001_Fulltest_m20//readbackCal_C2.dat
[16:12:36.173] <TB3> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1111_FullQualification_2016-11-02_15h17m_1478096238//001_Fulltest_m20//readbackCal_C3.dat
[16:12:36.173] <TB3> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1111_FullQualification_2016-11-02_15h17m_1478096238//001_Fulltest_m20//readbackCal_C4.dat
[16:12:36.173] <TB3> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1111_FullQualification_2016-11-02_15h17m_1478096238//001_Fulltest_m20//readbackCal_C5.dat
[16:12:36.173] <TB3> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1111_FullQualification_2016-11-02_15h17m_1478096238//001_Fulltest_m20//readbackCal_C6.dat
[16:12:36.173] <TB3> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1111_FullQualification_2016-11-02_15h17m_1478096238//001_Fulltest_m20//readbackCal_C7.dat
[16:12:36.174] <TB3> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1111_FullQualification_2016-11-02_15h17m_1478096238//001_Fulltest_m20//readbackCal_C8.dat
[16:12:36.174] <TB3> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1111_FullQualification_2016-11-02_15h17m_1478096238//001_Fulltest_m20//readbackCal_C9.dat
[16:12:36.174] <TB3> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1111_FullQualification_2016-11-02_15h17m_1478096238//001_Fulltest_m20//readbackCal_C10.dat
[16:12:36.174] <TB3> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1111_FullQualification_2016-11-02_15h17m_1478096238//001_Fulltest_m20//readbackCal_C11.dat
[16:12:36.174] <TB3> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1111_FullQualification_2016-11-02_15h17m_1478096238//001_Fulltest_m20//readbackCal_C12.dat
[16:12:36.174] <TB3> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1111_FullQualification_2016-11-02_15h17m_1478096238//001_Fulltest_m20//readbackCal_C13.dat
[16:12:36.174] <TB3> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1111_FullQualification_2016-11-02_15h17m_1478096238//001_Fulltest_m20//readbackCal_C14.dat
[16:12:36.174] <TB3> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1111_FullQualification_2016-11-02_15h17m_1478096238//001_Fulltest_m20//readbackCal_C15.dat
[16:12:36.204] <TB3> INFO: PixTestPattern:: pg_setup set to default.
[16:12:36.204] <TB3> INFO: ----------------------------------------------------------------------
[16:12:36.204] <TB3> INFO: PixTestReadback::readbackVbg()
[16:12:36.204] <TB3> INFO: ----------------------------------------------------------------------
[16:12:43.879] <TB3> INFO: PixTestPattern:: pg_setup set to default.
[16:12:43.879] <TB3> INFO: ----------------------------------------------------------------------
[16:12:43.879] <TB3> INFO: PixTestReadback::getCalibratedVbg()
[16:12:43.879] <TB3> INFO: ----------------------------------------------------------------------
[16:12:43.879] <TB3> INFO: Vbg will be calibrated using Vd calibration
[16:12:43.879] <TB3> INFO: /*/*/*/*::: ROC 0: uncalibrated Vbg = 154.2calibrated Vbg = 1.19722 :::*/*/*/*/
[16:12:43.879] <TB3> INFO: /*/*/*/*::: ROC 1: uncalibrated Vbg = 149.5calibrated Vbg = 1.19552 :::*/*/*/*/
[16:12:43.879] <TB3> INFO: /*/*/*/*::: ROC 2: uncalibrated Vbg = 155.3calibrated Vbg = 1.19001 :::*/*/*/*/
[16:12:43.879] <TB3> INFO: /*/*/*/*::: ROC 3: uncalibrated Vbg = 156.7calibrated Vbg = 1.19105 :::*/*/*/*/
[16:12:43.879] <TB3> INFO: /*/*/*/*::: ROC 4: uncalibrated Vbg = 141.9calibrated Vbg = 1.19169 :::*/*/*/*/
[16:12:43.879] <TB3> INFO: /*/*/*/*::: ROC 5: uncalibrated Vbg = 161.6calibrated Vbg = 1.19755 :::*/*/*/*/
[16:12:43.879] <TB3> INFO: /*/*/*/*::: ROC 6: uncalibrated Vbg = 159.3calibrated Vbg = 1.19769 :::*/*/*/*/
[16:12:43.879] <TB3> INFO: /*/*/*/*::: ROC 7: uncalibrated Vbg = 154.8calibrated Vbg = 1.19665 :::*/*/*/*/
[16:12:43.880] <TB3> INFO: /*/*/*/*::: ROC 8: uncalibrated Vbg = 150.1calibrated Vbg = 1.19705 :::*/*/*/*/
[16:12:43.880] <TB3> INFO: /*/*/*/*::: ROC 9: uncalibrated Vbg = 143.7calibrated Vbg = 1.19041 :::*/*/*/*/
[16:12:43.880] <TB3> INFO: /*/*/*/*::: ROC 10: uncalibrated Vbg = 162.1calibrated Vbg = 1.18699 :::*/*/*/*/
[16:12:43.880] <TB3> INFO: /*/*/*/*::: ROC 11: uncalibrated Vbg = 153.9calibrated Vbg = 1.18623 :::*/*/*/*/
[16:12:43.880] <TB3> INFO: /*/*/*/*::: ROC 12: uncalibrated Vbg = 155.3calibrated Vbg = 1.19 :::*/*/*/*/
[16:12:43.880] <TB3> INFO: /*/*/*/*::: ROC 13: uncalibrated Vbg = 157.9calibrated Vbg = 1.19277 :::*/*/*/*/
[16:12:43.880] <TB3> INFO: /*/*/*/*::: ROC 14: uncalibrated Vbg = 150calibrated Vbg = 1.19814 :::*/*/*/*/
[16:12:43.880] <TB3> INFO: /*/*/*/*::: ROC 15: uncalibrated Vbg = 156.9calibrated Vbg = 1.19294 :::*/*/*/*/
[16:12:43.883] <TB3> INFO: ----------------------------------------------------------------------
[16:12:43.883] <TB3> INFO: PixTestReadback::CalibrateIa()
[16:12:43.883] <TB3> INFO: ----------------------------------------------------------------------
[16:15:24.716] <TB3> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1111_FullQualification_2016-11-02_15h17m_1478096238//001_Fulltest_m20//readbackCal_C0.dat
[16:15:24.716] <TB3> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1111_FullQualification_2016-11-02_15h17m_1478096238//001_Fulltest_m20//readbackCal_C1.dat
[16:15:24.716] <TB3> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1111_FullQualification_2016-11-02_15h17m_1478096238//001_Fulltest_m20//readbackCal_C2.dat
[16:15:24.716] <TB3> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1111_FullQualification_2016-11-02_15h17m_1478096238//001_Fulltest_m20//readbackCal_C3.dat
[16:15:24.716] <TB3> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1111_FullQualification_2016-11-02_15h17m_1478096238//001_Fulltest_m20//readbackCal_C4.dat
[16:15:24.716] <TB3> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1111_FullQualification_2016-11-02_15h17m_1478096238//001_Fulltest_m20//readbackCal_C5.dat
[16:15:24.717] <TB3> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1111_FullQualification_2016-11-02_15h17m_1478096238//001_Fulltest_m20//readbackCal_C6.dat
[16:15:24.717] <TB3> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1111_FullQualification_2016-11-02_15h17m_1478096238//001_Fulltest_m20//readbackCal_C7.dat
[16:15:24.717] <TB3> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1111_FullQualification_2016-11-02_15h17m_1478096238//001_Fulltest_m20//readbackCal_C8.dat
[16:15:24.717] <TB3> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1111_FullQualification_2016-11-02_15h17m_1478096238//001_Fulltest_m20//readbackCal_C9.dat
[16:15:24.717] <TB3> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1111_FullQualification_2016-11-02_15h17m_1478096238//001_Fulltest_m20//readbackCal_C10.dat
[16:15:24.717] <TB3> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1111_FullQualification_2016-11-02_15h17m_1478096238//001_Fulltest_m20//readbackCal_C11.dat
[16:15:24.717] <TB3> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1111_FullQualification_2016-11-02_15h17m_1478096238//001_Fulltest_m20//readbackCal_C12.dat
[16:15:24.717] <TB3> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1111_FullQualification_2016-11-02_15h17m_1478096238//001_Fulltest_m20//readbackCal_C13.dat
[16:15:24.717] <TB3> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1111_FullQualification_2016-11-02_15h17m_1478096238//001_Fulltest_m20//readbackCal_C14.dat
[16:15:24.717] <TB3> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1111_FullQualification_2016-11-02_15h17m_1478096238//001_Fulltest_m20//readbackCal_C15.dat
[16:15:24.744] <TB3> INFO: PixTestPattern:: pg_setup set to default.
[16:15:24.745] <TB3> INFO: PixTestReadback::doTest() done
[16:15:24.745] <TB3> INFO: Decoding statistics:
[16:15:24.745] <TB3> INFO: General information:
[16:15:24.745] <TB3> INFO: 16bit words read: 1536
[16:15:24.745] <TB3> INFO: valid events total: 256
[16:15:24.745] <TB3> INFO: empty events: 256
[16:15:24.745] <TB3> INFO: valid events with pixels: 0
[16:15:24.745] <TB3> INFO: valid pixel hits: 0
[16:15:24.745] <TB3> INFO: Event errors: 0
[16:15:24.745] <TB3> INFO: start marker: 0
[16:15:24.745] <TB3> INFO: stop marker: 0
[16:15:24.745] <TB3> INFO: overflow: 0
[16:15:24.745] <TB3> INFO: invalid 5bit words: 0
[16:15:24.745] <TB3> INFO: invalid XOR eye diagram: 0
[16:15:24.745] <TB3> INFO: frame (failed synchr.): 0
[16:15:24.745] <TB3> INFO: idle data (no TBM trl): 0
[16:15:24.745] <TB3> INFO: no data (only TBM hdr): 0
[16:15:24.745] <TB3> INFO: TBM errors: 0
[16:15:24.745] <TB3> INFO: flawed TBM headers: 0
[16:15:24.745] <TB3> INFO: flawed TBM trailers: 0
[16:15:24.745] <TB3> INFO: event ID mismatches: 0
[16:15:24.745] <TB3> INFO: ROC errors: 0
[16:15:24.745] <TB3> INFO: missing ROC header(s): 0
[16:15:24.745] <TB3> INFO: misplaced readback start: 0
[16:15:24.745] <TB3> INFO: Pixel decoding errors: 0
[16:15:24.745] <TB3> INFO: pixel data incomplete: 0
[16:15:24.745] <TB3> INFO: pixel address: 0
[16:15:24.745] <TB3> INFO: pulse height fill bit: 0
[16:15:24.745] <TB3> INFO: buffer corruption: 0
[16:15:24.812] <TB3> INFO: ######################################################################
[16:15:24.812] <TB3> INFO: PixTestBBMap::doTest() Ntrig = 5, VcalS = 250 (high range)
[16:15:24.812] <TB3> INFO: ######################################################################
[16:15:24.814] <TB3> INFO: ---> dac: VthrComp name: calSMap ntrig: 5 dacrange: 0 .. 149 (-1/-1) hits flags = 514 (plus default)
[16:15:24.849] <TB3> INFO: dacScan split into 1 runs with ntrig = 5
[16:15:24.849] <TB3> INFO: run 1 of 1
[16:15:25.131] <TB3> INFO: Expecting 3120000 events.
[16:15:55.889] <TB3> INFO: 669780 events read in total (30166ms).











[16:16:26.439] <TB3> INFO: 1337560 events read in total (60716ms).











[16:16:56.668] <TB3> INFO: 2001420 events read in total (90945ms).











[16:17:26.960] <TB3> INFO: 2664970 events read in total (121237ms).











[16:17:47.668] <TB3> INFO: 3120000 events read in total (141945ms).
[16:17:47.787] <TB3> INFO: Test took 142939ms.
[16:18:10.552] <TB3> INFO: PixTestBBMap::doTest() done with 1 decoding errors: , duration: 165 seconds
[16:18:10.552] <TB3> INFO: number of dead bumps (per ROC): 117 3 91 7 7 14 1 2 9 7 2 322 65 301 21 21
[16:18:10.552] <TB3> INFO: separation cut (per ROC): 98 107 93 106 105 101 104 106 103 122 109 94 98 99 111 133
[16:18:10.552] <TB3> INFO: Decoding statistics:
[16:18:10.552] <TB3> INFO: General information:
[16:18:10.552] <TB3> INFO: 16bit words read: 0
[16:18:10.552] <TB3> INFO: valid events total: 0
[16:18:10.552] <TB3> INFO: empty events: 0
[16:18:10.552] <TB3> INFO: valid events with pixels: 0
[16:18:10.552] <TB3> INFO: valid pixel hits: 0
[16:18:10.552] <TB3> INFO: Event errors: 0
[16:18:10.552] <TB3> INFO: start marker: 0
[16:18:10.552] <TB3> INFO: stop marker: 0
[16:18:10.552] <TB3> INFO: overflow: 0
[16:18:10.552] <TB3> INFO: invalid 5bit words: 0
[16:18:10.552] <TB3> INFO: invalid XOR eye diagram: 0
[16:18:10.552] <TB3> INFO: frame (failed synchr.): 0
[16:18:10.552] <TB3> INFO: idle data (no TBM trl): 0
[16:18:10.552] <TB3> INFO: no data (only TBM hdr): 0
[16:18:10.552] <TB3> INFO: TBM errors: 0
[16:18:10.552] <TB3> INFO: flawed TBM headers: 0
[16:18:10.552] <TB3> INFO: flawed TBM trailers: 0
[16:18:10.552] <TB3> INFO: event ID mismatches: 0
[16:18:10.552] <TB3> INFO: ROC errors: 0
[16:18:10.552] <TB3> INFO: missing ROC header(s): 0
[16:18:10.552] <TB3> INFO: misplaced readback start: 0
[16:18:10.552] <TB3> INFO: Pixel decoding errors: 0
[16:18:10.552] <TB3> INFO: pixel data incomplete: 0
[16:18:10.552] <TB3> INFO: pixel address: 0
[16:18:10.552] <TB3> INFO: pulse height fill bit: 0
[16:18:10.552] <TB3> INFO: buffer corruption: 0
[16:18:10.614] <TB3> INFO: ######################################################################
[16:18:10.614] <TB3> INFO: PixTestScurves::fullTest() ntrig = 50, dacs/step = -1, ntrig/step = -1
[16:18:10.614] <TB3> INFO: ######################################################################
[16:18:10.614] <TB3> INFO: ----------------------------------------------------------------------
[16:18:10.614] <TB3> INFO: PixTestScurves::scurves(Vcal), ntrig = 50, dacs/step = -1, ntrig/step = -1
[16:18:10.614] <TB3> INFO: ----------------------------------------------------------------------
[16:18:10.614] <TB3> INFO: ---> dac: Vcal name: scurveVcal ntrig: 50 dacrange: 25 .. 200 (-1/-1) hits flags = 528 (plus default)
[16:18:10.628] <TB3> INFO: dacScan split into 1 runs with ntrig = 50
[16:18:10.628] <TB3> INFO: run 1 of 1
[16:18:10.885] <TB3> INFO: Expecting 36608000 events.
[16:18:34.234] <TB3> INFO: 656100 events read in total (22757ms).
[16:18:56.693] <TB3> INFO: 1303300 events read in total (45216ms).
[16:19:19.187] <TB3> INFO: 1950550 events read in total (67710ms).
[16:19:41.809] <TB3> INFO: 2595750 events read in total (90332ms).
[16:20:04.330] <TB3> INFO: 3242650 events read in total (112853ms).
[16:20:26.880] <TB3> INFO: 3889450 events read in total (135403ms).
[16:20:49.557] <TB3> INFO: 4538750 events read in total (158080ms).
[16:21:12.347] <TB3> INFO: 5189300 events read in total (180870ms).
[16:21:34.832] <TB3> INFO: 5838550 events read in total (203355ms).
[16:21:57.363] <TB3> INFO: 6488100 events read in total (225886ms).
[16:22:19.970] <TB3> INFO: 7136000 events read in total (248493ms).
[16:22:42.360] <TB3> INFO: 7784000 events read in total (270883ms).
[16:23:05.105] <TB3> INFO: 8430800 events read in total (293628ms).
[16:23:27.786] <TB3> INFO: 9077000 events read in total (316309ms).
[16:23:50.082] <TB3> INFO: 9723050 events read in total (338605ms).
[16:24:12.430] <TB3> INFO: 10372550 events read in total (360953ms).
[16:24:35.034] <TB3> INFO: 11021900 events read in total (383557ms).
[16:24:57.377] <TB3> INFO: 11667900 events read in total (405900ms).
[16:25:19.965] <TB3> INFO: 12316200 events read in total (428488ms).
[16:25:42.575] <TB3> INFO: 12963350 events read in total (451098ms).
[16:26:05.033] <TB3> INFO: 13610150 events read in total (473556ms).
[16:26:27.386] <TB3> INFO: 14258100 events read in total (495909ms).
[16:26:50.079] <TB3> INFO: 14905850 events read in total (518602ms).
[16:27:12.573] <TB3> INFO: 15552950 events read in total (541096ms).
[16:27:35.300] <TB3> INFO: 16200450 events read in total (563823ms).
[16:27:58.171] <TB3> INFO: 16847950 events read in total (586694ms).
[16:28:20.633] <TB3> INFO: 17493450 events read in total (609156ms).
[16:28:43.197] <TB3> INFO: 18139200 events read in total (631720ms).
[16:29:05.915] <TB3> INFO: 18785200 events read in total (654438ms).
[16:29:28.502] <TB3> INFO: 19430900 events read in total (677025ms).
[16:29:50.864] <TB3> INFO: 20075600 events read in total (699387ms).
[16:30:13.283] <TB3> INFO: 20719600 events read in total (721806ms).
[16:30:35.625] <TB3> INFO: 21364250 events read in total (744149ms).
[16:30:58.119] <TB3> INFO: 22008750 events read in total (766642ms).
[16:31:20.899] <TB3> INFO: 22655400 events read in total (789422ms).
[16:31:43.253] <TB3> INFO: 23299900 events read in total (811776ms).
[16:32:05.603] <TB3> INFO: 23945800 events read in total (834126ms).
[16:32:28.333] <TB3> INFO: 24589950 events read in total (856856ms).
[16:32:50.920] <TB3> INFO: 25234850 events read in total (879443ms).
[16:33:13.384] <TB3> INFO: 25878900 events read in total (901907ms).
[16:33:36.175] <TB3> INFO: 26522700 events read in total (924698ms).
[16:33:58.614] <TB3> INFO: 27166300 events read in total (947137ms).
[16:34:21.045] <TB3> INFO: 27811550 events read in total (969568ms).
[16:34:43.505] <TB3> INFO: 28455800 events read in total (992028ms).
[16:35:05.938] <TB3> INFO: 29100550 events read in total (1014461ms).
[16:35:28.405] <TB3> INFO: 29745300 events read in total (1036928ms).
[16:35:50.960] <TB3> INFO: 30386850 events read in total (1059483ms).
[16:36:13.575] <TB3> INFO: 31031450 events read in total (1082098ms).
[16:36:36.170] <TB3> INFO: 31675600 events read in total (1104693ms).
[16:36:58.911] <TB3> INFO: 32320050 events read in total (1127434ms).
[16:37:21.284] <TB3> INFO: 32963900 events read in total (1149807ms).
[16:37:44.152] <TB3> INFO: 33609750 events read in total (1172675ms).
[16:38:06.729] <TB3> INFO: 34254500 events read in total (1195252ms).
[16:38:29.592] <TB3> INFO: 34899550 events read in total (1218115ms).
[16:38:52.260] <TB3> INFO: 35544400 events read in total (1240783ms).
[16:39:14.755] <TB3> INFO: 36193450 events read in total (1263278ms).
[16:39:29.471] <TB3> INFO: 36608000 events read in total (1277994ms).
[16:39:29.585] <TB3> INFO: Test took 1278957ms.
[16:39:30.113] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[16:39:32.417] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[16:39:34.428] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[16:39:36.595] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[16:39:38.722] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[16:39:40.823] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[16:39:42.542] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[16:39:44.314] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[16:39:45.839] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[16:39:47.307] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[16:39:49.052] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[16:39:50.567] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[16:39:51.000] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[16:39:53.848] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[16:39:55.760] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[16:39:57.825] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[16:39:59.858] <TB3> INFO: PixTestScurves::scurves() done
[16:39:59.858] <TB3> INFO: Vcal mean: 105.66 104.65 104.09 109.62 112.05 105.48 101.59 110.03 106.06 116.17 116.81 95.27 106.78 98.02 107.63 122.56
[16:39:59.858] <TB3> INFO: Vcal RMS: 5.21 5.05 5.42 4.92 4.59 4.96 4.98 4.14 4.80 6.14 6.15 6.07 4.98 6.00 5.11 7.21
[16:39:59.858] <TB3> INFO: PixTestScurves::fullTest() done, duration: 1309 seconds
[16:39:59.858] <TB3> INFO: Decoding statistics:
[16:39:59.858] <TB3> INFO: General information:
[16:39:59.858] <TB3> INFO: 16bit words read: 0
[16:39:59.858] <TB3> INFO: valid events total: 0
[16:39:59.858] <TB3> INFO: empty events: 0
[16:39:59.858] <TB3> INFO: valid events with pixels: 0
[16:39:59.858] <TB3> INFO: valid pixel hits: 0
[16:39:59.858] <TB3> INFO: Event errors: 0
[16:39:59.858] <TB3> INFO: start marker: 0
[16:39:59.858] <TB3> INFO: stop marker: 0
[16:39:59.858] <TB3> INFO: overflow: 0
[16:39:59.858] <TB3> INFO: invalid 5bit words: 0
[16:39:59.858] <TB3> INFO: invalid XOR eye diagram: 0
[16:39:59.858] <TB3> INFO: frame (failed synchr.): 0
[16:39:59.859] <TB3> INFO: idle data (no TBM trl): 0
[16:39:59.859] <TB3> INFO: no data (only TBM hdr): 0
[16:39:59.859] <TB3> INFO: TBM errors: 0
[16:39:59.859] <TB3> INFO: flawed TBM headers: 0
[16:39:59.859] <TB3> INFO: flawed TBM trailers: 0
[16:39:59.859] <TB3> INFO: event ID mismatches: 0
[16:39:59.859] <TB3> INFO: ROC errors: 0
[16:39:59.859] <TB3> INFO: missing ROC header(s): 0
[16:39:59.859] <TB3> INFO: misplaced readback start: 0
[16:39:59.859] <TB3> INFO: Pixel decoding errors: 0
[16:39:59.859] <TB3> INFO: pixel data incomplete: 0
[16:39:59.859] <TB3> INFO: pixel address: 0
[16:39:59.859] <TB3> INFO: pulse height fill bit: 0
[16:39:59.859] <TB3> INFO: buffer corruption: 0
[16:39:59.939] <TB3> INFO: ######################################################################
[16:39:59.939] <TB3> INFO: PixTestTrim::doTest()
[16:39:59.939] <TB3> INFO: ######################################################################
[16:39:59.940] <TB3> INFO: ----------------------------------------------------------------------
[16:39:59.940] <TB3> INFO: PixTestTrim::trimTest() ntrig = 8, vcal = 35
[16:39:59.940] <TB3> INFO: ----------------------------------------------------------------------
[16:39:59.981] <TB3> INFO: ---> VthrComp thr map (minimal VthrComp)
[16:39:59.981] <TB3> INFO: ---> dac: vthrcomp name: TrimThr0 ntrig: 8 dacrange: 10 .. 160 (-1/-1) hits flags = 528 (plus default)
[16:39:59.995] <TB3> INFO: dacScan split into 1 runs with ntrig = 8
[16:39:59.995] <TB3> INFO: run 1 of 1
[16:40:00.251] <TB3> INFO: Expecting 5025280 events.
[16:40:30.963] <TB3> INFO: 808400 events read in total (30103ms).
[16:41:01.426] <TB3> INFO: 1615496 events read in total (60566ms).
[16:41:31.800] <TB3> INFO: 2420648 events read in total (90940ms).
[16:42:01.852] <TB3> INFO: 3223080 events read in total (120992ms).
[16:42:31.998] <TB3> INFO: 4022904 events read in total (151139ms).
[16:43:02.339] <TB3> INFO: 4821520 events read in total (181479ms).
[16:43:10.380] <TB3> INFO: 5025280 events read in total (189520ms).
[16:43:10.449] <TB3> INFO: Test took 190454ms.
[16:43:27.287] <TB3> INFO: ROC 0 VthrComp = 123
[16:43:27.287] <TB3> INFO: ROC 1 VthrComp = 124
[16:43:27.287] <TB3> INFO: ROC 2 VthrComp = 118
[16:43:27.287] <TB3> INFO: ROC 3 VthrComp = 122
[16:43:27.287] <TB3> INFO: ROC 4 VthrComp = 128
[16:43:27.287] <TB3> INFO: ROC 5 VthrComp = 112
[16:43:27.287] <TB3> INFO: ROC 6 VthrComp = 117
[16:43:27.287] <TB3> INFO: ROC 7 VthrComp = 127
[16:43:27.288] <TB3> INFO: ROC 8 VthrComp = 116
[16:43:27.288] <TB3> INFO: ROC 9 VthrComp = 129
[16:43:27.288] <TB3> INFO: ROC 10 VthrComp = 131
[16:43:27.288] <TB3> INFO: ROC 11 VthrComp = 103
[16:43:27.289] <TB3> INFO: ROC 12 VthrComp = 113
[16:43:27.289] <TB3> INFO: ROC 13 VthrComp = 107
[16:43:27.289] <TB3> INFO: ROC 14 VthrComp = 126
[16:43:27.289] <TB3> INFO: ROC 15 VthrComp = 133
[16:43:27.544] <TB3> INFO: Expecting 41600 events.
[16:43:31.087] <TB3> INFO: 41600 events read in total (2951ms).
[16:43:31.088] <TB3> INFO: Test took 3797ms.
[16:43:31.097] <TB3> INFO: ---> Vcal thr map (pixel with maximum Vcal thr)
[16:43:31.097] <TB3> INFO: ---> dac: vcal name: TrimThr1 ntrig: 8 dacrange: 10 .. 160 (-1/-1) hits flags = 528 (plus default)
[16:43:31.110] <TB3> INFO: dacScan split into 1 runs with ntrig = 8
[16:43:31.111] <TB3> INFO: run 1 of 1
[16:43:31.389] <TB3> INFO: Expecting 5025280 events.
[16:43:58.216] <TB3> INFO: 586312 events read in total (26236ms).
[16:44:24.463] <TB3> INFO: 1172760 events read in total (52483ms).
[16:44:50.686] <TB3> INFO: 1759544 events read in total (78706ms).
[16:45:16.754] <TB3> INFO: 2345816 events read in total (104774ms).
[16:45:42.764] <TB3> INFO: 2930392 events read in total (130784ms).
[16:46:09.161] <TB3> INFO: 3513504 events read in total (157181ms).
[16:46:35.068] <TB3> INFO: 4095512 events read in total (183088ms).
[16:47:01.447] <TB3> INFO: 4676720 events read in total (209467ms).
[16:47:16.669] <TB3> INFO: 5025280 events read in total (224689ms).
[16:47:16.825] <TB3> INFO: Test took 225714ms.
[16:47:44.290] <TB3> INFO: roc 0 with ID = 0 has maximal Vcal 56.219 for pixel 21/61 mean/min/max = 43.9013/31.495/56.3076
[16:47:44.290] <TB3> INFO: roc 1 with ID = 1 has maximal Vcal 55.1249 for pixel 11/70 mean/min/max = 43.5631/31.438/55.6882
[16:47:44.290] <TB3> INFO: roc 2 with ID = 2 has maximal Vcal 57.2275 for pixel 18/79 mean/min/max = 44.1985/31.0044/57.3926
[16:47:44.291] <TB3> INFO: roc 3 with ID = 3 has maximal Vcal 58.5164 for pixel 12/79 mean/min/max = 45.0954/31.6439/58.5469
[16:47:44.291] <TB3> INFO: roc 4 with ID = 4 has maximal Vcal 56.0908 for pixel 45/25 mean/min/max = 44.1068/31.9842/56.2293
[16:47:44.292] <TB3> INFO: roc 5 with ID = 5 has maximal Vcal 58.3861 for pixel 23/78 mean/min/max = 45.9251/33.3503/58.4999
[16:47:44.292] <TB3> INFO: roc 6 with ID = 6 has maximal Vcal 55.9137 for pixel 28/50 mean/min/max = 44.0114/32.0869/55.9358
[16:47:44.293] <TB3> INFO: roc 7 with ID = 7 has maximal Vcal 55.7357 for pixel 0/1 mean/min/max = 44.2957/32.6824/55.909
[16:47:44.293] <TB3> INFO: roc 8 with ID = 8 has maximal Vcal 56.8166 for pixel 15/69 mean/min/max = 44.441/32.0581/56.824
[16:47:44.294] <TB3> INFO: roc 9 with ID = 9 has maximal Vcal 59.9718 for pixel 13/70 mean/min/max = 45.9186/31.8623/59.9748
[16:47:44.294] <TB3> INFO: roc 10 with ID = 10 has maximal Vcal 59.0063 for pixel 4/79 mean/min/max = 45.0688/31.0247/59.1129
[16:47:44.295] <TB3> INFO: roc 11 with ID = 11 has maximal Vcal 58.5094 for pixel 16/28 mean/min/max = 46.0355/33.5293/58.5417
[16:47:44.295] <TB3> INFO: roc 12 with ID = 12 has maximal Vcal 56.9218 for pixel 51/64 mean/min/max = 44.9274/32.7532/57.1017
[16:47:44.296] <TB3> INFO: roc 13 with ID = 13 has maximal Vcal 57.5484 for pixel 18/66 mean/min/max = 45.8374/33.9098/57.7651
[16:47:44.296] <TB3> INFO: roc 14 with ID = 14 has maximal Vcal 55.8108 for pixel 28/79 mean/min/max = 43.5072/31.0851/55.9293
[16:47:44.297] <TB3> INFO: roc 15 with ID = 15 has maximal Vcal 63.1906 for pixel 17/76 mean/min/max = 50.2841/37.3419/63.2263
[16:47:44.297] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[16:47:44.386] <TB3> INFO: Expecting 411648 events.
[16:47:54.024] <TB3> INFO: 411648 events read in total (9039ms).
[16:47:54.032] <TB3> INFO: Expecting 411648 events.
[16:48:03.453] <TB3> INFO: 411648 events read in total (9018ms).
[16:48:03.467] <TB3> INFO: Expecting 411648 events.
[16:48:12.872] <TB3> INFO: 411648 events read in total (9002ms).
[16:48:12.886] <TB3> INFO: Expecting 411648 events.
[16:48:22.322] <TB3> INFO: 411648 events read in total (9033ms).
[16:48:22.339] <TB3> INFO: Expecting 411648 events.
[16:48:31.701] <TB3> INFO: 411648 events read in total (8959ms).
[16:48:31.721] <TB3> INFO: Expecting 411648 events.
[16:48:41.109] <TB3> INFO: 411648 events read in total (8985ms).
[16:48:41.131] <TB3> INFO: Expecting 411648 events.
[16:48:50.552] <TB3> INFO: 411648 events read in total (9018ms).
[16:48:50.578] <TB3> INFO: Expecting 411648 events.
[16:49:00.052] <TB3> INFO: 411648 events read in total (9071ms).
[16:49:00.081] <TB3> INFO: Expecting 411648 events.
[16:49:09.462] <TB3> INFO: 411648 events read in total (8978ms).
[16:49:09.500] <TB3> INFO: Expecting 411648 events.
[16:49:18.910] <TB3> INFO: 411648 events read in total (9007ms).
[16:49:18.946] <TB3> INFO: Expecting 411648 events.
[16:49:28.286] <TB3> INFO: 411648 events read in total (8937ms).
[16:49:28.393] <TB3> INFO: Expecting 411648 events.
[16:49:37.814] <TB3> INFO: 411648 events read in total (9018ms).
[16:49:37.859] <TB3> INFO: Expecting 411648 events.
[16:49:47.238] <TB3> INFO: 411648 events read in total (8976ms).
[16:49:47.377] <TB3> INFO: Expecting 411648 events.
[16:49:56.827] <TB3> INFO: 411648 events read in total (9047ms).
[16:49:56.877] <TB3> INFO: Expecting 411648 events.
[16:50:06.306] <TB3> INFO: 411648 events read in total (9026ms).
[16:50:06.477] <TB3> INFO: Expecting 411648 events.
[16:50:15.731] <TB3> INFO: 411648 events read in total (8852ms).
[16:50:15.816] <TB3> INFO: Test took 151519ms.
[16:50:16.774] <TB3> INFO: ---> dac: vcal name: TrimThr2 ntrig: 8 dacrange: 0 .. 150 (-1/-1) hits flags = 528 (plus default)
[16:50:16.788] <TB3> INFO: dacScan split into 1 runs with ntrig = 8
[16:50:16.788] <TB3> INFO: run 1 of 1
[16:50:17.060] <TB3> INFO: Expecting 5025280 events.
[16:50:43.916] <TB3> INFO: 581656 events read in total (26264ms).
[16:51:09.636] <TB3> INFO: 1162840 events read in total (51984ms).
[16:51:35.970] <TB3> INFO: 1743712 events read in total (78318ms).
[16:52:02.233] <TB3> INFO: 2323904 events read in total (104581ms).
[16:52:28.132] <TB3> INFO: 2903376 events read in total (130480ms).
[16:52:54.067] <TB3> INFO: 3482920 events read in total (156415ms).
[16:53:20.068] <TB3> INFO: 4061336 events read in total (182416ms).
[16:53:46.253] <TB3> INFO: 4638560 events read in total (208601ms).
[16:54:04.060] <TB3> INFO: 5025280 events read in total (226408ms).
[16:54:04.187] <TB3> INFO: Test took 227400ms.
[16:54:31.036] <TB3> INFO: ---> TrimStepCorr4 extremal thresholds: 11.612318 .. 126.744874
[16:54:31.304] <TB3> INFO: Expecting 208000 events.
[16:54:41.292] <TB3> INFO: 208000 events read in total (9396ms).
[16:54:41.292] <TB3> INFO: Test took 10254ms.
[16:54:41.340] <TB3> INFO: ---> dac: vcal name: trimStepCorr4 ntrig: 8 dacrange: 1 .. 136 (-1/-1) hits flags = 528 (plus default)
[16:54:41.353] <TB3> INFO: dacScan split into 1 runs with ntrig = 8
[16:54:41.353] <TB3> INFO: run 1 of 1
[16:54:41.632] <TB3> INFO: Expecting 4526080 events.
[16:55:08.027] <TB3> INFO: 591728 events read in total (25803ms).
[16:55:33.481] <TB3> INFO: 1183704 events read in total (51257ms).
[16:55:59.295] <TB3> INFO: 1776168 events read in total (77071ms).
[16:56:25.024] <TB3> INFO: 2368976 events read in total (102800ms).
[16:56:50.821] <TB3> INFO: 2960088 events read in total (128597ms).
[16:57:16.488] <TB3> INFO: 3550816 events read in total (154264ms).
[16:57:42.298] <TB3> INFO: 4139904 events read in total (180074ms).
[16:57:59.329] <TB3> INFO: 4526080 events read in total (197105ms).
[16:57:59.439] <TB3> INFO: Test took 198086ms.
[16:58:25.054] <TB3> INFO: ---> TrimStepCorr2 extremal thresholds: 25.295384 .. 48.087432
[16:58:25.291] <TB3> INFO: Expecting 208000 events.
[16:58:35.390] <TB3> INFO: 208000 events read in total (9507ms).
[16:58:35.391] <TB3> INFO: Test took 10336ms.
[16:58:35.439] <TB3> INFO: ---> dac: vcal name: trimStepCorr2 ntrig: 8 dacrange: 15 .. 58 (-1/-1) hits flags = 528 (plus default)
[16:58:35.452] <TB3> INFO: dacScan split into 1 runs with ntrig = 8
[16:58:35.452] <TB3> INFO: run 1 of 1
[16:58:35.731] <TB3> INFO: Expecting 1464320 events.
[16:59:04.117] <TB3> INFO: 654880 events read in total (27795ms).
[16:59:31.895] <TB3> INFO: 1308056 events read in total (55573ms).
[16:59:38.641] <TB3> INFO: 1464320 events read in total (62319ms).
[16:59:38.678] <TB3> INFO: Test took 63226ms.
[16:59:53.972] <TB3> INFO: ---> TrimStepCorr1a extremal thresholds: 24.639827 .. 42.801893
[16:59:54.211] <TB3> INFO: Expecting 208000 events.
[17:00:03.619] <TB3> INFO: 208000 events read in total (8817ms).
[17:00:03.621] <TB3> INFO: Test took 9648ms.
[17:00:03.670] <TB3> INFO: ---> dac: vcal name: trimStepCorr1a ntrig: 8 dacrange: 14 .. 52 (-1/-1) hits flags = 528 (plus default)
[17:00:03.683] <TB3> INFO: dacScan split into 1 runs with ntrig = 8
[17:00:03.683] <TB3> INFO: run 1 of 1
[17:00:03.961] <TB3> INFO: Expecting 1297920 events.
[17:00:32.673] <TB3> INFO: 684872 events read in total (28121ms).
[17:00:58.783] <TB3> INFO: 1297920 events read in total (54231ms).
[17:00:58.814] <TB3> INFO: Test took 55132ms.
[17:01:13.004] <TB3> INFO: ---> TrimStepCorr1b extremal thresholds: 24.268301 .. 42.355293
[17:01:13.282] <TB3> INFO: Expecting 208000 events.
[17:01:22.881] <TB3> INFO: 208000 events read in total (9007ms).
[17:01:22.882] <TB3> INFO: Test took 9876ms.
[17:01:22.930] <TB3> INFO: ---> dac: vcal name: trimStepCorr1b ntrig: 8 dacrange: 14 .. 52 (-1/-1) hits flags = 528 (plus default)
[17:01:22.943] <TB3> INFO: dacScan split into 1 runs with ntrig = 8
[17:01:22.943] <TB3> INFO: run 1 of 1
[17:01:23.221] <TB3> INFO: Expecting 1297920 events.
[17:01:52.017] <TB3> INFO: 682872 events read in total (28205ms).
[17:02:17.633] <TB3> INFO: 1297920 events read in total (53821ms).
[17:02:17.669] <TB3> INFO: Test took 54727ms.
[17:02:31.627] <TB3> INFO: ---> TrimThrFinal extremal thresholds: 15 .. 55
[17:02:31.627] <TB3> INFO: ---> dac: vcal name: TrimThrFinal ntrig: 8 dacrange: 15 .. 55 (-1/-1) hits flags = 528 (plus default)
[17:02:31.640] <TB3> INFO: dacScan split into 1 runs with ntrig = 8
[17:02:31.640] <TB3> INFO: run 1 of 1
[17:02:31.876] <TB3> INFO: Expecting 1364480 events.
[17:03:00.493] <TB3> INFO: 665840 events read in total (28025ms).
[17:03:28.471] <TB3> INFO: 1332136 events read in total (56003ms).
[17:03:30.199] <TB3> INFO: 1364480 events read in total (57732ms).
[17:03:30.226] <TB3> INFO: Test took 58587ms.
[17:03:45.094] <TB3> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1111_FullQualification_2016-11-02_15h17m_1478096238//001_Fulltest_m20//dacParameters35_C0.dat
[17:03:45.094] <TB3> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1111_FullQualification_2016-11-02_15h17m_1478096238//001_Fulltest_m20//dacParameters35_C1.dat
[17:03:45.094] <TB3> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1111_FullQualification_2016-11-02_15h17m_1478096238//001_Fulltest_m20//dacParameters35_C2.dat
[17:03:45.094] <TB3> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1111_FullQualification_2016-11-02_15h17m_1478096238//001_Fulltest_m20//dacParameters35_C3.dat
[17:03:45.094] <TB3> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1111_FullQualification_2016-11-02_15h17m_1478096238//001_Fulltest_m20//dacParameters35_C4.dat
[17:03:45.094] <TB3> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1111_FullQualification_2016-11-02_15h17m_1478096238//001_Fulltest_m20//dacParameters35_C5.dat
[17:03:45.094] <TB3> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1111_FullQualification_2016-11-02_15h17m_1478096238//001_Fulltest_m20//dacParameters35_C6.dat
[17:03:45.094] <TB3> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1111_FullQualification_2016-11-02_15h17m_1478096238//001_Fulltest_m20//dacParameters35_C7.dat
[17:03:45.094] <TB3> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1111_FullQualification_2016-11-02_15h17m_1478096238//001_Fulltest_m20//dacParameters35_C8.dat
[17:03:45.094] <TB3> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1111_FullQualification_2016-11-02_15h17m_1478096238//001_Fulltest_m20//dacParameters35_C9.dat
[17:03:45.094] <TB3> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1111_FullQualification_2016-11-02_15h17m_1478096238//001_Fulltest_m20//dacParameters35_C10.dat
[17:03:45.094] <TB3> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1111_FullQualification_2016-11-02_15h17m_1478096238//001_Fulltest_m20//dacParameters35_C11.dat
[17:03:45.094] <TB3> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1111_FullQualification_2016-11-02_15h17m_1478096238//001_Fulltest_m20//dacParameters35_C12.dat
[17:03:45.094] <TB3> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1111_FullQualification_2016-11-02_15h17m_1478096238//001_Fulltest_m20//dacParameters35_C13.dat
[17:03:45.095] <TB3> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1111_FullQualification_2016-11-02_15h17m_1478096238//001_Fulltest_m20//dacParameters35_C14.dat
[17:03:45.095] <TB3> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1111_FullQualification_2016-11-02_15h17m_1478096238//001_Fulltest_m20//dacParameters35_C15.dat
[17:03:45.095] <TB3> INFO: write trim parameters into /home/l_tester/david/FullQualification/data/M1111_FullQualification_2016-11-02_15h17m_1478096238//001_Fulltest_m20//trimParameters35_C0.dat
[17:03:45.100] <TB3> INFO: write trim parameters into /home/l_tester/david/FullQualification/data/M1111_FullQualification_2016-11-02_15h17m_1478096238//001_Fulltest_m20//trimParameters35_C1.dat
[17:03:45.105] <TB3> INFO: write trim parameters into /home/l_tester/david/FullQualification/data/M1111_FullQualification_2016-11-02_15h17m_1478096238//001_Fulltest_m20//trimParameters35_C2.dat
[17:03:45.109] <TB3> INFO: write trim parameters into /home/l_tester/david/FullQualification/data/M1111_FullQualification_2016-11-02_15h17m_1478096238//001_Fulltest_m20//trimParameters35_C3.dat
[17:03:45.114] <TB3> INFO: write trim parameters into /home/l_tester/david/FullQualification/data/M1111_FullQualification_2016-11-02_15h17m_1478096238//001_Fulltest_m20//trimParameters35_C4.dat
[17:03:45.119] <TB3> INFO: write trim parameters into /home/l_tester/david/FullQualification/data/M1111_FullQualification_2016-11-02_15h17m_1478096238//001_Fulltest_m20//trimParameters35_C5.dat
[17:03:45.124] <TB3> INFO: write trim parameters into /home/l_tester/david/FullQualification/data/M1111_FullQualification_2016-11-02_15h17m_1478096238//001_Fulltest_m20//trimParameters35_C6.dat
[17:03:45.128] <TB3> INFO: write trim parameters into /home/l_tester/david/FullQualification/data/M1111_FullQualification_2016-11-02_15h17m_1478096238//001_Fulltest_m20//trimParameters35_C7.dat
[17:03:45.134] <TB3> INFO: write trim parameters into /home/l_tester/david/FullQualification/data/M1111_FullQualification_2016-11-02_15h17m_1478096238//001_Fulltest_m20//trimParameters35_C8.dat
[17:03:45.140] <TB3> INFO: write trim parameters into /home/l_tester/david/FullQualification/data/M1111_FullQualification_2016-11-02_15h17m_1478096238//001_Fulltest_m20//trimParameters35_C9.dat
[17:03:45.146] <TB3> INFO: write trim parameters into /home/l_tester/david/FullQualification/data/M1111_FullQualification_2016-11-02_15h17m_1478096238//001_Fulltest_m20//trimParameters35_C10.dat
[17:03:45.152] <TB3> INFO: write trim parameters into /home/l_tester/david/FullQualification/data/M1111_FullQualification_2016-11-02_15h17m_1478096238//001_Fulltest_m20//trimParameters35_C11.dat
[17:03:45.159] <TB3> INFO: write trim parameters into /home/l_tester/david/FullQualification/data/M1111_FullQualification_2016-11-02_15h17m_1478096238//001_Fulltest_m20//trimParameters35_C12.dat
[17:03:45.165] <TB3> INFO: write trim parameters into /home/l_tester/david/FullQualification/data/M1111_FullQualification_2016-11-02_15h17m_1478096238//001_Fulltest_m20//trimParameters35_C13.dat
[17:03:45.171] <TB3> INFO: write trim parameters into /home/l_tester/david/FullQualification/data/M1111_FullQualification_2016-11-02_15h17m_1478096238//001_Fulltest_m20//trimParameters35_C14.dat
[17:03:45.177] <TB3> INFO: write trim parameters into /home/l_tester/david/FullQualification/data/M1111_FullQualification_2016-11-02_15h17m_1478096238//001_Fulltest_m20//trimParameters35_C15.dat
[17:03:45.184] <TB3> INFO: PixTestTrim::trimTest() done
[17:03:45.184] <TB3> INFO: vtrim: 138 116 127 121 116 131 136 116 124 138 132 119 116 132 127 165
[17:03:45.184] <TB3> INFO: vthrcomp: 123 124 118 122 128 112 117 127 116 129 131 103 113 107 126 133
[17:03:45.184] <TB3> INFO: vcal mean: 34.93 34.94 34.90 34.95 34.95 34.77 34.97 34.90 34.98 34.95 34.92 34.93 34.97 34.96 34.88 34.98
[17:03:45.184] <TB3> INFO: vcal RMS: 0.96 0.97 1.06 1.02 0.98 0.99 0.90 0.93 0.94 0.93 1.19 0.93 0.92 0.88 0.90 1.01
[17:03:45.184] <TB3> INFO: bits mean: 10.30 10.08 9.82 9.47 10.01 9.60 10.21 9.11 9.69 9.07 9.72 9.17 9.47 9.46 10.13 7.81
[17:03:45.184] <TB3> INFO: bits RMS: 2.42 2.58 2.78 2.82 2.58 2.60 2.44 2.88 2.70 2.91 2.75 2.60 2.66 2.38 2.68 2.22
[17:03:45.192] <TB3> INFO: ----------------------------------------------------------------------
[17:03:45.192] <TB3> INFO: PixTestTrim::trimBitTest() ntrig = 5, vtrims = 254 126 63 32
[17:03:45.192] <TB3> INFO: ----------------------------------------------------------------------
[17:03:45.194] <TB3> INFO: ---> dac: Vcal name: TrimBitsThr0 ntrig: 5 dacrange: 0 .. 199 (-1/-1) hits flags = 528 (plus default)
[17:03:45.209] <TB3> INFO: dacScan split into 1 runs with ntrig = 5
[17:03:45.209] <TB3> INFO: run 1 of 1
[17:03:45.451] <TB3> INFO: Expecting 4160000 events.
[17:04:17.525] <TB3> INFO: 725520 events read in total (31482ms).
[17:04:49.458] <TB3> INFO: 1448485 events read in total (63415ms).
[17:05:21.289] <TB3> INFO: 2170280 events read in total (95246ms).
[17:05:52.954] <TB3> INFO: 2888715 events read in total (126912ms).
[17:06:24.780] <TB3> INFO: 3603865 events read in total (158737ms).
[17:06:49.162] <TB3> INFO: 4160000 events read in total (183119ms).
[17:06:49.303] <TB3> INFO: Test took 184094ms.
[17:07:18.693] <TB3> INFO: ---> dac: Vcal name: TrimThr_trim14 ntrig: 5 dacrange: 0 .. 195 (-1/-1) hits flags = 528 (plus default)
[17:07:18.706] <TB3> INFO: dacScan split into 1 runs with ntrig = 5
[17:07:18.706] <TB3> INFO: run 1 of 1
[17:07:18.943] <TB3> INFO: Expecting 4076800 events.
[17:07:51.014] <TB3> INFO: 708330 events read in total (31480ms).
[17:08:22.480] <TB3> INFO: 1414300 events read in total (62946ms).
[17:08:53.808] <TB3> INFO: 2119140 events read in total (94275ms).
[17:09:24.901] <TB3> INFO: 2820775 events read in total (125367ms).
[17:09:56.144] <TB3> INFO: 3519910 events read in total (156610ms).
[17:10:21.011] <TB3> INFO: 4076800 events read in total (181477ms).
[17:10:21.191] <TB3> INFO: Test took 182485ms.
[17:10:49.002] <TB3> INFO: ---> dac: Vcal name: TrimThr_trim13 ntrig: 5 dacrange: 0 .. 198 (-1/-1) hits flags = 528 (plus default)
[17:10:50.016] <TB3> INFO: dacScan split into 1 runs with ntrig = 5
[17:10:50.016] <TB3> INFO: run 1 of 1
[17:10:50.257] <TB3> INFO: Expecting 4139200 events.
[17:11:21.551] <TB3> INFO: 705185 events read in total (30702ms).
[17:11:52.497] <TB3> INFO: 1408170 events read in total (61648ms).
[17:12:23.595] <TB3> INFO: 2110270 events read in total (92746ms).
[17:12:54.459] <TB3> INFO: 2808995 events read in total (123610ms).
[17:13:25.773] <TB3> INFO: 3505155 events read in total (154924ms).
[17:13:54.868] <TB3> INFO: 4139200 events read in total (184019ms).
[17:13:54.958] <TB3> INFO: Test took 184942ms.
[17:14:21.540] <TB3> INFO: ---> dac: Vcal name: TrimThr_trim11 ntrig: 5 dacrange: 0 .. 198 (-1/-1) hits flags = 528 (plus default)
[17:14:21.554] <TB3> INFO: dacScan split into 1 runs with ntrig = 5
[17:14:21.554] <TB3> INFO: run 1 of 1
[17:14:21.790] <TB3> INFO: Expecting 4139200 events.
[17:14:54.289] <TB3> INFO: 705370 events read in total (31907ms).
[17:15:26.062] <TB3> INFO: 1408540 events read in total (63680ms).
[17:15:57.692] <TB3> INFO: 2110500 events read in total (95310ms).
[17:16:28.775] <TB3> INFO: 2809250 events read in total (126393ms).
[17:16:59.310] <TB3> INFO: 3505250 events read in total (156928ms).
[17:17:26.588] <TB3> INFO: 4139200 events read in total (184206ms).
[17:17:26.703] <TB3> INFO: Test took 185149ms.
[17:17:53.960] <TB3> INFO: ---> dac: Vcal name: TrimThr_trim7 ntrig: 5 dacrange: 0 .. 197 (-1/-1) hits flags = 528 (plus default)
[17:17:53.973] <TB3> INFO: dacScan split into 1 runs with ntrig = 5
[17:17:53.973] <TB3> INFO: run 1 of 1
[17:17:54.210] <TB3> INFO: Expecting 4118400 events.
[17:18:25.714] <TB3> INFO: 706910 events read in total (30913ms).
[17:18:56.718] <TB3> INFO: 1411495 events read in total (61916ms).
[17:19:27.911] <TB3> INFO: 2114595 events read in total (93110ms).
[17:19:58.886] <TB3> INFO: 2814335 events read in total (124084ms).
[17:20:29.987] <TB3> INFO: 3511250 events read in total (155185ms).
[17:20:57.003] <TB3> INFO: 4118400 events read in total (182201ms).
[17:20:57.186] <TB3> INFO: Test took 183213ms.
[17:21:23.516] <TB3> INFO: PixTestTrim::trimBitTest() done
[17:21:23.517] <TB3> INFO: PixTestTrim::doTest() done, duration: 2483 seconds
[17:21:23.517] <TB3> INFO: Decoding statistics:
[17:21:23.517] <TB3> INFO: General information:
[17:21:23.517] <TB3> INFO: 16bit words read: 0
[17:21:23.517] <TB3> INFO: valid events total: 0
[17:21:23.517] <TB3> INFO: empty events: 0
[17:21:23.517] <TB3> INFO: valid events with pixels: 0
[17:21:23.517] <TB3> INFO: valid pixel hits: 0
[17:21:23.517] <TB3> INFO: Event errors: 0
[17:21:23.517] <TB3> INFO: start marker: 0
[17:21:23.517] <TB3> INFO: stop marker: 0
[17:21:23.517] <TB3> INFO: overflow: 0
[17:21:23.517] <TB3> INFO: invalid 5bit words: 0
[17:21:23.517] <TB3> INFO: invalid XOR eye diagram: 0
[17:21:23.517] <TB3> INFO: frame (failed synchr.): 0
[17:21:23.517] <TB3> INFO: idle data (no TBM trl): 0
[17:21:23.517] <TB3> INFO: no data (only TBM hdr): 0
[17:21:23.517] <TB3> INFO: TBM errors: 0
[17:21:23.517] <TB3> INFO: flawed TBM headers: 0
[17:21:23.517] <TB3> INFO: flawed TBM trailers: 0
[17:21:23.517] <TB3> INFO: event ID mismatches: 0
[17:21:23.517] <TB3> INFO: ROC errors: 0
[17:21:23.517] <TB3> INFO: missing ROC header(s): 0
[17:21:23.517] <TB3> INFO: misplaced readback start: 0
[17:21:23.517] <TB3> INFO: Pixel decoding errors: 0
[17:21:23.517] <TB3> INFO: pixel data incomplete: 0
[17:21:23.517] <TB3> INFO: pixel address: 0
[17:21:23.517] <TB3> INFO: pulse height fill bit: 0
[17:21:23.517] <TB3> INFO: buffer corruption: 0
[17:21:24.148] <TB3> INFO: ######################################################################
[17:21:24.148] <TB3> INFO: PixTestPhOptimization::doTest() Ntrig = 10
[17:21:24.148] <TB3> INFO: ######################################################################
[17:21:24.386] <TB3> INFO: Expecting 41600 events.
[17:21:27.911] <TB3> INFO: 41600 events read in total (2934ms).
[17:21:27.912] <TB3> INFO: Test took 3763ms.
[17:21:28.399] <TB3> INFO: Expecting 41600 events.
[17:21:31.879] <TB3> INFO: 41600 events read in total (2888ms).
[17:21:31.880] <TB3> INFO: Test took 3764ms.
[17:21:32.170] <TB3> INFO: Expecting 41600 events.
[17:21:35.814] <TB3> INFO: 41600 events read in total (3052ms).
[17:21:35.815] <TB3> INFO: Test took 3911ms.
[17:21:36.113] <TB3> INFO: Expecting 41600 events.
[17:21:39.697] <TB3> INFO: 41600 events read in total (2992ms).
[17:21:39.697] <TB3> INFO: Test took 3858ms.
[17:21:39.990] <TB3> INFO: Expecting 41600 events.
[17:21:43.542] <TB3> INFO: 41600 events read in total (2961ms).
[17:21:43.542] <TB3> INFO: Test took 3817ms.
[17:21:43.831] <TB3> INFO: Expecting 41600 events.
[17:21:47.339] <TB3> INFO: 41600 events read in total (2916ms).
[17:21:47.340] <TB3> INFO: Test took 3774ms.
[17:21:47.629] <TB3> INFO: Expecting 41600 events.
[17:21:51.229] <TB3> INFO: 41600 events read in total (3008ms).
[17:21:51.230] <TB3> INFO: Test took 3866ms.
[17:21:51.521] <TB3> INFO: Expecting 41600 events.
[17:21:55.075] <TB3> INFO: 41600 events read in total (2962ms).
[17:21:55.076] <TB3> INFO: Test took 3820ms.
[17:21:55.365] <TB3> INFO: Expecting 41600 events.
[17:21:58.886] <TB3> INFO: 41600 events read in total (2929ms).
[17:21:58.887] <TB3> INFO: Test took 3787ms.
[17:21:59.176] <TB3> INFO: Expecting 41600 events.
[17:22:02.743] <TB3> INFO: 41600 events read in total (2975ms).
[17:22:02.744] <TB3> INFO: Test took 3833ms.
[17:22:03.046] <TB3> INFO: Expecting 41600 events.
[17:22:06.684] <TB3> INFO: 41600 events read in total (3046ms).
[17:22:06.685] <TB3> INFO: Test took 3915ms.
[17:22:06.975] <TB3> INFO: Expecting 41600 events.
[17:22:10.496] <TB3> INFO: 41600 events read in total (2929ms).
[17:22:10.497] <TB3> INFO: Test took 3788ms.
[17:22:10.789] <TB3> INFO: Expecting 41600 events.
[17:22:14.292] <TB3> INFO: 41600 events read in total (2911ms).
[17:22:14.293] <TB3> INFO: Test took 3769ms.
[17:22:14.599] <TB3> INFO: Expecting 41600 events.
[17:22:18.177] <TB3> INFO: 41600 events read in total (2987ms).
[17:22:18.178] <TB3> INFO: Test took 3861ms.
[17:22:18.468] <TB3> INFO: Expecting 41600 events.
[17:22:21.958] <TB3> INFO: 41600 events read in total (2898ms).
[17:22:21.959] <TB3> INFO: Test took 3756ms.
[17:22:22.249] <TB3> INFO: Expecting 41600 events.
[17:22:25.808] <TB3> INFO: 41600 events read in total (2967ms).
[17:22:25.809] <TB3> INFO: Test took 3825ms.
[17:22:26.100] <TB3> INFO: Expecting 41600 events.
[17:22:29.706] <TB3> INFO: 41600 events read in total (3014ms).
[17:22:29.707] <TB3> INFO: Test took 3873ms.
[17:22:29.996] <TB3> INFO: Expecting 41600 events.
[17:22:33.585] <TB3> INFO: 41600 events read in total (2997ms).
[17:22:33.585] <TB3> INFO: Test took 3854ms.
[17:22:33.874] <TB3> INFO: Expecting 41600 events.
[17:22:37.425] <TB3> INFO: 41600 events read in total (2959ms).
[17:22:37.425] <TB3> INFO: Test took 3815ms.
[17:22:37.743] <TB3> INFO: Expecting 41600 events.
[17:22:41.391] <TB3> INFO: 41600 events read in total (3056ms).
[17:22:41.392] <TB3> INFO: Test took 3942ms.
[17:22:41.700] <TB3> INFO: Expecting 41600 events.
[17:22:45.202] <TB3> INFO: 41600 events read in total (2910ms).
[17:22:45.203] <TB3> INFO: Test took 3784ms.
[17:22:45.493] <TB3> INFO: Expecting 41600 events.
[17:22:49.105] <TB3> INFO: 41600 events read in total (3021ms).
[17:22:49.106] <TB3> INFO: Test took 3878ms.
[17:22:49.397] <TB3> INFO: Expecting 41600 events.
[17:22:52.901] <TB3> INFO: 41600 events read in total (2912ms).
[17:22:52.902] <TB3> INFO: Test took 3770ms.
[17:22:53.233] <TB3> INFO: Expecting 41600 events.
[17:22:57.030] <TB3> INFO: 41600 events read in total (3205ms).
[17:22:57.031] <TB3> INFO: Test took 4105ms.
[17:22:57.321] <TB3> INFO: Expecting 41600 events.
[17:23:00.817] <TB3> INFO: 41600 events read in total (2904ms).
[17:23:00.818] <TB3> INFO: Test took 3762ms.
[17:23:01.118] <TB3> INFO: Expecting 41600 events.
[17:23:04.670] <TB3> INFO: 41600 events read in total (2960ms).
[17:23:04.671] <TB3> INFO: Test took 3828ms.
[17:23:04.961] <TB3> INFO: Expecting 41600 events.
[17:23:08.550] <TB3> INFO: 41600 events read in total (2997ms).
[17:23:08.550] <TB3> INFO: Test took 3854ms.
[17:23:08.841] <TB3> INFO: Expecting 41600 events.
[17:23:12.441] <TB3> INFO: 41600 events read in total (3008ms).
[17:23:12.442] <TB3> INFO: Test took 3867ms.
[17:23:12.735] <TB3> INFO: Expecting 41600 events.
[17:23:16.290] <TB3> INFO: 41600 events read in total (2964ms).
[17:23:16.291] <TB3> INFO: Test took 3822ms.
[17:23:16.597] <TB3> INFO: Expecting 41600 events.
[17:23:20.172] <TB3> INFO: 41600 events read in total (2984ms).
[17:23:20.173] <TB3> INFO: Test took 3854ms.
[17:23:20.466] <TB3> INFO: Expecting 41600 events.
[17:23:23.976] <TB3> INFO: 41600 events read in total (2918ms).
[17:23:23.977] <TB3> INFO: Test took 3776ms.
[17:23:24.268] <TB3> INFO: Expecting 2560 events.
[17:23:25.168] <TB3> INFO: 2560 events read in total (309ms).
[17:23:25.169] <TB3> INFO: Test took 1179ms.
[17:23:25.477] <TB3> INFO: Expecting 2560 events.
[17:23:26.371] <TB3> INFO: 2560 events read in total (303ms).
[17:23:26.371] <TB3> INFO: Test took 1201ms.
[17:23:26.679] <TB3> INFO: Expecting 2560 events.
[17:23:27.562] <TB3> INFO: 2560 events read in total (292ms).
[17:23:27.563] <TB3> INFO: Test took 1191ms.
[17:23:27.871] <TB3> INFO: Expecting 2560 events.
[17:23:28.766] <TB3> INFO: 2560 events read in total (303ms).
[17:23:28.767] <TB3> INFO: Test took 1204ms.
[17:23:29.074] <TB3> INFO: Expecting 2560 events.
[17:23:29.959] <TB3> INFO: 2560 events read in total (292ms).
[17:23:29.959] <TB3> INFO: Test took 1191ms.
[17:23:30.267] <TB3> INFO: Expecting 2560 events.
[17:23:31.150] <TB3> INFO: 2560 events read in total (291ms).
[17:23:31.150] <TB3> INFO: Test took 1190ms.
[17:23:31.459] <TB3> INFO: Expecting 2560 events.
[17:23:32.348] <TB3> INFO: 2560 events read in total (298ms).
[17:23:32.348] <TB3> INFO: Test took 1197ms.
[17:23:32.657] <TB3> INFO: Expecting 2560 events.
[17:23:33.542] <TB3> INFO: 2560 events read in total (294ms).
[17:23:33.542] <TB3> INFO: Test took 1193ms.
[17:23:33.849] <TB3> INFO: Expecting 2560 events.
[17:23:34.728] <TB3> INFO: 2560 events read in total (287ms).
[17:23:34.728] <TB3> INFO: Test took 1185ms.
[17:23:35.036] <TB3> INFO: Expecting 2560 events.
[17:23:35.918] <TB3> INFO: 2560 events read in total (290ms).
[17:23:35.919] <TB3> INFO: Test took 1191ms.
[17:23:36.226] <TB3> INFO: Expecting 2560 events.
[17:23:37.109] <TB3> INFO: 2560 events read in total (291ms).
[17:23:37.110] <TB3> INFO: Test took 1190ms.
[17:23:37.418] <TB3> INFO: Expecting 2560 events.
[17:23:38.297] <TB3> INFO: 2560 events read in total (288ms).
[17:23:38.297] <TB3> INFO: Test took 1187ms.
[17:23:38.605] <TB3> INFO: Expecting 2560 events.
[17:23:39.499] <TB3> INFO: 2560 events read in total (302ms).
[17:23:39.499] <TB3> INFO: Test took 1201ms.
[17:23:39.808] <TB3> INFO: Expecting 2560 events.
[17:23:40.699] <TB3> INFO: 2560 events read in total (299ms).
[17:23:40.699] <TB3> INFO: Test took 1200ms.
[17:23:41.007] <TB3> INFO: Expecting 2560 events.
[17:23:41.900] <TB3> INFO: 2560 events read in total (301ms).
[17:23:41.900] <TB3> INFO: Test took 1200ms.
[17:23:42.209] <TB3> INFO: Expecting 2560 events.
[17:23:43.098] <TB3> INFO: 2560 events read in total (297ms).
[17:23:43.099] <TB3> INFO: Test took 1199ms.
[17:23:43.103] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[17:23:43.406] <TB3> INFO: Expecting 655360 events.
[17:23:58.350] <TB3> INFO: 655360 events read in total (14352ms).
[17:23:58.365] <TB3> INFO: Expecting 655360 events.
[17:24:13.180] <TB3> INFO: 655360 events read in total (14412ms).
[17:24:13.204] <TB3> INFO: Expecting 655360 events.
[17:24:28.222] <TB3> INFO: 655360 events read in total (14615ms).
[17:24:28.248] <TB3> INFO: Expecting 655360 events.
[17:24:43.226] <TB3> INFO: 655360 events read in total (14576ms).
[17:24:43.253] <TB3> INFO: Expecting 655360 events.
[17:24:57.904] <TB3> INFO: 655360 events read in total (14248ms).
[17:24:57.943] <TB3> INFO: Expecting 655360 events.
[17:25:12.735] <TB3> INFO: 655360 events read in total (14389ms).
[17:25:12.777] <TB3> INFO: Expecting 655360 events.
[17:25:27.667] <TB3> INFO: 655360 events read in total (14487ms).
[17:25:27.711] <TB3> INFO: Expecting 655360 events.
[17:25:42.487] <TB3> INFO: 655360 events read in total (14372ms).
[17:25:42.531] <TB3> INFO: Expecting 655360 events.
[17:25:57.243] <TB3> INFO: 655360 events read in total (14309ms).
[17:25:57.303] <TB3> INFO: Expecting 655360 events.
[17:26:12.041] <TB3> INFO: 655360 events read in total (14335ms).
[17:26:12.109] <TB3> INFO: Expecting 655360 events.
[17:26:26.869] <TB3> INFO: 655360 events read in total (14357ms).
[17:26:26.944] <TB3> INFO: Expecting 655360 events.
[17:26:41.774] <TB3> INFO: 655360 events read in total (14427ms).
[17:26:41.950] <TB3> INFO: Expecting 655360 events.
[17:26:56.760] <TB3> INFO: 655360 events read in total (14407ms).
[17:26:56.846] <TB3> INFO: Expecting 655360 events.
[17:27:11.677] <TB3> INFO: 655360 events read in total (14428ms).
[17:27:11.833] <TB3> INFO: Expecting 655360 events.
[17:27:26.567] <TB3> INFO: 655360 events read in total (14331ms).
[17:27:26.712] <TB3> INFO: Expecting 655360 events.
[17:27:41.402] <TB3> INFO: 655360 events read in total (14287ms).
[17:27:41.524] <TB3> INFO: Test took 238421ms.
[17:27:41.620] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[17:27:41.878] <TB3> INFO: Expecting 655360 events.
[17:27:56.471] <TB3> INFO: 655360 events read in total (14002ms).
[17:27:56.490] <TB3> INFO: Expecting 655360 events.
[17:28:11.150] <TB3> INFO: 655360 events read in total (14257ms).
[17:28:11.169] <TB3> INFO: Expecting 655360 events.
[17:28:25.626] <TB3> INFO: 655360 events read in total (14054ms).
[17:28:25.649] <TB3> INFO: Expecting 655360 events.
[17:28:40.174] <TB3> INFO: 655360 events read in total (14122ms).
[17:28:40.203] <TB3> INFO: Expecting 655360 events.
[17:28:54.608] <TB3> INFO: 655360 events read in total (14002ms).
[17:28:54.648] <TB3> INFO: Expecting 655360 events.
[17:29:09.390] <TB3> INFO: 655360 events read in total (14339ms).
[17:29:09.426] <TB3> INFO: Expecting 655360 events.
[17:29:24.405] <TB3> INFO: 655360 events read in total (14576ms).
[17:29:24.458] <TB3> INFO: Expecting 655360 events.
[17:29:39.227] <TB3> INFO: 655360 events read in total (14366ms).
[17:29:39.272] <TB3> INFO: Expecting 655360 events.
[17:29:54.042] <TB3> INFO: 655360 events read in total (14367ms).
[17:29:54.177] <TB3> INFO: Expecting 655360 events.
[17:30:09.040] <TB3> INFO: 655360 events read in total (14460ms).
[17:30:09.143] <TB3> INFO: Expecting 655360 events.
[17:30:24.187] <TB3> INFO: 655360 events read in total (14641ms).
[17:30:24.313] <TB3> INFO: Expecting 655360 events.
[17:30:39.356] <TB3> INFO: 655360 events read in total (14639ms).
[17:30:39.477] <TB3> INFO: Expecting 655360 events.
[17:30:54.507] <TB3> INFO: 655360 events read in total (14627ms).
[17:30:54.636] <TB3> INFO: Expecting 655360 events.
[17:31:09.708] <TB3> INFO: 655360 events read in total (14669ms).
[17:31:09.800] <TB3> INFO: Expecting 655360 events.
[17:31:24.616] <TB3> INFO: 655360 events read in total (14412ms).
[17:31:24.712] <TB3> INFO: Expecting 655360 events.
[17:31:39.609] <TB3> INFO: 655360 events read in total (14494ms).
[17:31:39.756] <TB3> INFO: Test took 238136ms.
[17:31:39.985] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[17:31:39.993] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[17:31:39.001] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[17:31:40.009] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[17:31:40.017] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[17:31:40.025] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[17:31:40.033] <TB3> INFO: safety margin for low PH: adding 1, margin is now 21
[17:31:40.041] <TB3> INFO: safety margin for low PH: adding 2, margin is now 22
[17:31:40.049] <TB3> INFO: safety margin for low PH: adding 3, margin is now 23
[17:31:40.057] <TB3> INFO: safety margin for low PH: adding 4, margin is now 24
[17:31:40.065] <TB3> INFO: safety margin for low PH: adding 5, margin is now 25
[17:31:40.073] <TB3> INFO: safety margin for low PH: adding 6, margin is now 26
[17:31:40.082] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[17:31:40.089] <TB3> INFO: safety margin for low PH: adding 1, margin is now 21
[17:31:40.098] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[17:31:40.106] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[17:31:40.115] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[17:31:40.124] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[17:31:40.132] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[17:31:40.141] <TB3> INFO: safety margin for low PH: adding 1, margin is now 21
[17:31:40.149] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[17:31:40.157] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[17:31:40.165] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[17:31:40.175] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[17:31:40.186] <TB3> INFO: safety margin for low PH: adding 1, margin is now 21
[17:31:40.194] <TB3> INFO: safety margin for low PH: adding 2, margin is now 22
[17:31:40.203] <TB3> INFO: safety margin for low PH: adding 3, margin is now 23
[17:31:40.212] <TB3> INFO: safety margin for low PH: adding 4, margin is now 24
[17:31:40.250] <TB3> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1111_FullQualification_2016-11-02_15h17m_1478096238//001_Fulltest_m20//dacParameters35_C0.dat
[17:31:40.250] <TB3> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1111_FullQualification_2016-11-02_15h17m_1478096238//001_Fulltest_m20//dacParameters35_C1.dat
[17:31:40.250] <TB3> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1111_FullQualification_2016-11-02_15h17m_1478096238//001_Fulltest_m20//dacParameters35_C2.dat
[17:31:40.251] <TB3> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1111_FullQualification_2016-11-02_15h17m_1478096238//001_Fulltest_m20//dacParameters35_C3.dat
[17:31:40.251] <TB3> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1111_FullQualification_2016-11-02_15h17m_1478096238//001_Fulltest_m20//dacParameters35_C4.dat
[17:31:40.251] <TB3> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1111_FullQualification_2016-11-02_15h17m_1478096238//001_Fulltest_m20//dacParameters35_C5.dat
[17:31:40.251] <TB3> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1111_FullQualification_2016-11-02_15h17m_1478096238//001_Fulltest_m20//dacParameters35_C6.dat
[17:31:40.251] <TB3> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1111_FullQualification_2016-11-02_15h17m_1478096238//001_Fulltest_m20//dacParameters35_C7.dat
[17:31:40.251] <TB3> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1111_FullQualification_2016-11-02_15h17m_1478096238//001_Fulltest_m20//dacParameters35_C8.dat
[17:31:40.251] <TB3> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1111_FullQualification_2016-11-02_15h17m_1478096238//001_Fulltest_m20//dacParameters35_C9.dat
[17:31:40.251] <TB3> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1111_FullQualification_2016-11-02_15h17m_1478096238//001_Fulltest_m20//dacParameters35_C10.dat
[17:31:40.251] <TB3> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1111_FullQualification_2016-11-02_15h17m_1478096238//001_Fulltest_m20//dacParameters35_C11.dat
[17:31:40.251] <TB3> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1111_FullQualification_2016-11-02_15h17m_1478096238//001_Fulltest_m20//dacParameters35_C12.dat
[17:31:40.252] <TB3> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1111_FullQualification_2016-11-02_15h17m_1478096238//001_Fulltest_m20//dacParameters35_C13.dat
[17:31:40.252] <TB3> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1111_FullQualification_2016-11-02_15h17m_1478096238//001_Fulltest_m20//dacParameters35_C14.dat
[17:31:40.252] <TB3> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1111_FullQualification_2016-11-02_15h17m_1478096238//001_Fulltest_m20//dacParameters35_C15.dat
[17:31:40.492] <TB3> INFO: Expecting 41600 events.
[17:31:43.697] <TB3> INFO: 41600 events read in total (2614ms).
[17:31:43.698] <TB3> INFO: Test took 3444ms.
[17:31:44.146] <TB3> INFO: Expecting 41600 events.
[17:31:47.308] <TB3> INFO: 41600 events read in total (2570ms).
[17:31:47.309] <TB3> INFO: Test took 3400ms.
[17:31:47.768] <TB3> INFO: Expecting 41600 events.
[17:31:50.984] <TB3> INFO: 41600 events read in total (2624ms).
[17:31:50.984] <TB3> INFO: Test took 3459ms.
[17:31:51.202] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[17:31:51.291] <TB3> INFO: Expecting 2560 events.
[17:31:52.186] <TB3> INFO: 2560 events read in total (303ms).
[17:31:52.186] <TB3> INFO: Test took 984ms.
[17:31:52.188] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[17:31:52.493] <TB3> INFO: Expecting 2560 events.
[17:31:53.378] <TB3> INFO: 2560 events read in total (293ms).
[17:31:53.378] <TB3> INFO: Test took 1190ms.
[17:31:53.380] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[17:31:53.686] <TB3> INFO: Expecting 2560 events.
[17:31:54.570] <TB3> INFO: 2560 events read in total (292ms).
[17:31:54.570] <TB3> INFO: Test took 1190ms.
[17:31:54.573] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[17:31:54.878] <TB3> INFO: Expecting 2560 events.
[17:31:55.761] <TB3> INFO: 2560 events read in total (291ms).
[17:31:55.761] <TB3> INFO: Test took 1188ms.
[17:31:55.763] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[17:31:56.069] <TB3> INFO: Expecting 2560 events.
[17:31:56.953] <TB3> INFO: 2560 events read in total (292ms).
[17:31:56.954] <TB3> INFO: Test took 1191ms.
[17:31:56.956] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[17:31:57.262] <TB3> INFO: Expecting 2560 events.
[17:31:58.146] <TB3> INFO: 2560 events read in total (292ms).
[17:31:58.146] <TB3> INFO: Test took 1190ms.
[17:31:58.148] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[17:31:58.455] <TB3> INFO: Expecting 2560 events.
[17:31:59.340] <TB3> INFO: 2560 events read in total (294ms).
[17:31:59.340] <TB3> INFO: Test took 1192ms.
[17:31:59.344] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[17:31:59.649] <TB3> INFO: Expecting 2560 events.
[17:32:00.535] <TB3> INFO: 2560 events read in total (295ms).
[17:32:00.535] <TB3> INFO: Test took 1191ms.
[17:32:00.537] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[17:32:00.843] <TB3> INFO: Expecting 2560 events.
[17:32:01.731] <TB3> INFO: 2560 events read in total (296ms).
[17:32:01.732] <TB3> INFO: Test took 1195ms.
[17:32:01.734] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[17:32:02.040] <TB3> INFO: Expecting 2560 events.
[17:32:02.919] <TB3> INFO: 2560 events read in total (288ms).
[17:32:02.919] <TB3> INFO: Test took 1185ms.
[17:32:02.922] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[17:32:03.228] <TB3> INFO: Expecting 2560 events.
[17:32:04.107] <TB3> INFO: 2560 events read in total (287ms).
[17:32:04.107] <TB3> INFO: Test took 1185ms.
[17:32:04.111] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[17:32:04.416] <TB3> INFO: Expecting 2560 events.
[17:32:05.297] <TB3> INFO: 2560 events read in total (289ms).
[17:32:05.298] <TB3> INFO: Test took 1187ms.
[17:32:05.300] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[17:32:05.606] <TB3> INFO: Expecting 2560 events.
[17:32:06.486] <TB3> INFO: 2560 events read in total (289ms).
[17:32:06.486] <TB3> INFO: Test took 1186ms.
[17:32:06.488] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[17:32:06.795] <TB3> INFO: Expecting 2560 events.
[17:32:07.673] <TB3> INFO: 2560 events read in total (287ms).
[17:32:07.673] <TB3> INFO: Test took 1185ms.
[17:32:07.676] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[17:32:07.982] <TB3> INFO: Expecting 2560 events.
[17:32:08.861] <TB3> INFO: 2560 events read in total (287ms).
[17:32:08.861] <TB3> INFO: Test took 1185ms.
[17:32:08.863] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[17:32:09.169] <TB3> INFO: Expecting 2560 events.
[17:32:10.049] <TB3> INFO: 2560 events read in total (288ms).
[17:32:10.049] <TB3> INFO: Test took 1186ms.
[17:32:10.053] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[17:32:10.357] <TB3> INFO: Expecting 2560 events.
[17:32:11.236] <TB3> INFO: 2560 events read in total (287ms).
[17:32:11.237] <TB3> INFO: Test took 1184ms.
[17:32:11.240] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[17:32:11.545] <TB3> INFO: Expecting 2560 events.
[17:32:12.424] <TB3> INFO: 2560 events read in total (287ms).
[17:32:12.424] <TB3> INFO: Test took 1184ms.
[17:32:12.426] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[17:32:12.733] <TB3> INFO: Expecting 2560 events.
[17:32:13.612] <TB3> INFO: 2560 events read in total (288ms).
[17:32:13.612] <TB3> INFO: Test took 1186ms.
[17:32:13.615] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[17:32:13.921] <TB3> INFO: Expecting 2560 events.
[17:32:14.801] <TB3> INFO: 2560 events read in total (289ms).
[17:32:14.801] <TB3> INFO: Test took 1187ms.
[17:32:14.803] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[17:32:15.109] <TB3> INFO: Expecting 2560 events.
[17:32:15.989] <TB3> INFO: 2560 events read in total (288ms).
[17:32:15.989] <TB3> INFO: Test took 1186ms.
[17:32:15.992] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[17:32:16.298] <TB3> INFO: Expecting 2560 events.
[17:32:17.176] <TB3> INFO: 2560 events read in total (287ms).
[17:32:17.176] <TB3> INFO: Test took 1184ms.
[17:32:17.180] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[17:32:17.485] <TB3> INFO: Expecting 2560 events.
[17:32:18.363] <TB3> INFO: 2560 events read in total (287ms).
[17:32:18.363] <TB3> INFO: Test took 1183ms.
[17:32:18.368] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[17:32:18.672] <TB3> INFO: Expecting 2560 events.
[17:32:19.551] <TB3> INFO: 2560 events read in total (288ms).
[17:32:19.551] <TB3> INFO: Test took 1184ms.
[17:32:19.554] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[17:32:19.859] <TB3> INFO: Expecting 2560 events.
[17:32:20.743] <TB3> INFO: 2560 events read in total (292ms).
[17:32:20.743] <TB3> INFO: Test took 1189ms.
[17:32:20.747] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[17:32:21.052] <TB3> INFO: Expecting 2560 events.
[17:32:21.935] <TB3> INFO: 2560 events read in total (292ms).
[17:32:21.935] <TB3> INFO: Test took 1188ms.
[17:32:21.938] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[17:32:22.245] <TB3> INFO: Expecting 2560 events.
[17:32:23.131] <TB3> INFO: 2560 events read in total (295ms).
[17:32:23.132] <TB3> INFO: Test took 1194ms.
[17:32:23.134] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[17:32:23.440] <TB3> INFO: Expecting 2560 events.
[17:32:24.322] <TB3> INFO: 2560 events read in total (291ms).
[17:32:24.323] <TB3> INFO: Test took 1189ms.
[17:32:24.326] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[17:32:24.631] <TB3> INFO: Expecting 2560 events.
[17:32:25.515] <TB3> INFO: 2560 events read in total (292ms).
[17:32:25.515] <TB3> INFO: Test took 1189ms.
[17:32:25.517] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[17:32:25.823] <TB3> INFO: Expecting 2560 events.
[17:32:26.711] <TB3> INFO: 2560 events read in total (296ms).
[17:32:26.711] <TB3> INFO: Test took 1194ms.
[17:32:26.715] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[17:32:27.022] <TB3> INFO: Expecting 2560 events.
[17:32:27.912] <TB3> INFO: 2560 events read in total (299ms).
[17:32:27.912] <TB3> INFO: Test took 1197ms.
[17:32:27.915] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[17:32:28.221] <TB3> INFO: Expecting 2560 events.
[17:32:29.104] <TB3> INFO: 2560 events read in total (291ms).
[17:32:29.105] <TB3> INFO: Test took 1190ms.
[17:32:29.573] <TB3> INFO: PixTestPhOptimization::doTest() done, duration: 665 seconds
[17:32:29.573] <TB3> INFO: PH scale (per ROC): 67 66 77 45 66 49 70 66 51 57 44 60 69 66 63 70
[17:32:29.573] <TB3> INFO: PH offset (per ROC): 124 117 114 101 127 112 124 115 105 133 96 131 115 124 124 112
[17:32:29.581] <TB3> INFO: Decoding statistics:
[17:32:29.581] <TB3> INFO: General information:
[17:32:29.582] <TB3> INFO: 16bit words read: 127884
[17:32:29.582] <TB3> INFO: valid events total: 20480
[17:32:29.582] <TB3> INFO: empty events: 17978
[17:32:29.582] <TB3> INFO: valid events with pixels: 2502
[17:32:29.582] <TB3> INFO: valid pixel hits: 2502
[17:32:29.582] <TB3> INFO: Event errors: 0
[17:32:29.582] <TB3> INFO: start marker: 0
[17:32:29.582] <TB3> INFO: stop marker: 0
[17:32:29.582] <TB3> INFO: overflow: 0
[17:32:29.582] <TB3> INFO: invalid 5bit words: 0
[17:32:29.582] <TB3> INFO: invalid XOR eye diagram: 0
[17:32:29.582] <TB3> INFO: frame (failed synchr.): 0
[17:32:29.582] <TB3> INFO: idle data (no TBM trl): 0
[17:32:29.582] <TB3> INFO: no data (only TBM hdr): 0
[17:32:29.582] <TB3> INFO: TBM errors: 0
[17:32:29.582] <TB3> INFO: flawed TBM headers: 0
[17:32:29.582] <TB3> INFO: flawed TBM trailers: 0
[17:32:29.582] <TB3> INFO: event ID mismatches: 0
[17:32:29.582] <TB3> INFO: ROC errors: 0
[17:32:29.582] <TB3> INFO: missing ROC header(s): 0
[17:32:29.582] <TB3> INFO: misplaced readback start: 0
[17:32:29.582] <TB3> INFO: Pixel decoding errors: 0
[17:32:29.582] <TB3> INFO: pixel data incomplete: 0
[17:32:29.582] <TB3> INFO: pixel address: 0
[17:32:29.582] <TB3> INFO: pulse height fill bit: 0
[17:32:29.582] <TB3> INFO: buffer corruption: 0
[17:32:29.742] <TB3> INFO: ######################################################################
[17:32:29.742] <TB3> INFO: PixTestGainPedestal::fullTest() ntrig = 10
[17:32:29.742] <TB3> INFO: ######################################################################
[17:32:29.756] <TB3> INFO: scanning low vcal = 10
[17:32:29.993] <TB3> INFO: Expecting 41600 events.
[17:32:33.627] <TB3> INFO: 41600 events read in total (3042ms).
[17:32:33.628] <TB3> INFO: Test took 3872ms.
[17:32:33.629] <TB3> INFO: scanning low vcal = 20
[17:32:33.924] <TB3> INFO: Expecting 41600 events.
[17:32:37.554] <TB3> INFO: 41600 events read in total (3039ms).
[17:32:37.554] <TB3> INFO: Test took 3924ms.
[17:32:37.556] <TB3> INFO: scanning low vcal = 30
[17:32:37.851] <TB3> INFO: Expecting 41600 events.
[17:32:41.518] <TB3> INFO: 41600 events read in total (3075ms).
[17:32:41.520] <TB3> INFO: Test took 3963ms.
[17:32:41.522] <TB3> INFO: scanning low vcal = 40
[17:32:41.807] <TB3> INFO: Expecting 41600 events.
[17:32:45.757] <TB3> INFO: 41600 events read in total (3358ms).
[17:32:45.758] <TB3> INFO: Test took 4236ms.
[17:32:45.762] <TB3> INFO: scanning low vcal = 50
[17:32:46.060] <TB3> INFO: Expecting 41600 events.
[17:32:50.084] <TB3> INFO: 41600 events read in total (3432ms).
[17:32:50.085] <TB3> INFO: Test took 4323ms.
[17:32:50.088] <TB3> INFO: scanning low vcal = 60
[17:32:50.364] <TB3> INFO: Expecting 41600 events.
[17:32:54.394] <TB3> INFO: 41600 events read in total (3438ms).
[17:32:54.394] <TB3> INFO: Test took 4305ms.
[17:32:54.398] <TB3> INFO: scanning low vcal = 70
[17:32:54.674] <TB3> INFO: Expecting 41600 events.
[17:32:58.664] <TB3> INFO: 41600 events read in total (3398ms).
[17:32:58.665] <TB3> INFO: Test took 4267ms.
[17:32:58.668] <TB3> INFO: scanning low vcal = 80
[17:32:58.944] <TB3> INFO: Expecting 41600 events.
[17:33:02.907] <TB3> INFO: 41600 events read in total (3371ms).
[17:33:02.908] <TB3> INFO: Test took 4240ms.
[17:33:02.911] <TB3> INFO: scanning low vcal = 90
[17:33:03.187] <TB3> INFO: Expecting 41600 events.
[17:33:07.224] <TB3> INFO: 41600 events read in total (3445ms).
[17:33:07.225] <TB3> INFO: Test took 4314ms.
[17:33:07.229] <TB3> INFO: scanning low vcal = 100
[17:33:07.504] <TB3> INFO: Expecting 41600 events.
[17:33:11.491] <TB3> INFO: 41600 events read in total (3395ms).
[17:33:11.491] <TB3> INFO: Test took 4262ms.
[17:33:11.495] <TB3> INFO: scanning low vcal = 110
[17:33:11.771] <TB3> INFO: Expecting 41600 events.
[17:33:15.736] <TB3> INFO: 41600 events read in total (3374ms).
[17:33:15.737] <TB3> INFO: Test took 4242ms.
[17:33:15.740] <TB3> INFO: scanning low vcal = 120
[17:33:16.016] <TB3> INFO: Expecting 41600 events.
[17:33:20.040] <TB3> INFO: 41600 events read in total (3432ms).
[17:33:20.041] <TB3> INFO: Test took 4301ms.
[17:33:20.044] <TB3> INFO: scanning low vcal = 130
[17:33:20.361] <TB3> INFO: Expecting 41600 events.
[17:33:24.440] <TB3> INFO: 41600 events read in total (3488ms).
[17:33:24.441] <TB3> INFO: Test took 4396ms.
[17:33:24.445] <TB3> INFO: scanning low vcal = 140
[17:33:24.762] <TB3> INFO: Expecting 41600 events.
[17:33:28.755] <TB3> INFO: 41600 events read in total (3401ms).
[17:33:28.755] <TB3> INFO: Test took 4310ms.
[17:33:28.758] <TB3> INFO: scanning low vcal = 150
[17:33:29.073] <TB3> INFO: Expecting 41600 events.
[17:33:33.125] <TB3> INFO: 41600 events read in total (3460ms).
[17:33:33.126] <TB3> INFO: Test took 4368ms.
[17:33:33.129] <TB3> INFO: scanning low vcal = 160
[17:33:33.448] <TB3> INFO: Expecting 41600 events.
[17:33:37.529] <TB3> INFO: 41600 events read in total (3489ms).
[17:33:37.529] <TB3> INFO: Test took 4400ms.
[17:33:37.533] <TB3> INFO: scanning low vcal = 170
[17:33:37.852] <TB3> INFO: Expecting 41600 events.
[17:33:41.883] <TB3> INFO: 41600 events read in total (3440ms).
[17:33:41.883] <TB3> INFO: Test took 4350ms.
[17:33:41.889] <TB3> INFO: scanning low vcal = 180
[17:33:42.163] <TB3> INFO: Expecting 41600 events.
[17:33:46.123] <TB3> INFO: 41600 events read in total (3369ms).
[17:33:46.124] <TB3> INFO: Test took 4235ms.
[17:33:46.127] <TB3> INFO: scanning low vcal = 190
[17:33:46.404] <TB3> INFO: Expecting 41600 events.
[17:33:50.363] <TB3> INFO: 41600 events read in total (3367ms).
[17:33:50.364] <TB3> INFO: Test took 4237ms.
[17:33:50.367] <TB3> INFO: scanning low vcal = 200
[17:33:50.644] <TB3> INFO: Expecting 41600 events.
[17:33:54.602] <TB3> INFO: 41600 events read in total (3366ms).
[17:33:54.603] <TB3> INFO: Test took 4236ms.
[17:33:54.606] <TB3> INFO: scanning low vcal = 210
[17:33:54.883] <TB3> INFO: Expecting 41600 events.
[17:33:58.837] <TB3> INFO: 41600 events read in total (3362ms).
[17:33:58.838] <TB3> INFO: Test took 4232ms.
[17:33:58.841] <TB3> INFO: scanning low vcal = 220
[17:33:59.118] <TB3> INFO: Expecting 41600 events.
[17:34:03.074] <TB3> INFO: 41600 events read in total (3364ms).
[17:34:03.075] <TB3> INFO: Test took 4234ms.
[17:34:03.078] <TB3> INFO: scanning low vcal = 230
[17:34:03.355] <TB3> INFO: Expecting 41600 events.
[17:34:07.312] <TB3> INFO: 41600 events read in total (3365ms).
[17:34:07.313] <TB3> INFO: Test took 4235ms.
[17:34:07.316] <TB3> INFO: scanning low vcal = 240
[17:34:07.593] <TB3> INFO: Expecting 41600 events.
[17:34:11.552] <TB3> INFO: 41600 events read in total (3367ms).
[17:34:11.552] <TB3> INFO: Test took 4236ms.
[17:34:11.556] <TB3> INFO: scanning low vcal = 250
[17:34:11.832] <TB3> INFO: Expecting 41600 events.
[17:34:15.790] <TB3> INFO: 41600 events read in total (3366ms).
[17:34:15.791] <TB3> INFO: Test took 4235ms.
[17:34:15.795] <TB3> INFO: scanning high vcal = 30 (= 210 in low range)
[17:34:16.071] <TB3> INFO: Expecting 41600 events.
[17:34:20.037] <TB3> INFO: 41600 events read in total (3374ms).
[17:34:20.037] <TB3> INFO: Test took 4242ms.
[17:34:20.040] <TB3> INFO: scanning high vcal = 50 (= 350 in low range)
[17:34:20.317] <TB3> INFO: Expecting 41600 events.
[17:34:24.276] <TB3> INFO: 41600 events read in total (3367ms).
[17:34:24.277] <TB3> INFO: Test took 4236ms.
[17:34:24.280] <TB3> INFO: scanning high vcal = 70 (= 490 in low range)
[17:34:24.557] <TB3> INFO: Expecting 41600 events.
[17:34:28.512] <TB3> INFO: 41600 events read in total (3364ms).
[17:34:28.512] <TB3> INFO: Test took 4232ms.
[17:34:28.516] <TB3> INFO: scanning high vcal = 90 (= 630 in low range)
[17:34:28.792] <TB3> INFO: Expecting 41600 events.
[17:34:32.750] <TB3> INFO: 41600 events read in total (3366ms).
[17:34:32.751] <TB3> INFO: Test took 4235ms.
[17:34:32.754] <TB3> INFO: scanning high vcal = 200 (= 1400 in low range)
[17:34:33.031] <TB3> INFO: Expecting 41600 events.
[17:34:36.990] <TB3> INFO: 41600 events read in total (3367ms).
[17:34:36.991] <TB3> INFO: Test took 4237ms.
[17:34:37.379] <TB3> INFO: PixTestGainPedestal::measure() done
[17:35:08.845] <TB3> INFO: PixTestGainPedestal::fit() done
[17:35:08.845] <TB3> INFO: non-linearity mean: 0.980 0.984 0.986 0.912 0.981 0.916 0.980 0.984 0.930 0.984 0.981 0.977 0.985 0.980 0.982 0.985
[17:35:08.845] <TB3> INFO: non-linearity RMS: 0.005 0.003 0.003 0.079 0.003 0.086 0.003 0.005 0.077 0.004 0.241 0.005 0.004 0.006 0.003 0.004
[17:35:08.845] <TB3> INFO: write gain/ped parameters into /home/l_tester/david/FullQualification/data/M1111_FullQualification_2016-11-02_15h17m_1478096238//001_Fulltest_m20//phCalibrationFitErr35_C0.dat
[17:35:08.859] <TB3> INFO: write gain/ped parameters into /home/l_tester/david/FullQualification/data/M1111_FullQualification_2016-11-02_15h17m_1478096238//001_Fulltest_m20//phCalibrationFitErr35_C1.dat
[17:35:08.872] <TB3> INFO: write gain/ped parameters into /home/l_tester/david/FullQualification/data/M1111_FullQualification_2016-11-02_15h17m_1478096238//001_Fulltest_m20//phCalibrationFitErr35_C2.dat
[17:35:08.886] <TB3> INFO: write gain/ped parameters into /home/l_tester/david/FullQualification/data/M1111_FullQualification_2016-11-02_15h17m_1478096238//001_Fulltest_m20//phCalibrationFitErr35_C3.dat
[17:35:08.900] <TB3> INFO: write gain/ped parameters into /home/l_tester/david/FullQualification/data/M1111_FullQualification_2016-11-02_15h17m_1478096238//001_Fulltest_m20//phCalibrationFitErr35_C4.dat
[17:35:08.914] <TB3> INFO: write gain/ped parameters into /home/l_tester/david/FullQualification/data/M1111_FullQualification_2016-11-02_15h17m_1478096238//001_Fulltest_m20//phCalibrationFitErr35_C5.dat
[17:35:08.927] <TB3> INFO: write gain/ped parameters into /home/l_tester/david/FullQualification/data/M1111_FullQualification_2016-11-02_15h17m_1478096238//001_Fulltest_m20//phCalibrationFitErr35_C6.dat
[17:35:08.941] <TB3> INFO: write gain/ped parameters into /home/l_tester/david/FullQualification/data/M1111_FullQualification_2016-11-02_15h17m_1478096238//001_Fulltest_m20//phCalibrationFitErr35_C7.dat
[17:35:08.955] <TB3> INFO: write gain/ped parameters into /home/l_tester/david/FullQualification/data/M1111_FullQualification_2016-11-02_15h17m_1478096238//001_Fulltest_m20//phCalibrationFitErr35_C8.dat
[17:35:08.969] <TB3> INFO: write gain/ped parameters into /home/l_tester/david/FullQualification/data/M1111_FullQualification_2016-11-02_15h17m_1478096238//001_Fulltest_m20//phCalibrationFitErr35_C9.dat
[17:35:08.983] <TB3> INFO: write gain/ped parameters into /home/l_tester/david/FullQualification/data/M1111_FullQualification_2016-11-02_15h17m_1478096238//001_Fulltest_m20//phCalibrationFitErr35_C10.dat
[17:35:08.997] <TB3> INFO: write gain/ped parameters into /home/l_tester/david/FullQualification/data/M1111_FullQualification_2016-11-02_15h17m_1478096238//001_Fulltest_m20//phCalibrationFitErr35_C11.dat
[17:35:09.011] <TB3> INFO: write gain/ped parameters into /home/l_tester/david/FullQualification/data/M1111_FullQualification_2016-11-02_15h17m_1478096238//001_Fulltest_m20//phCalibrationFitErr35_C12.dat
[17:35:09.025] <TB3> INFO: write gain/ped parameters into /home/l_tester/david/FullQualification/data/M1111_FullQualification_2016-11-02_15h17m_1478096238//001_Fulltest_m20//phCalibrationFitErr35_C13.dat
[17:35:09.039] <TB3> INFO: write gain/ped parameters into /home/l_tester/david/FullQualification/data/M1111_FullQualification_2016-11-02_15h17m_1478096238//001_Fulltest_m20//phCalibrationFitErr35_C14.dat
[17:35:09.053] <TB3> INFO: write gain/ped parameters into /home/l_tester/david/FullQualification/data/M1111_FullQualification_2016-11-02_15h17m_1478096238//001_Fulltest_m20//phCalibrationFitErr35_C15.dat
[17:35:09.066] <TB3> INFO: PixTestGainPedestal::fullTest() done, duration: 159 seconds
[17:35:09.066] <TB3> INFO: Decoding statistics:
[17:35:09.066] <TB3> INFO: General information:
[17:35:09.066] <TB3> INFO: 16bit words read: 3327962
[17:35:09.066] <TB3> INFO: valid events total: 332800
[17:35:09.066] <TB3> INFO: empty events: 0
[17:35:09.066] <TB3> INFO: valid events with pixels: 332800
[17:35:09.066] <TB3> INFO: valid pixel hits: 665581
[17:35:09.066] <TB3> INFO: Event errors: 0
[17:35:09.066] <TB3> INFO: start marker: 0
[17:35:09.066] <TB3> INFO: stop marker: 0
[17:35:09.066] <TB3> INFO: overflow: 0
[17:35:09.066] <TB3> INFO: invalid 5bit words: 0
[17:35:09.066] <TB3> INFO: invalid XOR eye diagram: 0
[17:35:09.066] <TB3> INFO: frame (failed synchr.): 0
[17:35:09.066] <TB3> INFO: idle data (no TBM trl): 0
[17:35:09.066] <TB3> INFO: no data (only TBM hdr): 0
[17:35:09.066] <TB3> INFO: TBM errors: 0
[17:35:09.067] <TB3> INFO: flawed TBM headers: 0
[17:35:09.067] <TB3> INFO: flawed TBM trailers: 0
[17:35:09.067] <TB3> INFO: event ID mismatches: 0
[17:35:09.067] <TB3> INFO: ROC errors: 0
[17:35:09.067] <TB3> INFO: missing ROC header(s): 0
[17:35:09.067] <TB3> INFO: misplaced readback start: 0
[17:35:09.067] <TB3> INFO: Pixel decoding errors: 0
[17:35:09.067] <TB3> INFO: pixel data incomplete: 0
[17:35:09.067] <TB3> INFO: pixel address: 0
[17:35:09.067] <TB3> INFO: pulse height fill bit: 0
[17:35:09.067] <TB3> INFO: buffer corruption: 0
[17:35:09.086] <TB3> INFO: Decoding statistics:
[17:35:09.086] <TB3> INFO: General information:
[17:35:09.086] <TB3> INFO: 16bit words read: 3457382
[17:35:09.086] <TB3> INFO: valid events total: 353536
[17:35:09.086] <TB3> INFO: empty events: 18234
[17:35:09.086] <TB3> INFO: valid events with pixels: 335302
[17:35:09.086] <TB3> INFO: valid pixel hits: 668083
[17:35:09.086] <TB3> INFO: Event errors: 0
[17:35:09.086] <TB3> INFO: start marker: 0
[17:35:09.086] <TB3> INFO: stop marker: 0
[17:35:09.086] <TB3> INFO: overflow: 0
[17:35:09.086] <TB3> INFO: invalid 5bit words: 0
[17:35:09.086] <TB3> INFO: invalid XOR eye diagram: 0
[17:35:09.086] <TB3> INFO: frame (failed synchr.): 0
[17:35:09.086] <TB3> INFO: idle data (no TBM trl): 0
[17:35:09.086] <TB3> INFO: no data (only TBM hdr): 0
[17:35:09.086] <TB3> INFO: TBM errors: 0
[17:35:09.086] <TB3> INFO: flawed TBM headers: 0
[17:35:09.086] <TB3> INFO: flawed TBM trailers: 0
[17:35:09.086] <TB3> INFO: event ID mismatches: 0
[17:35:09.086] <TB3> INFO: ROC errors: 0
[17:35:09.086] <TB3> INFO: missing ROC header(s): 0
[17:35:09.086] <TB3> INFO: misplaced readback start: 0
[17:35:09.086] <TB3> INFO: Pixel decoding errors: 0
[17:35:09.086] <TB3> INFO: pixel data incomplete: 0
[17:35:09.086] <TB3> INFO: pixel address: 0
[17:35:09.086] <TB3> INFO: pulse height fill bit: 0
[17:35:09.086] <TB3> INFO: buffer corruption: 0
[17:35:09.086] <TB3> INFO: enter test to run
[17:35:09.086] <TB3> INFO: test: exit no parameter change
[17:35:09.217] <TB3> QUIET: Connection to board 126 closed.
[17:35:09.217] <TB3> INFO: pXar: this is the end, my friend