[20:44:06.608] <TB3> INFO: *** Welcome to pxar ***
[20:44:06.608] <TB3> INFO: *** Today: 2016/10/19
[20:44:06.615] <TB3> INFO: *** Version: c8ba-dirty
[20:44:06.615] <TB3> INFO: readRocDacs: /home/l_tester/david/FullQualification/data/M1027_FullQualification_2016-10-19_19h52m_1476899523//001_Fulltest_m20//dacParameters_C0.dat .. /home/l_tester/david/FullQualification/data/M1027_FullQualification_2016-10-19_19h52m_1476899523//001_Fulltest_m20//dacParameters_C15.dat
[20:44:06.615] <TB3> INFO: readTbmDacs: /home/l_tester/david/FullQualification/data/M1027_FullQualification_2016-10-19_19h52m_1476899523//001_Fulltest_m20//tbmParameters_C0a.dat .. /home/l_tester/david/FullQualification/data/M1027_FullQualification_2016-10-19_19h52m_1476899523//001_Fulltest_m20//tbmParameters_C1b.dat
[20:44:06.616] <TB3> INFO: readMaskFile: /home/l_tester/david/FullQualification/data/M1027_FullQualification_2016-10-19_19h52m_1476899523//001_Fulltest_m20//defaultMaskFile.dat
[20:44:06.616] <TB3> INFO: readTrimFile: /home/l_tester/david/FullQualification/data/M1027_FullQualification_2016-10-19_19h52m_1476899523//001_Fulltest_m20//trimParameters_C0.dat .. /home/l_tester/david/FullQualification/data/M1027_FullQualification_2016-10-19_19h52m_1476899523//001_Fulltest_m20//trimParameters_C15.dat
[20:44:06.681] <TB3> INFO: clk: 4
[20:44:06.681] <TB3> INFO: ctr: 4
[20:44:06.681] <TB3> INFO: sda: 19
[20:44:06.681] <TB3> INFO: tin: 9
[20:44:06.681] <TB3> INFO: level: 15
[20:44:06.681] <TB3> INFO: triggerdelay: 0
[20:44:06.681] <TB3> QUIET: Instanciating API for pxar v2.7.6+55~gafdbfd9
[20:44:06.681] <TB3> INFO: Log level: INFO
[20:44:06.690] <TB3> INFO: Found DTB DTB_WWVASW
[20:44:06.699] <TB3> QUIET: Connection to board DTB_WWVASW opened.
[20:44:06.701] <TB3> INFO: DTB startup information
--- DTB info------------------------------------------
Board id: 126
HW version: DTB1.2
FW version: 4.6
SW version: 4.7
Options:
USB id: DTB_WWVASW
MAC address: 40D85511807E
Hostname: pixelDTB126
Comment:
------------------------------------------------------
[20:44:06.702] <TB3> INFO: RPC call hashes of host and DTB match: 486171790
[20:44:08.202] <TB3> INFO: DUT info:
[20:44:08.202] <TB3> INFO: The DUT currently contains the following objects:
[20:44:08.202] <TB3> INFO: 4 TBM Cores tbm10c (4 ON)
[20:44:08.202] <TB3> INFO: TBM Core alpha (0): 7 registers set
[20:44:08.202] <TB3> INFO: TBM Core beta (1): 7 registers set
[20:44:08.202] <TB3> INFO: TBM Core alpha (2): 7 registers set
[20:44:08.202] <TB3> INFO: TBM Core beta (3): 7 registers set
[20:44:08.202] <TB3> INFO: 16 ROCs proc600 (16 ON) with 4160 pixelConfigs
[20:44:08.202] <TB3> INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[20:44:08.202] <TB3> INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[20:44:08.202] <TB3> INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[20:44:08.202] <TB3> INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[20:44:08.202] <TB3> INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[20:44:08.203] <TB3> INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[20:44:08.203] <TB3> INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[20:44:08.203] <TB3> INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[20:44:08.203] <TB3> INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[20:44:08.203] <TB3> INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[20:44:08.203] <TB3> INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[20:44:08.203] <TB3> INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[20:44:08.203] <TB3> INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[20:44:08.203] <TB3> INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[20:44:08.203] <TB3> INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[20:44:08.203] <TB3> INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[20:44:08.604] <TB3> INFO: enter 'restricted' command line mode
[20:44:08.604] <TB3> INFO: enter test to run
[20:44:08.604] <TB3> INFO: test: pretest no parameter change
[20:44:08.604] <TB3> INFO: running: pretest
[20:44:08.612] <TB3> INFO: ######################################################################
[20:44:08.612] <TB3> INFO: PixTestPretest::doTest()
[20:44:08.612] <TB3> INFO: ######################################################################
[20:44:08.613] <TB3> INFO: ----------------------------------------------------------------------
[20:44:08.613] <TB3> INFO: PixTestPretest::programROC()
[20:44:08.613] <TB3> INFO: ----------------------------------------------------------------------
[20:44:26.628] <TB3> INFO: PixTestPretest::programROC() done: ROCs are all programmable
[20:44:26.628] <TB3> INFO: IA differences per ROC: 18.5 21.7 19.3 20.9 19.3 20.9 16.9 19.3 18.5 20.1 18.5 19.3 19.3 21.7 20.9 18.5
[20:44:26.702] <TB3> INFO: ----------------------------------------------------------------------
[20:44:26.702] <TB3> INFO: PixTestPretest::setVana() target Ia = 24 mA/ROC
[20:44:26.702] <TB3> INFO: ----------------------------------------------------------------------
[20:44:34.210] <TB3> INFO: PixTestPretest::setVana() done, Module Ia 377 mA = 23.5625 mA/ROC
[20:44:34.211] <TB3> INFO: i(loss) [mA/ROC]: 19.3 19.3 19.3 18.5 19.3 19.3 19.3 19.3 18.5 19.3 19.3 18.5 19.3 17.7 19.3 18.5
[20:44:34.245] <TB3> INFO: ----------------------------------------------------------------------
[20:44:34.246] <TB3> INFO: PixTestPretest::findTiming()
[20:44:34.246] <TB3> INFO: ----------------------------------------------------------------------
[20:44:34.246] <TB3> INFO: PixTestCmd::init()

[20:45:06.383] <TB3> INFO: TBM phases: 160MHz: 0, 400MHz: 2, TBM delays: ROC(0/1):5, header/trailer: 1, token: 1
[20:45:06.383] <TB3> INFO: (success/tries = 100/100), width = 3
[20:45:07.890] <TB3> INFO: ----------------------------------------------------------------------
[20:45:07.890] <TB3> INFO: PixTestPretest::findWorkingPixel()
[20:45:07.890] <TB3> INFO: ----------------------------------------------------------------------
[20:45:07.984] <TB3> INFO: Expecting 231680 events.
[20:45:18.035] <TB3> INFO: 231680 events read in total (9459ms).
[20:45:18.045] <TB3> INFO: Test took 10151ms.
[20:45:18.288] <TB3> INFO: Found working pixel in all ROCs: col/row = 12/22
[20:45:18.321] <TB3> INFO: ----------------------------------------------------------------------
[20:45:18.321] <TB3> INFO: PixTestPretest::setVthrCompCalDel()
[20:45:18.321] <TB3> INFO: ----------------------------------------------------------------------
[20:45:18.415] <TB3> INFO: Expecting 231680 events.
[20:45:28.441] <TB3> INFO: 231680 events read in total (9434ms).
[20:45:28.449] <TB3> INFO: Test took 10123ms.
[20:45:28.709] <TB3> INFO: PixTestPretest::setVthrCompCalDel() done
[20:45:28.709] <TB3> INFO: CalDel: 126 105 117 112 91 103 111 99 89 99 113 109 102 99 111 107
[20:45:28.709] <TB3> INFO: VthrComp: 51 51 51 51 52 51 51 51 51 51 51 51 51 51 51 51
[20:45:28.713] <TB3> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1027_FullQualification_2016-10-19_19h52m_1476899523//001_Fulltest_m20//dacParameters_C0.dat
[20:45:28.713] <TB3> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1027_FullQualification_2016-10-19_19h52m_1476899523//001_Fulltest_m20//dacParameters_C1.dat
[20:45:28.713] <TB3> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1027_FullQualification_2016-10-19_19h52m_1476899523//001_Fulltest_m20//dacParameters_C2.dat
[20:45:28.714] <TB3> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1027_FullQualification_2016-10-19_19h52m_1476899523//001_Fulltest_m20//dacParameters_C3.dat
[20:45:28.714] <TB3> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1027_FullQualification_2016-10-19_19h52m_1476899523//001_Fulltest_m20//dacParameters_C4.dat
[20:45:28.714] <TB3> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1027_FullQualification_2016-10-19_19h52m_1476899523//001_Fulltest_m20//dacParameters_C5.dat
[20:45:28.714] <TB3> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1027_FullQualification_2016-10-19_19h52m_1476899523//001_Fulltest_m20//dacParameters_C6.dat
[20:45:28.714] <TB3> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1027_FullQualification_2016-10-19_19h52m_1476899523//001_Fulltest_m20//dacParameters_C7.dat
[20:45:28.714] <TB3> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1027_FullQualification_2016-10-19_19h52m_1476899523//001_Fulltest_m20//dacParameters_C8.dat
[20:45:28.714] <TB3> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1027_FullQualification_2016-10-19_19h52m_1476899523//001_Fulltest_m20//dacParameters_C9.dat
[20:45:28.714] <TB3> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1027_FullQualification_2016-10-19_19h52m_1476899523//001_Fulltest_m20//dacParameters_C10.dat
[20:45:28.714] <TB3> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1027_FullQualification_2016-10-19_19h52m_1476899523//001_Fulltest_m20//dacParameters_C11.dat
[20:45:28.714] <TB3> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1027_FullQualification_2016-10-19_19h52m_1476899523//001_Fulltest_m20//dacParameters_C12.dat
[20:45:28.715] <TB3> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1027_FullQualification_2016-10-19_19h52m_1476899523//001_Fulltest_m20//dacParameters_C13.dat
[20:45:28.715] <TB3> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1027_FullQualification_2016-10-19_19h52m_1476899523//001_Fulltest_m20//dacParameters_C14.dat
[20:45:28.715] <TB3> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1027_FullQualification_2016-10-19_19h52m_1476899523//001_Fulltest_m20//dacParameters_C15.dat
[20:45:28.715] <TB3> INFO: write tbm parameters into /home/l_tester/david/FullQualification/data/M1027_FullQualification_2016-10-19_19h52m_1476899523//001_Fulltest_m20//tbmParameters_C0a.dat
[20:45:28.715] <TB3> INFO: write tbm parameters into /home/l_tester/david/FullQualification/data/M1027_FullQualification_2016-10-19_19h52m_1476899523//001_Fulltest_m20//tbmParameters_C0b.dat
[20:45:28.715] <TB3> INFO: write tbm parameters into /home/l_tester/david/FullQualification/data/M1027_FullQualification_2016-10-19_19h52m_1476899523//001_Fulltest_m20//tbmParameters_C1a.dat
[20:45:28.715] <TB3> INFO: write tbm parameters into /home/l_tester/david/FullQualification/data/M1027_FullQualification_2016-10-19_19h52m_1476899523//001_Fulltest_m20//tbmParameters_C1b.dat
[20:45:28.715] <TB3> INFO: PixTestPretest::doTest() done, duration: 80 seconds
[20:45:28.768] <TB3> INFO: enter test to run
[20:45:28.768] <TB3> INFO: test: FullTest no parameter change
[20:45:28.768] <TB3> INFO: running: fulltest
[20:45:28.768] <TB3> INFO: ######################################################################
[20:45:28.768] <TB3> INFO: PixTestFullTest::doTest()
[20:45:28.768] <TB3> INFO: ######################################################################
[20:45:28.769] <TB3> INFO: ######################################################################
[20:45:28.769] <TB3> INFO: PixTestAlive::doTest()
[20:45:28.769] <TB3> INFO: ######################################################################
[20:45:28.770] <TB3> INFO: ----------------------------------------------------------------------
[20:45:28.770] <TB3> INFO: PixTestAlive::aliveTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[20:45:28.770] <TB3> INFO: ----------------------------------------------------------------------
[20:45:29.007] <TB3> INFO: Expecting 41600 events.
[20:45:32.532] <TB3> INFO: 41600 events read in total (2933ms).
[20:45:32.532] <TB3> INFO: Test took 3760ms.
[20:45:32.764] <TB3> INFO: PixTestAlive::aliveTest() done
[20:45:32.764] <TB3> INFO: number of dead pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[20:45:32.765] <TB3> INFO: ----------------------------------------------------------------------
[20:45:32.765] <TB3> INFO: PixTestAlive::maskTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[20:45:32.765] <TB3> INFO: ----------------------------------------------------------------------
[20:45:33.048] <TB3> INFO: Expecting 41600 events.
[20:45:36.025] <TB3> INFO: 41600 events read in total (2385ms).
[20:45:36.025] <TB3> INFO: Test took 3258ms.
[20:45:36.026] <TB3> INFO: mask vs. old pixelAlive PixelAlive_C0_V0 .. PixelAlive_C15_V0
[20:45:36.263] <TB3> INFO: PixTestAlive::maskTest() done
[20:45:36.263] <TB3> INFO: number of mask-defect pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[20:45:36.264] <TB3> INFO: ----------------------------------------------------------------------
[20:45:36.264] <TB3> INFO: PixTestAlive::addressDecodingTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[20:45:36.264] <TB3> INFO: ----------------------------------------------------------------------
[20:45:36.504] <TB3> INFO: Expecting 41600 events.
[20:45:40.041] <TB3> INFO: 41600 events read in total (2945ms).
[20:45:40.041] <TB3> INFO: Test took 3774ms.
[20:45:40.280] <TB3> INFO: PixTestAlive::addressDecodingTest() done
[20:45:40.280] <TB3> INFO: number of address-decoding pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[20:45:40.280] <TB3> INFO: PixTestAlive::doTest() done, duration: 11 seconds
[20:45:40.280] <TB3> INFO: Decoding statistics:
[20:45:40.280] <TB3> INFO: General information:
[20:45:40.280] <TB3> INFO: 16bit words read: 0
[20:45:40.280] <TB3> INFO: valid events total: 0
[20:45:40.280] <TB3> INFO: empty events: 0
[20:45:40.280] <TB3> INFO: valid events with pixels: 0
[20:45:40.280] <TB3> INFO: valid pixel hits: 0
[20:45:40.280] <TB3> INFO: Event errors: 0
[20:45:40.280] <TB3> INFO: start marker: 0
[20:45:40.280] <TB3> INFO: stop marker: 0
[20:45:40.280] <TB3> INFO: overflow: 0
[20:45:40.280] <TB3> INFO: invalid 5bit words: 0
[20:45:40.280] <TB3> INFO: invalid XOR eye diagram: 0
[20:45:40.280] <TB3> INFO: frame (failed synchr.): 0
[20:45:40.280] <TB3> INFO: idle data (no TBM trl): 0
[20:45:40.280] <TB3> INFO: no data (only TBM hdr): 0
[20:45:40.280] <TB3> INFO: TBM errors: 0
[20:45:40.280] <TB3> INFO: flawed TBM headers: 0
[20:45:40.280] <TB3> INFO: flawed TBM trailers: 0
[20:45:40.280] <TB3> INFO: event ID mismatches: 0
[20:45:40.280] <TB3> INFO: ROC errors: 0
[20:45:40.280] <TB3> INFO: missing ROC header(s): 0
[20:45:40.280] <TB3> INFO: misplaced readback start: 0
[20:45:40.280] <TB3> INFO: Pixel decoding errors: 0
[20:45:40.280] <TB3> INFO: pixel data incomplete: 0
[20:45:40.280] <TB3> INFO: pixel address: 0
[20:45:40.280] <TB3> INFO: pulse height fill bit: 0
[20:45:40.280] <TB3> INFO: buffer corruption: 0
[20:45:40.288] <TB3> INFO: readReadbackCal: /home/l_tester/david/FullQualification/data/M1027_FullQualification_2016-10-19_19h52m_1476899523//001_Fulltest_m20//readbackCal_C0.dat .. /home/l_tester/david/FullQualification/data/M1027_FullQualification_2016-10-19_19h52m_1476899523//001_Fulltest_m20//readbackCal_C15.dat
[20:45:40.289] <TB3> INFO: readGainPedestalParameters /home/l_tester/david/FullQualification/data/M1027_FullQualification_2016-10-19_19h52m_1476899523//001_Fulltest_m20//phCalibrationFitErr_C0.dat .. /home/l_tester/david/FullQualification/data/M1027_FullQualification_2016-10-19_19h52m_1476899523//001_Fulltest_m20//phCalibrationFitErr_C15.dat

[20:45:40.289] <TB3> INFO: ######################################################################
[20:45:40.289] <TB3> INFO: PixTestReadback::doTest()
[20:45:40.289] <TB3> INFO: ######################################################################
[20:45:40.289] <TB3> INFO: ----------------------------------------------------------------------
[20:45:40.289] <TB3> INFO: PixTestReadback::CalibrateVd()
[20:45:40.289] <TB3> INFO: ----------------------------------------------------------------------
[20:45:50.268] <TB3> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1027_FullQualification_2016-10-19_19h52m_1476899523//001_Fulltest_m20//readbackCal_C0.dat
[20:45:50.268] <TB3> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1027_FullQualification_2016-10-19_19h52m_1476899523//001_Fulltest_m20//readbackCal_C1.dat
[20:45:50.268] <TB3> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1027_FullQualification_2016-10-19_19h52m_1476899523//001_Fulltest_m20//readbackCal_C2.dat
[20:45:50.269] <TB3> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1027_FullQualification_2016-10-19_19h52m_1476899523//001_Fulltest_m20//readbackCal_C3.dat
[20:45:50.269] <TB3> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1027_FullQualification_2016-10-19_19h52m_1476899523//001_Fulltest_m20//readbackCal_C4.dat
[20:45:50.269] <TB3> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1027_FullQualification_2016-10-19_19h52m_1476899523//001_Fulltest_m20//readbackCal_C5.dat
[20:45:50.269] <TB3> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1027_FullQualification_2016-10-19_19h52m_1476899523//001_Fulltest_m20//readbackCal_C6.dat
[20:45:50.269] <TB3> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1027_FullQualification_2016-10-19_19h52m_1476899523//001_Fulltest_m20//readbackCal_C7.dat
[20:45:50.269] <TB3> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1027_FullQualification_2016-10-19_19h52m_1476899523//001_Fulltest_m20//readbackCal_C8.dat
[20:45:50.269] <TB3> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1027_FullQualification_2016-10-19_19h52m_1476899523//001_Fulltest_m20//readbackCal_C9.dat
[20:45:50.269] <TB3> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1027_FullQualification_2016-10-19_19h52m_1476899523//001_Fulltest_m20//readbackCal_C10.dat
[20:45:50.269] <TB3> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1027_FullQualification_2016-10-19_19h52m_1476899523//001_Fulltest_m20//readbackCal_C11.dat
[20:45:50.269] <TB3> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1027_FullQualification_2016-10-19_19h52m_1476899523//001_Fulltest_m20//readbackCal_C12.dat
[20:45:50.270] <TB3> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1027_FullQualification_2016-10-19_19h52m_1476899523//001_Fulltest_m20//readbackCal_C13.dat
[20:45:50.270] <TB3> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1027_FullQualification_2016-10-19_19h52m_1476899523//001_Fulltest_m20//readbackCal_C14.dat
[20:45:50.270] <TB3> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1027_FullQualification_2016-10-19_19h52m_1476899523//001_Fulltest_m20//readbackCal_C15.dat
[20:45:50.301] <TB3> INFO: PixTestPattern:: pg_setup set to default.
[20:45:50.301] <TB3> INFO: ----------------------------------------------------------------------
[20:45:50.301] <TB3> INFO: PixTestReadback::CalibrateVa()
[20:45:50.301] <TB3> INFO: ----------------------------------------------------------------------
[20:46:00.238] <TB3> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1027_FullQualification_2016-10-19_19h52m_1476899523//001_Fulltest_m20//readbackCal_C0.dat
[20:46:00.238] <TB3> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1027_FullQualification_2016-10-19_19h52m_1476899523//001_Fulltest_m20//readbackCal_C1.dat
[20:46:00.239] <TB3> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1027_FullQualification_2016-10-19_19h52m_1476899523//001_Fulltest_m20//readbackCal_C2.dat
[20:46:00.239] <TB3> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1027_FullQualification_2016-10-19_19h52m_1476899523//001_Fulltest_m20//readbackCal_C3.dat
[20:46:00.239] <TB3> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1027_FullQualification_2016-10-19_19h52m_1476899523//001_Fulltest_m20//readbackCal_C4.dat
[20:46:00.239] <TB3> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1027_FullQualification_2016-10-19_19h52m_1476899523//001_Fulltest_m20//readbackCal_C5.dat
[20:46:00.239] <TB3> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1027_FullQualification_2016-10-19_19h52m_1476899523//001_Fulltest_m20//readbackCal_C6.dat
[20:46:00.239] <TB3> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1027_FullQualification_2016-10-19_19h52m_1476899523//001_Fulltest_m20//readbackCal_C7.dat
[20:46:00.239] <TB3> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1027_FullQualification_2016-10-19_19h52m_1476899523//001_Fulltest_m20//readbackCal_C8.dat
[20:46:00.239] <TB3> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1027_FullQualification_2016-10-19_19h52m_1476899523//001_Fulltest_m20//readbackCal_C9.dat
[20:46:00.239] <TB3> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1027_FullQualification_2016-10-19_19h52m_1476899523//001_Fulltest_m20//readbackCal_C10.dat
[20:46:00.239] <TB3> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1027_FullQualification_2016-10-19_19h52m_1476899523//001_Fulltest_m20//readbackCal_C11.dat
[20:46:00.239] <TB3> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1027_FullQualification_2016-10-19_19h52m_1476899523//001_Fulltest_m20//readbackCal_C12.dat
[20:46:00.239] <TB3> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1027_FullQualification_2016-10-19_19h52m_1476899523//001_Fulltest_m20//readbackCal_C13.dat
[20:46:00.239] <TB3> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1027_FullQualification_2016-10-19_19h52m_1476899523//001_Fulltest_m20//readbackCal_C14.dat
[20:46:00.240] <TB3> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1027_FullQualification_2016-10-19_19h52m_1476899523//001_Fulltest_m20//readbackCal_C15.dat
[20:46:00.270] <TB3> INFO: PixTestPattern:: pg_setup set to default.
[20:46:00.270] <TB3> INFO: ----------------------------------------------------------------------
[20:46:00.270] <TB3> INFO: PixTestReadback::readbackVbg()
[20:46:00.270] <TB3> INFO: ----------------------------------------------------------------------
[20:46:07.942] <TB3> INFO: PixTestPattern:: pg_setup set to default.
[20:46:07.942] <TB3> INFO: ----------------------------------------------------------------------
[20:46:07.942] <TB3> INFO: PixTestReadback::getCalibratedVbg()
[20:46:07.942] <TB3> INFO: ----------------------------------------------------------------------
[20:46:07.942] <TB3> INFO: Vbg will be calibrated using Vd calibration
[20:46:07.942] <TB3> INFO: /*/*/*/*::: ROC 0: uncalibrated Vbg = 143.5calibrated Vbg = 1.18826 :::*/*/*/*/
[20:46:07.942] <TB3> INFO: /*/*/*/*::: ROC 1: uncalibrated Vbg = 151.1calibrated Vbg = 1.1963 :::*/*/*/*/
[20:46:07.942] <TB3> INFO: /*/*/*/*::: ROC 2: uncalibrated Vbg = 156.4calibrated Vbg = 1.19288 :::*/*/*/*/
[20:46:07.942] <TB3> INFO: /*/*/*/*::: ROC 3: uncalibrated Vbg = 157.2calibrated Vbg = 1.19262 :::*/*/*/*/
[20:46:07.942] <TB3> INFO: /*/*/*/*::: ROC 4: uncalibrated Vbg = 164.2calibrated Vbg = 1.18797 :::*/*/*/*/
[20:46:07.942] <TB3> INFO: /*/*/*/*::: ROC 5: uncalibrated Vbg = 153.6calibrated Vbg = 1.19989 :::*/*/*/*/
[20:46:07.942] <TB3> INFO: /*/*/*/*::: ROC 6: uncalibrated Vbg = 155.3calibrated Vbg = 1.1902 :::*/*/*/*/
[20:46:07.942] <TB3> INFO: /*/*/*/*::: ROC 7: uncalibrated Vbg = 146.6calibrated Vbg = 1.19959 :::*/*/*/*/
[20:46:07.942] <TB3> INFO: /*/*/*/*::: ROC 8: uncalibrated Vbg = 163.6calibrated Vbg = 1.19689 :::*/*/*/*/
[20:46:07.942] <TB3> INFO: /*/*/*/*::: ROC 9: uncalibrated Vbg = 156.9calibrated Vbg = 1.19251 :::*/*/*/*/
[20:46:07.942] <TB3> INFO: /*/*/*/*::: ROC 10: uncalibrated Vbg = 161.3calibrated Vbg = 1.18044 :::*/*/*/*/
[20:46:07.942] <TB3> INFO: /*/*/*/*::: ROC 11: uncalibrated Vbg = 156.3calibrated Vbg = 1.18326 :::*/*/*/*/
[20:46:07.942] <TB3> INFO: /*/*/*/*::: ROC 12: uncalibrated Vbg = 160.5calibrated Vbg = 1.19048 :::*/*/*/*/
[20:46:07.943] <TB3> INFO: /*/*/*/*::: ROC 13: uncalibrated Vbg = 153.6calibrated Vbg = 1.19607 :::*/*/*/*/
[20:46:07.943] <TB3> INFO: /*/*/*/*::: ROC 14: uncalibrated Vbg = 161.6calibrated Vbg = 1.1921 :::*/*/*/*/
[20:46:07.943] <TB3> INFO: /*/*/*/*::: ROC 15: uncalibrated Vbg = 154.4calibrated Vbg = 1.19039 :::*/*/*/*/
[20:46:07.945] <TB3> INFO: ----------------------------------------------------------------------
[20:46:07.945] <TB3> INFO: PixTestReadback::CalibrateIa()
[20:46:07.945] <TB3> INFO: ----------------------------------------------------------------------
[20:48:48.718] <TB3> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1027_FullQualification_2016-10-19_19h52m_1476899523//001_Fulltest_m20//readbackCal_C0.dat
[20:48:48.718] <TB3> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1027_FullQualification_2016-10-19_19h52m_1476899523//001_Fulltest_m20//readbackCal_C1.dat
[20:48:48.718] <TB3> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1027_FullQualification_2016-10-19_19h52m_1476899523//001_Fulltest_m20//readbackCal_C2.dat
[20:48:48.718] <TB3> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1027_FullQualification_2016-10-19_19h52m_1476899523//001_Fulltest_m20//readbackCal_C3.dat
[20:48:48.718] <TB3> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1027_FullQualification_2016-10-19_19h52m_1476899523//001_Fulltest_m20//readbackCal_C4.dat
[20:48:48.718] <TB3> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1027_FullQualification_2016-10-19_19h52m_1476899523//001_Fulltest_m20//readbackCal_C5.dat
[20:48:48.718] <TB3> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1027_FullQualification_2016-10-19_19h52m_1476899523//001_Fulltest_m20//readbackCal_C6.dat
[20:48:48.718] <TB3> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1027_FullQualification_2016-10-19_19h52m_1476899523//001_Fulltest_m20//readbackCal_C7.dat
[20:48:48.718] <TB3> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1027_FullQualification_2016-10-19_19h52m_1476899523//001_Fulltest_m20//readbackCal_C8.dat
[20:48:48.718] <TB3> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1027_FullQualification_2016-10-19_19h52m_1476899523//001_Fulltest_m20//readbackCal_C9.dat
[20:48:48.718] <TB3> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1027_FullQualification_2016-10-19_19h52m_1476899523//001_Fulltest_m20//readbackCal_C10.dat
[20:48:48.718] <TB3> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1027_FullQualification_2016-10-19_19h52m_1476899523//001_Fulltest_m20//readbackCal_C11.dat
[20:48:48.719] <TB3> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1027_FullQualification_2016-10-19_19h52m_1476899523//001_Fulltest_m20//readbackCal_C12.dat
[20:48:48.719] <TB3> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1027_FullQualification_2016-10-19_19h52m_1476899523//001_Fulltest_m20//readbackCal_C13.dat
[20:48:48.719] <TB3> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1027_FullQualification_2016-10-19_19h52m_1476899523//001_Fulltest_m20//readbackCal_C14.dat
[20:48:48.719] <TB3> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1027_FullQualification_2016-10-19_19h52m_1476899523//001_Fulltest_m20//readbackCal_C15.dat
[20:48:48.749] <TB3> INFO: PixTestPattern:: pg_setup set to default.
[20:48:48.751] <TB3> INFO: PixTestReadback::doTest() done
[20:48:48.751] <TB3> INFO: Decoding statistics:
[20:48:48.751] <TB3> INFO: General information:
[20:48:48.751] <TB3> INFO: 16bit words read: 1536
[20:48:48.751] <TB3> INFO: valid events total: 256
[20:48:48.751] <TB3> INFO: empty events: 256
[20:48:48.751] <TB3> INFO: valid events with pixels: 0
[20:48:48.751] <TB3> INFO: valid pixel hits: 0
[20:48:48.751] <TB3> INFO: Event errors: 0
[20:48:48.751] <TB3> INFO: start marker: 0
[20:48:48.751] <TB3> INFO: stop marker: 0
[20:48:48.751] <TB3> INFO: overflow: 0
[20:48:48.751] <TB3> INFO: invalid 5bit words: 0
[20:48:48.751] <TB3> INFO: invalid XOR eye diagram: 0
[20:48:48.751] <TB3> INFO: frame (failed synchr.): 0
[20:48:48.751] <TB3> INFO: idle data (no TBM trl): 0
[20:48:48.751] <TB3> INFO: no data (only TBM hdr): 0
[20:48:48.751] <TB3> INFO: TBM errors: 0
[20:48:48.751] <TB3> INFO: flawed TBM headers: 0
[20:48:48.751] <TB3> INFO: flawed TBM trailers: 0
[20:48:48.751] <TB3> INFO: event ID mismatches: 0
[20:48:48.751] <TB3> INFO: ROC errors: 0
[20:48:48.751] <TB3> INFO: missing ROC header(s): 0
[20:48:48.751] <TB3> INFO: misplaced readback start: 0
[20:48:48.751] <TB3> INFO: Pixel decoding errors: 0
[20:48:48.751] <TB3> INFO: pixel data incomplete: 0
[20:48:48.751] <TB3> INFO: pixel address: 0
[20:48:48.752] <TB3> INFO: pulse height fill bit: 0
[20:48:48.752] <TB3> INFO: buffer corruption: 0
[20:48:48.811] <TB3> INFO: ######################################################################
[20:48:48.811] <TB3> INFO: PixTestBBMap::doTest() Ntrig = 5, VcalS = 250 (high range)
[20:48:48.811] <TB3> INFO: ######################################################################
[20:48:48.814] <TB3> INFO: ---> dac: VthrComp name: calSMap ntrig: 5 dacrange: 0 .. 149 (-1/-1) hits flags = 514 (plus default)
[20:48:48.826] <TB3> INFO: dacScan split into 1 runs with ntrig = 5
[20:48:48.826] <TB3> INFO: run 1 of 1
[20:48:49.062] <TB3> INFO: Expecting 3120000 events.
[20:49:20.564] <TB3> INFO: 677890 events read in total (30909ms).











[20:49:50.906] <TB3> INFO: 1349055 events read in total (61252ms).
[20:50:21.167] <TB3> INFO: 2018025 events read in total (91512ms).











[20:50:51.228] <TB3> INFO: 2688040 events read in total (121573ms).











[20:51:10.485] <TB3> INFO: 3120000 events read in total (140830ms).
[20:51:10.562] <TB3> INFO: Test took 141737ms.
[20:51:35.850] <TB3> INFO: PixTestBBMap::doTest() done with 2 decoding errors: , duration: 167 seconds
[20:51:35.850] <TB3> INFO: number of dead bumps (per ROC): 3 1 1 2 1 0 0 2 1 1 0 1 0 0 0 0
[20:51:35.850] <TB3> INFO: separation cut (per ROC): 105 103 108 102 107 101 99 103 108 108 105 105 103 112 106 118
[20:51:35.850] <TB3> INFO: Decoding statistics:
[20:51:35.850] <TB3> INFO: General information:
[20:51:35.850] <TB3> INFO: 16bit words read: 0
[20:51:35.850] <TB3> INFO: valid events total: 0
[20:51:35.850] <TB3> INFO: empty events: 0
[20:51:35.850] <TB3> INFO: valid events with pixels: 0
[20:51:35.850] <TB3> INFO: valid pixel hits: 0
[20:51:35.850] <TB3> INFO: Event errors: 0
[20:51:35.850] <TB3> INFO: start marker: 0
[20:51:35.850] <TB3> INFO: stop marker: 0
[20:51:35.850] <TB3> INFO: overflow: 0
[20:51:35.850] <TB3> INFO: invalid 5bit words: 0
[20:51:35.850] <TB3> INFO: invalid XOR eye diagram: 0
[20:51:35.850] <TB3> INFO: frame (failed synchr.): 0
[20:51:35.850] <TB3> INFO: idle data (no TBM trl): 0
[20:51:35.850] <TB3> INFO: no data (only TBM hdr): 0
[20:51:35.850] <TB3> INFO: TBM errors: 0
[20:51:35.850] <TB3> INFO: flawed TBM headers: 0
[20:51:35.850] <TB3> INFO: flawed TBM trailers: 0
[20:51:35.850] <TB3> INFO: event ID mismatches: 0
[20:51:35.850] <TB3> INFO: ROC errors: 0
[20:51:35.850] <TB3> INFO: missing ROC header(s): 0
[20:51:35.850] <TB3> INFO: misplaced readback start: 0
[20:51:35.850] <TB3> INFO: Pixel decoding errors: 0
[20:51:35.850] <TB3> INFO: pixel data incomplete: 0
[20:51:35.850] <TB3> INFO: pixel address: 0
[20:51:35.850] <TB3> INFO: pulse height fill bit: 0
[20:51:35.850] <TB3> INFO: buffer corruption: 0
[20:51:35.886] <TB3> INFO: ######################################################################
[20:51:35.886] <TB3> INFO: PixTestScurves::fullTest() ntrig = 50, dacs/step = -1, ntrig/step = -1
[20:51:35.886] <TB3> INFO: ######################################################################
[20:51:35.886] <TB3> INFO: ----------------------------------------------------------------------
[20:51:35.886] <TB3> INFO: PixTestScurves::scurves(Vcal), ntrig = 50, dacs/step = -1, ntrig/step = -1
[20:51:35.886] <TB3> INFO: ----------------------------------------------------------------------
[20:51:35.886] <TB3> INFO: ---> dac: Vcal name: scurveVcal ntrig: 50 dacrange: 25 .. 200 (-1/-1) hits flags = 528 (plus default)
[20:51:35.901] <TB3> INFO: dacScan split into 1 runs with ntrig = 50
[20:51:35.901] <TB3> INFO: run 1 of 1
[20:51:36.181] <TB3> INFO: Expecting 36608000 events.
[20:51:59.645] <TB3> INFO: 667750 events read in total (22865ms).
[20:52:22.380] <TB3> INFO: 1324050 events read in total (45600ms).
[20:52:45.070] <TB3> INFO: 1979300 events read in total (68290ms).
[20:53:07.642] <TB3> INFO: 2633800 events read in total (90862ms).
[20:53:30.260] <TB3> INFO: 3289950 events read in total (113480ms).
[20:53:52.748] <TB3> INFO: 3946900 events read in total (135968ms).
[20:54:15.579] <TB3> INFO: 4600800 events read in total (158799ms).
[20:54:38.206] <TB3> INFO: 5254550 events read in total (181426ms).
[20:55:00.820] <TB3> INFO: 5909750 events read in total (204040ms).
[20:55:23.279] <TB3> INFO: 6563100 events read in total (226499ms).
[20:55:45.607] <TB3> INFO: 7215850 events read in total (248827ms).
[20:56:08.270] <TB3> INFO: 7870100 events read in total (271490ms).
[20:56:31.158] <TB3> INFO: 8522950 events read in total (294378ms).
[20:56:53.589] <TB3> INFO: 9175850 events read in total (316809ms).
[20:57:16.309] <TB3> INFO: 9828050 events read in total (339529ms).
[20:57:38.885] <TB3> INFO: 10479600 events read in total (362105ms).
[20:58:01.332] <TB3> INFO: 11129750 events read in total (384552ms).
[20:58:23.732] <TB3> INFO: 11781900 events read in total (406952ms).
[20:58:46.195] <TB3> INFO: 12433600 events read in total (429415ms).
[20:59:08.777] <TB3> INFO: 13086900 events read in total (451997ms).
[20:59:31.180] <TB3> INFO: 13738900 events read in total (474400ms).
[20:59:53.800] <TB3> INFO: 14391100 events read in total (497020ms).
[21:00:16.274] <TB3> INFO: 15043700 events read in total (519494ms).
[21:00:38.842] <TB3> INFO: 15695500 events read in total (542062ms).
[21:01:01.275] <TB3> INFO: 16347300 events read in total (564495ms).
[21:01:23.935] <TB3> INFO: 16997950 events read in total (587155ms).
[21:01:46.568] <TB3> INFO: 17647700 events read in total (609788ms).
[21:02:09.048] <TB3> INFO: 18296400 events read in total (632268ms).
[21:02:31.613] <TB3> INFO: 18942700 events read in total (654833ms).
[21:02:54.492] <TB3> INFO: 19588400 events read in total (677712ms).
[21:03:16.922] <TB3> INFO: 20234800 events read in total (700142ms).
[21:03:39.508] <TB3> INFO: 20879300 events read in total (722728ms).
[21:04:01.835] <TB3> INFO: 21524200 events read in total (745055ms).
[21:04:24.310] <TB3> INFO: 22170450 events read in total (767530ms).
[21:04:46.968] <TB3> INFO: 22817050 events read in total (790188ms).
[21:05:09.203] <TB3> INFO: 23462950 events read in total (812423ms).
[21:05:31.895] <TB3> INFO: 24109400 events read in total (835115ms).
[21:05:54.171] <TB3> INFO: 24755300 events read in total (857391ms).
[21:06:16.751] <TB3> INFO: 25403000 events read in total (879971ms).
[21:06:39.887] <TB3> INFO: 26048350 events read in total (903107ms).
[21:07:02.431] <TB3> INFO: 26693950 events read in total (925651ms).
[21:07:24.980] <TB3> INFO: 27338900 events read in total (948200ms).
[21:07:47.451] <TB3> INFO: 27985350 events read in total (970671ms).
[21:08:09.954] <TB3> INFO: 28629950 events read in total (993174ms).
[21:08:32.447] <TB3> INFO: 29274200 events read in total (1015667ms).
[21:08:54.947] <TB3> INFO: 29918150 events read in total (1038167ms).
[21:09:17.602] <TB3> INFO: 30564050 events read in total (1060822ms).
[21:09:40.182] <TB3> INFO: 31209750 events read in total (1083402ms).
[21:10:02.696] <TB3> INFO: 31854100 events read in total (1105916ms).
[21:10:25.334] <TB3> INFO: 32499300 events read in total (1128554ms).
[21:10:47.880] <TB3> INFO: 33146500 events read in total (1151100ms).
[21:11:10.497] <TB3> INFO: 33793900 events read in total (1173717ms).
[21:11:33.116] <TB3> INFO: 34439250 events read in total (1196336ms).
[21:11:55.555] <TB3> INFO: 35084800 events read in total (1218775ms).
[21:12:18.411] <TB3> INFO: 35732750 events read in total (1241631ms).
[21:12:41.438] <TB3> INFO: 36386200 events read in total (1264658ms).
[21:12:49.378] <TB3> INFO: 36608000 events read in total (1272598ms).
[21:12:49.518] <TB3> INFO: Test took 1273617ms.
[21:12:50.276] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[21:12:52.011] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[21:12:53.794] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[21:12:55.719] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[21:12:57.505] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[21:12:59.656] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[21:13:01.528] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[21:13:03.968] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[21:13:06.266] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[21:13:07.784] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[21:13:09.286] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[21:13:10.811] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[21:13:12.352] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[21:13:13.861] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[21:13:15.391] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[21:13:17.361] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[21:13:19.206] <TB3> INFO: PixTestScurves::scurves() done
[21:13:19.206] <TB3> INFO: Vcal mean: 113.27 108.25 108.69 106.88 118.95 105.40 101.66 106.87 114.12 115.22 111.36 115.25 109.66 117.81 108.70 114.97
[21:13:19.206] <TB3> INFO: Vcal RMS: 4.98 5.52 5.85 5.96 5.60 5.29 5.51 5.22 5.89 5.57 5.63 5.32 4.92 5.78 5.15 5.22
[21:13:19.206] <TB3> INFO: PixTestScurves::fullTest() done, duration: 1303 seconds
[21:13:19.206] <TB3> INFO: Decoding statistics:
[21:13:19.206] <TB3> INFO: General information:
[21:13:19.206] <TB3> INFO: 16bit words read: 0
[21:13:19.206] <TB3> INFO: valid events total: 0
[21:13:19.206] <TB3> INFO: empty events: 0
[21:13:19.206] <TB3> INFO: valid events with pixels: 0
[21:13:19.206] <TB3> INFO: valid pixel hits: 0
[21:13:19.206] <TB3> INFO: Event errors: 0
[21:13:19.206] <TB3> INFO: start marker: 0
[21:13:19.206] <TB3> INFO: stop marker: 0
[21:13:19.206] <TB3> INFO: overflow: 0
[21:13:19.206] <TB3> INFO: invalid 5bit words: 0
[21:13:19.206] <TB3> INFO: invalid XOR eye diagram: 0
[21:13:19.206] <TB3> INFO: frame (failed synchr.): 0
[21:13:19.206] <TB3> INFO: idle data (no TBM trl): 0
[21:13:19.206] <TB3> INFO: no data (only TBM hdr): 0
[21:13:19.206] <TB3> INFO: TBM errors: 0
[21:13:19.206] <TB3> INFO: flawed TBM headers: 0
[21:13:19.206] <TB3> INFO: flawed TBM trailers: 0
[21:13:19.206] <TB3> INFO: event ID mismatches: 0
[21:13:19.206] <TB3> INFO: ROC errors: 0
[21:13:19.206] <TB3> INFO: missing ROC header(s): 0
[21:13:19.206] <TB3> INFO: misplaced readback start: 0
[21:13:19.206] <TB3> INFO: Pixel decoding errors: 0
[21:13:19.206] <TB3> INFO: pixel data incomplete: 0
[21:13:19.206] <TB3> INFO: pixel address: 0
[21:13:19.206] <TB3> INFO: pulse height fill bit: 0
[21:13:19.206] <TB3> INFO: buffer corruption: 0
[21:13:19.271] <TB3> INFO: ######################################################################
[21:13:19.271] <TB3> INFO: PixTestTrim::doTest()
[21:13:19.271] <TB3> INFO: ######################################################################
[21:13:19.273] <TB3> INFO: ----------------------------------------------------------------------
[21:13:19.273] <TB3> INFO: PixTestTrim::trimTest() ntrig = 8, vcal = 35
[21:13:19.273] <TB3> INFO: ----------------------------------------------------------------------
[21:13:19.313] <TB3> INFO: ---> VthrComp thr map (minimal VthrComp)
[21:13:19.313] <TB3> INFO: ---> dac: vthrcomp name: TrimThr0 ntrig: 8 dacrange: 10 .. 160 (-1/-1) hits flags = 528 (plus default)
[21:13:19.325] <TB3> INFO: dacScan split into 1 runs with ntrig = 8
[21:13:19.325] <TB3> INFO: run 1 of 1
[21:13:19.600] <TB3> INFO: Expecting 5025280 events.
[21:13:50.106] <TB3> INFO: 823456 events read in total (29904ms).
[21:14:19.729] <TB3> INFO: 1643368 events read in total (59527ms).
[21:14:49.703] <TB3> INFO: 2461432 events read in total (89501ms).
[21:15:19.693] <TB3> INFO: 3276112 events read in total (119491ms).
[21:15:49.710] <TB3> INFO: 4087672 events read in total (149508ms).
[21:16:20.430] <TB3> INFO: 4897792 events read in total (180228ms).
[21:16:25.418] <TB3> INFO: 5025280 events read in total (185216ms).
[21:16:25.478] <TB3> INFO: Test took 186153ms.
[21:16:48.772] <TB3> INFO: ROC 0 VthrComp = 109
[21:16:48.772] <TB3> INFO: ROC 1 VthrComp = 109
[21:16:48.772] <TB3> INFO: ROC 2 VthrComp = 112
[21:16:48.772] <TB3> INFO: ROC 3 VthrComp = 106
[21:16:48.772] <TB3> INFO: ROC 4 VthrComp = 120
[21:16:48.772] <TB3> INFO: ROC 5 VthrComp = 108
[21:16:48.773] <TB3> INFO: ROC 6 VthrComp = 103
[21:16:48.773] <TB3> INFO: ROC 7 VthrComp = 113
[21:16:48.773] <TB3> INFO: ROC 8 VthrComp = 113
[21:16:48.773] <TB3> INFO: ROC 9 VthrComp = 117
[21:16:48.773] <TB3> INFO: ROC 10 VthrComp = 108
[21:16:48.774] <TB3> INFO: ROC 11 VthrComp = 112
[21:16:48.774] <TB3> INFO: ROC 12 VthrComp = 109
[21:16:48.774] <TB3> INFO: ROC 13 VthrComp = 119
[21:16:48.774] <TB3> INFO: ROC 14 VthrComp = 109
[21:16:48.774] <TB3> INFO: ROC 15 VthrComp = 121
[21:16:49.100] <TB3> INFO: Expecting 41600 events.
[21:16:52.585] <TB3> INFO: 41600 events read in total (2893ms).
[21:16:52.586] <TB3> INFO: Test took 3810ms.
[21:16:52.595] <TB3> INFO: ---> Vcal thr map (pixel with maximum Vcal thr)
[21:16:52.595] <TB3> INFO: ---> dac: vcal name: TrimThr1 ntrig: 8 dacrange: 10 .. 160 (-1/-1) hits flags = 528 (plus default)
[21:16:52.606] <TB3> INFO: dacScan split into 1 runs with ntrig = 8
[21:16:52.606] <TB3> INFO: run 1 of 1
[21:16:52.884] <TB3> INFO: Expecting 5025280 events.
[21:17:19.466] <TB3> INFO: 591288 events read in total (25990ms).
[21:17:45.451] <TB3> INFO: 1181528 events read in total (51975ms).
[21:18:10.999] <TB3> INFO: 1772768 events read in total (77523ms).
[21:18:36.616] <TB3> INFO: 2363536 events read in total (103140ms).
[21:19:02.454] <TB3> INFO: 2951760 events read in total (128978ms).
[21:19:28.179] <TB3> INFO: 3538232 events read in total (154703ms).
[21:19:54.060] <TB3> INFO: 4124496 events read in total (180584ms).
[21:20:19.994] <TB3> INFO: 4709608 events read in total (206518ms).
[21:20:34.474] <TB3> INFO: 5025280 events read in total (220998ms).
[21:20:34.596] <TB3> INFO: Test took 221991ms.
[21:21:02.987] <TB3> INFO: roc 0 with ID = 0 has maximal Vcal 63.0755 for pixel 39/24 mean/min/max = 48.8816/34.4834/63.2799
[21:21:02.987] <TB3> INFO: roc 1 with ID = 1 has maximal Vcal 62.2599 for pixel 8/9 mean/min/max = 48.1127/33.7984/62.427
[21:21:02.988] <TB3> INFO: roc 2 with ID = 2 has maximal Vcal 62.5467 for pixel 10/3 mean/min/max = 46.9945/31.4109/62.5782
[21:21:02.989] <TB3> INFO: roc 3 with ID = 3 has maximal Vcal 65.1564 for pixel 6/69 mean/min/max = 49.3592/33.3595/65.3588
[21:21:02.989] <TB3> INFO: roc 4 with ID = 4 has maximal Vcal 61.5795 for pixel 13/18 mean/min/max = 46.7641/31.7513/61.7769
[21:21:02.989] <TB3> INFO: roc 5 with ID = 5 has maximal Vcal 60.9724 for pixel 51/10 mean/min/max = 47.9218/34.8235/61.02
[21:21:02.990] <TB3> INFO: roc 6 with ID = 6 has maximal Vcal 60.7874 for pixel 4/8 mean/min/max = 47.2065/33.5255/60.8874
[21:21:02.990] <TB3> INFO: roc 7 with ID = 7 has maximal Vcal 58.6317 for pixel 6/12 mean/min/max = 45.5211/32.2085/58.8337
[21:21:02.991] <TB3> INFO: roc 8 with ID = 8 has maximal Vcal 62.4238 for pixel 10/2 mean/min/max = 46.7968/31.0915/62.5021
[21:21:02.991] <TB3> INFO: roc 9 with ID = 9 has maximal Vcal 60.2034 for pixel 0/16 mean/min/max = 45.689/31.1107/60.2673
[21:21:02.992] <TB3> INFO: roc 10 with ID = 10 has maximal Vcal 64.8062 for pixel 13/3 mean/min/max = 49.5299/33.5199/65.54
[21:21:02.993] <TB3> INFO: roc 11 with ID = 11 has maximal Vcal 62.7015 for pixel 6/10 mean/min/max = 47.558/32.4128/62.7033
[21:21:02.993] <TB3> INFO: roc 12 with ID = 12 has maximal Vcal 62.8468 for pixel 0/13 mean/min/max = 48.3631/33.7791/62.9471
[21:21:02.994] <TB3> INFO: roc 13 with ID = 13 has maximal Vcal 60.1439 for pixel 51/66 mean/min/max = 45.6101/30.8195/60.4006
[21:21:02.994] <TB3> INFO: roc 14 with ID = 14 has maximal Vcal 62.2352 for pixel 51/65 mean/min/max = 48.0612/33.8687/62.2538
[21:21:02.995] <TB3> INFO: roc 15 with ID = 15 has maximal Vcal 58.8408 for pixel 17/78 mean/min/max = 45.1384/31.3391/58.9377
[21:21:02.995] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[21:21:03.084] <TB3> INFO: Expecting 411648 events.
[21:21:12.562] <TB3> INFO: 411648 events read in total (8882ms).
[21:21:12.569] <TB3> INFO: Expecting 411648 events.
[21:21:21.823] <TB3> INFO: 411648 events read in total (8852ms).
[21:21:21.834] <TB3> INFO: Expecting 411648 events.
[21:21:31.104] <TB3> INFO: 411648 events read in total (8867ms).
[21:21:31.118] <TB3> INFO: Expecting 411648 events.
[21:21:40.393] <TB3> INFO: 411648 events read in total (8872ms).
[21:21:40.415] <TB3> INFO: Expecting 411648 events.
[21:21:49.727] <TB3> INFO: 411648 events read in total (8909ms).
[21:21:49.745] <TB3> INFO: Expecting 411648 events.
[21:21:59.073] <TB3> INFO: 411648 events read in total (8925ms).
[21:21:59.094] <TB3> INFO: Expecting 411648 events.
[21:22:08.356] <TB3> INFO: 411648 events read in total (8859ms).
[21:22:08.380] <TB3> INFO: Expecting 411648 events.
[21:22:17.772] <TB3> INFO: 411648 events read in total (8989ms).
[21:22:17.799] <TB3> INFO: Expecting 411648 events.
[21:22:27.081] <TB3> INFO: 411648 events read in total (8879ms).
[21:22:27.112] <TB3> INFO: Expecting 411648 events.
[21:22:36.404] <TB3> INFO: 411648 events read in total (8888ms).
[21:22:36.436] <TB3> INFO: Expecting 411648 events.
[21:22:45.672] <TB3> INFO: 411648 events read in total (8833ms).
[21:22:45.711] <TB3> INFO: Expecting 411648 events.
[21:22:55.031] <TB3> INFO: 411648 events read in total (8916ms).
[21:22:55.107] <TB3> INFO: Expecting 411648 events.
[21:23:04.392] <TB3> INFO: 411648 events read in total (8882ms).
[21:23:04.448] <TB3> INFO: Expecting 411648 events.
[21:23:13.597] <TB3> INFO: 411648 events read in total (8745ms).
[21:23:13.647] <TB3> INFO: Expecting 411648 events.
[21:23:22.967] <TB3> INFO: 411648 events read in total (8917ms).
[21:23:23.030] <TB3> INFO: Expecting 411648 events.
[21:23:32.217] <TB3> INFO: 411648 events read in total (8784ms).
[21:23:32.267] <TB3> INFO: Test took 149272ms.
[21:23:32.968] <TB3> INFO: ---> dac: vcal name: TrimThr2 ntrig: 8 dacrange: 0 .. 150 (-1/-1) hits flags = 528 (plus default)
[21:23:32.980] <TB3> INFO: dacScan split into 1 runs with ntrig = 8
[21:23:32.980] <TB3> INFO: run 1 of 1
[21:23:33.217] <TB3> INFO: Expecting 5025280 events.
[21:23:59.281] <TB3> INFO: 588904 events read in total (25472ms).
[21:24:25.082] <TB3> INFO: 1176768 events read in total (51274ms).
[21:24:51.222] <TB3> INFO: 1764288 events read in total (77414ms).
[21:25:17.193] <TB3> INFO: 2351288 events read in total (103384ms).
[21:25:43.366] <TB3> INFO: 2938808 events read in total (129557ms).
[21:26:09.400] <TB3> INFO: 3525672 events read in total (155591ms).
[21:26:35.699] <TB3> INFO: 4111288 events read in total (181890ms).
[21:27:01.824] <TB3> INFO: 4696640 events read in total (208015ms).
[21:27:16.860] <TB3> INFO: 5025280 events read in total (223051ms).
[21:27:16.994] <TB3> INFO: Test took 224015ms.
[21:27:39.741] <TB3> INFO: ---> TrimStepCorr4 extremal thresholds: 5.752820 .. 147.686802
[21:27:39.981] <TB3> INFO: Expecting 208000 events.
[21:27:49.630] <TB3> INFO: 208000 events read in total (9057ms).
[21:27:49.632] <TB3> INFO: Test took 9890ms.
[21:27:49.687] <TB3> INFO: ---> dac: vcal name: trimStepCorr4 ntrig: 8 dacrange: 5 .. 157 (-1/-1) hits flags = 528 (plus default)
[21:27:49.700] <TB3> INFO: dacScan split into 1 runs with ntrig = 8
[21:27:49.700] <TB3> INFO: run 1 of 1
[21:27:49.979] <TB3> INFO: Expecting 5091840 events.
[21:28:16.393] <TB3> INFO: 578704 events read in total (25822ms).
[21:28:42.187] <TB3> INFO: 1157296 events read in total (51617ms).
[21:29:07.945] <TB3> INFO: 1735928 events read in total (77374ms).
[21:29:33.478] <TB3> INFO: 2313936 events read in total (102907ms).
[21:29:58.822] <TB3> INFO: 2892064 events read in total (128251ms).
[21:30:24.738] <TB3> INFO: 3470264 events read in total (154167ms).
[21:30:50.527] <TB3> INFO: 4048328 events read in total (179956ms).
[21:31:15.868] <TB3> INFO: 4626576 events read in total (205297ms).
[21:31:37.085] <TB3> INFO: 5091840 events read in total (226514ms).
[21:31:37.198] <TB3> INFO: Test took 227498ms.
[21:32:05.316] <TB3> INFO: ---> TrimStepCorr2 extremal thresholds: 27.522117 .. 47.123987
[21:32:05.556] <TB3> INFO: Expecting 208000 events.
[21:32:15.364] <TB3> INFO: 208000 events read in total (9216ms).
[21:32:15.365] <TB3> INFO: Test took 10047ms.
[21:32:15.435] <TB3> INFO: ---> dac: vcal name: trimStepCorr2 ntrig: 8 dacrange: 17 .. 57 (-1/-1) hits flags = 528 (plus default)
[21:32:15.449] <TB3> INFO: dacScan split into 1 runs with ntrig = 8
[21:32:15.449] <TB3> INFO: run 1 of 1
[21:32:15.729] <TB3> INFO: Expecting 1364480 events.
[21:32:44.268] <TB3> INFO: 651240 events read in total (27948ms).
[21:33:11.835] <TB3> INFO: 1300872 events read in total (55516ms).
[21:33:15.109] <TB3> INFO: 1364480 events read in total (58790ms).
[21:33:15.151] <TB3> INFO: Test took 59702ms.
[21:33:29.734] <TB3> INFO: ---> TrimStepCorr1a extremal thresholds: 26.481154 .. 45.801438
[21:33:30.008] <TB3> INFO: Expecting 208000 events.
[21:33:39.755] <TB3> INFO: 208000 events read in total (9155ms).
[21:33:39.756] <TB3> INFO: Test took 10020ms.
[21:33:39.804] <TB3> INFO: ---> dac: vcal name: trimStepCorr1a ntrig: 8 dacrange: 16 .. 55 (-1/-1) hits flags = 528 (plus default)
[21:33:39.816] <TB3> INFO: dacScan split into 1 runs with ntrig = 8
[21:33:39.816] <TB3> INFO: run 1 of 1
[21:33:40.095] <TB3> INFO: Expecting 1331200 events.
[21:34:08.261] <TB3> INFO: 663312 events read in total (27574ms).
[21:34:36.264] <TB3> INFO: 1326056 events read in total (55578ms).
[21:34:36.965] <TB3> INFO: 1331200 events read in total (56278ms).
[21:34:36.999] <TB3> INFO: Test took 57183ms.
[21:34:49.607] <TB3> INFO: ---> TrimStepCorr1b extremal thresholds: 25.182086 .. 43.589520
[21:34:49.846] <TB3> INFO: Expecting 208000 events.
[21:34:59.349] <TB3> INFO: 208000 events read in total (8912ms).
[21:34:59.350] <TB3> INFO: Test took 9742ms.
[21:34:59.398] <TB3> INFO: ---> dac: vcal name: trimStepCorr1b ntrig: 8 dacrange: 15 .. 53 (-1/-1) hits flags = 528 (plus default)
[21:34:59.410] <TB3> INFO: dacScan split into 1 runs with ntrig = 8
[21:34:59.410] <TB3> INFO: run 1 of 1
[21:34:59.688] <TB3> INFO: Expecting 1297920 events.
[21:35:28.223] <TB3> INFO: 676328 events read in total (27943ms).
[21:35:54.492] <TB3> INFO: 1297920 events read in total (54212ms).
[21:35:54.530] <TB3> INFO: Test took 55120ms.
[21:36:07.091] <TB3> INFO: ---> TrimThrFinal extremal thresholds: 15 .. 55
[21:36:07.091] <TB3> INFO: ---> dac: vcal name: TrimThrFinal ntrig: 8 dacrange: 15 .. 55 (-1/-1) hits flags = 528 (plus default)
[21:36:07.103] <TB3> INFO: dacScan split into 1 runs with ntrig = 8
[21:36:07.103] <TB3> INFO: run 1 of 1
[21:36:07.343] <TB3> INFO: Expecting 1364480 events.
[21:36:35.528] <TB3> INFO: 667672 events read in total (27593ms).
[21:37:04.143] <TB3> INFO: 1333944 events read in total (56208ms).
[21:37:06.091] <TB3> INFO: 1364480 events read in total (58157ms).
[21:37:06.125] <TB3> INFO: Test took 59021ms.
[21:37:22.907] <TB3> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1027_FullQualification_2016-10-19_19h52m_1476899523//001_Fulltest_m20//dacParameters35_C0.dat
[21:37:22.907] <TB3> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1027_FullQualification_2016-10-19_19h52m_1476899523//001_Fulltest_m20//dacParameters35_C1.dat
[21:37:22.907] <TB3> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1027_FullQualification_2016-10-19_19h52m_1476899523//001_Fulltest_m20//dacParameters35_C2.dat
[21:37:22.908] <TB3> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1027_FullQualification_2016-10-19_19h52m_1476899523//001_Fulltest_m20//dacParameters35_C3.dat
[21:37:22.908] <TB3> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1027_FullQualification_2016-10-19_19h52m_1476899523//001_Fulltest_m20//dacParameters35_C4.dat
[21:37:22.908] <TB3> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1027_FullQualification_2016-10-19_19h52m_1476899523//001_Fulltest_m20//dacParameters35_C5.dat
[21:37:22.908] <TB3> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1027_FullQualification_2016-10-19_19h52m_1476899523//001_Fulltest_m20//dacParameters35_C6.dat
[21:37:22.908] <TB3> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1027_FullQualification_2016-10-19_19h52m_1476899523//001_Fulltest_m20//dacParameters35_C7.dat
[21:37:22.908] <TB3> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1027_FullQualification_2016-10-19_19h52m_1476899523//001_Fulltest_m20//dacParameters35_C8.dat
[21:37:22.909] <TB3> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1027_FullQualification_2016-10-19_19h52m_1476899523//001_Fulltest_m20//dacParameters35_C9.dat
[21:37:22.909] <TB3> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1027_FullQualification_2016-10-19_19h52m_1476899523//001_Fulltest_m20//dacParameters35_C10.dat
[21:37:22.909] <TB3> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1027_FullQualification_2016-10-19_19h52m_1476899523//001_Fulltest_m20//dacParameters35_C11.dat
[21:37:22.909] <TB3> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1027_FullQualification_2016-10-19_19h52m_1476899523//001_Fulltest_m20//dacParameters35_C12.dat
[21:37:22.909] <TB3> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1027_FullQualification_2016-10-19_19h52m_1476899523//001_Fulltest_m20//dacParameters35_C13.dat
[21:37:22.909] <TB3> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1027_FullQualification_2016-10-19_19h52m_1476899523//001_Fulltest_m20//dacParameters35_C14.dat
[21:37:22.910] <TB3> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1027_FullQualification_2016-10-19_19h52m_1476899523//001_Fulltest_m20//dacParameters35_C15.dat
[21:37:22.910] <TB3> INFO: write trim parameters into /home/l_tester/david/FullQualification/data/M1027_FullQualification_2016-10-19_19h52m_1476899523//001_Fulltest_m20//trimParameters35_C0.dat
[21:37:22.917] <TB3> INFO: write trim parameters into /home/l_tester/david/FullQualification/data/M1027_FullQualification_2016-10-19_19h52m_1476899523//001_Fulltest_m20//trimParameters35_C1.dat
[21:37:22.922] <TB3> INFO: write trim parameters into /home/l_tester/david/FullQualification/data/M1027_FullQualification_2016-10-19_19h52m_1476899523//001_Fulltest_m20//trimParameters35_C2.dat
[21:37:22.927] <TB3> INFO: write trim parameters into /home/l_tester/david/FullQualification/data/M1027_FullQualification_2016-10-19_19h52m_1476899523//001_Fulltest_m20//trimParameters35_C3.dat
[21:37:22.932] <TB3> INFO: write trim parameters into /home/l_tester/david/FullQualification/data/M1027_FullQualification_2016-10-19_19h52m_1476899523//001_Fulltest_m20//trimParameters35_C4.dat
[21:37:22.936] <TB3> INFO: write trim parameters into /home/l_tester/david/FullQualification/data/M1027_FullQualification_2016-10-19_19h52m_1476899523//001_Fulltest_m20//trimParameters35_C5.dat
[21:37:22.941] <TB3> INFO: write trim parameters into /home/l_tester/david/FullQualification/data/M1027_FullQualification_2016-10-19_19h52m_1476899523//001_Fulltest_m20//trimParameters35_C6.dat
[21:37:22.946] <TB3> INFO: write trim parameters into /home/l_tester/david/FullQualification/data/M1027_FullQualification_2016-10-19_19h52m_1476899523//001_Fulltest_m20//trimParameters35_C7.dat
[21:37:22.951] <TB3> INFO: write trim parameters into /home/l_tester/david/FullQualification/data/M1027_FullQualification_2016-10-19_19h52m_1476899523//001_Fulltest_m20//trimParameters35_C8.dat
[21:37:22.955] <TB3> INFO: write trim parameters into /home/l_tester/david/FullQualification/data/M1027_FullQualification_2016-10-19_19h52m_1476899523//001_Fulltest_m20//trimParameters35_C9.dat
[21:37:22.960] <TB3> INFO: write trim parameters into /home/l_tester/david/FullQualification/data/M1027_FullQualification_2016-10-19_19h52m_1476899523//001_Fulltest_m20//trimParameters35_C10.dat
[21:37:22.965] <TB3> INFO: write trim parameters into /home/l_tester/david/FullQualification/data/M1027_FullQualification_2016-10-19_19h52m_1476899523//001_Fulltest_m20//trimParameters35_C11.dat
[21:37:22.970] <TB3> INFO: write trim parameters into /home/l_tester/david/FullQualification/data/M1027_FullQualification_2016-10-19_19h52m_1476899523//001_Fulltest_m20//trimParameters35_C12.dat
[21:37:22.974] <TB3> INFO: write trim parameters into /home/l_tester/david/FullQualification/data/M1027_FullQualification_2016-10-19_19h52m_1476899523//001_Fulltest_m20//trimParameters35_C13.dat
[21:37:22.979] <TB3> INFO: write trim parameters into /home/l_tester/david/FullQualification/data/M1027_FullQualification_2016-10-19_19h52m_1476899523//001_Fulltest_m20//trimParameters35_C14.dat
[21:37:22.984] <TB3> INFO: write trim parameters into /home/l_tester/david/FullQualification/data/M1027_FullQualification_2016-10-19_19h52m_1476899523//001_Fulltest_m20//trimParameters35_C15.dat
[21:37:22.988] <TB3> INFO: PixTestTrim::trimTest() done
[21:37:22.988] <TB3> INFO: vtrim: 124 132 144 144 126 117 123 122 132 122 141 127 125 108 123 133
[21:37:22.988] <TB3> INFO: vthrcomp: 109 109 112 106 120 108 103 113 113 117 108 112 109 119 109 121
[21:37:22.988] <TB3> INFO: vcal mean: 35.04 34.99 34.95 35.06 34.96 34.99 34.91 34.97 34.98 34.86 35.00 35.01 35.03 34.90 34.98 34.93
[21:37:22.988] <TB3> INFO: vcal RMS: 1.12 1.06 1.09 1.10 1.17 0.95 1.01 0.99 1.20 1.07 1.14 1.09 0.98 1.12 0.99 1.00
[21:37:22.988] <TB3> INFO: bits mean: 8.94 8.78 9.56 9.00 9.84 8.39 9.01 9.26 9.81 9.49 9.17 9.64 8.82 9.28 8.64 9.55
[21:37:22.988] <TB3> INFO: bits RMS: 2.46 2.60 2.63 2.52 2.54 2.65 2.62 2.79 2.57 2.85 2.40 2.49 2.61 2.98 2.67 2.83
[21:37:22.995] <TB3> INFO: ----------------------------------------------------------------------
[21:37:22.996] <TB3> INFO: PixTestTrim::trimBitTest() ntrig = 5, vtrims = 254 126 63 32
[21:37:22.996] <TB3> INFO: ----------------------------------------------------------------------
[21:37:22.998] <TB3> INFO: ---> dac: Vcal name: TrimBitsThr0 ntrig: 5 dacrange: 0 .. 199 (-1/-1) hits flags = 528 (plus default)
[21:37:23.012] <TB3> INFO: dacScan split into 1 runs with ntrig = 5
[21:37:23.012] <TB3> INFO: run 1 of 1
[21:37:23.250] <TB3> INFO: Expecting 4160000 events.
[21:37:55.549] <TB3> INFO: 739240 events read in total (31707ms).
[21:38:26.003] <TB3> INFO: 1472310 events read in total (63161ms).
[21:38:58.430] <TB3> INFO: 2202245 events read in total (94588ms).
[21:39:29.509] <TB3> INFO: 2925705 events read in total (125667ms).
[21:40:00.703] <TB3> INFO: 3647480 events read in total (156861ms).
[21:40:23.028] <TB3> INFO: 4160000 events read in total (179186ms).
[21:40:23.192] <TB3> INFO: Test took 180180ms.
[21:40:49.549] <TB3> INFO: ---> dac: Vcal name: TrimThr_trim14 ntrig: 5 dacrange: 0 .. 194 (-1/-1) hits flags = 528 (plus default)
[21:40:49.561] <TB3> INFO: dacScan split into 1 runs with ntrig = 5
[21:40:49.562] <TB3> INFO: run 1 of 1
[21:40:49.800] <TB3> INFO: Expecting 4056000 events.
[21:41:21.526] <TB3> INFO: 721570 events read in total (31135ms).
[21:41:52.563] <TB3> INFO: 1437190 events read in total (62172ms).
[21:42:23.538] <TB3> INFO: 2149935 events read in total (93147ms).
[21:42:54.550] <TB3> INFO: 2856725 events read in total (124159ms).
[21:43:25.770] <TB3> INFO: 3562240 events read in total (155379ms).
[21:43:47.361] <TB3> INFO: 4056000 events read in total (176970ms).
[21:43:47.436] <TB3> INFO: Test took 177874ms.
[21:44:16.795] <TB3> INFO: ---> dac: Vcal name: TrimThr_trim13 ntrig: 5 dacrange: 0 .. 182 (-1/-1) hits flags = 528 (plus default)
[21:44:16.809] <TB3> INFO: dacScan split into 1 runs with ntrig = 5
[21:44:16.809] <TB3> INFO: run 1 of 1
[21:44:17.093] <TB3> INFO: Expecting 3806400 events.
[21:44:49.745] <TB3> INFO: 739075 events read in total (32060ms).
[21:45:20.978] <TB3> INFO: 1471595 events read in total (63293ms).
[21:45:52.060] <TB3> INFO: 2198650 events read in total (94375ms).
[21:46:22.002] <TB3> INFO: 2921445 events read in total (125317ms).
[21:46:54.146] <TB3> INFO: 3643265 events read in total (156461ms).
[21:47:01.386] <TB3> INFO: 3806400 events read in total (163701ms).
[21:47:01.459] <TB3> INFO: Test took 164650ms.
[21:47:30.981] <TB3> INFO: ---> dac: Vcal name: TrimThr_trim11 ntrig: 5 dacrange: 0 .. 182 (-1/-1) hits flags = 528 (plus default)
[21:47:30.994] <TB3> INFO: dacScan split into 1 runs with ntrig = 5
[21:47:30.994] <TB3> INFO: run 1 of 1
[21:47:31.259] <TB3> INFO: Expecting 3806400 events.
[21:48:03.218] <TB3> INFO: 739290 events read in total (31367ms).
[21:48:34.662] <TB3> INFO: 1471960 events read in total (62811ms).
[21:49:06.220] <TB3> INFO: 2199310 events read in total (94369ms).
[21:49:37.352] <TB3> INFO: 2922495 events read in total (125501ms).
[21:50:08.732] <TB3> INFO: 3644445 events read in total (156881ms).
[21:50:16.008] <TB3> INFO: 3806400 events read in total (164157ms).
[21:50:16.094] <TB3> INFO: Test took 165099ms.
[21:50:42.827] <TB3> INFO: ---> dac: Vcal name: TrimThr_trim7 ntrig: 5 dacrange: 0 .. 181 (-1/-1) hits flags = 528 (plus default)
[21:50:42.841] <TB3> INFO: dacScan split into 1 runs with ntrig = 5
[21:50:42.841] <TB3> INFO: run 1 of 1
[21:50:43.171] <TB3> INFO: Expecting 3785600 events.
[21:51:15.642] <TB3> INFO: 741110 events read in total (31880ms).
[21:51:47.703] <TB3> INFO: 1475605 events read in total (63940ms).
[21:52:19.034] <TB3> INFO: 2204490 events read in total (95272ms).
[21:52:50.606] <TB3> INFO: 2929090 events read in total (126843ms).
[21:53:21.999] <TB3> INFO: 3652790 events read in total (158236ms).
[21:53:27.979] <TB3> INFO: 3785600 events read in total (164216ms).
[21:53:28.042] <TB3> INFO: Test took 165201ms.
[21:53:52.502] <TB3> INFO: PixTestTrim::trimBitTest() done
[21:53:52.503] <TB3> INFO: PixTestTrim::doTest() done, duration: 2433 seconds
[21:53:52.503] <TB3> INFO: Decoding statistics:
[21:53:52.503] <TB3> INFO: General information:
[21:53:52.503] <TB3> INFO: 16bit words read: 0
[21:53:52.503] <TB3> INFO: valid events total: 0
[21:53:52.503] <TB3> INFO: empty events: 0
[21:53:52.503] <TB3> INFO: valid events with pixels: 0
[21:53:52.503] <TB3> INFO: valid pixel hits: 0
[21:53:52.503] <TB3> INFO: Event errors: 0
[21:53:52.503] <TB3> INFO: start marker: 0
[21:53:52.503] <TB3> INFO: stop marker: 0
[21:53:52.503] <TB3> INFO: overflow: 0
[21:53:52.503] <TB3> INFO: invalid 5bit words: 0
[21:53:52.503] <TB3> INFO: invalid XOR eye diagram: 0
[21:53:52.503] <TB3> INFO: frame (failed synchr.): 0
[21:53:52.503] <TB3> INFO: idle data (no TBM trl): 0
[21:53:52.503] <TB3> INFO: no data (only TBM hdr): 0
[21:53:52.503] <TB3> INFO: TBM errors: 0
[21:53:52.503] <TB3> INFO: flawed TBM headers: 0
[21:53:52.503] <TB3> INFO: flawed TBM trailers: 0
[21:53:52.503] <TB3> INFO: event ID mismatches: 0
[21:53:52.503] <TB3> INFO: ROC errors: 0
[21:53:52.503] <TB3> INFO: missing ROC header(s): 0
[21:53:52.503] <TB3> INFO: misplaced readback start: 0
[21:53:52.503] <TB3> INFO: Pixel decoding errors: 0
[21:53:52.503] <TB3> INFO: pixel data incomplete: 0
[21:53:52.503] <TB3> INFO: pixel address: 0
[21:53:52.503] <TB3> INFO: pulse height fill bit: 0
[21:53:52.503] <TB3> INFO: buffer corruption: 0
[21:53:53.258] <TB3> INFO: ######################################################################
[21:53:53.258] <TB3> INFO: PixTestPhOptimization::doTest() Ntrig = 10
[21:53:53.258] <TB3> INFO: ######################################################################
[21:53:53.537] <TB3> INFO: Expecting 41600 events.
[21:53:57.261] <TB3> INFO: 41600 events read in total (3132ms).
[21:53:57.262] <TB3> INFO: Test took 4002ms.
[21:53:57.706] <TB3> INFO: Expecting 41600 events.
[21:54:01.365] <TB3> INFO: 41600 events read in total (3067ms).
[21:54:01.366] <TB3> INFO: Test took 3901ms.
[21:54:01.656] <TB3> INFO: Expecting 41600 events.
[21:54:05.194] <TB3> INFO: 41600 events read in total (2946ms).
[21:54:05.195] <TB3> INFO: Test took 3804ms.
[21:54:05.484] <TB3> INFO: Expecting 41600 events.
[21:54:09.075] <TB3> INFO: 41600 events read in total (2999ms).
[21:54:09.075] <TB3> INFO: Test took 3856ms.
[21:54:09.365] <TB3> INFO: Expecting 41600 events.
[21:54:12.907] <TB3> INFO: 41600 events read in total (2950ms).
[21:54:12.908] <TB3> INFO: Test took 3809ms.
[21:54:13.197] <TB3> INFO: Expecting 41600 events.
[21:54:16.698] <TB3> INFO: 41600 events read in total (2909ms).
[21:54:16.699] <TB3> INFO: Test took 3768ms.
[21:54:16.987] <TB3> INFO: Expecting 41600 events.
[21:54:20.574] <TB3> INFO: 41600 events read in total (2995ms).
[21:54:20.575] <TB3> INFO: Test took 3852ms.
[21:54:20.865] <TB3> INFO: Expecting 41600 events.
[21:54:24.396] <TB3> INFO: 41600 events read in total (2939ms).
[21:54:24.396] <TB3> INFO: Test took 3796ms.
[21:54:24.686] <TB3> INFO: Expecting 41600 events.
[21:54:28.191] <TB3> INFO: 41600 events read in total (2914ms).
[21:54:28.192] <TB3> INFO: Test took 3772ms.
[21:54:28.494] <TB3> INFO: Expecting 41600 events.
[21:54:32.129] <TB3> INFO: 41600 events read in total (3043ms).
[21:54:32.130] <TB3> INFO: Test took 3914ms.
[21:54:32.419] <TB3> INFO: Expecting 41600 events.
[21:54:35.971] <TB3> INFO: 41600 events read in total (2960ms).
[21:54:35.972] <TB3> INFO: Test took 3818ms.
[21:54:36.262] <TB3> INFO: Expecting 41600 events.
[21:54:39.821] <TB3> INFO: 41600 events read in total (2968ms).
[21:54:39.822] <TB3> INFO: Test took 3826ms.
[21:54:40.113] <TB3> INFO: Expecting 41600 events.
[21:54:43.662] <TB3> INFO: 41600 events read in total (2957ms).
[21:54:43.663] <TB3> INFO: Test took 3816ms.
[21:54:43.953] <TB3> INFO: Expecting 41600 events.
[21:54:47.453] <TB3> INFO: 41600 events read in total (2909ms).
[21:54:47.454] <TB3> INFO: Test took 3767ms.
[21:54:47.746] <TB3> INFO: Expecting 41600 events.
[21:54:51.301] <TB3> INFO: 41600 events read in total (2963ms).
[21:54:51.302] <TB3> INFO: Test took 3820ms.
[21:54:51.591] <TB3> INFO: Expecting 41600 events.
[21:54:55.077] <TB3> INFO: 41600 events read in total (2895ms).
[21:54:55.078] <TB3> INFO: Test took 3752ms.
[21:54:55.367] <TB3> INFO: Expecting 41600 events.
[21:54:58.875] <TB3> INFO: 41600 events read in total (2916ms).
[21:54:58.876] <TB3> INFO: Test took 3774ms.
[21:54:59.165] <TB3> INFO: Expecting 41600 events.
[21:55:02.804] <TB3> INFO: 41600 events read in total (3047ms).
[21:55:02.804] <TB3> INFO: Test took 3904ms.
[21:55:03.108] <TB3> INFO: Expecting 41600 events.
[21:55:06.687] <TB3> INFO: 41600 events read in total (2987ms).
[21:55:06.687] <TB3> INFO: Test took 3858ms.
[21:55:06.999] <TB3> INFO: Expecting 41600 events.
[21:55:10.601] <TB3> INFO: 41600 events read in total (3011ms).
[21:55:10.602] <TB3> INFO: Test took 3891ms.
[21:55:10.892] <TB3> INFO: Expecting 41600 events.
[21:55:14.536] <TB3> INFO: 41600 events read in total (3053ms).
[21:55:14.537] <TB3> INFO: Test took 3911ms.
[21:55:14.831] <TB3> INFO: Expecting 41600 events.
[21:55:18.366] <TB3> INFO: 41600 events read in total (2943ms).
[21:55:18.367] <TB3> INFO: Test took 3802ms.
[21:55:18.715] <TB3> INFO: Expecting 41600 events.
[21:55:22.227] <TB3> INFO: 41600 events read in total (2920ms).
[21:55:22.228] <TB3> INFO: Test took 3837ms.
[21:55:22.518] <TB3> INFO: Expecting 41600 events.
[21:55:26.081] <TB3> INFO: 41600 events read in total (2971ms).
[21:55:26.082] <TB3> INFO: Test took 3829ms.
[21:55:26.371] <TB3> INFO: Expecting 41600 events.
[21:55:29.946] <TB3> INFO: 41600 events read in total (2983ms).
[21:55:29.947] <TB3> INFO: Test took 3840ms.
[21:55:30.259] <TB3> INFO: Expecting 41600 events.
[21:55:33.801] <TB3> INFO: 41600 events read in total (2950ms).
[21:55:33.802] <TB3> INFO: Test took 3830ms.
[21:55:34.094] <TB3> INFO: Expecting 41600 events.
[21:55:37.567] <TB3> INFO: 41600 events read in total (2882ms).
[21:55:37.568] <TB3> INFO: Test took 3739ms.
[21:55:37.857] <TB3> INFO: Expecting 41600 events.
[21:55:41.335] <TB3> INFO: 41600 events read in total (2886ms).
[21:55:41.336] <TB3> INFO: Test took 3743ms.
[21:55:41.626] <TB3> INFO: Expecting 41600 events.
[21:55:45.096] <TB3> INFO: 41600 events read in total (2878ms).
[21:55:45.097] <TB3> INFO: Test took 3736ms.
[21:55:45.386] <TB3> INFO: Expecting 41600 events.
[21:55:48.860] <TB3> INFO: 41600 events read in total (2882ms).
[21:55:48.861] <TB3> INFO: Test took 3739ms.
[21:55:49.150] <TB3> INFO: Expecting 41600 events.
[21:55:52.651] <TB3> INFO: 41600 events read in total (2910ms).
[21:55:52.652] <TB3> INFO: Test took 3767ms.
[21:55:52.944] <TB3> INFO: Expecting 41600 events.
[21:55:56.414] <TB3> INFO: 41600 events read in total (2879ms).
[21:55:56.414] <TB3> INFO: Test took 3735ms.
[21:55:56.703] <TB3> INFO: Expecting 41600 events.
[21:56:00.254] <TB3> INFO: 41600 events read in total (2959ms).
[21:56:00.254] <TB3> INFO: Test took 3815ms.
[21:56:00.543] <TB3> INFO: Expecting 41600 events.
[21:56:04.136] <TB3> INFO: 41600 events read in total (3001ms).
[21:56:04.137] <TB3> INFO: Test took 3858ms.
[21:56:04.429] <TB3> INFO: Expecting 41600 events.
[21:56:08.012] <TB3> INFO: 41600 events read in total (2991ms).
[21:56:08.013] <TB3> INFO: Test took 3850ms.
[21:56:08.305] <TB3> INFO: Expecting 41600 events.
[21:56:11.866] <TB3> INFO: 41600 events read in total (2969ms).
[21:56:11.866] <TB3> INFO: Test took 3826ms.
[21:56:12.155] <TB3> INFO: Expecting 41600 events.
[21:56:15.800] <TB3> INFO: 41600 events read in total (3053ms).
[21:56:15.801] <TB3> INFO: Test took 3910ms.
[21:56:16.093] <TB3> INFO: Expecting 41600 events.
[21:56:19.661] <TB3> INFO: 41600 events read in total (2976ms).
[21:56:19.662] <TB3> INFO: Test took 3835ms.
[21:56:19.951] <TB3> INFO: Expecting 41600 events.
[21:56:23.458] <TB3> INFO: 41600 events read in total (2915ms).
[21:56:23.459] <TB3> INFO: Test took 3773ms.
[21:56:23.750] <TB3> INFO: Expecting 41600 events.
[21:56:27.281] <TB3> INFO: 41600 events read in total (2940ms).
[21:56:27.282] <TB3> INFO: Test took 3798ms.
[21:56:27.573] <TB3> INFO: Expecting 41600 events.
[21:56:31.097] <TB3> INFO: 41600 events read in total (2932ms).
[21:56:31.097] <TB3> INFO: Test took 3789ms.
[21:56:31.388] <TB3> INFO: Expecting 41600 events.
[21:56:35.025] <TB3> INFO: 41600 events read in total (3045ms).
[21:56:35.025] <TB3> INFO: Test took 3903ms.
[21:56:35.314] <TB3> INFO: Expecting 41600 events.
[21:56:38.872] <TB3> INFO: 41600 events read in total (2966ms).
[21:56:38.873] <TB3> INFO: Test took 3823ms.
[21:56:39.163] <TB3> INFO: Expecting 41600 events.
[21:56:42.644] <TB3> INFO: 41600 events read in total (2890ms).
[21:56:42.645] <TB3> INFO: Test took 3748ms.
[21:56:42.934] <TB3> INFO: Expecting 41600 events.
[21:56:46.626] <TB3> INFO: 41600 events read in total (3100ms).
[21:56:46.628] <TB3> INFO: Test took 3959ms.
[21:56:46.918] <TB3> INFO: Expecting 41600 events.
[21:56:50.502] <TB3> INFO: 41600 events read in total (2992ms).
[21:56:50.502] <TB3> INFO: Test took 3849ms.
[21:56:50.794] <TB3> INFO: Expecting 41600 events.
[21:56:54.391] <TB3> INFO: 41600 events read in total (3005ms).
[21:56:54.392] <TB3> INFO: Test took 3863ms.
[21:56:54.681] <TB3> INFO: Expecting 41600 events.
[21:56:58.178] <TB3> INFO: 41600 events read in total (2905ms).
[21:56:58.178] <TB3> INFO: Test took 3762ms.
[21:56:58.469] <TB3> INFO: Expecting 41600 events.
[21:57:02.090] <TB3> INFO: 41600 events read in total (3030ms).
[21:57:02.091] <TB3> INFO: Test took 3887ms.
[21:57:02.383] <TB3> INFO: Expecting 41600 events.
[21:57:05.919] <TB3> INFO: 41600 events read in total (2944ms).
[21:57:05.919] <TB3> INFO: Test took 3801ms.
[21:57:06.211] <TB3> INFO: Expecting 41600 events.
[21:57:09.751] <TB3> INFO: 41600 events read in total (2948ms).
[21:57:09.751] <TB3> INFO: Test took 3805ms.
[21:57:10.046] <TB3> INFO: Expecting 41600 events.
[21:57:13.625] <TB3> INFO: 41600 events read in total (2987ms).
[21:57:13.626] <TB3> INFO: Test took 3849ms.
[21:57:13.919] <TB3> INFO: Expecting 41600 events.
[21:57:17.485] <TB3> INFO: 41600 events read in total (2975ms).
[21:57:17.485] <TB3> INFO: Test took 3832ms.
[21:57:17.778] <TB3> INFO: Expecting 41600 events.
[21:57:21.296] <TB3> INFO: 41600 events read in total (2926ms).
[21:57:21.297] <TB3> INFO: Test took 3784ms.
[21:57:21.587] <TB3> INFO: Expecting 2560 events.
[21:57:22.475] <TB3> INFO: 2560 events read in total (297ms).
[21:57:22.475] <TB3> INFO: Test took 1165ms.
[21:57:22.783] <TB3> INFO: Expecting 2560 events.
[21:57:23.670] <TB3> INFO: 2560 events read in total (295ms).
[21:57:23.671] <TB3> INFO: Test took 1195ms.
[21:57:23.978] <TB3> INFO: Expecting 2560 events.
[21:57:24.861] <TB3> INFO: 2560 events read in total (291ms).
[21:57:24.861] <TB3> INFO: Test took 1190ms.
[21:57:25.169] <TB3> INFO: Expecting 2560 events.
[21:57:26.056] <TB3> INFO: 2560 events read in total (295ms).
[21:57:26.056] <TB3> INFO: Test took 1194ms.
[21:57:26.365] <TB3> INFO: Expecting 2560 events.
[21:57:27.248] <TB3> INFO: 2560 events read in total (291ms).
[21:57:27.248] <TB3> INFO: Test took 1191ms.
[21:57:27.556] <TB3> INFO: Expecting 2560 events.
[21:57:28.442] <TB3> INFO: 2560 events read in total (294ms).
[21:57:28.442] <TB3> INFO: Test took 1193ms.
[21:57:28.750] <TB3> INFO: Expecting 2560 events.
[21:57:29.631] <TB3> INFO: 2560 events read in total (289ms).
[21:57:29.632] <TB3> INFO: Test took 1190ms.
[21:57:29.940] <TB3> INFO: Expecting 2560 events.
[21:57:30.830] <TB3> INFO: 2560 events read in total (299ms).
[21:57:30.830] <TB3> INFO: Test took 1198ms.
[21:57:31.138] <TB3> INFO: Expecting 2560 events.
[21:57:32.029] <TB3> INFO: 2560 events read in total (299ms).
[21:57:32.030] <TB3> INFO: Test took 1200ms.
[21:57:32.337] <TB3> INFO: Expecting 2560 events.
[21:57:33.227] <TB3> INFO: 2560 events read in total (299ms).
[21:57:33.227] <TB3> INFO: Test took 1197ms.
[21:57:33.536] <TB3> INFO: Expecting 2560 events.
[21:57:34.425] <TB3> INFO: 2560 events read in total (297ms).
[21:57:34.425] <TB3> INFO: Test took 1197ms.
[21:57:34.733] <TB3> INFO: Expecting 2560 events.
[21:57:35.622] <TB3> INFO: 2560 events read in total (297ms).
[21:57:35.622] <TB3> INFO: Test took 1196ms.
[21:57:35.929] <TB3> INFO: Expecting 2560 events.
[21:57:36.814] <TB3> INFO: 2560 events read in total (293ms).
[21:57:36.814] <TB3> INFO: Test took 1191ms.
[21:57:37.123] <TB3> INFO: Expecting 2560 events.
[21:57:38.014] <TB3> INFO: 2560 events read in total (300ms).
[21:57:38.014] <TB3> INFO: Test took 1199ms.
[21:57:38.321] <TB3> INFO: Expecting 2560 events.
[21:57:39.210] <TB3> INFO: 2560 events read in total (297ms).
[21:57:39.210] <TB3> INFO: Test took 1194ms.
[21:57:39.517] <TB3> INFO: Expecting 2560 events.
[21:57:40.407] <TB3> INFO: 2560 events read in total (298ms).
[21:57:40.407] <TB3> INFO: Test took 1196ms.
[21:57:40.412] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[21:57:40.716] <TB3> INFO: Expecting 655360 events.
[21:57:55.608] <TB3> INFO: 655360 events read in total (14300ms).
[21:57:55.620] <TB3> INFO: Expecting 655360 events.
[21:58:10.388] <TB3> INFO: 655360 events read in total (14365ms).
[21:58:10.404] <TB3> INFO: Expecting 655360 events.
[21:58:25.271] <TB3> INFO: 655360 events read in total (14464ms).
[21:58:25.295] <TB3> INFO: Expecting 655360 events.
[21:58:39.902] <TB3> INFO: 655360 events read in total (14204ms).
[21:58:39.927] <TB3> INFO: Expecting 655360 events.
[21:58:54.420] <TB3> INFO: 655360 events read in total (14090ms).
[21:58:54.449] <TB3> INFO: Expecting 655360 events.
[21:59:09.178] <TB3> INFO: 655360 events read in total (14326ms).
[21:59:09.217] <TB3> INFO: Expecting 655360 events.
[21:59:23.724] <TB3> INFO: 655360 events read in total (14104ms).
[21:59:23.762] <TB3> INFO: Expecting 655360 events.
[21:59:38.273] <TB3> INFO: 655360 events read in total (14108ms).
[21:59:38.330] <TB3> INFO: Expecting 655360 events.
[21:59:52.833] <TB3> INFO: 655360 events read in total (14100ms).
[21:59:52.880] <TB3> INFO: Expecting 655360 events.
[22:00:07.344] <TB3> INFO: 655360 events read in total (14061ms).
[22:00:07.414] <TB3> INFO: Expecting 655360 events.
[22:00:22.133] <TB3> INFO: 655360 events read in total (14316ms).
[22:00:22.334] <TB3> INFO: Expecting 655360 events.
[22:00:36.428] <TB3> INFO: 655360 events read in total (13691ms).
[22:00:36.490] <TB3> INFO: Expecting 655360 events.
[22:00:51.022] <TB3> INFO: 655360 events read in total (14129ms).
[22:00:51.231] <TB3> INFO: Expecting 655360 events.
[22:01:05.858] <TB3> INFO: 655360 events read in total (14224ms).
[22:01:05.948] <TB3> INFO: Expecting 655360 events.
[22:01:20.479] <TB3> INFO: 655360 events read in total (14128ms).
[22:01:20.695] <TB3> INFO: Expecting 655360 events.
[22:01:35.219] <TB3> INFO: 655360 events read in total (14121ms).
[22:01:35.373] <TB3> INFO: Test took 234962ms.
[22:01:35.468] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[22:01:35.725] <TB3> INFO: Expecting 655360 events.
[22:01:50.392] <TB3> INFO: 655360 events read in total (14076ms).
[22:01:50.404] <TB3> INFO: Expecting 655360 events.
[22:02:04.785] <TB3> INFO: 655360 events read in total (13978ms).
[22:02:04.801] <TB3> INFO: Expecting 655360 events.
[22:02:18.854] <TB3> INFO: 655360 events read in total (13650ms).
[22:02:18.875] <TB3> INFO: Expecting 655360 events.
[22:02:33.299] <TB3> INFO: 655360 events read in total (14021ms).
[22:02:33.325] <TB3> INFO: Expecting 655360 events.
[22:02:47.804] <TB3> INFO: 655360 events read in total (14076ms).
[22:02:47.835] <TB3> INFO: Expecting 655360 events.
[22:03:02.134] <TB3> INFO: 655360 events read in total (13896ms).
[22:03:02.173] <TB3> INFO: Expecting 655360 events.
[22:03:16.428] <TB3> INFO: 655360 events read in total (13852ms).
[22:03:16.467] <TB3> INFO: Expecting 655360 events.
[22:03:31.016] <TB3> INFO: 655360 events read in total (14145ms).
[22:03:31.060] <TB3> INFO: Expecting 655360 events.
[22:03:45.384] <TB3> INFO: 655360 events read in total (13921ms).
[22:03:45.431] <TB3> INFO: Expecting 655360 events.
[22:03:59.866] <TB3> INFO: 655360 events read in total (14032ms).
[22:03:59.920] <TB3> INFO: Expecting 655360 events.
[22:04:14.556] <TB3> INFO: 655360 events read in total (14232ms).
[22:04:14.626] <TB3> INFO: Expecting 655360 events.
[22:04:29.375] <TB3> INFO: 655360 events read in total (14346ms).
[22:04:29.457] <TB3> INFO: Expecting 655360 events.
[22:04:43.965] <TB3> INFO: 655360 events read in total (14105ms).
[22:04:44.038] <TB3> INFO: Expecting 655360 events.
[22:04:58.584] <TB3> INFO: 655360 events read in total (14142ms).
[22:04:58.716] <TB3> INFO: Expecting 655360 events.
[22:05:13.327] <TB3> INFO: 655360 events read in total (14208ms).
[22:05:13.446] <TB3> INFO: Expecting 655360 events.
[22:05:55.321] <TB3> INFO: 655360 events read in total (41472ms).
[22:05:55.486] <TB3> INFO: Test took 260018ms.
[22:05:56.253] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[22:05:56.278] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[22:05:56.311] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[22:05:56.337] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[22:05:56.368] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[22:05:56.393] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[22:05:56.423] <TB3> INFO: safety margin for low PH: adding 1, margin is now 21
[22:05:56.449] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[22:05:56.477] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[22:05:56.510] <TB3> INFO: safety margin for low PH: adding 1, margin is now 21
[22:05:56.541] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[22:05:56.571] <TB3> INFO: safety margin for low PH: adding 1, margin is now 21
[22:05:56.598] <TB3> INFO: safety margin for low PH: adding 2, margin is now 22
[22:05:56.669] <TB3> INFO: safety margin for low PH: adding 3, margin is now 23
[22:05:56.716] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[22:05:56.746] <TB3> INFO: safety margin for low PH: adding 1, margin is now 21
[22:05:56.793] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[22:05:56.839] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[22:05:56.867] <TB3> INFO: safety margin for low PH: adding 1, margin is now 21
[22:05:56.903] <TB3> INFO: safety margin for low PH: adding 2, margin is now 22
[22:05:56.935] <TB3> INFO: safety margin for low PH: adding 3, margin is now 23
[22:05:56.975] <TB3> INFO: safety margin for low PH: adding 4, margin is now 24
[22:05:56.004] <TB3> INFO: safety margin for low PH: adding 5, margin is now 25
[22:05:57.035] <TB3> INFO: safety margin for low PH: adding 6, margin is now 26
[22:05:57.065] <TB3> INFO: safety margin for low PH: adding 7, margin is now 27
[22:05:57.092] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[22:05:57.128] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[22:05:57.184] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[22:05:57.229] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[22:05:58.448] <TB3> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1027_FullQualification_2016-10-19_19h52m_1476899523//001_Fulltest_m20//dacParameters35_C0.dat
[22:05:58.448] <TB3> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1027_FullQualification_2016-10-19_19h52m_1476899523//001_Fulltest_m20//dacParameters35_C1.dat
[22:05:58.448] <TB3> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1027_FullQualification_2016-10-19_19h52m_1476899523//001_Fulltest_m20//dacParameters35_C2.dat
[22:05:58.448] <TB3> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1027_FullQualification_2016-10-19_19h52m_1476899523//001_Fulltest_m20//dacParameters35_C3.dat
[22:05:58.448] <TB3> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1027_FullQualification_2016-10-19_19h52m_1476899523//001_Fulltest_m20//dacParameters35_C4.dat
[22:05:58.449] <TB3> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1027_FullQualification_2016-10-19_19h52m_1476899523//001_Fulltest_m20//dacParameters35_C5.dat
[22:05:58.449] <TB3> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1027_FullQualification_2016-10-19_19h52m_1476899523//001_Fulltest_m20//dacParameters35_C6.dat
[22:05:58.449] <TB3> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1027_FullQualification_2016-10-19_19h52m_1476899523//001_Fulltest_m20//dacParameters35_C7.dat
[22:05:58.449] <TB3> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1027_FullQualification_2016-10-19_19h52m_1476899523//001_Fulltest_m20//dacParameters35_C8.dat
[22:05:58.449] <TB3> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1027_FullQualification_2016-10-19_19h52m_1476899523//001_Fulltest_m20//dacParameters35_C9.dat
[22:05:58.449] <TB3> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1027_FullQualification_2016-10-19_19h52m_1476899523//001_Fulltest_m20//dacParameters35_C10.dat
[22:05:58.449] <TB3> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1027_FullQualification_2016-10-19_19h52m_1476899523//001_Fulltest_m20//dacParameters35_C11.dat
[22:05:58.449] <TB3> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1027_FullQualification_2016-10-19_19h52m_1476899523//001_Fulltest_m20//dacParameters35_C12.dat
[22:05:58.449] <TB3> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1027_FullQualification_2016-10-19_19h52m_1476899523//001_Fulltest_m20//dacParameters35_C13.dat
[22:05:58.450] <TB3> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1027_FullQualification_2016-10-19_19h52m_1476899523//001_Fulltest_m20//dacParameters35_C14.dat
[22:05:58.450] <TB3> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1027_FullQualification_2016-10-19_19h52m_1476899523//001_Fulltest_m20//dacParameters35_C15.dat
[22:05:59.135] <TB3> INFO: Expecting 41600 events.
[22:06:16.061] <TB3> INFO: 41600 events read in total (16206ms).
[22:06:16.063] <TB3> INFO: Test took 17491ms.
[22:06:16.588] <TB3> INFO: Expecting 41600 events.
[22:06:19.630] <TB3> INFO: 41600 events read in total (2451ms).
[22:06:19.631] <TB3> INFO: Test took 3356ms.
[22:06:20.086] <TB3> INFO: Expecting 41600 events.
[22:06:23.249] <TB3> INFO: 41600 events read in total (2564ms).
[22:06:23.249] <TB3> INFO: Test took 3406ms.
[22:06:23.465] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[22:06:23.554] <TB3> INFO: Expecting 2560 events.
[22:06:24.437] <TB3> INFO: 2560 events read in total (292ms).
[22:06:24.437] <TB3> INFO: Test took 972ms.
[22:06:24.440] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[22:06:24.745] <TB3> INFO: Expecting 2560 events.
[22:06:25.630] <TB3> INFO: 2560 events read in total (294ms).
[22:06:25.630] <TB3> INFO: Test took 1190ms.
[22:06:25.632] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[22:06:25.938] <TB3> INFO: Expecting 2560 events.
[22:06:26.826] <TB3> INFO: 2560 events read in total (296ms).
[22:06:26.827] <TB3> INFO: Test took 1195ms.
[22:06:26.830] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[22:06:27.135] <TB3> INFO: Expecting 2560 events.
[22:06:28.021] <TB3> INFO: 2560 events read in total (295ms).
[22:06:28.022] <TB3> INFO: Test took 1192ms.
[22:06:28.023] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[22:06:28.330] <TB3> INFO: Expecting 2560 events.
[22:06:29.214] <TB3> INFO: 2560 events read in total (292ms).
[22:06:29.214] <TB3> INFO: Test took 1191ms.
[22:06:29.216] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[22:06:29.523] <TB3> INFO: Expecting 2560 events.
[22:06:30.410] <TB3> INFO: 2560 events read in total (296ms).
[22:06:30.410] <TB3> INFO: Test took 1194ms.
[22:06:30.412] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[22:06:30.719] <TB3> INFO: Expecting 2560 events.
[22:06:31.603] <TB3> INFO: 2560 events read in total (292ms).
[22:06:31.603] <TB3> INFO: Test took 1191ms.
[22:06:31.606] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[22:06:31.912] <TB3> INFO: Expecting 2560 events.
[22:06:32.794] <TB3> INFO: 2560 events read in total (291ms).
[22:06:32.794] <TB3> INFO: Test took 1188ms.
[22:06:32.798] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[22:06:33.103] <TB3> INFO: Expecting 2560 events.
[22:06:33.983] <TB3> INFO: 2560 events read in total (288ms).
[22:06:33.983] <TB3> INFO: Test took 1185ms.
[22:06:33.985] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[22:06:34.292] <TB3> INFO: Expecting 2560 events.
[22:06:35.171] <TB3> INFO: 2560 events read in total (288ms).
[22:06:35.171] <TB3> INFO: Test took 1186ms.
[22:06:35.173] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[22:06:35.480] <TB3> INFO: Expecting 2560 events.
[22:06:36.364] <TB3> INFO: 2560 events read in total (290ms).
[22:06:36.364] <TB3> INFO: Test took 1191ms.
[22:06:36.367] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[22:06:36.673] <TB3> INFO: Expecting 2560 events.
[22:06:37.552] <TB3> INFO: 2560 events read in total (287ms).
[22:06:37.552] <TB3> INFO: Test took 1185ms.
[22:06:37.554] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[22:06:37.860] <TB3> INFO: Expecting 2560 events.
[22:06:38.743] <TB3> INFO: 2560 events read in total (291ms).
[22:06:38.743] <TB3> INFO: Test took 1189ms.
[22:06:38.745] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[22:06:39.052] <TB3> INFO: Expecting 2560 events.
[22:06:39.933] <TB3> INFO: 2560 events read in total (290ms).
[22:06:39.934] <TB3> INFO: Test took 1189ms.
[22:06:39.937] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[22:06:40.242] <TB3> INFO: Expecting 2560 events.
[22:06:41.121] <TB3> INFO: 2560 events read in total (287ms).
[22:06:41.121] <TB3> INFO: Test took 1185ms.
[22:06:41.123] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[22:06:41.430] <TB3> INFO: Expecting 2560 events.
[22:06:42.353] <TB3> INFO: 2560 events read in total (331ms).
[22:06:42.359] <TB3> INFO: Test took 1236ms.
[22:06:42.410] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[22:06:42.763] <TB3> INFO: Expecting 2560 events.
[22:06:45.096] <TB3> INFO: 2560 events read in total (1689ms).
[22:06:45.103] <TB3> INFO: Test took 2693ms.
[22:06:45.179] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[22:06:45.596] <TB3> INFO: Expecting 2560 events.
[22:06:46.506] <TB3> INFO: 2560 events read in total (295ms).
[22:06:46.506] <TB3> INFO: Test took 1327ms.
[22:06:46.508] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[22:06:46.816] <TB3> INFO: Expecting 2560 events.
[22:06:47.736] <TB3> INFO: 2560 events read in total (328ms).
[22:06:47.736] <TB3> INFO: Test took 1228ms.
[22:06:47.739] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[22:06:48.057] <TB3> INFO: Expecting 2560 events.
[22:06:50.381] <TB3> INFO: 2560 events read in total (1666ms).
[22:06:50.383] <TB3> INFO: Test took 2644ms.
[22:06:50.429] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[22:06:50.689] <TB3> INFO: Expecting 2560 events.
[22:06:51.625] <TB3> INFO: 2560 events read in total (345ms).
[22:06:51.631] <TB3> INFO: Test took 1203ms.
[22:06:51.695] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[22:06:52.190] <TB3> INFO: Expecting 2560 events.
[22:06:53.324] <TB3> INFO: 2560 events read in total (477ms).
[22:06:53.332] <TB3> INFO: Test took 1637ms.
[22:06:53.435] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[22:06:53.724] <TB3> INFO: Expecting 2560 events.
[22:06:55.950] <TB3> INFO: 2560 events read in total (1571ms).
[22:06:55.951] <TB3> INFO: Test took 2516ms.
[22:06:55.953] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[22:06:56.259] <TB3> INFO: Expecting 2560 events.
[22:06:57.414] <TB3> INFO: 2560 events read in total (556ms).
[22:06:57.415] <TB3> INFO: Test took 1462ms.
[22:06:57.418] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[22:06:57.981] <TB3> INFO: Expecting 2560 events.
[22:07:01.096] <TB3> INFO: 2560 events read in total (2441ms).
[22:07:01.107] <TB3> INFO: Test took 3689ms.
[22:07:01.207] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[22:07:01.643] <TB3> INFO: Expecting 2560 events.
[22:07:03.732] <TB3> INFO: 2560 events read in total (1385ms).
[22:07:03.743] <TB3> INFO: Test took 2536ms.
[22:07:03.879] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[22:07:04.287] <TB3> INFO: Expecting 2560 events.
[22:07:05.692] <TB3> INFO: 2560 events read in total (790ms).
[22:07:05.693] <TB3> INFO: Test took 1814ms.
[22:07:05.694] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[22:07:06.158] <TB3> INFO: Expecting 2560 events.
[22:07:08.492] <TB3> INFO: 2560 events read in total (1734ms).
[22:07:08.501] <TB3> INFO: Test took 2807ms.
[22:07:08.552] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[22:07:09.031] <TB3> INFO: Expecting 2560 events.
[22:07:11.956] <TB3> INFO: 2560 events read in total (2216ms).
[22:07:11.965] <TB3> INFO: Test took 3413ms.
[22:07:12.056] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[22:07:12.489] <TB3> INFO: Expecting 2560 events.
[22:07:14.060] <TB3> INFO: 2560 events read in total (897ms).
[22:07:14.071] <TB3> INFO: Test took 2015ms.
[22:07:14.085] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[22:07:14.402] <TB3> INFO: Expecting 2560 events.
[22:07:15.713] <TB3> INFO: 2560 events read in total (666ms).
[22:07:15.713] <TB3> INFO: Test took 1628ms.
[22:07:15.715] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[22:07:16.021] <TB3> INFO: Expecting 2560 events.
[22:07:16.909] <TB3> INFO: 2560 events read in total (297ms).
[22:07:16.909] <TB3> INFO: Test took 1194ms.
[22:07:17.378] <TB3> INFO: PixTestPhOptimization::doTest() done, duration: 804 seconds
[22:07:17.378] <TB3> INFO: PH scale (per ROC): 52 62 39 49 40 39 58 43 46 38 52 46 45 37 47 42
[22:07:17.378] <TB3> INFO: PH offset (per ROC): 142 131 109 133 101 140 135 109 133 104 132 140 133 94 130 86
[22:07:17.388] <TB3> INFO: Decoding statistics:
[22:07:17.388] <TB3> INFO: General information:
[22:07:17.388] <TB3> INFO: 16bit words read: 127890
[22:07:17.388] <TB3> INFO: valid events total: 20480
[22:07:17.388] <TB3> INFO: empty events: 17975
[22:07:17.388] <TB3> INFO: valid events with pixels: 2505
[22:07:17.388] <TB3> INFO: valid pixel hits: 2505
[22:07:17.389] <TB3> INFO: Event errors: 0
[22:07:17.389] <TB3> INFO: start marker: 0
[22:07:17.389] <TB3> INFO: stop marker: 0
[22:07:17.389] <TB3> INFO: overflow: 0
[22:07:17.389] <TB3> INFO: invalid 5bit words: 0
[22:07:17.389] <TB3> INFO: invalid XOR eye diagram: 0
[22:07:17.389] <TB3> INFO: frame (failed synchr.): 0
[22:07:17.389] <TB3> INFO: idle data (no TBM trl): 0
[22:07:17.389] <TB3> INFO: no data (only TBM hdr): 0
[22:07:17.389] <TB3> INFO: TBM errors: 0
[22:07:17.389] <TB3> INFO: flawed TBM headers: 0
[22:07:17.389] <TB3> INFO: flawed TBM trailers: 0
[22:07:17.389] <TB3> INFO: event ID mismatches: 0
[22:07:17.389] <TB3> INFO: ROC errors: 0
[22:07:17.389] <TB3> INFO: missing ROC header(s): 0
[22:07:17.389] <TB3> INFO: misplaced readback start: 0
[22:07:17.389] <TB3> INFO: Pixel decoding errors: 0
[22:07:17.389] <TB3> INFO: pixel data incomplete: 0
[22:07:17.389] <TB3> INFO: pixel address: 0
[22:07:17.389] <TB3> INFO: pulse height fill bit: 0
[22:07:17.389] <TB3> INFO: buffer corruption: 0
[22:07:17.603] <TB3> INFO: ######################################################################
[22:07:17.603] <TB3> INFO: PixTestGainPedestal::fullTest() ntrig = 10
[22:07:17.603] <TB3> INFO: ######################################################################
[22:07:17.618] <TB3> INFO: scanning low vcal = 10
[22:07:17.876] <TB3> INFO: Expecting 41600 events.
[22:07:38.753] <TB3> INFO: 41600 events read in total (20286ms).
[22:07:38.779] <TB3> INFO: Test took 21161ms.
[22:07:38.907] <TB3> INFO: scanning low vcal = 20
[22:07:39.414] <TB3> INFO: Expecting 41600 events.
[22:07:43.033] <TB3> INFO: 41600 events read in total (3028ms).
[22:07:43.034] <TB3> INFO: Test took 4123ms.
[22:07:43.036] <TB3> INFO: scanning low vcal = 30
[22:07:43.329] <TB3> INFO: Expecting 41600 events.
[22:07:46.970] <TB3> INFO: 41600 events read in total (3050ms).
[22:07:46.971] <TB3> INFO: Test took 3935ms.
[22:07:46.974] <TB3> INFO: scanning low vcal = 40
[22:07:47.252] <TB3> INFO: Expecting 41600 events.
[22:07:51.176] <TB3> INFO: 41600 events read in total (3332ms).
[22:07:51.177] <TB3> INFO: Test took 4203ms.
[22:07:51.180] <TB3> INFO: scanning low vcal = 50
[22:07:51.457] <TB3> INFO: Expecting 41600 events.
[22:07:55.432] <TB3> INFO: 41600 events read in total (3384ms).
[22:07:55.433] <TB3> INFO: Test took 4253ms.
[22:07:55.436] <TB3> INFO: scanning low vcal = 60
[22:07:55.713] <TB3> INFO: Expecting 41600 events.
[22:07:59.682] <TB3> INFO: 41600 events read in total (3377ms).
[22:07:59.683] <TB3> INFO: Test took 4247ms.
[22:07:59.686] <TB3> INFO: scanning low vcal = 70
[22:07:59.962] <TB3> INFO: Expecting 41600 events.
[22:08:03.907] <TB3> INFO: 41600 events read in total (3353ms).
[22:08:03.907] <TB3> INFO: Test took 4221ms.
[22:08:03.911] <TB3> INFO: scanning low vcal = 80
[22:08:04.188] <TB3> INFO: Expecting 41600 events.
[22:08:11.905] <TB3> INFO: 41600 events read in total (7126ms).
[22:08:11.914] <TB3> INFO: Test took 8003ms.
[22:08:11.918] <TB3> INFO: scanning low vcal = 90
[22:08:13.075] <TB3> INFO: Expecting 41600 events.
[22:08:17.465] <TB3> INFO: 41600 events read in total (3754ms).
[22:08:17.466] <TB3> INFO: Test took 5548ms.
[22:08:17.471] <TB3> INFO: scanning low vcal = 100
[22:08:17.820] <TB3> INFO: Expecting 41600 events.
[22:08:24.567] <TB3> INFO: 41600 events read in total (6096ms).
[22:08:24.569] <TB3> INFO: Test took 7098ms.
[22:08:24.587] <TB3> INFO: scanning low vcal = 110
[22:08:25.383] <TB3> INFO: Expecting 41600 events.
[22:08:34.004] <TB3> INFO: 41600 events read in total (9026ms).
[22:08:34.006] <TB3> INFO: Test took 10418ms.
[22:08:35.011] <TB3> INFO: scanning low vcal = 120
[22:08:35.585] <TB3> INFO: Expecting 41600 events.
[22:08:47.918] <TB3> INFO: 41600 events read in total (11736ms).
[22:08:47.920] <TB3> INFO: Test took 12908ms.
[22:08:47.925] <TB3> INFO: scanning low vcal = 130
[22:08:48.251] <TB3> INFO: Expecting 41600 events.
[22:08:52.920] <TB3> INFO: 41600 events read in total (4077ms).
[22:08:52.927] <TB3> INFO: Test took 5002ms.
[22:08:52.936] <TB3> INFO: scanning low vcal = 140
[22:08:53.509] <TB3> INFO: Expecting 41600 events.
[22:09:01.551] <TB3> INFO: 41600 events read in total (7450ms).
[22:09:01.565] <TB3> INFO: Test took 8627ms.
[22:09:01.687] <TB3> INFO: scanning low vcal = 150
[22:09:02.663] <TB3> INFO: Expecting 41600 events.
[22:09:12.334] <TB3> INFO: 41600 events read in total (9007ms).
[22:09:12.335] <TB3> INFO: Test took 10637ms.
[22:09:12.339] <TB3> INFO: scanning low vcal = 160
[22:09:12.675] <TB3> INFO: Expecting 41600 events.
[22:09:16.813] <TB3> INFO: 41600 events read in total (3547ms).
[22:09:16.818] <TB3> INFO: Test took 4479ms.
[22:09:16.823] <TB3> INFO: scanning low vcal = 170
[22:09:17.100] <TB3> INFO: Expecting 41600 events.
[22:09:21.089] <TB3> INFO: 41600 events read in total (3393ms).
[22:09:21.090] <TB3> INFO: Test took 4266ms.
[22:09:21.097] <TB3> INFO: scanning low vcal = 180
[22:09:21.369] <TB3> INFO: Expecting 41600 events.
[22:09:25.404] <TB3> INFO: 41600 events read in total (3438ms).
[22:09:25.405] <TB3> INFO: Test took 4308ms.
[22:09:25.409] <TB3> INFO: scanning low vcal = 190
[22:09:25.684] <TB3> INFO: Expecting 41600 events.
[22:09:29.708] <TB3> INFO: 41600 events read in total (3432ms).
[22:09:29.709] <TB3> INFO: Test took 4299ms.
[22:09:29.714] <TB3> INFO: scanning low vcal = 200
[22:09:29.988] <TB3> INFO: Expecting 41600 events.
[22:09:33.994] <TB3> INFO: 41600 events read in total (3414ms).
[22:09:33.994] <TB3> INFO: Test took 4280ms.
[22:09:33.997] <TB3> INFO: scanning low vcal = 210
[22:09:34.285] <TB3> INFO: Expecting 41600 events.
[22:09:38.297] <TB3> INFO: 41600 events read in total (3420ms).
[22:09:38.298] <TB3> INFO: Test took 4301ms.
[22:09:38.302] <TB3> INFO: scanning low vcal = 220
[22:09:38.608] <TB3> INFO: Expecting 41600 events.
[22:09:43.259] <TB3> INFO: 41600 events read in total (4059ms).
[22:09:43.266] <TB3> INFO: Test took 4964ms.
[22:09:43.399] <TB3> INFO: scanning low vcal = 230
[22:09:44.135] <TB3> INFO: Expecting 41600 events.
[22:09:51.178] <TB3> INFO: 41600 events read in total (6407ms).
[22:09:51.179] <TB3> INFO: Test took 7776ms.
[22:09:51.183] <TB3> INFO: scanning low vcal = 240
[22:09:51.807] <TB3> INFO: Expecting 41600 events.
[22:09:56.897] <TB3> INFO: 41600 events read in total (4380ms).
[22:09:56.905] <TB3> INFO: Test took 5722ms.
[22:09:57.039] <TB3> INFO: scanning low vcal = 250
[22:09:57.591] <TB3> INFO: Expecting 41600 events.
[22:10:08.190] <TB3> INFO: 41600 events read in total (9943ms).
[22:10:08.204] <TB3> INFO: Test took 11157ms.
[22:10:08.435] <TB3> INFO: scanning high vcal = 30 (= 210 in low range)
[22:10:08.003] <TB3> INFO: Expecting 41600 events.
[22:10:18.896] <TB3> INFO: 41600 events read in total (9259ms).
[22:10:18.897] <TB3> INFO: Test took 10454ms.
[22:10:18.901] <TB3> INFO: scanning high vcal = 50 (= 350 in low range)
[22:10:19.176] <TB3> INFO: Expecting 41600 events.
[22:10:40.877] <TB3> INFO: 41600 events read in total (21109ms).
[22:10:40.878] <TB3> INFO: Test took 21977ms.
[22:10:40.881] <TB3> INFO: scanning high vcal = 70 (= 490 in low range)
[22:10:41.157] <TB3> INFO: Expecting 41600 events.
[22:10:45.236] <TB3> INFO: 41600 events read in total (3488ms).
[22:10:45.237] <TB3> INFO: Test took 4356ms.
[22:10:45.241] <TB3> INFO: scanning high vcal = 90 (= 630 in low range)
[22:10:45.552] <TB3> INFO: Expecting 41600 events.
[22:10:49.577] <TB3> INFO: 41600 events read in total (3433ms).
[22:10:49.578] <TB3> INFO: Test took 4337ms.
[22:10:49.581] <TB3> INFO: scanning high vcal = 200 (= 1400 in low range)
[22:10:49.857] <TB3> INFO: Expecting 41600 events.
[22:10:53.907] <TB3> INFO: 41600 events read in total (3458ms).
[22:10:53.908] <TB3> INFO: Test took 4327ms.
[22:10:54.497] <TB3> INFO: PixTestGainPedestal::measure() done
[22:11:40.517] <TB3> INFO: PixTestGainPedestal::fit() done
[22:11:40.517] <TB3> INFO: non-linearity mean: 0.978 0.984 0.945 0.976 0.910 0.963 0.981 0.924 0.971 0.954 0.976 0.973 0.970 0.935 0.974 1.034
[22:11:40.517] <TB3> INFO: non-linearity RMS: 0.003 0.003 0.172 0.004 0.114 0.007 0.004 0.069 0.006 0.160 0.003 0.003 0.005 0.135 0.004 0.150
[22:11:40.517] <TB3> INFO: write gain/ped parameters into /home/l_tester/david/FullQualification/data/M1027_FullQualification_2016-10-19_19h52m_1476899523//001_Fulltest_m20//phCalibrationFitErr35_C0.dat
[22:11:40.546] <TB3> INFO: write gain/ped parameters into /home/l_tester/david/FullQualification/data/M1027_FullQualification_2016-10-19_19h52m_1476899523//001_Fulltest_m20//phCalibrationFitErr35_C1.dat
[22:11:40.575] <TB3> INFO: write gain/ped parameters into /home/l_tester/david/FullQualification/data/M1027_FullQualification_2016-10-19_19h52m_1476899523//001_Fulltest_m20//phCalibrationFitErr35_C2.dat
[22:11:40.598] <TB3> INFO: write gain/ped parameters into /home/l_tester/david/FullQualification/data/M1027_FullQualification_2016-10-19_19h52m_1476899523//001_Fulltest_m20//phCalibrationFitErr35_C3.dat
[22:11:40.622] <TB3> INFO: write gain/ped parameters into /home/l_tester/david/FullQualification/data/M1027_FullQualification_2016-10-19_19h52m_1476899523//001_Fulltest_m20//phCalibrationFitErr35_C4.dat
[22:11:40.645] <TB3> INFO: write gain/ped parameters into /home/l_tester/david/FullQualification/data/M1027_FullQualification_2016-10-19_19h52m_1476899523//001_Fulltest_m20//phCalibrationFitErr35_C5.dat
[22:11:40.669] <TB3> INFO: write gain/ped parameters into /home/l_tester/david/FullQualification/data/M1027_FullQualification_2016-10-19_19h52m_1476899523//001_Fulltest_m20//phCalibrationFitErr35_C6.dat
[22:11:40.693] <TB3> INFO: write gain/ped parameters into /home/l_tester/david/FullQualification/data/M1027_FullQualification_2016-10-19_19h52m_1476899523//001_Fulltest_m20//phCalibrationFitErr35_C7.dat
[22:11:40.716] <TB3> INFO: write gain/ped parameters into /home/l_tester/david/FullQualification/data/M1027_FullQualification_2016-10-19_19h52m_1476899523//001_Fulltest_m20//phCalibrationFitErr35_C8.dat
[22:11:40.740] <TB3> INFO: write gain/ped parameters into /home/l_tester/david/FullQualification/data/M1027_FullQualification_2016-10-19_19h52m_1476899523//001_Fulltest_m20//phCalibrationFitErr35_C9.dat
[22:11:40.763] <TB3> INFO: write gain/ped parameters into /home/l_tester/david/FullQualification/data/M1027_FullQualification_2016-10-19_19h52m_1476899523//001_Fulltest_m20//phCalibrationFitErr35_C10.dat
[22:11:40.787] <TB3> INFO: write gain/ped parameters into /home/l_tester/david/FullQualification/data/M1027_FullQualification_2016-10-19_19h52m_1476899523//001_Fulltest_m20//phCalibrationFitErr35_C11.dat
[22:11:40.810] <TB3> INFO: write gain/ped parameters into /home/l_tester/david/FullQualification/data/M1027_FullQualification_2016-10-19_19h52m_1476899523//001_Fulltest_m20//phCalibrationFitErr35_C12.dat
[22:11:40.834] <TB3> INFO: write gain/ped parameters into /home/l_tester/david/FullQualification/data/M1027_FullQualification_2016-10-19_19h52m_1476899523//001_Fulltest_m20//phCalibrationFitErr35_C13.dat
[22:11:40.858] <TB3> INFO: write gain/ped parameters into /home/l_tester/david/FullQualification/data/M1027_FullQualification_2016-10-19_19h52m_1476899523//001_Fulltest_m20//phCalibrationFitErr35_C14.dat
[22:11:40.881] <TB3> INFO: write gain/ped parameters into /home/l_tester/david/FullQualification/data/M1027_FullQualification_2016-10-19_19h52m_1476899523//001_Fulltest_m20//phCalibrationFitErr35_C15.dat
[22:11:40.905] <TB3> INFO: PixTestGainPedestal::fullTest() done, duration: 263 seconds
[22:11:40.905] <TB3> INFO: Decoding statistics:
[22:11:40.905] <TB3> INFO: General information:
[22:11:40.905] <TB3> INFO: 16bit words read: 3327890
[22:11:40.905] <TB3> INFO: valid events total: 332800
[22:11:40.905] <TB3> INFO: empty events: 0
[22:11:40.905] <TB3> INFO: valid events with pixels: 332800
[22:11:40.905] <TB3> INFO: valid pixel hits: 665545
[22:11:40.905] <TB3> INFO: Event errors: 0
[22:11:40.905] <TB3> INFO: start marker: 0
[22:11:40.905] <TB3> INFO: stop marker: 0
[22:11:40.905] <TB3> INFO: overflow: 0
[22:11:40.905] <TB3> INFO: invalid 5bit words: 0
[22:11:40.905] <TB3> INFO: invalid XOR eye diagram: 0
[22:11:40.905] <TB3> INFO: frame (failed synchr.): 0
[22:11:40.905] <TB3> INFO: idle data (no TBM trl): 0
[22:11:40.905] <TB3> INFO: no data (only TBM hdr): 0
[22:11:40.905] <TB3> INFO: TBM errors: 0
[22:11:40.905] <TB3> INFO: flawed TBM headers: 0
[22:11:40.905] <TB3> INFO: flawed TBM trailers: 0
[22:11:40.905] <TB3> INFO: event ID mismatches: 0
[22:11:40.905] <TB3> INFO: ROC errors: 0
[22:11:40.905] <TB3> INFO: missing ROC header(s): 0
[22:11:40.905] <TB3> INFO: misplaced readback start: 0
[22:11:40.905] <TB3> INFO: Pixel decoding errors: 0
[22:11:40.905] <TB3> INFO: pixel data incomplete: 0
[22:11:40.905] <TB3> INFO: pixel address: 0
[22:11:40.905] <TB3> INFO: pulse height fill bit: 0
[22:11:40.905] <TB3> INFO: buffer corruption: 0
[22:11:40.936] <TB3> INFO: Decoding statistics:
[22:11:40.936] <TB3> INFO: General information:
[22:11:40.936] <TB3> INFO: 16bit words read: 3457316
[22:11:40.936] <TB3> INFO: valid events total: 353536
[22:11:40.936] <TB3> INFO: empty events: 18231
[22:11:40.936] <TB3> INFO: valid events with pixels: 335305
[22:11:40.936] <TB3> INFO: valid pixel hits: 668050
[22:11:40.936] <TB3> INFO: Event errors: 0
[22:11:40.936] <TB3> INFO: start marker: 0
[22:11:40.936] <TB3> INFO: stop marker: 0
[22:11:40.936] <TB3> INFO: overflow: 0
[22:11:40.936] <TB3> INFO: invalid 5bit words: 0
[22:11:40.936] <TB3> INFO: invalid XOR eye diagram: 0
[22:11:40.936] <TB3> INFO: frame (failed synchr.): 0
[22:11:40.936] <TB3> INFO: idle data (no TBM trl): 0
[22:11:40.936] <TB3> INFO: no data (only TBM hdr): 0
[22:11:40.936] <TB3> INFO: TBM errors: 0
[22:11:40.936] <TB3> INFO: flawed TBM headers: 0
[22:11:40.936] <TB3> INFO: flawed TBM trailers: 0
[22:11:40.936] <TB3> INFO: event ID mismatches: 0
[22:11:40.936] <TB3> INFO: ROC errors: 0
[22:11:40.936] <TB3> INFO: missing ROC header(s): 0
[22:11:40.936] <TB3> INFO: misplaced readback start: 0
[22:11:40.936] <TB3> INFO: Pixel decoding errors: 0
[22:11:40.936] <TB3> INFO: pixel data incomplete: 0
[22:11:40.936] <TB3> INFO: pixel address: 0
[22:11:40.936] <TB3> INFO: pulse height fill bit: 0
[22:11:40.936] <TB3> INFO: buffer corruption: 0
[22:11:40.936] <TB3> INFO: enter test to run
[22:11:40.936] <TB3> INFO: test: exit no parameter change
[22:11:41.191] <TB3> QUIET: Connection to board 126 closed.
[22:11:41.203] <TB3> INFO: pXar: this is the end, my friend