[14:50:55.795] <TB3> INFO: *** Welcome to pxar ***
[14:50:55.795] <TB3> INFO: *** Today: 2016/10/17
[14:50:55.803] <TB3> INFO: *** Version: c8ba-dirty
[14:50:55.803] <TB3> INFO: readRocDacs: /home/l_tester/david/FullQualification/data/M1012_FullQualification_2016-10-17_13h57m_1476705426//001_Fulltest_m20//dacParameters_C0.dat .. /home/l_tester/david/FullQualification/data/M1012_FullQualification_2016-10-17_13h57m_1476705426//001_Fulltest_m20//dacParameters_C15.dat
[14:50:55.803] <TB3> INFO: readTbmDacs: /home/l_tester/david/FullQualification/data/M1012_FullQualification_2016-10-17_13h57m_1476705426//001_Fulltest_m20//tbmParameters_C0a.dat .. /home/l_tester/david/FullQualification/data/M1012_FullQualification_2016-10-17_13h57m_1476705426//001_Fulltest_m20//tbmParameters_C1b.dat
[14:50:55.803] <TB3> INFO: readMaskFile: /home/l_tester/david/FullQualification/data/M1012_FullQualification_2016-10-17_13h57m_1476705426//001_Fulltest_m20//defaultMaskFile.dat
[14:50:55.803] <TB3> INFO: readTrimFile: /home/l_tester/david/FullQualification/data/M1012_FullQualification_2016-10-17_13h57m_1476705426//001_Fulltest_m20//trimParameters_C0.dat .. /home/l_tester/david/FullQualification/data/M1012_FullQualification_2016-10-17_13h57m_1476705426//001_Fulltest_m20//trimParameters_C15.dat
[14:50:55.868] <TB3> INFO: clk: 4
[14:50:55.868] <TB3> INFO: ctr: 4
[14:50:55.868] <TB3> INFO: sda: 19
[14:50:55.868] <TB3> INFO: tin: 9
[14:50:55.868] <TB3> INFO: level: 15
[14:50:55.868] <TB3> INFO: triggerdelay: 0
[14:50:55.868] <TB3> QUIET: Instanciating API for pxar v2.7.6+55~gafdbfd9
[14:50:55.868] <TB3> INFO: Log level: INFO
[14:50:55.876] <TB3> INFO: Found DTB DTB_WWVASW
[14:50:55.885] <TB3> QUIET: Connection to board DTB_WWVASW opened.
[14:50:55.887] <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:50:55.889] <TB3> INFO: RPC call hashes of host and DTB match: 486171790
[14:50:57.386] <TB3> INFO: DUT info:
[14:50:57.386] <TB3> INFO: The DUT currently contains the following objects:
[14:50:57.386] <TB3> INFO: 4 TBM Cores tbm10c (4 ON)
[14:50:57.386] <TB3> INFO: TBM Core alpha (0): 7 registers set
[14:50:57.387] <TB3> INFO: TBM Core beta (1): 7 registers set
[14:50:57.387] <TB3> INFO: TBM Core alpha (2): 7 registers set
[14:50:57.387] <TB3> INFO: TBM Core beta (3): 7 registers set
[14:50:57.387] <TB3> INFO: 16 ROCs proc600 (16 ON) with 4160 pixelConfigs
[14:50:57.387] <TB3> INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[14:50:57.387] <TB3> INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[14:50:57.387] <TB3> INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[14:50:57.387] <TB3> INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[14:50:57.387] <TB3> INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[14:50:57.387] <TB3> INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[14:50:57.387] <TB3> INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[14:50:57.387] <TB3> INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[14:50:57.387] <TB3> INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[14:50:57.387] <TB3> INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[14:50:57.387] <TB3> INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[14:50:57.387] <TB3> INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[14:50:57.387] <TB3> INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[14:50:57.387] <TB3> INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[14:50:57.387] <TB3> INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[14:50:57.387] <TB3> INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[14:50:57.788] <TB3> INFO: enter 'restricted' command line mode
[14:50:57.788] <TB3> INFO: enter test to run
[14:50:57.788] <TB3> INFO: test: pretest no parameter change
[14:50:57.788] <TB3> INFO: running: pretest
[14:50:57.797] <TB3> INFO: ######################################################################
[14:50:57.797] <TB3> INFO: PixTestPretest::doTest()
[14:50:57.797] <TB3> INFO: ######################################################################
[14:50:57.798] <TB3> INFO: ----------------------------------------------------------------------
[14:50:57.798] <TB3> INFO: PixTestPretest::programROC()
[14:50:57.798] <TB3> INFO: ----------------------------------------------------------------------
[14:51:15.813] <TB3> INFO: PixTestPretest::programROC() done: ROCs are all programmable
[14:51:15.813] <TB3> INFO: IA differences per ROC: 20.9 18.5 16.1 19.3 16.9 20.1 19.3 19.3 20.1 19.3 19.3 20.1 19.3 21.7 21.7 20.1
[14:51:15.876] <TB3> INFO: ----------------------------------------------------------------------
[14:51:15.876] <TB3> INFO: PixTestPretest::setVana() target Ia = 24 mA/ROC
[14:51:15.876] <TB3> INFO: ----------------------------------------------------------------------
[14:51:21.571] <TB3> INFO: PixTestPretest::setVana() done, Module Ia 385.1 mA = 24.0688 mA/ROC
[14:51:21.571] <TB3> INFO: i(loss) [mA/ROC]: 18.5 18.5 19.3 20.1 18.5 19.3 18.5 18.5 18.5 19.3 19.3 18.5 18.5 19.3 18.5 18.5
[14:51:21.607] <TB3> INFO: ----------------------------------------------------------------------
[14:51:21.608] <TB3> INFO: PixTestPretest::findTiming()
[14:51:21.608] <TB3> INFO: ----------------------------------------------------------------------
[14:51:21.608] <TB3> INFO: PixTestCmd::init()

[14:51:53.773] <TB3> INFO: TBM phases: 160MHz: 7, 400MHz: 6, TBM delays: ROC(0/1):5, header/trailer: 1, token: 1
[14:51:53.773] <TB3> INFO: (success/tries = 100/100), width = 4
[14:51:55.278] <TB3> INFO: ----------------------------------------------------------------------
[14:51:55.278] <TB3> INFO: PixTestPretest::findWorkingPixel()
[14:51:55.278] <TB3> INFO: ----------------------------------------------------------------------
[14:51:55.373] <TB3> INFO: Expecting 231680 events.
[14:52:05.351] <TB3> INFO: 231680 events read in total (9386ms).
[14:52:05.358] <TB3> INFO: Test took 10075ms.
[14:52:05.606] <TB3> INFO: Found working pixel in all ROCs: col/row = 12/22
[14:52:05.643] <TB3> INFO: ----------------------------------------------------------------------
[14:52:05.643] <TB3> INFO: PixTestPretest::setVthrCompCalDel()
[14:52:05.643] <TB3> INFO: ----------------------------------------------------------------------
[14:52:05.737] <TB3> INFO: Expecting 231680 events.
[14:52:15.607] <TB3> INFO: 231680 events read in total (9279ms).
[14:52:15.615] <TB3> INFO: Test took 9967ms.
[14:52:15.869] <TB3> INFO: PixTestPretest::setVthrCompCalDel() done
[14:52:15.869] <TB3> INFO: CalDel: 88 102 111 96 94 105 106 92 81 95 86 86 102 98 100 95
[14:52:15.869] <TB3> INFO: VthrComp: 51 51 51 51 51 51 51 51 51 51 51 51 51 51 51 51
[14:52:15.872] <TB3> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1012_FullQualification_2016-10-17_13h57m_1476705426//001_Fulltest_m20//dacParameters_C0.dat
[14:52:15.872] <TB3> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1012_FullQualification_2016-10-17_13h57m_1476705426//001_Fulltest_m20//dacParameters_C1.dat
[14:52:15.873] <TB3> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1012_FullQualification_2016-10-17_13h57m_1476705426//001_Fulltest_m20//dacParameters_C2.dat
[14:52:15.873] <TB3> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1012_FullQualification_2016-10-17_13h57m_1476705426//001_Fulltest_m20//dacParameters_C3.dat
[14:52:15.873] <TB3> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1012_FullQualification_2016-10-17_13h57m_1476705426//001_Fulltest_m20//dacParameters_C4.dat
[14:52:15.873] <TB3> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1012_FullQualification_2016-10-17_13h57m_1476705426//001_Fulltest_m20//dacParameters_C5.dat
[14:52:15.873] <TB3> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1012_FullQualification_2016-10-17_13h57m_1476705426//001_Fulltest_m20//dacParameters_C6.dat
[14:52:15.873] <TB3> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1012_FullQualification_2016-10-17_13h57m_1476705426//001_Fulltest_m20//dacParameters_C7.dat
[14:52:15.874] <TB3> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1012_FullQualification_2016-10-17_13h57m_1476705426//001_Fulltest_m20//dacParameters_C8.dat
[14:52:15.874] <TB3> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1012_FullQualification_2016-10-17_13h57m_1476705426//001_Fulltest_m20//dacParameters_C9.dat
[14:52:15.874] <TB3> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1012_FullQualification_2016-10-17_13h57m_1476705426//001_Fulltest_m20//dacParameters_C10.dat
[14:52:15.874] <TB3> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1012_FullQualification_2016-10-17_13h57m_1476705426//001_Fulltest_m20//dacParameters_C11.dat
[14:52:15.874] <TB3> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1012_FullQualification_2016-10-17_13h57m_1476705426//001_Fulltest_m20//dacParameters_C12.dat
[14:52:15.874] <TB3> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1012_FullQualification_2016-10-17_13h57m_1476705426//001_Fulltest_m20//dacParameters_C13.dat
[14:52:15.875] <TB3> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1012_FullQualification_2016-10-17_13h57m_1476705426//001_Fulltest_m20//dacParameters_C14.dat
[14:52:15.875] <TB3> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1012_FullQualification_2016-10-17_13h57m_1476705426//001_Fulltest_m20//dacParameters_C15.dat
[14:52:15.875] <TB3> INFO: write tbm parameters into /home/l_tester/david/FullQualification/data/M1012_FullQualification_2016-10-17_13h57m_1476705426//001_Fulltest_m20//tbmParameters_C0a.dat
[14:52:15.875] <TB3> INFO: write tbm parameters into /home/l_tester/david/FullQualification/data/M1012_FullQualification_2016-10-17_13h57m_1476705426//001_Fulltest_m20//tbmParameters_C0b.dat
[14:52:15.875] <TB3> INFO: write tbm parameters into /home/l_tester/david/FullQualification/data/M1012_FullQualification_2016-10-17_13h57m_1476705426//001_Fulltest_m20//tbmParameters_C1a.dat
[14:52:15.875] <TB3> INFO: write tbm parameters into /home/l_tester/david/FullQualification/data/M1012_FullQualification_2016-10-17_13h57m_1476705426//001_Fulltest_m20//tbmParameters_C1b.dat
[14:52:15.875] <TB3> INFO: PixTestPretest::doTest() done, duration: 78 seconds
[14:52:15.929] <TB3> INFO: enter test to run
[14:52:15.929] <TB3> INFO: test: FullTest no parameter change
[14:52:15.929] <TB3> INFO: running: fulltest
[14:52:15.929] <TB3> INFO: ######################################################################
[14:52:15.929] <TB3> INFO: PixTestFullTest::doTest()
[14:52:15.929] <TB3> INFO: ######################################################################
[14:52:15.930] <TB3> INFO: ######################################################################
[14:52:15.930] <TB3> INFO: PixTestAlive::doTest()
[14:52:15.930] <TB3> INFO: ######################################################################
[14:52:15.931] <TB3> INFO: ----------------------------------------------------------------------
[14:52:15.931] <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:52:15.931] <TB3> INFO: ----------------------------------------------------------------------
[14:52:16.213] <TB3> INFO: Expecting 41600 events.
[14:52:19.723] <TB3> INFO: 41600 events read in total (2918ms).
[14:52:19.724] <TB3> INFO: Test took 3792ms.
[14:52:19.956] <TB3> INFO: PixTestAlive::aliveTest() done
[14:52:19.956] <TB3> INFO: number of dead pixels (per ROC): 1 0 0 1 0 0 0 0 0 1 0 0 0 0 0 0
[14:52:19.958] <TB3> INFO: ----------------------------------------------------------------------
[14:52:19.958] <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:52:19.958] <TB3> INFO: ----------------------------------------------------------------------
[14:52:20.202] <TB3> INFO: Expecting 41600 events.
[14:52:23.162] <TB3> INFO: 41600 events read in total (2368ms).
[14:52:23.163] <TB3> INFO: Test took 3203ms.
[14:52:23.163] <TB3> INFO: mask vs. old pixelAlive PixelAlive_C0_V0 .. PixelAlive_C15_V0
[14:52:23.404] <TB3> INFO: PixTestAlive::maskTest() done
[14:52:23.404] <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:52:23.405] <TB3> INFO: ----------------------------------------------------------------------
[14:52:23.405] <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:52:23.405] <TB3> INFO: ----------------------------------------------------------------------
[14:52:23.649] <TB3> INFO: Expecting 41600 events.
[14:52:27.271] <TB3> INFO: 41600 events read in total (3031ms).
[14:52:27.272] <TB3> INFO: Test took 3865ms.
[14:52:27.506] <TB3> INFO: PixTestAlive::addressDecodingTest() done
[14:52:27.506] <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:52:27.507] <TB3> INFO: PixTestAlive::doTest() done, duration: 11 seconds
[14:52:27.507] <TB3> INFO: Decoding statistics:
[14:52:27.507] <TB3> INFO: General information:
[14:52:27.507] <TB3> INFO: 16bit words read: 0
[14:52:27.507] <TB3> INFO: valid events total: 0
[14:52:27.507] <TB3> INFO: empty events: 0
[14:52:27.507] <TB3> INFO: valid events with pixels: 0
[14:52:27.507] <TB3> INFO: valid pixel hits: 0
[14:52:27.507] <TB3> INFO: Event errors: 0
[14:52:27.507] <TB3> INFO: start marker: 0
[14:52:27.507] <TB3> INFO: stop marker: 0
[14:52:27.507] <TB3> INFO: overflow: 0
[14:52:27.507] <TB3> INFO: invalid 5bit words: 0
[14:52:27.507] <TB3> INFO: invalid XOR eye diagram: 0
[14:52:27.507] <TB3> INFO: frame (failed synchr.): 0
[14:52:27.507] <TB3> INFO: idle data (no TBM trl): 0
[14:52:27.507] <TB3> INFO: no data (only TBM hdr): 0
[14:52:27.507] <TB3> INFO: TBM errors: 0
[14:52:27.507] <TB3> INFO: flawed TBM headers: 0
[14:52:27.507] <TB3> INFO: flawed TBM trailers: 0
[14:52:27.507] <TB3> INFO: event ID mismatches: 0
[14:52:27.507] <TB3> INFO: ROC errors: 0
[14:52:27.507] <TB3> INFO: missing ROC header(s): 0
[14:52:27.507] <TB3> INFO: misplaced readback start: 0
[14:52:27.507] <TB3> INFO: Pixel decoding errors: 0
[14:52:27.507] <TB3> INFO: pixel data incomplete: 0
[14:52:27.507] <TB3> INFO: pixel address: 0
[14:52:27.507] <TB3> INFO: pulse height fill bit: 0
[14:52:27.507] <TB3> INFO: buffer corruption: 0
[14:52:27.514] <TB3> INFO: readReadbackCal: /home/l_tester/david/FullQualification/data/M1012_FullQualification_2016-10-17_13h57m_1476705426//001_Fulltest_m20//readbackCal_C0.dat .. /home/l_tester/david/FullQualification/data/M1012_FullQualification_2016-10-17_13h57m_1476705426//001_Fulltest_m20//readbackCal_C15.dat
[14:52:27.514] <TB3> INFO: readGainPedestalParameters /home/l_tester/david/FullQualification/data/M1012_FullQualification_2016-10-17_13h57m_1476705426//001_Fulltest_m20//phCalibrationFitErr_C0.dat .. /home/l_tester/david/FullQualification/data/M1012_FullQualification_2016-10-17_13h57m_1476705426//001_Fulltest_m20//phCalibrationFitErr_C15.dat

[14:52:27.514] <TB3> INFO: ######################################################################
[14:52:27.514] <TB3> INFO: PixTestReadback::doTest()
[14:52:27.514] <TB3> INFO: ######################################################################
[14:52:27.514] <TB3> INFO: ----------------------------------------------------------------------
[14:52:27.514] <TB3> INFO: PixTestReadback::CalibrateVd()
[14:52:27.514] <TB3> INFO: ----------------------------------------------------------------------
[14:52:37.492] <TB3> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1012_FullQualification_2016-10-17_13h57m_1476705426//001_Fulltest_m20//readbackCal_C0.dat
[14:52:37.493] <TB3> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1012_FullQualification_2016-10-17_13h57m_1476705426//001_Fulltest_m20//readbackCal_C1.dat
[14:52:37.493] <TB3> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1012_FullQualification_2016-10-17_13h57m_1476705426//001_Fulltest_m20//readbackCal_C2.dat
[14:52:37.493] <TB3> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1012_FullQualification_2016-10-17_13h57m_1476705426//001_Fulltest_m20//readbackCal_C3.dat
[14:52:37.493] <TB3> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1012_FullQualification_2016-10-17_13h57m_1476705426//001_Fulltest_m20//readbackCal_C4.dat
[14:52:37.493] <TB3> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1012_FullQualification_2016-10-17_13h57m_1476705426//001_Fulltest_m20//readbackCal_C5.dat
[14:52:37.493] <TB3> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1012_FullQualification_2016-10-17_13h57m_1476705426//001_Fulltest_m20//readbackCal_C6.dat
[14:52:37.493] <TB3> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1012_FullQualification_2016-10-17_13h57m_1476705426//001_Fulltest_m20//readbackCal_C7.dat
[14:52:37.493] <TB3> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1012_FullQualification_2016-10-17_13h57m_1476705426//001_Fulltest_m20//readbackCal_C8.dat
[14:52:37.493] <TB3> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1012_FullQualification_2016-10-17_13h57m_1476705426//001_Fulltest_m20//readbackCal_C9.dat
[14:52:37.493] <TB3> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1012_FullQualification_2016-10-17_13h57m_1476705426//001_Fulltest_m20//readbackCal_C10.dat
[14:52:37.493] <TB3> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1012_FullQualification_2016-10-17_13h57m_1476705426//001_Fulltest_m20//readbackCal_C11.dat
[14:52:37.494] <TB3> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1012_FullQualification_2016-10-17_13h57m_1476705426//001_Fulltest_m20//readbackCal_C12.dat
[14:52:37.494] <TB3> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1012_FullQualification_2016-10-17_13h57m_1476705426//001_Fulltest_m20//readbackCal_C13.dat
[14:52:37.494] <TB3> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1012_FullQualification_2016-10-17_13h57m_1476705426//001_Fulltest_m20//readbackCal_C14.dat
[14:52:37.494] <TB3> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1012_FullQualification_2016-10-17_13h57m_1476705426//001_Fulltest_m20//readbackCal_C15.dat
[14:52:37.523] <TB3> INFO: PixTestPattern:: pg_setup set to default.
[14:52:37.523] <TB3> INFO: ----------------------------------------------------------------------
[14:52:37.523] <TB3> INFO: PixTestReadback::CalibrateVa()
[14:52:37.523] <TB3> INFO: ----------------------------------------------------------------------
[14:52:47.457] <TB3> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1012_FullQualification_2016-10-17_13h57m_1476705426//001_Fulltest_m20//readbackCal_C0.dat
[14:52:47.457] <TB3> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1012_FullQualification_2016-10-17_13h57m_1476705426//001_Fulltest_m20//readbackCal_C1.dat
[14:52:47.457] <TB3> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1012_FullQualification_2016-10-17_13h57m_1476705426//001_Fulltest_m20//readbackCal_C2.dat
[14:52:47.457] <TB3> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1012_FullQualification_2016-10-17_13h57m_1476705426//001_Fulltest_m20//readbackCal_C3.dat
[14:52:47.457] <TB3> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1012_FullQualification_2016-10-17_13h57m_1476705426//001_Fulltest_m20//readbackCal_C4.dat
[14:52:47.457] <TB3> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1012_FullQualification_2016-10-17_13h57m_1476705426//001_Fulltest_m20//readbackCal_C5.dat
[14:52:47.457] <TB3> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1012_FullQualification_2016-10-17_13h57m_1476705426//001_Fulltest_m20//readbackCal_C6.dat
[14:52:47.457] <TB3> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1012_FullQualification_2016-10-17_13h57m_1476705426//001_Fulltest_m20//readbackCal_C7.dat
[14:52:47.457] <TB3> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1012_FullQualification_2016-10-17_13h57m_1476705426//001_Fulltest_m20//readbackCal_C8.dat
[14:52:47.457] <TB3> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1012_FullQualification_2016-10-17_13h57m_1476705426//001_Fulltest_m20//readbackCal_C9.dat
[14:52:47.457] <TB3> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1012_FullQualification_2016-10-17_13h57m_1476705426//001_Fulltest_m20//readbackCal_C10.dat
[14:52:47.457] <TB3> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1012_FullQualification_2016-10-17_13h57m_1476705426//001_Fulltest_m20//readbackCal_C11.dat
[14:52:47.457] <TB3> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1012_FullQualification_2016-10-17_13h57m_1476705426//001_Fulltest_m20//readbackCal_C12.dat
[14:52:47.458] <TB3> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1012_FullQualification_2016-10-17_13h57m_1476705426//001_Fulltest_m20//readbackCal_C13.dat
[14:52:47.458] <TB3> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1012_FullQualification_2016-10-17_13h57m_1476705426//001_Fulltest_m20//readbackCal_C14.dat
[14:52:47.458] <TB3> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1012_FullQualification_2016-10-17_13h57m_1476705426//001_Fulltest_m20//readbackCal_C15.dat
[14:52:47.490] <TB3> INFO: PixTestPattern:: pg_setup set to default.
[14:52:47.490] <TB3> INFO: ----------------------------------------------------------------------
[14:52:47.490] <TB3> INFO: PixTestReadback::readbackVbg()
[14:52:47.490] <TB3> INFO: ----------------------------------------------------------------------
[14:52:55.163] <TB3> INFO: PixTestPattern:: pg_setup set to default.
[14:52:55.163] <TB3> INFO: ----------------------------------------------------------------------
[14:52:55.163] <TB3> INFO: PixTestReadback::getCalibratedVbg()
[14:52:55.163] <TB3> INFO: ----------------------------------------------------------------------
[14:52:55.163] <TB3> INFO: Vbg will be calibrated using Vd calibration
[14:52:55.164] <TB3> INFO: /*/*/*/*::: ROC 0: uncalibrated Vbg = 156.6calibrated Vbg = 1.19183 :::*/*/*/*/
[14:52:55.164] <TB3> INFO: /*/*/*/*::: ROC 1: uncalibrated Vbg = 158.6calibrated Vbg = 1.19782 :::*/*/*/*/
[14:52:55.164] <TB3> INFO: /*/*/*/*::: ROC 2: uncalibrated Vbg = 159.1calibrated Vbg = 1.18823 :::*/*/*/*/
[14:52:55.164] <TB3> INFO: /*/*/*/*::: ROC 3: uncalibrated Vbg = 156.2calibrated Vbg = 1.189 :::*/*/*/*/
[14:52:55.164] <TB3> INFO: /*/*/*/*::: ROC 4: uncalibrated Vbg = 153.6calibrated Vbg = 1.18879 :::*/*/*/*/
[14:52:55.164] <TB3> INFO: /*/*/*/*::: ROC 5: uncalibrated Vbg = 160.7calibrated Vbg = 1.19105 :::*/*/*/*/
[14:52:55.164] <TB3> INFO: /*/*/*/*::: ROC 6: uncalibrated Vbg = 151.2calibrated Vbg = 1.19993 :::*/*/*/*/
[14:52:55.164] <TB3> INFO: /*/*/*/*::: ROC 7: uncalibrated Vbg = 156.3calibrated Vbg = 1.19983 :::*/*/*/*/
[14:52:55.164] <TB3> INFO: /*/*/*/*::: ROC 8: uncalibrated Vbg = 155.4calibrated Vbg = 1.19156 :::*/*/*/*/
[14:52:55.164] <TB3> INFO: /*/*/*/*::: ROC 9: uncalibrated Vbg = 152.5calibrated Vbg = 1.19311 :::*/*/*/*/
[14:52:55.164] <TB3> INFO: /*/*/*/*::: ROC 10: uncalibrated Vbg = 148.9calibrated Vbg = 1.1882 :::*/*/*/*/
[14:52:55.164] <TB3> INFO: /*/*/*/*::: ROC 11: uncalibrated Vbg = 151.5calibrated Vbg = 1.17885 :::*/*/*/*/
[14:52:55.164] <TB3> INFO: /*/*/*/*::: ROC 12: uncalibrated Vbg = 156.1calibrated Vbg = 1.1929 :::*/*/*/*/
[14:52:55.164] <TB3> INFO: /*/*/*/*::: ROC 13: uncalibrated Vbg = 146.1calibrated Vbg = 1.19007 :::*/*/*/*/
[14:52:55.164] <TB3> INFO: /*/*/*/*::: ROC 14: uncalibrated Vbg = 151.4calibrated Vbg = 1.18665 :::*/*/*/*/
[14:52:55.164] <TB3> INFO: /*/*/*/*::: ROC 15: uncalibrated Vbg = 156.9calibrated Vbg = 1.19289 :::*/*/*/*/
[14:52:55.167] <TB3> INFO: ----------------------------------------------------------------------
[14:52:55.167] <TB3> INFO: PixTestReadback::CalibrateIa()
[14:52:55.167] <TB3> INFO: ----------------------------------------------------------------------
[14:55:35.988] <TB3> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1012_FullQualification_2016-10-17_13h57m_1476705426//001_Fulltest_m20//readbackCal_C0.dat
[14:55:35.989] <TB3> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1012_FullQualification_2016-10-17_13h57m_1476705426//001_Fulltest_m20//readbackCal_C1.dat
[14:55:35.989] <TB3> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1012_FullQualification_2016-10-17_13h57m_1476705426//001_Fulltest_m20//readbackCal_C2.dat
[14:55:35.989] <TB3> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1012_FullQualification_2016-10-17_13h57m_1476705426//001_Fulltest_m20//readbackCal_C3.dat
[14:55:35.989] <TB3> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1012_FullQualification_2016-10-17_13h57m_1476705426//001_Fulltest_m20//readbackCal_C4.dat
[14:55:35.989] <TB3> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1012_FullQualification_2016-10-17_13h57m_1476705426//001_Fulltest_m20//readbackCal_C5.dat
[14:55:35.989] <TB3> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1012_FullQualification_2016-10-17_13h57m_1476705426//001_Fulltest_m20//readbackCal_C6.dat
[14:55:35.989] <TB3> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1012_FullQualification_2016-10-17_13h57m_1476705426//001_Fulltest_m20//readbackCal_C7.dat
[14:55:35.989] <TB3> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1012_FullQualification_2016-10-17_13h57m_1476705426//001_Fulltest_m20//readbackCal_C8.dat
[14:55:35.989] <TB3> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1012_FullQualification_2016-10-17_13h57m_1476705426//001_Fulltest_m20//readbackCal_C9.dat
[14:55:35.989] <TB3> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1012_FullQualification_2016-10-17_13h57m_1476705426//001_Fulltest_m20//readbackCal_C10.dat
[14:55:35.989] <TB3> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1012_FullQualification_2016-10-17_13h57m_1476705426//001_Fulltest_m20//readbackCal_C11.dat
[14:55:35.989] <TB3> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1012_FullQualification_2016-10-17_13h57m_1476705426//001_Fulltest_m20//readbackCal_C12.dat
[14:55:35.989] <TB3> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1012_FullQualification_2016-10-17_13h57m_1476705426//001_Fulltest_m20//readbackCal_C13.dat
[14:55:35.989] <TB3> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1012_FullQualification_2016-10-17_13h57m_1476705426//001_Fulltest_m20//readbackCal_C14.dat
[14:55:35.989] <TB3> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1012_FullQualification_2016-10-17_13h57m_1476705426//001_Fulltest_m20//readbackCal_C15.dat
[14:55:36.018] <TB3> INFO: PixTestPattern:: pg_setup set to default.
[14:55:36.019] <TB3> INFO: PixTestReadback::doTest() done
[14:55:36.020] <TB3> INFO: Decoding statistics:
[14:55:36.020] <TB3> INFO: General information:
[14:55:36.020] <TB3> INFO: 16bit words read: 1536
[14:55:36.020] <TB3> INFO: valid events total: 256
[14:55:36.020] <TB3> INFO: empty events: 256
[14:55:36.020] <TB3> INFO: valid events with pixels: 0
[14:55:36.020] <TB3> INFO: valid pixel hits: 0
[14:55:36.020] <TB3> INFO: Event errors: 0
[14:55:36.020] <TB3> INFO: start marker: 0
[14:55:36.020] <TB3> INFO: stop marker: 0
[14:55:36.020] <TB3> INFO: overflow: 0
[14:55:36.020] <TB3> INFO: invalid 5bit words: 0
[14:55:36.020] <TB3> INFO: invalid XOR eye diagram: 0
[14:55:36.020] <TB3> INFO: frame (failed synchr.): 0
[14:55:36.020] <TB3> INFO: idle data (no TBM trl): 0
[14:55:36.020] <TB3> INFO: no data (only TBM hdr): 0
[14:55:36.020] <TB3> INFO: TBM errors: 0
[14:55:36.020] <TB3> INFO: flawed TBM headers: 0
[14:55:36.020] <TB3> INFO: flawed TBM trailers: 0
[14:55:36.020] <TB3> INFO: event ID mismatches: 0
[14:55:36.020] <TB3> INFO: ROC errors: 0
[14:55:36.020] <TB3> INFO: missing ROC header(s): 0
[14:55:36.020] <TB3> INFO: misplaced readback start: 0
[14:55:36.020] <TB3> INFO: Pixel decoding errors: 0
[14:55:36.020] <TB3> INFO: pixel data incomplete: 0
[14:55:36.020] <TB3> INFO: pixel address: 0
[14:55:36.020] <TB3> INFO: pulse height fill bit: 0
[14:55:36.020] <TB3> INFO: buffer corruption: 0
[14:55:36.066] <TB3> INFO: ######################################################################
[14:55:36.066] <TB3> INFO: PixTestBBMap::doTest() Ntrig = 5, VcalS = 250 (high range)
[14:55:36.066] <TB3> INFO: ######################################################################
[14:55:36.069] <TB3> INFO: ---> dac: VthrComp name: calSMap ntrig: 5 dacrange: 0 .. 149 (-1/-1) hits flags = 514 (plus default)
[14:55:36.090] <TB3> INFO: dacScan split into 1 runs with ntrig = 5
[14:55:36.091] <TB3> INFO: run 1 of 1
[14:55:36.328] <TB3> INFO: Expecting 3120000 events.
[14:56:07.291] <TB3> INFO: 663735 events read in total (30371ms).











[14:56:37.857] <TB3> INFO: 1325075 events read in total (60937ms).











[14:57:08.036] <TB3> INFO: 1984455 events read in total (91116ms).











[14:57:38.304] <TB3> INFO: 2643085 events read in total (121384ms).











[14:58:00.964] <TB3> INFO: 3120000 events read in total (144044ms).
[14:58:01.066] <TB3> INFO: Test took 144976ms.
[14:58:27.604] <TB3> INFO: PixTestBBMap::doTest() done with 2 decoding errors: , duration: 171 seconds
[14:58:27.604] <TB3> INFO: number of dead bumps (per ROC): 0 0 0 1 0 0 0 0 0 1 0 0 0 37 0 0
[14:58:27.604] <TB3> INFO: separation cut (per ROC): 111 101 107 105 99 105 95 110 104 101 98 98 92 102 98 110
[14:58:27.604] <TB3> INFO: Decoding statistics:
[14:58:27.604] <TB3> INFO: General information:
[14:58:27.604] <TB3> INFO: 16bit words read: 0
[14:58:27.604] <TB3> INFO: valid events total: 0
[14:58:27.604] <TB3> INFO: empty events: 0
[14:58:27.604] <TB3> INFO: valid events with pixels: 0
[14:58:27.604] <TB3> INFO: valid pixel hits: 0
[14:58:27.604] <TB3> INFO: Event errors: 0
[14:58:27.604] <TB3> INFO: start marker: 0
[14:58:27.604] <TB3> INFO: stop marker: 0
[14:58:27.604] <TB3> INFO: overflow: 0
[14:58:27.604] <TB3> INFO: invalid 5bit words: 0
[14:58:27.604] <TB3> INFO: invalid XOR eye diagram: 0
[14:58:27.604] <TB3> INFO: frame (failed synchr.): 0
[14:58:27.604] <TB3> INFO: idle data (no TBM trl): 0
[14:58:27.604] <TB3> INFO: no data (only TBM hdr): 0
[14:58:27.604] <TB3> INFO: TBM errors: 0
[14:58:27.604] <TB3> INFO: flawed TBM headers: 0
[14:58:27.604] <TB3> INFO: flawed TBM trailers: 0
[14:58:27.604] <TB3> INFO: event ID mismatches: 0
[14:58:27.604] <TB3> INFO: ROC errors: 0
[14:58:27.604] <TB3> INFO: missing ROC header(s): 0
[14:58:27.604] <TB3> INFO: misplaced readback start: 0
[14:58:27.604] <TB3> INFO: Pixel decoding errors: 0
[14:58:27.604] <TB3> INFO: pixel data incomplete: 0
[14:58:27.604] <TB3> INFO: pixel address: 0
[14:58:27.604] <TB3> INFO: pulse height fill bit: 0
[14:58:27.604] <TB3> INFO: buffer corruption: 0
[14:58:27.643] <TB3> INFO: ######################################################################
[14:58:27.643] <TB3> INFO: PixTestScurves::fullTest() ntrig = 50, dacs/step = -1, ntrig/step = -1
[14:58:27.643] <TB3> INFO: ######################################################################
[14:58:27.643] <TB3> INFO: ----------------------------------------------------------------------
[14:58:27.643] <TB3> INFO: PixTestScurves::scurves(Vcal), ntrig = 50, dacs/step = -1, ntrig/step = -1
[14:58:27.643] <TB3> INFO: ----------------------------------------------------------------------
[14:58:27.643] <TB3> INFO: ---> dac: Vcal name: scurveVcal ntrig: 50 dacrange: 25 .. 200 (-1/-1) hits flags = 528 (plus default)
[14:58:27.655] <TB3> INFO: dacScan split into 1 runs with ntrig = 50
[14:58:27.655] <TB3> INFO: run 1 of 1
[14:58:27.892] <TB3> INFO: Expecting 36608000 events.
[14:58:51.260] <TB3> INFO: 648050 events read in total (22777ms).
[14:59:13.974] <TB3> INFO: 1288350 events read in total (45491ms).
[14:59:36.402] <TB3> INFO: 1924650 events read in total (67920ms).
[14:59:58.795] <TB3> INFO: 2559500 events read in total (90312ms).
[15:00:21.566] <TB3> INFO: 3195100 events read in total (113083ms).
[15:00:44.415] <TB3> INFO: 3831150 events read in total (135932ms).
[15:01:06.604] <TB3> INFO: 4467750 events read in total (158121ms).
[15:01:29.132] <TB3> INFO: 5103050 events read in total (180649ms).
[15:01:51.863] <TB3> INFO: 5740050 events read in total (203380ms).
[15:02:14.610] <TB3> INFO: 6376750 events read in total (226127ms).
[15:02:37.408] <TB3> INFO: 7013750 events read in total (248925ms).
[15:02:59.861] <TB3> INFO: 7647900 events read in total (271378ms).
[15:03:22.400] <TB3> INFO: 8285250 events read in total (293917ms).
[15:03:45.383] <TB3> INFO: 8921100 events read in total (316900ms).
[15:04:07.989] <TB3> INFO: 9556200 events read in total (339506ms).
[15:04:30.605] <TB3> INFO: 10192600 events read in total (362122ms).
[15:04:53.167] <TB3> INFO: 10827450 events read in total (384684ms).
[15:05:16.051] <TB3> INFO: 11463300 events read in total (407568ms).
[15:05:38.794] <TB3> INFO: 12096100 events read in total (430311ms).
[15:06:00.920] <TB3> INFO: 12728600 events read in total (452437ms).
[15:06:23.139] <TB3> INFO: 13359400 events read in total (474656ms).
[15:06:45.804] <TB3> INFO: 13991400 events read in total (497321ms).
[15:07:08.362] <TB3> INFO: 14625050 events read in total (519879ms).
[15:07:31.199] <TB3> INFO: 15258400 events read in total (542716ms).
[15:07:53.925] <TB3> INFO: 15891400 events read in total (565442ms).
[15:08:16.772] <TB3> INFO: 16524250 events read in total (588289ms).
[15:08:39.733] <TB3> INFO: 17156800 events read in total (611250ms).
[15:09:02.868] <TB3> INFO: 17788800 events read in total (634385ms).
[15:09:25.623] <TB3> INFO: 18420950 events read in total (657140ms).
[15:09:48.507] <TB3> INFO: 19049350 events read in total (680024ms).
[15:10:11.325] <TB3> INFO: 19678500 events read in total (702842ms).
[15:10:33.864] <TB3> INFO: 20306400 events read in total (725381ms).
[15:10:56.427] <TB3> INFO: 20933900 events read in total (747945ms).
[15:11:19.195] <TB3> INFO: 21562400 events read in total (770712ms).
[15:11:41.541] <TB3> INFO: 22191200 events read in total (793058ms).
[15:12:03.840] <TB3> INFO: 22820600 events read in total (815357ms).
[15:12:26.300] <TB3> INFO: 23448450 events read in total (837817ms).
[15:12:48.696] <TB3> INFO: 24075700 events read in total (860213ms).
[15:13:10.990] <TB3> INFO: 24702200 events read in total (882507ms).
[15:13:33.578] <TB3> INFO: 25331500 events read in total (905095ms).
[15:13:55.880] <TB3> INFO: 25958150 events read in total (927397ms).
[15:14:18.147] <TB3> INFO: 26588200 events read in total (949664ms).
[15:14:40.725] <TB3> INFO: 27214350 events read in total (972242ms).
[15:15:03.274] <TB3> INFO: 27840800 events read in total (994791ms).
[15:15:25.532] <TB3> INFO: 28467550 events read in total (1017049ms).
[15:15:48.120] <TB3> INFO: 29093200 events read in total (1039637ms).
[15:16:10.676] <TB3> INFO: 29720250 events read in total (1062193ms).
[15:16:33.324] <TB3> INFO: 30344400 events read in total (1084841ms).
[15:16:55.982] <TB3> INFO: 30971800 events read in total (1107499ms).
[15:17:18.500] <TB3> INFO: 31595500 events read in total (1130017ms).
[15:17:41.041] <TB3> INFO: 32220700 events read in total (1152558ms).
[15:18:03.128] <TB3> INFO: 32846400 events read in total (1174645ms).
[15:18:25.616] <TB3> INFO: 33473000 events read in total (1197133ms).
[15:18:48.230] <TB3> INFO: 34100500 events read in total (1219747ms).
[15:19:10.401] <TB3> INFO: 34728150 events read in total (1241918ms).
[15:19:32.616] <TB3> INFO: 35356650 events read in total (1264133ms).
[15:19:55.614] <TB3> INFO: 35985200 events read in total (1287131ms).
[15:20:17.784] <TB3> INFO: 36608000 events read in total (1309301ms).
[15:20:17.884] <TB3> INFO: Test took 1310228ms.
[15:20:18.519] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[15:20:20.625] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[15:20:22.683] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[15:20:24.745] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[15:20:26.977] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[15:20:28.644] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[15:20:31.162] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[15:20:33.656] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[15:20:36.146] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[15:20:37.727] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[15:20:39.222] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[15:20:40.721] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[15:20:42.399] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[15:20:43.959] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[15:20:45.637] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[15:20:47.307] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[15:20:49.182] <TB3> INFO: PixTestScurves::scurves() done
[15:20:49.182] <TB3> INFO: Vcal mean: 110.33 104.19 110.61 108.16 97.52 106.02 96.97 110.75 104.58 98.33 92.45 95.12 99.89 103.70 98.26 109.95
[15:20:49.182] <TB3> INFO: Vcal RMS: 5.24 5.39 4.54 5.18 4.84 4.94 4.91 4.94 5.47 5.53 5.50 5.07 5.05 5.01 5.22 5.21
[15:20:49.182] <TB3> INFO: PixTestScurves::fullTest() done, duration: 1341 seconds
[15:20:49.182] <TB3> INFO: Decoding statistics:
[15:20:49.182] <TB3> INFO: General information:
[15:20:49.182] <TB3> INFO: 16bit words read: 0
[15:20:49.182] <TB3> INFO: valid events total: 0
[15:20:49.182] <TB3> INFO: empty events: 0
[15:20:49.182] <TB3> INFO: valid events with pixels: 0
[15:20:49.182] <TB3> INFO: valid pixel hits: 0
[15:20:49.182] <TB3> INFO: Event errors: 0
[15:20:49.182] <TB3> INFO: start marker: 0
[15:20:49.182] <TB3> INFO: stop marker: 0
[15:20:49.182] <TB3> INFO: overflow: 0
[15:20:49.182] <TB3> INFO: invalid 5bit words: 0
[15:20:49.182] <TB3> INFO: invalid XOR eye diagram: 0
[15:20:49.182] <TB3> INFO: frame (failed synchr.): 0
[15:20:49.182] <TB3> INFO: idle data (no TBM trl): 0
[15:20:49.182] <TB3> INFO: no data (only TBM hdr): 0
[15:20:49.182] <TB3> INFO: TBM errors: 0
[15:20:49.182] <TB3> INFO: flawed TBM headers: 0
[15:20:49.182] <TB3> INFO: flawed TBM trailers: 0
[15:20:49.182] <TB3> INFO: event ID mismatches: 0
[15:20:49.182] <TB3> INFO: ROC errors: 0
[15:20:49.182] <TB3> INFO: missing ROC header(s): 0
[15:20:49.182] <TB3> INFO: misplaced readback start: 0
[15:20:49.182] <TB3> INFO: Pixel decoding errors: 0
[15:20:49.182] <TB3> INFO: pixel data incomplete: 0
[15:20:49.182] <TB3> INFO: pixel address: 0
[15:20:49.182] <TB3> INFO: pulse height fill bit: 0
[15:20:49.182] <TB3> INFO: buffer corruption: 0
[15:20:49.247] <TB3> INFO: ######################################################################
[15:20:49.247] <TB3> INFO: PixTestTrim::doTest()
[15:20:49.247] <TB3> INFO: ######################################################################
[15:20:49.248] <TB3> INFO: ----------------------------------------------------------------------
[15:20:49.248] <TB3> INFO: PixTestTrim::trimTest() ntrig = 8, vcal = 35
[15:20:49.248] <TB3> INFO: ----------------------------------------------------------------------
[15:20:49.288] <TB3> INFO: ---> VthrComp thr map (minimal VthrComp)
[15:20:49.289] <TB3> INFO: ---> dac: vthrcomp name: TrimThr0 ntrig: 8 dacrange: 10 .. 160 (-1/-1) hits flags = 528 (plus default)
[15:20:49.300] <TB3> INFO: dacScan split into 1 runs with ntrig = 8
[15:20:49.300] <TB3> INFO: run 1 of 1
[15:20:49.537] <TB3> INFO: Expecting 5025280 events.
[15:21:20.332] <TB3> INFO: 813152 events read in total (30183ms).
[15:21:50.281] <TB3> INFO: 1622240 events read in total (60132ms).
[15:22:20.337] <TB3> INFO: 2427864 events read in total (90189ms).
[15:22:50.387] <TB3> INFO: 3230608 events read in total (120238ms).
[15:23:19.631] <TB3> INFO: 4029664 events read in total (149483ms).
[15:23:50.463] <TB3> INFO: 4827016 events read in total (180314ms).
[15:23:57.994] <TB3> INFO: 5025280 events read in total (187845ms).
[15:23:58.108] <TB3> INFO: Test took 188807ms.
[15:24:15.719] <TB3> INFO: ROC 0 VthrComp = 125
[15:24:15.719] <TB3> INFO: ROC 1 VthrComp = 111
[15:24:15.719] <TB3> INFO: ROC 2 VthrComp = 119
[15:24:15.719] <TB3> INFO: ROC 3 VthrComp = 117
[15:24:15.719] <TB3> INFO: ROC 4 VthrComp = 106
[15:24:15.720] <TB3> INFO: ROC 5 VthrComp = 115
[15:24:15.720] <TB3> INFO: ROC 6 VthrComp = 104
[15:24:15.720] <TB3> INFO: ROC 7 VthrComp = 123
[15:24:15.720] <TB3> INFO: ROC 8 VthrComp = 115
[15:24:15.721] <TB3> INFO: ROC 9 VthrComp = 109
[15:24:15.721] <TB3> INFO: ROC 10 VthrComp = 101
[15:24:15.721] <TB3> INFO: ROC 11 VthrComp = 103
[15:24:15.721] <TB3> INFO: ROC 12 VthrComp = 105
[15:24:15.721] <TB3> INFO: ROC 13 VthrComp = 110
[15:24:15.721] <TB3> INFO: ROC 14 VthrComp = 107
[15:24:15.721] <TB3> INFO: ROC 15 VthrComp = 120
[15:24:15.721] <TB3> INFO: ---> Vcal thr map (pixel with maximum Vcal thr)
[15:24:15.721] <TB3> INFO: ---> dac: vcal name: TrimThr1 ntrig: 8 dacrange: 10 .. 160 (-1/-1) hits flags = 528 (plus default)
[15:24:15.735] <TB3> INFO: dacScan split into 1 runs with ntrig = 8
[15:24:15.735] <TB3> INFO: run 1 of 1
[15:24:16.005] <TB3> INFO: Expecting 5025280 events.
[15:24:42.286] <TB3> INFO: 588528 events read in total (25690ms).
[15:25:07.863] <TB3> INFO: 1177072 events read in total (51267ms).
[15:25:33.959] <TB3> INFO: 1765048 events read in total (77363ms).
[15:25:59.807] <TB3> INFO: 2352800 events read in total (103211ms).
[15:26:25.358] <TB3> INFO: 2938896 events read in total (128762ms).
[15:26:51.652] <TB3> INFO: 3523864 events read in total (155056ms).
[15:27:17.159] <TB3> INFO: 4108152 events read in total (180563ms).
[15:27:42.965] <TB3> INFO: 4691936 events read in total (206369ms).
[15:27:58.692] <TB3> INFO: 5025280 events read in total (222096ms).
[15:27:58.879] <TB3> INFO: Test took 223144ms.
[15:28:22.046] <TB3> INFO: roc 0 with ID = 0 has maximal Vcal 57.1213 for pixel 17/8 mean/min/max = 44.2073/31.2667/57.148
[15:28:22.047] <TB3> INFO: roc 1 with ID = 1 has maximal Vcal 60.3578 for pixel 1/23 mean/min/max = 46.9606/33.5307/60.3904
[15:28:22.047] <TB3> INFO: roc 2 with ID = 2 has maximal Vcal 57.8398 for pixel 5/3 mean/min/max = 44.7743/31.6586/57.89
[15:28:22.048] <TB3> INFO: roc 3 with ID = 3 has maximal Vcal 58.1921 for pixel 0/33 mean/min/max = 44.8843/31.4986/58.2701
[15:28:22.048] <TB3> INFO: roc 4 with ID = 4 has maximal Vcal 57.5697 for pixel 11/39 mean/min/max = 46.2883/34.9695/57.6071
[15:28:22.048] <TB3> INFO: roc 5 with ID = 5 has maximal Vcal 57.5378 for pixel 17/71 mean/min/max = 44.9272/32.3093/57.5452
[15:28:22.049] <TB3> INFO: roc 6 with ID = 6 has maximal Vcal 58.5578 for pixel 37/8 mean/min/max = 46.4685/34.3601/58.577
[15:28:22.049] <TB3> INFO: roc 7 with ID = 7 has maximal Vcal 58.137 for pixel 9/21 mean/min/max = 45.3588/31.9735/58.7442
[15:28:22.049] <TB3> INFO: roc 8 with ID = 8 has maximal Vcal 58.1426 for pixel 2/36 mean/min/max = 45.1504/32.0091/58.2918
[15:28:22.050] <TB3> INFO: roc 9 with ID = 9 has maximal Vcal 59.2896 for pixel 14/77 mean/min/max = 46.2827/33.2746/59.2908
[15:28:22.050] <TB3> INFO: roc 10 with ID = 10 has maximal Vcal 59.8756 for pixel 11/2 mean/min/max = 46.2763/32.5699/59.9826
[15:28:22.050] <TB3> INFO: roc 11 with ID = 11 has maximal Vcal 58.234 for pixel 48/13 mean/min/max = 46.1177/33.8683/58.3671
[15:28:22.051] <TB3> INFO: roc 12 with ID = 12 has maximal Vcal 58.3138 for pixel 23/73 mean/min/max = 46.4261/34.4599/58.3922
[15:28:22.051] <TB3> INFO: roc 13 with ID = 13 has maximal Vcal 59.0144 for pixel 27/39 mean/min/max = 46.8459/34.6037/59.0881
[15:28:22.051] <TB3> INFO: roc 14 with ID = 14 has maximal Vcal 57.8156 for pixel 0/38 mean/min/max = 46.29/34.7365/57.8435
[15:28:22.052] <TB3> INFO: roc 15 with ID = 15 has maximal Vcal 59.2085 for pixel 24/77 mean/min/max = 45.8905/32.3303/59.4506
[15:28:22.052] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[15:28:22.141] <TB3> INFO: Expecting 411648 events.
[15:28:31.692] <TB3> INFO: 411648 events read in total (8959ms).
[15:28:31.702] <TB3> INFO: Expecting 411648 events.
[15:28:41.096] <TB3> INFO: 411648 events read in total (8991ms).
[15:28:41.107] <TB3> INFO: Expecting 411648 events.
[15:28:50.467] <TB3> INFO: 411648 events read in total (8957ms).
[15:28:50.479] <TB3> INFO: Expecting 411648 events.
[15:28:59.843] <TB3> INFO: 411648 events read in total (8960ms).
[15:28:59.859] <TB3> INFO: Expecting 411648 events.
[15:29:09.188] <TB3> INFO: 411648 events read in total (8926ms).
[15:29:09.207] <TB3> INFO: Expecting 411648 events.
[15:29:18.576] <TB3> INFO: 411648 events read in total (8966ms).
[15:29:18.597] <TB3> INFO: Expecting 411648 events.
[15:29:27.952] <TB3> INFO: 411648 events read in total (8952ms).
[15:29:27.976] <TB3> INFO: Expecting 411648 events.
[15:29:37.309] <TB3> INFO: 411648 events read in total (8930ms).
[15:29:37.336] <TB3> INFO: Expecting 411648 events.
[15:29:46.768] <TB3> INFO: 411648 events read in total (9029ms).
[15:29:46.799] <TB3> INFO: Expecting 411648 events.
[15:29:56.152] <TB3> INFO: 411648 events read in total (8950ms).
[15:29:56.185] <TB3> INFO: Expecting 411648 events.
[15:30:05.535] <TB3> INFO: 411648 events read in total (8946ms).
[15:30:05.571] <TB3> INFO: Expecting 411648 events.
[15:30:14.929] <TB3> INFO: 411648 events read in total (8955ms).
[15:30:14.969] <TB3> INFO: Expecting 411648 events.
[15:30:24.131] <TB3> INFO: 411648 events read in total (8759ms).
[15:30:24.182] <TB3> INFO: Expecting 411648 events.
[15:30:33.481] <TB3> INFO: 411648 events read in total (8895ms).
[15:30:33.530] <TB3> INFO: Expecting 411648 events.
[15:30:42.934] <TB3> INFO: 411648 events read in total (9001ms).
[15:30:42.994] <TB3> INFO: Expecting 411648 events.
[15:30:52.385] <TB3> INFO: 411648 events read in total (8988ms).
[15:30:52.621] <TB3> INFO: Test took 150569ms.
[15:30:53.426] <TB3> INFO: ---> dac: vcal name: TrimThr2 ntrig: 8 dacrange: 0 .. 150 (-1/-1) hits flags = 528 (plus default)
[15:30:53.440] <TB3> INFO: dacScan split into 1 runs with ntrig = 8
[15:30:53.440] <TB3> INFO: run 1 of 1
[15:30:53.681] <TB3> INFO: Expecting 5025280 events.
[15:31:20.284] <TB3> INFO: 585296 events read in total (26011ms).
[15:31:46.480] <TB3> INFO: 1169240 events read in total (52208ms).
[15:32:12.358] <TB3> INFO: 1753224 events read in total (78085ms).
[15:32:38.574] <TB3> INFO: 2336600 events read in total (104301ms).
[15:33:04.672] <TB3> INFO: 2918936 events read in total (130399ms).
[15:33:30.864] <TB3> INFO: 3500712 events read in total (156591ms).
[15:33:57.193] <TB3> INFO: 4080824 events read in total (182920ms).
[15:34:23.158] <TB3> INFO: 4661864 events read in total (208885ms).
[15:34:39.683] <TB3> INFO: 5025280 events read in total (225410ms).
[15:34:39.957] <TB3> INFO: Test took 226519ms.
[15:35:04.858] <TB3> INFO: ---> TrimStepCorr4 extremal thresholds: 3.921299 .. 141.055998
[15:35:05.097] <TB3> INFO: Expecting 208000 events.
[15:35:14.952] <TB3> INFO: 208000 events read in total (9263ms).
[15:35:14.953] <TB3> INFO: Test took 10094ms.
[15:35:14.001] <TB3> INFO: ---> dac: vcal name: trimStepCorr4 ntrig: 8 dacrange: 3 .. 151 (-1/-1) hits flags = 528 (plus default)
[15:35:15.012] <TB3> INFO: dacScan split into 1 runs with ntrig = 8
[15:35:15.012] <TB3> INFO: run 1 of 1
[15:35:15.290] <TB3> INFO: Expecting 4958720 events.
[15:35:41.117] <TB3> INFO: 582672 events read in total (25235ms).
[15:36:07.114] <TB3> INFO: 1166552 events read in total (51232ms).
[15:36:32.839] <TB3> INFO: 1749936 events read in total (76957ms).
[15:36:58.715] <TB3> INFO: 2333448 events read in total (102833ms).
[15:37:24.209] <TB3> INFO: 2916416 events read in total (128327ms).
[15:37:49.971] <TB3> INFO: 3498832 events read in total (154089ms).
[15:38:16.414] <TB3> INFO: 4080640 events read in total (180532ms).
[15:38:42.532] <TB3> INFO: 4662200 events read in total (206650ms).
[15:38:55.747] <TB3> INFO: 4958720 events read in total (219865ms).
[15:38:55.969] <TB3> INFO: Test took 220957ms.
[15:39:23.771] <TB3> INFO: ---> TrimStepCorr2 extremal thresholds: 27.700541 .. 60.136910
[15:39:24.011] <TB3> INFO: Expecting 208000 events.
[15:39:33.849] <TB3> INFO: 208000 events read in total (9246ms).
[15:39:33.851] <TB3> INFO: Test took 10078ms.
[15:39:33.900] <TB3> INFO: ---> dac: vcal name: trimStepCorr2 ntrig: 8 dacrange: 17 .. 70 (-1/-1) hits flags = 528 (plus default)
[15:39:33.916] <TB3> INFO: dacScan split into 1 runs with ntrig = 8
[15:39:33.916] <TB3> INFO: run 1 of 1
[15:39:34.194] <TB3> INFO: Expecting 1797120 events.
[15:40:02.665] <TB3> INFO: 617072 events read in total (27879ms).
[15:40:28.987] <TB3> INFO: 1233872 events read in total (54202ms).
[15:40:53.836] <TB3> INFO: 1797120 events read in total (79050ms).
[15:40:53.877] <TB3> INFO: Test took 79960ms.
[15:41:08.713] <TB3> INFO: ---> TrimStepCorr1a extremal thresholds: 25.401406 .. 53.229315
[15:41:08.950] <TB3> INFO: Expecting 208000 events.
[15:41:18.846] <TB3> INFO: 208000 events read in total (9299ms).
[15:41:18.847] <TB3> INFO: Test took 10133ms.
[15:41:18.897] <TB3> INFO: ---> dac: vcal name: trimStepCorr1a ntrig: 8 dacrange: 15 .. 63 (-1/-1) hits flags = 528 (plus default)
[15:41:18.910] <TB3> INFO: dacScan split into 1 runs with ntrig = 8
[15:41:18.910] <TB3> INFO: run 1 of 1
[15:41:19.188] <TB3> INFO: Expecting 1630720 events.
[15:41:47.180] <TB3> INFO: 640680 events read in total (27401ms).
[15:42:13.987] <TB3> INFO: 1280592 events read in total (54208ms).
[15:42:29.072] <TB3> INFO: 1630720 events read in total (69293ms).
[15:42:29.105] <TB3> INFO: Test took 70195ms.
[15:42:43.978] <TB3> INFO: ---> TrimStepCorr1b extremal thresholds: 23.459736 .. 53.159596
[15:42:44.219] <TB3> INFO: Expecting 208000 events.
[15:42:54.051] <TB3> INFO: 208000 events read in total (9240ms).
[15:42:54.053] <TB3> INFO: Test took 10074ms.
[15:42:54.122] <TB3> INFO: ---> dac: vcal name: trimStepCorr1b ntrig: 8 dacrange: 13 .. 63 (-1/-1) hits flags = 528 (plus default)
[15:42:54.136] <TB3> INFO: dacScan split into 1 runs with ntrig = 8
[15:42:54.136] <TB3> INFO: run 1 of 1
[15:42:54.414] <TB3> INFO: Expecting 1697280 events.
[15:43:22.688] <TB3> INFO: 647856 events read in total (27682ms).
[15:43:49.955] <TB3> INFO: 1295552 events read in total (54950ms).
[15:44:06.882] <TB3> INFO: 1697280 events read in total (71876ms).
[15:44:06.923] <TB3> INFO: Test took 72787ms.
[15:44:21.965] <TB3> INFO: ---> TrimThrFinal extremal thresholds: 15 .. 55
[15:44:21.965] <TB3> INFO: ---> dac: vcal name: TrimThrFinal ntrig: 8 dacrange: 15 .. 55 (-1/-1) hits flags = 528 (plus default)
[15:44:21.978] <TB3> INFO: dacScan split into 1 runs with ntrig = 8
[15:44:21.978] <TB3> INFO: run 1 of 1
[15:44:22.215] <TB3> INFO: Expecting 1364480 events.
[15:44:50.901] <TB3> INFO: 667528 events read in total (28094ms).
[15:45:18.512] <TB3> INFO: 1334272 events read in total (55705ms).
[15:45:20.201] <TB3> INFO: 1364480 events read in total (57395ms).
[15:45:20.228] <TB3> INFO: Test took 58250ms.
[15:45:32.777] <TB3> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1012_FullQualification_2016-10-17_13h57m_1476705426//001_Fulltest_m20//dacParameters35_C0.dat
[15:45:32.777] <TB3> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1012_FullQualification_2016-10-17_13h57m_1476705426//001_Fulltest_m20//dacParameters35_C1.dat
[15:45:32.777] <TB3> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1012_FullQualification_2016-10-17_13h57m_1476705426//001_Fulltest_m20//dacParameters35_C2.dat
[15:45:32.777] <TB3> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1012_FullQualification_2016-10-17_13h57m_1476705426//001_Fulltest_m20//dacParameters35_C3.dat
[15:45:32.778] <TB3> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1012_FullQualification_2016-10-17_13h57m_1476705426//001_Fulltest_m20//dacParameters35_C4.dat
[15:45:32.778] <TB3> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1012_FullQualification_2016-10-17_13h57m_1476705426//001_Fulltest_m20//dacParameters35_C5.dat
[15:45:32.778] <TB3> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1012_FullQualification_2016-10-17_13h57m_1476705426//001_Fulltest_m20//dacParameters35_C6.dat
[15:45:32.778] <TB3> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1012_FullQualification_2016-10-17_13h57m_1476705426//001_Fulltest_m20//dacParameters35_C7.dat
[15:45:32.778] <TB3> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1012_FullQualification_2016-10-17_13h57m_1476705426//001_Fulltest_m20//dacParameters35_C8.dat
[15:45:32.778] <TB3> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1012_FullQualification_2016-10-17_13h57m_1476705426//001_Fulltest_m20//dacParameters35_C9.dat
[15:45:32.778] <TB3> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1012_FullQualification_2016-10-17_13h57m_1476705426//001_Fulltest_m20//dacParameters35_C10.dat
[15:45:32.778] <TB3> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1012_FullQualification_2016-10-17_13h57m_1476705426//001_Fulltest_m20//dacParameters35_C11.dat
[15:45:32.778] <TB3> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1012_FullQualification_2016-10-17_13h57m_1476705426//001_Fulltest_m20//dacParameters35_C12.dat
[15:45:32.778] <TB3> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1012_FullQualification_2016-10-17_13h57m_1476705426//001_Fulltest_m20//dacParameters35_C13.dat
[15:45:32.778] <TB3> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1012_FullQualification_2016-10-17_13h57m_1476705426//001_Fulltest_m20//dacParameters35_C14.dat
[15:45:32.778] <TB3> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1012_FullQualification_2016-10-17_13h57m_1476705426//001_Fulltest_m20//dacParameters35_C15.dat
[15:45:32.779] <TB3> INFO: write trim parameters into /home/l_tester/david/FullQualification/data/M1012_FullQualification_2016-10-17_13h57m_1476705426//001_Fulltest_m20//trimParameters35_C0.dat
[15:45:32.785] <TB3> INFO: write trim parameters into /home/l_tester/david/FullQualification/data/M1012_FullQualification_2016-10-17_13h57m_1476705426//001_Fulltest_m20//trimParameters35_C1.dat
[15:45:32.791] <TB3> INFO: write trim parameters into /home/l_tester/david/FullQualification/data/M1012_FullQualification_2016-10-17_13h57m_1476705426//001_Fulltest_m20//trimParameters35_C2.dat
[15:45:32.798] <TB3> INFO: write trim parameters into /home/l_tester/david/FullQualification/data/M1012_FullQualification_2016-10-17_13h57m_1476705426//001_Fulltest_m20//trimParameters35_C3.dat
[15:45:32.804] <TB3> INFO: write trim parameters into /home/l_tester/david/FullQualification/data/M1012_FullQualification_2016-10-17_13h57m_1476705426//001_Fulltest_m20//trimParameters35_C4.dat
[15:45:32.810] <TB3> INFO: write trim parameters into /home/l_tester/david/FullQualification/data/M1012_FullQualification_2016-10-17_13h57m_1476705426//001_Fulltest_m20//trimParameters35_C5.dat
[15:45:32.816] <TB3> INFO: write trim parameters into /home/l_tester/david/FullQualification/data/M1012_FullQualification_2016-10-17_13h57m_1476705426//001_Fulltest_m20//trimParameters35_C6.dat
[15:45:32.822] <TB3> INFO: write trim parameters into /home/l_tester/david/FullQualification/data/M1012_FullQualification_2016-10-17_13h57m_1476705426//001_Fulltest_m20//trimParameters35_C7.dat
[15:45:32.828] <TB3> INFO: write trim parameters into /home/l_tester/david/FullQualification/data/M1012_FullQualification_2016-10-17_13h57m_1476705426//001_Fulltest_m20//trimParameters35_C8.dat
[15:45:32.834] <TB3> INFO: write trim parameters into /home/l_tester/david/FullQualification/data/M1012_FullQualification_2016-10-17_13h57m_1476705426//001_Fulltest_m20//trimParameters35_C9.dat
[15:45:32.840] <TB3> INFO: write trim parameters into /home/l_tester/david/FullQualification/data/M1012_FullQualification_2016-10-17_13h57m_1476705426//001_Fulltest_m20//trimParameters35_C10.dat
[15:45:32.846] <TB3> INFO: write trim parameters into /home/l_tester/david/FullQualification/data/M1012_FullQualification_2016-10-17_13h57m_1476705426//001_Fulltest_m20//trimParameters35_C11.dat
[15:45:32.852] <TB3> INFO: write trim parameters into /home/l_tester/david/FullQualification/data/M1012_FullQualification_2016-10-17_13h57m_1476705426//001_Fulltest_m20//trimParameters35_C12.dat
[15:45:32.858] <TB3> INFO: write trim parameters into /home/l_tester/david/FullQualification/data/M1012_FullQualification_2016-10-17_13h57m_1476705426//001_Fulltest_m20//trimParameters35_C13.dat
[15:45:32.864] <TB3> INFO: write trim parameters into /home/l_tester/david/FullQualification/data/M1012_FullQualification_2016-10-17_13h57m_1476705426//001_Fulltest_m20//trimParameters35_C14.dat
[15:45:32.870] <TB3> INFO: write trim parameters into /home/l_tester/david/FullQualification/data/M1012_FullQualification_2016-10-17_13h57m_1476705426//001_Fulltest_m20//trimParameters35_C15.dat
[15:45:32.876] <TB3> INFO: PixTestTrim::trimTest() done
[15:45:32.876] <TB3> INFO: vtrim: 129 142 113 119 119 118 119 124 126 132 144 117 119 124 117 149
[15:45:32.876] <TB3> INFO: vthrcomp: 125 111 119 117 106 115 104 123 115 109 101 103 105 110 107 120
[15:45:32.876] <TB3> INFO: vcal mean: 34.88 34.98 34.94 34.93 35.02 34.96 34.98 34.94 34.94 34.91 34.98 34.98 34.97 34.97 35.00 34.91
[15:45:32.876] <TB3> INFO: vcal RMS: 1.21 0.97 1.09 1.17 0.91 0.99 0.91 1.03 0.96 0.93 0.96 0.95 0.93 0.93 0.88 1.09
[15:45:32.876] <TB3> INFO: bits mean: 10.06 9.18 9.46 9.56 9.11 9.51 9.23 9.57 9.35 9.22 9.65 9.35 9.02 9.23 8.36 9.68
[15:45:32.876] <TB3> INFO: bits RMS: 2.62 2.53 2.82 2.78 2.41 2.73 2.46 2.69 2.82 2.54 2.52 2.43 2.50 2.36 2.76 2.59
[15:45:32.883] <TB3> INFO: ----------------------------------------------------------------------
[15:45:32.884] <TB3> INFO: PixTestTrim::trimBitTest() ntrig = 5, vtrims = 254 126 63 32
[15:45:32.884] <TB3> INFO: ----------------------------------------------------------------------
[15:45:32.886] <TB3> INFO: ---> dac: Vcal name: TrimBitsThr0 ntrig: 5 dacrange: 0 .. 199 (-1/-1) hits flags = 528 (plus default)
[15:45:32.898] <TB3> INFO: dacScan split into 1 runs with ntrig = 5
[15:45:32.898] <TB3> INFO: run 1 of 1
[15:45:33.135] <TB3> INFO: Expecting 4160000 events.
[15:46:04.485] <TB3> INFO: 709115 events read in total (30757ms).
[15:46:35.181] <TB3> INFO: 1414885 events read in total (61453ms).
[15:47:05.746] <TB3> INFO: 2116850 events read in total (92019ms).
[15:47:36.174] <TB3> INFO: 2813920 events read in total (122446ms).
[15:48:07.619] <TB3> INFO: 3509405 events read in total (153891ms).
[15:48:36.613] <TB3> INFO: 4160000 events read in total (182885ms).
[15:48:36.714] <TB3> INFO: Test took 183817ms.
[15:49:04.221] <TB3> INFO: ---> dac: Vcal name: TrimThr_trim14 ntrig: 5 dacrange: 0 .. 185 (-1/-1) hits flags = 528 (plus default)
[15:49:04.234] <TB3> INFO: dacScan split into 1 runs with ntrig = 5
[15:49:04.234] <TB3> INFO: run 1 of 1
[15:49:04.514] <TB3> INFO: Expecting 3868800 events.
[15:49:36.121] <TB3> INFO: 706360 events read in total (31015ms).
[15:50:06.861] <TB3> INFO: 1408955 events read in total (61755ms).
[15:50:37.569] <TB3> INFO: 2108245 events read in total (92463ms).
[15:51:08.476] <TB3> INFO: 2803960 events read in total (123370ms).
[15:51:39.489] <TB3> INFO: 3497295 events read in total (154383ms).
[15:51:56.029] <TB3> INFO: 3868800 events read in total (170923ms).
[15:51:56.226] <TB3> INFO: Test took 171992ms.
[15:52:20.761] <TB3> INFO: ---> dac: Vcal name: TrimThr_trim13 ntrig: 5 dacrange: 0 .. 171 (-1/-1) hits flags = 528 (plus default)
[15:52:20.772] <TB3> INFO: dacScan split into 1 runs with ntrig = 5
[15:52:20.772] <TB3> INFO: run 1 of 1
[15:52:21.010] <TB3> INFO: Expecting 3577600 events.
[15:52:53.017] <TB3> INFO: 725570 events read in total (31415ms).
[15:53:24.427] <TB3> INFO: 1446950 events read in total (62825ms).
[15:53:55.870] <TB3> INFO: 2163270 events read in total (94269ms).
[15:54:26.929] <TB3> INFO: 2875975 events read in total (125327ms).
[15:54:58.364] <TB3> INFO: 3577600 events read in total (156762ms).
[15:54:58.431] <TB3> INFO: Test took 157658ms.
[15:55:24.205] <TB3> INFO: ---> dac: Vcal name: TrimThr_trim11 ntrig: 5 dacrange: 0 .. 173 (-1/-1) hits flags = 528 (plus default)
[15:55:24.219] <TB3> INFO: dacScan split into 1 runs with ntrig = 5
[15:55:24.219] <TB3> INFO: run 1 of 1
[15:55:24.455] <TB3> INFO: Expecting 3619200 events.
[15:55:56.712] <TB3> INFO: 722650 events read in total (31665ms).
[15:56:27.921] <TB3> INFO: 1440755 events read in total (62874ms).
[15:56:59.551] <TB3> INFO: 2154390 events read in total (94504ms).
[15:57:30.453] <TB3> INFO: 2864460 events read in total (125406ms).
[15:58:02.559] <TB3> INFO: 3573840 events read in total (157512ms).
[15:58:05.010] <TB3> INFO: 3619200 events read in total (159963ms).
[15:58:05.095] <TB3> INFO: Test took 160876ms.
[15:58:28.034] <TB3> INFO: ---> dac: Vcal name: TrimThr_trim7 ntrig: 5 dacrange: 0 .. 172 (-1/-1) hits flags = 528 (plus default)
[15:58:28.048] <TB3> INFO: dacScan split into 1 runs with ntrig = 5
[15:58:28.048] <TB3> INFO: run 1 of 1
[15:58:28.335] <TB3> INFO: Expecting 3598400 events.
[15:59:00.729] <TB3> INFO: 723950 events read in total (31803ms).
[15:59:32.641] <TB3> INFO: 1443705 events read in total (63715ms).
[16:00:04.342] <TB3> INFO: 2158740 events read in total (95417ms).
[16:00:35.770] <TB3> INFO: 2869980 events read in total (126844ms).
[16:01:07.222] <TB3> INFO: 3581240 events read in total (158296ms).
[16:01:08.463] <TB3> INFO: 3598400 events read in total (159537ms).
[16:01:08.525] <TB3> INFO: Test took 160477ms.
[16:01:31.248] <TB3> INFO: PixTestTrim::trimBitTest() done
[16:01:31.249] <TB3> INFO: PixTestTrim::doTest() done, duration: 2442 seconds
[16:01:31.249] <TB3> INFO: Decoding statistics:
[16:01:31.249] <TB3> INFO: General information:
[16:01:31.249] <TB3> INFO: 16bit words read: 0
[16:01:31.249] <TB3> INFO: valid events total: 0
[16:01:31.250] <TB3> INFO: empty events: 0
[16:01:31.250] <TB3> INFO: valid events with pixels: 0
[16:01:31.250] <TB3> INFO: valid pixel hits: 0
[16:01:31.250] <TB3> INFO: Event errors: 0
[16:01:31.250] <TB3> INFO: start marker: 0
[16:01:31.250] <TB3> INFO: stop marker: 0
[16:01:31.250] <TB3> INFO: overflow: 0
[16:01:31.250] <TB3> INFO: invalid 5bit words: 0
[16:01:31.250] <TB3> INFO: invalid XOR eye diagram: 0
[16:01:31.250] <TB3> INFO: frame (failed synchr.): 0
[16:01:31.250] <TB3> INFO: idle data (no TBM trl): 0
[16:01:31.250] <TB3> INFO: no data (only TBM hdr): 0
[16:01:31.250] <TB3> INFO: TBM errors: 0
[16:01:31.250] <TB3> INFO: flawed TBM headers: 0
[16:01:31.250] <TB3> INFO: flawed TBM trailers: 0
[16:01:31.250] <TB3> INFO: event ID mismatches: 0
[16:01:31.250] <TB3> INFO: ROC errors: 0
[16:01:31.250] <TB3> INFO: missing ROC header(s): 0
[16:01:31.250] <TB3> INFO: misplaced readback start: 0
[16:01:31.250] <TB3> INFO: Pixel decoding errors: 0
[16:01:31.250] <TB3> INFO: pixel data incomplete: 0
[16:01:31.250] <TB3> INFO: pixel address: 0
[16:01:31.250] <TB3> INFO: pulse height fill bit: 0
[16:01:31.250] <TB3> INFO: buffer corruption: 0
[16:01:31.883] <TB3> INFO: ######################################################################
[16:01:31.883] <TB3> INFO: PixTestPhOptimization::doTest() Ntrig = 10
[16:01:31.883] <TB3> INFO: ######################################################################
[16:01:32.122] <TB3> INFO: Expecting 41600 events.
[16:01:35.606] <TB3> INFO: 41600 events read in total (2892ms).
[16:01:35.607] <TB3> INFO: Test took 3722ms.
[16:01:36.086] <TB3> INFO: Expecting 41600 events.
[16:01:39.755] <TB3> INFO: 41600 events read in total (3077ms).
[16:01:39.757] <TB3> INFO: Test took 3947ms.
[16:01:40.047] <TB3> INFO: Expecting 41600 events.
[16:01:43.602] <TB3> INFO: 41600 events read in total (2963ms).
[16:01:43.603] <TB3> INFO: Test took 3822ms.
[16:01:43.893] <TB3> INFO: Expecting 41600 events.
[16:01:47.465] <TB3> INFO: 41600 events read in total (2980ms).
[16:01:47.466] <TB3> INFO: Test took 3839ms.
[16:01:47.754] <TB3> INFO: Expecting 41600 events.
[16:01:51.380] <TB3> INFO: 41600 events read in total (3034ms).
[16:01:51.380] <TB3> INFO: Test took 3891ms.
[16:01:51.672] <TB3> INFO: Expecting 41600 events.
[16:01:55.189] <TB3> INFO: 41600 events read in total (2925ms).
[16:01:55.190] <TB3> INFO: Test took 3783ms.
[16:01:55.482] <TB3> INFO: Expecting 41600 events.
[16:01:59.056] <TB3> INFO: 41600 events read in total (2983ms).
[16:01:59.056] <TB3> INFO: Test took 3839ms.
[16:01:59.346] <TB3> INFO: Expecting 41600 events.
[16:02:02.873] <TB3> INFO: 41600 events read in total (2936ms).
[16:02:02.874] <TB3> INFO: Test took 3793ms.
[16:02:03.163] <TB3> INFO: Expecting 41600 events.
[16:02:06.669] <TB3> INFO: 41600 events read in total (2915ms).
[16:02:06.670] <TB3> INFO: Test took 3772ms.
[16:02:06.993] <TB3> INFO: Expecting 41600 events.
[16:02:10.530] <TB3> INFO: 41600 events read in total (2945ms).
[16:02:10.531] <TB3> INFO: Test took 3837ms.
[16:02:10.820] <TB3> INFO: Expecting 41600 events.
[16:02:14.325] <TB3> INFO: 41600 events read in total (2913ms).
[16:02:14.326] <TB3> INFO: Test took 3771ms.
[16:02:14.617] <TB3> INFO: Expecting 41600 events.
[16:02:18.207] <TB3> INFO: 41600 events read in total (2998ms).
[16:02:18.208] <TB3> INFO: Test took 3856ms.
[16:02:18.497] <TB3> INFO: Expecting 41600 events.
[16:02:22.016] <TB3> INFO: 41600 events read in total (2927ms).
[16:02:22.017] <TB3> INFO: Test took 3785ms.
[16:02:22.307] <TB3> INFO: Expecting 41600 events.
[16:02:25.834] <TB3> INFO: 41600 events read in total (2935ms).
[16:02:25.835] <TB3> INFO: Test took 3793ms.
[16:02:26.125] <TB3> INFO: Expecting 41600 events.
[16:02:29.620] <TB3> INFO: 41600 events read in total (2903ms).
[16:02:29.621] <TB3> INFO: Test took 3762ms.
[16:02:29.911] <TB3> INFO: Expecting 41600 events.
[16:02:33.523] <TB3> INFO: 41600 events read in total (3020ms).
[16:02:33.524] <TB3> INFO: Test took 3879ms.
[16:02:33.814] <TB3> INFO: Expecting 41600 events.
[16:02:37.381] <TB3> INFO: 41600 events read in total (2975ms).
[16:02:37.382] <TB3> INFO: Test took 3834ms.
[16:02:37.672] <TB3> INFO: Expecting 41600 events.
[16:02:41.204] <TB3> INFO: 41600 events read in total (2941ms).
[16:02:41.205] <TB3> INFO: Test took 3799ms.
[16:02:41.498] <TB3> INFO: Expecting 41600 events.
[16:02:45.137] <TB3> INFO: 41600 events read in total (3047ms).
[16:02:45.138] <TB3> INFO: Test took 3906ms.
[16:02:45.428] <TB3> INFO: Expecting 41600 events.
[16:02:49.037] <TB3> INFO: 41600 events read in total (3018ms).
[16:02:49.038] <TB3> INFO: Test took 3876ms.
[16:02:49.328] <TB3> INFO: Expecting 41600 events.
[16:02:52.990] <TB3> INFO: 41600 events read in total (3070ms).
[16:02:52.991] <TB3> INFO: Test took 3929ms.
[16:02:53.296] <TB3> INFO: Expecting 41600 events.
[16:02:56.982] <TB3> INFO: 41600 events read in total (3094ms).
[16:02:56.982] <TB3> INFO: Test took 3967ms.
[16:02:57.271] <TB3> INFO: Expecting 41600 events.
[16:03:01.004] <TB3> INFO: 41600 events read in total (3141ms).
[16:03:01.005] <TB3> INFO: Test took 3998ms.
[16:03:01.294] <TB3> INFO: Expecting 41600 events.
[16:03:04.857] <TB3> INFO: 41600 events read in total (2971ms).
[16:03:04.858] <TB3> INFO: Test took 3829ms.
[16:03:05.147] <TB3> INFO: Expecting 41600 events.
[16:03:08.741] <TB3> INFO: 41600 events read in total (3002ms).
[16:03:08.741] <TB3> INFO: Test took 3860ms.
[16:03:09.033] <TB3> INFO: Expecting 41600 events.
[16:03:12.550] <TB3> INFO: 41600 events read in total (2926ms).
[16:03:12.551] <TB3> INFO: Test took 3784ms.
[16:03:12.841] <TB3> INFO: Expecting 41600 events.
[16:03:16.373] <TB3> INFO: 41600 events read in total (2940ms).
[16:03:16.374] <TB3> INFO: Test took 3798ms.
[16:03:16.663] <TB3> INFO: Expecting 41600 events.
[16:03:20.266] <TB3> INFO: 41600 events read in total (3011ms).
[16:03:20.267] <TB3> INFO: Test took 3869ms.
[16:03:20.556] <TB3> INFO: Expecting 41600 events.
[16:03:24.156] <TB3> INFO: 41600 events read in total (3008ms).
[16:03:24.157] <TB3> INFO: Test took 3866ms.
[16:03:24.450] <TB3> INFO: Expecting 41600 events.
[16:03:28.132] <TB3> INFO: 41600 events read in total (3090ms).
[16:03:28.133] <TB3> INFO: Test took 3949ms.
[16:03:28.423] <TB3> INFO: Expecting 41600 events.
[16:03:31.971] <TB3> INFO: 41600 events read in total (2956ms).
[16:03:31.972] <TB3> INFO: Test took 3815ms.
[16:03:32.263] <TB3> INFO: Expecting 41600 events.
[16:03:35.889] <TB3> INFO: 41600 events read in total (3034ms).
[16:03:35.890] <TB3> INFO: Test took 3893ms.
[16:03:36.180] <TB3> INFO: Expecting 41600 events.
[16:03:39.789] <TB3> INFO: 41600 events read in total (3017ms).
[16:03:39.790] <TB3> INFO: Test took 3876ms.
[16:03:40.083] <TB3> INFO: Expecting 41600 events.
[16:03:43.618] <TB3> INFO: 41600 events read in total (2943ms).
[16:03:43.619] <TB3> INFO: Test took 3802ms.
[16:03:43.928] <TB3> INFO: Expecting 41600 events.
[16:03:47.442] <TB3> INFO: 41600 events read in total (2923ms).
[16:03:47.443] <TB3> INFO: Test took 3799ms.
[16:03:47.736] <TB3> INFO: Expecting 41600 events.
[16:03:51.246] <TB3> INFO: 41600 events read in total (2918ms).
[16:03:51.246] <TB3> INFO: Test took 3775ms.
[16:03:51.548] <TB3> INFO: Expecting 41600 events.
[16:03:55.071] <TB3> INFO: 41600 events read in total (2932ms).
[16:03:55.072] <TB3> INFO: Test took 3802ms.
[16:03:55.362] <TB3> INFO: Expecting 41600 events.
[16:03:58.955] <TB3> INFO: 41600 events read in total (3002ms).
[16:03:58.956] <TB3> INFO: Test took 3860ms.
[16:03:59.248] <TB3> INFO: Expecting 41600 events.
[16:04:02.754] <TB3> INFO: 41600 events read in total (2914ms).
[16:04:02.755] <TB3> INFO: Test took 3772ms.
[16:04:03.045] <TB3> INFO: Expecting 41600 events.
[16:04:06.618] <TB3> INFO: 41600 events read in total (2981ms).
[16:04:06.619] <TB3> INFO: Test took 3839ms.
[16:04:06.908] <TB3> INFO: Expecting 41600 events.
[16:04:10.419] <TB3> INFO: 41600 events read in total (2919ms).
[16:04:10.420] <TB3> INFO: Test took 3777ms.
[16:04:10.711] <TB3> INFO: Expecting 41600 events.
[16:04:14.240] <TB3> INFO: 41600 events read in total (2938ms).
[16:04:14.241] <TB3> INFO: Test took 3797ms.
[16:04:14.531] <TB3> INFO: Expecting 41600 events.
[16:04:18.067] <TB3> INFO: 41600 events read in total (2944ms).
[16:04:18.067] <TB3> INFO: Test took 3801ms.
[16:04:18.357] <TB3> INFO: Expecting 41600 events.
[16:04:21.883] <TB3> INFO: 41600 events read in total (2935ms).
[16:04:21.884] <TB3> INFO: Test took 3792ms.
[16:04:22.181] <TB3> INFO: Expecting 41600 events.
[16:04:25.737] <TB3> INFO: 41600 events read in total (2965ms).
[16:04:25.738] <TB3> INFO: Test took 3829ms.
[16:04:26.027] <TB3> INFO: Expecting 41600 events.
[16:04:29.503] <TB3> INFO: 41600 events read in total (2884ms).
[16:04:29.504] <TB3> INFO: Test took 3742ms.
[16:04:29.808] <TB3> INFO: Expecting 41600 events.
[16:04:33.313] <TB3> INFO: 41600 events read in total (2913ms).
[16:04:33.314] <TB3> INFO: Test took 3786ms.
[16:04:33.606] <TB3> INFO: Expecting 41600 events.
[16:04:37.182] <TB3> INFO: 41600 events read in total (2984ms).
[16:04:37.183] <TB3> INFO: Test took 3842ms.
[16:04:37.473] <TB3> INFO: Expecting 41600 events.
[16:04:41.121] <TB3> INFO: 41600 events read in total (3056ms).
[16:04:41.122] <TB3> INFO: Test took 3914ms.
[16:04:41.412] <TB3> INFO: Expecting 41600 events.
[16:04:44.930] <TB3> INFO: 41600 events read in total (2927ms).
[16:04:44.931] <TB3> INFO: Test took 3785ms.
[16:04:45.221] <TB3> INFO: Expecting 41600 events.
[16:04:48.731] <TB3> INFO: 41600 events read in total (2919ms).
[16:04:48.732] <TB3> INFO: Test took 3776ms.
[16:04:49.024] <TB3> INFO: Expecting 41600 events.
[16:04:52.592] <TB3> INFO: 41600 events read in total (2976ms).
[16:04:52.593] <TB3> INFO: Test took 3834ms.
[16:04:52.884] <TB3> INFO: Expecting 41600 events.
[16:04:56.443] <TB3> INFO: 41600 events read in total (2968ms).
[16:04:56.443] <TB3> INFO: Test took 3825ms.
[16:04:56.735] <TB3> INFO: Expecting 41600 events.
[16:05:00.248] <TB3> INFO: 41600 events read in total (2921ms).
[16:05:00.249] <TB3> INFO: Test took 3779ms.
[16:05:00.540] <TB3> INFO: Expecting 2560 events.
[16:05:01.432] <TB3> INFO: 2560 events read in total (301ms).
[16:05:01.433] <TB3> INFO: Test took 1171ms.
[16:05:01.740] <TB3> INFO: Expecting 2560 events.
[16:05:02.625] <TB3> INFO: 2560 events read in total (294ms).
[16:05:02.626] <TB3> INFO: Test took 1193ms.
[16:05:02.933] <TB3> INFO: Expecting 2560 events.
[16:05:03.825] <TB3> INFO: 2560 events read in total (300ms).
[16:05:03.826] <TB3> INFO: Test took 1200ms.
[16:05:04.133] <TB3> INFO: Expecting 2560 events.
[16:05:05.018] <TB3> INFO: 2560 events read in total (293ms).
[16:05:05.018] <TB3> INFO: Test took 1192ms.
[16:05:05.327] <TB3> INFO: Expecting 2560 events.
[16:05:06.214] <TB3> INFO: 2560 events read in total (296ms).
[16:05:06.215] <TB3> INFO: Test took 1196ms.
[16:05:06.523] <TB3> INFO: Expecting 2560 events.
[16:05:07.409] <TB3> INFO: 2560 events read in total (294ms).
[16:05:07.409] <TB3> INFO: Test took 1194ms.
[16:05:07.716] <TB3> INFO: Expecting 2560 events.
[16:05:08.601] <TB3> INFO: 2560 events read in total (293ms).
[16:05:08.601] <TB3> INFO: Test took 1191ms.
[16:05:08.909] <TB3> INFO: Expecting 2560 events.
[16:05:09.799] <TB3> INFO: 2560 events read in total (297ms).
[16:05:09.799] <TB3> INFO: Test took 1197ms.
[16:05:10.107] <TB3> INFO: Expecting 2560 events.
[16:05:10.991] <TB3> INFO: 2560 events read in total (293ms).
[16:05:10.992] <TB3> INFO: Test took 1192ms.
[16:05:11.299] <TB3> INFO: Expecting 2560 events.
[16:05:12.186] <TB3> INFO: 2560 events read in total (295ms).
[16:05:12.187] <TB3> INFO: Test took 1194ms.
[16:05:12.493] <TB3> INFO: Expecting 2560 events.
[16:05:13.381] <TB3> INFO: 2560 events read in total (296ms).
[16:05:13.381] <TB3> INFO: Test took 1194ms.
[16:05:13.689] <TB3> INFO: Expecting 2560 events.
[16:05:14.568] <TB3> INFO: 2560 events read in total (287ms).
[16:05:14.568] <TB3> INFO: Test took 1186ms.
[16:05:14.876] <TB3> INFO: Expecting 2560 events.
[16:05:15.764] <TB3> INFO: 2560 events read in total (296ms).
[16:05:15.764] <TB3> INFO: Test took 1195ms.
[16:05:16.072] <TB3> INFO: Expecting 2560 events.
[16:05:16.955] <TB3> INFO: 2560 events read in total (291ms).
[16:05:16.955] <TB3> INFO: Test took 1190ms.
[16:05:17.264] <TB3> INFO: Expecting 2560 events.
[16:05:18.150] <TB3> INFO: 2560 events read in total (294ms).
[16:05:18.150] <TB3> INFO: Test took 1194ms.
[16:05:18.457] <TB3> INFO: Expecting 2560 events.
[16:05:19.350] <TB3> INFO: 2560 events read in total (301ms).
[16:05:19.350] <TB3> INFO: Test took 1199ms.


[16:05:19.354] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[16:05:19.659] <TB3> INFO: Expecting 655360 events.
[16:05:34.456] <TB3> INFO: 655360 events read in total (14205ms).
[16:05:34.467] <TB3> INFO: Expecting 655360 events.
[16:05:49.048] <TB3> INFO: 655360 events read in total (14178ms).
[16:05:49.064] <TB3> INFO: Expecting 655360 events.
[16:06:03.810] <TB3> INFO: 655360 events read in total (14343ms).
[16:06:03.840] <TB3> INFO: Expecting 655360 events.
[16:06:18.446] <TB3> INFO: 655360 events read in total (14203ms).
[16:06:18.473] <TB3> INFO: Expecting 655360 events.
[16:06:33.014] <TB3> INFO: 655360 events read in total (14138ms).
[16:06:33.044] <TB3> INFO: Expecting 655360 events.
[16:06:47.590] <TB3> INFO: 655360 events read in total (14143ms).
[16:06:47.631] <TB3> INFO: Expecting 655360 events.
[16:07:02.048] <TB3> INFO: 655360 events read in total (14014ms).
[16:07:02.087] <TB3> INFO: Expecting 655360 events.
[16:07:16.671] <TB3> INFO: 655360 events read in total (14181ms).
[16:07:16.821] <TB3> INFO: Expecting 655360 events.
[16:07:31.413] <TB3> INFO: 655360 events read in total (14188ms).
[16:07:31.464] <TB3> INFO: Expecting 655360 events.
[16:07:46.184] <TB3> INFO: 655360 events read in total (14317ms).
[16:07:46.250] <TB3> INFO: Expecting 655360 events.
[16:08:00.999] <TB3> INFO: 655360 events read in total (14346ms).
[16:08:01.071] <TB3> INFO: Expecting 655360 events.
[16:08:15.717] <TB3> INFO: 655360 events read in total (14243ms).
[16:08:15.945] <TB3> INFO: Expecting 655360 events.
[16:08:30.784] <TB3> INFO: 655360 events read in total (14436ms).
[16:08:30.867] <TB3> INFO: Expecting 655360 events.
[16:08:45.407] <TB3> INFO: 655360 events read in total (14137ms).
[16:08:45.495] <TB3> INFO: Expecting 655360 events.
[16:09:00.190] <TB3> INFO: 655360 events read in total (14292ms).
[16:09:00.287] <TB3> INFO: Test took 220933ms.
[16:09:00.379] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[16:09:00.637] <TB3> INFO: Expecting 655360 events.
[16:09:15.361] <TB3> INFO: 655360 events read in total (14132ms).
[16:09:15.373] <TB3> INFO: Expecting 655360 events.
[16:09:29.956] <TB3> INFO: 655360 events read in total (14180ms).
[16:09:29.974] <TB3> INFO: Expecting 655360 events.
[16:09:44.579] <TB3> INFO: 655360 events read in total (14202ms).
[16:09:44.604] <TB3> INFO: Expecting 655360 events.
[16:09:59.117] <TB3> INFO: 655360 events read in total (14110ms).
[16:09:59.142] <TB3> INFO: Expecting 655360 events.
[16:10:13.677] <TB3> INFO: 655360 events read in total (14132ms).
[16:10:13.707] <TB3> INFO: Expecting 655360 events.
[16:10:28.282] <TB3> INFO: 655360 events read in total (14172ms).
[16:10:28.318] <TB3> INFO: Expecting 655360 events.
[16:10:42.842] <TB3> INFO: 655360 events read in total (14121ms).
[16:10:42.882] <TB3> INFO: Expecting 655360 events.
[16:10:57.409] <TB3> INFO: 655360 events read in total (14124ms).
[16:10:57.461] <TB3> INFO: Expecting 655360 events.
[16:11:12.055] <TB3> INFO: 655360 events read in total (14191ms).
[16:11:12.118] <TB3> INFO: Expecting 655360 events.
[16:11:26.836] <TB3> INFO: 655360 events read in total (14315ms).
[16:11:26.899] <TB3> INFO: Expecting 655360 events.
[16:11:41.630] <TB3> INFO: 655360 events read in total (14328ms).
[16:11:41.721] <TB3> INFO: Expecting 655360 events.
[16:11:56.642] <TB3> INFO: 655360 events read in total (14517ms).
[16:11:56.739] <TB3> INFO: Expecting 655360 events.
[16:12:11.682] <TB3> INFO: 655360 events read in total (14536ms).
[16:12:11.769] <TB3> INFO: Expecting 655360 events.
[16:12:26.608] <TB3> INFO: 655360 events read in total (14436ms).
[16:12:26.697] <TB3> INFO: Expecting 655360 events.
[16:12:41.616] <TB3> INFO: 655360 events read in total (14516ms).
[16:12:41.749] <TB3> INFO: Expecting 655360 events.
[16:12:56.629] <TB3> INFO: 655360 events read in total (14477ms).
[16:12:56.746] <TB3> INFO: Test took 236367ms.
[16:12:56.964] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[16:12:56.970] <TB3> INFO: safety margin for low PH: adding 1, margin is now 21
[16:12:56.976] <TB3> INFO: safety margin for low PH: adding 2, margin is now 22
[16:12:56.983] <TB3> INFO: safety margin for low PH: adding 3, margin is now 23
[16:12:56.989] <TB3> INFO: safety margin for low PH: adding 4, margin is now 24
[16:12:56.995] <TB3> INFO: safety margin for low PH: adding 5, margin is now 25
[16:12:56.001] <TB3> INFO: safety margin for low PH: adding 6, margin is now 26
[16:12:57.007] <TB3> INFO: safety margin for low PH: adding 7, margin is now 27
[16:12:57.013] <TB3> INFO: safety margin for low PH: adding 8, margin is now 28
[16:12:57.019] <TB3> INFO: safety margin for low PH: adding 9, margin is now 29
[16:12:57.024] <TB3> INFO: safety margin for low PH: adding 10, margin is now 30
[16:12:57.030] <TB3> INFO: safety margin for low PH: adding 11, margin is now 31
[16:12:57.036] <TB3> INFO: safety margin for low PH: adding 12, margin is now 32
[16:12:57.041] <TB3> INFO: safety margin for low PH: adding 13, margin is now 33
[16:12:57.047] <TB3> INFO: safety margin for low PH: adding 14, margin is now 34
[16:12:57.052] <TB3> INFO: safety margin for low PH: adding 15, margin is now 35
[16:12:57.058] <TB3> INFO: safety margin for low PH: adding 16, margin is now 36
[16:12:57.064] <TB3> INFO: safety margin for low PH: adding 17, margin is now 37
[16:12:57.069] <TB3> INFO: safety margin for low PH: adding 18, margin is now 38
[16:12:57.075] <TB3> INFO: safety margin for low PH: adding 19, margin is now 39
[16:12:57.081] <TB3> INFO: safety margin for low PH: adding 20, margin is now 40
[16:12:57.086] <TB3> INFO: safety margin for low PH: adding 21, margin is now 41
[16:12:57.092] <TB3> INFO: safety margin for low PH: adding 22, margin is now 42
[16:12:57.098] <TB3> INFO: safety margin for low PH: adding 23, margin is now 43
[16:12:57.104] <TB3> INFO: safety margin for low PH: adding 24, margin is now 44
[16:12:57.110] <TB3> INFO: safety margin for low PH: adding 25, margin is now 45
[16:12:57.115] <TB3> INFO: safety margin for low PH: adding 26, margin is now 46
[16:12:57.121] <TB3> INFO: safety margin for low PH: adding 27, margin is now 47
[16:12:57.127] <TB3> INFO: safety margin for low PH: adding 28, margin is now 48
[16:12:57.133] <TB3> INFO: safety margin for low PH: adding 29, margin is now 49
[16:12:57.138] <TB3> INFO: safety margin for low PH: adding 30, margin is now 50
[16:12:57.144] <TB3> INFO: safety margin for low PH: adding 31, margin is now 51
[16:12:57.150] <TB3> INFO: safety margin for low PH: adding 32, margin is now 52
[16:12:57.156] <TB3> INFO: safety margin for low PH: adding 33, margin is now 53
[16:12:57.161] <TB3> INFO: safety margin for low PH: adding 34, margin is now 54
[16:12:57.167] <TB3> INFO: safety margin for low PH: adding 35, margin is now 55
[16:12:57.173] <TB3> INFO: safety margin for low PH: adding 36, margin is now 56
[16:12:57.179] <TB3> INFO: safety margin for low PH: adding 37, margin is now 57
[16:12:57.184] <TB3> INFO: safety margin for low PH: adding 38, margin is now 58
[16:12:57.190] <TB3> INFO: safety margin for low PH: adding 39, margin is now 59
[16:12:57.196] <TB3> INFO: safety margin for low PH: adding 40, margin is now 60
[16:12:57.201] <TB3> INFO: safety margin for low PH: adding 41, margin is now 61
[16:12:57.207] <TB3> INFO: safety margin for low PH: adding 42, margin is now 62
[16:12:57.212] <TB3> INFO: safety margin for low PH: adding 43, margin is now 63
[16:12:57.218] <TB3> INFO: safety margin for low PH: adding 44, margin is now 64
[16:12:57.224] <TB3> INFO: safety margin for low PH: adding 45, margin is now 65
[16:12:57.229] <TB3> INFO: safety margin for low PH: adding 46, margin is now 66
[16:12:57.235] <TB3> INFO: safety margin for low PH: adding 47, margin is now 67
[16:12:57.241] <TB3> INFO: safety margin for low PH: adding 48, margin is now 68
[16:12:57.247] <TB3> INFO: safety margin for low PH: adding 49, margin is now 69
[16:12:57.252] <TB3> INFO: safety margin for low PH: adding 50, margin is now 70
[16:12:57.258] <TB3> INFO: safety margin for low PH: adding 51, margin is now 71
[16:12:57.264] <TB3> INFO: safety margin for low PH: adding 52, margin is now 72
[16:12:57.269] <TB3> INFO: safety margin for low PH: adding 53, margin is now 73
[16:12:57.275] <TB3> INFO: safety margin for low PH: adding 54, margin is now 74
[16:12:57.281] <TB3> INFO: safety margin for low PH: adding 55, margin is now 75
[16:12:57.286] <TB3> INFO: safety margin for low PH: adding 56, margin is now 76
[16:12:57.292] <TB3> INFO: safety margin for low PH: adding 57, margin is now 77
[16:12:57.298] <TB3> INFO: safety margin for low PH: adding 58, margin is now 78
[16:12:57.304] <TB3> INFO: safety margin for low PH: adding 59, margin is now 79
[16:12:57.310] <TB3> INFO: safety margin for low PH: adding 60, margin is now 80
[16:12:57.316] <TB3> INFO: safety margin for low PH: adding 61, margin is now 81
[16:12:57.321] <TB3> INFO: safety margin for low PH: adding 62, margin is now 82
[16:12:57.327] <TB3> INFO: safety margin for low PH: adding 63, margin is now 83
[16:12:57.333] <TB3> INFO: safety margin for low PH: adding 64, margin is now 84
[16:12:57.339] <TB3> INFO: safety margin for low PH: adding 65, margin is now 85
[16:12:57.345] <TB3> INFO: safety margin for low PH: adding 66, margin is now 86
[16:12:57.350] <TB3> INFO: safety margin for low PH: adding 67, margin is now 87
[16:12:57.356] <TB3> INFO: safety margin for low PH: adding 68, margin is now 88
[16:12:57.362] <TB3> INFO: safety margin for low PH: adding 69, margin is now 89
[16:12:57.368] <TB3> INFO: safety margin for low PH: adding 70, margin is now 90
[16:12:57.374] <TB3> INFO: safety margin for low PH: adding 71, margin is now 91
[16:12:57.379] <TB3> INFO: safety margin for low PH: adding 72, margin is now 92
[16:12:57.385] <TB3> INFO: safety margin for low PH: adding 73, margin is now 93
[16:12:57.391] <TB3> INFO: safety margin for low PH: adding 74, margin is now 94
[16:12:57.396] <TB3> INFO: safety margin for low PH: adding 75, margin is now 95
[16:12:57.402] <TB3> INFO: safety margin for low PH: adding 76, margin is now 96
[16:12:57.408] <TB3> INFO: safety margin for low PH: adding 77, margin is now 97
[16:12:57.413] <TB3> INFO: safety margin for low PH: adding 78, margin is now 98
[16:12:57.419] <TB3> INFO: safety margin for low PH: adding 79, margin is now 99
[16:12:57.425] <TB3> INFO: safety margin for low PH: adding 80, margin is now 100
[16:12:57.431] <TB3> INFO: safety margin for low PH: adding 81, margin is now 101
[16:12:57.436] <TB3> INFO: safety margin for low PH: adding 82, margin is now 102
[16:12:57.442] <TB3> INFO: safety margin for low PH: adding 83, margin is now 103
[16:12:57.448] <TB3> INFO: safety margin for low PH: adding 84, margin is now 104
[16:12:57.454] <TB3> INFO: safety margin for low PH: adding 85, margin is now 105
[16:12:57.460] <TB3> INFO: safety margin for low PH: adding 86, margin is now 106
[16:12:57.465] <TB3> INFO: safety margin for low PH: adding 87, margin is now 107
[16:12:57.471] <TB3> INFO: safety margin for low PH: adding 88, margin is now 108
[16:12:57.477] <TB3> INFO: safety margin for low PH: adding 89, margin is now 109
[16:12:57.482] <TB3> INFO: safety margin for low PH: adding 90, margin is now 110
[16:12:57.488] <TB3> INFO: safety margin for low PH: adding 91, margin is now 111
[16:12:57.494] <TB3> INFO: safety margin for low PH: adding 92, margin is now 112
[16:12:57.499] <TB3> INFO: safety margin for low PH: adding 93, margin is now 113
[16:12:57.505] <TB3> INFO: safety margin for low PH: adding 94, margin is now 114
[16:12:57.511] <TB3> INFO: safety margin for low PH: adding 95, margin is now 115
[16:12:57.516] <TB3> INFO: safety margin for low PH: adding 96, margin is now 116
[16:12:57.522] <TB3> INFO: safety margin for low PH: adding 97, margin is now 117
[16:12:57.528] <TB3> INFO: safety margin for low PH: adding 98, margin is now 118
[16:12:57.533] <TB3> INFO: safety margin for low PH: adding 99, margin is now 119
[16:12:57.539] <TB3> INFO: safety margin for low PH: adding 100, margin is now 120
[16:12:57.545] <TB3> INFO: safety margin for low PH: adding 101, margin is now 121
[16:12:57.550] <TB3> INFO: safety margin for low PH: adding 102, margin is now 122
[16:12:57.556] <TB3> INFO: safety margin for low PH: adding 103, margin is now 123
[16:12:57.562] <TB3> INFO: safety margin for low PH: adding 104, margin is now 124
[16:12:57.568] <TB3> INFO: safety margin for low PH: adding 105, margin is now 125
[16:12:57.573] <TB3> INFO: safety margin for low PH: adding 106, margin is now 126
[16:12:57.579] <TB3> INFO: safety margin for low PH: adding 107, margin is now 127
[16:12:57.585] <TB3> INFO: safety margin for low PH: adding 108, margin is now 128
[16:12:57.590] <TB3> INFO: safety margin for low PH: adding 109, margin is now 129
[16:12:57.596] <TB3> INFO: safety margin for low PH: adding 110, margin is now 130
[16:12:57.602] <TB3> INFO: safety margin for low PH: adding 111, margin is now 131
[16:12:57.608] <TB3> INFO: safety margin for low PH: adding 112, margin is now 132
[16:12:57.613] <TB3> INFO: safety margin for low PH: adding 113, margin is now 133
[16:12:57.619] <TB3> INFO: safety margin for low PH: adding 114, margin is now 134
[16:12:57.625] <TB3> INFO: safety margin for low PH: adding 115, margin is now 135
[16:12:57.630] <TB3> INFO: safety margin for low PH: adding 116, margin is now 136
[16:12:57.636] <TB3> INFO: safety margin for low PH: adding 117, margin is now 137
[16:12:57.642] <TB3> INFO: safety margin for low PH: adding 118, margin is now 138
[16:12:57.647] <TB3> INFO: safety margin for low PH: adding 119, margin is now 139
[16:12:57.653] <TB3> INFO: safety margin for low PH: adding 120, margin is now 140
[16:12:57.659] <TB3> INFO: safety margin for low PH: adding 121, margin is now 141
[16:12:57.665] <TB3> INFO: safety margin for low PH: adding 122, margin is now 142
[16:12:57.670] <TB3> INFO: safety margin for low PH: adding 123, margin is now 143
[16:12:57.676] <TB3> INFO: safety margin for low PH: adding 124, margin is now 144
[16:12:57.682] <TB3> INFO: safety margin for low PH: adding 125, margin is now 145
[16:12:57.687] <TB3> INFO: safety margin for low PH: adding 126, margin is now 146
[16:12:57.693] <TB3> INFO: safety margin for low PH: adding 127, margin is now 147
[16:12:57.699] <TB3> INFO: safety margin for low PH: adding 128, margin is now 148
[16:12:57.704] <TB3> INFO: safety margin for low PH: adding 129, margin is now 149
[16:12:57.710] <TB3> INFO: safety margin for low PH: adding 130, margin is now 150
[16:12:57.716] <TB3> INFO: safety margin for low PH: adding 131, margin is now 151
[16:12:57.721] <TB3> INFO: safety margin for low PH: adding 132, margin is now 152
[16:12:57.727] <TB3> INFO: safety margin for low PH: adding 133, margin is now 153
[16:12:57.733] <TB3> INFO: safety margin for low PH: adding 134, margin is now 154
[16:12:57.738] <TB3> INFO: safety margin for low PH: adding 135, margin is now 155
[16:12:57.744] <TB3> INFO: safety margin for low PH: adding 136, margin is now 156
[16:12:57.750] <TB3> INFO: safety margin for low PH: adding 137, margin is now 157
[16:12:57.756] <TB3> INFO: safety margin for low PH: adding 138, margin is now 158
[16:12:57.761] <TB3> INFO: safety margin for low PH: adding 139, margin is now 159
[16:12:57.767] <TB3> INFO: safety margin for low PH: adding 140, margin is now 160
[16:12:57.773] <TB3> INFO: safety margin for low PH: adding 141, margin is now 161
[16:12:57.778] <TB3> INFO: safety margin for low PH: adding 142, margin is now 162
[16:12:57.784] <TB3> INFO: safety margin for low PH: adding 143, margin is now 163
[16:12:57.790] <TB3> INFO: safety margin for low PH: adding 144, margin is now 164
[16:12:57.795] <TB3> INFO: safety margin for low PH: adding 145, margin is now 165
[16:12:57.801] <TB3> INFO: safety margin for low PH: adding 146, margin is now 166
[16:12:57.807] <TB3> INFO: safety margin for low PH: adding 147, margin is now 167
[16:12:57.812] <TB3> INFO: safety margin for low PH: adding 148, margin is now 168
[16:12:57.818] <TB3> INFO: safety margin for low PH: adding 149, margin is now 169
[16:12:57.824] <TB3> INFO: safety margin for low PH: adding 150, margin is now 170
[16:12:57.829] <TB3> INFO: safety margin for low PH: adding 151, margin is now 171
[16:12:57.835] <TB3> INFO: safety margin for low PH: adding 152, margin is now 172
[16:12:57.841] <TB3> INFO: safety margin for low PH: adding 153, margin is now 173
[16:12:57.847] <TB3> INFO: safety margin for low PH: adding 154, margin is now 174
[16:12:57.852] <TB3> INFO: safety margin for low PH: adding 155, margin is now 175
[16:12:57.858] <TB3> INFO: safety margin for low PH: adding 156, margin is now 176
[16:12:57.864] <TB3> INFO: safety margin for low PH: adding 157, margin is now 177
[16:12:57.869] <TB3> INFO: safety margin for low PH: adding 158, margin is now 178
[16:12:57.875] <TB3> INFO: safety margin for low PH: adding 159, margin is now 179
[16:12:57.881] <TB3> INFO: safety margin for low PH: adding 160, margin is now 180
[16:12:57.886] <TB3> INFO: safety margin for low PH: adding 161, margin is now 181
[16:12:57.892] <TB3> INFO: safety margin for low PH: adding 162, margin is now 182
[16:12:57.898] <TB3> INFO: safety margin for low PH: adding 163, margin is now 183
[16:12:57.903] <TB3> INFO: safety margin for low PH: adding 164, margin is now 184
[16:12:57.909] <TB3> INFO: safety margin for low PH: adding 165, margin is now 185
[16:12:57.915] <TB3> INFO: safety margin for low PH: adding 166, margin is now 186
[16:12:57.920] <TB3> INFO: safety margin for low PH: adding 167, margin is now 187
[16:12:57.926] <TB3> INFO: safety margin for low PH: adding 168, margin is now 188
[16:12:57.932] <TB3> INFO: safety margin for low PH: adding 169, margin is now 189
[16:12:57.937] <TB3> INFO: safety margin for low PH: adding 170, margin is now 190
[16:12:57.943] <TB3> INFO: safety margin for low PH: adding 171, margin is now 191
[16:12:57.949] <TB3> INFO: safety margin for low PH: adding 172, margin is now 192
[16:12:57.955] <TB3> INFO: safety margin for low PH: adding 173, margin is now 193
[16:12:57.961] <TB3> INFO: safety margin for low PH: adding 174, margin is now 194
[16:12:57.966] <TB3> INFO: safety margin for low PH: adding 175, margin is now 195
[16:12:57.972] <TB3> INFO: safety margin for low PH: adding 176, margin is now 196
[16:12:57.978] <TB3> INFO: safety margin for low PH: adding 177, margin is now 197
[16:12:57.983] <TB3> INFO: safety margin for low PH: adding 178, margin is now 198
[16:12:57.989] <TB3> INFO: safety margin for low PH: adding 179, margin is now 199
[16:12:57.995] <TB3> INFO: safety margin for low PH: adding 180, margin is now 200
[16:12:57.000] <TB3> INFO: safety margin for low PH: adding 181, margin is now 201
[16:12:58.006] <TB3> INFO: safety margin for low PH: adding 182, margin is now 202
[16:12:58.012] <TB3> INFO: safety margin for low PH: adding 183, margin is now 203
[16:12:58.018] <TB3> INFO: safety margin for low PH: adding 184, margin is now 204
[16:12:58.023] <TB3> INFO: safety margin for low PH: adding 185, margin is now 205
[16:12:58.029] <TB3> INFO: safety margin for low PH: adding 186, margin is now 206
[16:12:58.035] <TB3> INFO: safety margin for low PH: adding 187, margin is now 207
[16:12:58.040] <TB3> INFO: safety margin for low PH: adding 188, margin is now 208
[16:12:58.046] <TB3> INFO: safety margin for low PH: adding 189, margin is now 209
[16:12:58.052] <TB3> INFO: safety margin for low PH: adding 190, margin is now 210
[16:12:58.058] <TB3> INFO: safety margin for low PH: adding 191, margin is now 211
[16:12:58.063] <TB3> INFO: safety margin for low PH: adding 192, margin is now 212
[16:12:58.069] <TB3> INFO: safety margin for low PH: adding 193, margin is now 213
[16:12:58.075] <TB3> INFO: safety margin for low PH: adding 194, margin is now 214
[16:12:58.080] <TB3> INFO: safety margin for low PH: adding 195, margin is now 215
[16:12:58.086] <TB3> INFO: safety margin for low PH: adding 196, margin is now 216
[16:12:58.092] <TB3> INFO: safety margin for low PH: adding 197, margin is now 217
[16:12:58.097] <TB3> INFO: safety margin for low PH: adding 198, margin is now 218
[16:12:58.103] <TB3> INFO: safety margin for low PH: adding 199, margin is now 219
[16:12:58.109] <TB3> INFO: safety margin for low PH: adding 200, margin is now 220
[16:12:58.115] <TB3> INFO: safety margin for low PH: adding 201, margin is now 221
[16:12:58.120] <TB3> INFO: safety margin for low PH: adding 202, margin is now 222
[16:12:58.126] <TB3> INFO: safety margin for low PH: adding 203, margin is now 223
[16:12:58.131] <TB3> INFO: safety margin for low PH: adding 204, margin is now 224
[16:12:58.137] <TB3> INFO: safety margin for low PH: adding 205, margin is now 225
[16:12:58.143] <TB3> INFO: safety margin for low PH: adding 206, margin is now 226
[16:12:58.149] <TB3> INFO: safety margin for low PH: adding 207, margin is now 227
[16:12:58.154] <TB3> INFO: safety margin for low PH: adding 208, margin is now 228
[16:12:58.160] <TB3> INFO: safety margin for low PH: adding 209, margin is now 229
[16:12:58.166] <TB3> INFO: safety margin for low PH: adding 210, margin is now 230
[16:12:58.171] <TB3> INFO: safety margin for low PH: adding 211, margin is now 231
[16:12:58.177] <TB3> INFO: safety margin for low PH: adding 212, margin is now 232
[16:12:58.183] <TB3> INFO: safety margin for low PH: adding 213, margin is now 233
[16:12:58.189] <TB3> INFO: safety margin for low PH: adding 214, margin is now 234
[16:12:58.194] <TB3> INFO: safety margin for low PH: adding 215, margin is now 235
[16:12:58.200] <TB3> INFO: safety margin for low PH: adding 216, margin is now 236
[16:12:58.206] <TB3> INFO: safety margin for low PH: adding 217, margin is now 237
[16:12:58.212] <TB3> INFO: safety margin for low PH: adding 218, margin is now 238
[16:12:58.218] <TB3> INFO: safety margin for low PH: adding 219, margin is now 239
[16:12:58.224] <TB3> INFO: safety margin for low PH: adding 220, margin is now 240
[16:12:58.231] <TB3> INFO: safety margin for low PH: adding 221, margin is now 241
[16:12:58.237] <TB3> INFO: safety margin for low PH: adding 222, margin is now 242
[16:12:58.243] <TB3> INFO: safety margin for low PH: adding 223, margin is now 243
[16:12:58.250] <TB3> INFO: safety margin for low PH: adding 224, margin is now 244
[16:12:58.256] <TB3> INFO: safety margin for low PH: adding 225, margin is now 245
[16:12:58.262] <TB3> INFO: safety margin for low PH: adding 226, margin is now 246
[16:12:58.268] <TB3> INFO: safety margin for low PH: adding 227, margin is now 247
[16:12:58.274] <TB3> INFO: safety margin for low PH: adding 228, margin is now 248
[16:12:58.281] <TB3> INFO: safety margin for low PH: adding 229, margin is now 249
[16:12:58.287] <TB3> INFO: safety margin for low PH: adding 230, margin is now 250
[16:12:58.293] <TB3> INFO: safety margin for low PH: adding 231, margin is now 251
[16:12:58.299] <TB3> INFO: safety margin for low PH: adding 232, margin is now 252
[16:12:58.305] <TB3> INFO: safety margin for low PH: adding 233, margin is now 253
[16:12:58.312] <TB3> INFO: safety margin for low PH: adding 234, margin is now 254
[16:12:58.317] <TB3> INFO: safety margin for low PH: adding 235, margin is now 255
[16:12:58.324] <TB3> INFO: For ROC 0: No solutions with PhScale > 20 could be found. Looking for a solution with PhScale < 20.
[16:12:58.324] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[16:12:58.330] <TB3> INFO: safety margin for low PH: adding 1, margin is now 21
[16:12:58.336] <TB3> INFO: safety margin for low PH: adding 2, margin is now 22
[16:12:58.343] <TB3> INFO: safety margin for low PH: adding 3, margin is now 23
[16:12:58.349] <TB3> INFO: safety margin for low PH: adding 4, margin is now 24
[16:12:58.355] <TB3> INFO: safety margin for low PH: adding 5, margin is now 25
[16:12:58.362] <TB3> INFO: safety margin for low PH: adding 6, margin is now 26
[16:12:58.368] <TB3> INFO: safety margin for low PH: adding 7, margin is now 27
[16:12:58.374] <TB3> INFO: safety margin for low PH: adding 8, margin is now 28
[16:12:58.380] <TB3> INFO: safety margin for low PH: adding 9, margin is now 29
[16:12:58.385] <TB3> INFO: safety margin for low PH: adding 10, margin is now 30
[16:12:58.391] <TB3> INFO: safety margin for low PH: adding 11, margin is now 31
[16:12:58.397] <TB3> INFO: safety margin for low PH: adding 12, margin is now 32
[16:12:58.403] <TB3> INFO: safety margin for low PH: adding 13, margin is now 33
[16:12:58.409] <TB3> INFO: safety margin for low PH: adding 14, margin is now 34
[16:12:58.415] <TB3> INFO: safety margin for low PH: adding 15, margin is now 35
[16:12:58.420] <TB3> INFO: safety margin for low PH: adding 16, margin is now 36
[16:12:58.426] <TB3> INFO: safety margin for low PH: adding 17, margin is now 37
[16:12:58.432] <TB3> INFO: safety margin for low PH: adding 18, margin is now 38
[16:12:58.438] <TB3> INFO: safety margin for low PH: adding 19, margin is now 39
[16:12:58.444] <TB3> INFO: safety margin for low PH: adding 20, margin is now 40
[16:12:58.450] <TB3> INFO: safety margin for low PH: adding 21, margin is now 41
[16:12:58.456] <TB3> INFO: safety margin for low PH: adding 22, margin is now 42
[16:12:58.462] <TB3> INFO: safety margin for low PH: adding 23, margin is now 43
[16:12:58.468] <TB3> INFO: safety margin for low PH: adding 24, margin is now 44
[16:12:58.474] <TB3> INFO: safety margin for low PH: adding 25, margin is now 45
[16:12:58.480] <TB3> INFO: safety margin for low PH: adding 26, margin is now 46
[16:12:58.485] <TB3> INFO: safety margin for low PH: adding 27, margin is now 47
[16:12:58.492] <TB3> INFO: safety margin for low PH: adding 28, margin is now 48
[16:12:58.498] <TB3> INFO: safety margin for low PH: adding 29, margin is now 49
[16:12:58.504] <TB3> INFO: safety margin for low PH: adding 30, margin is now 50
[16:12:58.510] <TB3> INFO: safety margin for low PH: adding 31, margin is now 51
[16:12:58.516] <TB3> INFO: safety margin for low PH: adding 32, margin is now 52
[16:12:58.522] <TB3> INFO: safety margin for low PH: adding 33, margin is now 53
[16:12:58.528] <TB3> INFO: safety margin for low PH: adding 34, margin is now 54
[16:12:58.534] <TB3> INFO: safety margin for low PH: adding 35, margin is now 55
[16:12:58.540] <TB3> INFO: safety margin for low PH: adding 36, margin is now 56
[16:12:58.546] <TB3> INFO: safety margin for low PH: adding 37, margin is now 57
[16:12:58.551] <TB3> INFO: safety margin for low PH: adding 38, margin is now 58
[16:12:58.557] <TB3> INFO: safety margin for low PH: adding 39, margin is now 59
[16:12:58.563] <TB3> INFO: safety margin for low PH: adding 40, margin is now 60
[16:12:58.569] <TB3> INFO: safety margin for low PH: adding 41, margin is now 61
[16:12:58.575] <TB3> INFO: safety margin for low PH: adding 42, margin is now 62
[16:12:58.581] <TB3> INFO: safety margin for low PH: adding 43, margin is now 63
[16:12:58.588] <TB3> INFO: safety margin for low PH: adding 44, margin is now 64
[16:12:58.594] <TB3> INFO: safety margin for low PH: adding 45, margin is now 65
[16:12:58.600] <TB3> INFO: safety margin for low PH: adding 46, margin is now 66
[16:12:58.605] <TB3> INFO: safety margin for low PH: adding 47, margin is now 67
[16:12:58.611] <TB3> INFO: safety margin for low PH: adding 48, margin is now 68
[16:12:58.618] <TB3> INFO: safety margin for low PH: adding 49, margin is now 69
[16:12:58.624] <TB3> INFO: safety margin for low PH: adding 50, margin is now 70
[16:12:58.629] <TB3> INFO: safety margin for low PH: adding 51, margin is now 71
[16:12:58.635] <TB3> INFO: safety margin for low PH: adding 52, margin is now 72
[16:12:58.641] <TB3> INFO: safety margin for low PH: adding 53, margin is now 73
[16:12:58.647] <TB3> INFO: safety margin for low PH: adding 54, margin is now 74
[16:12:58.653] <TB3> INFO: safety margin for low PH: adding 55, margin is now 75
[16:12:58.659] <TB3> INFO: safety margin for low PH: adding 56, margin is now 76
[16:12:58.665] <TB3> INFO: safety margin for low PH: adding 57, margin is now 77
[16:12:58.671] <TB3> INFO: safety margin for low PH: adding 58, margin is now 78
[16:12:58.680] <TB3> INFO: safety margin for low PH: adding 59, margin is now 79
[16:12:58.686] <TB3> INFO: safety margin for low PH: adding 60, margin is now 80
[16:12:58.696] <TB3> INFO: safety margin for low PH: adding 61, margin is now 81
[16:12:58.702] <TB3> INFO: safety margin for low PH: adding 62, margin is now 82
[16:12:58.711] <TB3> INFO: safety margin for low PH: adding 63, margin is now 83
[16:12:58.717] <TB3> INFO: safety margin for low PH: adding 64, margin is now 84
[16:12:58.725] <TB3> INFO: safety margin for low PH: adding 65, margin is now 85
[16:12:58.732] <TB3> INFO: safety margin for low PH: adding 66, margin is now 86
[16:12:58.739] <TB3> INFO: safety margin for low PH: adding 67, margin is now 87
[16:12:58.748] <TB3> INFO: safety margin for low PH: adding 68, margin is now 88
[16:12:58.754] <TB3> INFO: safety margin for low PH: adding 69, margin is now 89
[16:12:58.763] <TB3> INFO: safety margin for low PH: adding 70, margin is now 90
[16:12:58.769] <TB3> INFO: safety margin for low PH: adding 71, margin is now 91
[16:12:58.778] <TB3> INFO: safety margin for low PH: adding 72, margin is now 92
[16:12:58.784] <TB3> INFO: safety margin for low PH: adding 73, margin is now 93
[16:12:58.793] <TB3> INFO: safety margin for low PH: adding 74, margin is now 94
[16:12:58.799] <TB3> INFO: safety margin for low PH: adding 75, margin is now 95
[16:12:58.808] <TB3> INFO: safety margin for low PH: adding 76, margin is now 96
[16:12:58.815] <TB3> INFO: safety margin for low PH: adding 77, margin is now 97
[16:12:58.823] <TB3> INFO: safety margin for low PH: adding 78, margin is now 98
[16:12:58.831] <TB3> INFO: safety margin for low PH: adding 79, margin is now 99
[16:12:58.837] <TB3> INFO: safety margin for low PH: adding 80, margin is now 100
[16:12:58.843] <TB3> INFO: safety margin for low PH: adding 81, margin is now 101
[16:12:58.849] <TB3> INFO: safety margin for low PH: adding 82, margin is now 102
[16:12:58.855] <TB3> INFO: safety margin for low PH: adding 83, margin is now 103
[16:12:58.861] <TB3> INFO: safety margin for low PH: adding 84, margin is now 104
[16:12:58.867] <TB3> INFO: safety margin for low PH: adding 85, margin is now 105
[16:12:58.873] <TB3> INFO: safety margin for low PH: adding 86, margin is now 106
[16:12:58.879] <TB3> INFO: safety margin for low PH: adding 87, margin is now 107
[16:12:58.885] <TB3> INFO: safety margin for low PH: adding 88, margin is now 108
[16:12:58.892] <TB3> INFO: safety margin for low PH: adding 89, margin is now 109
[16:12:58.898] <TB3> INFO: safety margin for low PH: adding 90, margin is now 110
[16:12:58.904] <TB3> INFO: safety margin for low PH: adding 91, margin is now 111
[16:12:58.910] <TB3> INFO: safety margin for low PH: adding 92, margin is now 112
[16:12:58.916] <TB3> INFO: safety margin for low PH: adding 93, margin is now 113
[16:12:58.923] <TB3> INFO: safety margin for low PH: adding 94, margin is now 114
[16:12:58.929] <TB3> INFO: safety margin for low PH: adding 95, margin is now 115
[16:12:58.935] <TB3> INFO: safety margin for low PH: adding 96, margin is now 116
[16:12:58.941] <TB3> INFO: safety margin for low PH: adding 97, margin is now 117
[16:12:58.948] <TB3> INFO: safety margin for low PH: adding 98, margin is now 118
[16:12:58.954] <TB3> INFO: safety margin for low PH: adding 99, margin is now 119
[16:12:58.960] <TB3> INFO: safety margin for low PH: adding 100, margin is now 120
[16:12:58.966] <TB3> INFO: safety margin for low PH: adding 101, margin is now 121
[16:12:58.971] <TB3> INFO: safety margin for low PH: adding 102, margin is now 122
[16:12:58.977] <TB3> INFO: safety margin for low PH: adding 103, margin is now 123
[16:12:58.983] <TB3> INFO: safety margin for low PH: adding 104, margin is now 124
[16:12:58.989] <TB3> INFO: safety margin for low PH: adding 105, margin is now 125
[16:12:58.995] <TB3> INFO: safety margin for low PH: adding 106, margin is now 126
[16:12:58.001] <TB3> INFO: safety margin for low PH: adding 107, margin is now 127
[16:12:59.007] <TB3> INFO: safety margin for low PH: adding 108, margin is now 128
[16:12:59.013] <TB3> INFO: safety margin for low PH: adding 109, margin is now 129
[16:12:59.019] <TB3> INFO: safety margin for low PH: adding 110, margin is now 130
[16:12:59.025] <TB3> INFO: safety margin for low PH: adding 111, margin is now 131
[16:12:59.031] <TB3> INFO: safety margin for low PH: adding 112, margin is now 132
[16:12:59.037] <TB3> INFO: safety margin for low PH: adding 113, margin is now 133
[16:12:59.043] <TB3> INFO: safety margin for low PH: adding 114, margin is now 134
[16:12:59.049] <TB3> INFO: safety margin for low PH: adding 115, margin is now 135
[16:12:59.056] <TB3> INFO: safety margin for low PH: adding 116, margin is now 136
[16:12:59.065] <TB3> INFO: safety margin for low PH: adding 117, margin is now 137
[16:12:59.073] <TB3> INFO: safety margin for low PH: adding 118, margin is now 138
[16:12:59.081] <TB3> INFO: safety margin for low PH: adding 119, margin is now 139
[16:12:59.089] <TB3> INFO: safety margin for low PH: adding 120, margin is now 140
[16:12:59.098] <TB3> INFO: safety margin for low PH: adding 121, margin is now 141
[16:12:59.106] <TB3> INFO: safety margin for low PH: adding 122, margin is now 142
[16:12:59.114] <TB3> INFO: safety margin for low PH: adding 123, margin is now 143
[16:12:59.122] <TB3> INFO: safety margin for low PH: adding 124, margin is now 144
[16:12:59.130] <TB3> INFO: safety margin for low PH: adding 125, margin is now 145
[16:12:59.138] <TB3> INFO: safety margin for low PH: adding 126, margin is now 146
[16:12:59.147] <TB3> INFO: safety margin for low PH: adding 127, margin is now 147
[16:12:59.155] <TB3> INFO: safety margin for low PH: adding 128, margin is now 148
[16:12:59.163] <TB3> INFO: safety margin for low PH: adding 129, margin is now 149
[16:12:59.172] <TB3> INFO: safety margin for low PH: adding 130, margin is now 150
[16:12:59.180] <TB3> INFO: safety margin for low PH: adding 131, margin is now 151
[16:12:59.188] <TB3> INFO: safety margin for low PH: adding 132, margin is now 152
[16:12:59.196] <TB3> INFO: safety margin for low PH: adding 133, margin is now 153
[16:12:59.204] <TB3> INFO: safety margin for low PH: adding 134, margin is now 154
[16:12:59.213] <TB3> INFO: safety margin for low PH: adding 135, margin is now 155
[16:12:59.221] <TB3> INFO: safety margin for low PH: adding 136, margin is now 156
[16:12:59.229] <TB3> INFO: safety margin for low PH: adding 137, margin is now 157
[16:12:59.237] <TB3> INFO: safety margin for low PH: adding 138, margin is now 158
[16:12:59.246] <TB3> INFO: safety margin for low PH: adding 139, margin is now 159
[16:12:59.254] <TB3> INFO: safety margin for low PH: adding 140, margin is now 160
[16:12:59.262] <TB3> INFO: safety margin for low PH: adding 141, margin is now 161
[16:12:59.270] <TB3> INFO: safety margin for low PH: adding 142, margin is now 162
[16:12:59.278] <TB3> INFO: safety margin for low PH: adding 143, margin is now 163
[16:12:59.286] <TB3> INFO: safety margin for low PH: adding 144, margin is now 164
[16:12:59.295] <TB3> INFO: safety margin for low PH: adding 145, margin is now 165
[16:12:59.303] <TB3> INFO: safety margin for low PH: adding 146, margin is now 166
[16:12:59.311] <TB3> INFO: safety margin for low PH: adding 147, margin is now 167
[16:12:59.319] <TB3> INFO: safety margin for low PH: adding 148, margin is now 168
[16:12:59.328] <TB3> INFO: safety margin for low PH: adding 149, margin is now 169
[16:12:59.336] <TB3> INFO: safety margin for low PH: adding 150, margin is now 170
[16:12:59.344] <TB3> INFO: safety margin for low PH: adding 151, margin is now 171
[16:12:59.352] <TB3> INFO: safety margin for low PH: adding 152, margin is now 172
[16:12:59.361] <TB3> INFO: safety margin for low PH: adding 153, margin is now 173
[16:12:59.369] <TB3> INFO: safety margin for low PH: adding 154, margin is now 174
[16:12:59.378] <TB3> INFO: safety margin for low PH: adding 155, margin is now 175
[16:12:59.386] <TB3> INFO: safety margin for low PH: adding 156, margin is now 176
[16:12:59.394] <TB3> INFO: safety margin for low PH: adding 157, margin is now 177
[16:12:59.402] <TB3> INFO: safety margin for low PH: adding 158, margin is now 178
[16:12:59.410] <TB3> INFO: safety margin for low PH: adding 159, margin is now 179
[16:12:59.418] <TB3> INFO: safety margin for low PH: adding 160, margin is now 180
[16:12:59.427] <TB3> INFO: safety margin for low PH: adding 161, margin is now 181
[16:12:59.435] <TB3> INFO: safety margin for low PH: adding 162, margin is now 182
[16:12:59.444] <TB3> INFO: safety margin for low PH: adding 163, margin is now 183
[16:12:59.452] <TB3> INFO: safety margin for low PH: adding 164, margin is now 184
[16:12:59.460] <TB3> INFO: safety margin for low PH: adding 165, margin is now 185
[16:12:59.468] <TB3> INFO: safety margin for low PH: adding 166, margin is now 186
[16:12:59.477] <TB3> INFO: safety margin for low PH: adding 167, margin is now 187
[16:12:59.485] <TB3> INFO: safety margin for low PH: adding 168, margin is now 188
[16:12:59.494] <TB3> INFO: safety margin for low PH: adding 169, margin is now 189
[16:12:59.502] <TB3> INFO: safety margin for low PH: adding 170, margin is now 190
[16:12:59.510] <TB3> INFO: safety margin for low PH: adding 171, margin is now 191
[16:12:59.518] <TB3> INFO: safety margin for low PH: adding 172, margin is now 192
[16:12:59.526] <TB3> INFO: safety margin for low PH: adding 173, margin is now 193
[16:12:59.534] <TB3> INFO: safety margin for low PH: adding 174, margin is now 194
[16:12:59.543] <TB3> INFO: safety margin for low PH: adding 175, margin is now 195
[16:12:59.551] <TB3> INFO: safety margin for low PH: adding 176, margin is now 196
[16:12:59.559] <TB3> INFO: safety margin for low PH: adding 177, margin is now 197
[16:12:59.567] <TB3> INFO: safety margin for low PH: adding 178, margin is now 198
[16:12:59.575] <TB3> INFO: safety margin for low PH: adding 179, margin is now 199
[16:12:59.583] <TB3> INFO: safety margin for low PH: adding 180, margin is now 200
[16:12:59.592] <TB3> INFO: safety margin for low PH: adding 181, margin is now 201
[16:12:59.600] <TB3> INFO: safety margin for low PH: adding 182, margin is now 202
[16:12:59.608] <TB3> INFO: safety margin for low PH: adding 183, margin is now 203
[16:12:59.616] <TB3> INFO: safety margin for low PH: adding 184, margin is now 204
[16:12:59.624] <TB3> INFO: safety margin for low PH: adding 185, margin is now 205
[16:12:59.632] <TB3> INFO: safety margin for low PH: adding 186, margin is now 206
[16:12:59.641] <TB3> INFO: safety margin for low PH: adding 187, margin is now 207
[16:12:59.649] <TB3> INFO: safety margin for low PH: adding 188, margin is now 208
[16:12:59.657] <TB3> INFO: safety margin for low PH: adding 189, margin is now 209
[16:12:59.665] <TB3> INFO: safety margin for low PH: adding 190, margin is now 210
[16:12:59.674] <TB3> INFO: safety margin for low PH: adding 191, margin is now 211
[16:12:59.682] <TB3> INFO: safety margin for low PH: adding 192, margin is now 212
[16:12:59.690] <TB3> INFO: safety margin for low PH: adding 193, margin is now 213
[16:12:59.698] <TB3> INFO: safety margin for low PH: adding 194, margin is now 214
[16:12:59.706] <TB3> INFO: safety margin for low PH: adding 195, margin is now 215
[16:12:59.714] <TB3> INFO: safety margin for low PH: adding 196, margin is now 216
[16:12:59.722] <TB3> INFO: safety margin for low PH: adding 197, margin is now 217
[16:12:59.731] <TB3> INFO: safety margin for low PH: adding 198, margin is now 218
[16:12:59.740] <TB3> INFO: safety margin for low PH: adding 199, margin is now 219
[16:12:59.748] <TB3> INFO: safety margin for low PH: adding 200, margin is now 220
[16:12:59.756] <TB3> INFO: safety margin for low PH: adding 201, margin is now 221
[16:12:59.764] <TB3> INFO: safety margin for low PH: adding 202, margin is now 222
[16:12:59.773] <TB3> INFO: safety margin for low PH: adding 203, margin is now 223
[16:12:59.782] <TB3> INFO: safety margin for low PH: adding 204, margin is now 224
[16:12:59.791] <TB3> INFO: safety margin for low PH: adding 205, margin is now 225
[16:12:59.800] <TB3> INFO: safety margin for low PH: adding 206, margin is now 226
[16:12:59.809] <TB3> INFO: safety margin for low PH: adding 207, margin is now 227
[16:12:59.818] <TB3> INFO: safety margin for low PH: adding 208, margin is now 228
[16:12:59.827] <TB3> INFO: safety margin for low PH: adding 209, margin is now 229
[16:12:59.836] <TB3> INFO: safety margin for low PH: adding 210, margin is now 230
[16:12:59.846] <TB3> INFO: safety margin for low PH: adding 211, margin is now 231
[16:12:59.855] <TB3> INFO: safety margin for low PH: adding 212, margin is now 232
[16:12:59.865] <TB3> INFO: safety margin for low PH: adding 213, margin is now 233
[16:12:59.874] <TB3> INFO: safety margin for low PH: adding 214, margin is now 234
[16:12:59.882] <TB3> INFO: safety margin for low PH: adding 215, margin is now 235
[16:12:59.891] <TB3> INFO: safety margin for low PH: adding 216, margin is now 236
[16:12:59.899] <TB3> INFO: safety margin for low PH: adding 217, margin is now 237
[16:12:59.908] <TB3> INFO: safety margin for low PH: adding 218, margin is now 238
[16:12:59.916] <TB3> INFO: safety margin for low PH: adding 219, margin is now 239
[16:12:59.923] <TB3> INFO: safety margin for low PH: adding 220, margin is now 240
[16:12:59.930] <TB3> INFO: safety margin for low PH: adding 221, margin is now 241
[16:12:59.937] <TB3> INFO: safety margin for low PH: adding 222, margin is now 242
[16:12:59.944] <TB3> INFO: safety margin for low PH: adding 223, margin is now 243
[16:12:59.950] <TB3> INFO: safety margin for low PH: adding 224, margin is now 244
[16:12:59.959] <TB3> INFO: safety margin for low PH: adding 225, margin is now 245
[16:12:59.968] <TB3> INFO: safety margin for low PH: adding 226, margin is now 246
[16:12:59.976] <TB3> INFO: safety margin for low PH: adding 227, margin is now 247
[16:12:59.984] <TB3> INFO: safety margin for low PH: adding 228, margin is now 248
[16:12:59.993] <TB3> INFO: safety margin for low PH: adding 229, margin is now 249
[16:12:59.001] <TB3> INFO: safety margin for low PH: adding 230, margin is now 250
[16:13:00.009] <TB3> INFO: safety margin for low PH: adding 231, margin is now 251
[16:13:00.017] <TB3> INFO: safety margin for low PH: adding 232, margin is now 252
[16:13:00.025] <TB3> INFO: safety margin for low PH: adding 233, margin is now 253
[16:13:00.033] <TB3> INFO: safety margin for low PH: adding 234, margin is now 254
[16:13:00.042] <TB3> INFO: safety margin for low PH: adding 235, margin is now 255
[16:13:00.050] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[16:13:00.059] <TB3> INFO: safety margin for low PH: adding 1, margin is now 21
[16:13:00.067] <TB3> INFO: safety margin for low PH: adding 2, margin is now 22
[16:13:00.075] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[16:13:00.084] <TB3> INFO: safety margin for low PH: adding 1, margin is now 21
[16:13:00.092] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[16:13:00.100] <TB3> INFO: safety margin for low PH: adding 1, margin is now 21
[16:13:00.108] <TB3> INFO: safety margin for low PH: adding 2, margin is now 22
[16:13:00.117] <TB3> INFO: safety margin for low PH: adding 3, margin is now 23
[16:13:00.125] <TB3> INFO: safety margin for low PH: adding 4, margin is now 24
[16:13:00.133] <TB3> INFO: safety margin for low PH: adding 5, margin is now 25
[16:13:00.141] <TB3> INFO: safety margin for low PH: adding 6, margin is now 26
[16:13:00.150] <TB3> INFO: safety margin for low PH: adding 7, margin is now 27
[16:13:00.158] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[16:13:00.166] <TB3> INFO: safety margin for low PH: adding 1, margin is now 21
[16:13:00.174] <TB3> INFO: safety margin for low PH: adding 2, margin is now 22
[16:13:00.181] <TB3> INFO: safety margin for low PH: adding 3, margin is now 23
[16:13:00.187] <TB3> INFO: safety margin for low PH: adding 4, margin is now 24
[16:13:00.193] <TB3> INFO: safety margin for low PH: adding 5, margin is now 25
[16:13:00.199] <TB3> INFO: safety margin for low PH: adding 6, margin is now 26
[16:13:00.204] <TB3> INFO: safety margin for low PH: adding 7, margin is now 27
[16:13:00.210] <TB3> INFO: safety margin for low PH: adding 8, margin is now 28
[16:13:00.216] <TB3> INFO: safety margin for low PH: adding 9, margin is now 29
[16:13:00.222] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[16:13:00.231] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[16:13:00.240] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[16:13:00.248] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[16:13:00.256] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[16:13:00.264] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[16:13:00.272] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[16:13:00.280] <TB3> INFO: safety margin for low PH: adding 1, margin is now 21
[16:13:00.289] <TB3> INFO: safety margin for low PH: adding 2, margin is now 22
[16:13:00.297] <TB3> INFO: safety margin for low PH: adding 3, margin is now 23
[16:13:00.305] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[16:13:00.313] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[16:13:00.322] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[16:13:00.330] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[16:13:00.369] <TB3> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1012_FullQualification_2016-10-17_13h57m_1476705426//001_Fulltest_m20//dacParameters35_C0.dat
[16:13:00.369] <TB3> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1012_FullQualification_2016-10-17_13h57m_1476705426//001_Fulltest_m20//dacParameters35_C1.dat
[16:13:00.370] <TB3> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1012_FullQualification_2016-10-17_13h57m_1476705426//001_Fulltest_m20//dacParameters35_C2.dat
[16:13:00.370] <TB3> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1012_FullQualification_2016-10-17_13h57m_1476705426//001_Fulltest_m20//dacParameters35_C3.dat
[16:13:00.370] <TB3> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1012_FullQualification_2016-10-17_13h57m_1476705426//001_Fulltest_m20//dacParameters35_C4.dat
[16:13:00.370] <TB3> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1012_FullQualification_2016-10-17_13h57m_1476705426//001_Fulltest_m20//dacParameters35_C5.dat
[16:13:00.370] <TB3> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1012_FullQualification_2016-10-17_13h57m_1476705426//001_Fulltest_m20//dacParameters35_C6.dat
[16:13:00.370] <TB3> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1012_FullQualification_2016-10-17_13h57m_1476705426//001_Fulltest_m20//dacParameters35_C7.dat
[16:13:00.370] <TB3> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1012_FullQualification_2016-10-17_13h57m_1476705426//001_Fulltest_m20//dacParameters35_C8.dat
[16:13:00.370] <TB3> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1012_FullQualification_2016-10-17_13h57m_1476705426//001_Fulltest_m20//dacParameters35_C9.dat
[16:13:00.371] <TB3> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1012_FullQualification_2016-10-17_13h57m_1476705426//001_Fulltest_m20//dacParameters35_C10.dat
[16:13:00.371] <TB3> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1012_FullQualification_2016-10-17_13h57m_1476705426//001_Fulltest_m20//dacParameters35_C11.dat
[16:13:00.371] <TB3> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1012_FullQualification_2016-10-17_13h57m_1476705426//001_Fulltest_m20//dacParameters35_C12.dat
[16:13:00.371] <TB3> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1012_FullQualification_2016-10-17_13h57m_1476705426//001_Fulltest_m20//dacParameters35_C13.dat
[16:13:00.371] <TB3> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1012_FullQualification_2016-10-17_13h57m_1476705426//001_Fulltest_m20//dacParameters35_C14.dat
[16:13:00.371] <TB3> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1012_FullQualification_2016-10-17_13h57m_1476705426//001_Fulltest_m20//dacParameters35_C15.dat
[16:13:00.616] <TB3> INFO: Expecting 41600 events.
[16:13:03.782] <TB3> INFO: 41600 events read in total (2575ms).
[16:13:03.783] <TB3> INFO: Test took 3408ms.
[16:13:04.245] <TB3> INFO: Expecting 41600 events.
[16:13:07.396] <TB3> INFO: 41600 events read in total (2560ms).
[16:13:07.397] <TB3> INFO: Test took 3402ms.
[16:13:07.857] <TB3> INFO: Expecting 41600 events.
[16:13:11.048] <TB3> INFO: 41600 events read in total (2600ms).
[16:13:11.049] <TB3> INFO: Test took 3439ms.



[16:13:11.358] <TB3> INFO: Test took 90ms.
[16:13:11.362] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[16:13:11.826] <TB3> INFO: Expecting 2560 events.
[16:13:12.717] <TB3> INFO: 2560 events read in total (300ms).
[16:13:12.717] <TB3> INFO: Test took 1356ms.
[16:13:12.720] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[16:13:13.026] <TB3> INFO: Expecting 2560 events.
[16:13:13.911] <TB3> INFO: 2560 events read in total (293ms).
[16:13:13.912] <TB3> INFO: Test took 1192ms.
[16:13:13.915] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[16:13:14.220] <TB3> INFO: Expecting 2560 events.
[16:13:15.113] <TB3> INFO: 2560 events read in total (301ms).
[16:13:15.113] <TB3> INFO: Test took 1198ms.
[16:13:15.117] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[16:13:15.422] <TB3> INFO: Expecting 2560 events.
[16:13:16.307] <TB3> INFO: 2560 events read in total (294ms).
[16:13:16.307] <TB3> INFO: Test took 1191ms.
[16:13:16.310] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[16:13:16.621] <TB3> INFO: Expecting 2560 events.
[16:13:17.516] <TB3> INFO: 2560 events read in total (298ms).
[16:13:17.516] <TB3> INFO: Test took 1206ms.
[16:13:17.518] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[16:13:17.826] <TB3> INFO: Expecting 2560 events.
[16:13:18.719] <TB3> INFO: 2560 events read in total (302ms).
[16:13:18.719] <TB3> INFO: Test took 1201ms.
[16:13:18.722] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[16:13:19.028] <TB3> INFO: Expecting 2560 events.
[16:13:19.922] <TB3> INFO: 2560 events read in total (303ms).
[16:13:19.922] <TB3> INFO: Test took 1200ms.
[16:13:19.927] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[16:13:20.230] <TB3> INFO: Expecting 2560 events.
[16:13:21.116] <TB3> INFO: 2560 events read in total (294ms).
[16:13:21.116] <TB3> INFO: Test took 1189ms.
[16:13:21.119] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[16:13:21.423] <TB3> INFO: Expecting 2560 events.
[16:13:22.314] <TB3> INFO: 2560 events read in total (299ms).
[16:13:22.314] <TB3> INFO: Test took 1195ms.
[16:13:22.317] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[16:13:22.624] <TB3> INFO: Expecting 2560 events.
[16:13:23.515] <TB3> INFO: 2560 events read in total (299ms).
[16:13:23.515] <TB3> INFO: Test took 1198ms.
[16:13:23.519] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[16:13:23.824] <TB3> INFO: Expecting 2560 events.
[16:13:24.710] <TB3> INFO: 2560 events read in total (294ms).
[16:13:24.710] <TB3> INFO: Test took 1192ms.
[16:13:24.712] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[16:13:25.019] <TB3> INFO: Expecting 2560 events.
[16:13:25.905] <TB3> INFO: 2560 events read in total (294ms).
[16:13:25.906] <TB3> INFO: Test took 1194ms.
[16:13:25.908] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[16:13:26.214] <TB3> INFO: Expecting 2560 events.
[16:13:27.103] <TB3> INFO: 2560 events read in total (297ms).
[16:13:27.104] <TB3> INFO: Test took 1196ms.
[16:13:27.107] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[16:13:27.412] <TB3> INFO: Expecting 2560 events.
[16:13:28.302] <TB3> INFO: 2560 events read in total (298ms).
[16:13:28.302] <TB3> INFO: Test took 1196ms.
[16:13:28.305] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[16:13:28.610] <TB3> INFO: Expecting 2560 events.
[16:13:29.495] <TB3> INFO: 2560 events read in total (293ms).
[16:13:29.495] <TB3> INFO: Test took 1190ms.
[16:13:29.499] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[16:13:29.803] <TB3> INFO: Expecting 2560 events.
[16:13:30.694] <TB3> INFO: 2560 events read in total (299ms).
[16:13:30.694] <TB3> INFO: Test took 1195ms.
[16:13:30.697] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[16:13:30.001] <TB3> INFO: Expecting 2560 events.
[16:13:31.888] <TB3> INFO: 2560 events read in total (295ms).
[16:13:31.889] <TB3> INFO: Test took 1192ms.
[16:13:31.892] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[16:13:32.197] <TB3> INFO: Expecting 2560 events.
[16:13:33.088] <TB3> INFO: 2560 events read in total (299ms).
[16:13:33.089] <TB3> INFO: Test took 1198ms.
[16:13:33.092] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[16:13:33.397] <TB3> INFO: Expecting 2560 events.
[16:13:34.288] <TB3> INFO: 2560 events read in total (299ms).
[16:13:34.288] <TB3> INFO: Test took 1196ms.
[16:13:34.290] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[16:13:34.597] <TB3> INFO: Expecting 2560 events.
[16:13:35.477] <TB3> INFO: 2560 events read in total (288ms).
[16:13:35.478] <TB3> INFO: Test took 1188ms.
[16:13:35.481] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[16:13:35.786] <TB3> INFO: Expecting 2560 events.
[16:13:36.676] <TB3> INFO: 2560 events read in total (298ms).
[16:13:36.677] <TB3> INFO: Test took 1196ms.
[16:13:36.680] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[16:13:36.985] <TB3> INFO: Expecting 2560 events.
[16:13:37.867] <TB3> INFO: 2560 events read in total (290ms).
[16:13:37.868] <TB3> INFO: Test took 1189ms.
[16:13:37.871] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[16:13:38.176] <TB3> INFO: Expecting 2560 events.
[16:13:39.065] <TB3> INFO: 2560 events read in total (298ms).
[16:13:39.065] <TB3> INFO: Test took 1195ms.
[16:13:39.068] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[16:13:39.374] <TB3> INFO: Expecting 2560 events.
[16:13:40.269] <TB3> INFO: 2560 events read in total (303ms).
[16:13:40.269] <TB3> INFO: Test took 1201ms.
[16:13:40.274] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[16:13:40.577] <TB3> INFO: Expecting 2560 events.
[16:13:41.471] <TB3> INFO: 2560 events read in total (302ms).
[16:13:41.471] <TB3> INFO: Test took 1198ms.
[16:13:41.476] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[16:13:41.779] <TB3> INFO: Expecting 2560 events.
[16:13:42.673] <TB3> INFO: 2560 events read in total (302ms).
[16:13:42.674] <TB3> INFO: Test took 1198ms.
[16:13:42.677] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[16:13:42.981] <TB3> INFO: Expecting 2560 events.
[16:13:43.870] <TB3> INFO: 2560 events read in total (297ms).
[16:13:43.870] <TB3> INFO: Test took 1193ms.
[16:13:43.873] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[16:13:44.180] <TB3> INFO: Expecting 2560 events.
[16:13:45.078] <TB3> INFO: 2560 events read in total (306ms).
[16:13:45.078] <TB3> INFO: Test took 1205ms.
[16:13:45.081] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[16:13:45.387] <TB3> INFO: Expecting 2560 events.
[16:13:46.278] <TB3> INFO: 2560 events read in total (299ms).
[16:13:46.279] <TB3> INFO: Test took 1198ms.
[16:13:46.281] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[16:13:46.587] <TB3> INFO: Expecting 2560 events.
[16:13:47.481] <TB3> INFO: 2560 events read in total (302ms).
[16:13:47.482] <TB3> INFO: Test took 1201ms.
[16:13:47.486] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[16:13:47.790] <TB3> INFO: Expecting 2560 events.
[16:13:48.680] <TB3> INFO: 2560 events read in total (298ms).
[16:13:48.680] <TB3> INFO: Test took 1195ms.
[16:13:49.172] <TB3> INFO: PixTestPhOptimization::doTest() done, duration: 737 seconds
[16:13:49.172] <TB3> INFO: PH scale (per ROC): 0 40 46 48 48 47 49 55 50 41 60 43 56 57 54 57
[16:13:49.172] <TB3> INFO: PH offset (per ROC): 0 110 137 134 136 116 142 129 131 110 133 126 126 132 126 114
[16:13:49.181] <TB3> INFO: Decoding statistics:
[16:13:49.181] <TB3> INFO: General information:
[16:13:49.181] <TB3> INFO: 16bit words read: 127882
[16:13:49.181] <TB3> INFO: valid events total: 20480
[16:13:49.181] <TB3> INFO: empty events: 17979
[16:13:49.181] <TB3> INFO: valid events with pixels: 2501
[16:13:49.181] <TB3> INFO: valid pixel hits: 2501
[16:13:49.181] <TB3> INFO: Event errors: 0
[16:13:49.181] <TB3> INFO: start marker: 0
[16:13:49.181] <TB3> INFO: stop marker: 0
[16:13:49.181] <TB3> INFO: overflow: 0
[16:13:49.181] <TB3> INFO: invalid 5bit words: 0
[16:13:49.181] <TB3> INFO: invalid XOR eye diagram: 0
[16:13:49.181] <TB3> INFO: frame (failed synchr.): 0
[16:13:49.181] <TB3> INFO: idle data (no TBM trl): 0
[16:13:49.182] <TB3> INFO: no data (only TBM hdr): 0
[16:13:49.182] <TB3> INFO: TBM errors: 0
[16:13:49.182] <TB3> INFO: flawed TBM headers: 0
[16:13:49.182] <TB3> INFO: flawed TBM trailers: 0
[16:13:49.182] <TB3> INFO: event ID mismatches: 0
[16:13:49.182] <TB3> INFO: ROC errors: 0
[16:13:49.182] <TB3> INFO: missing ROC header(s): 0
[16:13:49.182] <TB3> INFO: misplaced readback start: 0
[16:13:49.182] <TB3> INFO: Pixel decoding errors: 0
[16:13:49.182] <TB3> INFO: pixel data incomplete: 0
[16:13:49.182] <TB3> INFO: pixel address: 0
[16:13:49.182] <TB3> INFO: pulse height fill bit: 0
[16:13:49.182] <TB3> INFO: buffer corruption: 0
[16:13:49.363] <TB3> INFO: ######################################################################
[16:13:49.363] <TB3> INFO: PixTestGainPedestal::fullTest() ntrig = 10
[16:13:49.363] <TB3> INFO: ######################################################################
[16:13:49.377] <TB3> INFO: scanning low vcal = 10
[16:13:49.613] <TB3> INFO: Expecting 41600 events.
[16:13:53.189] <TB3> INFO: 41600 events read in total (2985ms).
[16:13:53.189] <TB3> INFO: Test took 3812ms.
[16:13:53.191] <TB3> INFO: scanning low vcal = 20
[16:13:53.489] <TB3> INFO: Expecting 41600 events.
[16:13:57.066] <TB3> INFO: 41600 events read in total (2986ms).
[16:13:57.067] <TB3> INFO: Test took 3876ms.
[16:13:57.069] <TB3> INFO: scanning low vcal = 30
[16:13:57.367] <TB3> INFO: Expecting 41600 events.
[16:14:00.001] <TB3> INFO: 41600 events read in total (3043ms).
[16:14:00.002] <TB3> INFO: Test took 3933ms.
[16:14:01.005] <TB3> INFO: scanning low vcal = 40
[16:14:01.282] <TB3> INFO: Expecting 41600 events.
[16:14:05.243] <TB3> INFO: 41600 events read in total (3369ms).
[16:14:05.244] <TB3> INFO: Test took 4238ms.
[16:14:05.249] <TB3> INFO: scanning low vcal = 50
[16:14:05.525] <TB3> INFO: Expecting 41600 events.
[16:14:09.514] <TB3> INFO: 41600 events read in total (3398ms).
[16:14:09.515] <TB3> INFO: Test took 4266ms.
[16:14:09.518] <TB3> INFO: scanning low vcal = 60
[16:14:09.794] <TB3> INFO: Expecting 41600 events.
[16:14:13.759] <TB3> INFO: 41600 events read in total (3373ms).
[16:14:13.760] <TB3> INFO: Test took 4242ms.
[16:14:13.765] <TB3> INFO: scanning low vcal = 70
[16:14:14.040] <TB3> INFO: Expecting 41600 events.
[16:14:17.984] <TB3> INFO: 41600 events read in total (3352ms).
[16:14:17.985] <TB3> INFO: Test took 4219ms.
[16:14:17.989] <TB3> INFO: scanning low vcal = 80
[16:14:18.265] <TB3> INFO: Expecting 41600 events.
[16:14:22.235] <TB3> INFO: 41600 events read in total (3378ms).
[16:14:22.236] <TB3> INFO: Test took 4247ms.
[16:14:22.240] <TB3> INFO: scanning low vcal = 90
[16:14:22.564] <TB3> INFO: Expecting 41600 events.
[16:14:26.554] <TB3> INFO: 41600 events read in total (3398ms).
[16:14:26.555] <TB3> INFO: Test took 4315ms.
[16:14:26.560] <TB3> INFO: scanning low vcal = 100
[16:14:26.835] <TB3> INFO: Expecting 41600 events.
[16:14:30.874] <TB3> INFO: 41600 events read in total (3448ms).
[16:14:30.877] <TB3> INFO: Test took 4317ms.
[16:14:30.881] <TB3> INFO: scanning low vcal = 110
[16:14:31.166] <TB3> INFO: Expecting 41600 events.
[16:14:35.166] <TB3> INFO: 41600 events read in total (3408ms).
[16:14:35.166] <TB3> INFO: Test took 4284ms.
[16:14:35.169] <TB3> INFO: scanning low vcal = 120
[16:14:35.446] <TB3> INFO: Expecting 41600 events.
[16:14:39.530] <TB3> INFO: 41600 events read in total (3492ms).
[16:14:39.531] <TB3> INFO: Test took 4361ms.
[16:14:39.534] <TB3> INFO: scanning low vcal = 130
[16:14:39.831] <TB3> INFO: Expecting 41600 events.
[16:14:43.806] <TB3> INFO: 41600 events read in total (3384ms).
[16:14:43.807] <TB3> INFO: Test took 4272ms.
[16:14:43.810] <TB3> INFO: scanning low vcal = 140
[16:14:44.086] <TB3> INFO: Expecting 41600 events.
[16:14:48.155] <TB3> INFO: 41600 events read in total (3477ms).
[16:14:48.156] <TB3> INFO: Test took 4345ms.
[16:14:48.159] <TB3> INFO: scanning low vcal = 150
[16:14:48.452] <TB3> INFO: Expecting 41600 events.
[16:14:52.470] <TB3> INFO: 41600 events read in total (3426ms).
[16:14:52.471] <TB3> INFO: Test took 4312ms.
[16:14:52.475] <TB3> INFO: scanning low vcal = 160
[16:14:52.800] <TB3> INFO: Expecting 41600 events.
[16:14:56.875] <TB3> INFO: 41600 events read in total (3483ms).
[16:14:56.876] <TB3> INFO: Test took 4401ms.
[16:14:56.879] <TB3> INFO: scanning low vcal = 170
[16:14:57.156] <TB3> INFO: Expecting 41600 events.
[16:15:01.159] <TB3> INFO: 41600 events read in total (3412ms).
[16:15:01.159] <TB3> INFO: Test took 4280ms.
[16:15:01.165] <TB3> INFO: scanning low vcal = 180
[16:15:01.439] <TB3> INFO: Expecting 41600 events.
[16:15:05.523] <TB3> INFO: 41600 events read in total (3492ms).
[16:15:05.524] <TB3> INFO: Test took 4359ms.
[16:15:05.528] <TB3> INFO: scanning low vcal = 190
[16:15:05.804] <TB3> INFO: Expecting 41600 events.
[16:15:09.822] <TB3> INFO: 41600 events read in total (3426ms).
[16:15:09.823] <TB3> INFO: Test took 4295ms.
[16:15:09.826] <TB3> INFO: scanning low vcal = 200
[16:15:10.102] <TB3> INFO: Expecting 41600 events.
[16:15:14.059] <TB3> INFO: 41600 events read in total (3365ms).
[16:15:14.060] <TB3> INFO: Test took 4234ms.
[16:15:14.063] <TB3> INFO: scanning low vcal = 210
[16:15:14.340] <TB3> INFO: Expecting 41600 events.
[16:15:18.294] <TB3> INFO: 41600 events read in total (3362ms).
[16:15:18.295] <TB3> INFO: Test took 4232ms.
[16:15:18.298] <TB3> INFO: scanning low vcal = 220
[16:15:18.575] <TB3> INFO: Expecting 41600 events.
[16:15:22.530] <TB3> INFO: 41600 events read in total (3363ms).
[16:15:22.530] <TB3> INFO: Test took 4232ms.
[16:15:22.533] <TB3> INFO: scanning low vcal = 230
[16:15:22.810] <TB3> INFO: Expecting 41600 events.
[16:15:26.770] <TB3> INFO: 41600 events read in total (3368ms).
[16:15:26.771] <TB3> INFO: Test took 4237ms.
[16:15:26.774] <TB3> INFO: scanning low vcal = 240
[16:15:27.051] <TB3> INFO: Expecting 41600 events.
[16:15:30.001] <TB3> INFO: 41600 events read in total (3358ms).
[16:15:30.002] <TB3> INFO: Test took 4228ms.
[16:15:31.005] <TB3> INFO: scanning low vcal = 250
[16:15:31.282] <TB3> INFO: Expecting 41600 events.
[16:15:35.235] <TB3> INFO: 41600 events read in total (3361ms).
[16:15:35.236] <TB3> INFO: Test took 4231ms.
[16:15:35.240] <TB3> INFO: scanning high vcal = 30 (= 210 in low range)
[16:15:35.516] <TB3> INFO: Expecting 41600 events.
[16:15:39.477] <TB3> INFO: 41600 events read in total (3369ms).
[16:15:39.478] <TB3> INFO: Test took 4238ms.
[16:15:39.481] <TB3> INFO: scanning high vcal = 50 (= 350 in low range)
[16:15:39.758] <TB3> INFO: Expecting 41600 events.
[16:15:43.715] <TB3> INFO: 41600 events read in total (3365ms).
[16:15:43.716] <TB3> INFO: Test took 4235ms.
[16:15:43.719] <TB3> INFO: scanning high vcal = 70 (= 490 in low range)
[16:15:43.996] <TB3> INFO: Expecting 41600 events.
[16:15:47.955] <TB3> INFO: 41600 events read in total (3367ms).
[16:15:47.956] <TB3> INFO: Test took 4237ms.
[16:15:47.959] <TB3> INFO: scanning high vcal = 90 (= 630 in low range)
[16:15:48.236] <TB3> INFO: Expecting 41600 events.
[16:15:52.196] <TB3> INFO: 41600 events read in total (3368ms).
[16:15:52.197] <TB3> INFO: Test took 4238ms.
[16:15:52.200] <TB3> INFO: scanning high vcal = 200 (= 1400 in low range)
[16:15:52.477] <TB3> INFO: Expecting 41600 events.
[16:15:56.444] <TB3> INFO: 41600 events read in total (3375ms).
[16:15:56.445] <TB3> INFO: Test took 4245ms.
[16:15:56.829] <TB3> INFO: PixTestGainPedestal::measure() done
[16:16:27.447] <TB3> INFO: PixTestGainPedestal::fit() done
[16:16:27.447] <TB3> INFO: non-linearity mean: 0.000 0.908 0.965 0.971 0.965 0.912 0.974 0.969 0.969 0.896 0.980 0.964 0.977 0.971 0.969 0.976
[16:16:27.447] <TB3> INFO: non-linearity RMS: 0.000 0.093 0.006 0.004 0.005 0.073 0.005 0.006 0.004 0.091 0.004 0.005 0.005 0.005 0.004 0.004
[16:16:27.448] <TB3> INFO: write gain/ped parameters into /home/l_tester/david/FullQualification/data/M1012_FullQualification_2016-10-17_13h57m_1476705426//001_Fulltest_m20//phCalibrationFitErr35_C0.dat
[16:16:27.458] <TB3> INFO: write gain/ped parameters into /home/l_tester/david/FullQualification/data/M1012_FullQualification_2016-10-17_13h57m_1476705426//001_Fulltest_m20//phCalibrationFitErr35_C1.dat
[16:16:27.472] <TB3> INFO: write gain/ped parameters into /home/l_tester/david/FullQualification/data/M1012_FullQualification_2016-10-17_13h57m_1476705426//001_Fulltest_m20//phCalibrationFitErr35_C2.dat
[16:16:27.485] <TB3> INFO: write gain/ped parameters into /home/l_tester/david/FullQualification/data/M1012_FullQualification_2016-10-17_13h57m_1476705426//001_Fulltest_m20//phCalibrationFitErr35_C3.dat
[16:16:27.499] <TB3> INFO: write gain/ped parameters into /home/l_tester/david/FullQualification/data/M1012_FullQualification_2016-10-17_13h57m_1476705426//001_Fulltest_m20//phCalibrationFitErr35_C4.dat
[16:16:27.512] <TB3> INFO: write gain/ped parameters into /home/l_tester/david/FullQualification/data/M1012_FullQualification_2016-10-17_13h57m_1476705426//001_Fulltest_m20//phCalibrationFitErr35_C5.dat
[16:16:27.526] <TB3> INFO: write gain/ped parameters into /home/l_tester/david/FullQualification/data/M1012_FullQualification_2016-10-17_13h57m_1476705426//001_Fulltest_m20//phCalibrationFitErr35_C6.dat
[16:16:27.540] <TB3> INFO: write gain/ped parameters into /home/l_tester/david/FullQualification/data/M1012_FullQualification_2016-10-17_13h57m_1476705426//001_Fulltest_m20//phCalibrationFitErr35_C7.dat
[16:16:27.553] <TB3> INFO: write gain/ped parameters into /home/l_tester/david/FullQualification/data/M1012_FullQualification_2016-10-17_13h57m_1476705426//001_Fulltest_m20//phCalibrationFitErr35_C8.dat
[16:16:27.566] <TB3> INFO: write gain/ped parameters into /home/l_tester/david/FullQualification/data/M1012_FullQualification_2016-10-17_13h57m_1476705426//001_Fulltest_m20//phCalibrationFitErr35_C9.dat
[16:16:27.579] <TB3> INFO: write gain/ped parameters into /home/l_tester/david/FullQualification/data/M1012_FullQualification_2016-10-17_13h57m_1476705426//001_Fulltest_m20//phCalibrationFitErr35_C10.dat
[16:16:27.593] <TB3> INFO: write gain/ped parameters into /home/l_tester/david/FullQualification/data/M1012_FullQualification_2016-10-17_13h57m_1476705426//001_Fulltest_m20//phCalibrationFitErr35_C11.dat
[16:16:27.606] <TB3> INFO: write gain/ped parameters into /home/l_tester/david/FullQualification/data/M1012_FullQualification_2016-10-17_13h57m_1476705426//001_Fulltest_m20//phCalibrationFitErr35_C12.dat
[16:16:27.620] <TB3> INFO: write gain/ped parameters into /home/l_tester/david/FullQualification/data/M1012_FullQualification_2016-10-17_13h57m_1476705426//001_Fulltest_m20//phCalibrationFitErr35_C13.dat
[16:16:27.633] <TB3> INFO: write gain/ped parameters into /home/l_tester/david/FullQualification/data/M1012_FullQualification_2016-10-17_13h57m_1476705426//001_Fulltest_m20//phCalibrationFitErr35_C14.dat
[16:16:27.647] <TB3> INFO: write gain/ped parameters into /home/l_tester/david/FullQualification/data/M1012_FullQualification_2016-10-17_13h57m_1476705426//001_Fulltest_m20//phCalibrationFitErr35_C15.dat
[16:16:27.660] <TB3> INFO: PixTestGainPedestal::fullTest() done, duration: 158 seconds
[16:16:27.660] <TB3> INFO: Decoding statistics:
[16:16:27.660] <TB3> INFO: General information:
[16:16:27.660] <TB3> INFO: 16bit words read: 3327942
[16:16:27.660] <TB3> INFO: valid events total: 332800
[16:16:27.660] <TB3> INFO: empty events: 0
[16:16:27.660] <TB3> INFO: valid events with pixels: 332800
[16:16:27.660] <TB3> INFO: valid pixel hits: 665571
[16:16:27.660] <TB3> INFO: Event errors: 0
[16:16:27.660] <TB3> INFO: start marker: 0
[16:16:27.660] <TB3> INFO: stop marker: 0
[16:16:27.660] <TB3> INFO: overflow: 0
[16:16:27.660] <TB3> INFO: invalid 5bit words: 0
[16:16:27.660] <TB3> INFO: invalid XOR eye diagram: 0
[16:16:27.660] <TB3> INFO: frame (failed synchr.): 0
[16:16:27.660] <TB3> INFO: idle data (no TBM trl): 0
[16:16:27.660] <TB3> INFO: no data (only TBM hdr): 0
[16:16:27.660] <TB3> INFO: TBM errors: 0
[16:16:27.660] <TB3> INFO: flawed TBM headers: 0
[16:16:27.660] <TB3> INFO: flawed TBM trailers: 0
[16:16:27.660] <TB3> INFO: event ID mismatches: 0
[16:16:27.660] <TB3> INFO: ROC errors: 0
[16:16:27.660] <TB3> INFO: missing ROC header(s): 0
[16:16:27.660] <TB3> INFO: misplaced readback start: 0
[16:16:27.660] <TB3> INFO: Pixel decoding errors: 0
[16:16:27.660] <TB3> INFO: pixel data incomplete: 0
[16:16:27.660] <TB3> INFO: pixel address: 0
[16:16:27.660] <TB3> INFO: pulse height fill bit: 0
[16:16:27.660] <TB3> INFO: buffer corruption: 0
[16:16:27.686] <TB3> INFO: Decoding statistics:
[16:16:27.686] <TB3> INFO: General information:
[16:16:27.686] <TB3> INFO: 16bit words read: 3457360
[16:16:27.686] <TB3> INFO: valid events total: 353536
[16:16:27.686] <TB3> INFO: empty events: 18235
[16:16:27.686] <TB3> INFO: valid events with pixels: 335301
[16:16:27.686] <TB3> INFO: valid pixel hits: 668072
[16:16:27.687] <TB3> INFO: Event errors: 0
[16:16:27.687] <TB3> INFO: start marker: 0
[16:16:27.687] <TB3> INFO: stop marker: 0
[16:16:27.687] <TB3> INFO: overflow: 0
[16:16:27.687] <TB3> INFO: invalid 5bit words: 0
[16:16:27.687] <TB3> INFO: invalid XOR eye diagram: 0
[16:16:27.687] <TB3> INFO: frame (failed synchr.): 0
[16:16:27.687] <TB3> INFO: idle data (no TBM trl): 0
[16:16:27.687] <TB3> INFO: no data (only TBM hdr): 0
[16:16:27.687] <TB3> INFO: TBM errors: 0
[16:16:27.687] <TB3> INFO: flawed TBM headers: 0
[16:16:27.687] <TB3> INFO: flawed TBM trailers: 0
[16:16:27.687] <TB3> INFO: event ID mismatches: 0
[16:16:27.687] <TB3> INFO: ROC errors: 0
[16:16:27.687] <TB3> INFO: missing ROC header(s): 0
[16:16:27.687] <TB3> INFO: misplaced readback start: 0
[16:16:27.687] <TB3> INFO: Pixel decoding errors: 0
[16:16:27.687] <TB3> INFO: pixel data incomplete: 0
[16:16:27.687] <TB3> INFO: pixel address: 0
[16:16:27.687] <TB3> INFO: pulse height fill bit: 0
[16:16:27.687] <TB3> INFO: buffer corruption: 0
[16:16:27.687] <TB3> INFO: enter test to run
[16:16:27.687] <TB3> INFO: test: exit no parameter change
[16:16:27.904] <TB3> QUIET: Connection to board 126 closed.
[16:16:27.905] <TB3> INFO: pXar: this is the end, my friend