[17:20:59.079] <TB3> INFO: *** Welcome to pxar ***
[17:20:59.079] <TB3> INFO: *** Today: 2016/10/26
[17:20:59.085] <TB3> INFO: *** Version: c8ba-dirty
[17:20:59.085] <TB3> INFO: readRocDacs: /home/l_tester/david/FullQualification/data/M1037_FullQualification_2016-10-26_16h26m_1477492000//001_Fulltest_m20//dacParameters_C0.dat .. /home/l_tester/david/FullQualification/data/M1037_FullQualification_2016-10-26_16h26m_1477492000//001_Fulltest_m20//dacParameters_C15.dat
[17:20:59.085] <TB3> INFO: readTbmDacs: /home/l_tester/david/FullQualification/data/M1037_FullQualification_2016-10-26_16h26m_1477492000//001_Fulltest_m20//tbmParameters_C0a.dat .. /home/l_tester/david/FullQualification/data/M1037_FullQualification_2016-10-26_16h26m_1477492000//001_Fulltest_m20//tbmParameters_C1b.dat
[17:20:59.085] <TB3> INFO: readMaskFile: /home/l_tester/david/FullQualification/data/M1037_FullQualification_2016-10-26_16h26m_1477492000//001_Fulltest_m20//defaultMaskFile.dat
[17:20:59.085] <TB3> INFO: readTrimFile: /home/l_tester/david/FullQualification/data/M1037_FullQualification_2016-10-26_16h26m_1477492000//001_Fulltest_m20//trimParameters_C0.dat .. /home/l_tester/david/FullQualification/data/M1037_FullQualification_2016-10-26_16h26m_1477492000//001_Fulltest_m20//trimParameters_C15.dat
[17:20:59.141] <TB3> INFO: clk: 4
[17:20:59.141] <TB3> INFO: ctr: 4
[17:20:59.141] <TB3> INFO: sda: 19
[17:20:59.141] <TB3> INFO: tin: 9
[17:20:59.141] <TB3> INFO: level: 15
[17:20:59.141] <TB3> INFO: triggerdelay: 0
[17:20:59.141] <TB3> QUIET: Instanciating API for pxar v2.1.0+867~g2c7f7f2
[17:20:59.141] <TB3> INFO: Log level: INFO
[17:20:59.150] <TB3> INFO: Found DTB DTB_WZ4I6J
[17:20:59.158] <TB3> QUIET: Connection to board DTB_WZ4I6J opened.
[17:20:59.160] <TB3> INFO: DTB startup information
--- DTB info------------------------------------------
Board id: 170
HW version: DTB1.2
FW version: 4.6
SW version: 4.7
Options:
USB id: DTB_WZ4I6J
MAC address: 40D8551180AA
Hostname: pixelDTB170
Comment:
------------------------------------------------------
[17:20:59.162] <TB3> INFO: RPC call hashes of host and DTB match: 486171790
[17:21:00.646] <TB3> INFO: DUT info:
[17:21:00.647] <TB3> INFO: The DUT currently contains the following objects:
[17:21:00.647] <TB3> INFO: 4 TBM Cores tbm10c (4 ON)
[17:21:00.647] <TB3> INFO: TBM Core alpha (0): 7 registers set
[17:21:00.647] <TB3> INFO: TBM Core beta (1): 7 registers set
[17:21:00.647] <TB3> INFO: TBM Core alpha (2): 7 registers set
[17:21:00.647] <TB3> INFO: TBM Core beta (3): 7 registers set
[17:21:00.647] <TB3> INFO: 16 ROCs proc600 (16 ON) with 4160 pixelConfigs
[17:21:00.647] <TB3> INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[17:21:00.647] <TB3> INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[17:21:00.647] <TB3> INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[17:21:00.647] <TB3> INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[17:21:00.647] <TB3> INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[17:21:00.647] <TB3> INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[17:21:00.647] <TB3> INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[17:21:00.647] <TB3> INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[17:21:00.647] <TB3> INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[17:21:00.647] <TB3> INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[17:21:00.647] <TB3> INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[17:21:00.647] <TB3> INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[17:21:00.647] <TB3> INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[17:21:00.647] <TB3> INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[17:21:00.647] <TB3> INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[17:21:00.647] <TB3> INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[17:21:01.048] <TB3> INFO: enter 'restricted' command line mode
[17:21:01.048] <TB3> INFO: enter test to run
[17:21:01.048] <TB3> INFO: test: pretest no parameter change
[17:21:01.048] <TB3> INFO: running: pretest
[17:21:01.586] <TB3> INFO: ######################################################################
[17:21:01.586] <TB3> INFO: PixTestPretest::doTest()
[17:21:01.586] <TB3> INFO: ######################################################################
[17:21:01.587] <TB3> INFO: ----------------------------------------------------------------------
[17:21:01.587] <TB3> INFO: PixTestPretest::programROC()
[17:21:01.587] <TB3> INFO: ----------------------------------------------------------------------
[17:21:19.600] <TB3> INFO: PixTestPretest::programROC() done: ROCs are all programmable
[17:21:19.600] <TB3> INFO: IA differences per ROC: 18.5 18.5 20.1 18.5 20.1 19.3 21.7 20.1 18.5 19.3 19.3 20.1 19.3 19.3 20.1 17.7
[17:21:19.636] <TB3> INFO: ----------------------------------------------------------------------
[17:21:19.637] <TB3> INFO: PixTestPretest::setVana() target Ia = 24 mA/ROC
[17:21:19.637] <TB3> INFO: ----------------------------------------------------------------------
[17:21:40.883] <TB3> INFO: PixTestPretest::setVana() done, Module Ia 383.5 mA = 23.9688 mA/ROC
[17:21:40.883] <TB3> INFO: i(loss) [mA/ROC]: 20.1 19.3 20.1 19.3 20.1 19.3 19.3 19.3 19.3 20.1 19.3 19.3 19.3 20.1 19.3 18.5
[17:21:40.913] <TB3> INFO: ----------------------------------------------------------------------
[17:21:40.913] <TB3> INFO: PixTestPretest::findTiming()
[17:21:40.913] <TB3> INFO: ----------------------------------------------------------------------
[17:21:40.913] <TB3> INFO: PixTestCmd::init()

[17:22:12.225] <TB3> INFO: TBM phases: 160MHz: 0, 400MHz: 2, TBM delays: ROC(0/1):5, header/trailer: 1, token: 1
[17:22:12.225] <TB3> INFO: (success/tries = 100/100), width = 3
[17:22:13.727] <TB3> INFO: ----------------------------------------------------------------------
[17:22:13.727] <TB3> INFO: PixTestPretest::findWorkingPixel()
[17:22:13.727] <TB3> INFO: ----------------------------------------------------------------------
[17:22:13.818] <TB3> INFO: Expecting 231680 events.
[17:22:23.408] <TB3> INFO: 231680 events read in total (8998ms).
[17:22:23.414] <TB3> INFO: Test took 9685ms.
[17:22:23.658] <TB3> INFO: Found working pixel in all ROCs: col/row = 12/22
[17:22:23.691] <TB3> INFO: ----------------------------------------------------------------------
[17:22:23.691] <TB3> INFO: PixTestPretest::setVthrCompCalDel()
[17:22:23.691] <TB3> INFO: ----------------------------------------------------------------------
[17:22:23.783] <TB3> INFO: Expecting 231680 events.
[17:22:33.508] <TB3> INFO: 231680 events read in total (9133ms).
[17:22:33.517] <TB3> INFO: Test took 9822ms.
[17:22:33.775] <TB3> INFO: PixTestPretest::setVthrCompCalDel() done
[17:22:33.775] <TB3> INFO: CalDel: 95 102 121 110 122 133 134 120 135 112 105 123 119 115 120 114
[17:22:33.775] <TB3> INFO: VthrComp: 52 51 51 51 51 51 51 51 51 51 52 51 52 51 51 51
[17:22:33.777] <TB3> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1037_FullQualification_2016-10-26_16h26m_1477492000//001_Fulltest_m20//dacParameters_C0.dat
[17:22:33.778] <TB3> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1037_FullQualification_2016-10-26_16h26m_1477492000//001_Fulltest_m20//dacParameters_C1.dat
[17:22:33.778] <TB3> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1037_FullQualification_2016-10-26_16h26m_1477492000//001_Fulltest_m20//dacParameters_C2.dat
[17:22:33.778] <TB3> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1037_FullQualification_2016-10-26_16h26m_1477492000//001_Fulltest_m20//dacParameters_C3.dat
[17:22:33.778] <TB3> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1037_FullQualification_2016-10-26_16h26m_1477492000//001_Fulltest_m20//dacParameters_C4.dat
[17:22:33.778] <TB3> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1037_FullQualification_2016-10-26_16h26m_1477492000//001_Fulltest_m20//dacParameters_C5.dat
[17:22:33.778] <TB3> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1037_FullQualification_2016-10-26_16h26m_1477492000//001_Fulltest_m20//dacParameters_C6.dat
[17:22:33.778] <TB3> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1037_FullQualification_2016-10-26_16h26m_1477492000//001_Fulltest_m20//dacParameters_C7.dat
[17:22:33.778] <TB3> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1037_FullQualification_2016-10-26_16h26m_1477492000//001_Fulltest_m20//dacParameters_C8.dat
[17:22:33.778] <TB3> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1037_FullQualification_2016-10-26_16h26m_1477492000//001_Fulltest_m20//dacParameters_C9.dat
[17:22:33.779] <TB3> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1037_FullQualification_2016-10-26_16h26m_1477492000//001_Fulltest_m20//dacParameters_C10.dat
[17:22:33.779] <TB3> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1037_FullQualification_2016-10-26_16h26m_1477492000//001_Fulltest_m20//dacParameters_C11.dat
[17:22:33.779] <TB3> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1037_FullQualification_2016-10-26_16h26m_1477492000//001_Fulltest_m20//dacParameters_C12.dat
[17:22:33.779] <TB3> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1037_FullQualification_2016-10-26_16h26m_1477492000//001_Fulltest_m20//dacParameters_C13.dat
[17:22:33.779] <TB3> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1037_FullQualification_2016-10-26_16h26m_1477492000//001_Fulltest_m20//dacParameters_C14.dat
[17:22:33.779] <TB3> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1037_FullQualification_2016-10-26_16h26m_1477492000//001_Fulltest_m20//dacParameters_C15.dat
[17:22:33.779] <TB3> INFO: write tbm parameters into /home/l_tester/david/FullQualification/data/M1037_FullQualification_2016-10-26_16h26m_1477492000//001_Fulltest_m20//tbmParameters_C0a.dat
[17:22:33.779] <TB3> INFO: write tbm parameters into /home/l_tester/david/FullQualification/data/M1037_FullQualification_2016-10-26_16h26m_1477492000//001_Fulltest_m20//tbmParameters_C0b.dat
[17:22:33.779] <TB3> INFO: write tbm parameters into /home/l_tester/david/FullQualification/data/M1037_FullQualification_2016-10-26_16h26m_1477492000//001_Fulltest_m20//tbmParameters_C1a.dat
[17:22:33.779] <TB3> INFO: write tbm parameters into /home/l_tester/david/FullQualification/data/M1037_FullQualification_2016-10-26_16h26m_1477492000//001_Fulltest_m20//tbmParameters_C1b.dat
[17:22:33.779] <TB3> INFO: PixTestPretest::doTest() done, duration: 92 seconds
[17:22:33.874] <TB3> INFO: enter test to run
[17:22:33.874] <TB3> INFO: test: FullTest no parameter change
[17:22:33.874] <TB3> INFO: running: fulltest
[17:22:33.874] <TB3> INFO: ######################################################################
[17:22:33.874] <TB3> INFO: PixTestFullTest::doTest()
[17:22:33.874] <TB3> INFO: ######################################################################
[17:22:33.875] <TB3> INFO: ######################################################################
[17:22:33.875] <TB3> INFO: PixTestAlive::doTest()
[17:22:33.875] <TB3> INFO: ######################################################################
[17:22:33.877] <TB3> INFO: ----------------------------------------------------------------------
[17:22:33.877] <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)
[17:22:33.877] <TB3> INFO: ----------------------------------------------------------------------
[17:22:34.109] <TB3> INFO: Expecting 41600 events.
[17:22:37.557] <TB3> INFO: 41600 events read in total (2856ms).
[17:22:37.558] <TB3> INFO: Test took 3680ms.
[17:22:37.785] <TB3> INFO: PixTestAlive::aliveTest() done
[17:22:37.785] <TB3> INFO: number of dead pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[17:22:37.786] <TB3> INFO: ----------------------------------------------------------------------
[17:22:37.786] <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)
[17:22:37.786] <TB3> INFO: ----------------------------------------------------------------------
[17:22:38.061] <TB3> INFO: Expecting 41600 events.
[17:22:40.993] <TB3> INFO: 41600 events read in total (2340ms).
[17:22:40.993] <TB3> INFO: Test took 3206ms.
[17:22:40.993] <TB3> INFO: mask vs. old pixelAlive PixelAlive_C0_V0 .. PixelAlive_C15_V0
[17:22:41.230] <TB3> INFO: PixTestAlive::maskTest() done
[17:22:41.230] <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
[17:22:41.231] <TB3> INFO: ----------------------------------------------------------------------
[17:22:41.231] <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)
[17:22:41.231] <TB3> INFO: ----------------------------------------------------------------------
[17:22:41.464] <TB3> INFO: Expecting 41600 events.
[17:22:44.898] <TB3> INFO: 41600 events read in total (2842ms).
[17:22:44.899] <TB3> INFO: Test took 3666ms.
[17:22:45.127] <TB3> INFO: PixTestAlive::addressDecodingTest() done
[17:22:45.127] <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
[17:22:45.127] <TB3> INFO: PixTestAlive::doTest() done, duration: 11 seconds
[17:22:45.127] <TB3> INFO: Decoding statistics:
[17:22:45.127] <TB3> INFO: General information:
[17:22:45.127] <TB3> INFO: 16bit words read: 0
[17:22:45.127] <TB3> INFO: valid events total: 0
[17:22:45.127] <TB3> INFO: empty events: 0
[17:22:45.127] <TB3> INFO: valid events with pixels: 0
[17:22:45.127] <TB3> INFO: valid pixel hits: 0
[17:22:45.127] <TB3> INFO: Event errors: 0
[17:22:45.127] <TB3> INFO: start marker: 0
[17:22:45.127] <TB3> INFO: stop marker: 0
[17:22:45.127] <TB3> INFO: overflow: 0
[17:22:45.127] <TB3> INFO: invalid 5bit words: 0
[17:22:45.127] <TB3> INFO: invalid XOR eye diagram: 0
[17:22:45.127] <TB3> INFO: frame (failed synchr.): 0
[17:22:45.127] <TB3> INFO: idle data (no TBM trl): 0
[17:22:45.127] <TB3> INFO: no data (only TBM hdr): 0
[17:22:45.127] <TB3> INFO: TBM errors: 0
[17:22:45.127] <TB3> INFO: flawed TBM headers: 0
[17:22:45.127] <TB3> INFO: flawed TBM trailers: 0
[17:22:45.127] <TB3> INFO: event ID mismatches: 0
[17:22:45.127] <TB3> INFO: ROC errors: 0
[17:22:45.127] <TB3> INFO: missing ROC header(s): 0
[17:22:45.127] <TB3> INFO: misplaced readback start: 0
[17:22:45.127] <TB3> INFO: Pixel decoding errors: 0
[17:22:45.127] <TB3> INFO: pixel data incomplete: 0
[17:22:45.127] <TB3> INFO: pixel address: 0
[17:22:45.127] <TB3> INFO: pulse height fill bit: 0
[17:22:45.127] <TB3> INFO: buffer corruption: 0
[17:22:45.134] <TB3> INFO: readReadbackCal: /home/l_tester/david/FullQualification/data/M1037_FullQualification_2016-10-26_16h26m_1477492000//001_Fulltest_m20//readbackCal_C0.dat .. /home/l_tester/david/FullQualification/data/M1037_FullQualification_2016-10-26_16h26m_1477492000//001_Fulltest_m20//readbackCal_C15.dat
[17:22:45.134] <TB3> INFO: readGainPedestalParameters /home/l_tester/david/FullQualification/data/M1037_FullQualification_2016-10-26_16h26m_1477492000//001_Fulltest_m20//phCalibrationFitErr_C0.dat .. /home/l_tester/david/FullQualification/data/M1037_FullQualification_2016-10-26_16h26m_1477492000//001_Fulltest_m20//phCalibrationFitErr_C15.dat

[17:22:45.134] <TB3> INFO: ######################################################################
[17:22:45.134] <TB3> INFO: PixTestReadback::doTest()
[17:22:45.134] <TB3> INFO: ######################################################################
[17:22:45.134] <TB3> INFO: ----------------------------------------------------------------------
[17:22:45.134] <TB3> INFO: PixTestReadback::CalibrateVd()
[17:22:45.134] <TB3> INFO: ----------------------------------------------------------------------
[17:22:55.094] <TB3> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1037_FullQualification_2016-10-26_16h26m_1477492000//001_Fulltest_m20//readbackCal_C0.dat
[17:22:55.094] <TB3> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1037_FullQualification_2016-10-26_16h26m_1477492000//001_Fulltest_m20//readbackCal_C1.dat
[17:22:55.094] <TB3> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1037_FullQualification_2016-10-26_16h26m_1477492000//001_Fulltest_m20//readbackCal_C2.dat
[17:22:55.094] <TB3> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1037_FullQualification_2016-10-26_16h26m_1477492000//001_Fulltest_m20//readbackCal_C3.dat
[17:22:55.094] <TB3> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1037_FullQualification_2016-10-26_16h26m_1477492000//001_Fulltest_m20//readbackCal_C4.dat
[17:22:55.094] <TB3> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1037_FullQualification_2016-10-26_16h26m_1477492000//001_Fulltest_m20//readbackCal_C5.dat
[17:22:55.094] <TB3> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1037_FullQualification_2016-10-26_16h26m_1477492000//001_Fulltest_m20//readbackCal_C6.dat
[17:22:55.094] <TB3> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1037_FullQualification_2016-10-26_16h26m_1477492000//001_Fulltest_m20//readbackCal_C7.dat
[17:22:55.094] <TB3> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1037_FullQualification_2016-10-26_16h26m_1477492000//001_Fulltest_m20//readbackCal_C8.dat
[17:22:55.095] <TB3> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1037_FullQualification_2016-10-26_16h26m_1477492000//001_Fulltest_m20//readbackCal_C9.dat
[17:22:55.095] <TB3> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1037_FullQualification_2016-10-26_16h26m_1477492000//001_Fulltest_m20//readbackCal_C10.dat
[17:22:55.095] <TB3> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1037_FullQualification_2016-10-26_16h26m_1477492000//001_Fulltest_m20//readbackCal_C11.dat
[17:22:55.095] <TB3> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1037_FullQualification_2016-10-26_16h26m_1477492000//001_Fulltest_m20//readbackCal_C12.dat
[17:22:55.095] <TB3> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1037_FullQualification_2016-10-26_16h26m_1477492000//001_Fulltest_m20//readbackCal_C13.dat
[17:22:55.095] <TB3> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1037_FullQualification_2016-10-26_16h26m_1477492000//001_Fulltest_m20//readbackCal_C14.dat
[17:22:55.095] <TB3> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1037_FullQualification_2016-10-26_16h26m_1477492000//001_Fulltest_m20//readbackCal_C15.dat
[17:22:55.124] <TB3> INFO: PixTestPattern:: pg_setup set to default.
[17:22:55.124] <TB3> INFO: ----------------------------------------------------------------------
[17:22:55.124] <TB3> INFO: PixTestReadback::CalibrateVa()
[17:22:55.124] <TB3> INFO: ----------------------------------------------------------------------
[17:23:05.018] <TB3> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1037_FullQualification_2016-10-26_16h26m_1477492000//001_Fulltest_m20//readbackCal_C0.dat
[17:23:05.018] <TB3> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1037_FullQualification_2016-10-26_16h26m_1477492000//001_Fulltest_m20//readbackCal_C1.dat
[17:23:05.018] <TB3> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1037_FullQualification_2016-10-26_16h26m_1477492000//001_Fulltest_m20//readbackCal_C2.dat
[17:23:05.018] <TB3> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1037_FullQualification_2016-10-26_16h26m_1477492000//001_Fulltest_m20//readbackCal_C3.dat
[17:23:05.018] <TB3> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1037_FullQualification_2016-10-26_16h26m_1477492000//001_Fulltest_m20//readbackCal_C4.dat
[17:23:05.018] <TB3> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1037_FullQualification_2016-10-26_16h26m_1477492000//001_Fulltest_m20//readbackCal_C5.dat
[17:23:05.018] <TB3> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1037_FullQualification_2016-10-26_16h26m_1477492000//001_Fulltest_m20//readbackCal_C6.dat
[17:23:05.019] <TB3> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1037_FullQualification_2016-10-26_16h26m_1477492000//001_Fulltest_m20//readbackCal_C7.dat
[17:23:05.019] <TB3> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1037_FullQualification_2016-10-26_16h26m_1477492000//001_Fulltest_m20//readbackCal_C8.dat
[17:23:05.019] <TB3> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1037_FullQualification_2016-10-26_16h26m_1477492000//001_Fulltest_m20//readbackCal_C9.dat
[17:23:05.019] <TB3> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1037_FullQualification_2016-10-26_16h26m_1477492000//001_Fulltest_m20//readbackCal_C10.dat
[17:23:05.019] <TB3> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1037_FullQualification_2016-10-26_16h26m_1477492000//001_Fulltest_m20//readbackCal_C11.dat
[17:23:05.019] <TB3> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1037_FullQualification_2016-10-26_16h26m_1477492000//001_Fulltest_m20//readbackCal_C12.dat
[17:23:05.019] <TB3> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1037_FullQualification_2016-10-26_16h26m_1477492000//001_Fulltest_m20//readbackCal_C13.dat
[17:23:05.019] <TB3> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1037_FullQualification_2016-10-26_16h26m_1477492000//001_Fulltest_m20//readbackCal_C14.dat
[17:23:05.019] <TB3> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1037_FullQualification_2016-10-26_16h26m_1477492000//001_Fulltest_m20//readbackCal_C15.dat
[17:23:05.047] <TB3> INFO: PixTestPattern:: pg_setup set to default.
[17:23:05.047] <TB3> INFO: ----------------------------------------------------------------------
[17:23:05.047] <TB3> INFO: PixTestReadback::readbackVbg()
[17:23:05.047] <TB3> INFO: ----------------------------------------------------------------------
[17:23:12.689] <TB3> INFO: PixTestPattern:: pg_setup set to default.
[17:23:12.689] <TB3> INFO: ----------------------------------------------------------------------
[17:23:12.689] <TB3> INFO: PixTestReadback::getCalibratedVbg()
[17:23:12.689] <TB3> INFO: ----------------------------------------------------------------------
[17:23:12.689] <TB3> INFO: Vbg will be calibrated using Vd calibration
[17:23:12.689] <TB3> INFO: /*/*/*/*::: ROC 0: uncalibrated Vbg = 175.9calibrated Vbg = 1.15785 :::*/*/*/*/
[17:23:12.689] <TB3> INFO: /*/*/*/*::: ROC 1: uncalibrated Vbg = 143.8calibrated Vbg = 1.1585 :::*/*/*/*/
[17:23:12.689] <TB3> INFO: /*/*/*/*::: ROC 2: uncalibrated Vbg = 148.2calibrated Vbg = 1.15146 :::*/*/*/*/
[17:23:12.689] <TB3> INFO: /*/*/*/*::: ROC 3: uncalibrated Vbg = 157calibrated Vbg = 1.15132 :::*/*/*/*/
[17:23:12.689] <TB3> INFO: /*/*/*/*::: ROC 4: uncalibrated Vbg = 159.1calibrated Vbg = 1.1499 :::*/*/*/*/
[17:23:12.689] <TB3> INFO: /*/*/*/*::: ROC 5: uncalibrated Vbg = 153.7calibrated Vbg = 1.15633 :::*/*/*/*/
[17:23:12.689] <TB3> INFO: /*/*/*/*::: ROC 6: uncalibrated Vbg = 155.1calibrated Vbg = 1.16009 :::*/*/*/*/
[17:23:12.689] <TB3> INFO: /*/*/*/*::: ROC 7: uncalibrated Vbg = 153.3calibrated Vbg = 1.16674 :::*/*/*/*/
[17:23:12.689] <TB3> INFO: /*/*/*/*::: ROC 8: uncalibrated Vbg = 154.1calibrated Vbg = 1.1581 :::*/*/*/*/
[17:23:12.689] <TB3> INFO: /*/*/*/*::: ROC 9: uncalibrated Vbg = 154.5calibrated Vbg = 1.14796 :::*/*/*/*/
[17:23:12.689] <TB3> INFO: /*/*/*/*::: ROC 10: uncalibrated Vbg = 168.8calibrated Vbg = 1.14867 :::*/*/*/*/
[17:23:12.689] <TB3> INFO: /*/*/*/*::: ROC 11: uncalibrated Vbg = 153calibrated Vbg = 1.14231 :::*/*/*/*/
[17:23:12.689] <TB3> INFO: /*/*/*/*::: ROC 12: uncalibrated Vbg = 158.4calibrated Vbg = 1.14425 :::*/*/*/*/
[17:23:12.689] <TB3> INFO: /*/*/*/*::: ROC 13: uncalibrated Vbg = 156.9calibrated Vbg = 1.15402 :::*/*/*/*/
[17:23:12.689] <TB3> INFO: /*/*/*/*::: ROC 14: uncalibrated Vbg = 163.4calibrated Vbg = 1.15257 :::*/*/*/*/
[17:23:12.689] <TB3> INFO: /*/*/*/*::: ROC 15: uncalibrated Vbg = 150.4calibrated Vbg = 1.15513 :::*/*/*/*/
[17:23:12.691] <TB3> INFO: ----------------------------------------------------------------------
[17:23:12.691] <TB3> INFO: PixTestReadback::CalibrateIa()
[17:23:12.691] <TB3> INFO: ----------------------------------------------------------------------
[17:25:53.012] <TB3> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1037_FullQualification_2016-10-26_16h26m_1477492000//001_Fulltest_m20//readbackCal_C0.dat
[17:25:53.012] <TB3> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1037_FullQualification_2016-10-26_16h26m_1477492000//001_Fulltest_m20//readbackCal_C1.dat
[17:25:53.012] <TB3> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1037_FullQualification_2016-10-26_16h26m_1477492000//001_Fulltest_m20//readbackCal_C2.dat
[17:25:53.012] <TB3> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1037_FullQualification_2016-10-26_16h26m_1477492000//001_Fulltest_m20//readbackCal_C3.dat
[17:25:53.012] <TB3> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1037_FullQualification_2016-10-26_16h26m_1477492000//001_Fulltest_m20//readbackCal_C4.dat
[17:25:53.012] <TB3> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1037_FullQualification_2016-10-26_16h26m_1477492000//001_Fulltest_m20//readbackCal_C5.dat
[17:25:53.013] <TB3> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1037_FullQualification_2016-10-26_16h26m_1477492000//001_Fulltest_m20//readbackCal_C6.dat
[17:25:53.013] <TB3> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1037_FullQualification_2016-10-26_16h26m_1477492000//001_Fulltest_m20//readbackCal_C7.dat
[17:25:53.013] <TB3> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1037_FullQualification_2016-10-26_16h26m_1477492000//001_Fulltest_m20//readbackCal_C8.dat
[17:25:53.013] <TB3> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1037_FullQualification_2016-10-26_16h26m_1477492000//001_Fulltest_m20//readbackCal_C9.dat
[17:25:53.013] <TB3> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1037_FullQualification_2016-10-26_16h26m_1477492000//001_Fulltest_m20//readbackCal_C10.dat
[17:25:53.013] <TB3> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1037_FullQualification_2016-10-26_16h26m_1477492000//001_Fulltest_m20//readbackCal_C11.dat
[17:25:53.013] <TB3> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1037_FullQualification_2016-10-26_16h26m_1477492000//001_Fulltest_m20//readbackCal_C12.dat
[17:25:53.013] <TB3> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1037_FullQualification_2016-10-26_16h26m_1477492000//001_Fulltest_m20//readbackCal_C13.dat
[17:25:53.013] <TB3> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1037_FullQualification_2016-10-26_16h26m_1477492000//001_Fulltest_m20//readbackCal_C14.dat
[17:25:53.013] <TB3> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1037_FullQualification_2016-10-26_16h26m_1477492000//001_Fulltest_m20//readbackCal_C15.dat
[17:25:53.041] <TB3> INFO: PixTestPattern:: pg_setup set to default.
[17:25:53.042] <TB3> INFO: PixTestReadback::doTest() done
[17:25:53.042] <TB3> INFO: Decoding statistics:
[17:25:53.042] <TB3> INFO: General information:
[17:25:53.042] <TB3> INFO: 16bit words read: 1536
[17:25:53.042] <TB3> INFO: valid events total: 256
[17:25:53.042] <TB3> INFO: empty events: 256
[17:25:53.042] <TB3> INFO: valid events with pixels: 0
[17:25:53.042] <TB3> INFO: valid pixel hits: 0
[17:25:53.042] <TB3> INFO: Event errors: 0
[17:25:53.042] <TB3> INFO: start marker: 0
[17:25:53.042] <TB3> INFO: stop marker: 0
[17:25:53.042] <TB3> INFO: overflow: 0
[17:25:53.042] <TB3> INFO: invalid 5bit words: 0
[17:25:53.042] <TB3> INFO: invalid XOR eye diagram: 0
[17:25:53.042] <TB3> INFO: frame (failed synchr.): 0
[17:25:53.042] <TB3> INFO: idle data (no TBM trl): 0
[17:25:53.042] <TB3> INFO: no data (only TBM hdr): 0
[17:25:53.042] <TB3> INFO: TBM errors: 0
[17:25:53.042] <TB3> INFO: flawed TBM headers: 0
[17:25:53.042] <TB3> INFO: flawed TBM trailers: 0
[17:25:53.042] <TB3> INFO: event ID mismatches: 0
[17:25:53.042] <TB3> INFO: ROC errors: 0
[17:25:53.042] <TB3> INFO: missing ROC header(s): 0
[17:25:53.042] <TB3> INFO: misplaced readback start: 0
[17:25:53.043] <TB3> INFO: Pixel decoding errors: 0
[17:25:53.043] <TB3> INFO: pixel data incomplete: 0
[17:25:53.043] <TB3> INFO: pixel address: 0
[17:25:53.043] <TB3> INFO: pulse height fill bit: 0
[17:25:53.043] <TB3> INFO: buffer corruption: 0
[17:25:53.076] <TB3> INFO: ######################################################################
[17:25:53.076] <TB3> INFO: PixTestBBMap::doTest() Ntrig = 5, VcalS = 250 (high range)
[17:25:53.076] <TB3> INFO: ######################################################################
[17:25:53.078] <TB3> INFO: ---> dac: VthrComp name: calSMap ntrig: 5 dacrange: 0 .. 149 (-1/-1) hits flags = 514 (plus default)
[17:25:53.089] <TB3> INFO: dacScan split into 1 runs with ntrig = 5
[17:25:53.089] <TB3> INFO: run 1 of 1
[17:25:53.321] <TB3> INFO: Expecting 3120000 events.
[17:26:23.480] <TB3> INFO: 659600 events read in total (29567ms).











[17:26:52.817] <TB3> INFO: 1310320 events read in total (58904ms).











[17:27:22.256] <TB3> INFO: 1958810 events read in total (88343ms).
[17:27:51.932] <TB3> INFO: 2609325 events read in total (118019ms).











[17:28:15.869] <TB3> INFO: 3120000 events read in total (141956ms).
[17:28:15.929] <TB3> INFO: Test took 142841ms.
[17:28:40.783] <TB3> INFO: PixTestBBMap::doTest() done with 1 decoding errors: , duration: 167 seconds
[17:28:40.783] <TB3> INFO: number of dead bumps (per ROC): 0 1 0 2 0 0 0 0 2 0 0 0 0 0 0 0
[17:28:40.783] <TB3> INFO: separation cut (per ROC): 110 98 96 110 109 93 88 83 88 102 102 100 100 107 100 92
[17:28:40.783] <TB3> INFO: Decoding statistics:
[17:28:40.783] <TB3> INFO: General information:
[17:28:40.783] <TB3> INFO: 16bit words read: 0
[17:28:40.783] <TB3> INFO: valid events total: 0
[17:28:40.783] <TB3> INFO: empty events: 0
[17:28:40.783] <TB3> INFO: valid events with pixels: 0
[17:28:40.783] <TB3> INFO: valid pixel hits: 0
[17:28:40.783] <TB3> INFO: Event errors: 0
[17:28:40.783] <TB3> INFO: start marker: 0
[17:28:40.783] <TB3> INFO: stop marker: 0
[17:28:40.783] <TB3> INFO: overflow: 0
[17:28:40.783] <TB3> INFO: invalid 5bit words: 0
[17:28:40.783] <TB3> INFO: invalid XOR eye diagram: 0
[17:28:40.783] <TB3> INFO: frame (failed synchr.): 0
[17:28:40.783] <TB3> INFO: idle data (no TBM trl): 0
[17:28:40.783] <TB3> INFO: no data (only TBM hdr): 0
[17:28:40.783] <TB3> INFO: TBM errors: 0
[17:28:40.783] <TB3> INFO: flawed TBM headers: 0
[17:28:40.783] <TB3> INFO: flawed TBM trailers: 0
[17:28:40.783] <TB3> INFO: event ID mismatches: 0
[17:28:40.783] <TB3> INFO: ROC errors: 0
[17:28:40.783] <TB3> INFO: missing ROC header(s): 0
[17:28:40.783] <TB3> INFO: misplaced readback start: 0
[17:28:40.783] <TB3> INFO: Pixel decoding errors: 0
[17:28:40.783] <TB3> INFO: pixel data incomplete: 0
[17:28:40.783] <TB3> INFO: pixel address: 0
[17:28:40.783] <TB3> INFO: pulse height fill bit: 0
[17:28:40.783] <TB3> INFO: buffer corruption: 0
[17:28:40.819] <TB3> INFO: ######################################################################
[17:28:40.819] <TB3> INFO: PixTestScurves::fullTest() ntrig = 50, dacs/step = -1, ntrig/step = -1
[17:28:40.819] <TB3> INFO: ######################################################################
[17:28:40.819] <TB3> INFO: ----------------------------------------------------------------------
[17:28:40.819] <TB3> INFO: PixTestScurves::scurves(Vcal), ntrig = 50, dacs/step = -1, ntrig/step = -1
[17:28:40.819] <TB3> INFO: ----------------------------------------------------------------------
[17:28:40.819] <TB3> INFO: ---> dac: Vcal name: scurveVcal ntrig: 50 dacrange: 25 .. 200 (-1/-1) hits flags = 528 (plus default)
[17:28:40.831] <TB3> INFO: dacScan split into 1 runs with ntrig = 50
[17:28:40.831] <TB3> INFO: run 1 of 1
[17:28:41.084] <TB3> INFO: Expecting 36608000 events.
[17:29:04.029] <TB3> INFO: 663700 events read in total (22350ms).
[17:29:26.366] <TB3> INFO: 1316000 events read in total (44687ms).
[17:29:48.539] <TB3> INFO: 1966700 events read in total (66861ms).
[17:30:10.593] <TB3> INFO: 2617050 events read in total (88914ms).
[17:30:32.781] <TB3> INFO: 3265950 events read in total (111102ms).
[17:30:55.115] <TB3> INFO: 3914600 events read in total (133436ms).
[17:31:17.354] <TB3> INFO: 4564550 events read in total (155675ms).
[17:31:39.687] <TB3> INFO: 5215000 events read in total (178008ms).
[17:32:01.658] <TB3> INFO: 5864050 events read in total (199979ms).
[17:32:23.622] <TB3> INFO: 6511450 events read in total (221943ms).
[17:32:45.778] <TB3> INFO: 7159450 events read in total (244099ms).
[17:33:07.754] <TB3> INFO: 7805600 events read in total (266075ms).
[17:33:30.099] <TB3> INFO: 8452700 events read in total (288420ms).
[17:33:52.179] <TB3> INFO: 9100100 events read in total (310500ms).
[17:34:14.066] <TB3> INFO: 9747800 events read in total (332387ms).
[17:34:36.109] <TB3> INFO: 10396850 events read in total (354430ms).
[17:34:58.079] <TB3> INFO: 11043900 events read in total (376400ms).
[17:35:20.336] <TB3> INFO: 11692500 events read in total (398657ms).
[17:35:42.547] <TB3> INFO: 12340550 events read in total (420868ms).
[17:36:04.807] <TB3> INFO: 12987700 events read in total (443128ms).
[17:36:27.106] <TB3> INFO: 13633500 events read in total (465427ms).
[17:36:49.190] <TB3> INFO: 14278450 events read in total (487511ms).
[17:37:11.329] <TB3> INFO: 14922250 events read in total (509650ms).
[17:37:33.455] <TB3> INFO: 15565550 events read in total (531776ms).
[17:37:55.548] <TB3> INFO: 16207800 events read in total (553869ms).
[17:38:17.622] <TB3> INFO: 16850900 events read in total (575943ms).
[17:38:39.756] <TB3> INFO: 17498150 events read in total (598078ms).
[17:39:01.730] <TB3> INFO: 18141850 events read in total (620051ms).
[17:39:23.780] <TB3> INFO: 18784550 events read in total (642101ms).
[17:39:45.849] <TB3> INFO: 19427400 events read in total (664170ms).
[17:40:08.145] <TB3> INFO: 20070200 events read in total (686466ms).
[17:40:30.452] <TB3> INFO: 20712600 events read in total (708773ms).
[17:40:52.577] <TB3> INFO: 21354500 events read in total (730899ms).
[17:41:14.662] <TB3> INFO: 21994500 events read in total (752983ms).
[17:41:36.785] <TB3> INFO: 22632200 events read in total (775106ms).
[17:41:58.874] <TB3> INFO: 23272850 events read in total (797195ms).
[17:42:21.016] <TB3> INFO: 23911250 events read in total (819337ms).
[17:42:43.102] <TB3> INFO: 24550750 events read in total (841423ms).
[17:43:05.060] <TB3> INFO: 25189600 events read in total (863381ms).
[17:43:27.112] <TB3> INFO: 25829750 events read in total (885433ms).
[17:43:49.105] <TB3> INFO: 26470100 events read in total (907426ms).
[17:44:11.268] <TB3> INFO: 27111400 events read in total (929589ms).
[17:44:33.685] <TB3> INFO: 27752450 events read in total (952006ms).
[17:44:55.715] <TB3> INFO: 28392000 events read in total (974036ms).
[17:45:17.613] <TB3> INFO: 29031350 events read in total (995934ms).
[17:45:39.900] <TB3> INFO: 29670100 events read in total (1018221ms).
[17:46:01.910] <TB3> INFO: 30305700 events read in total (1040231ms).
[17:46:23.937] <TB3> INFO: 30943300 events read in total (1062258ms).
[17:46:45.628] <TB3> INFO: 31581800 events read in total (1083949ms).
[17:47:07.608] <TB3> INFO: 32221750 events read in total (1105929ms).
[17:47:29.654] <TB3> INFO: 32862850 events read in total (1127975ms).
[17:47:51.532] <TB3> INFO: 33505500 events read in total (1149853ms).
[17:48:13.707] <TB3> INFO: 34147500 events read in total (1172028ms).
[17:48:35.479] <TB3> INFO: 34787600 events read in total (1193800ms).
[17:48:57.511] <TB3> INFO: 35428300 events read in total (1215832ms).
[17:49:19.797] <TB3> INFO: 36072150 events read in total (1238118ms).
[17:49:38.280] <TB3> INFO: 36608000 events read in total (1256601ms).
[17:49:38.340] <TB3> INFO: Test took 1257509ms.
[17:49:38.800] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[17:49:40.822] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[17:49:42.609] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[17:49:44.577] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[17:49:46.520] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[17:49:48.357] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[17:49:50.329] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[17:49:52.212] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[17:49:54.187] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[17:49:55.971] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[17:49:58.018] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[17:50:00.053] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[17:50:01.906] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[17:50:03.743] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[17:50:05.562] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[17:50:07.219] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[17:50:08.777] <TB3> INFO: PixTestScurves::scurves() done
[17:50:08.777] <TB3> INFO: Vcal mean: 129.77 113.76 115.33 111.31 113.56 98.11 101.02 107.47 99.84 102.45 111.37 106.94 120.81 118.45 114.35 102.68
[17:50:08.777] <TB3> INFO: Vcal RMS: 6.64 6.22 6.37 5.63 4.78 4.86 5.17 6.06 5.99 5.63 5.40 4.87 6.86 6.16 5.83 5.35
[17:50:08.777] <TB3> INFO: PixTestScurves::fullTest() done, duration: 1287 seconds
[17:50:08.777] <TB3> INFO: Decoding statistics:
[17:50:08.777] <TB3> INFO: General information:
[17:50:08.777] <TB3> INFO: 16bit words read: 0
[17:50:08.777] <TB3> INFO: valid events total: 0
[17:50:08.778] <TB3> INFO: empty events: 0
[17:50:08.778] <TB3> INFO: valid events with pixels: 0
[17:50:08.778] <TB3> INFO: valid pixel hits: 0
[17:50:08.778] <TB3> INFO: Event errors: 0
[17:50:08.778] <TB3> INFO: start marker: 0
[17:50:08.778] <TB3> INFO: stop marker: 0
[17:50:08.778] <TB3> INFO: overflow: 0
[17:50:08.778] <TB3> INFO: invalid 5bit words: 0
[17:50:08.778] <TB3> INFO: invalid XOR eye diagram: 0
[17:50:08.778] <TB3> INFO: frame (failed synchr.): 0
[17:50:08.778] <TB3> INFO: idle data (no TBM trl): 0
[17:50:08.778] <TB3> INFO: no data (only TBM hdr): 0
[17:50:08.778] <TB3> INFO: TBM errors: 0
[17:50:08.778] <TB3> INFO: flawed TBM headers: 0
[17:50:08.778] <TB3> INFO: flawed TBM trailers: 0
[17:50:08.778] <TB3> INFO: event ID mismatches: 0
[17:50:08.778] <TB3> INFO: ROC errors: 0
[17:50:08.778] <TB3> INFO: missing ROC header(s): 0
[17:50:08.778] <TB3> INFO: misplaced readback start: 0
[17:50:08.778] <TB3> INFO: Pixel decoding errors: 0
[17:50:08.778] <TB3> INFO: pixel data incomplete: 0
[17:50:08.778] <TB3> INFO: pixel address: 0
[17:50:08.778] <TB3> INFO: pulse height fill bit: 0
[17:50:08.778] <TB3> INFO: buffer corruption: 0
[17:50:08.851] <TB3> INFO: ######################################################################
[17:50:08.851] <TB3> INFO: PixTestTrim::doTest()
[17:50:08.851] <TB3> INFO: ######################################################################
[17:50:08.852] <TB3> INFO: ----------------------------------------------------------------------
[17:50:08.852] <TB3> INFO: PixTestTrim::trimTest() ntrig = 8, vcal = 35
[17:50:08.852] <TB3> INFO: ----------------------------------------------------------------------
[17:50:08.892] <TB3> INFO: ---> VthrComp thr map (minimal VthrComp)
[17:50:08.892] <TB3> INFO: ---> dac: vthrcomp name: TrimThr0 ntrig: 8 dacrange: 10 .. 160 (-1/-1) hits flags = 528 (plus default)
[17:50:08.901] <TB3> INFO: dacScan split into 1 runs with ntrig = 8
[17:50:08.901] <TB3> INFO: run 1 of 1
[17:50:09.134] <TB3> INFO: Expecting 5025280 events.
[17:50:39.747] <TB3> INFO: 819912 events read in total (30020ms).
[17:51:09.764] <TB3> INFO: 1636240 events read in total (60037ms).
[17:51:39.488] <TB3> INFO: 2448968 events read in total (89762ms).
[17:52:08.905] <TB3> INFO: 3259848 events read in total (119178ms).
[17:52:38.499] <TB3> INFO: 4066528 events read in total (148772ms).
[17:53:08.327] <TB3> INFO: 4870120 events read in total (178600ms).
[17:53:14.366] <TB3> INFO: 5025280 events read in total (184639ms).
[17:53:14.415] <TB3> INFO: Test took 185515ms.
[17:53:33.808] <TB3> INFO: ROC 0 VthrComp = 133
[17:53:33.808] <TB3> INFO: ROC 1 VthrComp = 110
[17:53:33.808] <TB3> INFO: ROC 2 VthrComp = 115
[17:53:33.808] <TB3> INFO: ROC 3 VthrComp = 121
[17:53:33.808] <TB3> INFO: ROC 4 VthrComp = 124
[17:53:33.808] <TB3> INFO: ROC 5 VthrComp = 101
[17:53:33.808] <TB3> INFO: ROC 6 VthrComp = 102
[17:53:33.809] <TB3> INFO: ROC 7 VthrComp = 104
[17:53:33.809] <TB3> INFO: ROC 8 VthrComp = 100
[17:53:33.809] <TB3> INFO: ROC 9 VthrComp = 107
[17:53:33.809] <TB3> INFO: ROC 10 VthrComp = 114
[17:53:33.809] <TB3> INFO: ROC 11 VthrComp = 112
[17:53:33.809] <TB3> INFO: ROC 12 VthrComp = 118
[17:53:33.809] <TB3> INFO: ROC 13 VthrComp = 122
[17:53:33.809] <TB3> INFO: ROC 14 VthrComp = 114
[17:53:33.810] <TB3> INFO: ROC 15 VthrComp = 105
[17:53:34.043] <TB3> INFO: Expecting 41600 events.
[17:53:37.466] <TB3> INFO: 41600 events read in total (2832ms).
[17:53:37.467] <TB3> INFO: Test took 3656ms.
[17:53:37.477] <TB3> INFO: ---> Vcal thr map (pixel with maximum Vcal thr)
[17:53:37.477] <TB3> INFO: ---> dac: vcal name: TrimThr1 ntrig: 8 dacrange: 10 .. 160 (-1/-1) hits flags = 528 (plus default)
[17:53:37.488] <TB3> INFO: dacScan split into 1 runs with ntrig = 8
[17:53:37.488] <TB3> INFO: run 1 of 1
[17:53:37.766] <TB3> INFO: Expecting 5025280 events.
[17:54:03.928] <TB3> INFO: 590032 events read in total (25571ms).
[17:54:29.269] <TB3> INFO: 1178784 events read in total (50913ms).
[17:54:54.588] <TB3> INFO: 1768256 events read in total (76231ms).
[17:55:20.049] <TB3> INFO: 2358008 events read in total (101692ms).
[17:55:45.137] <TB3> INFO: 2945648 events read in total (126780ms).
[17:56:10.211] <TB3> INFO: 3532584 events read in total (151854ms).
[17:56:35.634] <TB3> INFO: 4117928 events read in total (177277ms).
[17:57:01.337] <TB3> INFO: 4703168 events read in total (202980ms).
[17:57:15.601] <TB3> INFO: 5025280 events read in total (217244ms).
[17:57:15.661] <TB3> INFO: Test took 218173ms.
[17:57:42.577] <TB3> INFO: roc 0 with ID = 0 has maximal Vcal 64.6367 for pixel 0/0 mean/min/max = 49.0534/33.195/64.9119
[17:57:42.578] <TB3> INFO: roc 1 with ID = 1 has maximal Vcal 67.4445 for pixel 0/4 mean/min/max = 50.0533/32.4469/67.6598
[17:57:42.578] <TB3> INFO: roc 2 with ID = 2 has maximal Vcal 65.2168 for pixel 3/6 mean/min/max = 47.527/29.7645/65.2894
[17:57:42.579] <TB3> INFO: roc 3 with ID = 3 has maximal Vcal 59.7502 for pixel 0/38 mean/min/max = 45.4844/30.9336/60.0351
[17:57:42.579] <TB3> INFO: roc 4 with ID = 4 has maximal Vcal 58.0628 for pixel 22/32 mean/min/max = 45.1082/31.9898/58.2265
[17:57:42.579] <TB3> INFO: roc 5 with ID = 5 has maximal Vcal 60.0488 for pixel 4/10 mean/min/max = 46.4968/32.7681/60.2255
[17:57:42.580] <TB3> INFO: roc 6 with ID = 6 has maximal Vcal 61.9351 for pixel 16/2 mean/min/max = 47.9642/33.6883/62.2401
[17:57:42.580] <TB3> INFO: roc 7 with ID = 7 has maximal Vcal 66.1958 for pixel 6/2 mean/min/max = 49.4404/32.6081/66.2727
[17:57:42.580] <TB3> INFO: roc 8 with ID = 8 has maximal Vcal 65.236 for pixel 13/8 mean/min/max = 48.8138/32.3571/65.2705
[17:57:42.581] <TB3> INFO: roc 9 with ID = 9 has maximal Vcal 64.6984 for pixel 4/0 mean/min/max = 48.9509/32.8595/65.0422
[17:57:42.581] <TB3> INFO: roc 10 with ID = 10 has maximal Vcal 62.5126 for pixel 27/12 mean/min/max = 47.0167/31.3144/62.719
[17:57:42.582] <TB3> INFO: roc 11 with ID = 11 has maximal Vcal 59.5809 for pixel 37/27 mean/min/max = 45.9642/32.2885/59.6399
[17:57:42.582] <TB3> INFO: roc 12 with ID = 12 has maximal Vcal 66.7346 for pixel 3/2 mean/min/max = 48.6186/30.3175/66.9198
[17:57:42.582] <TB3> INFO: roc 13 with ID = 13 has maximal Vcal 65.1529 for pixel 0/14 mean/min/max = 47.6693/29.8775/65.4611
[17:57:42.582] <TB3> INFO: roc 14 with ID = 14 has maximal Vcal 65.4083 for pixel 1/13 mean/min/max = 47.8491/30.2337/65.4644
[17:57:42.583] <TB3> INFO: roc 15 with ID = 15 has maximal Vcal 60.3676 for pixel 19/76 mean/min/max = 47.5479/34.3793/60.7164
[17:57:42.583] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[17:57:42.672] <TB3> INFO: Expecting 411648 events.
[17:57:51.948] <TB3> INFO: 411648 events read in total (8685ms).
[17:57:51.956] <TB3> INFO: Expecting 411648 events.
[17:58:01.027] <TB3> INFO: 411648 events read in total (8668ms).
[17:58:01.035] <TB3> INFO: Expecting 411648 events.
[17:58:10.066] <TB3> INFO: 411648 events read in total (8628ms).
[17:58:10.082] <TB3> INFO: Expecting 411648 events.
[17:58:19.143] <TB3> INFO: 411648 events read in total (8657ms).
[17:58:19.163] <TB3> INFO: Expecting 411648 events.
[17:58:28.260] <TB3> INFO: 411648 events read in total (8694ms).
[17:58:28.279] <TB3> INFO: Expecting 411648 events.
[17:58:37.363] <TB3> INFO: 411648 events read in total (8681ms).
[17:58:37.384] <TB3> INFO: Expecting 411648 events.
[17:58:46.370] <TB3> INFO: 411648 events read in total (8583ms).
[17:58:46.393] <TB3> INFO: Expecting 411648 events.
[17:58:55.361] <TB3> INFO: 411648 events read in total (8565ms).
[17:58:55.387] <TB3> INFO: Expecting 411648 events.
[17:59:04.476] <TB3> INFO: 411648 events read in total (8686ms).
[17:59:04.505] <TB3> INFO: Expecting 411648 events.
[17:59:13.573] <TB3> INFO: 411648 events read in total (8665ms).
[17:59:13.617] <TB3> INFO: Expecting 411648 events.
[17:59:22.632] <TB3> INFO: 411648 events read in total (8612ms).
[17:59:22.674] <TB3> INFO: Expecting 411648 events.
[17:59:31.738] <TB3> INFO: 411648 events read in total (8661ms).
[17:59:31.784] <TB3> INFO: Expecting 411648 events.
[17:59:40.912] <TB3> INFO: 411648 events read in total (8725ms).
[17:59:40.957] <TB3> INFO: Expecting 411648 events.
[17:59:50.004] <TB3> INFO: 411648 events read in total (8644ms).
[17:59:50.050] <TB3> INFO: Expecting 411648 events.
[17:59:59.113] <TB3> INFO: 411648 events read in total (8660ms).
[17:59:59.173] <TB3> INFO: Expecting 411648 events.
[18:00:08.251] <TB3> INFO: 411648 events read in total (8675ms).
[18:00:08.317] <TB3> INFO: Test took 145734ms.
[18:00:09.038] <TB3> INFO: ---> dac: vcal name: TrimThr2 ntrig: 8 dacrange: 0 .. 150 (-1/-1) hits flags = 528 (plus default)
[18:00:09.047] <TB3> INFO: dacScan split into 1 runs with ntrig = 8
[18:00:09.047] <TB3> INFO: run 1 of 1
[18:00:09.280] <TB3> INFO: Expecting 5025280 events.
[18:00:35.186] <TB3> INFO: 591864 events read in total (25314ms).
[18:01:00.762] <TB3> INFO: 1182928 events read in total (50890ms).
[18:01:26.115] <TB3> INFO: 1773280 events read in total (76243ms).
[18:01:51.641] <TB3> INFO: 2361024 events read in total (101769ms).
[18:02:16.845] <TB3> INFO: 2949696 events read in total (126973ms).
[18:02:42.106] <TB3> INFO: 3536936 events read in total (152234ms).
[18:03:07.341] <TB3> INFO: 4124216 events read in total (177469ms).
[18:03:33.400] <TB3> INFO: 4709952 events read in total (203528ms).
[18:03:47.318] <TB3> INFO: 5025280 events read in total (217446ms).
[18:03:47.430] <TB3> INFO: Test took 218384ms.
[18:04:11.249] <TB3> INFO: ---> TrimStepCorr4 extremal thresholds: 0.737928 .. 147.013132
[18:04:11.485] <TB3> INFO: Expecting 208000 events.
[18:04:20.898] <TB3> INFO: 208000 events read in total (8821ms).
[18:04:20.899] <TB3> INFO: Test took 9648ms.
[18:04:20.944] <TB3> INFO: ---> dac: vcal name: trimStepCorr4 ntrig: 8 dacrange: 0 .. 157 (-1/-1) hits flags = 528 (plus default)
[18:04:20.954] <TB3> INFO: dacScan split into 1 runs with ntrig = 8
[18:04:20.954] <TB3> INFO: run 1 of 1
[18:04:21.232] <TB3> INFO: Expecting 5258240 events.
[18:04:47.338] <TB3> INFO: 585472 events read in total (25514ms).
[18:05:12.859] <TB3> INFO: 1171160 events read in total (51035ms).
[18:05:38.362] <TB3> INFO: 1756184 events read in total (76538ms).
[18:06:04.201] <TB3> INFO: 2341408 events read in total (102377ms).
[18:06:29.832] <TB3> INFO: 2926152 events read in total (128009ms).
[18:06:55.216] <TB3> INFO: 3510536 events read in total (153392ms).
[18:07:20.503] <TB3> INFO: 4093896 events read in total (178679ms).
[18:07:46.159] <TB3> INFO: 4677440 events read in total (204335ms).
[18:08:11.205] <TB3> INFO: 5258240 events read in total (229381ms).
[18:08:11.294] <TB3> INFO: Test took 230341ms.
[18:08:39.327] <TB3> INFO: ---> TrimStepCorr2 extremal thresholds: 27.187122 .. 48.968871
[18:08:39.561] <TB3> INFO: Expecting 208000 events.
[18:08:49.143] <TB3> INFO: 208000 events read in total (8990ms).
[18:08:49.144] <TB3> INFO: Test took 9815ms.
[18:08:49.219] <TB3> INFO: ---> dac: vcal name: trimStepCorr2 ntrig: 8 dacrange: 17 .. 58 (-1/-1) hits flags = 528 (plus default)
[18:08:49.229] <TB3> INFO: dacScan split into 1 runs with ntrig = 8
[18:08:49.229] <TB3> INFO: run 1 of 1
[18:08:49.507] <TB3> INFO: Expecting 1397760 events.
[18:09:17.310] <TB3> INFO: 648920 events read in total (27211ms).
[18:09:44.602] <TB3> INFO: 1295192 events read in total (54504ms).
[18:09:49.257] <TB3> INFO: 1397760 events read in total (59158ms).
[18:09:49.283] <TB3> INFO: Test took 60054ms.
[18:10:02.599] <TB3> INFO: ---> TrimStepCorr1a extremal thresholds: 25.487642 .. 47.016902
[18:10:02.873] <TB3> INFO: Expecting 208000 events.
[18:10:12.582] <TB3> INFO: 208000 events read in total (9118ms).
[18:10:12.583] <TB3> INFO: Test took 9983ms.
[18:10:12.628] <TB3> INFO: ---> dac: vcal name: trimStepCorr1a ntrig: 8 dacrange: 15 .. 57 (-1/-1) hits flags = 528 (plus default)
[18:10:12.637] <TB3> INFO: dacScan split into 1 runs with ntrig = 8
[18:10:12.637] <TB3> INFO: run 1 of 1
[18:10:12.915] <TB3> INFO: Expecting 1431040 events.
[18:10:41.076] <TB3> INFO: 660256 events read in total (27569ms).
[18:11:08.525] <TB3> INFO: 1319512 events read in total (55018ms).
[18:11:13.473] <TB3> INFO: 1431040 events read in total (59966ms).
[18:11:13.497] <TB3> INFO: Test took 60861ms.
[18:11:27.338] <TB3> INFO: ---> TrimStepCorr1b extremal thresholds: 24.262778 .. 44.913825
[18:11:27.572] <TB3> INFO: Expecting 208000 events.
[18:11:37.367] <TB3> INFO: 208000 events read in total (9203ms).
[18:11:37.368] <TB3> INFO: Test took 10028ms.
[18:11:37.414] <TB3> INFO: ---> dac: vcal name: trimStepCorr1b ntrig: 8 dacrange: 14 .. 54 (-1/-1) hits flags = 528 (plus default)
[18:11:37.424] <TB3> INFO: dacScan split into 1 runs with ntrig = 8
[18:11:37.424] <TB3> INFO: run 1 of 1
[18:11:37.703] <TB3> INFO: Expecting 1364480 events.
[18:12:05.650] <TB3> INFO: 675304 events read in total (27356ms).
[18:12:33.423] <TB3> INFO: 1350440 events read in total (55129ms).
[18:12:34.493] <TB3> INFO: 1364480 events read in total (56200ms).
[18:12:34.515] <TB3> INFO: Test took 57090ms.
[18:12:48.339] <TB3> INFO: ---> TrimThrFinal extremal thresholds: 15 .. 55
[18:12:48.339] <TB3> INFO: ---> dac: vcal name: TrimThrFinal ntrig: 8 dacrange: 15 .. 55 (-1/-1) hits flags = 528 (plus default)
[18:12:48.348] <TB3> INFO: dacScan split into 1 runs with ntrig = 8
[18:12:48.348] <TB3> INFO: run 1 of 1
[18:12:48.579] <TB3> INFO: Expecting 1364480 events.
[18:13:16.253] <TB3> INFO: 667480 events read in total (27082ms).
[18:13:44.138] <TB3> INFO: 1334552 events read in total (54968ms).
[18:13:45.756] <TB3> INFO: 1364480 events read in total (56586ms).
[18:13:45.786] <TB3> INFO: Test took 57438ms.
[18:13:59.012] <TB3> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1037_FullQualification_2016-10-26_16h26m_1477492000//001_Fulltest_m20//dacParameters35_C0.dat
[18:13:59.012] <TB3> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1037_FullQualification_2016-10-26_16h26m_1477492000//001_Fulltest_m20//dacParameters35_C1.dat
[18:13:59.012] <TB3> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1037_FullQualification_2016-10-26_16h26m_1477492000//001_Fulltest_m20//dacParameters35_C2.dat
[18:13:59.012] <TB3> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1037_FullQualification_2016-10-26_16h26m_1477492000//001_Fulltest_m20//dacParameters35_C3.dat
[18:13:59.012] <TB3> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1037_FullQualification_2016-10-26_16h26m_1477492000//001_Fulltest_m20//dacParameters35_C4.dat
[18:13:59.012] <TB3> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1037_FullQualification_2016-10-26_16h26m_1477492000//001_Fulltest_m20//dacParameters35_C5.dat
[18:13:59.012] <TB3> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1037_FullQualification_2016-10-26_16h26m_1477492000//001_Fulltest_m20//dacParameters35_C6.dat
[18:13:59.012] <TB3> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1037_FullQualification_2016-10-26_16h26m_1477492000//001_Fulltest_m20//dacParameters35_C7.dat
[18:13:59.012] <TB3> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1037_FullQualification_2016-10-26_16h26m_1477492000//001_Fulltest_m20//dacParameters35_C8.dat
[18:13:59.012] <TB3> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1037_FullQualification_2016-10-26_16h26m_1477492000//001_Fulltest_m20//dacParameters35_C9.dat
[18:13:59.013] <TB3> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1037_FullQualification_2016-10-26_16h26m_1477492000//001_Fulltest_m20//dacParameters35_C10.dat
[18:13:59.013] <TB3> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1037_FullQualification_2016-10-26_16h26m_1477492000//001_Fulltest_m20//dacParameters35_C11.dat
[18:13:59.013] <TB3> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1037_FullQualification_2016-10-26_16h26m_1477492000//001_Fulltest_m20//dacParameters35_C12.dat
[18:13:59.013] <TB3> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1037_FullQualification_2016-10-26_16h26m_1477492000//001_Fulltest_m20//dacParameters35_C13.dat
[18:13:59.013] <TB3> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1037_FullQualification_2016-10-26_16h26m_1477492000//001_Fulltest_m20//dacParameters35_C14.dat
[18:13:59.013] <TB3> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1037_FullQualification_2016-10-26_16h26m_1477492000//001_Fulltest_m20//dacParameters35_C15.dat
[18:13:59.013] <TB3> INFO: write trim parameters into /home/l_tester/david/FullQualification/data/M1037_FullQualification_2016-10-26_16h26m_1477492000//001_Fulltest_m20//trimParameters35_C0.dat
[18:13:59.020] <TB3> INFO: write trim parameters into /home/l_tester/david/FullQualification/data/M1037_FullQualification_2016-10-26_16h26m_1477492000//001_Fulltest_m20//trimParameters35_C1.dat
[18:13:59.025] <TB3> INFO: write trim parameters into /home/l_tester/david/FullQualification/data/M1037_FullQualification_2016-10-26_16h26m_1477492000//001_Fulltest_m20//trimParameters35_C2.dat
[18:13:59.031] <TB3> INFO: write trim parameters into /home/l_tester/david/FullQualification/data/M1037_FullQualification_2016-10-26_16h26m_1477492000//001_Fulltest_m20//trimParameters35_C3.dat
[18:13:59.037] <TB3> INFO: write trim parameters into /home/l_tester/david/FullQualification/data/M1037_FullQualification_2016-10-26_16h26m_1477492000//001_Fulltest_m20//trimParameters35_C4.dat
[18:13:59.042] <TB3> INFO: write trim parameters into /home/l_tester/david/FullQualification/data/M1037_FullQualification_2016-10-26_16h26m_1477492000//001_Fulltest_m20//trimParameters35_C5.dat
[18:13:59.048] <TB3> INFO: write trim parameters into /home/l_tester/david/FullQualification/data/M1037_FullQualification_2016-10-26_16h26m_1477492000//001_Fulltest_m20//trimParameters35_C6.dat
[18:13:59.053] <TB3> INFO: write trim parameters into /home/l_tester/david/FullQualification/data/M1037_FullQualification_2016-10-26_16h26m_1477492000//001_Fulltest_m20//trimParameters35_C7.dat
[18:13:59.059] <TB3> INFO: write trim parameters into /home/l_tester/david/FullQualification/data/M1037_FullQualification_2016-10-26_16h26m_1477492000//001_Fulltest_m20//trimParameters35_C8.dat
[18:13:59.065] <TB3> INFO: write trim parameters into /home/l_tester/david/FullQualification/data/M1037_FullQualification_2016-10-26_16h26m_1477492000//001_Fulltest_m20//trimParameters35_C9.dat
[18:13:59.071] <TB3> INFO: write trim parameters into /home/l_tester/david/FullQualification/data/M1037_FullQualification_2016-10-26_16h26m_1477492000//001_Fulltest_m20//trimParameters35_C10.dat
[18:13:59.078] <TB3> INFO: write trim parameters into /home/l_tester/david/FullQualification/data/M1037_FullQualification_2016-10-26_16h26m_1477492000//001_Fulltest_m20//trimParameters35_C11.dat
[18:13:59.086] <TB3> INFO: write trim parameters into /home/l_tester/david/FullQualification/data/M1037_FullQualification_2016-10-26_16h26m_1477492000//001_Fulltest_m20//trimParameters35_C12.dat
[18:13:59.095] <TB3> INFO: write trim parameters into /home/l_tester/david/FullQualification/data/M1037_FullQualification_2016-10-26_16h26m_1477492000//001_Fulltest_m20//trimParameters35_C13.dat
[18:13:59.103] <TB3> INFO: write trim parameters into /home/l_tester/david/FullQualification/data/M1037_FullQualification_2016-10-26_16h26m_1477492000//001_Fulltest_m20//trimParameters35_C14.dat
[18:13:59.111] <TB3> INFO: write trim parameters into /home/l_tester/david/FullQualification/data/M1037_FullQualification_2016-10-26_16h26m_1477492000//001_Fulltest_m20//trimParameters35_C15.dat
[18:13:59.119] <TB3> INFO: PixTestTrim::trimTest() done
[18:13:59.119] <TB3> INFO: vtrim: 140 139 147 133 151 124 134 134 148 158 137 146 169 145 155 131
[18:13:59.119] <TB3> INFO: vthrcomp: 133 110 115 121 124 101 102 104 100 107 114 112 118 122 114 105
[18:13:59.119] <TB3> INFO: vcal mean: 35.05 35.07 35.04 34.91 34.98 34.91 34.99 35.04 35.02 35.02 34.98 34.99 35.12 34.98 35.06 35.05
[18:13:59.119] <TB3> INFO: vcal RMS: 1.18 1.20 1.22 1.03 1.01 1.04 1.04 1.15 1.07 1.04 1.12 1.16 1.39 1.24 1.25 1.09
[18:13:59.119] <TB3> INFO: bits mean: 8.86 8.71 10.06 9.62 10.20 9.81 9.45 8.79 9.60 9.19 9.59 10.25 10.01 10.22 10.03 9.50
[18:13:59.119] <TB3> INFO: bits RMS: 2.55 2.64 2.59 2.76 2.35 2.43 2.37 2.67 2.43 2.54 2.56 2.26 2.50 2.49 2.54 2.29
[18:13:59.126] <TB3> INFO: ----------------------------------------------------------------------
[18:13:59.126] <TB3> INFO: PixTestTrim::trimBitTest() ntrig = 5, vtrims = 254 126 63 32
[18:13:59.126] <TB3> INFO: ----------------------------------------------------------------------
[18:13:59.128] <TB3> INFO: ---> dac: Vcal name: TrimBitsThr0 ntrig: 5 dacrange: 0 .. 199 (-1/-1) hits flags = 528 (plus default)
[18:13:59.138] <TB3> INFO: dacScan split into 1 runs with ntrig = 5
[18:13:59.139] <TB3> INFO: run 1 of 1
[18:13:59.372] <TB3> INFO: Expecting 4160000 events.
[18:14:30.582] <TB3> INFO: 728095 events read in total (30619ms).
[18:15:01.190] <TB3> INFO: 1451045 events read in total (61227ms).
[18:15:31.915] <TB3> INFO: 2168875 events read in total (91952ms).
[18:16:02.342] <TB3> INFO: 2881410 events read in total (122379ms).
[18:16:32.841] <TB3> INFO: 3592735 events read in total (152878ms).
[18:16:57.954] <TB3> INFO: 4160000 events read in total (177992ms).
[18:16:58.034] <TB3> INFO: Test took 178895ms.
[18:17:27.092] <TB3> INFO: ---> dac: Vcal name: TrimThr_trim14 ntrig: 5 dacrange: 0 .. 204 (-1/-1) hits flags = 528 (plus default)
[18:17:27.102] <TB3> INFO: dacScan split into 1 runs with ntrig = 5
[18:17:27.102] <TB3> INFO: run 1 of 1
[18:17:27.352] <TB3> INFO: Expecting 4264000 events.
[18:17:58.090] <TB3> INFO: 698800 events read in total (30146ms).
[18:18:28.085] <TB3> INFO: 1393250 events read in total (60141ms).
[18:18:57.898] <TB3> INFO: 2084595 events read in total (89954ms).
[18:19:27.642] <TB3> INFO: 2772215 events read in total (119698ms).
[18:19:57.304] <TB3> INFO: 3458200 events read in total (149360ms).
[18:20:27.494] <TB3> INFO: 4144140 events read in total (179550ms).
[18:20:33.007] <TB3> INFO: 4264000 events read in total (185063ms).
[18:20:33.066] <TB3> INFO: Test took 185963ms.
[18:21:03.804] <TB3> INFO: ---> dac: Vcal name: TrimThr_trim13 ntrig: 5 dacrange: 0 .. 187 (-1/-1) hits flags = 528 (plus default)
[18:21:03.813] <TB3> INFO: dacScan split into 1 runs with ntrig = 5
[18:21:03.813] <TB3> INFO: run 1 of 1
[18:21:04.045] <TB3> INFO: Expecting 3910400 events.
[18:21:35.270] <TB3> INFO: 719485 events read in total (30633ms).
[18:22:05.439] <TB3> INFO: 1434170 events read in total (60802ms).
[18:22:35.499] <TB3> INFO: 2144015 events read in total (90862ms).
[18:23:05.469] <TB3> INFO: 2849380 events read in total (120832ms).
[18:23:35.772] <TB3> INFO: 3554220 events read in total (151135ms).
[18:23:51.167] <TB3> INFO: 3910400 events read in total (166530ms).
[18:23:51.232] <TB3> INFO: Test took 167419ms.
[18:24:19.003] <TB3> INFO: ---> dac: Vcal name: TrimThr_trim11 ntrig: 5 dacrange: 0 .. 188 (-1/-1) hits flags = 528 (plus default)
[18:24:19.012] <TB3> INFO: dacScan split into 1 runs with ntrig = 5
[18:24:19.012] <TB3> INFO: run 1 of 1
[18:24:19.259] <TB3> INFO: Expecting 3931200 events.
[18:24:50.373] <TB3> INFO: 718815 events read in total (30522ms).
[18:25:20.559] <TB3> INFO: 1432350 events read in total (60708ms).
[18:25:50.648] <TB3> INFO: 2141260 events read in total (90798ms).
[18:26:20.771] <TB3> INFO: 2845810 events read in total (120920ms).
[18:26:51.696] <TB3> INFO: 3549275 events read in total (151845ms).
[18:27:08.421] <TB3> INFO: 3931200 events read in total (168570ms).
[18:27:08.472] <TB3> INFO: Test took 169460ms.
[18:27:37.344] <TB3> INFO: ---> dac: Vcal name: TrimThr_trim7 ntrig: 5 dacrange: 0 .. 188 (-1/-1) hits flags = 528 (plus default)
[18:27:37.355] <TB3> INFO: dacScan split into 1 runs with ntrig = 5
[18:27:37.355] <TB3> INFO: run 1 of 1
[18:27:37.594] <TB3> INFO: Expecting 3931200 events.
[18:28:08.238] <TB3> INFO: 718940 events read in total (30052ms).
[18:28:38.414] <TB3> INFO: 1432890 events read in total (60228ms).
[18:29:08.866] <TB3> INFO: 2142245 events read in total (90680ms).
[18:29:39.350] <TB3> INFO: 2846865 events read in total (121164ms).
[18:30:09.853] <TB3> INFO: 3550310 events read in total (151667ms).
[18:30:26.494] <TB3> INFO: 3931200 events read in total (168308ms).
[18:30:26.547] <TB3> INFO: Test took 169190ms.
[18:30:52.369] <TB3> INFO: PixTestTrim::trimBitTest() done
[18:30:52.371] <TB3> INFO: PixTestTrim::doTest() done, duration: 2443 seconds
[18:30:52.371] <TB3> INFO: Decoding statistics:
[18:30:52.371] <TB3> INFO: General information:
[18:30:52.371] <TB3> INFO: 16bit words read: 0
[18:30:52.371] <TB3> INFO: valid events total: 0
[18:30:52.371] <TB3> INFO: empty events: 0
[18:30:52.371] <TB3> INFO: valid events with pixels: 0
[18:30:52.371] <TB3> INFO: valid pixel hits: 0
[18:30:52.371] <TB3> INFO: Event errors: 0
[18:30:52.371] <TB3> INFO: start marker: 0
[18:30:52.371] <TB3> INFO: stop marker: 0
[18:30:52.371] <TB3> INFO: overflow: 0
[18:30:52.371] <TB3> INFO: invalid 5bit words: 0
[18:30:52.371] <TB3> INFO: invalid XOR eye diagram: 0
[18:30:52.371] <TB3> INFO: frame (failed synchr.): 0
[18:30:52.371] <TB3> INFO: idle data (no TBM trl): 0
[18:30:52.371] <TB3> INFO: no data (only TBM hdr): 0
[18:30:52.371] <TB3> INFO: TBM errors: 0
[18:30:52.371] <TB3> INFO: flawed TBM headers: 0
[18:30:52.371] <TB3> INFO: flawed TBM trailers: 0
[18:30:52.371] <TB3> INFO: event ID mismatches: 0
[18:30:52.371] <TB3> INFO: ROC errors: 0
[18:30:52.371] <TB3> INFO: missing ROC header(s): 0
[18:30:52.371] <TB3> INFO: misplaced readback start: 0
[18:30:52.371] <TB3> INFO: Pixel decoding errors: 0
[18:30:52.371] <TB3> INFO: pixel data incomplete: 0
[18:30:52.371] <TB3> INFO: pixel address: 0
[18:30:52.371] <TB3> INFO: pulse height fill bit: 0
[18:30:52.371] <TB3> INFO: buffer corruption: 0
[18:30:52.991] <TB3> INFO: ######################################################################
[18:30:52.991] <TB3> INFO: PixTestPhOptimization::doTest() Ntrig = 10
[18:30:52.991] <TB3> INFO: ######################################################################
[18:30:53.230] <TB3> INFO: Expecting 41600 events.
[18:30:56.733] <TB3> INFO: 41600 events read in total (2911ms).
[18:30:56.734] <TB3> INFO: Test took 3738ms.
[18:30:57.201] <TB3> INFO: Expecting 41600 events.
[18:31:00.759] <TB3> INFO: 41600 events read in total (2966ms).
[18:31:00.760] <TB3> INFO: Test took 3824ms.
[18:31:01.048] <TB3> INFO: Expecting 41600 events.
[18:31:04.586] <TB3> INFO: 41600 events read in total (2947ms).
[18:31:04.587] <TB3> INFO: Test took 3804ms.
[18:31:04.885] <TB3> INFO: Expecting 41600 events.
[18:31:08.368] <TB3> INFO: 41600 events read in total (2892ms).
[18:31:08.369] <TB3> INFO: Test took 3759ms.
[18:31:08.657] <TB3> INFO: Expecting 41600 events.
[18:31:12.096] <TB3> INFO: 41600 events read in total (2847ms).
[18:31:12.098] <TB3> INFO: Test took 3705ms.
[18:31:12.386] <TB3> INFO: Expecting 41600 events.
[18:31:15.869] <TB3> INFO: 41600 events read in total (2891ms).
[18:31:15.870] <TB3> INFO: Test took 3748ms.
[18:31:16.158] <TB3> INFO: Expecting 41600 events.
[18:31:19.592] <TB3> INFO: 41600 events read in total (2842ms).
[18:31:19.593] <TB3> INFO: Test took 3699ms.
[18:31:19.881] <TB3> INFO: Expecting 41600 events.
[18:31:23.549] <TB3> INFO: 41600 events read in total (3076ms).
[18:31:23.550] <TB3> INFO: Test took 3934ms.
[18:31:23.840] <TB3> INFO: Expecting 41600 events.
[18:31:27.342] <TB3> INFO: 41600 events read in total (2910ms).
[18:31:27.343] <TB3> INFO: Test took 3768ms.
[18:31:27.631] <TB3> INFO: Expecting 41600 events.
[18:31:31.116] <TB3> INFO: 41600 events read in total (2894ms).
[18:31:31.117] <TB3> INFO: Test took 3751ms.
[18:31:31.405] <TB3> INFO: Expecting 41600 events.
[18:31:34.882] <TB3> INFO: 41600 events read in total (2885ms).
[18:31:34.883] <TB3> INFO: Test took 3743ms.
[18:31:35.171] <TB3> INFO: Expecting 41600 events.
[18:31:38.622] <TB3> INFO: 41600 events read in total (2859ms).
[18:31:38.622] <TB3> INFO: Test took 3716ms.
[18:31:38.913] <TB3> INFO: Expecting 41600 events.
[18:31:42.407] <TB3> INFO: 41600 events read in total (2903ms).
[18:31:42.407] <TB3> INFO: Test took 3759ms.
[18:31:42.712] <TB3> INFO: Expecting 41600 events.
[18:31:46.249] <TB3> INFO: 41600 events read in total (2945ms).
[18:31:46.249] <TB3> INFO: Test took 3816ms.
[18:31:46.537] <TB3> INFO: Expecting 41600 events.
[18:31:49.001] <TB3> INFO: 41600 events read in total (2872ms).
[18:31:49.002] <TB3> INFO: Test took 3729ms.
[18:31:50.290] <TB3> INFO: Expecting 41600 events.
[18:31:53.815] <TB3> INFO: 41600 events read in total (2934ms).
[18:31:53.815] <TB3> INFO: Test took 3790ms.
[18:31:54.111] <TB3> INFO: Expecting 41600 events.
[18:31:57.561] <TB3> INFO: 41600 events read in total (2858ms).
[18:31:57.561] <TB3> INFO: Test took 3722ms.
[18:31:57.852] <TB3> INFO: Expecting 41600 events.
[18:32:01.342] <TB3> INFO: 41600 events read in total (2898ms).
[18:32:01.343] <TB3> INFO: Test took 3756ms.
[18:32:01.631] <TB3> INFO: Expecting 41600 events.
[18:32:05.104] <TB3> INFO: 41600 events read in total (2881ms).
[18:32:05.105] <TB3> INFO: Test took 3739ms.
[18:32:05.393] <TB3> INFO: Expecting 41600 events.
[18:32:08.954] <TB3> INFO: 41600 events read in total (2969ms).
[18:32:08.955] <TB3> INFO: Test took 3827ms.
[18:32:09.247] <TB3> INFO: Expecting 41600 events.
[18:32:12.762] <TB3> INFO: 41600 events read in total (2923ms).
[18:32:12.763] <TB3> INFO: Test took 3781ms.
[18:32:13.065] <TB3> INFO: Expecting 41600 events.
[18:32:16.598] <TB3> INFO: 41600 events read in total (2941ms).
[18:32:16.598] <TB3> INFO: Test took 3810ms.
[18:32:16.886] <TB3> INFO: Expecting 41600 events.
[18:32:20.342] <TB3> INFO: 41600 events read in total (2864ms).
[18:32:20.342] <TB3> INFO: Test took 3721ms.
[18:32:20.630] <TB3> INFO: Expecting 41600 events.
[18:32:24.075] <TB3> INFO: 41600 events read in total (2853ms).
[18:32:24.075] <TB3> INFO: Test took 3709ms.
[18:32:24.363] <TB3> INFO: Expecting 41600 events.
[18:32:27.863] <TB3> INFO: 41600 events read in total (2908ms).
[18:32:27.864] <TB3> INFO: Test took 3765ms.
[18:32:28.152] <TB3> INFO: Expecting 41600 events.
[18:32:31.600] <TB3> INFO: 41600 events read in total (2856ms).
[18:32:31.601] <TB3> INFO: Test took 3714ms.
[18:32:31.889] <TB3> INFO: Expecting 41600 events.
[18:32:35.418] <TB3> INFO: 41600 events read in total (2938ms).
[18:32:35.419] <TB3> INFO: Test took 3795ms.
[18:32:35.717] <TB3> INFO: Expecting 41600 events.
[18:32:39.219] <TB3> INFO: 41600 events read in total (2911ms).
[18:32:39.219] <TB3> INFO: Test took 3777ms.
[18:32:39.508] <TB3> INFO: Expecting 41600 events.
[18:32:42.984] <TB3> INFO: 41600 events read in total (2885ms).
[18:32:42.985] <TB3> INFO: Test took 3742ms.
[18:32:43.277] <TB3> INFO: Expecting 41600 events.
[18:32:46.803] <TB3> INFO: 41600 events read in total (2935ms).
[18:32:46.803] <TB3> INFO: Test took 3792ms.
[18:32:47.093] <TB3> INFO: Expecting 41600 events.
[18:32:50.588] <TB3> INFO: 41600 events read in total (2903ms).
[18:32:50.589] <TB3> INFO: Test took 3761ms.
[18:32:50.878] <TB3> INFO: Expecting 2560 events.
[18:32:51.767] <TB3> INFO: 2560 events read in total (298ms).
[18:32:51.767] <TB3> INFO: Test took 1166ms.
[18:32:52.075] <TB3> INFO: Expecting 2560 events.
[18:32:52.957] <TB3> INFO: 2560 events read in total (291ms).
[18:32:52.957] <TB3> INFO: Test took 1190ms.
[18:32:53.265] <TB3> INFO: Expecting 2560 events.
[18:32:54.146] <TB3> INFO: 2560 events read in total (290ms).
[18:32:54.146] <TB3> INFO: Test took 1189ms.
[18:32:54.453] <TB3> INFO: Expecting 2560 events.
[18:32:55.337] <TB3> INFO: 2560 events read in total (292ms).
[18:32:55.337] <TB3> INFO: Test took 1191ms.
[18:32:55.645] <TB3> INFO: Expecting 2560 events.
[18:32:56.523] <TB3> INFO: 2560 events read in total (287ms).
[18:32:56.524] <TB3> INFO: Test took 1187ms.
[18:32:56.831] <TB3> INFO: Expecting 2560 events.
[18:32:57.710] <TB3> INFO: 2560 events read in total (287ms).
[18:32:57.711] <TB3> INFO: Test took 1187ms.
[18:32:58.019] <TB3> INFO: Expecting 2560 events.
[18:32:58.896] <TB3> INFO: 2560 events read in total (286ms).
[18:32:58.896] <TB3> INFO: Test took 1185ms.
[18:32:59.204] <TB3> INFO: Expecting 2560 events.
[18:33:00.083] <TB3> INFO: 2560 events read in total (287ms).
[18:33:00.084] <TB3> INFO: Test took 1187ms.
[18:33:00.392] <TB3> INFO: Expecting 2560 events.
[18:33:01.270] <TB3> INFO: 2560 events read in total (287ms).
[18:33:01.270] <TB3> INFO: Test took 1186ms.
[18:33:01.578] <TB3> INFO: Expecting 2560 events.
[18:33:02.455] <TB3> INFO: 2560 events read in total (286ms).
[18:33:02.455] <TB3> INFO: Test took 1185ms.
[18:33:02.763] <TB3> INFO: Expecting 2560 events.
[18:33:03.641] <TB3> INFO: 2560 events read in total (287ms).
[18:33:03.642] <TB3> INFO: Test took 1186ms.
[18:33:03.950] <TB3> INFO: Expecting 2560 events.
[18:33:04.829] <TB3> INFO: 2560 events read in total (288ms).
[18:33:04.829] <TB3> INFO: Test took 1187ms.
[18:33:05.137] <TB3> INFO: Expecting 2560 events.
[18:33:06.020] <TB3> INFO: 2560 events read in total (291ms).
[18:33:06.021] <TB3> INFO: Test took 1192ms.
[18:33:06.329] <TB3> INFO: Expecting 2560 events.
[18:33:07.214] <TB3> INFO: 2560 events read in total (294ms).
[18:33:07.215] <TB3> INFO: Test took 1194ms.
[18:33:07.523] <TB3> INFO: Expecting 2560 events.
[18:33:08.408] <TB3> INFO: 2560 events read in total (294ms).
[18:33:08.408] <TB3> INFO: Test took 1193ms.
[18:33:08.716] <TB3> INFO: Expecting 2560 events.
[18:33:09.601] <TB3> INFO: 2560 events read in total (293ms).
[18:33:09.602] <TB3> INFO: Test took 1194ms.
[18:33:09.605] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[18:33:09.910] <TB3> INFO: Expecting 655360 events.
[18:33:24.092] <TB3> INFO: 655360 events read in total (13590ms).
[18:33:24.102] <TB3> INFO: Expecting 655360 events.
[18:33:38.074] <TB3> INFO: 655360 events read in total (13569ms).
[18:33:38.089] <TB3> INFO: Expecting 655360 events.
[18:33:52.127] <TB3> INFO: 655360 events read in total (13635ms).
[18:33:52.145] <TB3> INFO: Expecting 655360 events.
[18:34:06.195] <TB3> INFO: 655360 events read in total (13647ms).
[18:34:06.217] <TB3> INFO: Expecting 655360 events.
[18:34:20.336] <TB3> INFO: 655360 events read in total (13716ms).
[18:34:20.364] <TB3> INFO: Expecting 655360 events.
[18:34:34.340] <TB3> INFO: 655360 events read in total (13573ms).
[18:34:34.371] <TB3> INFO: Expecting 655360 events.
[18:34:48.418] <TB3> INFO: 655360 events read in total (13644ms).
[18:34:48.452] <TB3> INFO: Expecting 655360 events.
[18:35:02.401] <TB3> INFO: 655360 events read in total (13546ms).
[18:35:02.440] <TB3> INFO: Expecting 655360 events.
[18:35:16.570] <TB3> INFO: 655360 events read in total (13727ms).
[18:35:16.613] <TB3> INFO: Expecting 655360 events.
[18:35:30.650] <TB3> INFO: 655360 events read in total (13634ms).
[18:35:30.700] <TB3> INFO: Expecting 655360 events.
[18:35:44.770] <TB3> INFO: 655360 events read in total (13667ms).
[18:35:44.824] <TB3> INFO: Expecting 655360 events.
[18:35:58.914] <TB3> INFO: 655360 events read in total (13687ms).
[18:35:58.973] <TB3> INFO: Expecting 655360 events.
[18:36:13.048] <TB3> INFO: 655360 events read in total (13663ms).
[18:36:13.114] <TB3> INFO: Expecting 655360 events.
[18:36:27.196] <TB3> INFO: 655360 events read in total (13679ms).
[18:36:27.262] <TB3> INFO: Expecting 655360 events.
[18:36:41.448] <TB3> INFO: 655360 events read in total (13783ms).
[18:36:41.519] <TB3> INFO: Expecting 655360 events.
[18:36:55.521] <TB3> INFO: 655360 events read in total (13599ms).
[18:36:55.594] <TB3> INFO: Test took 225990ms.
[18:36:55.672] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[18:36:55.937] <TB3> INFO: Expecting 655360 events.
[18:37:09.841] <TB3> INFO: 655360 events read in total (13312ms).
[18:37:09.851] <TB3> INFO: Expecting 655360 events.
[18:37:23.795] <TB3> INFO: 655360 events read in total (13541ms).
[18:37:23.810] <TB3> INFO: Expecting 655360 events.
[18:37:37.703] <TB3> INFO: 655360 events read in total (13490ms).
[18:37:37.721] <TB3> INFO: Expecting 655360 events.
[18:37:51.821] <TB3> INFO: 655360 events read in total (13697ms).
[18:37:51.851] <TB3> INFO: Expecting 655360 events.
[18:38:05.870] <TB3> INFO: 655360 events read in total (13616ms).
[18:38:05.897] <TB3> INFO: Expecting 655360 events.
[18:38:19.939] <TB3> INFO: 655360 events read in total (13639ms).
[18:38:19.970] <TB3> INFO: Expecting 655360 events.
[18:38:33.877] <TB3> INFO: 655360 events read in total (13504ms).
[18:38:33.912] <TB3> INFO: Expecting 655360 events.
[18:38:47.864] <TB3> INFO: 655360 events read in total (13549ms).
[18:38:47.916] <TB3> INFO: Expecting 655360 events.
[18:39:01.747] <TB3> INFO: 655360 events read in total (13428ms).
[18:39:01.791] <TB3> INFO: Expecting 655360 events.
[18:39:15.859] <TB3> INFO: 655360 events read in total (13665ms).
[18:39:15.909] <TB3> INFO: Expecting 655360 events.
[18:39:30.005] <TB3> INFO: 655360 events read in total (13694ms).
[18:39:30.058] <TB3> INFO: Expecting 655360 events.
[18:39:43.889] <TB3> INFO: 655360 events read in total (13428ms).
[18:39:43.947] <TB3> INFO: Expecting 655360 events.
[18:39:57.834] <TB3> INFO: 655360 events read in total (13484ms).
[18:39:57.895] <TB3> INFO: Expecting 655360 events.
[18:40:11.900] <TB3> INFO: 655360 events read in total (13602ms).
[18:40:11.989] <TB3> INFO: Expecting 655360 events.
[18:40:25.908] <TB3> INFO: 655360 events read in total (13516ms).
[18:40:25.002] <TB3> INFO: Expecting 655360 events.
[18:40:40.230] <TB3> INFO: 655360 events read in total (13825ms).
[18:40:40.304] <TB3> INFO: Test took 224632ms.
[18:40:40.529] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[18:40:40.536] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[18:40:40.542] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[18:40:40.548] <TB3> INFO: safety margin for low PH: adding 1, margin is now 21
[18:40:40.555] <TB3> INFO: safety margin for low PH: adding 2, margin is now 22
[18:40:40.561] <TB3> INFO: safety margin for low PH: adding 3, margin is now 23
[18:40:40.567] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[18:40:40.574] <TB3> INFO: safety margin for low PH: adding 1, margin is now 21
[18:40:40.580] <TB3> INFO: safety margin for low PH: adding 2, margin is now 22
[18:40:40.586] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[18:40:40.593] <TB3> INFO: safety margin for low PH: adding 1, margin is now 21
[18:40:40.599] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[18:40:40.605] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[18:40:40.611] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[18:40:40.618] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[18:40:40.624] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[18:40:40.630] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[18:40:40.636] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[18:40:40.643] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[18:40:40.649] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[18:40:40.655] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[18:40:40.662] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[18:40:40.668] <TB3> INFO: safety margin for low PH: adding 1, margin is now 21
[18:40:40.674] <TB3> INFO: safety margin for low PH: adding 2, margin is now 22
[18:40:40.680] <TB3> INFO: safety margin for low PH: adding 3, margin is now 23
[18:40:40.687] <TB3> INFO: safety margin for low PH: adding 4, margin is now 24
[18:40:40.721] <TB3> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1037_FullQualification_2016-10-26_16h26m_1477492000//001_Fulltest_m20//dacParameters35_C0.dat
[18:40:40.721] <TB3> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1037_FullQualification_2016-10-26_16h26m_1477492000//001_Fulltest_m20//dacParameters35_C1.dat
[18:40:40.721] <TB3> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1037_FullQualification_2016-10-26_16h26m_1477492000//001_Fulltest_m20//dacParameters35_C2.dat
[18:40:40.721] <TB3> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1037_FullQualification_2016-10-26_16h26m_1477492000//001_Fulltest_m20//dacParameters35_C3.dat
[18:40:40.722] <TB3> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1037_FullQualification_2016-10-26_16h26m_1477492000//001_Fulltest_m20//dacParameters35_C4.dat
[18:40:40.722] <TB3> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1037_FullQualification_2016-10-26_16h26m_1477492000//001_Fulltest_m20//dacParameters35_C5.dat
[18:40:40.722] <TB3> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1037_FullQualification_2016-10-26_16h26m_1477492000//001_Fulltest_m20//dacParameters35_C6.dat
[18:40:40.722] <TB3> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1037_FullQualification_2016-10-26_16h26m_1477492000//001_Fulltest_m20//dacParameters35_C7.dat
[18:40:40.722] <TB3> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1037_FullQualification_2016-10-26_16h26m_1477492000//001_Fulltest_m20//dacParameters35_C8.dat
[18:40:40.722] <TB3> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1037_FullQualification_2016-10-26_16h26m_1477492000//001_Fulltest_m20//dacParameters35_C9.dat
[18:40:40.722] <TB3> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1037_FullQualification_2016-10-26_16h26m_1477492000//001_Fulltest_m20//dacParameters35_C10.dat
[18:40:40.722] <TB3> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1037_FullQualification_2016-10-26_16h26m_1477492000//001_Fulltest_m20//dacParameters35_C11.dat
[18:40:40.722] <TB3> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1037_FullQualification_2016-10-26_16h26m_1477492000//001_Fulltest_m20//dacParameters35_C12.dat
[18:40:40.723] <TB3> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1037_FullQualification_2016-10-26_16h26m_1477492000//001_Fulltest_m20//dacParameters35_C13.dat
[18:40:40.723] <TB3> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1037_FullQualification_2016-10-26_16h26m_1477492000//001_Fulltest_m20//dacParameters35_C14.dat
[18:40:40.723] <TB3> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1037_FullQualification_2016-10-26_16h26m_1477492000//001_Fulltest_m20//dacParameters35_C15.dat
[18:40:40.957] <TB3> INFO: Expecting 41600 events.
[18:40:44.061] <TB3> INFO: 41600 events read in total (2513ms).
[18:40:44.061] <TB3> INFO: Test took 3335ms.
[18:40:44.505] <TB3> INFO: Expecting 41600 events.
[18:40:47.511] <TB3> INFO: 41600 events read in total (2416ms).
[18:40:47.512] <TB3> INFO: Test took 3239ms.
[18:40:47.955] <TB3> INFO: Expecting 41600 events.
[18:40:51.045] <TB3> INFO: 41600 events read in total (2499ms).
[18:40:51.045] <TB3> INFO: Test took 3322ms.
[18:40:51.259] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[18:40:51.347] <TB3> INFO: Expecting 2560 events.
[18:40:52.234] <TB3> INFO: 2560 events read in total (295ms).
[18:40:52.234] <TB3> INFO: Test took 975ms.
[18:40:52.236] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[18:40:52.542] <TB3> INFO: Expecting 2560 events.
[18:40:53.425] <TB3> INFO: 2560 events read in total (291ms).
[18:40:53.425] <TB3> INFO: Test took 1189ms.
[18:40:53.427] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[18:40:53.734] <TB3> INFO: Expecting 2560 events.
[18:40:54.617] <TB3> INFO: 2560 events read in total (292ms).
[18:40:54.617] <TB3> INFO: Test took 1190ms.
[18:40:54.619] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[18:40:54.925] <TB3> INFO: Expecting 2560 events.
[18:40:55.809] <TB3> INFO: 2560 events read in total (292ms).
[18:40:55.809] <TB3> INFO: Test took 1190ms.
[18:40:55.811] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[18:40:56.118] <TB3> INFO: Expecting 2560 events.
[18:40:56.001] <TB3> INFO: 2560 events read in total (292ms).
[18:40:56.001] <TB3> INFO: Test took 1190ms.
[18:40:57.004] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[18:40:57.310] <TB3> INFO: Expecting 2560 events.
[18:40:58.193] <TB3> INFO: 2560 events read in total (292ms).
[18:40:58.193] <TB3> INFO: Test took 1189ms.
[18:40:58.195] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[18:40:58.501] <TB3> INFO: Expecting 2560 events.
[18:40:59.383] <TB3> INFO: 2560 events read in total (290ms).
[18:40:59.383] <TB3> INFO: Test took 1188ms.
[18:40:59.385] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[18:40:59.692] <TB3> INFO: Expecting 2560 events.
[18:41:00.576] <TB3> INFO: 2560 events read in total (292ms).
[18:41:00.577] <TB3> INFO: Test took 1192ms.
[18:41:00.579] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[18:41:00.886] <TB3> INFO: Expecting 2560 events.
[18:41:01.765] <TB3> INFO: 2560 events read in total (288ms).
[18:41:01.765] <TB3> INFO: Test took 1187ms.
[18:41:01.766] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[18:41:02.073] <TB3> INFO: Expecting 2560 events.
[18:41:02.951] <TB3> INFO: 2560 events read in total (286ms).
[18:41:02.951] <TB3> INFO: Test took 1185ms.
[18:41:02.953] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[18:41:03.260] <TB3> INFO: Expecting 2560 events.
[18:41:04.139] <TB3> INFO: 2560 events read in total (288ms).
[18:41:04.139] <TB3> INFO: Test took 1186ms.
[18:41:04.141] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[18:41:04.447] <TB3> INFO: Expecting 2560 events.
[18:41:05.326] <TB3> INFO: 2560 events read in total (287ms).
[18:41:05.326] <TB3> INFO: Test took 1185ms.
[18:41:05.328] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[18:41:05.635] <TB3> INFO: Expecting 2560 events.
[18:41:06.516] <TB3> INFO: 2560 events read in total (289ms).
[18:41:06.516] <TB3> INFO: Test took 1188ms.
[18:41:06.518] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[18:41:06.825] <TB3> INFO: Expecting 2560 events.
[18:41:07.704] <TB3> INFO: 2560 events read in total (288ms).
[18:41:07.704] <TB3> INFO: Test took 1186ms.
[18:41:07.706] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[18:41:08.013] <TB3> INFO: Expecting 2560 events.
[18:41:08.892] <TB3> INFO: 2560 events read in total (287ms).
[18:41:08.893] <TB3> INFO: Test took 1187ms.
[18:41:08.894] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[18:41:09.201] <TB3> INFO: Expecting 2560 events.
[18:41:10.080] <TB3> INFO: 2560 events read in total (287ms).
[18:41:10.080] <TB3> INFO: Test took 1186ms.
[18:41:10.082] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[18:41:10.388] <TB3> INFO: Expecting 2560 events.
[18:41:11.269] <TB3> INFO: 2560 events read in total (289ms).
[18:41:11.269] <TB3> INFO: Test took 1187ms.
[18:41:11.272] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[18:41:11.578] <TB3> INFO: Expecting 2560 events.
[18:41:12.460] <TB3> INFO: 2560 events read in total (291ms).
[18:41:12.460] <TB3> INFO: Test took 1188ms.
[18:41:12.462] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[18:41:12.768] <TB3> INFO: Expecting 2560 events.
[18:41:13.648] <TB3> INFO: 2560 events read in total (288ms).
[18:41:13.649] <TB3> INFO: Test took 1187ms.
[18:41:13.650] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[18:41:13.957] <TB3> INFO: Expecting 2560 events.
[18:41:14.836] <TB3> INFO: 2560 events read in total (287ms).
[18:41:14.836] <TB3> INFO: Test took 1186ms.
[18:41:14.838] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[18:41:15.145] <TB3> INFO: Expecting 2560 events.
[18:41:16.023] <TB3> INFO: 2560 events read in total (287ms).
[18:41:16.024] <TB3> INFO: Test took 1186ms.
[18:41:16.025] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[18:41:16.332] <TB3> INFO: Expecting 2560 events.
[18:41:17.213] <TB3> INFO: 2560 events read in total (290ms).
[18:41:17.213] <TB3> INFO: Test took 1188ms.
[18:41:17.215] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[18:41:17.521] <TB3> INFO: Expecting 2560 events.
[18:41:18.400] <TB3> INFO: 2560 events read in total (287ms).
[18:41:18.400] <TB3> INFO: Test took 1186ms.
[18:41:18.402] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[18:41:18.708] <TB3> INFO: Expecting 2560 events.
[18:41:19.591] <TB3> INFO: 2560 events read in total (291ms).
[18:41:19.591] <TB3> INFO: Test took 1189ms.
[18:41:19.593] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[18:41:19.899] <TB3> INFO: Expecting 2560 events.
[18:41:20.786] <TB3> INFO: 2560 events read in total (295ms).
[18:41:20.786] <TB3> INFO: Test took 1193ms.
[18:41:20.788] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[18:41:21.095] <TB3> INFO: Expecting 2560 events.
[18:41:21.978] <TB3> INFO: 2560 events read in total (292ms).
[18:41:21.978] <TB3> INFO: Test took 1190ms.
[18:41:21.981] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[18:41:22.286] <TB3> INFO: Expecting 2560 events.
[18:41:23.172] <TB3> INFO: 2560 events read in total (294ms).
[18:41:23.172] <TB3> INFO: Test took 1191ms.
[18:41:23.174] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[18:41:23.480] <TB3> INFO: Expecting 2560 events.
[18:41:24.365] <TB3> INFO: 2560 events read in total (293ms).
[18:41:24.365] <TB3> INFO: Test took 1191ms.
[18:41:24.367] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[18:41:24.673] <TB3> INFO: Expecting 2560 events.
[18:41:25.555] <TB3> INFO: 2560 events read in total (290ms).
[18:41:25.555] <TB3> INFO: Test took 1189ms.
[18:41:25.558] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[18:41:25.863] <TB3> INFO: Expecting 2560 events.
[18:41:26.747] <TB3> INFO: 2560 events read in total (292ms).
[18:41:26.747] <TB3> INFO: Test took 1189ms.
[18:41:26.749] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[18:41:27.056] <TB3> INFO: Expecting 2560 events.
[18:41:27.939] <TB3> INFO: 2560 events read in total (292ms).
[18:41:27.939] <TB3> INFO: Test took 1190ms.
[18:41:27.941] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[18:41:28.248] <TB3> INFO: Expecting 2560 events.
[18:41:29.135] <TB3> INFO: 2560 events read in total (295ms).
[18:41:29.135] <TB3> INFO: Test took 1194ms.
[18:41:29.598] <TB3> INFO: PixTestPhOptimization::doTest() done, duration: 636 seconds
[18:41:29.598] <TB3> INFO: PH scale (per ROC): 42 47 48 51 59 46 59 43 53 51 64 57 50 48 49 34
[18:41:29.598] <TB3> INFO: PH offset (per ROC): 100 113 95 98 99 103 122 94 108 85 116 109 101 96 104 107
[18:41:29.603] <TB3> INFO: Decoding statistics:
[18:41:29.603] <TB3> INFO: General information:
[18:41:29.603] <TB3> INFO: 16bit words read: 127880
[18:41:29.603] <TB3> INFO: valid events total: 20480
[18:41:29.603] <TB3> INFO: empty events: 17980
[18:41:29.603] <TB3> INFO: valid events with pixels: 2500
[18:41:29.603] <TB3> INFO: valid pixel hits: 2500
[18:41:29.603] <TB3> INFO: Event errors: 0
[18:41:29.603] <TB3> INFO: start marker: 0
[18:41:29.603] <TB3> INFO: stop marker: 0
[18:41:29.603] <TB3> INFO: overflow: 0
[18:41:29.603] <TB3> INFO: invalid 5bit words: 0
[18:41:29.603] <TB3> INFO: invalid XOR eye diagram: 0
[18:41:29.603] <TB3> INFO: frame (failed synchr.): 0
[18:41:29.603] <TB3> INFO: idle data (no TBM trl): 0
[18:41:29.603] <TB3> INFO: no data (only TBM hdr): 0
[18:41:29.603] <TB3> INFO: TBM errors: 0
[18:41:29.603] <TB3> INFO: flawed TBM headers: 0
[18:41:29.603] <TB3> INFO: flawed TBM trailers: 0
[18:41:29.603] <TB3> INFO: event ID mismatches: 0
[18:41:29.603] <TB3> INFO: ROC errors: 0
[18:41:29.603] <TB3> INFO: missing ROC header(s): 0
[18:41:29.603] <TB3> INFO: misplaced readback start: 0
[18:41:29.603] <TB3> INFO: Pixel decoding errors: 0
[18:41:29.603] <TB3> INFO: pixel data incomplete: 0
[18:41:29.603] <TB3> INFO: pixel address: 0
[18:41:29.603] <TB3> INFO: pulse height fill bit: 0
[18:41:29.603] <TB3> INFO: buffer corruption: 0
[18:41:29.893] <TB3> INFO: ######################################################################
[18:41:29.893] <TB3> INFO: PixTestGainPedestal::fullTest() ntrig = 10
[18:41:29.893] <TB3> INFO: ######################################################################
[18:41:29.906] <TB3> INFO: scanning low vcal = 10
[18:41:30.181] <TB3> INFO: Expecting 41600 events.
[18:41:33.756] <TB3> INFO: 41600 events read in total (2984ms).
[18:41:33.756] <TB3> INFO: Test took 3850ms.
[18:41:33.758] <TB3> INFO: scanning low vcal = 20
[18:41:34.057] <TB3> INFO: Expecting 41600 events.
[18:41:37.599] <TB3> INFO: 41600 events read in total (2950ms).
[18:41:37.599] <TB3> INFO: Test took 3841ms.
[18:41:37.601] <TB3> INFO: scanning low vcal = 30
[18:41:37.899] <TB3> INFO: Expecting 41600 events.
[18:41:41.510] <TB3> INFO: 41600 events read in total (3019ms).
[18:41:41.510] <TB3> INFO: Test took 3909ms.
[18:41:41.512] <TB3> INFO: scanning low vcal = 40
[18:41:41.791] <TB3> INFO: Expecting 41600 events.
[18:41:45.666] <TB3> INFO: 41600 events read in total (3284ms).
[18:41:45.667] <TB3> INFO: Test took 4154ms.
[18:41:45.670] <TB3> INFO: scanning low vcal = 50
[18:41:45.947] <TB3> INFO: Expecting 41600 events.
[18:41:49.871] <TB3> INFO: 41600 events read in total (3333ms).
[18:41:49.871] <TB3> INFO: Test took 4201ms.
[18:41:49.874] <TB3> INFO: scanning low vcal = 60
[18:41:50.169] <TB3> INFO: Expecting 41600 events.
[18:41:54.118] <TB3> INFO: 41600 events read in total (3357ms).
[18:41:54.118] <TB3> INFO: Test took 4244ms.
[18:41:54.121] <TB3> INFO: scanning low vcal = 70
[18:41:54.398] <TB3> INFO: Expecting 41600 events.
[18:41:58.331] <TB3> INFO: 41600 events read in total (3342ms).
[18:41:58.332] <TB3> INFO: Test took 4211ms.
[18:41:58.335] <TB3> INFO: scanning low vcal = 80
[18:41:58.612] <TB3> INFO: Expecting 41600 events.
[18:42:02.537] <TB3> INFO: 41600 events read in total (3334ms).
[18:42:02.538] <TB3> INFO: Test took 4203ms.
[18:42:02.540] <TB3> INFO: scanning low vcal = 90
[18:42:02.822] <TB3> INFO: Expecting 41600 events.
[18:42:06.742] <TB3> INFO: 41600 events read in total (3328ms).
[18:42:06.743] <TB3> INFO: Test took 4203ms.
[18:42:06.746] <TB3> INFO: scanning low vcal = 100
[18:42:07.023] <TB3> INFO: Expecting 41600 events.
[18:42:10.963] <TB3> INFO: 41600 events read in total (3349ms).
[18:42:10.964] <TB3> INFO: Test took 4218ms.
[18:42:10.966] <TB3> INFO: scanning low vcal = 110
[18:42:11.243] <TB3> INFO: Expecting 41600 events.
[18:42:15.149] <TB3> INFO: 41600 events read in total (3314ms).
[18:42:15.150] <TB3> INFO: Test took 4183ms.
[18:42:15.152] <TB3> INFO: scanning low vcal = 120
[18:42:15.429] <TB3> INFO: Expecting 41600 events.
[18:42:19.393] <TB3> INFO: 41600 events read in total (3372ms).
[18:42:19.394] <TB3> INFO: Test took 4241ms.
[18:42:19.397] <TB3> INFO: scanning low vcal = 130
[18:42:19.674] <TB3> INFO: Expecting 41600 events.
[18:42:23.607] <TB3> INFO: 41600 events read in total (3342ms).
[18:42:23.607] <TB3> INFO: Test took 4210ms.
[18:42:23.610] <TB3> INFO: scanning low vcal = 140
[18:42:23.887] <TB3> INFO: Expecting 41600 events.
[18:42:27.823] <TB3> INFO: 41600 events read in total (3344ms).
[18:42:27.823] <TB3> INFO: Test took 4213ms.
[18:42:27.826] <TB3> INFO: scanning low vcal = 150
[18:42:28.103] <TB3> INFO: Expecting 41600 events.
[18:42:32.044] <TB3> INFO: 41600 events read in total (3350ms).
[18:42:32.045] <TB3> INFO: Test took 4219ms.
[18:42:32.047] <TB3> INFO: scanning low vcal = 160
[18:42:32.324] <TB3> INFO: Expecting 41600 events.
[18:42:36.273] <TB3> INFO: 41600 events read in total (3358ms).
[18:42:36.273] <TB3> INFO: Test took 4226ms.
[18:42:36.276] <TB3> INFO: scanning low vcal = 170
[18:42:36.554] <TB3> INFO: Expecting 41600 events.
[18:42:40.503] <TB3> INFO: 41600 events read in total (3358ms).
[18:42:40.504] <TB3> INFO: Test took 4228ms.
[18:42:40.506] <TB3> INFO: scanning low vcal = 180
[18:42:40.783] <TB3> INFO: Expecting 41600 events.
[18:42:44.736] <TB3> INFO: 41600 events read in total (3362ms).
[18:42:44.736] <TB3> INFO: Test took 4231ms.
[18:42:44.739] <TB3> INFO: scanning low vcal = 190
[18:42:45.016] <TB3> INFO: Expecting 41600 events.
[18:42:48.954] <TB3> INFO: 41600 events read in total (3346ms).
[18:42:48.955] <TB3> INFO: Test took 4216ms.
[18:42:48.957] <TB3> INFO: scanning low vcal = 200
[18:42:49.238] <TB3> INFO: Expecting 41600 events.
[18:42:53.254] <TB3> INFO: 41600 events read in total (3425ms).
[18:42:53.254] <TB3> INFO: Test took 4297ms.
[18:42:53.257] <TB3> INFO: scanning low vcal = 210
[18:42:53.534] <TB3> INFO: Expecting 41600 events.
[18:42:57.520] <TB3> INFO: 41600 events read in total (3395ms).
[18:42:57.520] <TB3> INFO: Test took 4263ms.
[18:42:57.523] <TB3> INFO: scanning low vcal = 220
[18:42:57.799] <TB3> INFO: Expecting 41600 events.
[18:43:01.743] <TB3> INFO: 41600 events read in total (3352ms).
[18:43:01.744] <TB3> INFO: Test took 4221ms.
[18:43:01.746] <TB3> INFO: scanning low vcal = 230
[18:43:02.023] <TB3> INFO: Expecting 41600 events.
[18:43:06.048] <TB3> INFO: 41600 events read in total (3433ms).
[18:43:06.048] <TB3> INFO: Test took 4301ms.
[18:43:06.051] <TB3> INFO: scanning low vcal = 240
[18:43:06.327] <TB3> INFO: Expecting 41600 events.
[18:43:10.327] <TB3> INFO: 41600 events read in total (3408ms).
[18:43:10.328] <TB3> INFO: Test took 4278ms.
[18:43:10.331] <TB3> INFO: scanning low vcal = 250
[18:43:10.608] <TB3> INFO: Expecting 41600 events.
[18:43:14.607] <TB3> INFO: 41600 events read in total (3407ms).
[18:43:14.608] <TB3> INFO: Test took 4277ms.
[18:43:14.612] <TB3> INFO: scanning high vcal = 30 (= 210 in low range)
[18:43:14.888] <TB3> INFO: Expecting 41600 events.
[18:43:18.863] <TB3> INFO: 41600 events read in total (3384ms).
[18:43:18.863] <TB3> INFO: Test took 4251ms.
[18:43:18.866] <TB3> INFO: scanning high vcal = 50 (= 350 in low range)
[18:43:19.150] <TB3> INFO: Expecting 41600 events.
[18:43:23.109] <TB3> INFO: 41600 events read in total (3367ms).
[18:43:23.110] <TB3> INFO: Test took 4244ms.
[18:43:23.113] <TB3> INFO: scanning high vcal = 70 (= 490 in low range)
[18:43:23.389] <TB3> INFO: Expecting 41600 events.
[18:43:27.402] <TB3> INFO: 41600 events read in total (3421ms).
[18:43:27.403] <TB3> INFO: Test took 4290ms.
[18:43:27.406] <TB3> INFO: scanning high vcal = 90 (= 630 in low range)
[18:43:27.683] <TB3> INFO: Expecting 41600 events.
[18:43:31.673] <TB3> INFO: 41600 events read in total (3398ms).
[18:43:31.674] <TB3> INFO: Test took 4268ms.
[18:43:31.676] <TB3> INFO: scanning high vcal = 200 (= 1400 in low range)
[18:43:31.953] <TB3> INFO: Expecting 41600 events.
[18:43:36.011] <TB3> INFO: 41600 events read in total (3466ms).
[18:43:36.012] <TB3> INFO: Test took 4336ms.
[18:43:36.422] <TB3> INFO: PixTestGainPedestal::measure() done
[18:44:07.869] <TB3> INFO: PixTestGainPedestal::fit() done
[18:44:07.869] <TB3> INFO: non-linearity mean: 0.927 0.917 0.948 0.939 0.963 0.923 0.981 0.951 0.979 0.926 0.987 0.956 0.946 0.948 0.940 0.929
[18:44:07.869] <TB3> INFO: non-linearity RMS: 0.057 0.100 0.077 0.048 0.028 0.134 0.003 0.051 0.006 0.086 0.003 0.057 0.067 0.043 0.083 0.182
[18:44:07.870] <TB3> INFO: write gain/ped parameters into /home/l_tester/david/FullQualification/data/M1037_FullQualification_2016-10-26_16h26m_1477492000//001_Fulltest_m20//phCalibrationFitErr35_C0.dat
[18:44:07.883] <TB3> INFO: write gain/ped parameters into /home/l_tester/david/FullQualification/data/M1037_FullQualification_2016-10-26_16h26m_1477492000//001_Fulltest_m20//phCalibrationFitErr35_C1.dat
[18:44:07.898] <TB3> INFO: write gain/ped parameters into /home/l_tester/david/FullQualification/data/M1037_FullQualification_2016-10-26_16h26m_1477492000//001_Fulltest_m20//phCalibrationFitErr35_C2.dat
[18:44:07.911] <TB3> INFO: write gain/ped parameters into /home/l_tester/david/FullQualification/data/M1037_FullQualification_2016-10-26_16h26m_1477492000//001_Fulltest_m20//phCalibrationFitErr35_C3.dat
[18:44:07.925] <TB3> INFO: write gain/ped parameters into /home/l_tester/david/FullQualification/data/M1037_FullQualification_2016-10-26_16h26m_1477492000//001_Fulltest_m20//phCalibrationFitErr35_C4.dat
[18:44:07.939] <TB3> INFO: write gain/ped parameters into /home/l_tester/david/FullQualification/data/M1037_FullQualification_2016-10-26_16h26m_1477492000//001_Fulltest_m20//phCalibrationFitErr35_C5.dat
[18:44:07.953] <TB3> INFO: write gain/ped parameters into /home/l_tester/david/FullQualification/data/M1037_FullQualification_2016-10-26_16h26m_1477492000//001_Fulltest_m20//phCalibrationFitErr35_C6.dat
[18:44:07.966] <TB3> INFO: write gain/ped parameters into /home/l_tester/david/FullQualification/data/M1037_FullQualification_2016-10-26_16h26m_1477492000//001_Fulltest_m20//phCalibrationFitErr35_C7.dat
[18:44:07.980] <TB3> INFO: write gain/ped parameters into /home/l_tester/david/FullQualification/data/M1037_FullQualification_2016-10-26_16h26m_1477492000//001_Fulltest_m20//phCalibrationFitErr35_C8.dat
[18:44:07.993] <TB3> INFO: write gain/ped parameters into /home/l_tester/david/FullQualification/data/M1037_FullQualification_2016-10-26_16h26m_1477492000//001_Fulltest_m20//phCalibrationFitErr35_C9.dat
[18:44:08.007] <TB3> INFO: write gain/ped parameters into /home/l_tester/david/FullQualification/data/M1037_FullQualification_2016-10-26_16h26m_1477492000//001_Fulltest_m20//phCalibrationFitErr35_C10.dat
[18:44:08.020] <TB3> INFO: write gain/ped parameters into /home/l_tester/david/FullQualification/data/M1037_FullQualification_2016-10-26_16h26m_1477492000//001_Fulltest_m20//phCalibrationFitErr35_C11.dat
[18:44:08.034] <TB3> INFO: write gain/ped parameters into /home/l_tester/david/FullQualification/data/M1037_FullQualification_2016-10-26_16h26m_1477492000//001_Fulltest_m20//phCalibrationFitErr35_C12.dat
[18:44:08.048] <TB3> INFO: write gain/ped parameters into /home/l_tester/david/FullQualification/data/M1037_FullQualification_2016-10-26_16h26m_1477492000//001_Fulltest_m20//phCalibrationFitErr35_C13.dat
[18:44:08.061] <TB3> INFO: write gain/ped parameters into /home/l_tester/david/FullQualification/data/M1037_FullQualification_2016-10-26_16h26m_1477492000//001_Fulltest_m20//phCalibrationFitErr35_C14.dat
[18:44:08.075] <TB3> INFO: write gain/ped parameters into /home/l_tester/david/FullQualification/data/M1037_FullQualification_2016-10-26_16h26m_1477492000//001_Fulltest_m20//phCalibrationFitErr35_C15.dat
[18:44:08.088] <TB3> INFO: PixTestGainPedestal::fullTest() done, duration: 158 seconds
[18:44:08.088] <TB3> INFO: Decoding statistics:
[18:44:08.088] <TB3> INFO: General information:
[18:44:08.088] <TB3> INFO: 16bit words read: 3327898
[18:44:08.088] <TB3> INFO: valid events total: 332800
[18:44:08.088] <TB3> INFO: empty events: 0
[18:44:08.088] <TB3> INFO: valid events with pixels: 332800
[18:44:08.088] <TB3> INFO: valid pixel hits: 665549
[18:44:08.088] <TB3> INFO: Event errors: 0
[18:44:08.088] <TB3> INFO: start marker: 0
[18:44:08.088] <TB3> INFO: stop marker: 0
[18:44:08.088] <TB3> INFO: overflow: 0
[18:44:08.088] <TB3> INFO: invalid 5bit words: 0
[18:44:08.088] <TB3> INFO: invalid XOR eye diagram: 0
[18:44:08.088] <TB3> INFO: frame (failed synchr.): 0
[18:44:08.088] <TB3> INFO: idle data (no TBM trl): 0
[18:44:08.088] <TB3> INFO: no data (only TBM hdr): 0
[18:44:08.088] <TB3> INFO: TBM errors: 0
[18:44:08.088] <TB3> INFO: flawed TBM headers: 0
[18:44:08.088] <TB3> INFO: flawed TBM trailers: 0
[18:44:08.089] <TB3> INFO: event ID mismatches: 0
[18:44:08.089] <TB3> INFO: ROC errors: 0
[18:44:08.089] <TB3> INFO: missing ROC header(s): 0
[18:44:08.089] <TB3> INFO: misplaced readback start: 0
[18:44:08.089] <TB3> INFO: Pixel decoding errors: 0
[18:44:08.089] <TB3> INFO: pixel data incomplete: 0
[18:44:08.089] <TB3> INFO: pixel address: 0
[18:44:08.089] <TB3> INFO: pulse height fill bit: 0
[18:44:08.089] <TB3> INFO: buffer corruption: 0
[18:44:08.104] <TB3> INFO: Decoding statistics:
[18:44:08.104] <TB3> INFO: General information:
[18:44:08.104] <TB3> INFO: 16bit words read: 3457314
[18:44:08.104] <TB3> INFO: valid events total: 353536
[18:44:08.104] <TB3> INFO: empty events: 18236
[18:44:08.104] <TB3> INFO: valid events with pixels: 335300
[18:44:08.104] <TB3> INFO: valid pixel hits: 668049
[18:44:08.104] <TB3> INFO: Event errors: 0
[18:44:08.104] <TB3> INFO: start marker: 0
[18:44:08.104] <TB3> INFO: stop marker: 0
[18:44:08.104] <TB3> INFO: overflow: 0
[18:44:08.104] <TB3> INFO: invalid 5bit words: 0
[18:44:08.104] <TB3> INFO: invalid XOR eye diagram: 0
[18:44:08.104] <TB3> INFO: frame (failed synchr.): 0
[18:44:08.104] <TB3> INFO: idle data (no TBM trl): 0
[18:44:08.104] <TB3> INFO: no data (only TBM hdr): 0
[18:44:08.104] <TB3> INFO: TBM errors: 0
[18:44:08.104] <TB3> INFO: flawed TBM headers: 0
[18:44:08.104] <TB3> INFO: flawed TBM trailers: 0
[18:44:08.104] <TB3> INFO: event ID mismatches: 0
[18:44:08.104] <TB3> INFO: ROC errors: 0
[18:44:08.104] <TB3> INFO: missing ROC header(s): 0
[18:44:08.104] <TB3> INFO: misplaced readback start: 0
[18:44:08.104] <TB3> INFO: Pixel decoding errors: 0
[18:44:08.104] <TB3> INFO: pixel data incomplete: 0
[18:44:08.104] <TB3> INFO: pixel address: 0
[18:44:08.104] <TB3> INFO: pulse height fill bit: 0
[18:44:08.104] <TB3> INFO: buffer corruption: 0
[18:44:08.104] <TB3> INFO: enter test to run
[18:44:08.104] <TB3> INFO: test: exit no parameter change
[18:44:08.142] <TB3> QUIET: Connection to board 170 closed.
[18:44:08.143] <TB3> INFO: pXar: this is the end, my friend