[16:39:12.197] <TB0> INFO: *** Welcome to pxar ***
[16:39:12.197] <TB0> INFO: *** Today: 2016/10/19
[16:39:12.203] <TB0> INFO: *** Version: c8ba-dirty
[16:39:12.203] <TB0> INFO: readRocDacs: /home/l_tester/david/FullQualification/data/M1015_FullQualification_2016-10-19_14h13m_1476879208//003_Fulltest_p17//dacParameters_C0.dat .. /home/l_tester/david/FullQualification/data/M1015_FullQualification_2016-10-19_14h13m_1476879208//003_Fulltest_p17//dacParameters_C15.dat
[16:39:12.204] <TB0> INFO: readTbmDacs: /home/l_tester/david/FullQualification/data/M1015_FullQualification_2016-10-19_14h13m_1476879208//003_Fulltest_p17//tbmParameters_C0a.dat .. /home/l_tester/david/FullQualification/data/M1015_FullQualification_2016-10-19_14h13m_1476879208//003_Fulltest_p17//tbmParameters_C1b.dat
[16:39:12.204] <TB0> INFO: readMaskFile: /home/l_tester/david/FullQualification/data/M1015_FullQualification_2016-10-19_14h13m_1476879208//003_Fulltest_p17//defaultMaskFile.dat
[16:39:12.204] <TB0> INFO: readTrimFile: /home/l_tester/david/FullQualification/data/M1015_FullQualification_2016-10-19_14h13m_1476879208//003_Fulltest_p17//trimParameters_C0.dat .. /home/l_tester/david/FullQualification/data/M1015_FullQualification_2016-10-19_14h13m_1476879208//003_Fulltest_p17//trimParameters_C15.dat
[16:39:12.268] <TB0> INFO: clk: 4
[16:39:12.268] <TB0> INFO: ctr: 4
[16:39:12.268] <TB0> INFO: sda: 19
[16:39:12.268] <TB0> INFO: tin: 9
[16:39:12.268] <TB0> INFO: level: 15
[16:39:12.268] <TB0> INFO: triggerdelay: 0
[16:39:12.268] <TB0> QUIET: Instanciating API for pxar v2.7.6+55~gafdbfd9
[16:39:12.268] <TB0> INFO: Log level: INFO
[16:39:12.277] <TB0> INFO: Found DTB DTB_WRQ4OZ
[16:39:12.287] <TB0> QUIET: Connection to board DTB_WRQ4OZ opened.
[16:39:12.289] <TB0> INFO: DTB startup information
--- DTB info------------------------------------------
Board id: 71
HW version: DTB1.2
FW version: 4.6
SW version: 4.7
Options:
USB id: DTB_WRQ4OZ
MAC address: 40D855118047
Hostname: pixelDTB071
Comment:
------------------------------------------------------
[16:39:12.291] <TB0> INFO: RPC call hashes of host and DTB match: 486171790
[16:39:13.779] <TB0> INFO: DUT info:
[16:39:13.779] <TB0> INFO: The DUT currently contains the following objects:
[16:39:13.779] <TB0> INFO: 4 TBM Cores tbm10c (4 ON)
[16:39:13.779] <TB0> INFO: TBM Core alpha (0): 7 registers set
[16:39:13.779] <TB0> INFO: TBM Core beta (1): 7 registers set
[16:39:13.779] <TB0> INFO: TBM Core alpha (2): 7 registers set
[16:39:13.779] <TB0> INFO: TBM Core beta (3): 7 registers set
[16:39:13.779] <TB0> INFO: 16 ROCs proc600 (16 ON) with 4160 pixelConfigs
[16:39:13.779] <TB0> INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[16:39:13.779] <TB0> INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[16:39:13.779] <TB0> INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[16:39:13.779] <TB0> INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[16:39:13.780] <TB0> INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[16:39:13.780] <TB0> INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[16:39:13.780] <TB0> INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[16:39:13.780] <TB0> INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[16:39:13.780] <TB0> INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[16:39:13.780] <TB0> INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[16:39:13.780] <TB0> INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[16:39:13.780] <TB0> INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[16:39:13.780] <TB0> INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[16:39:13.780] <TB0> INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[16:39:13.780] <TB0> INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[16:39:13.780] <TB0> INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[16:39:14.180] <TB0> INFO: enter 'restricted' command line mode
[16:39:14.180] <TB0> INFO: enter test to run
[16:39:14.180] <TB0> INFO: test: pretest no parameter change
[16:39:14.180] <TB0> INFO: running: pretest
[16:39:14.185] <TB0> INFO: ######################################################################
[16:39:14.185] <TB0> INFO: PixTestPretest::doTest()
[16:39:14.185] <TB0> INFO: ######################################################################
[16:39:14.186] <TB0> INFO: ----------------------------------------------------------------------
[16:39:14.186] <TB0> INFO: PixTestPretest::programROC()
[16:39:14.186] <TB0> INFO: ----------------------------------------------------------------------
[16:39:32.199] <TB0> INFO: PixTestPretest::programROC() done: ROCs are all programmable
[16:39:32.199] <TB0> INFO: IA differences per ROC: 20.1 19.3 20.1 20.1 18.5 21.7 20.1 20.1 19.3 20.1 18.5 20.1 20.1 19.3 20.1 20.9
[16:39:32.260] <TB0> INFO: ----------------------------------------------------------------------
[16:39:32.260] <TB0> INFO: PixTestPretest::setVana() target Ia = 24 mA/ROC
[16:39:32.260] <TB0> INFO: ----------------------------------------------------------------------
[16:39:39.445] <TB0> INFO: PixTestPretest::setVana() done, Module Ia 385.1 mA = 24.0688 mA/ROC
[16:39:39.445] <TB0> INFO: i(loss) [mA/ROC]: 18.5 18.5 18.5 18.5 18.5 18.5 18.5 17.7 18.5 18.5 18.5 18.5 18.5 18.5 18.5 18.5
[16:39:39.475] <TB0> INFO: ----------------------------------------------------------------------
[16:39:39.475] <TB0> INFO: PixTestPretest::findTiming()
[16:39:39.475] <TB0> INFO: ----------------------------------------------------------------------
[16:39:39.475] <TB0> INFO: PixTestCmd::init()

[16:40:12.112] <TB0> INFO: TBM phases: 160MHz: 0, 400MHz: 2, TBM delays: ROC(0/1):5, header/trailer: 1, token: 1
[16:40:12.113] <TB0> INFO: (success/tries = 100/100), width = 4
[16:40:13.626] <TB0> INFO: ----------------------------------------------------------------------
[16:40:13.626] <TB0> INFO: PixTestPretest::findWorkingPixel()
[16:40:13.626] <TB0> INFO: ----------------------------------------------------------------------
[16:40:13.722] <TB0> INFO: Expecting 231680 events.
[16:40:23.732] <TB0> INFO: 231680 events read in total (9418ms).
[16:40:23.741] <TB0> INFO: Test took 10109ms.
[16:40:23.992] <TB0> INFO: Found working pixel in all ROCs: col/row = 12/22
[16:40:24.029] <TB0> INFO: ----------------------------------------------------------------------
[16:40:24.029] <TB0> INFO: PixTestPretest::setVthrCompCalDel()
[16:40:24.029] <TB0> INFO: ----------------------------------------------------------------------
[16:40:24.124] <TB0> INFO: Expecting 231680 events.
[16:40:34.033] <TB0> INFO: 231680 events read in total (9318ms).
[16:40:34.042] <TB0> INFO: Test took 10008ms.
[16:40:34.308] <TB0> INFO: PixTestPretest::setVthrCompCalDel() done
[16:40:34.308] <TB0> INFO: CalDel: 76 79 78 88 77 90 71 83 87 74 78 101 79 75 76 84
[16:40:34.308] <TB0> INFO: VthrComp: 52 51 51 51 52 51 51 51 51 52 52 51 52 51 51 51
[16:40:34.311] <TB0> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1015_FullQualification_2016-10-19_14h13m_1476879208//003_Fulltest_p17//dacParameters_C0.dat
[16:40:34.311] <TB0> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1015_FullQualification_2016-10-19_14h13m_1476879208//003_Fulltest_p17//dacParameters_C1.dat
[16:40:34.312] <TB0> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1015_FullQualification_2016-10-19_14h13m_1476879208//003_Fulltest_p17//dacParameters_C2.dat
[16:40:34.312] <TB0> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1015_FullQualification_2016-10-19_14h13m_1476879208//003_Fulltest_p17//dacParameters_C3.dat
[16:40:34.312] <TB0> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1015_FullQualification_2016-10-19_14h13m_1476879208//003_Fulltest_p17//dacParameters_C4.dat
[16:40:34.312] <TB0> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1015_FullQualification_2016-10-19_14h13m_1476879208//003_Fulltest_p17//dacParameters_C5.dat
[16:40:34.312] <TB0> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1015_FullQualification_2016-10-19_14h13m_1476879208//003_Fulltest_p17//dacParameters_C6.dat
[16:40:34.312] <TB0> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1015_FullQualification_2016-10-19_14h13m_1476879208//003_Fulltest_p17//dacParameters_C7.dat
[16:40:34.312] <TB0> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1015_FullQualification_2016-10-19_14h13m_1476879208//003_Fulltest_p17//dacParameters_C8.dat
[16:40:34.313] <TB0> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1015_FullQualification_2016-10-19_14h13m_1476879208//003_Fulltest_p17//dacParameters_C9.dat
[16:40:34.313] <TB0> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1015_FullQualification_2016-10-19_14h13m_1476879208//003_Fulltest_p17//dacParameters_C10.dat
[16:40:34.313] <TB0> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1015_FullQualification_2016-10-19_14h13m_1476879208//003_Fulltest_p17//dacParameters_C11.dat
[16:40:34.313] <TB0> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1015_FullQualification_2016-10-19_14h13m_1476879208//003_Fulltest_p17//dacParameters_C12.dat
[16:40:34.313] <TB0> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1015_FullQualification_2016-10-19_14h13m_1476879208//003_Fulltest_p17//dacParameters_C13.dat
[16:40:34.313] <TB0> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1015_FullQualification_2016-10-19_14h13m_1476879208//003_Fulltest_p17//dacParameters_C14.dat
[16:40:34.314] <TB0> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1015_FullQualification_2016-10-19_14h13m_1476879208//003_Fulltest_p17//dacParameters_C15.dat
[16:40:34.314] <TB0> INFO: write tbm parameters into /home/l_tester/david/FullQualification/data/M1015_FullQualification_2016-10-19_14h13m_1476879208//003_Fulltest_p17//tbmParameters_C0a.dat
[16:40:34.314] <TB0> INFO: write tbm parameters into /home/l_tester/david/FullQualification/data/M1015_FullQualification_2016-10-19_14h13m_1476879208//003_Fulltest_p17//tbmParameters_C0b.dat
[16:40:34.314] <TB0> INFO: write tbm parameters into /home/l_tester/david/FullQualification/data/M1015_FullQualification_2016-10-19_14h13m_1476879208//003_Fulltest_p17//tbmParameters_C1a.dat
[16:40:34.314] <TB0> INFO: write tbm parameters into /home/l_tester/david/FullQualification/data/M1015_FullQualification_2016-10-19_14h13m_1476879208//003_Fulltest_p17//tbmParameters_C1b.dat
[16:40:34.314] <TB0> INFO: PixTestPretest::doTest() done, duration: 80 seconds
[16:40:34.365] <TB0> INFO: enter test to run
[16:40:34.365] <TB0> INFO: test: FullTest no parameter change
[16:40:34.365] <TB0> INFO: running: fulltest
[16:40:34.365] <TB0> INFO: ######################################################################
[16:40:34.365] <TB0> INFO: PixTestFullTest::doTest()
[16:40:34.365] <TB0> INFO: ######################################################################
[16:40:34.366] <TB0> INFO: ######################################################################
[16:40:34.366] <TB0> INFO: PixTestAlive::doTest()
[16:40:34.366] <TB0> INFO: ######################################################################
[16:40:34.367] <TB0> INFO: ----------------------------------------------------------------------
[16:40:34.367] <TB0> INFO: PixTestAlive::aliveTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[16:40:34.367] <TB0> INFO: ----------------------------------------------------------------------
[16:40:34.648] <TB0> INFO: Expecting 41600 events.
[16:40:38.266] <TB0> INFO: 41600 events read in total (3026ms).
[16:40:38.266] <TB0> INFO: Test took 3897ms.
[16:40:38.500] <TB0> INFO: PixTestAlive::aliveTest() done
[16:40:38.500] <TB0> INFO: number of dead pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[16:40:38.502] <TB0> INFO: ----------------------------------------------------------------------
[16:40:38.502] <TB0> INFO: PixTestAlive::maskTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[16:40:38.502] <TB0> INFO: ----------------------------------------------------------------------
[16:40:38.745] <TB0> INFO: Expecting 41600 events.
[16:40:41.687] <TB0> INFO: 41600 events read in total (2350ms).
[16:40:41.687] <TB0> INFO: Test took 3184ms.
[16:40:41.688] <TB0> INFO: mask vs. old pixelAlive PixelAlive_C0_V0 .. PixelAlive_C15_V0
[16:40:41.925] <TB0> INFO: PixTestAlive::maskTest() done
[16:40:41.925] <TB0> INFO: number of mask-defect pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[16:40:41.927] <TB0> INFO: ----------------------------------------------------------------------
[16:40:41.927] <TB0> INFO: PixTestAlive::addressDecodingTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[16:40:41.927] <TB0> INFO: ----------------------------------------------------------------------
[16:40:42.169] <TB0> INFO: Expecting 41600 events.
[16:40:45.705] <TB0> INFO: 41600 events read in total (2944ms).
[16:40:45.706] <TB0> INFO: Test took 3778ms.
[16:40:45.942] <TB0> INFO: PixTestAlive::addressDecodingTest() done
[16:40:45.942] <TB0> INFO: number of address-decoding pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[16:40:45.942] <TB0> INFO: PixTestAlive::doTest() done, duration: 11 seconds
[16:40:45.942] <TB0> INFO: Decoding statistics:
[16:40:45.942] <TB0> INFO: General information:
[16:40:45.942] <TB0> INFO: 16bit words read: 0
[16:40:45.942] <TB0> INFO: valid events total: 0
[16:40:45.942] <TB0> INFO: empty events: 0
[16:40:45.942] <TB0> INFO: valid events with pixels: 0
[16:40:45.942] <TB0> INFO: valid pixel hits: 0
[16:40:45.942] <TB0> INFO: Event errors: 0
[16:40:45.942] <TB0> INFO: start marker: 0
[16:40:45.942] <TB0> INFO: stop marker: 0
[16:40:45.942] <TB0> INFO: overflow: 0
[16:40:45.943] <TB0> INFO: invalid 5bit words: 0
[16:40:45.943] <TB0> INFO: invalid XOR eye diagram: 0
[16:40:45.943] <TB0> INFO: frame (failed synchr.): 0
[16:40:45.943] <TB0> INFO: idle data (no TBM trl): 0
[16:40:45.943] <TB0> INFO: no data (only TBM hdr): 0
[16:40:45.943] <TB0> INFO: TBM errors: 0
[16:40:45.943] <TB0> INFO: flawed TBM headers: 0
[16:40:45.943] <TB0> INFO: flawed TBM trailers: 0
[16:40:45.943] <TB0> INFO: event ID mismatches: 0
[16:40:45.943] <TB0> INFO: ROC errors: 0
[16:40:45.943] <TB0> INFO: missing ROC header(s): 0
[16:40:45.943] <TB0> INFO: misplaced readback start: 0
[16:40:45.943] <TB0> INFO: Pixel decoding errors: 0
[16:40:45.943] <TB0> INFO: pixel data incomplete: 0
[16:40:45.943] <TB0> INFO: pixel address: 0
[16:40:45.943] <TB0> INFO: pulse height fill bit: 0
[16:40:45.943] <TB0> INFO: buffer corruption: 0
[16:40:45.950] <TB0> INFO: readReadbackCal: /home/l_tester/david/FullQualification/data/M1015_FullQualification_2016-10-19_14h13m_1476879208//003_Fulltest_p17//readbackCal_C0.dat .. /home/l_tester/david/FullQualification/data/M1015_FullQualification_2016-10-19_14h13m_1476879208//003_Fulltest_p17//readbackCal_C15.dat
[16:40:45.951] <TB0> INFO: readGainPedestalParameters /home/l_tester/david/FullQualification/data/M1015_FullQualification_2016-10-19_14h13m_1476879208//003_Fulltest_p17//phCalibrationFitErr_C0.dat .. /home/l_tester/david/FullQualification/data/M1015_FullQualification_2016-10-19_14h13m_1476879208//003_Fulltest_p17//phCalibrationFitErr_C15.dat

[16:40:45.951] <TB0> INFO: ######################################################################
[16:40:45.951] <TB0> INFO: PixTestReadback::doTest()
[16:40:45.951] <TB0> INFO: ######################################################################
[16:40:45.951] <TB0> INFO: ----------------------------------------------------------------------
[16:40:45.951] <TB0> INFO: PixTestReadback::CalibrateVd()
[16:40:45.951] <TB0> INFO: ----------------------------------------------------------------------
[16:40:55.911] <TB0> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1015_FullQualification_2016-10-19_14h13m_1476879208//003_Fulltest_p17//readbackCal_C0.dat
[16:40:55.911] <TB0> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1015_FullQualification_2016-10-19_14h13m_1476879208//003_Fulltest_p17//readbackCal_C1.dat
[16:40:55.911] <TB0> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1015_FullQualification_2016-10-19_14h13m_1476879208//003_Fulltest_p17//readbackCal_C2.dat
[16:40:55.911] <TB0> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1015_FullQualification_2016-10-19_14h13m_1476879208//003_Fulltest_p17//readbackCal_C3.dat
[16:40:55.911] <TB0> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1015_FullQualification_2016-10-19_14h13m_1476879208//003_Fulltest_p17//readbackCal_C4.dat
[16:40:55.912] <TB0> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1015_FullQualification_2016-10-19_14h13m_1476879208//003_Fulltest_p17//readbackCal_C5.dat
[16:40:55.912] <TB0> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1015_FullQualification_2016-10-19_14h13m_1476879208//003_Fulltest_p17//readbackCal_C6.dat
[16:40:55.912] <TB0> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1015_FullQualification_2016-10-19_14h13m_1476879208//003_Fulltest_p17//readbackCal_C7.dat
[16:40:55.912] <TB0> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1015_FullQualification_2016-10-19_14h13m_1476879208//003_Fulltest_p17//readbackCal_C8.dat
[16:40:55.912] <TB0> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1015_FullQualification_2016-10-19_14h13m_1476879208//003_Fulltest_p17//readbackCal_C9.dat
[16:40:55.912] <TB0> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1015_FullQualification_2016-10-19_14h13m_1476879208//003_Fulltest_p17//readbackCal_C10.dat
[16:40:55.912] <TB0> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1015_FullQualification_2016-10-19_14h13m_1476879208//003_Fulltest_p17//readbackCal_C11.dat
[16:40:55.912] <TB0> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1015_FullQualification_2016-10-19_14h13m_1476879208//003_Fulltest_p17//readbackCal_C12.dat
[16:40:55.912] <TB0> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1015_FullQualification_2016-10-19_14h13m_1476879208//003_Fulltest_p17//readbackCal_C13.dat
[16:40:55.912] <TB0> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1015_FullQualification_2016-10-19_14h13m_1476879208//003_Fulltest_p17//readbackCal_C14.dat
[16:40:55.912] <TB0> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1015_FullQualification_2016-10-19_14h13m_1476879208//003_Fulltest_p17//readbackCal_C15.dat
[16:40:55.941] <TB0> INFO: PixTestPattern:: pg_setup set to default.
[16:40:55.941] <TB0> INFO: ----------------------------------------------------------------------
[16:40:55.941] <TB0> INFO: PixTestReadback::CalibrateVa()
[16:40:55.942] <TB0> INFO: ----------------------------------------------------------------------
[16:41:05.856] <TB0> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1015_FullQualification_2016-10-19_14h13m_1476879208//003_Fulltest_p17//readbackCal_C0.dat
[16:41:05.856] <TB0> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1015_FullQualification_2016-10-19_14h13m_1476879208//003_Fulltest_p17//readbackCal_C1.dat
[16:41:05.856] <TB0> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1015_FullQualification_2016-10-19_14h13m_1476879208//003_Fulltest_p17//readbackCal_C2.dat
[16:41:05.856] <TB0> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1015_FullQualification_2016-10-19_14h13m_1476879208//003_Fulltest_p17//readbackCal_C3.dat
[16:41:05.856] <TB0> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1015_FullQualification_2016-10-19_14h13m_1476879208//003_Fulltest_p17//readbackCal_C4.dat
[16:41:05.856] <TB0> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1015_FullQualification_2016-10-19_14h13m_1476879208//003_Fulltest_p17//readbackCal_C5.dat
[16:41:05.856] <TB0> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1015_FullQualification_2016-10-19_14h13m_1476879208//003_Fulltest_p17//readbackCal_C6.dat
[16:41:05.856] <TB0> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1015_FullQualification_2016-10-19_14h13m_1476879208//003_Fulltest_p17//readbackCal_C7.dat
[16:41:05.856] <TB0> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1015_FullQualification_2016-10-19_14h13m_1476879208//003_Fulltest_p17//readbackCal_C8.dat
[16:41:05.857] <TB0> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1015_FullQualification_2016-10-19_14h13m_1476879208//003_Fulltest_p17//readbackCal_C9.dat
[16:41:05.857] <TB0> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1015_FullQualification_2016-10-19_14h13m_1476879208//003_Fulltest_p17//readbackCal_C10.dat
[16:41:05.857] <TB0> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1015_FullQualification_2016-10-19_14h13m_1476879208//003_Fulltest_p17//readbackCal_C11.dat
[16:41:05.857] <TB0> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1015_FullQualification_2016-10-19_14h13m_1476879208//003_Fulltest_p17//readbackCal_C12.dat
[16:41:05.857] <TB0> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1015_FullQualification_2016-10-19_14h13m_1476879208//003_Fulltest_p17//readbackCal_C13.dat
[16:41:05.857] <TB0> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1015_FullQualification_2016-10-19_14h13m_1476879208//003_Fulltest_p17//readbackCal_C14.dat
[16:41:05.857] <TB0> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1015_FullQualification_2016-10-19_14h13m_1476879208//003_Fulltest_p17//readbackCal_C15.dat
[16:41:05.886] <TB0> INFO: PixTestPattern:: pg_setup set to default.
[16:41:05.886] <TB0> INFO: ----------------------------------------------------------------------
[16:41:05.886] <TB0> INFO: PixTestReadback::readbackVbg()
[16:41:05.886] <TB0> INFO: ----------------------------------------------------------------------
[16:41:13.548] <TB0> INFO: PixTestPattern:: pg_setup set to default.
[16:41:13.548] <TB0> INFO: ----------------------------------------------------------------------
[16:41:13.548] <TB0> INFO: PixTestReadback::getCalibratedVbg()
[16:41:13.548] <TB0> INFO: ----------------------------------------------------------------------
[16:41:13.548] <TB0> INFO: Vbg will be calibrated using Vd calibration
[16:41:13.548] <TB0> INFO: /*/*/*/*::: ROC 0: uncalibrated Vbg = 146.4calibrated Vbg = 1.18829 :::*/*/*/*/
[16:41:13.548] <TB0> INFO: /*/*/*/*::: ROC 1: uncalibrated Vbg = 147.3calibrated Vbg = 1.18937 :::*/*/*/*/
[16:41:13.548] <TB0> INFO: /*/*/*/*::: ROC 2: uncalibrated Vbg = 159calibrated Vbg = 1.18359 :::*/*/*/*/
[16:41:13.548] <TB0> INFO: /*/*/*/*::: ROC 3: uncalibrated Vbg = 149.7calibrated Vbg = 1.17925 :::*/*/*/*/
[16:41:13.548] <TB0> INFO: /*/*/*/*::: ROC 4: uncalibrated Vbg = 157.4calibrated Vbg = 1.18538 :::*/*/*/*/
[16:41:13.548] <TB0> INFO: /*/*/*/*::: ROC 5: uncalibrated Vbg = 158.5calibrated Vbg = 1.18942 :::*/*/*/*/
[16:41:13.548] <TB0> INFO: /*/*/*/*::: ROC 6: uncalibrated Vbg = 157.1calibrated Vbg = 1.18845 :::*/*/*/*/
[16:41:13.548] <TB0> INFO: /*/*/*/*::: ROC 7: uncalibrated Vbg = 152.3calibrated Vbg = 1.19015 :::*/*/*/*/
[16:41:13.548] <TB0> INFO: /*/*/*/*::: ROC 8: uncalibrated Vbg = 148.9calibrated Vbg = 1.18429 :::*/*/*/*/
[16:41:13.548] <TB0> INFO: /*/*/*/*::: ROC 9: uncalibrated Vbg = 159calibrated Vbg = 1.17832 :::*/*/*/*/
[16:41:13.548] <TB0> INFO: /*/*/*/*::: ROC 10: uncalibrated Vbg = 154.5calibrated Vbg = 1.17523 :::*/*/*/*/
[16:41:13.548] <TB0> INFO: /*/*/*/*::: ROC 11: uncalibrated Vbg = 157calibrated Vbg = 1.17886 :::*/*/*/*/
[16:41:13.548] <TB0> INFO: /*/*/*/*::: ROC 12: uncalibrated Vbg = 158.9calibrated Vbg = 1.18153 :::*/*/*/*/
[16:41:13.548] <TB0> INFO: /*/*/*/*::: ROC 13: uncalibrated Vbg = 152.5calibrated Vbg = 1.18986 :::*/*/*/*/
[16:41:13.548] <TB0> INFO: /*/*/*/*::: ROC 14: uncalibrated Vbg = 147.6calibrated Vbg = 1.18569 :::*/*/*/*/
[16:41:13.548] <TB0> INFO: /*/*/*/*::: ROC 15: uncalibrated Vbg = 156.9calibrated Vbg = 1.18333 :::*/*/*/*/
[16:41:13.550] <TB0> INFO: ----------------------------------------------------------------------
[16:41:13.550] <TB0> INFO: PixTestReadback::CalibrateIa()
[16:41:13.550] <TB0> INFO: ----------------------------------------------------------------------
[16:43:54.361] <TB0> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1015_FullQualification_2016-10-19_14h13m_1476879208//003_Fulltest_p17//readbackCal_C0.dat
[16:43:54.361] <TB0> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1015_FullQualification_2016-10-19_14h13m_1476879208//003_Fulltest_p17//readbackCal_C1.dat
[16:43:54.361] <TB0> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1015_FullQualification_2016-10-19_14h13m_1476879208//003_Fulltest_p17//readbackCal_C2.dat
[16:43:54.361] <TB0> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1015_FullQualification_2016-10-19_14h13m_1476879208//003_Fulltest_p17//readbackCal_C3.dat
[16:43:54.361] <TB0> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1015_FullQualification_2016-10-19_14h13m_1476879208//003_Fulltest_p17//readbackCal_C4.dat
[16:43:54.361] <TB0> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1015_FullQualification_2016-10-19_14h13m_1476879208//003_Fulltest_p17//readbackCal_C5.dat
[16:43:54.361] <TB0> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1015_FullQualification_2016-10-19_14h13m_1476879208//003_Fulltest_p17//readbackCal_C6.dat
[16:43:54.361] <TB0> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1015_FullQualification_2016-10-19_14h13m_1476879208//003_Fulltest_p17//readbackCal_C7.dat
[16:43:54.361] <TB0> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1015_FullQualification_2016-10-19_14h13m_1476879208//003_Fulltest_p17//readbackCal_C8.dat
[16:43:54.361] <TB0> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1015_FullQualification_2016-10-19_14h13m_1476879208//003_Fulltest_p17//readbackCal_C9.dat
[16:43:54.361] <TB0> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1015_FullQualification_2016-10-19_14h13m_1476879208//003_Fulltest_p17//readbackCal_C10.dat
[16:43:54.361] <TB0> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1015_FullQualification_2016-10-19_14h13m_1476879208//003_Fulltest_p17//readbackCal_C11.dat
[16:43:54.362] <TB0> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1015_FullQualification_2016-10-19_14h13m_1476879208//003_Fulltest_p17//readbackCal_C12.dat
[16:43:54.362] <TB0> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1015_FullQualification_2016-10-19_14h13m_1476879208//003_Fulltest_p17//readbackCal_C13.dat
[16:43:54.362] <TB0> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1015_FullQualification_2016-10-19_14h13m_1476879208//003_Fulltest_p17//readbackCal_C14.dat
[16:43:54.362] <TB0> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1015_FullQualification_2016-10-19_14h13m_1476879208//003_Fulltest_p17//readbackCal_C15.dat
[16:43:54.393] <TB0> INFO: PixTestPattern:: pg_setup set to default.
[16:43:54.395] <TB0> INFO: PixTestReadback::doTest() done
[16:43:54.395] <TB0> INFO: Decoding statistics:
[16:43:54.396] <TB0> INFO: General information:
[16:43:54.396] <TB0> INFO: 16bit words read: 1536
[16:43:54.396] <TB0> INFO: valid events total: 256
[16:43:54.396] <TB0> INFO: empty events: 256
[16:43:54.396] <TB0> INFO: valid events with pixels: 0
[16:43:54.396] <TB0> INFO: valid pixel hits: 0
[16:43:54.396] <TB0> INFO: Event errors: 0
[16:43:54.396] <TB0> INFO: start marker: 0
[16:43:54.396] <TB0> INFO: stop marker: 0
[16:43:54.396] <TB0> INFO: overflow: 0
[16:43:54.396] <TB0> INFO: invalid 5bit words: 0
[16:43:54.396] <TB0> INFO: invalid XOR eye diagram: 0
[16:43:54.396] <TB0> INFO: frame (failed synchr.): 0
[16:43:54.396] <TB0> INFO: idle data (no TBM trl): 0
[16:43:54.396] <TB0> INFO: no data (only TBM hdr): 0
[16:43:54.396] <TB0> INFO: TBM errors: 0
[16:43:54.396] <TB0> INFO: flawed TBM headers: 0
[16:43:54.396] <TB0> INFO: flawed TBM trailers: 0
[16:43:54.396] <TB0> INFO: event ID mismatches: 0
[16:43:54.396] <TB0> INFO: ROC errors: 0
[16:43:54.396] <TB0> INFO: missing ROC header(s): 0
[16:43:54.396] <TB0> INFO: misplaced readback start: 0
[16:43:54.396] <TB0> INFO: Pixel decoding errors: 0
[16:43:54.396] <TB0> INFO: pixel data incomplete: 0
[16:43:54.396] <TB0> INFO: pixel address: 0
[16:43:54.396] <TB0> INFO: pulse height fill bit: 0
[16:43:54.396] <TB0> INFO: buffer corruption: 0
[16:43:54.446] <TB0> INFO: ######################################################################
[16:43:54.446] <TB0> INFO: PixTestBBMap::doTest() Ntrig = 5, VcalS = 250 (high range)
[16:43:54.446] <TB0> INFO: ######################################################################
[16:43:54.449] <TB0> INFO: ---> dac: VthrComp name: calSMap ntrig: 5 dacrange: 0 .. 149 (-1/-1) hits flags = 514 (plus default)
[16:43:54.463] <TB0> INFO: dacScan split into 1 runs with ntrig = 5
[16:43:54.463] <TB0> INFO: run 1 of 1
[16:43:54.699] <TB0> INFO: Expecting 3120000 events.
[16:44:27.023] <TB0> INFO: 676565 events read in total (31732ms).











[16:44:57.907] <TB0> INFO: 1346820 events read in total (62616ms).
[16:45:28.510] <TB0> INFO: 2014525 events read in total (93219ms).











[16:45:59.233] <TB0> INFO: 2683690 events read in total (123942ms).











[16:46:19.194] <TB0> INFO: 3120000 events read in total (143903ms).
[16:46:19.260] <TB0> INFO: Test took 144798ms.
[16:46:43.123] <TB0> INFO: PixTestBBMap::doTest() done with 2 decoding errors: , duration: 168 seconds
[16:46:43.123] <TB0> INFO: number of dead bumps (per ROC): 6 2 1 1 0 0 0 0 0 0 0 0 0 0 2 55
[16:46:43.123] <TB0> INFO: separation cut (per ROC): 110 111 108 106 104 107 110 105 112 126 122 115 111 105 112 98
[16:46:43.123] <TB0> INFO: Decoding statistics:
[16:46:43.123] <TB0> INFO: General information:
[16:46:43.123] <TB0> INFO: 16bit words read: 0
[16:46:43.123] <TB0> INFO: valid events total: 0
[16:46:43.123] <TB0> INFO: empty events: 0
[16:46:43.123] <TB0> INFO: valid events with pixels: 0
[16:46:43.124] <TB0> INFO: valid pixel hits: 0
[16:46:43.124] <TB0> INFO: Event errors: 0
[16:46:43.124] <TB0> INFO: start marker: 0
[16:46:43.124] <TB0> INFO: stop marker: 0
[16:46:43.124] <TB0> INFO: overflow: 0
[16:46:43.124] <TB0> INFO: invalid 5bit words: 0
[16:46:43.124] <TB0> INFO: invalid XOR eye diagram: 0
[16:46:43.124] <TB0> INFO: frame (failed synchr.): 0
[16:46:43.124] <TB0> INFO: idle data (no TBM trl): 0
[16:46:43.124] <TB0> INFO: no data (only TBM hdr): 0
[16:46:43.124] <TB0> INFO: TBM errors: 0
[16:46:43.124] <TB0> INFO: flawed TBM headers: 0
[16:46:43.124] <TB0> INFO: flawed TBM trailers: 0
[16:46:43.124] <TB0> INFO: event ID mismatches: 0
[16:46:43.124] <TB0> INFO: ROC errors: 0
[16:46:43.124] <TB0> INFO: missing ROC header(s): 0
[16:46:43.124] <TB0> INFO: misplaced readback start: 0
[16:46:43.124] <TB0> INFO: Pixel decoding errors: 0
[16:46:43.124] <TB0> INFO: pixel data incomplete: 0
[16:46:43.124] <TB0> INFO: pixel address: 0
[16:46:43.124] <TB0> INFO: pulse height fill bit: 0
[16:46:43.124] <TB0> INFO: buffer corruption: 0
[16:46:43.163] <TB0> INFO: ######################################################################
[16:46:43.163] <TB0> INFO: PixTestScurves::fullTest() ntrig = 50, dacs/step = -1, ntrig/step = -1
[16:46:43.163] <TB0> INFO: ######################################################################
[16:46:43.164] <TB0> INFO: ----------------------------------------------------------------------
[16:46:43.164] <TB0> INFO: PixTestScurves::scurves(Vcal), ntrig = 50, dacs/step = -1, ntrig/step = -1
[16:46:43.164] <TB0> INFO: ----------------------------------------------------------------------
[16:46:43.164] <TB0> INFO: ---> dac: Vcal name: scurveVcal ntrig: 50 dacrange: 25 .. 200 (-1/-1) hits flags = 528 (plus default)
[16:46:43.178] <TB0> INFO: dacScan split into 1 runs with ntrig = 50
[16:46:43.178] <TB0> INFO: run 1 of 1
[16:46:43.465] <TB0> INFO: Expecting 36608000 events.
[16:47:08.323] <TB0> INFO: 698250 events read in total (24266ms).
[16:47:31.181] <TB0> INFO: 1379550 events read in total (47124ms).
[16:47:54.275] <TB0> INFO: 2061600 events read in total (70218ms).
[16:48:17.370] <TB0> INFO: 2742700 events read in total (93313ms).
[16:48:40.672] <TB0> INFO: 3423050 events read in total (116615ms).
[16:49:03.614] <TB0> INFO: 4102250 events read in total (139557ms).
[16:49:26.908] <TB0> INFO: 4783650 events read in total (162851ms).
[16:49:49.758] <TB0> INFO: 5464100 events read in total (185701ms).
[16:50:12.784] <TB0> INFO: 6142750 events read in total (208727ms).
[16:50:35.899] <TB0> INFO: 6820050 events read in total (231842ms).
[16:50:58.679] <TB0> INFO: 7497150 events read in total (254622ms).
[16:51:21.641] <TB0> INFO: 8174950 events read in total (277584ms).
[16:51:44.839] <TB0> INFO: 8853450 events read in total (300782ms).
[16:52:07.926] <TB0> INFO: 9531550 events read in total (323869ms).
[16:52:31.011] <TB0> INFO: 10208150 events read in total (346954ms).
[16:52:53.919] <TB0> INFO: 10885100 events read in total (369862ms).
[16:53:16.766] <TB0> INFO: 11562350 events read in total (392709ms).
[16:53:39.524] <TB0> INFO: 12241300 events read in total (415467ms).
[16:54:02.539] <TB0> INFO: 12919550 events read in total (438482ms).
[16:54:25.597] <TB0> INFO: 13596600 events read in total (461541ms).
[16:54:48.661] <TB0> INFO: 14272250 events read in total (484604ms).
[16:55:11.483] <TB0> INFO: 14946850 events read in total (507426ms).
[16:55:34.342] <TB0> INFO: 15621550 events read in total (530285ms).
[16:55:57.431] <TB0> INFO: 16298400 events read in total (553374ms).
[16:56:20.525] <TB0> INFO: 16975150 events read in total (576468ms).
[16:56:43.356] <TB0> INFO: 17650550 events read in total (599299ms).
[16:57:06.109] <TB0> INFO: 18325400 events read in total (622052ms).
[16:57:29.166] <TB0> INFO: 18998450 events read in total (645109ms).
[16:57:52.222] <TB0> INFO: 19671800 events read in total (668165ms).
[16:58:15.115] <TB0> INFO: 20343750 events read in total (691058ms).
[16:58:38.160] <TB0> INFO: 21014250 events read in total (714103ms).
[16:59:01.260] <TB0> INFO: 21688500 events read in total (737203ms).
[16:59:24.351] <TB0> INFO: 22359800 events read in total (760295ms).
[16:59:47.301] <TB0> INFO: 23033100 events read in total (783244ms).
[17:00:10.230] <TB0> INFO: 23705950 events read in total (806173ms).
[17:00:33.195] <TB0> INFO: 24378300 events read in total (829138ms).
[17:00:56.069] <TB0> INFO: 25049500 events read in total (852012ms).
[17:01:19.199] <TB0> INFO: 25721750 events read in total (875142ms).
[17:01:41.965] <TB0> INFO: 26393750 events read in total (897908ms).
[17:02:05.079] <TB0> INFO: 27065450 events read in total (921022ms).
[17:02:28.089] <TB0> INFO: 27735850 events read in total (944032ms).
[17:02:51.363] <TB0> INFO: 28406750 events read in total (967306ms).
[17:03:14.579] <TB0> INFO: 29076450 events read in total (990522ms).
[17:03:37.647] <TB0> INFO: 29747400 events read in total (1013590ms).
[17:04:00.638] <TB0> INFO: 30419100 events read in total (1036581ms).
[17:04:23.746] <TB0> INFO: 31089550 events read in total (1059689ms).
[17:04:46.745] <TB0> INFO: 31762800 events read in total (1082688ms).
[17:05:10.029] <TB0> INFO: 32434650 events read in total (1105972ms).
[17:05:33.182] <TB0> INFO: 33106150 events read in total (1129125ms).
[17:05:56.243] <TB0> INFO: 33778900 events read in total (1152186ms).
[17:06:19.452] <TB0> INFO: 34451250 events read in total (1175395ms).
[17:06:42.677] <TB0> INFO: 35122750 events read in total (1198620ms).
[17:07:05.773] <TB0> INFO: 35797350 events read in total (1221716ms).
[17:07:29.121] <TB0> INFO: 36482950 events read in total (1245064ms).
[17:07:33.450] <TB0> INFO: 36608000 events read in total (1249393ms).
[17:07:33.513] <TB0> INFO: Test took 1250335ms.
[17:07:33.917] <TB0> INFO: dumping ASCII scurve output file: SCurveData
[17:07:35.350] <TB0> INFO: dumping ASCII scurve output file: SCurveData
[17:07:36.799] <TB0> INFO: dumping ASCII scurve output file: SCurveData
[17:07:38.325] <TB0> INFO: dumping ASCII scurve output file: SCurveData
[17:07:39.833] <TB0> INFO: dumping ASCII scurve output file: SCurveData
[17:07:41.486] <TB0> INFO: dumping ASCII scurve output file: SCurveData
[17:07:42.976] <TB0> INFO: dumping ASCII scurve output file: SCurveData
[17:07:44.492] <TB0> INFO: dumping ASCII scurve output file: SCurveData
[17:07:45.991] <TB0> INFO: dumping ASCII scurve output file: SCurveData
[17:07:47.541] <TB0> INFO: dumping ASCII scurve output file: SCurveData
[17:07:49.482] <TB0> INFO: dumping ASCII scurve output file: SCurveData
[17:07:51.302] <TB0> INFO: dumping ASCII scurve output file: SCurveData
[17:07:53.049] <TB0> INFO: dumping ASCII scurve output file: SCurveData
[17:07:54.585] <TB0> INFO: dumping ASCII scurve output file: SCurveData
[17:07:56.040] <TB0> INFO: dumping ASCII scurve output file: SCurveData
[17:07:57.483] <TB0> INFO: dumping ASCII scurve output file: SCurveData
[17:07:58.966] <TB0> INFO: PixTestScurves::scurves() done
[17:07:58.966] <TB0> INFO: Vcal mean: 132.65 124.58 115.52 120.82 119.19 124.14 125.37 117.59 119.95 124.46 126.84 128.15 122.87 118.00 123.31 117.09
[17:07:58.966] <TB0> INFO: Vcal RMS: 6.01 5.53 5.35 6.48 5.42 5.31 5.20 5.50 5.80 5.13 5.40 5.60 6.36 5.49 6.08 5.89
[17:07:58.966] <TB0> INFO: PixTestScurves::fullTest() done, duration: 1275 seconds
[17:07:58.966] <TB0> INFO: Decoding statistics:
[17:07:58.966] <TB0> INFO: General information:
[17:07:58.966] <TB0> INFO: 16bit words read: 0
[17:07:58.966] <TB0> INFO: valid events total: 0
[17:07:58.966] <TB0> INFO: empty events: 0
[17:07:58.966] <TB0> INFO: valid events with pixels: 0
[17:07:58.966] <TB0> INFO: valid pixel hits: 0
[17:07:58.966] <TB0> INFO: Event errors: 0
[17:07:58.966] <TB0> INFO: start marker: 0
[17:07:58.966] <TB0> INFO: stop marker: 0
[17:07:58.966] <TB0> INFO: overflow: 0
[17:07:58.966] <TB0> INFO: invalid 5bit words: 0
[17:07:58.966] <TB0> INFO: invalid XOR eye diagram: 0
[17:07:58.966] <TB0> INFO: frame (failed synchr.): 0
[17:07:58.966] <TB0> INFO: idle data (no TBM trl): 0
[17:07:58.966] <TB0> INFO: no data (only TBM hdr): 0
[17:07:58.966] <TB0> INFO: TBM errors: 0
[17:07:58.966] <TB0> INFO: flawed TBM headers: 0
[17:07:58.966] <TB0> INFO: flawed TBM trailers: 0
[17:07:58.966] <TB0> INFO: event ID mismatches: 0
[17:07:58.966] <TB0> INFO: ROC errors: 0
[17:07:58.966] <TB0> INFO: missing ROC header(s): 0
[17:07:58.966] <TB0> INFO: misplaced readback start: 0
[17:07:58.966] <TB0> INFO: Pixel decoding errors: 0
[17:07:58.966] <TB0> INFO: pixel data incomplete: 0
[17:07:58.966] <TB0> INFO: pixel address: 0
[17:07:58.966] <TB0> INFO: pulse height fill bit: 0
[17:07:58.966] <TB0> INFO: buffer corruption: 0
[17:07:59.036] <TB0> INFO: ######################################################################
[17:07:59.036] <TB0> INFO: PixTestTrim::doTest()
[17:07:59.036] <TB0> INFO: ######################################################################
[17:07:59.037] <TB0> INFO: ----------------------------------------------------------------------
[17:07:59.037] <TB0> INFO: PixTestTrim::trimTest() ntrig = 8, vcal = 35
[17:07:59.037] <TB0> INFO: ----------------------------------------------------------------------
[17:07:59.083] <TB0> INFO: ---> VthrComp thr map (minimal VthrComp)
[17:07:59.083] <TB0> INFO: ---> dac: vthrcomp name: TrimThr0 ntrig: 8 dacrange: 10 .. 160 (-1/-1) hits flags = 528 (plus default)
[17:07:59.103] <TB0> INFO: dacScan split into 1 runs with ntrig = 8
[17:07:59.103] <TB0> INFO: run 1 of 1
[17:07:59.352] <TB0> INFO: Expecting 5025280 events.
[17:08:31.588] <TB0> INFO: 827728 events read in total (31638ms).
[17:09:01.788] <TB0> INFO: 1652392 events read in total (61838ms).
[17:09:31.891] <TB0> INFO: 2475184 events read in total (91941ms).
[17:10:01.895] <TB0> INFO: 3295024 events read in total (121945ms).
[17:10:32.373] <TB0> INFO: 4110728 events read in total (152423ms).
[17:11:02.591] <TB0> INFO: 4927080 events read in total (182641ms).
[17:11:06.438] <TB0> INFO: 5025280 events read in total (186488ms).
[17:11:06.484] <TB0> INFO: Test took 187381ms.
[17:11:25.557] <TB0> INFO: ROC 0 VthrComp = 132
[17:11:25.557] <TB0> INFO: ROC 1 VthrComp = 125
[17:11:25.557] <TB0> INFO: ROC 2 VthrComp = 117
[17:11:25.557] <TB0> INFO: ROC 3 VthrComp = 126
[17:11:25.557] <TB0> INFO: ROC 4 VthrComp = 119
[17:11:25.557] <TB0> INFO: ROC 5 VthrComp = 127
[17:11:25.557] <TB0> INFO: ROC 6 VthrComp = 133
[17:11:25.557] <TB0> INFO: ROC 7 VthrComp = 116
[17:11:25.557] <TB0> INFO: ROC 8 VthrComp = 124
[17:11:25.557] <TB0> INFO: ROC 9 VthrComp = 133
[17:11:25.557] <TB0> INFO: ROC 10 VthrComp = 130
[17:11:25.558] <TB0> INFO: ROC 11 VthrComp = 126
[17:11:25.558] <TB0> INFO: ROC 12 VthrComp = 126
[17:11:25.558] <TB0> INFO: ROC 13 VthrComp = 122
[17:11:25.558] <TB0> INFO: ROC 14 VthrComp = 127
[17:11:25.558] <TB0> INFO: ROC 15 VthrComp = 120
[17:11:25.832] <TB0> INFO: Expecting 41600 events.
[17:11:29.359] <TB0> INFO: 41600 events read in total (2936ms).
[17:11:29.359] <TB0> INFO: Test took 3800ms.
[17:11:29.368] <TB0> INFO: ---> Vcal thr map (pixel with maximum Vcal thr)
[17:11:29.368] <TB0> INFO: ---> dac: vcal name: TrimThr1 ntrig: 8 dacrange: 10 .. 160 (-1/-1) hits flags = 528 (plus default)
[17:11:29.380] <TB0> INFO: dacScan split into 1 runs with ntrig = 8
[17:11:29.380] <TB0> INFO: run 1 of 1
[17:11:29.658] <TB0> INFO: Expecting 5025280 events.
[17:11:57.615] <TB0> INFO: 589200 events read in total (27365ms).
[17:12:23.504] <TB0> INFO: 1177520 events read in total (53254ms).
[17:12:49.325] <TB0> INFO: 1766584 events read in total (79075ms).
[17:13:14.862] <TB0> INFO: 2355336 events read in total (104612ms).
[17:13:40.792] <TB0> INFO: 2942304 events read in total (130542ms).
[17:14:06.661] <TB0> INFO: 3528184 events read in total (156411ms).
[17:14:32.424] <TB0> INFO: 4113256 events read in total (182174ms).
[17:14:58.019] <TB0> INFO: 4697872 events read in total (207769ms).
[17:15:12.603] <TB0> INFO: 5025280 events read in total (222353ms).
[17:15:12.676] <TB0> INFO: Test took 223295ms.
[17:15:36.748] <TB0> INFO: roc 0 with ID = 0 has maximal Vcal 59.7487 for pixel 2/10 mean/min/max = 46.3958/33.0276/59.7639
[17:15:36.749] <TB0> INFO: roc 1 with ID = 1 has maximal Vcal 63.1509 for pixel 12/7 mean/min/max = 47.3199/31.4649/63.1749
[17:15:36.749] <TB0> INFO: roc 2 with ID = 2 has maximal Vcal 59.5221 for pixel 15/4 mean/min/max = 45.8964/32.2445/59.5483
[17:15:36.749] <TB0> INFO: roc 3 with ID = 3 has maximal Vcal 57.0031 for pixel 7/46 mean/min/max = 44.7143/32.3844/57.0442
[17:15:36.750] <TB0> INFO: roc 4 with ID = 4 has maximal Vcal 58.5114 for pixel 13/25 mean/min/max = 45.5767/32.5273/58.6262
[17:15:36.750] <TB0> INFO: roc 5 with ID = 5 has maximal Vcal 58.2731 for pixel 50/65 mean/min/max = 45.2819/32.0995/58.4643
[17:15:36.750] <TB0> INFO: roc 6 with ID = 6 has maximal Vcal 56.9719 for pixel 46/68 mean/min/max = 44.7313/32.2728/57.1898
[17:15:36.751] <TB0> INFO: roc 7 with ID = 7 has maximal Vcal 59.7634 for pixel 46/2 mean/min/max = 46.3786/32.9848/59.7724
[17:15:36.751] <TB0> INFO: roc 8 with ID = 8 has maximal Vcal 58.7333 for pixel 48/73 mean/min/max = 45.6201/32.3823/58.8579
[17:15:36.751] <TB0> INFO: roc 9 with ID = 9 has maximal Vcal 57.5516 for pixel 8/9 mean/min/max = 45.0795/32.579/57.5801
[17:15:36.752] <TB0> INFO: roc 10 with ID = 10 has maximal Vcal 59.3581 for pixel 3/21 mean/min/max = 46.0009/32.5697/59.4322
[17:15:36.752] <TB0> INFO: roc 11 with ID = 11 has maximal Vcal 60.248 for pixel 32/5 mean/min/max = 46.8773/33.4769/60.2777
[17:15:36.752] <TB0> INFO: roc 12 with ID = 12 has maximal Vcal 59.7736 for pixel 12/3 mean/min/max = 45.8246/31.8534/59.7958
[17:15:36.753] <TB0> INFO: roc 13 with ID = 13 has maximal Vcal 58.1642 for pixel 1/3 mean/min/max = 45.6128/33.0226/58.203
[17:15:36.753] <TB0> INFO: roc 14 with ID = 14 has maximal Vcal 60.0293 for pixel 25/11 mean/min/max = 45.9083/31.64/60.1765
[17:15:36.753] <TB0> INFO: roc 15 with ID = 15 has maximal Vcal 59.8078 for pixel 12/79 mean/min/max = 46.6757/33.3937/59.9577
[17:15:36.754] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[17:15:36.843] <TB0> INFO: Expecting 411648 events.
[17:15:46.378] <TB0> INFO: 411648 events read in total (8944ms).
[17:15:46.387] <TB0> INFO: Expecting 411648 events.
[17:15:56.056] <TB0> INFO: 411648 events read in total (9266ms).
[17:15:56.068] <TB0> INFO: Expecting 411648 events.
[17:16:05.611] <TB0> INFO: 411648 events read in total (9134ms).
[17:16:05.630] <TB0> INFO: Expecting 411648 events.
[17:16:15.010] <TB0> INFO: 411648 events read in total (8977ms).
[17:16:15.034] <TB0> INFO: Expecting 411648 events.
[17:16:24.371] <TB0> INFO: 411648 events read in total (8932ms).
[17:16:24.396] <TB0> INFO: Expecting 411648 events.
[17:16:33.801] <TB0> INFO: 411648 events read in total (9002ms).
[17:16:33.822] <TB0> INFO: Expecting 411648 events.
[17:16:43.225] <TB0> INFO: 411648 events read in total (8999ms).
[17:16:43.249] <TB0> INFO: Expecting 411648 events.
[17:16:52.702] <TB0> INFO: 411648 events read in total (9050ms).
[17:16:52.737] <TB0> INFO: Expecting 411648 events.
[17:17:02.153] <TB0> INFO: 411648 events read in total (9012ms).
[17:17:02.189] <TB0> INFO: Expecting 411648 events.
[17:17:11.592] <TB0> INFO: 411648 events read in total (8996ms).
[17:17:11.640] <TB0> INFO: Expecting 411648 events.
[17:17:20.958] <TB0> INFO: 411648 events read in total (8915ms).
[17:17:21.011] <TB0> INFO: Expecting 411648 events.
[17:17:30.432] <TB0> INFO: 411648 events read in total (9018ms).
[17:17:30.497] <TB0> INFO: Expecting 411648 events.
[17:17:39.895] <TB0> INFO: 411648 events read in total (8994ms).
[17:17:39.949] <TB0> INFO: Expecting 411648 events.
[17:17:49.332] <TB0> INFO: 411648 events read in total (8979ms).
[17:17:49.422] <TB0> INFO: Expecting 411648 events.
[17:17:58.845] <TB0> INFO: 411648 events read in total (9020ms).
[17:17:58.937] <TB0> INFO: Expecting 411648 events.
[17:18:08.322] <TB0> INFO: 411648 events read in total (8982ms).
[17:18:08.390] <TB0> INFO: Test took 151636ms.
[17:18:09.126] <TB0> INFO: ---> dac: vcal name: TrimThr2 ntrig: 8 dacrange: 0 .. 150 (-1/-1) hits flags = 528 (plus default)
[17:18:09.138] <TB0> INFO: dacScan split into 1 runs with ntrig = 8
[17:18:09.138] <TB0> INFO: run 1 of 1
[17:18:09.376] <TB0> INFO: Expecting 5025280 events.
[17:18:36.093] <TB0> INFO: 587808 events read in total (26126ms).
[17:19:02.291] <TB0> INFO: 1175744 events read in total (52324ms).
[17:19:28.742] <TB0> INFO: 1762656 events read in total (78775ms).
[17:19:54.846] <TB0> INFO: 2349112 events read in total (104879ms).
[17:20:21.135] <TB0> INFO: 2936104 events read in total (131169ms).
[17:20:47.568] <TB0> INFO: 3525344 events read in total (157601ms).
[17:21:13.440] <TB0> INFO: 4112896 events read in total (183473ms).
[17:21:39.988] <TB0> INFO: 4700600 events read in total (210022ms).
[17:21:54.825] <TB0> INFO: 5025280 events read in total (224858ms).
[17:21:54.993] <TB0> INFO: Test took 225855ms.
[17:22:19.788] <TB0> INFO: ---> TrimStepCorr4 extremal thresholds: 3.720140 .. 142.444483
[17:22:20.028] <TB0> INFO: Expecting 208000 events.
[17:22:29.607] <TB0> INFO: 208000 events read in total (8988ms).
[17:22:29.609] <TB0> INFO: Test took 9820ms.
[17:22:29.676] <TB0> INFO: ---> dac: vcal name: trimStepCorr4 ntrig: 8 dacrange: 3 .. 152 (-1/-1) hits flags = 528 (plus default)
[17:22:29.694] <TB0> INFO: dacScan split into 1 runs with ntrig = 8
[17:22:29.694] <TB0> INFO: run 1 of 1
[17:22:30.014] <TB0> INFO: Expecting 4992000 events.
[17:22:56.837] <TB0> INFO: 583128 events read in total (26231ms).
[17:23:22.222] <TB0> INFO: 1166360 events read in total (51617ms).
[17:23:48.188] <TB0> INFO: 1749416 events read in total (77582ms).
[17:24:14.039] <TB0> INFO: 2332592 events read in total (103433ms).
[17:24:39.783] <TB0> INFO: 2915576 events read in total (129177ms).
[17:25:05.592] <TB0> INFO: 3498280 events read in total (154986ms).
[17:25:31.271] <TB0> INFO: 4080360 events read in total (180665ms).
[17:25:56.993] <TB0> INFO: 4662320 events read in total (206387ms).
[17:26:11.506] <TB0> INFO: 4992000 events read in total (220900ms).
[17:26:11.645] <TB0> INFO: Test took 221952ms.
[17:26:36.480] <TB0> INFO: ---> TrimStepCorr2 extremal thresholds: 27.818414 .. 46.120851
[17:26:36.718] <TB0> INFO: Expecting 208000 events.
[17:26:46.448] <TB0> INFO: 208000 events read in total (9138ms).
[17:26:46.452] <TB0> INFO: Test took 9971ms.
[17:26:46.501] <TB0> INFO: ---> dac: vcal name: trimStepCorr2 ntrig: 8 dacrange: 17 .. 56 (-1/-1) hits flags = 528 (plus default)
[17:26:46.513] <TB0> INFO: dacScan split into 1 runs with ntrig = 8
[17:26:46.514] <TB0> INFO: run 1 of 1
[17:26:46.792] <TB0> INFO: Expecting 1331200 events.
[17:27:17.159] <TB0> INFO: 656760 events read in total (29776ms).
[17:27:44.632] <TB0> INFO: 1311112 events read in total (57249ms).
[17:27:45.886] <TB0> INFO: 1331200 events read in total (58504ms).
[17:27:45.915] <TB0> INFO: Test took 59402ms.
[17:27:58.741] <TB0> INFO: ---> TrimStepCorr1a extremal thresholds: 28.032534 .. 46.665087
[17:27:58.983] <TB0> INFO: Expecting 208000 events.
[17:28:08.976] <TB0> INFO: 208000 events read in total (9401ms).
[17:28:08.977] <TB0> INFO: Test took 10234ms.
[17:28:09.045] <TB0> INFO: ---> dac: vcal name: trimStepCorr1a ntrig: 8 dacrange: 18 .. 56 (-1/-1) hits flags = 528 (plus default)
[17:28:09.063] <TB0> INFO: dacScan split into 1 runs with ntrig = 8
[17:28:09.064] <TB0> INFO: run 1 of 1
[17:28:09.342] <TB0> INFO: Expecting 1297920 events.
[17:28:37.563] <TB0> INFO: 652592 events read in total (27629ms).
[17:29:05.034] <TB0> INFO: 1297920 events read in total (55100ms).
[17:29:05.070] <TB0> INFO: Test took 56007ms.
[17:29:18.900] <TB0> INFO: ---> TrimStepCorr1b extremal thresholds: 25.896334 .. 47.454636
[17:29:19.180] <TB0> INFO: Expecting 208000 events.
[17:29:28.908] <TB0> INFO: 208000 events read in total (9136ms).
[17:29:28.909] <TB0> INFO: Test took 10008ms.
[17:29:28.957] <TB0> INFO: ---> dac: vcal name: trimStepCorr1b ntrig: 8 dacrange: 15 .. 57 (-1/-1) hits flags = 528 (plus default)
[17:29:28.969] <TB0> INFO: dacScan split into 1 runs with ntrig = 8
[17:29:28.969] <TB0> INFO: run 1 of 1
[17:29:29.248] <TB0> INFO: Expecting 1431040 events.
[17:29:57.692] <TB0> INFO: 660072 events read in total (27853ms).
[17:30:25.009] <TB0> INFO: 1320608 events read in total (55170ms).
[17:30:29.807] <TB0> INFO: 1431040 events read in total (59968ms).
[17:30:29.836] <TB0> INFO: Test took 60867ms.
[17:30:42.586] <TB0> INFO: ---> TrimThrFinal extremal thresholds: 15 .. 55
[17:30:42.586] <TB0> INFO: ---> dac: vcal name: TrimThrFinal ntrig: 8 dacrange: 15 .. 55 (-1/-1) hits flags = 528 (plus default)
[17:30:42.600] <TB0> INFO: dacScan split into 1 runs with ntrig = 8
[17:30:42.600] <TB0> INFO: run 1 of 1
[17:30:42.912] <TB0> INFO: Expecting 1364480 events.
[17:31:11.353] <TB0> INFO: 668392 events read in total (27849ms).
[17:31:39.533] <TB0> INFO: 1335888 events read in total (56029ms).
[17:31:41.284] <TB0> INFO: 1364480 events read in total (57781ms).
[17:31:41.310] <TB0> INFO: Test took 58711ms.
[17:31:54.315] <TB0> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1015_FullQualification_2016-10-19_14h13m_1476879208//003_Fulltest_p17//dacParameters35_C0.dat
[17:31:54.315] <TB0> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1015_FullQualification_2016-10-19_14h13m_1476879208//003_Fulltest_p17//dacParameters35_C1.dat
[17:31:54.315] <TB0> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1015_FullQualification_2016-10-19_14h13m_1476879208//003_Fulltest_p17//dacParameters35_C2.dat
[17:31:54.315] <TB0> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1015_FullQualification_2016-10-19_14h13m_1476879208//003_Fulltest_p17//dacParameters35_C3.dat
[17:31:54.316] <TB0> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1015_FullQualification_2016-10-19_14h13m_1476879208//003_Fulltest_p17//dacParameters35_C4.dat
[17:31:54.316] <TB0> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1015_FullQualification_2016-10-19_14h13m_1476879208//003_Fulltest_p17//dacParameters35_C5.dat
[17:31:54.316] <TB0> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1015_FullQualification_2016-10-19_14h13m_1476879208//003_Fulltest_p17//dacParameters35_C6.dat
[17:31:54.316] <TB0> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1015_FullQualification_2016-10-19_14h13m_1476879208//003_Fulltest_p17//dacParameters35_C7.dat
[17:31:54.316] <TB0> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1015_FullQualification_2016-10-19_14h13m_1476879208//003_Fulltest_p17//dacParameters35_C8.dat
[17:31:54.316] <TB0> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1015_FullQualification_2016-10-19_14h13m_1476879208//003_Fulltest_p17//dacParameters35_C9.dat
[17:31:54.316] <TB0> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1015_FullQualification_2016-10-19_14h13m_1476879208//003_Fulltest_p17//dacParameters35_C10.dat
[17:31:54.316] <TB0> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1015_FullQualification_2016-10-19_14h13m_1476879208//003_Fulltest_p17//dacParameters35_C11.dat
[17:31:54.316] <TB0> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1015_FullQualification_2016-10-19_14h13m_1476879208//003_Fulltest_p17//dacParameters35_C12.dat
[17:31:54.317] <TB0> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1015_FullQualification_2016-10-19_14h13m_1476879208//003_Fulltest_p17//dacParameters35_C13.dat
[17:31:54.317] <TB0> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1015_FullQualification_2016-10-19_14h13m_1476879208//003_Fulltest_p17//dacParameters35_C14.dat
[17:31:54.317] <TB0> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1015_FullQualification_2016-10-19_14h13m_1476879208//003_Fulltest_p17//dacParameters35_C15.dat
[17:31:54.317] <TB0> INFO: write trim parameters into /home/l_tester/david/FullQualification/data/M1015_FullQualification_2016-10-19_14h13m_1476879208//003_Fulltest_p17//trimParameters35_C0.dat
[17:31:54.323] <TB0> INFO: write trim parameters into /home/l_tester/david/FullQualification/data/M1015_FullQualification_2016-10-19_14h13m_1476879208//003_Fulltest_p17//trimParameters35_C1.dat
[17:31:54.331] <TB0> INFO: write trim parameters into /home/l_tester/david/FullQualification/data/M1015_FullQualification_2016-10-19_14h13m_1476879208//003_Fulltest_p17//trimParameters35_C2.dat
[17:31:54.338] <TB0> INFO: write trim parameters into /home/l_tester/david/FullQualification/data/M1015_FullQualification_2016-10-19_14h13m_1476879208//003_Fulltest_p17//trimParameters35_C3.dat
[17:31:54.345] <TB0> INFO: write trim parameters into /home/l_tester/david/FullQualification/data/M1015_FullQualification_2016-10-19_14h13m_1476879208//003_Fulltest_p17//trimParameters35_C4.dat
[17:31:54.352] <TB0> INFO: write trim parameters into /home/l_tester/david/FullQualification/data/M1015_FullQualification_2016-10-19_14h13m_1476879208//003_Fulltest_p17//trimParameters35_C5.dat
[17:31:54.359] <TB0> INFO: write trim parameters into /home/l_tester/david/FullQualification/data/M1015_FullQualification_2016-10-19_14h13m_1476879208//003_Fulltest_p17//trimParameters35_C6.dat
[17:31:54.366] <TB0> INFO: write trim parameters into /home/l_tester/david/FullQualification/data/M1015_FullQualification_2016-10-19_14h13m_1476879208//003_Fulltest_p17//trimParameters35_C7.dat
[17:31:54.372] <TB0> INFO: write trim parameters into /home/l_tester/david/FullQualification/data/M1015_FullQualification_2016-10-19_14h13m_1476879208//003_Fulltest_p17//trimParameters35_C8.dat
[17:31:54.378] <TB0> INFO: write trim parameters into /home/l_tester/david/FullQualification/data/M1015_FullQualification_2016-10-19_14h13m_1476879208//003_Fulltest_p17//trimParameters35_C9.dat
[17:31:54.384] <TB0> INFO: write trim parameters into /home/l_tester/david/FullQualification/data/M1015_FullQualification_2016-10-19_14h13m_1476879208//003_Fulltest_p17//trimParameters35_C10.dat
[17:31:54.390] <TB0> INFO: write trim parameters into /home/l_tester/david/FullQualification/data/M1015_FullQualification_2016-10-19_14h13m_1476879208//003_Fulltest_p17//trimParameters35_C11.dat
[17:31:54.396] <TB0> INFO: write trim parameters into /home/l_tester/david/FullQualification/data/M1015_FullQualification_2016-10-19_14h13m_1476879208//003_Fulltest_p17//trimParameters35_C12.dat
[17:31:54.402] <TB0> INFO: write trim parameters into /home/l_tester/david/FullQualification/data/M1015_FullQualification_2016-10-19_14h13m_1476879208//003_Fulltest_p17//trimParameters35_C13.dat
[17:31:54.408] <TB0> INFO: write trim parameters into /home/l_tester/david/FullQualification/data/M1015_FullQualification_2016-10-19_14h13m_1476879208//003_Fulltest_p17//trimParameters35_C14.dat
[17:31:54.413] <TB0> INFO: write trim parameters into /home/l_tester/david/FullQualification/data/M1015_FullQualification_2016-10-19_14h13m_1476879208//003_Fulltest_p17//trimParameters35_C15.dat
[17:31:54.419] <TB0> INFO: PixTestTrim::trimTest() done
[17:31:54.419] <TB0> INFO: vtrim: 120 140 131 117 113 119 121 116 127 123 129 125 129 110 129 120
[17:31:54.419] <TB0> INFO: vthrcomp: 132 125 117 126 119 127 133 116 124 133 130 126 126 122 127 120
[17:31:54.419] <TB0> INFO: vcal mean: 35.10 35.32 34.97 34.97 35.16 35.05 35.00 35.11 35.01 34.99 35.04 34.99 34.97 34.93 35.02 35.00
[17:31:54.419] <TB0> INFO: vcal RMS: 1.22 1.44 0.99 1.00 1.17 1.04 1.04 1.26 1.07 0.95 1.13 1.06 1.02 0.94 1.15 1.05
[17:31:54.419] <TB0> INFO: bits mean: 9.29 9.75 9.72 9.90 9.54 9.74 10.03 9.42 9.94 9.48 9.50 9.03 9.44 9.09 9.88 8.87
[17:31:54.419] <TB0> INFO: bits RMS: 2.75 2.74 2.63 2.62 2.71 2.64 2.50 2.70 2.56 2.72 2.69 2.70 2.81 2.77 2.67 2.77
[17:31:54.427] <TB0> INFO: ----------------------------------------------------------------------
[17:31:54.427] <TB0> INFO: PixTestTrim::trimBitTest() ntrig = 5, vtrims = 254 126 63 32
[17:31:54.427] <TB0> INFO: ----------------------------------------------------------------------
[17:31:54.431] <TB0> INFO: ---> dac: Vcal name: TrimBitsThr0 ntrig: 5 dacrange: 0 .. 199 (-1/-1) hits flags = 528 (plus default)
[17:31:54.443] <TB0> INFO: dacScan split into 1 runs with ntrig = 5
[17:31:54.443] <TB0> INFO: run 1 of 1
[17:31:54.679] <TB0> INFO: Expecting 4160000 events.
[17:32:27.676] <TB0> INFO: 769920 events read in total (32405ms).
[17:32:59.818] <TB0> INFO: 1533705 events read in total (64547ms).
[17:33:31.704] <TB0> INFO: 2293505 events read in total (96433ms).
[17:34:04.036] <TB0> INFO: 3050815 events read in total (128765ms).
[17:34:36.393] <TB0> INFO: 3803800 events read in total (161122ms).
[17:34:52.040] <TB0> INFO: 4160000 events read in total (176769ms).
[17:34:52.125] <TB0> INFO: Test took 177682ms.
[17:35:16.396] <TB0> INFO: ---> dac: Vcal name: TrimThr_trim14 ntrig: 5 dacrange: 0 .. 209 (-1/-1) hits flags = 528 (plus default)
[17:35:16.409] <TB0> INFO: dacScan split into 1 runs with ntrig = 5
[17:35:16.409] <TB0> INFO: run 1 of 1
[17:35:16.646] <TB0> INFO: Expecting 4368000 events.
[17:35:48.565] <TB0> INFO: 731285 events read in total (31327ms).
[17:36:19.931] <TB0> INFO: 1457340 events read in total (62693ms).
[17:36:50.996] <TB0> INFO: 2181700 events read in total (93758ms).
[17:37:22.310] <TB0> INFO: 2903080 events read in total (125072ms).
[17:37:53.577] <TB0> INFO: 3622310 events read in total (156339ms).
[17:38:24.662] <TB0> INFO: 4341985 events read in total (187424ms).
[17:38:26.148] <TB0> INFO: 4368000 events read in total (188910ms).
[17:38:26.221] <TB0> INFO: Test took 189812ms.
[17:38:52.252] <TB0> INFO: ---> dac: Vcal name: TrimThr_trim13 ntrig: 5 dacrange: 0 .. 196 (-1/-1) hits flags = 528 (plus default)
[17:38:52.265] <TB0> INFO: dacScan split into 1 runs with ntrig = 5
[17:38:52.265] <TB0> INFO: run 1 of 1
[17:38:52.503] <TB0> INFO: Expecting 4097600 events.
[17:39:24.751] <TB0> INFO: 749885 events read in total (31657ms).
[17:39:56.877] <TB0> INFO: 1494280 events read in total (63783ms).
[17:40:29.186] <TB0> INFO: 2235335 events read in total (96092ms).
[17:41:01.206] <TB0> INFO: 2973695 events read in total (128112ms).
[17:41:32.950] <TB0> INFO: 3709465 events read in total (159856ms).
[17:41:49.650] <TB0> INFO: 4097600 events read in total (176556ms).
[17:41:49.760] <TB0> INFO: Test took 177495ms.
[17:42:15.273] <TB0> INFO: ---> dac: Vcal name: TrimThr_trim11 ntrig: 5 dacrange: 0 .. 195 (-1/-1) hits flags = 528 (plus default)
[17:42:15.286] <TB0> INFO: dacScan split into 1 runs with ntrig = 5
[17:42:15.286] <TB0> INFO: run 1 of 1
[17:42:15.522] <TB0> INFO: Expecting 4076800 events.
[17:42:47.959] <TB0> INFO: 751465 events read in total (31845ms).
[17:43:20.056] <TB0> INFO: 1497785 events read in total (63942ms).
[17:43:52.281] <TB0> INFO: 2240795 events read in total (96168ms).
[17:44:24.045] <TB0> INFO: 2981340 events read in total (127931ms).
[17:44:55.645] <TB0> INFO: 3718650 events read in total (159531ms).
[17:45:11.116] <TB0> INFO: 4076800 events read in total (175002ms).
[17:45:11.259] <TB0> INFO: Test took 175973ms.
[17:45:37.680] <TB0> INFO: ---> dac: Vcal name: TrimThr_trim7 ntrig: 5 dacrange: 0 .. 196 (-1/-1) hits flags = 528 (plus default)
[17:45:37.693] <TB0> INFO: dacScan split into 1 runs with ntrig = 5
[17:45:37.693] <TB0> INFO: run 1 of 1
[17:45:37.970] <TB0> INFO: Expecting 4097600 events.
[17:46:10.387] <TB0> INFO: 750300 events read in total (31825ms).
[17:46:42.492] <TB0> INFO: 1495260 events read in total (63930ms).
[17:47:13.809] <TB0> INFO: 2236955 events read in total (95247ms).
[17:47:45.477] <TB0> INFO: 2975810 events read in total (126915ms).
[17:48:16.797] <TB0> INFO: 3712080 events read in total (158235ms).
[17:48:33.602] <TB0> INFO: 4097600 events read in total (175040ms).
[17:48:33.670] <TB0> INFO: Test took 175977ms.
[17:48:58.946] <TB0> INFO: PixTestTrim::trimBitTest() done
[17:48:58.947] <TB0> INFO: PixTestTrim::doTest() done, duration: 2459 seconds
[17:48:58.947] <TB0> INFO: Decoding statistics:
[17:48:58.947] <TB0> INFO: General information:
[17:48:58.947] <TB0> INFO: 16bit words read: 0
[17:48:58.947] <TB0> INFO: valid events total: 0
[17:48:58.947] <TB0> INFO: empty events: 0
[17:48:58.947] <TB0> INFO: valid events with pixels: 0
[17:48:58.947] <TB0> INFO: valid pixel hits: 0
[17:48:58.947] <TB0> INFO: Event errors: 0
[17:48:58.947] <TB0> INFO: start marker: 0
[17:48:58.947] <TB0> INFO: stop marker: 0
[17:48:58.947] <TB0> INFO: overflow: 0
[17:48:58.947] <TB0> INFO: invalid 5bit words: 0
[17:48:58.947] <TB0> INFO: invalid XOR eye diagram: 0
[17:48:58.947] <TB0> INFO: frame (failed synchr.): 0
[17:48:58.947] <TB0> INFO: idle data (no TBM trl): 0
[17:48:58.947] <TB0> INFO: no data (only TBM hdr): 0
[17:48:58.947] <TB0> INFO: TBM errors: 0
[17:48:58.947] <TB0> INFO: flawed TBM headers: 0
[17:48:58.947] <TB0> INFO: flawed TBM trailers: 0
[17:48:58.947] <TB0> INFO: event ID mismatches: 0
[17:48:58.947] <TB0> INFO: ROC errors: 0
[17:48:58.947] <TB0> INFO: missing ROC header(s): 0
[17:48:58.947] <TB0> INFO: misplaced readback start: 0
[17:48:58.947] <TB0> INFO: Pixel decoding errors: 0
[17:48:58.947] <TB0> INFO: pixel data incomplete: 0
[17:48:58.947] <TB0> INFO: pixel address: 0
[17:48:58.947] <TB0> INFO: pulse height fill bit: 0
[17:48:58.947] <TB0> INFO: buffer corruption: 0
[17:48:59.549] <TB0> INFO: ######################################################################
[17:48:59.549] <TB0> INFO: PixTestPhOptimization::doTest() Ntrig = 10
[17:48:59.549] <TB0> INFO: ######################################################################
[17:48:59.786] <TB0> INFO: Expecting 41600 events.
[17:49:03.231] <TB0> INFO: 41600 events read in total (2853ms).
[17:49:03.232] <TB0> INFO: Test took 3682ms.
[17:49:03.676] <TB0> INFO: Expecting 41600 events.
[17:49:07.262] <TB0> INFO: 41600 events read in total (2995ms).
[17:49:07.263] <TB0> INFO: Test took 3828ms.
[17:49:07.551] <TB0> INFO: Expecting 41600 events.
[17:49:11.107] <TB0> INFO: 41600 events read in total (2964ms).
[17:49:11.108] <TB0> INFO: Test took 3821ms.
[17:49:11.406] <TB0> INFO: Expecting 41600 events.
[17:49:15.039] <TB0> INFO: 41600 events read in total (3041ms).
[17:49:15.039] <TB0> INFO: Test took 3905ms.
[17:49:15.343] <TB0> INFO: Expecting 41600 events.
[17:49:18.962] <TB0> INFO: 41600 events read in total (3027ms).
[17:49:18.963] <TB0> INFO: Test took 3897ms.
[17:49:19.252] <TB0> INFO: Expecting 41600 events.
[17:49:22.868] <TB0> INFO: 41600 events read in total (3024ms).
[17:49:22.869] <TB0> INFO: Test took 3881ms.
[17:49:23.160] <TB0> INFO: Expecting 41600 events.
[17:49:26.874] <TB0> INFO: 41600 events read in total (3122ms).
[17:49:26.875] <TB0> INFO: Test took 3979ms.
[17:49:27.164] <TB0> INFO: Expecting 41600 events.
[17:49:30.725] <TB0> INFO: 41600 events read in total (2969ms).
[17:49:30.726] <TB0> INFO: Test took 3826ms.
[17:49:31.015] <TB0> INFO: Expecting 41600 events.
[17:49:34.473] <TB0> INFO: 41600 events read in total (2866ms).
[17:49:34.474] <TB0> INFO: Test took 3724ms.
[17:49:34.763] <TB0> INFO: Expecting 41600 events.
[17:49:38.293] <TB0> INFO: 41600 events read in total (2939ms).
[17:49:38.294] <TB0> INFO: Test took 3796ms.
[17:49:38.583] <TB0> INFO: Expecting 41600 events.
[17:49:42.137] <TB0> INFO: 41600 events read in total (2963ms).
[17:49:42.138] <TB0> INFO: Test took 3820ms.
[17:49:42.430] <TB0> INFO: Expecting 41600 events.
[17:49:46.051] <TB0> INFO: 41600 events read in total (3030ms).
[17:49:46.052] <TB0> INFO: Test took 3887ms.
[17:49:46.348] <TB0> INFO: Expecting 41600 events.
[17:49:50.045] <TB0> INFO: 41600 events read in total (3105ms).
[17:49:50.046] <TB0> INFO: Test took 3965ms.
[17:49:50.339] <TB0> INFO: Expecting 41600 events.
[17:49:53.955] <TB0> INFO: 41600 events read in total (3025ms).
[17:49:53.957] <TB0> INFO: Test took 3883ms.
[17:49:54.307] <TB0> INFO: Expecting 41600 events.
[17:49:57.863] <TB0> INFO: 41600 events read in total (2964ms).
[17:49:57.864] <TB0> INFO: Test took 3883ms.
[17:49:58.218] <TB0> INFO: Expecting 41600 events.
[17:50:01.684] <TB0> INFO: 41600 events read in total (2874ms).
[17:50:01.685] <TB0> INFO: Test took 3793ms.
[17:50:01.982] <TB0> INFO: Expecting 41600 events.
[17:50:05.448] <TB0> INFO: 41600 events read in total (2874ms).
[17:50:05.449] <TB0> INFO: Test took 3737ms.
[17:50:05.740] <TB0> INFO: Expecting 41600 events.
[17:50:09.206] <TB0> INFO: 41600 events read in total (2874ms).
[17:50:09.206] <TB0> INFO: Test took 3730ms.
[17:50:09.495] <TB0> INFO: Expecting 41600 events.
[17:50:13.023] <TB0> INFO: 41600 events read in total (2936ms).
[17:50:13.024] <TB0> INFO: Test took 3793ms.
[17:50:13.329] <TB0> INFO: Expecting 41600 events.
[17:50:16.818] <TB0> INFO: 41600 events read in total (2897ms).
[17:50:16.818] <TB0> INFO: Test took 3766ms.
[17:50:17.110] <TB0> INFO: Expecting 41600 events.
[17:50:20.595] <TB0> INFO: 41600 events read in total (2893ms).
[17:50:20.596] <TB0> INFO: Test took 3750ms.
[17:50:20.886] <TB0> INFO: Expecting 41600 events.
[17:50:24.347] <TB0> INFO: 41600 events read in total (2869ms).
[17:50:24.348] <TB0> INFO: Test took 3728ms.
[17:50:24.637] <TB0> INFO: Expecting 41600 events.
[17:50:28.229] <TB0> INFO: 41600 events read in total (3001ms).
[17:50:28.230] <TB0> INFO: Test took 3858ms.
[17:50:28.579] <TB0> INFO: Expecting 41600 events.
[17:50:32.114] <TB0> INFO: 41600 events read in total (2943ms).
[17:50:32.114] <TB0> INFO: Test took 3859ms.
[17:50:32.406] <TB0> INFO: Expecting 41600 events.
[17:50:35.876] <TB0> INFO: 41600 events read in total (2878ms).
[17:50:35.877] <TB0> INFO: Test took 3736ms.
[17:50:36.167] <TB0> INFO: Expecting 41600 events.
[17:50:39.723] <TB0> INFO: 41600 events read in total (2964ms).
[17:50:39.724] <TB0> INFO: Test took 3821ms.
[17:50:40.014] <TB0> INFO: Expecting 2560 events.
[17:50:40.897] <TB0> INFO: 2560 events read in total (292ms).
[17:50:40.897] <TB0> INFO: Test took 1160ms.
[17:50:41.205] <TB0> INFO: Expecting 2560 events.
[17:50:42.091] <TB0> INFO: 2560 events read in total (295ms).
[17:50:42.091] <TB0> INFO: Test took 1194ms.
[17:50:42.399] <TB0> INFO: Expecting 2560 events.
[17:50:43.285] <TB0> INFO: 2560 events read in total (295ms).
[17:50:43.285] <TB0> INFO: Test took 1193ms.
[17:50:43.593] <TB0> INFO: Expecting 2560 events.
[17:50:44.476] <TB0> INFO: 2560 events read in total (291ms).
[17:50:44.476] <TB0> INFO: Test took 1190ms.
[17:50:44.785] <TB0> INFO: Expecting 2560 events.
[17:50:45.663] <TB0> INFO: 2560 events read in total (287ms).
[17:50:45.663] <TB0> INFO: Test took 1186ms.
[17:50:45.971] <TB0> INFO: Expecting 2560 events.
[17:50:46.863] <TB0> INFO: 2560 events read in total (300ms).
[17:50:46.864] <TB0> INFO: Test took 1200ms.
[17:50:47.172] <TB0> INFO: Expecting 2560 events.
[17:50:48.062] <TB0> INFO: 2560 events read in total (298ms).
[17:50:48.062] <TB0> INFO: Test took 1198ms.
[17:50:48.369] <TB0> INFO: Expecting 2560 events.
[17:50:49.258] <TB0> INFO: 2560 events read in total (297ms).
[17:50:49.258] <TB0> INFO: Test took 1197ms.
[17:50:49.565] <TB0> INFO: Expecting 2560 events.
[17:50:50.449] <TB0> INFO: 2560 events read in total (292ms).
[17:50:50.449] <TB0> INFO: Test took 1190ms.
[17:50:50.756] <TB0> INFO: Expecting 2560 events.
[17:50:51.644] <TB0> INFO: 2560 events read in total (296ms).
[17:50:51.644] <TB0> INFO: Test took 1194ms.
[17:50:51.951] <TB0> INFO: Expecting 2560 events.
[17:50:52.834] <TB0> INFO: 2560 events read in total (291ms).
[17:50:52.834] <TB0> INFO: Test took 1189ms.
[17:50:53.143] <TB0> INFO: Expecting 2560 events.
[17:50:54.029] <TB0> INFO: 2560 events read in total (294ms).
[17:50:54.029] <TB0> INFO: Test took 1194ms.
[17:50:54.338] <TB0> INFO: Expecting 2560 events.
[17:50:55.229] <TB0> INFO: 2560 events read in total (299ms).
[17:50:55.229] <TB0> INFO: Test took 1199ms.
[17:50:55.537] <TB0> INFO: Expecting 2560 events.
[17:50:56.428] <TB0> INFO: 2560 events read in total (299ms).
[17:50:56.428] <TB0> INFO: Test took 1198ms.
[17:50:56.737] <TB0> INFO: Expecting 2560 events.
[17:50:57.623] <TB0> INFO: 2560 events read in total (295ms).
[17:50:57.623] <TB0> INFO: Test took 1194ms.
[17:50:57.930] <TB0> INFO: Expecting 2560 events.
[17:50:58.820] <TB0> INFO: 2560 events read in total (299ms).
[17:50:58.820] <TB0> INFO: Test took 1196ms.
[17:50:58.825] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[17:50:59.129] <TB0> INFO: Expecting 655360 events.
[17:51:14.118] <TB0> INFO: 655360 events read in total (14397ms).
[17:51:14.133] <TB0> INFO: Expecting 655360 events.
[17:51:28.704] <TB0> INFO: 655360 events read in total (14168ms).
[17:51:28.720] <TB0> INFO: Expecting 655360 events.
[17:51:43.525] <TB0> INFO: 655360 events read in total (14402ms).
[17:51:43.549] <TB0> INFO: Expecting 655360 events.
[17:51:58.321] <TB0> INFO: 655360 events read in total (14369ms).
[17:51:58.345] <TB0> INFO: Expecting 655360 events.
[17:52:13.374] <TB0> INFO: 655360 events read in total (14625ms).
[17:52:13.403] <TB0> INFO: Expecting 655360 events.
[17:52:28.128] <TB0> INFO: 655360 events read in total (14322ms).
[17:52:28.161] <TB0> INFO: Expecting 655360 events.
[17:52:42.751] <TB0> INFO: 655360 events read in total (14187ms).
[17:52:42.802] <TB0> INFO: Expecting 655360 events.
[17:52:57.532] <TB0> INFO: 655360 events read in total (14327ms).
[17:52:57.574] <TB0> INFO: Expecting 655360 events.
[17:53:12.169] <TB0> INFO: 655360 events read in total (14192ms).
[17:53:12.270] <TB0> INFO: Expecting 655360 events.
[17:53:26.703] <TB0> INFO: 655360 events read in total (14030ms).
[17:53:26.757] <TB0> INFO: Expecting 655360 events.
[17:53:41.388] <TB0> INFO: 655360 events read in total (14228ms).
[17:53:41.459] <TB0> INFO: Expecting 655360 events.
[17:53:56.100] <TB0> INFO: 655360 events read in total (14238ms).
[17:53:56.174] <TB0> INFO: Expecting 655360 events.
[17:54:10.784] <TB0> INFO: 655360 events read in total (14207ms).
[17:54:10.907] <TB0> INFO: Expecting 655360 events.
[17:54:25.567] <TB0> INFO: 655360 events read in total (14257ms).
[17:54:25.655] <TB0> INFO: Expecting 655360 events.
[17:54:40.271] <TB0> INFO: 655360 events read in total (14213ms).
[17:54:40.384] <TB0> INFO: Expecting 655360 events.
[17:54:54.935] <TB0> INFO: 655360 events read in total (14147ms).
[17:54:55.052] <TB0> INFO: Test took 236227ms.
[17:54:55.146] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[17:54:55.404] <TB0> INFO: Expecting 655360 events.
[17:55:09.999] <TB0> INFO: 655360 events read in total (14004ms).
[17:55:10.011] <TB0> INFO: Expecting 655360 events.
[17:55:24.504] <TB0> INFO: 655360 events read in total (14090ms).
[17:55:24.521] <TB0> INFO: Expecting 655360 events.
[17:55:39.070] <TB0> INFO: 655360 events read in total (14146ms).
[17:55:39.096] <TB0> INFO: Expecting 655360 events.
[17:55:53.611] <TB0> INFO: 655360 events read in total (14112ms).
[17:55:53.635] <TB0> INFO: Expecting 655360 events.
[17:56:07.629] <TB0> INFO: 655360 events read in total (13591ms).
[17:56:07.657] <TB0> INFO: Expecting 655360 events.
[17:56:22.219] <TB0> INFO: 655360 events read in total (14159ms).
[17:56:22.252] <TB0> INFO: Expecting 655360 events.
[17:56:36.894] <TB0> INFO: 655360 events read in total (14239ms).
[17:56:36.945] <TB0> INFO: Expecting 655360 events.
[17:56:51.485] <TB0> INFO: 655360 events read in total (14137ms).
[17:56:51.526] <TB0> INFO: Expecting 655360 events.
[17:57:06.139] <TB0> INFO: 655360 events read in total (14209ms).
[17:57:06.270] <TB0> INFO: Expecting 655360 events.
[17:57:20.876] <TB0> INFO: 655360 events read in total (14203ms).
[17:57:20.932] <TB0> INFO: Expecting 655360 events.
[17:57:35.547] <TB0> INFO: 655360 events read in total (14212ms).
[17:57:35.620] <TB0> INFO: Expecting 655360 events.
[17:57:50.136] <TB0> INFO: 655360 events read in total (14112ms).
[17:57:50.215] <TB0> INFO: Expecting 655360 events.
[17:58:04.742] <TB0> INFO: 655360 events read in total (14124ms).
[17:58:04.890] <TB0> INFO: Expecting 655360 events.
[17:58:19.420] <TB0> INFO: 655360 events read in total (14126ms).
[17:58:19.542] <TB0> INFO: Expecting 655360 events.
[17:58:34.264] <TB0> INFO: 655360 events read in total (14319ms).
[17:58:34.359] <TB0> INFO: Expecting 655360 events.
[17:58:48.918] <TB0> INFO: 655360 events read in total (14156ms).
[17:58:49.015] <TB0> INFO: Test took 233869ms.
[17:58:49.179] <TB0> INFO: safety margin for low PH: adding 0, margin is now 20
[17:58:49.184] <TB0> INFO: safety margin for low PH: adding 1, margin is now 21
[17:58:49.190] <TB0> INFO: safety margin for low PH: adding 0, margin is now 20
[17:58:49.196] <TB0> INFO: safety margin for low PH: adding 0, margin is now 20
[17:58:49.201] <TB0> INFO: safety margin for low PH: adding 0, margin is now 20
[17:58:49.207] <TB0> INFO: safety margin for low PH: adding 0, margin is now 20
[17:58:49.213] <TB0> INFO: safety margin for low PH: adding 1, margin is now 21
[17:58:49.218] <TB0> INFO: safety margin for low PH: adding 2, margin is now 22
[17:58:49.224] <TB0> INFO: safety margin for low PH: adding 3, margin is now 23
[17:58:49.230] <TB0> INFO: safety margin for low PH: adding 4, margin is now 24
[17:58:49.236] <TB0> INFO: safety margin for low PH: adding 5, margin is now 25
[17:58:49.242] <TB0> INFO: safety margin for low PH: adding 6, margin is now 26
[17:58:49.248] <TB0> INFO: safety margin for low PH: adding 7, margin is now 27
[17:58:49.254] <TB0> INFO: safety margin for low PH: adding 0, margin is now 20
[17:58:49.260] <TB0> INFO: safety margin for low PH: adding 0, margin is now 20
[17:58:49.265] <TB0> INFO: safety margin for low PH: adding 0, margin is now 20
[17:58:49.271] <TB0> INFO: safety margin for low PH: adding 0, margin is now 20
[17:58:49.277] <TB0> INFO: safety margin for low PH: adding 0, margin is now 20
[17:58:49.283] <TB0> INFO: safety margin for low PH: adding 1, margin is now 21
[17:58:49.288] <TB0> INFO: safety margin for low PH: adding 2, margin is now 22
[17:58:49.294] <TB0> INFO: safety margin for low PH: adding 3, margin is now 23
[17:58:49.300] <TB0> INFO: safety margin for low PH: adding 4, margin is now 24
[17:58:49.306] <TB0> INFO: safety margin for low PH: adding 5, margin is now 25
[17:58:49.312] <TB0> INFO: safety margin for low PH: adding 6, margin is now 26
[17:58:49.318] <TB0> INFO: safety margin for low PH: adding 7, margin is now 27
[17:58:49.323] <TB0> INFO: safety margin for low PH: adding 8, margin is now 28
[17:58:49.329] <TB0> INFO: safety margin for low PH: adding 9, margin is now 29
[17:58:49.335] <TB0> INFO: safety margin for low PH: adding 10, margin is now 30
[17:58:49.340] <TB0> INFO: safety margin for low PH: adding 11, margin is now 31
[17:58:49.346] <TB0> INFO: safety margin for low PH: adding 12, margin is now 32
[17:58:49.352] <TB0> INFO: safety margin for low PH: adding 13, margin is now 33
[17:58:49.358] <TB0> INFO: safety margin for low PH: adding 0, margin is now 20
[17:58:49.364] <TB0> INFO: safety margin for low PH: adding 0, margin is now 20
[17:58:49.369] <TB0> INFO: safety margin for low PH: adding 0, margin is now 20
[17:58:49.375] <TB0> INFO: safety margin for low PH: adding 1, margin is now 21
[17:58:49.381] <TB0> INFO: safety margin for low PH: adding 2, margin is now 22
[17:58:49.386] <TB0> INFO: safety margin for low PH: adding 3, margin is now 23
[17:58:49.392] <TB0> INFO: safety margin for low PH: adding 4, margin is now 24
[17:58:49.398] <TB0> INFO: safety margin for low PH: adding 5, margin is now 25
[17:58:49.404] <TB0> INFO: safety margin for low PH: adding 6, margin is now 26
[17:58:49.410] <TB0> INFO: safety margin for low PH: adding 7, margin is now 27
[17:58:49.416] <TB0> INFO: safety margin for low PH: adding 8, margin is now 28
[17:58:49.421] <TB0> INFO: safety margin for low PH: adding 9, margin is now 29
[17:58:49.427] <TB0> INFO: safety margin for low PH: adding 10, margin is now 30
[17:58:49.433] <TB0> INFO: safety margin for low PH: adding 11, margin is now 31
[17:58:49.439] <TB0> INFO: safety margin for low PH: adding 12, margin is now 32
[17:58:49.445] <TB0> INFO: safety margin for low PH: adding 13, margin is now 33
[17:58:49.451] <TB0> INFO: safety margin for low PH: adding 14, margin is now 34
[17:58:49.457] <TB0> INFO: safety margin for low PH: adding 15, margin is now 35
[17:58:49.463] <TB0> INFO: safety margin for low PH: adding 0, margin is now 20
[17:58:49.469] <TB0> INFO: safety margin for low PH: adding 0, margin is now 20
[17:58:49.474] <TB0> INFO: safety margin for low PH: adding 0, margin is now 20
[17:58:49.508] <TB0> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1015_FullQualification_2016-10-19_14h13m_1476879208//003_Fulltest_p17//dacParameters35_C0.dat
[17:58:49.509] <TB0> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1015_FullQualification_2016-10-19_14h13m_1476879208//003_Fulltest_p17//dacParameters35_C1.dat
[17:58:49.509] <TB0> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1015_FullQualification_2016-10-19_14h13m_1476879208//003_Fulltest_p17//dacParameters35_C2.dat
[17:58:49.509] <TB0> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1015_FullQualification_2016-10-19_14h13m_1476879208//003_Fulltest_p17//dacParameters35_C3.dat
[17:58:49.509] <TB0> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1015_FullQualification_2016-10-19_14h13m_1476879208//003_Fulltest_p17//dacParameters35_C4.dat
[17:58:49.509] <TB0> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1015_FullQualification_2016-10-19_14h13m_1476879208//003_Fulltest_p17//dacParameters35_C5.dat
[17:58:49.509] <TB0> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1015_FullQualification_2016-10-19_14h13m_1476879208//003_Fulltest_p17//dacParameters35_C6.dat
[17:58:49.509] <TB0> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1015_FullQualification_2016-10-19_14h13m_1476879208//003_Fulltest_p17//dacParameters35_C7.dat
[17:58:49.509] <TB0> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1015_FullQualification_2016-10-19_14h13m_1476879208//003_Fulltest_p17//dacParameters35_C8.dat
[17:58:49.509] <TB0> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1015_FullQualification_2016-10-19_14h13m_1476879208//003_Fulltest_p17//dacParameters35_C9.dat
[17:58:49.510] <TB0> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1015_FullQualification_2016-10-19_14h13m_1476879208//003_Fulltest_p17//dacParameters35_C10.dat
[17:58:49.510] <TB0> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1015_FullQualification_2016-10-19_14h13m_1476879208//003_Fulltest_p17//dacParameters35_C11.dat
[17:58:49.510] <TB0> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1015_FullQualification_2016-10-19_14h13m_1476879208//003_Fulltest_p17//dacParameters35_C12.dat
[17:58:49.510] <TB0> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1015_FullQualification_2016-10-19_14h13m_1476879208//003_Fulltest_p17//dacParameters35_C13.dat
[17:58:49.510] <TB0> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1015_FullQualification_2016-10-19_14h13m_1476879208//003_Fulltest_p17//dacParameters35_C14.dat
[17:58:49.510] <TB0> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1015_FullQualification_2016-10-19_14h13m_1476879208//003_Fulltest_p17//dacParameters35_C15.dat
[17:58:49.750] <TB0> INFO: Expecting 41600 events.
[17:58:52.842] <TB0> INFO: 41600 events read in total (2500ms).
[17:58:52.843] <TB0> INFO: Test took 3330ms.
[17:58:53.331] <TB0> INFO: Expecting 41600 events.
[17:58:56.378] <TB0> INFO: 41600 events read in total (2455ms).
[17:58:56.379] <TB0> INFO: Test took 3326ms.
[17:58:56.836] <TB0> INFO: Expecting 41600 events.
[17:58:59.970] <TB0> INFO: 41600 events read in total (2543ms).
[17:58:59.971] <TB0> INFO: Test took 3379ms.
[17:59:00.186] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[17:59:00.275] <TB0> INFO: Expecting 2560 events.
[17:59:01.159] <TB0> INFO: 2560 events read in total (292ms).
[17:59:01.159] <TB0> INFO: Test took 973ms.
[17:59:01.162] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[17:59:01.466] <TB0> INFO: Expecting 2560 events.
[17:59:02.357] <TB0> INFO: 2560 events read in total (299ms).
[17:59:02.358] <TB0> INFO: Test took 1196ms.
[17:59:02.360] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[17:59:02.666] <TB0> INFO: Expecting 2560 events.
[17:59:03.550] <TB0> INFO: 2560 events read in total (292ms).
[17:59:03.551] <TB0> INFO: Test took 1191ms.
[17:59:03.553] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[17:59:03.859] <TB0> INFO: Expecting 2560 events.
[17:59:04.744] <TB0> INFO: 2560 events read in total (293ms).
[17:59:04.744] <TB0> INFO: Test took 1191ms.
[17:59:04.746] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[17:59:05.053] <TB0> INFO: Expecting 2560 events.
[17:59:05.936] <TB0> INFO: 2560 events read in total (291ms).
[17:59:05.936] <TB0> INFO: Test took 1190ms.
[17:59:05.937] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[17:59:06.245] <TB0> INFO: Expecting 2560 events.
[17:59:07.129] <TB0> INFO: 2560 events read in total (293ms).
[17:59:07.129] <TB0> INFO: Test took 1192ms.
[17:59:07.132] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[17:59:07.437] <TB0> INFO: Expecting 2560 events.
[17:59:08.330] <TB0> INFO: 2560 events read in total (302ms).
[17:59:08.330] <TB0> INFO: Test took 1198ms.
[17:59:08.333] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[17:59:08.638] <TB0> INFO: Expecting 2560 events.
[17:59:09.525] <TB0> INFO: 2560 events read in total (295ms).
[17:59:09.525] <TB0> INFO: Test took 1192ms.
[17:59:09.529] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[17:59:09.834] <TB0> INFO: Expecting 2560 events.
[17:59:10.716] <TB0> INFO: 2560 events read in total (290ms).
[17:59:10.716] <TB0> INFO: Test took 1187ms.
[17:59:10.718] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[17:59:11.024] <TB0> INFO: Expecting 2560 events.
[17:59:11.910] <TB0> INFO: 2560 events read in total (294ms).
[17:59:11.910] <TB0> INFO: Test took 1192ms.
[17:59:11.913] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[17:59:12.218] <TB0> INFO: Expecting 2560 events.
[17:59:13.110] <TB0> INFO: 2560 events read in total (300ms).
[17:59:13.111] <TB0> INFO: Test took 1198ms.
[17:59:13.114] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[17:59:13.420] <TB0> INFO: Expecting 2560 events.
[17:59:14.305] <TB0> INFO: 2560 events read in total (293ms).
[17:59:14.305] <TB0> INFO: Test took 1192ms.
[17:59:14.308] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[17:59:14.614] <TB0> INFO: Expecting 2560 events.
[17:59:15.498] <TB0> INFO: 2560 events read in total (292ms).
[17:59:15.499] <TB0> INFO: Test took 1191ms.
[17:59:15.502] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[17:59:15.807] <TB0> INFO: Expecting 2560 events.
[17:59:16.691] <TB0> INFO: 2560 events read in total (292ms).
[17:59:16.692] <TB0> INFO: Test took 1190ms.
[17:59:16.695] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[17:59:16.000] <TB0> INFO: Expecting 2560 events.
[17:59:17.883] <TB0> INFO: 2560 events read in total (291ms).
[17:59:17.883] <TB0> INFO: Test took 1188ms.
[17:59:17.887] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[17:59:18.192] <TB0> INFO: Expecting 2560 events.
[17:59:19.073] <TB0> INFO: 2560 events read in total (290ms).
[17:59:19.074] <TB0> INFO: Test took 1187ms.
[17:59:19.077] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[17:59:19.382] <TB0> INFO: Expecting 2560 events.
[17:59:20.263] <TB0> INFO: 2560 events read in total (289ms).
[17:59:20.263] <TB0> INFO: Test took 1186ms.
[17:59:20.266] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[17:59:20.571] <TB0> INFO: Expecting 2560 events.
[17:59:21.450] <TB0> INFO: 2560 events read in total (288ms).
[17:59:21.451] <TB0> INFO: Test took 1186ms.
[17:59:21.454] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[17:59:21.759] <TB0> INFO: Expecting 2560 events.
[17:59:22.639] <TB0> INFO: 2560 events read in total (288ms).
[17:59:22.639] <TB0> INFO: Test took 1186ms.
[17:59:22.642] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[17:59:22.948] <TB0> INFO: Expecting 2560 events.
[17:59:23.831] <TB0> INFO: 2560 events read in total (291ms).
[17:59:23.831] <TB0> INFO: Test took 1189ms.
[17:59:23.834] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[17:59:24.140] <TB0> INFO: Expecting 2560 events.
[17:59:25.024] <TB0> INFO: 2560 events read in total (293ms).
[17:59:25.024] <TB0> INFO: Test took 1190ms.
[17:59:25.028] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[17:59:25.332] <TB0> INFO: Expecting 2560 events.
[17:59:26.217] <TB0> INFO: 2560 events read in total (293ms).
[17:59:26.218] <TB0> INFO: Test took 1190ms.
[17:59:26.221] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[17:59:26.525] <TB0> INFO: Expecting 2560 events.
[17:59:27.408] <TB0> INFO: 2560 events read in total (291ms).
[17:59:27.409] <TB0> INFO: Test took 1189ms.
[17:59:27.411] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[17:59:27.718] <TB0> INFO: Expecting 2560 events.
[17:59:28.610] <TB0> INFO: 2560 events read in total (300ms).
[17:59:28.610] <TB0> INFO: Test took 1199ms.
[17:59:28.614] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[17:59:28.919] <TB0> INFO: Expecting 2560 events.
[17:59:29.807] <TB0> INFO: 2560 events read in total (296ms).
[17:59:29.807] <TB0> INFO: Test took 1194ms.
[17:59:29.810] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[17:59:30.114] <TB0> INFO: Expecting 2560 events.
[17:59:31.009] <TB0> INFO: 2560 events read in total (303ms).
[17:59:31.009] <TB0> INFO: Test took 1199ms.
[17:59:31.013] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[17:59:31.317] <TB0> INFO: Expecting 2560 events.
[17:59:32.209] <TB0> INFO: 2560 events read in total (300ms).
[17:59:32.209] <TB0> INFO: Test took 1197ms.
[17:59:32.211] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[17:59:32.518] <TB0> INFO: Expecting 2560 events.
[17:59:33.405] <TB0> INFO: 2560 events read in total (295ms).
[17:59:33.406] <TB0> INFO: Test took 1195ms.
[17:59:33.409] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[17:59:33.715] <TB0> INFO: Expecting 2560 events.
[17:59:34.608] <TB0> INFO: 2560 events read in total (302ms).
[17:59:34.608] <TB0> INFO: Test took 1200ms.
[17:59:34.610] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[17:59:34.916] <TB0> INFO: Expecting 2560 events.
[17:59:35.801] <TB0> INFO: 2560 events read in total (293ms).
[17:59:35.801] <TB0> INFO: Test took 1191ms.
[17:59:35.804] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[17:59:36.109] <TB0> INFO: Expecting 2560 events.
[17:59:36.992] <TB0> INFO: 2560 events read in total (291ms).
[17:59:36.992] <TB0> INFO: Test took 1188ms.
[17:59:36.994] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[17:59:37.301] <TB0> INFO: Expecting 2560 events.
[17:59:38.183] <TB0> INFO: 2560 events read in total (291ms).
[17:59:38.183] <TB0> INFO: Test took 1189ms.
[17:59:38.655] <TB0> INFO: PixTestPhOptimization::doTest() done, duration: 639 seconds
[17:59:38.655] <TB0> INFO: PH scale (per ROC): 34 37 51 54 48 37 32 48 35 45 63 46 39 33 35 44
[17:59:38.655] <TB0> INFO: PH offset (per ROC): 111 109 128 124 113 101 98 129 101 112 123 119 112 115 111 130
[17:59:38.663] <TB0> INFO: Decoding statistics:
[17:59:38.663] <TB0> INFO: General information:
[17:59:38.664] <TB0> INFO: 16bit words read: 127882
[17:59:38.664] <TB0> INFO: valid events total: 20480
[17:59:38.664] <TB0> INFO: empty events: 17979
[17:59:38.664] <TB0> INFO: valid events with pixels: 2501
[17:59:38.664] <TB0> INFO: valid pixel hits: 2501
[17:59:38.664] <TB0> INFO: Event errors: 0
[17:59:38.664] <TB0> INFO: start marker: 0
[17:59:38.664] <TB0> INFO: stop marker: 0
[17:59:38.664] <TB0> INFO: overflow: 0
[17:59:38.664] <TB0> INFO: invalid 5bit words: 0
[17:59:38.664] <TB0> INFO: invalid XOR eye diagram: 0
[17:59:38.664] <TB0> INFO: frame (failed synchr.): 0
[17:59:38.664] <TB0> INFO: idle data (no TBM trl): 0
[17:59:38.664] <TB0> INFO: no data (only TBM hdr): 0
[17:59:38.664] <TB0> INFO: TBM errors: 0
[17:59:38.664] <TB0> INFO: flawed TBM headers: 0
[17:59:38.664] <TB0> INFO: flawed TBM trailers: 0
[17:59:38.664] <TB0> INFO: event ID mismatches: 0
[17:59:38.664] <TB0> INFO: ROC errors: 0
[17:59:38.664] <TB0> INFO: missing ROC header(s): 0
[17:59:38.664] <TB0> INFO: misplaced readback start: 0
[17:59:38.664] <TB0> INFO: Pixel decoding errors: 0
[17:59:38.664] <TB0> INFO: pixel data incomplete: 0
[17:59:38.664] <TB0> INFO: pixel address: 0
[17:59:38.664] <TB0> INFO: pulse height fill bit: 0
[17:59:38.664] <TB0> INFO: buffer corruption: 0
[17:59:38.824] <TB0> INFO: ######################################################################
[17:59:38.824] <TB0> INFO: PixTestGainPedestal::fullTest() ntrig = 10
[17:59:38.824] <TB0> INFO: ######################################################################
[17:59:38.839] <TB0> INFO: scanning low vcal = 10
[17:59:39.086] <TB0> INFO: Expecting 41600 events.
[17:59:42.691] <TB0> INFO: 41600 events read in total (3013ms).
[17:59:42.691] <TB0> INFO: Test took 3852ms.
[17:59:42.693] <TB0> INFO: scanning low vcal = 20
[17:59:42.984] <TB0> INFO: Expecting 41600 events.
[17:59:46.580] <TB0> INFO: 41600 events read in total (3005ms).
[17:59:46.581] <TB0> INFO: Test took 3888ms.
[17:59:46.583] <TB0> INFO: scanning low vcal = 30
[17:59:46.874] <TB0> INFO: Expecting 41600 events.
[17:59:50.491] <TB0> INFO: 41600 events read in total (3026ms).
[17:59:50.492] <TB0> INFO: Test took 3909ms.
[17:59:50.495] <TB0> INFO: scanning low vcal = 40
[17:59:50.773] <TB0> INFO: Expecting 41600 events.
[17:59:54.734] <TB0> INFO: 41600 events read in total (3369ms).
[17:59:54.735] <TB0> INFO: Test took 4240ms.
[17:59:54.739] <TB0> INFO: scanning low vcal = 50
[17:59:55.016] <TB0> INFO: Expecting 41600 events.
[17:59:59.041] <TB0> INFO: 41600 events read in total (3434ms).
[17:59:59.042] <TB0> INFO: Test took 4303ms.
[17:59:59.045] <TB0> INFO: scanning low vcal = 60
[17:59:59.322] <TB0> INFO: Expecting 41600 events.
[18:00:03.315] <TB0> INFO: 41600 events read in total (3401ms).
[18:00:03.315] <TB0> INFO: Test took 4270ms.
[18:00:03.318] <TB0> INFO: scanning low vcal = 70
[18:00:03.595] <TB0> INFO: Expecting 41600 events.
[18:00:07.577] <TB0> INFO: 41600 events read in total (3390ms).
[18:00:07.578] <TB0> INFO: Test took 4260ms.
[18:00:07.582] <TB0> INFO: scanning low vcal = 80
[18:00:07.859] <TB0> INFO: Expecting 41600 events.
[18:00:11.854] <TB0> INFO: 41600 events read in total (3404ms).
[18:00:11.855] <TB0> INFO: Test took 4273ms.
[18:00:11.858] <TB0> INFO: scanning low vcal = 90
[18:00:12.135] <TB0> INFO: Expecting 41600 events.
[18:00:16.135] <TB0> INFO: 41600 events read in total (3409ms).
[18:00:16.136] <TB0> INFO: Test took 4278ms.
[18:00:16.140] <TB0> INFO: scanning low vcal = 100
[18:00:16.416] <TB0> INFO: Expecting 41600 events.
[18:00:20.390] <TB0> INFO: 41600 events read in total (3382ms).
[18:00:20.390] <TB0> INFO: Test took 4250ms.
[18:00:20.395] <TB0> INFO: scanning low vcal = 110
[18:00:20.677] <TB0> INFO: Expecting 41600 events.
[18:00:24.733] <TB0> INFO: 41600 events read in total (3463ms).
[18:00:24.734] <TB0> INFO: Test took 4339ms.
[18:00:24.737] <TB0> INFO: scanning low vcal = 120
[18:00:25.014] <TB0> INFO: Expecting 41600 events.
[18:00:29.009] <TB0> INFO: 41600 events read in total (3403ms).
[18:00:29.010] <TB0> INFO: Test took 4273ms.
[18:00:29.013] <TB0> INFO: scanning low vcal = 130
[18:00:29.290] <TB0> INFO: Expecting 41600 events.
[18:00:33.288] <TB0> INFO: 41600 events read in total (3406ms).
[18:00:33.289] <TB0> INFO: Test took 4276ms.
[18:00:33.292] <TB0> INFO: scanning low vcal = 140
[18:00:33.569] <TB0> INFO: Expecting 41600 events.
[18:00:37.599] <TB0> INFO: 41600 events read in total (3438ms).
[18:00:37.600] <TB0> INFO: Test took 4308ms.
[18:00:37.603] <TB0> INFO: scanning low vcal = 150
[18:00:37.881] <TB0> INFO: Expecting 41600 events.
[18:00:41.945] <TB0> INFO: 41600 events read in total (3472ms).
[18:00:41.946] <TB0> INFO: Test took 4343ms.
[18:00:41.949] <TB0> INFO: scanning low vcal = 160
[18:00:42.226] <TB0> INFO: Expecting 41600 events.
[18:00:46.219] <TB0> INFO: 41600 events read in total (3401ms).
[18:00:46.220] <TB0> INFO: Test took 4271ms.
[18:00:46.223] <TB0> INFO: scanning low vcal = 170
[18:00:46.499] <TB0> INFO: Expecting 41600 events.
[18:00:50.482] <TB0> INFO: 41600 events read in total (3391ms).
[18:00:50.483] <TB0> INFO: Test took 4260ms.
[18:00:50.488] <TB0> INFO: scanning low vcal = 180
[18:00:50.763] <TB0> INFO: Expecting 41600 events.
[18:00:54.785] <TB0> INFO: 41600 events read in total (3430ms).
[18:00:54.786] <TB0> INFO: Test took 4298ms.
[18:00:54.789] <TB0> INFO: scanning low vcal = 190
[18:00:55.066] <TB0> INFO: Expecting 41600 events.
[18:00:59.116] <TB0> INFO: 41600 events read in total (3459ms).
[18:00:59.117] <TB0> INFO: Test took 4328ms.
[18:00:59.120] <TB0> INFO: scanning low vcal = 200
[18:00:59.398] <TB0> INFO: Expecting 41600 events.
[18:01:03.375] <TB0> INFO: 41600 events read in total (3385ms).
[18:01:03.376] <TB0> INFO: Test took 4256ms.
[18:01:03.378] <TB0> INFO: scanning low vcal = 210
[18:01:03.656] <TB0> INFO: Expecting 41600 events.
[18:01:07.675] <TB0> INFO: 41600 events read in total (3427ms).
[18:01:07.675] <TB0> INFO: Test took 4296ms.
[18:01:07.679] <TB0> INFO: scanning low vcal = 220
[18:01:07.956] <TB0> INFO: Expecting 41600 events.
[18:01:11.956] <TB0> INFO: 41600 events read in total (3408ms).
[18:01:11.957] <TB0> INFO: Test took 4278ms.
[18:01:11.960] <TB0> INFO: scanning low vcal = 230
[18:01:12.238] <TB0> INFO: Expecting 41600 events.
[18:01:16.268] <TB0> INFO: 41600 events read in total (3439ms).
[18:01:16.268] <TB0> INFO: Test took 4309ms.
[18:01:16.271] <TB0> INFO: scanning low vcal = 240
[18:01:16.549] <TB0> INFO: Expecting 41600 events.
[18:01:20.625] <TB0> INFO: 41600 events read in total (3484ms).
[18:01:20.626] <TB0> INFO: Test took 4354ms.
[18:01:20.629] <TB0> INFO: scanning low vcal = 250
[18:01:20.912] <TB0> INFO: Expecting 41600 events.
[18:01:24.945] <TB0> INFO: 41600 events read in total (3441ms).
[18:01:24.946] <TB0> INFO: Test took 4317ms.
[18:01:24.950] <TB0> INFO: scanning high vcal = 30 (= 210 in low range)
[18:01:25.226] <TB0> INFO: Expecting 41600 events.
[18:01:29.291] <TB0> INFO: 41600 events read in total (3473ms).
[18:01:29.292] <TB0> INFO: Test took 4342ms.
[18:01:29.295] <TB0> INFO: scanning high vcal = 50 (= 350 in low range)
[18:01:29.572] <TB0> INFO: Expecting 41600 events.
[18:01:33.626] <TB0> INFO: 41600 events read in total (3462ms).
[18:01:33.627] <TB0> INFO: Test took 4332ms.
[18:01:33.630] <TB0> INFO: scanning high vcal = 70 (= 490 in low range)
[18:01:33.907] <TB0> INFO: Expecting 41600 events.
[18:01:37.915] <TB0> INFO: 41600 events read in total (3416ms).
[18:01:37.916] <TB0> INFO: Test took 4285ms.
[18:01:37.919] <TB0> INFO: scanning high vcal = 90 (= 630 in low range)
[18:01:38.196] <TB0> INFO: Expecting 41600 events.
[18:01:42.206] <TB0> INFO: 41600 events read in total (3418ms).
[18:01:42.207] <TB0> INFO: Test took 4288ms.
[18:01:42.210] <TB0> INFO: scanning high vcal = 200 (= 1400 in low range)
[18:01:42.488] <TB0> INFO: Expecting 41600 events.
[18:01:46.510] <TB0> INFO: 41600 events read in total (3431ms).
[18:01:46.510] <TB0> INFO: Test took 4300ms.
[18:01:46.944] <TB0> INFO: PixTestGainPedestal::measure() done
[18:02:21.415] <TB0> INFO: PixTestGainPedestal::fit() done
[18:02:21.415] <TB0> INFO: non-linearity mean: 0.953 0.923 0.980 0.980 0.981 0.915 0.956 0.978 0.949 0.946 0.988 0.980 0.932 0.903 0.918 0.977
[18:02:21.415] <TB0> INFO: non-linearity RMS: 0.155 0.095 0.005 0.004 0.004 0.132 0.249 0.004 0.165 0.043 0.001 0.003 0.082 0.155 0.104 0.006
[18:02:21.415] <TB0> INFO: write gain/ped parameters into /home/l_tester/david/FullQualification/data/M1015_FullQualification_2016-10-19_14h13m_1476879208//003_Fulltest_p17//phCalibrationFitErr35_C0.dat
[18:02:21.431] <TB0> INFO: write gain/ped parameters into /home/l_tester/david/FullQualification/data/M1015_FullQualification_2016-10-19_14h13m_1476879208//003_Fulltest_p17//phCalibrationFitErr35_C1.dat
[18:02:21.444] <TB0> INFO: write gain/ped parameters into /home/l_tester/david/FullQualification/data/M1015_FullQualification_2016-10-19_14h13m_1476879208//003_Fulltest_p17//phCalibrationFitErr35_C2.dat
[18:02:21.456] <TB0> INFO: write gain/ped parameters into /home/l_tester/david/FullQualification/data/M1015_FullQualification_2016-10-19_14h13m_1476879208//003_Fulltest_p17//phCalibrationFitErr35_C3.dat
[18:02:21.469] <TB0> INFO: write gain/ped parameters into /home/l_tester/david/FullQualification/data/M1015_FullQualification_2016-10-19_14h13m_1476879208//003_Fulltest_p17//phCalibrationFitErr35_C4.dat
[18:02:21.482] <TB0> INFO: write gain/ped parameters into /home/l_tester/david/FullQualification/data/M1015_FullQualification_2016-10-19_14h13m_1476879208//003_Fulltest_p17//phCalibrationFitErr35_C5.dat
[18:02:21.495] <TB0> INFO: write gain/ped parameters into /home/l_tester/david/FullQualification/data/M1015_FullQualification_2016-10-19_14h13m_1476879208//003_Fulltest_p17//phCalibrationFitErr35_C6.dat
[18:02:21.508] <TB0> INFO: write gain/ped parameters into /home/l_tester/david/FullQualification/data/M1015_FullQualification_2016-10-19_14h13m_1476879208//003_Fulltest_p17//phCalibrationFitErr35_C7.dat
[18:02:21.520] <TB0> INFO: write gain/ped parameters into /home/l_tester/david/FullQualification/data/M1015_FullQualification_2016-10-19_14h13m_1476879208//003_Fulltest_p17//phCalibrationFitErr35_C8.dat
[18:02:21.533] <TB0> INFO: write gain/ped parameters into /home/l_tester/david/FullQualification/data/M1015_FullQualification_2016-10-19_14h13m_1476879208//003_Fulltest_p17//phCalibrationFitErr35_C9.dat
[18:02:21.546] <TB0> INFO: write gain/ped parameters into /home/l_tester/david/FullQualification/data/M1015_FullQualification_2016-10-19_14h13m_1476879208//003_Fulltest_p17//phCalibrationFitErr35_C10.dat
[18:02:21.559] <TB0> INFO: write gain/ped parameters into /home/l_tester/david/FullQualification/data/M1015_FullQualification_2016-10-19_14h13m_1476879208//003_Fulltest_p17//phCalibrationFitErr35_C11.dat
[18:02:21.572] <TB0> INFO: write gain/ped parameters into /home/l_tester/david/FullQualification/data/M1015_FullQualification_2016-10-19_14h13m_1476879208//003_Fulltest_p17//phCalibrationFitErr35_C12.dat
[18:02:21.584] <TB0> INFO: write gain/ped parameters into /home/l_tester/david/FullQualification/data/M1015_FullQualification_2016-10-19_14h13m_1476879208//003_Fulltest_p17//phCalibrationFitErr35_C13.dat
[18:02:21.597] <TB0> INFO: write gain/ped parameters into /home/l_tester/david/FullQualification/data/M1015_FullQualification_2016-10-19_14h13m_1476879208//003_Fulltest_p17//phCalibrationFitErr35_C14.dat
[18:02:21.610] <TB0> INFO: write gain/ped parameters into /home/l_tester/david/FullQualification/data/M1015_FullQualification_2016-10-19_14h13m_1476879208//003_Fulltest_p17//phCalibrationFitErr35_C15.dat
[18:02:21.623] <TB0> INFO: PixTestGainPedestal::fullTest() done, duration: 162 seconds
[18:02:21.623] <TB0> INFO: Decoding statistics:
[18:02:21.623] <TB0> INFO: General information:
[18:02:21.623] <TB0> INFO: 16bit words read: 3289252
[18:02:21.623] <TB0> INFO: valid events total: 332800
[18:02:21.623] <TB0> INFO: empty events: 740
[18:02:21.623] <TB0> INFO: valid events with pixels: 332060
[18:02:21.623] <TB0> INFO: valid pixel hits: 646226
[18:02:21.623] <TB0> INFO: Event errors: 0
[18:02:21.623] <TB0> INFO: start marker: 0
[18:02:21.623] <TB0> INFO: stop marker: 0
[18:02:21.623] <TB0> INFO: overflow: 0
[18:02:21.623] <TB0> INFO: invalid 5bit words: 0
[18:02:21.623] <TB0> INFO: invalid XOR eye diagram: 0
[18:02:21.623] <TB0> INFO: frame (failed synchr.): 0
[18:02:21.623] <TB0> INFO: idle data (no TBM trl): 0
[18:02:21.623] <TB0> INFO: no data (only TBM hdr): 0
[18:02:21.623] <TB0> INFO: TBM errors: 0
[18:02:21.623] <TB0> INFO: flawed TBM headers: 0
[18:02:21.623] <TB0> INFO: flawed TBM trailers: 0
[18:02:21.623] <TB0> INFO: event ID mismatches: 0
[18:02:21.623] <TB0> INFO: ROC errors: 0
[18:02:21.623] <TB0> INFO: missing ROC header(s): 0
[18:02:21.623] <TB0> INFO: misplaced readback start: 0
[18:02:21.623] <TB0> INFO: Pixel decoding errors: 0
[18:02:21.623] <TB0> INFO: pixel data incomplete: 0
[18:02:21.623] <TB0> INFO: pixel address: 0
[18:02:21.623] <TB0> INFO: pulse height fill bit: 0
[18:02:21.623] <TB0> INFO: buffer corruption: 0
[18:02:21.638] <TB0> INFO: Decoding statistics:
[18:02:21.638] <TB0> INFO: General information:
[18:02:21.638] <TB0> INFO: 16bit words read: 3418670
[18:02:21.638] <TB0> INFO: valid events total: 353536
[18:02:21.638] <TB0> INFO: empty events: 18975
[18:02:21.638] <TB0> INFO: valid events with pixels: 334561
[18:02:21.638] <TB0> INFO: valid pixel hits: 648727
[18:02:21.638] <TB0> INFO: Event errors: 0
[18:02:21.638] <TB0> INFO: start marker: 0
[18:02:21.638] <TB0> INFO: stop marker: 0
[18:02:21.638] <TB0> INFO: overflow: 0
[18:02:21.638] <TB0> INFO: invalid 5bit words: 0
[18:02:21.638] <TB0> INFO: invalid XOR eye diagram: 0
[18:02:21.638] <TB0> INFO: frame (failed synchr.): 0
[18:02:21.638] <TB0> INFO: idle data (no TBM trl): 0
[18:02:21.638] <TB0> INFO: no data (only TBM hdr): 0
[18:02:21.638] <TB0> INFO: TBM errors: 0
[18:02:21.638] <TB0> INFO: flawed TBM headers: 0
[18:02:21.638] <TB0> INFO: flawed TBM trailers: 0
[18:02:21.638] <TB0> INFO: event ID mismatches: 0
[18:02:21.638] <TB0> INFO: ROC errors: 0
[18:02:21.638] <TB0> INFO: missing ROC header(s): 0
[18:02:21.638] <TB0> INFO: misplaced readback start: 0
[18:02:21.638] <TB0> INFO: Pixel decoding errors: 0
[18:02:21.638] <TB0> INFO: pixel data incomplete: 0
[18:02:21.638] <TB0> INFO: pixel address: 0
[18:02:21.638] <TB0> INFO: pulse height fill bit: 0
[18:02:21.638] <TB0> INFO: buffer corruption: 0
[18:02:21.638] <TB0> INFO: enter test to run
[18:02:21.638] <TB0> INFO: test: exit no parameter change
[18:02:21.758] <TB0> QUIET: Connection to board 71 closed.
[18:02:21.759] <TB0> INFO: pXar: this is the end, my friend