[18:38:21.180] <TB3> INFO: *** Welcome to pxar ***
[18:38:21.180] <TB3> INFO: *** Today: 2016/10/16
[18:38:21.186] <TB3> INFO: *** Version: c8ba-dirty
[18:38:21.186] <TB3> INFO: readRocDacs: /home/l_tester/david/FullQualification/data/M1008_FullQualification_2016-10-16_17h47m_1476632865//001_Fulltest_m20//dacParameters_C0.dat .. /home/l_tester/david/FullQualification/data/M1008_FullQualification_2016-10-16_17h47m_1476632865//001_Fulltest_m20//dacParameters_C15.dat
[18:38:21.186] <TB3> INFO: readTbmDacs: /home/l_tester/david/FullQualification/data/M1008_FullQualification_2016-10-16_17h47m_1476632865//001_Fulltest_m20//tbmParameters_C0a.dat .. /home/l_tester/david/FullQualification/data/M1008_FullQualification_2016-10-16_17h47m_1476632865//001_Fulltest_m20//tbmParameters_C1b.dat
[18:38:21.186] <TB3> INFO: readMaskFile: /home/l_tester/david/FullQualification/data/M1008_FullQualification_2016-10-16_17h47m_1476632865//001_Fulltest_m20//defaultMaskFile.dat
[18:38:21.186] <TB3> INFO: readTrimFile: /home/l_tester/david/FullQualification/data/M1008_FullQualification_2016-10-16_17h47m_1476632865//001_Fulltest_m20//trimParameters_C0.dat .. /home/l_tester/david/FullQualification/data/M1008_FullQualification_2016-10-16_17h47m_1476632865//001_Fulltest_m20//trimParameters_C15.dat
[18:38:21.252] <TB3> INFO: clk: 4
[18:38:21.252] <TB3> INFO: ctr: 4
[18:38:21.252] <TB3> INFO: sda: 19
[18:38:21.252] <TB3> INFO: tin: 9
[18:38:21.252] <TB3> INFO: level: 15
[18:38:21.252] <TB3> INFO: triggerdelay: 0
[18:38:21.252] <TB3> QUIET: Instanciating API for pxar v2.7.6+55~gafdbfd9
[18:38:21.252] <TB3> INFO: Log level: INFO
[18:38:21.261] <TB3> INFO: Found DTB DTB_WWVASW
[18:38:21.270] <TB3> QUIET: Connection to board DTB_WWVASW opened.
[18:38:21.272] <TB3> INFO: DTB startup information
--- DTB info------------------------------------------
Board id: 126
HW version: DTB1.2
FW version: 4.6
SW version: 4.7
Options:
USB id: DTB_WWVASW
MAC address: 40D85511807E
Hostname: pixelDTB126
Comment:
------------------------------------------------------
[18:38:21.274] <TB3> INFO: RPC call hashes of host and DTB match: 486171790
[18:38:22.773] <TB3> INFO: DUT info:
[18:38:22.773] <TB3> INFO: The DUT currently contains the following objects:
[18:38:22.773] <TB3> INFO: 4 TBM Cores tbm10c (4 ON)
[18:38:22.773] <TB3> INFO: TBM Core alpha (0): 7 registers set
[18:38:22.773] <TB3> INFO: TBM Core beta (1): 7 registers set
[18:38:22.773] <TB3> INFO: TBM Core alpha (2): 7 registers set
[18:38:22.773] <TB3> INFO: TBM Core beta (3): 7 registers set
[18:38:22.773] <TB3> INFO: 16 ROCs proc600 (16 ON) with 4160 pixelConfigs
[18:38:22.773] <TB3> INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[18:38:22.773] <TB3> INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[18:38:22.773] <TB3> INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[18:38:22.773] <TB3> INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[18:38:22.773] <TB3> INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[18:38:22.773] <TB3> INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[18:38:22.773] <TB3> INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[18:38:22.773] <TB3> INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[18:38:22.773] <TB3> INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[18:38:22.773] <TB3> INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[18:38:22.774] <TB3> INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[18:38:22.774] <TB3> INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[18:38:22.774] <TB3> INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[18:38:22.774] <TB3> INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[18:38:22.774] <TB3> INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[18:38:22.774] <TB3> INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[18:38:23.175] <TB3> INFO: enter 'restricted' command line mode
[18:38:23.175] <TB3> INFO: enter test to run
[18:38:23.175] <TB3> INFO: test: pretest no parameter change
[18:38:23.175] <TB3> INFO: running: pretest
[18:38:23.183] <TB3> INFO: ######################################################################
[18:38:23.183] <TB3> INFO: PixTestPretest::doTest()
[18:38:23.183] <TB3> INFO: ######################################################################
[18:38:23.184] <TB3> INFO: ----------------------------------------------------------------------
[18:38:23.184] <TB3> INFO: PixTestPretest::programROC()
[18:38:23.184] <TB3> INFO: ----------------------------------------------------------------------
[18:38:41.198] <TB3> INFO: PixTestPretest::programROC() done: ROCs are all programmable
[18:38:41.199] <TB3> INFO: IA differences per ROC: 19.3 20.9 20.1 19.3 20.1 18.5 18.5 18.5 19.3 19.3 19.3 20.9 21.7 20.9 20.1 17.7
[18:38:41.264] <TB3> INFO: ----------------------------------------------------------------------
[18:38:41.264] <TB3> INFO: PixTestPretest::setVana() target Ia = 24 mA/ROC
[18:38:41.264] <TB3> INFO: ----------------------------------------------------------------------
[18:39:02.559] <TB3> INFO: PixTestPretest::setVana() done, Module Ia 380.2 mA = 23.7625 mA/ROC
[18:39:02.559] <TB3> INFO: i(loss) [mA/ROC]: 19.3 18.4 19.3 19.3 19.3 19.3 18.4 20.1 19.3 20.1 19.3 19.3 18.4 19.3 18.4 20.1
[18:39:02.591] <TB3> INFO: ----------------------------------------------------------------------
[18:39:02.591] <TB3> INFO: PixTestPretest::findTiming()
[18:39:02.591] <TB3> INFO: ----------------------------------------------------------------------
[18:39:02.591] <TB3> INFO: PixTestCmd::init()

[18:39:34.513] <TB3> INFO: TBM phases: 160MHz: 7, 400MHz: 7, TBM delays: ROC(0/1):5, header/trailer: 1, token: 1
[18:39:34.513] <TB3> INFO: (success/tries = 100/100), width = 4
[18:39:36.016] <TB3> INFO: ----------------------------------------------------------------------
[18:39:36.016] <TB3> INFO: PixTestPretest::findWorkingPixel()
[18:39:36.016] <TB3> INFO: ----------------------------------------------------------------------
[18:39:36.112] <TB3> INFO: Expecting 231680 events.
[18:39:46.013] <TB3> INFO: 231680 events read in total (9309ms).
[18:39:46.021] <TB3> INFO: Test took 9999ms.
[18:39:46.267] <TB3> INFO: Found working pixel in all ROCs: col/row = 12/22
[18:39:46.304] <TB3> INFO: ----------------------------------------------------------------------
[18:39:46.304] <TB3> INFO: PixTestPretest::setVthrCompCalDel()
[18:39:46.304] <TB3> INFO: ----------------------------------------------------------------------
[18:39:46.399] <TB3> INFO: Expecting 231680 events.
[18:39:56.345] <TB3> INFO: 231680 events read in total (9353ms).
[18:39:56.354] <TB3> INFO: Test took 10044ms.
[18:39:56.619] <TB3> INFO: PixTestPretest::setVthrCompCalDel() done
[18:39:56.619] <TB3> INFO: CalDel: 103 98 80 93 97 108 86 90 109 110 88 95 89 103 87 78
[18:39:56.619] <TB3> INFO: VthrComp: 51 51 51 51 51 51 51 51 51 51 51 51 51 51 51 51
[18:39:56.623] <TB3> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1008_FullQualification_2016-10-16_17h47m_1476632865//001_Fulltest_m20//dacParameters_C0.dat
[18:39:56.623] <TB3> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1008_FullQualification_2016-10-16_17h47m_1476632865//001_Fulltest_m20//dacParameters_C1.dat
[18:39:56.624] <TB3> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1008_FullQualification_2016-10-16_17h47m_1476632865//001_Fulltest_m20//dacParameters_C2.dat
[18:39:56.624] <TB3> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1008_FullQualification_2016-10-16_17h47m_1476632865//001_Fulltest_m20//dacParameters_C3.dat
[18:39:56.624] <TB3> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1008_FullQualification_2016-10-16_17h47m_1476632865//001_Fulltest_m20//dacParameters_C4.dat
[18:39:56.624] <TB3> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1008_FullQualification_2016-10-16_17h47m_1476632865//001_Fulltest_m20//dacParameters_C5.dat
[18:39:56.624] <TB3> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1008_FullQualification_2016-10-16_17h47m_1476632865//001_Fulltest_m20//dacParameters_C6.dat
[18:39:56.624] <TB3> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1008_FullQualification_2016-10-16_17h47m_1476632865//001_Fulltest_m20//dacParameters_C7.dat
[18:39:56.624] <TB3> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1008_FullQualification_2016-10-16_17h47m_1476632865//001_Fulltest_m20//dacParameters_C8.dat
[18:39:56.625] <TB3> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1008_FullQualification_2016-10-16_17h47m_1476632865//001_Fulltest_m20//dacParameters_C9.dat
[18:39:56.625] <TB3> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1008_FullQualification_2016-10-16_17h47m_1476632865//001_Fulltest_m20//dacParameters_C10.dat
[18:39:56.625] <TB3> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1008_FullQualification_2016-10-16_17h47m_1476632865//001_Fulltest_m20//dacParameters_C11.dat
[18:39:56.625] <TB3> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1008_FullQualification_2016-10-16_17h47m_1476632865//001_Fulltest_m20//dacParameters_C12.dat
[18:39:56.625] <TB3> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1008_FullQualification_2016-10-16_17h47m_1476632865//001_Fulltest_m20//dacParameters_C13.dat
[18:39:56.625] <TB3> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1008_FullQualification_2016-10-16_17h47m_1476632865//001_Fulltest_m20//dacParameters_C14.dat
[18:39:56.625] <TB3> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1008_FullQualification_2016-10-16_17h47m_1476632865//001_Fulltest_m20//dacParameters_C15.dat
[18:39:56.626] <TB3> INFO: write tbm parameters into /home/l_tester/david/FullQualification/data/M1008_FullQualification_2016-10-16_17h47m_1476632865//001_Fulltest_m20//tbmParameters_C0a.dat
[18:39:56.626] <TB3> INFO: write tbm parameters into /home/l_tester/david/FullQualification/data/M1008_FullQualification_2016-10-16_17h47m_1476632865//001_Fulltest_m20//tbmParameters_C0b.dat
[18:39:56.626] <TB3> INFO: write tbm parameters into /home/l_tester/david/FullQualification/data/M1008_FullQualification_2016-10-16_17h47m_1476632865//001_Fulltest_m20//tbmParameters_C1a.dat
[18:39:56.626] <TB3> INFO: write tbm parameters into /home/l_tester/david/FullQualification/data/M1008_FullQualification_2016-10-16_17h47m_1476632865//001_Fulltest_m20//tbmParameters_C1b.dat
[18:39:56.626] <TB3> INFO: PixTestPretest::doTest() done, duration: 93 seconds
[18:39:56.680] <TB3> INFO: enter test to run
[18:39:56.680] <TB3> INFO: test: FullTest no parameter change
[18:39:56.680] <TB3> INFO: running: fulltest
[18:39:56.680] <TB3> INFO: ######################################################################
[18:39:56.680] <TB3> INFO: PixTestFullTest::doTest()
[18:39:56.680] <TB3> INFO: ######################################################################
[18:39:56.681] <TB3> INFO: ######################################################################
[18:39:56.681] <TB3> INFO: PixTestAlive::doTest()
[18:39:56.681] <TB3> INFO: ######################################################################
[18:39:56.683] <TB3> INFO: ----------------------------------------------------------------------
[18:39:56.683] <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)
[18:39:56.683] <TB3> INFO: ----------------------------------------------------------------------
[18:39:56.924] <TB3> INFO: Expecting 41600 events.
[18:40:00.653] <TB3> INFO: 41600 events read in total (3137ms).
[18:40:00.654] <TB3> INFO: Test took 3970ms.
[18:40:00.887] <TB3> INFO: PixTestAlive::aliveTest() done
[18:40:00.887] <TB3> INFO: number of dead pixels (per ROC): 1 0 0 1 0 0 0 0 0 1 1 0 0 0 0 0
[18:40:00.889] <TB3> INFO: ----------------------------------------------------------------------
[18:40:00.889] <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)
[18:40:00.889] <TB3> INFO: ----------------------------------------------------------------------
[18:40:01.173] <TB3> INFO: Expecting 41600 events.
[18:40:04.176] <TB3> INFO: 41600 events read in total (2411ms).
[18:40:04.176] <TB3> INFO: Test took 3285ms.
[18:40:04.176] <TB3> INFO: mask vs. old pixelAlive PixelAlive_C0_V0 .. PixelAlive_C15_V0
[18:40:04.414] <TB3> INFO: PixTestAlive::maskTest() done
[18:40:04.414] <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
[18:40:04.415] <TB3> INFO: ----------------------------------------------------------------------
[18:40:04.415] <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)
[18:40:04.415] <TB3> INFO: ----------------------------------------------------------------------
[18:40:04.660] <TB3> INFO: Expecting 41600 events.
[18:40:08.205] <TB3> INFO: 41600 events read in total (2953ms).
[18:40:08.206] <TB3> INFO: Test took 3789ms.
[18:40:08.443] <TB3> INFO: PixTestAlive::addressDecodingTest() done
[18:40:08.443] <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
[18:40:08.444] <TB3> INFO: PixTestAlive::doTest() done, duration: 11 seconds
[18:40:08.444] <TB3> INFO: Decoding statistics:
[18:40:08.444] <TB3> INFO: General information:
[18:40:08.444] <TB3> INFO: 16bit words read: 0
[18:40:08.444] <TB3> INFO: valid events total: 0
[18:40:08.444] <TB3> INFO: empty events: 0
[18:40:08.444] <TB3> INFO: valid events with pixels: 0
[18:40:08.444] <TB3> INFO: valid pixel hits: 0
[18:40:08.444] <TB3> INFO: Event errors: 0
[18:40:08.444] <TB3> INFO: start marker: 0
[18:40:08.444] <TB3> INFO: stop marker: 0
[18:40:08.444] <TB3> INFO: overflow: 0
[18:40:08.444] <TB3> INFO: invalid 5bit words: 0
[18:40:08.444] <TB3> INFO: invalid XOR eye diagram: 0
[18:40:08.444] <TB3> INFO: frame (failed synchr.): 0
[18:40:08.444] <TB3> INFO: idle data (no TBM trl): 0
[18:40:08.444] <TB3> INFO: no data (only TBM hdr): 0
[18:40:08.444] <TB3> INFO: TBM errors: 0
[18:40:08.444] <TB3> INFO: flawed TBM headers: 0
[18:40:08.444] <TB3> INFO: flawed TBM trailers: 0
[18:40:08.444] <TB3> INFO: event ID mismatches: 0
[18:40:08.444] <TB3> INFO: ROC errors: 0
[18:40:08.444] <TB3> INFO: missing ROC header(s): 0
[18:40:08.444] <TB3> INFO: misplaced readback start: 0
[18:40:08.444] <TB3> INFO: Pixel decoding errors: 0
[18:40:08.444] <TB3> INFO: pixel data incomplete: 0
[18:40:08.444] <TB3> INFO: pixel address: 0
[18:40:08.444] <TB3> INFO: pulse height fill bit: 0
[18:40:08.444] <TB3> INFO: buffer corruption: 0
[18:40:08.453] <TB3> INFO: readReadbackCal: /home/l_tester/david/FullQualification/data/M1008_FullQualification_2016-10-16_17h47m_1476632865//001_Fulltest_m20//readbackCal_C0.dat .. /home/l_tester/david/FullQualification/data/M1008_FullQualification_2016-10-16_17h47m_1476632865//001_Fulltest_m20//readbackCal_C15.dat
[18:40:08.453] <TB3> INFO: readGainPedestalParameters /home/l_tester/david/FullQualification/data/M1008_FullQualification_2016-10-16_17h47m_1476632865//001_Fulltest_m20//phCalibrationFitErr_C0.dat .. /home/l_tester/david/FullQualification/data/M1008_FullQualification_2016-10-16_17h47m_1476632865//001_Fulltest_m20//phCalibrationFitErr_C15.dat

[18:40:08.454] <TB3> INFO: ######################################################################
[18:40:08.454] <TB3> INFO: PixTestReadback::doTest()
[18:40:08.454] <TB3> INFO: ######################################################################
[18:40:08.454] <TB3> INFO: ----------------------------------------------------------------------
[18:40:08.454] <TB3> INFO: PixTestReadback::CalibrateVd()
[18:40:08.454] <TB3> INFO: ----------------------------------------------------------------------
[18:40:18.428] <TB3> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1008_FullQualification_2016-10-16_17h47m_1476632865//001_Fulltest_m20//readbackCal_C0.dat
[18:40:18.428] <TB3> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1008_FullQualification_2016-10-16_17h47m_1476632865//001_Fulltest_m20//readbackCal_C1.dat
[18:40:18.428] <TB3> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1008_FullQualification_2016-10-16_17h47m_1476632865//001_Fulltest_m20//readbackCal_C2.dat
[18:40:18.428] <TB3> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1008_FullQualification_2016-10-16_17h47m_1476632865//001_Fulltest_m20//readbackCal_C3.dat
[18:40:18.428] <TB3> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1008_FullQualification_2016-10-16_17h47m_1476632865//001_Fulltest_m20//readbackCal_C4.dat
[18:40:18.429] <TB3> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1008_FullQualification_2016-10-16_17h47m_1476632865//001_Fulltest_m20//readbackCal_C5.dat
[18:40:18.429] <TB3> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1008_FullQualification_2016-10-16_17h47m_1476632865//001_Fulltest_m20//readbackCal_C6.dat
[18:40:18.429] <TB3> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1008_FullQualification_2016-10-16_17h47m_1476632865//001_Fulltest_m20//readbackCal_C7.dat
[18:40:18.429] <TB3> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1008_FullQualification_2016-10-16_17h47m_1476632865//001_Fulltest_m20//readbackCal_C8.dat
[18:40:18.429] <TB3> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1008_FullQualification_2016-10-16_17h47m_1476632865//001_Fulltest_m20//readbackCal_C9.dat
[18:40:18.429] <TB3> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1008_FullQualification_2016-10-16_17h47m_1476632865//001_Fulltest_m20//readbackCal_C10.dat
[18:40:18.429] <TB3> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1008_FullQualification_2016-10-16_17h47m_1476632865//001_Fulltest_m20//readbackCal_C11.dat
[18:40:18.429] <TB3> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1008_FullQualification_2016-10-16_17h47m_1476632865//001_Fulltest_m20//readbackCal_C12.dat
[18:40:18.429] <TB3> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1008_FullQualification_2016-10-16_17h47m_1476632865//001_Fulltest_m20//readbackCal_C13.dat
[18:40:18.429] <TB3> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1008_FullQualification_2016-10-16_17h47m_1476632865//001_Fulltest_m20//readbackCal_C14.dat
[18:40:18.429] <TB3> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1008_FullQualification_2016-10-16_17h47m_1476632865//001_Fulltest_m20//readbackCal_C15.dat
[18:40:18.459] <TB3> INFO: PixTestPattern:: pg_setup set to default.
[18:40:18.460] <TB3> INFO: ----------------------------------------------------------------------
[18:40:18.460] <TB3> INFO: PixTestReadback::CalibrateVa()
[18:40:18.460] <TB3> INFO: ----------------------------------------------------------------------
[18:40:28.396] <TB3> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1008_FullQualification_2016-10-16_17h47m_1476632865//001_Fulltest_m20//readbackCal_C0.dat
[18:40:28.396] <TB3> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1008_FullQualification_2016-10-16_17h47m_1476632865//001_Fulltest_m20//readbackCal_C1.dat
[18:40:28.396] <TB3> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1008_FullQualification_2016-10-16_17h47m_1476632865//001_Fulltest_m20//readbackCal_C2.dat
[18:40:28.396] <TB3> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1008_FullQualification_2016-10-16_17h47m_1476632865//001_Fulltest_m20//readbackCal_C3.dat
[18:40:28.396] <TB3> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1008_FullQualification_2016-10-16_17h47m_1476632865//001_Fulltest_m20//readbackCal_C4.dat
[18:40:28.396] <TB3> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1008_FullQualification_2016-10-16_17h47m_1476632865//001_Fulltest_m20//readbackCal_C5.dat
[18:40:28.396] <TB3> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1008_FullQualification_2016-10-16_17h47m_1476632865//001_Fulltest_m20//readbackCal_C6.dat
[18:40:28.397] <TB3> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1008_FullQualification_2016-10-16_17h47m_1476632865//001_Fulltest_m20//readbackCal_C7.dat
[18:40:28.397] <TB3> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1008_FullQualification_2016-10-16_17h47m_1476632865//001_Fulltest_m20//readbackCal_C8.dat
[18:40:28.397] <TB3> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1008_FullQualification_2016-10-16_17h47m_1476632865//001_Fulltest_m20//readbackCal_C9.dat
[18:40:28.397] <TB3> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1008_FullQualification_2016-10-16_17h47m_1476632865//001_Fulltest_m20//readbackCal_C10.dat
[18:40:28.397] <TB3> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1008_FullQualification_2016-10-16_17h47m_1476632865//001_Fulltest_m20//readbackCal_C11.dat
[18:40:28.397] <TB3> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1008_FullQualification_2016-10-16_17h47m_1476632865//001_Fulltest_m20//readbackCal_C12.dat
[18:40:28.397] <TB3> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1008_FullQualification_2016-10-16_17h47m_1476632865//001_Fulltest_m20//readbackCal_C13.dat
[18:40:28.397] <TB3> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1008_FullQualification_2016-10-16_17h47m_1476632865//001_Fulltest_m20//readbackCal_C14.dat
[18:40:28.397] <TB3> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1008_FullQualification_2016-10-16_17h47m_1476632865//001_Fulltest_m20//readbackCal_C15.dat
[18:40:28.425] <TB3> INFO: PixTestPattern:: pg_setup set to default.
[18:40:28.425] <TB3> INFO: ----------------------------------------------------------------------
[18:40:28.425] <TB3> INFO: PixTestReadback::readbackVbg()
[18:40:28.425] <TB3> INFO: ----------------------------------------------------------------------
[18:40:36.097] <TB3> INFO: PixTestPattern:: pg_setup set to default.
[18:40:36.097] <TB3> INFO: ----------------------------------------------------------------------
[18:40:36.097] <TB3> INFO: PixTestReadback::getCalibratedVbg()
[18:40:36.097] <TB3> INFO: ----------------------------------------------------------------------
[18:40:36.097] <TB3> INFO: Vbg will be calibrated using Vd calibration
[18:40:36.097] <TB3> INFO: /*/*/*/*::: ROC 0: uncalibrated Vbg = 147.9calibrated Vbg = 1.19128 :::*/*/*/*/
[18:40:36.097] <TB3> INFO: /*/*/*/*::: ROC 1: uncalibrated Vbg = 150calibrated Vbg = 1.18834 :::*/*/*/*/
[18:40:36.097] <TB3> INFO: /*/*/*/*::: ROC 2: uncalibrated Vbg = 149.7calibrated Vbg = 1.19066 :::*/*/*/*/
[18:40:36.097] <TB3> INFO: /*/*/*/*::: ROC 3: uncalibrated Vbg = 151.7calibrated Vbg = 1.18128 :::*/*/*/*/
[18:40:36.097] <TB3> INFO: /*/*/*/*::: ROC 4: uncalibrated Vbg = 155.1calibrated Vbg = 1.18769 :::*/*/*/*/
[18:40:36.097] <TB3> INFO: /*/*/*/*::: ROC 5: uncalibrated Vbg = 152.3calibrated Vbg = 1.18173 :::*/*/*/*/
[18:40:36.097] <TB3> INFO: /*/*/*/*::: ROC 6: uncalibrated Vbg = 147calibrated Vbg = 1.18931 :::*/*/*/*/
[18:40:36.097] <TB3> INFO: /*/*/*/*::: ROC 7: uncalibrated Vbg = 157calibrated Vbg = 1.19108 :::*/*/*/*/
[18:40:36.097] <TB3> INFO: /*/*/*/*::: ROC 8: uncalibrated Vbg = 153.7calibrated Vbg = 1.18414 :::*/*/*/*/
[18:40:36.097] <TB3> INFO: /*/*/*/*::: ROC 9: uncalibrated Vbg = 152.1calibrated Vbg = 1.18756 :::*/*/*/*/
[18:40:36.097] <TB3> INFO: /*/*/*/*::: ROC 10: uncalibrated Vbg = 155.9calibrated Vbg = 1.17183 :::*/*/*/*/
[18:40:36.097] <TB3> INFO: /*/*/*/*::: ROC 11: uncalibrated Vbg = 150.1calibrated Vbg = 1.18025 :::*/*/*/*/
[18:40:36.097] <TB3> INFO: /*/*/*/*::: ROC 12: uncalibrated Vbg = 142.9calibrated Vbg = 1.1834 :::*/*/*/*/
[18:40:36.097] <TB3> INFO: /*/*/*/*::: ROC 13: uncalibrated Vbg = 146.1calibrated Vbg = 1.18928 :::*/*/*/*/
[18:40:36.097] <TB3> INFO: /*/*/*/*::: ROC 14: uncalibrated Vbg = 149.2calibrated Vbg = 1.18701 :::*/*/*/*/
[18:40:36.097] <TB3> INFO: /*/*/*/*::: ROC 15: uncalibrated Vbg = 156calibrated Vbg = 1.18899 :::*/*/*/*/
[18:40:36.100] <TB3> INFO: ----------------------------------------------------------------------
[18:40:36.100] <TB3> INFO: PixTestReadback::CalibrateIa()
[18:40:36.100] <TB3> INFO: ----------------------------------------------------------------------
[18:43:16.857] <TB3> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1008_FullQualification_2016-10-16_17h47m_1476632865//001_Fulltest_m20//readbackCal_C0.dat
[18:43:16.857] <TB3> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1008_FullQualification_2016-10-16_17h47m_1476632865//001_Fulltest_m20//readbackCal_C1.dat
[18:43:16.857] <TB3> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1008_FullQualification_2016-10-16_17h47m_1476632865//001_Fulltest_m20//readbackCal_C2.dat
[18:43:16.857] <TB3> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1008_FullQualification_2016-10-16_17h47m_1476632865//001_Fulltest_m20//readbackCal_C3.dat
[18:43:16.857] <TB3> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1008_FullQualification_2016-10-16_17h47m_1476632865//001_Fulltest_m20//readbackCal_C4.dat
[18:43:16.857] <TB3> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1008_FullQualification_2016-10-16_17h47m_1476632865//001_Fulltest_m20//readbackCal_C5.dat
[18:43:16.857] <TB3> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1008_FullQualification_2016-10-16_17h47m_1476632865//001_Fulltest_m20//readbackCal_C6.dat
[18:43:16.858] <TB3> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1008_FullQualification_2016-10-16_17h47m_1476632865//001_Fulltest_m20//readbackCal_C7.dat
[18:43:16.858] <TB3> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1008_FullQualification_2016-10-16_17h47m_1476632865//001_Fulltest_m20//readbackCal_C8.dat
[18:43:16.858] <TB3> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1008_FullQualification_2016-10-16_17h47m_1476632865//001_Fulltest_m20//readbackCal_C9.dat
[18:43:16.858] <TB3> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1008_FullQualification_2016-10-16_17h47m_1476632865//001_Fulltest_m20//readbackCal_C10.dat
[18:43:16.858] <TB3> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1008_FullQualification_2016-10-16_17h47m_1476632865//001_Fulltest_m20//readbackCal_C11.dat
[18:43:16.858] <TB3> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1008_FullQualification_2016-10-16_17h47m_1476632865//001_Fulltest_m20//readbackCal_C12.dat
[18:43:16.858] <TB3> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1008_FullQualification_2016-10-16_17h47m_1476632865//001_Fulltest_m20//readbackCal_C13.dat
[18:43:16.858] <TB3> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1008_FullQualification_2016-10-16_17h47m_1476632865//001_Fulltest_m20//readbackCal_C14.dat
[18:43:16.858] <TB3> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1008_FullQualification_2016-10-16_17h47m_1476632865//001_Fulltest_m20//readbackCal_C15.dat
[18:43:16.886] <TB3> INFO: PixTestPattern:: pg_setup set to default.
[18:43:16.888] <TB3> INFO: PixTestReadback::doTest() done
[18:43:16.888] <TB3> INFO: Decoding statistics:
[18:43:16.888] <TB3> INFO: General information:
[18:43:16.888] <TB3> INFO: 16bit words read: 1536
[18:43:16.888] <TB3> INFO: valid events total: 256
[18:43:16.888] <TB3> INFO: empty events: 256
[18:43:16.888] <TB3> INFO: valid events with pixels: 0
[18:43:16.888] <TB3> INFO: valid pixel hits: 0
[18:43:16.888] <TB3> INFO: Event errors: 0
[18:43:16.888] <TB3> INFO: start marker: 0
[18:43:16.888] <TB3> INFO: stop marker: 0
[18:43:16.888] <TB3> INFO: overflow: 0
[18:43:16.888] <TB3> INFO: invalid 5bit words: 0
[18:43:16.888] <TB3> INFO: invalid XOR eye diagram: 0
[18:43:16.888] <TB3> INFO: frame (failed synchr.): 0
[18:43:16.888] <TB3> INFO: idle data (no TBM trl): 0
[18:43:16.888] <TB3> INFO: no data (only TBM hdr): 0
[18:43:16.888] <TB3> INFO: TBM errors: 0
[18:43:16.888] <TB3> INFO: flawed TBM headers: 0
[18:43:16.888] <TB3> INFO: flawed TBM trailers: 0
[18:43:16.888] <TB3> INFO: event ID mismatches: 0
[18:43:16.888] <TB3> INFO: ROC errors: 0
[18:43:16.888] <TB3> INFO: missing ROC header(s): 0
[18:43:16.888] <TB3> INFO: misplaced readback start: 0
[18:43:16.888] <TB3> INFO: Pixel decoding errors: 0
[18:43:16.888] <TB3> INFO: pixel data incomplete: 0
[18:43:16.888] <TB3> INFO: pixel address: 0
[18:43:16.888] <TB3> INFO: pulse height fill bit: 0
[18:43:16.888] <TB3> INFO: buffer corruption: 0
[18:43:16.939] <TB3> INFO: ######################################################################
[18:43:16.939] <TB3> INFO: PixTestBBMap::doTest() Ntrig = 5, VcalS = 250 (high range)
[18:43:16.939] <TB3> INFO: ######################################################################
[18:43:16.941] <TB3> INFO: ---> dac: VthrComp name: calSMap ntrig: 5 dacrange: 0 .. 149 (-1/-1) hits flags = 514 (plus default)
[18:43:16.954] <TB3> INFO: dacScan split into 1 runs with ntrig = 5
[18:43:16.954] <TB3> INFO: run 1 of 1
[18:43:17.192] <TB3> INFO: Expecting 3120000 events.
[18:43:48.122] <TB3> INFO: 659050 events read in total (30339ms).
[18:44:17.937] <TB3> INFO: 1312005 events read in total (60154ms).











[18:44:48.174] <TB3> INFO: 1961590 events read in total (90391ms).











[18:45:18.644] <TB3> INFO: 2613245 events read in total (120861ms).











[18:45:42.674] <TB3> INFO: 3120000 events read in total (144891ms).
[18:45:42.762] <TB3> INFO: Test took 145808ms.
[18:46:11.079] <TB3> INFO: PixTestBBMap::doTest() done with 2 decoding errors: , duration: 174 seconds
[18:46:11.079] <TB3> INFO: number of dead bumps (per ROC): 2 0 1 0 0 0 0 0 0 1 0 0 0 0 0 0
[18:46:11.079] <TB3> INFO: separation cut (per ROC): 97 109 94 111 100 105 102 102 96 106 105 108 104 108 102 99
[18:46:11.079] <TB3> INFO: Decoding statistics:
[18:46:11.079] <TB3> INFO: General information:
[18:46:11.079] <TB3> INFO: 16bit words read: 0
[18:46:11.079] <TB3> INFO: valid events total: 0
[18:46:11.079] <TB3> INFO: empty events: 0
[18:46:11.079] <TB3> INFO: valid events with pixels: 0
[18:46:11.079] <TB3> INFO: valid pixel hits: 0
[18:46:11.079] <TB3> INFO: Event errors: 0
[18:46:11.079] <TB3> INFO: start marker: 0
[18:46:11.079] <TB3> INFO: stop marker: 0
[18:46:11.079] <TB3> INFO: overflow: 0
[18:46:11.079] <TB3> INFO: invalid 5bit words: 0
[18:46:11.079] <TB3> INFO: invalid XOR eye diagram: 0
[18:46:11.079] <TB3> INFO: frame (failed synchr.): 0
[18:46:11.079] <TB3> INFO: idle data (no TBM trl): 0
[18:46:11.079] <TB3> INFO: no data (only TBM hdr): 0
[18:46:11.079] <TB3> INFO: TBM errors: 0
[18:46:11.079] <TB3> INFO: flawed TBM headers: 0
[18:46:11.079] <TB3> INFO: flawed TBM trailers: 0
[18:46:11.079] <TB3> INFO: event ID mismatches: 0
[18:46:11.079] <TB3> INFO: ROC errors: 0
[18:46:11.079] <TB3> INFO: missing ROC header(s): 0
[18:46:11.079] <TB3> INFO: misplaced readback start: 0
[18:46:11.079] <TB3> INFO: Pixel decoding errors: 0
[18:46:11.079] <TB3> INFO: pixel data incomplete: 0
[18:46:11.079] <TB3> INFO: pixel address: 0
[18:46:11.079] <TB3> INFO: pulse height fill bit: 0
[18:46:11.079] <TB3> INFO: buffer corruption: 0
[18:46:11.114] <TB3> INFO: ######################################################################
[18:46:11.115] <TB3> INFO: PixTestScurves::fullTest() ntrig = 50, dacs/step = -1, ntrig/step = -1
[18:46:11.115] <TB3> INFO: ######################################################################
[18:46:11.115] <TB3> INFO: ----------------------------------------------------------------------
[18:46:11.115] <TB3> INFO: PixTestScurves::scurves(Vcal), ntrig = 50, dacs/step = -1, ntrig/step = -1
[18:46:11.115] <TB3> INFO: ----------------------------------------------------------------------
[18:46:11.115] <TB3> INFO: ---> dac: Vcal name: scurveVcal ntrig: 50 dacrange: 25 .. 200 (-1/-1) hits flags = 528 (plus default)
[18:46:11.131] <TB3> INFO: dacScan split into 1 runs with ntrig = 50
[18:46:11.131] <TB3> INFO: run 1 of 1
[18:46:11.369] <TB3> INFO: Expecting 36608000 events.
[18:46:35.476] <TB3> INFO: 667900 events read in total (23515ms).
[18:46:58.103] <TB3> INFO: 1327200 events read in total (46142ms).
[18:47:20.694] <TB3> INFO: 1986300 events read in total (68733ms).
[18:47:43.610] <TB3> INFO: 2645550 events read in total (91649ms).
[18:48:06.184] <TB3> INFO: 3304900 events read in total (114223ms).
[18:48:28.622] <TB3> INFO: 3960650 events read in total (136661ms).
[18:48:51.699] <TB3> INFO: 4619250 events read in total (159738ms).
[18:49:14.654] <TB3> INFO: 5277700 events read in total (182693ms).
[18:49:37.593] <TB3> INFO: 5936650 events read in total (205632ms).
[18:50:00.388] <TB3> INFO: 6595100 events read in total (228427ms).
[18:50:23.096] <TB3> INFO: 7252250 events read in total (251135ms).
[18:50:45.347] <TB3> INFO: 7909700 events read in total (273386ms).
[18:51:08.015] <TB3> INFO: 8567450 events read in total (296054ms).
[18:51:30.963] <TB3> INFO: 9226050 events read in total (319002ms).
[18:51:53.676] <TB3> INFO: 9883950 events read in total (341715ms).
[18:52:16.574] <TB3> INFO: 10540850 events read in total (364613ms).
[18:52:39.494] <TB3> INFO: 11197000 events read in total (387533ms).
[18:53:02.392] <TB3> INFO: 11852750 events read in total (410431ms).
[18:53:25.304] <TB3> INFO: 12509950 events read in total (433343ms).
[18:53:48.213] <TB3> INFO: 13166950 events read in total (456252ms).
[18:54:11.348] <TB3> INFO: 13823100 events read in total (479387ms).
[18:54:34.079] <TB3> INFO: 14481200 events read in total (502118ms).
[18:54:56.995] <TB3> INFO: 15135600 events read in total (525034ms).
[18:55:19.461] <TB3> INFO: 15792350 events read in total (547500ms).
[18:55:41.984] <TB3> INFO: 16446000 events read in total (570023ms).
[18:56:04.498] <TB3> INFO: 17101850 events read in total (592537ms).
[18:56:27.584] <TB3> INFO: 17755650 events read in total (615623ms).
[18:56:50.235] <TB3> INFO: 18409100 events read in total (638274ms).
[18:57:13.237] <TB3> INFO: 19063550 events read in total (661276ms).
[18:57:35.925] <TB3> INFO: 19716400 events read in total (683964ms).
[18:57:58.515] <TB3> INFO: 20366450 events read in total (706554ms).
[18:58:21.535] <TB3> INFO: 21018400 events read in total (729574ms).
[18:58:44.942] <TB3> INFO: 21671600 events read in total (752981ms).
[18:59:07.590] <TB3> INFO: 22323850 events read in total (775629ms).
[18:59:30.264] <TB3> INFO: 22975500 events read in total (798303ms).
[18:59:52.759] <TB3> INFO: 23626100 events read in total (820798ms).
[19:00:15.306] <TB3> INFO: 24277450 events read in total (843345ms).
[19:00:37.985] <TB3> INFO: 24928250 events read in total (866024ms).
[19:01:00.630] <TB3> INFO: 25580500 events read in total (888669ms).
[19:01:23.277] <TB3> INFO: 26231900 events read in total (911316ms).
[19:01:46.158] <TB3> INFO: 26883800 events read in total (934197ms).
[19:02:09.098] <TB3> INFO: 27535000 events read in total (957137ms).
[19:02:31.855] <TB3> INFO: 28185900 events read in total (979894ms).
[19:02:54.348] <TB3> INFO: 28835900 events read in total (1002387ms).
[19:03:17.098] <TB3> INFO: 29485500 events read in total (1025137ms).
[19:03:40.323] <TB3> INFO: 30135850 events read in total (1048362ms).
[19:04:02.951] <TB3> INFO: 30785150 events read in total (1070990ms).
[19:04:25.630] <TB3> INFO: 31434550 events read in total (1093669ms).
[19:04:48.037] <TB3> INFO: 32085450 events read in total (1116076ms).
[19:05:10.362] <TB3> INFO: 32735400 events read in total (1138401ms).
[19:05:33.055] <TB3> INFO: 33385300 events read in total (1161094ms).
[19:05:55.729] <TB3> INFO: 34036700 events read in total (1183768ms).
[19:06:18.270] <TB3> INFO: 34688500 events read in total (1206309ms).
[19:06:41.564] <TB3> INFO: 35340250 events read in total (1229603ms).
[19:07:04.738] <TB3> INFO: 35995050 events read in total (1252777ms).
[19:07:26.516] <TB3> INFO: 36608000 events read in total (1274555ms).
[19:07:26.583] <TB3> INFO: Test took 1275451ms.
[19:07:27.178] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[19:07:28.964] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[19:07:30.552] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[19:07:32.028] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[19:07:33.529] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[19:07:35.092] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[19:07:36.546] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[19:07:38.050] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[19:07:39.573] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[19:07:41.091] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[19:07:42.615] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[19:07:44.114] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[19:07:45.596] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[19:07:47.113] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[19:07:48.669] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[19:07:50.183] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[19:07:51.790] <TB3> INFO: PixTestScurves::scurves() done
[19:07:51.790] <TB3> INFO: Vcal mean: 104.44 119.61 101.85 118.15 110.75 113.24 114.22 101.71 109.62 117.50 107.35 107.52 110.38 109.52 107.01 108.21
[19:07:51.790] <TB3> INFO: Vcal RMS: 5.43 6.48 5.86 5.79 6.18 5.10 5.47 5.33 5.20 6.14 5.53 5.27 5.24 4.94 5.02 5.22
[19:07:51.790] <TB3> INFO: PixTestScurves::fullTest() done, duration: 1300 seconds
[19:07:51.790] <TB3> INFO: Decoding statistics:
[19:07:51.790] <TB3> INFO: General information:
[19:07:51.790] <TB3> INFO: 16bit words read: 0
[19:07:51.790] <TB3> INFO: valid events total: 0
[19:07:51.790] <TB3> INFO: empty events: 0
[19:07:51.790] <TB3> INFO: valid events with pixels: 0
[19:07:51.790] <TB3> INFO: valid pixel hits: 0
[19:07:51.790] <TB3> INFO: Event errors: 0
[19:07:51.790] <TB3> INFO: start marker: 0
[19:07:51.790] <TB3> INFO: stop marker: 0
[19:07:51.790] <TB3> INFO: overflow: 0
[19:07:51.790] <TB3> INFO: invalid 5bit words: 0
[19:07:51.790] <TB3> INFO: invalid XOR eye diagram: 0
[19:07:51.790] <TB3> INFO: frame (failed synchr.): 0
[19:07:51.790] <TB3> INFO: idle data (no TBM trl): 0
[19:07:51.790] <TB3> INFO: no data (only TBM hdr): 0
[19:07:51.790] <TB3> INFO: TBM errors: 0
[19:07:51.790] <TB3> INFO: flawed TBM headers: 0
[19:07:51.790] <TB3> INFO: flawed TBM trailers: 0
[19:07:51.790] <TB3> INFO: event ID mismatches: 0
[19:07:51.790] <TB3> INFO: ROC errors: 0
[19:07:51.791] <TB3> INFO: missing ROC header(s): 0
[19:07:51.791] <TB3> INFO: misplaced readback start: 0
[19:07:51.791] <TB3> INFO: Pixel decoding errors: 0
[19:07:51.791] <TB3> INFO: pixel data incomplete: 0
[19:07:51.791] <TB3> INFO: pixel address: 0
[19:07:51.791] <TB3> INFO: pulse height fill bit: 0
[19:07:51.791] <TB3> INFO: buffer corruption: 0
[19:07:51.857] <TB3> INFO: ######################################################################
[19:07:51.857] <TB3> INFO: PixTestTrim::doTest()
[19:07:51.857] <TB3> INFO: ######################################################################
[19:07:51.858] <TB3> INFO: ----------------------------------------------------------------------
[19:07:51.858] <TB3> INFO: PixTestTrim::trimTest() ntrig = 8, vcal = 35
[19:07:51.858] <TB3> INFO: ----------------------------------------------------------------------
[19:07:51.899] <TB3> INFO: ---> VthrComp thr map (minimal VthrComp)
[19:07:51.899] <TB3> INFO: ---> dac: vthrcomp name: TrimThr0 ntrig: 8 dacrange: 10 .. 160 (-1/-1) hits flags = 528 (plus default)
[19:07:51.912] <TB3> INFO: dacScan split into 1 runs with ntrig = 8
[19:07:51.912] <TB3> INFO: run 1 of 1
[19:07:52.180] <TB3> INFO: Expecting 5025280 events.
[19:08:22.766] <TB3> INFO: 818608 events read in total (29983ms).
[19:08:52.300] <TB3> INFO: 1635224 events read in total (59517ms).
[19:09:22.094] <TB3> INFO: 2449624 events read in total (89312ms).
[19:09:51.824] <TB3> INFO: 3261144 events read in total (119041ms).
[19:10:21.435] <TB3> INFO: 4069160 events read in total (148652ms).
[19:10:51.797] <TB3> INFO: 4875264 events read in total (179014ms).
[19:10:58.050] <TB3> INFO: 5025280 events read in total (185267ms).
[19:10:58.099] <TB3> INFO: Test took 186187ms.
[19:11:16.853] <TB3> INFO: ROC 0 VthrComp = 108
[19:11:16.853] <TB3> INFO: ROC 1 VthrComp = 122
[19:11:16.853] <TB3> INFO: ROC 2 VthrComp = 106
[19:11:16.853] <TB3> INFO: ROC 3 VthrComp = 129
[19:11:16.853] <TB3> INFO: ROC 4 VthrComp = 115
[19:11:16.853] <TB3> INFO: ROC 5 VthrComp = 116
[19:11:16.853] <TB3> INFO: ROC 6 VthrComp = 117
[19:11:16.853] <TB3> INFO: ROC 7 VthrComp = 111
[19:11:16.854] <TB3> INFO: ROC 8 VthrComp = 109
[19:11:16.854] <TB3> INFO: ROC 9 VthrComp = 119
[19:11:16.854] <TB3> INFO: ROC 10 VthrComp = 113
[19:11:16.854] <TB3> INFO: ROC 11 VthrComp = 111
[19:11:16.854] <TB3> INFO: ROC 12 VthrComp = 120
[19:11:16.854] <TB3> INFO: ROC 13 VthrComp = 122
[19:11:16.854] <TB3> INFO: ROC 14 VthrComp = 113
[19:11:16.854] <TB3> INFO: ROC 15 VthrComp = 115
[19:11:16.854] <TB3> INFO: ---> Vcal thr map (pixel with maximum Vcal thr)
[19:11:16.854] <TB3> INFO: ---> dac: vcal name: TrimThr1 ntrig: 8 dacrange: 10 .. 160 (-1/-1) hits flags = 528 (plus default)
[19:11:16.870] <TB3> INFO: dacScan split into 1 runs with ntrig = 8
[19:11:16.870] <TB3> INFO: run 1 of 1
[19:11:17.117] <TB3> INFO: Expecting 5025280 events.
[19:11:43.517] <TB3> INFO: 588632 events read in total (25809ms).
[19:12:09.200] <TB3> INFO: 1176400 events read in total (51491ms).
[19:12:34.662] <TB3> INFO: 1763672 events read in total (76953ms).
[19:13:00.242] <TB3> INFO: 2351048 events read in total (102533ms).
[19:13:25.820] <TB3> INFO: 2936648 events read in total (128111ms).
[19:13:51.916] <TB3> INFO: 3521640 events read in total (154207ms).
[19:14:17.414] <TB3> INFO: 4106256 events read in total (179705ms).
[19:14:43.460] <TB3> INFO: 4690984 events read in total (205751ms).
[19:14:58.339] <TB3> INFO: 5025280 events read in total (220630ms).
[19:14:58.422] <TB3> INFO: Test took 221552ms.
[19:15:19.825] <TB3> INFO: roc 0 with ID = 0 has maximal Vcal 58.631 for pixel 15/7 mean/min/max = 46.8262/34.9729/58.6795
[19:15:19.825] <TB3> INFO: roc 1 with ID = 1 has maximal Vcal 64.0501 for pixel 18/3 mean/min/max = 47.6018/31.0975/64.1061
[19:15:19.825] <TB3> INFO: roc 2 with ID = 2 has maximal Vcal 61.8196 for pixel 1/24 mean/min/max = 47.8926/33.7351/62.0501
[19:15:19.826] <TB3> INFO: roc 3 with ID = 3 has maximal Vcal 58.9491 for pixel 0/61 mean/min/max = 45.144/31.1393/59.1487
[19:15:19.826] <TB3> INFO: roc 4 with ID = 4 has maximal Vcal 62.355 for pixel 12/42 mean/min/max = 46.6468/30.88/62.4136
[19:15:19.826] <TB3> INFO: roc 5 with ID = 5 has maximal Vcal 59.0833 for pixel 0/53 mean/min/max = 45.9703/32.8414/59.0993
[19:15:19.827] <TB3> INFO: roc 6 with ID = 6 has maximal Vcal 60.5065 for pixel 19/78 mean/min/max = 46.3071/32.0564/60.5578
[19:15:19.827] <TB3> INFO: roc 7 with ID = 7 has maximal Vcal 58.936 for pixel 12/54 mean/min/max = 46.3605/33.7026/59.0184
[19:15:19.827] <TB3> INFO: roc 8 with ID = 8 has maximal Vcal 63.3615 for pixel 10/4 mean/min/max = 48.9355/34.431/63.4399
[19:15:19.828] <TB3> INFO: roc 9 with ID = 9 has maximal Vcal 60.4222 for pixel 47/61 mean/min/max = 46.3015/32.1204/60.4825
[19:15:19.828] <TB3> INFO: roc 10 with ID = 10 has maximal Vcal 58.2023 for pixel 7/77 mean/min/max = 45.3182/32.4077/58.2288
[19:15:19.829] <TB3> INFO: roc 11 with ID = 11 has maximal Vcal 60.7466 for pixel 19/79 mean/min/max = 47.3805/33.9972/60.7638
[19:15:19.829] <TB3> INFO: roc 12 with ID = 12 has maximal Vcal 57.8531 for pixel 0/15 mean/min/max = 45.2192/32.4614/57.977
[19:15:19.829] <TB3> INFO: roc 13 with ID = 13 has maximal Vcal 57.2491 for pixel 0/62 mean/min/max = 44.1741/31.0589/57.2893
[19:15:19.830] <TB3> INFO: roc 14 with ID = 14 has maximal Vcal 57.6647 for pixel 36/68 mean/min/max = 45.2065/32.5371/57.876
[19:15:19.830] <TB3> INFO: roc 15 with ID = 15 has maximal Vcal 58.3355 for pixel 2/56 mean/min/max = 45.2523/32.1656/58.339
[19:15:19.830] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[19:15:19.919] <TB3> INFO: Expecting 411648 events.
[19:15:29.604] <TB3> INFO: 411648 events read in total (9093ms).
[19:15:29.611] <TB3> INFO: Expecting 411648 events.
[19:15:39.139] <TB3> INFO: 411648 events read in total (9125ms).
[19:15:39.153] <TB3> INFO: Expecting 411648 events.
[19:15:48.567] <TB3> INFO: 411648 events read in total (9011ms).
[19:15:48.580] <TB3> INFO: Expecting 411648 events.
[19:15:57.985] <TB3> INFO: 411648 events read in total (9002ms).
[19:15:58.003] <TB3> INFO: Expecting 411648 events.
[19:16:07.441] <TB3> INFO: 411648 events read in total (9031ms).
[19:16:07.464] <TB3> INFO: Expecting 411648 events.
[19:16:16.826] <TB3> INFO: 411648 events read in total (8959ms).
[19:16:16.850] <TB3> INFO: Expecting 411648 events.
[19:16:25.981] <TB3> INFO: 411648 events read in total (8728ms).
[19:16:26.005] <TB3> INFO: Expecting 411648 events.
[19:16:35.465] <TB3> INFO: 411648 events read in total (9057ms).
[19:16:35.494] <TB3> INFO: Expecting 411648 events.
[19:16:44.664] <TB3> INFO: 411648 events read in total (8767ms).
[19:16:44.701] <TB3> INFO: Expecting 411648 events.
[19:16:53.918] <TB3> INFO: 411648 events read in total (8814ms).
[19:16:53.959] <TB3> INFO: Expecting 411648 events.
[19:17:03.424] <TB3> INFO: 411648 events read in total (9062ms).
[19:17:03.466] <TB3> INFO: Expecting 411648 events.
[19:17:12.678] <TB3> INFO: 411648 events read in total (8809ms).
[19:17:12.715] <TB3> INFO: Expecting 411648 events.
[19:17:21.948] <TB3> INFO: 411648 events read in total (8830ms).
[19:17:21.990] <TB3> INFO: Expecting 411648 events.
[19:17:31.426] <TB3> INFO: 411648 events read in total (9033ms).
[19:17:31.468] <TB3> INFO: Expecting 411648 events.
[19:17:40.728] <TB3> INFO: 411648 events read in total (8857ms).
[19:17:40.776] <TB3> INFO: Expecting 411648 events.
[19:17:49.982] <TB3> INFO: 411648 events read in total (8803ms).
[19:17:50.032] <TB3> INFO: Test took 150202ms.
[19:17:50.924] <TB3> INFO: ---> dac: vcal name: TrimThr2 ntrig: 8 dacrange: 0 .. 150 (-1/-1) hits flags = 528 (plus default)
[19:17:50.937] <TB3> INFO: dacScan split into 1 runs with ntrig = 8
[19:17:50.937] <TB3> INFO: run 1 of 1
[19:17:51.177] <TB3> INFO: Expecting 5025280 events.
[19:18:17.677] <TB3> INFO: 586344 events read in total (25908ms).
[19:18:43.243] <TB3> INFO: 1171344 events read in total (51474ms).
[19:19:09.165] <TB3> INFO: 1755472 events read in total (77396ms).
[19:19:34.995] <TB3> INFO: 2337816 events read in total (103226ms).
[19:20:00.603] <TB3> INFO: 2920112 events read in total (128834ms).
[19:20:26.462] <TB3> INFO: 3501536 events read in total (154693ms).
[19:20:52.565] <TB3> INFO: 4082272 events read in total (180796ms).
[19:21:18.234] <TB3> INFO: 4664216 events read in total (206465ms).
[19:21:34.736] <TB3> INFO: 5025280 events read in total (222967ms).
[19:21:34.864] <TB3> INFO: Test took 223927ms.
[19:21:57.772] <TB3> INFO: ---> TrimStepCorr4 extremal thresholds: 1.700730 .. 139.802904
[19:21:58.009] <TB3> INFO: Expecting 208000 events.
[19:22:07.734] <TB3> INFO: 208000 events read in total (9133ms).
[19:22:07.735] <TB3> INFO: Test took 9961ms.
[19:22:07.787] <TB3> INFO: ---> dac: vcal name: trimStepCorr4 ntrig: 8 dacrange: 1 .. 149 (-1/-1) hits flags = 528 (plus default)
[19:22:07.801] <TB3> INFO: dacScan split into 1 runs with ntrig = 8
[19:22:07.801] <TB3> INFO: run 1 of 1
[19:22:08.079] <TB3> INFO: Expecting 4958720 events.
[19:22:34.252] <TB3> INFO: 586384 events read in total (25582ms).
[19:23:00.101] <TB3> INFO: 1172624 events read in total (51431ms).
[19:23:26.078] <TB3> INFO: 1758824 events read in total (77408ms).
[19:23:51.843] <TB3> INFO: 2345176 events read in total (103173ms).
[19:24:17.618] <TB3> INFO: 2931592 events read in total (128948ms).
[19:24:43.615] <TB3> INFO: 3517400 events read in total (154945ms).
[19:25:09.608] <TB3> INFO: 4102904 events read in total (180938ms).
[19:25:35.270] <TB3> INFO: 4688160 events read in total (206600ms).
[19:25:47.312] <TB3> INFO: 4958720 events read in total (218642ms).
[19:25:47.434] <TB3> INFO: Test took 219634ms.
[19:26:11.130] <TB3> INFO: ---> TrimStepCorr2 extremal thresholds: 27.678228 .. 44.666856
[19:26:11.372] <TB3> INFO: Expecting 208000 events.
[19:26:21.175] <TB3> INFO: 208000 events read in total (9212ms).
[19:26:21.175] <TB3> INFO: Test took 10042ms.
[19:26:21.229] <TB3> INFO: ---> dac: vcal name: trimStepCorr2 ntrig: 8 dacrange: 17 .. 54 (-1/-1) hits flags = 528 (plus default)
[19:26:21.242] <TB3> INFO: dacScan split into 1 runs with ntrig = 8
[19:26:21.242] <TB3> INFO: run 1 of 1
[19:26:21.521] <TB3> INFO: Expecting 1264640 events.
[19:26:50.080] <TB3> INFO: 662112 events read in total (27968ms).
[19:27:15.558] <TB3> INFO: 1264640 events read in total (53446ms).
[19:27:15.594] <TB3> INFO: Test took 54352ms.
[19:27:29.333] <TB3> INFO: ---> TrimStepCorr1a extremal thresholds: 25.753646 .. 45.497242
[19:27:29.622] <TB3> INFO: Expecting 208000 events.
[19:27:39.032] <TB3> INFO: 208000 events read in total (8819ms).
[19:27:39.033] <TB3> INFO: Test took 9699ms.
[19:27:39.081] <TB3> INFO: ---> dac: vcal name: trimStepCorr1a ntrig: 8 dacrange: 15 .. 55 (-1/-1) hits flags = 528 (plus default)
[19:27:39.094] <TB3> INFO: dacScan split into 1 runs with ntrig = 8
[19:27:39.094] <TB3> INFO: run 1 of 1
[19:27:39.372] <TB3> INFO: Expecting 1364480 events.
[19:28:07.693] <TB3> INFO: 666960 events read in total (27730ms).
[19:28:35.328] <TB3> INFO: 1333240 events read in total (55366ms).
[19:28:37.037] <TB3> INFO: 1364480 events read in total (57074ms).
[19:28:37.071] <TB3> INFO: Test took 57978ms.
[19:28:50.867] <TB3> INFO: ---> TrimStepCorr1b extremal thresholds: 23.943791 .. 43.771062
[19:28:51.104] <TB3> INFO: Expecting 208000 events.
[19:29:00.874] <TB3> INFO: 208000 events read in total (9178ms).
[19:29:00.875] <TB3> INFO: Test took 10007ms.
[19:29:00.923] <TB3> INFO: ---> dac: vcal name: trimStepCorr1b ntrig: 8 dacrange: 13 .. 53 (-1/-1) hits flags = 528 (plus default)
[19:29:00.935] <TB3> INFO: dacScan split into 1 runs with ntrig = 8
[19:29:00.935] <TB3> INFO: run 1 of 1
[19:29:01.213] <TB3> INFO: Expecting 1364480 events.
[19:29:29.544] <TB3> INFO: 684400 events read in total (27740ms).
[19:29:57.795] <TB3> INFO: 1364480 events read in total (55991ms).
[19:29:57.824] <TB3> INFO: Test took 56889ms.
[19:30:11.437] <TB3> INFO: ---> TrimThrFinal extremal thresholds: 15 .. 55
[19:30:11.437] <TB3> INFO: ---> dac: vcal name: TrimThrFinal ntrig: 8 dacrange: 15 .. 55 (-1/-1) hits flags = 528 (plus default)
[19:30:11.451] <TB3> INFO: dacScan split into 1 runs with ntrig = 8
[19:30:11.451] <TB3> INFO: run 1 of 1
[19:30:11.692] <TB3> INFO: Expecting 1364480 events.
[19:30:40.552] <TB3> INFO: 667440 events read in total (28268ms).
[19:31:07.899] <TB3> INFO: 1334072 events read in total (55615ms).
[19:31:09.532] <TB3> INFO: 1364480 events read in total (57248ms).
[19:31:09.558] <TB3> INFO: Test took 58108ms.
[19:31:22.916] <TB3> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1008_FullQualification_2016-10-16_17h47m_1476632865//001_Fulltest_m20//dacParameters35_C0.dat
[19:31:22.916] <TB3> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1008_FullQualification_2016-10-16_17h47m_1476632865//001_Fulltest_m20//dacParameters35_C1.dat
[19:31:22.917] <TB3> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1008_FullQualification_2016-10-16_17h47m_1476632865//001_Fulltest_m20//dacParameters35_C2.dat
[19:31:22.917] <TB3> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1008_FullQualification_2016-10-16_17h47m_1476632865//001_Fulltest_m20//dacParameters35_C3.dat
[19:31:22.917] <TB3> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1008_FullQualification_2016-10-16_17h47m_1476632865//001_Fulltest_m20//dacParameters35_C4.dat
[19:31:22.917] <TB3> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1008_FullQualification_2016-10-16_17h47m_1476632865//001_Fulltest_m20//dacParameters35_C5.dat
[19:31:22.917] <TB3> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1008_FullQualification_2016-10-16_17h47m_1476632865//001_Fulltest_m20//dacParameters35_C6.dat
[19:31:22.917] <TB3> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1008_FullQualification_2016-10-16_17h47m_1476632865//001_Fulltest_m20//dacParameters35_C7.dat
[19:31:22.917] <TB3> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1008_FullQualification_2016-10-16_17h47m_1476632865//001_Fulltest_m20//dacParameters35_C8.dat
[19:31:22.917] <TB3> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1008_FullQualification_2016-10-16_17h47m_1476632865//001_Fulltest_m20//dacParameters35_C9.dat
[19:31:22.917] <TB3> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1008_FullQualification_2016-10-16_17h47m_1476632865//001_Fulltest_m20//dacParameters35_C10.dat
[19:31:22.917] <TB3> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1008_FullQualification_2016-10-16_17h47m_1476632865//001_Fulltest_m20//dacParameters35_C11.dat
[19:31:22.917] <TB3> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1008_FullQualification_2016-10-16_17h47m_1476632865//001_Fulltest_m20//dacParameters35_C12.dat
[19:31:22.917] <TB3> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1008_FullQualification_2016-10-16_17h47m_1476632865//001_Fulltest_m20//dacParameters35_C13.dat
[19:31:22.917] <TB3> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1008_FullQualification_2016-10-16_17h47m_1476632865//001_Fulltest_m20//dacParameters35_C14.dat
[19:31:22.917] <TB3> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1008_FullQualification_2016-10-16_17h47m_1476632865//001_Fulltest_m20//dacParameters35_C15.dat
[19:31:22.918] <TB3> INFO: write trim parameters into /home/l_tester/david/FullQualification/data/M1008_FullQualification_2016-10-16_17h47m_1476632865//001_Fulltest_m20//trimParameters35_C0.dat
[19:31:22.923] <TB3> INFO: write trim parameters into /home/l_tester/david/FullQualification/data/M1008_FullQualification_2016-10-16_17h47m_1476632865//001_Fulltest_m20//trimParameters35_C1.dat
[19:31:22.928] <TB3> INFO: write trim parameters into /home/l_tester/david/FullQualification/data/M1008_FullQualification_2016-10-16_17h47m_1476632865//001_Fulltest_m20//trimParameters35_C2.dat
[19:31:22.932] <TB3> INFO: write trim parameters into /home/l_tester/david/FullQualification/data/M1008_FullQualification_2016-10-16_17h47m_1476632865//001_Fulltest_m20//trimParameters35_C3.dat
[19:31:22.937] <TB3> INFO: write trim parameters into /home/l_tester/david/FullQualification/data/M1008_FullQualification_2016-10-16_17h47m_1476632865//001_Fulltest_m20//trimParameters35_C4.dat
[19:31:22.942] <TB3> INFO: write trim parameters into /home/l_tester/david/FullQualification/data/M1008_FullQualification_2016-10-16_17h47m_1476632865//001_Fulltest_m20//trimParameters35_C5.dat
[19:31:22.948] <TB3> INFO: write trim parameters into /home/l_tester/david/FullQualification/data/M1008_FullQualification_2016-10-16_17h47m_1476632865//001_Fulltest_m20//trimParameters35_C6.dat
[19:31:22.954] <TB3> INFO: write trim parameters into /home/l_tester/david/FullQualification/data/M1008_FullQualification_2016-10-16_17h47m_1476632865//001_Fulltest_m20//trimParameters35_C7.dat
[19:31:22.961] <TB3> INFO: write trim parameters into /home/l_tester/david/FullQualification/data/M1008_FullQualification_2016-10-16_17h47m_1476632865//001_Fulltest_m20//trimParameters35_C8.dat
[19:31:22.967] <TB3> INFO: write trim parameters into /home/l_tester/david/FullQualification/data/M1008_FullQualification_2016-10-16_17h47m_1476632865//001_Fulltest_m20//trimParameters35_C9.dat
[19:31:22.973] <TB3> INFO: write trim parameters into /home/l_tester/david/FullQualification/data/M1008_FullQualification_2016-10-16_17h47m_1476632865//001_Fulltest_m20//trimParameters35_C10.dat
[19:31:22.979] <TB3> INFO: write trim parameters into /home/l_tester/david/FullQualification/data/M1008_FullQualification_2016-10-16_17h47m_1476632865//001_Fulltest_m20//trimParameters35_C11.dat
[19:31:22.985] <TB3> INFO: write trim parameters into /home/l_tester/david/FullQualification/data/M1008_FullQualification_2016-10-16_17h47m_1476632865//001_Fulltest_m20//trimParameters35_C12.dat
[19:31:22.991] <TB3> INFO: write trim parameters into /home/l_tester/david/FullQualification/data/M1008_FullQualification_2016-10-16_17h47m_1476632865//001_Fulltest_m20//trimParameters35_C13.dat
[19:31:22.997] <TB3> INFO: write trim parameters into /home/l_tester/david/FullQualification/data/M1008_FullQualification_2016-10-16_17h47m_1476632865//001_Fulltest_m20//trimParameters35_C14.dat
[19:31:23.003] <TB3> INFO: write trim parameters into /home/l_tester/david/FullQualification/data/M1008_FullQualification_2016-10-16_17h47m_1476632865//001_Fulltest_m20//trimParameters35_C15.dat
[19:31:23.010] <TB3> INFO: PixTestTrim::trimTest() done
[19:31:23.010] <TB3> INFO: vtrim: 123 159 147 131 131 115 124 130 126 129 117 127 111 115 125 116
[19:31:23.010] <TB3> INFO: vthrcomp: 108 122 106 129 115 116 117 111 109 119 113 111 120 122 113 115
[19:31:23.010] <TB3> INFO: vcal mean: 34.95 34.99 34.89 34.93 34.96 34.99 35.05 35.03 35.08 35.02 34.94 34.98 34.95 34.97 34.93 34.98
[19:31:23.010] <TB3> INFO: vcal RMS: 1.10 1.13 1.00 1.24 1.07 1.02 1.27 0.91 1.24 1.11 1.12 0.94 0.94 1.00 0.96 1.06
[19:31:23.010] <TB3> INFO: bits mean: 8.48 9.76 8.72 9.63 9.73 8.95 9.88 9.30 8.57 9.78 9.58 8.73 8.92 9.79 9.72 9.70
[19:31:23.010] <TB3> INFO: bits RMS: 2.62 2.56 2.62 2.80 2.61 2.82 2.52 2.47 2.58 2.47 2.64 2.58 2.92 2.82 2.56 2.61
[19:31:23.017] <TB3> INFO: ----------------------------------------------------------------------
[19:31:23.017] <TB3> INFO: PixTestTrim::trimBitTest() ntrig = 5, vtrims = 254 126 63 32
[19:31:23.017] <TB3> INFO: ----------------------------------------------------------------------
[19:31:23.020] <TB3> INFO: ---> dac: Vcal name: TrimBitsThr0 ntrig: 5 dacrange: 0 .. 199 (-1/-1) hits flags = 528 (plus default)
[19:31:23.032] <TB3> INFO: dacScan split into 1 runs with ntrig = 5
[19:31:23.032] <TB3> INFO: run 1 of 1
[19:31:23.271] <TB3> INFO: Expecting 4160000 events.
[19:31:55.532] <TB3> INFO: 737240 events read in total (31669ms).
[19:32:27.023] <TB3> INFO: 1471540 events read in total (63160ms).
[19:32:58.404] <TB3> INFO: 2202745 events read in total (94541ms).
[19:33:30.151] <TB3> INFO: 2930010 events read in total (126288ms).
[19:34:01.585] <TB3> INFO: 3656070 events read in total (157722ms).
[19:34:23.316] <TB3> INFO: 4160000 events read in total (179453ms).
[19:34:23.398] <TB3> INFO: Test took 180366ms.
[19:34:49.199] <TB3> INFO: ---> dac: Vcal name: TrimThr_trim14 ntrig: 5 dacrange: 0 .. 208 (-1/-1) hits flags = 528 (plus default)
[19:34:49.213] <TB3> INFO: dacScan split into 1 runs with ntrig = 5
[19:34:49.213] <TB3> INFO: run 1 of 1
[19:34:49.451] <TB3> INFO: Expecting 4347200 events.
[19:35:21.193] <TB3> INFO: 703605 events read in total (31150ms).
[19:35:52.333] <TB3> INFO: 1404675 events read in total (62290ms).
[19:36:23.051] <TB3> INFO: 2104215 events read in total (93008ms).
[19:36:53.974] <TB3> INFO: 2800030 events read in total (123931ms).
[19:37:24.854] <TB3> INFO: 3494620 events read in total (154811ms).
[19:37:55.433] <TB3> INFO: 4188795 events read in total (185390ms).
[19:38:02.792] <TB3> INFO: 4347200 events read in total (192749ms).
[19:38:02.958] <TB3> INFO: Test took 193745ms.
[19:38:33.195] <TB3> INFO: ---> dac: Vcal name: TrimThr_trim13 ntrig: 5 dacrange: 0 .. 191 (-1/-1) hits flags = 528 (plus default)
[19:38:33.209] <TB3> INFO: dacScan split into 1 runs with ntrig = 5
[19:38:33.209] <TB3> INFO: run 1 of 1
[19:38:33.450] <TB3> INFO: Expecting 3993600 events.
[19:39:05.304] <TB3> INFO: 724295 events read in total (31262ms).
[19:39:36.439] <TB3> INFO: 1445740 events read in total (62397ms).
[19:40:07.922] <TB3> INFO: 2164330 events read in total (93880ms).
[19:40:39.331] <TB3> INFO: 2878895 events read in total (125289ms).
[19:41:10.632] <TB3> INFO: 3593080 events read in total (156590ms).
[19:41:28.454] <TB3> INFO: 3993600 events read in total (174412ms).
[19:41:28.524] <TB3> INFO: Test took 175315ms.
[19:41:55.644] <TB3> INFO: ---> dac: Vcal name: TrimThr_trim11 ntrig: 5 dacrange: 0 .. 192 (-1/-1) hits flags = 528 (plus default)
[19:41:55.658] <TB3> INFO: dacScan split into 1 runs with ntrig = 5
[19:41:55.658] <TB3> INFO: run 1 of 1
[19:41:55.894] <TB3> INFO: Expecting 4014400 events.
[19:42:27.586] <TB3> INFO: 722900 events read in total (31100ms).
[19:42:58.524] <TB3> INFO: 1443260 events read in total (62038ms).
[19:43:29.434] <TB3> INFO: 2160380 events read in total (92949ms).
[19:44:00.262] <TB3> INFO: 2874225 events read in total (123776ms).
[19:44:31.256] <TB3> INFO: 3586815 events read in total (154770ms).
[19:44:49.895] <TB3> INFO: 4014400 events read in total (173409ms).
[19:44:49.959] <TB3> INFO: Test took 174302ms.
[19:45:20.004] <TB3> INFO: ---> dac: Vcal name: TrimThr_trim7 ntrig: 5 dacrange: 0 .. 194 (-1/-1) hits flags = 528 (plus default)
[19:45:20.018] <TB3> INFO: dacScan split into 1 runs with ntrig = 5
[19:45:20.018] <TB3> INFO: run 1 of 1
[19:45:20.256] <TB3> INFO: Expecting 4056000 events.
[19:45:51.749] <TB3> INFO: 720725 events read in total (30902ms).
[19:46:23.330] <TB3> INFO: 1438635 events read in total (62483ms).
[19:46:54.806] <TB3> INFO: 2153615 events read in total (93959ms).
[19:47:26.387] <TB3> INFO: 2865260 events read in total (125540ms).
[19:47:58.174] <TB3> INFO: 3576065 events read in total (157327ms).
[19:48:19.506] <TB3> INFO: 4056000 events read in total (178659ms).
[19:48:19.593] <TB3> INFO: Test took 179575ms.
[19:48:49.108] <TB3> INFO: PixTestTrim::trimBitTest() done
[19:48:49.110] <TB3> INFO: PixTestTrim::doTest() done, duration: 2457 seconds
[19:48:49.110] <TB3> INFO: Decoding statistics:
[19:48:49.110] <TB3> INFO: General information:
[19:48:49.110] <TB3> INFO: 16bit words read: 0
[19:48:49.110] <TB3> INFO: valid events total: 0
[19:48:49.110] <TB3> INFO: empty events: 0
[19:48:49.110] <TB3> INFO: valid events with pixels: 0
[19:48:49.110] <TB3> INFO: valid pixel hits: 0
[19:48:49.110] <TB3> INFO: Event errors: 0
[19:48:49.110] <TB3> INFO: start marker: 0
[19:48:49.110] <TB3> INFO: stop marker: 0
[19:48:49.110] <TB3> INFO: overflow: 0
[19:48:49.110] <TB3> INFO: invalid 5bit words: 0
[19:48:49.110] <TB3> INFO: invalid XOR eye diagram: 0
[19:48:49.110] <TB3> INFO: frame (failed synchr.): 0
[19:48:49.110] <TB3> INFO: idle data (no TBM trl): 0
[19:48:49.110] <TB3> INFO: no data (only TBM hdr): 0
[19:48:49.110] <TB3> INFO: TBM errors: 0
[19:48:49.110] <TB3> INFO: flawed TBM headers: 0
[19:48:49.110] <TB3> INFO: flawed TBM trailers: 0
[19:48:49.110] <TB3> INFO: event ID mismatches: 0
[19:48:49.110] <TB3> INFO: ROC errors: 0
[19:48:49.110] <TB3> INFO: missing ROC header(s): 0
[19:48:49.110] <TB3> INFO: misplaced readback start: 0
[19:48:49.110] <TB3> INFO: Pixel decoding errors: 0
[19:48:49.110] <TB3> INFO: pixel data incomplete: 0
[19:48:49.110] <TB3> INFO: pixel address: 0
[19:48:49.110] <TB3> INFO: pulse height fill bit: 0
[19:48:49.110] <TB3> INFO: buffer corruption: 0
[19:48:49.735] <TB3> INFO: ######################################################################
[19:48:49.735] <TB3> INFO: PixTestPhOptimization::doTest() Ntrig = 10
[19:48:49.735] <TB3> INFO: ######################################################################
[19:48:49.972] <TB3> INFO: Expecting 41600 events.
[19:48:53.391] <TB3> INFO: 41600 events read in total (2828ms).
[19:48:53.392] <TB3> INFO: Test took 3655ms.
[19:48:53.867] <TB3> INFO: Expecting 41600 events.
[19:48:57.363] <TB3> INFO: 41600 events read in total (2904ms).
[19:48:57.364] <TB3> INFO: Test took 3769ms.
[19:48:57.653] <TB3> INFO: Expecting 41600 events.
[19:49:01.197] <TB3> INFO: 41600 events read in total (2952ms).
[19:49:01.198] <TB3> INFO: Test took 3810ms.
[19:49:01.487] <TB3> INFO: Expecting 41600 events.
[19:49:05.251] <TB3> INFO: 41600 events read in total (3172ms).
[19:49:05.251] <TB3> INFO: Test took 4029ms.
[19:49:05.545] <TB3> INFO: Expecting 41600 events.
[19:49:09.096] <TB3> INFO: 41600 events read in total (2960ms).
[19:49:09.096] <TB3> INFO: Test took 3816ms.
[19:49:09.385] <TB3> INFO: Expecting 41600 events.
[19:49:12.980] <TB3> INFO: 41600 events read in total (3003ms).
[19:49:12.981] <TB3> INFO: Test took 3861ms.
[19:49:13.272] <TB3> INFO: Expecting 41600 events.
[19:49:16.797] <TB3> INFO: 41600 events read in total (2933ms).
[19:49:16.798] <TB3> INFO: Test took 3791ms.
[19:49:17.086] <TB3> INFO: Expecting 41600 events.
[19:49:20.641] <TB3> INFO: 41600 events read in total (2963ms).
[19:49:20.642] <TB3> INFO: Test took 3820ms.
[19:49:20.933] <TB3> INFO: Expecting 41600 events.
[19:49:24.477] <TB3> INFO: 41600 events read in total (2952ms).
[19:49:24.478] <TB3> INFO: Test took 3810ms.
[19:49:24.767] <TB3> INFO: Expecting 41600 events.
[19:49:28.361] <TB3> INFO: 41600 events read in total (3002ms).
[19:49:28.362] <TB3> INFO: Test took 3859ms.
[19:49:28.656] <TB3> INFO: Expecting 41600 events.
[19:49:32.195] <TB3> INFO: 41600 events read in total (2948ms).
[19:49:32.196] <TB3> INFO: Test took 3805ms.
[19:49:32.485] <TB3> INFO: Expecting 41600 events.
[19:49:35.959] <TB3> INFO: 41600 events read in total (2882ms).
[19:49:35.959] <TB3> INFO: Test took 3738ms.
[19:49:36.248] <TB3> INFO: Expecting 41600 events.
[19:49:39.742] <TB3> INFO: 41600 events read in total (2902ms).
[19:49:39.742] <TB3> INFO: Test took 3759ms.
[19:49:40.066] <TB3> INFO: Expecting 41600 events.
[19:49:43.547] <TB3> INFO: 41600 events read in total (2890ms).
[19:49:43.548] <TB3> INFO: Test took 3778ms.
[19:49:43.841] <TB3> INFO: Expecting 41600 events.
[19:49:47.372] <TB3> INFO: 41600 events read in total (2939ms).
[19:49:47.373] <TB3> INFO: Test took 3798ms.
[19:49:47.662] <TB3> INFO: Expecting 41600 events.
[19:49:51.189] <TB3> INFO: 41600 events read in total (2936ms).
[19:49:51.190] <TB3> INFO: Test took 3793ms.
[19:49:51.479] <TB3> INFO: Expecting 41600 events.
[19:49:55.057] <TB3> INFO: 41600 events read in total (2986ms).
[19:49:55.057] <TB3> INFO: Test took 3843ms.
[19:49:55.346] <TB3> INFO: Expecting 41600 events.
[19:49:58.977] <TB3> INFO: 41600 events read in total (3039ms).
[19:49:58.978] <TB3> INFO: Test took 3897ms.
[19:49:59.267] <TB3> INFO: Expecting 41600 events.
[19:50:02.774] <TB3> INFO: 41600 events read in total (2915ms).
[19:50:02.775] <TB3> INFO: Test took 3773ms.
[19:50:03.064] <TB3> INFO: Expecting 41600 events.
[19:50:06.588] <TB3> INFO: 41600 events read in total (2933ms).
[19:50:06.589] <TB3> INFO: Test took 3790ms.
[19:50:06.878] <TB3> INFO: Expecting 41600 events.
[19:50:10.444] <TB3> INFO: 41600 events read in total (2975ms).
[19:50:10.444] <TB3> INFO: Test took 3831ms.
[19:50:10.733] <TB3> INFO: Expecting 41600 events.
[19:50:14.213] <TB3> INFO: 41600 events read in total (2888ms).
[19:50:14.214] <TB3> INFO: Test took 3745ms.
[19:50:14.529] <TB3> INFO: Expecting 41600 events.
[19:50:18.107] <TB3> INFO: 41600 events read in total (2987ms).
[19:50:18.108] <TB3> INFO: Test took 3870ms.
[19:50:18.397] <TB3> INFO: Expecting 41600 events.
[19:50:21.935] <TB3> INFO: 41600 events read in total (2947ms).
[19:50:21.936] <TB3> INFO: Test took 3804ms.
[19:50:22.228] <TB3> INFO: Expecting 41600 events.
[19:50:25.813] <TB3> INFO: 41600 events read in total (2994ms).
[19:50:25.813] <TB3> INFO: Test took 3850ms.
[19:50:26.103] <TB3> INFO: Expecting 41600 events.
[19:50:29.612] <TB3> INFO: 41600 events read in total (2917ms).
[19:50:29.613] <TB3> INFO: Test took 3776ms.
[19:50:29.903] <TB3> INFO: Expecting 41600 events.
[19:50:33.633] <TB3> INFO: 41600 events read in total (3138ms).
[19:50:33.634] <TB3> INFO: Test took 3995ms.
[19:50:33.925] <TB3> INFO: Expecting 41600 events.
[19:50:37.480] <TB3> INFO: 41600 events read in total (2964ms).
[19:50:37.481] <TB3> INFO: Test took 3821ms.
[19:50:37.772] <TB3> INFO: Expecting 41600 events.
[19:50:41.306] <TB3> INFO: 41600 events read in total (2943ms).
[19:50:41.307] <TB3> INFO: Test took 3800ms.
[19:50:41.597] <TB3> INFO: Expecting 2560 events.
[19:50:42.485] <TB3> INFO: 2560 events read in total (297ms).
[19:50:42.485] <TB3> INFO: Test took 1165ms.
[19:50:42.793] <TB3> INFO: Expecting 2560 events.
[19:50:43.678] <TB3> INFO: 2560 events read in total (293ms).
[19:50:43.679] <TB3> INFO: Test took 1193ms.
[19:50:43.986] <TB3> INFO: Expecting 2560 events.
[19:50:44.873] <TB3> INFO: 2560 events read in total (295ms).
[19:50:44.873] <TB3> INFO: Test took 1194ms.
[19:50:45.181] <TB3> INFO: Expecting 2560 events.
[19:50:46.072] <TB3> INFO: 2560 events read in total (300ms).
[19:50:46.073] <TB3> INFO: Test took 1200ms.
[19:50:46.381] <TB3> INFO: Expecting 2560 events.
[19:50:47.260] <TB3> INFO: 2560 events read in total (288ms).
[19:50:47.261] <TB3> INFO: Test took 1188ms.
[19:50:47.568] <TB3> INFO: Expecting 2560 events.
[19:50:48.448] <TB3> INFO: 2560 events read in total (288ms).
[19:50:48.448] <TB3> INFO: Test took 1187ms.
[19:50:48.757] <TB3> INFO: Expecting 2560 events.
[19:50:49.637] <TB3> INFO: 2560 events read in total (288ms).
[19:50:49.637] <TB3> INFO: Test took 1188ms.
[19:50:49.945] <TB3> INFO: Expecting 2560 events.
[19:50:50.838] <TB3> INFO: 2560 events read in total (301ms).
[19:50:50.838] <TB3> INFO: Test took 1201ms.
[19:50:51.145] <TB3> INFO: Expecting 2560 events.
[19:50:52.028] <TB3> INFO: 2560 events read in total (291ms).
[19:50:52.029] <TB3> INFO: Test took 1190ms.
[19:50:52.336] <TB3> INFO: Expecting 2560 events.
[19:50:53.219] <TB3> INFO: 2560 events read in total (292ms).
[19:50:53.219] <TB3> INFO: Test took 1190ms.
[19:50:53.527] <TB3> INFO: Expecting 2560 events.
[19:50:54.420] <TB3> INFO: 2560 events read in total (301ms).
[19:50:54.420] <TB3> INFO: Test took 1200ms.
[19:50:54.728] <TB3> INFO: Expecting 2560 events.
[19:50:55.616] <TB3> INFO: 2560 events read in total (296ms).
[19:50:55.616] <TB3> INFO: Test took 1196ms.
[19:50:55.925] <TB3> INFO: Expecting 2560 events.
[19:50:56.814] <TB3> INFO: 2560 events read in total (298ms).
[19:50:56.814] <TB3> INFO: Test took 1198ms.
[19:50:57.122] <TB3> INFO: Expecting 2560 events.
[19:50:58.010] <TB3> INFO: 2560 events read in total (296ms).
[19:50:58.010] <TB3> INFO: Test took 1195ms.
[19:50:58.318] <TB3> INFO: Expecting 2560 events.
[19:50:59.212] <TB3> INFO: 2560 events read in total (302ms).
[19:50:59.212] <TB3> INFO: Test took 1201ms.
[19:50:59.519] <TB3> INFO: Expecting 2560 events.
[19:51:00.407] <TB3> INFO: 2560 events read in total (296ms).
[19:51:00.407] <TB3> INFO: Test took 1194ms.
[19:51:00.410] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[19:51:00.716] <TB3> INFO: Expecting 655360 events.
[19:51:15.486] <TB3> INFO: 655360 events read in total (14178ms).
[19:51:15.498] <TB3> INFO: Expecting 655360 events.
[19:51:30.271] <TB3> INFO: 655360 events read in total (14370ms).
[19:51:30.289] <TB3> INFO: Expecting 655360 events.
[19:51:44.958] <TB3> INFO: 655360 events read in total (14266ms).
[19:51:44.978] <TB3> INFO: Expecting 655360 events.
[19:51:59.655] <TB3> INFO: 655360 events read in total (14273ms).
[19:51:59.680] <TB3> INFO: Expecting 655360 events.
[19:52:14.306] <TB3> INFO: 655360 events read in total (14223ms).
[19:52:14.337] <TB3> INFO: Expecting 655360 events.
[19:52:29.046] <TB3> INFO: 655360 events read in total (14306ms).
[19:52:29.078] <TB3> INFO: Expecting 655360 events.
[19:52:44.055] <TB3> INFO: 655360 events read in total (14573ms).
[19:52:44.104] <TB3> INFO: Expecting 655360 events.
[19:52:58.739] <TB3> INFO: 655360 events read in total (14232ms).
[19:52:58.782] <TB3> INFO: Expecting 655360 events.
[19:53:13.345] <TB3> INFO: 655360 events read in total (14160ms).
[19:53:13.393] <TB3> INFO: Expecting 655360 events.
[19:53:27.872] <TB3> INFO: 655360 events read in total (14076ms).
[19:53:27.926] <TB3> INFO: Expecting 655360 events.
[19:53:42.605] <TB3> INFO: 655360 events read in total (14276ms).
[19:53:42.664] <TB3> INFO: Expecting 655360 events.
[19:53:57.183] <TB3> INFO: 655360 events read in total (14116ms).
[19:53:57.243] <TB3> INFO: Expecting 655360 events.
[19:54:11.776] <TB3> INFO: 655360 events read in total (14129ms).
[19:54:11.861] <TB3> INFO: Expecting 655360 events.
[19:54:26.530] <TB3> INFO: 655360 events read in total (14265ms).
[19:54:26.661] <TB3> INFO: Expecting 655360 events.
[19:54:41.236] <TB3> INFO: 655360 events read in total (14172ms).
[19:54:41.321] <TB3> INFO: Expecting 655360 events.
[19:54:56.025] <TB3> INFO: 655360 events read in total (14301ms).
[19:54:56.127] <TB3> INFO: Test took 235717ms.
[19:54:56.222] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[19:54:56.480] <TB3> INFO: Expecting 655360 events.
[19:55:11.118] <TB3> INFO: 655360 events read in total (14046ms).
[19:55:11.131] <TB3> INFO: Expecting 655360 events.
[19:55:25.570] <TB3> INFO: 655360 events read in total (14035ms).
[19:55:25.588] <TB3> INFO: Expecting 655360 events.
[19:55:40.066] <TB3> INFO: 655360 events read in total (14075ms).
[19:55:40.088] <TB3> INFO: Expecting 655360 events.
[19:55:54.579] <TB3> INFO: 655360 events read in total (14088ms).
[19:55:54.614] <TB3> INFO: Expecting 655360 events.
[19:56:09.072] <TB3> INFO: 655360 events read in total (14055ms).
[19:56:09.102] <TB3> INFO: Expecting 655360 events.
[19:56:23.652] <TB3> INFO: 655360 events read in total (14147ms).
[19:56:23.691] <TB3> INFO: Expecting 655360 events.
[19:56:37.888] <TB3> INFO: 655360 events read in total (13793ms).
[19:56:37.925] <TB3> INFO: Expecting 655360 events.
[19:56:52.405] <TB3> INFO: 655360 events read in total (14077ms).
[19:56:52.460] <TB3> INFO: Expecting 655360 events.
[19:57:06.894] <TB3> INFO: 655360 events read in total (14030ms).
[19:57:06.942] <TB3> INFO: Expecting 655360 events.
[19:57:21.487] <TB3> INFO: 655360 events read in total (14142ms).
[19:57:21.539] <TB3> INFO: Expecting 655360 events.
[19:57:35.950] <TB3> INFO: 655360 events read in total (14008ms).
[19:57:36.024] <TB3> INFO: Expecting 655360 events.
[19:57:50.549] <TB3> INFO: 655360 events read in total (14122ms).
[19:57:50.612] <TB3> INFO: Expecting 655360 events.
[19:58:05.208] <TB3> INFO: 655360 events read in total (14193ms).
[19:58:05.277] <TB3> INFO: Expecting 655360 events.
[19:58:19.873] <TB3> INFO: 655360 events read in total (14192ms).
[19:58:19.971] <TB3> INFO: Expecting 655360 events.
[19:58:34.127] <TB3> INFO: 655360 events read in total (13753ms).
[19:58:34.207] <TB3> INFO: Expecting 655360 events.
[19:58:48.927] <TB3> INFO: 655360 events read in total (14317ms).
[19:58:49.053] <TB3> INFO: Test took 232831ms.
[19:58:49.236] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[19:58:49.242] <TB3> INFO: safety margin for low PH: adding 1, margin is now 21
[19:58:49.248] <TB3> INFO: safety margin for low PH: adding 2, margin is now 22
[19:58:49.254] <TB3> INFO: safety margin for low PH: adding 3, margin is now 23
[19:58:49.261] <TB3> INFO: safety margin for low PH: adding 4, margin is now 24
[19:58:49.269] <TB3> INFO: safety margin for low PH: adding 5, margin is now 25
[19:58:49.276] <TB3> INFO: safety margin for low PH: adding 6, margin is now 26
[19:58:49.285] <TB3> INFO: safety margin for low PH: adding 7, margin is now 27
[19:58:49.292] <TB3> INFO: safety margin for low PH: adding 8, margin is now 28
[19:58:49.299] <TB3> INFO: safety margin for low PH: adding 9, margin is now 29
[19:58:49.305] <TB3> INFO: safety margin for low PH: adding 10, margin is now 30
[19:58:49.311] <TB3> INFO: safety margin for low PH: adding 11, margin is now 31
[19:58:49.317] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[19:58:49.322] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[19:58:49.328] <TB3> INFO: safety margin for low PH: adding 1, margin is now 21
[19:58:49.334] <TB3> INFO: safety margin for low PH: adding 2, margin is now 22
[19:58:49.342] <TB3> INFO: safety margin for low PH: adding 3, margin is now 23
[19:58:49.350] <TB3> INFO: safety margin for low PH: adding 4, margin is now 24
[19:58:49.358] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[19:58:49.366] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[19:58:49.374] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[19:58:49.382] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[19:58:49.390] <TB3> INFO: safety margin for low PH: adding 1, margin is now 21
[19:58:49.399] <TB3> INFO: safety margin for low PH: adding 2, margin is now 22
[19:58:49.407] <TB3> INFO: safety margin for low PH: adding 3, margin is now 23
[19:58:49.415] <TB3> INFO: safety margin for low PH: adding 4, margin is now 24
[19:58:49.423] <TB3> INFO: safety margin for low PH: adding 5, margin is now 25
[19:58:49.431] <TB3> INFO: safety margin for low PH: adding 6, margin is now 26
[19:58:49.439] <TB3> INFO: safety margin for low PH: adding 7, margin is now 27
[19:58:49.448] <TB3> INFO: safety margin for low PH: adding 8, margin is now 28
[19:58:49.456] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[19:58:49.464] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[19:58:49.472] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[19:58:49.480] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[19:58:49.489] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[19:58:49.497] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[19:58:49.505] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[19:58:49.513] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[19:58:49.521] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[19:58:49.560] <TB3> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1008_FullQualification_2016-10-16_17h47m_1476632865//001_Fulltest_m20//dacParameters35_C0.dat
[19:58:49.560] <TB3> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1008_FullQualification_2016-10-16_17h47m_1476632865//001_Fulltest_m20//dacParameters35_C1.dat
[19:58:49.561] <TB3> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1008_FullQualification_2016-10-16_17h47m_1476632865//001_Fulltest_m20//dacParameters35_C2.dat
[19:58:49.561] <TB3> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1008_FullQualification_2016-10-16_17h47m_1476632865//001_Fulltest_m20//dacParameters35_C3.dat
[19:58:49.561] <TB3> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1008_FullQualification_2016-10-16_17h47m_1476632865//001_Fulltest_m20//dacParameters35_C4.dat
[19:58:49.561] <TB3> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1008_FullQualification_2016-10-16_17h47m_1476632865//001_Fulltest_m20//dacParameters35_C5.dat
[19:58:49.561] <TB3> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1008_FullQualification_2016-10-16_17h47m_1476632865//001_Fulltest_m20//dacParameters35_C6.dat
[19:58:49.561] <TB3> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1008_FullQualification_2016-10-16_17h47m_1476632865//001_Fulltest_m20//dacParameters35_C7.dat
[19:58:49.562] <TB3> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1008_FullQualification_2016-10-16_17h47m_1476632865//001_Fulltest_m20//dacParameters35_C8.dat
[19:58:49.562] <TB3> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1008_FullQualification_2016-10-16_17h47m_1476632865//001_Fulltest_m20//dacParameters35_C9.dat
[19:58:49.562] <TB3> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1008_FullQualification_2016-10-16_17h47m_1476632865//001_Fulltest_m20//dacParameters35_C10.dat
[19:58:49.562] <TB3> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1008_FullQualification_2016-10-16_17h47m_1476632865//001_Fulltest_m20//dacParameters35_C11.dat
[19:58:49.562] <TB3> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1008_FullQualification_2016-10-16_17h47m_1476632865//001_Fulltest_m20//dacParameters35_C12.dat
[19:58:49.562] <TB3> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1008_FullQualification_2016-10-16_17h47m_1476632865//001_Fulltest_m20//dacParameters35_C13.dat
[19:58:49.562] <TB3> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1008_FullQualification_2016-10-16_17h47m_1476632865//001_Fulltest_m20//dacParameters35_C14.dat
[19:58:49.563] <TB3> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1008_FullQualification_2016-10-16_17h47m_1476632865//001_Fulltest_m20//dacParameters35_C15.dat
[19:58:49.846] <TB3> INFO: Expecting 41600 events.
[19:58:52.987] <TB3> INFO: 41600 events read in total (2550ms).
[19:58:52.988] <TB3> INFO: Test took 3422ms.
[19:58:53.440] <TB3> INFO: Expecting 41600 events.
[19:58:56.477] <TB3> INFO: 41600 events read in total (2446ms).
[19:58:56.478] <TB3> INFO: Test took 3279ms.
[19:58:56.946] <TB3> INFO: Expecting 41600 events.
[19:59:00.087] <TB3> INFO: 41600 events read in total (2550ms).
[19:59:00.088] <TB3> INFO: Test took 3400ms.
[19:59:00.307] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[19:59:00.396] <TB3> INFO: Expecting 2560 events.
[19:59:01.280] <TB3> INFO: 2560 events read in total (292ms).
[19:59:01.280] <TB3> INFO: Test took 973ms.
[19:59:01.283] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[19:59:01.589] <TB3> INFO: Expecting 2560 events.
[19:59:02.476] <TB3> INFO: 2560 events read in total (295ms).
[19:59:02.477] <TB3> INFO: Test took 1194ms.
[19:59:02.479] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[19:59:02.785] <TB3> INFO: Expecting 2560 events.
[19:59:03.672] <TB3> INFO: 2560 events read in total (295ms).
[19:59:03.672] <TB3> INFO: Test took 1193ms.
[19:59:03.674] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[19:59:03.981] <TB3> INFO: Expecting 2560 events.
[19:59:04.872] <TB3> INFO: 2560 events read in total (300ms).
[19:59:04.872] <TB3> INFO: Test took 1198ms.
[19:59:04.874] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[19:59:05.181] <TB3> INFO: Expecting 2560 events.
[19:59:06.068] <TB3> INFO: 2560 events read in total (295ms).
[19:59:06.069] <TB3> INFO: Test took 1195ms.
[19:59:06.072] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[19:59:06.377] <TB3> INFO: Expecting 2560 events.
[19:59:07.260] <TB3> INFO: 2560 events read in total (291ms).
[19:59:07.261] <TB3> INFO: Test took 1189ms.
[19:59:07.262] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[19:59:07.569] <TB3> INFO: Expecting 2560 events.
[19:59:08.463] <TB3> INFO: 2560 events read in total (302ms).
[19:59:08.463] <TB3> INFO: Test took 1201ms.
[19:59:08.467] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[19:59:08.770] <TB3> INFO: Expecting 2560 events.
[19:59:09.666] <TB3> INFO: 2560 events read in total (304ms).
[19:59:09.667] <TB3> INFO: Test took 1200ms.
[19:59:09.669] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[19:59:09.976] <TB3> INFO: Expecting 2560 events.
[19:59:10.862] <TB3> INFO: 2560 events read in total (295ms).
[19:59:10.862] <TB3> INFO: Test took 1193ms.
[19:59:10.864] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[19:59:11.171] <TB3> INFO: Expecting 2560 events.
[19:59:12.051] <TB3> INFO: 2560 events read in total (288ms).
[19:59:12.052] <TB3> INFO: Test took 1188ms.
[19:59:12.055] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[19:59:12.359] <TB3> INFO: Expecting 2560 events.
[19:59:13.246] <TB3> INFO: 2560 events read in total (295ms).
[19:59:13.246] <TB3> INFO: Test took 1191ms.
[19:59:13.249] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[19:59:13.554] <TB3> INFO: Expecting 2560 events.
[19:59:14.438] <TB3> INFO: 2560 events read in total (292ms).
[19:59:14.438] <TB3> INFO: Test took 1189ms.
[19:59:14.441] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[19:59:14.746] <TB3> INFO: Expecting 2560 events.
[19:59:15.625] <TB3> INFO: 2560 events read in total (287ms).
[19:59:15.625] <TB3> INFO: Test took 1184ms.
[19:59:15.627] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[19:59:15.934] <TB3> INFO: Expecting 2560 events.
[19:59:16.819] <TB3> INFO: 2560 events read in total (294ms).
[19:59:16.819] <TB3> INFO: Test took 1192ms.
[19:59:16.821] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[19:59:17.128] <TB3> INFO: Expecting 2560 events.
[19:59:18.016] <TB3> INFO: 2560 events read in total (296ms).
[19:59:18.016] <TB3> INFO: Test took 1195ms.
[19:59:18.019] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[19:59:18.324] <TB3> INFO: Expecting 2560 events.
[19:59:19.213] <TB3> INFO: 2560 events read in total (297ms).
[19:59:19.214] <TB3> INFO: Test took 1195ms.
[19:59:19.217] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[19:59:19.521] <TB3> INFO: Expecting 2560 events.
[19:59:20.406] <TB3> INFO: 2560 events read in total (293ms).
[19:59:20.407] <TB3> INFO: Test took 1191ms.
[19:59:20.409] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[19:59:20.715] <TB3> INFO: Expecting 2560 events.
[19:59:21.598] <TB3> INFO: 2560 events read in total (291ms).
[19:59:21.598] <TB3> INFO: Test took 1189ms.
[19:59:21.601] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[19:59:21.906] <TB3> INFO: Expecting 2560 events.
[19:59:22.788] <TB3> INFO: 2560 events read in total (290ms).
[19:59:22.788] <TB3> INFO: Test took 1188ms.
[19:59:22.792] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[19:59:23.096] <TB3> INFO: Expecting 2560 events.
[19:59:23.980] <TB3> INFO: 2560 events read in total (292ms).
[19:59:23.981] <TB3> INFO: Test took 1190ms.
[19:59:23.984] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[19:59:24.289] <TB3> INFO: Expecting 2560 events.
[19:59:25.177] <TB3> INFO: 2560 events read in total (297ms).
[19:59:25.178] <TB3> INFO: Test took 1194ms.
[19:59:25.179] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[19:59:25.487] <TB3> INFO: Expecting 2560 events.
[19:59:26.379] <TB3> INFO: 2560 events read in total (301ms).
[19:59:26.379] <TB3> INFO: Test took 1200ms.
[19:59:26.381] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[19:59:26.687] <TB3> INFO: Expecting 2560 events.
[19:59:27.580] <TB3> INFO: 2560 events read in total (301ms).
[19:59:27.580] <TB3> INFO: Test took 1199ms.
[19:59:27.584] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[19:59:27.887] <TB3> INFO: Expecting 2560 events.
[19:59:28.776] <TB3> INFO: 2560 events read in total (297ms).
[19:59:28.776] <TB3> INFO: Test took 1193ms.
[19:59:28.778] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[19:59:29.085] <TB3> INFO: Expecting 2560 events.
[19:59:29.972] <TB3> INFO: 2560 events read in total (295ms).
[19:59:29.972] <TB3> INFO: Test took 1194ms.
[19:59:29.976] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[19:59:30.281] <TB3> INFO: Expecting 2560 events.
[19:59:31.166] <TB3> INFO: 2560 events read in total (294ms).
[19:59:31.167] <TB3> INFO: Test took 1191ms.
[19:59:31.169] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[19:59:31.476] <TB3> INFO: Expecting 2560 events.
[19:59:32.364] <TB3> INFO: 2560 events read in total (297ms).
[19:59:32.365] <TB3> INFO: Test took 1196ms.
[19:59:32.367] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[19:59:32.674] <TB3> INFO: Expecting 2560 events.
[19:59:33.567] <TB3> INFO: 2560 events read in total (302ms).
[19:59:33.567] <TB3> INFO: Test took 1200ms.
[19:59:33.570] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[19:59:33.875] <TB3> INFO: Expecting 2560 events.
[19:59:34.766] <TB3> INFO: 2560 events read in total (300ms).
[19:59:34.767] <TB3> INFO: Test took 1198ms.
[19:59:34.771] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[19:59:35.074] <TB3> INFO: Expecting 2560 events.
[19:59:35.963] <TB3> INFO: 2560 events read in total (298ms).
[19:59:35.963] <TB3> INFO: Test took 1193ms.
[19:59:35.966] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[19:59:36.273] <TB3> INFO: Expecting 2560 events.
[19:59:37.157] <TB3> INFO: 2560 events read in total (293ms).
[19:59:37.157] <TB3> INFO: Test took 1191ms.
[19:59:37.159] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[19:59:37.465] <TB3> INFO: Expecting 2560 events.
[19:59:38.350] <TB3> INFO: 2560 events read in total (293ms).
[19:59:38.350] <TB3> INFO: Test took 1191ms.
[19:59:38.822] <TB3> INFO: PixTestPhOptimization::doTest() done, duration: 649 seconds
[19:59:38.822] <TB3> INFO: PH scale (per ROC): 48 61 49 63 44 54 37 52 53 47 42 70 54 48 52 62
[19:59:38.822] <TB3> INFO: PH offset (per ROC): 106 128 111 120 93 87 109 127 128 99 104 127 132 101 142 123
[19:59:38.830] <TB3> INFO: Decoding statistics:
[19:59:38.830] <TB3> INFO: General information:
[19:59:38.830] <TB3> INFO: 16bit words read: 127880
[19:59:38.830] <TB3> INFO: valid events total: 20480
[19:59:38.830] <TB3> INFO: empty events: 17980
[19:59:38.830] <TB3> INFO: valid events with pixels: 2500
[19:59:38.830] <TB3> INFO: valid pixel hits: 2500
[19:59:38.830] <TB3> INFO: Event errors: 0
[19:59:38.830] <TB3> INFO: start marker: 0
[19:59:38.830] <TB3> INFO: stop marker: 0
[19:59:38.830] <TB3> INFO: overflow: 0
[19:59:38.830] <TB3> INFO: invalid 5bit words: 0
[19:59:38.830] <TB3> INFO: invalid XOR eye diagram: 0
[19:59:38.830] <TB3> INFO: frame (failed synchr.): 0
[19:59:38.830] <TB3> INFO: idle data (no TBM trl): 0
[19:59:38.830] <TB3> INFO: no data (only TBM hdr): 0
[19:59:38.830] <TB3> INFO: TBM errors: 0
[19:59:38.830] <TB3> INFO: flawed TBM headers: 0
[19:59:38.830] <TB3> INFO: flawed TBM trailers: 0
[19:59:38.830] <TB3> INFO: event ID mismatches: 0
[19:59:38.830] <TB3> INFO: ROC errors: 0
[19:59:38.830] <TB3> INFO: missing ROC header(s): 0
[19:59:38.830] <TB3> INFO: misplaced readback start: 0
[19:59:38.830] <TB3> INFO: Pixel decoding errors: 0
[19:59:38.830] <TB3> INFO: pixel data incomplete: 0
[19:59:38.830] <TB3> INFO: pixel address: 0
[19:59:38.830] <TB3> INFO: pulse height fill bit: 0
[19:59:38.830] <TB3> INFO: buffer corruption: 0
[19:59:39.034] <TB3> INFO: ######################################################################
[19:59:39.034] <TB3> INFO: PixTestGainPedestal::fullTest() ntrig = 10
[19:59:39.034] <TB3> INFO: ######################################################################
[19:59:39.048] <TB3> INFO: scanning low vcal = 10
[19:59:39.294] <TB3> INFO: Expecting 41600 events.
[19:59:42.890] <TB3> INFO: 41600 events read in total (3005ms).
[19:59:42.891] <TB3> INFO: Test took 3842ms.
[19:59:42.893] <TB3> INFO: scanning low vcal = 20
[19:59:43.187] <TB3> INFO: Expecting 41600 events.
[19:59:46.803] <TB3> INFO: 41600 events read in total (3024ms).
[19:59:46.804] <TB3> INFO: Test took 3911ms.
[19:59:46.806] <TB3> INFO: scanning low vcal = 30
[19:59:47.097] <TB3> INFO: Expecting 41600 events.
[19:59:50.770] <TB3> INFO: 41600 events read in total (3081ms).
[19:59:50.771] <TB3> INFO: Test took 3965ms.
[19:59:50.774] <TB3> INFO: scanning low vcal = 40
[19:59:51.051] <TB3> INFO: Expecting 41600 events.
[19:59:55.035] <TB3> INFO: 41600 events read in total (3392ms).
[19:59:55.036] <TB3> INFO: Test took 4262ms.
[19:59:55.039] <TB3> INFO: scanning low vcal = 50
[19:59:55.316] <TB3> INFO: Expecting 41600 events.
[19:59:59.267] <TB3> INFO: 41600 events read in total (3359ms).
[19:59:59.268] <TB3> INFO: Test took 4229ms.
[19:59:59.271] <TB3> INFO: scanning low vcal = 60
[19:59:59.552] <TB3> INFO: Expecting 41600 events.
[20:00:03.565] <TB3> INFO: 41600 events read in total (3421ms).
[20:00:03.566] <TB3> INFO: Test took 4295ms.
[20:00:03.569] <TB3> INFO: scanning low vcal = 70
[20:00:03.845] <TB3> INFO: Expecting 41600 events.
[20:00:07.882] <TB3> INFO: 41600 events read in total (3445ms).
[20:00:07.882] <TB3> INFO: Test took 4313ms.
[20:00:07.885] <TB3> INFO: scanning low vcal = 80
[20:00:08.162] <TB3> INFO: Expecting 41600 events.
[20:00:12.194] <TB3> INFO: 41600 events read in total (3440ms).
[20:00:12.195] <TB3> INFO: Test took 4310ms.
[20:00:12.198] <TB3> INFO: scanning low vcal = 90
[20:00:12.475] <TB3> INFO: Expecting 41600 events.
[20:00:16.428] <TB3> INFO: 41600 events read in total (3362ms).
[20:00:16.429] <TB3> INFO: Test took 4231ms.
[20:00:16.433] <TB3> INFO: scanning low vcal = 100
[20:00:16.709] <TB3> INFO: Expecting 41600 events.
[20:00:20.707] <TB3> INFO: 41600 events read in total (3407ms).
[20:00:20.708] <TB3> INFO: Test took 4275ms.
[20:00:20.711] <TB3> INFO: scanning low vcal = 110
[20:00:20.988] <TB3> INFO: Expecting 41600 events.
[20:00:25.032] <TB3> INFO: 41600 events read in total (3452ms).
[20:00:25.033] <TB3> INFO: Test took 4322ms.
[20:00:25.036] <TB3> INFO: scanning low vcal = 120
[20:00:25.319] <TB3> INFO: Expecting 41600 events.
[20:00:29.292] <TB3> INFO: 41600 events read in total (3381ms).
[20:00:29.293] <TB3> INFO: Test took 4257ms.
[20:00:29.296] <TB3> INFO: scanning low vcal = 130
[20:00:29.573] <TB3> INFO: Expecting 41600 events.
[20:00:33.570] <TB3> INFO: 41600 events read in total (3405ms).
[20:00:33.571] <TB3> INFO: Test took 4275ms.
[20:00:33.574] <TB3> INFO: scanning low vcal = 140
[20:00:33.851] <TB3> INFO: Expecting 41600 events.
[20:00:37.838] <TB3> INFO: 41600 events read in total (3395ms).
[20:00:37.839] <TB3> INFO: Test took 4265ms.
[20:00:37.842] <TB3> INFO: scanning low vcal = 150
[20:00:38.119] <TB3> INFO: Expecting 41600 events.
[20:00:42.110] <TB3> INFO: 41600 events read in total (3399ms).
[20:00:42.111] <TB3> INFO: Test took 4269ms.
[20:00:42.114] <TB3> INFO: scanning low vcal = 160
[20:00:42.391] <TB3> INFO: Expecting 41600 events.
[20:00:46.426] <TB3> INFO: 41600 events read in total (3444ms).
[20:00:46.427] <TB3> INFO: Test took 4313ms.
[20:00:46.430] <TB3> INFO: scanning low vcal = 170
[20:00:46.707] <TB3> INFO: Expecting 41600 events.
[20:00:50.727] <TB3> INFO: 41600 events read in total (3429ms).
[20:00:50.727] <TB3> INFO: Test took 4297ms.
[20:00:50.732] <TB3> INFO: scanning low vcal = 180
[20:00:51.007] <TB3> INFO: Expecting 41600 events.
[20:00:55.032] <TB3> INFO: 41600 events read in total (3434ms).
[20:00:55.032] <TB3> INFO: Test took 4299ms.
[20:00:55.035] <TB3> INFO: scanning low vcal = 190
[20:00:55.313] <TB3> INFO: Expecting 41600 events.
[20:00:59.422] <TB3> INFO: 41600 events read in total (3518ms).
[20:00:59.423] <TB3> INFO: Test took 4388ms.
[20:00:59.426] <TB3> INFO: scanning low vcal = 200
[20:00:59.703] <TB3> INFO: Expecting 41600 events.
[20:01:03.741] <TB3> INFO: 41600 events read in total (3447ms).
[20:01:03.742] <TB3> INFO: Test took 4316ms.
[20:01:03.745] <TB3> INFO: scanning low vcal = 210
[20:01:04.023] <TB3> INFO: Expecting 41600 events.
[20:01:08.057] <TB3> INFO: 41600 events read in total (3443ms).
[20:01:08.057] <TB3> INFO: Test took 4312ms.
[20:01:08.060] <TB3> INFO: scanning low vcal = 220
[20:01:08.337] <TB3> INFO: Expecting 41600 events.
[20:01:12.330] <TB3> INFO: 41600 events read in total (3401ms).
[20:01:12.331] <TB3> INFO: Test took 4271ms.
[20:01:12.334] <TB3> INFO: scanning low vcal = 230
[20:01:12.611] <TB3> INFO: Expecting 41600 events.
[20:01:16.630] <TB3> INFO: 41600 events read in total (3427ms).
[20:01:16.631] <TB3> INFO: Test took 4297ms.
[20:01:16.633] <TB3> INFO: scanning low vcal = 240
[20:01:16.911] <TB3> INFO: Expecting 41600 events.
[20:01:20.943] <TB3> INFO: 41600 events read in total (3440ms).
[20:01:20.944] <TB3> INFO: Test took 4310ms.
[20:01:20.946] <TB3> INFO: scanning low vcal = 250
[20:01:21.224] <TB3> INFO: Expecting 41600 events.
[20:01:25.228] <TB3> INFO: 41600 events read in total (3412ms).
[20:01:25.229] <TB3> INFO: Test took 4283ms.
[20:01:25.232] <TB3> INFO: scanning high vcal = 30 (= 210 in low range)
[20:01:25.509] <TB3> INFO: Expecting 41600 events.
[20:01:29.526] <TB3> INFO: 41600 events read in total (3425ms).
[20:01:29.527] <TB3> INFO: Test took 4295ms.
[20:01:29.530] <TB3> INFO: scanning high vcal = 50 (= 350 in low range)
[20:01:29.807] <TB3> INFO: Expecting 41600 events.
[20:01:33.800] <TB3> INFO: 41600 events read in total (3402ms).
[20:01:33.801] <TB3> INFO: Test took 4271ms.
[20:01:33.803] <TB3> INFO: scanning high vcal = 70 (= 490 in low range)
[20:01:34.081] <TB3> INFO: Expecting 41600 events.
[20:01:38.089] <TB3> INFO: 41600 events read in total (3416ms).
[20:01:38.090] <TB3> INFO: Test took 4286ms.
[20:01:38.093] <TB3> INFO: scanning high vcal = 90 (= 630 in low range)
[20:01:38.371] <TB3> INFO: Expecting 41600 events.
[20:01:42.394] <TB3> INFO: 41600 events read in total (3432ms).
[20:01:42.394] <TB3> INFO: Test took 4301ms.
[20:01:42.397] <TB3> INFO: scanning high vcal = 200 (= 1400 in low range)
[20:01:42.678] <TB3> INFO: Expecting 41600 events.
[20:01:46.717] <TB3> INFO: 41600 events read in total (3447ms).
[20:01:46.718] <TB3> INFO: Test took 4321ms.
[20:01:47.133] <TB3> INFO: PixTestGainPedestal::measure() done
[20:02:20.395] <TB3> INFO: PixTestGainPedestal::fit() done
[20:02:20.395] <TB3> INFO: non-linearity mean: 0.937 0.986 0.939 0.979 0.913 0.924 0.966 0.971 0.983 0.916 0.910 0.983 0.976 0.962 0.976 0.983
[20:02:20.395] <TB3> INFO: non-linearity RMS: 0.090 0.003 0.059 0.004 0.164 0.120 0.181 0.006 0.003 0.134 0.160 0.004 0.006 0.176 0.005 0.003
[20:02:20.395] <TB3> INFO: write gain/ped parameters into /home/l_tester/david/FullQualification/data/M1008_FullQualification_2016-10-16_17h47m_1476632865//001_Fulltest_m20//phCalibrationFitErr35_C0.dat
[20:02:20.409] <TB3> INFO: write gain/ped parameters into /home/l_tester/david/FullQualification/data/M1008_FullQualification_2016-10-16_17h47m_1476632865//001_Fulltest_m20//phCalibrationFitErr35_C1.dat
[20:02:20.422] <TB3> INFO: write gain/ped parameters into /home/l_tester/david/FullQualification/data/M1008_FullQualification_2016-10-16_17h47m_1476632865//001_Fulltest_m20//phCalibrationFitErr35_C2.dat
[20:02:20.435] <TB3> INFO: write gain/ped parameters into /home/l_tester/david/FullQualification/data/M1008_FullQualification_2016-10-16_17h47m_1476632865//001_Fulltest_m20//phCalibrationFitErr35_C3.dat
[20:02:20.448] <TB3> INFO: write gain/ped parameters into /home/l_tester/david/FullQualification/data/M1008_FullQualification_2016-10-16_17h47m_1476632865//001_Fulltest_m20//phCalibrationFitErr35_C4.dat
[20:02:20.461] <TB3> INFO: write gain/ped parameters into /home/l_tester/david/FullQualification/data/M1008_FullQualification_2016-10-16_17h47m_1476632865//001_Fulltest_m20//phCalibrationFitErr35_C5.dat
[20:02:20.475] <TB3> INFO: write gain/ped parameters into /home/l_tester/david/FullQualification/data/M1008_FullQualification_2016-10-16_17h47m_1476632865//001_Fulltest_m20//phCalibrationFitErr35_C6.dat
[20:02:20.488] <TB3> INFO: write gain/ped parameters into /home/l_tester/david/FullQualification/data/M1008_FullQualification_2016-10-16_17h47m_1476632865//001_Fulltest_m20//phCalibrationFitErr35_C7.dat
[20:02:20.500] <TB3> INFO: write gain/ped parameters into /home/l_tester/david/FullQualification/data/M1008_FullQualification_2016-10-16_17h47m_1476632865//001_Fulltest_m20//phCalibrationFitErr35_C8.dat
[20:02:20.514] <TB3> INFO: write gain/ped parameters into /home/l_tester/david/FullQualification/data/M1008_FullQualification_2016-10-16_17h47m_1476632865//001_Fulltest_m20//phCalibrationFitErr35_C9.dat
[20:02:20.527] <TB3> INFO: write gain/ped parameters into /home/l_tester/david/FullQualification/data/M1008_FullQualification_2016-10-16_17h47m_1476632865//001_Fulltest_m20//phCalibrationFitErr35_C10.dat
[20:02:20.540] <TB3> INFO: write gain/ped parameters into /home/l_tester/david/FullQualification/data/M1008_FullQualification_2016-10-16_17h47m_1476632865//001_Fulltest_m20//phCalibrationFitErr35_C11.dat
[20:02:20.553] <TB3> INFO: write gain/ped parameters into /home/l_tester/david/FullQualification/data/M1008_FullQualification_2016-10-16_17h47m_1476632865//001_Fulltest_m20//phCalibrationFitErr35_C12.dat
[20:02:20.566] <TB3> INFO: write gain/ped parameters into /home/l_tester/david/FullQualification/data/M1008_FullQualification_2016-10-16_17h47m_1476632865//001_Fulltest_m20//phCalibrationFitErr35_C13.dat
[20:02:20.579] <TB3> INFO: write gain/ped parameters into /home/l_tester/david/FullQualification/data/M1008_FullQualification_2016-10-16_17h47m_1476632865//001_Fulltest_m20//phCalibrationFitErr35_C14.dat
[20:02:20.592] <TB3> INFO: write gain/ped parameters into /home/l_tester/david/FullQualification/data/M1008_FullQualification_2016-10-16_17h47m_1476632865//001_Fulltest_m20//phCalibrationFitErr35_C15.dat
[20:02:20.605] <TB3> INFO: PixTestGainPedestal::fullTest() done, duration: 161 seconds
[20:02:20.605] <TB3> INFO: Decoding statistics:
[20:02:20.605] <TB3> INFO: General information:
[20:02:20.605] <TB3> INFO: 16bit words read: 3327834
[20:02:20.605] <TB3> INFO: valid events total: 332800
[20:02:20.605] <TB3> INFO: empty events: 0
[20:02:20.605] <TB3> INFO: valid events with pixels: 332800
[20:02:20.605] <TB3> INFO: valid pixel hits: 665517
[20:02:20.605] <TB3> INFO: Event errors: 0
[20:02:20.605] <TB3> INFO: start marker: 0
[20:02:20.605] <TB3> INFO: stop marker: 0
[20:02:20.605] <TB3> INFO: overflow: 0
[20:02:20.605] <TB3> INFO: invalid 5bit words: 0
[20:02:20.605] <TB3> INFO: invalid XOR eye diagram: 0
[20:02:20.605] <TB3> INFO: frame (failed synchr.): 0
[20:02:20.605] <TB3> INFO: idle data (no TBM trl): 0
[20:02:20.605] <TB3> INFO: no data (only TBM hdr): 0
[20:02:20.605] <TB3> INFO: TBM errors: 0
[20:02:20.605] <TB3> INFO: flawed TBM headers: 0
[20:02:20.605] <TB3> INFO: flawed TBM trailers: 0
[20:02:20.605] <TB3> INFO: event ID mismatches: 0
[20:02:20.605] <TB3> INFO: ROC errors: 0
[20:02:20.605] <TB3> INFO: missing ROC header(s): 0
[20:02:20.605] <TB3> INFO: misplaced readback start: 0
[20:02:20.605] <TB3> INFO: Pixel decoding errors: 0
[20:02:20.605] <TB3> INFO: pixel data incomplete: 0
[20:02:20.605] <TB3> INFO: pixel address: 0
[20:02:20.606] <TB3> INFO: pulse height fill bit: 0
[20:02:20.606] <TB3> INFO: buffer corruption: 0
[20:02:20.620] <TB3> INFO: Decoding statistics:
[20:02:20.620] <TB3> INFO: General information:
[20:02:20.620] <TB3> INFO: 16bit words read: 3457250
[20:02:20.620] <TB3> INFO: valid events total: 353536
[20:02:20.620] <TB3> INFO: empty events: 18236
[20:02:20.620] <TB3> INFO: valid events with pixels: 335300
[20:02:20.620] <TB3> INFO: valid pixel hits: 668017
[20:02:20.620] <TB3> INFO: Event errors: 0
[20:02:20.620] <TB3> INFO: start marker: 0
[20:02:20.620] <TB3> INFO: stop marker: 0
[20:02:20.620] <TB3> INFO: overflow: 0
[20:02:20.620] <TB3> INFO: invalid 5bit words: 0
[20:02:20.621] <TB3> INFO: invalid XOR eye diagram: 0
[20:02:20.621] <TB3> INFO: frame (failed synchr.): 0
[20:02:20.621] <TB3> INFO: idle data (no TBM trl): 0
[20:02:20.621] <TB3> INFO: no data (only TBM hdr): 0
[20:02:20.621] <TB3> INFO: TBM errors: 0
[20:02:20.621] <TB3> INFO: flawed TBM headers: 0
[20:02:20.621] <TB3> INFO: flawed TBM trailers: 0
[20:02:20.621] <TB3> INFO: event ID mismatches: 0
[20:02:20.621] <TB3> INFO: ROC errors: 0
[20:02:20.621] <TB3> INFO: missing ROC header(s): 0
[20:02:20.621] <TB3> INFO: misplaced readback start: 0
[20:02:20.621] <TB3> INFO: Pixel decoding errors: 0
[20:02:20.621] <TB3> INFO: pixel data incomplete: 0
[20:02:20.621] <TB3> INFO: pixel address: 0
[20:02:20.621] <TB3> INFO: pulse height fill bit: 0
[20:02:20.621] <TB3> INFO: buffer corruption: 0
[20:02:20.621] <TB3> INFO: enter test to run
[20:02:20.621] <TB3> INFO: test: exit no parameter change
[20:02:20.757] <TB3> QUIET: Connection to board 126 closed.
[20:02:20.760] <TB3> INFO: pXar: this is the end, my friend