[20:43:36.579] <TB0> INFO: *** Welcome to pxar ***
[20:43:36.579] <TB0> INFO: *** Today: 2016/10/19
[20:43:36.585] <TB0> INFO: *** Version: c8ba-dirty
[20:43:36.585] <TB0> INFO: readRocDacs: /home/l_tester/david/FullQualification/data/M1024_FullQualification_2016-10-19_19h52m_1476899523//001_Fulltest_m20//dacParameters_C0.dat .. /home/l_tester/david/FullQualification/data/M1024_FullQualification_2016-10-19_19h52m_1476899523//001_Fulltest_m20//dacParameters_C15.dat
[20:43:36.585] <TB0> INFO: readTbmDacs: /home/l_tester/david/FullQualification/data/M1024_FullQualification_2016-10-19_19h52m_1476899523//001_Fulltest_m20//tbmParameters_C0a.dat .. /home/l_tester/david/FullQualification/data/M1024_FullQualification_2016-10-19_19h52m_1476899523//001_Fulltest_m20//tbmParameters_C1b.dat
[20:43:36.585] <TB0> INFO: readMaskFile: /home/l_tester/david/FullQualification/data/M1024_FullQualification_2016-10-19_19h52m_1476899523//001_Fulltest_m20//defaultMaskFile.dat
[20:43:36.585] <TB0> INFO: readTrimFile: /home/l_tester/david/FullQualification/data/M1024_FullQualification_2016-10-19_19h52m_1476899523//001_Fulltest_m20//trimParameters_C0.dat .. /home/l_tester/david/FullQualification/data/M1024_FullQualification_2016-10-19_19h52m_1476899523//001_Fulltest_m20//trimParameters_C15.dat
[20:43:36.644] <TB0> INFO: clk: 4
[20:43:36.644] <TB0> INFO: ctr: 4
[20:43:36.644] <TB0> INFO: sda: 19
[20:43:36.644] <TB0> INFO: tin: 9
[20:43:36.644] <TB0> INFO: level: 15
[20:43:36.644] <TB0> INFO: triggerdelay: 0
[20:43:36.644] <TB0> QUIET: Instanciating API for pxar v2.7.6+55~gafdbfd9
[20:43:36.644] <TB0> INFO: Log level: INFO
[20:43:36.653] <TB0> INFO: Found DTB DTB_WRQ4OZ
[20:43:36.663] <TB0> QUIET: Connection to board DTB_WRQ4OZ opened.
[20:43:36.665] <TB0> INFO: DTB startup information
--- DTB info------------------------------------------
Board id: 71
HW version: DTB1.2
FW version: 4.6
SW version: 4.7
Options:
USB id: DTB_WRQ4OZ
MAC address: 40D855118047
Hostname: pixelDTB071
Comment:
------------------------------------------------------
[20:43:36.667] <TB0> INFO: RPC call hashes of host and DTB match: 486171790
[20:43:38.152] <TB0> INFO: DUT info:
[20:43:38.152] <TB0> INFO: The DUT currently contains the following objects:
[20:43:38.152] <TB0> INFO: 4 TBM Cores tbm10c (4 ON)
[20:43:38.152] <TB0> INFO: TBM Core alpha (0): 7 registers set
[20:43:38.152] <TB0> INFO: TBM Core beta (1): 7 registers set
[20:43:38.152] <TB0> INFO: TBM Core alpha (2): 7 registers set
[20:43:38.152] <TB0> INFO: TBM Core beta (3): 7 registers set
[20:43:38.152] <TB0> INFO: 16 ROCs proc600 (16 ON) with 4160 pixelConfigs
[20:43:38.152] <TB0> INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[20:43:38.152] <TB0> INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[20:43:38.152] <TB0> INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[20:43:38.152] <TB0> INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[20:43:38.152] <TB0> INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[20:43:38.152] <TB0> INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[20:43:38.152] <TB0> INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[20:43:38.152] <TB0> INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[20:43:38.152] <TB0> INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[20:43:38.152] <TB0> INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[20:43:38.152] <TB0> INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[20:43:38.153] <TB0> INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[20:43:38.153] <TB0> INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[20:43:38.153] <TB0> INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[20:43:38.153] <TB0> INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[20:43:38.153] <TB0> INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[20:43:38.553] <TB0> INFO: enter 'restricted' command line mode
[20:43:38.553] <TB0> INFO: enter test to run
[20:43:38.553] <TB0> INFO: test: pretest no parameter change
[20:43:38.553] <TB0> INFO: running: pretest
[20:43:38.557] <TB0> INFO: ######################################################################
[20:43:38.557] <TB0> INFO: PixTestPretest::doTest()
[20:43:38.557] <TB0> INFO: ######################################################################
[20:43:38.558] <TB0> INFO: ----------------------------------------------------------------------
[20:43:38.558] <TB0> INFO: PixTestPretest::programROC()
[20:43:38.558] <TB0> INFO: ----------------------------------------------------------------------
[20:43:56.572] <TB0> INFO: PixTestPretest::programROC() done: ROCs are all programmable
[20:43:56.572] <TB0> INFO: IA differences per ROC: 19.3 18.5 20.9 19.3 16.9 20.1 19.3 16.9 20.1 20.1 18.5 17.7 18.5 19.3 23.3 19.3
[20:43:56.627] <TB0> INFO: ----------------------------------------------------------------------
[20:43:56.627] <TB0> INFO: PixTestPretest::setVana() target Ia = 24 mA/ROC
[20:43:56.627] <TB0> INFO: ----------------------------------------------------------------------
[20:44:02.302] <TB0> INFO: PixTestPretest::setVana() done, Module Ia 387.5 mA = 24.2188 mA/ROC
[20:44:02.302] <TB0> INFO: i(loss) [mA/ROC]: 19.3 20.1 20.1 19.3 20.1 19.3 19.3 19.3 20.1 19.3 19.3 19.3 19.3 20.1 20.1 19.3
[20:44:02.331] <TB0> INFO: ----------------------------------------------------------------------
[20:44:02.331] <TB0> INFO: PixTestPretest::findTiming()
[20:44:02.331] <TB0> INFO: ----------------------------------------------------------------------
[20:44:02.331] <TB0> INFO: PixTestCmd::init()

[20:44:34.329] <TB0> INFO: TBM phases: 160MHz: 7, 400MHz: 2, TBM delays: ROC(0/1):5, header/trailer: 1, token: 1
[20:44:34.329] <TB0> INFO: (success/tries = 100/100), width = 3
[20:44:35.835] <TB0> INFO: ----------------------------------------------------------------------
[20:44:35.835] <TB0> INFO: PixTestPretest::findWorkingPixel()
[20:44:35.835] <TB0> INFO: ----------------------------------------------------------------------
[20:44:35.930] <TB0> INFO: Expecting 231680 events.
[20:44:46.022] <TB0> INFO: 231680 events read in total (9500ms).
[20:44:46.032] <TB0> INFO: Test took 10192ms.
[20:44:46.282] <TB0> INFO: Found working pixel in all ROCs: col/row = 12/22
[20:44:46.321] <TB0> INFO: ----------------------------------------------------------------------
[20:44:46.321] <TB0> INFO: PixTestPretest::setVthrCompCalDel()
[20:44:46.321] <TB0> INFO: ----------------------------------------------------------------------
[20:44:46.416] <TB0> INFO: Expecting 231680 events.
[20:44:56.526] <TB0> INFO: 231680 events read in total (9518ms).
[20:44:56.535] <TB0> INFO: Test took 10209ms.
[20:44:56.803] <TB0> INFO: PixTestPretest::setVthrCompCalDel() done
[20:44:56.803] <TB0> INFO: CalDel: 82 83 94 98 79 88 101 82 100 101 101 105 89 100 101 112
[20:44:56.803] <TB0> INFO: VthrComp: 51 51 51 51 51 51 51 51 51 51 51 51 51 51 51 51
[20:44:56.806] <TB0> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1024_FullQualification_2016-10-19_19h52m_1476899523//001_Fulltest_m20//dacParameters_C0.dat
[20:44:56.806] <TB0> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1024_FullQualification_2016-10-19_19h52m_1476899523//001_Fulltest_m20//dacParameters_C1.dat
[20:44:56.806] <TB0> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1024_FullQualification_2016-10-19_19h52m_1476899523//001_Fulltest_m20//dacParameters_C2.dat
[20:44:56.807] <TB0> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1024_FullQualification_2016-10-19_19h52m_1476899523//001_Fulltest_m20//dacParameters_C3.dat
[20:44:56.807] <TB0> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1024_FullQualification_2016-10-19_19h52m_1476899523//001_Fulltest_m20//dacParameters_C4.dat
[20:44:56.807] <TB0> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1024_FullQualification_2016-10-19_19h52m_1476899523//001_Fulltest_m20//dacParameters_C5.dat
[20:44:56.807] <TB0> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1024_FullQualification_2016-10-19_19h52m_1476899523//001_Fulltest_m20//dacParameters_C6.dat
[20:44:56.807] <TB0> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1024_FullQualification_2016-10-19_19h52m_1476899523//001_Fulltest_m20//dacParameters_C7.dat
[20:44:56.807] <TB0> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1024_FullQualification_2016-10-19_19h52m_1476899523//001_Fulltest_m20//dacParameters_C8.dat
[20:44:56.807] <TB0> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1024_FullQualification_2016-10-19_19h52m_1476899523//001_Fulltest_m20//dacParameters_C9.dat
[20:44:56.808] <TB0> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1024_FullQualification_2016-10-19_19h52m_1476899523//001_Fulltest_m20//dacParameters_C10.dat
[20:44:56.808] <TB0> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1024_FullQualification_2016-10-19_19h52m_1476899523//001_Fulltest_m20//dacParameters_C11.dat
[20:44:56.808] <TB0> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1024_FullQualification_2016-10-19_19h52m_1476899523//001_Fulltest_m20//dacParameters_C12.dat
[20:44:56.808] <TB0> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1024_FullQualification_2016-10-19_19h52m_1476899523//001_Fulltest_m20//dacParameters_C13.dat
[20:44:56.808] <TB0> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1024_FullQualification_2016-10-19_19h52m_1476899523//001_Fulltest_m20//dacParameters_C14.dat
[20:44:56.808] <TB0> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1024_FullQualification_2016-10-19_19h52m_1476899523//001_Fulltest_m20//dacParameters_C15.dat
[20:44:56.809] <TB0> INFO: write tbm parameters into /home/l_tester/david/FullQualification/data/M1024_FullQualification_2016-10-19_19h52m_1476899523//001_Fulltest_m20//tbmParameters_C0a.dat
[20:44:56.809] <TB0> INFO: write tbm parameters into /home/l_tester/david/FullQualification/data/M1024_FullQualification_2016-10-19_19h52m_1476899523//001_Fulltest_m20//tbmParameters_C0b.dat
[20:44:56.809] <TB0> INFO: write tbm parameters into /home/l_tester/david/FullQualification/data/M1024_FullQualification_2016-10-19_19h52m_1476899523//001_Fulltest_m20//tbmParameters_C1a.dat
[20:44:56.809] <TB0> INFO: write tbm parameters into /home/l_tester/david/FullQualification/data/M1024_FullQualification_2016-10-19_19h52m_1476899523//001_Fulltest_m20//tbmParameters_C1b.dat
[20:44:56.809] <TB0> INFO: PixTestPretest::doTest() done, duration: 78 seconds
[20:44:56.862] <TB0> INFO: enter test to run
[20:44:56.862] <TB0> INFO: test: FullTest no parameter change
[20:44:56.862] <TB0> INFO: running: fulltest
[20:44:56.862] <TB0> INFO: ######################################################################
[20:44:56.862] <TB0> INFO: PixTestFullTest::doTest()
[20:44:56.862] <TB0> INFO: ######################################################################
[20:44:56.864] <TB0> INFO: ######################################################################
[20:44:56.864] <TB0> INFO: PixTestAlive::doTest()
[20:44:56.864] <TB0> INFO: ######################################################################
[20:44:56.866] <TB0> INFO: ----------------------------------------------------------------------
[20:44:56.866] <TB0> INFO: PixTestAlive::aliveTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[20:44:56.866] <TB0> INFO: ----------------------------------------------------------------------
[20:44:57.148] <TB0> INFO: Expecting 41600 events.
[20:45:00.625] <TB0> INFO: 41600 events read in total (2885ms).
[20:45:00.626] <TB0> INFO: Test took 3759ms.
[20:45:00.859] <TB0> INFO: PixTestAlive::aliveTest() done
[20:45:00.859] <TB0> INFO: number of dead pixels (per ROC): 0 1 0 1 0 0 0 3 0 0 0 0 0 0 0 0
[20:45:00.861] <TB0> INFO: ----------------------------------------------------------------------
[20:45:00.861] <TB0> INFO: PixTestAlive::maskTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[20:45:00.861] <TB0> INFO: ----------------------------------------------------------------------
[20:45:01.107] <TB0> INFO: Expecting 41600 events.
[20:45:04.091] <TB0> INFO: 41600 events read in total (2392ms).
[20:45:04.091] <TB0> INFO: Test took 3228ms.
[20:45:04.092] <TB0> INFO: mask vs. old pixelAlive PixelAlive_C0_V0 .. PixelAlive_C15_V0
[20:45:04.333] <TB0> INFO: PixTestAlive::maskTest() done
[20:45:04.333] <TB0> INFO: number of mask-defect pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[20:45:04.335] <TB0> INFO: ----------------------------------------------------------------------
[20:45:04.335] <TB0> INFO: PixTestAlive::addressDecodingTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[20:45:04.335] <TB0> INFO: ----------------------------------------------------------------------
[20:45:04.581] <TB0> INFO: Expecting 41600 events.
[20:45:08.051] <TB0> INFO: 41600 events read in total (2878ms).
[20:45:08.052] <TB0> INFO: Test took 3715ms.
[20:45:08.285] <TB0> INFO: PixTestAlive::addressDecodingTest() done
[20:45:08.285] <TB0> INFO: number of address-decoding pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[20:45:08.285] <TB0> INFO: PixTestAlive::doTest() done, duration: 11 seconds
[20:45:08.286] <TB0> INFO: Decoding statistics:
[20:45:08.286] <TB0> INFO: General information:
[20:45:08.286] <TB0> INFO: 16bit words read: 0
[20:45:08.286] <TB0> INFO: valid events total: 0
[20:45:08.286] <TB0> INFO: empty events: 0
[20:45:08.286] <TB0> INFO: valid events with pixels: 0
[20:45:08.286] <TB0> INFO: valid pixel hits: 0
[20:45:08.286] <TB0> INFO: Event errors: 0
[20:45:08.286] <TB0> INFO: start marker: 0
[20:45:08.286] <TB0> INFO: stop marker: 0
[20:45:08.286] <TB0> INFO: overflow: 0
[20:45:08.286] <TB0> INFO: invalid 5bit words: 0
[20:45:08.286] <TB0> INFO: invalid XOR eye diagram: 0
[20:45:08.286] <TB0> INFO: frame (failed synchr.): 0
[20:45:08.286] <TB0> INFO: idle data (no TBM trl): 0
[20:45:08.286] <TB0> INFO: no data (only TBM hdr): 0
[20:45:08.286] <TB0> INFO: TBM errors: 0
[20:45:08.286] <TB0> INFO: flawed TBM headers: 0
[20:45:08.286] <TB0> INFO: flawed TBM trailers: 0
[20:45:08.286] <TB0> INFO: event ID mismatches: 0
[20:45:08.286] <TB0> INFO: ROC errors: 0
[20:45:08.286] <TB0> INFO: missing ROC header(s): 0
[20:45:08.286] <TB0> INFO: misplaced readback start: 0
[20:45:08.286] <TB0> INFO: Pixel decoding errors: 0
[20:45:08.286] <TB0> INFO: pixel data incomplete: 0
[20:45:08.286] <TB0> INFO: pixel address: 0
[20:45:08.286] <TB0> INFO: pulse height fill bit: 0
[20:45:08.286] <TB0> INFO: buffer corruption: 0
[20:45:08.295] <TB0> INFO: readReadbackCal: /home/l_tester/david/FullQualification/data/M1024_FullQualification_2016-10-19_19h52m_1476899523//001_Fulltest_m20//readbackCal_C0.dat .. /home/l_tester/david/FullQualification/data/M1024_FullQualification_2016-10-19_19h52m_1476899523//001_Fulltest_m20//readbackCal_C15.dat
[20:45:08.295] <TB0> INFO: readGainPedestalParameters /home/l_tester/david/FullQualification/data/M1024_FullQualification_2016-10-19_19h52m_1476899523//001_Fulltest_m20//phCalibrationFitErr_C0.dat .. /home/l_tester/david/FullQualification/data/M1024_FullQualification_2016-10-19_19h52m_1476899523//001_Fulltest_m20//phCalibrationFitErr_C15.dat

[20:45:08.295] <TB0> INFO: ######################################################################
[20:45:08.295] <TB0> INFO: PixTestReadback::doTest()
[20:45:08.295] <TB0> INFO: ######################################################################
[20:45:08.295] <TB0> INFO: ----------------------------------------------------------------------
[20:45:08.295] <TB0> INFO: PixTestReadback::CalibrateVd()
[20:45:08.295] <TB0> INFO: ----------------------------------------------------------------------
[20:45:18.259] <TB0> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1024_FullQualification_2016-10-19_19h52m_1476899523//001_Fulltest_m20//readbackCal_C0.dat
[20:45:18.259] <TB0> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1024_FullQualification_2016-10-19_19h52m_1476899523//001_Fulltest_m20//readbackCal_C1.dat
[20:45:18.259] <TB0> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1024_FullQualification_2016-10-19_19h52m_1476899523//001_Fulltest_m20//readbackCal_C2.dat
[20:45:18.259] <TB0> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1024_FullQualification_2016-10-19_19h52m_1476899523//001_Fulltest_m20//readbackCal_C3.dat
[20:45:18.259] <TB0> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1024_FullQualification_2016-10-19_19h52m_1476899523//001_Fulltest_m20//readbackCal_C4.dat
[20:45:18.259] <TB0> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1024_FullQualification_2016-10-19_19h52m_1476899523//001_Fulltest_m20//readbackCal_C5.dat
[20:45:18.259] <TB0> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1024_FullQualification_2016-10-19_19h52m_1476899523//001_Fulltest_m20//readbackCal_C6.dat
[20:45:18.259] <TB0> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1024_FullQualification_2016-10-19_19h52m_1476899523//001_Fulltest_m20//readbackCal_C7.dat
[20:45:18.259] <TB0> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1024_FullQualification_2016-10-19_19h52m_1476899523//001_Fulltest_m20//readbackCal_C8.dat
[20:45:18.259] <TB0> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1024_FullQualification_2016-10-19_19h52m_1476899523//001_Fulltest_m20//readbackCal_C9.dat
[20:45:18.259] <TB0> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1024_FullQualification_2016-10-19_19h52m_1476899523//001_Fulltest_m20//readbackCal_C10.dat
[20:45:18.259] <TB0> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1024_FullQualification_2016-10-19_19h52m_1476899523//001_Fulltest_m20//readbackCal_C11.dat
[20:45:18.259] <TB0> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1024_FullQualification_2016-10-19_19h52m_1476899523//001_Fulltest_m20//readbackCal_C12.dat
[20:45:18.260] <TB0> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1024_FullQualification_2016-10-19_19h52m_1476899523//001_Fulltest_m20//readbackCal_C13.dat
[20:45:18.260] <TB0> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1024_FullQualification_2016-10-19_19h52m_1476899523//001_Fulltest_m20//readbackCal_C14.dat
[20:45:18.260] <TB0> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1024_FullQualification_2016-10-19_19h52m_1476899523//001_Fulltest_m20//readbackCal_C15.dat
[20:45:18.292] <TB0> INFO: PixTestPattern:: pg_setup set to default.
[20:45:18.292] <TB0> INFO: ----------------------------------------------------------------------
[20:45:18.292] <TB0> INFO: PixTestReadback::CalibrateVa()
[20:45:18.292] <TB0> INFO: ----------------------------------------------------------------------
[20:45:28.212] <TB0> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1024_FullQualification_2016-10-19_19h52m_1476899523//001_Fulltest_m20//readbackCal_C0.dat
[20:45:28.212] <TB0> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1024_FullQualification_2016-10-19_19h52m_1476899523//001_Fulltest_m20//readbackCal_C1.dat
[20:45:28.212] <TB0> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1024_FullQualification_2016-10-19_19h52m_1476899523//001_Fulltest_m20//readbackCal_C2.dat
[20:45:28.212] <TB0> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1024_FullQualification_2016-10-19_19h52m_1476899523//001_Fulltest_m20//readbackCal_C3.dat
[20:45:28.212] <TB0> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1024_FullQualification_2016-10-19_19h52m_1476899523//001_Fulltest_m20//readbackCal_C4.dat
[20:45:28.212] <TB0> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1024_FullQualification_2016-10-19_19h52m_1476899523//001_Fulltest_m20//readbackCal_C5.dat
[20:45:28.212] <TB0> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1024_FullQualification_2016-10-19_19h52m_1476899523//001_Fulltest_m20//readbackCal_C6.dat
[20:45:28.212] <TB0> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1024_FullQualification_2016-10-19_19h52m_1476899523//001_Fulltest_m20//readbackCal_C7.dat
[20:45:28.213] <TB0> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1024_FullQualification_2016-10-19_19h52m_1476899523//001_Fulltest_m20//readbackCal_C8.dat
[20:45:28.213] <TB0> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1024_FullQualification_2016-10-19_19h52m_1476899523//001_Fulltest_m20//readbackCal_C9.dat
[20:45:28.213] <TB0> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1024_FullQualification_2016-10-19_19h52m_1476899523//001_Fulltest_m20//readbackCal_C10.dat
[20:45:28.213] <TB0> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1024_FullQualification_2016-10-19_19h52m_1476899523//001_Fulltest_m20//readbackCal_C11.dat
[20:45:28.213] <TB0> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1024_FullQualification_2016-10-19_19h52m_1476899523//001_Fulltest_m20//readbackCal_C12.dat
[20:45:28.213] <TB0> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1024_FullQualification_2016-10-19_19h52m_1476899523//001_Fulltest_m20//readbackCal_C13.dat
[20:45:28.213] <TB0> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1024_FullQualification_2016-10-19_19h52m_1476899523//001_Fulltest_m20//readbackCal_C14.dat
[20:45:28.213] <TB0> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1024_FullQualification_2016-10-19_19h52m_1476899523//001_Fulltest_m20//readbackCal_C15.dat
[20:45:28.240] <TB0> INFO: PixTestPattern:: pg_setup set to default.
[20:45:28.240] <TB0> INFO: ----------------------------------------------------------------------
[20:45:28.240] <TB0> INFO: PixTestReadback::readbackVbg()
[20:45:28.240] <TB0> INFO: ----------------------------------------------------------------------
[20:45:35.897] <TB0> INFO: PixTestPattern:: pg_setup set to default.
[20:45:35.897] <TB0> INFO: ----------------------------------------------------------------------
[20:45:35.897] <TB0> INFO: PixTestReadback::getCalibratedVbg()
[20:45:35.897] <TB0> INFO: ----------------------------------------------------------------------
[20:45:35.897] <TB0> INFO: Vbg will be calibrated using Vd calibration
[20:45:35.897] <TB0> INFO: /*/*/*/*::: ROC 0: uncalibrated Vbg = 154.5calibrated Vbg = 1.17101 :::*/*/*/*/
[20:45:35.897] <TB0> INFO: /*/*/*/*::: ROC 1: uncalibrated Vbg = 147.1calibrated Vbg = 1.16965 :::*/*/*/*/
[20:45:35.897] <TB0> INFO: /*/*/*/*::: ROC 2: uncalibrated Vbg = 151.6calibrated Vbg = 1.16761 :::*/*/*/*/
[20:45:35.897] <TB0> INFO: /*/*/*/*::: ROC 3: uncalibrated Vbg = 159.6calibrated Vbg = 1.16228 :::*/*/*/*/
[20:45:35.897] <TB0> INFO: /*/*/*/*::: ROC 4: uncalibrated Vbg = 156.2calibrated Vbg = 1.15984 :::*/*/*/*/
[20:45:35.897] <TB0> INFO: /*/*/*/*::: ROC 5: uncalibrated Vbg = 147.6calibrated Vbg = 1.17139 :::*/*/*/*/
[20:45:35.897] <TB0> INFO: /*/*/*/*::: ROC 6: uncalibrated Vbg = 135.7calibrated Vbg = 1.16613 :::*/*/*/*/
[20:45:35.897] <TB0> INFO: /*/*/*/*::: ROC 7: uncalibrated Vbg = 149.8calibrated Vbg = 1.16789 :::*/*/*/*/
[20:45:35.897] <TB0> INFO: /*/*/*/*::: ROC 8: uncalibrated Vbg = 148.3calibrated Vbg = 1.16683 :::*/*/*/*/
[20:45:35.897] <TB0> INFO: /*/*/*/*::: ROC 9: uncalibrated Vbg = 148.6calibrated Vbg = 1.16506 :::*/*/*/*/
[20:45:35.897] <TB0> INFO: /*/*/*/*::: ROC 10: uncalibrated Vbg = 145.5calibrated Vbg = 1.1575 :::*/*/*/*/
[20:45:35.897] <TB0> INFO: /*/*/*/*::: ROC 11: uncalibrated Vbg = 147calibrated Vbg = 1.15531 :::*/*/*/*/
[20:45:35.897] <TB0> INFO: /*/*/*/*::: ROC 12: uncalibrated Vbg = 156calibrated Vbg = 1.16289 :::*/*/*/*/
[20:45:35.897] <TB0> INFO: /*/*/*/*::: ROC 13: uncalibrated Vbg = 152calibrated Vbg = 1.16967 :::*/*/*/*/
[20:45:35.897] <TB0> INFO: /*/*/*/*::: ROC 14: uncalibrated Vbg = 147.9calibrated Vbg = 1.16414 :::*/*/*/*/
[20:45:35.897] <TB0> INFO: /*/*/*/*::: ROC 15: uncalibrated Vbg = 147.8calibrated Vbg = 1.17112 :::*/*/*/*/
[20:45:35.899] <TB0> INFO: ----------------------------------------------------------------------
[20:45:35.899] <TB0> INFO: PixTestReadback::CalibrateIa()
[20:45:35.899] <TB0> INFO: ----------------------------------------------------------------------
[20:48:16.717] <TB0> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1024_FullQualification_2016-10-19_19h52m_1476899523//001_Fulltest_m20//readbackCal_C0.dat
[20:48:16.717] <TB0> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1024_FullQualification_2016-10-19_19h52m_1476899523//001_Fulltest_m20//readbackCal_C1.dat
[20:48:16.717] <TB0> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1024_FullQualification_2016-10-19_19h52m_1476899523//001_Fulltest_m20//readbackCal_C2.dat
[20:48:16.717] <TB0> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1024_FullQualification_2016-10-19_19h52m_1476899523//001_Fulltest_m20//readbackCal_C3.dat
[20:48:16.717] <TB0> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1024_FullQualification_2016-10-19_19h52m_1476899523//001_Fulltest_m20//readbackCal_C4.dat
[20:48:16.717] <TB0> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1024_FullQualification_2016-10-19_19h52m_1476899523//001_Fulltest_m20//readbackCal_C5.dat
[20:48:16.717] <TB0> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1024_FullQualification_2016-10-19_19h52m_1476899523//001_Fulltest_m20//readbackCal_C6.dat
[20:48:16.717] <TB0> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1024_FullQualification_2016-10-19_19h52m_1476899523//001_Fulltest_m20//readbackCal_C7.dat
[20:48:16.717] <TB0> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1024_FullQualification_2016-10-19_19h52m_1476899523//001_Fulltest_m20//readbackCal_C8.dat
[20:48:16.717] <TB0> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1024_FullQualification_2016-10-19_19h52m_1476899523//001_Fulltest_m20//readbackCal_C9.dat
[20:48:16.717] <TB0> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1024_FullQualification_2016-10-19_19h52m_1476899523//001_Fulltest_m20//readbackCal_C10.dat
[20:48:16.717] <TB0> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1024_FullQualification_2016-10-19_19h52m_1476899523//001_Fulltest_m20//readbackCal_C11.dat
[20:48:16.717] <TB0> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1024_FullQualification_2016-10-19_19h52m_1476899523//001_Fulltest_m20//readbackCal_C12.dat
[20:48:16.717] <TB0> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1024_FullQualification_2016-10-19_19h52m_1476899523//001_Fulltest_m20//readbackCal_C13.dat
[20:48:16.718] <TB0> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1024_FullQualification_2016-10-19_19h52m_1476899523//001_Fulltest_m20//readbackCal_C14.dat
[20:48:16.718] <TB0> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1024_FullQualification_2016-10-19_19h52m_1476899523//001_Fulltest_m20//readbackCal_C15.dat
[20:48:16.748] <TB0> INFO: PixTestPattern:: pg_setup set to default.
[20:48:16.751] <TB0> INFO: PixTestReadback::doTest() done
[20:48:16.751] <TB0> INFO: Decoding statistics:
[20:48:16.751] <TB0> INFO: General information:
[20:48:16.751] <TB0> INFO: 16bit words read: 1536
[20:48:16.751] <TB0> INFO: valid events total: 256
[20:48:16.751] <TB0> INFO: empty events: 256
[20:48:16.751] <TB0> INFO: valid events with pixels: 0
[20:48:16.751] <TB0> INFO: valid pixel hits: 0
[20:48:16.751] <TB0> INFO: Event errors: 0
[20:48:16.751] <TB0> INFO: start marker: 0
[20:48:16.751] <TB0> INFO: stop marker: 0
[20:48:16.751] <TB0> INFO: overflow: 0
[20:48:16.751] <TB0> INFO: invalid 5bit words: 0
[20:48:16.751] <TB0> INFO: invalid XOR eye diagram: 0
[20:48:16.751] <TB0> INFO: frame (failed synchr.): 0
[20:48:16.751] <TB0> INFO: idle data (no TBM trl): 0
[20:48:16.751] <TB0> INFO: no data (only TBM hdr): 0
[20:48:16.751] <TB0> INFO: TBM errors: 0
[20:48:16.751] <TB0> INFO: flawed TBM headers: 0
[20:48:16.751] <TB0> INFO: flawed TBM trailers: 0
[20:48:16.751] <TB0> INFO: event ID mismatches: 0
[20:48:16.751] <TB0> INFO: ROC errors: 0
[20:48:16.752] <TB0> INFO: missing ROC header(s): 0
[20:48:16.752] <TB0> INFO: misplaced readback start: 0
[20:48:16.752] <TB0> INFO: Pixel decoding errors: 0
[20:48:16.752] <TB0> INFO: pixel data incomplete: 0
[20:48:16.752] <TB0> INFO: pixel address: 0
[20:48:16.752] <TB0> INFO: pulse height fill bit: 0
[20:48:16.752] <TB0> INFO: buffer corruption: 0
[20:48:16.804] <TB0> INFO: ######################################################################
[20:48:16.804] <TB0> INFO: PixTestBBMap::doTest() Ntrig = 5, VcalS = 250 (high range)
[20:48:16.804] <TB0> INFO: ######################################################################
[20:48:16.806] <TB0> INFO: ---> dac: VthrComp name: calSMap ntrig: 5 dacrange: 0 .. 149 (-1/-1) hits flags = 514 (plus default)
[20:48:16.820] <TB0> INFO: dacScan split into 1 runs with ntrig = 5
[20:48:16.820] <TB0> INFO: run 1 of 1
[20:48:17.056] <TB0> INFO: Expecting 3120000 events.
[20:48:48.652] <TB0> INFO: 650840 events read in total (31004ms).











[20:49:17.832] <TB0> INFO: 1293550 events read in total (60184ms).
[20:49:47.203] <TB0> INFO: 1932745 events read in total (89555ms).





















[20:50:16.749] <TB0> INFO: 2572430 events read in total (119101ms).











[20:50:42.030] <TB0> INFO: 3120000 events read in total (144382ms).
[20:50:42.106] <TB0> INFO: Test took 145286ms.
[20:51:05.672] <TB0> INFO: PixTestBBMap::doTest() done with 1 decoding errors: , duration: 168 seconds
[20:51:05.672] <TB0> INFO: number of dead bumps (per ROC): 262 66 5 5 1 0 0 2 0 0 0 1 3 0 2 51
[20:51:05.672] <TB0> INFO: separation cut (per ROC): 90 96 115 114 106 101 97 99 108 107 101 111 102 106 109 99
[20:51:05.672] <TB0> INFO: Decoding statistics:
[20:51:05.672] <TB0> INFO: General information:
[20:51:05.672] <TB0> INFO: 16bit words read: 0
[20:51:05.672] <TB0> INFO: valid events total: 0
[20:51:05.672] <TB0> INFO: empty events: 0
[20:51:05.672] <TB0> INFO: valid events with pixels: 0
[20:51:05.672] <TB0> INFO: valid pixel hits: 0
[20:51:05.672] <TB0> INFO: Event errors: 0
[20:51:05.672] <TB0> INFO: start marker: 0
[20:51:05.672] <TB0> INFO: stop marker: 0
[20:51:05.672] <TB0> INFO: overflow: 0
[20:51:05.672] <TB0> INFO: invalid 5bit words: 0
[20:51:05.672] <TB0> INFO: invalid XOR eye diagram: 0
[20:51:05.672] <TB0> INFO: frame (failed synchr.): 0
[20:51:05.672] <TB0> INFO: idle data (no TBM trl): 0
[20:51:05.672] <TB0> INFO: no data (only TBM hdr): 0
[20:51:05.672] <TB0> INFO: TBM errors: 0
[20:51:05.672] <TB0> INFO: flawed TBM headers: 0
[20:51:05.672] <TB0> INFO: flawed TBM trailers: 0
[20:51:05.672] <TB0> INFO: event ID mismatches: 0
[20:51:05.672] <TB0> INFO: ROC errors: 0
[20:51:05.672] <TB0> INFO: missing ROC header(s): 0
[20:51:05.673] <TB0> INFO: misplaced readback start: 0
[20:51:05.673] <TB0> INFO: Pixel decoding errors: 0
[20:51:05.673] <TB0> INFO: pixel data incomplete: 0
[20:51:05.673] <TB0> INFO: pixel address: 0
[20:51:05.673] <TB0> INFO: pulse height fill bit: 0
[20:51:05.673] <TB0> INFO: buffer corruption: 0
[20:51:05.735] <TB0> INFO: ######################################################################
[20:51:05.735] <TB0> INFO: PixTestScurves::fullTest() ntrig = 50, dacs/step = -1, ntrig/step = -1
[20:51:05.735] <TB0> INFO: ######################################################################
[20:51:05.735] <TB0> INFO: ----------------------------------------------------------------------
[20:51:05.735] <TB0> INFO: PixTestScurves::scurves(Vcal), ntrig = 50, dacs/step = -1, ntrig/step = -1
[20:51:05.735] <TB0> INFO: ----------------------------------------------------------------------
[20:51:05.735] <TB0> INFO: ---> dac: Vcal name: scurveVcal ntrig: 50 dacrange: 25 .. 200 (-1/-1) hits flags = 528 (plus default)
[20:51:05.749] <TB0> INFO: dacScan split into 1 runs with ntrig = 50
[20:51:05.749] <TB0> INFO: run 1 of 1
[20:51:05.002] <TB0> INFO: Expecting 36608000 events.
[20:51:29.601] <TB0> INFO: 658150 events read in total (23007ms).
[20:51:52.255] <TB0> INFO: 1307600 events read in total (45661ms).
[20:52:14.874] <TB0> INFO: 1956400 events read in total (68280ms).
[20:52:37.337] <TB0> INFO: 2604600 events read in total (90743ms).
[20:52:59.912] <TB0> INFO: 3251950 events read in total (113318ms).
[20:53:22.604] <TB0> INFO: 3899900 events read in total (136010ms).
[20:53:44.874] <TB0> INFO: 4547400 events read in total (158280ms).
[20:54:07.238] <TB0> INFO: 5191900 events read in total (180644ms).
[20:54:29.734] <TB0> INFO: 5837550 events read in total (203140ms).
[20:54:52.156] <TB0> INFO: 6482750 events read in total (225562ms).
[20:55:14.502] <TB0> INFO: 7130400 events read in total (247908ms).
[20:55:36.647] <TB0> INFO: 7776200 events read in total (270053ms).
[20:55:59.245] <TB0> INFO: 8420900 events read in total (292651ms).
[20:56:22.459] <TB0> INFO: 9066900 events read in total (315865ms).
[20:56:45.200] <TB0> INFO: 9712100 events read in total (338606ms).
[20:57:07.826] <TB0> INFO: 10356750 events read in total (361232ms).
[20:57:30.272] <TB0> INFO: 11000350 events read in total (383678ms).
[20:57:52.583] <TB0> INFO: 11645800 events read in total (405989ms).
[20:58:14.769] <TB0> INFO: 12290300 events read in total (428175ms).
[20:58:37.326] <TB0> INFO: 12933050 events read in total (450732ms).
[20:58:59.493] <TB0> INFO: 13575950 events read in total (472899ms).
[20:59:21.809] <TB0> INFO: 14218900 events read in total (495215ms).
[20:59:44.154] <TB0> INFO: 14861600 events read in total (517560ms).
[21:00:06.442] <TB0> INFO: 15504250 events read in total (539848ms).
[21:00:28.912] <TB0> INFO: 16144950 events read in total (562318ms).
[21:00:51.341] <TB0> INFO: 16785700 events read in total (584747ms).
[21:01:14.049] <TB0> INFO: 17426750 events read in total (607455ms).
[21:01:36.411] <TB0> INFO: 18066700 events read in total (629817ms).
[21:01:58.686] <TB0> INFO: 18707750 events read in total (652092ms).
[21:02:21.431] <TB0> INFO: 19348950 events read in total (674837ms).
[21:02:44.025] <TB0> INFO: 19990100 events read in total (697431ms).
[21:03:06.680] <TB0> INFO: 20631050 events read in total (720086ms).
[21:03:29.234] <TB0> INFO: 21270850 events read in total (742640ms).
[21:03:51.928] <TB0> INFO: 21909700 events read in total (765334ms).
[21:04:14.528] <TB0> INFO: 22549350 events read in total (787934ms).
[21:04:36.714] <TB0> INFO: 23187450 events read in total (810120ms).
[21:04:59.163] <TB0> INFO: 23826650 events read in total (832569ms).
[21:05:21.392] <TB0> INFO: 24463750 events read in total (854798ms).
[21:05:43.804] <TB0> INFO: 25102900 events read in total (877210ms).
[21:06:06.134] <TB0> INFO: 25740200 events read in total (899540ms).
[21:06:28.588] <TB0> INFO: 26380400 events read in total (921994ms).
[21:06:51.192] <TB0> INFO: 27019050 events read in total (944598ms).
[21:07:13.725] <TB0> INFO: 27655950 events read in total (967131ms).
[21:07:36.065] <TB0> INFO: 28293600 events read in total (989471ms).
[21:07:58.401] <TB0> INFO: 28930350 events read in total (1011807ms).
[21:08:20.737] <TB0> INFO: 29568000 events read in total (1034143ms).
[21:08:43.152] <TB0> INFO: 30205000 events read in total (1056558ms).
[21:09:05.303] <TB0> INFO: 30842700 events read in total (1078709ms).
[21:09:27.918] <TB0> INFO: 31480550 events read in total (1101325ms).
[21:09:50.595] <TB0> INFO: 32118650 events read in total (1124001ms).
[21:10:13.163] <TB0> INFO: 32757400 events read in total (1146569ms).
[21:10:35.760] <TB0> INFO: 33395950 events read in total (1169166ms).
[21:10:58.129] <TB0> INFO: 34035350 events read in total (1191535ms).
[21:11:20.514] <TB0> INFO: 34672650 events read in total (1213920ms).
[21:11:43.493] <TB0> INFO: 35312750 events read in total (1236899ms).
[21:12:05.894] <TB0> INFO: 35952450 events read in total (1259300ms).
[21:12:28.439] <TB0> INFO: 36601000 events read in total (1281845ms).
[21:12:29.084] <TB0> INFO: 36608000 events read in total (1282490ms).
[21:12:29.187] <TB0> INFO: Test took 1283438ms.
[21:12:29.675] <TB0> INFO: dumping ASCII scurve output file: SCurveData
[21:12:31.290] <TB0> INFO: dumping ASCII scurve output file: SCurveData
[21:12:33.157] <TB0> INFO: dumping ASCII scurve output file: SCurveData
[21:12:34.851] <TB0> INFO: dumping ASCII scurve output file: SCurveData
[21:12:36.520] <TB0> INFO: dumping ASCII scurve output file: SCurveData
[21:12:38.108] <TB0> INFO: dumping ASCII scurve output file: SCurveData
[21:12:39.702] <TB0> INFO: dumping ASCII scurve output file: SCurveData
[21:12:41.257] <TB0> INFO: dumping ASCII scurve output file: SCurveData
[21:12:43.876] <TB0> INFO: dumping ASCII scurve output file: SCurveData
[21:12:45.992] <TB0> INFO: dumping ASCII scurve output file: SCurveData
[21:12:48.065] <TB0> INFO: dumping ASCII scurve output file: SCurveData
[21:12:50.083] <TB0> INFO: dumping ASCII scurve output file: SCurveData
[21:12:52.077] <TB0> INFO: dumping ASCII scurve output file: SCurveData
[21:12:54.082] <TB0> INFO: dumping ASCII scurve output file: SCurveData
[21:12:55.862] <TB0> INFO: dumping ASCII scurve output file: SCurveData
[21:12:57.892] <TB0> INFO: dumping ASCII scurve output file: SCurveData
[21:13:00.051] <TB0> INFO: PixTestScurves::scurves() done
[21:13:00.051] <TB0> INFO: Vcal mean: 100.30 104.55 111.43 118.93 108.42 102.52 92.33 107.48 110.14 108.85 100.77 110.55 106.40 115.30 104.13 110.02
[21:13:00.051] <TB0> INFO: Vcal RMS: 6.50 5.31 5.72 7.61 5.46 4.86 4.76 5.60 5.13 4.76 5.79 4.85 4.92 5.07 5.22 5.26
[21:13:00.051] <TB0> INFO: PixTestScurves::fullTest() done, duration: 1314 seconds
[21:13:00.051] <TB0> INFO: Decoding statistics:
[21:13:00.051] <TB0> INFO: General information:
[21:13:00.051] <TB0> INFO: 16bit words read: 0
[21:13:00.051] <TB0> INFO: valid events total: 0
[21:13:00.051] <TB0> INFO: empty events: 0
[21:13:00.051] <TB0> INFO: valid events with pixels: 0
[21:13:00.051] <TB0> INFO: valid pixel hits: 0
[21:13:00.051] <TB0> INFO: Event errors: 0
[21:13:00.052] <TB0> INFO: start marker: 0
[21:13:00.052] <TB0> INFO: stop marker: 0
[21:13:00.052] <TB0> INFO: overflow: 0
[21:13:00.052] <TB0> INFO: invalid 5bit words: 0
[21:13:00.052] <TB0> INFO: invalid XOR eye diagram: 0
[21:13:00.052] <TB0> INFO: frame (failed synchr.): 0
[21:13:00.052] <TB0> INFO: idle data (no TBM trl): 0
[21:13:00.052] <TB0> INFO: no data (only TBM hdr): 0
[21:13:00.052] <TB0> INFO: TBM errors: 0
[21:13:00.052] <TB0> INFO: flawed TBM headers: 0
[21:13:00.052] <TB0> INFO: flawed TBM trailers: 0
[21:13:00.052] <TB0> INFO: event ID mismatches: 0
[21:13:00.052] <TB0> INFO: ROC errors: 0
[21:13:00.052] <TB0> INFO: missing ROC header(s): 0
[21:13:00.052] <TB0> INFO: misplaced readback start: 0
[21:13:00.052] <TB0> INFO: Pixel decoding errors: 0
[21:13:00.052] <TB0> INFO: pixel data incomplete: 0
[21:13:00.052] <TB0> INFO: pixel address: 0
[21:13:00.052] <TB0> INFO: pulse height fill bit: 0
[21:13:00.052] <TB0> INFO: buffer corruption: 0
[21:13:00.121] <TB0> INFO: ######################################################################
[21:13:00.121] <TB0> INFO: PixTestTrim::doTest()
[21:13:00.121] <TB0> INFO: ######################################################################
[21:13:00.123] <TB0> INFO: ----------------------------------------------------------------------
[21:13:00.123] <TB0> INFO: PixTestTrim::trimTest() ntrig = 8, vcal = 35
[21:13:00.123] <TB0> INFO: ----------------------------------------------------------------------
[21:13:00.187] <TB0> INFO: ---> VthrComp thr map (minimal VthrComp)
[21:13:00.187] <TB0> INFO: ---> dac: vthrcomp name: TrimThr0 ntrig: 8 dacrange: 10 .. 160 (-1/-1) hits flags = 528 (plus default)
[21:13:00.202] <TB0> INFO: dacScan split into 1 runs with ntrig = 8
[21:13:00.202] <TB0> INFO: run 1 of 1
[21:13:00.464] <TB0> INFO: Expecting 5025280 events.
[21:13:30.669] <TB0> INFO: 811088 events read in total (29603ms).
[21:14:00.115] <TB0> INFO: 1618152 events read in total (59049ms).
[21:14:29.683] <TB0> INFO: 2420224 events read in total (88617ms).
[21:14:59.465] <TB0> INFO: 3217896 events read in total (118399ms).
[21:15:29.178] <TB0> INFO: 4011544 events read in total (148112ms).
[21:15:58.795] <TB0> INFO: 4803144 events read in total (177729ms).
[21:16:07.687] <TB0> INFO: 5025280 events read in total (186621ms).
[21:16:07.751] <TB0> INFO: Test took 187549ms.
[21:16:26.396] <TB0> INFO: ROC 0 VthrComp = 115
[21:16:26.397] <TB0> INFO: ROC 1 VthrComp = 117
[21:16:26.397] <TB0> INFO: ROC 2 VthrComp = 121
[21:16:26.397] <TB0> INFO: ROC 3 VthrComp = 124
[21:16:26.397] <TB0> INFO: ROC 4 VthrComp = 118
[21:16:26.397] <TB0> INFO: ROC 5 VthrComp = 116
[21:16:26.397] <TB0> INFO: ROC 6 VthrComp = 107
[21:16:26.397] <TB0> INFO: ROC 7 VthrComp = 119
[21:16:26.398] <TB0> INFO: ROC 8 VthrComp = 119
[21:16:26.398] <TB0> INFO: ROC 9 VthrComp = 117
[21:16:26.398] <TB0> INFO: ROC 10 VthrComp = 107
[21:16:26.398] <TB0> INFO: ROC 11 VthrComp = 117
[21:16:26.398] <TB0> INFO: ROC 12 VthrComp = 110
[21:16:26.398] <TB0> INFO: ROC 13 VthrComp = 124
[21:16:26.398] <TB0> INFO: ROC 14 VthrComp = 121
[21:16:26.398] <TB0> INFO: ROC 15 VthrComp = 119
[21:16:26.641] <TB0> INFO: Expecting 41600 events.
[21:16:30.184] <TB0> INFO: 41600 events read in total (2952ms).
[21:16:30.185] <TB0> INFO: Test took 3785ms.
[21:16:30.195] <TB0> INFO: ---> Vcal thr map (pixel with maximum Vcal thr)
[21:16:30.195] <TB0> INFO: ---> dac: vcal name: TrimThr1 ntrig: 8 dacrange: 10 .. 160 (-1/-1) hits flags = 528 (plus default)
[21:16:30.208] <TB0> INFO: dacScan split into 1 runs with ntrig = 8
[21:16:30.208] <TB0> INFO: run 1 of 1
[21:16:30.486] <TB0> INFO: Expecting 5025280 events.
[21:16:56.358] <TB0> INFO: 588744 events read in total (25281ms).
[21:17:22.357] <TB0> INFO: 1176056 events read in total (51280ms).
[21:17:48.629] <TB0> INFO: 1763120 events read in total (77552ms).
[21:18:13.771] <TB0> INFO: 2349344 events read in total (102694ms).
[21:18:39.329] <TB0> INFO: 2933736 events read in total (128252ms).
[21:19:05.056] <TB0> INFO: 3516376 events read in total (153979ms).
[21:19:30.896] <TB0> INFO: 4098440 events read in total (179819ms).
[21:19:56.826] <TB0> INFO: 4680336 events read in total (205749ms).
[21:20:12.631] <TB0> INFO: 5025280 events read in total (221554ms).
[21:20:12.731] <TB0> INFO: Test took 222523ms.
[21:20:40.626] <TB0> INFO: roc 0 with ID = 0 has maximal Vcal 55.9386 for pixel 6/75 mean/min/max = 43.9621/31.7726/56.1516
[21:20:40.626] <TB0> INFO: roc 1 with ID = 1 has maximal Vcal 56.5487 for pixel 2/17 mean/min/max = 44.3634/32.1776/56.5491
[21:20:40.627] <TB0> INFO: roc 2 with ID = 2 has maximal Vcal 60.7992 for pixel 0/12 mean/min/max = 45.9938/30.9872/61.0003
[21:20:40.627] <TB0> INFO: roc 3 with ID = 3 has maximal Vcal 64.6654 for pixel 5/58 mean/min/max = 47.1435/29.3963/64.8907
[21:20:40.628] <TB0> INFO: roc 4 with ID = 4 has maximal Vcal 59.4271 for pixel 2/11 mean/min/max = 45.3711/31.0259/59.7163
[21:20:40.629] <TB0> INFO: roc 5 with ID = 5 has maximal Vcal 55.1275 for pixel 26/76 mean/min/max = 43.8721/32.4303/55.3139
[21:20:40.629] <TB0> INFO: roc 6 with ID = 6 has maximal Vcal 54.9218 for pixel 33/66 mean/min/max = 44.6348/34.2516/55.018
[21:20:40.630] <TB0> INFO: roc 7 with ID = 7 has maximal Vcal 56.455 for pixel 0/1 mean/min/max = 44.1462/31.826/56.4665
[21:20:40.630] <TB0> INFO: roc 8 with ID = 8 has maximal Vcal 59.2359 for pixel 10/79 mean/min/max = 45.3342/31.4067/59.2617
[21:20:40.631] <TB0> INFO: roc 9 with ID = 9 has maximal Vcal 57.7967 for pixel 14/69 mean/min/max = 45.2353/32.6376/57.833
[21:20:40.632] <TB0> INFO: roc 10 with ID = 10 has maximal Vcal 59.7047 for pixel 5/41 mean/min/max = 46.6963/33.6836/59.7089
[21:20:40.632] <TB0> INFO: roc 11 with ID = 11 has maximal Vcal 58.4934 for pixel 9/8 mean/min/max = 45.3248/31.9652/58.6843
[21:20:40.633] <TB0> INFO: roc 12 with ID = 12 has maximal Vcal 60.3889 for pixel 0/0 mean/min/max = 47.4589/34.4576/60.4602
[21:20:40.633] <TB0> INFO: roc 13 with ID = 13 has maximal Vcal 56.8215 for pixel 21/75 mean/min/max = 44.398/31.8352/56.9608
[21:20:40.634] <TB0> INFO: roc 14 with ID = 14 has maximal Vcal 56.227 for pixel 6/61 mean/min/max = 43.8708/31.2245/56.5172
[21:20:40.634] <TB0> INFO: roc 15 with ID = 15 has maximal Vcal 56.8731 for pixel 0/62 mean/min/max = 44.1302/31.3327/56.9278
[21:20:40.635] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[21:20:40.724] <TB0> INFO: Expecting 411648 events.
[21:20:50.368] <TB0> INFO: 411648 events read in total (9053ms).
[21:20:50.376] <TB0> INFO: Expecting 411648 events.
[21:20:59.698] <TB0> INFO: 411648 events read in total (8919ms).
[21:20:59.710] <TB0> INFO: Expecting 411648 events.
[21:21:09.215] <TB0> INFO: 411648 events read in total (9102ms).
[21:21:09.229] <TB0> INFO: Expecting 411648 events.
[21:21:18.667] <TB0> INFO: 411648 events read in total (9035ms).
[21:21:18.688] <TB0> INFO: Expecting 411648 events.
[21:21:28.218] <TB0> INFO: 411648 events read in total (9127ms).
[21:21:28.237] <TB0> INFO: Expecting 411648 events.
[21:21:37.648] <TB0> INFO: 411648 events read in total (9008ms).
[21:21:37.670] <TB0> INFO: Expecting 411648 events.
[21:21:47.117] <TB0> INFO: 411648 events read in total (9044ms).
[21:21:47.144] <TB0> INFO: Expecting 411648 events.
[21:21:56.464] <TB0> INFO: 411648 events read in total (8917ms).
[21:21:56.491] <TB0> INFO: Expecting 411648 events.
[21:22:05.963] <TB0> INFO: 411648 events read in total (9069ms).
[21:22:05.994] <TB0> INFO: Expecting 411648 events.
[21:22:15.472] <TB0> INFO: 411648 events read in total (9075ms).
[21:22:15.505] <TB0> INFO: Expecting 411648 events.
[21:22:24.922] <TB0> INFO: 411648 events read in total (9014ms).
[21:22:24.961] <TB0> INFO: Expecting 411648 events.
[21:22:34.473] <TB0> INFO: 411648 events read in total (9108ms).
[21:22:34.515] <TB0> INFO: Expecting 411648 events.
[21:22:43.890] <TB0> INFO: 411648 events read in total (8972ms).
[21:22:43.931] <TB0> INFO: Expecting 411648 events.
[21:22:53.291] <TB0> INFO: 411648 events read in total (8957ms).
[21:22:53.343] <TB0> INFO: Expecting 411648 events.
[21:23:02.714] <TB0> INFO: 411648 events read in total (8968ms).
[21:23:02.768] <TB0> INFO: Expecting 411648 events.
[21:23:12.165] <TB0> INFO: 411648 events read in total (8994ms).
[21:23:12.216] <TB0> INFO: Test took 151581ms.
[21:23:13.045] <TB0> INFO: ---> dac: vcal name: TrimThr2 ntrig: 8 dacrange: 0 .. 150 (-1/-1) hits flags = 528 (plus default)
[21:23:13.057] <TB0> INFO: dacScan split into 1 runs with ntrig = 8
[21:23:13.057] <TB0> INFO: run 1 of 1
[21:23:13.293] <TB0> INFO: Expecting 5025280 events.
[21:23:39.560] <TB0> INFO: 583136 events read in total (25675ms).
[21:24:04.923] <TB0> INFO: 1165064 events read in total (51038ms).
[21:24:30.667] <TB0> INFO: 1746744 events read in total (76782ms).
[21:24:56.570] <TB0> INFO: 2327592 events read in total (102685ms).
[21:25:22.398] <TB0> INFO: 2907632 events read in total (128513ms).
[21:25:48.561] <TB0> INFO: 3486336 events read in total (154676ms).
[21:26:14.600] <TB0> INFO: 4064768 events read in total (180715ms).
[21:26:40.649] <TB0> INFO: 4642696 events read in total (206764ms).
[21:26:58.064] <TB0> INFO: 5025280 events read in total (224179ms).
[21:26:58.206] <TB0> INFO: Test took 225149ms.
[21:27:25.734] <TB0> INFO: ---> TrimStepCorr4 extremal thresholds: 1.500000 .. 145.530469
[21:27:26.061] <TB0> INFO: Expecting 208000 events.
[21:27:36.709] <TB0> INFO: 208000 events read in total (10056ms).
[21:27:36.711] <TB0> INFO: Test took 10975ms.
[21:27:36.761] <TB0> INFO: ---> dac: vcal name: trimStepCorr4 ntrig: 8 dacrange: 1 .. 155 (-1/-1) hits flags = 528 (plus default)
[21:27:36.774] <TB0> INFO: dacScan split into 1 runs with ntrig = 8
[21:27:36.774] <TB0> INFO: run 1 of 1
[21:27:37.052] <TB0> INFO: Expecting 5158400 events.
[21:28:03.137] <TB0> INFO: 584032 events read in total (25493ms).
[21:28:28.788] <TB0> INFO: 1168288 events read in total (51144ms).
[21:28:54.204] <TB0> INFO: 1752576 events read in total (76560ms).
[21:29:19.883] <TB0> INFO: 2336888 events read in total (102239ms).
[21:29:45.791] <TB0> INFO: 2920856 events read in total (128147ms).
[21:30:11.200] <TB0> INFO: 3503568 events read in total (153556ms).
[21:30:36.906] <TB0> INFO: 4086016 events read in total (179262ms).
[21:31:02.727] <TB0> INFO: 4667608 events read in total (205083ms).
[21:31:24.124] <TB0> INFO: 5158400 events read in total (226480ms).
[21:31:24.213] <TB0> INFO: Test took 227439ms.
[21:31:52.836] <TB0> INFO: ---> TrimStepCorr2 extremal thresholds: 25.210645 .. 44.284031
[21:31:53.105] <TB0> INFO: Expecting 208000 events.
[21:32:03.414] <TB0> INFO: 208000 events read in total (9718ms).
[21:32:03.415] <TB0> INFO: Test took 10577ms.
[21:32:03.496] <TB0> INFO: ---> dac: vcal name: trimStepCorr2 ntrig: 8 dacrange: 15 .. 54 (-1/-1) hits flags = 528 (plus default)
[21:32:03.510] <TB0> INFO: dacScan split into 1 runs with ntrig = 8
[21:32:03.510] <TB0> INFO: run 1 of 1
[21:32:03.801] <TB0> INFO: Expecting 1331200 events.
[21:32:32.437] <TB0> INFO: 670904 events read in total (28044ms).
[21:33:00.054] <TB0> INFO: 1331200 events read in total (55661ms).
[21:33:00.088] <TB0> INFO: Test took 56578ms.
[21:33:14.673] <TB0> INFO: ---> TrimStepCorr1a extremal thresholds: 24.881820 .. 45.010258
[21:33:14.959] <TB0> INFO: Expecting 208000 events.
[21:33:25.245] <TB0> INFO: 208000 events read in total (9694ms).
[21:33:25.246] <TB0> INFO: Test took 10571ms.
[21:33:25.295] <TB0> INFO: ---> dac: vcal name: trimStepCorr1a ntrig: 8 dacrange: 14 .. 55 (-1/-1) hits flags = 528 (plus default)
[21:33:25.307] <TB0> INFO: dacScan split into 1 runs with ntrig = 8
[21:33:25.307] <TB0> INFO: run 1 of 1
[21:33:25.585] <TB0> INFO: Expecting 1397760 events.
[21:33:54.360] <TB0> INFO: 670760 events read in total (28183ms).
[21:34:22.288] <TB0> INFO: 1339960 events read in total (56111ms).
[21:34:25.219] <TB0> INFO: 1397760 events read in total (59043ms).
[21:34:25.250] <TB0> INFO: Test took 59942ms.
[21:34:38.827] <TB0> INFO: ---> TrimStepCorr1b extremal thresholds: 23.335053 .. 41.981174
[21:34:39.151] <TB0> INFO: Expecting 208000 events.
[21:34:49.271] <TB0> INFO: 208000 events read in total (9528ms).
[21:34:49.272] <TB0> INFO: Test took 10443ms.
[21:34:49.344] <TB0> INFO: ---> dac: vcal name: trimStepCorr1b ntrig: 8 dacrange: 13 .. 51 (-1/-1) hits flags = 528 (plus default)
[21:34:49.358] <TB0> INFO: dacScan split into 1 runs with ntrig = 8
[21:34:49.358] <TB0> INFO: run 1 of 1
[21:34:49.671] <TB0> INFO: Expecting 1297920 events.
[21:35:19.308] <TB0> INFO: 694368 events read in total (29046ms).
[21:35:43.418] <TB0> INFO: 1297920 events read in total (53157ms).
[21:35:43.446] <TB0> INFO: Test took 54088ms.
[21:35:57.757] <TB0> INFO: ---> TrimThrFinal extremal thresholds: 15 .. 55
[21:35:57.757] <TB0> INFO: ---> dac: vcal name: TrimThrFinal ntrig: 8 dacrange: 15 .. 55 (-1/-1) hits flags = 528 (plus default)
[21:35:57.770] <TB0> INFO: dacScan split into 1 runs with ntrig = 8
[21:35:57.770] <TB0> INFO: run 1 of 1
[21:35:58.023] <TB0> INFO: Expecting 1364480 events.
[21:36:26.398] <TB0> INFO: 666776 events read in total (27782ms).
[21:36:54.636] <TB0> INFO: 1332944 events read in total (56020ms).
[21:36:56.386] <TB0> INFO: 1364480 events read in total (57770ms).
[21:36:56.412] <TB0> INFO: Test took 58642ms.
[21:37:09.079] <TB0> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1024_FullQualification_2016-10-19_19h52m_1476899523//001_Fulltest_m20//dacParameters35_C0.dat
[21:37:09.079] <TB0> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1024_FullQualification_2016-10-19_19h52m_1476899523//001_Fulltest_m20//dacParameters35_C1.dat
[21:37:09.079] <TB0> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1024_FullQualification_2016-10-19_19h52m_1476899523//001_Fulltest_m20//dacParameters35_C2.dat
[21:37:09.079] <TB0> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1024_FullQualification_2016-10-19_19h52m_1476899523//001_Fulltest_m20//dacParameters35_C3.dat
[21:37:09.079] <TB0> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1024_FullQualification_2016-10-19_19h52m_1476899523//001_Fulltest_m20//dacParameters35_C4.dat
[21:37:09.080] <TB0> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1024_FullQualification_2016-10-19_19h52m_1476899523//001_Fulltest_m20//dacParameters35_C5.dat
[21:37:09.080] <TB0> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1024_FullQualification_2016-10-19_19h52m_1476899523//001_Fulltest_m20//dacParameters35_C6.dat
[21:37:09.080] <TB0> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1024_FullQualification_2016-10-19_19h52m_1476899523//001_Fulltest_m20//dacParameters35_C7.dat
[21:37:09.080] <TB0> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1024_FullQualification_2016-10-19_19h52m_1476899523//001_Fulltest_m20//dacParameters35_C8.dat
[21:37:09.080] <TB0> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1024_FullQualification_2016-10-19_19h52m_1476899523//001_Fulltest_m20//dacParameters35_C9.dat
[21:37:09.080] <TB0> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1024_FullQualification_2016-10-19_19h52m_1476899523//001_Fulltest_m20//dacParameters35_C10.dat
[21:37:09.080] <TB0> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1024_FullQualification_2016-10-19_19h52m_1476899523//001_Fulltest_m20//dacParameters35_C11.dat
[21:37:09.080] <TB0> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1024_FullQualification_2016-10-19_19h52m_1476899523//001_Fulltest_m20//dacParameters35_C12.dat
[21:37:09.080] <TB0> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1024_FullQualification_2016-10-19_19h52m_1476899523//001_Fulltest_m20//dacParameters35_C13.dat
[21:37:09.080] <TB0> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1024_FullQualification_2016-10-19_19h52m_1476899523//001_Fulltest_m20//dacParameters35_C14.dat
[21:37:09.080] <TB0> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1024_FullQualification_2016-10-19_19h52m_1476899523//001_Fulltest_m20//dacParameters35_C15.dat
[21:37:09.080] <TB0> INFO: write trim parameters into /home/l_tester/david/FullQualification/data/M1024_FullQualification_2016-10-19_19h52m_1476899523//001_Fulltest_m20//trimParameters35_C0.dat
[21:37:09.085] <TB0> INFO: write trim parameters into /home/l_tester/david/FullQualification/data/M1024_FullQualification_2016-10-19_19h52m_1476899523//001_Fulltest_m20//trimParameters35_C1.dat
[21:37:09.090] <TB0> INFO: write trim parameters into /home/l_tester/david/FullQualification/data/M1024_FullQualification_2016-10-19_19h52m_1476899523//001_Fulltest_m20//trimParameters35_C2.dat
[21:37:09.094] <TB0> INFO: write trim parameters into /home/l_tester/david/FullQualification/data/M1024_FullQualification_2016-10-19_19h52m_1476899523//001_Fulltest_m20//trimParameters35_C3.dat
[21:37:09.099] <TB0> INFO: write trim parameters into /home/l_tester/david/FullQualification/data/M1024_FullQualification_2016-10-19_19h52m_1476899523//001_Fulltest_m20//trimParameters35_C4.dat
[21:37:09.104] <TB0> INFO: write trim parameters into /home/l_tester/david/FullQualification/data/M1024_FullQualification_2016-10-19_19h52m_1476899523//001_Fulltest_m20//trimParameters35_C5.dat
[21:37:09.109] <TB0> INFO: write trim parameters into /home/l_tester/david/FullQualification/data/M1024_FullQualification_2016-10-19_19h52m_1476899523//001_Fulltest_m20//trimParameters35_C6.dat
[21:37:09.114] <TB0> INFO: write trim parameters into /home/l_tester/david/FullQualification/data/M1024_FullQualification_2016-10-19_19h52m_1476899523//001_Fulltest_m20//trimParameters35_C7.dat
[21:37:09.119] <TB0> INFO: write trim parameters into /home/l_tester/david/FullQualification/data/M1024_FullQualification_2016-10-19_19h52m_1476899523//001_Fulltest_m20//trimParameters35_C8.dat
[21:37:09.123] <TB0> INFO: write trim parameters into /home/l_tester/david/FullQualification/data/M1024_FullQualification_2016-10-19_19h52m_1476899523//001_Fulltest_m20//trimParameters35_C9.dat
[21:37:09.128] <TB0> INFO: write trim parameters into /home/l_tester/david/FullQualification/data/M1024_FullQualification_2016-10-19_19h52m_1476899523//001_Fulltest_m20//trimParameters35_C10.dat
[21:37:09.133] <TB0> INFO: write trim parameters into /home/l_tester/david/FullQualification/data/M1024_FullQualification_2016-10-19_19h52m_1476899523//001_Fulltest_m20//trimParameters35_C11.dat
[21:37:09.138] <TB0> INFO: write trim parameters into /home/l_tester/david/FullQualification/data/M1024_FullQualification_2016-10-19_19h52m_1476899523//001_Fulltest_m20//trimParameters35_C12.dat
[21:37:09.143] <TB0> INFO: write trim parameters into /home/l_tester/david/FullQualification/data/M1024_FullQualification_2016-10-19_19h52m_1476899523//001_Fulltest_m20//trimParameters35_C13.dat
[21:37:09.147] <TB0> INFO: write trim parameters into /home/l_tester/david/FullQualification/data/M1024_FullQualification_2016-10-19_19h52m_1476899523//001_Fulltest_m20//trimParameters35_C14.dat
[21:37:09.152] <TB0> INFO: write trim parameters into /home/l_tester/david/FullQualification/data/M1024_FullQualification_2016-10-19_19h52m_1476899523//001_Fulltest_m20//trimParameters35_C15.dat
[21:37:09.157] <TB0> INFO: PixTestTrim::trimTest() done
[21:37:09.157] <TB0> INFO: vtrim: 118 131 128 134 131 122 123 109 126 123 131 128 113 114 136 130
[21:37:09.157] <TB0> INFO: vthrcomp: 115 117 121 124 118 116 107 119 119 117 107 117 110 124 121 119
[21:37:09.157] <TB0> INFO: vcal mean: 34.94 34.96 34.95 34.93 34.91 34.93 34.99 34.93 34.98 34.92 35.00 34.94 35.01 34.95 34.91 34.92
[21:37:09.157] <TB0> INFO: vcal RMS: 0.94 1.10 1.00 1.29 1.05 0.95 0.88 1.38 0.97 0.95 0.95 1.09 0.98 0.97 0.94 0.99
[21:37:09.157] <TB0> INFO: bits mean: 9.89 9.93 9.19 9.77 9.71 10.11 9.60 9.44 9.39 9.79 9.32 9.99 8.40 9.76 10.00 9.99
[21:37:09.157] <TB0> INFO: bits RMS: 2.62 2.59 2.97 2.79 2.72 2.45 2.34 2.85 2.89 2.53 2.47 2.49 2.72 2.66 2.68 2.64
[21:37:09.164] <TB0> INFO: ----------------------------------------------------------------------
[21:37:09.164] <TB0> INFO: PixTestTrim::trimBitTest() ntrig = 5, vtrims = 254 126 63 32
[21:37:09.164] <TB0> INFO: ----------------------------------------------------------------------
[21:37:09.167] <TB0> INFO: ---> dac: Vcal name: TrimBitsThr0 ntrig: 5 dacrange: 0 .. 199 (-1/-1) hits flags = 528 (plus default)
[21:37:09.180] <TB0> INFO: dacScan split into 1 runs with ntrig = 5
[21:37:09.180] <TB0> INFO: run 1 of 1
[21:37:09.458] <TB0> INFO: Expecting 4160000 events.
[21:37:40.795] <TB0> INFO: 721920 events read in total (30745ms).
[21:38:11.756] <TB0> INFO: 1438785 events read in total (61706ms).
[21:38:42.742] <TB0> INFO: 2150070 events read in total (92692ms).
[21:39:13.580] <TB0> INFO: 2858335 events read in total (123530ms).
[21:39:44.534] <TB0> INFO: 3563665 events read in total (154484ms).
[21:40:10.562] <TB0> INFO: 4160000 events read in total (180512ms).
[21:40:10.644] <TB0> INFO: Test took 181463ms.
[21:40:40.616] <TB0> INFO: ---> dac: Vcal name: TrimThr_trim14 ntrig: 5 dacrange: 0 .. 202 (-1/-1) hits flags = 528 (plus default)
[21:40:40.629] <TB0> INFO: dacScan split into 1 runs with ntrig = 5
[21:40:40.629] <TB0> INFO: run 1 of 1
[21:40:40.890] <TB0> INFO: Expecting 4222400 events.
[21:41:12.091] <TB0> INFO: 697900 events read in total (30609ms).
[21:41:42.763] <TB0> INFO: 1391865 events read in total (61281ms).
[21:42:13.064] <TB0> INFO: 2081585 events read in total (91583ms).
[21:42:43.390] <TB0> INFO: 2768805 events read in total (121908ms).
[21:43:13.945] <TB0> INFO: 3453150 events read in total (152463ms).
[21:43:44.513] <TB0> INFO: 4138155 events read in total (183031ms).
[21:43:48.503] <TB0> INFO: 4222400 events read in total (187021ms).
[21:43:48.631] <TB0> INFO: Test took 188002ms.
[21:44:19.246] <TB0> INFO: ---> dac: Vcal name: TrimThr_trim13 ntrig: 5 dacrange: 0 .. 184 (-1/-1) hits flags = 528 (plus default)
[21:44:19.259] <TB0> INFO: dacScan split into 1 runs with ntrig = 5
[21:44:19.259] <TB0> INFO: run 1 of 1
[21:44:19.495] <TB0> INFO: Expecting 3848000 events.
[21:44:50.896] <TB0> INFO: 719705 events read in total (30810ms).
[21:45:21.662] <TB0> INFO: 1434235 events read in total (61576ms).
[21:45:52.515] <TB0> INFO: 2143915 events read in total (92429ms).
[21:46:23.077] <TB0> INFO: 2849985 events read in total (122991ms).
[21:46:53.675] <TB0> INFO: 3554615 events read in total (153589ms).
[21:47:06.642] <TB0> INFO: 3848000 events read in total (166556ms).
[21:47:06.718] <TB0> INFO: Test took 167459ms.
[21:47:31.035] <TB0> INFO: ---> dac: Vcal name: TrimThr_trim11 ntrig: 5 dacrange: 0 .. 185 (-1/-1) hits flags = 528 (plus default)
[21:47:31.048] <TB0> INFO: dacScan split into 1 runs with ntrig = 5
[21:47:31.049] <TB0> INFO: run 1 of 1
[21:47:31.310] <TB0> INFO: Expecting 3868800 events.
[21:48:03.117] <TB0> INFO: 718430 events read in total (31215ms).
[21:48:33.988] <TB0> INFO: 1431600 events read in total (62086ms).
[21:49:04.859] <TB0> INFO: 2139760 events read in total (92957ms).
[21:49:35.797] <TB0> INFO: 2844850 events read in total (123895ms).
[21:50:06.945] <TB0> INFO: 3547865 events read in total (155043ms).
[21:50:21.126] <TB0> INFO: 3868800 events read in total (169224ms).
[21:50:21.324] <TB0> INFO: Test took 170276ms.
[21:50:47.017] <TB0> INFO: ---> dac: Vcal name: TrimThr_trim7 ntrig: 5 dacrange: 0 .. 184 (-1/-1) hits flags = 528 (plus default)
[21:50:47.030] <TB0> INFO: dacScan split into 1 runs with ntrig = 5
[21:50:47.030] <TB0> INFO: run 1 of 1
[21:50:47.301] <TB0> INFO: Expecting 3848000 events.
[21:51:18.827] <TB0> INFO: 719935 events read in total (30934ms).
[21:51:49.802] <TB0> INFO: 1434510 events read in total (61909ms).
[21:52:20.629] <TB0> INFO: 2144510 events read in total (92736ms).
[21:52:51.507] <TB0> INFO: 2850740 events read in total (123614ms).
[21:53:22.323] <TB0> INFO: 3555415 events read in total (154430ms).
[21:53:35.433] <TB0> INFO: 3848000 events read in total (167540ms).
[21:53:35.638] <TB0> INFO: Test took 168608ms.
[21:54:02.973] <TB0> INFO: PixTestTrim::trimBitTest() done
[21:54:02.974] <TB0> INFO: PixTestTrim::doTest() done, duration: 2462 seconds
[21:54:02.974] <TB0> INFO: Decoding statistics:
[21:54:02.974] <TB0> INFO: General information:
[21:54:02.974] <TB0> INFO: 16bit words read: 0
[21:54:02.974] <TB0> INFO: valid events total: 0
[21:54:02.974] <TB0> INFO: empty events: 0
[21:54:02.974] <TB0> INFO: valid events with pixels: 0
[21:54:02.974] <TB0> INFO: valid pixel hits: 0
[21:54:02.974] <TB0> INFO: Event errors: 0
[21:54:02.974] <TB0> INFO: start marker: 0
[21:54:02.974] <TB0> INFO: stop marker: 0
[21:54:02.974] <TB0> INFO: overflow: 0
[21:54:02.974] <TB0> INFO: invalid 5bit words: 0
[21:54:02.974] <TB0> INFO: invalid XOR eye diagram: 0
[21:54:02.974] <TB0> INFO: frame (failed synchr.): 0
[21:54:02.974] <TB0> INFO: idle data (no TBM trl): 0
[21:54:02.974] <TB0> INFO: no data (only TBM hdr): 0
[21:54:02.974] <TB0> INFO: TBM errors: 0
[21:54:02.974] <TB0> INFO: flawed TBM headers: 0
[21:54:02.975] <TB0> INFO: flawed TBM trailers: 0
[21:54:02.975] <TB0> INFO: event ID mismatches: 0
[21:54:02.975] <TB0> INFO: ROC errors: 0
[21:54:02.975] <TB0> INFO: missing ROC header(s): 0
[21:54:02.975] <TB0> INFO: misplaced readback start: 0
[21:54:02.975] <TB0> INFO: Pixel decoding errors: 0
[21:54:02.975] <TB0> INFO: pixel data incomplete: 0
[21:54:02.975] <TB0> INFO: pixel address: 0
[21:54:02.975] <TB0> INFO: pulse height fill bit: 0
[21:54:02.975] <TB0> INFO: buffer corruption: 0
[21:54:03.585] <TB0> INFO: ######################################################################
[21:54:03.585] <TB0> INFO: PixTestPhOptimization::doTest() Ntrig = 10
[21:54:03.585] <TB0> INFO: ######################################################################
[21:54:03.822] <TB0> INFO: Expecting 41600 events.
[21:54:07.265] <TB0> INFO: 41600 events read in total (2851ms).
[21:54:07.266] <TB0> INFO: Test took 3680ms.
[21:54:07.712] <TB0> INFO: Expecting 41600 events.
[21:54:11.418] <TB0> INFO: 41600 events read in total (3115ms).
[21:54:11.419] <TB0> INFO: Test took 3950ms.
[21:54:11.708] <TB0> INFO: Expecting 41600 events.
[21:54:15.309] <TB0> INFO: 41600 events read in total (3009ms).
[21:54:15.310] <TB0> INFO: Test took 3867ms.
[21:54:15.600] <TB0> INFO: Expecting 41600 events.
[21:54:19.109] <TB0> INFO: 41600 events read in total (2917ms).
[21:54:19.109] <TB0> INFO: Test took 3773ms.
[21:54:19.398] <TB0> INFO: Expecting 41600 events.
[21:54:22.955] <TB0> INFO: 41600 events read in total (2966ms).
[21:54:22.955] <TB0> INFO: Test took 3822ms.
[21:54:23.244] <TB0> INFO: Expecting 41600 events.
[21:54:26.732] <TB0> INFO: 41600 events read in total (2896ms).
[21:54:26.732] <TB0> INFO: Test took 3753ms.
[21:54:27.021] <TB0> INFO: Expecting 41600 events.
[21:54:30.613] <TB0> INFO: 41600 events read in total (2997ms).
[21:54:30.614] <TB0> INFO: Test took 3858ms.
[21:54:30.905] <TB0> INFO: Expecting 41600 events.
[21:54:34.413] <TB0> INFO: 41600 events read in total (2916ms).
[21:54:34.414] <TB0> INFO: Test took 3774ms.
[21:54:34.703] <TB0> INFO: Expecting 41600 events.
[21:54:38.229] <TB0> INFO: 41600 events read in total (2934ms).
[21:54:38.230] <TB0> INFO: Test took 3792ms.
[21:54:38.519] <TB0> INFO: Expecting 41600 events.
[21:54:42.009] <TB0> INFO: 41600 events read in total (2898ms).
[21:54:42.010] <TB0> INFO: Test took 3756ms.
[21:54:42.300] <TB0> INFO: Expecting 41600 events.
[21:54:45.839] <TB0> INFO: 41600 events read in total (2947ms).
[21:54:45.840] <TB0> INFO: Test took 3806ms.
[21:54:46.128] <TB0> INFO: Expecting 41600 events.
[21:54:49.668] <TB0> INFO: 41600 events read in total (2948ms).
[21:54:49.669] <TB0> INFO: Test took 3806ms.
[21:54:49.958] <TB0> INFO: Expecting 41600 events.
[21:54:53.506] <TB0> INFO: 41600 events read in total (2956ms).
[21:54:53.507] <TB0> INFO: Test took 3814ms.
[21:54:53.795] <TB0> INFO: Expecting 41600 events.
[21:54:57.282] <TB0> INFO: 41600 events read in total (2895ms).
[21:54:57.283] <TB0> INFO: Test took 3752ms.
[21:54:57.572] <TB0> INFO: Expecting 41600 events.
[21:55:01.088] <TB0> INFO: 41600 events read in total (2924ms).
[21:55:01.089] <TB0> INFO: Test took 3782ms.
[21:55:01.379] <TB0> INFO: Expecting 41600 events.
[21:55:04.947] <TB0> INFO: 41600 events read in total (2977ms).
[21:55:04.948] <TB0> INFO: Test took 3834ms.
[21:55:05.238] <TB0> INFO: Expecting 41600 events.
[21:55:08.786] <TB0> INFO: 41600 events read in total (2956ms).
[21:55:08.787] <TB0> INFO: Test took 3815ms.
[21:55:09.077] <TB0> INFO: Expecting 41600 events.
[21:55:12.584] <TB0> INFO: 41600 events read in total (2915ms).
[21:55:12.585] <TB0> INFO: Test took 3774ms.
[21:55:12.875] <TB0> INFO: Expecting 41600 events.
[21:55:16.472] <TB0> INFO: 41600 events read in total (3005ms).
[21:55:16.473] <TB0> INFO: Test took 3864ms.
[21:55:16.761] <TB0> INFO: Expecting 41600 events.
[21:55:20.369] <TB0> INFO: 41600 events read in total (3016ms).
[21:55:20.370] <TB0> INFO: Test took 3874ms.
[21:55:20.659] <TB0> INFO: Expecting 41600 events.
[21:55:24.329] <TB0> INFO: 41600 events read in total (3078ms).
[21:55:24.330] <TB0> INFO: Test took 3936ms.
[21:55:24.618] <TB0> INFO: Expecting 41600 events.
[21:55:28.199] <TB0> INFO: 41600 events read in total (2989ms).
[21:55:28.200] <TB0> INFO: Test took 3846ms.
[21:55:28.507] <TB0> INFO: Expecting 41600 events.
[21:55:31.995] <TB0> INFO: 41600 events read in total (2896ms).
[21:55:31.995] <TB0> INFO: Test took 3771ms.
[21:55:32.287] <TB0> INFO: Expecting 41600 events.
[21:55:35.878] <TB0> INFO: 41600 events read in total (2999ms).
[21:55:35.879] <TB0> INFO: Test took 3857ms.
[21:55:36.167] <TB0> INFO: Expecting 41600 events.
[21:55:39.674] <TB0> INFO: 41600 events read in total (2915ms).
[21:55:39.675] <TB0> INFO: Test took 3772ms.
[21:55:39.965] <TB0> INFO: Expecting 41600 events.
[21:55:43.441] <TB0> INFO: 41600 events read in total (2884ms).
[21:55:43.442] <TB0> INFO: Test took 3741ms.
[21:55:43.730] <TB0> INFO: Expecting 41600 events.
[21:55:47.216] <TB0> INFO: 41600 events read in total (2894ms).
[21:55:47.217] <TB0> INFO: Test took 3751ms.
[21:55:47.506] <TB0> INFO: Expecting 41600 events.
[21:55:51.081] <TB0> INFO: 41600 events read in total (2984ms).
[21:55:51.081] <TB0> INFO: Test took 3840ms.
[21:55:51.384] <TB0> INFO: Expecting 41600 events.
[21:55:54.877] <TB0> INFO: 41600 events read in total (2901ms).
[21:55:54.877] <TB0> INFO: Test took 3769ms.
[21:55:55.169] <TB0> INFO: Expecting 2560 events.
[21:55:56.054] <TB0> INFO: 2560 events read in total (293ms).
[21:55:56.055] <TB0> INFO: Test took 1162ms.
[21:55:56.362] <TB0> INFO: Expecting 2560 events.
[21:55:57.247] <TB0> INFO: 2560 events read in total (293ms).
[21:55:57.247] <TB0> INFO: Test took 1189ms.
[21:55:57.555] <TB0> INFO: Expecting 2560 events.
[21:55:58.437] <TB0> INFO: 2560 events read in total (291ms).
[21:55:58.438] <TB0> INFO: Test took 1191ms.
[21:55:58.746] <TB0> INFO: Expecting 2560 events.
[21:55:59.630] <TB0> INFO: 2560 events read in total (293ms).
[21:55:59.630] <TB0> INFO: Test took 1192ms.
[21:55:59.939] <TB0> INFO: Expecting 2560 events.
[21:56:00.819] <TB0> INFO: 2560 events read in total (289ms).
[21:56:00.819] <TB0> INFO: Test took 1188ms.
[21:56:01.127] <TB0> INFO: Expecting 2560 events.
[21:56:01.007] <TB0> INFO: 2560 events read in total (288ms).
[21:56:02.007] <TB0> INFO: Test took 1187ms.
[21:56:02.315] <TB0> INFO: Expecting 2560 events.
[21:56:03.204] <TB0> INFO: 2560 events read in total (297ms).
[21:56:03.205] <TB0> INFO: Test took 1196ms.
[21:56:03.514] <TB0> INFO: Expecting 2560 events.
[21:56:04.398] <TB0> INFO: 2560 events read in total (293ms).
[21:56:04.399] <TB0> INFO: Test took 1194ms.
[21:56:04.705] <TB0> INFO: Expecting 2560 events.
[21:56:05.588] <TB0> INFO: 2560 events read in total (291ms).
[21:56:05.588] <TB0> INFO: Test took 1188ms.
[21:56:05.896] <TB0> INFO: Expecting 2560 events.
[21:56:06.783] <TB0> INFO: 2560 events read in total (295ms).
[21:56:06.783] <TB0> INFO: Test took 1195ms.
[21:56:07.091] <TB0> INFO: Expecting 2560 events.
[21:56:07.982] <TB0> INFO: 2560 events read in total (299ms).
[21:56:07.982] <TB0> INFO: Test took 1198ms.
[21:56:08.290] <TB0> INFO: Expecting 2560 events.
[21:56:09.172] <TB0> INFO: 2560 events read in total (290ms).
[21:56:09.173] <TB0> INFO: Test took 1191ms.
[21:56:09.479] <TB0> INFO: Expecting 2560 events.
[21:56:10.372] <TB0> INFO: 2560 events read in total (302ms).
[21:56:10.373] <TB0> INFO: Test took 1199ms.
[21:56:10.680] <TB0> INFO: Expecting 2560 events.
[21:56:11.571] <TB0> INFO: 2560 events read in total (300ms).
[21:56:11.571] <TB0> INFO: Test took 1197ms.
[21:56:11.879] <TB0> INFO: Expecting 2560 events.
[21:56:12.771] <TB0> INFO: 2560 events read in total (300ms).
[21:56:12.772] <TB0> INFO: Test took 1200ms.
[21:56:13.079] <TB0> INFO: Expecting 2560 events.
[21:56:13.977] <TB0> INFO: 2560 events read in total (306ms).
[21:56:13.977] <TB0> INFO: Test took 1204ms.
[21:56:13.981] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[21:56:14.285] <TB0> INFO: Expecting 655360 events.
[21:56:29.112] <TB0> INFO: 655360 events read in total (14235ms).
[21:56:29.124] <TB0> INFO: Expecting 655360 events.
[21:56:43.689] <TB0> INFO: 655360 events read in total (14162ms).
[21:56:43.705] <TB0> INFO: Expecting 655360 events.
[21:56:58.311] <TB0> INFO: 655360 events read in total (14203ms).
[21:56:58.336] <TB0> INFO: Expecting 655360 events.
[21:57:13.011] <TB0> INFO: 655360 events read in total (14272ms).
[21:57:13.042] <TB0> INFO: Expecting 655360 events.
[21:57:27.829] <TB0> INFO: 655360 events read in total (14384ms).
[21:57:27.857] <TB0> INFO: Expecting 655360 events.
[21:57:42.722] <TB0> INFO: 655360 events read in total (14462ms).
[21:57:42.765] <TB0> INFO: Expecting 655360 events.
[21:57:57.543] <TB0> INFO: 655360 events read in total (14374ms).
[21:57:57.581] <TB0> INFO: Expecting 655360 events.
[21:58:12.229] <TB0> INFO: 655360 events read in total (14244ms).
[21:58:12.272] <TB0> INFO: Expecting 655360 events.
[21:58:26.934] <TB0> INFO: 655360 events read in total (14259ms).
[21:58:26.980] <TB0> INFO: Expecting 655360 events.
[21:58:41.496] <TB0> INFO: 655360 events read in total (14113ms).
[21:58:41.546] <TB0> INFO: Expecting 655360 events.
[21:58:56.124] <TB0> INFO: 655360 events read in total (14175ms).
[21:58:56.191] <TB0> INFO: Expecting 655360 events.
[21:59:10.668] <TB0> INFO: 655360 events read in total (14074ms).
[21:59:10.730] <TB0> INFO: Expecting 655360 events.
[21:59:25.385] <TB0> INFO: 655360 events read in total (14252ms).
[21:59:25.492] <TB0> INFO: Expecting 655360 events.
[21:59:39.976] <TB0> INFO: 655360 events read in total (14081ms).
[21:59:40.192] <TB0> INFO: Expecting 655360 events.
[21:59:54.754] <TB0> INFO: 655360 events read in total (14159ms).
[21:59:54.959] <TB0> INFO: Expecting 655360 events.
[22:00:09.497] <TB0> INFO: 655360 events read in total (14135ms).
[22:00:09.594] <TB0> INFO: Test took 235613ms.
[22:00:09.692] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[22:00:09.950] <TB0> INFO: Expecting 655360 events.
[22:00:24.761] <TB0> INFO: 655360 events read in total (14219ms).
[22:00:24.775] <TB0> INFO: Expecting 655360 events.
[22:00:39.237] <TB0> INFO: 655360 events read in total (14059ms).
[22:00:39.254] <TB0> INFO: Expecting 655360 events.
[22:00:53.919] <TB0> INFO: 655360 events read in total (14262ms).
[22:00:53.939] <TB0> INFO: Expecting 655360 events.
[22:01:08.112] <TB0> INFO: 655360 events read in total (13769ms).
[22:01:08.149] <TB0> INFO: Expecting 655360 events.
[22:01:22.356] <TB0> INFO: 655360 events read in total (13804ms).
[22:01:22.385] <TB0> INFO: Expecting 655360 events.
[22:01:36.843] <TB0> INFO: 655360 events read in total (14055ms).
[22:01:36.887] <TB0> INFO: Expecting 655360 events.
[22:01:51.333] <TB0> INFO: 655360 events read in total (14043ms).
[22:01:51.381] <TB0> INFO: Expecting 655360 events.
[22:02:05.004] <TB0> INFO: 655360 events read in total (14219ms).
[22:02:06.045] <TB0> INFO: Expecting 655360 events.
[22:02:20.577] <TB0> INFO: 655360 events read in total (14129ms).
[22:02:20.636] <TB0> INFO: Expecting 655360 events.
[22:02:35.076] <TB0> INFO: 655360 events read in total (14036ms).
[22:02:35.126] <TB0> INFO: Expecting 655360 events.
[22:02:49.726] <TB0> INFO: 655360 events read in total (14197ms).
[22:02:49.826] <TB0> INFO: Expecting 655360 events.
[22:03:04.244] <TB0> INFO: 655360 events read in total (14015ms).
[22:03:04.307] <TB0> INFO: Expecting 655360 events.
[22:03:18.935] <TB0> INFO: 655360 events read in total (14225ms).
[22:03:19.037] <TB0> INFO: Expecting 655360 events.
[22:03:33.640] <TB0> INFO: 655360 events read in total (14200ms).
[22:03:33.723] <TB0> INFO: Expecting 655360 events.
[22:03:48.374] <TB0> INFO: 655360 events read in total (14248ms).
[22:03:48.460] <TB0> INFO: Expecting 655360 events.
[22:04:03.238] <TB0> INFO: 655360 events read in total (14375ms).
[22:04:03.331] <TB0> INFO: Test took 233639ms.
[22:04:03.516] <TB0> INFO: safety margin for low PH: adding 0, margin is now 20
[22:04:03.521] <TB0> INFO: safety margin for low PH: adding 0, margin is now 20
[22:04:03.527] <TB0> INFO: safety margin for low PH: adding 0, margin is now 20
[22:04:03.532] <TB0> INFO: safety margin for low PH: adding 0, margin is now 20
[22:04:03.538] <TB0> INFO: safety margin for low PH: adding 0, margin is now 20
[22:04:03.543] <TB0> INFO: safety margin for low PH: adding 0, margin is now 20
[22:04:03.549] <TB0> INFO: safety margin for low PH: adding 0, margin is now 20
[22:04:03.555] <TB0> INFO: safety margin for low PH: adding 0, margin is now 20
[22:04:03.560] <TB0> INFO: safety margin for low PH: adding 1, margin is now 21
[22:04:03.565] <TB0> INFO: safety margin for low PH: adding 2, margin is now 22
[22:04:03.571] <TB0> INFO: safety margin for low PH: adding 3, margin is now 23
[22:04:03.577] <TB0> INFO: safety margin for low PH: adding 4, margin is now 24
[22:04:03.582] <TB0> INFO: safety margin for low PH: adding 5, margin is now 25
[22:04:03.588] <TB0> INFO: safety margin for low PH: adding 6, margin is now 26
[22:04:03.594] <TB0> INFO: safety margin for low PH: adding 0, margin is now 20
[22:04:03.599] <TB0> INFO: safety margin for low PH: adding 1, margin is now 21
[22:04:03.605] <TB0> INFO: safety margin for low PH: adding 2, margin is now 22
[22:04:03.610] <TB0> INFO: safety margin for low PH: adding 3, margin is now 23
[22:04:03.616] <TB0> INFO: safety margin for low PH: adding 4, margin is now 24
[22:04:03.621] <TB0> INFO: safety margin for low PH: adding 5, margin is now 25
[22:04:03.627] <TB0> INFO: safety margin for low PH: adding 6, margin is now 26
[22:04:03.632] <TB0> INFO: safety margin for low PH: adding 7, margin is now 27
[22:04:03.638] <TB0> INFO: safety margin for low PH: adding 8, margin is now 28
[22:04:03.644] <TB0> INFO: safety margin for low PH: adding 0, margin is now 20
[22:04:03.649] <TB0> INFO: safety margin for low PH: adding 1, margin is now 21
[22:04:03.655] <TB0> INFO: safety margin for low PH: adding 2, margin is now 22
[22:04:03.660] <TB0> INFO: safety margin for low PH: adding 3, margin is now 23
[22:04:03.666] <TB0> INFO: safety margin for low PH: adding 4, margin is now 24
[22:04:03.671] <TB0> INFO: safety margin for low PH: adding 0, margin is now 20
[22:04:03.677] <TB0> INFO: safety margin for low PH: adding 0, margin is now 20
[22:04:03.682] <TB0> INFO: safety margin for low PH: adding 0, margin is now 20
[22:04:03.688] <TB0> INFO: safety margin for low PH: adding 0, margin is now 20
[22:04:03.696] <TB0> INFO: safety margin for low PH: adding 0, margin is now 20
[22:04:03.701] <TB0> INFO: safety margin for low PH: adding 0, margin is now 20
[22:04:03.740] <TB0> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1024_FullQualification_2016-10-19_19h52m_1476899523//001_Fulltest_m20//dacParameters35_C0.dat
[22:04:03.741] <TB0> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1024_FullQualification_2016-10-19_19h52m_1476899523//001_Fulltest_m20//dacParameters35_C1.dat
[22:04:03.741] <TB0> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1024_FullQualification_2016-10-19_19h52m_1476899523//001_Fulltest_m20//dacParameters35_C2.dat
[22:04:03.741] <TB0> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1024_FullQualification_2016-10-19_19h52m_1476899523//001_Fulltest_m20//dacParameters35_C3.dat
[22:04:03.741] <TB0> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1024_FullQualification_2016-10-19_19h52m_1476899523//001_Fulltest_m20//dacParameters35_C4.dat
[22:04:03.741] <TB0> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1024_FullQualification_2016-10-19_19h52m_1476899523//001_Fulltest_m20//dacParameters35_C5.dat
[22:04:03.742] <TB0> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1024_FullQualification_2016-10-19_19h52m_1476899523//001_Fulltest_m20//dacParameters35_C6.dat
[22:04:03.742] <TB0> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1024_FullQualification_2016-10-19_19h52m_1476899523//001_Fulltest_m20//dacParameters35_C7.dat
[22:04:03.742] <TB0> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1024_FullQualification_2016-10-19_19h52m_1476899523//001_Fulltest_m20//dacParameters35_C8.dat
[22:04:03.742] <TB0> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1024_FullQualification_2016-10-19_19h52m_1476899523//001_Fulltest_m20//dacParameters35_C9.dat
[22:04:03.742] <TB0> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1024_FullQualification_2016-10-19_19h52m_1476899523//001_Fulltest_m20//dacParameters35_C10.dat
[22:04:03.743] <TB0> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1024_FullQualification_2016-10-19_19h52m_1476899523//001_Fulltest_m20//dacParameters35_C11.dat
[22:04:03.743] <TB0> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1024_FullQualification_2016-10-19_19h52m_1476899523//001_Fulltest_m20//dacParameters35_C12.dat
[22:04:03.743] <TB0> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1024_FullQualification_2016-10-19_19h52m_1476899523//001_Fulltest_m20//dacParameters35_C13.dat
[22:04:03.743] <TB0> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1024_FullQualification_2016-10-19_19h52m_1476899523//001_Fulltest_m20//dacParameters35_C14.dat
[22:04:03.743] <TB0> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1024_FullQualification_2016-10-19_19h52m_1476899523//001_Fulltest_m20//dacParameters35_C15.dat
[22:04:03.003] <TB0> INFO: Expecting 41600 events.
[22:04:07.162] <TB0> INFO: 41600 events read in total (2567ms).
[22:04:07.163] <TB0> INFO: Test took 3416ms.
[22:04:07.622] <TB0> INFO: Expecting 41600 events.
[22:04:10.711] <TB0> INFO: 41600 events read in total (2497ms).
[22:04:10.712] <TB0> INFO: Test took 3336ms.
[22:04:11.169] <TB0> INFO: Expecting 41600 events.
[22:04:14.312] <TB0> INFO: 41600 events read in total (2551ms).
[22:04:14.313] <TB0> INFO: Test took 3390ms.
[22:04:14.528] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[22:04:14.617] <TB0> INFO: Expecting 2560 events.
[22:04:15.511] <TB0> INFO: 2560 events read in total (302ms).
[22:04:15.511] <TB0> INFO: Test took 983ms.
[22:04:15.513] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[22:04:15.821] <TB0> INFO: Expecting 2560 events.
[22:04:16.720] <TB0> INFO: 2560 events read in total (308ms).
[22:04:16.720] <TB0> INFO: Test took 1207ms.
[22:04:16.723] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[22:04:17.029] <TB0> INFO: Expecting 2560 events.
[22:04:17.921] <TB0> INFO: 2560 events read in total (300ms).
[22:04:17.922] <TB0> INFO: Test took 1199ms.
[22:04:17.924] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[22:04:18.229] <TB0> INFO: Expecting 2560 events.
[22:04:19.120] <TB0> INFO: 2560 events read in total (299ms).
[22:04:19.120] <TB0> INFO: Test took 1196ms.
[22:04:19.122] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[22:04:19.430] <TB0> INFO: Expecting 2560 events.
[22:04:20.318] <TB0> INFO: 2560 events read in total (296ms).
[22:04:20.318] <TB0> INFO: Test took 1196ms.
[22:04:20.321] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[22:04:20.627] <TB0> INFO: Expecting 2560 events.
[22:04:21.513] <TB0> INFO: 2560 events read in total (294ms).
[22:04:21.513] <TB0> INFO: Test took 1192ms.
[22:04:21.515] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[22:04:21.824] <TB0> INFO: Expecting 2560 events.
[22:04:22.713] <TB0> INFO: 2560 events read in total (297ms).
[22:04:22.713] <TB0> INFO: Test took 1198ms.
[22:04:22.716] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[22:04:23.022] <TB0> INFO: Expecting 2560 events.
[22:04:23.907] <TB0> INFO: 2560 events read in total (293ms).
[22:04:23.908] <TB0> INFO: Test took 1192ms.
[22:04:23.911] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[22:04:24.216] <TB0> INFO: Expecting 2560 events.
[22:04:25.100] <TB0> INFO: 2560 events read in total (292ms).
[22:04:25.100] <TB0> INFO: Test took 1189ms.
[22:04:25.104] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[22:04:25.409] <TB0> INFO: Expecting 2560 events.
[22:04:26.294] <TB0> INFO: 2560 events read in total (294ms).
[22:04:26.294] <TB0> INFO: Test took 1190ms.
[22:04:26.298] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[22:04:26.602] <TB0> INFO: Expecting 2560 events.
[22:04:27.481] <TB0> INFO: 2560 events read in total (287ms).
[22:04:27.482] <TB0> INFO: Test took 1184ms.
[22:04:27.485] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[22:04:27.790] <TB0> INFO: Expecting 2560 events.
[22:04:28.677] <TB0> INFO: 2560 events read in total (296ms).
[22:04:28.677] <TB0> INFO: Test took 1192ms.
[22:04:28.680] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[22:04:28.986] <TB0> INFO: Expecting 2560 events.
[22:04:29.869] <TB0> INFO: 2560 events read in total (291ms).
[22:04:29.869] <TB0> INFO: Test took 1189ms.
[22:04:29.872] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[22:04:30.178] <TB0> INFO: Expecting 2560 events.
[22:04:31.066] <TB0> INFO: 2560 events read in total (297ms).
[22:04:31.067] <TB0> INFO: Test took 1195ms.
[22:04:31.070] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[22:04:31.375] <TB0> INFO: Expecting 2560 events.
[22:04:32.272] <TB0> INFO: 2560 events read in total (299ms).
[22:04:32.272] <TB0> INFO: Test took 1202ms.
[22:04:32.275] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[22:04:32.580] <TB0> INFO: Expecting 2560 events.
[22:04:33.472] <TB0> INFO: 2560 events read in total (297ms).
[22:04:33.473] <TB0> INFO: Test took 1198ms.
[22:04:33.476] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[22:04:33.780] <TB0> INFO: Expecting 2560 events.
[22:04:34.667] <TB0> INFO: 2560 events read in total (295ms).
[22:04:34.667] <TB0> INFO: Test took 1191ms.
[22:04:34.670] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[22:04:34.976] <TB0> INFO: Expecting 2560 events.
[22:04:35.858] <TB0> INFO: 2560 events read in total (291ms).
[22:04:35.859] <TB0> INFO: Test took 1189ms.
[22:04:35.862] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[22:04:36.167] <TB0> INFO: Expecting 2560 events.
[22:04:37.056] <TB0> INFO: 2560 events read in total (297ms).
[22:04:37.056] <TB0> INFO: Test took 1195ms.
[22:04:37.060] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[22:04:37.364] <TB0> INFO: Expecting 2560 events.
[22:04:38.252] <TB0> INFO: 2560 events read in total (296ms).
[22:04:38.252] <TB0> INFO: Test took 1193ms.
[22:04:38.255] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[22:04:38.561] <TB0> INFO: Expecting 2560 events.
[22:04:39.450] <TB0> INFO: 2560 events read in total (298ms).
[22:04:39.451] <TB0> INFO: Test took 1196ms.
[22:04:39.453] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[22:04:39.759] <TB0> INFO: Expecting 2560 events.
[22:04:40.646] <TB0> INFO: 2560 events read in total (296ms).
[22:04:40.646] <TB0> INFO: Test took 1193ms.
[22:04:40.649] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[22:04:40.954] <TB0> INFO: Expecting 2560 events.
[22:04:41.837] <TB0> INFO: 2560 events read in total (291ms).
[22:04:41.838] <TB0> INFO: Test took 1189ms.
[22:04:41.841] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[22:04:42.146] <TB0> INFO: Expecting 2560 events.
[22:04:43.032] <TB0> INFO: 2560 events read in total (295ms).
[22:04:43.032] <TB0> INFO: Test took 1191ms.
[22:04:43.034] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[22:04:43.341] <TB0> INFO: Expecting 2560 events.
[22:04:44.229] <TB0> INFO: 2560 events read in total (296ms).
[22:04:44.230] <TB0> INFO: Test took 1196ms.
[22:04:44.232] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[22:04:44.539] <TB0> INFO: Expecting 2560 events.
[22:04:45.430] <TB0> INFO: 2560 events read in total (299ms).
[22:04:45.431] <TB0> INFO: Test took 1199ms.
[22:04:45.434] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[22:04:45.739] <TB0> INFO: Expecting 2560 events.
[22:04:46.635] <TB0> INFO: 2560 events read in total (305ms).
[22:04:46.635] <TB0> INFO: Test took 1201ms.
[22:04:46.639] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[22:04:46.943] <TB0> INFO: Expecting 2560 events.
[22:04:47.829] <TB0> INFO: 2560 events read in total (294ms).
[22:04:47.829] <TB0> INFO: Test took 1191ms.
[22:04:47.832] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[22:04:48.138] <TB0> INFO: Expecting 2560 events.
[22:04:49.032] <TB0> INFO: 2560 events read in total (302ms).
[22:04:49.032] <TB0> INFO: Test took 1200ms.
[22:04:49.037] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[22:04:49.339] <TB0> INFO: Expecting 2560 events.
[22:04:50.231] <TB0> INFO: 2560 events read in total (300ms).
[22:04:50.231] <TB0> INFO: Test took 1195ms.
[22:04:50.234] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[22:04:50.540] <TB0> INFO: Expecting 2560 events.
[22:04:51.428] <TB0> INFO: 2560 events read in total (297ms).
[22:04:51.428] <TB0> INFO: Test took 1194ms.
[22:04:51.430] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[22:04:51.737] <TB0> INFO: Expecting 2560 events.
[22:04:52.625] <TB0> INFO: 2560 events read in total (296ms).
[22:04:52.625] <TB0> INFO: Test took 1195ms.
[22:04:53.099] <TB0> INFO: PixTestPhOptimization::doTest() done, duration: 649 seconds
[22:04:53.099] <TB0> INFO: PH scale (per ROC): 61 59 49 52 66 50 51 62 39 48 61 46 57 58 61 42
[22:04:53.099] <TB0> INFO: PH offset (per ROC): 124 127 103 116 131 95 102 128 96 110 130 100 129 112 131 109
[22:04:53.107] <TB0> INFO: Decoding statistics:
[22:04:53.107] <TB0> INFO: General information:
[22:04:53.107] <TB0> INFO: 16bit words read: 127888
[22:04:53.107] <TB0> INFO: valid events total: 20480
[22:04:53.107] <TB0> INFO: empty events: 17976
[22:04:53.107] <TB0> INFO: valid events with pixels: 2504
[22:04:53.107] <TB0> INFO: valid pixel hits: 2504
[22:04:53.107] <TB0> INFO: Event errors: 0
[22:04:53.107] <TB0> INFO: start marker: 0
[22:04:53.107] <TB0> INFO: stop marker: 0
[22:04:53.107] <TB0> INFO: overflow: 0
[22:04:53.107] <TB0> INFO: invalid 5bit words: 0
[22:04:53.107] <TB0> INFO: invalid XOR eye diagram: 0
[22:04:53.107] <TB0> INFO: frame (failed synchr.): 0
[22:04:53.107] <TB0> INFO: idle data (no TBM trl): 0
[22:04:53.107] <TB0> INFO: no data (only TBM hdr): 0
[22:04:53.107] <TB0> INFO: TBM errors: 0
[22:04:53.107] <TB0> INFO: flawed TBM headers: 0
[22:04:53.107] <TB0> INFO: flawed TBM trailers: 0
[22:04:53.107] <TB0> INFO: event ID mismatches: 0
[22:04:53.107] <TB0> INFO: ROC errors: 0
[22:04:53.107] <TB0> INFO: missing ROC header(s): 0
[22:04:53.107] <TB0> INFO: misplaced readback start: 0
[22:04:53.107] <TB0> INFO: Pixel decoding errors: 0
[22:04:53.107] <TB0> INFO: pixel data incomplete: 0
[22:04:53.107] <TB0> INFO: pixel address: 0
[22:04:53.107] <TB0> INFO: pulse height fill bit: 0
[22:04:53.107] <TB0> INFO: buffer corruption: 0
[22:04:53.265] <TB0> INFO: ######################################################################
[22:04:53.265] <TB0> INFO: PixTestGainPedestal::fullTest() ntrig = 10
[22:04:53.265] <TB0> INFO: ######################################################################
[22:04:53.280] <TB0> INFO: scanning low vcal = 10
[22:04:53.515] <TB0> INFO: Expecting 41600 events.
[22:04:57.111] <TB0> INFO: 41600 events read in total (3004ms).
[22:04:57.111] <TB0> INFO: Test took 3830ms.
[22:04:57.113] <TB0> INFO: scanning low vcal = 20
[22:04:57.410] <TB0> INFO: Expecting 41600 events.
[22:05:01.047] <TB0> INFO: 41600 events read in total (3045ms).
[22:05:01.047] <TB0> INFO: Test took 3934ms.
[22:05:01.049] <TB0> INFO: scanning low vcal = 30
[22:05:01.350] <TB0> INFO: Expecting 41600 events.
[22:05:04.982] <TB0> INFO: 41600 events read in total (3041ms).
[22:05:04.983] <TB0> INFO: Test took 3934ms.
[22:05:04.986] <TB0> INFO: scanning low vcal = 40
[22:05:05.263] <TB0> INFO: Expecting 41600 events.
[22:05:09.220] <TB0> INFO: 41600 events read in total (3365ms).
[22:05:09.221] <TB0> INFO: Test took 4235ms.
[22:05:09.224] <TB0> INFO: scanning low vcal = 50
[22:05:09.500] <TB0> INFO: Expecting 41600 events.
[22:05:13.483] <TB0> INFO: 41600 events read in total (3391ms).
[22:05:13.484] <TB0> INFO: Test took 4260ms.
[22:05:13.487] <TB0> INFO: scanning low vcal = 60
[22:05:13.770] <TB0> INFO: Expecting 41600 events.
[22:05:20.204] <TB0> INFO: 41600 events read in total (5842ms).
[22:05:20.212] <TB0> INFO: Test took 6725ms.
[22:05:20.315] <TB0> INFO: scanning low vcal = 70
[22:05:21.259] <TB0> INFO: Expecting 41600 events.
[22:05:26.202] <TB0> INFO: 41600 events read in total (4261ms).
[22:05:26.203] <TB0> INFO: Test took 5880ms.
[22:05:26.207] <TB0> INFO: scanning low vcal = 80
[22:05:26.581] <TB0> INFO: Expecting 41600 events.
[22:05:31.096] <TB0> INFO: 41600 events read in total (3845ms).
[22:05:31.103] <TB0> INFO: Test took 4896ms.
[22:05:31.110] <TB0> INFO: scanning low vcal = 90
[22:05:31.839] <TB0> INFO: Expecting 41600 events.
[22:05:50.421] <TB0> INFO: 41600 events read in total (17914ms).
[22:05:50.422] <TB0> INFO: Test took 19308ms.
[22:05:50.427] <TB0> INFO: scanning low vcal = 100
[22:05:50.796] <TB0> INFO: Expecting 41600 events.
[22:05:54.872] <TB0> INFO: 41600 events read in total (3477ms).
[22:05:54.873] <TB0> INFO: Test took 4446ms.
[22:05:54.883] <TB0> INFO: scanning low vcal = 110
[22:05:55.427] <TB0> INFO: Expecting 41600 events.
[22:06:14.801] <TB0> INFO: 41600 events read in total (18706ms).
[22:06:14.808] <TB0> INFO: Test took 19924ms.
[22:06:14.815] <TB0> INFO: scanning low vcal = 120
[22:06:15.303] <TB0> INFO: Expecting 41600 events.
[22:06:19.253] <TB0> INFO: 41600 events read in total (3358ms).
[22:06:19.253] <TB0> INFO: Test took 4435ms.
[22:06:19.257] <TB0> INFO: scanning low vcal = 130
[22:06:19.541] <TB0> INFO: Expecting 41600 events.
[22:06:23.485] <TB0> INFO: 41600 events read in total (3353ms).
[22:06:23.486] <TB0> INFO: Test took 4229ms.
[22:06:23.489] <TB0> INFO: scanning low vcal = 140
[22:06:23.766] <TB0> INFO: Expecting 41600 events.
[22:06:27.735] <TB0> INFO: 41600 events read in total (3367ms).
[22:06:27.736] <TB0> INFO: Test took 4247ms.
[22:06:27.739] <TB0> INFO: scanning low vcal = 150
[22:06:28.015] <TB0> INFO: Expecting 41600 events.
[22:06:31.973] <TB0> INFO: 41600 events read in total (3366ms).
[22:06:31.974] <TB0> INFO: Test took 4235ms.
[22:06:31.976] <TB0> INFO: scanning low vcal = 160
[22:06:32.253] <TB0> INFO: Expecting 41600 events.
[22:06:36.203] <TB0> INFO: 41600 events read in total (3358ms).
[22:06:36.204] <TB0> INFO: Test took 4227ms.
[22:06:36.207] <TB0> INFO: scanning low vcal = 170
[22:06:36.483] <TB0> INFO: Expecting 41600 events.
[22:06:40.443] <TB0> INFO: 41600 events read in total (3368ms).
[22:06:40.443] <TB0> INFO: Test took 4236ms.
[22:06:40.449] <TB0> INFO: scanning low vcal = 180
[22:06:40.723] <TB0> INFO: Expecting 41600 events.
[22:06:46.987] <TB0> INFO: 41600 events read in total (5672ms).
[22:06:46.988] <TB0> INFO: Test took 6539ms.
[22:06:46.992] <TB0> INFO: scanning low vcal = 190
[22:06:47.342] <TB0> INFO: Expecting 41600 events.
[22:06:53.630] <TB0> INFO: 41600 events read in total (5690ms).
[22:06:53.631] <TB0> INFO: Test took 6639ms.
[22:06:53.634] <TB0> INFO: scanning low vcal = 200
[22:06:54.075] <TB0> INFO: Expecting 41600 events.
[22:07:15.071] <TB0> INFO: 41600 events read in total (20340ms).
[22:07:15.079] <TB0> INFO: Test took 21446ms.
[22:07:15.130] <TB0> INFO: scanning low vcal = 210
[22:07:15.591] <TB0> INFO: Expecting 41600 events.
[22:07:22.853] <TB0> INFO: 41600 events read in total (6670ms).
[22:07:22.864] <TB0> INFO: Test took 7727ms.
[22:07:23.011] <TB0> INFO: scanning low vcal = 220
[22:07:24.039] <TB0> INFO: Expecting 41600 events.
[22:07:39.392] <TB0> INFO: 41600 events read in total (14662ms).
[22:07:39.399] <TB0> INFO: Test took 16380ms.
[22:07:39.406] <TB0> INFO: scanning low vcal = 230
[22:07:39.711] <TB0> INFO: Expecting 41600 events.
[22:07:43.674] <TB0> INFO: 41600 events read in total (3372ms).
[22:07:43.674] <TB0> INFO: Test took 4265ms.
[22:07:43.677] <TB0> INFO: scanning low vcal = 240
[22:07:43.954] <TB0> INFO: Expecting 41600 events.
[22:07:47.890] <TB0> INFO: 41600 events read in total (3344ms).
[22:07:47.891] <TB0> INFO: Test took 4214ms.
[22:07:47.894] <TB0> INFO: scanning low vcal = 250
[22:07:48.172] <TB0> INFO: Expecting 41600 events.
[22:07:52.139] <TB0> INFO: 41600 events read in total (3375ms).
[22:07:52.140] <TB0> INFO: Test took 4246ms.
[22:07:52.144] <TB0> INFO: scanning high vcal = 30 (= 210 in low range)
[22:07:52.420] <TB0> INFO: Expecting 41600 events.
[22:07:56.405] <TB0> INFO: 41600 events read in total (3393ms).
[22:07:56.405] <TB0> INFO: Test took 4261ms.
[22:07:56.408] <TB0> INFO: scanning high vcal = 50 (= 350 in low range)
[22:07:56.685] <TB0> INFO: Expecting 41600 events.
[22:08:00.652] <TB0> INFO: 41600 events read in total (3375ms).
[22:08:00.653] <TB0> INFO: Test took 4245ms.
[22:08:00.656] <TB0> INFO: scanning high vcal = 70 (= 490 in low range)
[22:08:00.932] <TB0> INFO: Expecting 41600 events.
[22:08:04.886] <TB0> INFO: 41600 events read in total (3362ms).
[22:08:04.887] <TB0> INFO: Test took 4231ms.
[22:08:04.891] <TB0> INFO: scanning high vcal = 90 (= 630 in low range)
[22:08:05.166] <TB0> INFO: Expecting 41600 events.
[22:08:12.189] <TB0> INFO: 41600 events read in total (6431ms).
[22:08:12.204] <TB0> INFO: Test took 7313ms.
[22:08:12.268] <TB0> INFO: scanning high vcal = 200 (= 1400 in low range)
[22:08:13.145] <TB0> INFO: Expecting 41600 events.
[22:08:17.820] <TB0> INFO: 41600 events read in total (3962ms).
[22:08:17.821] <TB0> INFO: Test took 5546ms.
[22:08:18.684] <TB0> INFO: PixTestGainPedestal::measure() done
[22:09:31.951] <TB0> INFO: PixTestGainPedestal::fit() done
[22:09:31.951] <TB0> INFO: non-linearity mean: 0.979 0.976 0.805 0.943 0.981 0.937 0.942 0.979 0.916 0.947 0.984 0.876 0.983 0.969 0.979 0.913
[22:09:31.951] <TB0> INFO: non-linearity RMS: 0.005 0.007 0.131 0.062 0.003 0.057 0.047 0.004 0.174 0.056 0.003 0.156 0.005 0.024 0.005 0.133
[22:09:31.951] <TB0> INFO: write gain/ped parameters into /home/l_tester/david/FullQualification/data/M1024_FullQualification_2016-10-19_19h52m_1476899523//001_Fulltest_m20//phCalibrationFitErr35_C0.dat
[22:09:31.966] <TB0> INFO: write gain/ped parameters into /home/l_tester/david/FullQualification/data/M1024_FullQualification_2016-10-19_19h52m_1476899523//001_Fulltest_m20//phCalibrationFitErr35_C1.dat
[22:09:31.988] <TB0> INFO: write gain/ped parameters into /home/l_tester/david/FullQualification/data/M1024_FullQualification_2016-10-19_19h52m_1476899523//001_Fulltest_m20//phCalibrationFitErr35_C2.dat
[22:09:32.009] <TB0> INFO: write gain/ped parameters into /home/l_tester/david/FullQualification/data/M1024_FullQualification_2016-10-19_19h52m_1476899523//001_Fulltest_m20//phCalibrationFitErr35_C3.dat
[22:09:32.031] <TB0> INFO: write gain/ped parameters into /home/l_tester/david/FullQualification/data/M1024_FullQualification_2016-10-19_19h52m_1476899523//001_Fulltest_m20//phCalibrationFitErr35_C4.dat
[22:09:32.052] <TB0> INFO: write gain/ped parameters into /home/l_tester/david/FullQualification/data/M1024_FullQualification_2016-10-19_19h52m_1476899523//001_Fulltest_m20//phCalibrationFitErr35_C5.dat
[22:09:32.074] <TB0> INFO: write gain/ped parameters into /home/l_tester/david/FullQualification/data/M1024_FullQualification_2016-10-19_19h52m_1476899523//001_Fulltest_m20//phCalibrationFitErr35_C6.dat
[22:09:32.096] <TB0> INFO: write gain/ped parameters into /home/l_tester/david/FullQualification/data/M1024_FullQualification_2016-10-19_19h52m_1476899523//001_Fulltest_m20//phCalibrationFitErr35_C7.dat
[22:09:32.117] <TB0> INFO: write gain/ped parameters into /home/l_tester/david/FullQualification/data/M1024_FullQualification_2016-10-19_19h52m_1476899523//001_Fulltest_m20//phCalibrationFitErr35_C8.dat
[22:09:32.139] <TB0> INFO: write gain/ped parameters into /home/l_tester/david/FullQualification/data/M1024_FullQualification_2016-10-19_19h52m_1476899523//001_Fulltest_m20//phCalibrationFitErr35_C9.dat
[22:09:32.160] <TB0> INFO: write gain/ped parameters into /home/l_tester/david/FullQualification/data/M1024_FullQualification_2016-10-19_19h52m_1476899523//001_Fulltest_m20//phCalibrationFitErr35_C10.dat
[22:09:32.182] <TB0> INFO: write gain/ped parameters into /home/l_tester/david/FullQualification/data/M1024_FullQualification_2016-10-19_19h52m_1476899523//001_Fulltest_m20//phCalibrationFitErr35_C11.dat
[22:09:32.203] <TB0> INFO: write gain/ped parameters into /home/l_tester/david/FullQualification/data/M1024_FullQualification_2016-10-19_19h52m_1476899523//001_Fulltest_m20//phCalibrationFitErr35_C12.dat
[22:09:32.225] <TB0> INFO: write gain/ped parameters into /home/l_tester/david/FullQualification/data/M1024_FullQualification_2016-10-19_19h52m_1476899523//001_Fulltest_m20//phCalibrationFitErr35_C13.dat
[22:09:32.246] <TB0> INFO: write gain/ped parameters into /home/l_tester/david/FullQualification/data/M1024_FullQualification_2016-10-19_19h52m_1476899523//001_Fulltest_m20//phCalibrationFitErr35_C14.dat
[22:09:32.268] <TB0> INFO: write gain/ped parameters into /home/l_tester/david/FullQualification/data/M1024_FullQualification_2016-10-19_19h52m_1476899523//001_Fulltest_m20//phCalibrationFitErr35_C15.dat
[22:09:32.289] <TB0> INFO: PixTestGainPedestal::fullTest() done, duration: 279 seconds
[22:09:32.289] <TB0> INFO: Decoding statistics:
[22:09:32.289] <TB0> INFO: General information:
[22:09:32.289] <TB0> INFO: 16bit words read: 3327910
[22:09:32.289] <TB0> INFO: valid events total: 332800
[22:09:32.289] <TB0> INFO: empty events: 0
[22:09:32.289] <TB0> INFO: valid events with pixels: 332800
[22:09:32.289] <TB0> INFO: valid pixel hits: 665555
[22:09:32.290] <TB0> INFO: Event errors: 0
[22:09:32.290] <TB0> INFO: start marker: 0
[22:09:32.290] <TB0> INFO: stop marker: 0
[22:09:32.290] <TB0> INFO: overflow: 0
[22:09:32.290] <TB0> INFO: invalid 5bit words: 0
[22:09:32.290] <TB0> INFO: invalid XOR eye diagram: 0
[22:09:32.290] <TB0> INFO: frame (failed synchr.): 0
[22:09:32.290] <TB0> INFO: idle data (no TBM trl): 0
[22:09:32.290] <TB0> INFO: no data (only TBM hdr): 0
[22:09:32.290] <TB0> INFO: TBM errors: 0
[22:09:32.290] <TB0> INFO: flawed TBM headers: 0
[22:09:32.290] <TB0> INFO: flawed TBM trailers: 0
[22:09:32.290] <TB0> INFO: event ID mismatches: 0
[22:09:32.290] <TB0> INFO: ROC errors: 0
[22:09:32.290] <TB0> INFO: missing ROC header(s): 0
[22:09:32.290] <TB0> INFO: misplaced readback start: 0
[22:09:32.290] <TB0> INFO: Pixel decoding errors: 0
[22:09:32.290] <TB0> INFO: pixel data incomplete: 0
[22:09:32.290] <TB0> INFO: pixel address: 0
[22:09:32.290] <TB0> INFO: pulse height fill bit: 0
[22:09:32.290] <TB0> INFO: buffer corruption: 0
[22:09:32.311] <TB0> INFO: Decoding statistics:
[22:09:32.311] <TB0> INFO: General information:
[22:09:32.311] <TB0> INFO: 16bit words read: 3457334
[22:09:32.311] <TB0> INFO: valid events total: 353536
[22:09:32.311] <TB0> INFO: empty events: 18232
[22:09:32.311] <TB0> INFO: valid events with pixels: 335304
[22:09:32.311] <TB0> INFO: valid pixel hits: 668059
[22:09:32.311] <TB0> INFO: Event errors: 0
[22:09:32.311] <TB0> INFO: start marker: 0
[22:09:32.311] <TB0> INFO: stop marker: 0
[22:09:32.311] <TB0> INFO: overflow: 0
[22:09:32.311] <TB0> INFO: invalid 5bit words: 0
[22:09:32.312] <TB0> INFO: invalid XOR eye diagram: 0
[22:09:32.312] <TB0> INFO: frame (failed synchr.): 0
[22:09:32.312] <TB0> INFO: idle data (no TBM trl): 0
[22:09:32.312] <TB0> INFO: no data (only TBM hdr): 0
[22:09:32.312] <TB0> INFO: TBM errors: 0
[22:09:32.312] <TB0> INFO: flawed TBM headers: 0
[22:09:32.312] <TB0> INFO: flawed TBM trailers: 0
[22:09:32.312] <TB0> INFO: event ID mismatches: 0
[22:09:32.312] <TB0> INFO: ROC errors: 0
[22:09:32.312] <TB0> INFO: missing ROC header(s): 0
[22:09:32.312] <TB0> INFO: misplaced readback start: 0
[22:09:32.312] <TB0> INFO: Pixel decoding errors: 0
[22:09:32.312] <TB0> INFO: pixel data incomplete: 0
[22:09:32.312] <TB0> INFO: pixel address: 0
[22:09:32.312] <TB0> INFO: pulse height fill bit: 0
[22:09:32.312] <TB0> INFO: buffer corruption: 0
[22:09:32.312] <TB0> INFO: enter test to run
[22:09:32.312] <TB0> INFO: test: exit no parameter change
[22:09:32.450] <TB0> QUIET: Connection to board 71 closed.
[22:09:32.450] <TB0> INFO: pXar: this is the end, my friend