[14:49:56.446] <TB3> INFO: *** Welcome to pxar ***
[14:49:56.446] <TB3> INFO: *** Today: 2016/10/21
[14:49:56.454] <TB3> INFO: *** Version: c8ba-dirty
[14:49:56.455] <TB3> INFO: readRocDacs: /home/l_tester/david/FullQualification/data/M1039_FullQualification_2016-10-21_13h57m_1477051074//001_Fulltest_m20//dacParameters_C0.dat .. /home/l_tester/david/FullQualification/data/M1039_FullQualification_2016-10-21_13h57m_1477051074//001_Fulltest_m20//dacParameters_C15.dat
[14:49:56.455] <TB3> INFO: readTbmDacs: /home/l_tester/david/FullQualification/data/M1039_FullQualification_2016-10-21_13h57m_1477051074//001_Fulltest_m20//tbmParameters_C0a.dat .. /home/l_tester/david/FullQualification/data/M1039_FullQualification_2016-10-21_13h57m_1477051074//001_Fulltest_m20//tbmParameters_C1b.dat
[14:49:56.456] <TB3> INFO: readMaskFile: /home/l_tester/david/FullQualification/data/M1039_FullQualification_2016-10-21_13h57m_1477051074//001_Fulltest_m20//defaultMaskFile.dat
[14:49:56.456] <TB3> INFO: readTrimFile: /home/l_tester/david/FullQualification/data/M1039_FullQualification_2016-10-21_13h57m_1477051074//001_Fulltest_m20//trimParameters_C0.dat .. /home/l_tester/david/FullQualification/data/M1039_FullQualification_2016-10-21_13h57m_1477051074//001_Fulltest_m20//trimParameters_C15.dat
[14:49:56.545] <TB3> INFO: clk: 4
[14:49:56.545] <TB3> INFO: ctr: 4
[14:49:56.545] <TB3> INFO: sda: 19
[14:49:56.545] <TB3> INFO: tin: 9
[14:49:56.545] <TB3> INFO: level: 15
[14:49:56.545] <TB3> INFO: triggerdelay: 0
[14:49:56.545] <TB3> QUIET: Instanciating API for pxar v2.7.6+55~gafdbfd9
[14:49:56.545] <TB3> INFO: Log level: INFO
[14:49:56.555] <TB3> INFO: Found DTB DTB_WWVASW
[14:49:56.564] <TB3> QUIET: Connection to board DTB_WWVASW opened.
[14:49:56.566] <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:
------------------------------------------------------
[14:49:56.567] <TB3> INFO: RPC call hashes of host and DTB match: 486171790
[14:49:58.058] <TB3> INFO: DUT info:
[14:49:58.058] <TB3> INFO: The DUT currently contains the following objects:
[14:49:58.058] <TB3> INFO: 4 TBM Cores tbm10c (4 ON)
[14:49:58.058] <TB3> INFO: TBM Core alpha (0): 7 registers set
[14:49:58.058] <TB3> INFO: TBM Core beta (1): 7 registers set
[14:49:58.058] <TB3> INFO: TBM Core alpha (2): 7 registers set
[14:49:58.058] <TB3> INFO: TBM Core beta (3): 7 registers set
[14:49:58.058] <TB3> INFO: 16 ROCs proc600 (16 ON) with 4160 pixelConfigs
[14:49:58.058] <TB3> INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[14:49:58.058] <TB3> INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[14:49:58.059] <TB3> INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[14:49:58.059] <TB3> INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[14:49:58.059] <TB3> INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[14:49:58.059] <TB3> INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[14:49:58.059] <TB3> INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[14:49:58.059] <TB3> INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[14:49:58.059] <TB3> INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[14:49:58.059] <TB3> INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[14:49:58.059] <TB3> INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[14:49:58.059] <TB3> INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[14:49:58.059] <TB3> INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[14:49:58.059] <TB3> INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[14:49:58.059] <TB3> INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[14:49:58.059] <TB3> INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[14:49:58.461] <TB3> INFO: enter 'restricted' command line mode
[14:49:58.461] <TB3> INFO: enter test to run
[14:49:58.461] <TB3> INFO: test: pretest no parameter change
[14:49:58.461] <TB3> INFO: running: pretest
[14:49:58.469] <TB3> INFO: ######################################################################
[14:49:58.469] <TB3> INFO: PixTestPretest::doTest()
[14:49:58.469] <TB3> INFO: ######################################################################
[14:49:58.470] <TB3> INFO: ----------------------------------------------------------------------
[14:49:58.470] <TB3> INFO: PixTestPretest::programROC()
[14:49:58.470] <TB3> INFO: ----------------------------------------------------------------------
[14:50:16.485] <TB3> INFO: PixTestPretest::programROC() done: ROCs are all programmable
[14:50:16.485] <TB3> INFO: IA differences per ROC: 16.9 18.5 19.3 15.3 20.9 16.1 19.3 16.9 20.1 22.5 18.5 16.9 20.1 18.5 18.5 18.5
[14:50:16.558] <TB3> INFO: ----------------------------------------------------------------------
[14:50:16.558] <TB3> INFO: PixTestPretest::setVana() target Ia = 24 mA/ROC
[14:50:16.558] <TB3> INFO: ----------------------------------------------------------------------
[14:50:37.847] <TB3> INFO: PixTestPretest::setVana() done, Module Ia 385.1 mA = 24.0688 mA/ROC
[14:50:37.847] <TB3> INFO: i(loss) [mA/ROC]: 18.5 19.3 19.3 19.3 19.3 19.3 20.1 19.3 17.7 18.5 20.1 18.5 19.3 21.7 20.9 19.3
[14:50:37.875] <TB3> INFO: ----------------------------------------------------------------------
[14:50:37.875] <TB3> INFO: PixTestPretest::findTiming()
[14:50:37.875] <TB3> INFO: ----------------------------------------------------------------------
[14:50:37.875] <TB3> INFO: PixTestCmd::init()

[14:51:09.613] <TB3> INFO: TBM phases: 160MHz: 7, 400MHz: 2, TBM delays: ROC(0/1):5, header/trailer: 1, token: 1
[14:51:09.613] <TB3> INFO: (success/tries = 100/100), width = 4
[14:51:11.111] <TB3> INFO: ----------------------------------------------------------------------
[14:51:11.111] <TB3> INFO: PixTestPretest::findWorkingPixel()
[14:51:11.111] <TB3> INFO: ----------------------------------------------------------------------
[14:51:11.203] <TB3> INFO: Expecting 231680 events.
[14:51:21.120] <TB3> INFO: 231680 events read in total (9325ms).
[14:51:21.129] <TB3> INFO: Test took 10015ms.
[14:51:21.376] <TB3> INFO: Found working pixel in all ROCs: col/row = 12/22
[14:51:21.417] <TB3> INFO: ----------------------------------------------------------------------
[14:51:21.417] <TB3> INFO: PixTestPretest::setVthrCompCalDel()
[14:51:21.417] <TB3> INFO: ----------------------------------------------------------------------
[14:51:21.512] <TB3> INFO: Expecting 231680 events.
[14:51:31.595] <TB3> INFO: 231680 events read in total (9492ms).
[14:51:31.603] <TB3> INFO: Test took 10181ms.
[14:51:31.856] <TB3> INFO: PixTestPretest::setVthrCompCalDel() done
[14:51:31.856] <TB3> INFO: CalDel: 110 105 106 89 94 109 95 111 94 102 122 110 125 111 107 106
[14:51:31.856] <TB3> INFO: VthrComp: 51 51 51 51 51 51 51 51 51 51 51 55 51 51 51 51
[14:51:31.861] <TB3> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1039_FullQualification_2016-10-21_13h57m_1477051074//001_Fulltest_m20//dacParameters_C0.dat
[14:51:31.862] <TB3> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1039_FullQualification_2016-10-21_13h57m_1477051074//001_Fulltest_m20//dacParameters_C1.dat
[14:51:31.862] <TB3> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1039_FullQualification_2016-10-21_13h57m_1477051074//001_Fulltest_m20//dacParameters_C2.dat
[14:51:31.862] <TB3> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1039_FullQualification_2016-10-21_13h57m_1477051074//001_Fulltest_m20//dacParameters_C3.dat
[14:51:31.862] <TB3> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1039_FullQualification_2016-10-21_13h57m_1477051074//001_Fulltest_m20//dacParameters_C4.dat
[14:51:31.862] <TB3> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1039_FullQualification_2016-10-21_13h57m_1477051074//001_Fulltest_m20//dacParameters_C5.dat
[14:51:31.862] <TB3> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1039_FullQualification_2016-10-21_13h57m_1477051074//001_Fulltest_m20//dacParameters_C6.dat
[14:51:31.862] <TB3> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1039_FullQualification_2016-10-21_13h57m_1477051074//001_Fulltest_m20//dacParameters_C7.dat
[14:51:31.862] <TB3> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1039_FullQualification_2016-10-21_13h57m_1477051074//001_Fulltest_m20//dacParameters_C8.dat
[14:51:31.862] <TB3> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1039_FullQualification_2016-10-21_13h57m_1477051074//001_Fulltest_m20//dacParameters_C9.dat
[14:51:31.863] <TB3> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1039_FullQualification_2016-10-21_13h57m_1477051074//001_Fulltest_m20//dacParameters_C10.dat
[14:51:31.863] <TB3> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1039_FullQualification_2016-10-21_13h57m_1477051074//001_Fulltest_m20//dacParameters_C11.dat
[14:51:31.863] <TB3> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1039_FullQualification_2016-10-21_13h57m_1477051074//001_Fulltest_m20//dacParameters_C12.dat
[14:51:31.863] <TB3> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1039_FullQualification_2016-10-21_13h57m_1477051074//001_Fulltest_m20//dacParameters_C13.dat
[14:51:31.863] <TB3> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1039_FullQualification_2016-10-21_13h57m_1477051074//001_Fulltest_m20//dacParameters_C14.dat
[14:51:31.863] <TB3> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1039_FullQualification_2016-10-21_13h57m_1477051074//001_Fulltest_m20//dacParameters_C15.dat
[14:51:31.863] <TB3> INFO: write tbm parameters into /home/l_tester/david/FullQualification/data/M1039_FullQualification_2016-10-21_13h57m_1477051074//001_Fulltest_m20//tbmParameters_C0a.dat
[14:51:31.863] <TB3> INFO: write tbm parameters into /home/l_tester/david/FullQualification/data/M1039_FullQualification_2016-10-21_13h57m_1477051074//001_Fulltest_m20//tbmParameters_C0b.dat
[14:51:31.863] <TB3> INFO: write tbm parameters into /home/l_tester/david/FullQualification/data/M1039_FullQualification_2016-10-21_13h57m_1477051074//001_Fulltest_m20//tbmParameters_C1a.dat
[14:51:31.863] <TB3> INFO: write tbm parameters into /home/l_tester/david/FullQualification/data/M1039_FullQualification_2016-10-21_13h57m_1477051074//001_Fulltest_m20//tbmParameters_C1b.dat
[14:51:31.863] <TB3> INFO: PixTestPretest::doTest() done, duration: 93 seconds
[14:51:31.920] <TB3> INFO: enter test to run
[14:51:31.920] <TB3> INFO: test: FullTest no parameter change
[14:51:31.920] <TB3> INFO: running: fulltest
[14:51:31.920] <TB3> INFO: ######################################################################
[14:51:31.920] <TB3> INFO: PixTestFullTest::doTest()
[14:51:31.920] <TB3> INFO: ######################################################################
[14:51:31.921] <TB3> INFO: ######################################################################
[14:51:31.921] <TB3> INFO: PixTestAlive::doTest()
[14:51:31.921] <TB3> INFO: ######################################################################
[14:51:31.923] <TB3> INFO: ----------------------------------------------------------------------
[14:51:31.923] <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)
[14:51:31.923] <TB3> INFO: ----------------------------------------------------------------------
[14:51:32.160] <TB3> INFO: Expecting 41600 events.
[14:51:35.691] <TB3> INFO: 41600 events read in total (2939ms).
[14:51:35.692] <TB3> INFO: Test took 3768ms.
[14:51:35.923] <TB3> INFO: PixTestAlive::aliveTest() done
[14:51:35.923] <TB3> INFO: number of dead pixels (per ROC): 0 2 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[14:51:35.925] <TB3> INFO: ----------------------------------------------------------------------
[14:51:35.925] <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)
[14:51:35.925] <TB3> INFO: ----------------------------------------------------------------------
[14:51:36.167] <TB3> INFO: Expecting 41600 events.
[14:51:39.156] <TB3> INFO: 41600 events read in total (2397ms).
[14:51:39.156] <TB3> INFO: Test took 3229ms.
[14:51:39.157] <TB3> INFO: mask vs. old pixelAlive PixelAlive_C0_V0 .. PixelAlive_C15_V0
[14:51:39.397] <TB3> INFO: PixTestAlive::maskTest() done
[14:51:39.397] <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
[14:51:39.399] <TB3> INFO: ----------------------------------------------------------------------
[14:51:39.399] <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)
[14:51:39.399] <TB3> INFO: ----------------------------------------------------------------------
[14:51:39.647] <TB3> INFO: Expecting 41600 events.
[14:51:43.210] <TB3> INFO: 41600 events read in total (2972ms).
[14:51:43.211] <TB3> INFO: Test took 3809ms.
[14:51:43.450] <TB3> INFO: PixTestAlive::addressDecodingTest() done
[14:51:43.450] <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
[14:51:43.450] <TB3> INFO: PixTestAlive::doTest() done, duration: 11 seconds
[14:51:43.450] <TB3> INFO: Decoding statistics:
[14:51:43.450] <TB3> INFO: General information:
[14:51:43.450] <TB3> INFO: 16bit words read: 0
[14:51:43.450] <TB3> INFO: valid events total: 0
[14:51:43.450] <TB3> INFO: empty events: 0
[14:51:43.450] <TB3> INFO: valid events with pixels: 0
[14:51:43.450] <TB3> INFO: valid pixel hits: 0
[14:51:43.450] <TB3> INFO: Event errors: 0
[14:51:43.450] <TB3> INFO: start marker: 0
[14:51:43.450] <TB3> INFO: stop marker: 0
[14:51:43.450] <TB3> INFO: overflow: 0
[14:51:43.450] <TB3> INFO: invalid 5bit words: 0
[14:51:43.450] <TB3> INFO: invalid XOR eye diagram: 0
[14:51:43.450] <TB3> INFO: frame (failed synchr.): 0
[14:51:43.450] <TB3> INFO: idle data (no TBM trl): 0
[14:51:43.450] <TB3> INFO: no data (only TBM hdr): 0
[14:51:43.451] <TB3> INFO: TBM errors: 0
[14:51:43.451] <TB3> INFO: flawed TBM headers: 0
[14:51:43.451] <TB3> INFO: flawed TBM trailers: 0
[14:51:43.451] <TB3> INFO: event ID mismatches: 0
[14:51:43.451] <TB3> INFO: ROC errors: 0
[14:51:43.451] <TB3> INFO: missing ROC header(s): 0
[14:51:43.451] <TB3> INFO: misplaced readback start: 0
[14:51:43.451] <TB3> INFO: Pixel decoding errors: 0
[14:51:43.451] <TB3> INFO: pixel data incomplete: 0
[14:51:43.451] <TB3> INFO: pixel address: 0
[14:51:43.451] <TB3> INFO: pulse height fill bit: 0
[14:51:43.451] <TB3> INFO: buffer corruption: 0
[14:51:43.459] <TB3> INFO: readReadbackCal: /home/l_tester/david/FullQualification/data/M1039_FullQualification_2016-10-21_13h57m_1477051074//001_Fulltest_m20//readbackCal_C0.dat .. /home/l_tester/david/FullQualification/data/M1039_FullQualification_2016-10-21_13h57m_1477051074//001_Fulltest_m20//readbackCal_C15.dat
[14:51:43.459] <TB3> INFO: readGainPedestalParameters /home/l_tester/david/FullQualification/data/M1039_FullQualification_2016-10-21_13h57m_1477051074//001_Fulltest_m20//phCalibrationFitErr_C0.dat .. /home/l_tester/david/FullQualification/data/M1039_FullQualification_2016-10-21_13h57m_1477051074//001_Fulltest_m20//phCalibrationFitErr_C15.dat

[14:51:43.459] <TB3> INFO: ######################################################################
[14:51:43.459] <TB3> INFO: PixTestReadback::doTest()
[14:51:43.459] <TB3> INFO: ######################################################################
[14:51:43.459] <TB3> INFO: ----------------------------------------------------------------------
[14:51:43.459] <TB3> INFO: PixTestReadback::CalibrateVd()
[14:51:43.459] <TB3> INFO: ----------------------------------------------------------------------
[14:51:53.447] <TB3> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1039_FullQualification_2016-10-21_13h57m_1477051074//001_Fulltest_m20//readbackCal_C0.dat
[14:51:53.447] <TB3> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1039_FullQualification_2016-10-21_13h57m_1477051074//001_Fulltest_m20//readbackCal_C1.dat
[14:51:53.447] <TB3> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1039_FullQualification_2016-10-21_13h57m_1477051074//001_Fulltest_m20//readbackCal_C2.dat
[14:51:53.448] <TB3> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1039_FullQualification_2016-10-21_13h57m_1477051074//001_Fulltest_m20//readbackCal_C3.dat
[14:51:53.448] <TB3> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1039_FullQualification_2016-10-21_13h57m_1477051074//001_Fulltest_m20//readbackCal_C4.dat
[14:51:53.448] <TB3> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1039_FullQualification_2016-10-21_13h57m_1477051074//001_Fulltest_m20//readbackCal_C5.dat
[14:51:53.448] <TB3> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1039_FullQualification_2016-10-21_13h57m_1477051074//001_Fulltest_m20//readbackCal_C6.dat
[14:51:53.448] <TB3> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1039_FullQualification_2016-10-21_13h57m_1477051074//001_Fulltest_m20//readbackCal_C7.dat
[14:51:53.448] <TB3> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1039_FullQualification_2016-10-21_13h57m_1477051074//001_Fulltest_m20//readbackCal_C8.dat
[14:51:53.448] <TB3> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1039_FullQualification_2016-10-21_13h57m_1477051074//001_Fulltest_m20//readbackCal_C9.dat
[14:51:53.448] <TB3> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1039_FullQualification_2016-10-21_13h57m_1477051074//001_Fulltest_m20//readbackCal_C10.dat
[14:51:53.448] <TB3> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1039_FullQualification_2016-10-21_13h57m_1477051074//001_Fulltest_m20//readbackCal_C11.dat
[14:51:53.448] <TB3> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1039_FullQualification_2016-10-21_13h57m_1477051074//001_Fulltest_m20//readbackCal_C12.dat
[14:51:53.449] <TB3> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1039_FullQualification_2016-10-21_13h57m_1477051074//001_Fulltest_m20//readbackCal_C13.dat
[14:51:53.449] <TB3> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1039_FullQualification_2016-10-21_13h57m_1477051074//001_Fulltest_m20//readbackCal_C14.dat
[14:51:53.449] <TB3> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1039_FullQualification_2016-10-21_13h57m_1477051074//001_Fulltest_m20//readbackCal_C15.dat
[14:51:53.482] <TB3> INFO: PixTestPattern:: pg_setup set to default.
[14:51:53.482] <TB3> INFO: ----------------------------------------------------------------------
[14:51:53.482] <TB3> INFO: PixTestReadback::CalibrateVa()
[14:51:53.482] <TB3> INFO: ----------------------------------------------------------------------
[14:52:03.421] <TB3> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1039_FullQualification_2016-10-21_13h57m_1477051074//001_Fulltest_m20//readbackCal_C0.dat
[14:52:03.421] <TB3> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1039_FullQualification_2016-10-21_13h57m_1477051074//001_Fulltest_m20//readbackCal_C1.dat
[14:52:03.421] <TB3> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1039_FullQualification_2016-10-21_13h57m_1477051074//001_Fulltest_m20//readbackCal_C2.dat
[14:52:03.421] <TB3> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1039_FullQualification_2016-10-21_13h57m_1477051074//001_Fulltest_m20//readbackCal_C3.dat
[14:52:03.421] <TB3> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1039_FullQualification_2016-10-21_13h57m_1477051074//001_Fulltest_m20//readbackCal_C4.dat
[14:52:03.421] <TB3> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1039_FullQualification_2016-10-21_13h57m_1477051074//001_Fulltest_m20//readbackCal_C5.dat
[14:52:03.421] <TB3> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1039_FullQualification_2016-10-21_13h57m_1477051074//001_Fulltest_m20//readbackCal_C6.dat
[14:52:03.421] <TB3> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1039_FullQualification_2016-10-21_13h57m_1477051074//001_Fulltest_m20//readbackCal_C7.dat
[14:52:03.421] <TB3> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1039_FullQualification_2016-10-21_13h57m_1477051074//001_Fulltest_m20//readbackCal_C8.dat
[14:52:03.421] <TB3> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1039_FullQualification_2016-10-21_13h57m_1477051074//001_Fulltest_m20//readbackCal_C9.dat
[14:52:03.421] <TB3> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1039_FullQualification_2016-10-21_13h57m_1477051074//001_Fulltest_m20//readbackCal_C10.dat
[14:52:03.421] <TB3> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1039_FullQualification_2016-10-21_13h57m_1477051074//001_Fulltest_m20//readbackCal_C11.dat
[14:52:03.421] <TB3> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1039_FullQualification_2016-10-21_13h57m_1477051074//001_Fulltest_m20//readbackCal_C12.dat
[14:52:03.422] <TB3> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1039_FullQualification_2016-10-21_13h57m_1477051074//001_Fulltest_m20//readbackCal_C13.dat
[14:52:03.422] <TB3> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1039_FullQualification_2016-10-21_13h57m_1477051074//001_Fulltest_m20//readbackCal_C14.dat
[14:52:03.422] <TB3> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1039_FullQualification_2016-10-21_13h57m_1477051074//001_Fulltest_m20//readbackCal_C15.dat
[14:52:03.454] <TB3> INFO: PixTestPattern:: pg_setup set to default.
[14:52:03.454] <TB3> INFO: ----------------------------------------------------------------------
[14:52:03.454] <TB3> INFO: PixTestReadback::readbackVbg()
[14:52:03.454] <TB3> INFO: ----------------------------------------------------------------------
[14:52:11.127] <TB3> INFO: PixTestPattern:: pg_setup set to default.
[14:52:11.127] <TB3> INFO: ----------------------------------------------------------------------
[14:52:11.127] <TB3> INFO: PixTestReadback::getCalibratedVbg()
[14:52:11.127] <TB3> INFO: ----------------------------------------------------------------------
[14:52:11.127] <TB3> INFO: Vbg will be calibrated using Vd calibration
[14:52:11.127] <TB3> INFO: /*/*/*/*::: ROC 0: uncalibrated Vbg = 143.5calibrated Vbg = 1.19499 :::*/*/*/*/
[14:52:11.127] <TB3> INFO: /*/*/*/*::: ROC 1: uncalibrated Vbg = 156calibrated Vbg = 1.19065 :::*/*/*/*/
[14:52:11.128] <TB3> INFO: /*/*/*/*::: ROC 2: uncalibrated Vbg = 156calibrated Vbg = 1.18274 :::*/*/*/*/
[14:52:11.128] <TB3> INFO: /*/*/*/*::: ROC 3: uncalibrated Vbg = 156.8calibrated Vbg = 1.18323 :::*/*/*/*/
[14:52:11.128] <TB3> INFO: /*/*/*/*::: ROC 4: uncalibrated Vbg = 150.8calibrated Vbg = 1.18517 :::*/*/*/*/
[14:52:11.128] <TB3> INFO: /*/*/*/*::: ROC 5: uncalibrated Vbg = 162.5calibrated Vbg = 1.19536 :::*/*/*/*/
[14:52:11.128] <TB3> INFO: /*/*/*/*::: ROC 6: uncalibrated Vbg = 159.8calibrated Vbg = 1.19122 :::*/*/*/*/
[14:52:11.128] <TB3> INFO: /*/*/*/*::: ROC 7: uncalibrated Vbg = 155calibrated Vbg = 1.19226 :::*/*/*/*/
[14:52:11.128] <TB3> INFO: /*/*/*/*::: ROC 8: uncalibrated Vbg = 151.9calibrated Vbg = 1.18722 :::*/*/*/*/
[14:52:11.128] <TB3> INFO: /*/*/*/*::: ROC 9: uncalibrated Vbg = 162.3calibrated Vbg = 1.18609 :::*/*/*/*/
[14:52:11.128] <TB3> INFO: /*/*/*/*::: ROC 10: uncalibrated Vbg = 150.6calibrated Vbg = 1.18291 :::*/*/*/*/
[14:52:11.128] <TB3> INFO: /*/*/*/*::: ROC 11: uncalibrated Vbg = 163.4calibrated Vbg = 1.17613 :::*/*/*/*/
[14:52:11.128] <TB3> INFO: /*/*/*/*::: ROC 12: uncalibrated Vbg = 159.1calibrated Vbg = 1.18161 :::*/*/*/*/
[14:52:11.128] <TB3> INFO: /*/*/*/*::: ROC 13: uncalibrated Vbg = 155.7calibrated Vbg = 1.18675 :::*/*/*/*/
[14:52:11.128] <TB3> INFO: /*/*/*/*::: ROC 14: uncalibrated Vbg = 144.8calibrated Vbg = 1.19109 :::*/*/*/*/
[14:52:11.128] <TB3> INFO: /*/*/*/*::: ROC 15: uncalibrated Vbg = 152.4calibrated Vbg = 1.18804 :::*/*/*/*/
[14:52:11.131] <TB3> INFO: ----------------------------------------------------------------------
[14:52:11.131] <TB3> INFO: PixTestReadback::CalibrateIa()
[14:52:11.131] <TB3> INFO: ----------------------------------------------------------------------
[14:54:51.909] <TB3> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1039_FullQualification_2016-10-21_13h57m_1477051074//001_Fulltest_m20//readbackCal_C0.dat
[14:54:51.909] <TB3> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1039_FullQualification_2016-10-21_13h57m_1477051074//001_Fulltest_m20//readbackCal_C1.dat
[14:54:51.909] <TB3> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1039_FullQualification_2016-10-21_13h57m_1477051074//001_Fulltest_m20//readbackCal_C2.dat
[14:54:51.910] <TB3> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1039_FullQualification_2016-10-21_13h57m_1477051074//001_Fulltest_m20//readbackCal_C3.dat
[14:54:51.910] <TB3> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1039_FullQualification_2016-10-21_13h57m_1477051074//001_Fulltest_m20//readbackCal_C4.dat
[14:54:51.910] <TB3> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1039_FullQualification_2016-10-21_13h57m_1477051074//001_Fulltest_m20//readbackCal_C5.dat
[14:54:51.910] <TB3> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1039_FullQualification_2016-10-21_13h57m_1477051074//001_Fulltest_m20//readbackCal_C6.dat
[14:54:51.910] <TB3> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1039_FullQualification_2016-10-21_13h57m_1477051074//001_Fulltest_m20//readbackCal_C7.dat
[14:54:51.910] <TB3> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1039_FullQualification_2016-10-21_13h57m_1477051074//001_Fulltest_m20//readbackCal_C8.dat
[14:54:51.910] <TB3> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1039_FullQualification_2016-10-21_13h57m_1477051074//001_Fulltest_m20//readbackCal_C9.dat
[14:54:51.910] <TB3> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1039_FullQualification_2016-10-21_13h57m_1477051074//001_Fulltest_m20//readbackCal_C10.dat
[14:54:51.910] <TB3> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1039_FullQualification_2016-10-21_13h57m_1477051074//001_Fulltest_m20//readbackCal_C11.dat
[14:54:51.911] <TB3> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1039_FullQualification_2016-10-21_13h57m_1477051074//001_Fulltest_m20//readbackCal_C12.dat
[14:54:51.911] <TB3> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1039_FullQualification_2016-10-21_13h57m_1477051074//001_Fulltest_m20//readbackCal_C13.dat
[14:54:51.911] <TB3> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1039_FullQualification_2016-10-21_13h57m_1477051074//001_Fulltest_m20//readbackCal_C14.dat
[14:54:51.911] <TB3> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1039_FullQualification_2016-10-21_13h57m_1477051074//001_Fulltest_m20//readbackCal_C15.dat
[14:54:51.939] <TB3> INFO: PixTestPattern:: pg_setup set to default.
[14:54:51.941] <TB3> INFO: PixTestReadback::doTest() done
[14:54:51.941] <TB3> INFO: Decoding statistics:
[14:54:51.941] <TB3> INFO: General information:
[14:54:51.941] <TB3> INFO: 16bit words read: 1536
[14:54:51.941] <TB3> INFO: valid events total: 256
[14:54:51.941] <TB3> INFO: empty events: 256
[14:54:51.941] <TB3> INFO: valid events with pixels: 0
[14:54:51.941] <TB3> INFO: valid pixel hits: 0
[14:54:51.941] <TB3> INFO: Event errors: 0
[14:54:51.941] <TB3> INFO: start marker: 0
[14:54:51.941] <TB3> INFO: stop marker: 0
[14:54:51.941] <TB3> INFO: overflow: 0
[14:54:51.941] <TB3> INFO: invalid 5bit words: 0
[14:54:51.941] <TB3> INFO: invalid XOR eye diagram: 0
[14:54:51.941] <TB3> INFO: frame (failed synchr.): 0
[14:54:51.941] <TB3> INFO: idle data (no TBM trl): 0
[14:54:51.941] <TB3> INFO: no data (only TBM hdr): 0
[14:54:51.941] <TB3> INFO: TBM errors: 0
[14:54:51.941] <TB3> INFO: flawed TBM headers: 0
[14:54:51.941] <TB3> INFO: flawed TBM trailers: 0
[14:54:51.941] <TB3> INFO: event ID mismatches: 0
[14:54:51.941] <TB3> INFO: ROC errors: 0
[14:54:51.941] <TB3> INFO: missing ROC header(s): 0
[14:54:51.941] <TB3> INFO: misplaced readback start: 0
[14:54:51.941] <TB3> INFO: Pixel decoding errors: 0
[14:54:51.941] <TB3> INFO: pixel data incomplete: 0
[14:54:51.941] <TB3> INFO: pixel address: 0
[14:54:51.941] <TB3> INFO: pulse height fill bit: 0
[14:54:51.941] <TB3> INFO: buffer corruption: 0
[14:54:52.005] <TB3> INFO: ######################################################################
[14:54:52.005] <TB3> INFO: PixTestBBMap::doTest() Ntrig = 5, VcalS = 250 (high range)
[14:54:52.005] <TB3> INFO: ######################################################################
[14:54:52.008] <TB3> INFO: ---> dac: VthrComp name: calSMap ntrig: 5 dacrange: 0 .. 149 (-1/-1) hits flags = 514 (plus default)
[14:54:52.022] <TB3> INFO: dacScan split into 1 runs with ntrig = 5
[14:54:52.022] <TB3> INFO: run 1 of 1
[14:54:52.260] <TB3> INFO: Expecting 3120000 events.
[14:55:22.731] <TB3> INFO: 650895 events read in total (29879ms).











[14:55:52.248] <TB3> INFO: 1296190 events read in total (59396ms).





















[14:56:22.062] <TB3> INFO: 1940715 events read in total (89210ms).











[14:56:51.436] <TB3> INFO: 2585325 events read in total (118584ms).











[14:57:16.835] <TB3> INFO: 3120000 events read in total (143983ms).
[14:57:16.917] <TB3> INFO: Test took 144895ms.
[14:57:38.788] <TB3> INFO: PixTestBBMap::doTest() done with 2 decoding errors: , duration: 166 seconds
[14:57:38.788] <TB3> INFO: number of dead bumps (per ROC): 0 2 0 0 0 0 0 1 0 1 0 0 1 0 0 0
[14:57:38.788] <TB3> INFO: separation cut (per ROC): 106 96 106 98 108 105 108 101 89 94 78 94 104 106 106 97
[14:57:38.788] <TB3> INFO: Decoding statistics:
[14:57:38.788] <TB3> INFO: General information:
[14:57:38.788] <TB3> INFO: 16bit words read: 0
[14:57:38.788] <TB3> INFO: valid events total: 0
[14:57:38.788] <TB3> INFO: empty events: 0
[14:57:38.788] <TB3> INFO: valid events with pixels: 0
[14:57:38.788] <TB3> INFO: valid pixel hits: 0
[14:57:38.788] <TB3> INFO: Event errors: 0
[14:57:38.788] <TB3> INFO: start marker: 0
[14:57:38.788] <TB3> INFO: stop marker: 0
[14:57:38.788] <TB3> INFO: overflow: 0
[14:57:38.788] <TB3> INFO: invalid 5bit words: 0
[14:57:38.788] <TB3> INFO: invalid XOR eye diagram: 0
[14:57:38.788] <TB3> INFO: frame (failed synchr.): 0
[14:57:38.788] <TB3> INFO: idle data (no TBM trl): 0
[14:57:38.788] <TB3> INFO: no data (only TBM hdr): 0
[14:57:38.788] <TB3> INFO: TBM errors: 0
[14:57:38.788] <TB3> INFO: flawed TBM headers: 0
[14:57:38.788] <TB3> INFO: flawed TBM trailers: 0
[14:57:38.788] <TB3> INFO: event ID mismatches: 0
[14:57:38.788] <TB3> INFO: ROC errors: 0
[14:57:38.788] <TB3> INFO: missing ROC header(s): 0
[14:57:38.788] <TB3> INFO: misplaced readback start: 0
[14:57:38.788] <TB3> INFO: Pixel decoding errors: 0
[14:57:38.788] <TB3> INFO: pixel data incomplete: 0
[14:57:38.788] <TB3> INFO: pixel address: 0
[14:57:38.788] <TB3> INFO: pulse height fill bit: 0
[14:57:38.788] <TB3> INFO: buffer corruption: 0
[14:57:38.825] <TB3> INFO: ######################################################################
[14:57:38.825] <TB3> INFO: PixTestScurves::fullTest() ntrig = 50, dacs/step = -1, ntrig/step = -1
[14:57:38.825] <TB3> INFO: ######################################################################
[14:57:38.826] <TB3> INFO: ----------------------------------------------------------------------
[14:57:38.826] <TB3> INFO: PixTestScurves::scurves(Vcal), ntrig = 50, dacs/step = -1, ntrig/step = -1
[14:57:38.826] <TB3> INFO: ----------------------------------------------------------------------
[14:57:38.826] <TB3> INFO: ---> dac: Vcal name: scurveVcal ntrig: 50 dacrange: 25 .. 200 (-1/-1) hits flags = 528 (plus default)
[14:57:38.839] <TB3> INFO: dacScan split into 1 runs with ntrig = 50
[14:57:38.839] <TB3> INFO: run 1 of 1
[14:57:39.090] <TB3> INFO: Expecting 36608000 events.
[14:58:02.753] <TB3> INFO: 666050 events read in total (23072ms).
[14:58:25.568] <TB3> INFO: 1317500 events read in total (45887ms).
[14:58:48.384] <TB3> INFO: 1970550 events read in total (68703ms).
[14:59:11.060] <TB3> INFO: 2621250 events read in total (91379ms).
[14:59:33.956] <TB3> INFO: 3274750 events read in total (114275ms).
[14:59:56.760] <TB3> INFO: 3927850 events read in total (137079ms).
[15:00:19.586] <TB3> INFO: 4580400 events read in total (159905ms).
[15:00:42.441] <TB3> INFO: 5231100 events read in total (182760ms).
[15:01:05.052] <TB3> INFO: 5879900 events read in total (205371ms).
[15:01:27.644] <TB3> INFO: 6528450 events read in total (227963ms).
[15:01:50.182] <TB3> INFO: 7177650 events read in total (250501ms).
[15:02:12.853] <TB3> INFO: 7828850 events read in total (273172ms).
[15:02:35.722] <TB3> INFO: 8479250 events read in total (296041ms).
[15:02:58.244] <TB3> INFO: 9131750 events read in total (318563ms).
[15:03:21.242] <TB3> INFO: 9782600 events read in total (341561ms).
[15:03:43.990] <TB3> INFO: 10433450 events read in total (364309ms).
[15:04:06.814] <TB3> INFO: 11083000 events read in total (387133ms).
[15:04:29.337] <TB3> INFO: 11733850 events read in total (409656ms).
[15:04:51.982] <TB3> INFO: 12381550 events read in total (432301ms).
[15:05:15.094] <TB3> INFO: 13030300 events read in total (455413ms).
[15:05:37.929] <TB3> INFO: 13680500 events read in total (478248ms).
[15:06:00.402] <TB3> INFO: 14327500 events read in total (500721ms).
[15:06:22.848] <TB3> INFO: 14975550 events read in total (523167ms).
[15:06:45.306] <TB3> INFO: 15621500 events read in total (545625ms).
[15:07:08.078] <TB3> INFO: 16270500 events read in total (568397ms).
[15:07:30.798] <TB3> INFO: 16917700 events read in total (591117ms).
[15:07:53.462] <TB3> INFO: 17564250 events read in total (613781ms).
[15:08:16.005] <TB3> INFO: 18209950 events read in total (636324ms).
[15:08:38.770] <TB3> INFO: 18856200 events read in total (659089ms).
[15:09:01.265] <TB3> INFO: 19500650 events read in total (681584ms).
[15:09:23.834] <TB3> INFO: 20145900 events read in total (704153ms).
[15:09:46.814] <TB3> INFO: 20789550 events read in total (727133ms).
[15:10:09.775] <TB3> INFO: 21433600 events read in total (750094ms).
[15:10:32.580] <TB3> INFO: 22079050 events read in total (772899ms).
[15:10:54.838] <TB3> INFO: 22723400 events read in total (795157ms).
[15:11:17.297] <TB3> INFO: 23369000 events read in total (817616ms).
[15:11:39.961] <TB3> INFO: 24010850 events read in total (840280ms).
[15:12:02.657] <TB3> INFO: 24655850 events read in total (862976ms).
[15:12:25.343] <TB3> INFO: 25297500 events read in total (885662ms).
[15:12:48.313] <TB3> INFO: 25941050 events read in total (908632ms).
[15:13:11.145] <TB3> INFO: 26582800 events read in total (931464ms).
[15:13:33.684] <TB3> INFO: 27225650 events read in total (954003ms).
[15:13:56.289] <TB3> INFO: 27869200 events read in total (976608ms).
[15:14:18.825] <TB3> INFO: 28510250 events read in total (999144ms).
[15:14:41.338] <TB3> INFO: 29151500 events read in total (1021657ms).
[15:15:04.029] <TB3> INFO: 29791050 events read in total (1044348ms).
[15:15:26.803] <TB3> INFO: 30433950 events read in total (1067122ms).
[15:15:49.472] <TB3> INFO: 31073800 events read in total (1089791ms).
[15:16:11.857] <TB3> INFO: 31715600 events read in total (1112176ms).
[15:16:34.213] <TB3> INFO: 32355750 events read in total (1134532ms).
[15:16:56.828] <TB3> INFO: 32998950 events read in total (1157147ms).
[15:17:19.888] <TB3> INFO: 33641200 events read in total (1180207ms).
[15:17:42.658] <TB3> INFO: 34283500 events read in total (1202977ms).
[15:18:05.161] <TB3> INFO: 34925100 events read in total (1225480ms).
[15:18:27.971] <TB3> INFO: 35569400 events read in total (1248290ms).
[15:18:50.497] <TB3> INFO: 36220250 events read in total (1270816ms).
[15:19:04.456] <TB3> INFO: 36608000 events read in total (1284775ms).
[15:19:04.553] <TB3> INFO: Test took 1285713ms.
[15:19:05.219] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[15:19:06.940] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[15:19:08.601] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[15:19:11.158] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[15:19:13.799] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[15:19:16.430] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[15:19:19.034] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[15:19:21.626] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[15:19:24.221] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[15:19:26.878] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[15:19:28.997] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[15:19:30.901] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[15:19:32.663] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[15:19:34.569] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[15:19:36.307] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[15:19:38.084] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[15:19:39.806] <TB3> INFO: PixTestScurves::scurves() done
[15:19:39.806] <TB3> INFO: Vcal mean: 109.99 102.91 117.30 96.19 105.39 111.26 115.35 97.48 110.28 95.07 103.08 111.17 101.45 116.32 109.27 106.35
[15:19:39.806] <TB3> INFO: Vcal RMS: 4.79 5.90 5.58 5.45 5.18 5.26 4.89 5.08 4.98 5.70 6.90 6.04 5.43 5.53 5.10 5.29
[15:19:39.806] <TB3> INFO: PixTestScurves::fullTest() done, duration: 1320 seconds
[15:19:39.806] <TB3> INFO: Decoding statistics:
[15:19:39.806] <TB3> INFO: General information:
[15:19:39.806] <TB3> INFO: 16bit words read: 0
[15:19:39.806] <TB3> INFO: valid events total: 0
[15:19:39.806] <TB3> INFO: empty events: 0
[15:19:39.806] <TB3> INFO: valid events with pixels: 0
[15:19:39.806] <TB3> INFO: valid pixel hits: 0
[15:19:39.806] <TB3> INFO: Event errors: 0
[15:19:39.806] <TB3> INFO: start marker: 0
[15:19:39.806] <TB3> INFO: stop marker: 0
[15:19:39.806] <TB3> INFO: overflow: 0
[15:19:39.806] <TB3> INFO: invalid 5bit words: 0
[15:19:39.806] <TB3> INFO: invalid XOR eye diagram: 0
[15:19:39.806] <TB3> INFO: frame (failed synchr.): 0
[15:19:39.806] <TB3> INFO: idle data (no TBM trl): 0
[15:19:39.806] <TB3> INFO: no data (only TBM hdr): 0
[15:19:39.806] <TB3> INFO: TBM errors: 0
[15:19:39.806] <TB3> INFO: flawed TBM headers: 0
[15:19:39.807] <TB3> INFO: flawed TBM trailers: 0
[15:19:39.807] <TB3> INFO: event ID mismatches: 0
[15:19:39.807] <TB3> INFO: ROC errors: 0
[15:19:39.807] <TB3> INFO: missing ROC header(s): 0
[15:19:39.807] <TB3> INFO: misplaced readback start: 0
[15:19:39.807] <TB3> INFO: Pixel decoding errors: 0
[15:19:39.807] <TB3> INFO: pixel data incomplete: 0
[15:19:39.807] <TB3> INFO: pixel address: 0
[15:19:39.807] <TB3> INFO: pulse height fill bit: 0
[15:19:39.807] <TB3> INFO: buffer corruption: 0
[15:19:39.898] <TB3> INFO: ######################################################################
[15:19:39.898] <TB3> INFO: PixTestTrim::doTest()
[15:19:39.898] <TB3> INFO: ######################################################################
[15:19:39.900] <TB3> INFO: ----------------------------------------------------------------------
[15:19:39.900] <TB3> INFO: PixTestTrim::trimTest() ntrig = 8, vcal = 35
[15:19:39.900] <TB3> INFO: ----------------------------------------------------------------------
[15:19:39.942] <TB3> INFO: ---> VthrComp thr map (minimal VthrComp)
[15:19:39.942] <TB3> INFO: ---> dac: vthrcomp name: TrimThr0 ntrig: 8 dacrange: 10 .. 160 (-1/-1) hits flags = 528 (plus default)
[15:19:39.955] <TB3> INFO: dacScan split into 1 runs with ntrig = 8
[15:19:39.955] <TB3> INFO: run 1 of 1
[15:19:40.192] <TB3> INFO: Expecting 5025280 events.
[15:20:10.971] <TB3> INFO: 820664 events read in total (30179ms).
[15:20:40.702] <TB3> INFO: 1638760 events read in total (59910ms).
[15:21:10.623] <TB3> INFO: 2453120 events read in total (89831ms).
[15:21:40.176] <TB3> INFO: 3264496 events read in total (119384ms).
[15:22:10.017] <TB3> INFO: 4072848 events read in total (149225ms).
[15:22:39.525] <TB3> INFO: 4879056 events read in total (178733ms).
[15:22:45.235] <TB3> INFO: 5025280 events read in total (184443ms).
[15:22:45.289] <TB3> INFO: Test took 185335ms.
[15:23:05.042] <TB3> INFO: ROC 0 VthrComp = 120
[15:23:05.043] <TB3> INFO: ROC 1 VthrComp = 110
[15:23:05.043] <TB3> INFO: ROC 2 VthrComp = 125
[15:23:05.043] <TB3> INFO: ROC 3 VthrComp = 102
[15:23:05.043] <TB3> INFO: ROC 4 VthrComp = 116
[15:23:05.043] <TB3> INFO: ROC 5 VthrComp = 113
[15:23:05.043] <TB3> INFO: ROC 6 VthrComp = 130
[15:23:05.043] <TB3> INFO: ROC 7 VthrComp = 104
[15:23:05.044] <TB3> INFO: ROC 8 VthrComp = 111
[15:23:05.044] <TB3> INFO: ROC 9 VthrComp = 102
[15:23:05.044] <TB3> INFO: ROC 10 VthrComp = 104
[15:23:05.044] <TB3> INFO: ROC 11 VthrComp = 110
[15:23:05.044] <TB3> INFO: ROC 12 VthrComp = 111
[15:23:05.044] <TB3> INFO: ROC 13 VthrComp = 132
[15:23:05.044] <TB3> INFO: ROC 14 VthrComp = 123
[15:23:05.044] <TB3> INFO: ROC 15 VthrComp = 111
[15:23:05.288] <TB3> INFO: Expecting 41600 events.
[15:23:08.761] <TB3> INFO: 41600 events read in total (2881ms).
[15:23:08.762] <TB3> INFO: Test took 3716ms.
[15:23:08.773] <TB3> INFO: ---> Vcal thr map (pixel with maximum Vcal thr)
[15:23:08.773] <TB3> INFO: ---> dac: vcal name: TrimThr1 ntrig: 8 dacrange: 10 .. 160 (-1/-1) hits flags = 528 (plus default)
[15:23:08.785] <TB3> INFO: dacScan split into 1 runs with ntrig = 8
[15:23:08.785] <TB3> INFO: run 1 of 1
[15:23:09.063] <TB3> INFO: Expecting 5025280 events.
[15:23:35.530] <TB3> INFO: 591600 events read in total (25875ms).
[15:24:01.884] <TB3> INFO: 1181312 events read in total (52229ms).
[15:24:27.452] <TB3> INFO: 1770864 events read in total (77797ms).
[15:24:53.100] <TB3> INFO: 2359824 events read in total (103445ms).
[15:25:18.695] <TB3> INFO: 2946984 events read in total (129040ms).
[15:25:44.576] <TB3> INFO: 3533208 events read in total (154921ms).
[15:26:10.208] <TB3> INFO: 4119032 events read in total (180553ms).
[15:26:36.616] <TB3> INFO: 4704072 events read in total (206961ms).
[15:26:50.856] <TB3> INFO: 5025280 events read in total (221202ms).
[15:26:50.928] <TB3> INFO: Test took 222143ms.
[15:27:14.439] <TB3> INFO: roc 0 with ID = 0 has maximal Vcal 58.9622 for pixel 14/15 mean/min/max = 45.8206/32.5376/59.1036
[15:27:14.439] <TB3> INFO: roc 1 with ID = 1 has maximal Vcal 59.7917 for pixel 9/41 mean/min/max = 46.8995/33.9115/59.8876
[15:27:14.440] <TB3> INFO: roc 2 with ID = 2 has maximal Vcal 59.9991 for pixel 34/0 mean/min/max = 45.2668/30.4039/60.1296
[15:27:14.440] <TB3> INFO: roc 3 with ID = 3 has maximal Vcal 61.1919 for pixel 4/67 mean/min/max = 47.5827/33.8822/61.2833
[15:27:14.440] <TB3> INFO: roc 4 with ID = 4 has maximal Vcal 58.5207 for pixel 8/79 mean/min/max = 45.2507/31.937/58.5643
[15:27:14.441] <TB3> INFO: roc 5 with ID = 5 has maximal Vcal 61.9367 for pixel 24/13 mean/min/max = 46.8528/31.7207/61.985
[15:27:14.441] <TB3> INFO: roc 6 with ID = 6 has maximal Vcal 57.895 for pixel 23/7 mean/min/max = 44.9589/31.8456/58.0723
[15:27:14.441] <TB3> INFO: roc 7 with ID = 7 has maximal Vcal 58.9061 for pixel 0/43 mean/min/max = 46.6276/34.0781/59.1771
[15:27:14.442] <TB3> INFO: roc 8 with ID = 8 has maximal Vcal 62.7378 for pixel 8/3 mean/min/max = 48.3144/33.7208/62.908
[15:27:14.442] <TB3> INFO: roc 9 with ID = 9 has maximal Vcal 60.644 for pixel 0/69 mean/min/max = 46.917/33.1/60.734
[15:27:14.442] <TB3> INFO: roc 10 with ID = 10 has maximal Vcal 67.0107 for pixel 0/28 mean/min/max = 49.9895/32.9323/67.0467
[15:27:14.443] <TB3> INFO: roc 11 with ID = 11 has maximal Vcal 67.4889 for pixel 0/1 mean/min/max = 50.3442/33.0173/67.6712
[15:27:14.443] <TB3> INFO: roc 12 with ID = 12 has maximal Vcal 59.4523 for pixel 0/65 mean/min/max = 46.6778/33.7775/59.5782
[15:27:14.443] <TB3> INFO: roc 13 with ID = 13 has maximal Vcal 58.7474 for pixel 12/16 mean/min/max = 45.3162/31.8813/58.7512
[15:27:14.444] <TB3> INFO: roc 14 with ID = 14 has maximal Vcal 56.7541 for pixel 0/13 mean/min/max = 43.8422/30.9053/56.7791
[15:27:14.444] <TB3> INFO: roc 15 with ID = 15 has maximal Vcal 61.0088 for pixel 18/1 mean/min/max = 47.5133/33.8989/61.1276
[15:27:14.444] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[15:27:14.534] <TB3> INFO: Expecting 411648 events.
[15:27:24.061] <TB3> INFO: 411648 events read in total (8936ms).
[15:27:24.070] <TB3> INFO: Expecting 411648 events.
[15:27:33.452] <TB3> INFO: 411648 events read in total (8979ms).
[15:27:33.463] <TB3> INFO: Expecting 411648 events.
[15:27:42.870] <TB3> INFO: 411648 events read in total (9004ms).
[15:27:42.885] <TB3> INFO: Expecting 411648 events.
[15:27:52.261] <TB3> INFO: 411648 events read in total (8972ms).
[15:27:52.282] <TB3> INFO: Expecting 411648 events.
[15:28:01.666] <TB3> INFO: 411648 events read in total (8981ms).
[15:28:01.685] <TB3> INFO: Expecting 411648 events.
[15:28:11.064] <TB3> INFO: 411648 events read in total (8976ms).
[15:28:11.086] <TB3> INFO: Expecting 411648 events.
[15:28:20.426] <TB3> INFO: 411648 events read in total (8937ms).
[15:28:20.451] <TB3> INFO: Expecting 411648 events.
[15:28:29.861] <TB3> INFO: 411648 events read in total (9007ms).
[15:28:29.888] <TB3> INFO: Expecting 411648 events.
[15:28:39.276] <TB3> INFO: 411648 events read in total (8985ms).
[15:28:39.306] <TB3> INFO: Expecting 411648 events.
[15:28:48.607] <TB3> INFO: 411648 events read in total (8898ms).
[15:28:48.638] <TB3> INFO: Expecting 411648 events.
[15:28:57.001] <TB3> INFO: 411648 events read in total (8959ms).
[15:28:58.045] <TB3> INFO: Expecting 411648 events.
[15:29:07.318] <TB3> INFO: 411648 events read in total (8870ms).
[15:29:07.355] <TB3> INFO: Expecting 411648 events.
[15:29:16.604] <TB3> INFO: 411648 events read in total (8846ms).
[15:29:16.655] <TB3> INFO: Expecting 411648 events.
[15:29:26.052] <TB3> INFO: 411648 events read in total (8994ms).
[15:29:26.135] <TB3> INFO: Expecting 411648 events.
[15:29:35.294] <TB3> INFO: 411648 events read in total (8756ms).
[15:29:35.346] <TB3> INFO: Expecting 411648 events.
[15:29:44.726] <TB3> INFO: 411648 events read in total (8977ms).
[15:29:44.793] <TB3> INFO: Test took 150349ms.
[15:29:45.584] <TB3> INFO: ---> dac: vcal name: TrimThr2 ntrig: 8 dacrange: 0 .. 150 (-1/-1) hits flags = 528 (plus default)
[15:29:45.597] <TB3> INFO: dacScan split into 1 runs with ntrig = 8
[15:29:45.597] <TB3> INFO: run 1 of 1
[15:29:45.832] <TB3> INFO: Expecting 5025280 events.
[15:30:12.685] <TB3> INFO: 586312 events read in total (26261ms).
[15:30:38.502] <TB3> INFO: 1171280 events read in total (52078ms).
[15:31:04.499] <TB3> INFO: 1757000 events read in total (78075ms).
[15:31:30.725] <TB3> INFO: 2341056 events read in total (104301ms).
[15:31:56.701] <TB3> INFO: 2923752 events read in total (130277ms).
[15:32:22.432] <TB3> INFO: 3505856 events read in total (156008ms).
[15:32:47.939] <TB3> INFO: 4087544 events read in total (181515ms).
[15:33:14.171] <TB3> INFO: 4669872 events read in total (207747ms).
[15:33:30.271] <TB3> INFO: 5025280 events read in total (223847ms).
[15:33:30.369] <TB3> INFO: Test took 224773ms.
[15:33:51.855] <TB3> INFO: ---> TrimStepCorr4 extremal thresholds: 10.440719 .. 143.817518
[15:33:52.096] <TB3> INFO: Expecting 208000 events.
[15:34:01.665] <TB3> INFO: 208000 events read in total (8977ms).
[15:34:01.666] <TB3> INFO: Test took 9809ms.
[15:34:01.713] <TB3> INFO: ---> dac: vcal name: trimStepCorr4 ntrig: 8 dacrange: 0 .. 153 (-1/-1) hits flags = 528 (plus default)
[15:34:01.726] <TB3> INFO: dacScan split into 1 runs with ntrig = 8
[15:34:01.726] <TB3> INFO: run 1 of 1
[15:34:02.005] <TB3> INFO: Expecting 5125120 events.
[15:34:28.905] <TB3> INFO: 586144 events read in total (26309ms).
[15:34:54.406] <TB3> INFO: 1172360 events read in total (51810ms).
[15:35:20.246] <TB3> INFO: 1758616 events read in total (77650ms).
[15:35:46.165] <TB3> INFO: 2344904 events read in total (103569ms).
[15:36:11.953] <TB3> INFO: 2931008 events read in total (129357ms).
[15:36:37.286] <TB3> INFO: 3517056 events read in total (154690ms).
[15:37:03.008] <TB3> INFO: 4102752 events read in total (180412ms).
[15:37:29.184] <TB3> INFO: 4688360 events read in total (206588ms).
[15:37:48.548] <TB3> INFO: 5125120 events read in total (225952ms).
[15:37:48.668] <TB3> INFO: Test took 226941ms.
[15:38:15.201] <TB3> INFO: ---> TrimStepCorr2 extremal thresholds: 26.695975 .. 47.118998
[15:38:15.454] <TB3> INFO: Expecting 208000 events.
[15:38:25.117] <TB3> INFO: 208000 events read in total (9071ms).
[15:38:25.118] <TB3> INFO: Test took 9915ms.
[15:38:25.167] <TB3> INFO: ---> dac: vcal name: trimStepCorr2 ntrig: 8 dacrange: 16 .. 57 (-1/-1) hits flags = 528 (plus default)
[15:38:25.182] <TB3> INFO: dacScan split into 1 runs with ntrig = 8
[15:38:25.182] <TB3> INFO: run 1 of 1
[15:38:25.461] <TB3> INFO: Expecting 1397760 events.
[15:38:53.483] <TB3> INFO: 654752 events read in total (27430ms).
[15:39:21.217] <TB3> INFO: 1308744 events read in total (55164ms).
[15:39:25.236] <TB3> INFO: 1397760 events read in total (59183ms).
[15:39:25.267] <TB3> INFO: Test took 60085ms.
[15:39:39.203] <TB3> INFO: ---> TrimStepCorr1a extremal thresholds: 25.409444 .. 47.546022
[15:39:39.442] <TB3> INFO: Expecting 208000 events.
[15:39:49.375] <TB3> INFO: 208000 events read in total (9342ms).
[15:39:49.375] <TB3> INFO: Test took 10171ms.
[15:39:49.424] <TB3> INFO: ---> dac: vcal name: trimStepCorr1a ntrig: 8 dacrange: 15 .. 57 (-1/-1) hits flags = 528 (plus default)
[15:39:49.437] <TB3> INFO: dacScan split into 1 runs with ntrig = 8
[15:39:49.437] <TB3> INFO: run 1 of 1
[15:39:49.715] <TB3> INFO: Expecting 1431040 events.
[15:40:17.724] <TB3> INFO: 659336 events read in total (27418ms).
[15:40:44.694] <TB3> INFO: 1318112 events read in total (54388ms).
[15:40:49.643] <TB3> INFO: 1431040 events read in total (59337ms).
[15:40:49.670] <TB3> INFO: Test took 60234ms.
[15:41:02.584] <TB3> INFO: ---> TrimStepCorr1b extremal thresholds: 22.432312 .. 45.207102
[15:41:02.823] <TB3> INFO: Expecting 208000 events.
[15:41:12.540] <TB3> INFO: 208000 events read in total (9126ms).
[15:41:12.540] <TB3> INFO: Test took 9955ms.
[15:41:12.588] <TB3> INFO: ---> dac: vcal name: trimStepCorr1b ntrig: 8 dacrange: 12 .. 55 (-1/-1) hits flags = 528 (plus default)
[15:41:12.602] <TB3> INFO: dacScan split into 1 runs with ntrig = 8
[15:41:12.602] <TB3> INFO: run 1 of 1
[15:41:12.880] <TB3> INFO: Expecting 1464320 events.
[15:41:41.193] <TB3> INFO: 678728 events read in total (27722ms).
[15:42:09.128] <TB3> INFO: 1357472 events read in total (55657ms).
[15:42:13.999] <TB3> INFO: 1464320 events read in total (60528ms).
[15:42:14.031] <TB3> INFO: Test took 61430ms.
[15:42:28.343] <TB3> INFO: ---> TrimThrFinal extremal thresholds: 15 .. 55
[15:42:28.343] <TB3> INFO: ---> dac: vcal name: TrimThrFinal ntrig: 8 dacrange: 15 .. 55 (-1/-1) hits flags = 528 (plus default)
[15:42:28.357] <TB3> INFO: dacScan split into 1 runs with ntrig = 8
[15:42:28.357] <TB3> INFO: run 1 of 1
[15:42:28.607] <TB3> INFO: Expecting 1364480 events.
[15:42:56.990] <TB3> INFO: 667264 events read in total (27791ms).
[15:43:24.746] <TB3> INFO: 1333896 events read in total (55547ms).
[15:43:26.488] <TB3> INFO: 1364480 events read in total (57289ms).
[15:43:26.515] <TB3> INFO: Test took 58158ms.
[15:43:41.784] <TB3> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1039_FullQualification_2016-10-21_13h57m_1477051074//001_Fulltest_m20//dacParameters35_C0.dat
[15:43:41.785] <TB3> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1039_FullQualification_2016-10-21_13h57m_1477051074//001_Fulltest_m20//dacParameters35_C1.dat
[15:43:41.785] <TB3> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1039_FullQualification_2016-10-21_13h57m_1477051074//001_Fulltest_m20//dacParameters35_C2.dat
[15:43:41.785] <TB3> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1039_FullQualification_2016-10-21_13h57m_1477051074//001_Fulltest_m20//dacParameters35_C3.dat
[15:43:41.785] <TB3> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1039_FullQualification_2016-10-21_13h57m_1477051074//001_Fulltest_m20//dacParameters35_C4.dat
[15:43:41.785] <TB3> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1039_FullQualification_2016-10-21_13h57m_1477051074//001_Fulltest_m20//dacParameters35_C5.dat
[15:43:41.785] <TB3> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1039_FullQualification_2016-10-21_13h57m_1477051074//001_Fulltest_m20//dacParameters35_C6.dat
[15:43:41.786] <TB3> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1039_FullQualification_2016-10-21_13h57m_1477051074//001_Fulltest_m20//dacParameters35_C7.dat
[15:43:41.786] <TB3> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1039_FullQualification_2016-10-21_13h57m_1477051074//001_Fulltest_m20//dacParameters35_C8.dat
[15:43:41.786] <TB3> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1039_FullQualification_2016-10-21_13h57m_1477051074//001_Fulltest_m20//dacParameters35_C9.dat
[15:43:41.786] <TB3> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1039_FullQualification_2016-10-21_13h57m_1477051074//001_Fulltest_m20//dacParameters35_C10.dat
[15:43:41.786] <TB3> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1039_FullQualification_2016-10-21_13h57m_1477051074//001_Fulltest_m20//dacParameters35_C11.dat
[15:43:41.786] <TB3> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1039_FullQualification_2016-10-21_13h57m_1477051074//001_Fulltest_m20//dacParameters35_C12.dat
[15:43:41.786] <TB3> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1039_FullQualification_2016-10-21_13h57m_1477051074//001_Fulltest_m20//dacParameters35_C13.dat
[15:43:41.787] <TB3> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1039_FullQualification_2016-10-21_13h57m_1477051074//001_Fulltest_m20//dacParameters35_C14.dat
[15:43:41.787] <TB3> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1039_FullQualification_2016-10-21_13h57m_1477051074//001_Fulltest_m20//dacParameters35_C15.dat
[15:43:41.787] <TB3> INFO: write trim parameters into /home/l_tester/david/FullQualification/data/M1039_FullQualification_2016-10-21_13h57m_1477051074//001_Fulltest_m20//trimParameters35_C0.dat
[15:43:41.794] <TB3> INFO: write trim parameters into /home/l_tester/david/FullQualification/data/M1039_FullQualification_2016-10-21_13h57m_1477051074//001_Fulltest_m20//trimParameters35_C1.dat
[15:43:41.798] <TB3> INFO: write trim parameters into /home/l_tester/david/FullQualification/data/M1039_FullQualification_2016-10-21_13h57m_1477051074//001_Fulltest_m20//trimParameters35_C2.dat
[15:43:41.803] <TB3> INFO: write trim parameters into /home/l_tester/david/FullQualification/data/M1039_FullQualification_2016-10-21_13h57m_1477051074//001_Fulltest_m20//trimParameters35_C3.dat
[15:43:41.807] <TB3> INFO: write trim parameters into /home/l_tester/david/FullQualification/data/M1039_FullQualification_2016-10-21_13h57m_1477051074//001_Fulltest_m20//trimParameters35_C4.dat
[15:43:41.812] <TB3> INFO: write trim parameters into /home/l_tester/david/FullQualification/data/M1039_FullQualification_2016-10-21_13h57m_1477051074//001_Fulltest_m20//trimParameters35_C5.dat
[15:43:41.817] <TB3> INFO: write trim parameters into /home/l_tester/david/FullQualification/data/M1039_FullQualification_2016-10-21_13h57m_1477051074//001_Fulltest_m20//trimParameters35_C6.dat
[15:43:41.821] <TB3> INFO: write trim parameters into /home/l_tester/david/FullQualification/data/M1039_FullQualification_2016-10-21_13h57m_1477051074//001_Fulltest_m20//trimParameters35_C7.dat
[15:43:41.826] <TB3> INFO: write trim parameters into /home/l_tester/david/FullQualification/data/M1039_FullQualification_2016-10-21_13h57m_1477051074//001_Fulltest_m20//trimParameters35_C8.dat
[15:43:41.831] <TB3> INFO: write trim parameters into /home/l_tester/david/FullQualification/data/M1039_FullQualification_2016-10-21_13h57m_1477051074//001_Fulltest_m20//trimParameters35_C9.dat
[15:43:41.835] <TB3> INFO: write trim parameters into /home/l_tester/david/FullQualification/data/M1039_FullQualification_2016-10-21_13h57m_1477051074//001_Fulltest_m20//trimParameters35_C10.dat
[15:43:41.840] <TB3> INFO: write trim parameters into /home/l_tester/david/FullQualification/data/M1039_FullQualification_2016-10-21_13h57m_1477051074//001_Fulltest_m20//trimParameters35_C11.dat
[15:43:41.845] <TB3> INFO: write trim parameters into /home/l_tester/david/FullQualification/data/M1039_FullQualification_2016-10-21_13h57m_1477051074//001_Fulltest_m20//trimParameters35_C12.dat
[15:43:41.849] <TB3> INFO: write trim parameters into /home/l_tester/david/FullQualification/data/M1039_FullQualification_2016-10-21_13h57m_1477051074//001_Fulltest_m20//trimParameters35_C13.dat
[15:43:41.854] <TB3> INFO: write trim parameters into /home/l_tester/david/FullQualification/data/M1039_FullQualification_2016-10-21_13h57m_1477051074//001_Fulltest_m20//trimParameters35_C14.dat
[15:43:41.859] <TB3> INFO: write trim parameters into /home/l_tester/david/FullQualification/data/M1039_FullQualification_2016-10-21_13h57m_1477051074//001_Fulltest_m20//trimParameters35_C15.dat
[15:43:41.863] <TB3> INFO: PixTestTrim::trimTest() done
[15:43:41.863] <TB3> INFO: vtrim: 119 126 132 132 127 123 137 109 128 110 123 126 124 135 120 114
[15:43:41.863] <TB3> INFO: vthrcomp: 120 110 125 102 116 113 130 104 111 102 104 110 111 132 123 111
[15:43:41.863] <TB3> INFO: vcal mean: 34.88 34.98 34.96 35.00 34.95 34.99 34.91 35.01 35.05 35.01 35.06 35.11 35.02 34.95 34.88 34.96
[15:43:41.863] <TB3> INFO: vcal RMS: 0.94 1.07 1.16 0.99 0.97 1.04 1.01 0.89 1.09 0.93 1.09 1.28 0.95 1.08 1.08 1.01
[15:43:41.863] <TB3> INFO: bits mean: 9.38 8.57 9.96 9.08 9.36 9.51 9.62 8.19 8.73 7.97 7.93 8.32 8.42 9.57 9.94 8.40
[15:43:41.863] <TB3> INFO: bits RMS: 2.68 2.72 2.68 2.52 2.78 2.67 2.71 2.86 2.62 3.07 2.86 2.82 2.83 2.65 2.76 2.78
[15:43:41.871] <TB3> INFO: ----------------------------------------------------------------------
[15:43:41.871] <TB3> INFO: PixTestTrim::trimBitTest() ntrig = 5, vtrims = 254 126 63 32
[15:43:41.871] <TB3> INFO: ----------------------------------------------------------------------
[15:43:41.873] <TB3> INFO: ---> dac: Vcal name: TrimBitsThr0 ntrig: 5 dacrange: 0 .. 199 (-1/-1) hits flags = 528 (plus default)
[15:43:41.887] <TB3> INFO: dacScan split into 1 runs with ntrig = 5
[15:43:41.888] <TB3> INFO: run 1 of 1
[15:43:42.125] <TB3> INFO: Expecting 4160000 events.
[15:44:14.208] <TB3> INFO: 729555 events read in total (31492ms).
[15:44:45.982] <TB3> INFO: 1454710 events read in total (63266ms).
[15:45:17.431] <TB3> INFO: 2175780 events read in total (94715ms).
[15:45:49.033] <TB3> INFO: 2892595 events read in total (126317ms).
[15:46:20.054] <TB3> INFO: 3606425 events read in total (157338ms).
[15:46:44.067] <TB3> INFO: 4160000 events read in total (181351ms).
[15:46:44.137] <TB3> INFO: Test took 182249ms.
[15:47:12.862] <TB3> INFO: ---> dac: Vcal name: TrimThr_trim14 ntrig: 5 dacrange: 0 .. 194 (-1/-1) hits flags = 528 (plus default)
[15:47:12.875] <TB3> INFO: dacScan split into 1 runs with ntrig = 5
[15:47:12.876] <TB3> INFO: run 1 of 1
[15:47:13.112] <TB3> INFO: Expecting 4056000 events.
[15:47:45.182] <TB3> INFO: 713425 events read in total (31478ms).
[15:48:16.698] <TB3> INFO: 1423325 events read in total (62994ms).
[15:48:48.184] <TB3> INFO: 2129640 events read in total (94480ms).
[15:49:18.774] <TB3> INFO: 2832260 events read in total (125070ms).
[15:49:49.316] <TB3> INFO: 3532335 events read in total (155613ms).
[15:50:11.808] <TB3> INFO: 4056000 events read in total (178104ms).
[15:50:11.871] <TB3> INFO: Test took 178995ms.
[15:50:35.409] <TB3> INFO: ---> dac: Vcal name: TrimThr_trim13 ntrig: 5 dacrange: 0 .. 185 (-1/-1) hits flags = 528 (plus default)
[15:50:35.423] <TB3> INFO: dacScan split into 1 runs with ntrig = 5
[15:50:35.423] <TB3> INFO: run 1 of 1
[15:50:35.701] <TB3> INFO: Expecting 3868800 events.
[15:51:07.439] <TB3> INFO: 725070 events read in total (31146ms).
[15:51:38.729] <TB3> INFO: 1447025 events read in total (62436ms).
[15:52:10.047] <TB3> INFO: 2164215 events read in total (93754ms).
[15:52:41.841] <TB3> INFO: 2877900 events read in total (125548ms).
[15:53:12.959] <TB3> INFO: 3589265 events read in total (156666ms).
[15:53:24.987] <TB3> INFO: 3868800 events read in total (168694ms).
[15:53:25.045] <TB3> INFO: Test took 169622ms.
[15:53:49.123] <TB3> INFO: ---> dac: Vcal name: TrimThr_trim11 ntrig: 5 dacrange: 0 .. 188 (-1/-1) hits flags = 528 (plus default)
[15:53:49.136] <TB3> INFO: dacScan split into 1 runs with ntrig = 5
[15:53:49.136] <TB3> INFO: run 1 of 1
[15:53:49.373] <TB3> INFO: Expecting 3931200 events.
[15:54:20.982] <TB3> INFO: 721455 events read in total (31017ms).
[15:54:51.922] <TB3> INFO: 1439650 events read in total (61957ms).
[15:55:23.381] <TB3> INFO: 2153205 events read in total (93416ms).
[15:55:54.237] <TB3> INFO: 2863300 events read in total (124272ms).
[15:56:24.846] <TB3> INFO: 3571180 events read in total (154881ms).
[15:56:40.857] <TB3> INFO: 3931200 events read in total (170892ms).
[15:56:40.922] <TB3> INFO: Test took 171786ms.
[15:57:05.887] <TB3> INFO: ---> dac: Vcal name: TrimThr_trim7 ntrig: 5 dacrange: 0 .. 186 (-1/-1) hits flags = 528 (plus default)
[15:57:05.901] <TB3> INFO: dacScan split into 1 runs with ntrig = 5
[15:57:05.901] <TB3> INFO: run 1 of 1
[15:57:06.205] <TB3> INFO: Expecting 3889600 events.
[15:57:38.619] <TB3> INFO: 724260 events read in total (31822ms).
[15:58:10.184] <TB3> INFO: 1445520 events read in total (63387ms).
[15:58:40.631] <TB3> INFO: 2161960 events read in total (93834ms).
[15:59:12.016] <TB3> INFO: 2874960 events read in total (125219ms).
[15:59:42.642] <TB3> INFO: 3585650 events read in total (155845ms).
[15:59:56.077] <TB3> INFO: 3889600 events read in total (169280ms).
[15:59:56.187] <TB3> INFO: Test took 170286ms.
[16:00:20.495] <TB3> INFO: PixTestTrim::trimBitTest() done
[16:00:20.496] <TB3> INFO: PixTestTrim::doTest() done, duration: 2440 seconds
[16:00:20.496] <TB3> INFO: Decoding statistics:
[16:00:20.496] <TB3> INFO: General information:
[16:00:20.496] <TB3> INFO: 16bit words read: 0
[16:00:20.496] <TB3> INFO: valid events total: 0
[16:00:20.496] <TB3> INFO: empty events: 0
[16:00:20.496] <TB3> INFO: valid events with pixels: 0
[16:00:20.496] <TB3> INFO: valid pixel hits: 0
[16:00:20.496] <TB3> INFO: Event errors: 0
[16:00:20.496] <TB3> INFO: start marker: 0
[16:00:20.496] <TB3> INFO: stop marker: 0
[16:00:20.496] <TB3> INFO: overflow: 0
[16:00:20.496] <TB3> INFO: invalid 5bit words: 0
[16:00:20.496] <TB3> INFO: invalid XOR eye diagram: 0
[16:00:20.496] <TB3> INFO: frame (failed synchr.): 0
[16:00:20.496] <TB3> INFO: idle data (no TBM trl): 0
[16:00:20.496] <TB3> INFO: no data (only TBM hdr): 0
[16:00:20.496] <TB3> INFO: TBM errors: 0
[16:00:20.496] <TB3> INFO: flawed TBM headers: 0
[16:00:20.496] <TB3> INFO: flawed TBM trailers: 0
[16:00:20.496] <TB3> INFO: event ID mismatches: 0
[16:00:20.496] <TB3> INFO: ROC errors: 0
[16:00:20.496] <TB3> INFO: missing ROC header(s): 0
[16:00:20.496] <TB3> INFO: misplaced readback start: 0
[16:00:20.496] <TB3> INFO: Pixel decoding errors: 0
[16:00:20.496] <TB3> INFO: pixel data incomplete: 0
[16:00:20.496] <TB3> INFO: pixel address: 0
[16:00:20.496] <TB3> INFO: pulse height fill bit: 0
[16:00:20.496] <TB3> INFO: buffer corruption: 0
[16:00:21.219] <TB3> INFO: ######################################################################
[16:00:21.219] <TB3> INFO: PixTestPhOptimization::doTest() Ntrig = 10
[16:00:21.219] <TB3> INFO: ######################################################################
[16:00:21.458] <TB3> INFO: Expecting 41600 events.
[16:00:24.940] <TB3> INFO: 41600 events read in total (2890ms).
[16:00:24.940] <TB3> INFO: Test took 3719ms.
[16:00:25.416] <TB3> INFO: Expecting 41600 events.
[16:00:28.972] <TB3> INFO: 41600 events read in total (2964ms).
[16:00:28.973] <TB3> INFO: Test took 3829ms.
[16:00:29.278] <TB3> INFO: Expecting 41600 events.
[16:00:32.947] <TB3> INFO: 41600 events read in total (3077ms).
[16:00:32.948] <TB3> INFO: Test took 3949ms.
[16:00:33.259] <TB3> INFO: Expecting 41600 events.
[16:00:36.770] <TB3> INFO: 41600 events read in total (2919ms).
[16:00:36.770] <TB3> INFO: Test took 3798ms.
[16:00:37.060] <TB3> INFO: Expecting 41600 events.
[16:00:40.525] <TB3> INFO: 41600 events read in total (2873ms).
[16:00:40.526] <TB3> INFO: Test took 3732ms.
[16:00:40.816] <TB3> INFO: Expecting 41600 events.
[16:00:44.383] <TB3> INFO: 41600 events read in total (2975ms).
[16:00:44.383] <TB3> INFO: Test took 3833ms.
[16:00:44.672] <TB3> INFO: Expecting 41600 events.
[16:00:48.185] <TB3> INFO: 41600 events read in total (2922ms).
[16:00:48.186] <TB3> INFO: Test took 3779ms.
[16:00:48.475] <TB3> INFO: Expecting 41600 events.
[16:00:52.155] <TB3> INFO: 41600 events read in total (3088ms).
[16:00:52.156] <TB3> INFO: Test took 3946ms.
[16:00:52.449] <TB3> INFO: Expecting 41600 events.
[16:00:55.943] <TB3> INFO: 41600 events read in total (2902ms).
[16:00:55.944] <TB3> INFO: Test took 3760ms.
[16:00:56.233] <TB3> INFO: Expecting 41600 events.
[16:00:59.724] <TB3> INFO: 41600 events read in total (2900ms).
[16:00:59.725] <TB3> INFO: Test took 3757ms.
[16:01:00.014] <TB3> INFO: Expecting 41600 events.
[16:01:03.546] <TB3> INFO: 41600 events read in total (2941ms).
[16:01:03.546] <TB3> INFO: Test took 3797ms.
[16:01:03.836] <TB3> INFO: Expecting 41600 events.
[16:01:07.446] <TB3> INFO: 41600 events read in total (3018ms).
[16:01:07.447] <TB3> INFO: Test took 3876ms.
[16:01:07.735] <TB3> INFO: Expecting 41600 events.
[16:01:11.394] <TB3> INFO: 41600 events read in total (3067ms).
[16:01:11.396] <TB3> INFO: Test took 3925ms.
[16:01:11.713] <TB3> INFO: Expecting 41600 events.
[16:01:15.357] <TB3> INFO: 41600 events read in total (3053ms).
[16:01:15.357] <TB3> INFO: Test took 3937ms.
[16:01:15.672] <TB3> INFO: Expecting 41600 events.
[16:01:19.359] <TB3> INFO: 41600 events read in total (3096ms).
[16:01:19.360] <TB3> INFO: Test took 3974ms.
[16:01:19.650] <TB3> INFO: Expecting 41600 events.
[16:01:23.162] <TB3> INFO: 41600 events read in total (2921ms).
[16:01:23.163] <TB3> INFO: Test took 3779ms.
[16:01:23.455] <TB3> INFO: Expecting 41600 events.
[16:01:26.928] <TB3> INFO: 41600 events read in total (2881ms).
[16:01:26.928] <TB3> INFO: Test took 3738ms.
[16:01:27.218] <TB3> INFO: Expecting 41600 events.
[16:01:30.717] <TB3> INFO: 41600 events read in total (2908ms).
[16:01:30.718] <TB3> INFO: Test took 3765ms.
[16:01:31.049] <TB3> INFO: Expecting 41600 events.
[16:01:34.529] <TB3> INFO: 41600 events read in total (2888ms).
[16:01:34.530] <TB3> INFO: Test took 3787ms.
[16:01:34.819] <TB3> INFO: Expecting 41600 events.
[16:01:38.283] <TB3> INFO: 41600 events read in total (2872ms).
[16:01:38.284] <TB3> INFO: Test took 3731ms.
[16:01:38.573] <TB3> INFO: Expecting 41600 events.
[16:01:42.062] <TB3> INFO: 41600 events read in total (2897ms).
[16:01:42.063] <TB3> INFO: Test took 3755ms.
[16:01:42.352] <TB3> INFO: Expecting 41600 events.
[16:01:45.816] <TB3> INFO: 41600 events read in total (2872ms).
[16:01:45.817] <TB3> INFO: Test took 3730ms.
[16:01:46.106] <TB3> INFO: Expecting 41600 events.
[16:01:49.573] <TB3> INFO: 41600 events read in total (2875ms).
[16:01:49.574] <TB3> INFO: Test took 3733ms.
[16:01:49.865] <TB3> INFO: Expecting 41600 events.
[16:01:53.450] <TB3> INFO: 41600 events read in total (2993ms).
[16:01:53.450] <TB3> INFO: Test took 3850ms.
[16:01:53.742] <TB3> INFO: Expecting 41600 events.
[16:01:57.284] <TB3> INFO: 41600 events read in total (2950ms).
[16:01:57.285] <TB3> INFO: Test took 3808ms.
[16:01:57.593] <TB3> INFO: Expecting 41600 events.
[16:02:01.128] <TB3> INFO: 41600 events read in total (2943ms).
[16:02:01.128] <TB3> INFO: Test took 3819ms.
[16:02:01.418] <TB3> INFO: Expecting 41600 events.
[16:02:04.933] <TB3> INFO: 41600 events read in total (2923ms).
[16:02:04.934] <TB3> INFO: Test took 3782ms.
[16:02:05.224] <TB3> INFO: Expecting 41600 events.
[16:02:08.741] <TB3> INFO: 41600 events read in total (2925ms).
[16:02:08.742] <TB3> INFO: Test took 3784ms.
[16:02:09.031] <TB3> INFO: Expecting 41600 events.
[16:02:12.677] <TB3> INFO: 41600 events read in total (3054ms).
[16:02:12.677] <TB3> INFO: Test took 3911ms.
[16:02:12.968] <TB3> INFO: Expecting 41600 events.
[16:02:16.471] <TB3> INFO: 41600 events read in total (2912ms).
[16:02:16.472] <TB3> INFO: Test took 3770ms.
[16:02:16.762] <TB3> INFO: Expecting 41600 events.
[16:02:20.273] <TB3> INFO: 41600 events read in total (2919ms).
[16:02:20.273] <TB3> INFO: Test took 3776ms.
[16:02:20.564] <TB3> INFO: Expecting 2560 events.
[16:02:21.456] <TB3> INFO: 2560 events read in total (300ms).
[16:02:21.456] <TB3> INFO: Test took 1170ms.
[16:02:21.764] <TB3> INFO: Expecting 2560 events.
[16:02:22.657] <TB3> INFO: 2560 events read in total (301ms).
[16:02:22.657] <TB3> INFO: Test took 1200ms.
[16:02:22.965] <TB3> INFO: Expecting 2560 events.
[16:02:23.862] <TB3> INFO: 2560 events read in total (306ms).
[16:02:23.862] <TB3> INFO: Test took 1205ms.
[16:02:24.170] <TB3> INFO: Expecting 2560 events.
[16:02:25.063] <TB3> INFO: 2560 events read in total (301ms).
[16:02:25.063] <TB3> INFO: Test took 1200ms.
[16:02:25.370] <TB3> INFO: Expecting 2560 events.
[16:02:26.249] <TB3> INFO: 2560 events read in total (287ms).
[16:02:26.249] <TB3> INFO: Test took 1185ms.
[16:02:26.557] <TB3> INFO: Expecting 2560 events.
[16:02:27.439] <TB3> INFO: 2560 events read in total (290ms).
[16:02:27.440] <TB3> INFO: Test took 1190ms.
[16:02:27.747] <TB3> INFO: Expecting 2560 events.
[16:02:28.634] <TB3> INFO: 2560 events read in total (296ms).
[16:02:28.634] <TB3> INFO: Test took 1194ms.
[16:02:28.942] <TB3> INFO: Expecting 2560 events.
[16:02:29.830] <TB3> INFO: 2560 events read in total (296ms).
[16:02:29.830] <TB3> INFO: Test took 1195ms.
[16:02:30.138] <TB3> INFO: Expecting 2560 events.
[16:02:31.019] <TB3> INFO: 2560 events read in total (289ms).
[16:02:31.020] <TB3> INFO: Test took 1189ms.
[16:02:31.327] <TB3> INFO: Expecting 2560 events.
[16:02:32.213] <TB3> INFO: 2560 events read in total (294ms).
[16:02:32.213] <TB3> INFO: Test took 1193ms.
[16:02:32.521] <TB3> INFO: Expecting 2560 events.
[16:02:33.409] <TB3> INFO: 2560 events read in total (297ms).
[16:02:33.409] <TB3> INFO: Test took 1195ms.
[16:02:33.717] <TB3> INFO: Expecting 2560 events.
[16:02:34.597] <TB3> INFO: 2560 events read in total (289ms).
[16:02:34.597] <TB3> INFO: Test took 1187ms.
[16:02:34.906] <TB3> INFO: Expecting 2560 events.
[16:02:35.799] <TB3> INFO: 2560 events read in total (302ms).
[16:02:35.799] <TB3> INFO: Test took 1201ms.
[16:02:36.108] <TB3> INFO: Expecting 2560 events.
[16:02:37.004] <TB3> INFO: 2560 events read in total (304ms).
[16:02:37.005] <TB3> INFO: Test took 1205ms.
[16:02:37.312] <TB3> INFO: Expecting 2560 events.
[16:02:38.200] <TB3> INFO: 2560 events read in total (296ms).
[16:02:38.201] <TB3> INFO: Test took 1194ms.
[16:02:38.507] <TB3> INFO: Expecting 2560 events.
[16:02:39.399] <TB3> INFO: 2560 events read in total (300ms).
[16:02:39.399] <TB3> INFO: Test took 1198ms.
[16:02:39.402] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[16:02:39.709] <TB3> INFO: Expecting 655360 events.
[16:02:54.421] <TB3> INFO: 655360 events read in total (14120ms).
[16:02:54.433] <TB3> INFO: Expecting 655360 events.
[16:03:09.049] <TB3> INFO: 655360 events read in total (14213ms).
[16:03:09.066] <TB3> INFO: Expecting 655360 events.
[16:03:23.663] <TB3> INFO: 655360 events read in total (14194ms).
[16:03:23.689] <TB3> INFO: Expecting 655360 events.
[16:03:38.299] <TB3> INFO: 655360 events read in total (14207ms).
[16:03:38.324] <TB3> INFO: Expecting 655360 events.
[16:03:53.107] <TB3> INFO: 655360 events read in total (14380ms).
[16:03:53.137] <TB3> INFO: Expecting 655360 events.
[16:04:07.932] <TB3> INFO: 655360 events read in total (14392ms).
[16:04:07.971] <TB3> INFO: Expecting 655360 events.
[16:04:22.732] <TB3> INFO: 655360 events read in total (14358ms).
[16:04:22.777] <TB3> INFO: Expecting 655360 events.
[16:04:37.770] <TB3> INFO: 655360 events read in total (14589ms).
[16:04:37.817] <TB3> INFO: Expecting 655360 events.
[16:04:52.620] <TB3> INFO: 655360 events read in total (14400ms).
[16:04:52.672] <TB3> INFO: Expecting 655360 events.
[16:05:07.532] <TB3> INFO: 655360 events read in total (14456ms).
[16:05:07.591] <TB3> INFO: Expecting 655360 events.
[16:05:22.349] <TB3> INFO: 655360 events read in total (14356ms).
[16:05:22.410] <TB3> INFO: Expecting 655360 events.
[16:05:37.179] <TB3> INFO: 655360 events read in total (14366ms).
[16:05:37.250] <TB3> INFO: Expecting 655360 events.
[16:05:51.000] <TB3> INFO: 655360 events read in total (14347ms).
[16:05:52.073] <TB3> INFO: Expecting 655360 events.
[16:06:06.872] <TB3> INFO: 655360 events read in total (14396ms).
[16:06:06.950] <TB3> INFO: Expecting 655360 events.
[16:06:21.688] <TB3> INFO: 655360 events read in total (14335ms).
[16:06:21.770] <TB3> INFO: Expecting 655360 events.
[16:06:36.511] <TB3> INFO: 655360 events read in total (14338ms).
[16:06:36.599] <TB3> INFO: Test took 237197ms.
[16:06:36.693] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[16:06:36.952] <TB3> INFO: Expecting 655360 events.
[16:06:51.788] <TB3> INFO: 655360 events read in total (14244ms).
[16:06:51.803] <TB3> INFO: Expecting 655360 events.
[16:07:06.128] <TB3> INFO: 655360 events read in total (13922ms).
[16:07:06.144] <TB3> INFO: Expecting 655360 events.
[16:07:20.702] <TB3> INFO: 655360 events read in total (14155ms).
[16:07:20.730] <TB3> INFO: Expecting 655360 events.
[16:07:35.259] <TB3> INFO: 655360 events read in total (14126ms).
[16:07:35.291] <TB3> INFO: Expecting 655360 events.
[16:07:49.895] <TB3> INFO: 655360 events read in total (14201ms).
[16:07:49.925] <TB3> INFO: Expecting 655360 events.
[16:08:04.512] <TB3> INFO: 655360 events read in total (14184ms).
[16:08:04.547] <TB3> INFO: Expecting 655360 events.
[16:08:19.087] <TB3> INFO: 655360 events read in total (14137ms).
[16:08:19.139] <TB3> INFO: Expecting 655360 events.
[16:08:33.709] <TB3> INFO: 655360 events read in total (14167ms).
[16:08:33.757] <TB3> INFO: Expecting 655360 events.
[16:08:48.378] <TB3> INFO: 655360 events read in total (14217ms).
[16:08:48.428] <TB3> INFO: Expecting 655360 events.
[16:09:03.172] <TB3> INFO: 655360 events read in total (14341ms).
[16:09:03.229] <TB3> INFO: Expecting 655360 events.
[16:09:18.007] <TB3> INFO: 655360 events read in total (14375ms).
[16:09:18.081] <TB3> INFO: Expecting 655360 events.
[16:09:32.523] <TB3> INFO: 655360 events read in total (14038ms).
[16:09:32.588] <TB3> INFO: Expecting 655360 events.
[16:09:47.403] <TB3> INFO: 655360 events read in total (14412ms).
[16:09:47.474] <TB3> INFO: Expecting 655360 events.
[16:10:02.236] <TB3> INFO: 655360 events read in total (14359ms).
[16:10:02.338] <TB3> INFO: Expecting 655360 events.
[16:10:16.941] <TB3> INFO: 655360 events read in total (14200ms).
[16:10:17.035] <TB3> INFO: Expecting 655360 events.
[16:10:31.835] <TB3> INFO: 655360 events read in total (14397ms).
[16:10:31.938] <TB3> INFO: Test took 235245ms.
[16:10:32.118] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[16:10:32.124] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[16:10:32.129] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[16:10:32.135] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[16:10:32.140] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[16:10:32.146] <TB3> INFO: safety margin for low PH: adding 1, margin is now 21
[16:10:32.152] <TB3> INFO: safety margin for low PH: adding 2, margin is now 22
[16:10:32.158] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[16:10:32.165] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[16:10:32.171] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[16:10:32.177] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[16:10:32.184] <TB3> INFO: safety margin for low PH: adding 1, margin is now 21
[16:10:32.190] <TB3> INFO: safety margin for low PH: adding 2, margin is now 22
[16:10:32.196] <TB3> INFO: safety margin for low PH: adding 3, margin is now 23
[16:10:32.202] <TB3> INFO: safety margin for low PH: adding 4, margin is now 24
[16:10:32.208] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[16:10:32.214] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[16:10:32.220] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[16:10:32.227] <TB3> INFO: safety margin for low PH: adding 1, margin is now 21
[16:10:32.233] <TB3> INFO: safety margin for low PH: adding 2, margin is now 22
[16:10:32.239] <TB3> INFO: safety margin for low PH: adding 3, margin is now 23
[16:10:32.246] <TB3> INFO: safety margin for low PH: adding 4, margin is now 24
[16:10:32.252] <TB3> INFO: safety margin for low PH: adding 5, margin is now 25
[16:10:32.258] <TB3> INFO: safety margin for low PH: adding 6, margin is now 26
[16:10:32.264] <TB3> INFO: safety margin for low PH: adding 7, margin is now 27
[16:10:32.270] <TB3> INFO: safety margin for low PH: adding 8, margin is now 28
[16:10:32.277] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[16:10:32.283] <TB3> INFO: safety margin for low PH: adding 1, margin is now 21
[16:10:32.289] <TB3> INFO: safety margin for low PH: adding 2, margin is now 22
[16:10:32.295] <TB3> INFO: safety margin for low PH: adding 3, margin is now 23
[16:10:32.301] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[16:10:32.307] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[16:10:32.313] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[16:10:32.350] <TB3> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1039_FullQualification_2016-10-21_13h57m_1477051074//001_Fulltest_m20//dacParameters35_C0.dat
[16:10:32.350] <TB3> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1039_FullQualification_2016-10-21_13h57m_1477051074//001_Fulltest_m20//dacParameters35_C1.dat
[16:10:32.350] <TB3> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1039_FullQualification_2016-10-21_13h57m_1477051074//001_Fulltest_m20//dacParameters35_C2.dat
[16:10:32.350] <TB3> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1039_FullQualification_2016-10-21_13h57m_1477051074//001_Fulltest_m20//dacParameters35_C3.dat
[16:10:32.350] <TB3> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1039_FullQualification_2016-10-21_13h57m_1477051074//001_Fulltest_m20//dacParameters35_C4.dat
[16:10:32.351] <TB3> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1039_FullQualification_2016-10-21_13h57m_1477051074//001_Fulltest_m20//dacParameters35_C5.dat
[16:10:32.351] <TB3> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1039_FullQualification_2016-10-21_13h57m_1477051074//001_Fulltest_m20//dacParameters35_C6.dat
[16:10:32.351] <TB3> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1039_FullQualification_2016-10-21_13h57m_1477051074//001_Fulltest_m20//dacParameters35_C7.dat
[16:10:32.351] <TB3> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1039_FullQualification_2016-10-21_13h57m_1477051074//001_Fulltest_m20//dacParameters35_C8.dat
[16:10:32.351] <TB3> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1039_FullQualification_2016-10-21_13h57m_1477051074//001_Fulltest_m20//dacParameters35_C9.dat
[16:10:32.351] <TB3> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1039_FullQualification_2016-10-21_13h57m_1477051074//001_Fulltest_m20//dacParameters35_C10.dat
[16:10:32.352] <TB3> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1039_FullQualification_2016-10-21_13h57m_1477051074//001_Fulltest_m20//dacParameters35_C11.dat
[16:10:32.352] <TB3> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1039_FullQualification_2016-10-21_13h57m_1477051074//001_Fulltest_m20//dacParameters35_C12.dat
[16:10:32.352] <TB3> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1039_FullQualification_2016-10-21_13h57m_1477051074//001_Fulltest_m20//dacParameters35_C13.dat
[16:10:32.352] <TB3> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1039_FullQualification_2016-10-21_13h57m_1477051074//001_Fulltest_m20//dacParameters35_C14.dat
[16:10:32.352] <TB3> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1039_FullQualification_2016-10-21_13h57m_1477051074//001_Fulltest_m20//dacParameters35_C15.dat
[16:10:32.596] <TB3> INFO: Expecting 41600 events.
[16:10:35.782] <TB3> INFO: 41600 events read in total (2594ms).
[16:10:35.783] <TB3> INFO: Test took 3427ms.
[16:10:36.239] <TB3> INFO: Expecting 41600 events.
[16:10:39.355] <TB3> INFO: 41600 events read in total (2524ms).
[16:10:39.356] <TB3> INFO: Test took 3360ms.
[16:10:39.859] <TB3> INFO: Expecting 41600 events.
[16:10:43.014] <TB3> INFO: 41600 events read in total (2563ms).
[16:10:43.015] <TB3> INFO: Test took 3444ms.
[16:10:43.230] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[16:10:43.320] <TB3> INFO: Expecting 2560 events.
[16:10:44.212] <TB3> INFO: 2560 events read in total (300ms).
[16:10:44.212] <TB3> INFO: Test took 983ms.
[16:10:44.215] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[16:10:44.521] <TB3> INFO: Expecting 2560 events.
[16:10:45.417] <TB3> INFO: 2560 events read in total (305ms).
[16:10:45.418] <TB3> INFO: Test took 1203ms.
[16:10:45.421] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[16:10:45.725] <TB3> INFO: Expecting 2560 events.
[16:10:46.616] <TB3> INFO: 2560 events read in total (300ms).
[16:10:46.617] <TB3> INFO: Test took 1196ms.
[16:10:46.619] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[16:10:46.926] <TB3> INFO: Expecting 2560 events.
[16:10:47.811] <TB3> INFO: 2560 events read in total (293ms).
[16:10:47.811] <TB3> INFO: Test took 1192ms.
[16:10:47.814] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[16:10:48.118] <TB3> INFO: Expecting 2560 events.
[16:10:48.002] <TB3> INFO: 2560 events read in total (292ms).
[16:10:48.003] <TB3> INFO: Test took 1189ms.
[16:10:49.005] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[16:10:49.312] <TB3> INFO: Expecting 2560 events.
[16:10:50.205] <TB3> INFO: 2560 events read in total (301ms).
[16:10:50.205] <TB3> INFO: Test took 1200ms.
[16:10:50.209] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[16:10:50.513] <TB3> INFO: Expecting 2560 events.
[16:10:51.408] <TB3> INFO: 2560 events read in total (303ms).
[16:10:51.409] <TB3> INFO: Test took 1200ms.
[16:10:51.412] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[16:10:51.716] <TB3> INFO: Expecting 2560 events.
[16:10:52.601] <TB3> INFO: 2560 events read in total (293ms).
[16:10:52.602] <TB3> INFO: Test took 1190ms.
[16:10:52.605] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[16:10:52.910] <TB3> INFO: Expecting 2560 events.
[16:10:53.800] <TB3> INFO: 2560 events read in total (298ms).
[16:10:53.801] <TB3> INFO: Test took 1196ms.
[16:10:53.804] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[16:10:54.109] <TB3> INFO: Expecting 2560 events.
[16:10:54.000] <TB3> INFO: 2560 events read in total (299ms).
[16:10:54.000] <TB3> INFO: Test took 1197ms.
[16:10:55.003] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[16:10:55.309] <TB3> INFO: Expecting 2560 events.
[16:10:56.202] <TB3> INFO: 2560 events read in total (301ms).
[16:10:56.203] <TB3> INFO: Test took 1200ms.
[16:10:56.205] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[16:10:56.511] <TB3> INFO: Expecting 2560 events.
[16:10:57.395] <TB3> INFO: 2560 events read in total (292ms).
[16:10:57.395] <TB3> INFO: Test took 1190ms.
[16:10:57.399] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[16:10:57.702] <TB3> INFO: Expecting 2560 events.
[16:10:58.592] <TB3> INFO: 2560 events read in total (298ms).
[16:10:58.592] <TB3> INFO: Test took 1194ms.
[16:10:58.596] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[16:10:58.900] <TB3> INFO: Expecting 2560 events.
[16:10:59.787] <TB3> INFO: 2560 events read in total (295ms).
[16:10:59.788] <TB3> INFO: Test took 1193ms.
[16:10:59.791] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[16:11:00.096] <TB3> INFO: Expecting 2560 events.
[16:11:00.984] <TB3> INFO: 2560 events read in total (296ms).
[16:11:00.984] <TB3> INFO: Test took 1193ms.
[16:11:00.986] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[16:11:01.293] <TB3> INFO: Expecting 2560 events.
[16:11:02.176] <TB3> INFO: 2560 events read in total (291ms).
[16:11:02.177] <TB3> INFO: Test took 1191ms.
[16:11:02.180] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[16:11:02.484] <TB3> INFO: Expecting 2560 events.
[16:11:03.368] <TB3> INFO: 2560 events read in total (292ms).
[16:11:03.368] <TB3> INFO: Test took 1188ms.
[16:11:03.371] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[16:11:03.677] <TB3> INFO: Expecting 2560 events.
[16:11:04.565] <TB3> INFO: 2560 events read in total (296ms).
[16:11:04.565] <TB3> INFO: Test took 1194ms.
[16:11:04.567] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[16:11:04.873] <TB3> INFO: Expecting 2560 events.
[16:11:05.754] <TB3> INFO: 2560 events read in total (290ms).
[16:11:05.755] <TB3> INFO: Test took 1188ms.
[16:11:05.758] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[16:11:06.064] <TB3> INFO: Expecting 2560 events.
[16:11:06.952] <TB3> INFO: 2560 events read in total (297ms).
[16:11:06.953] <TB3> INFO: Test took 1195ms.
[16:11:06.956] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[16:11:07.261] <TB3> INFO: Expecting 2560 events.
[16:11:08.153] <TB3> INFO: 2560 events read in total (300ms).
[16:11:08.153] <TB3> INFO: Test took 1197ms.
[16:11:08.156] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[16:11:08.462] <TB3> INFO: Expecting 2560 events.
[16:11:09.356] <TB3> INFO: 2560 events read in total (302ms).
[16:11:09.356] <TB3> INFO: Test took 1201ms.
[16:11:09.359] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[16:11:09.664] <TB3> INFO: Expecting 2560 events.
[16:11:10.552] <TB3> INFO: 2560 events read in total (296ms).
[16:11:10.552] <TB3> INFO: Test took 1194ms.
[16:11:10.554] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[16:11:10.862] <TB3> INFO: Expecting 2560 events.
[16:11:11.753] <TB3> INFO: 2560 events read in total (299ms).
[16:11:11.754] <TB3> INFO: Test took 1200ms.
[16:11:11.757] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[16:11:12.062] <TB3> INFO: Expecting 2560 events.
[16:11:12.946] <TB3> INFO: 2560 events read in total (292ms).
[16:11:12.947] <TB3> INFO: Test took 1190ms.
[16:11:12.950] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[16:11:13.254] <TB3> INFO: Expecting 2560 events.
[16:11:14.139] <TB3> INFO: 2560 events read in total (293ms).
[16:11:14.139] <TB3> INFO: Test took 1190ms.
[16:11:14.141] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[16:11:14.448] <TB3> INFO: Expecting 2560 events.
[16:11:15.341] <TB3> INFO: 2560 events read in total (301ms).
[16:11:15.341] <TB3> INFO: Test took 1200ms.
[16:11:15.345] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[16:11:15.650] <TB3> INFO: Expecting 2560 events.
[16:11:16.540] <TB3> INFO: 2560 events read in total (298ms).
[16:11:16.540] <TB3> INFO: Test took 1196ms.
[16:11:16.544] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[16:11:16.849] <TB3> INFO: Expecting 2560 events.
[16:11:17.741] <TB3> INFO: 2560 events read in total (300ms).
[16:11:17.742] <TB3> INFO: Test took 1198ms.
[16:11:17.744] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[16:11:18.050] <TB3> INFO: Expecting 2560 events.
[16:11:18.942] <TB3> INFO: 2560 events read in total (301ms).
[16:11:18.942] <TB3> INFO: Test took 1198ms.
[16:11:18.945] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[16:11:19.249] <TB3> INFO: Expecting 2560 events.
[16:11:20.144] <TB3> INFO: 2560 events read in total (303ms).
[16:11:20.144] <TB3> INFO: Test took 1199ms.
[16:11:20.147] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[16:11:20.451] <TB3> INFO: Expecting 2560 events.
[16:11:21.342] <TB3> INFO: 2560 events read in total (299ms).
[16:11:21.343] <TB3> INFO: Test took 1196ms.
[16:11:21.807] <TB3> INFO: PixTestPhOptimization::doTest() done, duration: 660 seconds
[16:11:21.807] <TB3> INFO: PH scale (per ROC): 60 58 53 45 52 57 75 64 42 51 39 48 49 55 58 43
[16:11:21.807] <TB3> INFO: PH offset (per ROC): 133 109 107 95 107 109 116 118 105 109 94 105 93 128 126 101
[16:11:21.816] <TB3> INFO: Decoding statistics:
[16:11:21.816] <TB3> INFO: General information:
[16:11:21.816] <TB3> INFO: 16bit words read: 127884
[16:11:21.816] <TB3> INFO: valid events total: 20480
[16:11:21.816] <TB3> INFO: empty events: 17978
[16:11:21.816] <TB3> INFO: valid events with pixels: 2502
[16:11:21.816] <TB3> INFO: valid pixel hits: 2502
[16:11:21.816] <TB3> INFO: Event errors: 0
[16:11:21.816] <TB3> INFO: start marker: 0
[16:11:21.816] <TB3> INFO: stop marker: 0
[16:11:21.816] <TB3> INFO: overflow: 0
[16:11:21.816] <TB3> INFO: invalid 5bit words: 0
[16:11:21.816] <TB3> INFO: invalid XOR eye diagram: 0
[16:11:21.816] <TB3> INFO: frame (failed synchr.): 0
[16:11:21.816] <TB3> INFO: idle data (no TBM trl): 0
[16:11:21.817] <TB3> INFO: no data (only TBM hdr): 0
[16:11:21.817] <TB3> INFO: TBM errors: 0
[16:11:21.817] <TB3> INFO: flawed TBM headers: 0
[16:11:21.817] <TB3> INFO: flawed TBM trailers: 0
[16:11:21.817] <TB3> INFO: event ID mismatches: 0
[16:11:21.817] <TB3> INFO: ROC errors: 0
[16:11:21.817] <TB3> INFO: missing ROC header(s): 0
[16:11:21.817] <TB3> INFO: misplaced readback start: 0
[16:11:21.817] <TB3> INFO: Pixel decoding errors: 0
[16:11:21.817] <TB3> INFO: pixel data incomplete: 0
[16:11:21.817] <TB3> INFO: pixel address: 0
[16:11:21.817] <TB3> INFO: pulse height fill bit: 0
[16:11:21.817] <TB3> INFO: buffer corruption: 0
[16:11:21.981] <TB3> INFO: ######################################################################
[16:11:21.981] <TB3> INFO: PixTestGainPedestal::fullTest() ntrig = 10
[16:11:21.981] <TB3> INFO: ######################################################################
[16:11:21.994] <TB3> INFO: scanning low vcal = 10
[16:11:22.231] <TB3> INFO: Expecting 41600 events.
[16:11:25.822] <TB3> INFO: 41600 events read in total (2999ms).
[16:11:25.822] <TB3> INFO: Test took 3828ms.
[16:11:25.824] <TB3> INFO: scanning low vcal = 20
[16:11:26.118] <TB3> INFO: Expecting 41600 events.
[16:11:29.689] <TB3> INFO: 41600 events read in total (2980ms).
[16:11:29.689] <TB3> INFO: Test took 3865ms.
[16:11:29.691] <TB3> INFO: scanning low vcal = 30
[16:11:29.990] <TB3> INFO: Expecting 41600 events.
[16:11:33.654] <TB3> INFO: 41600 events read in total (3072ms).
[16:11:33.655] <TB3> INFO: Test took 3964ms.
[16:11:33.657] <TB3> INFO: scanning low vcal = 40
[16:11:33.935] <TB3> INFO: Expecting 41600 events.
[16:11:37.946] <TB3> INFO: 41600 events read in total (3419ms).
[16:11:37.948] <TB3> INFO: Test took 4290ms.
[16:11:37.951] <TB3> INFO: scanning low vcal = 50
[16:11:38.228] <TB3> INFO: Expecting 41600 events.
[16:11:42.234] <TB3> INFO: 41600 events read in total (3414ms).
[16:11:42.235] <TB3> INFO: Test took 4284ms.
[16:11:42.238] <TB3> INFO: scanning low vcal = 60
[16:11:42.515] <TB3> INFO: Expecting 41600 events.
[16:11:46.496] <TB3> INFO: 41600 events read in total (3390ms).
[16:11:46.497] <TB3> INFO: Test took 4259ms.
[16:11:46.499] <TB3> INFO: scanning low vcal = 70
[16:11:46.776] <TB3> INFO: Expecting 41600 events.
[16:11:50.739] <TB3> INFO: 41600 events read in total (3371ms).
[16:11:50.740] <TB3> INFO: Test took 4241ms.
[16:11:50.744] <TB3> INFO: scanning low vcal = 80
[16:11:51.020] <TB3> INFO: Expecting 41600 events.
[16:11:54.981] <TB3> INFO: 41600 events read in total (3370ms).
[16:11:54.982] <TB3> INFO: Test took 4238ms.
[16:11:54.985] <TB3> INFO: scanning low vcal = 90
[16:11:55.261] <TB3> INFO: Expecting 41600 events.
[16:11:59.204] <TB3> INFO: 41600 events read in total (3351ms).
[16:11:59.204] <TB3> INFO: Test took 4219ms.
[16:11:59.209] <TB3> INFO: scanning low vcal = 100
[16:11:59.484] <TB3> INFO: Expecting 41600 events.
[16:12:03.425] <TB3> INFO: 41600 events read in total (3350ms).
[16:12:03.426] <TB3> INFO: Test took 4217ms.
[16:12:03.429] <TB3> INFO: scanning low vcal = 110
[16:12:03.705] <TB3> INFO: Expecting 41600 events.
[16:12:07.668] <TB3> INFO: 41600 events read in total (3371ms).
[16:12:07.669] <TB3> INFO: Test took 4240ms.
[16:12:07.672] <TB3> INFO: scanning low vcal = 120
[16:12:07.949] <TB3> INFO: Expecting 41600 events.
[16:12:11.892] <TB3> INFO: 41600 events read in total (3350ms).
[16:12:11.893] <TB3> INFO: Test took 4221ms.
[16:12:11.897] <TB3> INFO: scanning low vcal = 130
[16:12:12.173] <TB3> INFO: Expecting 41600 events.
[16:12:16.127] <TB3> INFO: 41600 events read in total (3363ms).
[16:12:16.128] <TB3> INFO: Test took 4231ms.
[16:12:16.131] <TB3> INFO: scanning low vcal = 140
[16:12:16.407] <TB3> INFO: Expecting 41600 events.
[16:12:20.344] <TB3> INFO: 41600 events read in total (3345ms).
[16:12:20.345] <TB3> INFO: Test took 4214ms.
[16:12:20.348] <TB3> INFO: scanning low vcal = 150
[16:12:20.624] <TB3> INFO: Expecting 41600 events.
[16:12:24.685] <TB3> INFO: 41600 events read in total (3469ms).
[16:12:24.686] <TB3> INFO: Test took 4338ms.
[16:12:24.690] <TB3> INFO: scanning low vcal = 160
[16:12:24.987] <TB3> INFO: Expecting 41600 events.
[16:12:29.055] <TB3> INFO: 41600 events read in total (3477ms).
[16:12:29.056] <TB3> INFO: Test took 4366ms.
[16:12:29.059] <TB3> INFO: scanning low vcal = 170
[16:12:29.335] <TB3> INFO: Expecting 41600 events.
[16:12:33.379] <TB3> INFO: 41600 events read in total (3452ms).
[16:12:33.380] <TB3> INFO: Test took 4321ms.
[16:12:33.385] <TB3> INFO: scanning low vcal = 180
[16:12:33.660] <TB3> INFO: Expecting 41600 events.
[16:12:37.685] <TB3> INFO: 41600 events read in total (3434ms).
[16:12:37.686] <TB3> INFO: Test took 4301ms.
[16:12:37.689] <TB3> INFO: scanning low vcal = 190
[16:12:37.966] <TB3> INFO: Expecting 41600 events.
[16:12:41.999] <TB3> INFO: 41600 events read in total (3442ms).
[16:12:41.999] <TB3> INFO: Test took 4310ms.
[16:12:41.002] <TB3> INFO: scanning low vcal = 200
[16:12:42.279] <TB3> INFO: Expecting 41600 events.
[16:12:46.356] <TB3> INFO: 41600 events read in total (3485ms).
[16:12:46.357] <TB3> INFO: Test took 4355ms.
[16:12:46.360] <TB3> INFO: scanning low vcal = 210
[16:12:46.636] <TB3> INFO: Expecting 41600 events.
[16:12:50.642] <TB3> INFO: 41600 events read in total (3414ms).
[16:12:50.643] <TB3> INFO: Test took 4283ms.
[16:12:50.646] <TB3> INFO: scanning low vcal = 220
[16:12:50.945] <TB3> INFO: Expecting 41600 events.
[16:12:54.953] <TB3> INFO: 41600 events read in total (3417ms).
[16:12:54.954] <TB3> INFO: Test took 4308ms.
[16:12:54.957] <TB3> INFO: scanning low vcal = 230
[16:12:55.233] <TB3> INFO: Expecting 41600 events.
[16:12:59.235] <TB3> INFO: 41600 events read in total (3410ms).
[16:12:59.235] <TB3> INFO: Test took 4278ms.
[16:12:59.238] <TB3> INFO: scanning low vcal = 240
[16:12:59.515] <TB3> INFO: Expecting 41600 events.
[16:13:03.587] <TB3> INFO: 41600 events read in total (3480ms).
[16:13:03.588] <TB3> INFO: Test took 4350ms.
[16:13:03.591] <TB3> INFO: scanning low vcal = 250
[16:13:03.868] <TB3> INFO: Expecting 41600 events.
[16:13:07.902] <TB3> INFO: 41600 events read in total (3442ms).
[16:13:07.903] <TB3> INFO: Test took 4312ms.
[16:13:07.907] <TB3> INFO: scanning high vcal = 30 (= 210 in low range)
[16:13:08.183] <TB3> INFO: Expecting 41600 events.
[16:13:12.169] <TB3> INFO: 41600 events read in total (3394ms).
[16:13:12.170] <TB3> INFO: Test took 4263ms.
[16:13:12.173] <TB3> INFO: scanning high vcal = 50 (= 350 in low range)
[16:13:12.450] <TB3> INFO: Expecting 41600 events.
[16:13:16.438] <TB3> INFO: 41600 events read in total (3397ms).
[16:13:16.438] <TB3> INFO: Test took 4265ms.
[16:13:16.442] <TB3> INFO: scanning high vcal = 70 (= 490 in low range)
[16:13:16.738] <TB3> INFO: Expecting 41600 events.
[16:13:20.701] <TB3> INFO: 41600 events read in total (3372ms).
[16:13:20.702] <TB3> INFO: Test took 4260ms.
[16:13:20.705] <TB3> INFO: scanning high vcal = 90 (= 630 in low range)
[16:13:20.981] <TB3> INFO: Expecting 41600 events.
[16:13:24.942] <TB3> INFO: 41600 events read in total (3369ms).
[16:13:24.942] <TB3> INFO: Test took 4237ms.
[16:13:24.945] <TB3> INFO: scanning high vcal = 200 (= 1400 in low range)
[16:13:25.222] <TB3> INFO: Expecting 41600 events.
[16:13:29.244] <TB3> INFO: 41600 events read in total (3430ms).
[16:13:29.245] <TB3> INFO: Test took 4299ms.
[16:13:29.651] <TB3> INFO: PixTestGainPedestal::measure() done
[16:14:02.416] <TB3> INFO: PixTestGainPedestal::fit() done
[16:14:02.416] <TB3> INFO: non-linearity mean: 0.980 0.968 0.947 0.979 0.935 0.959 0.986 0.981 0.988 0.953 0.948 0.964 0.933 0.977 0.973 0.914
[16:14:02.416] <TB3> INFO: non-linearity RMS: 0.003 0.029 0.085 0.197 0.061 0.035 0.003 0.005 0.170 0.039 0.123 0.049 0.093 0.007 0.006 0.122
[16:14:02.416] <TB3> INFO: write gain/ped parameters into /home/l_tester/david/FullQualification/data/M1039_FullQualification_2016-10-21_13h57m_1477051074//001_Fulltest_m20//phCalibrationFitErr35_C0.dat
[16:14:02.430] <TB3> INFO: write gain/ped parameters into /home/l_tester/david/FullQualification/data/M1039_FullQualification_2016-10-21_13h57m_1477051074//001_Fulltest_m20//phCalibrationFitErr35_C1.dat
[16:14:02.443] <TB3> INFO: write gain/ped parameters into /home/l_tester/david/FullQualification/data/M1039_FullQualification_2016-10-21_13h57m_1477051074//001_Fulltest_m20//phCalibrationFitErr35_C2.dat
[16:14:02.457] <TB3> INFO: write gain/ped parameters into /home/l_tester/david/FullQualification/data/M1039_FullQualification_2016-10-21_13h57m_1477051074//001_Fulltest_m20//phCalibrationFitErr35_C3.dat
[16:14:02.470] <TB3> INFO: write gain/ped parameters into /home/l_tester/david/FullQualification/data/M1039_FullQualification_2016-10-21_13h57m_1477051074//001_Fulltest_m20//phCalibrationFitErr35_C4.dat
[16:14:02.483] <TB3> INFO: write gain/ped parameters into /home/l_tester/david/FullQualification/data/M1039_FullQualification_2016-10-21_13h57m_1477051074//001_Fulltest_m20//phCalibrationFitErr35_C5.dat
[16:14:02.496] <TB3> INFO: write gain/ped parameters into /home/l_tester/david/FullQualification/data/M1039_FullQualification_2016-10-21_13h57m_1477051074//001_Fulltest_m20//phCalibrationFitErr35_C6.dat
[16:14:02.509] <TB3> INFO: write gain/ped parameters into /home/l_tester/david/FullQualification/data/M1039_FullQualification_2016-10-21_13h57m_1477051074//001_Fulltest_m20//phCalibrationFitErr35_C7.dat
[16:14:02.522] <TB3> INFO: write gain/ped parameters into /home/l_tester/david/FullQualification/data/M1039_FullQualification_2016-10-21_13h57m_1477051074//001_Fulltest_m20//phCalibrationFitErr35_C8.dat
[16:14:02.535] <TB3> INFO: write gain/ped parameters into /home/l_tester/david/FullQualification/data/M1039_FullQualification_2016-10-21_13h57m_1477051074//001_Fulltest_m20//phCalibrationFitErr35_C9.dat
[16:14:02.547] <TB3> INFO: write gain/ped parameters into /home/l_tester/david/FullQualification/data/M1039_FullQualification_2016-10-21_13h57m_1477051074//001_Fulltest_m20//phCalibrationFitErr35_C10.dat
[16:14:02.561] <TB3> INFO: write gain/ped parameters into /home/l_tester/david/FullQualification/data/M1039_FullQualification_2016-10-21_13h57m_1477051074//001_Fulltest_m20//phCalibrationFitErr35_C11.dat
[16:14:02.574] <TB3> INFO: write gain/ped parameters into /home/l_tester/david/FullQualification/data/M1039_FullQualification_2016-10-21_13h57m_1477051074//001_Fulltest_m20//phCalibrationFitErr35_C12.dat
[16:14:02.587] <TB3> INFO: write gain/ped parameters into /home/l_tester/david/FullQualification/data/M1039_FullQualification_2016-10-21_13h57m_1477051074//001_Fulltest_m20//phCalibrationFitErr35_C13.dat
[16:14:02.600] <TB3> INFO: write gain/ped parameters into /home/l_tester/david/FullQualification/data/M1039_FullQualification_2016-10-21_13h57m_1477051074//001_Fulltest_m20//phCalibrationFitErr35_C14.dat
[16:14:02.614] <TB3> INFO: write gain/ped parameters into /home/l_tester/david/FullQualification/data/M1039_FullQualification_2016-10-21_13h57m_1477051074//001_Fulltest_m20//phCalibrationFitErr35_C15.dat
[16:14:02.628] <TB3> INFO: PixTestGainPedestal::fullTest() done, duration: 160 seconds
[16:14:02.628] <TB3> INFO: Decoding statistics:
[16:14:02.628] <TB3> INFO: General information:
[16:14:02.628] <TB3> INFO: 16bit words read: 3327922
[16:14:02.628] <TB3> INFO: valid events total: 332800
[16:14:02.628] <TB3> INFO: empty events: 0
[16:14:02.628] <TB3> INFO: valid events with pixels: 332800
[16:14:02.628] <TB3> INFO: valid pixel hits: 665561
[16:14:02.628] <TB3> INFO: Event errors: 0
[16:14:02.628] <TB3> INFO: start marker: 0
[16:14:02.628] <TB3> INFO: stop marker: 0
[16:14:02.628] <TB3> INFO: overflow: 0
[16:14:02.628] <TB3> INFO: invalid 5bit words: 0
[16:14:02.628] <TB3> INFO: invalid XOR eye diagram: 0
[16:14:02.628] <TB3> INFO: frame (failed synchr.): 0
[16:14:02.628] <TB3> INFO: idle data (no TBM trl): 0
[16:14:02.628] <TB3> INFO: no data (only TBM hdr): 0
[16:14:02.628] <TB3> INFO: TBM errors: 0
[16:14:02.628] <TB3> INFO: flawed TBM headers: 0
[16:14:02.628] <TB3> INFO: flawed TBM trailers: 0
[16:14:02.628] <TB3> INFO: event ID mismatches: 0
[16:14:02.628] <TB3> INFO: ROC errors: 0
[16:14:02.628] <TB3> INFO: missing ROC header(s): 0
[16:14:02.628] <TB3> INFO: misplaced readback start: 0
[16:14:02.628] <TB3> INFO: Pixel decoding errors: 0
[16:14:02.628] <TB3> INFO: pixel data incomplete: 0
[16:14:02.628] <TB3> INFO: pixel address: 0
[16:14:02.628] <TB3> INFO: pulse height fill bit: 0
[16:14:02.628] <TB3> INFO: buffer corruption: 0
[16:14:02.644] <TB3> INFO: Decoding statistics:
[16:14:02.644] <TB3> INFO: General information:
[16:14:02.644] <TB3> INFO: 16bit words read: 3457342
[16:14:02.644] <TB3> INFO: valid events total: 353536
[16:14:02.644] <TB3> INFO: empty events: 18234
[16:14:02.644] <TB3> INFO: valid events with pixels: 335302
[16:14:02.644] <TB3> INFO: valid pixel hits: 668063
[16:14:02.644] <TB3> INFO: Event errors: 0
[16:14:02.644] <TB3> INFO: start marker: 0
[16:14:02.644] <TB3> INFO: stop marker: 0
[16:14:02.644] <TB3> INFO: overflow: 0
[16:14:02.644] <TB3> INFO: invalid 5bit words: 0
[16:14:02.644] <TB3> INFO: invalid XOR eye diagram: 0
[16:14:02.644] <TB3> INFO: frame (failed synchr.): 0
[16:14:02.644] <TB3> INFO: idle data (no TBM trl): 0
[16:14:02.644] <TB3> INFO: no data (only TBM hdr): 0
[16:14:02.644] <TB3> INFO: TBM errors: 0
[16:14:02.644] <TB3> INFO: flawed TBM headers: 0
[16:14:02.644] <TB3> INFO: flawed TBM trailers: 0
[16:14:02.644] <TB3> INFO: event ID mismatches: 0
[16:14:02.644] <TB3> INFO: ROC errors: 0
[16:14:02.644] <TB3> INFO: missing ROC header(s): 0
[16:14:02.644] <TB3> INFO: misplaced readback start: 0
[16:14:02.644] <TB3> INFO: Pixel decoding errors: 0
[16:14:02.644] <TB3> INFO: pixel data incomplete: 0
[16:14:02.644] <TB3> INFO: pixel address: 0
[16:14:02.644] <TB3> INFO: pulse height fill bit: 0
[16:14:02.644] <TB3> INFO: buffer corruption: 0
[16:14:02.644] <TB3> INFO: enter test to run
[16:14:02.644] <TB3> INFO: test: exit no parameter change
[16:14:02.765] <TB3> QUIET: Connection to board 126 closed.
[16:14:02.765] <TB3> INFO: pXar: this is the end, my friend