[13:08:18.594] <TB3> INFO: *** Welcome to pxar ***
[13:08:18.594] <TB3> INFO: *** Today: 2016/10/22
[13:08:18.601] <TB3> INFO: *** Version: c8ba-dirty
[13:08:18.601] <TB3> INFO: readRocDacs: /home/l_tester/david/FullQualification/data/M1047_FullQualification_2016-10-22_10h41m_1477125660//003_Fulltest_p17//dacParameters_C0.dat .. /home/l_tester/david/FullQualification/data/M1047_FullQualification_2016-10-22_10h41m_1477125660//003_Fulltest_p17//dacParameters_C15.dat
[13:08:18.602] <TB3> INFO: readTbmDacs: /home/l_tester/david/FullQualification/data/M1047_FullQualification_2016-10-22_10h41m_1477125660//003_Fulltest_p17//tbmParameters_C0a.dat .. /home/l_tester/david/FullQualification/data/M1047_FullQualification_2016-10-22_10h41m_1477125660//003_Fulltest_p17//tbmParameters_C1b.dat
[13:08:18.602] <TB3> INFO: readMaskFile: /home/l_tester/david/FullQualification/data/M1047_FullQualification_2016-10-22_10h41m_1477125660//003_Fulltest_p17//defaultMaskFile.dat
[13:08:18.602] <TB3> INFO: readTrimFile: /home/l_tester/david/FullQualification/data/M1047_FullQualification_2016-10-22_10h41m_1477125660//003_Fulltest_p17//trimParameters_C0.dat .. /home/l_tester/david/FullQualification/data/M1047_FullQualification_2016-10-22_10h41m_1477125660//003_Fulltest_p17//trimParameters_C15.dat
[13:08:18.662] <TB3> INFO: clk: 4
[13:08:18.662] <TB3> INFO: ctr: 4
[13:08:18.662] <TB3> INFO: sda: 19
[13:08:18.662] <TB3> INFO: tin: 9
[13:08:18.662] <TB3> INFO: level: 15
[13:08:18.662] <TB3> INFO: triggerdelay: 0
[13:08:18.662] <TB3> QUIET: Instanciating API for pxar v2.7.6+55~gafdbfd9
[13:08:18.662] <TB3> INFO: Log level: INFO
[13:08:18.672] <TB3> INFO: Found DTB DTB_WWVASW
[13:08:18.680] <TB3> QUIET: Connection to board DTB_WWVASW opened.
[13:08:18.682] <TB3> INFO: DTB startup information
--- DTB info------------------------------------------
Board id: 126
HW version: DTB1.2
FW version: 4.6
SW version: 4.7
Options:
USB id: DTB_WWVASW
MAC address: 40D85511807E
Hostname: pixelDTB126
Comment:
------------------------------------------------------
[13:08:18.684] <TB3> INFO: RPC call hashes of host and DTB match: 486171790
[13:08:20.184] <TB3> INFO: DUT info:
[13:08:20.184] <TB3> INFO: The DUT currently contains the following objects:
[13:08:20.184] <TB3> INFO: 4 TBM Cores tbm10c (4 ON)
[13:08:20.184] <TB3> INFO: TBM Core alpha (0): 7 registers set
[13:08:20.184] <TB3> INFO: TBM Core beta (1): 7 registers set
[13:08:20.184] <TB3> INFO: TBM Core alpha (2): 7 registers set
[13:08:20.184] <TB3> INFO: TBM Core beta (3): 7 registers set
[13:08:20.184] <TB3> INFO: 16 ROCs proc600 (16 ON) with 4160 pixelConfigs
[13:08:20.184] <TB3> INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[13:08:20.184] <TB3> INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[13:08:20.184] <TB3> INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[13:08:20.184] <TB3> INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[13:08:20.184] <TB3> INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[13:08:20.184] <TB3> INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[13:08:20.184] <TB3> INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[13:08:20.184] <TB3> INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[13:08:20.184] <TB3> INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[13:08:20.184] <TB3> INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[13:08:20.184] <TB3> INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[13:08:20.184] <TB3> INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[13:08:20.184] <TB3> INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[13:08:20.184] <TB3> INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[13:08:20.184] <TB3> INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[13:08:20.184] <TB3> INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[13:08:20.585] <TB3> INFO: enter 'restricted' command line mode
[13:08:20.586] <TB3> INFO: enter test to run
[13:08:20.586] <TB3> INFO: test: pretest no parameter change
[13:08:20.586] <TB3> INFO: running: pretest
[13:08:20.592] <TB3> INFO: ######################################################################
[13:08:20.592] <TB3> INFO: PixTestPretest::doTest()
[13:08:20.592] <TB3> INFO: ######################################################################
[13:08:20.594] <TB3> INFO: ----------------------------------------------------------------------
[13:08:20.594] <TB3> INFO: PixTestPretest::programROC()
[13:08:20.594] <TB3> INFO: ----------------------------------------------------------------------
[13:08:38.608] <TB3> INFO: PixTestPretest::programROC() done: ROCs are all programmable
[13:08:38.608] <TB3> INFO: IA differences per ROC: 19.3 16.9 19.3 19.3 19.3 19.3 20.9 19.3 20.1 16.9 17.7 18.5 19.3 19.3 20.9 20.1
[13:08:38.676] <TB3> INFO: ----------------------------------------------------------------------
[13:08:38.676] <TB3> INFO: PixTestPretest::setVana() target Ia = 24 mA/ROC
[13:08:38.676] <TB3> INFO: ----------------------------------------------------------------------
[13:08:59.968] <TB3> INFO: PixTestPretest::setVana() done, Module Ia 386.7 mA = 24.1687 mA/ROC
[13:08:59.968] <TB3> INFO: i(loss) [mA/ROC]: 20.1 20.1 18.5 19.3 20.1 20.1 20.1 20.1 20.1 20.1 20.1 19.3 20.1 20.1 19.3 19.3
[13:08:59.002] <TB3> INFO: ----------------------------------------------------------------------
[13:08:59.002] <TB3> INFO: PixTestPretest::findTiming()
[13:08:59.002] <TB3> INFO: ----------------------------------------------------------------------
[13:08:59.002] <TB3> INFO: PixTestCmd::init()

[13:09:31.982] <TB3> INFO: TBM phases: 160MHz: 0, 400MHz: 2, TBM delays: ROC(0/1):5, header/trailer: 1, token: 1
[13:09:31.982] <TB3> INFO: (success/tries = 100/100), width = 4
[13:09:33.464] <TB3> INFO: ----------------------------------------------------------------------
[13:09:33.464] <TB3> INFO: PixTestPretest::findWorkingPixel()
[13:09:33.464] <TB3> INFO: ----------------------------------------------------------------------
[13:09:33.556] <TB3> INFO: Expecting 231680 events.
[13:09:43.470] <TB3> INFO: 231680 events read in total (9322ms).
[13:09:43.478] <TB3> INFO: Test took 10011ms.
[13:09:43.727] <TB3> INFO: Found working pixel in all ROCs: col/row = 12/22
[13:09:43.765] <TB3> INFO: ----------------------------------------------------------------------
[13:09:43.765] <TB3> INFO: PixTestPretest::setVthrCompCalDel()
[13:09:43.765] <TB3> INFO: ----------------------------------------------------------------------
[13:09:43.860] <TB3> INFO: Expecting 231680 events.
[13:09:53.983] <TB3> INFO: 231680 events read in total (9531ms).
[13:09:53.994] <TB3> INFO: Test took 10223ms.
[13:09:54.262] <TB3> INFO: PixTestPretest::setVthrCompCalDel() done
[13:09:54.262] <TB3> INFO: CalDel: 100 96 82 108 94 83 95 87 95 97 93 95 79 89 96 85
[13:09:54.262] <TB3> INFO: VthrComp: 51 51 51 51 51 56 51 51 51 51 51 51 52 51 51 61
[13:09:54.266] <TB3> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1047_FullQualification_2016-10-22_10h41m_1477125660//003_Fulltest_p17//dacParameters_C0.dat
[13:09:54.266] <TB3> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1047_FullQualification_2016-10-22_10h41m_1477125660//003_Fulltest_p17//dacParameters_C1.dat
[13:09:54.266] <TB3> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1047_FullQualification_2016-10-22_10h41m_1477125660//003_Fulltest_p17//dacParameters_C2.dat
[13:09:54.266] <TB3> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1047_FullQualification_2016-10-22_10h41m_1477125660//003_Fulltest_p17//dacParameters_C3.dat
[13:09:54.267] <TB3> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1047_FullQualification_2016-10-22_10h41m_1477125660//003_Fulltest_p17//dacParameters_C4.dat
[13:09:54.267] <TB3> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1047_FullQualification_2016-10-22_10h41m_1477125660//003_Fulltest_p17//dacParameters_C5.dat
[13:09:54.267] <TB3> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1047_FullQualification_2016-10-22_10h41m_1477125660//003_Fulltest_p17//dacParameters_C6.dat
[13:09:54.267] <TB3> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1047_FullQualification_2016-10-22_10h41m_1477125660//003_Fulltest_p17//dacParameters_C7.dat
[13:09:54.268] <TB3> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1047_FullQualification_2016-10-22_10h41m_1477125660//003_Fulltest_p17//dacParameters_C8.dat
[13:09:54.268] <TB3> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1047_FullQualification_2016-10-22_10h41m_1477125660//003_Fulltest_p17//dacParameters_C9.dat
[13:09:54.268] <TB3> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1047_FullQualification_2016-10-22_10h41m_1477125660//003_Fulltest_p17//dacParameters_C10.dat
[13:09:54.268] <TB3> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1047_FullQualification_2016-10-22_10h41m_1477125660//003_Fulltest_p17//dacParameters_C11.dat
[13:09:54.269] <TB3> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1047_FullQualification_2016-10-22_10h41m_1477125660//003_Fulltest_p17//dacParameters_C12.dat
[13:09:54.269] <TB3> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1047_FullQualification_2016-10-22_10h41m_1477125660//003_Fulltest_p17//dacParameters_C13.dat
[13:09:54.269] <TB3> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1047_FullQualification_2016-10-22_10h41m_1477125660//003_Fulltest_p17//dacParameters_C14.dat
[13:09:54.269] <TB3> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1047_FullQualification_2016-10-22_10h41m_1477125660//003_Fulltest_p17//dacParameters_C15.dat
[13:09:54.270] <TB3> INFO: write tbm parameters into /home/l_tester/david/FullQualification/data/M1047_FullQualification_2016-10-22_10h41m_1477125660//003_Fulltest_p17//tbmParameters_C0a.dat
[13:09:54.270] <TB3> INFO: write tbm parameters into /home/l_tester/david/FullQualification/data/M1047_FullQualification_2016-10-22_10h41m_1477125660//003_Fulltest_p17//tbmParameters_C0b.dat
[13:09:54.270] <TB3> INFO: write tbm parameters into /home/l_tester/david/FullQualification/data/M1047_FullQualification_2016-10-22_10h41m_1477125660//003_Fulltest_p17//tbmParameters_C1a.dat
[13:09:54.270] <TB3> INFO: write tbm parameters into /home/l_tester/david/FullQualification/data/M1047_FullQualification_2016-10-22_10h41m_1477125660//003_Fulltest_p17//tbmParameters_C1b.dat
[13:09:54.270] <TB3> INFO: PixTestPretest::doTest() done, duration: 93 seconds
[13:09:54.328] <TB3> INFO: enter test to run
[13:09:54.328] <TB3> INFO: test: FullTest no parameter change
[13:09:54.328] <TB3> INFO: running: fulltest
[13:09:54.328] <TB3> INFO: ######################################################################
[13:09:54.328] <TB3> INFO: PixTestFullTest::doTest()
[13:09:54.328] <TB3> INFO: ######################################################################
[13:09:54.329] <TB3> INFO: ######################################################################
[13:09:54.329] <TB3> INFO: PixTestAlive::doTest()
[13:09:54.329] <TB3> INFO: ######################################################################
[13:09:54.330] <TB3> INFO: ----------------------------------------------------------------------
[13:09:54.330] <TB3> INFO: PixTestAlive::aliveTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[13:09:54.330] <TB3> INFO: ----------------------------------------------------------------------
[13:09:54.570] <TB3> INFO: Expecting 41600 events.
[13:09:58.167] <TB3> INFO: 41600 events read in total (3005ms).
[13:09:58.168] <TB3> INFO: Test took 3836ms.
[13:09:58.400] <TB3> INFO: PixTestAlive::aliveTest() done
[13:09:58.400] <TB3> INFO: number of dead pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 2
[13:09:58.402] <TB3> INFO: ----------------------------------------------------------------------
[13:09:58.402] <TB3> INFO: PixTestAlive::maskTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[13:09:58.402] <TB3> INFO: ----------------------------------------------------------------------
[13:09:58.646] <TB3> INFO: Expecting 41600 events.
[13:10:01.707] <TB3> INFO: 41600 events read in total (2469ms).
[13:10:01.707] <TB3> INFO: Test took 3303ms.
[13:10:01.708] <TB3> INFO: mask vs. old pixelAlive PixelAlive_C0_V0 .. PixelAlive_C15_V0
[13:10:01.945] <TB3> INFO: PixTestAlive::maskTest() done
[13:10:01.945] <TB3> INFO: number of mask-defect pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[13:10:01.947] <TB3> INFO: ----------------------------------------------------------------------
[13:10:01.947] <TB3> INFO: PixTestAlive::addressDecodingTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[13:10:01.947] <TB3> INFO: ----------------------------------------------------------------------
[13:10:02.238] <TB3> INFO: Expecting 41600 events.
[13:10:05.791] <TB3> INFO: 41600 events read in total (2961ms).
[13:10:05.792] <TB3> INFO: Test took 3843ms.
[13:10:06.028] <TB3> INFO: PixTestAlive::addressDecodingTest() done
[13:10:06.028] <TB3> INFO: number of address-decoding pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[13:10:06.028] <TB3> INFO: PixTestAlive::doTest() done, duration: 11 seconds
[13:10:06.028] <TB3> INFO: Decoding statistics:
[13:10:06.028] <TB3> INFO: General information:
[13:10:06.028] <TB3> INFO: 16bit words read: 0
[13:10:06.028] <TB3> INFO: valid events total: 0
[13:10:06.028] <TB3> INFO: empty events: 0
[13:10:06.028] <TB3> INFO: valid events with pixels: 0
[13:10:06.028] <TB3> INFO: valid pixel hits: 0
[13:10:06.028] <TB3> INFO: Event errors: 0
[13:10:06.028] <TB3> INFO: start marker: 0
[13:10:06.028] <TB3> INFO: stop marker: 0
[13:10:06.028] <TB3> INFO: overflow: 0
[13:10:06.028] <TB3> INFO: invalid 5bit words: 0
[13:10:06.028] <TB3> INFO: invalid XOR eye diagram: 0
[13:10:06.029] <TB3> INFO: frame (failed synchr.): 0
[13:10:06.029] <TB3> INFO: idle data (no TBM trl): 0
[13:10:06.029] <TB3> INFO: no data (only TBM hdr): 0
[13:10:06.029] <TB3> INFO: TBM errors: 0
[13:10:06.029] <TB3> INFO: flawed TBM headers: 0
[13:10:06.029] <TB3> INFO: flawed TBM trailers: 0
[13:10:06.029] <TB3> INFO: event ID mismatches: 0
[13:10:06.029] <TB3> INFO: ROC errors: 0
[13:10:06.029] <TB3> INFO: missing ROC header(s): 0
[13:10:06.029] <TB3> INFO: misplaced readback start: 0
[13:10:06.029] <TB3> INFO: Pixel decoding errors: 0
[13:10:06.029] <TB3> INFO: pixel data incomplete: 0
[13:10:06.029] <TB3> INFO: pixel address: 0
[13:10:06.029] <TB3> INFO: pulse height fill bit: 0
[13:10:06.029] <TB3> INFO: buffer corruption: 0
[13:10:06.036] <TB3> INFO: readReadbackCal: /home/l_tester/david/FullQualification/data/M1047_FullQualification_2016-10-22_10h41m_1477125660//003_Fulltest_p17//readbackCal_C0.dat .. /home/l_tester/david/FullQualification/data/M1047_FullQualification_2016-10-22_10h41m_1477125660//003_Fulltest_p17//readbackCal_C15.dat
[13:10:06.037] <TB3> INFO: readGainPedestalParameters /home/l_tester/david/FullQualification/data/M1047_FullQualification_2016-10-22_10h41m_1477125660//003_Fulltest_p17//phCalibrationFitErr_C0.dat .. /home/l_tester/david/FullQualification/data/M1047_FullQualification_2016-10-22_10h41m_1477125660//003_Fulltest_p17//phCalibrationFitErr_C15.dat

[13:10:06.037] <TB3> INFO: ######################################################################
[13:10:06.037] <TB3> INFO: PixTestReadback::doTest()
[13:10:06.037] <TB3> INFO: ######################################################################
[13:10:06.037] <TB3> INFO: ----------------------------------------------------------------------
[13:10:06.037] <TB3> INFO: PixTestReadback::CalibrateVd()
[13:10:06.037] <TB3> INFO: ----------------------------------------------------------------------
[13:10:16.021] <TB3> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1047_FullQualification_2016-10-22_10h41m_1477125660//003_Fulltest_p17//readbackCal_C0.dat
[13:10:16.022] <TB3> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1047_FullQualification_2016-10-22_10h41m_1477125660//003_Fulltest_p17//readbackCal_C1.dat
[13:10:16.022] <TB3> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1047_FullQualification_2016-10-22_10h41m_1477125660//003_Fulltest_p17//readbackCal_C2.dat
[13:10:16.022] <TB3> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1047_FullQualification_2016-10-22_10h41m_1477125660//003_Fulltest_p17//readbackCal_C3.dat
[13:10:16.022] <TB3> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1047_FullQualification_2016-10-22_10h41m_1477125660//003_Fulltest_p17//readbackCal_C4.dat
[13:10:16.022] <TB3> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1047_FullQualification_2016-10-22_10h41m_1477125660//003_Fulltest_p17//readbackCal_C5.dat
[13:10:16.022] <TB3> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1047_FullQualification_2016-10-22_10h41m_1477125660//003_Fulltest_p17//readbackCal_C6.dat
[13:10:16.022] <TB3> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1047_FullQualification_2016-10-22_10h41m_1477125660//003_Fulltest_p17//readbackCal_C7.dat
[13:10:16.023] <TB3> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1047_FullQualification_2016-10-22_10h41m_1477125660//003_Fulltest_p17//readbackCal_C8.dat
[13:10:16.023] <TB3> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1047_FullQualification_2016-10-22_10h41m_1477125660//003_Fulltest_p17//readbackCal_C9.dat
[13:10:16.023] <TB3> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1047_FullQualification_2016-10-22_10h41m_1477125660//003_Fulltest_p17//readbackCal_C10.dat
[13:10:16.023] <TB3> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1047_FullQualification_2016-10-22_10h41m_1477125660//003_Fulltest_p17//readbackCal_C11.dat
[13:10:16.023] <TB3> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1047_FullQualification_2016-10-22_10h41m_1477125660//003_Fulltest_p17//readbackCal_C12.dat
[13:10:16.023] <TB3> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1047_FullQualification_2016-10-22_10h41m_1477125660//003_Fulltest_p17//readbackCal_C13.dat
[13:10:16.023] <TB3> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1047_FullQualification_2016-10-22_10h41m_1477125660//003_Fulltest_p17//readbackCal_C14.dat
[13:10:16.023] <TB3> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1047_FullQualification_2016-10-22_10h41m_1477125660//003_Fulltest_p17//readbackCal_C15.dat
[13:10:16.057] <TB3> INFO: PixTestPattern:: pg_setup set to default.
[13:10:16.057] <TB3> INFO: ----------------------------------------------------------------------
[13:10:16.057] <TB3> INFO: PixTestReadback::CalibrateVa()
[13:10:16.057] <TB3> INFO: ----------------------------------------------------------------------
[13:10:25.999] <TB3> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1047_FullQualification_2016-10-22_10h41m_1477125660//003_Fulltest_p17//readbackCal_C0.dat
[13:10:25.000] <TB3> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1047_FullQualification_2016-10-22_10h41m_1477125660//003_Fulltest_p17//readbackCal_C1.dat
[13:10:25.000] <TB3> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1047_FullQualification_2016-10-22_10h41m_1477125660//003_Fulltest_p17//readbackCal_C2.dat
[13:10:25.000] <TB3> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1047_FullQualification_2016-10-22_10h41m_1477125660//003_Fulltest_p17//readbackCal_C3.dat
[13:10:25.000] <TB3> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1047_FullQualification_2016-10-22_10h41m_1477125660//003_Fulltest_p17//readbackCal_C4.dat
[13:10:25.000] <TB3> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1047_FullQualification_2016-10-22_10h41m_1477125660//003_Fulltest_p17//readbackCal_C5.dat
[13:10:25.000] <TB3> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1047_FullQualification_2016-10-22_10h41m_1477125660//003_Fulltest_p17//readbackCal_C6.dat
[13:10:25.000] <TB3> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1047_FullQualification_2016-10-22_10h41m_1477125660//003_Fulltest_p17//readbackCal_C7.dat
[13:10:25.000] <TB3> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1047_FullQualification_2016-10-22_10h41m_1477125660//003_Fulltest_p17//readbackCal_C8.dat
[13:10:25.000] <TB3> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1047_FullQualification_2016-10-22_10h41m_1477125660//003_Fulltest_p17//readbackCal_C9.dat
[13:10:25.000] <TB3> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1047_FullQualification_2016-10-22_10h41m_1477125660//003_Fulltest_p17//readbackCal_C10.dat
[13:10:25.000] <TB3> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1047_FullQualification_2016-10-22_10h41m_1477125660//003_Fulltest_p17//readbackCal_C11.dat
[13:10:25.001] <TB3> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1047_FullQualification_2016-10-22_10h41m_1477125660//003_Fulltest_p17//readbackCal_C12.dat
[13:10:25.001] <TB3> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1047_FullQualification_2016-10-22_10h41m_1477125660//003_Fulltest_p17//readbackCal_C13.dat
[13:10:25.001] <TB3> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1047_FullQualification_2016-10-22_10h41m_1477125660//003_Fulltest_p17//readbackCal_C14.dat
[13:10:25.001] <TB3> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1047_FullQualification_2016-10-22_10h41m_1477125660//003_Fulltest_p17//readbackCal_C15.dat
[13:10:26.034] <TB3> INFO: PixTestPattern:: pg_setup set to default.
[13:10:26.034] <TB3> INFO: ----------------------------------------------------------------------
[13:10:26.034] <TB3> INFO: PixTestReadback::readbackVbg()
[13:10:26.034] <TB3> INFO: ----------------------------------------------------------------------
[13:10:33.707] <TB3> INFO: PixTestPattern:: pg_setup set to default.
[13:10:33.707] <TB3> INFO: ----------------------------------------------------------------------
[13:10:33.707] <TB3> INFO: PixTestReadback::getCalibratedVbg()
[13:10:33.707] <TB3> INFO: ----------------------------------------------------------------------
[13:10:33.707] <TB3> INFO: Vbg will be calibrated using Vd calibration
[13:10:33.707] <TB3> INFO: /*/*/*/*::: ROC 0: uncalibrated Vbg = 151calibrated Vbg = 1.21983 :::*/*/*/*/
[13:10:33.707] <TB3> INFO: /*/*/*/*::: ROC 1: uncalibrated Vbg = 154.5calibrated Vbg = 1.21383 :::*/*/*/*/
[13:10:33.707] <TB3> INFO: /*/*/*/*::: ROC 2: uncalibrated Vbg = 155.6calibrated Vbg = 1.20823 :::*/*/*/*/
[13:10:33.707] <TB3> INFO: /*/*/*/*::: ROC 3: uncalibrated Vbg = 156.3calibrated Vbg = 1.20811 :::*/*/*/*/
[13:10:33.707] <TB3> INFO: /*/*/*/*::: ROC 4: uncalibrated Vbg = 154.5calibrated Vbg = 1.21029 :::*/*/*/*/
[13:10:33.707] <TB3> INFO: /*/*/*/*::: ROC 5: uncalibrated Vbg = 159.8calibrated Vbg = 1.21334 :::*/*/*/*/
[13:10:33.707] <TB3> INFO: /*/*/*/*::: ROC 6: uncalibrated Vbg = 160.8calibrated Vbg = 1.21643 :::*/*/*/*/
[13:10:33.707] <TB3> INFO: /*/*/*/*::: ROC 7: uncalibrated Vbg = 168.1calibrated Vbg = 1.21707 :::*/*/*/*/
[13:10:33.707] <TB3> INFO: /*/*/*/*::: ROC 8: uncalibrated Vbg = 153.3calibrated Vbg = 1.21474 :::*/*/*/*/
[13:10:33.707] <TB3> INFO: /*/*/*/*::: ROC 9: uncalibrated Vbg = 152calibrated Vbg = 1.20627 :::*/*/*/*/
[13:10:33.707] <TB3> INFO: /*/*/*/*::: ROC 10: uncalibrated Vbg = 158.2calibrated Vbg = 1.19789 :::*/*/*/*/
[13:10:33.708] <TB3> INFO: /*/*/*/*::: ROC 11: uncalibrated Vbg = 160.6calibrated Vbg = 1.19997 :::*/*/*/*/
[13:10:33.708] <TB3> INFO: /*/*/*/*::: ROC 12: uncalibrated Vbg = 162.9calibrated Vbg = 1.209 :::*/*/*/*/
[13:10:33.708] <TB3> INFO: /*/*/*/*::: ROC 13: uncalibrated Vbg = 156.8calibrated Vbg = 1.20857 :::*/*/*/*/
[13:10:33.708] <TB3> INFO: /*/*/*/*::: ROC 14: uncalibrated Vbg = 153.9calibrated Vbg = 1.20997 :::*/*/*/*/
[13:10:33.708] <TB3> INFO: /*/*/*/*::: ROC 15: uncalibrated Vbg = 157.1calibrated Vbg = 1.20929 :::*/*/*/*/
[13:10:33.710] <TB3> INFO: ----------------------------------------------------------------------
[13:10:33.710] <TB3> INFO: PixTestReadback::CalibrateIa()
[13:10:33.710] <TB3> INFO: ----------------------------------------------------------------------
[13:13:14.503] <TB3> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1047_FullQualification_2016-10-22_10h41m_1477125660//003_Fulltest_p17//readbackCal_C0.dat
[13:13:14.504] <TB3> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1047_FullQualification_2016-10-22_10h41m_1477125660//003_Fulltest_p17//readbackCal_C1.dat
[13:13:14.504] <TB3> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1047_FullQualification_2016-10-22_10h41m_1477125660//003_Fulltest_p17//readbackCal_C2.dat
[13:13:14.504] <TB3> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1047_FullQualification_2016-10-22_10h41m_1477125660//003_Fulltest_p17//readbackCal_C3.dat
[13:13:14.504] <TB3> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1047_FullQualification_2016-10-22_10h41m_1477125660//003_Fulltest_p17//readbackCal_C4.dat
[13:13:14.504] <TB3> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1047_FullQualification_2016-10-22_10h41m_1477125660//003_Fulltest_p17//readbackCal_C5.dat
[13:13:14.504] <TB3> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1047_FullQualification_2016-10-22_10h41m_1477125660//003_Fulltest_p17//readbackCal_C6.dat
[13:13:14.504] <TB3> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1047_FullQualification_2016-10-22_10h41m_1477125660//003_Fulltest_p17//readbackCal_C7.dat
[13:13:14.504] <TB3> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1047_FullQualification_2016-10-22_10h41m_1477125660//003_Fulltest_p17//readbackCal_C8.dat
[13:13:14.504] <TB3> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1047_FullQualification_2016-10-22_10h41m_1477125660//003_Fulltest_p17//readbackCal_C9.dat
[13:13:14.504] <TB3> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1047_FullQualification_2016-10-22_10h41m_1477125660//003_Fulltest_p17//readbackCal_C10.dat
[13:13:14.504] <TB3> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1047_FullQualification_2016-10-22_10h41m_1477125660//003_Fulltest_p17//readbackCal_C11.dat
[13:13:14.504] <TB3> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1047_FullQualification_2016-10-22_10h41m_1477125660//003_Fulltest_p17//readbackCal_C12.dat
[13:13:14.505] <TB3> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1047_FullQualification_2016-10-22_10h41m_1477125660//003_Fulltest_p17//readbackCal_C13.dat
[13:13:14.505] <TB3> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1047_FullQualification_2016-10-22_10h41m_1477125660//003_Fulltest_p17//readbackCal_C14.dat
[13:13:14.505] <TB3> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1047_FullQualification_2016-10-22_10h41m_1477125660//003_Fulltest_p17//readbackCal_C15.dat
[13:13:14.534] <TB3> INFO: PixTestPattern:: pg_setup set to default.
[13:13:14.535] <TB3> INFO: PixTestReadback::doTest() done
[13:13:14.535] <TB3> INFO: Decoding statistics:
[13:13:14.535] <TB3> INFO: General information:
[13:13:14.535] <TB3> INFO: 16bit words read: 1536
[13:13:14.535] <TB3> INFO: valid events total: 256
[13:13:14.535] <TB3> INFO: empty events: 256
[13:13:14.535] <TB3> INFO: valid events with pixels: 0
[13:13:14.535] <TB3> INFO: valid pixel hits: 0
[13:13:14.535] <TB3> INFO: Event errors: 0
[13:13:14.535] <TB3> INFO: start marker: 0
[13:13:14.535] <TB3> INFO: stop marker: 0
[13:13:14.535] <TB3> INFO: overflow: 0
[13:13:14.535] <TB3> INFO: invalid 5bit words: 0
[13:13:14.535] <TB3> INFO: invalid XOR eye diagram: 0
[13:13:14.535] <TB3> INFO: frame (failed synchr.): 0
[13:13:14.535] <TB3> INFO: idle data (no TBM trl): 0
[13:13:14.535] <TB3> INFO: no data (only TBM hdr): 0
[13:13:14.535] <TB3> INFO: TBM errors: 0
[13:13:14.535] <TB3> INFO: flawed TBM headers: 0
[13:13:14.535] <TB3> INFO: flawed TBM trailers: 0
[13:13:14.535] <TB3> INFO: event ID mismatches: 0
[13:13:14.535] <TB3> INFO: ROC errors: 0
[13:13:14.535] <TB3> INFO: missing ROC header(s): 0
[13:13:14.535] <TB3> INFO: misplaced readback start: 0
[13:13:14.535] <TB3> INFO: Pixel decoding errors: 0
[13:13:14.535] <TB3> INFO: pixel data incomplete: 0
[13:13:14.535] <TB3> INFO: pixel address: 0
[13:13:14.535] <TB3> INFO: pulse height fill bit: 0
[13:13:14.535] <TB3> INFO: buffer corruption: 0
[13:13:14.586] <TB3> INFO: ######################################################################
[13:13:14.586] <TB3> INFO: PixTestBBMap::doTest() Ntrig = 5, VcalS = 250 (high range)
[13:13:14.586] <TB3> INFO: ######################################################################
[13:13:14.588] <TB3> INFO: ---> dac: VthrComp name: calSMap ntrig: 5 dacrange: 0 .. 149 (-1/-1) hits flags = 514 (plus default)
[13:13:14.604] <TB3> INFO: dacScan split into 1 runs with ntrig = 5
[13:13:14.604] <TB3> INFO: run 1 of 1
[13:13:14.841] <TB3> INFO: Expecting 3120000 events.
[13:13:46.046] <TB3> INFO: 678035 events read in total (30613ms).











[13:14:16.538] <TB3> INFO: 1352445 events read in total (61105ms).











[13:14:46.926] <TB3> INFO: 2024825 events read in total (91493ms).











[13:15:17.309] <TB3> INFO: 2697545 events read in total (121876ms).
[13:15:36.819] <TB3> INFO: 3120000 events read in total (141386ms).
[13:15:36.892] <TB3> INFO: Test took 142289ms.
[13:15:58.621] <TB3> INFO: PixTestBBMap::doTest() done with 1 decoding errors: , duration: 164 seconds
[13:15:58.621] <TB3> INFO: number of dead bumps (per ROC): 0 0 0 1 1 0 1 0 0 0 0 0 0 0 0 1
[13:15:58.621] <TB3> INFO: separation cut (per ROC): 113 100 104 103 103 124 105 113 101 105 103 110 114 102 104 119
[13:15:58.621] <TB3> INFO: Decoding statistics:
[13:15:58.621] <TB3> INFO: General information:
[13:15:58.621] <TB3> INFO: 16bit words read: 0
[13:15:58.621] <TB3> INFO: valid events total: 0
[13:15:58.621] <TB3> INFO: empty events: 0
[13:15:58.621] <TB3> INFO: valid events with pixels: 0
[13:15:58.621] <TB3> INFO: valid pixel hits: 0
[13:15:58.621] <TB3> INFO: Event errors: 0
[13:15:58.621] <TB3> INFO: start marker: 0
[13:15:58.621] <TB3> INFO: stop marker: 0
[13:15:58.621] <TB3> INFO: overflow: 0
[13:15:58.621] <TB3> INFO: invalid 5bit words: 0
[13:15:58.621] <TB3> INFO: invalid XOR eye diagram: 0
[13:15:58.621] <TB3> INFO: frame (failed synchr.): 0
[13:15:58.621] <TB3> INFO: idle data (no TBM trl): 0
[13:15:58.621] <TB3> INFO: no data (only TBM hdr): 0
[13:15:58.621] <TB3> INFO: TBM errors: 0
[13:15:58.621] <TB3> INFO: flawed TBM headers: 0
[13:15:58.621] <TB3> INFO: flawed TBM trailers: 0
[13:15:58.621] <TB3> INFO: event ID mismatches: 0
[13:15:58.621] <TB3> INFO: ROC errors: 0
[13:15:58.621] <TB3> INFO: missing ROC header(s): 0
[13:15:58.621] <TB3> INFO: misplaced readback start: 0
[13:15:58.621] <TB3> INFO: Pixel decoding errors: 0
[13:15:58.621] <TB3> INFO: pixel data incomplete: 0
[13:15:58.621] <TB3> INFO: pixel address: 0
[13:15:58.621] <TB3> INFO: pulse height fill bit: 0
[13:15:58.621] <TB3> INFO: buffer corruption: 0
[13:15:58.672] <TB3> INFO: ######################################################################
[13:15:58.672] <TB3> INFO: PixTestScurves::fullTest() ntrig = 50, dacs/step = -1, ntrig/step = -1
[13:15:58.672] <TB3> INFO: ######################################################################
[13:15:58.672] <TB3> INFO: ----------------------------------------------------------------------
[13:15:58.672] <TB3> INFO: PixTestScurves::scurves(Vcal), ntrig = 50, dacs/step = -1, ntrig/step = -1
[13:15:58.672] <TB3> INFO: ----------------------------------------------------------------------
[13:15:58.672] <TB3> INFO: ---> dac: Vcal name: scurveVcal ntrig: 50 dacrange: 25 .. 200 (-1/-1) hits flags = 528 (plus default)
[13:15:58.687] <TB3> INFO: dacScan split into 1 runs with ntrig = 50
[13:15:58.687] <TB3> INFO: run 1 of 1
[13:15:58.927] <TB3> INFO: Expecting 36608000 events.
[13:16:22.457] <TB3> INFO: 700450 events read in total (22938ms).
[13:16:45.567] <TB3> INFO: 1384950 events read in total (46048ms).
[13:17:08.579] <TB3> INFO: 2069800 events read in total (69060ms).
[13:17:31.688] <TB3> INFO: 2753850 events read in total (92169ms).
[13:17:54.742] <TB3> INFO: 3432650 events read in total (115223ms).
[13:18:18.025] <TB3> INFO: 4111750 events read in total (138506ms).
[13:18:40.942] <TB3> INFO: 4790950 events read in total (161423ms).
[13:19:03.880] <TB3> INFO: 5471100 events read in total (184361ms).
[13:19:26.913] <TB3> INFO: 6150050 events read in total (207394ms).
[13:19:49.786] <TB3> INFO: 6828650 events read in total (230267ms).
[13:20:12.668] <TB3> INFO: 7510100 events read in total (253149ms).
[13:20:35.777] <TB3> INFO: 8190000 events read in total (276258ms).
[13:20:58.954] <TB3> INFO: 8870100 events read in total (299435ms).
[13:21:22.007] <TB3> INFO: 9548750 events read in total (322488ms).
[13:21:45.305] <TB3> INFO: 10228500 events read in total (345786ms).
[13:22:08.402] <TB3> INFO: 10907100 events read in total (368883ms).
[13:22:31.308] <TB3> INFO: 11585650 events read in total (391789ms).
[13:22:54.181] <TB3> INFO: 12263550 events read in total (414662ms).
[13:23:17.105] <TB3> INFO: 12941200 events read in total (437586ms).
[13:23:40.102] <TB3> INFO: 13620100 events read in total (460583ms).
[13:24:02.871] <TB3> INFO: 14296750 events read in total (483352ms).
[13:24:25.708] <TB3> INFO: 14971200 events read in total (506189ms).
[13:24:48.746] <TB3> INFO: 15645600 events read in total (529227ms).
[13:25:11.941] <TB3> INFO: 16320900 events read in total (552422ms).
[13:25:34.807] <TB3> INFO: 16993450 events read in total (575288ms).
[13:25:57.606] <TB3> INFO: 17668100 events read in total (598087ms).
[13:26:20.431] <TB3> INFO: 18341100 events read in total (620912ms).
[13:26:43.174] <TB3> INFO: 19015900 events read in total (643655ms).
[13:27:06.260] <TB3> INFO: 19689100 events read in total (666741ms).
[13:27:29.388] <TB3> INFO: 20361300 events read in total (689869ms).
[13:27:52.268] <TB3> INFO: 21033400 events read in total (712749ms).
[13:28:14.972] <TB3> INFO: 21703150 events read in total (735453ms).
[13:28:37.548] <TB3> INFO: 22371100 events read in total (758029ms).
[13:29:00.291] <TB3> INFO: 23040400 events read in total (780772ms).
[13:29:23.114] <TB3> INFO: 23709750 events read in total (803595ms).
[13:29:45.668] <TB3> INFO: 24379300 events read in total (826150ms).
[13:30:08.429] <TB3> INFO: 25047550 events read in total (848910ms).
[13:30:31.005] <TB3> INFO: 25718900 events read in total (871486ms).
[13:30:54.003] <TB3> INFO: 26389600 events read in total (894484ms).
[13:31:17.084] <TB3> INFO: 27059700 events read in total (917565ms).
[13:31:40.009] <TB3> INFO: 27730700 events read in total (940490ms).
[13:32:02.825] <TB3> INFO: 28400750 events read in total (963306ms).
[13:32:25.652] <TB3> INFO: 29070750 events read in total (986133ms).
[13:32:48.692] <TB3> INFO: 29739650 events read in total (1009173ms).
[13:33:11.754] <TB3> INFO: 30410300 events read in total (1032235ms).
[13:33:34.701] <TB3> INFO: 31080200 events read in total (1055182ms).
[13:33:57.649] <TB3> INFO: 31749950 events read in total (1078130ms).
[13:34:20.450] <TB3> INFO: 32419500 events read in total (1100931ms).
[13:34:43.727] <TB3> INFO: 33091500 events read in total (1124208ms).
[13:35:07.082] <TB3> INFO: 33764150 events read in total (1147563ms).
[13:35:30.332] <TB3> INFO: 34437150 events read in total (1170813ms).
[13:35:53.112] <TB3> INFO: 35110250 events read in total (1193593ms).
[13:36:16.226] <TB3> INFO: 35785700 events read in total (1216707ms).
[13:36:40.098] <TB3> INFO: 36471500 events read in total (1240579ms).
[13:36:44.970] <TB3> INFO: 36608000 events read in total (1245451ms).
[13:36:45.045] <TB3> INFO: Test took 1246358ms.
[13:36:45.447] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[13:36:46.863] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[13:36:48.259] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[13:36:49.657] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[13:36:51.096] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[13:36:52.800] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[13:36:54.829] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[13:36:56.784] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[13:36:58.545] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[13:37:00.546] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[13:37:01.931] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[13:37:03.325] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[13:37:04.724] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[13:37:06.141] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[13:37:07.531] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[13:37:08.932] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[13:37:10.407] <TB3> INFO: PixTestScurves::scurves() done
[13:37:10.407] <TB3> INFO: Vcal mean: 127.64 124.55 126.14 123.57 117.99 138.13 126.71 137.98 114.92 120.93 114.42 130.73 132.85 121.86 125.15 126.73
[13:37:10.407] <TB3> INFO: Vcal RMS: 5.68 6.17 6.39 6.42 6.34 6.63 5.69 5.88 5.75 5.94 4.97 6.09 6.72 6.43 6.36 7.87
[13:37:10.407] <TB3> INFO: PixTestScurves::fullTest() done, duration: 1271 seconds
[13:37:10.407] <TB3> INFO: Decoding statistics:
[13:37:10.407] <TB3> INFO: General information:
[13:37:10.407] <TB3> INFO: 16bit words read: 0
[13:37:10.407] <TB3> INFO: valid events total: 0
[13:37:10.407] <TB3> INFO: empty events: 0
[13:37:10.407] <TB3> INFO: valid events with pixels: 0
[13:37:10.407] <TB3> INFO: valid pixel hits: 0
[13:37:10.407] <TB3> INFO: Event errors: 0
[13:37:10.407] <TB3> INFO: start marker: 0
[13:37:10.407] <TB3> INFO: stop marker: 0
[13:37:10.407] <TB3> INFO: overflow: 0
[13:37:10.407] <TB3> INFO: invalid 5bit words: 0
[13:37:10.407] <TB3> INFO: invalid XOR eye diagram: 0
[13:37:10.407] <TB3> INFO: frame (failed synchr.): 0
[13:37:10.407] <TB3> INFO: idle data (no TBM trl): 0
[13:37:10.407] <TB3> INFO: no data (only TBM hdr): 0
[13:37:10.407] <TB3> INFO: TBM errors: 0
[13:37:10.407] <TB3> INFO: flawed TBM headers: 0
[13:37:10.407] <TB3> INFO: flawed TBM trailers: 0
[13:37:10.407] <TB3> INFO: event ID mismatches: 0
[13:37:10.407] <TB3> INFO: ROC errors: 0
[13:37:10.407] <TB3> INFO: missing ROC header(s): 0
[13:37:10.407] <TB3> INFO: misplaced readback start: 0
[13:37:10.407] <TB3> INFO: Pixel decoding errors: 0
[13:37:10.407] <TB3> INFO: pixel data incomplete: 0
[13:37:10.407] <TB3> INFO: pixel address: 0
[13:37:10.407] <TB3> INFO: pulse height fill bit: 0
[13:37:10.407] <TB3> INFO: buffer corruption: 0
[13:37:10.473] <TB3> INFO: ######################################################################
[13:37:10.473] <TB3> INFO: PixTestTrim::doTest()
[13:37:10.473] <TB3> INFO: ######################################################################
[13:37:10.474] <TB3> INFO: ----------------------------------------------------------------------
[13:37:10.474] <TB3> INFO: PixTestTrim::trimTest() ntrig = 8, vcal = 35
[13:37:10.474] <TB3> INFO: ----------------------------------------------------------------------
[13:37:10.515] <TB3> INFO: ---> VthrComp thr map (minimal VthrComp)
[13:37:10.515] <TB3> INFO: ---> dac: vthrcomp name: TrimThr0 ntrig: 8 dacrange: 10 .. 160 (-1/-1) hits flags = 528 (plus default)
[13:37:10.528] <TB3> INFO: dacScan split into 1 runs with ntrig = 8
[13:37:10.528] <TB3> INFO: run 1 of 1
[13:37:10.766] <TB3> INFO: Expecting 5025280 events.
[13:37:42.139] <TB3> INFO: 833536 events read in total (30775ms).
[13:38:12.842] <TB3> INFO: 1664224 events read in total (61478ms).
[13:38:43.136] <TB3> INFO: 2491664 events read in total (91772ms).
[13:39:13.388] <TB3> INFO: 3316688 events read in total (122024ms).
[13:39:43.491] <TB3> INFO: 4138576 events read in total (152128ms).
[13:40:13.844] <TB3> INFO: 4960480 events read in total (182480ms).
[13:40:16.625] <TB3> INFO: 5025280 events read in total (185261ms).
[13:40:16.679] <TB3> INFO: Test took 186151ms.
[13:40:33.041] <TB3> INFO: ROC 0 VthrComp = 129
[13:40:33.042] <TB3> INFO: ROC 1 VthrComp = 119
[13:40:33.042] <TB3> INFO: ROC 2 VthrComp = 118
[13:40:33.042] <TB3> INFO: ROC 3 VthrComp = 119
[13:40:33.042] <TB3> INFO: ROC 4 VthrComp = 113
[13:40:33.042] <TB3> INFO: ROC 5 VthrComp = 131
[13:40:33.042] <TB3> INFO: ROC 6 VthrComp = 126
[13:40:33.043] <TB3> INFO: ROC 7 VthrComp = 130
[13:40:33.043] <TB3> INFO: ROC 8 VthrComp = 112
[13:40:33.043] <TB3> INFO: ROC 9 VthrComp = 117
[13:40:33.043] <TB3> INFO: ROC 10 VthrComp = 117
[13:40:33.043] <TB3> INFO: ROC 11 VthrComp = 130
[13:40:33.043] <TB3> INFO: ROC 12 VthrComp = 127
[13:40:33.043] <TB3> INFO: ROC 13 VthrComp = 117
[13:40:33.044] <TB3> INFO: ROC 14 VthrComp = 122
[13:40:33.044] <TB3> INFO: ROC 15 VthrComp = 126
[13:40:33.286] <TB3> INFO: Expecting 41600 events.
[13:40:36.886] <TB3> INFO: 41600 events read in total (3008ms).
[13:40:36.887] <TB3> INFO: Test took 3842ms.
[13:40:36.896] <TB3> INFO: ---> Vcal thr map (pixel with maximum Vcal thr)
[13:40:36.896] <TB3> INFO: ---> dac: vcal name: TrimThr1 ntrig: 8 dacrange: 10 .. 160 (-1/-1) hits flags = 528 (plus default)
[13:40:36.908] <TB3> INFO: dacScan split into 1 runs with ntrig = 8
[13:40:36.908] <TB3> INFO: run 1 of 1
[13:40:37.186] <TB3> INFO: Expecting 5025280 events.
[13:41:04.383] <TB3> INFO: 593304 events read in total (26605ms).
[13:41:30.484] <TB3> INFO: 1184744 events read in total (52706ms).
[13:41:56.676] <TB3> INFO: 1776264 events read in total (78898ms).
[13:42:22.510] <TB3> INFO: 2366536 events read in total (104733ms).
[13:42:48.766] <TB3> INFO: 2955176 events read in total (130988ms).
[13:43:15.227] <TB3> INFO: 3541584 events read in total (157449ms).
[13:43:41.341] <TB3> INFO: 4127752 events read in total (183563ms).
[13:44:06.864] <TB3> INFO: 4713376 events read in total (209086ms).
[13:44:20.533] <TB3> INFO: 5025280 events read in total (222755ms).
[13:44:20.620] <TB3> INFO: Test took 223712ms.
[13:44:43.979] <TB3> INFO: roc 0 with ID = 0 has maximal Vcal 61.6007 for pixel 27/16 mean/min/max = 46.7083/31.7344/61.6822
[13:44:43.980] <TB3> INFO: roc 1 with ID = 1 has maximal Vcal 61.3458 for pixel 19/12 mean/min/max = 46.652/31.8596/61.4444
[13:44:43.981] <TB3> INFO: roc 2 with ID = 2 has maximal Vcal 63.7557 for pixel 22/6 mean/min/max = 48.0347/32.0576/64.0119
[13:44:43.981] <TB3> INFO: roc 3 with ID = 3 has maximal Vcal 61.6751 for pixel 22/70 mean/min/max = 46.614/31.5302/61.6978
[13:44:43.982] <TB3> INFO: roc 4 with ID = 4 has maximal Vcal 61.3695 for pixel 26/41 mean/min/max = 47.2685/33.0843/61.4526
[13:44:43.982] <TB3> INFO: roc 5 with ID = 5 has maximal Vcal 69.2037 for pixel 14/8 mean/min/max = 53.0686/36.5407/69.5964
[13:44:43.983] <TB3> INFO: roc 6 with ID = 6 has maximal Vcal 61.492 for pixel 33/9 mean/min/max = 46.9365/32.3438/61.5292
[13:44:43.983] <TB3> INFO: roc 7 with ID = 7 has maximal Vcal 65.8318 for pixel 23/4 mean/min/max = 50.5729/35.1713/65.9745
[13:44:43.984] <TB3> INFO: roc 8 with ID = 8 has maximal Vcal 61.7091 for pixel 5/2 mean/min/max = 47.0671/32.4086/61.7255
[13:44:43.984] <TB3> INFO: roc 9 with ID = 9 has maximal Vcal 61.3133 for pixel 22/22 mean/min/max = 46.6934/31.9295/61.4574
[13:44:43.985] <TB3> INFO: roc 10 with ID = 10 has maximal Vcal 59.2071 for pixel 51/3 mean/min/max = 45.8563/32.3318/59.3808
[13:44:43.985] <TB3> INFO: roc 11 with ID = 11 has maximal Vcal 60.9675 for pixel 49/0 mean/min/max = 46.4957/31.9007/61.0906
[13:44:43.986] <TB3> INFO: roc 12 with ID = 12 has maximal Vcal 63.4142 for pixel 16/56 mean/min/max = 47.371/30.9597/63.7824
[13:44:43.986] <TB3> INFO: roc 13 with ID = 13 has maximal Vcal 60.8417 for pixel 24/3 mean/min/max = 46.8741/32.7582/60.9899
[13:44:43.986] <TB3> INFO: roc 14 with ID = 14 has maximal Vcal 60.4332 for pixel 40/71 mean/min/max = 46.9311/33.0016/60.8606
[13:44:43.987] <TB3> INFO: roc 15 with ID = 15 has maximal Vcal 67.9186 for pixel 0/6 mean/min/max = 50.6577/32.9799/68.3354
[13:44:43.987] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[13:44:44.076] <TB3> INFO: Expecting 411648 events.
[13:44:53.661] <TB3> INFO: 411648 events read in total (8993ms).
[13:44:53.669] <TB3> INFO: Expecting 411648 events.
[13:45:02.950] <TB3> INFO: 411648 events read in total (8878ms).
[13:45:02.963] <TB3> INFO: Expecting 411648 events.
[13:45:12.265] <TB3> INFO: 411648 events read in total (8899ms).
[13:45:12.280] <TB3> INFO: Expecting 411648 events.
[13:45:21.548] <TB3> INFO: 411648 events read in total (8865ms).
[13:45:21.564] <TB3> INFO: Expecting 411648 events.
[13:45:30.809] <TB3> INFO: 411648 events read in total (8842ms).
[13:45:30.833] <TB3> INFO: Expecting 411648 events.
[13:45:40.129] <TB3> INFO: 411648 events read in total (8893ms).
[13:45:40.151] <TB3> INFO: Expecting 411648 events.
[13:45:49.457] <TB3> INFO: 411648 events read in total (8903ms).
[13:45:49.489] <TB3> INFO: Expecting 411648 events.
[13:45:58.816] <TB3> INFO: 411648 events read in total (8924ms).
[13:45:58.851] <TB3> INFO: Expecting 411648 events.
[13:46:08.119] <TB3> INFO: 411648 events read in total (8865ms).
[13:46:08.157] <TB3> INFO: Expecting 411648 events.
[13:46:17.482] <TB3> INFO: 411648 events read in total (8922ms).
[13:46:17.524] <TB3> INFO: Expecting 411648 events.
[13:46:26.902] <TB3> INFO: 411648 events read in total (8975ms).
[13:46:26.941] <TB3> INFO: Expecting 411648 events.
[13:46:36.223] <TB3> INFO: 411648 events read in total (8879ms).
[13:46:36.261] <TB3> INFO: Expecting 411648 events.
[13:46:45.555] <TB3> INFO: 411648 events read in total (8891ms).
[13:46:45.606] <TB3> INFO: Expecting 411648 events.
[13:46:55.024] <TB3> INFO: 411648 events read in total (9015ms).
[13:46:55.070] <TB3> INFO: Expecting 411648 events.
[13:47:04.290] <TB3> INFO: 411648 events read in total (8817ms).
[13:47:04.341] <TB3> INFO: Expecting 411648 events.
[13:47:13.827] <TB3> INFO: 411648 events read in total (9083ms).
[13:47:13.877] <TB3> INFO: Test took 149890ms.
[13:47:14.544] <TB3> INFO: ---> dac: vcal name: TrimThr2 ntrig: 8 dacrange: 0 .. 150 (-1/-1) hits flags = 528 (plus default)
[13:47:14.556] <TB3> INFO: dacScan split into 1 runs with ntrig = 8
[13:47:14.556] <TB3> INFO: run 1 of 1
[13:47:14.794] <TB3> INFO: Expecting 5025280 events.
[13:47:41.859] <TB3> INFO: 593416 events read in total (26473ms).
[13:48:08.211] <TB3> INFO: 1185760 events read in total (52825ms).
[13:48:34.709] <TB3> INFO: 1777824 events read in total (79323ms).
[13:49:01.376] <TB3> INFO: 2368008 events read in total (105990ms).
[13:49:28.254] <TB3> INFO: 2958896 events read in total (132868ms).
[13:49:55.105] <TB3> INFO: 3550184 events read in total (159719ms).
[13:50:21.719] <TB3> INFO: 4140440 events read in total (186333ms).
[13:50:47.922] <TB3> INFO: 4732800 events read in total (212536ms).
[13:51:01.320] <TB3> INFO: 5025280 events read in total (225934ms).
[13:51:01.469] <TB3> INFO: Test took 226914ms.
[13:51:21.482] <TB3> INFO: ---> TrimStepCorr4 extremal thresholds: 1.932447 .. 144.426055
[13:51:21.735] <TB3> INFO: Expecting 208000 events.
[13:51:31.434] <TB3> INFO: 208000 events read in total (9107ms).
[13:51:31.436] <TB3> INFO: Test took 9952ms.
[13:51:31.484] <TB3> INFO: ---> dac: vcal name: trimStepCorr4 ntrig: 8 dacrange: 1 .. 154 (-1/-1) hits flags = 528 (plus default)
[13:51:31.497] <TB3> INFO: dacScan split into 1 runs with ntrig = 8
[13:51:31.497] <TB3> INFO: run 1 of 1
[13:51:31.776] <TB3> INFO: Expecting 5125120 events.
[13:51:58.307] <TB3> INFO: 585680 events read in total (25939ms).
[13:52:24.369] <TB3> INFO: 1171432 events read in total (52002ms).
[13:52:50.589] <TB3> INFO: 1756488 events read in total (78221ms).
[13:53:16.561] <TB3> INFO: 2341392 events read in total (104193ms).
[13:53:42.961] <TB3> INFO: 2926696 events read in total (130593ms).
[13:54:09.609] <TB3> INFO: 3512232 events read in total (157241ms).
[13:54:36.366] <TB3> INFO: 4097160 events read in total (183998ms).
[13:55:02.830] <TB3> INFO: 4681936 events read in total (210462ms).
[13:55:22.560] <TB3> INFO: 5125120 events read in total (230193ms).
[13:55:22.691] <TB3> INFO: Test took 231195ms.
[13:55:47.781] <TB3> INFO: ---> TrimStepCorr2 extremal thresholds: 27.292007 .. 46.874476
[13:55:48.023] <TB3> INFO: Expecting 208000 events.
[13:55:57.910] <TB3> INFO: 208000 events read in total (9295ms).
[13:55:57.912] <TB3> INFO: Test took 10130ms.
[13:55:57.967] <TB3> INFO: ---> dac: vcal name: trimStepCorr2 ntrig: 8 dacrange: 17 .. 56 (-1/-1) hits flags = 528 (plus default)
[13:55:57.980] <TB3> INFO: dacScan split into 1 runs with ntrig = 8
[13:55:57.980] <TB3> INFO: run 1 of 1
[13:55:58.258] <TB3> INFO: Expecting 1331200 events.
[13:56:26.849] <TB3> INFO: 659832 events read in total (27999ms).
[13:56:54.500] <TB3> INFO: 1317304 events read in total (55651ms).
[13:56:55.499] <TB3> INFO: 1331200 events read in total (56650ms).
[13:56:55.531] <TB3> INFO: Test took 57552ms.
[13:57:07.954] <TB3> INFO: ---> TrimStepCorr1a extremal thresholds: 26.823642 .. 48.605534
[13:57:08.193] <TB3> INFO: Expecting 208000 events.
[13:57:17.767] <TB3> INFO: 208000 events read in total (8983ms).
[13:57:17.768] <TB3> INFO: Test took 9812ms.
[13:57:17.815] <TB3> INFO: ---> dac: vcal name: trimStepCorr1a ntrig: 8 dacrange: 16 .. 58 (-1/-1) hits flags = 528 (plus default)
[13:57:17.829] <TB3> INFO: dacScan split into 1 runs with ntrig = 8
[13:57:17.829] <TB3> INFO: run 1 of 1
[13:57:18.107] <TB3> INFO: Expecting 1431040 events.
[13:57:46.201] <TB3> INFO: 655552 events read in total (27502ms).
[13:58:13.704] <TB3> INFO: 1309696 events read in total (55005ms).
[13:58:19.279] <TB3> INFO: 1431040 events read in total (60580ms).
[13:58:19.310] <TB3> INFO: Test took 61482ms.
[13:58:32.813] <TB3> INFO: ---> TrimStepCorr1b extremal thresholds: 25.801383 .. 51.203710
[13:58:33.057] <TB3> INFO: Expecting 208000 events.
[13:58:42.883] <TB3> INFO: 208000 events read in total (9235ms).
[13:58:42.884] <TB3> INFO: Test took 10070ms.
[13:58:42.940] <TB3> INFO: ---> dac: vcal name: trimStepCorr1b ntrig: 8 dacrange: 15 .. 61 (-1/-1) hits flags = 528 (plus default)
[13:58:42.954] <TB3> INFO: dacScan split into 1 runs with ntrig = 8
[13:58:42.955] <TB3> INFO: run 1 of 1
[13:58:43.233] <TB3> INFO: Expecting 1564160 events.
[13:59:11.280] <TB3> INFO: 647344 events read in total (27456ms).
[13:59:38.573] <TB3> INFO: 1294288 events read in total (54749ms).
[13:59:50.194] <TB3> INFO: 1564160 events read in total (66370ms).
[13:59:50.231] <TB3> INFO: Test took 67276ms.
[14:00:03.454] <TB3> INFO: ---> TrimThrFinal extremal thresholds: 15 .. 55
[14:00:03.454] <TB3> INFO: ---> dac: vcal name: TrimThrFinal ntrig: 8 dacrange: 15 .. 55 (-1/-1) hits flags = 528 (plus default)
[14:00:03.468] <TB3> INFO: dacScan split into 1 runs with ntrig = 8
[14:00:03.468] <TB3> INFO: run 1 of 1
[14:00:03.706] <TB3> INFO: Expecting 1364480 events.
[14:00:32.625] <TB3> INFO: 669016 events read in total (28327ms).
[14:01:00.548] <TB3> INFO: 1337312 events read in total (56251ms).
[14:01:02.079] <TB3> INFO: 1364480 events read in total (57782ms).
[14:01:02.109] <TB3> INFO: Test took 58642ms.
[14:01:16.211] <TB3> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1047_FullQualification_2016-10-22_10h41m_1477125660//003_Fulltest_p17//dacParameters35_C0.dat
[14:01:16.211] <TB3> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1047_FullQualification_2016-10-22_10h41m_1477125660//003_Fulltest_p17//dacParameters35_C1.dat
[14:01:16.211] <TB3> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1047_FullQualification_2016-10-22_10h41m_1477125660//003_Fulltest_p17//dacParameters35_C2.dat
[14:01:16.211] <TB3> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1047_FullQualification_2016-10-22_10h41m_1477125660//003_Fulltest_p17//dacParameters35_C3.dat
[14:01:16.211] <TB3> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1047_FullQualification_2016-10-22_10h41m_1477125660//003_Fulltest_p17//dacParameters35_C4.dat
[14:01:16.211] <TB3> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1047_FullQualification_2016-10-22_10h41m_1477125660//003_Fulltest_p17//dacParameters35_C5.dat
[14:01:16.211] <TB3> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1047_FullQualification_2016-10-22_10h41m_1477125660//003_Fulltest_p17//dacParameters35_C6.dat
[14:01:16.211] <TB3> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1047_FullQualification_2016-10-22_10h41m_1477125660//003_Fulltest_p17//dacParameters35_C7.dat
[14:01:16.211] <TB3> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1047_FullQualification_2016-10-22_10h41m_1477125660//003_Fulltest_p17//dacParameters35_C8.dat
[14:01:16.212] <TB3> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1047_FullQualification_2016-10-22_10h41m_1477125660//003_Fulltest_p17//dacParameters35_C9.dat
[14:01:16.212] <TB3> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1047_FullQualification_2016-10-22_10h41m_1477125660//003_Fulltest_p17//dacParameters35_C10.dat
[14:01:16.212] <TB3> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1047_FullQualification_2016-10-22_10h41m_1477125660//003_Fulltest_p17//dacParameters35_C11.dat
[14:01:16.212] <TB3> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1047_FullQualification_2016-10-22_10h41m_1477125660//003_Fulltest_p17//dacParameters35_C12.dat
[14:01:16.212] <TB3> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1047_FullQualification_2016-10-22_10h41m_1477125660//003_Fulltest_p17//dacParameters35_C13.dat
[14:01:16.212] <TB3> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1047_FullQualification_2016-10-22_10h41m_1477125660//003_Fulltest_p17//dacParameters35_C14.dat
[14:01:16.212] <TB3> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1047_FullQualification_2016-10-22_10h41m_1477125660//003_Fulltest_p17//dacParameters35_C15.dat
[14:01:16.212] <TB3> INFO: write trim parameters into /home/l_tester/david/FullQualification/data/M1047_FullQualification_2016-10-22_10h41m_1477125660//003_Fulltest_p17//trimParameters35_C0.dat
[14:01:16.217] <TB3> INFO: write trim parameters into /home/l_tester/david/FullQualification/data/M1047_FullQualification_2016-10-22_10h41m_1477125660//003_Fulltest_p17//trimParameters35_C1.dat
[14:01:16.222] <TB3> INFO: write trim parameters into /home/l_tester/david/FullQualification/data/M1047_FullQualification_2016-10-22_10h41m_1477125660//003_Fulltest_p17//trimParameters35_C2.dat
[14:01:16.226] <TB3> INFO: write trim parameters into /home/l_tester/david/FullQualification/data/M1047_FullQualification_2016-10-22_10h41m_1477125660//003_Fulltest_p17//trimParameters35_C3.dat
[14:01:16.231] <TB3> INFO: write trim parameters into /home/l_tester/david/FullQualification/data/M1047_FullQualification_2016-10-22_10h41m_1477125660//003_Fulltest_p17//trimParameters35_C4.dat
[14:01:16.236] <TB3> INFO: write trim parameters into /home/l_tester/david/FullQualification/data/M1047_FullQualification_2016-10-22_10h41m_1477125660//003_Fulltest_p17//trimParameters35_C5.dat
[14:01:16.241] <TB3> INFO: write trim parameters into /home/l_tester/david/FullQualification/data/M1047_FullQualification_2016-10-22_10h41m_1477125660//003_Fulltest_p17//trimParameters35_C6.dat
[14:01:16.245] <TB3> INFO: write trim parameters into /home/l_tester/david/FullQualification/data/M1047_FullQualification_2016-10-22_10h41m_1477125660//003_Fulltest_p17//trimParameters35_C7.dat
[14:01:16.250] <TB3> INFO: write trim parameters into /home/l_tester/david/FullQualification/data/M1047_FullQualification_2016-10-22_10h41m_1477125660//003_Fulltest_p17//trimParameters35_C8.dat
[14:01:16.255] <TB3> INFO: write trim parameters into /home/l_tester/david/FullQualification/data/M1047_FullQualification_2016-10-22_10h41m_1477125660//003_Fulltest_p17//trimParameters35_C9.dat
[14:01:16.259] <TB3> INFO: write trim parameters into /home/l_tester/david/FullQualification/data/M1047_FullQualification_2016-10-22_10h41m_1477125660//003_Fulltest_p17//trimParameters35_C10.dat
[14:01:16.264] <TB3> INFO: write trim parameters into /home/l_tester/david/FullQualification/data/M1047_FullQualification_2016-10-22_10h41m_1477125660//003_Fulltest_p17//trimParameters35_C11.dat
[14:01:16.269] <TB3> INFO: write trim parameters into /home/l_tester/david/FullQualification/data/M1047_FullQualification_2016-10-22_10h41m_1477125660//003_Fulltest_p17//trimParameters35_C12.dat
[14:01:16.274] <TB3> INFO: write trim parameters into /home/l_tester/david/FullQualification/data/M1047_FullQualification_2016-10-22_10h41m_1477125660//003_Fulltest_p17//trimParameters35_C13.dat
[14:01:16.278] <TB3> INFO: write trim parameters into /home/l_tester/david/FullQualification/data/M1047_FullQualification_2016-10-22_10h41m_1477125660//003_Fulltest_p17//trimParameters35_C14.dat
[14:01:16.283] <TB3> INFO: write trim parameters into /home/l_tester/david/FullQualification/data/M1047_FullQualification_2016-10-22_10h41m_1477125660//003_Fulltest_p17//trimParameters35_C15.dat
[14:01:16.288] <TB3> INFO: PixTestTrim::trimTest() done
[14:01:16.288] <TB3> INFO: vtrim: 137 119 133 120 132 163 145 146 124 130 124 130 131 122 127 138
[14:01:16.288] <TB3> INFO: vthrcomp: 129 119 118 119 113 131 126 130 112 117 117 130 127 117 122 126
[14:01:16.288] <TB3> INFO: vcal mean: 34.99 35.06 35.56 35.37 35.00 35.10 35.08 35.26 35.02 35.00 34.93 35.14 35.42 35.03 35.16 35.49
[14:01:16.288] <TB3> INFO: vcal RMS: 1.09 1.19 1.76 1.59 1.10 1.19 1.20 1.35 1.11 1.18 1.08 1.27 1.73 1.12 1.31 1.92
[14:01:16.288] <TB3> INFO: bits mean: 9.63 9.88 9.53 9.94 9.67 8.00 9.58 8.42 9.31 9.55 9.10 9.67 10.03 9.40 9.62 8.52
[14:01:16.288] <TB3> INFO: bits RMS: 2.65 2.57 2.74 2.64 2.42 2.29 2.60 2.56 2.61 2.69 2.93 2.66 2.61 2.65 2.55 2.89
[14:01:16.295] <TB3> INFO: ----------------------------------------------------------------------
[14:01:16.295] <TB3> INFO: PixTestTrim::trimBitTest() ntrig = 5, vtrims = 254 126 63 32
[14:01:16.295] <TB3> INFO: ----------------------------------------------------------------------
[14:01:16.298] <TB3> INFO: ---> dac: Vcal name: TrimBitsThr0 ntrig: 5 dacrange: 0 .. 199 (-1/-1) hits flags = 528 (plus default)
[14:01:16.311] <TB3> INFO: dacScan split into 1 runs with ntrig = 5
[14:01:16.311] <TB3> INFO: run 1 of 1
[14:01:16.550] <TB3> INFO: Expecting 4160000 events.
[14:01:49.222] <TB3> INFO: 773600 events read in total (32080ms).
[14:02:21.465] <TB3> INFO: 1541210 events read in total (64324ms).
[14:02:53.345] <TB3> INFO: 2301900 events read in total (96203ms).
[14:03:25.373] <TB3> INFO: 3056815 events read in total (128231ms).
[14:03:57.633] <TB3> INFO: 3812235 events read in total (160491ms).
[14:04:13.010] <TB3> INFO: 4160000 events read in total (175868ms).
[14:04:13.248] <TB3> INFO: Test took 176937ms.
[14:04:38.955] <TB3> INFO: ---> dac: Vcal name: TrimThr_trim14 ntrig: 5 dacrange: 0 .. 215 (-1/-1) hits flags = 528 (plus default)
[14:04:38.969] <TB3> INFO: dacScan split into 1 runs with ntrig = 5
[14:04:38.969] <TB3> INFO: run 1 of 1
[14:04:39.301] <TB3> INFO: Expecting 4492800 events.
[14:05:11.035] <TB3> INFO: 725995 events read in total (31143ms).
[14:05:42.373] <TB3> INFO: 1447435 events read in total (62481ms).
[14:06:13.264] <TB3> INFO: 2165235 events read in total (93372ms).
[14:06:44.167] <TB3> INFO: 2878865 events read in total (124275ms).
[14:07:15.245] <TB3> INFO: 3590190 events read in total (155353ms).
[14:07:46.217] <TB3> INFO: 4303410 events read in total (186325ms).
[14:07:54.968] <TB3> INFO: 4492800 events read in total (195076ms).
[14:07:55.049] <TB3> INFO: Test took 196079ms.
[14:08:22.560] <TB3> INFO: ---> dac: Vcal name: TrimThr_trim13 ntrig: 5 dacrange: 0 .. 213 (-1/-1) hits flags = 528 (plus default)
[14:08:22.573] <TB3> INFO: dacScan split into 1 runs with ntrig = 5
[14:08:22.573] <TB3> INFO: run 1 of 1
[14:08:22.848] <TB3> INFO: Expecting 4451200 events.
[14:08:54.785] <TB3> INFO: 728555 events read in total (31346ms).
[14:09:26.366] <TB3> INFO: 1452840 events read in total (62927ms).
[14:09:57.175] <TB3> INFO: 2173080 events read in total (93736ms).
[14:10:28.277] <TB3> INFO: 2888945 events read in total (124838ms).
[14:10:59.136] <TB3> INFO: 3603490 events read in total (155698ms).
[14:11:30.990] <TB3> INFO: 4319580 events read in total (187551ms).
[14:11:37.359] <TB3> INFO: 4451200 events read in total (193920ms).
[14:11:37.464] <TB3> INFO: Test took 194891ms.
[14:12:06.807] <TB3> INFO: ---> dac: Vcal name: TrimThr_trim11 ntrig: 5 dacrange: 0 .. 214 (-1/-1) hits flags = 528 (plus default)
[14:12:06.821] <TB3> INFO: dacScan split into 1 runs with ntrig = 5
[14:12:06.821] <TB3> INFO: run 1 of 1
[14:12:07.068] <TB3> INFO: Expecting 4472000 events.
[14:12:38.927] <TB3> INFO: 727565 events read in total (31267ms).
[14:13:10.277] <TB3> INFO: 1450630 events read in total (62618ms).
[14:13:42.136] <TB3> INFO: 2170025 events read in total (94476ms).
[14:14:13.812] <TB3> INFO: 2885095 events read in total (126152ms).
[14:14:45.306] <TB3> INFO: 3598565 events read in total (157646ms).
[14:15:17.081] <TB3> INFO: 4313990 events read in total (189421ms).
[14:15:24.054] <TB3> INFO: 4472000 events read in total (196394ms).
[14:15:24.129] <TB3> INFO: Test took 197309ms.
[14:15:50.476] <TB3> INFO: ---> dac: Vcal name: TrimThr_trim7 ntrig: 5 dacrange: 0 .. 216 (-1/-1) hits flags = 528 (plus default)
[14:15:50.490] <TB3> INFO: dacScan split into 1 runs with ntrig = 5
[14:15:50.490] <TB3> INFO: run 1 of 1
[14:15:50.732] <TB3> INFO: Expecting 4513600 events.
[14:16:22.650] <TB3> INFO: 725755 events read in total (31326ms).
[14:16:53.804] <TB3> INFO: 1446940 events read in total (62480ms).
[14:17:25.097] <TB3> INFO: 2164540 events read in total (93773ms).
[14:17:56.238] <TB3> INFO: 2878240 events read in total (124914ms).
[14:18:27.204] <TB3> INFO: 3589500 events read in total (155880ms).
[14:18:57.991] <TB3> INFO: 4302340 events read in total (186667ms).
[14:19:07.277] <TB3> INFO: 4513600 events read in total (195953ms).
[14:19:07.368] <TB3> INFO: Test took 196877ms.
[14:19:32.274] <TB3> INFO: PixTestTrim::trimBitTest() done
[14:19:32.275] <TB3> INFO: PixTestTrim::doTest() done, duration: 2541 seconds
[14:19:32.275] <TB3> INFO: Decoding statistics:
[14:19:32.275] <TB3> INFO: General information:
[14:19:32.275] <TB3> INFO: 16bit words read: 0
[14:19:32.275] <TB3> INFO: valid events total: 0
[14:19:32.275] <TB3> INFO: empty events: 0
[14:19:32.276] <TB3> INFO: valid events with pixels: 0
[14:19:32.276] <TB3> INFO: valid pixel hits: 0
[14:19:32.276] <TB3> INFO: Event errors: 0
[14:19:32.276] <TB3> INFO: start marker: 0
[14:19:32.276] <TB3> INFO: stop marker: 0
[14:19:32.276] <TB3> INFO: overflow: 0
[14:19:32.276] <TB3> INFO: invalid 5bit words: 0
[14:19:32.276] <TB3> INFO: invalid XOR eye diagram: 0
[14:19:32.276] <TB3> INFO: frame (failed synchr.): 0
[14:19:32.276] <TB3> INFO: idle data (no TBM trl): 0
[14:19:32.276] <TB3> INFO: no data (only TBM hdr): 0
[14:19:32.276] <TB3> INFO: TBM errors: 0
[14:19:32.276] <TB3> INFO: flawed TBM headers: 0
[14:19:32.277] <TB3> INFO: flawed TBM trailers: 0
[14:19:32.277] <TB3> INFO: event ID mismatches: 0
[14:19:32.277] <TB3> INFO: ROC errors: 0
[14:19:32.277] <TB3> INFO: missing ROC header(s): 0
[14:19:32.277] <TB3> INFO: misplaced readback start: 0
[14:19:32.277] <TB3> INFO: Pixel decoding errors: 0
[14:19:32.277] <TB3> INFO: pixel data incomplete: 0
[14:19:32.277] <TB3> INFO: pixel address: 0
[14:19:32.277] <TB3> INFO: pulse height fill bit: 0
[14:19:32.277] <TB3> INFO: buffer corruption: 0
[14:19:32.900] <TB3> INFO: ######################################################################
[14:19:32.900] <TB3> INFO: PixTestPhOptimization::doTest() Ntrig = 10
[14:19:32.900] <TB3> INFO: ######################################################################
[14:19:33.141] <TB3> INFO: Expecting 41600 events.
[14:19:36.715] <TB3> INFO: 41600 events read in total (2982ms).
[14:19:36.716] <TB3> INFO: Test took 3815ms.
[14:19:37.181] <TB3> INFO: Expecting 41600 events.
[14:19:40.749] <TB3> INFO: 41600 events read in total (2976ms).
[14:19:40.750] <TB3> INFO: Test took 3830ms.
[14:19:41.040] <TB3> INFO: Expecting 41600 events.
[14:19:44.615] <TB3> INFO: 41600 events read in total (2983ms).
[14:19:44.616] <TB3> INFO: Test took 3841ms.
[14:19:44.905] <TB3> INFO: Expecting 41600 events.
[14:19:48.442] <TB3> INFO: 41600 events read in total (2945ms).
[14:19:48.443] <TB3> INFO: Test took 3803ms.
[14:19:48.732] <TB3> INFO: Expecting 41600 events.
[14:19:52.275] <TB3> INFO: 41600 events read in total (2950ms).
[14:19:52.276] <TB3> INFO: Test took 3809ms.
[14:19:52.566] <TB3> INFO: Expecting 41600 events.
[14:19:56.091] <TB3> INFO: 41600 events read in total (2934ms).
[14:19:56.092] <TB3> INFO: Test took 3792ms.
[14:19:56.382] <TB3> INFO: Expecting 41600 events.
[14:19:59.866] <TB3> INFO: 41600 events read in total (2892ms).
[14:19:59.867] <TB3> INFO: Test took 3750ms.
[14:20:00.158] <TB3> INFO: Expecting 41600 events.
[14:20:03.631] <TB3> INFO: 41600 events read in total (2881ms).
[14:20:03.632] <TB3> INFO: Test took 3740ms.
[14:20:03.921] <TB3> INFO: Expecting 41600 events.
[14:20:07.463] <TB3> INFO: 41600 events read in total (2951ms).
[14:20:07.464] <TB3> INFO: Test took 3808ms.
[14:20:07.758] <TB3> INFO: Expecting 41600 events.
[14:20:11.322] <TB3> INFO: 41600 events read in total (2972ms).
[14:20:11.323] <TB3> INFO: Test took 3830ms.
[14:20:11.615] <TB3> INFO: Expecting 41600 events.
[14:20:15.188] <TB3> INFO: 41600 events read in total (2981ms).
[14:20:15.189] <TB3> INFO: Test took 3839ms.
[14:20:15.484] <TB3> INFO: Expecting 41600 events.
[14:20:18.985] <TB3> INFO: 41600 events read in total (2910ms).
[14:20:18.986] <TB3> INFO: Test took 3770ms.
[14:20:19.276] <TB3> INFO: Expecting 41600 events.
[14:20:22.865] <TB3> INFO: 41600 events read in total (2997ms).
[14:20:22.866] <TB3> INFO: Test took 3855ms.
[14:20:23.156] <TB3> INFO: Expecting 41600 events.
[14:20:26.750] <TB3> INFO: 41600 events read in total (3002ms).
[14:20:26.751] <TB3> INFO: Test took 3861ms.
[14:20:27.042] <TB3> INFO: Expecting 41600 events.
[14:20:30.600] <TB3> INFO: 41600 events read in total (2967ms).
[14:20:30.601] <TB3> INFO: Test took 3825ms.
[14:20:30.892] <TB3> INFO: Expecting 41600 events.
[14:20:34.367] <TB3> INFO: 41600 events read in total (2883ms).
[14:20:34.368] <TB3> INFO: Test took 3743ms.
[14:20:34.657] <TB3> INFO: Expecting 41600 events.
[14:20:38.194] <TB3> INFO: 41600 events read in total (2945ms).
[14:20:38.195] <TB3> INFO: Test took 3803ms.
[14:20:38.484] <TB3> INFO: Expecting 41600 events.
[14:20:41.989] <TB3> INFO: 41600 events read in total (2914ms).
[14:20:41.990] <TB3> INFO: Test took 3771ms.
[14:20:42.279] <TB3> INFO: Expecting 41600 events.
[14:20:45.785] <TB3> INFO: 41600 events read in total (2914ms).
[14:20:45.786] <TB3> INFO: Test took 3772ms.
[14:20:46.077] <TB3> INFO: Expecting 41600 events.
[14:20:49.563] <TB3> INFO: 41600 events read in total (2895ms).
[14:20:49.564] <TB3> INFO: Test took 3752ms.
[14:20:49.853] <TB3> INFO: Expecting 41600 events.
[14:20:53.411] <TB3> INFO: 41600 events read in total (2966ms).
[14:20:53.411] <TB3> INFO: Test took 3823ms.
[14:20:53.700] <TB3> INFO: Expecting 41600 events.
[14:20:57.328] <TB3> INFO: 41600 events read in total (3036ms).
[14:20:57.328] <TB3> INFO: Test took 3892ms.
[14:20:57.618] <TB3> INFO: Expecting 41600 events.
[14:21:01.199] <TB3> INFO: 41600 events read in total (2990ms).
[14:21:01.200] <TB3> INFO: Test took 3847ms.
[14:21:01.489] <TB3> INFO: Expecting 41600 events.
[14:21:05.194] <TB3> INFO: 41600 events read in total (3113ms).
[14:21:05.195] <TB3> INFO: Test took 3972ms.
[14:21:05.484] <TB3> INFO: Expecting 41600 events.
[14:21:09.050] <TB3> INFO: 41600 events read in total (2974ms).
[14:21:09.051] <TB3> INFO: Test took 3832ms.
[14:21:09.340] <TB3> INFO: Expecting 41600 events.
[14:21:12.833] <TB3> INFO: 41600 events read in total (2901ms).
[14:21:12.834] <TB3> INFO: Test took 3759ms.
[14:21:13.125] <TB3> INFO: Expecting 41600 events.
[14:21:16.775] <TB3> INFO: 41600 events read in total (3058ms).
[14:21:16.776] <TB3> INFO: Test took 3917ms.
[14:21:17.069] <TB3> INFO: Expecting 2560 events.
[14:21:17.952] <TB3> INFO: 2560 events read in total (292ms).
[14:21:17.953] <TB3> INFO: Test took 1161ms.
[14:21:18.260] <TB3> INFO: Expecting 2560 events.
[14:21:19.154] <TB3> INFO: 2560 events read in total (302ms).
[14:21:19.154] <TB3> INFO: Test took 1200ms.
[14:21:19.462] <TB3> INFO: Expecting 2560 events.
[14:21:20.347] <TB3> INFO: 2560 events read in total (293ms).
[14:21:20.347] <TB3> INFO: Test took 1192ms.
[14:21:20.655] <TB3> INFO: Expecting 2560 events.
[14:21:21.541] <TB3> INFO: 2560 events read in total (294ms).
[14:21:21.542] <TB3> INFO: Test took 1195ms.
[14:21:21.850] <TB3> INFO: Expecting 2560 events.
[14:21:22.729] <TB3> INFO: 2560 events read in total (287ms).
[14:21:22.729] <TB3> INFO: Test took 1187ms.
[14:21:23.038] <TB3> INFO: Expecting 2560 events.
[14:21:23.922] <TB3> INFO: 2560 events read in total (293ms).
[14:21:23.922] <TB3> INFO: Test took 1192ms.
[14:21:24.229] <TB3> INFO: Expecting 2560 events.
[14:21:25.113] <TB3> INFO: 2560 events read in total (292ms).
[14:21:25.113] <TB3> INFO: Test took 1190ms.
[14:21:25.420] <TB3> INFO: Expecting 2560 events.
[14:21:26.300] <TB3> INFO: 2560 events read in total (288ms).
[14:21:26.300] <TB3> INFO: Test took 1186ms.
[14:21:26.608] <TB3> INFO: Expecting 2560 events.
[14:21:27.492] <TB3> INFO: 2560 events read in total (293ms).
[14:21:27.492] <TB3> INFO: Test took 1191ms.
[14:21:27.801] <TB3> INFO: Expecting 2560 events.
[14:21:28.682] <TB3> INFO: 2560 events read in total (289ms).
[14:21:28.683] <TB3> INFO: Test took 1190ms.
[14:21:28.991] <TB3> INFO: Expecting 2560 events.
[14:21:29.875] <TB3> INFO: 2560 events read in total (293ms).
[14:21:29.875] <TB3> INFO: Test took 1191ms.
[14:21:30.182] <TB3> INFO: Expecting 2560 events.
[14:21:31.070] <TB3> INFO: 2560 events read in total (296ms).
[14:21:31.070] <TB3> INFO: Test took 1194ms.
[14:21:31.378] <TB3> INFO: Expecting 2560 events.
[14:21:32.271] <TB3> INFO: 2560 events read in total (301ms).
[14:21:32.271] <TB3> INFO: Test took 1199ms.
[14:21:32.580] <TB3> INFO: Expecting 2560 events.
[14:21:33.464] <TB3> INFO: 2560 events read in total (293ms).
[14:21:33.464] <TB3> INFO: Test took 1192ms.
[14:21:33.773] <TB3> INFO: Expecting 2560 events.
[14:21:34.659] <TB3> INFO: 2560 events read in total (294ms).
[14:21:34.659] <TB3> INFO: Test took 1194ms.
[14:21:34.967] <TB3> INFO: Expecting 2560 events.
[14:21:35.853] <TB3> INFO: 2560 events read in total (294ms).
[14:21:35.853] <TB3> INFO: Test took 1193ms.
[14:21:35.857] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[14:21:36.161] <TB3> INFO: Expecting 655360 events.
[14:21:51.026] <TB3> INFO: 655360 events read in total (14272ms).
[14:21:51.039] <TB3> INFO: Expecting 655360 events.
[14:22:05.779] <TB3> INFO: 655360 events read in total (14337ms).
[14:22:05.796] <TB3> INFO: Expecting 655360 events.
[14:22:20.504] <TB3> INFO: 655360 events read in total (14305ms).
[14:22:20.526] <TB3> INFO: Expecting 655360 events.
[14:22:35.199] <TB3> INFO: 655360 events read in total (14270ms).
[14:22:35.225] <TB3> INFO: Expecting 655360 events.
[14:22:49.905] <TB3> INFO: 655360 events read in total (14277ms).
[14:22:49.935] <TB3> INFO: Expecting 655360 events.
[14:23:04.952] <TB3> INFO: 655360 events read in total (14614ms).
[14:23:04.989] <TB3> INFO: Expecting 655360 events.
[14:23:19.795] <TB3> INFO: 655360 events read in total (14403ms).
[14:23:19.834] <TB3> INFO: Expecting 655360 events.
[14:23:34.749] <TB3> INFO: 655360 events read in total (14512ms).
[14:23:34.805] <TB3> INFO: Expecting 655360 events.
[14:23:49.591] <TB3> INFO: 655360 events read in total (14383ms).
[14:23:49.650] <TB3> INFO: Expecting 655360 events.
[14:24:04.513] <TB3> INFO: 655360 events read in total (14460ms).
[14:24:04.646] <TB3> INFO: Expecting 655360 events.
[14:24:19.323] <TB3> INFO: 655360 events read in total (14274ms).
[14:24:19.383] <TB3> INFO: Expecting 655360 events.
[14:24:34.093] <TB3> INFO: 655360 events read in total (14307ms).
[14:24:34.198] <TB3> INFO: Expecting 655360 events.
[14:24:49.095] <TB3> INFO: 655360 events read in total (14494ms).
[14:24:49.174] <TB3> INFO: Expecting 655360 events.
[14:25:03.886] <TB3> INFO: 655360 events read in total (14309ms).
[14:25:03.994] <TB3> INFO: Expecting 655360 events.
[14:25:18.711] <TB3> INFO: 655360 events read in total (14314ms).
[14:25:18.825] <TB3> INFO: Expecting 655360 events.
[14:25:33.520] <TB3> INFO: 655360 events read in total (14292ms).
[14:25:33.635] <TB3> INFO: Test took 237778ms.
[14:25:33.731] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[14:25:33.988] <TB3> INFO: Expecting 655360 events.
[14:25:48.562] <TB3> INFO: 655360 events read in total (13983ms).
[14:25:48.574] <TB3> INFO: Expecting 655360 events.
[14:26:02.897] <TB3> INFO: 655360 events read in total (13920ms).
[14:26:02.917] <TB3> INFO: Expecting 655360 events.
[14:26:17.462] <TB3> INFO: 655360 events read in total (14142ms).
[14:26:17.494] <TB3> INFO: Expecting 655360 events.
[14:26:31.937] <TB3> INFO: 655360 events read in total (14040ms).
[14:26:31.969] <TB3> INFO: Expecting 655360 events.
[14:26:46.536] <TB3> INFO: 655360 events read in total (14164ms).
[14:26:46.569] <TB3> INFO: Expecting 655360 events.
[14:27:01.171] <TB3> INFO: 655360 events read in total (14199ms).
[14:27:01.207] <TB3> INFO: Expecting 655360 events.
[14:27:15.382] <TB3> INFO: 655360 events read in total (13772ms).
[14:27:15.421] <TB3> INFO: Expecting 655360 events.
[14:27:30.092] <TB3> INFO: 655360 events read in total (14268ms).
[14:27:30.157] <TB3> INFO: Expecting 655360 events.
[14:27:44.683] <TB3> INFO: 655360 events read in total (14122ms).
[14:27:44.734] <TB3> INFO: Expecting 655360 events.
[14:27:59.359] <TB3> INFO: 655360 events read in total (14221ms).
[14:27:59.422] <TB3> INFO: Expecting 655360 events.
[14:28:13.649] <TB3> INFO: 655360 events read in total (13824ms).
[14:28:13.724] <TB3> INFO: Expecting 655360 events.
[14:28:28.024] <TB3> INFO: 655360 events read in total (13897ms).
[14:28:28.101] <TB3> INFO: Expecting 655360 events.
[14:28:42.354] <TB3> INFO: 655360 events read in total (13850ms).
[14:28:42.485] <TB3> INFO: Expecting 655360 events.
[14:28:57.081] <TB3> INFO: 655360 events read in total (14193ms).
[14:28:57.169] <TB3> INFO: Expecting 655360 events.
[14:29:11.946] <TB3> INFO: 655360 events read in total (14374ms).
[14:29:12.038] <TB3> INFO: Expecting 655360 events.
[14:29:26.577] <TB3> INFO: 655360 events read in total (14136ms).
[14:29:26.770] <TB3> INFO: Test took 233039ms.
[14:29:26.934] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[14:29:26.940] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[14:29:26.946] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[14:29:26.952] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[14:29:26.957] <TB3> INFO: safety margin for low PH: adding 1, margin is now 21
[14:29:26.963] <TB3> INFO: safety margin for low PH: adding 2, margin is now 22
[14:29:26.969] <TB3> INFO: safety margin for low PH: adding 3, margin is now 23
[14:29:26.974] <TB3> INFO: safety margin for low PH: adding 4, margin is now 24
[14:29:26.980] <TB3> INFO: safety margin for low PH: adding 5, margin is now 25
[14:29:26.986] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[14:29:26.991] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[14:29:26.997] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[14:29:26.003] <TB3> INFO: safety margin for low PH: adding 1, margin is now 21
[14:29:27.008] <TB3> INFO: safety margin for low PH: adding 2, margin is now 22
[14:29:27.014] <TB3> INFO: safety margin for low PH: adding 3, margin is now 23
[14:29:27.020] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[14:29:27.026] <TB3> INFO: safety margin for low PH: adding 1, margin is now 21
[14:29:27.031] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[14:29:27.037] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[14:29:27.043] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[14:29:27.049] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[14:29:27.054] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[14:29:27.060] <TB3> INFO: safety margin for low PH: adding 1, margin is now 21
[14:29:27.066] <TB3> INFO: safety margin for low PH: adding 2, margin is now 22
[14:29:27.071] <TB3> INFO: safety margin for low PH: adding 3, margin is now 23
[14:29:27.077] <TB3> INFO: safety margin for low PH: adding 4, margin is now 24
[14:29:27.083] <TB3> INFO: safety margin for low PH: adding 5, margin is now 25
[14:29:27.089] <TB3> INFO: safety margin for low PH: adding 6, margin is now 26
[14:29:27.094] <TB3> INFO: safety margin for low PH: adding 7, margin is now 27
[14:29:27.101] <TB3> INFO: safety margin for low PH: adding 8, margin is now 28
[14:29:27.107] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[14:29:27.113] <TB3> INFO: safety margin for low PH: adding 1, margin is now 21
[14:29:27.119] <TB3> INFO: safety margin for low PH: adding 2, margin is now 22
[14:29:27.124] <TB3> INFO: safety margin for low PH: adding 3, margin is now 23
[14:29:27.131] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[14:29:27.137] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[14:29:27.145] <TB3> INFO: safety margin for low PH: adding 1, margin is now 21
[14:29:27.153] <TB3> INFO: safety margin for low PH: adding 2, margin is now 22
[14:29:27.161] <TB3> INFO: safety margin for low PH: adding 3, margin is now 23
[14:29:27.169] <TB3> INFO: safety margin for low PH: adding 4, margin is now 24
[14:29:27.208] <TB3> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1047_FullQualification_2016-10-22_10h41m_1477125660//003_Fulltest_p17//dacParameters35_C0.dat
[14:29:27.208] <TB3> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1047_FullQualification_2016-10-22_10h41m_1477125660//003_Fulltest_p17//dacParameters35_C1.dat
[14:29:27.208] <TB3> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1047_FullQualification_2016-10-22_10h41m_1477125660//003_Fulltest_p17//dacParameters35_C2.dat
[14:29:27.208] <TB3> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1047_FullQualification_2016-10-22_10h41m_1477125660//003_Fulltest_p17//dacParameters35_C3.dat
[14:29:27.209] <TB3> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1047_FullQualification_2016-10-22_10h41m_1477125660//003_Fulltest_p17//dacParameters35_C4.dat
[14:29:27.209] <TB3> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1047_FullQualification_2016-10-22_10h41m_1477125660//003_Fulltest_p17//dacParameters35_C5.dat
[14:29:27.209] <TB3> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1047_FullQualification_2016-10-22_10h41m_1477125660//003_Fulltest_p17//dacParameters35_C6.dat
[14:29:27.209] <TB3> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1047_FullQualification_2016-10-22_10h41m_1477125660//003_Fulltest_p17//dacParameters35_C7.dat
[14:29:27.209] <TB3> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1047_FullQualification_2016-10-22_10h41m_1477125660//003_Fulltest_p17//dacParameters35_C8.dat
[14:29:27.210] <TB3> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1047_FullQualification_2016-10-22_10h41m_1477125660//003_Fulltest_p17//dacParameters35_C9.dat
[14:29:27.210] <TB3> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1047_FullQualification_2016-10-22_10h41m_1477125660//003_Fulltest_p17//dacParameters35_C10.dat
[14:29:27.210] <TB3> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1047_FullQualification_2016-10-22_10h41m_1477125660//003_Fulltest_p17//dacParameters35_C11.dat
[14:29:27.210] <TB3> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1047_FullQualification_2016-10-22_10h41m_1477125660//003_Fulltest_p17//dacParameters35_C12.dat
[14:29:27.210] <TB3> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1047_FullQualification_2016-10-22_10h41m_1477125660//003_Fulltest_p17//dacParameters35_C13.dat
[14:29:27.210] <TB3> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1047_FullQualification_2016-10-22_10h41m_1477125660//003_Fulltest_p17//dacParameters35_C14.dat
[14:29:27.211] <TB3> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1047_FullQualification_2016-10-22_10h41m_1477125660//003_Fulltest_p17//dacParameters35_C15.dat
[14:29:27.455] <TB3> INFO: Expecting 41600 events.
[14:29:30.688] <TB3> INFO: 41600 events read in total (2641ms).
[14:29:30.689] <TB3> INFO: Test took 3475ms.
[14:29:31.141] <TB3> INFO: Expecting 41600 events.
[14:29:34.214] <TB3> INFO: 41600 events read in total (2481ms).
[14:29:34.215] <TB3> INFO: Test took 3312ms.
[14:29:34.671] <TB3> INFO: Expecting 41600 events.
[14:29:37.832] <TB3> INFO: 41600 events read in total (2569ms).
[14:29:37.833] <TB3> INFO: Test took 3406ms.
[14:29:38.055] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[14:29:38.144] <TB3> INFO: Expecting 2560 events.
[14:29:39.039] <TB3> INFO: 2560 events read in total (303ms).
[14:29:39.040] <TB3> INFO: Test took 985ms.
[14:29:39.042] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[14:29:39.347] <TB3> INFO: Expecting 2560 events.
[14:29:40.234] <TB3> INFO: 2560 events read in total (295ms).
[14:29:40.235] <TB3> INFO: Test took 1193ms.
[14:29:40.237] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[14:29:40.544] <TB3> INFO: Expecting 2560 events.
[14:29:41.431] <TB3> INFO: 2560 events read in total (295ms).
[14:29:41.432] <TB3> INFO: Test took 1195ms.
[14:29:41.435] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[14:29:41.739] <TB3> INFO: Expecting 2560 events.
[14:29:42.632] <TB3> INFO: 2560 events read in total (302ms).
[14:29:42.633] <TB3> INFO: Test took 1198ms.
[14:29:42.636] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[14:29:42.941] <TB3> INFO: Expecting 2560 events.
[14:29:43.834] <TB3> INFO: 2560 events read in total (301ms).
[14:29:43.835] <TB3> INFO: Test took 1200ms.
[14:29:43.838] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[14:29:44.143] <TB3> INFO: Expecting 2560 events.
[14:29:45.039] <TB3> INFO: 2560 events read in total (304ms).
[14:29:45.040] <TB3> INFO: Test took 1202ms.
[14:29:45.043] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[14:29:45.348] <TB3> INFO: Expecting 2560 events.
[14:29:46.242] <TB3> INFO: 2560 events read in total (302ms).
[14:29:46.242] <TB3> INFO: Test took 1199ms.
[14:29:46.244] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[14:29:46.551] <TB3> INFO: Expecting 2560 events.
[14:29:47.441] <TB3> INFO: 2560 events read in total (298ms).
[14:29:47.441] <TB3> INFO: Test took 1197ms.
[14:29:47.444] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[14:29:47.749] <TB3> INFO: Expecting 2560 events.
[14:29:48.640] <TB3> INFO: 2560 events read in total (299ms).
[14:29:48.641] <TB3> INFO: Test took 1197ms.
[14:29:48.643] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[14:29:48.948] <TB3> INFO: Expecting 2560 events.
[14:29:49.828] <TB3> INFO: 2560 events read in total (288ms).
[14:29:49.829] <TB3> INFO: Test took 1186ms.
[14:29:49.832] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[14:29:50.135] <TB3> INFO: Expecting 2560 events.
[14:29:51.019] <TB3> INFO: 2560 events read in total (292ms).
[14:29:51.020] <TB3> INFO: Test took 1188ms.
[14:29:51.023] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[14:29:51.326] <TB3> INFO: Expecting 2560 events.
[14:29:52.207] <TB3> INFO: 2560 events read in total (289ms).
[14:29:52.208] <TB3> INFO: Test took 1185ms.
[14:29:52.210] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[14:29:52.515] <TB3> INFO: Expecting 2560 events.
[14:29:53.398] <TB3> INFO: 2560 events read in total (291ms).
[14:29:53.399] <TB3> INFO: Test took 1189ms.
[14:29:53.403] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[14:29:53.707] <TB3> INFO: Expecting 2560 events.
[14:29:54.587] <TB3> INFO: 2560 events read in total (289ms).
[14:29:54.587] <TB3> INFO: Test took 1184ms.
[14:29:54.590] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[14:29:54.894] <TB3> INFO: Expecting 2560 events.
[14:29:55.777] <TB3> INFO: 2560 events read in total (292ms).
[14:29:55.777] <TB3> INFO: Test took 1187ms.
[14:29:55.779] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[14:29:56.086] <TB3> INFO: Expecting 2560 events.
[14:29:56.973] <TB3> INFO: 2560 events read in total (296ms).
[14:29:56.973] <TB3> INFO: Test took 1194ms.
[14:29:56.976] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[14:29:57.283] <TB3> INFO: Expecting 2560 events.
[14:29:58.164] <TB3> INFO: 2560 events read in total (289ms).
[14:29:58.164] <TB3> INFO: Test took 1189ms.
[14:29:58.168] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[14:29:58.471] <TB3> INFO: Expecting 2560 events.
[14:29:59.356] <TB3> INFO: 2560 events read in total (293ms).
[14:29:59.356] <TB3> INFO: Test took 1189ms.
[14:29:59.359] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[14:29:59.664] <TB3> INFO: Expecting 2560 events.
[14:30:00.556] <TB3> INFO: 2560 events read in total (300ms).
[14:30:00.556] <TB3> INFO: Test took 1197ms.
[14:30:00.559] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[14:30:00.866] <TB3> INFO: Expecting 2560 events.
[14:30:01.756] <TB3> INFO: 2560 events read in total (299ms).
[14:30:01.756] <TB3> INFO: Test took 1197ms.
[14:30:01.760] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[14:30:02.065] <TB3> INFO: Expecting 2560 events.
[14:30:02.950] <TB3> INFO: 2560 events read in total (294ms).
[14:30:02.950] <TB3> INFO: Test took 1190ms.
[14:30:02.955] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[14:30:03.259] <TB3> INFO: Expecting 2560 events.
[14:30:04.144] <TB3> INFO: 2560 events read in total (294ms).
[14:30:04.144] <TB3> INFO: Test took 1190ms.
[14:30:04.146] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[14:30:04.453] <TB3> INFO: Expecting 2560 events.
[14:30:05.343] <TB3> INFO: 2560 events read in total (298ms).
[14:30:05.343] <TB3> INFO: Test took 1197ms.
[14:30:05.347] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[14:30:05.651] <TB3> INFO: Expecting 2560 events.
[14:30:06.542] <TB3> INFO: 2560 events read in total (299ms).
[14:30:06.543] <TB3> INFO: Test took 1196ms.
[14:30:06.547] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[14:30:06.850] <TB3> INFO: Expecting 2560 events.
[14:30:07.743] <TB3> INFO: 2560 events read in total (301ms).
[14:30:07.743] <TB3> INFO: Test took 1196ms.
[14:30:07.745] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[14:30:08.052] <TB3> INFO: Expecting 2560 events.
[14:30:08.944] <TB3> INFO: 2560 events read in total (300ms).
[14:30:08.945] <TB3> INFO: Test took 1200ms.
[14:30:08.948] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[14:30:09.251] <TB3> INFO: Expecting 2560 events.
[14:30:10.137] <TB3> INFO: 2560 events read in total (294ms).
[14:30:10.137] <TB3> INFO: Test took 1189ms.
[14:30:10.141] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[14:30:10.445] <TB3> INFO: Expecting 2560 events.
[14:30:11.330] <TB3> INFO: 2560 events read in total (294ms).
[14:30:11.331] <TB3> INFO: Test took 1190ms.
[14:30:11.334] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[14:30:11.638] <TB3> INFO: Expecting 2560 events.
[14:30:12.532] <TB3> INFO: 2560 events read in total (302ms).
[14:30:12.533] <TB3> INFO: Test took 1200ms.
[14:30:12.535] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[14:30:12.841] <TB3> INFO: Expecting 2560 events.
[14:30:13.728] <TB3> INFO: 2560 events read in total (295ms).
[14:30:13.729] <TB3> INFO: Test took 1194ms.
[14:30:13.734] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[14:30:14.036] <TB3> INFO: Expecting 2560 events.
[14:30:14.933] <TB3> INFO: 2560 events read in total (305ms).
[14:30:14.933] <TB3> INFO: Test took 1200ms.
[14:30:14.936] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[14:30:15.242] <TB3> INFO: Expecting 2560 events.
[14:30:16.132] <TB3> INFO: 2560 events read in total (298ms).
[14:30:16.132] <TB3> INFO: Test took 1196ms.
[14:30:16.604] <TB3> INFO: PixTestPhOptimization::doTest() done, duration: 643 seconds
[14:30:16.604] <TB3> INFO: PH scale (per ROC): 38 48 39 43 48 42 46 42 45 45 57 39 41 40 42 35
[14:30:16.604] <TB3> INFO: PH offset (per ROC): 99 123 102 118 121 110 127 94 135 117 126 108 106 94 106 102
[14:30:16.614] <TB3> INFO: Decoding statistics:
[14:30:16.614] <TB3> INFO: General information:
[14:30:16.614] <TB3> INFO: 16bit words read: 127862
[14:30:16.614] <TB3> INFO: valid events total: 20480
[14:30:16.614] <TB3> INFO: empty events: 17989
[14:30:16.614] <TB3> INFO: valid events with pixels: 2491
[14:30:16.614] <TB3> INFO: valid pixel hits: 2491
[14:30:16.614] <TB3> INFO: Event errors: 0
[14:30:16.615] <TB3> INFO: start marker: 0
[14:30:16.615] <TB3> INFO: stop marker: 0
[14:30:16.615] <TB3> INFO: overflow: 0
[14:30:16.615] <TB3> INFO: invalid 5bit words: 0
[14:30:16.615] <TB3> INFO: invalid XOR eye diagram: 0
[14:30:16.615] <TB3> INFO: frame (failed synchr.): 0
[14:30:16.615] <TB3> INFO: idle data (no TBM trl): 0
[14:30:16.615] <TB3> INFO: no data (only TBM hdr): 0
[14:30:16.615] <TB3> INFO: TBM errors: 0
[14:30:16.615] <TB3> INFO: flawed TBM headers: 0
[14:30:16.615] <TB3> INFO: flawed TBM trailers: 0
[14:30:16.615] <TB3> INFO: event ID mismatches: 0
[14:30:16.615] <TB3> INFO: ROC errors: 0
[14:30:16.615] <TB3> INFO: missing ROC header(s): 0
[14:30:16.615] <TB3> INFO: misplaced readback start: 0
[14:30:16.615] <TB3> INFO: Pixel decoding errors: 0
[14:30:16.615] <TB3> INFO: pixel data incomplete: 0
[14:30:16.615] <TB3> INFO: pixel address: 0
[14:30:16.615] <TB3> INFO: pulse height fill bit: 0
[14:30:16.615] <TB3> INFO: buffer corruption: 0
[14:30:16.778] <TB3> INFO: ######################################################################
[14:30:16.778] <TB3> INFO: PixTestGainPedestal::fullTest() ntrig = 10
[14:30:16.778] <TB3> INFO: ######################################################################
[14:30:16.793] <TB3> INFO: scanning low vcal = 10
[14:30:17.033] <TB3> INFO: Expecting 41600 events.
[14:30:20.641] <TB3> INFO: 41600 events read in total (3017ms).
[14:30:20.641] <TB3> INFO: Test took 3848ms.
[14:30:20.643] <TB3> INFO: scanning low vcal = 20
[14:30:20.940] <TB3> INFO: Expecting 41600 events.
[14:30:24.510] <TB3> INFO: 41600 events read in total (2978ms).
[14:30:24.511] <TB3> INFO: Test took 3868ms.
[14:30:24.515] <TB3> INFO: scanning low vcal = 30
[14:30:24.812] <TB3> INFO: Expecting 41600 events.
[14:30:28.503] <TB3> INFO: 41600 events read in total (3099ms).
[14:30:28.504] <TB3> INFO: Test took 3989ms.
[14:30:28.507] <TB3> INFO: scanning low vcal = 40
[14:30:28.785] <TB3> INFO: Expecting 41600 events.
[14:30:32.785] <TB3> INFO: 41600 events read in total (3409ms).
[14:30:32.786] <TB3> INFO: Test took 4279ms.
[14:30:32.789] <TB3> INFO: scanning low vcal = 50
[14:30:33.066] <TB3> INFO: Expecting 41600 events.
[14:30:37.056] <TB3> INFO: 41600 events read in total (3398ms).
[14:30:37.057] <TB3> INFO: Test took 4268ms.
[14:30:37.060] <TB3> INFO: scanning low vcal = 60
[14:30:37.339] <TB3> INFO: Expecting 41600 events.
[14:30:41.345] <TB3> INFO: 41600 events read in total (3414ms).
[14:30:41.346] <TB3> INFO: Test took 4285ms.
[14:30:41.350] <TB3> INFO: scanning low vcal = 70
[14:30:41.626] <TB3> INFO: Expecting 41600 events.
[14:30:45.656] <TB3> INFO: 41600 events read in total (3438ms).
[14:30:45.657] <TB3> INFO: Test took 4307ms.
[14:30:45.660] <TB3> INFO: scanning low vcal = 80
[14:30:45.937] <TB3> INFO: Expecting 41600 events.
[14:30:49.940] <TB3> INFO: 41600 events read in total (3412ms).
[14:30:49.941] <TB3> INFO: Test took 4281ms.
[14:30:49.944] <TB3> INFO: scanning low vcal = 90
[14:30:50.221] <TB3> INFO: Expecting 41600 events.
[14:30:54.217] <TB3> INFO: 41600 events read in total (3404ms).
[14:30:54.218] <TB3> INFO: Test took 4274ms.
[14:30:54.221] <TB3> INFO: scanning low vcal = 100
[14:30:54.497] <TB3> INFO: Expecting 41600 events.
[14:30:58.496] <TB3> INFO: 41600 events read in total (3407ms).
[14:30:58.496] <TB3> INFO: Test took 4274ms.
[14:30:58.499] <TB3> INFO: scanning low vcal = 110
[14:30:58.776] <TB3> INFO: Expecting 41600 events.
[14:31:02.828] <TB3> INFO: 41600 events read in total (3460ms).
[14:31:02.828] <TB3> INFO: Test took 4329ms.
[14:31:02.831] <TB3> INFO: scanning low vcal = 120
[14:31:03.111] <TB3> INFO: Expecting 41600 events.
[14:31:07.160] <TB3> INFO: 41600 events read in total (3458ms).
[14:31:07.161] <TB3> INFO: Test took 4329ms.
[14:31:07.164] <TB3> INFO: scanning low vcal = 130
[14:31:07.441] <TB3> INFO: Expecting 41600 events.
[14:31:11.521] <TB3> INFO: 41600 events read in total (3488ms).
[14:31:11.522] <TB3> INFO: Test took 4357ms.
[14:31:11.525] <TB3> INFO: scanning low vcal = 140
[14:31:11.807] <TB3> INFO: Expecting 41600 events.
[14:31:15.838] <TB3> INFO: 41600 events read in total (3439ms).
[14:31:15.839] <TB3> INFO: Test took 4314ms.
[14:31:15.842] <TB3> INFO: scanning low vcal = 150
[14:31:16.121] <TB3> INFO: Expecting 41600 events.
[14:31:20.187] <TB3> INFO: 41600 events read in total (3474ms).
[14:31:20.188] <TB3> INFO: Test took 4346ms.
[14:31:20.191] <TB3> INFO: scanning low vcal = 160
[14:31:20.468] <TB3> INFO: Expecting 41600 events.
[14:31:24.502] <TB3> INFO: 41600 events read in total (3442ms).
[14:31:24.502] <TB3> INFO: Test took 4311ms.
[14:31:24.506] <TB3> INFO: scanning low vcal = 170
[14:31:24.783] <TB3> INFO: Expecting 41600 events.
[14:31:28.795] <TB3> INFO: 41600 events read in total (3420ms).
[14:31:28.796] <TB3> INFO: Test took 4290ms.
[14:31:28.803] <TB3> INFO: scanning low vcal = 180
[14:31:29.078] <TB3> INFO: Expecting 41600 events.
[14:31:33.115] <TB3> INFO: 41600 events read in total (3446ms).
[14:31:33.116] <TB3> INFO: Test took 4312ms.
[14:31:33.120] <TB3> INFO: scanning low vcal = 190
[14:31:33.397] <TB3> INFO: Expecting 41600 events.
[14:31:37.448] <TB3> INFO: 41600 events read in total (3459ms).
[14:31:37.449] <TB3> INFO: Test took 4329ms.
[14:31:37.452] <TB3> INFO: scanning low vcal = 200
[14:31:37.729] <TB3> INFO: Expecting 41600 events.
[14:31:41.766] <TB3> INFO: 41600 events read in total (3445ms).
[14:31:41.767] <TB3> INFO: Test took 4315ms.
[14:31:41.770] <TB3> INFO: scanning low vcal = 210
[14:31:42.047] <TB3> INFO: Expecting 41600 events.
[14:31:45.981] <TB3> INFO: 41600 events read in total (3343ms).
[14:31:45.982] <TB3> INFO: Test took 4212ms.
[14:31:45.985] <TB3> INFO: scanning low vcal = 220
[14:31:46.262] <TB3> INFO: Expecting 41600 events.
[14:31:50.218] <TB3> INFO: 41600 events read in total (3364ms).
[14:31:50.219] <TB3> INFO: Test took 4233ms.
[14:31:50.222] <TB3> INFO: scanning low vcal = 230
[14:31:50.498] <TB3> INFO: Expecting 41600 events.
[14:31:54.469] <TB3> INFO: 41600 events read in total (3379ms).
[14:31:54.471] <TB3> INFO: Test took 4249ms.
[14:31:54.474] <TB3> INFO: scanning low vcal = 240
[14:31:54.750] <TB3> INFO: Expecting 41600 events.
[14:31:58.817] <TB3> INFO: 41600 events read in total (3475ms).
[14:31:58.818] <TB3> INFO: Test took 4344ms.
[14:31:58.821] <TB3> INFO: scanning low vcal = 250
[14:31:59.147] <TB3> INFO: Expecting 41600 events.
[14:32:03.105] <TB3> INFO: 41600 events read in total (3366ms).
[14:32:03.106] <TB3> INFO: Test took 4285ms.
[14:32:03.112] <TB3> INFO: scanning high vcal = 30 (= 210 in low range)
[14:32:03.424] <TB3> INFO: Expecting 41600 events.
[14:32:07.479] <TB3> INFO: 41600 events read in total (3464ms).
[14:32:07.480] <TB3> INFO: Test took 4368ms.
[14:32:07.483] <TB3> INFO: scanning high vcal = 50 (= 350 in low range)
[14:32:07.762] <TB3> INFO: Expecting 41600 events.
[14:32:11.756] <TB3> INFO: 41600 events read in total (3403ms).
[14:32:11.757] <TB3> INFO: Test took 4274ms.
[14:32:11.761] <TB3> INFO: scanning high vcal = 70 (= 490 in low range)
[14:32:12.041] <TB3> INFO: Expecting 41600 events.
[14:32:16.036] <TB3> INFO: 41600 events read in total (3403ms).
[14:32:16.037] <TB3> INFO: Test took 4276ms.
[14:32:16.041] <TB3> INFO: scanning high vcal = 90 (= 630 in low range)
[14:32:16.365] <TB3> INFO: Expecting 41600 events.
[14:32:20.412] <TB3> INFO: 41600 events read in total (3455ms).
[14:32:20.414] <TB3> INFO: Test took 4373ms.
[14:32:20.417] <TB3> INFO: scanning high vcal = 200 (= 1400 in low range)
[14:32:20.694] <TB3> INFO: Expecting 41600 events.
[14:32:24.695] <TB3> INFO: 41600 events read in total (3410ms).
[14:32:24.697] <TB3> INFO: Test took 4280ms.
[14:32:25.121] <TB3> INFO: PixTestGainPedestal::measure() done
[14:32:59.476] <TB3> INFO: PixTestGainPedestal::fit() done
[14:32:59.476] <TB3> INFO: non-linearity mean: 0.940 0.982 0.938 0.969 0.983 0.945 0.977 0.951 0.981 0.977 0.982 0.942 0.969 0.922 0.944 0.933
[14:32:59.476] <TB3> INFO: non-linearity RMS: 0.067 0.003 0.113 0.021 0.004 0.055 0.005 0.068 0.005 0.005 0.004 0.062 0.017 0.126 0.111 0.104
[14:32:59.476] <TB3> INFO: write gain/ped parameters into /home/l_tester/david/FullQualification/data/M1047_FullQualification_2016-10-22_10h41m_1477125660//003_Fulltest_p17//phCalibrationFitErr35_C0.dat
[14:32:59.489] <TB3> INFO: write gain/ped parameters into /home/l_tester/david/FullQualification/data/M1047_FullQualification_2016-10-22_10h41m_1477125660//003_Fulltest_p17//phCalibrationFitErr35_C1.dat
[14:32:59.503] <TB3> INFO: write gain/ped parameters into /home/l_tester/david/FullQualification/data/M1047_FullQualification_2016-10-22_10h41m_1477125660//003_Fulltest_p17//phCalibrationFitErr35_C2.dat
[14:32:59.517] <TB3> INFO: write gain/ped parameters into /home/l_tester/david/FullQualification/data/M1047_FullQualification_2016-10-22_10h41m_1477125660//003_Fulltest_p17//phCalibrationFitErr35_C3.dat
[14:32:59.531] <TB3> INFO: write gain/ped parameters into /home/l_tester/david/FullQualification/data/M1047_FullQualification_2016-10-22_10h41m_1477125660//003_Fulltest_p17//phCalibrationFitErr35_C4.dat
[14:32:59.544] <TB3> INFO: write gain/ped parameters into /home/l_tester/david/FullQualification/data/M1047_FullQualification_2016-10-22_10h41m_1477125660//003_Fulltest_p17//phCalibrationFitErr35_C5.dat
[14:32:59.556] <TB3> INFO: write gain/ped parameters into /home/l_tester/david/FullQualification/data/M1047_FullQualification_2016-10-22_10h41m_1477125660//003_Fulltest_p17//phCalibrationFitErr35_C6.dat
[14:32:59.569] <TB3> INFO: write gain/ped parameters into /home/l_tester/david/FullQualification/data/M1047_FullQualification_2016-10-22_10h41m_1477125660//003_Fulltest_p17//phCalibrationFitErr35_C7.dat
[14:32:59.583] <TB3> INFO: write gain/ped parameters into /home/l_tester/david/FullQualification/data/M1047_FullQualification_2016-10-22_10h41m_1477125660//003_Fulltest_p17//phCalibrationFitErr35_C8.dat
[14:32:59.596] <TB3> INFO: write gain/ped parameters into /home/l_tester/david/FullQualification/data/M1047_FullQualification_2016-10-22_10h41m_1477125660//003_Fulltest_p17//phCalibrationFitErr35_C9.dat
[14:32:59.609] <TB3> INFO: write gain/ped parameters into /home/l_tester/david/FullQualification/data/M1047_FullQualification_2016-10-22_10h41m_1477125660//003_Fulltest_p17//phCalibrationFitErr35_C10.dat
[14:32:59.622] <TB3> INFO: write gain/ped parameters into /home/l_tester/david/FullQualification/data/M1047_FullQualification_2016-10-22_10h41m_1477125660//003_Fulltest_p17//phCalibrationFitErr35_C11.dat
[14:32:59.636] <TB3> INFO: write gain/ped parameters into /home/l_tester/david/FullQualification/data/M1047_FullQualification_2016-10-22_10h41m_1477125660//003_Fulltest_p17//phCalibrationFitErr35_C12.dat
[14:32:59.648] <TB3> INFO: write gain/ped parameters into /home/l_tester/david/FullQualification/data/M1047_FullQualification_2016-10-22_10h41m_1477125660//003_Fulltest_p17//phCalibrationFitErr35_C13.dat
[14:32:59.661] <TB3> INFO: write gain/ped parameters into /home/l_tester/david/FullQualification/data/M1047_FullQualification_2016-10-22_10h41m_1477125660//003_Fulltest_p17//phCalibrationFitErr35_C14.dat
[14:32:59.674] <TB3> INFO: write gain/ped parameters into /home/l_tester/david/FullQualification/data/M1047_FullQualification_2016-10-22_10h41m_1477125660//003_Fulltest_p17//phCalibrationFitErr35_C15.dat
[14:32:59.687] <TB3> INFO: PixTestGainPedestal::fullTest() done, duration: 162 seconds
[14:32:59.687] <TB3> INFO: Decoding statistics:
[14:32:59.687] <TB3> INFO: General information:
[14:32:59.687] <TB3> INFO: 16bit words read: 3306358
[14:32:59.687] <TB3> INFO: valid events total: 332800
[14:32:59.687] <TB3> INFO: empty events: 671
[14:32:59.687] <TB3> INFO: valid events with pixels: 332129
[14:32:59.687] <TB3> INFO: valid pixel hits: 654779
[14:32:59.687] <TB3> INFO: Event errors: 0
[14:32:59.687] <TB3> INFO: start marker: 0
[14:32:59.687] <TB3> INFO: stop marker: 0
[14:32:59.687] <TB3> INFO: overflow: 0
[14:32:59.687] <TB3> INFO: invalid 5bit words: 0
[14:32:59.687] <TB3> INFO: invalid XOR eye diagram: 0
[14:32:59.687] <TB3> INFO: frame (failed synchr.): 0
[14:32:59.687] <TB3> INFO: idle data (no TBM trl): 0
[14:32:59.687] <TB3> INFO: no data (only TBM hdr): 0
[14:32:59.687] <TB3> INFO: TBM errors: 0
[14:32:59.687] <TB3> INFO: flawed TBM headers: 0
[14:32:59.687] <TB3> INFO: flawed TBM trailers: 0
[14:32:59.687] <TB3> INFO: event ID mismatches: 0
[14:32:59.687] <TB3> INFO: ROC errors: 0
[14:32:59.687] <TB3> INFO: missing ROC header(s): 0
[14:32:59.687] <TB3> INFO: misplaced readback start: 0
[14:32:59.687] <TB3> INFO: Pixel decoding errors: 0
[14:32:59.687] <TB3> INFO: pixel data incomplete: 0
[14:32:59.687] <TB3> INFO: pixel address: 0
[14:32:59.687] <TB3> INFO: pulse height fill bit: 0
[14:32:59.687] <TB3> INFO: buffer corruption: 0
[14:32:59.704] <TB3> INFO: Decoding statistics:
[14:32:59.704] <TB3> INFO: General information:
[14:32:59.704] <TB3> INFO: 16bit words read: 3435756
[14:32:59.704] <TB3> INFO: valid events total: 353536
[14:32:59.704] <TB3> INFO: empty events: 18916
[14:32:59.704] <TB3> INFO: valid events with pixels: 334620
[14:32:59.704] <TB3> INFO: valid pixel hits: 657270
[14:32:59.704] <TB3> INFO: Event errors: 0
[14:32:59.704] <TB3> INFO: start marker: 0
[14:32:59.704] <TB3> INFO: stop marker: 0
[14:32:59.704] <TB3> INFO: overflow: 0
[14:32:59.704] <TB3> INFO: invalid 5bit words: 0
[14:32:59.704] <TB3> INFO: invalid XOR eye diagram: 0
[14:32:59.704] <TB3> INFO: frame (failed synchr.): 0
[14:32:59.704] <TB3> INFO: idle data (no TBM trl): 0
[14:32:59.704] <TB3> INFO: no data (only TBM hdr): 0
[14:32:59.704] <TB3> INFO: TBM errors: 0
[14:32:59.704] <TB3> INFO: flawed TBM headers: 0
[14:32:59.704] <TB3> INFO: flawed TBM trailers: 0
[14:32:59.704] <TB3> INFO: event ID mismatches: 0
[14:32:59.704] <TB3> INFO: ROC errors: 0
[14:32:59.704] <TB3> INFO: missing ROC header(s): 0
[14:32:59.704] <TB3> INFO: misplaced readback start: 0
[14:32:59.704] <TB3> INFO: Pixel decoding errors: 0
[14:32:59.704] <TB3> INFO: pixel data incomplete: 0
[14:32:59.704] <TB3> INFO: pixel address: 0
[14:32:59.704] <TB3> INFO: pulse height fill bit: 0
[14:32:59.704] <TB3> INFO: buffer corruption: 0
[14:32:59.704] <TB3> INFO: enter test to run
[14:32:59.704] <TB3> INFO: test: exit no parameter change
[14:32:59.855] <TB3> QUIET: Connection to board 126 closed.
[14:32:59.856] <TB3> INFO: pXar: this is the end, my friend