[18:01:24.506] <TB1> INFO: *** Welcome to pxar ***
[18:01:24.506] <TB1> INFO: *** Today: 2016/10/24
[18:01:24.512] <TB1> INFO: *** Version: c8ba-dirty
[18:01:24.512] <TB1> INFO: readRocDacs: /home/l_tester/david/FullQualification/data/M1061_FullQualification_2016-10-24_16h14m_1477318445//001_Fulltest_m20//dacParameters_C0.dat .. /home/l_tester/david/FullQualification/data/M1061_FullQualification_2016-10-24_16h14m_1477318445//001_Fulltest_m20//dacParameters_C15.dat
[18:01:24.513] <TB1> INFO: readTbmDacs: /home/l_tester/david/FullQualification/data/M1061_FullQualification_2016-10-24_16h14m_1477318445//001_Fulltest_m20//tbmParameters_C0a.dat .. /home/l_tester/david/FullQualification/data/M1061_FullQualification_2016-10-24_16h14m_1477318445//001_Fulltest_m20//tbmParameters_C1b.dat
[18:01:24.513] <TB1> INFO: readMaskFile: /home/l_tester/david/FullQualification/data/M1061_FullQualification_2016-10-24_16h14m_1477318445//001_Fulltest_m20//defaultMaskFile.dat
[18:01:24.513] <TB1> INFO: readTrimFile: /home/l_tester/david/FullQualification/data/M1061_FullQualification_2016-10-24_16h14m_1477318445//001_Fulltest_m20//trimParameters_C0.dat .. /home/l_tester/david/FullQualification/data/M1061_FullQualification_2016-10-24_16h14m_1477318445//001_Fulltest_m20//trimParameters_C15.dat
[18:01:24.574] <TB1> INFO: clk: 4
[18:01:24.574] <TB1> INFO: ctr: 4
[18:01:24.574] <TB1> INFO: sda: 19
[18:01:24.574] <TB1> INFO: tin: 9
[18:01:24.574] <TB1> INFO: level: 15
[18:01:24.574] <TB1> INFO: triggerdelay: 0
[18:01:24.574] <TB1> QUIET: Instanciating API for pxar v2.7.6+55~gafdbfd9
[18:01:24.574] <TB1> INFO: Log level: INFO
[18:01:24.583] <TB1> INFO: Found DTB DTB_WXC03A
[18:01:24.595] <TB1> QUIET: Connection to board DTB_WXC03A opened.
[18:01:24.597] <TB1> INFO: DTB startup information
--- DTB info------------------------------------------
Board id: 154
HW version: DTB1.2
FW version: 4.6
SW version: 4.7
Options:
USB id: DTB_WXC03A
MAC address: 40D85511809A
Hostname: pixelDTB154
Comment:
------------------------------------------------------
[18:01:24.599] <TB1> INFO: RPC call hashes of host and DTB match: 486171790
[18:01:26.088] <TB1> INFO: DUT info:
[18:01:26.088] <TB1> INFO: The DUT currently contains the following objects:
[18:01:26.088] <TB1> INFO: 4 TBM Cores tbm10c (4 ON)
[18:01:26.088] <TB1> INFO: TBM Core alpha (0): 7 registers set
[18:01:26.088] <TB1> INFO: TBM Core beta (1): 7 registers set
[18:01:26.088] <TB1> INFO: TBM Core alpha (2): 7 registers set
[18:01:26.088] <TB1> INFO: TBM Core beta (3): 7 registers set
[18:01:26.088] <TB1> INFO: 16 ROCs proc600 (16 ON) with 4160 pixelConfigs
[18:01:26.088] <TB1> INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[18:01:26.088] <TB1> INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[18:01:26.088] <TB1> INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[18:01:26.088] <TB1> INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[18:01:26.088] <TB1> INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[18:01:26.088] <TB1> INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[18:01:26.089] <TB1> INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[18:01:26.089] <TB1> INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[18:01:26.089] <TB1> INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[18:01:26.089] <TB1> INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[18:01:26.089] <TB1> INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[18:01:26.089] <TB1> INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[18:01:26.089] <TB1> INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[18:01:26.089] <TB1> INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[18:01:26.089] <TB1> INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[18:01:26.089] <TB1> INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[18:01:26.489] <TB1> INFO: enter 'restricted' command line mode
[18:01:26.489] <TB1> INFO: enter test to run
[18:01:26.489] <TB1> INFO: test: pretest no parameter change
[18:01:26.489] <TB1> INFO: running: pretest
[18:01:26.495] <TB1> INFO: ######################################################################
[18:01:26.495] <TB1> INFO: PixTestPretest::doTest()
[18:01:26.495] <TB1> INFO: ######################################################################
[18:01:26.496] <TB1> INFO: ----------------------------------------------------------------------
[18:01:26.496] <TB1> INFO: PixTestPretest::programROC()
[18:01:26.496] <TB1> INFO: ----------------------------------------------------------------------
[18:01:44.509] <TB1> INFO: PixTestPretest::programROC() done: ROCs are all programmable
[18:01:44.509] <TB1> INFO: IA differences per ROC: 18.5 19.3 17.7 17.7 18.5 18.5 19.3 19.3 20.1 20.1 17.7 21.7 18.5 19.3 20.1 19.3
[18:01:44.569] <TB1> INFO: ----------------------------------------------------------------------
[18:01:44.569] <TB1> INFO: PixTestPretest::setVana() target Ia = 24 mA/ROC
[18:01:44.569] <TB1> INFO: ----------------------------------------------------------------------
[18:02:05.865] <TB1> INFO: PixTestPretest::setVana() done, Module Ia 395.5 mA = 24.7188 mA/ROC
[18:02:05.866] <TB1> INFO: i(loss) [mA/ROC]: 18.5 21.7 20.9 20.1 20.9 20.9 20.9 20.1 18.5 18.5 20.9 19.3 17.7 19.3 20.9 20.9
[18:02:05.900] <TB1> INFO: ----------------------------------------------------------------------
[18:02:05.900] <TB1> INFO: PixTestPretest::findTiming()
[18:02:05.900] <TB1> INFO: ----------------------------------------------------------------------
[18:02:05.900] <TB1> INFO: PixTestCmd::init()

[18:02:38.160] <TB1> INFO: TBM phases: 160MHz: 0, 400MHz: 2, TBM delays: ROC(0/1):5, header/trailer: 1, token: 1
[18:02:38.160] <TB1> INFO: (success/tries = 100/100), width = 3
[18:02:39.665] <TB1> INFO: ----------------------------------------------------------------------
[18:02:39.665] <TB1> INFO: PixTestPretest::findWorkingPixel()
[18:02:39.665] <TB1> INFO: ----------------------------------------------------------------------
[18:02:39.761] <TB1> INFO: Expecting 231680 events.
[18:02:49.793] <TB1> INFO: 231680 events read in total (9441ms).
[18:02:49.822] <TB1> INFO: Test took 10151ms.
[18:02:50.072] <TB1> INFO: Found working pixel in all ROCs: col/row = 12/22
[18:02:50.111] <TB1> INFO: ----------------------------------------------------------------------
[18:02:50.113] <TB1> INFO: PixTestPretest::setVthrCompCalDel()
[18:02:50.113] <TB1> INFO: ----------------------------------------------------------------------
[18:02:50.206] <TB1> INFO: Expecting 231680 events.
[18:03:00.333] <TB1> INFO: 231680 events read in total (9532ms).
[18:03:00.341] <TB1> INFO: Test took 10224ms.
[18:03:00.612] <TB1> INFO: PixTestPretest::setVthrCompCalDel() done
[18:03:00.612] <TB1> INFO: CalDel: 109 110 122 97 89 97 98 91 120 106 108 115 97 130 130 110
[18:03:00.612] <TB1> INFO: VthrComp: 51 51 51 51 51 54 51 51 53 51 52 54 51 51 54 52
[18:03:00.615] <TB1> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1061_FullQualification_2016-10-24_16h14m_1477318445//001_Fulltest_m20//dacParameters_C0.dat
[18:03:00.615] <TB1> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1061_FullQualification_2016-10-24_16h14m_1477318445//001_Fulltest_m20//dacParameters_C1.dat
[18:03:00.616] <TB1> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1061_FullQualification_2016-10-24_16h14m_1477318445//001_Fulltest_m20//dacParameters_C2.dat
[18:03:00.616] <TB1> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1061_FullQualification_2016-10-24_16h14m_1477318445//001_Fulltest_m20//dacParameters_C3.dat
[18:03:00.616] <TB1> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1061_FullQualification_2016-10-24_16h14m_1477318445//001_Fulltest_m20//dacParameters_C4.dat
[18:03:00.616] <TB1> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1061_FullQualification_2016-10-24_16h14m_1477318445//001_Fulltest_m20//dacParameters_C5.dat
[18:03:00.616] <TB1> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1061_FullQualification_2016-10-24_16h14m_1477318445//001_Fulltest_m20//dacParameters_C6.dat
[18:03:00.617] <TB1> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1061_FullQualification_2016-10-24_16h14m_1477318445//001_Fulltest_m20//dacParameters_C7.dat
[18:03:00.617] <TB1> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1061_FullQualification_2016-10-24_16h14m_1477318445//001_Fulltest_m20//dacParameters_C8.dat
[18:03:00.617] <TB1> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1061_FullQualification_2016-10-24_16h14m_1477318445//001_Fulltest_m20//dacParameters_C9.dat
[18:03:00.617] <TB1> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1061_FullQualification_2016-10-24_16h14m_1477318445//001_Fulltest_m20//dacParameters_C10.dat
[18:03:00.618] <TB1> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1061_FullQualification_2016-10-24_16h14m_1477318445//001_Fulltest_m20//dacParameters_C11.dat
[18:03:00.618] <TB1> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1061_FullQualification_2016-10-24_16h14m_1477318445//001_Fulltest_m20//dacParameters_C12.dat
[18:03:00.618] <TB1> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1061_FullQualification_2016-10-24_16h14m_1477318445//001_Fulltest_m20//dacParameters_C13.dat
[18:03:00.618] <TB1> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1061_FullQualification_2016-10-24_16h14m_1477318445//001_Fulltest_m20//dacParameters_C14.dat
[18:03:00.618] <TB1> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1061_FullQualification_2016-10-24_16h14m_1477318445//001_Fulltest_m20//dacParameters_C15.dat
[18:03:00.619] <TB1> INFO: write tbm parameters into /home/l_tester/david/FullQualification/data/M1061_FullQualification_2016-10-24_16h14m_1477318445//001_Fulltest_m20//tbmParameters_C0a.dat
[18:03:00.619] <TB1> INFO: write tbm parameters into /home/l_tester/david/FullQualification/data/M1061_FullQualification_2016-10-24_16h14m_1477318445//001_Fulltest_m20//tbmParameters_C0b.dat
[18:03:00.619] <TB1> INFO: write tbm parameters into /home/l_tester/david/FullQualification/data/M1061_FullQualification_2016-10-24_16h14m_1477318445//001_Fulltest_m20//tbmParameters_C1a.dat
[18:03:00.619] <TB1> INFO: write tbm parameters into /home/l_tester/david/FullQualification/data/M1061_FullQualification_2016-10-24_16h14m_1477318445//001_Fulltest_m20//tbmParameters_C1b.dat
[18:03:00.619] <TB1> INFO: PixTestPretest::doTest() done, duration: 94 seconds
[18:03:00.692] <TB1> INFO: enter test to run
[18:03:00.692] <TB1> INFO: test: FullTest no parameter change
[18:03:00.692] <TB1> INFO: running: fulltest
[18:03:00.692] <TB1> INFO: ######################################################################
[18:03:00.692] <TB1> INFO: PixTestFullTest::doTest()
[18:03:00.692] <TB1> INFO: ######################################################################
[18:03:00.693] <TB1> INFO: ######################################################################
[18:03:00.693] <TB1> INFO: PixTestAlive::doTest()
[18:03:00.693] <TB1> INFO: ######################################################################
[18:03:00.694] <TB1> INFO: ----------------------------------------------------------------------
[18:03:00.694] <TB1> INFO: PixTestAlive::aliveTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[18:03:00.694] <TB1> INFO: ----------------------------------------------------------------------
[18:03:00.934] <TB1> INFO: Expecting 41600 events.
[18:03:04.583] <TB1> INFO: 41600 events read in total (3058ms).
[18:03:04.589] <TB1> INFO: Test took 3892ms.
[18:03:04.825] <TB1> INFO: PixTestAlive::aliveTest() done
[18:03:04.825] <TB1> INFO: number of dead pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[18:03:04.826] <TB1> INFO: ----------------------------------------------------------------------
[18:03:04.826] <TB1> INFO: PixTestAlive::maskTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[18:03:04.826] <TB1> INFO: ----------------------------------------------------------------------
[18:03:05.080] <TB1> INFO: Expecting 41600 events.
[18:03:08.182] <TB1> INFO: 41600 events read in total (2510ms).
[18:03:08.183] <TB1> INFO: Test took 3356ms.
[18:03:08.184] <TB1> INFO: mask vs. old pixelAlive PixelAlive_C0_V0 .. PixelAlive_C15_V0
[18:03:08.418] <TB1> INFO: PixTestAlive::maskTest() done
[18:03:08.419] <TB1> INFO: number of mask-defect pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[18:03:08.421] <TB1> INFO: ----------------------------------------------------------------------
[18:03:08.421] <TB1> INFO: PixTestAlive::addressDecodingTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[18:03:08.421] <TB1> INFO: ----------------------------------------------------------------------
[18:03:08.712] <TB1> INFO: Expecting 41600 events.
[18:03:12.300] <TB1> INFO: 41600 events read in total (2980ms).
[18:03:12.301] <TB1> INFO: Test took 3878ms.
[18:03:12.531] <TB1> INFO: PixTestAlive::addressDecodingTest() done
[18:03:12.531] <TB1> INFO: number of address-decoding pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[18:03:12.531] <TB1> INFO: PixTestAlive::doTest() done, duration: 11 seconds
[18:03:12.531] <TB1> INFO: Decoding statistics:
[18:03:12.531] <TB1> INFO: General information:
[18:03:12.531] <TB1> INFO: 16bit words read: 0
[18:03:12.531] <TB1> INFO: valid events total: 0
[18:03:12.531] <TB1> INFO: empty events: 0
[18:03:12.531] <TB1> INFO: valid events with pixels: 0
[18:03:12.531] <TB1> INFO: valid pixel hits: 0
[18:03:12.531] <TB1> INFO: Event errors: 0
[18:03:12.531] <TB1> INFO: start marker: 0
[18:03:12.531] <TB1> INFO: stop marker: 0
[18:03:12.531] <TB1> INFO: overflow: 0
[18:03:12.531] <TB1> INFO: invalid 5bit words: 0
[18:03:12.531] <TB1> INFO: invalid XOR eye diagram: 0
[18:03:12.531] <TB1> INFO: frame (failed synchr.): 0
[18:03:12.531] <TB1> INFO: idle data (no TBM trl): 0
[18:03:12.531] <TB1> INFO: no data (only TBM hdr): 0
[18:03:12.531] <TB1> INFO: TBM errors: 0
[18:03:12.531] <TB1> INFO: flawed TBM headers: 0
[18:03:12.531] <TB1> INFO: flawed TBM trailers: 0
[18:03:12.531] <TB1> INFO: event ID mismatches: 0
[18:03:12.531] <TB1> INFO: ROC errors: 0
[18:03:12.531] <TB1> INFO: missing ROC header(s): 0
[18:03:12.531] <TB1> INFO: misplaced readback start: 0
[18:03:12.531] <TB1> INFO: Pixel decoding errors: 0
[18:03:12.531] <TB1> INFO: pixel data incomplete: 0
[18:03:12.531] <TB1> INFO: pixel address: 0
[18:03:12.532] <TB1> INFO: pulse height fill bit: 0
[18:03:12.532] <TB1> INFO: buffer corruption: 0
[18:03:12.536] <TB1> INFO: readReadbackCal: /home/l_tester/david/FullQualification/data/M1061_FullQualification_2016-10-24_16h14m_1477318445//001_Fulltest_m20//readbackCal_C0.dat .. /home/l_tester/david/FullQualification/data/M1061_FullQualification_2016-10-24_16h14m_1477318445//001_Fulltest_m20//readbackCal_C15.dat
[18:03:12.537] <TB1> INFO: readGainPedestalParameters /home/l_tester/david/FullQualification/data/M1061_FullQualification_2016-10-24_16h14m_1477318445//001_Fulltest_m20//phCalibrationFitErr_C0.dat .. /home/l_tester/david/FullQualification/data/M1061_FullQualification_2016-10-24_16h14m_1477318445//001_Fulltest_m20//phCalibrationFitErr_C15.dat

[18:03:12.538] <TB1> INFO: ######################################################################
[18:03:12.538] <TB1> INFO: PixTestReadback::doTest()
[18:03:12.538] <TB1> INFO: ######################################################################
[18:03:12.538] <TB1> INFO: ----------------------------------------------------------------------
[18:03:12.538] <TB1> INFO: PixTestReadback::CalibrateVd()
[18:03:12.538] <TB1> INFO: ----------------------------------------------------------------------
[18:03:22.566] <TB1> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1061_FullQualification_2016-10-24_16h14m_1477318445//001_Fulltest_m20//readbackCal_C0.dat
[18:03:22.566] <TB1> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1061_FullQualification_2016-10-24_16h14m_1477318445//001_Fulltest_m20//readbackCal_C1.dat
[18:03:22.566] <TB1> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1061_FullQualification_2016-10-24_16h14m_1477318445//001_Fulltest_m20//readbackCal_C2.dat
[18:03:22.566] <TB1> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1061_FullQualification_2016-10-24_16h14m_1477318445//001_Fulltest_m20//readbackCal_C3.dat
[18:03:22.566] <TB1> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1061_FullQualification_2016-10-24_16h14m_1477318445//001_Fulltest_m20//readbackCal_C4.dat
[18:03:22.566] <TB1> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1061_FullQualification_2016-10-24_16h14m_1477318445//001_Fulltest_m20//readbackCal_C5.dat
[18:03:22.566] <TB1> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1061_FullQualification_2016-10-24_16h14m_1477318445//001_Fulltest_m20//readbackCal_C6.dat
[18:03:22.567] <TB1> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1061_FullQualification_2016-10-24_16h14m_1477318445//001_Fulltest_m20//readbackCal_C7.dat
[18:03:22.567] <TB1> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1061_FullQualification_2016-10-24_16h14m_1477318445//001_Fulltest_m20//readbackCal_C8.dat
[18:03:22.567] <TB1> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1061_FullQualification_2016-10-24_16h14m_1477318445//001_Fulltest_m20//readbackCal_C9.dat
[18:03:22.567] <TB1> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1061_FullQualification_2016-10-24_16h14m_1477318445//001_Fulltest_m20//readbackCal_C10.dat
[18:03:22.567] <TB1> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1061_FullQualification_2016-10-24_16h14m_1477318445//001_Fulltest_m20//readbackCal_C11.dat
[18:03:22.567] <TB1> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1061_FullQualification_2016-10-24_16h14m_1477318445//001_Fulltest_m20//readbackCal_C12.dat
[18:03:22.567] <TB1> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1061_FullQualification_2016-10-24_16h14m_1477318445//001_Fulltest_m20//readbackCal_C13.dat
[18:03:22.567] <TB1> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1061_FullQualification_2016-10-24_16h14m_1477318445//001_Fulltest_m20//readbackCal_C14.dat
[18:03:22.567] <TB1> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1061_FullQualification_2016-10-24_16h14m_1477318445//001_Fulltest_m20//readbackCal_C15.dat
[18:03:22.598] <TB1> INFO: PixTestPattern:: pg_setup set to default.
[18:03:22.598] <TB1> INFO: ----------------------------------------------------------------------
[18:03:22.598] <TB1> INFO: PixTestReadback::CalibrateVa()
[18:03:22.598] <TB1> INFO: ----------------------------------------------------------------------
[18:03:32.552] <TB1> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1061_FullQualification_2016-10-24_16h14m_1477318445//001_Fulltest_m20//readbackCal_C0.dat
[18:03:32.572] <TB1> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1061_FullQualification_2016-10-24_16h14m_1477318445//001_Fulltest_m20//readbackCal_C1.dat
[18:03:32.572] <TB1> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1061_FullQualification_2016-10-24_16h14m_1477318445//001_Fulltest_m20//readbackCal_C2.dat
[18:03:32.572] <TB1> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1061_FullQualification_2016-10-24_16h14m_1477318445//001_Fulltest_m20//readbackCal_C3.dat
[18:03:32.572] <TB1> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1061_FullQualification_2016-10-24_16h14m_1477318445//001_Fulltest_m20//readbackCal_C4.dat
[18:03:32.572] <TB1> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1061_FullQualification_2016-10-24_16h14m_1477318445//001_Fulltest_m20//readbackCal_C5.dat
[18:03:32.572] <TB1> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1061_FullQualification_2016-10-24_16h14m_1477318445//001_Fulltest_m20//readbackCal_C6.dat
[18:03:32.572] <TB1> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1061_FullQualification_2016-10-24_16h14m_1477318445//001_Fulltest_m20//readbackCal_C7.dat
[18:03:32.573] <TB1> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1061_FullQualification_2016-10-24_16h14m_1477318445//001_Fulltest_m20//readbackCal_C8.dat
[18:03:32.573] <TB1> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1061_FullQualification_2016-10-24_16h14m_1477318445//001_Fulltest_m20//readbackCal_C9.dat
[18:03:32.573] <TB1> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1061_FullQualification_2016-10-24_16h14m_1477318445//001_Fulltest_m20//readbackCal_C10.dat
[18:03:32.573] <TB1> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1061_FullQualification_2016-10-24_16h14m_1477318445//001_Fulltest_m20//readbackCal_C11.dat
[18:03:32.573] <TB1> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1061_FullQualification_2016-10-24_16h14m_1477318445//001_Fulltest_m20//readbackCal_C12.dat
[18:03:32.573] <TB1> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1061_FullQualification_2016-10-24_16h14m_1477318445//001_Fulltest_m20//readbackCal_C13.dat
[18:03:32.573] <TB1> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1061_FullQualification_2016-10-24_16h14m_1477318445//001_Fulltest_m20//readbackCal_C14.dat
[18:03:32.574] <TB1> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1061_FullQualification_2016-10-24_16h14m_1477318445//001_Fulltest_m20//readbackCal_C15.dat
[18:03:32.605] <TB1> INFO: PixTestPattern:: pg_setup set to default.
[18:03:32.605] <TB1> INFO: ----------------------------------------------------------------------
[18:03:32.605] <TB1> INFO: PixTestReadback::readbackVbg()
[18:03:32.605] <TB1> INFO: ----------------------------------------------------------------------
[18:03:40.290] <TB1> INFO: PixTestPattern:: pg_setup set to default.
[18:03:40.290] <TB1> INFO: ----------------------------------------------------------------------
[18:03:40.290] <TB1> INFO: PixTestReadback::getCalibratedVbg()
[18:03:40.290] <TB1> INFO: ----------------------------------------------------------------------
[18:03:40.290] <TB1> INFO: Vbg will be calibrated using Vd calibration
[18:03:40.290] <TB1> INFO: /*/*/*/*::: ROC 0: uncalibrated Vbg = 160.8calibrated Vbg = 1.17167 :::*/*/*/*/
[18:03:40.290] <TB1> INFO: /*/*/*/*::: ROC 1: uncalibrated Vbg = 150.9calibrated Vbg = 1.18529 :::*/*/*/*/
[18:03:40.290] <TB1> INFO: /*/*/*/*::: ROC 2: uncalibrated Vbg = 163.1calibrated Vbg = 1.17445 :::*/*/*/*/
[18:03:40.290] <TB1> INFO: /*/*/*/*::: ROC 3: uncalibrated Vbg = 160calibrated Vbg = 1.17289 :::*/*/*/*/
[18:03:40.290] <TB1> INFO: /*/*/*/*::: ROC 4: uncalibrated Vbg = 155.1calibrated Vbg = 1.17449 :::*/*/*/*/
[18:03:40.290] <TB1> INFO: /*/*/*/*::: ROC 5: uncalibrated Vbg = 154.1calibrated Vbg = 1.17288 :::*/*/*/*/
[18:03:40.290] <TB1> INFO: /*/*/*/*::: ROC 6: uncalibrated Vbg = 156.6calibrated Vbg = 1.18189 :::*/*/*/*/
[18:03:40.290] <TB1> INFO: /*/*/*/*::: ROC 7: uncalibrated Vbg = 148.5calibrated Vbg = 1.17895 :::*/*/*/*/
[18:03:40.290] <TB1> INFO: /*/*/*/*::: ROC 8: uncalibrated Vbg = 157.6calibrated Vbg = 1.17989 :::*/*/*/*/
[18:03:40.290] <TB1> INFO: /*/*/*/*::: ROC 9: uncalibrated Vbg = 155.5calibrated Vbg = 1.17505 :::*/*/*/*/
[18:03:40.290] <TB1> INFO: /*/*/*/*::: ROC 10: uncalibrated Vbg = 163.4calibrated Vbg = 1.17113 :::*/*/*/*/
[18:03:40.290] <TB1> INFO: /*/*/*/*::: ROC 11: uncalibrated Vbg = 159calibrated Vbg = 1.16862 :::*/*/*/*/
[18:03:40.290] <TB1> INFO: /*/*/*/*::: ROC 12: uncalibrated Vbg = 150.9calibrated Vbg = 1.1736 :::*/*/*/*/
[18:03:40.290] <TB1> INFO: /*/*/*/*::: ROC 13: uncalibrated Vbg = 156calibrated Vbg = 1.17987 :::*/*/*/*/
[18:03:40.290] <TB1> INFO: /*/*/*/*::: ROC 14: uncalibrated Vbg = 156.8calibrated Vbg = 1.17861 :::*/*/*/*/
[18:03:40.290] <TB1> INFO: /*/*/*/*::: ROC 15: uncalibrated Vbg = 159.7calibrated Vbg = 1.17274 :::*/*/*/*/
[18:03:40.294] <TB1> INFO: ----------------------------------------------------------------------
[18:03:40.294] <TB1> INFO: PixTestReadback::CalibrateIa()
[18:03:40.295] <TB1> INFO: ----------------------------------------------------------------------
[18:06:21.121] <TB1> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1061_FullQualification_2016-10-24_16h14m_1477318445//001_Fulltest_m20//readbackCal_C0.dat
[18:06:21.121] <TB1> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1061_FullQualification_2016-10-24_16h14m_1477318445//001_Fulltest_m20//readbackCal_C1.dat
[18:06:21.121] <TB1> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1061_FullQualification_2016-10-24_16h14m_1477318445//001_Fulltest_m20//readbackCal_C2.dat
[18:06:21.121] <TB1> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1061_FullQualification_2016-10-24_16h14m_1477318445//001_Fulltest_m20//readbackCal_C3.dat
[18:06:21.121] <TB1> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1061_FullQualification_2016-10-24_16h14m_1477318445//001_Fulltest_m20//readbackCal_C4.dat
[18:06:21.121] <TB1> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1061_FullQualification_2016-10-24_16h14m_1477318445//001_Fulltest_m20//readbackCal_C5.dat
[18:06:21.121] <TB1> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1061_FullQualification_2016-10-24_16h14m_1477318445//001_Fulltest_m20//readbackCal_C6.dat
[18:06:21.121] <TB1> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1061_FullQualification_2016-10-24_16h14m_1477318445//001_Fulltest_m20//readbackCal_C7.dat
[18:06:21.121] <TB1> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1061_FullQualification_2016-10-24_16h14m_1477318445//001_Fulltest_m20//readbackCal_C8.dat
[18:06:21.121] <TB1> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1061_FullQualification_2016-10-24_16h14m_1477318445//001_Fulltest_m20//readbackCal_C9.dat
[18:06:21.121] <TB1> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1061_FullQualification_2016-10-24_16h14m_1477318445//001_Fulltest_m20//readbackCal_C10.dat
[18:06:21.121] <TB1> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1061_FullQualification_2016-10-24_16h14m_1477318445//001_Fulltest_m20//readbackCal_C11.dat
[18:06:21.121] <TB1> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1061_FullQualification_2016-10-24_16h14m_1477318445//001_Fulltest_m20//readbackCal_C12.dat
[18:06:21.122] <TB1> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1061_FullQualification_2016-10-24_16h14m_1477318445//001_Fulltest_m20//readbackCal_C13.dat
[18:06:21.122] <TB1> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1061_FullQualification_2016-10-24_16h14m_1477318445//001_Fulltest_m20//readbackCal_C14.dat
[18:06:21.122] <TB1> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1061_FullQualification_2016-10-24_16h14m_1477318445//001_Fulltest_m20//readbackCal_C15.dat
[18:06:21.152] <TB1> INFO: PixTestPattern:: pg_setup set to default.
[18:06:21.154] <TB1> INFO: PixTestReadback::doTest() done
[18:06:21.155] <TB1> INFO: Decoding statistics:
[18:06:21.155] <TB1> INFO: General information:
[18:06:21.155] <TB1> INFO: 16bit words read: 1536
[18:06:21.155] <TB1> INFO: valid events total: 256
[18:06:21.155] <TB1> INFO: empty events: 256
[18:06:21.155] <TB1> INFO: valid events with pixels: 0
[18:06:21.155] <TB1> INFO: valid pixel hits: 0
[18:06:21.155] <TB1> INFO: Event errors: 0
[18:06:21.155] <TB1> INFO: start marker: 0
[18:06:21.155] <TB1> INFO: stop marker: 0
[18:06:21.155] <TB1> INFO: overflow: 0
[18:06:21.155] <TB1> INFO: invalid 5bit words: 0
[18:06:21.155] <TB1> INFO: invalid XOR eye diagram: 0
[18:06:21.155] <TB1> INFO: frame (failed synchr.): 0
[18:06:21.155] <TB1> INFO: idle data (no TBM trl): 0
[18:06:21.155] <TB1> INFO: no data (only TBM hdr): 0
[18:06:21.155] <TB1> INFO: TBM errors: 0
[18:06:21.155] <TB1> INFO: flawed TBM headers: 0
[18:06:21.155] <TB1> INFO: flawed TBM trailers: 0
[18:06:21.155] <TB1> INFO: event ID mismatches: 0
[18:06:21.155] <TB1> INFO: ROC errors: 0
[18:06:21.155] <TB1> INFO: missing ROC header(s): 0
[18:06:21.155] <TB1> INFO: misplaced readback start: 0
[18:06:21.155] <TB1> INFO: Pixel decoding errors: 0
[18:06:21.155] <TB1> INFO: pixel data incomplete: 0
[18:06:21.155] <TB1> INFO: pixel address: 0
[18:06:21.155] <TB1> INFO: pulse height fill bit: 0
[18:06:21.155] <TB1> INFO: buffer corruption: 0
[18:06:21.225] <TB1> INFO: ######################################################################
[18:06:21.226] <TB1> INFO: PixTestBBMap::doTest() Ntrig = 5, VcalS = 250 (high range)
[18:06:21.226] <TB1> INFO: ######################################################################
[18:06:21.228] <TB1> INFO: ---> dac: VthrComp name: calSMap ntrig: 5 dacrange: 0 .. 149 (-1/-1) hits flags = 514 (plus default)
[18:06:21.264] <TB1> INFO: dacScan split into 1 runs with ntrig = 5
[18:06:21.264] <TB1> INFO: run 1 of 1
[18:06:21.505] <TB1> INFO: Expecting 3120000 events.
[18:06:53.120] <TB1> INFO: 658630 events read in total (31023ms).











[18:07:25.371] <TB1> INFO: 1312680 events read in total (63275ms).











[18:07:57.616] <TB1> INFO: 1965020 events read in total (95519ms).











[18:08:29.441] <TB1> INFO: 2617920 events read in total (127344ms).











[18:08:54.375] <TB1> INFO: 3120000 events read in total (152278ms).
[18:08:54.488] <TB1> INFO: Test took 153225ms.
[18:09:19.832] <TB1> INFO: PixTestBBMap::doTest() done with 1 decoding errors: , duration: 178 seconds
[18:09:19.832] <TB1> INFO: number of dead bumps (per ROC): 3 0 0 1 1 0 2 1 0 1 0 0 1 0 0 1
[18:09:19.832] <TB1> INFO: separation cut (per ROC): 101 112 96 105 113 126 113 109 106 105 108 119 101 99 113 116
[18:09:19.832] <TB1> INFO: Decoding statistics:
[18:09:19.832] <TB1> INFO: General information:
[18:09:19.832] <TB1> INFO: 16bit words read: 0
[18:09:19.832] <TB1> INFO: valid events total: 0
[18:09:19.832] <TB1> INFO: empty events: 0
[18:09:19.832] <TB1> INFO: valid events with pixels: 0
[18:09:19.832] <TB1> INFO: valid pixel hits: 0
[18:09:19.832] <TB1> INFO: Event errors: 0
[18:09:19.832] <TB1> INFO: start marker: 0
[18:09:19.832] <TB1> INFO: stop marker: 0
[18:09:19.832] <TB1> INFO: overflow: 0
[18:09:19.832] <TB1> INFO: invalid 5bit words: 0
[18:09:19.832] <TB1> INFO: invalid XOR eye diagram: 0
[18:09:19.832] <TB1> INFO: frame (failed synchr.): 0
[18:09:19.832] <TB1> INFO: idle data (no TBM trl): 0
[18:09:19.832] <TB1> INFO: no data (only TBM hdr): 0
[18:09:19.832] <TB1> INFO: TBM errors: 0
[18:09:19.832] <TB1> INFO: flawed TBM headers: 0
[18:09:19.832] <TB1> INFO: flawed TBM trailers: 0
[18:09:19.832] <TB1> INFO: event ID mismatches: 0
[18:09:19.832] <TB1> INFO: ROC errors: 0
[18:09:19.832] <TB1> INFO: missing ROC header(s): 0
[18:09:19.832] <TB1> INFO: misplaced readback start: 0
[18:09:19.832] <TB1> INFO: Pixel decoding errors: 0
[18:09:19.832] <TB1> INFO: pixel data incomplete: 0
[18:09:19.832] <TB1> INFO: pixel address: 0
[18:09:19.832] <TB1> INFO: pulse height fill bit: 0
[18:09:19.832] <TB1> INFO: buffer corruption: 0
[18:09:19.879] <TB1> INFO: ######################################################################
[18:09:19.879] <TB1> INFO: PixTestScurves::fullTest() ntrig = 50, dacs/step = -1, ntrig/step = -1
[18:09:19.879] <TB1> INFO: ######################################################################
[18:09:19.879] <TB1> INFO: ----------------------------------------------------------------------
[18:09:19.879] <TB1> INFO: PixTestScurves::scurves(Vcal), ntrig = 50, dacs/step = -1, ntrig/step = -1
[18:09:19.879] <TB1> INFO: ----------------------------------------------------------------------
[18:09:19.879] <TB1> INFO: ---> dac: Vcal name: scurveVcal ntrig: 50 dacrange: 25 .. 200 (-1/-1) hits flags = 528 (plus default)
[18:09:19.892] <TB1> INFO: dacScan split into 1 runs with ntrig = 50
[18:09:19.892] <TB1> INFO: run 1 of 1
[18:09:20.131] <TB1> INFO: Expecting 36608000 events.
[18:09:45.590] <TB1> INFO: 669800 events read in total (24867ms).
[18:10:09.642] <TB1> INFO: 1328500 events read in total (48919ms).
[18:10:34.628] <TB1> INFO: 1988500 events read in total (73905ms).
[18:10:57.194] <TB1> INFO: 2649900 events read in total (96471ms).
[18:11:21.885] <TB1> INFO: 3308100 events read in total (121162ms).
[18:11:46.397] <TB1> INFO: 3966100 events read in total (145674ms).
[18:12:11.366] <TB1> INFO: 4624850 events read in total (170643ms).
[18:12:35.584] <TB1> INFO: 5281800 events read in total (194861ms).
[18:13:00.746] <TB1> INFO: 5938300 events read in total (220023ms).
[18:13:25.427] <TB1> INFO: 6594100 events read in total (244704ms).
[18:13:50.084] <TB1> INFO: 7249350 events read in total (269361ms).
[18:14:14.857] <TB1> INFO: 7904750 events read in total (294134ms).
[18:14:39.062] <TB1> INFO: 8561100 events read in total (318339ms).
[18:15:03.682] <TB1> INFO: 9218200 events read in total (342959ms).
[18:15:28.227] <TB1> INFO: 9873350 events read in total (367504ms).
[18:15:52.790] <TB1> INFO: 10530850 events read in total (392067ms).
[18:16:17.185] <TB1> INFO: 11185600 events read in total (416462ms).
[18:16:41.527] <TB1> INFO: 11842750 events read in total (440804ms).
[18:17:06.230] <TB1> INFO: 12500100 events read in total (465507ms).
[18:17:30.836] <TB1> INFO: 13153700 events read in total (490113ms).
[18:17:55.488] <TB1> INFO: 13806150 events read in total (514765ms).
[18:18:19.923] <TB1> INFO: 14458800 events read in total (539200ms).
[18:18:44.938] <TB1> INFO: 15112450 events read in total (564215ms).
[18:19:09.569] <TB1> INFO: 15765200 events read in total (588846ms).
[18:19:33.777] <TB1> INFO: 16418250 events read in total (613054ms).
[18:19:58.629] <TB1> INFO: 17071300 events read in total (637906ms).
[18:20:23.046] <TB1> INFO: 17722800 events read in total (662323ms).
[18:20:48.355] <TB1> INFO: 18374250 events read in total (687632ms).
[18:21:12.927] <TB1> INFO: 19026700 events read in total (712204ms).
[18:21:37.713] <TB1> INFO: 19679600 events read in total (736990ms).
[18:22:02.474] <TB1> INFO: 20330550 events read in total (761751ms).
[18:22:26.902] <TB1> INFO: 20983050 events read in total (786179ms).
[18:22:52.089] <TB1> INFO: 21631800 events read in total (811366ms).
[18:23:16.203] <TB1> INFO: 22280200 events read in total (835480ms).
[18:23:41.005] <TB1> INFO: 22930050 events read in total (860282ms).
[18:24:05.771] <TB1> INFO: 23581150 events read in total (885048ms).
[18:24:30.581] <TB1> INFO: 24231950 events read in total (909858ms).
[18:24:55.253] <TB1> INFO: 24883500 events read in total (934530ms).
[18:25:19.568] <TB1> INFO: 25532900 events read in total (958845ms).
[18:25:44.189] <TB1> INFO: 26181900 events read in total (983466ms).
[18:26:08.432] <TB1> INFO: 26830100 events read in total (1007709ms).
[18:26:32.870] <TB1> INFO: 27482100 events read in total (1032147ms).
[18:26:57.459] <TB1> INFO: 28131400 events read in total (1056736ms).
[18:27:21.459] <TB1> INFO: 28782600 events read in total (1080736ms).
[18:27:46.320] <TB1> INFO: 29433600 events read in total (1105597ms).
[18:28:10.580] <TB1> INFO: 30084250 events read in total (1129857ms).
[18:28:35.240] <TB1> INFO: 30734950 events read in total (1154517ms).
[18:28:59.679] <TB1> INFO: 31385850 events read in total (1178956ms).
[18:29:24.044] <TB1> INFO: 32037100 events read in total (1203321ms).
[18:29:48.870] <TB1> INFO: 32688000 events read in total (1228147ms).
[18:30:13.161] <TB1> INFO: 33339250 events read in total (1252438ms).
[18:30:38.116] <TB1> INFO: 33990450 events read in total (1277393ms).
[18:31:02.373] <TB1> INFO: 34641500 events read in total (1301650ms).
[18:31:27.058] <TB1> INFO: 35292250 events read in total (1326335ms).
[18:31:51.776] <TB1> INFO: 35945800 events read in total (1351053ms).
[18:32:16.109] <TB1> INFO: 36608000 events read in total (1375386ms).
[18:32:16.213] <TB1> INFO: Test took 1376320ms.
[18:32:16.811] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[18:32:18.585] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[18:32:20.476] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[18:32:22.399] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[18:32:24.067] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[18:32:25.983] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[18:32:27.915] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[18:32:29.677] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[18:32:31.852] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[18:32:33.937] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[18:32:36.005] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[18:32:38.055] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[18:32:40.077] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[18:32:42.030] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[18:32:44.178] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[18:32:46.095] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[18:32:48.206] <TB1> INFO: PixTestScurves::scurves() done
[18:32:48.206] <TB1> INFO: Vcal mean: 113.25 115.97 111.21 115.26 114.98 132.72 122.31 110.85 120.95 121.67 119.09 130.38 104.34 108.59 127.76 127.36
[18:32:48.206] <TB1> INFO: Vcal RMS: 5.16 5.90 5.62 5.88 5.43 6.02 6.29 4.99 6.50 6.12 6.55 6.40 5.13 5.48 6.66 6.35
[18:32:48.206] <TB1> INFO: PixTestScurves::fullTest() done, duration: 1408 seconds
[18:32:48.206] <TB1> INFO: Decoding statistics:
[18:32:48.206] <TB1> INFO: General information:
[18:32:48.206] <TB1> INFO: 16bit words read: 0
[18:32:48.206] <TB1> INFO: valid events total: 0
[18:32:48.206] <TB1> INFO: empty events: 0
[18:32:48.206] <TB1> INFO: valid events with pixels: 0
[18:32:48.207] <TB1> INFO: valid pixel hits: 0
[18:32:48.207] <TB1> INFO: Event errors: 0
[18:32:48.207] <TB1> INFO: start marker: 0
[18:32:48.207] <TB1> INFO: stop marker: 0
[18:32:48.207] <TB1> INFO: overflow: 0
[18:32:48.207] <TB1> INFO: invalid 5bit words: 0
[18:32:48.207] <TB1> INFO: invalid XOR eye diagram: 0
[18:32:48.207] <TB1> INFO: frame (failed synchr.): 0
[18:32:48.207] <TB1> INFO: idle data (no TBM trl): 0
[18:32:48.207] <TB1> INFO: no data (only TBM hdr): 0
[18:32:48.207] <TB1> INFO: TBM errors: 0
[18:32:48.207] <TB1> INFO: flawed TBM headers: 0
[18:32:48.207] <TB1> INFO: flawed TBM trailers: 0
[18:32:48.207] <TB1> INFO: event ID mismatches: 0
[18:32:48.207] <TB1> INFO: ROC errors: 0
[18:32:48.207] <TB1> INFO: missing ROC header(s): 0
[18:32:48.207] <TB1> INFO: misplaced readback start: 0
[18:32:48.207] <TB1> INFO: Pixel decoding errors: 0
[18:32:48.207] <TB1> INFO: pixel data incomplete: 0
[18:32:48.207] <TB1> INFO: pixel address: 0
[18:32:48.207] <TB1> INFO: pulse height fill bit: 0
[18:32:48.207] <TB1> INFO: buffer corruption: 0
[18:32:48.304] <TB1> INFO: ######################################################################
[18:32:48.304] <TB1> INFO: PixTestTrim::doTest()
[18:32:48.304] <TB1> INFO: ######################################################################
[18:32:48.305] <TB1> INFO: ----------------------------------------------------------------------
[18:32:48.305] <TB1> INFO: PixTestTrim::trimTest() ntrig = 8, vcal = 35
[18:32:48.305] <TB1> INFO: ----------------------------------------------------------------------
[18:32:48.378] <TB1> INFO: ---> VthrComp thr map (minimal VthrComp)
[18:32:48.379] <TB1> INFO: ---> dac: vthrcomp name: TrimThr0 ntrig: 8 dacrange: 10 .. 160 (-1/-1) hits flags = 528 (plus default)
[18:32:48.392] <TB1> INFO: dacScan split into 1 runs with ntrig = 8
[18:32:48.392] <TB1> INFO: run 1 of 1
[18:32:48.636] <TB1> INFO: Expecting 5025280 events.
[18:33:22.442] <TB1> INFO: 822376 events read in total (33202ms).
[18:33:54.377] <TB1> INFO: 1641424 events read in total (65137ms).
[18:34:26.094] <TB1> INFO: 2457008 events read in total (96855ms).
[18:34:58.722] <TB1> INFO: 3270448 events read in total (129482ms).
[18:35:31.622] <TB1> INFO: 4081488 events read in total (162382ms).
[18:36:03.952] <TB1> INFO: 4890984 events read in total (194712ms).
[18:36:09.670] <TB1> INFO: 5025280 events read in total (200430ms).
[18:36:09.753] <TB1> INFO: Test took 201361ms.
[18:36:27.511] <TB1> INFO: ROC 0 VthrComp = 115
[18:36:27.512] <TB1> INFO: ROC 1 VthrComp = 124
[18:36:27.512] <TB1> INFO: ROC 2 VthrComp = 110
[18:36:27.512] <TB1> INFO: ROC 3 VthrComp = 118
[18:36:27.512] <TB1> INFO: ROC 4 VthrComp = 129
[18:36:27.512] <TB1> INFO: ROC 5 VthrComp = 140
[18:36:27.512] <TB1> INFO: ROC 6 VthrComp = 131
[18:36:27.513] <TB1> INFO: ROC 7 VthrComp = 121
[18:36:27.513] <TB1> INFO: ROC 8 VthrComp = 118
[18:36:27.513] <TB1> INFO: ROC 9 VthrComp = 125
[18:36:27.515] <TB1> INFO: ROC 10 VthrComp = 115
[18:36:27.515] <TB1> INFO: ROC 11 VthrComp = 132
[18:36:27.515] <TB1> INFO: ROC 12 VthrComp = 108
[18:36:27.515] <TB1> INFO: ROC 13 VthrComp = 111
[18:36:27.515] <TB1> INFO: ROC 14 VthrComp = 129
[18:36:27.516] <TB1> INFO: ROC 15 VthrComp = 126
[18:36:27.802] <TB1> INFO: Expecting 41600 events.
[18:36:31.547] <TB1> INFO: 41600 events read in total (3152ms).
[18:36:31.550] <TB1> INFO: Test took 4033ms.
[18:36:31.565] <TB1> INFO: ---> Vcal thr map (pixel with maximum Vcal thr)
[18:36:31.565] <TB1> INFO: ---> dac: vcal name: TrimThr1 ntrig: 8 dacrange: 10 .. 160 (-1/-1) hits flags = 528 (plus default)
[18:36:31.581] <TB1> INFO: dacScan split into 1 runs with ntrig = 8
[18:36:31.581] <TB1> INFO: run 1 of 1
[18:36:31.860] <TB1> INFO: Expecting 5025280 events.
[18:37:01.355] <TB1> INFO: 591344 events read in total (28896ms).
[18:37:29.296] <TB1> INFO: 1180856 events read in total (56837ms).
[18:37:56.866] <TB1> INFO: 1771392 events read in total (84407ms).
[18:38:25.071] <TB1> INFO: 2361232 events read in total (112612ms).
[18:38:53.163] <TB1> INFO: 2949384 events read in total (140704ms).
[18:39:20.703] <TB1> INFO: 3536960 events read in total (168244ms).
[18:39:48.618] <TB1> INFO: 4124056 events read in total (196159ms).
[18:40:16.878] <TB1> INFO: 4710088 events read in total (224419ms).
[18:40:32.509] <TB1> INFO: 5025280 events read in total (240050ms).
[18:40:32.700] <TB1> INFO: Test took 241119ms.
[18:40:59.369] <TB1> INFO: roc 0 with ID = 0 has maximal Vcal 60.4813 for pixel 23/5 mean/min/max = 46.209/31.7032/60.7147
[18:40:59.371] <TB1> INFO: roc 1 with ID = 1 has maximal Vcal 62.6941 for pixel 18/1 mean/min/max = 47.6177/32.515/62.7204
[18:40:59.371] <TB1> INFO: roc 2 with ID = 2 has maximal Vcal 68.1101 for pixel 0/9 mean/min/max = 50.479/32.791/68.1669
[18:40:59.372] <TB1> INFO: roc 3 with ID = 3 has maximal Vcal 61.6718 for pixel 2/3 mean/min/max = 46.4144/31.0422/61.7867
[18:40:59.378] <TB1> INFO: roc 4 with ID = 4 has maximal Vcal 60.0906 for pixel 0/16 mean/min/max = 46.1656/32.1738/60.1574
[18:40:59.379] <TB1> INFO: roc 5 with ID = 5 has maximal Vcal 65.9928 for pixel 5/15 mean/min/max = 51.4275/36.3005/66.5545
[18:40:59.379] <TB1> INFO: roc 6 with ID = 6 has maximal Vcal 60.813 for pixel 0/44 mean/min/max = 46.9576/33.083/60.8321
[18:40:59.380] <TB1> INFO: roc 7 with ID = 7 has maximal Vcal 58.6537 for pixel 5/2 mean/min/max = 45.3108/31.7381/58.8835
[18:40:59.381] <TB1> INFO: roc 8 with ID = 8 has maximal Vcal 66.6764 for pixel 21/3 mean/min/max = 48.5705/30.4098/66.7312
[18:40:59.382] <TB1> INFO: roc 9 with ID = 9 has maximal Vcal 61.721 for pixel 0/69 mean/min/max = 45.9518/30.1165/61.7872
[18:40:59.382] <TB1> INFO: roc 10 with ID = 10 has maximal Vcal 65.5262 for pixel 13/13 mean/min/max = 47.8208/30.0668/65.5747
[18:40:59.383] <TB1> INFO: roc 11 with ID = 11 has maximal Vcal 65.3065 for pixel 51/53 mean/min/max = 50.1084/34.6901/65.5266
[18:40:59.383] <TB1> INFO: roc 12 with ID = 12 has maximal Vcal 61.2803 for pixel 51/0 mean/min/max = 47.9285/34.5619/61.2951
[18:40:59.384] <TB1> INFO: roc 13 with ID = 13 has maximal Vcal 65.1356 for pixel 51/4 mean/min/max = 48.7946/32.4359/65.1533
[18:40:59.384] <TB1> INFO: roc 14 with ID = 14 has maximal Vcal 63.3723 for pixel 18/1 mean/min/max = 46.6636/29.7905/63.5367
[18:40:59.385] <TB1> INFO: roc 15 with ID = 15 has maximal Vcal 65.4325 for pixel 47/2 mean/min/max = 48.1527/30.4541/65.8512
[18:40:59.385] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[18:40:59.476] <TB1> INFO: Expecting 411648 events.
[18:41:09.467] <TB1> INFO: 411648 events read in total (9394ms).
[18:41:09.476] <TB1> INFO: Expecting 411648 events.
[18:41:18.967] <TB1> INFO: 411648 events read in total (9088ms).
[18:41:18.982] <TB1> INFO: Expecting 411648 events.
[18:41:28.948] <TB1> INFO: 411648 events read in total (9563ms).
[18:41:28.968] <TB1> INFO: Expecting 411648 events.
[18:41:38.765] <TB1> INFO: 411648 events read in total (9383ms).
[18:41:38.790] <TB1> INFO: Expecting 411648 events.
[18:41:48.449] <TB1> INFO: 411648 events read in total (9256ms).
[18:41:48.489] <TB1> INFO: Expecting 411648 events.
[18:41:58.226] <TB1> INFO: 411648 events read in total (9334ms).
[18:41:58.256] <TB1> INFO: Expecting 411648 events.
[18:42:07.986] <TB1> INFO: 411648 events read in total (9326ms).
[18:42:08.038] <TB1> INFO: Expecting 411648 events.
[18:42:17.780] <TB1> INFO: 411648 events read in total (9336ms).
[18:42:17.816] <TB1> INFO: Expecting 411648 events.
[18:42:27.468] <TB1> INFO: 411648 events read in total (9245ms).
[18:42:27.516] <TB1> INFO: Expecting 411648 events.
[18:42:37.269] <TB1> INFO: 411648 events read in total (9346ms).
[18:42:37.315] <TB1> INFO: Expecting 411648 events.
[18:42:47.014] <TB1> INFO: 411648 events read in total (9295ms).
[18:42:47.067] <TB1> INFO: Expecting 411648 events.
[18:42:56.678] <TB1> INFO: 411648 events read in total (9204ms).
[18:42:56.739] <TB1> INFO: Expecting 411648 events.
[18:43:06.433] <TB1> INFO: 411648 events read in total (9288ms).
[18:43:06.492] <TB1> INFO: Expecting 411648 events.
[18:43:16.214] <TB1> INFO: 411648 events read in total (9316ms).
[18:43:16.317] <TB1> INFO: Expecting 411648 events.
[18:43:26.087] <TB1> INFO: 411648 events read in total (9366ms).
[18:43:26.165] <TB1> INFO: Expecting 411648 events.
[18:43:35.948] <TB1> INFO: 411648 events read in total (9375ms).
[18:43:36.031] <TB1> INFO: Test took 156646ms.
[18:43:36.929] <TB1> INFO: ---> dac: vcal name: TrimThr2 ntrig: 8 dacrange: 0 .. 150 (-1/-1) hits flags = 528 (plus default)
[18:43:36.945] <TB1> INFO: dacScan split into 1 runs with ntrig = 8
[18:43:36.945] <TB1> INFO: run 1 of 1
[18:43:37.189] <TB1> INFO: Expecting 5025280 events.
[18:44:05.566] <TB1> INFO: 590016 events read in total (27785ms).
[18:44:33.825] <TB1> INFO: 1177288 events read in total (56045ms).
[18:45:02.078] <TB1> INFO: 1762880 events read in total (84298ms).
[18:45:30.010] <TB1> INFO: 2347448 events read in total (112229ms).
[18:45:58.249] <TB1> INFO: 2931528 events read in total (140468ms).
[18:46:26.636] <TB1> INFO: 3515280 events read in total (168855ms).
[18:46:54.219] <TB1> INFO: 4099544 events read in total (196438ms).
[18:47:22.498] <TB1> INFO: 4682808 events read in total (224717ms).
[18:47:39.381] <TB1> INFO: 5025280 events read in total (241600ms).
[18:47:39.540] <TB1> INFO: Test took 242595ms.
[18:48:05.918] <TB1> INFO: ---> TrimStepCorr4 extremal thresholds: 0.015254 .. 146.091769
[18:48:06.173] <TB1> INFO: Expecting 208000 events.
[18:48:16.154] <TB1> INFO: 208000 events read in total (9386ms).
[18:48:16.155] <TB1> INFO: Test took 10226ms.
[18:48:16.205] <TB1> INFO: ---> dac: vcal name: trimStepCorr4 ntrig: 8 dacrange: 0 .. 156 (-1/-1) hits flags = 528 (plus default)
[18:48:16.221] <TB1> INFO: dacScan split into 1 runs with ntrig = 8
[18:48:16.221] <TB1> INFO: run 1 of 1
[18:48:16.499] <TB1> INFO: Expecting 5224960 events.
[18:48:45.814] <TB1> INFO: 585088 events read in total (28723ms).
[18:49:13.700] <TB1> INFO: 1170360 events read in total (56610ms).
[18:49:41.383] <TB1> INFO: 1755464 events read in total (84293ms).
[18:50:09.928] <TB1> INFO: 2340808 events read in total (112837ms).
[18:50:37.299] <TB1> INFO: 2926216 events read in total (140208ms).
[18:51:02.996] <TB1> INFO: 3510968 events read in total (165905ms).
[18:51:31.321] <TB1> INFO: 4096304 events read in total (194230ms).
[18:51:58.675] <TB1> INFO: 4680272 events read in total (221584ms).
[18:52:25.114] <TB1> INFO: 5224960 events read in total (248023ms).
[18:52:25.298] <TB1> INFO: Test took 249077ms.
[18:52:52.513] <TB1> INFO: ---> TrimStepCorr2 extremal thresholds: 27.500000 .. 46.605529
[18:52:52.751] <TB1> INFO: Expecting 208000 events.
[18:53:02.949] <TB1> INFO: 208000 events read in total (9607ms).
[18:53:02.950] <TB1> INFO: Test took 10435ms.
[18:53:03.025] <TB1> INFO: ---> dac: vcal name: trimStepCorr2 ntrig: 8 dacrange: 17 .. 56 (-1/-1) hits flags = 528 (plus default)
[18:53:03.041] <TB1> INFO: dacScan split into 1 runs with ntrig = 8
[18:53:03.041] <TB1> INFO: run 1 of 1
[18:53:03.327] <TB1> INFO: Expecting 1331200 events.
[18:53:34.178] <TB1> INFO: 654256 events read in total (30259ms).
[18:54:03.102] <TB1> INFO: 1307448 events read in total (59183ms).
[18:54:04.684] <TB1> INFO: 1331200 events read in total (60766ms).
[18:54:04.721] <TB1> INFO: Test took 61680ms.
[18:54:20.387] <TB1> INFO: ---> TrimStepCorr1a extremal thresholds: 26.713533 .. 47.045422
[18:54:20.641] <TB1> INFO: Expecting 208000 events.
[18:54:32.060] <TB1> INFO: 208000 events read in total (10826ms).
[18:54:32.061] <TB1> INFO: Test took 11672ms.
[18:54:32.144] <TB1> INFO: ---> dac: vcal name: trimStepCorr1a ntrig: 8 dacrange: 16 .. 57 (-1/-1) hits flags = 528 (plus default)
[18:54:32.160] <TB1> INFO: dacScan split into 1 runs with ntrig = 8
[18:54:32.164] <TB1> INFO: run 1 of 1
[18:54:32.495] <TB1> INFO: Expecting 1397760 events.
[18:55:01.989] <TB1> INFO: 655632 events read in total (28903ms).
[18:55:32.108] <TB1> INFO: 1310376 events read in total (59022ms).
[18:55:36.455] <TB1> INFO: 1397760 events read in total (63369ms).
[18:55:36.485] <TB1> INFO: Test took 64321ms.
[18:55:49.146] <TB1> INFO: ---> TrimStepCorr1b extremal thresholds: 24.511527 .. 45.517422
[18:55:49.416] <TB1> INFO: Expecting 208000 events.
[18:56:00.688] <TB1> INFO: 208000 events read in total (10679ms).
[18:56:00.689] <TB1> INFO: Test took 11542ms.
[18:56:00.745] <TB1> INFO: ---> dac: vcal name: trimStepCorr1b ntrig: 8 dacrange: 14 .. 55 (-1/-1) hits flags = 528 (plus default)
[18:56:00.762] <TB1> INFO: dacScan split into 1 runs with ntrig = 8
[18:56:00.762] <TB1> INFO: run 1 of 1
[18:56:01.041] <TB1> INFO: Expecting 1397760 events.
[18:56:31.863] <TB1> INFO: 670936 events read in total (30229ms).
[18:57:00.033] <TB1> INFO: 1341864 events read in total (58399ms).
[18:57:02.873] <TB1> INFO: 1397760 events read in total (61240ms).
[18:57:02.902] <TB1> INFO: Test took 62140ms.
[18:57:15.262] <TB1> INFO: ---> TrimThrFinal extremal thresholds: 15 .. 55
[18:57:15.262] <TB1> INFO: ---> dac: vcal name: TrimThrFinal ntrig: 8 dacrange: 15 .. 55 (-1/-1) hits flags = 528 (plus default)
[18:57:15.278] <TB1> INFO: dacScan split into 1 runs with ntrig = 8
[18:57:15.278] <TB1> INFO: run 1 of 1
[18:57:15.518] <TB1> INFO: Expecting 1364480 events.
[18:57:47.012] <TB1> INFO: 667328 events read in total (30902ms).
[18:58:16.463] <TB1> INFO: 1333792 events read in total (60353ms).
[18:58:18.269] <TB1> INFO: 1364480 events read in total (62160ms).
[18:58:18.319] <TB1> INFO: Test took 63040ms.
[18:58:33.174] <TB1> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1061_FullQualification_2016-10-24_16h14m_1477318445//001_Fulltest_m20//dacParameters35_C0.dat
[18:58:33.174] <TB1> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1061_FullQualification_2016-10-24_16h14m_1477318445//001_Fulltest_m20//dacParameters35_C1.dat
[18:58:33.174] <TB1> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1061_FullQualification_2016-10-24_16h14m_1477318445//001_Fulltest_m20//dacParameters35_C2.dat
[18:58:33.177] <TB1> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1061_FullQualification_2016-10-24_16h14m_1477318445//001_Fulltest_m20//dacParameters35_C3.dat
[18:58:33.177] <TB1> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1061_FullQualification_2016-10-24_16h14m_1477318445//001_Fulltest_m20//dacParameters35_C4.dat
[18:58:33.177] <TB1> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1061_FullQualification_2016-10-24_16h14m_1477318445//001_Fulltest_m20//dacParameters35_C5.dat
[18:58:33.177] <TB1> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1061_FullQualification_2016-10-24_16h14m_1477318445//001_Fulltest_m20//dacParameters35_C6.dat
[18:58:33.178] <TB1> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1061_FullQualification_2016-10-24_16h14m_1477318445//001_Fulltest_m20//dacParameters35_C7.dat
[18:58:33.178] <TB1> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1061_FullQualification_2016-10-24_16h14m_1477318445//001_Fulltest_m20//dacParameters35_C8.dat
[18:58:33.178] <TB1> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1061_FullQualification_2016-10-24_16h14m_1477318445//001_Fulltest_m20//dacParameters35_C9.dat
[18:58:33.178] <TB1> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1061_FullQualification_2016-10-24_16h14m_1477318445//001_Fulltest_m20//dacParameters35_C10.dat
[18:58:33.178] <TB1> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1061_FullQualification_2016-10-24_16h14m_1477318445//001_Fulltest_m20//dacParameters35_C11.dat
[18:58:33.178] <TB1> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1061_FullQualification_2016-10-24_16h14m_1477318445//001_Fulltest_m20//dacParameters35_C12.dat
[18:58:33.178] <TB1> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1061_FullQualification_2016-10-24_16h14m_1477318445//001_Fulltest_m20//dacParameters35_C13.dat
[18:58:33.178] <TB1> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1061_FullQualification_2016-10-24_16h14m_1477318445//001_Fulltest_m20//dacParameters35_C14.dat
[18:58:33.178] <TB1> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1061_FullQualification_2016-10-24_16h14m_1477318445//001_Fulltest_m20//dacParameters35_C15.dat
[18:58:33.179] <TB1> INFO: write trim parameters into /home/l_tester/david/FullQualification/data/M1061_FullQualification_2016-10-24_16h14m_1477318445//001_Fulltest_m20//trimParameters35_C0.dat
[18:58:33.186] <TB1> INFO: write trim parameters into /home/l_tester/david/FullQualification/data/M1061_FullQualification_2016-10-24_16h14m_1477318445//001_Fulltest_m20//trimParameters35_C1.dat
[18:58:33.192] <TB1> INFO: write trim parameters into /home/l_tester/david/FullQualification/data/M1061_FullQualification_2016-10-24_16h14m_1477318445//001_Fulltest_m20//trimParameters35_C2.dat
[18:58:33.203] <TB1> INFO: write trim parameters into /home/l_tester/david/FullQualification/data/M1061_FullQualification_2016-10-24_16h14m_1477318445//001_Fulltest_m20//trimParameters35_C3.dat
[18:58:33.209] <TB1> INFO: write trim parameters into /home/l_tester/david/FullQualification/data/M1061_FullQualification_2016-10-24_16h14m_1477318445//001_Fulltest_m20//trimParameters35_C4.dat
[18:58:33.216] <TB1> INFO: write trim parameters into /home/l_tester/david/FullQualification/data/M1061_FullQualification_2016-10-24_16h14m_1477318445//001_Fulltest_m20//trimParameters35_C5.dat
[18:58:33.226] <TB1> INFO: write trim parameters into /home/l_tester/david/FullQualification/data/M1061_FullQualification_2016-10-24_16h14m_1477318445//001_Fulltest_m20//trimParameters35_C6.dat
[18:58:33.231] <TB1> INFO: write trim parameters into /home/l_tester/david/FullQualification/data/M1061_FullQualification_2016-10-24_16h14m_1477318445//001_Fulltest_m20//trimParameters35_C7.dat
[18:58:33.235] <TB1> INFO: write trim parameters into /home/l_tester/david/FullQualification/data/M1061_FullQualification_2016-10-24_16h14m_1477318445//001_Fulltest_m20//trimParameters35_C8.dat
[18:58:33.241] <TB1> INFO: write trim parameters into /home/l_tester/david/FullQualification/data/M1061_FullQualification_2016-10-24_16h14m_1477318445//001_Fulltest_m20//trimParameters35_C9.dat
[18:58:33.246] <TB1> INFO: write trim parameters into /home/l_tester/david/FullQualification/data/M1061_FullQualification_2016-10-24_16h14m_1477318445//001_Fulltest_m20//trimParameters35_C10.dat
[18:58:33.251] <TB1> INFO: write trim parameters into /home/l_tester/david/FullQualification/data/M1061_FullQualification_2016-10-24_16h14m_1477318445//001_Fulltest_m20//trimParameters35_C11.dat
[18:58:33.257] <TB1> INFO: write trim parameters into /home/l_tester/david/FullQualification/data/M1061_FullQualification_2016-10-24_16h14m_1477318445//001_Fulltest_m20//trimParameters35_C12.dat
[18:58:33.265] <TB1> INFO: write trim parameters into /home/l_tester/david/FullQualification/data/M1061_FullQualification_2016-10-24_16h14m_1477318445//001_Fulltest_m20//trimParameters35_C13.dat
[18:58:33.272] <TB1> INFO: write trim parameters into /home/l_tester/david/FullQualification/data/M1061_FullQualification_2016-10-24_16h14m_1477318445//001_Fulltest_m20//trimParameters35_C14.dat
[18:58:33.277] <TB1> INFO: write trim parameters into /home/l_tester/david/FullQualification/data/M1061_FullQualification_2016-10-24_16h14m_1477318445//001_Fulltest_m20//trimParameters35_C15.dat
[18:58:33.281] <TB1> INFO: PixTestTrim::trimTest() done
[18:58:33.283] <TB1> INFO: vtrim: 130 156 149 133 143 171 149 140 148 129 140 131 99 143 137 161
[18:58:33.283] <TB1> INFO: vthrcomp: 115 124 110 118 129 140 131 121 118 125 115 132 108 111 129 126
[18:58:33.283] <TB1> INFO: vcal mean: 34.94 34.97 35.09 34.97 34.94 34.98 34.96 34.93 35.23 34.90 34.96 34.98 35.03 34.97 34.97 34.92
[18:58:33.283] <TB1> INFO: vcal RMS: 1.10 1.06 1.28 1.12 1.06 1.16 0.99 1.05 1.38 1.17 1.40 1.06 0.98 1.08 1.22 1.13
[18:58:33.283] <TB1> INFO: bits mean: 9.71 9.00 8.73 9.41 9.11 7.76 9.26 9.59 9.70 9.39 9.95 7.74 8.04 8.56 9.66 9.70
[18:58:33.283] <TB1> INFO: bits RMS: 2.64 2.75 2.74 2.82 2.84 2.44 2.58 2.70 2.69 2.97 2.58 2.73 2.80 2.84 2.83 2.66
[18:58:33.291] <TB1> INFO: ----------------------------------------------------------------------
[18:58:33.292] <TB1> INFO: PixTestTrim::trimBitTest() ntrig = 5, vtrims = 254 126 63 32
[18:58:33.292] <TB1> INFO: ----------------------------------------------------------------------
[18:58:33.295] <TB1> INFO: ---> dac: Vcal name: TrimBitsThr0 ntrig: 5 dacrange: 0 .. 199 (-1/-1) hits flags = 528 (plus default)
[18:58:33.309] <TB1> INFO: dacScan split into 1 runs with ntrig = 5
[18:58:33.314] <TB1> INFO: run 1 of 1
[18:58:33.561] <TB1> INFO: Expecting 4160000 events.
[18:59:07.656] <TB1> INFO: 741290 events read in total (33503ms).
[18:59:40.729] <TB1> INFO: 1477720 events read in total (66576ms).
[19:00:14.223] <TB1> INFO: 2208670 events read in total (100070ms).
[19:00:47.674] <TB1> INFO: 2936405 events read in total (133521ms).
[19:01:21.325] <TB1> INFO: 3664560 events read in total (167172ms).
[19:01:43.248] <TB1> INFO: 4160000 events read in total (189095ms).
[19:01:43.342] <TB1> INFO: Test took 190028ms.
[19:02:13.833] <TB1> INFO: ---> dac: Vcal name: TrimThr_trim14 ntrig: 5 dacrange: 0 .. 202 (-1/-1) hits flags = 528 (plus default)
[19:02:13.848] <TB1> INFO: dacScan split into 1 runs with ntrig = 5
[19:02:13.851] <TB1> INFO: run 1 of 1
[19:02:14.151] <TB1> INFO: Expecting 4222400 events.
[19:02:47.910] <TB1> INFO: 712560 events read in total (33167ms).
[19:03:20.523] <TB1> INFO: 1420260 events read in total (65780ms).
[19:03:53.467] <TB1> INFO: 2123085 events read in total (98724ms).
[19:04:26.174] <TB1> INFO: 2823425 events read in total (131431ms).
[19:04:58.104] <TB1> INFO: 3523290 events read in total (163361ms).
[19:05:30.619] <TB1> INFO: 4222400 events read in total (195876ms).
[19:05:30.737] <TB1> INFO: Test took 196885ms.
[19:06:03.362] <TB1> INFO: ---> dac: Vcal name: TrimThr_trim13 ntrig: 5 dacrange: 0 .. 195 (-1/-1) hits flags = 528 (plus default)
[19:06:03.383] <TB1> INFO: dacScan split into 1 runs with ntrig = 5
[19:06:03.383] <TB1> INFO: run 1 of 1
[19:06:03.716] <TB1> INFO: Expecting 4076800 events.
[19:06:37.096] <TB1> INFO: 721250 events read in total (32788ms).
[19:07:09.614] <TB1> INFO: 1437410 events read in total (65306ms).
[19:07:42.398] <TB1> INFO: 2148255 events read in total (98090ms).
[19:08:14.934] <TB1> INFO: 2856455 events read in total (130626ms).
[19:08:47.700] <TB1> INFO: 3564590 events read in total (163392ms).
[19:09:10.889] <TB1> INFO: 4076800 events read in total (186581ms).
[19:09:11.013] <TB1> INFO: Test took 187629ms.
[19:09:38.280] <TB1> INFO: ---> dac: Vcal name: TrimThr_trim11 ntrig: 5 dacrange: 0 .. 193 (-1/-1) hits flags = 528 (plus default)
[19:09:38.297] <TB1> INFO: dacScan split into 1 runs with ntrig = 5
[19:09:38.297] <TB1> INFO: run 1 of 1
[19:09:38.568] <TB1> INFO: Expecting 4035200 events.
[19:10:12.410] <TB1> INFO: 723720 events read in total (33249ms).
[19:10:45.766] <TB1> INFO: 1442275 events read in total (66605ms).
[19:11:18.841] <TB1> INFO: 2156035 events read in total (99680ms).
[19:11:51.089] <TB1> INFO: 2866490 events read in total (131928ms).
[19:12:23.796] <TB1> INFO: 3577315 events read in total (164635ms).
[19:12:45.075] <TB1> INFO: 4035200 events read in total (185914ms).
[19:12:45.196] <TB1> INFO: Test took 186899ms.
[19:13:19.611] <TB1> INFO: ---> dac: Vcal name: TrimThr_trim7 ntrig: 5 dacrange: 0 .. 195 (-1/-1) hits flags = 528 (plus default)
[19:13:19.625] <TB1> INFO: dacScan split into 1 runs with ntrig = 5
[19:13:19.625] <TB1> INFO: run 1 of 1
[19:13:19.958] <TB1> INFO: Expecting 4076800 events.
[19:13:53.117] <TB1> INFO: 721330 events read in total (32567ms).
[19:14:26.473] <TB1> INFO: 1437595 events read in total (65923ms).
[19:14:59.694] <TB1> INFO: 2148555 events read in total (99144ms).
[19:15:32.535] <TB1> INFO: 2856615 events read in total (131985ms).
[19:16:04.812] <TB1> INFO: 3564425 events read in total (164262ms).
[19:16:28.982] <TB1> INFO: 4076800 events read in total (188432ms).
[19:16:29.095] <TB1> INFO: Test took 189470ms.
[19:16:58.820] <TB1> INFO: PixTestTrim::trimBitTest() done
[19:16:58.821] <TB1> INFO: PixTestTrim::doTest() done, duration: 2650 seconds
[19:16:58.821] <TB1> INFO: Decoding statistics:
[19:16:58.821] <TB1> INFO: General information:
[19:16:58.821] <TB1> INFO: 16bit words read: 0
[19:16:58.821] <TB1> INFO: valid events total: 0
[19:16:58.821] <TB1> INFO: empty events: 0
[19:16:58.821] <TB1> INFO: valid events with pixels: 0
[19:16:58.821] <TB1> INFO: valid pixel hits: 0
[19:16:58.821] <TB1> INFO: Event errors: 0
[19:16:58.821] <TB1> INFO: start marker: 0
[19:16:58.821] <TB1> INFO: stop marker: 0
[19:16:58.821] <TB1> INFO: overflow: 0
[19:16:58.821] <TB1> INFO: invalid 5bit words: 0
[19:16:58.821] <TB1> INFO: invalid XOR eye diagram: 0
[19:16:58.821] <TB1> INFO: frame (failed synchr.): 0
[19:16:58.821] <TB1> INFO: idle data (no TBM trl): 0
[19:16:58.821] <TB1> INFO: no data (only TBM hdr): 0
[19:16:58.821] <TB1> INFO: TBM errors: 0
[19:16:58.821] <TB1> INFO: flawed TBM headers: 0
[19:16:58.821] <TB1> INFO: flawed TBM trailers: 0
[19:16:58.821] <TB1> INFO: event ID mismatches: 0
[19:16:58.821] <TB1> INFO: ROC errors: 0
[19:16:58.821] <TB1> INFO: missing ROC header(s): 0
[19:16:58.821] <TB1> INFO: misplaced readback start: 0
[19:16:58.821] <TB1> INFO: Pixel decoding errors: 0
[19:16:58.821] <TB1> INFO: pixel data incomplete: 0
[19:16:58.821] <TB1> INFO: pixel address: 0
[19:16:58.821] <TB1> INFO: pulse height fill bit: 0
[19:16:58.821] <TB1> INFO: buffer corruption: 0
[19:16:59.524] <TB1> INFO: ######################################################################
[19:16:59.531] <TB1> INFO: PixTestPhOptimization::doTest() Ntrig = 10
[19:16:59.531] <TB1> INFO: ######################################################################
[19:16:59.796] <TB1> INFO: Expecting 41600 events.
[19:17:03.442] <TB1> INFO: 41600 events read in total (3047ms).
[19:17:03.443] <TB1> INFO: Test took 3910ms.
[19:17:03.904] <TB1> INFO: Expecting 41600 events.
[19:17:07.640] <TB1> INFO: 41600 events read in total (3144ms).
[19:17:07.641] <TB1> INFO: Test took 3992ms.
[19:17:07.936] <TB1> INFO: Expecting 41600 events.
[19:17:11.648] <TB1> INFO: 41600 events read in total (3120ms).
[19:17:11.653] <TB1> INFO: Test took 3984ms.
[19:17:11.951] <TB1> INFO: Expecting 41600 events.
[19:17:15.693] <TB1> INFO: 41600 events read in total (3150ms).
[19:17:15.694] <TB1> INFO: Test took 4016ms.
[19:17:15.999] <TB1> INFO: Expecting 41600 events.
[19:17:19.738] <TB1> INFO: 41600 events read in total (3147ms).
[19:17:19.739] <TB1> INFO: Test took 4018ms.
[19:17:20.031] <TB1> INFO: Expecting 41600 events.
[19:17:23.820] <TB1> INFO: 41600 events read in total (3197ms).
[19:17:23.821] <TB1> INFO: Test took 4055ms.
[19:17:24.114] <TB1> INFO: Expecting 41600 events.
[19:17:27.822] <TB1> INFO: 41600 events read in total (3116ms).
[19:17:27.826] <TB1> INFO: Test took 3977ms.
[19:17:28.120] <TB1> INFO: Expecting 41600 events.
[19:17:31.863] <TB1> INFO: 41600 events read in total (3150ms).
[19:17:31.864] <TB1> INFO: Test took 4010ms.
[19:17:32.160] <TB1> INFO: Expecting 41600 events.
[19:17:35.762] <TB1> INFO: 41600 events read in total (3008ms).
[19:17:35.762] <TB1> INFO: Test took 3868ms.
[19:17:36.054] <TB1> INFO: Expecting 41600 events.
[19:17:39.678] <TB1> INFO: 41600 events read in total (3033ms).
[19:17:39.679] <TB1> INFO: Test took 3890ms.
[19:17:39.969] <TB1> INFO: Expecting 41600 events.
[19:17:43.710] <TB1> INFO: 41600 events read in total (3149ms).
[19:17:43.715] <TB1> INFO: Test took 4011ms.
[19:17:44.005] <TB1> INFO: Expecting 41600 events.
[19:17:47.708] <TB1> INFO: 41600 events read in total (3110ms).
[19:17:47.709] <TB1> INFO: Test took 3969ms.
[19:17:48.005] <TB1> INFO: Expecting 41600 events.
[19:17:51.722] <TB1> INFO: 41600 events read in total (3125ms).
[19:17:51.723] <TB1> INFO: Test took 3987ms.
[19:17:52.015] <TB1> INFO: Expecting 41600 events.
[19:17:55.689] <TB1> INFO: 41600 events read in total (3082ms).
[19:17:55.690] <TB1> INFO: Test took 3942ms.
[19:17:55.001] <TB1> INFO: Expecting 41600 events.
[19:17:59.726] <TB1> INFO: 41600 events read in total (3133ms).
[19:17:59.727] <TB1> INFO: Test took 4008ms.
[19:18:00.034] <TB1> INFO: Expecting 41600 events.
[19:18:03.775] <TB1> INFO: 41600 events read in total (3148ms).
[19:18:03.776] <TB1> INFO: Test took 4025ms.
[19:18:04.073] <TB1> INFO: Expecting 41600 events.
[19:18:07.762] <TB1> INFO: 41600 events read in total (3096ms).
[19:18:07.762] <TB1> INFO: Test took 3959ms.
[19:18:08.055] <TB1> INFO: Expecting 41600 events.
[19:18:11.812] <TB1> INFO: 41600 events read in total (3154ms).
[19:18:11.818] <TB1> INFO: Test took 4028ms.
[19:18:12.114] <TB1> INFO: Expecting 41600 events.
[19:18:15.780] <TB1> INFO: 41600 events read in total (3075ms).
[19:18:15.783] <TB1> INFO: Test took 3939ms.
[19:18:16.094] <TB1> INFO: Expecting 41600 events.
[19:18:19.708] <TB1> INFO: 41600 events read in total (3023ms).
[19:18:19.709] <TB1> INFO: Test took 3900ms.
[19:18:20.024] <TB1> INFO: Expecting 41600 events.
[19:18:23.685] <TB1> INFO: 41600 events read in total (3070ms).
[19:18:23.686] <TB1> INFO: Test took 3950ms.
[19:18:23.976] <TB1> INFO: Expecting 41600 events.
[19:18:27.693] <TB1> INFO: 41600 events read in total (3125ms).
[19:18:27.695] <TB1> INFO: Test took 3985ms.
[19:18:27.989] <TB1> INFO: Expecting 41600 events.
[19:18:31.693] <TB1> INFO: 41600 events read in total (3104ms).
[19:18:31.695] <TB1> INFO: Test took 3976ms.
[19:18:31.994] <TB1> INFO: Expecting 41600 events.
[19:18:35.748] <TB1> INFO: 41600 events read in total (3161ms).
[19:18:35.749] <TB1> INFO: Test took 4028ms.
[19:18:36.042] <TB1> INFO: Expecting 41600 events.
[19:18:39.717] <TB1> INFO: 41600 events read in total (3082ms).
[19:18:39.718] <TB1> INFO: Test took 3942ms.
[19:18:40.016] <TB1> INFO: Expecting 41600 events.
[19:18:43.661] <TB1> INFO: 41600 events read in total (3053ms).
[19:18:43.662] <TB1> INFO: Test took 3919ms.
[19:18:43.956] <TB1> INFO: Expecting 41600 events.
[19:18:47.711] <TB1> INFO: 41600 events read in total (3163ms).
[19:18:47.712] <TB1> INFO: Test took 4026ms.
[19:18:48.026] <TB1> INFO: Expecting 41600 events.
[19:18:51.775] <TB1> INFO: 41600 events read in total (3157ms).
[19:18:51.776] <TB1> INFO: Test took 4028ms.
[19:18:52.073] <TB1> INFO: Expecting 41600 events.
[19:18:55.790] <TB1> INFO: 41600 events read in total (3101ms).
[19:18:55.791] <TB1> INFO: Test took 3990ms.
[19:18:56.091] <TB1> INFO: Expecting 41600 events.
[19:18:59.730] <TB1> INFO: 41600 events read in total (3047ms).
[19:18:59.731] <TB1> INFO: Test took 3908ms.
[19:19:00.046] <TB1> INFO: Expecting 41600 events.
[19:19:03.708] <TB1> INFO: 41600 events read in total (3064ms).
[19:19:03.709] <TB1> INFO: Test took 3945ms.
[19:19:03.999] <TB1> INFO: Expecting 2560 events.
[19:19:04.893] <TB1> INFO: 2560 events read in total (302ms).
[19:19:04.894] <TB1> INFO: Test took 1173ms.
[19:19:05.202] <TB1> INFO: Expecting 2560 events.
[19:19:06.097] <TB1> INFO: 2560 events read in total (303ms).
[19:19:06.097] <TB1> INFO: Test took 1203ms.
[19:19:06.405] <TB1> INFO: Expecting 2560 events.
[19:19:07.300] <TB1> INFO: 2560 events read in total (303ms).
[19:19:07.300] <TB1> INFO: Test took 1202ms.
[19:19:07.608] <TB1> INFO: Expecting 2560 events.
[19:19:08.500] <TB1> INFO: 2560 events read in total (300ms).
[19:19:08.501] <TB1> INFO: Test took 1200ms.
[19:19:08.809] <TB1> INFO: Expecting 2560 events.
[19:19:09.699] <TB1> INFO: 2560 events read in total (298ms).
[19:19:09.700] <TB1> INFO: Test took 1199ms.
[19:19:10.007] <TB1> INFO: Expecting 2560 events.
[19:19:10.895] <TB1> INFO: 2560 events read in total (291ms).
[19:19:10.895] <TB1> INFO: Test took 1195ms.
[19:19:11.204] <TB1> INFO: Expecting 2560 events.
[19:19:12.098] <TB1> INFO: 2560 events read in total (302ms).
[19:19:12.104] <TB1> INFO: Test took 1208ms.
[19:19:12.407] <TB1> INFO: Expecting 2560 events.
[19:19:13.291] <TB1> INFO: 2560 events read in total (290ms).
[19:19:13.291] <TB1> INFO: Test took 1187ms.
[19:19:13.598] <TB1> INFO: Expecting 2560 events.
[19:19:14.482] <TB1> INFO: 2560 events read in total (292ms).
[19:19:14.482] <TB1> INFO: Test took 1190ms.
[19:19:14.790] <TB1> INFO: Expecting 2560 events.
[19:19:15.683] <TB1> INFO: 2560 events read in total (301ms).
[19:19:15.683] <TB1> INFO: Test took 1201ms.
[19:19:15.995] <TB1> INFO: Expecting 2560 events.
[19:19:16.882] <TB1> INFO: 2560 events read in total (294ms).
[19:19:16.889] <TB1> INFO: Test took 1206ms.
[19:19:17.190] <TB1> INFO: Expecting 2560 events.
[19:19:18.073] <TB1> INFO: 2560 events read in total (290ms).
[19:19:18.083] <TB1> INFO: Test took 1193ms.
[19:19:18.382] <TB1> INFO: Expecting 2560 events.
[19:19:19.272] <TB1> INFO: 2560 events read in total (298ms).
[19:19:19.273] <TB1> INFO: Test took 1189ms.
[19:19:19.581] <TB1> INFO: Expecting 2560 events.
[19:19:20.479] <TB1> INFO: 2560 events read in total (299ms).
[19:19:20.479] <TB1> INFO: Test took 1206ms.
[19:19:20.788] <TB1> INFO: Expecting 2560 events.
[19:19:21.677] <TB1> INFO: 2560 events read in total (296ms).
[19:19:21.679] <TB1> INFO: Test took 1200ms.
[19:19:21.985] <TB1> INFO: Expecting 2560 events.
[19:19:22.881] <TB1> INFO: 2560 events read in total (303ms).
[19:19:22.881] <TB1> INFO: Test took 1201ms.
[19:19:22.885] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[19:19:23.190] <TB1> INFO: Expecting 655360 events.
[19:19:38.689] <TB1> INFO: 655360 events read in total (14906ms).
[19:19:38.708] <TB1> INFO: Expecting 655360 events.
[19:19:53.905] <TB1> INFO: 655360 events read in total (14793ms).
[19:19:53.923] <TB1> INFO: Expecting 655360 events.
[19:20:09.179] <TB1> INFO: 655360 events read in total (14853ms).
[19:20:09.203] <TB1> INFO: Expecting 655360 events.
[19:20:24.362] <TB1> INFO: 655360 events read in total (14756ms).
[19:20:24.414] <TB1> INFO: Expecting 655360 events.
[19:20:39.599] <TB1> INFO: 655360 events read in total (14777ms).
[19:20:39.642] <TB1> INFO: Expecting 655360 events.
[19:20:54.724] <TB1> INFO: 655360 events read in total (14663ms).
[19:20:54.777] <TB1> INFO: Expecting 655360 events.
[19:21:09.996] <TB1> INFO: 655360 events read in total (14815ms).
[19:21:10.050] <TB1> INFO: Expecting 655360 events.
[19:21:25.250] <TB1> INFO: 655360 events read in total (14786ms).
[19:21:25.306] <TB1> INFO: Expecting 655360 events.
[19:21:40.323] <TB1> INFO: 655360 events read in total (14612ms).
[19:21:40.383] <TB1> INFO: Expecting 655360 events.
[19:21:55.644] <TB1> INFO: 655360 events read in total (14850ms).
[19:21:55.729] <TB1> INFO: Expecting 655360 events.
[19:22:10.942] <TB1> INFO: 655360 events read in total (14809ms).
[19:22:11.107] <TB1> INFO: Expecting 655360 events.
[19:22:26.275] <TB1> INFO: 655360 events read in total (14764ms).
[19:22:26.393] <TB1> INFO: Expecting 655360 events.
[19:22:40.775] <TB1> INFO: 655360 events read in total (13974ms).
[19:22:40.856] <TB1> INFO: Expecting 655360 events.
[19:22:56.053] <TB1> INFO: 655360 events read in total (14794ms).
[19:22:56.174] <TB1> INFO: Expecting 655360 events.
[19:23:11.305] <TB1> INFO: 655360 events read in total (14705ms).
[19:23:11.400] <TB1> INFO: Expecting 655360 events.
[19:23:26.507] <TB1> INFO: 655360 events read in total (14704ms).
[19:23:26.640] <TB1> INFO: Test took 243755ms.
[19:23:26.783] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[19:23:27.027] <TB1> INFO: Expecting 655360 events.
[19:23:41.835] <TB1> INFO: 655360 events read in total (14217ms).
[19:23:41.860] <TB1> INFO: Expecting 655360 events.
[19:23:56.971] <TB1> INFO: 655360 events read in total (14708ms).
[19:23:56.989] <TB1> INFO: Expecting 655360 events.
[19:24:11.890] <TB1> INFO: 655360 events read in total (14498ms).
[19:24:11.922] <TB1> INFO: Expecting 655360 events.
[19:24:27.062] <TB1> INFO: 655360 events read in total (14728ms).
[19:24:27.091] <TB1> INFO: Expecting 655360 events.
[19:24:41.899] <TB1> INFO: 655360 events read in total (14399ms).
[19:24:41.934] <TB1> INFO: Expecting 655360 events.
[19:24:56.911] <TB1> INFO: 655360 events read in total (14571ms).
[19:24:56.961] <TB1> INFO: Expecting 655360 events.
[19:25:11.648] <TB1> INFO: 655360 events read in total (14284ms).
[19:25:11.699] <TB1> INFO: Expecting 655360 events.
[19:25:26.411] <TB1> INFO: 655360 events read in total (14309ms).
[19:25:26.460] <TB1> INFO: Expecting 655360 events.
[19:25:41.535] <TB1> INFO: 655360 events read in total (14671ms).
[19:25:41.582] <TB1> INFO: Expecting 655360 events.
[19:25:56.300] <TB1> INFO: 655360 events read in total (14315ms).
[19:25:56.421] <TB1> INFO: Expecting 655360 events.
[19:26:11.409] <TB1> INFO: 655360 events read in total (14585ms).
[19:26:11.510] <TB1> INFO: Expecting 655360 events.
[19:26:26.431] <TB1> INFO: 655360 events read in total (14517ms).
[19:26:26.571] <TB1> INFO: Expecting 655360 events.
[19:26:41.501] <TB1> INFO: 655360 events read in total (14527ms).
[19:26:41.626] <TB1> INFO: Expecting 655360 events.
[19:26:56.779] <TB1> INFO: 655360 events read in total (14739ms).
[19:26:56.926] <TB1> INFO: Expecting 655360 events.
[19:27:11.939] <TB1> INFO: 655360 events read in total (14606ms).
[19:27:12.055] <TB1> INFO: Expecting 655360 events.
[19:27:27.083] <TB1> INFO: 655360 events read in total (14620ms).
[19:27:27.203] <TB1> INFO: Test took 240420ms.
[19:27:27.436] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[19:27:27.452] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[19:27:27.463] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[19:27:27.470] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[19:27:27.476] <TB1> INFO: safety margin for low PH: adding 1, margin is now 21
[19:27:27.483] <TB1> INFO: safety margin for low PH: adding 2, margin is now 22
[19:27:27.489] <TB1> INFO: safety margin for low PH: adding 3, margin is now 23
[19:27:27.496] <TB1> INFO: safety margin for low PH: adding 4, margin is now 24
[19:27:27.502] <TB1> INFO: safety margin for low PH: adding 5, margin is now 25
[19:27:27.509] <TB1> INFO: safety margin for low PH: adding 6, margin is now 26
[19:27:27.515] <TB1> INFO: safety margin for low PH: adding 7, margin is now 27
[19:27:27.522] <TB1> INFO: safety margin for low PH: adding 8, margin is now 28
[19:27:27.528] <TB1> INFO: safety margin for low PH: adding 9, margin is now 29
[19:27:27.535] <TB1> INFO: safety margin for low PH: adding 10, margin is now 30
[19:27:27.543] <TB1> INFO: safety margin for low PH: adding 11, margin is now 31
[19:27:27.548] <TB1> INFO: safety margin for low PH: adding 12, margin is now 32
[19:27:27.555] <TB1> INFO: safety margin for low PH: adding 13, margin is now 33
[19:27:27.561] <TB1> INFO: safety margin for low PH: adding 14, margin is now 34
[19:27:27.567] <TB1> INFO: safety margin for low PH: adding 15, margin is now 35
[19:27:27.573] <TB1> INFO: safety margin for low PH: adding 16, margin is now 36
[19:27:27.578] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[19:27:27.585] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[19:27:27.591] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[19:27:27.597] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[19:27:27.603] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[19:27:27.610] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[19:27:27.616] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[19:27:27.623] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[19:27:27.629] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[19:27:27.635] <TB1> INFO: safety margin for low PH: adding 1, margin is now 21
[19:27:27.641] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[19:27:27.648] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[19:27:27.654] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[19:27:27.695] <TB1> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1061_FullQualification_2016-10-24_16h14m_1477318445//001_Fulltest_m20//dacParameters35_C0.dat
[19:27:27.695] <TB1> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1061_FullQualification_2016-10-24_16h14m_1477318445//001_Fulltest_m20//dacParameters35_C1.dat
[19:27:27.696] <TB1> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1061_FullQualification_2016-10-24_16h14m_1477318445//001_Fulltest_m20//dacParameters35_C2.dat
[19:27:27.696] <TB1> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1061_FullQualification_2016-10-24_16h14m_1477318445//001_Fulltest_m20//dacParameters35_C3.dat
[19:27:27.696] <TB1> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1061_FullQualification_2016-10-24_16h14m_1477318445//001_Fulltest_m20//dacParameters35_C4.dat
[19:27:27.696] <TB1> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1061_FullQualification_2016-10-24_16h14m_1477318445//001_Fulltest_m20//dacParameters35_C5.dat
[19:27:27.696] <TB1> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1061_FullQualification_2016-10-24_16h14m_1477318445//001_Fulltest_m20//dacParameters35_C6.dat
[19:27:27.696] <TB1> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1061_FullQualification_2016-10-24_16h14m_1477318445//001_Fulltest_m20//dacParameters35_C7.dat
[19:27:27.696] <TB1> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1061_FullQualification_2016-10-24_16h14m_1477318445//001_Fulltest_m20//dacParameters35_C8.dat
[19:27:27.696] <TB1> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1061_FullQualification_2016-10-24_16h14m_1477318445//001_Fulltest_m20//dacParameters35_C9.dat
[19:27:27.696] <TB1> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1061_FullQualification_2016-10-24_16h14m_1477318445//001_Fulltest_m20//dacParameters35_C10.dat
[19:27:27.696] <TB1> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1061_FullQualification_2016-10-24_16h14m_1477318445//001_Fulltest_m20//dacParameters35_C11.dat
[19:27:27.696] <TB1> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1061_FullQualification_2016-10-24_16h14m_1477318445//001_Fulltest_m20//dacParameters35_C12.dat
[19:27:27.697] <TB1> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1061_FullQualification_2016-10-24_16h14m_1477318445//001_Fulltest_m20//dacParameters35_C13.dat
[19:27:27.697] <TB1> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1061_FullQualification_2016-10-24_16h14m_1477318445//001_Fulltest_m20//dacParameters35_C14.dat
[19:27:27.697] <TB1> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1061_FullQualification_2016-10-24_16h14m_1477318445//001_Fulltest_m20//dacParameters35_C15.dat
[19:27:27.967] <TB1> INFO: Expecting 41600 events.
[19:27:31.188] <TB1> INFO: 41600 events read in total (2627ms).
[19:27:31.189] <TB1> INFO: Test took 3489ms.
[19:27:31.666] <TB1> INFO: Expecting 41600 events.
[19:27:34.683] <TB1> INFO: 41600 events read in total (2424ms).
[19:27:34.684] <TB1> INFO: Test took 3280ms.
[19:27:35.134] <TB1> INFO: Expecting 41600 events.
[19:27:38.242] <TB1> INFO: 41600 events read in total (2516ms).
[19:27:38.243] <TB1> INFO: Test took 3345ms.
[19:27:38.458] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[19:27:38.547] <TB1> INFO: Expecting 2560 events.
[19:27:39.431] <TB1> INFO: 2560 events read in total (293ms).
[19:27:39.431] <TB1> INFO: Test took 973ms.
[19:27:39.433] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[19:27:39.740] <TB1> INFO: Expecting 2560 events.
[19:27:40.623] <TB1> INFO: 2560 events read in total (292ms).
[19:27:40.624] <TB1> INFO: Test took 1191ms.
[19:27:40.626] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[19:27:40.932] <TB1> INFO: Expecting 2560 events.
[19:27:41.816] <TB1> INFO: 2560 events read in total (293ms).
[19:27:41.816] <TB1> INFO: Test took 1191ms.
[19:27:41.818] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[19:27:42.124] <TB1> INFO: Expecting 2560 events.
[19:27:43.022] <TB1> INFO: 2560 events read in total (306ms).
[19:27:43.022] <TB1> INFO: Test took 1204ms.
[19:27:43.025] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[19:27:43.330] <TB1> INFO: Expecting 2560 events.
[19:27:44.226] <TB1> INFO: 2560 events read in total (304ms).
[19:27:44.226] <TB1> INFO: Test took 1201ms.
[19:27:44.230] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[19:27:44.533] <TB1> INFO: Expecting 2560 events.
[19:27:45.424] <TB1> INFO: 2560 events read in total (299ms).
[19:27:45.425] <TB1> INFO: Test took 1195ms.
[19:27:45.428] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[19:27:45.733] <TB1> INFO: Expecting 2560 events.
[19:27:46.623] <TB1> INFO: 2560 events read in total (295ms).
[19:27:46.623] <TB1> INFO: Test took 1195ms.
[19:27:46.625] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[19:27:46.934] <TB1> INFO: Expecting 2560 events.
[19:27:47.825] <TB1> INFO: 2560 events read in total (298ms).
[19:27:47.826] <TB1> INFO: Test took 1201ms.
[19:27:47.827] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[19:27:48.136] <TB1> INFO: Expecting 2560 events.
[19:27:49.018] <TB1> INFO: 2560 events read in total (290ms).
[19:27:49.019] <TB1> INFO: Test took 1192ms.
[19:27:49.022] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[19:27:49.327] <TB1> INFO: Expecting 2560 events.
[19:27:50.216] <TB1> INFO: 2560 events read in total (297ms).
[19:27:50.217] <TB1> INFO: Test took 1195ms.
[19:27:50.221] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[19:27:50.525] <TB1> INFO: Expecting 2560 events.
[19:27:51.409] <TB1> INFO: 2560 events read in total (293ms).
[19:27:51.409] <TB1> INFO: Test took 1188ms.
[19:27:51.411] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[19:27:51.718] <TB1> INFO: Expecting 2560 events.
[19:27:52.604] <TB1> INFO: 2560 events read in total (293ms).
[19:27:52.604] <TB1> INFO: Test took 1193ms.
[19:27:52.606] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[19:27:52.914] <TB1> INFO: Expecting 2560 events.
[19:27:53.806] <TB1> INFO: 2560 events read in total (300ms).
[19:27:53.806] <TB1> INFO: Test took 1200ms.
[19:27:53.809] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[19:27:54.115] <TB1> INFO: Expecting 2560 events.
[19:27:54.000] <TB1> INFO: 2560 events read in total (289ms).
[19:27:54.001] <TB1> INFO: Test took 1192ms.
[19:27:55.004] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[19:27:55.309] <TB1> INFO: Expecting 2560 events.
[19:27:56.200] <TB1> INFO: 2560 events read in total (299ms).
[19:27:56.203] <TB1> INFO: Test took 1199ms.
[19:27:56.205] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[19:27:56.509] <TB1> INFO: Expecting 2560 events.
[19:27:57.398] <TB1> INFO: 2560 events read in total (294ms).
[19:27:57.398] <TB1> INFO: Test took 1193ms.
[19:27:57.401] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[19:27:57.707] <TB1> INFO: Expecting 2560 events.
[19:27:58.592] <TB1> INFO: 2560 events read in total (293ms).
[19:27:58.598] <TB1> INFO: Test took 1197ms.
[19:27:58.601] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[19:27:58.901] <TB1> INFO: Expecting 2560 events.
[19:27:59.791] <TB1> INFO: 2560 events read in total (298ms).
[19:27:59.791] <TB1> INFO: Test took 1191ms.
[19:27:59.794] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[19:28:00.098] <TB1> INFO: Expecting 2560 events.
[19:28:00.988] <TB1> INFO: 2560 events read in total (298ms).
[19:28:00.989] <TB1> INFO: Test took 1195ms.
[19:28:00.992] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[19:28:01.297] <TB1> INFO: Expecting 2560 events.
[19:28:02.190] <TB1> INFO: 2560 events read in total (301ms).
[19:28:02.191] <TB1> INFO: Test took 1199ms.
[19:28:02.193] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[19:28:02.499] <TB1> INFO: Expecting 2560 events.
[19:28:03.389] <TB1> INFO: 2560 events read in total (298ms).
[19:28:03.389] <TB1> INFO: Test took 1196ms.
[19:28:03.392] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[19:28:03.698] <TB1> INFO: Expecting 2560 events.
[19:28:04.591] <TB1> INFO: 2560 events read in total (301ms).
[19:28:04.592] <TB1> INFO: Test took 1200ms.
[19:28:04.594] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[19:28:04.900] <TB1> INFO: Expecting 2560 events.
[19:28:05.790] <TB1> INFO: 2560 events read in total (298ms).
[19:28:05.791] <TB1> INFO: Test took 1197ms.
[19:28:05.794] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[19:28:06.099] <TB1> INFO: Expecting 2560 events.
[19:28:06.991] <TB1> INFO: 2560 events read in total (300ms).
[19:28:06.992] <TB1> INFO: Test took 1198ms.
[19:28:06.995] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[19:28:07.299] <TB1> INFO: Expecting 2560 events.
[19:28:08.189] <TB1> INFO: 2560 events read in total (298ms).
[19:28:08.189] <TB1> INFO: Test took 1195ms.
[19:28:08.193] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[19:28:08.498] <TB1> INFO: Expecting 2560 events.
[19:28:09.392] <TB1> INFO: 2560 events read in total (302ms).
[19:28:09.392] <TB1> INFO: Test took 1200ms.
[19:28:09.395] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[19:28:09.701] <TB1> INFO: Expecting 2560 events.
[19:28:10.589] <TB1> INFO: 2560 events read in total (297ms).
[19:28:10.589] <TB1> INFO: Test took 1194ms.
[19:28:10.592] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[19:28:10.899] <TB1> INFO: Expecting 2560 events.
[19:28:11.794] <TB1> INFO: 2560 events read in total (302ms).
[19:28:11.794] <TB1> INFO: Test took 1202ms.
[19:28:11.796] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[19:28:12.102] <TB1> INFO: Expecting 2560 events.
[19:28:12.996] <TB1> INFO: 2560 events read in total (302ms).
[19:28:12.996] <TB1> INFO: Test took 1200ms.
[19:28:12.998] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[19:28:13.305] <TB1> INFO: Expecting 2560 events.
[19:28:14.192] <TB1> INFO: 2560 events read in total (295ms).
[19:28:14.204] <TB1> INFO: Test took 1206ms.
[19:28:14.207] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[19:28:14.501] <TB1> INFO: Expecting 2560 events.
[19:28:15.389] <TB1> INFO: 2560 events read in total (295ms).
[19:28:15.389] <TB1> INFO: Test took 1183ms.
[19:28:15.391] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[19:28:15.702] <TB1> INFO: Expecting 2560 events.
[19:28:16.607] <TB1> INFO: 2560 events read in total (312ms).
[19:28:16.607] <TB1> INFO: Test took 1216ms.
[19:28:17.108] <TB1> INFO: PixTestPhOptimization::doTest() done, duration: 677 seconds
[19:28:17.108] <TB1> INFO: PH scale (per ROC): 52 60 39 48 70 66 62 48 50 63 49 49 62 50 47 49
[19:28:17.108] <TB1> INFO: PH offset (per ROC): 104 124 110 112 121 123 122 129 107 124 85 110 123 103 102 99
[19:28:17.116] <TB1> INFO: Decoding statistics:
[19:28:17.132] <TB1> INFO: General information:
[19:28:17.132] <TB1> INFO: 16bit words read: 127886
[19:28:17.132] <TB1> INFO: valid events total: 20480
[19:28:17.132] <TB1> INFO: empty events: 17977
[19:28:17.132] <TB1> INFO: valid events with pixels: 2503
[19:28:17.132] <TB1> INFO: valid pixel hits: 2503
[19:28:17.132] <TB1> INFO: Event errors: 0
[19:28:17.132] <TB1> INFO: start marker: 0
[19:28:17.132] <TB1> INFO: stop marker: 0
[19:28:17.132] <TB1> INFO: overflow: 0
[19:28:17.132] <TB1> INFO: invalid 5bit words: 0
[19:28:17.132] <TB1> INFO: invalid XOR eye diagram: 0
[19:28:17.132] <TB1> INFO: frame (failed synchr.): 0
[19:28:17.132] <TB1> INFO: idle data (no TBM trl): 0
[19:28:17.132] <TB1> INFO: no data (only TBM hdr): 0
[19:28:17.132] <TB1> INFO: TBM errors: 0
[19:28:17.132] <TB1> INFO: flawed TBM headers: 0
[19:28:17.132] <TB1> INFO: flawed TBM trailers: 0
[19:28:17.132] <TB1> INFO: event ID mismatches: 0
[19:28:17.132] <TB1> INFO: ROC errors: 0
[19:28:17.132] <TB1> INFO: missing ROC header(s): 0
[19:28:17.132] <TB1> INFO: misplaced readback start: 0
[19:28:17.132] <TB1> INFO: Pixel decoding errors: 0
[19:28:17.132] <TB1> INFO: pixel data incomplete: 0
[19:28:17.132] <TB1> INFO: pixel address: 0
[19:28:17.132] <TB1> INFO: pulse height fill bit: 0
[19:28:17.132] <TB1> INFO: buffer corruption: 0
[19:28:17.354] <TB1> INFO: ######################################################################
[19:28:17.354] <TB1> INFO: PixTestGainPedestal::fullTest() ntrig = 10
[19:28:17.354] <TB1> INFO: ######################################################################
[19:28:17.372] <TB1> INFO: scanning low vcal = 10
[19:28:17.618] <TB1> INFO: Expecting 41600 events.
[19:28:21.239] <TB1> INFO: 41600 events read in total (3029ms).
[19:28:21.240] <TB1> INFO: Test took 3867ms.
[19:28:21.241] <TB1> INFO: scanning low vcal = 20
[19:28:21.548] <TB1> INFO: Expecting 41600 events.
[19:28:25.185] <TB1> INFO: 41600 events read in total (3044ms).
[19:28:25.194] <TB1> INFO: Test took 3952ms.
[19:28:25.196] <TB1> INFO: scanning low vcal = 30
[19:28:25.503] <TB1> INFO: Expecting 41600 events.
[19:28:29.204] <TB1> INFO: 41600 events read in total (3109ms).
[19:28:29.205] <TB1> INFO: Test took 4006ms.
[19:28:29.208] <TB1> INFO: scanning low vcal = 40
[19:28:29.486] <TB1> INFO: Expecting 41600 events.
[19:28:33.513] <TB1> INFO: 41600 events read in total (3434ms).
[19:28:33.515] <TB1> INFO: Test took 4307ms.
[19:28:33.518] <TB1> INFO: scanning low vcal = 50
[19:28:33.806] <TB1> INFO: Expecting 41600 events.
[19:28:37.853] <TB1> INFO: 41600 events read in total (3447ms).
[19:28:37.854] <TB1> INFO: Test took 4327ms.
[19:28:37.858] <TB1> INFO: scanning low vcal = 60
[19:28:38.147] <TB1> INFO: Expecting 41600 events.
[19:28:42.224] <TB1> INFO: 41600 events read in total (3479ms).
[19:28:42.225] <TB1> INFO: Test took 4367ms.
[19:28:42.228] <TB1> INFO: scanning low vcal = 70
[19:28:42.508] <TB1> INFO: Expecting 41600 events.
[19:28:46.560] <TB1> INFO: 41600 events read in total (3459ms).
[19:28:46.561] <TB1> INFO: Test took 4331ms.
[19:28:46.564] <TB1> INFO: scanning low vcal = 80
[19:28:46.843] <TB1> INFO: Expecting 41600 events.
[19:28:50.907] <TB1> INFO: 41600 events read in total (3465ms).
[19:28:50.907] <TB1> INFO: Test took 4343ms.
[19:28:50.910] <TB1> INFO: scanning low vcal = 90
[19:28:51.188] <TB1> INFO: Expecting 41600 events.
[19:28:55.263] <TB1> INFO: 41600 events read in total (3483ms).
[19:28:55.264] <TB1> INFO: Test took 4353ms.
[19:28:55.268] <TB1> INFO: scanning low vcal = 100
[19:28:55.544] <TB1> INFO: Expecting 41600 events.
[19:28:59.594] <TB1> INFO: 41600 events read in total (3458ms).
[19:28:59.595] <TB1> INFO: Test took 4327ms.
[19:28:59.604] <TB1> INFO: scanning low vcal = 110
[19:28:59.891] <TB1> INFO: Expecting 41600 events.
[19:29:03.967] <TB1> INFO: 41600 events read in total (3484ms).
[19:29:03.974] <TB1> INFO: Test took 4368ms.
[19:29:03.978] <TB1> INFO: scanning low vcal = 120
[19:29:04.259] <TB1> INFO: Expecting 41600 events.
[19:29:08.317] <TB1> INFO: 41600 events read in total (3462ms).
[19:29:08.318] <TB1> INFO: Test took 4339ms.
[19:29:08.321] <TB1> INFO: scanning low vcal = 130
[19:29:08.607] <TB1> INFO: Expecting 41600 events.
[19:29:12.639] <TB1> INFO: 41600 events read in total (3440ms).
[19:29:12.640] <TB1> INFO: Test took 4319ms.
[19:29:12.644] <TB1> INFO: scanning low vcal = 140
[19:29:12.933] <TB1> INFO: Expecting 41600 events.
[19:29:17.008] <TB1> INFO: 41600 events read in total (3483ms).
[19:29:17.009] <TB1> INFO: Test took 4348ms.
[19:29:17.014] <TB1> INFO: scanning low vcal = 150
[19:29:17.295] <TB1> INFO: Expecting 41600 events.
[19:29:21.339] <TB1> INFO: 41600 events read in total (3448ms).
[19:29:21.340] <TB1> INFO: Test took 4326ms.
[19:29:21.343] <TB1> INFO: scanning low vcal = 160
[19:29:21.620] <TB1> INFO: Expecting 41600 events.
[19:29:25.669] <TB1> INFO: 41600 events read in total (3456ms).
[19:29:25.670] <TB1> INFO: Test took 4327ms.
[19:29:25.673] <TB1> INFO: scanning low vcal = 170
[19:29:25.952] <TB1> INFO: Expecting 41600 events.
[19:29:29.984] <TB1> INFO: 41600 events read in total (3440ms).
[19:29:29.985] <TB1> INFO: Test took 4312ms.
[19:29:29.990] <TB1> INFO: scanning low vcal = 180
[19:29:30.265] <TB1> INFO: Expecting 41600 events.
[19:29:34.315] <TB1> INFO: 41600 events read in total (3458ms).
[19:29:34.316] <TB1> INFO: Test took 4325ms.
[19:29:34.319] <TB1> INFO: scanning low vcal = 190
[19:29:34.598] <TB1> INFO: Expecting 41600 events.
[19:29:38.683] <TB1> INFO: 41600 events read in total (3488ms).
[19:29:38.684] <TB1> INFO: Test took 4365ms.
[19:29:38.687] <TB1> INFO: scanning low vcal = 200
[19:29:38.972] <TB1> INFO: Expecting 41600 events.
[19:29:43.038] <TB1> INFO: 41600 events read in total (3474ms).
[19:29:43.039] <TB1> INFO: Test took 4352ms.
[19:29:43.042] <TB1> INFO: scanning low vcal = 210
[19:29:43.319] <TB1> INFO: Expecting 41600 events.
[19:29:47.398] <TB1> INFO: 41600 events read in total (3473ms).
[19:29:47.399] <TB1> INFO: Test took 4356ms.
[19:29:47.402] <TB1> INFO: scanning low vcal = 220
[19:29:47.679] <TB1> INFO: Expecting 41600 events.
[19:29:51.739] <TB1> INFO: 41600 events read in total (3459ms).
[19:29:51.740] <TB1> INFO: Test took 4338ms.
[19:29:51.743] <TB1> INFO: scanning low vcal = 230
[19:29:52.023] <TB1> INFO: Expecting 41600 events.
[19:29:56.082] <TB1> INFO: 41600 events read in total (3467ms).
[19:29:56.084] <TB1> INFO: Test took 4341ms.
[19:29:56.087] <TB1> INFO: scanning low vcal = 240
[19:29:56.364] <TB1> INFO: Expecting 41600 events.
[19:30:00.441] <TB1> INFO: 41600 events read in total (3479ms).
[19:30:00.441] <TB1> INFO: Test took 4354ms.
[19:30:00.446] <TB1> INFO: scanning low vcal = 250
[19:30:00.727] <TB1> INFO: Expecting 41600 events.
[19:30:04.824] <TB1> INFO: 41600 events read in total (3503ms).
[19:30:04.825] <TB1> INFO: Test took 4378ms.
[19:30:04.830] <TB1> INFO: scanning high vcal = 30 (= 210 in low range)
[19:30:05.105] <TB1> INFO: Expecting 41600 events.
[19:30:09.095] <TB1> INFO: 41600 events read in total (3398ms).
[19:30:09.095] <TB1> INFO: Test took 4265ms.
[19:30:09.099] <TB1> INFO: scanning high vcal = 50 (= 350 in low range)
[19:30:09.379] <TB1> INFO: Expecting 41600 events.
[19:30:13.332] <TB1> INFO: 41600 events read in total (3361ms).
[19:30:13.332] <TB1> INFO: Test took 4234ms.
[19:30:13.336] <TB1> INFO: scanning high vcal = 70 (= 490 in low range)
[19:30:13.612] <TB1> INFO: Expecting 41600 events.
[19:30:17.689] <TB1> INFO: 41600 events read in total (3485ms).
[19:30:17.690] <TB1> INFO: Test took 4354ms.
[19:30:17.694] <TB1> INFO: scanning high vcal = 90 (= 630 in low range)
[19:30:17.970] <TB1> INFO: Expecting 41600 events.
[19:30:22.018] <TB1> INFO: 41600 events read in total (3456ms).
[19:30:22.018] <TB1> INFO: Test took 4324ms.
[19:30:22.022] <TB1> INFO: scanning high vcal = 200 (= 1400 in low range)
[19:30:22.299] <TB1> INFO: Expecting 41600 events.
[19:30:26.344] <TB1> INFO: 41600 events read in total (3453ms).
[19:30:26.356] <TB1> INFO: Test took 4334ms.
[19:30:26.928] <TB1> INFO: PixTestGainPedestal::measure() done
[19:31:15.928] <TB1> INFO: PixTestGainPedestal::fit() done
[19:31:15.928] <TB1> INFO: non-linearity mean: 0.942 0.975 0.924 0.952 0.985 0.983 0.979 0.943 0.963 0.983 0.948 0.937 0.982 0.944 0.931 0.970
[19:31:15.928] <TB1> INFO: non-linearity RMS: 0.069 0.005 0.148 0.045 0.003 0.003 0.004 0.058 0.029 0.003 0.068 0.071 0.003 0.072 0.107 0.020
[19:31:15.928] <TB1> INFO: write gain/ped parameters into /home/l_tester/david/FullQualification/data/M1061_FullQualification_2016-10-24_16h14m_1477318445//001_Fulltest_m20//phCalibrationFitErr35_C0.dat
[19:31:15.942] <TB1> INFO: write gain/ped parameters into /home/l_tester/david/FullQualification/data/M1061_FullQualification_2016-10-24_16h14m_1477318445//001_Fulltest_m20//phCalibrationFitErr35_C1.dat
[19:31:15.957] <TB1> INFO: write gain/ped parameters into /home/l_tester/david/FullQualification/data/M1061_FullQualification_2016-10-24_16h14m_1477318445//001_Fulltest_m20//phCalibrationFitErr35_C2.dat
[19:31:15.978] <TB1> INFO: write gain/ped parameters into /home/l_tester/david/FullQualification/data/M1061_FullQualification_2016-10-24_16h14m_1477318445//001_Fulltest_m20//phCalibrationFitErr35_C3.dat
[19:31:15.993] <TB1> INFO: write gain/ped parameters into /home/l_tester/david/FullQualification/data/M1061_FullQualification_2016-10-24_16h14m_1477318445//001_Fulltest_m20//phCalibrationFitErr35_C4.dat
[19:31:16.006] <TB1> INFO: write gain/ped parameters into /home/l_tester/david/FullQualification/data/M1061_FullQualification_2016-10-24_16h14m_1477318445//001_Fulltest_m20//phCalibrationFitErr35_C5.dat
[19:31:16.030] <TB1> INFO: write gain/ped parameters into /home/l_tester/david/FullQualification/data/M1061_FullQualification_2016-10-24_16h14m_1477318445//001_Fulltest_m20//phCalibrationFitErr35_C6.dat
[19:31:16.045] <TB1> INFO: write gain/ped parameters into /home/l_tester/david/FullQualification/data/M1061_FullQualification_2016-10-24_16h14m_1477318445//001_Fulltest_m20//phCalibrationFitErr35_C7.dat
[19:31:16.065] <TB1> INFO: write gain/ped parameters into /home/l_tester/david/FullQualification/data/M1061_FullQualification_2016-10-24_16h14m_1477318445//001_Fulltest_m20//phCalibrationFitErr35_C8.dat
[19:31:16.095] <TB1> INFO: write gain/ped parameters into /home/l_tester/david/FullQualification/data/M1061_FullQualification_2016-10-24_16h14m_1477318445//001_Fulltest_m20//phCalibrationFitErr35_C9.dat
[19:31:16.126] <TB1> INFO: write gain/ped parameters into /home/l_tester/david/FullQualification/data/M1061_FullQualification_2016-10-24_16h14m_1477318445//001_Fulltest_m20//phCalibrationFitErr35_C10.dat
[19:31:16.147] <TB1> INFO: write gain/ped parameters into /home/l_tester/david/FullQualification/data/M1061_FullQualification_2016-10-24_16h14m_1477318445//001_Fulltest_m20//phCalibrationFitErr35_C11.dat
[19:31:16.168] <TB1> INFO: write gain/ped parameters into /home/l_tester/david/FullQualification/data/M1061_FullQualification_2016-10-24_16h14m_1477318445//001_Fulltest_m20//phCalibrationFitErr35_C12.dat
[19:31:16.194] <TB1> INFO: write gain/ped parameters into /home/l_tester/david/FullQualification/data/M1061_FullQualification_2016-10-24_16h14m_1477318445//001_Fulltest_m20//phCalibrationFitErr35_C13.dat
[19:31:16.216] <TB1> INFO: write gain/ped parameters into /home/l_tester/david/FullQualification/data/M1061_FullQualification_2016-10-24_16h14m_1477318445//001_Fulltest_m20//phCalibrationFitErr35_C14.dat
[19:31:16.240] <TB1> INFO: write gain/ped parameters into /home/l_tester/david/FullQualification/data/M1061_FullQualification_2016-10-24_16h14m_1477318445//001_Fulltest_m20//phCalibrationFitErr35_C15.dat
[19:31:16.260] <TB1> INFO: PixTestGainPedestal::fullTest() done, duration: 178 seconds
[19:31:16.261] <TB1> INFO: Decoding statistics:
[19:31:16.261] <TB1> INFO: General information:
[19:31:16.261] <TB1> INFO: 16bit words read: 3327792
[19:31:16.261] <TB1> INFO: valid events total: 332800
[19:31:16.261] <TB1> INFO: empty events: 0
[19:31:16.261] <TB1> INFO: valid events with pixels: 332800
[19:31:16.261] <TB1> INFO: valid pixel hits: 665496
[19:31:16.261] <TB1> INFO: Event errors: 0
[19:31:16.261] <TB1> INFO: start marker: 0
[19:31:16.261] <TB1> INFO: stop marker: 0
[19:31:16.261] <TB1> INFO: overflow: 0
[19:31:16.261] <TB1> INFO: invalid 5bit words: 0
[19:31:16.261] <TB1> INFO: invalid XOR eye diagram: 0
[19:31:16.261] <TB1> INFO: frame (failed synchr.): 0
[19:31:16.261] <TB1> INFO: idle data (no TBM trl): 0
[19:31:16.261] <TB1> INFO: no data (only TBM hdr): 0
[19:31:16.261] <TB1> INFO: TBM errors: 0
[19:31:16.261] <TB1> INFO: flawed TBM headers: 0
[19:31:16.261] <TB1> INFO: flawed TBM trailers: 0
[19:31:16.261] <TB1> INFO: event ID mismatches: 0
[19:31:16.261] <TB1> INFO: ROC errors: 0
[19:31:16.261] <TB1> INFO: missing ROC header(s): 0
[19:31:16.261] <TB1> INFO: misplaced readback start: 0
[19:31:16.261] <TB1> INFO: Pixel decoding errors: 0
[19:31:16.261] <TB1> INFO: pixel data incomplete: 0
[19:31:16.261] <TB1> INFO: pixel address: 0
[19:31:16.261] <TB1> INFO: pulse height fill bit: 0
[19:31:16.261] <TB1> INFO: buffer corruption: 0
[19:31:16.287] <TB1> INFO: Decoding statistics:
[19:31:16.291] <TB1> INFO: General information:
[19:31:16.291] <TB1> INFO: 16bit words read: 3457214
[19:31:16.291] <TB1> INFO: valid events total: 353536
[19:31:16.291] <TB1> INFO: empty events: 18233
[19:31:16.291] <TB1> INFO: valid events with pixels: 335303
[19:31:16.291] <TB1> INFO: valid pixel hits: 667999
[19:31:16.291] <TB1> INFO: Event errors: 0
[19:31:16.291] <TB1> INFO: start marker: 0
[19:31:16.291] <TB1> INFO: stop marker: 0
[19:31:16.291] <TB1> INFO: overflow: 0
[19:31:16.291] <TB1> INFO: invalid 5bit words: 0
[19:31:16.291] <TB1> INFO: invalid XOR eye diagram: 0
[19:31:16.291] <TB1> INFO: frame (failed synchr.): 0
[19:31:16.291] <TB1> INFO: idle data (no TBM trl): 0
[19:31:16.291] <TB1> INFO: no data (only TBM hdr): 0
[19:31:16.291] <TB1> INFO: TBM errors: 0
[19:31:16.291] <TB1> INFO: flawed TBM headers: 0
[19:31:16.291] <TB1> INFO: flawed TBM trailers: 0
[19:31:16.291] <TB1> INFO: event ID mismatches: 0
[19:31:16.291] <TB1> INFO: ROC errors: 0
[19:31:16.291] <TB1> INFO: missing ROC header(s): 0
[19:31:16.291] <TB1> INFO: misplaced readback start: 0
[19:31:16.291] <TB1> INFO: Pixel decoding errors: 0
[19:31:16.291] <TB1> INFO: pixel data incomplete: 0
[19:31:16.291] <TB1> INFO: pixel address: 0
[19:31:16.291] <TB1> INFO: pulse height fill bit: 0
[19:31:16.291] <TB1> INFO: buffer corruption: 0
[19:31:16.291] <TB1> INFO: enter test to run
[19:31:16.291] <TB1> INFO: test: exit no parameter change
[19:31:16.437] <TB1> QUIET: Connection to board 154 closed.
[19:31:16.438] <TB1> INFO: pXar: this is the end, my friend