[19:26:47.430] <TB0> INFO: *** Welcome to pxar ***
[19:26:47.430] <TB0> INFO: *** Today: 2016/11/03
[19:26:47.436] <TB0> INFO: *** Version: c8ba-dirty
[19:26:47.436] <TB0> INFO: readRocDacs: /home/l_tester/david/FullQualification/data/M1071_FullQualification_2016-11-03_14h08m_1478178483//001_Fulltest_m20//dacParameters_C0.dat .. /home/l_tester/david/FullQualification/data/M1071_FullQualification_2016-11-03_14h08m_1478178483//001_Fulltest_m20//dacParameters_C15.dat
[19:26:47.436] <TB0> INFO: readTbmDacs: /home/l_tester/david/FullQualification/data/M1071_FullQualification_2016-11-03_14h08m_1478178483//001_Fulltest_m20//tbmParameters_C0a.dat .. /home/l_tester/david/FullQualification/data/M1071_FullQualification_2016-11-03_14h08m_1478178483//001_Fulltest_m20//tbmParameters_C1b.dat
[19:26:47.437] <TB0> INFO: readMaskFile: /home/l_tester/david/FullQualification/data/M1071_FullQualification_2016-11-03_14h08m_1478178483//001_Fulltest_m20//defaultMaskFile.dat
[19:26:47.437] <TB0> INFO: readTrimFile: /home/l_tester/david/FullQualification/data/M1071_FullQualification_2016-11-03_14h08m_1478178483//001_Fulltest_m20//trimParameters_C0.dat .. /home/l_tester/david/FullQualification/data/M1071_FullQualification_2016-11-03_14h08m_1478178483//001_Fulltest_m20//trimParameters_C15.dat
[19:26:47.496] <TB0> INFO: clk: 4
[19:26:47.496] <TB0> INFO: ctr: 4
[19:26:47.496] <TB0> INFO: sda: 19
[19:26:47.496] <TB0> INFO: tin: 9
[19:26:47.496] <TB0> INFO: level: 15
[19:26:47.496] <TB0> INFO: triggerdelay: 0
[19:26:47.496] <TB0> QUIET: Instanciating API for pxar v2.7.6+61~g7f4a123
[19:26:47.496] <TB0> INFO: Log level: INFO
[19:26:47.504] <TB0> INFO: Found DTB DTB_WRQ4OZ
[19:26:47.515] <TB0> QUIET: Connection to board DTB_WRQ4OZ opened.
[19:26:47.517] <TB0> INFO: DTB startup information
--- DTB info------------------------------------------
Board id: 71
HW version: DTB1.2
FW version: 4.6
SW version: 4.7
Options:
USB id: DTB_WRQ4OZ
MAC address: 40D855118047
Hostname: pixelDTB071
Comment:
------------------------------------------------------
[19:26:47.519] <TB0> INFO: RPC call hashes of host and DTB match: 486171790
[19:26:49.009] <TB0> INFO: DUT info:
[19:26:49.009] <TB0> INFO: The DUT currently contains the following objects:
[19:26:49.009] <TB0> INFO: 4 TBM Cores tbm10c (4 ON)
[19:26:49.009] <TB0> INFO: TBM Core alpha (0): 7 registers set
[19:26:49.009] <TB0> INFO: TBM Core beta (1): 7 registers set
[19:26:49.009] <TB0> INFO: TBM Core alpha (2): 7 registers set
[19:26:49.009] <TB0> INFO: TBM Core beta (3): 7 registers set
[19:26:49.009] <TB0> INFO: 16 ROCs proc600 (16 ON) with 4160 pixelConfigs
[19:26:49.009] <TB0> INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[19:26:49.009] <TB0> INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[19:26:49.009] <TB0> INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[19:26:49.009] <TB0> INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[19:26:49.009] <TB0> INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[19:26:49.009] <TB0> INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[19:26:49.009] <TB0> INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[19:26:49.009] <TB0> INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[19:26:49.009] <TB0> INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[19:26:49.009] <TB0> INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[19:26:49.009] <TB0> INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[19:26:49.009] <TB0> INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[19:26:49.009] <TB0> INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[19:26:49.009] <TB0> INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[19:26:49.009] <TB0> INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[19:26:49.009] <TB0> INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[19:26:49.410] <TB0> INFO: enter 'restricted' command line mode
[19:26:49.410] <TB0> INFO: enter test to run
[19:26:49.410] <TB0> INFO: test: pretest no parameter change
[19:26:49.410] <TB0> INFO: running: pretest
[19:26:49.415] <TB0> INFO: ######################################################################
[19:26:49.416] <TB0> INFO: PixTestPretest::doTest()
[19:26:49.416] <TB0> INFO: ######################################################################
[19:26:49.417] <TB0> INFO: ----------------------------------------------------------------------
[19:26:49.417] <TB0> INFO: PixTestPretest::programROC()
[19:26:49.417] <TB0> INFO: ----------------------------------------------------------------------
[19:27:07.430] <TB0> INFO: PixTestPretest::programROC() done: ROCs are all programmable
[19:27:07.430] <TB0> INFO: IA differences per ROC: 18.5 18.5 19.3 18.5 21.7 19.3 18.5 20.9 22.5 19.3 20.9 19.3 19.3 17.7 20.9 20.1
[19:27:07.486] <TB0> INFO: ----------------------------------------------------------------------
[19:27:07.486] <TB0> INFO: PixTestPretest::setVana() target Ia = 24 mA/ROC
[19:27:07.486] <TB0> INFO: ----------------------------------------------------------------------
[19:27:15.475] <TB0> INFO: PixTestPretest::setVana() done, Module Ia 390.7 mA = 24.4187 mA/ROC
[19:27:15.475] <TB0> INFO: i(loss) [mA/ROC]: 20.1 20.9 20.1 20.1 20.1 20.1 20.1 20.1 20.1 19.3 20.1 20.1 20.1 20.1 20.1 20.1
[19:27:15.503] <TB0> INFO: ----------------------------------------------------------------------
[19:27:15.503] <TB0> INFO: PixTestPretest::findTiming()
[19:27:15.503] <TB0> INFO: ----------------------------------------------------------------------
[19:27:15.504] <TB0> INFO: PixTestCmd::init()

[19:27:47.881] <TB0> INFO: TBM phases: 160MHz: 0, 400MHz: 2, TBM delays: ROC(0/1):5, header/trailer: 1, token: 1
[19:27:47.881] <TB0> INFO: (success/tries = 100/100), width = 3
[19:27:49.385] <TB0> INFO: ----------------------------------------------------------------------
[19:27:49.385] <TB0> INFO: PixTestPretest::findWorkingPixel()
[19:27:49.385] <TB0> INFO: ----------------------------------------------------------------------
[19:27:49.481] <TB0> INFO: Expecting 231680 events.
[19:27:59.457] <TB0> INFO: 231680 events read in total (9385ms).
[19:27:59.469] <TB0> INFO: Test took 10078ms.
[19:27:59.721] <TB0> INFO: Found working pixel in all ROCs: col/row = 12/22
[19:27:59.759] <TB0> INFO: ----------------------------------------------------------------------
[19:27:59.759] <TB0> INFO: PixTestPretest::setVthrCompCalDel()
[19:27:59.759] <TB0> INFO: ----------------------------------------------------------------------
[19:27:59.855] <TB0> INFO: Expecting 231680 events.
[19:28:09.763] <TB0> INFO: 231680 events read in total (9316ms).
[19:28:09.783] <TB0> INFO: Test took 10018ms.
[19:28:10.053] <TB0> INFO: PixTestPretest::setVthrCompCalDel() done
[19:28:10.053] <TB0> INFO: CalDel: 105 103 97 95 106 113 85 93 112 95 115 88 103 113 116 94
[19:28:10.053] <TB0> INFO: VthrComp: 52 53 51 51 56 51 51 52 51 51 51 51 51 51 55 51
[19:28:10.056] <TB0> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1071_FullQualification_2016-11-03_14h08m_1478178483//001_Fulltest_m20//dacParameters_C0.dat
[19:28:10.056] <TB0> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1071_FullQualification_2016-11-03_14h08m_1478178483//001_Fulltest_m20//dacParameters_C1.dat
[19:28:10.056] <TB0> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1071_FullQualification_2016-11-03_14h08m_1478178483//001_Fulltest_m20//dacParameters_C2.dat
[19:28:10.056] <TB0> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1071_FullQualification_2016-11-03_14h08m_1478178483//001_Fulltest_m20//dacParameters_C3.dat
[19:28:10.057] <TB0> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1071_FullQualification_2016-11-03_14h08m_1478178483//001_Fulltest_m20//dacParameters_C4.dat
[19:28:10.057] <TB0> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1071_FullQualification_2016-11-03_14h08m_1478178483//001_Fulltest_m20//dacParameters_C5.dat
[19:28:10.057] <TB0> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1071_FullQualification_2016-11-03_14h08m_1478178483//001_Fulltest_m20//dacParameters_C6.dat
[19:28:10.057] <TB0> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1071_FullQualification_2016-11-03_14h08m_1478178483//001_Fulltest_m20//dacParameters_C7.dat
[19:28:10.057] <TB0> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1071_FullQualification_2016-11-03_14h08m_1478178483//001_Fulltest_m20//dacParameters_C8.dat
[19:28:10.057] <TB0> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1071_FullQualification_2016-11-03_14h08m_1478178483//001_Fulltest_m20//dacParameters_C9.dat
[19:28:10.057] <TB0> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1071_FullQualification_2016-11-03_14h08m_1478178483//001_Fulltest_m20//dacParameters_C10.dat
[19:28:10.058] <TB0> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1071_FullQualification_2016-11-03_14h08m_1478178483//001_Fulltest_m20//dacParameters_C11.dat
[19:28:10.058] <TB0> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1071_FullQualification_2016-11-03_14h08m_1478178483//001_Fulltest_m20//dacParameters_C12.dat
[19:28:10.058] <TB0> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1071_FullQualification_2016-11-03_14h08m_1478178483//001_Fulltest_m20//dacParameters_C13.dat
[19:28:10.058] <TB0> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1071_FullQualification_2016-11-03_14h08m_1478178483//001_Fulltest_m20//dacParameters_C14.dat
[19:28:10.058] <TB0> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1071_FullQualification_2016-11-03_14h08m_1478178483//001_Fulltest_m20//dacParameters_C15.dat
[19:28:10.058] <TB0> INFO: write tbm parameters into /home/l_tester/david/FullQualification/data/M1071_FullQualification_2016-11-03_14h08m_1478178483//001_Fulltest_m20//tbmParameters_C0a.dat
[19:28:10.058] <TB0> INFO: write tbm parameters into /home/l_tester/david/FullQualification/data/M1071_FullQualification_2016-11-03_14h08m_1478178483//001_Fulltest_m20//tbmParameters_C0b.dat
[19:28:10.058] <TB0> INFO: write tbm parameters into /home/l_tester/david/FullQualification/data/M1071_FullQualification_2016-11-03_14h08m_1478178483//001_Fulltest_m20//tbmParameters_C1a.dat
[19:28:10.058] <TB0> INFO: write tbm parameters into /home/l_tester/david/FullQualification/data/M1071_FullQualification_2016-11-03_14h08m_1478178483//001_Fulltest_m20//tbmParameters_C1b.dat
[19:28:10.059] <TB0> INFO: PixTestPretest::doTest() done, duration: 80 seconds
[19:28:10.116] <TB0> INFO: enter test to run
[19:28:10.116] <TB0> INFO: test: FullTest no parameter change
[19:28:10.116] <TB0> INFO: running: fulltest
[19:28:10.116] <TB0> INFO: ######################################################################
[19:28:10.116] <TB0> INFO: PixTestFullTest::doTest()
[19:28:10.116] <TB0> INFO: ######################################################################
[19:28:10.117] <TB0> INFO: ######################################################################
[19:28:10.117] <TB0> INFO: PixTestAlive::doTest()
[19:28:10.117] <TB0> INFO: ######################################################################
[19:28:10.119] <TB0> INFO: ----------------------------------------------------------------------
[19:28:10.119] <TB0> INFO: PixTestAlive::aliveTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[19:28:10.119] <TB0> INFO: ----------------------------------------------------------------------
[19:28:10.401] <TB0> INFO: Expecting 41600 events.
[19:28:13.947] <TB0> INFO: 41600 events read in total (2954ms).
[19:28:13.947] <TB0> INFO: Test took 3827ms.
[19:28:14.179] <TB0> INFO: PixTestAlive::aliveTest() done
[19:28:14.179] <TB0> INFO: number of dead pixels (per ROC): 0 0 0 0 0 2 0 0 0 2 0 0 0 0 0 0
[19:28:14.181] <TB0> INFO: ----------------------------------------------------------------------
[19:28:14.181] <TB0> INFO: PixTestAlive::maskTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[19:28:14.181] <TB0> INFO: ----------------------------------------------------------------------
[19:28:14.468] <TB0> INFO: Expecting 41600 events.
[19:28:17.414] <TB0> INFO: 41600 events read in total (2354ms).
[19:28:17.416] <TB0> INFO: Test took 3233ms.
[19:28:17.416] <TB0> INFO: mask vs. old pixelAlive PixelAlive_C0_V0 .. PixelAlive_C15_V0
[19:28:17.653] <TB0> INFO: PixTestAlive::maskTest() done
[19:28:17.653] <TB0> INFO: number of mask-defect pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[19:28:17.654] <TB0> INFO: ----------------------------------------------------------------------
[19:28:17.654] <TB0> INFO: PixTestAlive::addressDecodingTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[19:28:17.654] <TB0> INFO: ----------------------------------------------------------------------
[19:28:17.894] <TB0> INFO: Expecting 41600 events.
[19:28:21.510] <TB0> INFO: 41600 events read in total (3024ms).
[19:28:21.510] <TB0> INFO: Test took 3854ms.
[19:28:21.745] <TB0> INFO: PixTestAlive::addressDecodingTest() done
[19:28:21.745] <TB0> INFO: number of address-decoding pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[19:28:21.745] <TB0> INFO: PixTestAlive::doTest() done, duration: 11 seconds
[19:28:21.745] <TB0> INFO: Decoding statistics:
[19:28:21.745] <TB0> INFO: General information:
[19:28:21.745] <TB0> INFO: 16bit words read: 0
[19:28:21.745] <TB0> INFO: valid events total: 0
[19:28:21.745] <TB0> INFO: empty events: 0
[19:28:21.745] <TB0> INFO: valid events with pixels: 0
[19:28:21.745] <TB0> INFO: valid pixel hits: 0
[19:28:21.745] <TB0> INFO: Event errors: 0
[19:28:21.745] <TB0> INFO: start marker: 0
[19:28:21.745] <TB0> INFO: stop marker: 0
[19:28:21.745] <TB0> INFO: overflow: 0
[19:28:21.745] <TB0> INFO: invalid 5bit words: 0
[19:28:21.745] <TB0> INFO: invalid XOR eye diagram: 0
[19:28:21.745] <TB0> INFO: frame (failed synchr.): 0
[19:28:21.745] <TB0> INFO: idle data (no TBM trl): 0
[19:28:21.745] <TB0> INFO: no data (only TBM hdr): 0
[19:28:21.745] <TB0> INFO: TBM errors: 0
[19:28:21.746] <TB0> INFO: flawed TBM headers: 0
[19:28:21.746] <TB0> INFO: flawed TBM trailers: 0
[19:28:21.746] <TB0> INFO: event ID mismatches: 0
[19:28:21.746] <TB0> INFO: ROC errors: 0
[19:28:21.746] <TB0> INFO: missing ROC header(s): 0
[19:28:21.746] <TB0> INFO: misplaced readback start: 0
[19:28:21.746] <TB0> INFO: Pixel decoding errors: 0
[19:28:21.746] <TB0> INFO: pixel data incomplete: 0
[19:28:21.746] <TB0> INFO: pixel address: 0
[19:28:21.746] <TB0> INFO: pulse height fill bit: 0
[19:28:21.746] <TB0> INFO: buffer corruption: 0
[19:28:21.753] <TB0> INFO: readReadbackCal: /home/l_tester/david/FullQualification/data/M1071_FullQualification_2016-11-03_14h08m_1478178483//001_Fulltest_m20//readbackCal_C0.dat .. /home/l_tester/david/FullQualification/data/M1071_FullQualification_2016-11-03_14h08m_1478178483//001_Fulltest_m20//readbackCal_C15.dat
[19:28:21.753] <TB0> INFO: readGainPedestalParameters /home/l_tester/david/FullQualification/data/M1071_FullQualification_2016-11-03_14h08m_1478178483//001_Fulltest_m20//phCalibrationFitErr_C0.dat .. /home/l_tester/david/FullQualification/data/M1071_FullQualification_2016-11-03_14h08m_1478178483//001_Fulltest_m20//phCalibrationFitErr_C15.dat

[19:28:21.753] <TB0> INFO: ######################################################################
[19:28:21.753] <TB0> INFO: PixTestReadback::doTest()
[19:28:21.753] <TB0> INFO: ######################################################################
[19:28:21.753] <TB0> INFO: ----------------------------------------------------------------------
[19:28:21.753] <TB0> INFO: PixTestReadback::CalibrateVd()
[19:28:21.753] <TB0> INFO: ----------------------------------------------------------------------
[19:28:31.720] <TB0> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1071_FullQualification_2016-11-03_14h08m_1478178483//001_Fulltest_m20//readbackCal_C0.dat
[19:28:31.720] <TB0> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1071_FullQualification_2016-11-03_14h08m_1478178483//001_Fulltest_m20//readbackCal_C1.dat
[19:28:31.720] <TB0> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1071_FullQualification_2016-11-03_14h08m_1478178483//001_Fulltest_m20//readbackCal_C2.dat
[19:28:31.721] <TB0> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1071_FullQualification_2016-11-03_14h08m_1478178483//001_Fulltest_m20//readbackCal_C3.dat
[19:28:31.721] <TB0> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1071_FullQualification_2016-11-03_14h08m_1478178483//001_Fulltest_m20//readbackCal_C4.dat
[19:28:31.721] <TB0> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1071_FullQualification_2016-11-03_14h08m_1478178483//001_Fulltest_m20//readbackCal_C5.dat
[19:28:31.721] <TB0> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1071_FullQualification_2016-11-03_14h08m_1478178483//001_Fulltest_m20//readbackCal_C6.dat
[19:28:31.721] <TB0> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1071_FullQualification_2016-11-03_14h08m_1478178483//001_Fulltest_m20//readbackCal_C7.dat
[19:28:31.721] <TB0> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1071_FullQualification_2016-11-03_14h08m_1478178483//001_Fulltest_m20//readbackCal_C8.dat
[19:28:31.721] <TB0> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1071_FullQualification_2016-11-03_14h08m_1478178483//001_Fulltest_m20//readbackCal_C9.dat
[19:28:31.721] <TB0> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1071_FullQualification_2016-11-03_14h08m_1478178483//001_Fulltest_m20//readbackCal_C10.dat
[19:28:31.721] <TB0> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1071_FullQualification_2016-11-03_14h08m_1478178483//001_Fulltest_m20//readbackCal_C11.dat
[19:28:31.721] <TB0> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1071_FullQualification_2016-11-03_14h08m_1478178483//001_Fulltest_m20//readbackCal_C12.dat
[19:28:31.721] <TB0> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1071_FullQualification_2016-11-03_14h08m_1478178483//001_Fulltest_m20//readbackCal_C13.dat
[19:28:31.722] <TB0> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1071_FullQualification_2016-11-03_14h08m_1478178483//001_Fulltest_m20//readbackCal_C14.dat
[19:28:31.722] <TB0> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1071_FullQualification_2016-11-03_14h08m_1478178483//001_Fulltest_m20//readbackCal_C15.dat
[19:28:31.751] <TB0> INFO: PixTestPattern:: pg_setup set to default.
[19:28:31.751] <TB0> INFO: ----------------------------------------------------------------------
[19:28:31.751] <TB0> INFO: PixTestReadback::CalibrateVa()
[19:28:31.751] <TB0> INFO: ----------------------------------------------------------------------
[19:28:41.711] <TB0> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1071_FullQualification_2016-11-03_14h08m_1478178483//001_Fulltest_m20//readbackCal_C0.dat
[19:28:41.712] <TB0> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1071_FullQualification_2016-11-03_14h08m_1478178483//001_Fulltest_m20//readbackCal_C1.dat
[19:28:41.712] <TB0> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1071_FullQualification_2016-11-03_14h08m_1478178483//001_Fulltest_m20//readbackCal_C2.dat
[19:28:41.713] <TB0> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1071_FullQualification_2016-11-03_14h08m_1478178483//001_Fulltest_m20//readbackCal_C3.dat
[19:28:41.713] <TB0> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1071_FullQualification_2016-11-03_14h08m_1478178483//001_Fulltest_m20//readbackCal_C4.dat
[19:28:41.713] <TB0> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1071_FullQualification_2016-11-03_14h08m_1478178483//001_Fulltest_m20//readbackCal_C5.dat
[19:28:41.713] <TB0> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1071_FullQualification_2016-11-03_14h08m_1478178483//001_Fulltest_m20//readbackCal_C6.dat
[19:28:41.713] <TB0> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1071_FullQualification_2016-11-03_14h08m_1478178483//001_Fulltest_m20//readbackCal_C7.dat
[19:28:41.713] <TB0> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1071_FullQualification_2016-11-03_14h08m_1478178483//001_Fulltest_m20//readbackCal_C8.dat
[19:28:41.713] <TB0> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1071_FullQualification_2016-11-03_14h08m_1478178483//001_Fulltest_m20//readbackCal_C9.dat
[19:28:41.713] <TB0> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1071_FullQualification_2016-11-03_14h08m_1478178483//001_Fulltest_m20//readbackCal_C10.dat
[19:28:41.713] <TB0> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1071_FullQualification_2016-11-03_14h08m_1478178483//001_Fulltest_m20//readbackCal_C11.dat
[19:28:41.713] <TB0> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1071_FullQualification_2016-11-03_14h08m_1478178483//001_Fulltest_m20//readbackCal_C12.dat
[19:28:41.713] <TB0> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1071_FullQualification_2016-11-03_14h08m_1478178483//001_Fulltest_m20//readbackCal_C13.dat
[19:28:41.713] <TB0> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1071_FullQualification_2016-11-03_14h08m_1478178483//001_Fulltest_m20//readbackCal_C14.dat
[19:28:41.714] <TB0> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1071_FullQualification_2016-11-03_14h08m_1478178483//001_Fulltest_m20//readbackCal_C15.dat
[19:28:41.749] <TB0> INFO: PixTestPattern:: pg_setup set to default.
[19:28:41.749] <TB0> INFO: ----------------------------------------------------------------------
[19:28:41.749] <TB0> INFO: PixTestReadback::readbackVbg()
[19:28:41.749] <TB0> INFO: ----------------------------------------------------------------------
[19:28:49.426] <TB0> INFO: PixTestPattern:: pg_setup set to default.
[19:28:49.432] <TB0> INFO: ----------------------------------------------------------------------
[19:28:49.432] <TB0> INFO: PixTestReadback::getCalibratedVbg()
[19:28:49.432] <TB0> INFO: ----------------------------------------------------------------------
[19:28:49.432] <TB0> INFO: Vbg will be calibrated using Vd calibration
[19:28:49.432] <TB0> INFO: /*/*/*/*::: ROC 0: uncalibrated Vbg = 161calibrated Vbg = 1.16628 :::*/*/*/*/
[19:28:49.432] <TB0> INFO: /*/*/*/*::: ROC 1: uncalibrated Vbg = 148calibrated Vbg = 1.17011 :::*/*/*/*/
[19:28:49.432] <TB0> INFO: /*/*/*/*::: ROC 2: uncalibrated Vbg = 151.1calibrated Vbg = 1.16355 :::*/*/*/*/
[19:28:49.432] <TB0> INFO: /*/*/*/*::: ROC 3: uncalibrated Vbg = 158.4calibrated Vbg = 1.16184 :::*/*/*/*/
[19:28:49.432] <TB0> INFO: /*/*/*/*::: ROC 4: uncalibrated Vbg = 159.1calibrated Vbg = 1.171 :::*/*/*/*/
[19:28:49.432] <TB0> INFO: /*/*/*/*::: ROC 5: uncalibrated Vbg = 148.1calibrated Vbg = 1.17101 :::*/*/*/*/
[19:28:49.432] <TB0> INFO: /*/*/*/*::: ROC 6: uncalibrated Vbg = 150.9calibrated Vbg = 1.16654 :::*/*/*/*/
[19:28:49.432] <TB0> INFO: /*/*/*/*::: ROC 7: uncalibrated Vbg = 150.1calibrated Vbg = 1.17329 :::*/*/*/*/
[19:28:49.432] <TB0> INFO: /*/*/*/*::: ROC 8: uncalibrated Vbg = 148.4calibrated Vbg = 1.16097 :::*/*/*/*/
[19:28:49.432] <TB0> INFO: /*/*/*/*::: ROC 9: uncalibrated Vbg = 157.1calibrated Vbg = 1.16173 :::*/*/*/*/
[19:28:49.432] <TB0> INFO: /*/*/*/*::: ROC 10: uncalibrated Vbg = 142.3calibrated Vbg = 1.16547 :::*/*/*/*/
[19:28:49.432] <TB0> INFO: /*/*/*/*::: ROC 11: uncalibrated Vbg = 160.4calibrated Vbg = 1.15386 :::*/*/*/*/
[19:28:49.432] <TB0> INFO: /*/*/*/*::: ROC 12: uncalibrated Vbg = 159.1calibrated Vbg = 1.16232 :::*/*/*/*/
[19:28:49.432] <TB0> INFO: /*/*/*/*::: ROC 13: uncalibrated Vbg = 154.7calibrated Vbg = 1.17197 :::*/*/*/*/
[19:28:49.432] <TB0> INFO: /*/*/*/*::: ROC 14: uncalibrated Vbg = 158.9calibrated Vbg = 1.16391 :::*/*/*/*/
[19:28:49.432] <TB0> INFO: /*/*/*/*::: ROC 15: uncalibrated Vbg = 155.8calibrated Vbg = 1.16614 :::*/*/*/*/
[19:28:49.434] <TB0> INFO: ----------------------------------------------------------------------
[19:28:49.434] <TB0> INFO: PixTestReadback::CalibrateIa()
[19:28:49.434] <TB0> INFO: ----------------------------------------------------------------------
[19:31:30.337] <TB0> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1071_FullQualification_2016-11-03_14h08m_1478178483//001_Fulltest_m20//readbackCal_C0.dat
[19:31:30.337] <TB0> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1071_FullQualification_2016-11-03_14h08m_1478178483//001_Fulltest_m20//readbackCal_C1.dat
[19:31:30.338] <TB0> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1071_FullQualification_2016-11-03_14h08m_1478178483//001_Fulltest_m20//readbackCal_C2.dat
[19:31:30.338] <TB0> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1071_FullQualification_2016-11-03_14h08m_1478178483//001_Fulltest_m20//readbackCal_C3.dat
[19:31:30.338] <TB0> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1071_FullQualification_2016-11-03_14h08m_1478178483//001_Fulltest_m20//readbackCal_C4.dat
[19:31:30.338] <TB0> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1071_FullQualification_2016-11-03_14h08m_1478178483//001_Fulltest_m20//readbackCal_C5.dat
[19:31:30.338] <TB0> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1071_FullQualification_2016-11-03_14h08m_1478178483//001_Fulltest_m20//readbackCal_C6.dat
[19:31:30.338] <TB0> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1071_FullQualification_2016-11-03_14h08m_1478178483//001_Fulltest_m20//readbackCal_C7.dat
[19:31:30.338] <TB0> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1071_FullQualification_2016-11-03_14h08m_1478178483//001_Fulltest_m20//readbackCal_C8.dat
[19:31:30.338] <TB0> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1071_FullQualification_2016-11-03_14h08m_1478178483//001_Fulltest_m20//readbackCal_C9.dat
[19:31:30.338] <TB0> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1071_FullQualification_2016-11-03_14h08m_1478178483//001_Fulltest_m20//readbackCal_C10.dat
[19:31:30.338] <TB0> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1071_FullQualification_2016-11-03_14h08m_1478178483//001_Fulltest_m20//readbackCal_C11.dat
[19:31:30.338] <TB0> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1071_FullQualification_2016-11-03_14h08m_1478178483//001_Fulltest_m20//readbackCal_C12.dat
[19:31:30.338] <TB0> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1071_FullQualification_2016-11-03_14h08m_1478178483//001_Fulltest_m20//readbackCal_C13.dat
[19:31:30.339] <TB0> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1071_FullQualification_2016-11-03_14h08m_1478178483//001_Fulltest_m20//readbackCal_C14.dat
[19:31:30.339] <TB0> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1071_FullQualification_2016-11-03_14h08m_1478178483//001_Fulltest_m20//readbackCal_C15.dat
[19:31:30.369] <TB0> INFO: PixTestPattern:: pg_setup set to default.
[19:31:30.370] <TB0> INFO: PixTestReadback::doTest() done
[19:31:30.370] <TB0> INFO: Decoding statistics:
[19:31:30.370] <TB0> INFO: General information:
[19:31:30.370] <TB0> INFO: 16bit words read: 1536
[19:31:30.370] <TB0> INFO: valid events total: 256
[19:31:30.370] <TB0> INFO: empty events: 256
[19:31:30.370] <TB0> INFO: valid events with pixels: 0
[19:31:30.370] <TB0> INFO: valid pixel hits: 0
[19:31:30.370] <TB0> INFO: Event errors: 0
[19:31:30.370] <TB0> INFO: start marker: 0
[19:31:30.370] <TB0> INFO: stop marker: 0
[19:31:30.370] <TB0> INFO: overflow: 0
[19:31:30.370] <TB0> INFO: invalid 5bit words: 0
[19:31:30.370] <TB0> INFO: invalid XOR eye diagram: 0
[19:31:30.370] <TB0> INFO: frame (failed synchr.): 0
[19:31:30.370] <TB0> INFO: idle data (no TBM trl): 0
[19:31:30.371] <TB0> INFO: no data (only TBM hdr): 0
[19:31:30.371] <TB0> INFO: TBM errors: 0
[19:31:30.371] <TB0> INFO: flawed TBM headers: 0
[19:31:30.371] <TB0> INFO: flawed TBM trailers: 0
[19:31:30.371] <TB0> INFO: event ID mismatches: 0
[19:31:30.371] <TB0> INFO: ROC errors: 0
[19:31:30.371] <TB0> INFO: missing ROC header(s): 0
[19:31:30.371] <TB0> INFO: misplaced readback start: 0
[19:31:30.371] <TB0> INFO: Pixel decoding errors: 0
[19:31:30.371] <TB0> INFO: pixel data incomplete: 0
[19:31:30.371] <TB0> INFO: pixel address: 0
[19:31:30.371] <TB0> INFO: pulse height fill bit: 0
[19:31:30.371] <TB0> INFO: buffer corruption: 0
[19:31:30.431] <TB0> INFO: ######################################################################
[19:31:30.431] <TB0> INFO: PixTestBBMap::doTest() Ntrig = 5, VcalS = 250 (high range)
[19:31:30.431] <TB0> INFO: ######################################################################
[19:31:30.433] <TB0> INFO: ---> dac: VthrComp name: calSMap ntrig: 5 dacrange: 0 .. 149 (-1/-1) hits flags = 514 (plus default)
[19:31:30.458] <TB0> INFO: dacScan split into 1 runs with ntrig = 5
[19:31:30.459] <TB0> INFO: run 1 of 1
[19:31:30.698] <TB0> INFO: Expecting 3120000 events.
[19:32:02.737] <TB0> INFO: 665580 events read in total (31447ms).











[19:32:33.568] <TB0> INFO: 1324855 events read in total (62278ms).











[19:33:04.606] <TB0> INFO: 1981025 events read in total (93316ms).











[19:33:35.570] <TB0> INFO: 2636530 events read in total (124280ms).











[19:33:58.706] <TB0> INFO: 3120000 events read in total (147416ms).
[19:33:58.780] <TB0> INFO: Test took 148322ms.
[19:34:20.471] <TB0> INFO: PixTestBBMap::doTest() done with 1 decoding errors: , duration: 170 seconds
[19:34:20.471] <TB0> INFO: number of dead bumps (per ROC): 2 2 2 4 0 2 0 0 1 1 5 4 1 3 1 1
[19:34:20.471] <TB0> INFO: separation cut (per ROC): 108 106 105 101 109 103 106 108 104 106 100 107 109 105 107 102
[19:34:20.471] <TB0> INFO: Decoding statistics:
[19:34:20.471] <TB0> INFO: General information:
[19:34:20.471] <TB0> INFO: 16bit words read: 0
[19:34:20.471] <TB0> INFO: valid events total: 0
[19:34:20.471] <TB0> INFO: empty events: 0
[19:34:20.471] <TB0> INFO: valid events with pixels: 0
[19:34:20.471] <TB0> INFO: valid pixel hits: 0
[19:34:20.471] <TB0> INFO: Event errors: 0
[19:34:20.471] <TB0> INFO: start marker: 0
[19:34:20.471] <TB0> INFO: stop marker: 0
[19:34:20.471] <TB0> INFO: overflow: 0
[19:34:20.471] <TB0> INFO: invalid 5bit words: 0
[19:34:20.471] <TB0> INFO: invalid XOR eye diagram: 0
[19:34:20.471] <TB0> INFO: frame (failed synchr.): 0
[19:34:20.471] <TB0> INFO: idle data (no TBM trl): 0
[19:34:20.471] <TB0> INFO: no data (only TBM hdr): 0
[19:34:20.471] <TB0> INFO: TBM errors: 0
[19:34:20.471] <TB0> INFO: flawed TBM headers: 0
[19:34:20.471] <TB0> INFO: flawed TBM trailers: 0
[19:34:20.471] <TB0> INFO: event ID mismatches: 0
[19:34:20.471] <TB0> INFO: ROC errors: 0
[19:34:20.471] <TB0> INFO: missing ROC header(s): 0
[19:34:20.471] <TB0> INFO: misplaced readback start: 0
[19:34:20.471] <TB0> INFO: Pixel decoding errors: 0
[19:34:20.471] <TB0> INFO: pixel data incomplete: 0
[19:34:20.471] <TB0> INFO: pixel address: 0
[19:34:20.471] <TB0> INFO: pulse height fill bit: 0
[19:34:20.471] <TB0> INFO: buffer corruption: 0
[19:34:20.521] <TB0> INFO: ######################################################################
[19:34:20.521] <TB0> INFO: PixTestScurves::fullTest() ntrig = 50, dacs/step = -1, ntrig/step = -1
[19:34:20.521] <TB0> INFO: ######################################################################
[19:34:20.521] <TB0> INFO: ----------------------------------------------------------------------
[19:34:20.521] <TB0> INFO: PixTestScurves::scurves(Vcal), ntrig = 50, dacs/step = -1, ntrig/step = -1
[19:34:20.521] <TB0> INFO: ----------------------------------------------------------------------
[19:34:20.522] <TB0> INFO: ---> dac: Vcal name: scurveVcal ntrig: 50 dacrange: 25 .. 200 (-1/-1) hits flags = 528 (plus default)
[19:34:20.537] <TB0> INFO: dacScan split into 1 runs with ntrig = 50
[19:34:20.537] <TB0> INFO: run 1 of 1
[19:34:20.830] <TB0> INFO: Expecting 36608000 events.
[19:34:45.415] <TB0> INFO: 684300 events read in total (23994ms).
[19:35:09.136] <TB0> INFO: 1354850 events read in total (47715ms).
[19:35:32.618] <TB0> INFO: 2028350 events read in total (71197ms).
[19:35:56.018] <TB0> INFO: 2697150 events read in total (94597ms).
[19:36:20.013] <TB0> INFO: 3365400 events read in total (118592ms).
[19:36:43.800] <TB0> INFO: 4033650 events read in total (142379ms).
[19:37:07.538] <TB0> INFO: 4702400 events read in total (166117ms).
[19:37:31.100] <TB0> INFO: 5370800 events read in total (189679ms).
[19:37:54.599] <TB0> INFO: 6036950 events read in total (213178ms).
[19:38:18.236] <TB0> INFO: 6704850 events read in total (236815ms).
[19:38:41.970] <TB0> INFO: 7373150 events read in total (260549ms).
[19:39:05.722] <TB0> INFO: 8043000 events read in total (284301ms).
[19:39:29.135] <TB0> INFO: 8715600 events read in total (307714ms).
[19:39:52.883] <TB0> INFO: 9387050 events read in total (331462ms).
[19:40:16.248] <TB0> INFO: 10056300 events read in total (354827ms).
[19:40:39.966] <TB0> INFO: 10724100 events read in total (378545ms).
[19:41:04.083] <TB0> INFO: 11390250 events read in total (402662ms).
[19:41:27.722] <TB0> INFO: 12057100 events read in total (426301ms).
[19:41:51.536] <TB0> INFO: 12724600 events read in total (450115ms).
[19:42:15.347] <TB0> INFO: 13398250 events read in total (473926ms).
[19:42:39.614] <TB0> INFO: 14066150 events read in total (498193ms).
[19:43:03.437] <TB0> INFO: 14730850 events read in total (522016ms).
[19:43:27.265] <TB0> INFO: 15394950 events read in total (545844ms).
[19:43:51.073] <TB0> INFO: 16059450 events read in total (569652ms).
[19:44:14.541] <TB0> INFO: 16726850 events read in total (593120ms).
[19:44:37.896] <TB0> INFO: 17395700 events read in total (616475ms).
[19:45:01.771] <TB0> INFO: 18063750 events read in total (640350ms).
[19:45:25.349] <TB0> INFO: 18729550 events read in total (663928ms).
[19:45:48.758] <TB0> INFO: 19392350 events read in total (687337ms).
[19:46:12.440] <TB0> INFO: 20055050 events read in total (711019ms).
[19:46:35.925] <TB0> INFO: 20719800 events read in total (734504ms).
[19:46:59.779] <TB0> INFO: 21384300 events read in total (758358ms).
[19:47:23.861] <TB0> INFO: 22050350 events read in total (782440ms).
[19:47:47.434] <TB0> INFO: 22712650 events read in total (806013ms).
[19:48:10.785] <TB0> INFO: 23372900 events read in total (829364ms).
[19:48:34.136] <TB0> INFO: 24034450 events read in total (852715ms).
[19:48:58.100] <TB0> INFO: 24698600 events read in total (876679ms).
[19:49:21.637] <TB0> INFO: 25363450 events read in total (900216ms).
[19:49:45.031] <TB0> INFO: 26028900 events read in total (923610ms).
[19:50:08.407] <TB0> INFO: 26690800 events read in total (946986ms).
[19:50:32.011] <TB0> INFO: 27356300 events read in total (970590ms).
[19:50:55.603] <TB0> INFO: 28019750 events read in total (994182ms).
[19:51:19.032] <TB0> INFO: 28685800 events read in total (1017611ms).
[19:51:42.384] <TB0> INFO: 29349650 events read in total (1040963ms).
[19:52:06.123] <TB0> INFO: 30014850 events read in total (1064702ms).
[19:52:29.288] <TB0> INFO: 30680450 events read in total (1087867ms).
[19:52:53.136] <TB0> INFO: 31344550 events read in total (1111715ms).
[19:53:16.464] <TB0> INFO: 32010500 events read in total (1135043ms).
[19:53:39.835] <TB0> INFO: 32675400 events read in total (1158414ms).
[19:54:03.274] <TB0> INFO: 33341400 events read in total (1181853ms).
[19:54:26.737] <TB0> INFO: 34007600 events read in total (1205316ms).
[19:54:50.110] <TB0> INFO: 34675300 events read in total (1228689ms).
[19:55:13.551] <TB0> INFO: 35342600 events read in total (1252130ms).
[19:55:37.537] <TB0> INFO: 36014550 events read in total (1276116ms).
[19:55:58.333] <TB0> INFO: 36608000 events read in total (1296912ms).
[19:55:58.412] <TB0> INFO: Test took 1297875ms.
[19:55:58.754] <TB0> INFO: dumping ASCII scurve output file: SCurveData
[19:56:00.317] <TB0> INFO: dumping ASCII scurve output file: SCurveData
[19:56:01.783] <TB0> INFO: dumping ASCII scurve output file: SCurveData
[19:56:03.251] <TB0> INFO: dumping ASCII scurve output file: SCurveData
[19:56:04.673] <TB0> INFO: dumping ASCII scurve output file: SCurveData
[19:56:06.109] <TB0> INFO: dumping ASCII scurve output file: SCurveData
[19:56:07.570] <TB0> INFO: dumping ASCII scurve output file: SCurveData
[19:56:09.060] <TB0> INFO: dumping ASCII scurve output file: SCurveData
[19:56:10.535] <TB0> INFO: dumping ASCII scurve output file: SCurveData
[19:56:12.013] <TB0> INFO: dumping ASCII scurve output file: SCurveData
[19:56:13.537] <TB0> INFO: dumping ASCII scurve output file: SCurveData
[19:56:14.960] <TB0> INFO: dumping ASCII scurve output file: SCurveData
[19:56:16.442] <TB0> INFO: dumping ASCII scurve output file: SCurveData
[19:56:17.933] <TB0> INFO: dumping ASCII scurve output file: SCurveData
[19:56:19.543] <TB0> INFO: dumping ASCII scurve output file: SCurveData
[19:56:21.203] <TB0> INFO: dumping ASCII scurve output file: SCurveData
[19:56:22.714] <TB0> INFO: PixTestScurves::scurves() done
[19:56:22.714] <TB0> INFO: Vcal mean: 124.69 123.01 116.26 112.13 125.13 128.13 113.47 119.16 117.19 112.79 115.80 120.51 117.03 110.70 126.36 130.95
[19:56:22.714] <TB0> INFO: Vcal RMS: 6.46 7.34 5.70 5.05 8.69 7.69 5.41 6.12 6.18 5.54 5.87 6.35 5.87 4.95 7.32 6.61
[19:56:22.714] <TB0> INFO: PixTestScurves::fullTest() done, duration: 1322 seconds
[19:56:22.714] <TB0> INFO: Decoding statistics:
[19:56:22.714] <TB0> INFO: General information:
[19:56:22.714] <TB0> INFO: 16bit words read: 0
[19:56:22.714] <TB0> INFO: valid events total: 0
[19:56:22.714] <TB0> INFO: empty events: 0
[19:56:22.714] <TB0> INFO: valid events with pixels: 0
[19:56:22.714] <TB0> INFO: valid pixel hits: 0
[19:56:22.714] <TB0> INFO: Event errors: 0
[19:56:22.714] <TB0> INFO: start marker: 0
[19:56:22.714] <TB0> INFO: stop marker: 0
[19:56:22.714] <TB0> INFO: overflow: 0
[19:56:22.714] <TB0> INFO: invalid 5bit words: 0
[19:56:22.714] <TB0> INFO: invalid XOR eye diagram: 0
[19:56:22.714] <TB0> INFO: frame (failed synchr.): 0
[19:56:22.714] <TB0> INFO: idle data (no TBM trl): 0
[19:56:22.714] <TB0> INFO: no data (only TBM hdr): 0
[19:56:22.714] <TB0> INFO: TBM errors: 0
[19:56:22.714] <TB0> INFO: flawed TBM headers: 0
[19:56:22.714] <TB0> INFO: flawed TBM trailers: 0
[19:56:22.714] <TB0> INFO: event ID mismatches: 0
[19:56:22.714] <TB0> INFO: ROC errors: 0
[19:56:22.714] <TB0> INFO: missing ROC header(s): 0
[19:56:22.714] <TB0> INFO: misplaced readback start: 0
[19:56:22.714] <TB0> INFO: Pixel decoding errors: 0
[19:56:22.714] <TB0> INFO: pixel data incomplete: 0
[19:56:22.714] <TB0> INFO: pixel address: 0
[19:56:22.714] <TB0> INFO: pulse height fill bit: 0
[19:56:22.714] <TB0> INFO: buffer corruption: 0
[19:56:22.781] <TB0> INFO: ######################################################################
[19:56:22.781] <TB0> INFO: PixTestTrim::doTest()
[19:56:22.781] <TB0> INFO: ######################################################################
[19:56:22.782] <TB0> INFO: ----------------------------------------------------------------------
[19:56:22.782] <TB0> INFO: PixTestTrim::trimTest() ntrig = 8, vcal = 35
[19:56:22.782] <TB0> INFO: ----------------------------------------------------------------------
[19:56:22.823] <TB0> INFO: ---> VthrComp thr map (minimal VthrComp)
[19:56:22.824] <TB0> INFO: ---> dac: vthrcomp name: TrimThr0 ntrig: 8 dacrange: 10 .. 160 (-1/-1) hits flags = 528 (plus default)
[19:56:22.837] <TB0> INFO: dacScan split into 1 runs with ntrig = 8
[19:56:22.837] <TB0> INFO: run 1 of 1
[19:56:23.073] <TB0> INFO: Expecting 5025280 events.
[19:56:54.260] <TB0> INFO: 829968 events read in total (30585ms).
[19:57:25.793] <TB0> INFO: 1658504 events read in total (62118ms).
[19:57:57.076] <TB0> INFO: 2484664 events read in total (93402ms).
[19:58:28.296] <TB0> INFO: 3306968 events read in total (124621ms).
[19:58:59.072] <TB0> INFO: 4125128 events read in total (155398ms).
[19:59:30.223] <TB0> INFO: 4942176 events read in total (186548ms).
[19:59:33.681] <TB0> INFO: 5025280 events read in total (190006ms).
[19:59:33.734] <TB0> INFO: Test took 190898ms.
[19:59:49.222] <TB0> INFO: ROC 0 VthrComp = 128
[19:59:49.222] <TB0> INFO: ROC 1 VthrComp = 121
[19:59:49.222] <TB0> INFO: ROC 2 VthrComp = 118
[19:59:49.222] <TB0> INFO: ROC 3 VthrComp = 114
[19:59:49.223] <TB0> INFO: ROC 4 VthrComp = 125
[19:59:49.223] <TB0> INFO: ROC 5 VthrComp = 118
[19:59:49.223] <TB0> INFO: ROC 6 VthrComp = 121
[19:59:49.223] <TB0> INFO: ROC 7 VthrComp = 126
[19:59:49.223] <TB0> INFO: ROC 8 VthrComp = 116
[19:59:49.223] <TB0> INFO: ROC 9 VthrComp = 118
[19:59:49.223] <TB0> INFO: ROC 10 VthrComp = 110
[19:59:49.223] <TB0> INFO: ROC 11 VthrComp = 119
[19:59:49.223] <TB0> INFO: ROC 12 VthrComp = 124
[19:59:49.223] <TB0> INFO: ROC 13 VthrComp = 113
[19:59:49.224] <TB0> INFO: ROC 14 VthrComp = 126
[19:59:49.224] <TB0> INFO: ROC 15 VthrComp = 126
[19:59:49.465] <TB0> INFO: Expecting 41600 events.
[19:59:53.119] <TB0> INFO: 41600 events read in total (3062ms).
[19:59:53.120] <TB0> INFO: Test took 3895ms.
[19:59:53.132] <TB0> INFO: ---> Vcal thr map (pixel with maximum Vcal thr)
[19:59:53.132] <TB0> INFO: ---> dac: vcal name: TrimThr1 ntrig: 8 dacrange: 10 .. 160 (-1/-1) hits flags = 528 (plus default)
[19:59:53.146] <TB0> INFO: dacScan split into 1 runs with ntrig = 8
[19:59:53.146] <TB0> INFO: run 1 of 1
[19:59:53.425] <TB0> INFO: Expecting 5025280 events.
[20:00:20.648] <TB0> INFO: 590272 events read in total (26631ms).
[20:00:47.735] <TB0> INFO: 1179632 events read in total (53718ms).
[20:01:14.332] <TB0> INFO: 1770544 events read in total (80315ms).
[20:01:40.697] <TB0> INFO: 2360256 events read in total (106680ms).
[20:02:07.447] <TB0> INFO: 2948408 events read in total (133430ms).
[20:02:34.513] <TB0> INFO: 3533784 events read in total (160496ms).
[20:03:00.541] <TB0> INFO: 4118576 events read in total (186524ms).
[20:03:26.679] <TB0> INFO: 4702560 events read in total (212662ms).
[20:03:41.296] <TB0> INFO: 5025280 events read in total (227279ms).
[20:03:41.414] <TB0> INFO: Test took 228267ms.
[20:04:03.560] <TB0> INFO: roc 0 with ID = 0 has maximal Vcal 62.3669 for pixel 4/6 mean/min/max = 47.0217/31.3711/62.6722
[20:04:03.560] <TB0> INFO: roc 1 with ID = 1 has maximal Vcal 65.969 for pixel 9/24 mean/min/max = 48.4582/30.5396/66.3768
[20:04:03.561] <TB0> INFO: roc 2 with ID = 2 has maximal Vcal 61.1863 for pixel 0/0 mean/min/max = 46.2631/30.8526/61.6737
[20:04:03.561] <TB0> INFO: roc 3 with ID = 3 has maximal Vcal 62.1665 for pixel 16/4 mean/min/max = 46.9537/31.6671/62.2403
[20:04:03.562] <TB0> INFO: roc 4 with ID = 4 has maximal Vcal 67.1002 for pixel 4/0 mean/min/max = 47.8909/28.6714/67.1103
[20:04:03.562] <TB0> INFO: roc 5 with ID = 5 has maximal Vcal 65.6553 for pixel 49/0 mean/min/max = 48.339/30.9994/65.6785
[20:04:03.563] <TB0> INFO: roc 6 with ID = 6 has maximal Vcal 60.6907 for pixel 47/16 mean/min/max = 46.0143/31.0814/60.9473
[20:04:03.564] <TB0> INFO: roc 7 with ID = 7 has maximal Vcal 60.7237 for pixel 2/11 mean/min/max = 45.5484/30.1535/60.9433
[20:04:03.564] <TB0> INFO: roc 8 with ID = 8 has maximal Vcal 63.4305 for pixel 22/66 mean/min/max = 47.339/31.1804/63.4976
[20:04:03.565] <TB0> INFO: roc 9 with ID = 9 has maximal Vcal 60.2644 for pixel 36/58 mean/min/max = 45.977/31.4651/60.4889
[20:04:03.565] <TB0> INFO: roc 10 with ID = 10 has maximal Vcal 68.3553 for pixel 7/1 mean/min/max = 51.1137/33.7889/68.4386
[20:04:03.566] <TB0> INFO: roc 11 with ID = 11 has maximal Vcal 63.5256 for pixel 25/79 mean/min/max = 47.295/30.966/63.624
[20:04:03.566] <TB0> INFO: roc 12 with ID = 12 has maximal Vcal 60.8341 for pixel 24/44 mean/min/max = 46.4366/31.8946/60.9786
[20:04:03.566] <TB0> INFO: roc 13 with ID = 13 has maximal Vcal 61.0557 for pixel 4/11 mean/min/max = 46.8268/32.4351/61.2185
[20:04:03.567] <TB0> INFO: roc 14 with ID = 14 has maximal Vcal 62.6366 for pixel 13/68 mean/min/max = 46.1269/29.4726/62.7813
[20:04:03.567] <TB0> INFO: roc 15 with ID = 15 has maximal Vcal 62.7821 for pixel 31/0 mean/min/max = 46.426/29.9416/62.9104
[20:04:03.568] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[20:04:03.656] <TB0> INFO: Expecting 411648 events.
[20:04:13.457] <TB0> INFO: 411648 events read in total (9209ms).
[20:04:13.469] <TB0> INFO: Expecting 411648 events.
[20:04:22.902] <TB0> INFO: 411648 events read in total (9030ms).
[20:04:22.912] <TB0> INFO: Expecting 411648 events.
[20:04:32.349] <TB0> INFO: 411648 events read in total (9034ms).
[20:04:32.362] <TB0> INFO: Expecting 411648 events.
[20:04:41.960] <TB0> INFO: 411648 events read in total (9195ms).
[20:04:41.975] <TB0> INFO: Expecting 411648 events.
[20:04:51.589] <TB0> INFO: 411648 events read in total (9211ms).
[20:04:51.614] <TB0> INFO: Expecting 411648 events.
[20:05:00.005] <TB0> INFO: 411648 events read in total (8988ms).
[20:05:01.025] <TB0> INFO: Expecting 411648 events.
[20:05:10.459] <TB0> INFO: 411648 events read in total (9031ms).
[20:05:10.483] <TB0> INFO: Expecting 411648 events.
[20:05:19.984] <TB0> INFO: 411648 events read in total (9099ms).
[20:05:20.011] <TB0> INFO: Expecting 411648 events.
[20:05:29.498] <TB0> INFO: 411648 events read in total (9084ms).
[20:05:29.535] <TB0> INFO: Expecting 411648 events.
[20:05:38.862] <TB0> INFO: 411648 events read in total (8924ms).
[20:05:38.901] <TB0> INFO: Expecting 411648 events.
[20:05:48.312] <TB0> INFO: 411648 events read in total (9008ms).
[20:05:48.453] <TB0> INFO: Expecting 411648 events.
[20:05:57.828] <TB0> INFO: 411648 events read in total (8972ms).
[20:05:57.876] <TB0> INFO: Expecting 411648 events.
[20:06:07.394] <TB0> INFO: 411648 events read in total (9115ms).
[20:06:07.474] <TB0> INFO: Expecting 411648 events.
[20:06:16.928] <TB0> INFO: 411648 events read in total (9051ms).
[20:06:17.028] <TB0> INFO: Expecting 411648 events.
[20:06:26.474] <TB0> INFO: 411648 events read in total (9043ms).
[20:06:26.567] <TB0> INFO: Expecting 411648 events.
[20:06:36.015] <TB0> INFO: 411648 events read in total (9045ms).
[20:06:36.081] <TB0> INFO: Test took 152513ms.
[20:06:36.769] <TB0> INFO: ---> dac: vcal name: TrimThr2 ntrig: 8 dacrange: 0 .. 150 (-1/-1) hits flags = 528 (plus default)
[20:06:36.783] <TB0> INFO: dacScan split into 1 runs with ntrig = 8
[20:06:36.783] <TB0> INFO: run 1 of 1
[20:06:37.018] <TB0> INFO: Expecting 5025280 events.
[20:07:04.222] <TB0> INFO: 593232 events read in total (26613ms).
[20:07:31.102] <TB0> INFO: 1183936 events read in total (53493ms).
[20:07:57.909] <TB0> INFO: 1770120 events read in total (80299ms).
[20:08:24.946] <TB0> INFO: 2358480 events read in total (107336ms).
[20:08:52.024] <TB0> INFO: 2947560 events read in total (134414ms).
[20:09:19.071] <TB0> INFO: 3541056 events read in total (161461ms).
[20:09:45.999] <TB0> INFO: 4133584 events read in total (188389ms).
[20:10:12.873] <TB0> INFO: 4725088 events read in total (215264ms).
[20:10:26.715] <TB0> INFO: 5025280 events read in total (229105ms).
[20:10:26.901] <TB0> INFO: Test took 230119ms.
[20:10:45.936] <TB0> INFO: ---> TrimStepCorr4 extremal thresholds: 2.603064 .. 147.752381
[20:10:46.176] <TB0> INFO: Expecting 208000 events.
[20:10:55.974] <TB0> INFO: 208000 events read in total (9206ms).
[20:10:55.976] <TB0> INFO: Test took 10038ms.
[20:10:56.024] <TB0> INFO: ---> dac: vcal name: trimStepCorr4 ntrig: 8 dacrange: 2 .. 157 (-1/-1) hits flags = 528 (plus default)
[20:10:56.038] <TB0> INFO: dacScan split into 1 runs with ntrig = 8
[20:10:56.038] <TB0> INFO: run 1 of 1
[20:10:56.316] <TB0> INFO: Expecting 5191680 events.
[20:11:23.451] <TB0> INFO: 583424 events read in total (26544ms).
[20:11:49.545] <TB0> INFO: 1165912 events read in total (52639ms).
[20:12:15.716] <TB0> INFO: 1748720 events read in total (78810ms).
[20:12:42.571] <TB0> INFO: 2331016 events read in total (105664ms).
[20:13:09.237] <TB0> INFO: 2913920 events read in total (132330ms).
[20:13:35.458] <TB0> INFO: 3495240 events read in total (158551ms).
[20:14:01.947] <TB0> INFO: 4076368 events read in total (185040ms).
[20:14:29.045] <TB0> INFO: 4656848 events read in total (212138ms).
[20:14:53.358] <TB0> INFO: 5191680 events read in total (236451ms).
[20:14:53.504] <TB0> INFO: Test took 237467ms.
[20:15:16.316] <TB0> INFO: ---> TrimStepCorr2 extremal thresholds: 26.336993 .. 48.087241
[20:15:16.608] <TB0> INFO: Expecting 208000 events.
[20:15:26.829] <TB0> INFO: 208000 events read in total (9630ms).
[20:15:26.830] <TB0> INFO: Test took 10512ms.
[20:15:26.878] <TB0> INFO: ---> dac: vcal name: trimStepCorr2 ntrig: 8 dacrange: 16 .. 58 (-1/-1) hits flags = 528 (plus default)
[20:15:26.891] <TB0> INFO: dacScan split into 1 runs with ntrig = 8
[20:15:26.892] <TB0> INFO: run 1 of 1
[20:15:27.170] <TB0> INFO: Expecting 1431040 events.
[20:15:55.823] <TB0> INFO: 652184 events read in total (28062ms).
[20:16:24.493] <TB0> INFO: 1301032 events read in total (56733ms).
[20:16:30.513] <TB0> INFO: 1431040 events read in total (62752ms).
[20:16:30.551] <TB0> INFO: Test took 63660ms.
[20:16:43.417] <TB0> INFO: ---> TrimStepCorr1a extremal thresholds: 27.284895 .. 47.605503
[20:16:43.655] <TB0> INFO: Expecting 208000 events.
[20:16:53.669] <TB0> INFO: 208000 events read in total (9422ms).
[20:16:53.670] <TB0> INFO: Test took 10252ms.
[20:16:53.717] <TB0> INFO: ---> dac: vcal name: trimStepCorr1a ntrig: 8 dacrange: 17 .. 57 (-1/-1) hits flags = 528 (plus default)
[20:16:53.730] <TB0> INFO: dacScan split into 1 runs with ntrig = 8
[20:16:53.730] <TB0> INFO: run 1 of 1
[20:16:54.009] <TB0> INFO: Expecting 1364480 events.
[20:17:22.997] <TB0> INFO: 652128 events read in total (28397ms).
[20:17:52.041] <TB0> INFO: 1302592 events read in total (57441ms).
[20:17:55.049] <TB0> INFO: 1364480 events read in total (60449ms).
[20:17:55.082] <TB0> INFO: Test took 61353ms.
[20:18:07.886] <TB0> INFO: ---> TrimStepCorr1b extremal thresholds: 24.942444 .. 45.588313
[20:18:08.183] <TB0> INFO: Expecting 208000 events.
[20:18:18.190] <TB0> INFO: 208000 events read in total (9415ms).
[20:18:18.191] <TB0> INFO: Test took 10304ms.
[20:18:18.238] <TB0> INFO: ---> dac: vcal name: trimStepCorr1b ntrig: 8 dacrange: 14 .. 55 (-1/-1) hits flags = 528 (plus default)
[20:18:18.252] <TB0> INFO: dacScan split into 1 runs with ntrig = 8
[20:18:18.252] <TB0> INFO: run 1 of 1
[20:18:18.530] <TB0> INFO: Expecting 1397760 events.
[20:18:47.468] <TB0> INFO: 671072 events read in total (28346ms).
[20:19:16.463] <TB0> INFO: 1342080 events read in total (57342ms).
[20:19:19.183] <TB0> INFO: 1397760 events read in total (60061ms).
[20:19:19.214] <TB0> INFO: Test took 60963ms.
[20:19:31.211] <TB0> INFO: ---> TrimThrFinal extremal thresholds: 15 .. 55
[20:19:31.211] <TB0> INFO: ---> dac: vcal name: TrimThrFinal ntrig: 8 dacrange: 15 .. 55 (-1/-1) hits flags = 528 (plus default)
[20:19:31.225] <TB0> INFO: dacScan split into 1 runs with ntrig = 8
[20:19:31.225] <TB0> INFO: run 1 of 1
[20:19:31.462] <TB0> INFO: Expecting 1364480 events.
[20:20:00.903] <TB0> INFO: 667368 events read in total (28849ms).
[20:20:29.037] <TB0> INFO: 1334688 events read in total (56983ms).
[20:20:30.779] <TB0> INFO: 1364480 events read in total (58726ms).
[20:20:30.809] <TB0> INFO: Test took 59585ms.
[20:20:42.384] <TB0> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1071_FullQualification_2016-11-03_14h08m_1478178483//001_Fulltest_m20//dacParameters35_C0.dat
[20:20:42.384] <TB0> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1071_FullQualification_2016-11-03_14h08m_1478178483//001_Fulltest_m20//dacParameters35_C1.dat
[20:20:42.384] <TB0> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1071_FullQualification_2016-11-03_14h08m_1478178483//001_Fulltest_m20//dacParameters35_C2.dat
[20:20:42.384] <TB0> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1071_FullQualification_2016-11-03_14h08m_1478178483//001_Fulltest_m20//dacParameters35_C3.dat
[20:20:42.384] <TB0> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1071_FullQualification_2016-11-03_14h08m_1478178483//001_Fulltest_m20//dacParameters35_C4.dat
[20:20:42.385] <TB0> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1071_FullQualification_2016-11-03_14h08m_1478178483//001_Fulltest_m20//dacParameters35_C5.dat
[20:20:42.385] <TB0> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1071_FullQualification_2016-11-03_14h08m_1478178483//001_Fulltest_m20//dacParameters35_C6.dat
[20:20:42.385] <TB0> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1071_FullQualification_2016-11-03_14h08m_1478178483//001_Fulltest_m20//dacParameters35_C7.dat
[20:20:42.385] <TB0> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1071_FullQualification_2016-11-03_14h08m_1478178483//001_Fulltest_m20//dacParameters35_C8.dat
[20:20:42.385] <TB0> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1071_FullQualification_2016-11-03_14h08m_1478178483//001_Fulltest_m20//dacParameters35_C9.dat
[20:20:42.385] <TB0> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1071_FullQualification_2016-11-03_14h08m_1478178483//001_Fulltest_m20//dacParameters35_C10.dat
[20:20:42.385] <TB0> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1071_FullQualification_2016-11-03_14h08m_1478178483//001_Fulltest_m20//dacParameters35_C11.dat
[20:20:42.385] <TB0> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1071_FullQualification_2016-11-03_14h08m_1478178483//001_Fulltest_m20//dacParameters35_C12.dat
[20:20:42.385] <TB0> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1071_FullQualification_2016-11-03_14h08m_1478178483//001_Fulltest_m20//dacParameters35_C13.dat
[20:20:42.385] <TB0> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1071_FullQualification_2016-11-03_14h08m_1478178483//001_Fulltest_m20//dacParameters35_C14.dat
[20:20:42.385] <TB0> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1071_FullQualification_2016-11-03_14h08m_1478178483//001_Fulltest_m20//dacParameters35_C15.dat
[20:20:42.386] <TB0> INFO: write trim parameters into /home/l_tester/david/FullQualification/data/M1071_FullQualification_2016-11-03_14h08m_1478178483//001_Fulltest_m20//trimParameters35_C0.dat
[20:20:42.391] <TB0> INFO: write trim parameters into /home/l_tester/david/FullQualification/data/M1071_FullQualification_2016-11-03_14h08m_1478178483//001_Fulltest_m20//trimParameters35_C1.dat
[20:20:42.396] <TB0> INFO: write trim parameters into /home/l_tester/david/FullQualification/data/M1071_FullQualification_2016-11-03_14h08m_1478178483//001_Fulltest_m20//trimParameters35_C2.dat
[20:20:42.400] <TB0> INFO: write trim parameters into /home/l_tester/david/FullQualification/data/M1071_FullQualification_2016-11-03_14h08m_1478178483//001_Fulltest_m20//trimParameters35_C3.dat
[20:20:42.405] <TB0> INFO: write trim parameters into /home/l_tester/david/FullQualification/data/M1071_FullQualification_2016-11-03_14h08m_1478178483//001_Fulltest_m20//trimParameters35_C4.dat
[20:20:42.410] <TB0> INFO: write trim parameters into /home/l_tester/david/FullQualification/data/M1071_FullQualification_2016-11-03_14h08m_1478178483//001_Fulltest_m20//trimParameters35_C5.dat
[20:20:42.415] <TB0> INFO: write trim parameters into /home/l_tester/david/FullQualification/data/M1071_FullQualification_2016-11-03_14h08m_1478178483//001_Fulltest_m20//trimParameters35_C6.dat
[20:20:42.419] <TB0> INFO: write trim parameters into /home/l_tester/david/FullQualification/data/M1071_FullQualification_2016-11-03_14h08m_1478178483//001_Fulltest_m20//trimParameters35_C7.dat
[20:20:42.424] <TB0> INFO: write trim parameters into /home/l_tester/david/FullQualification/data/M1071_FullQualification_2016-11-03_14h08m_1478178483//001_Fulltest_m20//trimParameters35_C8.dat
[20:20:42.429] <TB0> INFO: write trim parameters into /home/l_tester/david/FullQualification/data/M1071_FullQualification_2016-11-03_14h08m_1478178483//001_Fulltest_m20//trimParameters35_C9.dat
[20:20:42.433] <TB0> INFO: write trim parameters into /home/l_tester/david/FullQualification/data/M1071_FullQualification_2016-11-03_14h08m_1478178483//001_Fulltest_m20//trimParameters35_C10.dat
[20:20:42.438] <TB0> INFO: write trim parameters into /home/l_tester/david/FullQualification/data/M1071_FullQualification_2016-11-03_14h08m_1478178483//001_Fulltest_m20//trimParameters35_C11.dat
[20:20:42.443] <TB0> INFO: write trim parameters into /home/l_tester/david/FullQualification/data/M1071_FullQualification_2016-11-03_14h08m_1478178483//001_Fulltest_m20//trimParameters35_C12.dat
[20:20:42.447] <TB0> INFO: write trim parameters into /home/l_tester/david/FullQualification/data/M1071_FullQualification_2016-11-03_14h08m_1478178483//001_Fulltest_m20//trimParameters35_C13.dat
[20:20:42.452] <TB0> INFO: write trim parameters into /home/l_tester/david/FullQualification/data/M1071_FullQualification_2016-11-03_14h08m_1478178483//001_Fulltest_m20//trimParameters35_C14.dat
[20:20:42.457] <TB0> INFO: write trim parameters into /home/l_tester/david/FullQualification/data/M1071_FullQualification_2016-11-03_14h08m_1478178483//001_Fulltest_m20//trimParameters35_C15.dat
[20:20:42.461] <TB0> INFO: PixTestTrim::trimTest() done
[20:20:42.461] <TB0> INFO: vtrim: 118 130 116 118 139 122 147 121 144 132 139 123 120 134 123 131
[20:20:42.461] <TB0> INFO: vthrcomp: 128 121 118 114 125 118 121 126 116 118 110 119 124 113 126 126
[20:20:42.461] <TB0> INFO: vcal mean: 34.96 35.08 34.91 35.01 34.96 35.12 34.96 34.89 35.04 34.94 35.12 34.96 34.95 34.99 34.92 35.05
[20:20:42.461] <TB0> INFO: vcal RMS: 1.18 1.38 1.19 1.15 1.27 1.64 1.08 1.13 1.23 1.38 1.41 1.26 1.09 1.15 1.34 1.40
[20:20:42.461] <TB0> INFO: bits mean: 9.30 9.49 9.70 9.63 9.92 9.20 9.98 9.78 9.73 9.88 8.72 9.07 9.81 9.65 9.96 10.23
[20:20:42.461] <TB0> INFO: bits RMS: 2.82 2.69 2.76 2.64 2.68 2.86 2.59 2.82 2.58 2.62 2.61 3.03 2.53 2.54 2.74 2.65
[20:20:42.470] <TB0> INFO: ----------------------------------------------------------------------
[20:20:42.470] <TB0> INFO: PixTestTrim::trimBitTest() ntrig = 5, vtrims = 254 126 63 32
[20:20:42.470] <TB0> INFO: ----------------------------------------------------------------------
[20:20:42.473] <TB0> INFO: ---> dac: Vcal name: TrimBitsThr0 ntrig: 5 dacrange: 0 .. 199 (-1/-1) hits flags = 528 (plus default)
[20:20:42.486] <TB0> INFO: dacScan split into 1 runs with ntrig = 5
[20:20:42.486] <TB0> INFO: run 1 of 1
[20:20:42.723] <TB0> INFO: Expecting 4160000 events.
[20:21:16.343] <TB0> INFO: 769095 events read in total (33028ms).
[20:21:49.165] <TB0> INFO: 1531010 events read in total (65850ms).
[20:22:22.354] <TB0> INFO: 2287785 events read in total (99039ms).
[20:22:55.226] <TB0> INFO: 3039830 events read in total (131911ms).
[20:23:28.154] <TB0> INFO: 3788870 events read in total (164839ms).
[20:23:44.227] <TB0> INFO: 4160000 events read in total (180912ms).
[20:23:44.294] <TB0> INFO: Test took 181807ms.
[20:24:06.055] <TB0> INFO: ---> dac: Vcal name: TrimThr_trim14 ntrig: 5 dacrange: 0 .. 221 (-1/-1) hits flags = 528 (plus default)
[20:24:06.069] <TB0> INFO: dacScan split into 1 runs with ntrig = 5
[20:24:06.069] <TB0> INFO: run 1 of 1
[20:24:06.319] <TB0> INFO: Expecting 4617600 events.
[20:24:38.786] <TB0> INFO: 711945 events read in total (31876ms).
[20:25:10.614] <TB0> INFO: 1420270 events read in total (63704ms).
[20:25:42.390] <TB0> INFO: 2124175 events read in total (95480ms).
[20:26:14.690] <TB0> INFO: 2826835 events read in total (127780ms).
[20:26:46.324] <TB0> INFO: 3525015 events read in total (159414ms).
[20:27:18.084] <TB0> INFO: 4223285 events read in total (191174ms).
[20:27:35.979] <TB0> INFO: 4617600 events read in total (209069ms).
[20:27:36.065] <TB0> INFO: Test took 209996ms.
[20:28:00.951] <TB0> INFO: ---> dac: Vcal name: TrimThr_trim13 ntrig: 5 dacrange: 0 .. 219 (-1/-1) hits flags = 528 (plus default)
[20:28:00.962] <TB0> INFO: dacScan split into 1 runs with ntrig = 5
[20:28:00.962] <TB0> INFO: run 1 of 1
[20:28:01.202] <TB0> INFO: Expecting 4576000 events.
[20:28:33.521] <TB0> INFO: 714820 events read in total (31728ms).
[20:29:05.405] <TB0> INFO: 1425280 events read in total (63612ms).
[20:29:37.650] <TB0> INFO: 2132105 events read in total (95857ms).
[20:30:09.451] <TB0> INFO: 2836710 events read in total (127658ms).
[20:30:41.136] <TB0> INFO: 3538305 events read in total (159343ms).
[20:31:12.678] <TB0> INFO: 4238685 events read in total (190885ms).
[20:31:27.823] <TB0> INFO: 4576000 events read in total (206030ms).
[20:31:27.909] <TB0> INFO: Test took 206946ms.
[20:31:54.620] <TB0> INFO: ---> dac: Vcal name: TrimThr_trim11 ntrig: 5 dacrange: 0 .. 213 (-1/-1) hits flags = 528 (plus default)
[20:31:54.635] <TB0> INFO: dacScan split into 1 runs with ntrig = 5
[20:31:54.635] <TB0> INFO: run 1 of 1
[20:31:54.903] <TB0> INFO: Expecting 4451200 events.
[20:32:27.629] <TB0> INFO: 722215 events read in total (32134ms).
[20:33:00.122] <TB0> INFO: 1439145 events read in total (64627ms).
[20:33:32.310] <TB0> INFO: 2153945 events read in total (96815ms).
[20:34:04.505] <TB0> INFO: 2864605 events read in total (129010ms).
[20:34:36.381] <TB0> INFO: 3572765 events read in total (160886ms).
[20:35:08.693] <TB0> INFO: 4280825 events read in total (193198ms).
[20:35:16.642] <TB0> INFO: 4451200 events read in total (201147ms).
[20:35:16.759] <TB0> INFO: Test took 202123ms.
[20:35:42.258] <TB0> INFO: ---> dac: Vcal name: TrimThr_trim7 ntrig: 5 dacrange: 0 .. 213 (-1/-1) hits flags = 528 (plus default)
[20:35:42.272] <TB0> INFO: dacScan split into 1 runs with ntrig = 5
[20:35:42.272] <TB0> INFO: run 1 of 1
[20:35:42.508] <TB0> INFO: Expecting 4451200 events.
[20:36:15.587] <TB0> INFO: 722115 events read in total (32487ms).
[20:36:47.640] <TB0> INFO: 1438465 events read in total (64540ms).
[20:37:19.655] <TB0> INFO: 2152680 events read in total (96555ms).
[20:37:51.493] <TB0> INFO: 2863175 events read in total (128393ms).
[20:38:23.475] <TB0> INFO: 3570740 events read in total (160375ms).
[20:38:55.082] <TB0> INFO: 4278850 events read in total (191982ms).
[20:39:03.151] <TB0> INFO: 4451200 events read in total (200051ms).
[20:39:03.248] <TB0> INFO: Test took 200976ms.
[20:39:27.562] <TB0> INFO: PixTestTrim::trimBitTest() done
[20:39:27.563] <TB0> INFO: PixTestTrim::doTest() done, duration: 2584 seconds
[20:39:27.563] <TB0> INFO: Decoding statistics:
[20:39:27.563] <TB0> INFO: General information:
[20:39:27.563] <TB0> INFO: 16bit words read: 0
[20:39:27.563] <TB0> INFO: valid events total: 0
[20:39:27.563] <TB0> INFO: empty events: 0
[20:39:27.564] <TB0> INFO: valid events with pixels: 0
[20:39:27.564] <TB0> INFO: valid pixel hits: 0
[20:39:27.564] <TB0> INFO: Event errors: 0
[20:39:27.564] <TB0> INFO: start marker: 0
[20:39:27.564] <TB0> INFO: stop marker: 0
[20:39:27.564] <TB0> INFO: overflow: 0
[20:39:27.564] <TB0> INFO: invalid 5bit words: 0
[20:39:27.564] <TB0> INFO: invalid XOR eye diagram: 0
[20:39:27.564] <TB0> INFO: frame (failed synchr.): 0
[20:39:27.564] <TB0> INFO: idle data (no TBM trl): 0
[20:39:27.564] <TB0> INFO: no data (only TBM hdr): 0
[20:39:27.564] <TB0> INFO: TBM errors: 0
[20:39:27.564] <TB0> INFO: flawed TBM headers: 0
[20:39:27.564] <TB0> INFO: flawed TBM trailers: 0
[20:39:27.564] <TB0> INFO: event ID mismatches: 0
[20:39:27.564] <TB0> INFO: ROC errors: 0
[20:39:27.564] <TB0> INFO: missing ROC header(s): 0
[20:39:27.564] <TB0> INFO: misplaced readback start: 0
[20:39:27.564] <TB0> INFO: Pixel decoding errors: 0
[20:39:27.564] <TB0> INFO: pixel data incomplete: 0
[20:39:27.564] <TB0> INFO: pixel address: 0
[20:39:27.564] <TB0> INFO: pulse height fill bit: 0
[20:39:27.564] <TB0> INFO: buffer corruption: 0
[20:39:28.286] <TB0> INFO: ######################################################################
[20:39:28.286] <TB0> INFO: PixTestPhOptimization::doTest() Ntrig = 10
[20:39:28.286] <TB0> INFO: ######################################################################
[20:39:28.526] <TB0> INFO: Expecting 41600 events.
[20:39:31.002] <TB0> INFO: 41600 events read in total (2884ms).
[20:39:32.003] <TB0> INFO: Test took 3716ms.
[20:39:32.446] <TB0> INFO: Expecting 41600 events.
[20:39:36.061] <TB0> INFO: 41600 events read in total (3023ms).
[20:39:36.062] <TB0> INFO: Test took 3855ms.
[20:39:36.351] <TB0> INFO: Expecting 41600 events.
[20:39:39.888] <TB0> INFO: 41600 events read in total (2945ms).
[20:39:39.889] <TB0> INFO: Test took 3803ms.
[20:39:40.178] <TB0> INFO: Expecting 41600 events.
[20:39:43.801] <TB0> INFO: 41600 events read in total (3031ms).
[20:39:43.801] <TB0> INFO: Test took 3888ms.
[20:39:44.091] <TB0> INFO: Expecting 41600 events.
[20:39:47.591] <TB0> INFO: 41600 events read in total (2909ms).
[20:39:47.592] <TB0> INFO: Test took 3766ms.
[20:39:47.881] <TB0> INFO: Expecting 41600 events.
[20:39:51.554] <TB0> INFO: 41600 events read in total (3081ms).
[20:39:51.555] <TB0> INFO: Test took 3939ms.
[20:39:51.881] <TB0> INFO: Expecting 41600 events.
[20:39:55.454] <TB0> INFO: 41600 events read in total (2982ms).
[20:39:55.455] <TB0> INFO: Test took 3876ms.
[20:39:55.744] <TB0> INFO: Expecting 41600 events.
[20:39:59.308] <TB0> INFO: 41600 events read in total (2972ms).
[20:39:59.309] <TB0> INFO: Test took 3830ms.
[20:39:59.598] <TB0> INFO: Expecting 41600 events.
[20:40:03.199] <TB0> INFO: 41600 events read in total (3009ms).
[20:40:03.200] <TB0> INFO: Test took 3867ms.
[20:40:03.493] <TB0> INFO: Expecting 41600 events.
[20:40:07.026] <TB0> INFO: 41600 events read in total (2941ms).
[20:40:07.027] <TB0> INFO: Test took 3803ms.
[20:40:07.316] <TB0> INFO: Expecting 41600 events.
[20:40:10.848] <TB0> INFO: 41600 events read in total (2940ms).
[20:40:10.849] <TB0> INFO: Test took 3798ms.
[20:40:11.138] <TB0> INFO: Expecting 41600 events.
[20:40:14.765] <TB0> INFO: 41600 events read in total (3035ms).
[20:40:14.765] <TB0> INFO: Test took 3892ms.
[20:40:15.054] <TB0> INFO: Expecting 41600 events.
[20:40:18.755] <TB0> INFO: 41600 events read in total (3109ms).
[20:40:18.756] <TB0> INFO: Test took 3966ms.
[20:40:19.050] <TB0> INFO: Expecting 41600 events.
[20:40:22.681] <TB0> INFO: 41600 events read in total (3040ms).
[20:40:22.682] <TB0> INFO: Test took 3897ms.
[20:40:22.972] <TB0> INFO: Expecting 41600 events.
[20:40:26.786] <TB0> INFO: 41600 events read in total (3223ms).
[20:40:26.787] <TB0> INFO: Test took 4080ms.
[20:40:27.119] <TB0> INFO: Expecting 41600 events.
[20:40:30.804] <TB0> INFO: 41600 events read in total (3094ms).
[20:40:30.805] <TB0> INFO: Test took 3989ms.
[20:40:31.095] <TB0> INFO: Expecting 41600 events.
[20:40:34.597] <TB0> INFO: 41600 events read in total (2911ms).
[20:40:34.599] <TB0> INFO: Test took 3770ms.
[20:40:34.889] <TB0> INFO: Expecting 41600 events.
[20:40:38.526] <TB0> INFO: 41600 events read in total (3046ms).
[20:40:38.527] <TB0> INFO: Test took 3903ms.
[20:40:38.816] <TB0> INFO: Expecting 41600 events.
[20:40:42.386] <TB0> INFO: 41600 events read in total (2978ms).
[20:40:42.387] <TB0> INFO: Test took 3836ms.
[20:40:42.704] <TB0> INFO: Expecting 41600 events.
[20:40:46.358] <TB0> INFO: 41600 events read in total (3063ms).
[20:40:46.359] <TB0> INFO: Test took 3948ms.
[20:40:46.648] <TB0> INFO: Expecting 41600 events.
[20:40:50.272] <TB0> INFO: 41600 events read in total (3032ms).
[20:40:50.272] <TB0> INFO: Test took 3889ms.
[20:40:50.562] <TB0> INFO: Expecting 41600 events.
[20:40:54.236] <TB0> INFO: 41600 events read in total (3083ms).
[20:40:54.237] <TB0> INFO: Test took 3940ms.
[20:40:54.529] <TB0> INFO: Expecting 41600 events.
[20:40:58.320] <TB0> INFO: 41600 events read in total (3199ms).
[20:40:58.321] <TB0> INFO: Test took 4057ms.
[20:40:58.614] <TB0> INFO: Expecting 41600 events.
[20:41:02.183] <TB0> INFO: 41600 events read in total (2977ms).
[20:41:02.184] <TB0> INFO: Test took 3835ms.
[20:41:02.474] <TB0> INFO: Expecting 41600 events.
[20:41:05.981] <TB0> INFO: 41600 events read in total (2916ms).
[20:41:05.982] <TB0> INFO: Test took 3773ms.
[20:41:06.313] <TB0> INFO: Expecting 41600 events.
[20:41:09.997] <TB0> INFO: 41600 events read in total (3092ms).
[20:41:09.998] <TB0> INFO: Test took 3992ms.
[20:41:10.287] <TB0> INFO: Expecting 41600 events.
[20:41:13.991] <TB0> INFO: 41600 events read in total (3113ms).
[20:41:13.991] <TB0> INFO: Test took 3969ms.
[20:41:14.280] <TB0> INFO: Expecting 41600 events.
[20:41:17.866] <TB0> INFO: 41600 events read in total (2994ms).
[20:41:17.867] <TB0> INFO: Test took 3851ms.
[20:41:18.160] <TB0> INFO: Expecting 41600 events.
[20:41:21.781] <TB0> INFO: 41600 events read in total (3029ms).
[20:41:21.782] <TB0> INFO: Test took 3887ms.
[20:41:22.071] <TB0> INFO: Expecting 41600 events.
[20:41:25.587] <TB0> INFO: 41600 events read in total (2924ms).
[20:41:25.587] <TB0> INFO: Test took 3781ms.
[20:41:25.878] <TB0> INFO: Expecting 41600 events.
[20:41:29.409] <TB0> INFO: 41600 events read in total (2939ms).
[20:41:29.410] <TB0> INFO: Test took 3797ms.
[20:41:29.705] <TB0> INFO: Expecting 2560 events.
[20:41:30.600] <TB0> INFO: 2560 events read in total (303ms).
[20:41:30.601] <TB0> INFO: Test took 1173ms.
[20:41:30.908] <TB0> INFO: Expecting 2560 events.
[20:41:31.798] <TB0> INFO: 2560 events read in total (299ms).
[20:41:31.798] <TB0> INFO: Test took 1197ms.
[20:41:32.105] <TB0> INFO: Expecting 2560 events.
[20:41:32.994] <TB0> INFO: 2560 events read in total (298ms).
[20:41:32.994] <TB0> INFO: Test took 1195ms.
[20:41:33.301] <TB0> INFO: Expecting 2560 events.
[20:41:34.190] <TB0> INFO: 2560 events read in total (298ms).
[20:41:34.190] <TB0> INFO: Test took 1195ms.
[20:41:34.498] <TB0> INFO: Expecting 2560 events.
[20:41:35.378] <TB0> INFO: 2560 events read in total (288ms).
[20:41:35.378] <TB0> INFO: Test took 1187ms.
[20:41:35.686] <TB0> INFO: Expecting 2560 events.
[20:41:36.572] <TB0> INFO: 2560 events read in total (294ms).
[20:41:36.572] <TB0> INFO: Test took 1193ms.
[20:41:36.880] <TB0> INFO: Expecting 2560 events.
[20:41:37.769] <TB0> INFO: 2560 events read in total (297ms).
[20:41:37.769] <TB0> INFO: Test took 1196ms.
[20:41:38.077] <TB0> INFO: Expecting 2560 events.
[20:41:38.962] <TB0> INFO: 2560 events read in total (293ms).
[20:41:38.962] <TB0> INFO: Test took 1192ms.
[20:41:39.270] <TB0> INFO: Expecting 2560 events.
[20:41:40.152] <TB0> INFO: 2560 events read in total (290ms).
[20:41:40.152] <TB0> INFO: Test took 1189ms.
[20:41:40.459] <TB0> INFO: Expecting 2560 events.
[20:41:41.343] <TB0> INFO: 2560 events read in total (293ms).
[20:41:41.344] <TB0> INFO: Test took 1191ms.
[20:41:41.651] <TB0> INFO: Expecting 2560 events.
[20:41:42.542] <TB0> INFO: 2560 events read in total (299ms).
[20:41:42.542] <TB0> INFO: Test took 1198ms.
[20:41:42.850] <TB0> INFO: Expecting 2560 events.
[20:41:43.736] <TB0> INFO: 2560 events read in total (295ms).
[20:41:43.736] <TB0> INFO: Test took 1193ms.
[20:41:44.044] <TB0> INFO: Expecting 2560 events.
[20:41:44.934] <TB0> INFO: 2560 events read in total (298ms).
[20:41:44.934] <TB0> INFO: Test took 1197ms.
[20:41:45.242] <TB0> INFO: Expecting 2560 events.
[20:41:46.132] <TB0> INFO: 2560 events read in total (298ms).
[20:41:46.132] <TB0> INFO: Test took 1198ms.
[20:41:46.439] <TB0> INFO: Expecting 2560 events.
[20:41:47.333] <TB0> INFO: 2560 events read in total (302ms).
[20:41:47.334] <TB0> INFO: Test took 1201ms.
[20:41:47.641] <TB0> INFO: Expecting 2560 events.
[20:41:48.536] <TB0> INFO: 2560 events read in total (303ms).
[20:41:48.536] <TB0> INFO: Test took 1202ms.
[20:41:48.540] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[20:41:48.845] <TB0> INFO: Expecting 655360 events.
[20:42:03.950] <TB0> INFO: 655360 events read in total (14513ms).
[20:42:03.968] <TB0> INFO: Expecting 655360 events.
[20:42:18.998] <TB0> INFO: 655360 events read in total (14627ms).
[20:42:19.016] <TB0> INFO: Expecting 655360 events.
[20:42:34.062] <TB0> INFO: 655360 events read in total (14643ms).
[20:42:34.085] <TB0> INFO: Expecting 655360 events.
[20:42:49.115] <TB0> INFO: 655360 events read in total (14627ms).
[20:42:49.143] <TB0> INFO: Expecting 655360 events.
[20:43:04.283] <TB0> INFO: 655360 events read in total (14737ms).
[20:43:04.328] <TB0> INFO: Expecting 655360 events.
[20:43:19.420] <TB0> INFO: 655360 events read in total (14689ms).
[20:43:19.463] <TB0> INFO: Expecting 655360 events.
[20:43:34.522] <TB0> INFO: 655360 events read in total (14656ms).
[20:43:34.570] <TB0> INFO: Expecting 655360 events.
[20:43:49.487] <TB0> INFO: 655360 events read in total (14514ms).
[20:43:49.533] <TB0> INFO: Expecting 655360 events.
[20:44:04.581] <TB0> INFO: 655360 events read in total (14645ms).
[20:44:04.639] <TB0> INFO: Expecting 655360 events.
[20:44:19.411] <TB0> INFO: 655360 events read in total (14369ms).
[20:44:19.467] <TB0> INFO: Expecting 655360 events.
[20:44:34.302] <TB0> INFO: 655360 events read in total (14432ms).
[20:44:34.371] <TB0> INFO: Expecting 655360 events.
[20:44:49.275] <TB0> INFO: 655360 events read in total (14501ms).
[20:44:49.392] <TB0> INFO: Expecting 655360 events.
[20:45:04.192] <TB0> INFO: 655360 events read in total (14397ms).
[20:45:04.314] <TB0> INFO: Expecting 655360 events.
[20:45:19.217] <TB0> INFO: 655360 events read in total (14500ms).
[20:45:19.355] <TB0> INFO: Expecting 655360 events.
[20:45:34.161] <TB0> INFO: 655360 events read in total (14403ms).
[20:45:34.312] <TB0> INFO: Expecting 655360 events.
[20:45:49.278] <TB0> INFO: 655360 events read in total (14563ms).
[20:45:49.411] <TB0> INFO: Test took 240872ms.
[20:45:49.504] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[20:45:49.761] <TB0> INFO: Expecting 655360 events.
[20:46:04.559] <TB0> INFO: 655360 events read in total (14206ms).
[20:46:04.570] <TB0> INFO: Expecting 655360 events.
[20:46:19.084] <TB0> INFO: 655360 events read in total (14110ms).
[20:46:19.100] <TB0> INFO: Expecting 655360 events.
[20:46:33.916] <TB0> INFO: 655360 events read in total (14413ms).
[20:46:33.940] <TB0> INFO: Expecting 655360 events.
[20:46:48.927] <TB0> INFO: 655360 events read in total (14584ms).
[20:46:48.952] <TB0> INFO: Expecting 655360 events.
[20:47:03.803] <TB0> INFO: 655360 events read in total (14448ms).
[20:47:03.833] <TB0> INFO: Expecting 655360 events.
[20:47:18.559] <TB0> INFO: 655360 events read in total (14323ms).
[20:47:18.608] <TB0> INFO: Expecting 655360 events.
[20:47:33.404] <TB0> INFO: 655360 events read in total (14393ms).
[20:47:33.453] <TB0> INFO: Expecting 655360 events.
[20:47:48.487] <TB0> INFO: 655360 events read in total (14631ms).
[20:47:48.547] <TB0> INFO: Expecting 655360 events.
[20:48:03.308] <TB0> INFO: 655360 events read in total (14358ms).
[20:48:03.353] <TB0> INFO: Expecting 655360 events.
[20:48:18.231] <TB0> INFO: 655360 events read in total (14475ms).
[20:48:18.340] <TB0> INFO: Expecting 655360 events.
[20:48:33.129] <TB0> INFO: 655360 events read in total (14386ms).
[20:48:33.204] <TB0> INFO: Expecting 655360 events.
[20:48:48.068] <TB0> INFO: 655360 events read in total (14461ms).
[20:48:48.148] <TB0> INFO: Expecting 655360 events.
[20:49:03.061] <TB0> INFO: 655360 events read in total (14510ms).
[20:49:03.197] <TB0> INFO: Expecting 655360 events.
[20:49:17.793] <TB0> INFO: 655360 events read in total (14193ms).
[20:49:17.933] <TB0> INFO: Expecting 655360 events.
[20:49:32.195] <TB0> INFO: 655360 events read in total (13859ms).
[20:49:32.346] <TB0> INFO: Expecting 655360 events.
[20:49:47.258] <TB0> INFO: 655360 events read in total (14509ms).
[20:49:47.363] <TB0> INFO: Test took 237859ms.
[20:49:47.534] <TB0> INFO: safety margin for low PH: adding 0, margin is now 20
[20:49:47.539] <TB0> INFO: safety margin for low PH: adding 1, margin is now 21
[20:49:47.545] <TB0> INFO: safety margin for low PH: adding 2, margin is now 22
[20:49:47.551] <TB0> INFO: safety margin for low PH: adding 3, margin is now 23
[20:49:47.556] <TB0> INFO: safety margin for low PH: adding 4, margin is now 24
[20:49:47.562] <TB0> INFO: safety margin for low PH: adding 0, margin is now 20
[20:49:47.568] <TB0> INFO: safety margin for low PH: adding 0, margin is now 20
[20:49:47.574] <TB0> INFO: safety margin for low PH: adding 0, margin is now 20
[20:49:47.580] <TB0> INFO: safety margin for low PH: adding 1, margin is now 21
[20:49:47.586] <TB0> INFO: safety margin for low PH: adding 2, margin is now 22
[20:49:47.593] <TB0> INFO: safety margin for low PH: adding 0, margin is now 20
[20:49:47.599] <TB0> INFO: safety margin for low PH: adding 0, margin is now 20
[20:49:47.605] <TB0> INFO: safety margin for low PH: adding 0, margin is now 20
[20:49:47.611] <TB0> INFO: safety margin for low PH: adding 0, margin is now 20
[20:49:47.617] <TB0> INFO: safety margin for low PH: adding 0, margin is now 20
[20:49:47.624] <TB0> INFO: safety margin for low PH: adding 0, margin is now 20
[20:49:47.630] <TB0> INFO: safety margin for low PH: adding 0, margin is now 20
[20:49:47.636] <TB0> INFO: safety margin for low PH: adding 0, margin is now 20
[20:49:47.642] <TB0> INFO: safety margin for low PH: adding 0, margin is now 20
[20:49:47.649] <TB0> INFO: safety margin for low PH: adding 1, margin is now 21
[20:49:47.655] <TB0> INFO: safety margin for low PH: adding 2, margin is now 22
[20:49:47.661] <TB0> INFO: safety margin for low PH: adding 3, margin is now 23
[20:49:47.667] <TB0> INFO: safety margin for low PH: adding 4, margin is now 24
[20:49:47.673] <TB0> INFO: safety margin for low PH: adding 5, margin is now 25
[20:49:47.679] <TB0> INFO: safety margin for low PH: adding 6, margin is now 26
[20:49:47.685] <TB0> INFO: safety margin for low PH: adding 7, margin is now 27
[20:49:47.691] <TB0> INFO: safety margin for low PH: adding 8, margin is now 28
[20:49:47.698] <TB0> INFO: safety margin for low PH: adding 9, margin is now 29
[20:49:47.703] <TB0> INFO: safety margin for low PH: adding 0, margin is now 20
[20:49:47.709] <TB0> INFO: safety margin for low PH: adding 1, margin is now 21
[20:49:47.716] <TB0> INFO: safety margin for low PH: adding 2, margin is now 22
[20:49:47.722] <TB0> INFO: safety margin for low PH: adding 3, margin is now 23
[20:49:47.728] <TB0> INFO: safety margin for low PH: adding 4, margin is now 24
[20:49:47.734] <TB0> INFO: safety margin for low PH: adding 5, margin is now 25
[20:49:47.740] <TB0> INFO: safety margin for low PH: adding 6, margin is now 26
[20:49:47.747] <TB0> INFO: safety margin for low PH: adding 7, margin is now 27
[20:49:47.753] <TB0> INFO: safety margin for low PH: adding 0, margin is now 20
[20:49:47.759] <TB0> INFO: safety margin for low PH: adding 1, margin is now 21
[20:49:47.766] <TB0> INFO: safety margin for low PH: adding 2, margin is now 22
[20:49:47.771] <TB0> INFO: safety margin for low PH: adding 3, margin is now 23
[20:49:47.777] <TB0> INFO: safety margin for low PH: adding 0, margin is now 20
[20:49:47.812] <TB0> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1071_FullQualification_2016-11-03_14h08m_1478178483//001_Fulltest_m20//dacParameters35_C0.dat
[20:49:47.812] <TB0> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1071_FullQualification_2016-11-03_14h08m_1478178483//001_Fulltest_m20//dacParameters35_C1.dat
[20:49:47.812] <TB0> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1071_FullQualification_2016-11-03_14h08m_1478178483//001_Fulltest_m20//dacParameters35_C2.dat
[20:49:47.813] <TB0> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1071_FullQualification_2016-11-03_14h08m_1478178483//001_Fulltest_m20//dacParameters35_C3.dat
[20:49:47.813] <TB0> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1071_FullQualification_2016-11-03_14h08m_1478178483//001_Fulltest_m20//dacParameters35_C4.dat
[20:49:47.813] <TB0> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1071_FullQualification_2016-11-03_14h08m_1478178483//001_Fulltest_m20//dacParameters35_C5.dat
[20:49:47.813] <TB0> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1071_FullQualification_2016-11-03_14h08m_1478178483//001_Fulltest_m20//dacParameters35_C6.dat
[20:49:47.813] <TB0> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1071_FullQualification_2016-11-03_14h08m_1478178483//001_Fulltest_m20//dacParameters35_C7.dat
[20:49:47.813] <TB0> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1071_FullQualification_2016-11-03_14h08m_1478178483//001_Fulltest_m20//dacParameters35_C8.dat
[20:49:47.813] <TB0> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1071_FullQualification_2016-11-03_14h08m_1478178483//001_Fulltest_m20//dacParameters35_C9.dat
[20:49:47.813] <TB0> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1071_FullQualification_2016-11-03_14h08m_1478178483//001_Fulltest_m20//dacParameters35_C10.dat
[20:49:47.813] <TB0> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1071_FullQualification_2016-11-03_14h08m_1478178483//001_Fulltest_m20//dacParameters35_C11.dat
[20:49:47.813] <TB0> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1071_FullQualification_2016-11-03_14h08m_1478178483//001_Fulltest_m20//dacParameters35_C12.dat
[20:49:47.813] <TB0> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1071_FullQualification_2016-11-03_14h08m_1478178483//001_Fulltest_m20//dacParameters35_C13.dat
[20:49:47.813] <TB0> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1071_FullQualification_2016-11-03_14h08m_1478178483//001_Fulltest_m20//dacParameters35_C14.dat
[20:49:47.813] <TB0> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1071_FullQualification_2016-11-03_14h08m_1478178483//001_Fulltest_m20//dacParameters35_C15.dat
[20:49:48.055] <TB0> INFO: Expecting 41600 events.
[20:49:51.191] <TB0> INFO: 41600 events read in total (2544ms).
[20:49:51.192] <TB0> INFO: Test took 3376ms.
[20:49:51.722] <TB0> INFO: Expecting 41600 events.
[20:49:54.789] <TB0> INFO: 41600 events read in total (2475ms).
[20:49:54.790] <TB0> INFO: Test took 3386ms.
[20:49:55.313] <TB0> INFO: Expecting 41600 events.
[20:49:58.533] <TB0> INFO: 41600 events read in total (2628ms).
[20:49:58.534] <TB0> INFO: Test took 3532ms.
[20:49:58.749] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[20:49:58.837] <TB0> INFO: Expecting 2560 events.
[20:49:59.730] <TB0> INFO: 2560 events read in total (301ms).
[20:49:59.730] <TB0> INFO: Test took 981ms.
[20:49:59.733] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[20:50:00.038] <TB0> INFO: Expecting 2560 events.
[20:50:00.925] <TB0> INFO: 2560 events read in total (295ms).
[20:50:00.926] <TB0> INFO: Test took 1193ms.
[20:50:00.928] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[20:50:01.234] <TB0> INFO: Expecting 2560 events.
[20:50:02.122] <TB0> INFO: 2560 events read in total (296ms).
[20:50:02.123] <TB0> INFO: Test took 1195ms.
[20:50:02.125] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[20:50:02.431] <TB0> INFO: Expecting 2560 events.
[20:50:03.323] <TB0> INFO: 2560 events read in total (300ms).
[20:50:03.323] <TB0> INFO: Test took 1198ms.
[20:50:03.325] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[20:50:03.632] <TB0> INFO: Expecting 2560 events.
[20:50:04.519] <TB0> INFO: 2560 events read in total (295ms).
[20:50:04.519] <TB0> INFO: Test took 1194ms.
[20:50:04.521] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[20:50:04.828] <TB0> INFO: Expecting 2560 events.
[20:50:05.715] <TB0> INFO: 2560 events read in total (295ms).
[20:50:05.715] <TB0> INFO: Test took 1194ms.
[20:50:05.718] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[20:50:06.023] <TB0> INFO: Expecting 2560 events.
[20:50:06.908] <TB0> INFO: 2560 events read in total (293ms).
[20:50:06.908] <TB0> INFO: Test took 1190ms.
[20:50:06.910] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[20:50:07.217] <TB0> INFO: Expecting 2560 events.
[20:50:08.101] <TB0> INFO: 2560 events read in total (292ms).
[20:50:08.101] <TB0> INFO: Test took 1191ms.
[20:50:08.104] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[20:50:08.410] <TB0> INFO: Expecting 2560 events.
[20:50:09.296] <TB0> INFO: 2560 events read in total (294ms).
[20:50:09.297] <TB0> INFO: Test took 1193ms.
[20:50:09.299] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[20:50:09.605] <TB0> INFO: Expecting 2560 events.
[20:50:10.488] <TB0> INFO: 2560 events read in total (291ms).
[20:50:10.488] <TB0> INFO: Test took 1189ms.
[20:50:10.490] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[20:50:10.796] <TB0> INFO: Expecting 2560 events.
[20:50:11.685] <TB0> INFO: 2560 events read in total (297ms).
[20:50:11.685] <TB0> INFO: Test took 1195ms.
[20:50:11.688] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[20:50:11.994] <TB0> INFO: Expecting 2560 events.
[20:50:12.882] <TB0> INFO: 2560 events read in total (297ms).
[20:50:12.882] <TB0> INFO: Test took 1194ms.
[20:50:12.884] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[20:50:13.191] <TB0> INFO: Expecting 2560 events.
[20:50:14.079] <TB0> INFO: 2560 events read in total (296ms).
[20:50:14.080] <TB0> INFO: Test took 1196ms.
[20:50:14.082] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[20:50:14.388] <TB0> INFO: Expecting 2560 events.
[20:50:15.272] <TB0> INFO: 2560 events read in total (292ms).
[20:50:15.272] <TB0> INFO: Test took 1190ms.
[20:50:15.275] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[20:50:15.579] <TB0> INFO: Expecting 2560 events.
[20:50:16.461] <TB0> INFO: 2560 events read in total (290ms).
[20:50:16.461] <TB0> INFO: Test took 1186ms.
[20:50:16.463] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[20:50:16.769] <TB0> INFO: Expecting 2560 events.
[20:50:17.654] <TB0> INFO: 2560 events read in total (293ms).
[20:50:17.654] <TB0> INFO: Test took 1191ms.
[20:50:17.656] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[20:50:17.962] <TB0> INFO: Expecting 2560 events.
[20:50:18.849] <TB0> INFO: 2560 events read in total (295ms).
[20:50:18.849] <TB0> INFO: Test took 1193ms.
[20:50:18.851] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[20:50:19.157] <TB0> INFO: Expecting 2560 events.
[20:50:20.042] <TB0> INFO: 2560 events read in total (294ms).
[20:50:20.042] <TB0> INFO: Test took 1191ms.
[20:50:20.044] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[20:50:20.350] <TB0> INFO: Expecting 2560 events.
[20:50:21.231] <TB0> INFO: 2560 events read in total (289ms).
[20:50:21.232] <TB0> INFO: Test took 1188ms.
[20:50:21.234] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[20:50:21.539] <TB0> INFO: Expecting 2560 events.
[20:50:22.420] <TB0> INFO: 2560 events read in total (289ms).
[20:50:22.420] <TB0> INFO: Test took 1186ms.
[20:50:22.422] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[20:50:22.728] <TB0> INFO: Expecting 2560 events.
[20:50:23.609] <TB0> INFO: 2560 events read in total (289ms).
[20:50:23.609] <TB0> INFO: Test took 1187ms.
[20:50:23.612] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[20:50:23.918] <TB0> INFO: Expecting 2560 events.
[20:50:24.799] <TB0> INFO: 2560 events read in total (289ms).
[20:50:24.799] <TB0> INFO: Test took 1187ms.
[20:50:24.801] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[20:50:25.107] <TB0> INFO: Expecting 2560 events.
[20:50:25.987] <TB0> INFO: 2560 events read in total (288ms).
[20:50:25.987] <TB0> INFO: Test took 1186ms.
[20:50:25.990] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[20:50:26.296] <TB0> INFO: Expecting 2560 events.
[20:50:27.176] <TB0> INFO: 2560 events read in total (288ms).
[20:50:27.177] <TB0> INFO: Test took 1188ms.
[20:50:27.180] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[20:50:27.485] <TB0> INFO: Expecting 2560 events.
[20:50:28.375] <TB0> INFO: 2560 events read in total (298ms).
[20:50:28.375] <TB0> INFO: Test took 1195ms.
[20:50:28.377] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[20:50:28.684] <TB0> INFO: Expecting 2560 events.
[20:50:29.568] <TB0> INFO: 2560 events read in total (293ms).
[20:50:29.569] <TB0> INFO: Test took 1192ms.
[20:50:29.572] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[20:50:29.877] <TB0> INFO: Expecting 2560 events.
[20:50:30.762] <TB0> INFO: 2560 events read in total (294ms).
[20:50:30.762] <TB0> INFO: Test took 1190ms.
[20:50:30.765] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[20:50:31.070] <TB0> INFO: Expecting 2560 events.
[20:50:31.960] <TB0> INFO: 2560 events read in total (298ms).
[20:50:31.961] <TB0> INFO: Test took 1196ms.
[20:50:31.963] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[20:50:32.269] <TB0> INFO: Expecting 2560 events.
[20:50:33.159] <TB0> INFO: 2560 events read in total (299ms).
[20:50:33.159] <TB0> INFO: Test took 1196ms.
[20:50:33.162] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[20:50:33.467] <TB0> INFO: Expecting 2560 events.
[20:50:34.351] <TB0> INFO: 2560 events read in total (292ms).
[20:50:34.351] <TB0> INFO: Test took 1189ms.
[20:50:34.355] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[20:50:34.660] <TB0> INFO: Expecting 2560 events.
[20:50:35.550] <TB0> INFO: 2560 events read in total (299ms).
[20:50:35.550] <TB0> INFO: Test took 1195ms.
[20:50:35.553] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[20:50:35.859] <TB0> INFO: Expecting 2560 events.
[20:50:36.743] <TB0> INFO: 2560 events read in total (293ms).
[20:50:36.743] <TB0> INFO: Test took 1191ms.
[20:50:37.203] <TB0> INFO: PixTestPhOptimization::doTest() done, duration: 668 seconds
[20:50:37.203] <TB0> INFO: PH scale (per ROC): 37 58 51 34 52 48 66 53 51 53 55 49 48 48 32 58
[20:50:37.203] <TB0> INFO: PH offset (per ROC): 110 126 110 106 101 131 131 125 136 122 133 133 86 90 95 116
[20:50:37.210] <TB0> INFO: Decoding statistics:
[20:50:37.210] <TB0> INFO: General information:
[20:50:37.210] <TB0> INFO: 16bit words read: 127874
[20:50:37.210] <TB0> INFO: valid events total: 20480
[20:50:37.210] <TB0> INFO: empty events: 17983
[20:50:37.210] <TB0> INFO: valid events with pixels: 2497
[20:50:37.210] <TB0> INFO: valid pixel hits: 2497
[20:50:37.210] <TB0> INFO: Event errors: 0
[20:50:37.210] <TB0> INFO: start marker: 0
[20:50:37.210] <TB0> INFO: stop marker: 0
[20:50:37.210] <TB0> INFO: overflow: 0
[20:50:37.210] <TB0> INFO: invalid 5bit words: 0
[20:50:37.210] <TB0> INFO: invalid XOR eye diagram: 0
[20:50:37.210] <TB0> INFO: frame (failed synchr.): 0
[20:50:37.210] <TB0> INFO: idle data (no TBM trl): 0
[20:50:37.210] <TB0> INFO: no data (only TBM hdr): 0
[20:50:37.210] <TB0> INFO: TBM errors: 0
[20:50:37.210] <TB0> INFO: flawed TBM headers: 0
[20:50:37.210] <TB0> INFO: flawed TBM trailers: 0
[20:50:37.210] <TB0> INFO: event ID mismatches: 0
[20:50:37.210] <TB0> INFO: ROC errors: 0
[20:50:37.210] <TB0> INFO: missing ROC header(s): 0
[20:50:37.210] <TB0> INFO: misplaced readback start: 0
[20:50:37.210] <TB0> INFO: Pixel decoding errors: 0
[20:50:37.210] <TB0> INFO: pixel data incomplete: 0
[20:50:37.210] <TB0> INFO: pixel address: 0
[20:50:37.210] <TB0> INFO: pulse height fill bit: 0
[20:50:37.210] <TB0> INFO: buffer corruption: 0
[20:50:37.375] <TB0> INFO: ######################################################################
[20:50:37.375] <TB0> INFO: PixTestGainPedestal::fullTest() ntrig = 10
[20:50:37.375] <TB0> INFO: ######################################################################
[20:50:37.390] <TB0> INFO: scanning low vcal = 10
[20:50:37.625] <TB0> INFO: Expecting 41600 events.
[20:50:41.196] <TB0> INFO: 41600 events read in total (2980ms).
[20:50:41.197] <TB0> INFO: Test took 3807ms.
[20:50:41.199] <TB0> INFO: scanning low vcal = 20
[20:50:41.498] <TB0> INFO: Expecting 41600 events.
[20:50:45.078] <TB0> INFO: 41600 events read in total (2989ms).
[20:50:45.078] <TB0> INFO: Test took 3879ms.
[20:50:45.080] <TB0> INFO: scanning low vcal = 30
[20:50:45.373] <TB0> INFO: Expecting 41600 events.
[20:50:49.134] <TB0> INFO: 41600 events read in total (3169ms).
[20:50:49.135] <TB0> INFO: Test took 4055ms.
[20:50:49.138] <TB0> INFO: scanning low vcal = 40
[20:50:49.456] <TB0> INFO: Expecting 41600 events.
[20:50:53.381] <TB0> INFO: 41600 events read in total (3333ms).
[20:50:53.382] <TB0> INFO: Test took 4244ms.
[20:50:53.385] <TB0> INFO: scanning low vcal = 50
[20:50:53.663] <TB0> INFO: Expecting 41600 events.
[20:50:57.621] <TB0> INFO: 41600 events read in total (3367ms).
[20:50:57.622] <TB0> INFO: Test took 4236ms.
[20:50:57.625] <TB0> INFO: scanning low vcal = 60
[20:50:57.902] <TB0> INFO: Expecting 41600 events.
[20:51:01.859] <TB0> INFO: 41600 events read in total (3365ms).
[20:51:01.859] <TB0> INFO: Test took 4233ms.
[20:51:01.862] <TB0> INFO: scanning low vcal = 70
[20:51:02.139] <TB0> INFO: Expecting 41600 events.
[20:51:06.104] <TB0> INFO: 41600 events read in total (3372ms).
[20:51:06.105] <TB0> INFO: Test took 4242ms.
[20:51:06.108] <TB0> INFO: scanning low vcal = 80
[20:51:06.385] <TB0> INFO: Expecting 41600 events.
[20:51:10.341] <TB0> INFO: 41600 events read in total (3364ms).
[20:51:10.342] <TB0> INFO: Test took 4234ms.
[20:51:10.345] <TB0> INFO: scanning low vcal = 90
[20:51:10.622] <TB0> INFO: Expecting 41600 events.
[20:51:14.577] <TB0> INFO: 41600 events read in total (3363ms).
[20:51:14.578] <TB0> INFO: Test took 4233ms.
[20:51:14.582] <TB0> INFO: scanning low vcal = 100
[20:51:14.858] <TB0> INFO: Expecting 41600 events.
[20:51:18.812] <TB0> INFO: 41600 events read in total (3362ms).
[20:51:18.813] <TB0> INFO: Test took 4231ms.
[20:51:18.816] <TB0> INFO: scanning low vcal = 110
[20:51:19.093] <TB0> INFO: Expecting 41600 events.
[20:51:23.051] <TB0> INFO: 41600 events read in total (3366ms).
[20:51:23.052] <TB0> INFO: Test took 4236ms.
[20:51:23.055] <TB0> INFO: scanning low vcal = 120
[20:51:23.332] <TB0> INFO: Expecting 41600 events.
[20:51:27.285] <TB0> INFO: 41600 events read in total (3361ms).
[20:51:27.286] <TB0> INFO: Test took 4231ms.
[20:51:27.289] <TB0> INFO: scanning low vcal = 130
[20:51:27.566] <TB0> INFO: Expecting 41600 events.
[20:51:31.518] <TB0> INFO: 41600 events read in total (3360ms).
[20:51:31.519] <TB0> INFO: Test took 4230ms.
[20:51:31.522] <TB0> INFO: scanning low vcal = 140
[20:51:31.799] <TB0> INFO: Expecting 41600 events.
[20:51:35.755] <TB0> INFO: 41600 events read in total (3364ms).
[20:51:35.756] <TB0> INFO: Test took 4234ms.
[20:51:35.759] <TB0> INFO: scanning low vcal = 150
[20:51:36.036] <TB0> INFO: Expecting 41600 events.
[20:51:39.993] <TB0> INFO: 41600 events read in total (3365ms).
[20:51:39.994] <TB0> INFO: Test took 4235ms.
[20:51:39.998] <TB0> INFO: scanning low vcal = 160
[20:51:40.275] <TB0> INFO: Expecting 41600 events.
[20:51:44.233] <TB0> INFO: 41600 events read in total (3366ms).
[20:51:44.234] <TB0> INFO: Test took 4236ms.
[20:51:44.237] <TB0> INFO: scanning low vcal = 170
[20:51:44.514] <TB0> INFO: Expecting 41600 events.
[20:51:48.482] <TB0> INFO: 41600 events read in total (3376ms).
[20:51:48.483] <TB0> INFO: Test took 4245ms.
[20:51:48.489] <TB0> INFO: scanning low vcal = 180
[20:51:48.763] <TB0> INFO: Expecting 41600 events.
[20:51:52.719] <TB0> INFO: 41600 events read in total (3364ms).
[20:51:52.720] <TB0> INFO: Test took 4231ms.
[20:51:52.723] <TB0> INFO: scanning low vcal = 190
[20:51:52.000] <TB0> INFO: Expecting 41600 events.
[20:51:56.963] <TB0> INFO: 41600 events read in total (3371ms).
[20:51:56.964] <TB0> INFO: Test took 4241ms.
[20:51:56.967] <TB0> INFO: scanning low vcal = 200
[20:51:57.244] <TB0> INFO: Expecting 41600 events.
[20:52:01.200] <TB0> INFO: 41600 events read in total (3364ms).
[20:52:01.201] <TB0> INFO: Test took 4234ms.
[20:52:01.203] <TB0> INFO: scanning low vcal = 210
[20:52:01.481] <TB0> INFO: Expecting 41600 events.
[20:52:05.434] <TB0> INFO: 41600 events read in total (3361ms).
[20:52:05.435] <TB0> INFO: Test took 4232ms.
[20:52:05.438] <TB0> INFO: scanning low vcal = 220
[20:52:05.715] <TB0> INFO: Expecting 41600 events.
[20:52:09.672] <TB0> INFO: 41600 events read in total (3365ms).
[20:52:09.673] <TB0> INFO: Test took 4235ms.
[20:52:09.676] <TB0> INFO: scanning low vcal = 230
[20:52:09.953] <TB0> INFO: Expecting 41600 events.
[20:52:13.910] <TB0> INFO: 41600 events read in total (3366ms).
[20:52:13.910] <TB0> INFO: Test took 4234ms.
[20:52:13.914] <TB0> INFO: scanning low vcal = 240
[20:52:14.191] <TB0> INFO: Expecting 41600 events.
[20:52:18.147] <TB0> INFO: 41600 events read in total (3364ms).
[20:52:18.147] <TB0> INFO: Test took 4233ms.
[20:52:18.150] <TB0> INFO: scanning low vcal = 250
[20:52:18.427] <TB0> INFO: Expecting 41600 events.
[20:52:22.385] <TB0> INFO: 41600 events read in total (3366ms).
[20:52:22.386] <TB0> INFO: Test took 4236ms.
[20:52:22.390] <TB0> INFO: scanning high vcal = 30 (= 210 in low range)
[20:52:22.666] <TB0> INFO: Expecting 41600 events.
[20:52:26.635] <TB0> INFO: 41600 events read in total (3377ms).
[20:52:26.636] <TB0> INFO: Test took 4246ms.
[20:52:26.639] <TB0> INFO: scanning high vcal = 50 (= 350 in low range)
[20:52:26.916] <TB0> INFO: Expecting 41600 events.
[20:52:30.870] <TB0> INFO: 41600 events read in total (3362ms).
[20:52:30.871] <TB0> INFO: Test took 4232ms.
[20:52:30.874] <TB0> INFO: scanning high vcal = 70 (= 490 in low range)
[20:52:31.151] <TB0> INFO: Expecting 41600 events.
[20:52:35.107] <TB0> INFO: 41600 events read in total (3364ms).
[20:52:35.108] <TB0> INFO: Test took 4233ms.
[20:52:35.112] <TB0> INFO: scanning high vcal = 90 (= 630 in low range)
[20:52:35.388] <TB0> INFO: Expecting 41600 events.
[20:52:39.345] <TB0> INFO: 41600 events read in total (3365ms).
[20:52:39.346] <TB0> INFO: Test took 4234ms.
[20:52:39.349] <TB0> INFO: scanning high vcal = 200 (= 1400 in low range)
[20:52:39.626] <TB0> INFO: Expecting 41600 events.
[20:52:43.585] <TB0> INFO: 41600 events read in total (3367ms).
[20:52:43.586] <TB0> INFO: Test took 4237ms.
[20:52:43.972] <TB0> INFO: PixTestGainPedestal::measure() done
[20:53:17.401] <TB0> INFO: PixTestGainPedestal::fit() done
[20:53:17.401] <TB0> INFO: non-linearity mean: 0.923 0.987 0.974 1.036 0.961 0.981 0.985 0.975 0.981 0.981 0.985 0.981 0.943 0.948 1.025 0.974
[20:53:17.401] <TB0> INFO: non-linearity RMS: 0.150 0.002 0.015 0.153 0.024 0.006 0.003 0.005 0.004 0.003 0.003 0.006 0.078 0.063 0.177 0.014
[20:53:17.401] <TB0> INFO: write gain/ped parameters into /home/l_tester/david/FullQualification/data/M1071_FullQualification_2016-11-03_14h08m_1478178483//001_Fulltest_m20//phCalibrationFitErr35_C0.dat
[20:53:17.415] <TB0> INFO: write gain/ped parameters into /home/l_tester/david/FullQualification/data/M1071_FullQualification_2016-11-03_14h08m_1478178483//001_Fulltest_m20//phCalibrationFitErr35_C1.dat
[20:53:17.428] <TB0> INFO: write gain/ped parameters into /home/l_tester/david/FullQualification/data/M1071_FullQualification_2016-11-03_14h08m_1478178483//001_Fulltest_m20//phCalibrationFitErr35_C2.dat
[20:53:17.442] <TB0> INFO: write gain/ped parameters into /home/l_tester/david/FullQualification/data/M1071_FullQualification_2016-11-03_14h08m_1478178483//001_Fulltest_m20//phCalibrationFitErr35_C3.dat
[20:53:17.456] <TB0> INFO: write gain/ped parameters into /home/l_tester/david/FullQualification/data/M1071_FullQualification_2016-11-03_14h08m_1478178483//001_Fulltest_m20//phCalibrationFitErr35_C4.dat
[20:53:17.470] <TB0> INFO: write gain/ped parameters into /home/l_tester/david/FullQualification/data/M1071_FullQualification_2016-11-03_14h08m_1478178483//001_Fulltest_m20//phCalibrationFitErr35_C5.dat
[20:53:17.483] <TB0> INFO: write gain/ped parameters into /home/l_tester/david/FullQualification/data/M1071_FullQualification_2016-11-03_14h08m_1478178483//001_Fulltest_m20//phCalibrationFitErr35_C6.dat
[20:53:17.497] <TB0> INFO: write gain/ped parameters into /home/l_tester/david/FullQualification/data/M1071_FullQualification_2016-11-03_14h08m_1478178483//001_Fulltest_m20//phCalibrationFitErr35_C7.dat
[20:53:17.510] <TB0> INFO: write gain/ped parameters into /home/l_tester/david/FullQualification/data/M1071_FullQualification_2016-11-03_14h08m_1478178483//001_Fulltest_m20//phCalibrationFitErr35_C8.dat
[20:53:17.523] <TB0> INFO: write gain/ped parameters into /home/l_tester/david/FullQualification/data/M1071_FullQualification_2016-11-03_14h08m_1478178483//001_Fulltest_m20//phCalibrationFitErr35_C9.dat
[20:53:17.537] <TB0> INFO: write gain/ped parameters into /home/l_tester/david/FullQualification/data/M1071_FullQualification_2016-11-03_14h08m_1478178483//001_Fulltest_m20//phCalibrationFitErr35_C10.dat
[20:53:17.550] <TB0> INFO: write gain/ped parameters into /home/l_tester/david/FullQualification/data/M1071_FullQualification_2016-11-03_14h08m_1478178483//001_Fulltest_m20//phCalibrationFitErr35_C11.dat
[20:53:17.564] <TB0> INFO: write gain/ped parameters into /home/l_tester/david/FullQualification/data/M1071_FullQualification_2016-11-03_14h08m_1478178483//001_Fulltest_m20//phCalibrationFitErr35_C12.dat
[20:53:17.577] <TB0> INFO: write gain/ped parameters into /home/l_tester/david/FullQualification/data/M1071_FullQualification_2016-11-03_14h08m_1478178483//001_Fulltest_m20//phCalibrationFitErr35_C13.dat
[20:53:17.591] <TB0> INFO: write gain/ped parameters into /home/l_tester/david/FullQualification/data/M1071_FullQualification_2016-11-03_14h08m_1478178483//001_Fulltest_m20//phCalibrationFitErr35_C14.dat
[20:53:17.605] <TB0> INFO: write gain/ped parameters into /home/l_tester/david/FullQualification/data/M1071_FullQualification_2016-11-03_14h08m_1478178483//001_Fulltest_m20//phCalibrationFitErr35_C15.dat
[20:53:17.618] <TB0> INFO: PixTestGainPedestal::fullTest() done, duration: 160 seconds
[20:53:17.618] <TB0> INFO: Decoding statistics:
[20:53:17.618] <TB0> INFO: General information:
[20:53:17.618] <TB0> INFO: 16bit words read: 3327260
[20:53:17.618] <TB0> INFO: valid events total: 332800
[20:53:17.618] <TB0> INFO: empty events: 0
[20:53:17.618] <TB0> INFO: valid events with pixels: 332800
[20:53:17.618] <TB0> INFO: valid pixel hits: 665230
[20:53:17.618] <TB0> INFO: Event errors: 0
[20:53:17.619] <TB0> INFO: start marker: 0
[20:53:17.619] <TB0> INFO: stop marker: 0
[20:53:17.619] <TB0> INFO: overflow: 0
[20:53:17.619] <TB0> INFO: invalid 5bit words: 0
[20:53:17.619] <TB0> INFO: invalid XOR eye diagram: 0
[20:53:17.619] <TB0> INFO: frame (failed synchr.): 0
[20:53:17.619] <TB0> INFO: idle data (no TBM trl): 0
[20:53:17.619] <TB0> INFO: no data (only TBM hdr): 0
[20:53:17.619] <TB0> INFO: TBM errors: 0
[20:53:17.619] <TB0> INFO: flawed TBM headers: 0
[20:53:17.619] <TB0> INFO: flawed TBM trailers: 0
[20:53:17.619] <TB0> INFO: event ID mismatches: 0
[20:53:17.619] <TB0> INFO: ROC errors: 0
[20:53:17.619] <TB0> INFO: missing ROC header(s): 0
[20:53:17.619] <TB0> INFO: misplaced readback start: 0
[20:53:17.619] <TB0> INFO: Pixel decoding errors: 0
[20:53:17.619] <TB0> INFO: pixel data incomplete: 0
[20:53:17.619] <TB0> INFO: pixel address: 0
[20:53:17.619] <TB0> INFO: pulse height fill bit: 0
[20:53:17.619] <TB0> INFO: buffer corruption: 0
[20:53:17.635] <TB0> INFO: Decoding statistics:
[20:53:17.635] <TB0> INFO: General information:
[20:53:17.635] <TB0> INFO: 16bit words read: 3456670
[20:53:17.635] <TB0> INFO: valid events total: 353536
[20:53:17.635] <TB0> INFO: empty events: 18239
[20:53:17.636] <TB0> INFO: valid events with pixels: 335297
[20:53:17.636] <TB0> INFO: valid pixel hits: 667727
[20:53:17.636] <TB0> INFO: Event errors: 0
[20:53:17.636] <TB0> INFO: start marker: 0
[20:53:17.636] <TB0> INFO: stop marker: 0
[20:53:17.636] <TB0> INFO: overflow: 0
[20:53:17.636] <TB0> INFO: invalid 5bit words: 0
[20:53:17.636] <TB0> INFO: invalid XOR eye diagram: 0
[20:53:17.636] <TB0> INFO: frame (failed synchr.): 0
[20:53:17.636] <TB0> INFO: idle data (no TBM trl): 0
[20:53:17.636] <TB0> INFO: no data (only TBM hdr): 0
[20:53:17.636] <TB0> INFO: TBM errors: 0
[20:53:17.636] <TB0> INFO: flawed TBM headers: 0
[20:53:17.636] <TB0> INFO: flawed TBM trailers: 0
[20:53:17.636] <TB0> INFO: event ID mismatches: 0
[20:53:17.636] <TB0> INFO: ROC errors: 0
[20:53:17.636] <TB0> INFO: missing ROC header(s): 0
[20:53:17.636] <TB0> INFO: misplaced readback start: 0
[20:53:17.636] <TB0> INFO: Pixel decoding errors: 0
[20:53:17.636] <TB0> INFO: pixel data incomplete: 0
[20:53:17.636] <TB0> INFO: pixel address: 0
[20:53:17.636] <TB0> INFO: pulse height fill bit: 0
[20:53:17.636] <TB0> INFO: buffer corruption: 0
[20:53:17.636] <TB0> INFO: enter test to run
[20:53:17.636] <TB0> INFO: test: exit no parameter change
[20:53:17.763] <TB0> QUIET: Connection to board 71 closed.
[20:53:17.764] <TB0> INFO: pXar: this is the end, my friend