[20:48:39.497] <TB3> INFO: *** Welcome to pxar ***
[20:48:39.497] <TB3> INFO: *** Today: 2016/11/07
[20:48:39.503] <TB3> INFO: *** Version: c8ba-dirty
[20:48:39.503] <TB3> INFO: readRocDacs: /home/l_tester/david/FullQualification/data/M1140_FullQualification_2016-11-07_19h53m_1478544796//001_Fulltest_m20//dacParameters_C0.dat .. /home/l_tester/david/FullQualification/data/M1140_FullQualification_2016-11-07_19h53m_1478544796//001_Fulltest_m20//dacParameters_C15.dat
[20:48:39.504] <TB3> INFO: readTbmDacs: /home/l_tester/david/FullQualification/data/M1140_FullQualification_2016-11-07_19h53m_1478544796//001_Fulltest_m20//tbmParameters_C0a.dat .. /home/l_tester/david/FullQualification/data/M1140_FullQualification_2016-11-07_19h53m_1478544796//001_Fulltest_m20//tbmParameters_C1b.dat
[20:48:39.504] <TB3> INFO: readMaskFile: /home/l_tester/david/FullQualification/data/M1140_FullQualification_2016-11-07_19h53m_1478544796//001_Fulltest_m20//defaultMaskFile.dat
[20:48:39.504] <TB3> INFO: readTrimFile: /home/l_tester/david/FullQualification/data/M1140_FullQualification_2016-11-07_19h53m_1478544796//001_Fulltest_m20//trimParameters_C0.dat .. /home/l_tester/david/FullQualification/data/M1140_FullQualification_2016-11-07_19h53m_1478544796//001_Fulltest_m20//trimParameters_C15.dat
[20:48:39.559] <TB3> INFO: clk: 4
[20:48:39.559] <TB3> INFO: ctr: 4
[20:48:39.559] <TB3> INFO: sda: 19
[20:48:39.559] <TB3> INFO: tin: 9
[20:48:39.559] <TB3> INFO: level: 15
[20:48:39.559] <TB3> INFO: triggerdelay: 0
[20:48:39.559] <TB3> QUIET: Instanciating API for pxar v2.1.0+867~g2c7f7f2
[20:48:39.559] <TB3> INFO: Log level: INFO
[20:48:39.567] <TB3> INFO: Found DTB DTB_WZ4I6J
[20:48:39.576] <TB3> QUIET: Connection to board DTB_WZ4I6J opened.
[20:48:39.578] <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:
------------------------------------------------------
[20:48:39.580] <TB3> INFO: RPC call hashes of host and DTB match: 486171790
[20:48:41.064] <TB3> INFO: DUT info:
[20:48:41.064] <TB3> INFO: The DUT currently contains the following objects:
[20:48:41.064] <TB3> INFO: 4 TBM Cores tbm10c (4 ON)
[20:48:41.064] <TB3> INFO: TBM Core alpha (0): 7 registers set
[20:48:41.064] <TB3> INFO: TBM Core beta (1): 7 registers set
[20:48:41.064] <TB3> INFO: TBM Core alpha (2): 7 registers set
[20:48:41.064] <TB3> INFO: TBM Core beta (3): 7 registers set
[20:48:41.064] <TB3> INFO: 16 ROCs proc600 (16 ON) with 4160 pixelConfigs
[20:48:41.064] <TB3> INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[20:48:41.064] <TB3> INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[20:48:41.064] <TB3> INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[20:48:41.064] <TB3> INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[20:48:41.064] <TB3> INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[20:48:41.064] <TB3> INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[20:48:41.064] <TB3> INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[20:48:41.064] <TB3> INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[20:48:41.064] <TB3> INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[20:48:41.064] <TB3> INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[20:48:41.064] <TB3> INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[20:48:41.064] <TB3> INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[20:48:41.064] <TB3> INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[20:48:41.064] <TB3> INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[20:48:41.064] <TB3> INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[20:48:41.064] <TB3> INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[20:48:41.465] <TB3> INFO: enter 'restricted' command line mode
[20:48:41.465] <TB3> INFO: enter test to run
[20:48:41.465] <TB3> INFO: test: pretest no parameter change
[20:48:41.465] <TB3> INFO: running: pretest
[20:48:42.247] <TB3> INFO: ######################################################################
[20:48:42.247] <TB3> INFO: PixTestPretest::doTest()
[20:48:42.247] <TB3> INFO: ######################################################################
[20:48:42.248] <TB3> INFO: ----------------------------------------------------------------------
[20:48:42.248] <TB3> INFO: PixTestPretest::programROC()
[20:48:42.248] <TB3> INFO: ----------------------------------------------------------------------
[20:49:00.261] <TB3> INFO: PixTestPretest::programROC() done: ROCs are all programmable
[20:49:00.261] <TB3> INFO: IA differences per ROC: 18.5 19.3 19.3 17.7 22.5 19.3 20.9 17.7 18.5 17.7 20.9 19.3 17.7 18.5 19.3 17.7
[20:49:00.301] <TB3> INFO: ----------------------------------------------------------------------
[20:49:00.301] <TB3> INFO: PixTestPretest::setVana() target Ia = 24 mA/ROC
[20:49:00.301] <TB3> INFO: ----------------------------------------------------------------------
[20:49:21.544] <TB3> INFO: PixTestPretest::setVana() done, Module Ia 389.1 mA = 24.3188 mA/ROC
[20:49:21.544] <TB3> INFO: i(loss) [mA/ROC]: 21.7 19.3 20.9 20.9 20.9 20.1 19.3 19.3 21.7 20.9 20.1 19.3 20.9 20.9 20.1 19.3
[20:49:21.573] <TB3> INFO: ----------------------------------------------------------------------
[20:49:21.573] <TB3> INFO: PixTestPretest::findTiming()
[20:49:21.573] <TB3> INFO: ----------------------------------------------------------------------
[20:49:21.573] <TB3> INFO: PixTestCmd::init()

[20:49:52.398] <TB3> INFO: TBM phases: 160MHz: 0, 400MHz: 7, TBM delays: ROC(0/1):5, header/trailer: 1, token: 1
[20:49:52.398] <TB3> INFO: (success/tries = 100/100), width = 3
[20:49:53.895] <TB3> INFO: ----------------------------------------------------------------------
[20:49:53.895] <TB3> INFO: PixTestPretest::findWorkingPixel()
[20:49:53.895] <TB3> INFO: ----------------------------------------------------------------------
[20:49:53.986] <TB3> INFO: Expecting 231680 events.
[20:50:03.595] <TB3> INFO: 231680 events read in total (9017ms).
[20:50:03.602] <TB3> INFO: Test took 9705ms.
[20:50:03.847] <TB3> INFO: Found working pixel in all ROCs: col/row = 12/22
[20:50:03.879] <TB3> INFO: ----------------------------------------------------------------------
[20:50:03.879] <TB3> INFO: PixTestPretest::setVthrCompCalDel()
[20:50:03.879] <TB3> INFO: ----------------------------------------------------------------------
[20:50:03.973] <TB3> INFO: Expecting 231680 events.
[20:50:13.622] <TB3> INFO: 231680 events read in total (9058ms).
[20:50:13.629] <TB3> INFO: Test took 9745ms.
[20:50:13.885] <TB3> INFO: PixTestPretest::setVthrCompCalDel() done
[20:50:13.885] <TB3> INFO: CalDel: 100 98 105 117 110 105 107 116 108 100 108 97 92 106 105 112
[20:50:13.885] <TB3> INFO: VthrComp: 51 51 51 51 51 51 51 51 51 51 51 51 51 51 51 51
[20:50:13.888] <TB3> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1140_FullQualification_2016-11-07_19h53m_1478544796//001_Fulltest_m20//dacParameters_C0.dat
[20:50:13.889] <TB3> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1140_FullQualification_2016-11-07_19h53m_1478544796//001_Fulltest_m20//dacParameters_C1.dat
[20:50:13.889] <TB3> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1140_FullQualification_2016-11-07_19h53m_1478544796//001_Fulltest_m20//dacParameters_C2.dat
[20:50:13.889] <TB3> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1140_FullQualification_2016-11-07_19h53m_1478544796//001_Fulltest_m20//dacParameters_C3.dat
[20:50:13.889] <TB3> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1140_FullQualification_2016-11-07_19h53m_1478544796//001_Fulltest_m20//dacParameters_C4.dat
[20:50:13.889] <TB3> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1140_FullQualification_2016-11-07_19h53m_1478544796//001_Fulltest_m20//dacParameters_C5.dat
[20:50:13.889] <TB3> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1140_FullQualification_2016-11-07_19h53m_1478544796//001_Fulltest_m20//dacParameters_C6.dat
[20:50:13.889] <TB3> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1140_FullQualification_2016-11-07_19h53m_1478544796//001_Fulltest_m20//dacParameters_C7.dat
[20:50:13.889] <TB3> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1140_FullQualification_2016-11-07_19h53m_1478544796//001_Fulltest_m20//dacParameters_C8.dat
[20:50:13.889] <TB3> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1140_FullQualification_2016-11-07_19h53m_1478544796//001_Fulltest_m20//dacParameters_C9.dat
[20:50:13.889] <TB3> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1140_FullQualification_2016-11-07_19h53m_1478544796//001_Fulltest_m20//dacParameters_C10.dat
[20:50:13.889] <TB3> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1140_FullQualification_2016-11-07_19h53m_1478544796//001_Fulltest_m20//dacParameters_C11.dat
[20:50:13.889] <TB3> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1140_FullQualification_2016-11-07_19h53m_1478544796//001_Fulltest_m20//dacParameters_C12.dat
[20:50:13.890] <TB3> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1140_FullQualification_2016-11-07_19h53m_1478544796//001_Fulltest_m20//dacParameters_C13.dat
[20:50:13.890] <TB3> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1140_FullQualification_2016-11-07_19h53m_1478544796//001_Fulltest_m20//dacParameters_C14.dat
[20:50:13.890] <TB3> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1140_FullQualification_2016-11-07_19h53m_1478544796//001_Fulltest_m20//dacParameters_C15.dat
[20:50:13.890] <TB3> INFO: write tbm parameters into /home/l_tester/david/FullQualification/data/M1140_FullQualification_2016-11-07_19h53m_1478544796//001_Fulltest_m20//tbmParameters_C0a.dat
[20:50:13.890] <TB3> INFO: write tbm parameters into /home/l_tester/david/FullQualification/data/M1140_FullQualification_2016-11-07_19h53m_1478544796//001_Fulltest_m20//tbmParameters_C0b.dat
[20:50:13.890] <TB3> INFO: write tbm parameters into /home/l_tester/david/FullQualification/data/M1140_FullQualification_2016-11-07_19h53m_1478544796//001_Fulltest_m20//tbmParameters_C1a.dat
[20:50:13.890] <TB3> INFO: write tbm parameters into /home/l_tester/david/FullQualification/data/M1140_FullQualification_2016-11-07_19h53m_1478544796//001_Fulltest_m20//tbmParameters_C1b.dat
[20:50:13.890] <TB3> INFO: PixTestPretest::doTest() done, duration: 92 seconds
[20:50:13.984] <TB3> INFO: enter test to run
[20:50:13.984] <TB3> INFO: test: FullTest no parameter change
[20:50:13.984] <TB3> INFO: running: fulltest
[20:50:13.984] <TB3> INFO: ######################################################################
[20:50:13.984] <TB3> INFO: PixTestFullTest::doTest()
[20:50:13.984] <TB3> INFO: ######################################################################
[20:50:13.985] <TB3> INFO: ######################################################################
[20:50:13.985] <TB3> INFO: PixTestAlive::doTest()
[20:50:13.985] <TB3> INFO: ######################################################################
[20:50:13.986] <TB3> INFO: ----------------------------------------------------------------------
[20:50:13.986] <TB3> INFO: PixTestAlive::aliveTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[20:50:13.987] <TB3> INFO: ----------------------------------------------------------------------
[20:50:14.220] <TB3> INFO: Expecting 41600 events.
[20:50:17.754] <TB3> INFO: 41600 events read in total (2942ms).
[20:50:17.755] <TB3> INFO: Test took 3767ms.
[20:50:17.980] <TB3> INFO: PixTestAlive::aliveTest() done
[20:50:17.980] <TB3> INFO: number of dead pixels (per ROC): 0 0 0 0 0 0 1 0 0 0 0 0 0 0 0 0
[20:50:17.981] <TB3> INFO: ----------------------------------------------------------------------
[20:50:17.981] <TB3> INFO: PixTestAlive::maskTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[20:50:17.981] <TB3> INFO: ----------------------------------------------------------------------
[20:50:18.259] <TB3> INFO: Expecting 41600 events.
[20:50:21.243] <TB3> INFO: 41600 events read in total (2392ms).
[20:50:21.243] <TB3> INFO: Test took 3260ms.
[20:50:21.244] <TB3> INFO: mask vs. old pixelAlive PixelAlive_C0_V0 .. PixelAlive_C15_V0
[20:50:21.481] <TB3> INFO: PixTestAlive::maskTest() done
[20:50:21.481] <TB3> INFO: number of mask-defect pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[20:50:21.483] <TB3> INFO: ----------------------------------------------------------------------
[20:50:21.483] <TB3> INFO: PixTestAlive::addressDecodingTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[20:50:21.483] <TB3> INFO: ----------------------------------------------------------------------
[20:50:21.741] <TB3> INFO: Expecting 41600 events.
[20:50:25.210] <TB3> INFO: 41600 events read in total (2878ms).
[20:50:25.211] <TB3> INFO: Test took 3727ms.
[20:50:25.442] <TB3> INFO: PixTestAlive::addressDecodingTest() done
[20:50:25.442] <TB3> INFO: number of address-decoding pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[20:50:25.442] <TB3> INFO: PixTestAlive::doTest() done, duration: 11 seconds
[20:50:25.442] <TB3> INFO: Decoding statistics:
[20:50:25.442] <TB3> INFO: General information:
[20:50:25.442] <TB3> INFO: 16bit words read: 0
[20:50:25.442] <TB3> INFO: valid events total: 0
[20:50:25.442] <TB3> INFO: empty events: 0
[20:50:25.442] <TB3> INFO: valid events with pixels: 0
[20:50:25.442] <TB3> INFO: valid pixel hits: 0
[20:50:25.442] <TB3> INFO: Event errors: 0
[20:50:25.442] <TB3> INFO: start marker: 0
[20:50:25.443] <TB3> INFO: stop marker: 0
[20:50:25.443] <TB3> INFO: overflow: 0
[20:50:25.443] <TB3> INFO: invalid 5bit words: 0
[20:50:25.443] <TB3> INFO: invalid XOR eye diagram: 0
[20:50:25.443] <TB3> INFO: frame (failed synchr.): 0
[20:50:25.443] <TB3> INFO: idle data (no TBM trl): 0
[20:50:25.443] <TB3> INFO: no data (only TBM hdr): 0
[20:50:25.443] <TB3> INFO: TBM errors: 0
[20:50:25.443] <TB3> INFO: flawed TBM headers: 0
[20:50:25.443] <TB3> INFO: flawed TBM trailers: 0
[20:50:25.443] <TB3> INFO: event ID mismatches: 0
[20:50:25.443] <TB3> INFO: ROC errors: 0
[20:50:25.443] <TB3> INFO: missing ROC header(s): 0
[20:50:25.443] <TB3> INFO: misplaced readback start: 0
[20:50:25.443] <TB3> INFO: Pixel decoding errors: 0
[20:50:25.443] <TB3> INFO: pixel data incomplete: 0
[20:50:25.443] <TB3> INFO: pixel address: 0
[20:50:25.443] <TB3> INFO: pulse height fill bit: 0
[20:50:25.443] <TB3> INFO: buffer corruption: 0
[20:50:25.450] <TB3> INFO: readReadbackCal: /home/l_tester/david/FullQualification/data/M1140_FullQualification_2016-11-07_19h53m_1478544796//001_Fulltest_m20//readbackCal_C0.dat .. /home/l_tester/david/FullQualification/data/M1140_FullQualification_2016-11-07_19h53m_1478544796//001_Fulltest_m20//readbackCal_C15.dat
[20:50:25.450] <TB3> INFO: readGainPedestalParameters /home/l_tester/david/FullQualification/data/M1140_FullQualification_2016-11-07_19h53m_1478544796//001_Fulltest_m20//phCalibrationFitErr_C0.dat .. /home/l_tester/david/FullQualification/data/M1140_FullQualification_2016-11-07_19h53m_1478544796//001_Fulltest_m20//phCalibrationFitErr_C15.dat

[20:50:25.450] <TB3> INFO: ######################################################################
[20:50:25.450] <TB3> INFO: PixTestReadback::doTest()
[20:50:25.450] <TB3> INFO: ######################################################################
[20:50:25.450] <TB3> INFO: ----------------------------------------------------------------------
[20:50:25.450] <TB3> INFO: PixTestReadback::CalibrateVd()
[20:50:25.450] <TB3> INFO: ----------------------------------------------------------------------
[20:50:35.412] <TB3> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1140_FullQualification_2016-11-07_19h53m_1478544796//001_Fulltest_m20//readbackCal_C0.dat
[20:50:35.412] <TB3> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1140_FullQualification_2016-11-07_19h53m_1478544796//001_Fulltest_m20//readbackCal_C1.dat
[20:50:35.412] <TB3> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1140_FullQualification_2016-11-07_19h53m_1478544796//001_Fulltest_m20//readbackCal_C2.dat
[20:50:35.412] <TB3> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1140_FullQualification_2016-11-07_19h53m_1478544796//001_Fulltest_m20//readbackCal_C3.dat
[20:50:35.412] <TB3> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1140_FullQualification_2016-11-07_19h53m_1478544796//001_Fulltest_m20//readbackCal_C4.dat
[20:50:35.412] <TB3> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1140_FullQualification_2016-11-07_19h53m_1478544796//001_Fulltest_m20//readbackCal_C5.dat
[20:50:35.412] <TB3> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1140_FullQualification_2016-11-07_19h53m_1478544796//001_Fulltest_m20//readbackCal_C6.dat
[20:50:35.413] <TB3> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1140_FullQualification_2016-11-07_19h53m_1478544796//001_Fulltest_m20//readbackCal_C7.dat
[20:50:35.413] <TB3> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1140_FullQualification_2016-11-07_19h53m_1478544796//001_Fulltest_m20//readbackCal_C8.dat
[20:50:35.413] <TB3> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1140_FullQualification_2016-11-07_19h53m_1478544796//001_Fulltest_m20//readbackCal_C9.dat
[20:50:35.413] <TB3> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1140_FullQualification_2016-11-07_19h53m_1478544796//001_Fulltest_m20//readbackCal_C10.dat
[20:50:35.413] <TB3> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1140_FullQualification_2016-11-07_19h53m_1478544796//001_Fulltest_m20//readbackCal_C11.dat
[20:50:35.413] <TB3> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1140_FullQualification_2016-11-07_19h53m_1478544796//001_Fulltest_m20//readbackCal_C12.dat
[20:50:35.413] <TB3> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1140_FullQualification_2016-11-07_19h53m_1478544796//001_Fulltest_m20//readbackCal_C13.dat
[20:50:35.413] <TB3> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1140_FullQualification_2016-11-07_19h53m_1478544796//001_Fulltest_m20//readbackCal_C14.dat
[20:50:35.413] <TB3> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1140_FullQualification_2016-11-07_19h53m_1478544796//001_Fulltest_m20//readbackCal_C15.dat
[20:50:35.440] <TB3> INFO: PixTestPattern:: pg_setup set to default.
[20:50:35.440] <TB3> INFO: ----------------------------------------------------------------------
[20:50:35.440] <TB3> INFO: PixTestReadback::CalibrateVa()
[20:50:35.440] <TB3> INFO: ----------------------------------------------------------------------
[20:50:45.328] <TB3> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1140_FullQualification_2016-11-07_19h53m_1478544796//001_Fulltest_m20//readbackCal_C0.dat
[20:50:45.328] <TB3> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1140_FullQualification_2016-11-07_19h53m_1478544796//001_Fulltest_m20//readbackCal_C1.dat
[20:50:45.328] <TB3> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1140_FullQualification_2016-11-07_19h53m_1478544796//001_Fulltest_m20//readbackCal_C2.dat
[20:50:45.328] <TB3> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1140_FullQualification_2016-11-07_19h53m_1478544796//001_Fulltest_m20//readbackCal_C3.dat
[20:50:45.328] <TB3> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1140_FullQualification_2016-11-07_19h53m_1478544796//001_Fulltest_m20//readbackCal_C4.dat
[20:50:45.328] <TB3> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1140_FullQualification_2016-11-07_19h53m_1478544796//001_Fulltest_m20//readbackCal_C5.dat
[20:50:45.328] <TB3> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1140_FullQualification_2016-11-07_19h53m_1478544796//001_Fulltest_m20//readbackCal_C6.dat
[20:50:45.328] <TB3> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1140_FullQualification_2016-11-07_19h53m_1478544796//001_Fulltest_m20//readbackCal_C7.dat
[20:50:45.328] <TB3> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1140_FullQualification_2016-11-07_19h53m_1478544796//001_Fulltest_m20//readbackCal_C8.dat
[20:50:45.328] <TB3> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1140_FullQualification_2016-11-07_19h53m_1478544796//001_Fulltest_m20//readbackCal_C9.dat
[20:50:45.328] <TB3> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1140_FullQualification_2016-11-07_19h53m_1478544796//001_Fulltest_m20//readbackCal_C10.dat
[20:50:45.328] <TB3> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1140_FullQualification_2016-11-07_19h53m_1478544796//001_Fulltest_m20//readbackCal_C11.dat
[20:50:45.329] <TB3> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1140_FullQualification_2016-11-07_19h53m_1478544796//001_Fulltest_m20//readbackCal_C12.dat
[20:50:45.329] <TB3> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1140_FullQualification_2016-11-07_19h53m_1478544796//001_Fulltest_m20//readbackCal_C13.dat
[20:50:45.329] <TB3> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1140_FullQualification_2016-11-07_19h53m_1478544796//001_Fulltest_m20//readbackCal_C14.dat
[20:50:45.329] <TB3> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1140_FullQualification_2016-11-07_19h53m_1478544796//001_Fulltest_m20//readbackCal_C15.dat
[20:50:45.358] <TB3> INFO: PixTestPattern:: pg_setup set to default.
[20:50:45.358] <TB3> INFO: ----------------------------------------------------------------------
[20:50:45.358] <TB3> INFO: PixTestReadback::readbackVbg()
[20:50:45.358] <TB3> INFO: ----------------------------------------------------------------------
[20:50:52.000] <TB3> INFO: PixTestPattern:: pg_setup set to default.
[20:50:52.000] <TB3> INFO: ----------------------------------------------------------------------
[20:50:52.000] <TB3> INFO: PixTestReadback::getCalibratedVbg()
[20:50:52.000] <TB3> INFO: ----------------------------------------------------------------------
[20:50:52.000] <TB3> INFO: Vbg will be calibrated using Vd calibration
[20:50:52.000] <TB3> INFO: /*/*/*/*::: ROC 0: uncalibrated Vbg = 151.3calibrated Vbg = 1.15479 :::*/*/*/*/
[20:50:52.000] <TB3> INFO: /*/*/*/*::: ROC 1: uncalibrated Vbg = 159.5calibrated Vbg = 1.15594 :::*/*/*/*/
[20:50:52.000] <TB3> INFO: /*/*/*/*::: ROC 2: uncalibrated Vbg = 151.9calibrated Vbg = 1.15292 :::*/*/*/*/
[20:50:52.000] <TB3> INFO: /*/*/*/*::: ROC 3: uncalibrated Vbg = 154.4calibrated Vbg = 1.15686 :::*/*/*/*/
[20:50:52.000] <TB3> INFO: /*/*/*/*::: ROC 4: uncalibrated Vbg = 151.6calibrated Vbg = 1.15782 :::*/*/*/*/
[20:50:52.000] <TB3> INFO: /*/*/*/*::: ROC 5: uncalibrated Vbg = 149.4calibrated Vbg = 1.15086 :::*/*/*/*/
[20:50:52.000] <TB3> INFO: /*/*/*/*::: ROC 6: uncalibrated Vbg = 155.7calibrated Vbg = 1.16294 :::*/*/*/*/
[20:50:52.000] <TB3> INFO: /*/*/*/*::: ROC 7: uncalibrated Vbg = 148.8calibrated Vbg = 1.16149 :::*/*/*/*/
[20:50:52.000] <TB3> INFO: /*/*/*/*::: ROC 8: uncalibrated Vbg = 156.6calibrated Vbg = 1.15703 :::*/*/*/*/
[20:50:52.000] <TB3> INFO: /*/*/*/*::: ROC 9: uncalibrated Vbg = 156calibrated Vbg = 1.15523 :::*/*/*/*/
[20:50:52.000] <TB3> INFO: /*/*/*/*::: ROC 10: uncalibrated Vbg = 146.2calibrated Vbg = 1.15305 :::*/*/*/*/
[20:50:52.000] <TB3> INFO: /*/*/*/*::: ROC 11: uncalibrated Vbg = 161.3calibrated Vbg = 1.14361 :::*/*/*/*/
[20:50:52.000] <TB3> INFO: /*/*/*/*::: ROC 12: uncalibrated Vbg = 155.5calibrated Vbg = 1.15871 :::*/*/*/*/
[20:50:52.000] <TB3> INFO: /*/*/*/*::: ROC 13: uncalibrated Vbg = 146.2calibrated Vbg = 1.15399 :::*/*/*/*/
[20:50:52.000] <TB3> INFO: /*/*/*/*::: ROC 14: uncalibrated Vbg = 157.6calibrated Vbg = 1.16088 :::*/*/*/*/
[20:50:52.000] <TB3> INFO: /*/*/*/*::: ROC 15: uncalibrated Vbg = 155.8calibrated Vbg = 1.14991 :::*/*/*/*/
[20:50:52.002] <TB3> INFO: ----------------------------------------------------------------------
[20:50:52.002] <TB3> INFO: PixTestReadback::CalibrateIa()
[20:50:52.002] <TB3> INFO: ----------------------------------------------------------------------
[20:53:33.295] <TB3> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1140_FullQualification_2016-11-07_19h53m_1478544796//001_Fulltest_m20//readbackCal_C0.dat
[20:53:33.296] <TB3> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1140_FullQualification_2016-11-07_19h53m_1478544796//001_Fulltest_m20//readbackCal_C1.dat
[20:53:33.296] <TB3> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1140_FullQualification_2016-11-07_19h53m_1478544796//001_Fulltest_m20//readbackCal_C2.dat
[20:53:33.296] <TB3> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1140_FullQualification_2016-11-07_19h53m_1478544796//001_Fulltest_m20//readbackCal_C3.dat
[20:53:33.296] <TB3> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1140_FullQualification_2016-11-07_19h53m_1478544796//001_Fulltest_m20//readbackCal_C4.dat
[20:53:33.296] <TB3> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1140_FullQualification_2016-11-07_19h53m_1478544796//001_Fulltest_m20//readbackCal_C5.dat
[20:53:33.296] <TB3> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1140_FullQualification_2016-11-07_19h53m_1478544796//001_Fulltest_m20//readbackCal_C6.dat
[20:53:33.296] <TB3> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1140_FullQualification_2016-11-07_19h53m_1478544796//001_Fulltest_m20//readbackCal_C7.dat
[20:53:33.296] <TB3> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1140_FullQualification_2016-11-07_19h53m_1478544796//001_Fulltest_m20//readbackCal_C8.dat
[20:53:33.296] <TB3> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1140_FullQualification_2016-11-07_19h53m_1478544796//001_Fulltest_m20//readbackCal_C9.dat
[20:53:33.296] <TB3> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1140_FullQualification_2016-11-07_19h53m_1478544796//001_Fulltest_m20//readbackCal_C10.dat
[20:53:33.296] <TB3> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1140_FullQualification_2016-11-07_19h53m_1478544796//001_Fulltest_m20//readbackCal_C11.dat
[20:53:33.296] <TB3> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1140_FullQualification_2016-11-07_19h53m_1478544796//001_Fulltest_m20//readbackCal_C12.dat
[20:53:33.297] <TB3> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1140_FullQualification_2016-11-07_19h53m_1478544796//001_Fulltest_m20//readbackCal_C13.dat
[20:53:33.297] <TB3> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1140_FullQualification_2016-11-07_19h53m_1478544796//001_Fulltest_m20//readbackCal_C14.dat
[20:53:33.297] <TB3> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1140_FullQualification_2016-11-07_19h53m_1478544796//001_Fulltest_m20//readbackCal_C15.dat
[20:53:33.324] <TB3> INFO: PixTestPattern:: pg_setup set to default.
[20:53:33.325] <TB3> INFO: PixTestReadback::doTest() done
[20:53:33.325] <TB3> INFO: Decoding statistics:
[20:53:33.325] <TB3> INFO: General information:
[20:53:33.325] <TB3> INFO: 16bit words read: 1536
[20:53:33.325] <TB3> INFO: valid events total: 256
[20:53:33.325] <TB3> INFO: empty events: 256
[20:53:33.325] <TB3> INFO: valid events with pixels: 0
[20:53:33.325] <TB3> INFO: valid pixel hits: 0
[20:53:33.325] <TB3> INFO: Event errors: 0
[20:53:33.325] <TB3> INFO: start marker: 0
[20:53:33.325] <TB3> INFO: stop marker: 0
[20:53:33.325] <TB3> INFO: overflow: 0
[20:53:33.325] <TB3> INFO: invalid 5bit words: 0
[20:53:33.325] <TB3> INFO: invalid XOR eye diagram: 0
[20:53:33.325] <TB3> INFO: frame (failed synchr.): 0
[20:53:33.325] <TB3> INFO: idle data (no TBM trl): 0
[20:53:33.325] <TB3> INFO: no data (only TBM hdr): 0
[20:53:33.325] <TB3> INFO: TBM errors: 0
[20:53:33.325] <TB3> INFO: flawed TBM headers: 0
[20:53:33.325] <TB3> INFO: flawed TBM trailers: 0
[20:53:33.325] <TB3> INFO: event ID mismatches: 0
[20:53:33.325] <TB3> INFO: ROC errors: 0
[20:53:33.325] <TB3> INFO: missing ROC header(s): 0
[20:53:33.325] <TB3> INFO: misplaced readback start: 0
[20:53:33.325] <TB3> INFO: Pixel decoding errors: 0
[20:53:33.325] <TB3> INFO: pixel data incomplete: 0
[20:53:33.325] <TB3> INFO: pixel address: 0
[20:53:33.325] <TB3> INFO: pulse height fill bit: 0
[20:53:33.325] <TB3> INFO: buffer corruption: 0
[20:53:33.360] <TB3> INFO: ######################################################################
[20:53:33.360] <TB3> INFO: PixTestBBMap::doTest() Ntrig = 5, VcalS = 250 (high range)
[20:53:33.360] <TB3> INFO: ######################################################################
[20:53:33.362] <TB3> INFO: ---> dac: VthrComp name: calSMap ntrig: 5 dacrange: 0 .. 149 (-1/-1) hits flags = 514 (plus default)
[20:53:33.374] <TB3> INFO: dacScan split into 1 runs with ntrig = 5
[20:53:33.374] <TB3> INFO: run 1 of 1
[20:53:33.608] <TB3> INFO: Expecting 3120000 events.
[20:54:03.762] <TB3> INFO: 656610 events read in total (29562ms).











[20:54:33.119] <TB3> INFO: 1310725 events read in total (58919ms).











[20:55:02.691] <TB3> INFO: 1961945 events read in total (88491ms).











[20:55:32.406] <TB3> INFO: 2613235 events read in total (118206ms).











[20:55:55.303] <TB3> INFO: 3120000 events read in total (141103ms).
[20:55:55.377] <TB3> INFO: Test took 142003ms.
[20:56:20.724] <TB3> INFO: PixTestBBMap::doTest() done with 2 decoding errors: , duration: 167 seconds
[20:56:20.724] <TB3> INFO: number of dead bumps (per ROC): 0 0 0 1 0 0 1 0 0 0 0 0 1 0 0 0
[20:56:20.724] <TB3> INFO: separation cut (per ROC): 111 96 109 101 106 102 99 99 100 91 106 104 95 105 106 101
[20:56:20.724] <TB3> INFO: Decoding statistics:
[20:56:20.724] <TB3> INFO: General information:
[20:56:20.724] <TB3> INFO: 16bit words read: 0
[20:56:20.724] <TB3> INFO: valid events total: 0
[20:56:20.724] <TB3> INFO: empty events: 0
[20:56:20.724] <TB3> INFO: valid events with pixels: 0
[20:56:20.724] <TB3> INFO: valid pixel hits: 0
[20:56:20.724] <TB3> INFO: Event errors: 0
[20:56:20.724] <TB3> INFO: start marker: 0
[20:56:20.724] <TB3> INFO: stop marker: 0
[20:56:20.724] <TB3> INFO: overflow: 0
[20:56:20.724] <TB3> INFO: invalid 5bit words: 0
[20:56:20.724] <TB3> INFO: invalid XOR eye diagram: 0
[20:56:20.724] <TB3> INFO: frame (failed synchr.): 0
[20:56:20.724] <TB3> INFO: idle data (no TBM trl): 0
[20:56:20.724] <TB3> INFO: no data (only TBM hdr): 0
[20:56:20.724] <TB3> INFO: TBM errors: 0
[20:56:20.724] <TB3> INFO: flawed TBM headers: 0
[20:56:20.724] <TB3> INFO: flawed TBM trailers: 0
[20:56:20.724] <TB3> INFO: event ID mismatches: 0
[20:56:20.724] <TB3> INFO: ROC errors: 0
[20:56:20.724] <TB3> INFO: missing ROC header(s): 0
[20:56:20.724] <TB3> INFO: misplaced readback start: 0
[20:56:20.724] <TB3> INFO: Pixel decoding errors: 0
[20:56:20.724] <TB3> INFO: pixel data incomplete: 0
[20:56:20.724] <TB3> INFO: pixel address: 0
[20:56:20.724] <TB3> INFO: pulse height fill bit: 0
[20:56:20.724] <TB3> INFO: buffer corruption: 0
[20:56:20.769] <TB3> INFO: ######################################################################
[20:56:20.769] <TB3> INFO: PixTestScurves::fullTest() ntrig = 50, dacs/step = -1, ntrig/step = -1
[20:56:20.769] <TB3> INFO: ######################################################################
[20:56:20.769] <TB3> INFO: ----------------------------------------------------------------------
[20:56:20.769] <TB3> INFO: PixTestScurves::scurves(Vcal), ntrig = 50, dacs/step = -1, ntrig/step = -1
[20:56:20.769] <TB3> INFO: ----------------------------------------------------------------------
[20:56:20.769] <TB3> INFO: ---> dac: Vcal name: scurveVcal ntrig: 50 dacrange: 25 .. 200 (-1/-1) hits flags = 528 (plus default)
[20:56:20.781] <TB3> INFO: dacScan split into 1 runs with ntrig = 50
[20:56:20.781] <TB3> INFO: run 1 of 1
[20:56:21.050] <TB3> INFO: Expecting 36608000 events.
[20:56:43.939] <TB3> INFO: 664800 events read in total (22297ms).
[20:57:06.595] <TB3> INFO: 1319300 events read in total (44953ms).
[20:57:29.068] <TB3> INFO: 1973050 events read in total (67426ms).
[20:57:51.398] <TB3> INFO: 2626550 events read in total (89756ms).
[20:58:13.891] <TB3> INFO: 3280050 events read in total (112249ms).
[20:58:36.246] <TB3> INFO: 3933450 events read in total (134604ms).
[20:58:58.738] <TB3> INFO: 4586050 events read in total (157096ms).
[20:59:20.995] <TB3> INFO: 5239800 events read in total (179353ms).
[20:59:43.433] <TB3> INFO: 5892050 events read in total (201791ms).
[21:00:05.635] <TB3> INFO: 6544850 events read in total (223993ms).
[21:00:27.941] <TB3> INFO: 7197100 events read in total (246299ms).
[21:00:50.473] <TB3> INFO: 7849100 events read in total (268831ms).
[21:01:12.875] <TB3> INFO: 8502900 events read in total (291233ms).
[21:01:34.928] <TB3> INFO: 9153250 events read in total (313286ms).
[21:01:57.107] <TB3> INFO: 9803500 events read in total (335465ms).
[21:02:19.367] <TB3> INFO: 10455550 events read in total (357725ms).
[21:02:41.675] <TB3> INFO: 11109800 events read in total (380033ms).
[21:03:03.933] <TB3> INFO: 11759850 events read in total (402291ms).
[21:03:26.063] <TB3> INFO: 12410100 events read in total (424421ms).
[21:03:48.456] <TB3> INFO: 13058950 events read in total (446814ms).
[21:04:10.628] <TB3> INFO: 13709100 events read in total (468986ms).
[21:04:32.960] <TB3> INFO: 14359200 events read in total (491318ms).
[21:04:55.207] <TB3> INFO: 15009250 events read in total (513565ms).
[21:05:17.579] <TB3> INFO: 15656850 events read in total (535937ms).
[21:05:39.657] <TB3> INFO: 16307700 events read in total (558015ms).
[21:06:02.053] <TB3> INFO: 16958850 events read in total (580411ms).
[21:06:24.193] <TB3> INFO: 17607300 events read in total (602551ms).
[21:06:46.280] <TB3> INFO: 18256950 events read in total (624638ms).
[21:07:08.809] <TB3> INFO: 18904200 events read in total (647167ms).
[21:07:31.069] <TB3> INFO: 19554800 events read in total (669427ms).
[21:07:53.266] <TB3> INFO: 20202600 events read in total (691624ms).
[21:08:15.527] <TB3> INFO: 20851800 events read in total (713885ms).
[21:08:37.816] <TB3> INFO: 21499500 events read in total (736174ms).
[21:09:00.191] <TB3> INFO: 22147750 events read in total (758549ms).
[21:09:22.048] <TB3> INFO: 22792650 events read in total (780406ms).
[21:09:44.103] <TB3> INFO: 23439750 events read in total (802461ms).
[21:10:06.419] <TB3> INFO: 24085600 events read in total (824777ms).
[21:10:28.552] <TB3> INFO: 24732350 events read in total (846910ms).
[21:10:50.727] <TB3> INFO: 25378850 events read in total (869085ms).
[21:11:12.733] <TB3> INFO: 26025200 events read in total (891091ms).
[21:11:35.171] <TB3> INFO: 26672450 events read in total (913530ms).
[21:11:57.451] <TB3> INFO: 27319050 events read in total (935809ms).
[21:12:19.417] <TB3> INFO: 27964700 events read in total (957775ms).
[21:12:41.353] <TB3> INFO: 28608950 events read in total (979711ms).
[21:13:03.813] <TB3> INFO: 29254300 events read in total (1002171ms).
[21:13:25.900] <TB3> INFO: 29899750 events read in total (1024258ms).
[21:13:48.076] <TB3> INFO: 30544500 events read in total (1046434ms).
[21:14:10.424] <TB3> INFO: 31192200 events read in total (1068782ms).
[21:14:32.754] <TB3> INFO: 31837150 events read in total (1091112ms).
[21:14:55.234] <TB3> INFO: 32485500 events read in total (1113592ms).
[21:15:17.536] <TB3> INFO: 33132250 events read in total (1135894ms).
[21:15:39.870] <TB3> INFO: 33781950 events read in total (1158228ms).
[21:16:02.153] <TB3> INFO: 34430100 events read in total (1180511ms).
[21:16:24.364] <TB3> INFO: 35078850 events read in total (1202722ms).
[21:16:47.180] <TB3> INFO: 35727000 events read in total (1225538ms).
[21:17:09.001] <TB3> INFO: 36384850 events read in total (1248360ms).
[21:17:17.910] <TB3> INFO: 36608000 events read in total (1256268ms).
[21:17:17.965] <TB3> INFO: Test took 1257184ms.
[21:17:18.377] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[21:17:20.224] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[21:17:22.286] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[21:17:24.462] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[21:17:26.700] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[21:17:28.763] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[21:17:30.720] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[21:17:32.684] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[21:17:34.619] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[21:17:36.627] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[21:17:38.666] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[21:17:40.378] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[21:17:41.884] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[21:17:43.471] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[21:17:44.937] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[21:17:46.385] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[21:17:47.864] <TB3> INFO: PixTestScurves::scurves() done
[21:17:47.865] <TB3> INFO: Vcal mean: 121.63 107.54 112.05 104.98 112.27 112.43 103.02 105.47 115.43 98.87 108.44 114.45 111.32 114.03 117.17 117.72
[21:17:47.865] <TB3> INFO: Vcal RMS: 5.46 5.31 5.88 5.69 4.68 4.75 5.42 4.87 5.55 5.55 5.61 5.55 5.16 4.97 5.65 5.87
[21:17:47.865] <TB3> INFO: PixTestScurves::fullTest() done, duration: 1287 seconds
[21:17:47.865] <TB3> INFO: Decoding statistics:
[21:17:47.865] <TB3> INFO: General information:
[21:17:47.865] <TB3> INFO: 16bit words read: 0
[21:17:47.865] <TB3> INFO: valid events total: 0
[21:17:47.865] <TB3> INFO: empty events: 0
[21:17:47.865] <TB3> INFO: valid events with pixels: 0
[21:17:47.865] <TB3> INFO: valid pixel hits: 0
[21:17:47.865] <TB3> INFO: Event errors: 0
[21:17:47.865] <TB3> INFO: start marker: 0
[21:17:47.865] <TB3> INFO: stop marker: 0
[21:17:47.865] <TB3> INFO: overflow: 0
[21:17:47.865] <TB3> INFO: invalid 5bit words: 0
[21:17:47.865] <TB3> INFO: invalid XOR eye diagram: 0
[21:17:47.865] <TB3> INFO: frame (failed synchr.): 0
[21:17:47.865] <TB3> INFO: idle data (no TBM trl): 0
[21:17:47.865] <TB3> INFO: no data (only TBM hdr): 0
[21:17:47.865] <TB3> INFO: TBM errors: 0
[21:17:47.865] <TB3> INFO: flawed TBM headers: 0
[21:17:47.865] <TB3> INFO: flawed TBM trailers: 0
[21:17:47.865] <TB3> INFO: event ID mismatches: 0
[21:17:47.865] <TB3> INFO: ROC errors: 0
[21:17:47.865] <TB3> INFO: missing ROC header(s): 0
[21:17:47.865] <TB3> INFO: misplaced readback start: 0
[21:17:47.865] <TB3> INFO: Pixel decoding errors: 0
[21:17:47.865] <TB3> INFO: pixel data incomplete: 0
[21:17:47.865] <TB3> INFO: pixel address: 0
[21:17:47.865] <TB3> INFO: pulse height fill bit: 0
[21:17:47.865] <TB3> INFO: buffer corruption: 0
[21:17:47.948] <TB3> INFO: ######################################################################
[21:17:47.948] <TB3> INFO: PixTestTrim::doTest()
[21:17:47.948] <TB3> INFO: ######################################################################
[21:17:47.949] <TB3> INFO: ----------------------------------------------------------------------
[21:17:47.949] <TB3> INFO: PixTestTrim::trimTest() ntrig = 8, vcal = 35
[21:17:47.949] <TB3> INFO: ----------------------------------------------------------------------
[21:17:48.005] <TB3> INFO: ---> VthrComp thr map (minimal VthrComp)
[21:17:48.006] <TB3> INFO: ---> dac: vthrcomp name: TrimThr0 ntrig: 8 dacrange: 10 .. 160 (-1/-1) hits flags = 528 (plus default)
[21:17:48.016] <TB3> INFO: dacScan split into 1 runs with ntrig = 8
[21:17:48.016] <TB3> INFO: run 1 of 1
[21:17:48.290] <TB3> INFO: Expecting 5025280 events.
[21:18:18.937] <TB3> INFO: 820504 events read in total (30053ms).
[21:18:48.459] <TB3> INFO: 1639096 events read in total (59575ms).
[21:19:18.160] <TB3> INFO: 2454480 events read in total (89276ms).
[21:19:47.571] <TB3> INFO: 3263552 events read in total (118687ms).
[21:20:16.991] <TB3> INFO: 4067632 events read in total (148108ms).
[21:20:46.601] <TB3> INFO: 4869184 events read in total (177717ms).
[21:20:52.943] <TB3> INFO: 5025280 events read in total (184059ms).
[21:20:52.991] <TB3> INFO: Test took 184974ms.
[21:21:13.211] <TB3> INFO: ROC 0 VthrComp = 130
[21:21:13.211] <TB3> INFO: ROC 1 VthrComp = 102
[21:21:13.211] <TB3> INFO: ROC 2 VthrComp = 123
[21:21:13.211] <TB3> INFO: ROC 3 VthrComp = 110
[21:21:13.211] <TB3> INFO: ROC 4 VthrComp = 121
[21:21:13.211] <TB3> INFO: ROC 5 VthrComp = 114
[21:21:13.212] <TB3> INFO: ROC 6 VthrComp = 105
[21:21:13.212] <TB3> INFO: ROC 7 VthrComp = 109
[21:21:13.212] <TB3> INFO: ROC 8 VthrComp = 122
[21:21:13.212] <TB3> INFO: ROC 9 VthrComp = 104
[21:21:13.212] <TB3> INFO: ROC 10 VthrComp = 112
[21:21:13.212] <TB3> INFO: ROC 11 VthrComp = 118
[21:21:13.212] <TB3> INFO: ROC 12 VthrComp = 115
[21:21:13.213] <TB3> INFO: ROC 13 VthrComp = 122
[21:21:13.213] <TB3> INFO: ROC 14 VthrComp = 126
[21:21:13.213] <TB3> INFO: ROC 15 VthrComp = 113
[21:21:13.476] <TB3> INFO: Expecting 41600 events.
[21:21:17.045] <TB3> INFO: 41600 events read in total (2977ms).
[21:21:17.046] <TB3> INFO: Test took 3832ms.
[21:21:17.055] <TB3> INFO: ---> Vcal thr map (pixel with maximum Vcal thr)
[21:21:17.055] <TB3> INFO: ---> dac: vcal name: TrimThr1 ntrig: 8 dacrange: 10 .. 160 (-1/-1) hits flags = 528 (plus default)
[21:21:17.064] <TB3> INFO: dacScan split into 1 runs with ntrig = 8
[21:21:17.064] <TB3> INFO: run 1 of 1
[21:21:17.342] <TB3> INFO: Expecting 5025280 events.
[21:21:43.330] <TB3> INFO: 589776 events read in total (25396ms).
[21:22:08.557] <TB3> INFO: 1178368 events read in total (50623ms).
[21:22:34.207] <TB3> INFO: 1767576 events read in total (76273ms).
[21:22:59.312] <TB3> INFO: 2355952 events read in total (101378ms).
[21:23:24.439] <TB3> INFO: 2943016 events read in total (126505ms).
[21:23:49.625] <TB3> INFO: 3528896 events read in total (151691ms).
[21:24:14.674] <TB3> INFO: 4113808 events read in total (176740ms).
[21:24:40.578] <TB3> INFO: 4697952 events read in total (202644ms).
[21:24:54.737] <TB3> INFO: 5025280 events read in total (216803ms).
[21:24:54.794] <TB3> INFO: Test took 217730ms.
[21:25:19.245] <TB3> INFO: roc 0 with ID = 0 has maximal Vcal 57.9963 for pixel 17/28 mean/min/max = 44.8744/31.2279/58.5209
[21:25:19.245] <TB3> INFO: roc 1 with ID = 1 has maximal Vcal 65.4246 for pixel 18/22 mean/min/max = 49.1257/32.744/65.5075
[21:25:19.245] <TB3> INFO: roc 2 with ID = 2 has maximal Vcal 59.4612 for pixel 22/4 mean/min/max = 45.1087/30.6098/59.6077
[21:25:19.246] <TB3> INFO: roc 3 with ID = 3 has maximal Vcal 62.3542 for pixel 16/2 mean/min/max = 48.0073/33.5689/62.4457
[21:25:19.246] <TB3> INFO: roc 4 with ID = 4 has maximal Vcal 57.0865 for pixel 48/13 mean/min/max = 44.8869/32.0814/57.6924
[21:25:19.246] <TB3> INFO: roc 5 with ID = 5 has maximal Vcal 59.378 for pixel 1/17 mean/min/max = 46.0556/32.6126/59.4986
[21:25:19.246] <TB3> INFO: roc 6 with ID = 6 has maximal Vcal 61.5277 for pixel 0/6 mean/min/max = 48.1278/34.7053/61.5504
[21:25:19.247] <TB3> INFO: roc 7 with ID = 7 has maximal Vcal 61.1133 for pixel 11/13 mean/min/max = 47.9186/34.563/61.2741
[21:25:19.247] <TB3> INFO: roc 8 with ID = 8 has maximal Vcal 62.0256 for pixel 21/7 mean/min/max = 46.3776/30.4954/62.2598
[21:25:19.247] <TB3> INFO: roc 9 with ID = 9 has maximal Vcal 59.5813 for pixel 1/11 mean/min/max = 47.2096/34.5298/59.8894
[21:25:19.248] <TB3> INFO: roc 10 with ID = 10 has maximal Vcal 60.2164 for pixel 1/72 mean/min/max = 46.2612/32.1498/60.3726
[21:25:19.248] <TB3> INFO: roc 11 with ID = 11 has maximal Vcal 60.6248 for pixel 11/9 mean/min/max = 46.1576/31.4664/60.8488
[21:25:19.248] <TB3> INFO: roc 12 with ID = 12 has maximal Vcal 59.5972 for pixel 16/8 mean/min/max = 45.606/31.5119/59.7001
[21:25:19.248] <TB3> INFO: roc 13 with ID = 13 has maximal Vcal 58.6854 for pixel 8/2 mean/min/max = 45.4973/32.2511/58.7434
[21:25:19.249] <TB3> INFO: roc 14 with ID = 14 has maximal Vcal 60.1617 for pixel 14/9 mean/min/max = 45.4472/30.5252/60.3691
[21:25:19.249] <TB3> INFO: roc 15 with ID = 15 has maximal Vcal 63.5203 for pixel 22/0 mean/min/max = 47.6213/31.5511/63.6916
[21:25:19.249] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[21:25:19.338] <TB3> INFO: Expecting 411648 events.
[21:25:28.613] <TB3> INFO: 411648 events read in total (8684ms).
[21:25:28.621] <TB3> INFO: Expecting 411648 events.
[21:25:37.632] <TB3> INFO: 411648 events read in total (8608ms).
[21:25:37.642] <TB3> INFO: Expecting 411648 events.
[21:25:46.765] <TB3> INFO: 411648 events read in total (8720ms).
[21:25:46.782] <TB3> INFO: Expecting 411648 events.
[21:25:55.760] <TB3> INFO: 411648 events read in total (8575ms).
[21:25:55.776] <TB3> INFO: Expecting 411648 events.
[21:26:04.825] <TB3> INFO: 411648 events read in total (8646ms).
[21:26:04.843] <TB3> INFO: Expecting 411648 events.
[21:26:13.838] <TB3> INFO: 411648 events read in total (8593ms).
[21:26:13.866] <TB3> INFO: Expecting 411648 events.
[21:26:22.870] <TB3> INFO: 411648 events read in total (8601ms).
[21:26:22.893] <TB3> INFO: Expecting 411648 events.
[21:26:31.927] <TB3> INFO: 411648 events read in total (8632ms).
[21:26:31.953] <TB3> INFO: Expecting 411648 events.
[21:26:41.042] <TB3> INFO: 411648 events read in total (8675ms).
[21:26:41.081] <TB3> INFO: Expecting 411648 events.
[21:26:50.042] <TB3> INFO: 411648 events read in total (8558ms).
[21:26:50.074] <TB3> INFO: Expecting 411648 events.
[21:26:59.090] <TB3> INFO: 411648 events read in total (8613ms).
[21:26:59.134] <TB3> INFO: Expecting 411648 events.
[21:27:08.134] <TB3> INFO: 411648 events read in total (8597ms).
[21:27:08.185] <TB3> INFO: Expecting 411648 events.
[21:27:17.203] <TB3> INFO: 411648 events read in total (8615ms).
[21:27:17.256] <TB3> INFO: Expecting 411648 events.
[21:27:26.371] <TB3> INFO: 411648 events read in total (8712ms).
[21:27:26.416] <TB3> INFO: Expecting 411648 events.
[21:27:35.511] <TB3> INFO: 411648 events read in total (8692ms).
[21:27:35.559] <TB3> INFO: Expecting 411648 events.
[21:27:44.595] <TB3> INFO: 411648 events read in total (8634ms).
[21:27:44.648] <TB3> INFO: Test took 145399ms.
[21:27:45.444] <TB3> INFO: ---> dac: vcal name: TrimThr2 ntrig: 8 dacrange: 0 .. 150 (-1/-1) hits flags = 528 (plus default)
[21:27:45.455] <TB3> INFO: dacScan split into 1 runs with ntrig = 8
[21:27:45.455] <TB3> INFO: run 1 of 1
[21:27:45.721] <TB3> INFO: Expecting 5025280 events.
[21:28:11.776] <TB3> INFO: 586648 events read in total (25463ms).
[21:28:36.904] <TB3> INFO: 1170080 events read in total (50591ms).
[21:29:02.091] <TB3> INFO: 1753136 events read in total (75778ms).
[21:29:27.258] <TB3> INFO: 2335912 events read in total (100945ms).
[21:29:52.666] <TB3> INFO: 2918944 events read in total (126353ms).
[21:30:17.880] <TB3> INFO: 3501216 events read in total (151567ms).
[21:30:43.733] <TB3> INFO: 4086696 events read in total (177420ms).
[21:31:09.349] <TB3> INFO: 4670112 events read in total (203036ms).
[21:31:25.201] <TB3> INFO: 5025280 events read in total (218888ms).
[21:31:25.326] <TB3> INFO: Test took 219872ms.
[21:31:49.178] <TB3> INFO: ---> TrimStepCorr4 extremal thresholds: 4.299565 .. 147.353001
[21:31:49.412] <TB3> INFO: Expecting 208000 events.
[21:31:58.917] <TB3> INFO: 208000 events read in total (8914ms).
[21:31:58.918] <TB3> INFO: Test took 9739ms.
[21:31:58.968] <TB3> INFO: ---> dac: vcal name: trimStepCorr4 ntrig: 8 dacrange: 4 .. 157 (-1/-1) hits flags = 528 (plus default)
[21:31:58.979] <TB3> INFO: dacScan split into 1 runs with ntrig = 8
[21:31:58.979] <TB3> INFO: run 1 of 1
[21:31:59.262] <TB3> INFO: Expecting 5125120 events.
[21:32:24.913] <TB3> INFO: 579760 events read in total (25060ms).
[21:32:50.063] <TB3> INFO: 1160048 events read in total (50210ms).
[21:33:15.211] <TB3> INFO: 1739744 events read in total (75358ms).
[21:33:40.553] <TB3> INFO: 2319312 events read in total (100700ms).
[21:34:05.744] <TB3> INFO: 2898912 events read in total (125891ms).
[21:34:31.068] <TB3> INFO: 3478400 events read in total (151215ms).
[21:34:56.463] <TB3> INFO: 4056928 events read in total (176610ms).
[21:35:21.978] <TB3> INFO: 4634192 events read in total (202125ms).
[21:35:44.473] <TB3> INFO: 5125120 events read in total (224620ms).
[21:35:44.560] <TB3> INFO: Test took 225581ms.
[21:36:11.008] <TB3> INFO: ---> TrimStepCorr2 extremal thresholds: 27.500000 .. 44.611383
[21:36:11.274] <TB3> INFO: Expecting 208000 events.
[21:36:20.963] <TB3> INFO: 208000 events read in total (9097ms).
[21:36:20.964] <TB3> INFO: Test took 9954ms.
[21:36:21.011] <TB3> INFO: ---> dac: vcal name: trimStepCorr2 ntrig: 8 dacrange: 17 .. 54 (-1/-1) hits flags = 528 (plus default)
[21:36:21.020] <TB3> INFO: dacScan split into 1 runs with ntrig = 8
[21:36:21.020] <TB3> INFO: run 1 of 1
[21:36:21.297] <TB3> INFO: Expecting 1264640 events.
[21:36:49.704] <TB3> INFO: 662600 events read in total (27815ms).
[21:37:14.985] <TB3> INFO: 1264640 events read in total (53097ms).
[21:37:15.012] <TB3> INFO: Test took 53993ms.
[21:37:27.680] <TB3> INFO: ---> TrimStepCorr1a extremal thresholds: 25.604257 .. 46.658337
[21:37:27.954] <TB3> INFO: Expecting 208000 events.
[21:37:37.710] <TB3> INFO: 208000 events read in total (9164ms).
[21:37:37.710] <TB3> INFO: Test took 10028ms.
[21:37:37.755] <TB3> INFO: ---> dac: vcal name: trimStepCorr1a ntrig: 8 dacrange: 15 .. 56 (-1/-1) hits flags = 528 (plus default)
[21:37:37.766] <TB3> INFO: dacScan split into 1 runs with ntrig = 8
[21:37:37.766] <TB3> INFO: run 1 of 1
[21:37:38.043] <TB3> INFO: Expecting 1397760 events.
[21:38:06.306] <TB3> INFO: 663240 events read in total (27671ms).
[21:38:33.908] <TB3> INFO: 1325808 events read in total (55273ms).
[21:38:37.313] <TB3> INFO: 1397760 events read in total (58678ms).
[21:38:37.342] <TB3> INFO: Test took 59576ms.
[21:38:50.643] <TB3> INFO: ---> TrimStepCorr1b extremal thresholds: 24.165831 .. 44.701742
[21:38:50.887] <TB3> INFO: Expecting 208000 events.
[21:39:00.498] <TB3> INFO: 208000 events read in total (9020ms).
[21:39:00.499] <TB3> INFO: Test took 9855ms.
[21:39:00.544] <TB3> INFO: ---> dac: vcal name: trimStepCorr1b ntrig: 8 dacrange: 14 .. 54 (-1/-1) hits flags = 528 (plus default)
[21:39:00.554] <TB3> INFO: dacScan split into 1 runs with ntrig = 8
[21:39:00.554] <TB3> INFO: run 1 of 1
[21:39:00.832] <TB3> INFO: Expecting 1364480 events.
[21:39:29.364] <TB3> INFO: 675952 events read in total (27941ms).
[21:39:57.227] <TB3> INFO: 1351240 events read in total (55804ms).
[21:39:58.205] <TB3> INFO: 1364480 events read in total (56783ms).
[21:39:58.228] <TB3> INFO: Test took 57674ms.
[21:40:12.269] <TB3> INFO: ---> TrimThrFinal extremal thresholds: 15 .. 55
[21:40:12.269] <TB3> INFO: ---> dac: vcal name: TrimThrFinal ntrig: 8 dacrange: 15 .. 55 (-1/-1) hits flags = 528 (plus default)
[21:40:12.278] <TB3> INFO: dacScan split into 1 runs with ntrig = 8
[21:40:12.278] <TB3> INFO: run 1 of 1
[21:40:12.525] <TB3> INFO: Expecting 1364480 events.
[21:40:40.598] <TB3> INFO: 667600 events read in total (27481ms).
[21:41:08.040] <TB3> INFO: 1334288 events read in total (54923ms).
[21:41:09.650] <TB3> INFO: 1364480 events read in total (56534ms).
[21:41:09.671] <TB3> INFO: Test took 57394ms.
[21:41:23.063] <TB3> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1140_FullQualification_2016-11-07_19h53m_1478544796//001_Fulltest_m20//dacParameters35_C0.dat
[21:41:23.063] <TB3> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1140_FullQualification_2016-11-07_19h53m_1478544796//001_Fulltest_m20//dacParameters35_C1.dat
[21:41:23.063] <TB3> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1140_FullQualification_2016-11-07_19h53m_1478544796//001_Fulltest_m20//dacParameters35_C2.dat
[21:41:23.063] <TB3> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1140_FullQualification_2016-11-07_19h53m_1478544796//001_Fulltest_m20//dacParameters35_C3.dat
[21:41:23.063] <TB3> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1140_FullQualification_2016-11-07_19h53m_1478544796//001_Fulltest_m20//dacParameters35_C4.dat
[21:41:23.063] <TB3> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1140_FullQualification_2016-11-07_19h53m_1478544796//001_Fulltest_m20//dacParameters35_C5.dat
[21:41:23.063] <TB3> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1140_FullQualification_2016-11-07_19h53m_1478544796//001_Fulltest_m20//dacParameters35_C6.dat
[21:41:23.063] <TB3> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1140_FullQualification_2016-11-07_19h53m_1478544796//001_Fulltest_m20//dacParameters35_C7.dat
[21:41:23.064] <TB3> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1140_FullQualification_2016-11-07_19h53m_1478544796//001_Fulltest_m20//dacParameters35_C8.dat
[21:41:23.064] <TB3> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1140_FullQualification_2016-11-07_19h53m_1478544796//001_Fulltest_m20//dacParameters35_C9.dat
[21:41:23.064] <TB3> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1140_FullQualification_2016-11-07_19h53m_1478544796//001_Fulltest_m20//dacParameters35_C10.dat
[21:41:23.064] <TB3> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1140_FullQualification_2016-11-07_19h53m_1478544796//001_Fulltest_m20//dacParameters35_C11.dat
[21:41:23.064] <TB3> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1140_FullQualification_2016-11-07_19h53m_1478544796//001_Fulltest_m20//dacParameters35_C12.dat
[21:41:23.064] <TB3> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1140_FullQualification_2016-11-07_19h53m_1478544796//001_Fulltest_m20//dacParameters35_C13.dat
[21:41:23.064] <TB3> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1140_FullQualification_2016-11-07_19h53m_1478544796//001_Fulltest_m20//dacParameters35_C14.dat
[21:41:23.064] <TB3> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1140_FullQualification_2016-11-07_19h53m_1478544796//001_Fulltest_m20//dacParameters35_C15.dat
[21:41:23.064] <TB3> INFO: write trim parameters into /home/l_tester/david/FullQualification/data/M1140_FullQualification_2016-11-07_19h53m_1478544796//001_Fulltest_m20//trimParameters35_C0.dat
[21:41:23.070] <TB3> INFO: write trim parameters into /home/l_tester/david/FullQualification/data/M1140_FullQualification_2016-11-07_19h53m_1478544796//001_Fulltest_m20//trimParameters35_C1.dat
[21:41:23.075] <TB3> INFO: write trim parameters into /home/l_tester/david/FullQualification/data/M1140_FullQualification_2016-11-07_19h53m_1478544796//001_Fulltest_m20//trimParameters35_C2.dat
[21:41:23.081] <TB3> INFO: write trim parameters into /home/l_tester/david/FullQualification/data/M1140_FullQualification_2016-11-07_19h53m_1478544796//001_Fulltest_m20//trimParameters35_C3.dat
[21:41:23.086] <TB3> INFO: write trim parameters into /home/l_tester/david/FullQualification/data/M1140_FullQualification_2016-11-07_19h53m_1478544796//001_Fulltest_m20//trimParameters35_C4.dat
[21:41:23.092] <TB3> INFO: write trim parameters into /home/l_tester/david/FullQualification/data/M1140_FullQualification_2016-11-07_19h53m_1478544796//001_Fulltest_m20//trimParameters35_C5.dat
[21:41:23.097] <TB3> INFO: write trim parameters into /home/l_tester/david/FullQualification/data/M1140_FullQualification_2016-11-07_19h53m_1478544796//001_Fulltest_m20//trimParameters35_C6.dat
[21:41:23.103] <TB3> INFO: write trim parameters into /home/l_tester/david/FullQualification/data/M1140_FullQualification_2016-11-07_19h53m_1478544796//001_Fulltest_m20//trimParameters35_C7.dat
[21:41:23.109] <TB3> INFO: write trim parameters into /home/l_tester/david/FullQualification/data/M1140_FullQualification_2016-11-07_19h53m_1478544796//001_Fulltest_m20//trimParameters35_C8.dat
[21:41:23.115] <TB3> INFO: write trim parameters into /home/l_tester/david/FullQualification/data/M1140_FullQualification_2016-11-07_19h53m_1478544796//001_Fulltest_m20//trimParameters35_C9.dat
[21:41:23.120] <TB3> INFO: write trim parameters into /home/l_tester/david/FullQualification/data/M1140_FullQualification_2016-11-07_19h53m_1478544796//001_Fulltest_m20//trimParameters35_C10.dat
[21:41:23.128] <TB3> INFO: write trim parameters into /home/l_tester/david/FullQualification/data/M1140_FullQualification_2016-11-07_19h53m_1478544796//001_Fulltest_m20//trimParameters35_C11.dat
[21:41:23.137] <TB3> INFO: write trim parameters into /home/l_tester/david/FullQualification/data/M1140_FullQualification_2016-11-07_19h53m_1478544796//001_Fulltest_m20//trimParameters35_C12.dat
[21:41:23.145] <TB3> INFO: write trim parameters into /home/l_tester/david/FullQualification/data/M1140_FullQualification_2016-11-07_19h53m_1478544796//001_Fulltest_m20//trimParameters35_C13.dat
[21:41:23.154] <TB3> INFO: write trim parameters into /home/l_tester/david/FullQualification/data/M1140_FullQualification_2016-11-07_19h53m_1478544796//001_Fulltest_m20//trimParameters35_C14.dat
[21:41:23.163] <TB3> INFO: write trim parameters into /home/l_tester/david/FullQualification/data/M1140_FullQualification_2016-11-07_19h53m_1478544796//001_Fulltest_m20//trimParameters35_C15.dat
[21:41:23.172] <TB3> INFO: PixTestTrim::trimTest() done
[21:41:23.172] <TB3> INFO: vtrim: 158 156 135 143 132 130 141 125 161 125 117 129 122 144 159 142
[21:41:23.172] <TB3> INFO: vthrcomp: 130 102 123 110 121 114 105 109 122 104 112 118 115 122 126 113
[21:41:23.172] <TB3> INFO: vcal mean: 34.90 35.07 34.91 34.99 34.96 34.94 34.94 34.97 34.97 35.00 34.95 34.97 34.96 34.95 34.94 35.23
[21:41:23.172] <TB3> INFO: vcal RMS: 1.04 1.19 1.05 0.97 1.02 1.03 1.10 0.98 1.19 0.93 0.99 1.03 1.03 0.97 1.09 1.48
[21:41:23.172] <TB3> INFO: bits mean: 10.39 9.76 10.17 9.07 10.21 9.79 8.90 8.66 10.28 8.72 9.33 9.41 9.51 9.62 10.32 10.09
[21:41:23.172] <TB3> INFO: bits RMS: 2.40 2.33 2.53 2.49 2.39 2.46 2.43 2.61 2.50 2.57 2.71 2.74 2.74 2.59 2.51 2.43
[21:41:23.180] <TB3> INFO: ----------------------------------------------------------------------
[21:41:23.180] <TB3> INFO: PixTestTrim::trimBitTest() ntrig = 5, vtrims = 254 126 63 32
[21:41:23.180] <TB3> INFO: ----------------------------------------------------------------------
[21:41:23.183] <TB3> INFO: ---> dac: Vcal name: TrimBitsThr0 ntrig: 5 dacrange: 0 .. 199 (-1/-1) hits flags = 528 (plus default)
[21:41:23.193] <TB3> INFO: dacScan split into 1 runs with ntrig = 5
[21:41:23.194] <TB3> INFO: run 1 of 1
[21:41:23.426] <TB3> INFO: Expecting 4160000 events.
[21:41:54.561] <TB3> INFO: 732005 events read in total (30543ms).
[21:42:25.270] <TB3> INFO: 1459470 events read in total (61252ms).
[21:42:56.228] <TB3> INFO: 2183710 events read in total (92210ms).
[21:43:27.021] <TB3> INFO: 2905870 events read in total (123003ms).
[21:43:57.624] <TB3> INFO: 3625915 events read in total (153606ms).
[21:44:20.784] <TB3> INFO: 4160000 events read in total (176766ms).
[21:44:20.858] <TB3> INFO: Test took 177664ms.
[21:44:47.810] <TB3> INFO: ---> dac: Vcal name: TrimThr_trim14 ntrig: 5 dacrange: 0 .. 191 (-1/-1) hits flags = 528 (plus default)
[21:44:47.820] <TB3> INFO: dacScan split into 1 runs with ntrig = 5
[21:44:47.820] <TB3> INFO: run 1 of 1
[21:44:48.054] <TB3> INFO: Expecting 3993600 events.
[21:45:19.140] <TB3> INFO: 718895 events read in total (30495ms).
[21:45:49.278] <TB3> INFO: 1433390 events read in total (60633ms).
[21:46:19.606] <TB3> INFO: 2145820 events read in total (90961ms).
[21:46:50.114] <TB3> INFO: 2855385 events read in total (121469ms).
[21:47:20.710] <TB3> INFO: 3563685 events read in total (152065ms).
[21:47:39.409] <TB3> INFO: 3993600 events read in total (170764ms).
[21:47:39.474] <TB3> INFO: Test took 171654ms.
[21:48:06.455] <TB3> INFO: ---> dac: Vcal name: TrimThr_trim13 ntrig: 5 dacrange: 0 .. 178 (-1/-1) hits flags = 528 (plus default)
[21:48:06.466] <TB3> INFO: dacScan split into 1 runs with ntrig = 5
[21:48:06.466] <TB3> INFO: run 1 of 1
[21:48:06.701] <TB3> INFO: Expecting 3723200 events.
[21:48:38.219] <TB3> INFO: 738320 events read in total (30927ms).
[21:49:08.681] <TB3> INFO: 1471230 events read in total (61389ms).
[21:49:39.444] <TB3> INFO: 2201510 events read in total (92152ms).
[21:50:10.118] <TB3> INFO: 2928285 events read in total (122826ms).
[21:50:41.132] <TB3> INFO: 3657100 events read in total (153840ms).
[21:50:44.284] <TB3> INFO: 3723200 events read in total (156992ms).
[21:50:44.327] <TB3> INFO: Test took 157861ms.
[21:51:10.563] <TB3> INFO: ---> dac: Vcal name: TrimThr_trim11 ntrig: 5 dacrange: 0 .. 179 (-1/-1) hits flags = 528 (plus default)
[21:51:10.573] <TB3> INFO: dacScan split into 1 runs with ntrig = 5
[21:51:10.573] <TB3> INFO: run 1 of 1
[21:51:10.805] <TB3> INFO: Expecting 3744000 events.
[21:51:42.477] <TB3> INFO: 736955 events read in total (31080ms).
[21:52:13.274] <TB3> INFO: 1468670 events read in total (61877ms).
[21:52:44.079] <TB3> INFO: 2197795 events read in total (92682ms).
[21:53:14.782] <TB3> INFO: 2923665 events read in total (123385ms).
[21:53:45.745] <TB3> INFO: 3650705 events read in total (154348ms).
[21:53:50.079] <TB3> INFO: 3744000 events read in total (158682ms).
[21:53:50.121] <TB3> INFO: Test took 159548ms.
[21:54:16.377] <TB3> INFO: ---> dac: Vcal name: TrimThr_trim7 ntrig: 5 dacrange: 0 .. 180 (-1/-1) hits flags = 528 (plus default)
[21:54:16.387] <TB3> INFO: dacScan split into 1 runs with ntrig = 5
[21:54:16.387] <TB3> INFO: run 1 of 1
[21:54:16.681] <TB3> INFO: Expecting 3764800 events.
[21:54:48.247] <TB3> INFO: 735670 events read in total (30974ms).
[21:55:19.149] <TB3> INFO: 1466170 events read in total (61876ms).
[21:55:49.855] <TB3> INFO: 2194225 events read in total (92582ms).
[21:56:21.044] <TB3> INFO: 2919105 events read in total (123771ms).
[21:56:51.833] <TB3> INFO: 3644860 events read in total (154560ms).
[21:56:57.196] <TB3> INFO: 3764800 events read in total (159923ms).
[21:56:57.239] <TB3> INFO: Test took 160852ms.
[21:57:23.472] <TB3> INFO: PixTestTrim::trimBitTest() done
[21:57:23.473] <TB3> INFO: PixTestTrim::doTest() done, duration: 2375 seconds
[21:57:23.473] <TB3> INFO: Decoding statistics:
[21:57:23.473] <TB3> INFO: General information:
[21:57:23.473] <TB3> INFO: 16bit words read: 0
[21:57:23.473] <TB3> INFO: valid events total: 0
[21:57:23.473] <TB3> INFO: empty events: 0
[21:57:23.473] <TB3> INFO: valid events with pixels: 0
[21:57:23.473] <TB3> INFO: valid pixel hits: 0
[21:57:23.473] <TB3> INFO: Event errors: 0
[21:57:23.473] <TB3> INFO: start marker: 0
[21:57:23.473] <TB3> INFO: stop marker: 0
[21:57:23.473] <TB3> INFO: overflow: 0
[21:57:23.473] <TB3> INFO: invalid 5bit words: 0
[21:57:23.473] <TB3> INFO: invalid XOR eye diagram: 0
[21:57:23.473] <TB3> INFO: frame (failed synchr.): 0
[21:57:23.473] <TB3> INFO: idle data (no TBM trl): 0
[21:57:23.474] <TB3> INFO: no data (only TBM hdr): 0
[21:57:23.474] <TB3> INFO: TBM errors: 0
[21:57:23.474] <TB3> INFO: flawed TBM headers: 0
[21:57:23.474] <TB3> INFO: flawed TBM trailers: 0
[21:57:23.474] <TB3> INFO: event ID mismatches: 0
[21:57:23.474] <TB3> INFO: ROC errors: 0
[21:57:23.474] <TB3> INFO: missing ROC header(s): 0
[21:57:23.474] <TB3> INFO: misplaced readback start: 0
[21:57:23.474] <TB3> INFO: Pixel decoding errors: 0
[21:57:23.474] <TB3> INFO: pixel data incomplete: 0
[21:57:23.474] <TB3> INFO: pixel address: 0
[21:57:23.474] <TB3> INFO: pulse height fill bit: 0
[21:57:23.474] <TB3> INFO: buffer corruption: 0
[21:57:24.119] <TB3> INFO: ######################################################################
[21:57:24.119] <TB3> INFO: PixTestPhOptimization::doTest() Ntrig = 10
[21:57:24.119] <TB3> INFO: ######################################################################
[21:57:24.392] <TB3> INFO: Expecting 41600 events.
[21:57:27.904] <TB3> INFO: 41600 events read in total (2920ms).
[21:57:27.904] <TB3> INFO: Test took 3783ms.
[21:57:28.395] <TB3> INFO: Expecting 41600 events.
[21:57:31.997] <TB3> INFO: 41600 events read in total (3010ms).
[21:57:31.998] <TB3> INFO: Test took 3889ms.
[21:57:32.295] <TB3> INFO: Expecting 41600 events.
[21:57:35.823] <TB3> INFO: 41600 events read in total (2937ms).
[21:57:35.824] <TB3> INFO: Test took 3803ms.
[21:57:36.112] <TB3> INFO: Expecting 41600 events.
[21:57:39.613] <TB3> INFO: 41600 events read in total (2909ms).
[21:57:39.614] <TB3> INFO: Test took 3767ms.
[21:57:39.904] <TB3> INFO: Expecting 41600 events.
[21:57:43.463] <TB3> INFO: 41600 events read in total (2967ms).
[21:57:43.464] <TB3> INFO: Test took 3824ms.
[21:57:43.754] <TB3> INFO: Expecting 41600 events.
[21:57:47.317] <TB3> INFO: 41600 events read in total (2971ms).
[21:57:47.318] <TB3> INFO: Test took 3828ms.
[21:57:47.606] <TB3> INFO: Expecting 41600 events.
[21:57:51.134] <TB3> INFO: 41600 events read in total (2937ms).
[21:57:51.135] <TB3> INFO: Test took 3794ms.
[21:57:51.426] <TB3> INFO: Expecting 41600 events.
[21:57:54.940] <TB3> INFO: 41600 events read in total (2923ms).
[21:57:54.941] <TB3> INFO: Test took 3780ms.
[21:57:55.230] <TB3> INFO: Expecting 41600 events.
[21:57:58.844] <TB3> INFO: 41600 events read in total (3023ms).
[21:57:58.845] <TB3> INFO: Test took 3880ms.
[21:57:59.133] <TB3> INFO: Expecting 41600 events.
[21:58:02.711] <TB3> INFO: 41600 events read in total (2986ms).
[21:58:02.712] <TB3> INFO: Test took 3843ms.
[21:58:02.000] <TB3> INFO: Expecting 41600 events.
[21:58:06.466] <TB3> INFO: 41600 events read in total (2874ms).
[21:58:06.467] <TB3> INFO: Test took 3732ms.
[21:58:06.755] <TB3> INFO: Expecting 41600 events.
[21:58:10.262] <TB3> INFO: 41600 events read in total (2916ms).
[21:58:10.262] <TB3> INFO: Test took 3772ms.
[21:58:10.551] <TB3> INFO: Expecting 41600 events.
[21:58:13.998] <TB3> INFO: 41600 events read in total (2856ms).
[21:58:13.998] <TB3> INFO: Test took 3712ms.
[21:58:14.289] <TB3> INFO: Expecting 41600 events.
[21:58:17.820] <TB3> INFO: 41600 events read in total (2939ms).
[21:58:17.820] <TB3> INFO: Test took 3796ms.
[21:58:18.108] <TB3> INFO: Expecting 41600 events.
[21:58:21.551] <TB3> INFO: 41600 events read in total (2851ms).
[21:58:21.552] <TB3> INFO: Test took 3708ms.
[21:58:21.842] <TB3> INFO: Expecting 41600 events.
[21:58:25.467] <TB3> INFO: 41600 events read in total (3033ms).
[21:58:25.468] <TB3> INFO: Test took 3890ms.
[21:58:25.759] <TB3> INFO: Expecting 41600 events.
[21:58:29.272] <TB3> INFO: 41600 events read in total (2921ms).
[21:58:29.273] <TB3> INFO: Test took 3779ms.
[21:58:29.563] <TB3> INFO: Expecting 41600 events.
[21:58:33.049] <TB3> INFO: 41600 events read in total (2895ms).
[21:58:33.049] <TB3> INFO: Test took 3751ms.
[21:58:33.337] <TB3> INFO: Expecting 41600 events.
[21:58:36.890] <TB3> INFO: 41600 events read in total (2961ms).
[21:58:36.891] <TB3> INFO: Test took 3818ms.
[21:58:37.179] <TB3> INFO: Expecting 41600 events.
[21:58:40.636] <TB3> INFO: 41600 events read in total (2865ms).
[21:58:40.637] <TB3> INFO: Test took 3723ms.
[21:58:40.925] <TB3> INFO: Expecting 41600 events.
[21:58:44.380] <TB3> INFO: 41600 events read in total (2863ms).
[21:58:44.381] <TB3> INFO: Test took 3720ms.
[21:58:44.674] <TB3> INFO: Expecting 41600 events.
[21:58:48.221] <TB3> INFO: 41600 events read in total (2955ms).
[21:58:48.221] <TB3> INFO: Test took 3817ms.
[21:58:48.527] <TB3> INFO: Expecting 41600 events.
[21:58:52.092] <TB3> INFO: 41600 events read in total (2974ms).
[21:58:52.092] <TB3> INFO: Test took 3847ms.
[21:58:52.383] <TB3> INFO: Expecting 41600 events.
[21:58:55.951] <TB3> INFO: 41600 events read in total (2976ms).
[21:58:55.951] <TB3> INFO: Test took 3833ms.
[21:58:56.239] <TB3> INFO: Expecting 41600 events.
[21:58:59.883] <TB3> INFO: 41600 events read in total (3052ms).
[21:58:59.884] <TB3> INFO: Test took 3909ms.
[21:59:00.172] <TB3> INFO: Expecting 41600 events.
[21:59:03.766] <TB3> INFO: 41600 events read in total (3002ms).
[21:59:03.767] <TB3> INFO: Test took 3859ms.
[21:59:04.058] <TB3> INFO: Expecting 41600 events.
[21:59:07.586] <TB3> INFO: 41600 events read in total (2937ms).
[21:59:07.587] <TB3> INFO: Test took 3794ms.
[21:59:07.875] <TB3> INFO: Expecting 41600 events.
[21:59:11.330] <TB3> INFO: 41600 events read in total (2863ms).
[21:59:11.331] <TB3> INFO: Test took 3720ms.
[21:59:11.619] <TB3> INFO: Expecting 41600 events.
[21:59:15.117] <TB3> INFO: 41600 events read in total (2906ms).
[21:59:15.117] <TB3> INFO: Test took 3763ms.
[21:59:15.407] <TB3> INFO: Expecting 41600 events.
[21:59:19.102] <TB3> INFO: 41600 events read in total (3103ms).
[21:59:19.102] <TB3> INFO: Test took 3960ms.
[21:59:19.392] <TB3> INFO: Expecting 2560 events.
[21:59:20.274] <TB3> INFO: 2560 events read in total (291ms).
[21:59:20.275] <TB3> INFO: Test took 1160ms.
[21:59:20.582] <TB3> INFO: Expecting 2560 events.
[21:59:21.465] <TB3> INFO: 2560 events read in total (291ms).
[21:59:21.465] <TB3> INFO: Test took 1190ms.
[21:59:21.773] <TB3> INFO: Expecting 2560 events.
[21:59:22.655] <TB3> INFO: 2560 events read in total (290ms).
[21:59:22.655] <TB3> INFO: Test took 1190ms.
[21:59:22.963] <TB3> INFO: Expecting 2560 events.
[21:59:23.845] <TB3> INFO: 2560 events read in total (290ms).
[21:59:23.845] <TB3> INFO: Test took 1189ms.
[21:59:24.153] <TB3> INFO: Expecting 2560 events.
[21:59:25.031] <TB3> INFO: 2560 events read in total (286ms).
[21:59:25.031] <TB3> INFO: Test took 1185ms.
[21:59:25.339] <TB3> INFO: Expecting 2560 events.
[21:59:26.217] <TB3> INFO: 2560 events read in total (286ms).
[21:59:26.217] <TB3> INFO: Test took 1185ms.
[21:59:26.525] <TB3> INFO: Expecting 2560 events.
[21:59:27.403] <TB3> INFO: 2560 events read in total (287ms).
[21:59:27.403] <TB3> INFO: Test took 1186ms.
[21:59:27.711] <TB3> INFO: Expecting 2560 events.
[21:59:28.591] <TB3> INFO: 2560 events read in total (289ms).
[21:59:28.591] <TB3> INFO: Test took 1188ms.
[21:59:28.899] <TB3> INFO: Expecting 2560 events.
[21:59:29.777] <TB3> INFO: 2560 events read in total (287ms).
[21:59:29.777] <TB3> INFO: Test took 1186ms.
[21:59:30.085] <TB3> INFO: Expecting 2560 events.
[21:59:30.962] <TB3> INFO: 2560 events read in total (286ms).
[21:59:30.963] <TB3> INFO: Test took 1186ms.
[21:59:31.271] <TB3> INFO: Expecting 2560 events.
[21:59:32.152] <TB3> INFO: 2560 events read in total (290ms).
[21:59:32.152] <TB3> INFO: Test took 1189ms.
[21:59:32.460] <TB3> INFO: Expecting 2560 events.
[21:59:33.336] <TB3> INFO: 2560 events read in total (285ms).
[21:59:33.337] <TB3> INFO: Test took 1185ms.
[21:59:33.645] <TB3> INFO: Expecting 2560 events.
[21:59:34.528] <TB3> INFO: 2560 events read in total (292ms).
[21:59:34.528] <TB3> INFO: Test took 1191ms.
[21:59:34.836] <TB3> INFO: Expecting 2560 events.
[21:59:35.719] <TB3> INFO: 2560 events read in total (291ms).
[21:59:35.719] <TB3> INFO: Test took 1190ms.
[21:59:36.027] <TB3> INFO: Expecting 2560 events.
[21:59:36.910] <TB3> INFO: 2560 events read in total (292ms).
[21:59:36.910] <TB3> INFO: Test took 1191ms.
[21:59:37.218] <TB3> INFO: Expecting 2560 events.
[21:59:38.100] <TB3> INFO: 2560 events read in total (290ms).
[21:59:38.100] <TB3> INFO: Test took 1190ms.
[21:59:38.103] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[21:59:38.409] <TB3> INFO: Expecting 655360 events.
[21:59:52.615] <TB3> INFO: 655360 events read in total (13614ms).
[21:59:52.627] <TB3> INFO: Expecting 655360 events.
[22:00:06.711] <TB3> INFO: 655360 events read in total (13681ms).
[22:00:06.732] <TB3> INFO: Expecting 655360 events.
[22:00:20.780] <TB3> INFO: 655360 events read in total (13645ms).
[22:00:20.799] <TB3> INFO: Expecting 655360 events.
[22:00:34.931] <TB3> INFO: 655360 events read in total (13729ms).
[22:00:34.953] <TB3> INFO: Expecting 655360 events.
[22:00:49.088] <TB3> INFO: 655360 events read in total (13732ms).
[22:00:49.126] <TB3> INFO: Expecting 655360 events.
[22:01:03.219] <TB3> INFO: 655360 events read in total (13690ms).
[22:01:03.251] <TB3> INFO: Expecting 655360 events.
[22:01:17.374] <TB3> INFO: 655360 events read in total (13720ms).
[22:01:17.410] <TB3> INFO: Expecting 655360 events.
[22:01:31.412] <TB3> INFO: 655360 events read in total (13599ms).
[22:01:31.453] <TB3> INFO: Expecting 655360 events.
[22:01:45.510] <TB3> INFO: 655360 events read in total (13654ms).
[22:01:45.555] <TB3> INFO: Expecting 655360 events.
[22:01:59.518] <TB3> INFO: 655360 events read in total (13560ms).
[22:01:59.569] <TB3> INFO: Expecting 655360 events.
[22:02:13.690] <TB3> INFO: 655360 events read in total (13718ms).
[22:02:13.745] <TB3> INFO: Expecting 655360 events.
[22:02:27.830] <TB3> INFO: 655360 events read in total (13682ms).
[22:02:27.889] <TB3> INFO: Expecting 655360 events.
[22:02:41.893] <TB3> INFO: 655360 events read in total (13601ms).
[22:02:41.964] <TB3> INFO: Expecting 655360 events.
[22:02:55.898] <TB3> INFO: 655360 events read in total (13531ms).
[22:02:55.965] <TB3> INFO: Expecting 655360 events.
[22:03:09.987] <TB3> INFO: 655360 events read in total (13619ms).
[22:03:10.082] <TB3> INFO: Expecting 655360 events.
[22:03:24.221] <TB3> INFO: 655360 events read in total (13736ms).
[22:03:24.296] <TB3> INFO: Test took 226193ms.
[22:03:24.374] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[22:03:24.639] <TB3> INFO: Expecting 655360 events.
[22:03:38.895] <TB3> INFO: 655360 events read in total (13665ms).
[22:03:38.909] <TB3> INFO: Expecting 655360 events.
[22:03:52.863] <TB3> INFO: 655360 events read in total (13551ms).
[22:03:52.878] <TB3> INFO: Expecting 655360 events.
[22:04:06.923] <TB3> INFO: 655360 events read in total (13642ms).
[22:04:06.942] <TB3> INFO: Expecting 655360 events.
[22:04:20.929] <TB3> INFO: 655360 events read in total (13584ms).
[22:04:20.960] <TB3> INFO: Expecting 655360 events.
[22:04:35.005] <TB3> INFO: 655360 events read in total (13642ms).
[22:04:35.034] <TB3> INFO: Expecting 655360 events.
[22:04:49.062] <TB3> INFO: 655360 events read in total (13626ms).
[22:04:49.103] <TB3> INFO: Expecting 655360 events.
[22:05:03.049] <TB3> INFO: 655360 events read in total (13543ms).
[22:05:03.085] <TB3> INFO: Expecting 655360 events.
[22:05:17.087] <TB3> INFO: 655360 events read in total (13599ms).
[22:05:17.144] <TB3> INFO: Expecting 655360 events.
[22:05:30.976] <TB3> INFO: 655360 events read in total (13430ms).
[22:05:31.034] <TB3> INFO: Expecting 655360 events.
[22:05:45.045] <TB3> INFO: 655360 events read in total (13609ms).
[22:05:45.115] <TB3> INFO: Expecting 655360 events.
[22:05:58.763] <TB3> INFO: 655360 events read in total (13245ms).
[22:05:58.816] <TB3> INFO: Expecting 655360 events.
[22:06:12.734] <TB3> INFO: 655360 events read in total (13515ms).
[22:06:12.792] <TB3> INFO: Expecting 655360 events.
[22:06:26.784] <TB3> INFO: 655360 events read in total (13589ms).
[22:06:26.846] <TB3> INFO: Expecting 655360 events.
[22:06:40.768] <TB3> INFO: 655360 events read in total (13519ms).
[22:06:40.858] <TB3> INFO: Expecting 655360 events.
[22:06:54.734] <TB3> INFO: 655360 events read in total (13473ms).
[22:06:54.806] <TB3> INFO: Expecting 655360 events.
[22:07:08.613] <TB3> INFO: 655360 events read in total (13404ms).
[22:07:08.715] <TB3> INFO: Test took 224341ms.
[22:07:08.888] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[22:07:08.893] <TB3> INFO: safety margin for low PH: adding 1, margin is now 21
[22:07:08.897] <TB3> INFO: safety margin for low PH: adding 2, margin is now 22
[22:07:08.902] <TB3> INFO: safety margin for low PH: adding 3, margin is now 23
[22:07:08.906] <TB3> INFO: safety margin for low PH: adding 4, margin is now 24
[22:07:08.911] <TB3> INFO: safety margin for low PH: adding 5, margin is now 25
[22:07:08.915] <TB3> INFO: safety margin for low PH: adding 6, margin is now 26
[22:07:08.920] <TB3> INFO: safety margin for low PH: adding 7, margin is now 27
[22:07:08.925] <TB3> INFO: safety margin for low PH: adding 8, margin is now 28
[22:07:08.929] <TB3> INFO: safety margin for low PH: adding 9, margin is now 29
[22:07:08.934] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[22:07:08.938] <TB3> INFO: safety margin for low PH: adding 1, margin is now 21
[22:07:08.943] <TB3> INFO: safety margin for low PH: adding 2, margin is now 22
[22:07:08.947] <TB3> INFO: safety margin for low PH: adding 3, margin is now 23
[22:07:08.951] <TB3> INFO: safety margin for low PH: adding 4, margin is now 24
[22:07:08.956] <TB3> INFO: safety margin for low PH: adding 5, margin is now 25
[22:07:08.961] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[22:07:08.965] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[22:07:08.970] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[22:07:08.974] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[22:07:08.979] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[22:07:08.983] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[22:07:08.988] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[22:07:08.993] <TB3> INFO: safety margin for low PH: adding 1, margin is now 21
[22:07:08.997] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[22:07:08.002] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[22:07:09.006] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[22:07:09.010] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[22:07:09.015] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[22:07:09.019] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[22:07:09.024] <TB3> INFO: safety margin for low PH: adding 1, margin is now 21
[22:07:09.029] <TB3> INFO: safety margin for low PH: adding 2, margin is now 22
[22:07:09.033] <TB3> INFO: safety margin for low PH: adding 3, margin is now 23
[22:07:09.038] <TB3> INFO: safety margin for low PH: adding 4, margin is now 24
[22:07:09.042] <TB3> INFO: safety margin for low PH: adding 5, margin is now 25
[22:07:09.047] <TB3> INFO: safety margin for low PH: adding 6, margin is now 26
[22:07:09.051] <TB3> INFO: safety margin for low PH: adding 7, margin is now 27
[22:07:09.056] <TB3> INFO: safety margin for low PH: adding 8, margin is now 28
[22:07:09.061] <TB3> INFO: safety margin for low PH: adding 9, margin is now 29
[22:07:09.066] <TB3> INFO: safety margin for low PH: adding 10, margin is now 30
[22:07:09.070] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[22:07:09.075] <TB3> INFO: safety margin for low PH: adding 1, margin is now 21
[22:07:09.079] <TB3> INFO: safety margin for low PH: adding 2, margin is now 22
[22:07:09.083] <TB3> INFO: safety margin for low PH: adding 3, margin is now 23
[22:07:09.088] <TB3> INFO: safety margin for low PH: adding 4, margin is now 24
[22:07:09.093] <TB3> INFO: safety margin for low PH: adding 5, margin is now 25
[22:07:09.098] <TB3> INFO: safety margin for low PH: adding 6, margin is now 26
[22:07:09.102] <TB3> INFO: safety margin for low PH: adding 7, margin is now 27
[22:07:09.136] <TB3> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1140_FullQualification_2016-11-07_19h53m_1478544796//001_Fulltest_m20//dacParameters35_C0.dat
[22:07:09.136] <TB3> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1140_FullQualification_2016-11-07_19h53m_1478544796//001_Fulltest_m20//dacParameters35_C1.dat
[22:07:09.136] <TB3> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1140_FullQualification_2016-11-07_19h53m_1478544796//001_Fulltest_m20//dacParameters35_C2.dat
[22:07:09.136] <TB3> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1140_FullQualification_2016-11-07_19h53m_1478544796//001_Fulltest_m20//dacParameters35_C3.dat
[22:07:09.136] <TB3> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1140_FullQualification_2016-11-07_19h53m_1478544796//001_Fulltest_m20//dacParameters35_C4.dat
[22:07:09.136] <TB3> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1140_FullQualification_2016-11-07_19h53m_1478544796//001_Fulltest_m20//dacParameters35_C5.dat
[22:07:09.137] <TB3> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1140_FullQualification_2016-11-07_19h53m_1478544796//001_Fulltest_m20//dacParameters35_C6.dat
[22:07:09.137] <TB3> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1140_FullQualification_2016-11-07_19h53m_1478544796//001_Fulltest_m20//dacParameters35_C7.dat
[22:07:09.137] <TB3> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1140_FullQualification_2016-11-07_19h53m_1478544796//001_Fulltest_m20//dacParameters35_C8.dat
[22:07:09.137] <TB3> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1140_FullQualification_2016-11-07_19h53m_1478544796//001_Fulltest_m20//dacParameters35_C9.dat
[22:07:09.137] <TB3> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1140_FullQualification_2016-11-07_19h53m_1478544796//001_Fulltest_m20//dacParameters35_C10.dat
[22:07:09.137] <TB3> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1140_FullQualification_2016-11-07_19h53m_1478544796//001_Fulltest_m20//dacParameters35_C11.dat
[22:07:09.137] <TB3> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1140_FullQualification_2016-11-07_19h53m_1478544796//001_Fulltest_m20//dacParameters35_C12.dat
[22:07:09.137] <TB3> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1140_FullQualification_2016-11-07_19h53m_1478544796//001_Fulltest_m20//dacParameters35_C13.dat
[22:07:09.137] <TB3> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1140_FullQualification_2016-11-07_19h53m_1478544796//001_Fulltest_m20//dacParameters35_C14.dat
[22:07:09.138] <TB3> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1140_FullQualification_2016-11-07_19h53m_1478544796//001_Fulltest_m20//dacParameters35_C15.dat
[22:07:09.390] <TB3> INFO: Expecting 41600 events.
[22:07:12.516] <TB3> INFO: 41600 events read in total (2534ms).
[22:07:12.516] <TB3> INFO: Test took 3376ms.
[22:07:12.958] <TB3> INFO: Expecting 41600 events.
[22:07:16.009] <TB3> INFO: 41600 events read in total (2459ms).
[22:07:16.010] <TB3> INFO: Test took 3283ms.
[22:07:16.453] <TB3> INFO: Expecting 41600 events.
[22:07:19.555] <TB3> INFO: 41600 events read in total (2510ms).
[22:07:19.556] <TB3> INFO: Test took 3334ms.
[22:07:19.770] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[22:07:19.859] <TB3> INFO: Expecting 2560 events.
[22:07:20.742] <TB3> INFO: 2560 events read in total (291ms).
[22:07:20.742] <TB3> INFO: Test took 972ms.
[22:07:20.744] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[22:07:21.051] <TB3> INFO: Expecting 2560 events.
[22:07:21.935] <TB3> INFO: 2560 events read in total (292ms).
[22:07:21.936] <TB3> INFO: Test took 1192ms.
[22:07:21.938] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[22:07:22.244] <TB3> INFO: Expecting 2560 events.
[22:07:23.127] <TB3> INFO: 2560 events read in total (292ms).
[22:07:23.127] <TB3> INFO: Test took 1190ms.
[22:07:23.129] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[22:07:23.435] <TB3> INFO: Expecting 2560 events.
[22:07:24.318] <TB3> INFO: 2560 events read in total (291ms).
[22:07:24.318] <TB3> INFO: Test took 1189ms.
[22:07:24.320] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[22:07:24.627] <TB3> INFO: Expecting 2560 events.
[22:07:25.509] <TB3> INFO: 2560 events read in total (292ms).
[22:07:25.510] <TB3> INFO: Test took 1190ms.
[22:07:25.512] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[22:07:25.818] <TB3> INFO: Expecting 2560 events.
[22:07:26.702] <TB3> INFO: 2560 events read in total (292ms).
[22:07:26.702] <TB3> INFO: Test took 1190ms.
[22:07:26.704] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[22:07:27.010] <TB3> INFO: Expecting 2560 events.
[22:07:27.893] <TB3> INFO: 2560 events read in total (291ms).
[22:07:27.894] <TB3> INFO: Test took 1190ms.
[22:07:27.895] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[22:07:28.202] <TB3> INFO: Expecting 2560 events.
[22:07:29.085] <TB3> INFO: 2560 events read in total (292ms).
[22:07:29.085] <TB3> INFO: Test took 1190ms.
[22:07:29.087] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[22:07:29.393] <TB3> INFO: Expecting 2560 events.
[22:07:30.274] <TB3> INFO: 2560 events read in total (289ms).
[22:07:30.275] <TB3> INFO: Test took 1188ms.
[22:07:30.277] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[22:07:30.583] <TB3> INFO: Expecting 2560 events.
[22:07:31.460] <TB3> INFO: 2560 events read in total (286ms).
[22:07:31.461] <TB3> INFO: Test took 1184ms.
[22:07:31.463] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[22:07:31.769] <TB3> INFO: Expecting 2560 events.
[22:07:32.648] <TB3> INFO: 2560 events read in total (287ms).
[22:07:32.648] <TB3> INFO: Test took 1185ms.
[22:07:32.650] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[22:07:32.956] <TB3> INFO: Expecting 2560 events.
[22:07:33.835] <TB3> INFO: 2560 events read in total (287ms).
[22:07:33.835] <TB3> INFO: Test took 1185ms.
[22:07:33.838] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[22:07:34.144] <TB3> INFO: Expecting 2560 events.
[22:07:35.023] <TB3> INFO: 2560 events read in total (288ms).
[22:07:35.023] <TB3> INFO: Test took 1185ms.
[22:07:35.025] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[22:07:35.331] <TB3> INFO: Expecting 2560 events.
[22:07:36.214] <TB3> INFO: 2560 events read in total (291ms).
[22:07:36.214] <TB3> INFO: Test took 1189ms.
[22:07:36.216] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[22:07:36.522] <TB3> INFO: Expecting 2560 events.
[22:07:37.401] <TB3> INFO: 2560 events read in total (287ms).
[22:07:37.402] <TB3> INFO: Test took 1186ms.
[22:07:37.403] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[22:07:37.710] <TB3> INFO: Expecting 2560 events.
[22:07:38.590] <TB3> INFO: 2560 events read in total (289ms).
[22:07:38.590] <TB3> INFO: Test took 1187ms.
[22:07:38.592] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[22:07:38.899] <TB3> INFO: Expecting 2560 events.
[22:07:39.777] <TB3> INFO: 2560 events read in total (287ms).
[22:07:39.777] <TB3> INFO: Test took 1185ms.
[22:07:39.779] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[22:07:40.086] <TB3> INFO: Expecting 2560 events.
[22:07:40.963] <TB3> INFO: 2560 events read in total (286ms).
[22:07:40.964] <TB3> INFO: Test took 1185ms.
[22:07:40.966] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[22:07:41.272] <TB3> INFO: Expecting 2560 events.
[22:07:42.151] <TB3> INFO: 2560 events read in total (287ms).
[22:07:42.151] <TB3> INFO: Test took 1186ms.
[22:07:42.153] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[22:07:42.460] <TB3> INFO: Expecting 2560 events.
[22:07:43.337] <TB3> INFO: 2560 events read in total (286ms).
[22:07:43.337] <TB3> INFO: Test took 1184ms.
[22:07:43.339] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[22:07:43.646] <TB3> INFO: Expecting 2560 events.
[22:07:44.527] <TB3> INFO: 2560 events read in total (290ms).
[22:07:44.527] <TB3> INFO: Test took 1188ms.
[22:07:44.529] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[22:07:44.836] <TB3> INFO: Expecting 2560 events.
[22:07:45.714] <TB3> INFO: 2560 events read in total (287ms).
[22:07:45.714] <TB3> INFO: Test took 1185ms.
[22:07:45.717] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[22:07:46.022] <TB3> INFO: Expecting 2560 events.
[22:07:46.901] <TB3> INFO: 2560 events read in total (287ms).
[22:07:46.901] <TB3> INFO: Test took 1185ms.
[22:07:46.903] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[22:07:47.210] <TB3> INFO: Expecting 2560 events.
[22:07:48.092] <TB3> INFO: 2560 events read in total (291ms).
[22:07:48.093] <TB3> INFO: Test took 1190ms.
[22:07:48.094] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[22:07:48.401] <TB3> INFO: Expecting 2560 events.
[22:07:49.285] <TB3> INFO: 2560 events read in total (293ms).
[22:07:49.285] <TB3> INFO: Test took 1191ms.
[22:07:49.287] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[22:07:49.594] <TB3> INFO: Expecting 2560 events.
[22:07:50.477] <TB3> INFO: 2560 events read in total (292ms).
[22:07:50.477] <TB3> INFO: Test took 1190ms.
[22:07:50.479] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[22:07:50.785] <TB3> INFO: Expecting 2560 events.
[22:07:51.670] <TB3> INFO: 2560 events read in total (293ms).
[22:07:51.671] <TB3> INFO: Test took 1192ms.
[22:07:51.672] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[22:07:51.979] <TB3> INFO: Expecting 2560 events.
[22:07:52.863] <TB3> INFO: 2560 events read in total (292ms).
[22:07:52.863] <TB3> INFO: Test took 1191ms.
[22:07:52.865] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[22:07:53.171] <TB3> INFO: Expecting 2560 events.
[22:07:54.056] <TB3> INFO: 2560 events read in total (293ms).
[22:07:54.056] <TB3> INFO: Test took 1191ms.
[22:07:54.058] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[22:07:54.365] <TB3> INFO: Expecting 2560 events.
[22:07:55.247] <TB3> INFO: 2560 events read in total (291ms).
[22:07:55.247] <TB3> INFO: Test took 1189ms.
[22:07:55.250] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[22:07:55.556] <TB3> INFO: Expecting 2560 events.
[22:07:56.439] <TB3> INFO: 2560 events read in total (292ms).
[22:07:56.439] <TB3> INFO: Test took 1189ms.
[22:07:56.441] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[22:07:56.747] <TB3> INFO: Expecting 2560 events.
[22:07:57.631] <TB3> INFO: 2560 events read in total (292ms).
[22:07:57.631] <TB3> INFO: Test took 1190ms.
[22:07:58.093] <TB3> INFO: PixTestPhOptimization::doTest() done, duration: 633 seconds
[22:07:58.093] <TB3> INFO: PH scale (per ROC): 63 45 48 54 47 61 63 60 53 39 48 63 53 51 48 40
[22:07:58.093] <TB3> INFO: PH offset (per ROC): 112 101 108 107 100 122 127 125 126 111 105 122 118 109 103 112
[22:07:58.098] <TB3> INFO: Decoding statistics:
[22:07:58.098] <TB3> INFO: General information:
[22:07:58.099] <TB3> INFO: 16bit words read: 127888
[22:07:58.099] <TB3> INFO: valid events total: 20480
[22:07:58.099] <TB3> INFO: empty events: 17976
[22:07:58.099] <TB3> INFO: valid events with pixels: 2504
[22:07:58.099] <TB3> INFO: valid pixel hits: 2504
[22:07:58.099] <TB3> INFO: Event errors: 0
[22:07:58.099] <TB3> INFO: start marker: 0
[22:07:58.099] <TB3> INFO: stop marker: 0
[22:07:58.099] <TB3> INFO: overflow: 0
[22:07:58.099] <TB3> INFO: invalid 5bit words: 0
[22:07:58.099] <TB3> INFO: invalid XOR eye diagram: 0
[22:07:58.099] <TB3> INFO: frame (failed synchr.): 0
[22:07:58.099] <TB3> INFO: idle data (no TBM trl): 0
[22:07:58.099] <TB3> INFO: no data (only TBM hdr): 0
[22:07:58.099] <TB3> INFO: TBM errors: 0
[22:07:58.099] <TB3> INFO: flawed TBM headers: 0
[22:07:58.099] <TB3> INFO: flawed TBM trailers: 0
[22:07:58.099] <TB3> INFO: event ID mismatches: 0
[22:07:58.099] <TB3> INFO: ROC errors: 0
[22:07:58.099] <TB3> INFO: missing ROC header(s): 0
[22:07:58.099] <TB3> INFO: misplaced readback start: 0
[22:07:58.099] <TB3> INFO: Pixel decoding errors: 0
[22:07:58.099] <TB3> INFO: pixel data incomplete: 0
[22:07:58.099] <TB3> INFO: pixel address: 0
[22:07:58.099] <TB3> INFO: pulse height fill bit: 0
[22:07:58.099] <TB3> INFO: buffer corruption: 0
[22:07:58.364] <TB3> INFO: ######################################################################
[22:07:58.364] <TB3> INFO: PixTestGainPedestal::fullTest() ntrig = 10
[22:07:58.364] <TB3> INFO: ######################################################################
[22:07:58.376] <TB3> INFO: scanning low vcal = 10
[22:07:58.610] <TB3> INFO: Expecting 41600 events.
[22:08:02.157] <TB3> INFO: 41600 events read in total (2956ms).
[22:08:02.158] <TB3> INFO: Test took 3782ms.
[22:08:02.160] <TB3> INFO: scanning low vcal = 20
[22:08:02.458] <TB3> INFO: Expecting 41600 events.
[22:08:06.022] <TB3> INFO: 41600 events read in total (2972ms).
[22:08:06.022] <TB3> INFO: Test took 3862ms.
[22:08:06.023] <TB3> INFO: scanning low vcal = 30
[22:08:06.323] <TB3> INFO: Expecting 41600 events.
[22:08:09.942] <TB3> INFO: 41600 events read in total (3028ms).
[22:08:09.943] <TB3> INFO: Test took 3919ms.
[22:08:09.946] <TB3> INFO: scanning low vcal = 40
[22:08:10.223] <TB3> INFO: Expecting 41600 events.
[22:08:14.159] <TB3> INFO: 41600 events read in total (3345ms).
[22:08:14.161] <TB3> INFO: Test took 4215ms.
[22:08:14.163] <TB3> INFO: scanning low vcal = 50
[22:08:14.440] <TB3> INFO: Expecting 41600 events.
[22:08:18.372] <TB3> INFO: 41600 events read in total (3340ms).
[22:08:18.373] <TB3> INFO: Test took 4209ms.
[22:08:18.375] <TB3> INFO: scanning low vcal = 60
[22:08:18.652] <TB3> INFO: Expecting 41600 events.
[22:08:22.643] <TB3> INFO: 41600 events read in total (3399ms).
[22:08:22.643] <TB3> INFO: Test took 4267ms.
[22:08:22.646] <TB3> INFO: scanning low vcal = 70
[22:08:22.923] <TB3> INFO: Expecting 41600 events.
[22:08:26.889] <TB3> INFO: 41600 events read in total (3375ms).
[22:08:26.890] <TB3> INFO: Test took 4244ms.
[22:08:26.892] <TB3> INFO: scanning low vcal = 80
[22:08:27.169] <TB3> INFO: Expecting 41600 events.
[22:08:31.141] <TB3> INFO: 41600 events read in total (3381ms).
[22:08:31.141] <TB3> INFO: Test took 4249ms.
[22:08:31.144] <TB3> INFO: scanning low vcal = 90
[22:08:31.420] <TB3> INFO: Expecting 41600 events.
[22:08:35.384] <TB3> INFO: 41600 events read in total (3372ms).
[22:08:35.384] <TB3> INFO: Test took 4240ms.
[22:08:35.387] <TB3> INFO: scanning low vcal = 100
[22:08:35.664] <TB3> INFO: Expecting 41600 events.
[22:08:39.592] <TB3> INFO: 41600 events read in total (3337ms).
[22:08:39.592] <TB3> INFO: Test took 4205ms.
[22:08:39.595] <TB3> INFO: scanning low vcal = 110
[22:08:39.872] <TB3> INFO: Expecting 41600 events.
[22:08:43.784] <TB3> INFO: 41600 events read in total (3321ms).
[22:08:43.785] <TB3> INFO: Test took 4190ms.
[22:08:43.787] <TB3> INFO: scanning low vcal = 120
[22:08:44.064] <TB3> INFO: Expecting 41600 events.
[22:08:48.028] <TB3> INFO: 41600 events read in total (3372ms).
[22:08:48.028] <TB3> INFO: Test took 4240ms.
[22:08:48.031] <TB3> INFO: scanning low vcal = 130
[22:08:48.308] <TB3> INFO: Expecting 41600 events.
[22:08:52.264] <TB3> INFO: 41600 events read in total (3365ms).
[22:08:52.265] <TB3> INFO: Test took 4234ms.
[22:08:52.268] <TB3> INFO: scanning low vcal = 140
[22:08:52.545] <TB3> INFO: Expecting 41600 events.
[22:08:56.483] <TB3> INFO: 41600 events read in total (3347ms).
[22:08:56.484] <TB3> INFO: Test took 4216ms.
[22:08:56.486] <TB3> INFO: scanning low vcal = 150
[22:08:56.763] <TB3> INFO: Expecting 41600 events.
[22:09:00.710] <TB3> INFO: 41600 events read in total (3355ms).
[22:09:00.711] <TB3> INFO: Test took 4224ms.
[22:09:00.714] <TB3> INFO: scanning low vcal = 160
[22:09:00.990] <TB3> INFO: Expecting 41600 events.
[22:09:04.944] <TB3> INFO: 41600 events read in total (3362ms).
[22:09:04.945] <TB3> INFO: Test took 4231ms.
[22:09:04.947] <TB3> INFO: scanning low vcal = 170
[22:09:05.224] <TB3> INFO: Expecting 41600 events.
[22:09:09.202] <TB3> INFO: 41600 events read in total (3387ms).
[22:09:09.202] <TB3> INFO: Test took 4255ms.
[22:09:09.205] <TB3> INFO: scanning low vcal = 180
[22:09:09.482] <TB3> INFO: Expecting 41600 events.
[22:09:13.423] <TB3> INFO: 41600 events read in total (3350ms).
[22:09:13.424] <TB3> INFO: Test took 4219ms.
[22:09:13.427] <TB3> INFO: scanning low vcal = 190
[22:09:13.703] <TB3> INFO: Expecting 41600 events.
[22:09:17.640] <TB3> INFO: 41600 events read in total (3345ms).
[22:09:17.641] <TB3> INFO: Test took 4214ms.
[22:09:17.644] <TB3> INFO: scanning low vcal = 200
[22:09:17.920] <TB3> INFO: Expecting 41600 events.
[22:09:21.903] <TB3> INFO: 41600 events read in total (3391ms).
[22:09:21.904] <TB3> INFO: Test took 4260ms.
[22:09:21.907] <TB3> INFO: scanning low vcal = 210
[22:09:22.184] <TB3> INFO: Expecting 41600 events.
[22:09:26.136] <TB3> INFO: 41600 events read in total (3361ms).
[22:09:26.136] <TB3> INFO: Test took 4229ms.
[22:09:26.139] <TB3> INFO: scanning low vcal = 220
[22:09:26.416] <TB3> INFO: Expecting 41600 events.
[22:09:30.352] <TB3> INFO: 41600 events read in total (3345ms).
[22:09:30.353] <TB3> INFO: Test took 4214ms.
[22:09:30.356] <TB3> INFO: scanning low vcal = 230
[22:09:30.633] <TB3> INFO: Expecting 41600 events.
[22:09:34.592] <TB3> INFO: 41600 events read in total (3368ms).
[22:09:34.593] <TB3> INFO: Test took 4237ms.
[22:09:34.595] <TB3> INFO: scanning low vcal = 240
[22:09:34.872] <TB3> INFO: Expecting 41600 events.
[22:09:38.822] <TB3> INFO: 41600 events read in total (3359ms).
[22:09:38.823] <TB3> INFO: Test took 4228ms.
[22:09:38.825] <TB3> INFO: scanning low vcal = 250
[22:09:39.102] <TB3> INFO: Expecting 41600 events.
[22:09:43.036] <TB3> INFO: 41600 events read in total (3342ms).
[22:09:43.037] <TB3> INFO: Test took 4212ms.
[22:09:43.041] <TB3> INFO: scanning high vcal = 30 (= 210 in low range)
[22:09:43.316] <TB3> INFO: Expecting 41600 events.
[22:09:47.304] <TB3> INFO: 41600 events read in total (3396ms).
[22:09:47.305] <TB3> INFO: Test took 4264ms.
[22:09:47.308] <TB3> INFO: scanning high vcal = 50 (= 350 in low range)
[22:09:47.584] <TB3> INFO: Expecting 41600 events.
[22:09:51.583] <TB3> INFO: 41600 events read in total (3407ms).
[22:09:51.584] <TB3> INFO: Test took 4276ms.
[22:09:51.586] <TB3> INFO: scanning high vcal = 70 (= 490 in low range)
[22:09:51.863] <TB3> INFO: Expecting 41600 events.
[22:09:55.845] <TB3> INFO: 41600 events read in total (3391ms).
[22:09:55.846] <TB3> INFO: Test took 4260ms.
[22:09:55.848] <TB3> INFO: scanning high vcal = 90 (= 630 in low range)
[22:09:56.125] <TB3> INFO: Expecting 41600 events.
[22:10:00.106] <TB3> INFO: 41600 events read in total (3389ms).
[22:10:00.106] <TB3> INFO: Test took 4258ms.
[22:10:00.109] <TB3> INFO: scanning high vcal = 200 (= 1400 in low range)
[22:10:00.385] <TB3> INFO: Expecting 41600 events.
[22:10:04.384] <TB3> INFO: 41600 events read in total (3407ms).
[22:10:04.384] <TB3> INFO: Test took 4275ms.
[22:10:04.924] <TB3> INFO: PixTestGainPedestal::measure() done
[22:10:40.897] <TB3> INFO: PixTestGainPedestal::fit() done
[22:10:40.897] <TB3> INFO: non-linearity mean: 0.986 0.928 0.940 0.953 0.950 0.983 0.989 0.983 0.981 0.924 0.939 0.982 0.979 0.964 0.938 0.967
[22:10:40.897] <TB3> INFO: non-linearity RMS: 0.002 0.108 0.049 0.045 0.048 0.003 0.002 0.004 0.004 0.149 0.064 0.004 0.003 0.020 0.072 0.159
[22:10:40.897] <TB3> INFO: write gain/ped parameters into /home/l_tester/david/FullQualification/data/M1140_FullQualification_2016-11-07_19h53m_1478544796//001_Fulltest_m20//phCalibrationFitErr35_C0.dat
[22:10:40.919] <TB3> INFO: write gain/ped parameters into /home/l_tester/david/FullQualification/data/M1140_FullQualification_2016-11-07_19h53m_1478544796//001_Fulltest_m20//phCalibrationFitErr35_C1.dat
[22:10:40.941] <TB3> INFO: write gain/ped parameters into /home/l_tester/david/FullQualification/data/M1140_FullQualification_2016-11-07_19h53m_1478544796//001_Fulltest_m20//phCalibrationFitErr35_C2.dat
[22:10:40.964] <TB3> INFO: write gain/ped parameters into /home/l_tester/david/FullQualification/data/M1140_FullQualification_2016-11-07_19h53m_1478544796//001_Fulltest_m20//phCalibrationFitErr35_C3.dat
[22:10:40.986] <TB3> INFO: write gain/ped parameters into /home/l_tester/david/FullQualification/data/M1140_FullQualification_2016-11-07_19h53m_1478544796//001_Fulltest_m20//phCalibrationFitErr35_C4.dat
[22:10:41.004] <TB3> INFO: write gain/ped parameters into /home/l_tester/david/FullQualification/data/M1140_FullQualification_2016-11-07_19h53m_1478544796//001_Fulltest_m20//phCalibrationFitErr35_C5.dat
[22:10:41.018] <TB3> INFO: write gain/ped parameters into /home/l_tester/david/FullQualification/data/M1140_FullQualification_2016-11-07_19h53m_1478544796//001_Fulltest_m20//phCalibrationFitErr35_C6.dat
[22:10:41.032] <TB3> INFO: write gain/ped parameters into /home/l_tester/david/FullQualification/data/M1140_FullQualification_2016-11-07_19h53m_1478544796//001_Fulltest_m20//phCalibrationFitErr35_C7.dat
[22:10:41.046] <TB3> INFO: write gain/ped parameters into /home/l_tester/david/FullQualification/data/M1140_FullQualification_2016-11-07_19h53m_1478544796//001_Fulltest_m20//phCalibrationFitErr35_C8.dat
[22:10:41.060] <TB3> INFO: write gain/ped parameters into /home/l_tester/david/FullQualification/data/M1140_FullQualification_2016-11-07_19h53m_1478544796//001_Fulltest_m20//phCalibrationFitErr35_C9.dat
[22:10:41.074] <TB3> INFO: write gain/ped parameters into /home/l_tester/david/FullQualification/data/M1140_FullQualification_2016-11-07_19h53m_1478544796//001_Fulltest_m20//phCalibrationFitErr35_C10.dat
[22:10:41.088] <TB3> INFO: write gain/ped parameters into /home/l_tester/david/FullQualification/data/M1140_FullQualification_2016-11-07_19h53m_1478544796//001_Fulltest_m20//phCalibrationFitErr35_C11.dat
[22:10:41.102] <TB3> INFO: write gain/ped parameters into /home/l_tester/david/FullQualification/data/M1140_FullQualification_2016-11-07_19h53m_1478544796//001_Fulltest_m20//phCalibrationFitErr35_C12.dat
[22:10:41.116] <TB3> INFO: write gain/ped parameters into /home/l_tester/david/FullQualification/data/M1140_FullQualification_2016-11-07_19h53m_1478544796//001_Fulltest_m20//phCalibrationFitErr35_C13.dat
[22:10:41.130] <TB3> INFO: write gain/ped parameters into /home/l_tester/david/FullQualification/data/M1140_FullQualification_2016-11-07_19h53m_1478544796//001_Fulltest_m20//phCalibrationFitErr35_C14.dat
[22:10:41.145] <TB3> INFO: write gain/ped parameters into /home/l_tester/david/FullQualification/data/M1140_FullQualification_2016-11-07_19h53m_1478544796//001_Fulltest_m20//phCalibrationFitErr35_C15.dat
[22:10:41.159] <TB3> INFO: PixTestGainPedestal::fullTest() done, duration: 162 seconds
[22:10:41.159] <TB3> INFO: Decoding statistics:
[22:10:41.159] <TB3> INFO: General information:
[22:10:41.159] <TB3> INFO: 16bit words read: 3327884
[22:10:41.159] <TB3> INFO: valid events total: 332800
[22:10:41.159] <TB3> INFO: empty events: 0
[22:10:41.159] <TB3> INFO: valid events with pixels: 332800
[22:10:41.159] <TB3> INFO: valid pixel hits: 665542
[22:10:41.159] <TB3> INFO: Event errors: 0
[22:10:41.159] <TB3> INFO: start marker: 0
[22:10:41.159] <TB3> INFO: stop marker: 0
[22:10:41.159] <TB3> INFO: overflow: 0
[22:10:41.159] <TB3> INFO: invalid 5bit words: 0
[22:10:41.159] <TB3> INFO: invalid XOR eye diagram: 0
[22:10:41.159] <TB3> INFO: frame (failed synchr.): 0
[22:10:41.159] <TB3> INFO: idle data (no TBM trl): 0
[22:10:41.159] <TB3> INFO: no data (only TBM hdr): 0
[22:10:41.159] <TB3> INFO: TBM errors: 0
[22:10:41.159] <TB3> INFO: flawed TBM headers: 0
[22:10:41.159] <TB3> INFO: flawed TBM trailers: 0
[22:10:41.159] <TB3> INFO: event ID mismatches: 0
[22:10:41.159] <TB3> INFO: ROC errors: 0
[22:10:41.159] <TB3> INFO: missing ROC header(s): 0
[22:10:41.159] <TB3> INFO: misplaced readback start: 0
[22:10:41.159] <TB3> INFO: Pixel decoding errors: 0
[22:10:41.159] <TB3> INFO: pixel data incomplete: 0
[22:10:41.159] <TB3> INFO: pixel address: 0
[22:10:41.159] <TB3> INFO: pulse height fill bit: 0
[22:10:41.159] <TB3> INFO: buffer corruption: 0
[22:10:41.174] <TB3> INFO: Decoding statistics:
[22:10:41.174] <TB3> INFO: General information:
[22:10:41.174] <TB3> INFO: 16bit words read: 3457308
[22:10:41.174] <TB3> INFO: valid events total: 353536
[22:10:41.174] <TB3> INFO: empty events: 18232
[22:10:41.174] <TB3> INFO: valid events with pixels: 335304
[22:10:41.174] <TB3> INFO: valid pixel hits: 668046
[22:10:41.174] <TB3> INFO: Event errors: 0
[22:10:41.174] <TB3> INFO: start marker: 0
[22:10:41.174] <TB3> INFO: stop marker: 0
[22:10:41.174] <TB3> INFO: overflow: 0
[22:10:41.174] <TB3> INFO: invalid 5bit words: 0
[22:10:41.174] <TB3> INFO: invalid XOR eye diagram: 0
[22:10:41.174] <TB3> INFO: frame (failed synchr.): 0
[22:10:41.174] <TB3> INFO: idle data (no TBM trl): 0
[22:10:41.174] <TB3> INFO: no data (only TBM hdr): 0
[22:10:41.174] <TB3> INFO: TBM errors: 0
[22:10:41.174] <TB3> INFO: flawed TBM headers: 0
[22:10:41.174] <TB3> INFO: flawed TBM trailers: 0
[22:10:41.174] <TB3> INFO: event ID mismatches: 0
[22:10:41.174] <TB3> INFO: ROC errors: 0
[22:10:41.174] <TB3> INFO: missing ROC header(s): 0
[22:10:41.174] <TB3> INFO: misplaced readback start: 0
[22:10:41.174] <TB3> INFO: Pixel decoding errors: 0
[22:10:41.174] <TB3> INFO: pixel data incomplete: 0
[22:10:41.174] <TB3> INFO: pixel address: 0
[22:10:41.174] <TB3> INFO: pulse height fill bit: 0
[22:10:41.174] <TB3> INFO: buffer corruption: 0
[22:10:41.174] <TB3> INFO: enter test to run
[22:10:41.174] <TB3> INFO: test: exit no parameter change
[22:10:41.214] <TB3> QUIET: Connection to board 170 closed.
[22:10:41.215] <TB3> INFO: pXar: this is the end, my friend