[18:11:53.858] <TB3> INFO: *** Welcome to pxar ***
[18:11:53.858] <TB3> INFO: *** Today: 2016/11/04
[18:11:53.865] <TB3> INFO: *** Version: c8ba-dirty
[18:11:53.865] <TB3> INFO: readRocDacs: /home/l_tester/david/FullQualification/data/M1033_FullQualification_2016-11-04_17h17m_1478276226//001_Fulltest_m20//dacParameters_C0.dat .. /home/l_tester/david/FullQualification/data/M1033_FullQualification_2016-11-04_17h17m_1478276226//001_Fulltest_m20//dacParameters_C15.dat
[18:11:53.867] <TB3> INFO: readTbmDacs: /home/l_tester/david/FullQualification/data/M1033_FullQualification_2016-11-04_17h17m_1478276226//001_Fulltest_m20//tbmParameters_C0a.dat .. /home/l_tester/david/FullQualification/data/M1033_FullQualification_2016-11-04_17h17m_1478276226//001_Fulltest_m20//tbmParameters_C1b.dat
[18:11:53.867] <TB3> INFO: readMaskFile: /home/l_tester/david/FullQualification/data/M1033_FullQualification_2016-11-04_17h17m_1478276226//001_Fulltest_m20//defaultMaskFile.dat
[18:11:53.867] <TB3> INFO: readTrimFile: /home/l_tester/david/FullQualification/data/M1033_FullQualification_2016-11-04_17h17m_1478276226//001_Fulltest_m20//trimParameters_C0.dat .. /home/l_tester/david/FullQualification/data/M1033_FullQualification_2016-11-04_17h17m_1478276226//001_Fulltest_m20//trimParameters_C15.dat
[18:11:53.955] <TB3> INFO: clk: 4
[18:11:53.955] <TB3> INFO: ctr: 4
[18:11:53.955] <TB3> INFO: sda: 19
[18:11:53.955] <TB3> INFO: tin: 9
[18:11:53.955] <TB3> INFO: level: 15
[18:11:53.955] <TB3> INFO: triggerdelay: 0
[18:11:53.955] <TB3> QUIET: Instanciating API for pxar v2.7.6+61~g7f4a123
[18:11:53.956] <TB3> INFO: Log level: INFO
[18:11:53.965] <TB3> INFO: Found DTB DTB_WWVASW
[18:11:53.981] <TB3> QUIET: Connection to board DTB_WWVASW opened.
[18:11:53.983] <TB3> INFO: DTB startup information
--- DTB info------------------------------------------
Board id: 126
HW version: DTB1.2
FW version: 4.6
SW version: 4.7
Options:
USB id: DTB_WWVASW
MAC address: 40D85511807E
Hostname: pixelDTB126
Comment:
------------------------------------------------------
[18:11:53.985] <TB3> INFO: RPC call hashes of host and DTB match: 486171790
[18:11:55.484] <TB3> INFO: DUT info:
[18:11:55.484] <TB3> INFO: The DUT currently contains the following objects:
[18:11:55.484] <TB3> INFO: 4 TBM Cores tbm10c (4 ON)
[18:11:55.484] <TB3> INFO: TBM Core alpha (0): 7 registers set
[18:11:55.484] <TB3> INFO: TBM Core beta (1): 7 registers set
[18:11:55.484] <TB3> INFO: TBM Core alpha (2): 7 registers set
[18:11:55.484] <TB3> INFO: TBM Core beta (3): 7 registers set
[18:11:55.484] <TB3> INFO: 16 ROCs proc600 (16 ON) with 4160 pixelConfigs
[18:11:55.484] <TB3> INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[18:11:55.484] <TB3> INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[18:11:55.484] <TB3> INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[18:11:55.484] <TB3> INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[18:11:55.484] <TB3> INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[18:11:55.484] <TB3> INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[18:11:55.484] <TB3> INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[18:11:55.484] <TB3> INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[18:11:55.484] <TB3> INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[18:11:55.484] <TB3> INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[18:11:55.484] <TB3> INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[18:11:55.484] <TB3> INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[18:11:55.484] <TB3> INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[18:11:55.484] <TB3> INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[18:11:55.484] <TB3> INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[18:11:55.484] <TB3> INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[18:11:55.885] <TB3> INFO: enter 'restricted' command line mode
[18:11:55.885] <TB3> INFO: enter test to run
[18:11:55.885] <TB3> INFO: test: pretest no parameter change
[18:11:55.885] <TB3> INFO: running: pretest
[18:11:55.892] <TB3> INFO: ######################################################################
[18:11:55.892] <TB3> INFO: PixTestPretest::doTest()
[18:11:55.892] <TB3> INFO: ######################################################################
[18:11:55.894] <TB3> INFO: ----------------------------------------------------------------------
[18:11:55.894] <TB3> INFO: PixTestPretest::programROC()
[18:11:55.894] <TB3> INFO: ----------------------------------------------------------------------
[18:12:13.908] <TB3> INFO: PixTestPretest::programROC() done: ROCs are all programmable
[18:12:13.908] <TB3> INFO: IA differences per ROC: 20.9 22.5 19.3 19.3 19.3 20.1 19.3 17.7 16.9 19.3 18.5 22.5 16.9 18.5 17.7 20.1
[18:12:13.971] <TB3> INFO: ----------------------------------------------------------------------
[18:12:13.971] <TB3> INFO: PixTestPretest::setVana() target Ia = 24 mA/ROC
[18:12:13.971] <TB3> INFO: ----------------------------------------------------------------------
[18:12:22.082] <TB3> INFO: PixTestPretest::setVana() done, Module Ia 392.3 mA = 24.5187 mA/ROC
[18:12:22.083] <TB3> INFO: i(loss) [mA/ROC]: 20.9 20.9 20.1 20.1 20.1 20.1 20.1 19.3 20.1 20.1 20.1 20.1 20.1 19.3 20.1 20.1
[18:12:22.120] <TB3> INFO: ----------------------------------------------------------------------
[18:12:22.120] <TB3> INFO: PixTestPretest::findTiming()
[18:12:22.120] <TB3> INFO: ----------------------------------------------------------------------
[18:12:22.120] <TB3> INFO: PixTestCmd::init()

[18:12:53.947] <TB3> INFO: TBM phases: 160MHz: 0, 400MHz: 4, TBM delays: ROC(0/1):5, header/trailer: 1, token: 1
[18:12:53.947] <TB3> INFO: (success/tries = 100/100), width = 3
[18:12:55.453] <TB3> INFO: ----------------------------------------------------------------------
[18:12:55.453] <TB3> INFO: PixTestPretest::findWorkingPixel()
[18:12:55.453] <TB3> INFO: ----------------------------------------------------------------------
[18:12:55.545] <TB3> INFO: Expecting 231680 events.
[18:13:05.546] <TB3> INFO: 231680 events read in total (9409ms).
[18:13:05.553] <TB3> INFO: Test took 10098ms.
[18:13:05.803] <TB3> INFO: Found working pixel in all ROCs: col/row = 12/22
[18:13:05.837] <TB3> INFO: ----------------------------------------------------------------------
[18:13:05.837] <TB3> INFO: PixTestPretest::setVthrCompCalDel()
[18:13:05.837] <TB3> INFO: ----------------------------------------------------------------------
[18:13:05.931] <TB3> INFO: Expecting 231680 events.
[18:13:16.041] <TB3> INFO: 231680 events read in total (9518ms).
[18:13:16.052] <TB3> INFO: Test took 10210ms.
[18:13:16.317] <TB3> INFO: PixTestPretest::setVthrCompCalDel() done
[18:13:16.317] <TB3> INFO: CalDel: 96 111 105 112 103 109 126 103 95 91 87 99 86 110 105 106
[18:13:16.317] <TB3> INFO: VthrComp: 51 54 51 53 51 56 51 51 51 51 51 51 53 51 51 53
[18:13:16.320] <TB3> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1033_FullQualification_2016-11-04_17h17m_1478276226//001_Fulltest_m20//dacParameters_C0.dat
[18:13:16.321] <TB3> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1033_FullQualification_2016-11-04_17h17m_1478276226//001_Fulltest_m20//dacParameters_C1.dat
[18:13:16.321] <TB3> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1033_FullQualification_2016-11-04_17h17m_1478276226//001_Fulltest_m20//dacParameters_C2.dat
[18:13:16.321] <TB3> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1033_FullQualification_2016-11-04_17h17m_1478276226//001_Fulltest_m20//dacParameters_C3.dat
[18:13:16.321] <TB3> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1033_FullQualification_2016-11-04_17h17m_1478276226//001_Fulltest_m20//dacParameters_C4.dat
[18:13:16.321] <TB3> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1033_FullQualification_2016-11-04_17h17m_1478276226//001_Fulltest_m20//dacParameters_C5.dat
[18:13:16.321] <TB3> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1033_FullQualification_2016-11-04_17h17m_1478276226//001_Fulltest_m20//dacParameters_C6.dat
[18:13:16.322] <TB3> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1033_FullQualification_2016-11-04_17h17m_1478276226//001_Fulltest_m20//dacParameters_C7.dat
[18:13:16.322] <TB3> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1033_FullQualification_2016-11-04_17h17m_1478276226//001_Fulltest_m20//dacParameters_C8.dat
[18:13:16.322] <TB3> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1033_FullQualification_2016-11-04_17h17m_1478276226//001_Fulltest_m20//dacParameters_C9.dat
[18:13:16.322] <TB3> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1033_FullQualification_2016-11-04_17h17m_1478276226//001_Fulltest_m20//dacParameters_C10.dat
[18:13:16.322] <TB3> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1033_FullQualification_2016-11-04_17h17m_1478276226//001_Fulltest_m20//dacParameters_C11.dat
[18:13:16.322] <TB3> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1033_FullQualification_2016-11-04_17h17m_1478276226//001_Fulltest_m20//dacParameters_C12.dat
[18:13:16.323] <TB3> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1033_FullQualification_2016-11-04_17h17m_1478276226//001_Fulltest_m20//dacParameters_C13.dat
[18:13:16.323] <TB3> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1033_FullQualification_2016-11-04_17h17m_1478276226//001_Fulltest_m20//dacParameters_C14.dat
[18:13:16.323] <TB3> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1033_FullQualification_2016-11-04_17h17m_1478276226//001_Fulltest_m20//dacParameters_C15.dat
[18:13:16.323] <TB3> INFO: write tbm parameters into /home/l_tester/david/FullQualification/data/M1033_FullQualification_2016-11-04_17h17m_1478276226//001_Fulltest_m20//tbmParameters_C0a.dat
[18:13:16.323] <TB3> INFO: write tbm parameters into /home/l_tester/david/FullQualification/data/M1033_FullQualification_2016-11-04_17h17m_1478276226//001_Fulltest_m20//tbmParameters_C0b.dat
[18:13:16.323] <TB3> INFO: write tbm parameters into /home/l_tester/david/FullQualification/data/M1033_FullQualification_2016-11-04_17h17m_1478276226//001_Fulltest_m20//tbmParameters_C1a.dat
[18:13:16.323] <TB3> INFO: write tbm parameters into /home/l_tester/david/FullQualification/data/M1033_FullQualification_2016-11-04_17h17m_1478276226//001_Fulltest_m20//tbmParameters_C1b.dat
[18:13:16.324] <TB3> INFO: PixTestPretest::doTest() done, duration: 80 seconds
[18:13:16.378] <TB3> INFO: enter test to run
[18:13:16.378] <TB3> INFO: test: FullTest no parameter change
[18:13:16.378] <TB3> INFO: running: fulltest
[18:13:16.378] <TB3> INFO: ######################################################################
[18:13:16.378] <TB3> INFO: PixTestFullTest::doTest()
[18:13:16.378] <TB3> INFO: ######################################################################
[18:13:16.379] <TB3> INFO: ######################################################################
[18:13:16.379] <TB3> INFO: PixTestAlive::doTest()
[18:13:16.379] <TB3> INFO: ######################################################################
[18:13:16.381] <TB3> INFO: ----------------------------------------------------------------------
[18:13:16.381] <TB3> INFO: PixTestAlive::aliveTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[18:13:16.381] <TB3> INFO: ----------------------------------------------------------------------
[18:13:16.649] <TB3> INFO: Expecting 41600 events.
[18:13:20.180] <TB3> INFO: 41600 events read in total (2939ms).
[18:13:20.181] <TB3> INFO: Test took 3799ms.
[18:13:20.414] <TB3> INFO: PixTestAlive::aliveTest() done
[18:13:20.414] <TB3> INFO: number of dead pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[18:13:20.416] <TB3> INFO: ----------------------------------------------------------------------
[18:13:20.416] <TB3> INFO: PixTestAlive::maskTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[18:13:20.416] <TB3> INFO: ----------------------------------------------------------------------
[18:13:20.660] <TB3> INFO: Expecting 41600 events.
[18:13:23.612] <TB3> INFO: 41600 events read in total (2360ms).
[18:13:23.613] <TB3> INFO: Test took 3195ms.
[18:13:23.613] <TB3> INFO: mask vs. old pixelAlive PixelAlive_C0_V0 .. PixelAlive_C15_V0
[18:13:23.850] <TB3> INFO: PixTestAlive::maskTest() done
[18:13:23.850] <TB3> INFO: number of mask-defect pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[18:13:23.852] <TB3> INFO: ----------------------------------------------------------------------
[18:13:23.852] <TB3> INFO: PixTestAlive::addressDecodingTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[18:13:23.852] <TB3> INFO: ----------------------------------------------------------------------
[18:13:24.095] <TB3> INFO: Expecting 41600 events.
[18:13:27.697] <TB3> INFO: 41600 events read in total (3010ms).
[18:13:27.698] <TB3> INFO: Test took 3844ms.
[18:13:27.933] <TB3> INFO: PixTestAlive::addressDecodingTest() done
[18:13:27.933] <TB3> INFO: number of address-decoding pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[18:13:27.934] <TB3> INFO: PixTestAlive::doTest() done, duration: 11 seconds
[18:13:27.934] <TB3> INFO: Decoding statistics:
[18:13:27.934] <TB3> INFO: General information:
[18:13:27.934] <TB3> INFO: 16bit words read: 0
[18:13:27.934] <TB3> INFO: valid events total: 0
[18:13:27.934] <TB3> INFO: empty events: 0
[18:13:27.934] <TB3> INFO: valid events with pixels: 0
[18:13:27.934] <TB3> INFO: valid pixel hits: 0
[18:13:27.934] <TB3> INFO: Event errors: 0
[18:13:27.934] <TB3> INFO: start marker: 0
[18:13:27.934] <TB3> INFO: stop marker: 0
[18:13:27.934] <TB3> INFO: overflow: 0
[18:13:27.934] <TB3> INFO: invalid 5bit words: 0
[18:13:27.934] <TB3> INFO: invalid XOR eye diagram: 0
[18:13:27.934] <TB3> INFO: frame (failed synchr.): 0
[18:13:27.934] <TB3> INFO: idle data (no TBM trl): 0
[18:13:27.934] <TB3> INFO: no data (only TBM hdr): 0
[18:13:27.934] <TB3> INFO: TBM errors: 0
[18:13:27.934] <TB3> INFO: flawed TBM headers: 0
[18:13:27.934] <TB3> INFO: flawed TBM trailers: 0
[18:13:27.934] <TB3> INFO: event ID mismatches: 0
[18:13:27.934] <TB3> INFO: ROC errors: 0
[18:13:27.934] <TB3> INFO: missing ROC header(s): 0
[18:13:27.934] <TB3> INFO: misplaced readback start: 0
[18:13:27.934] <TB3> INFO: Pixel decoding errors: 0
[18:13:27.935] <TB3> INFO: pixel data incomplete: 0
[18:13:27.935] <TB3> INFO: pixel address: 0
[18:13:27.935] <TB3> INFO: pulse height fill bit: 0
[18:13:27.935] <TB3> INFO: buffer corruption: 0
[18:13:27.943] <TB3> INFO: readReadbackCal: /home/l_tester/david/FullQualification/data/M1033_FullQualification_2016-11-04_17h17m_1478276226//001_Fulltest_m20//readbackCal_C0.dat .. /home/l_tester/david/FullQualification/data/M1033_FullQualification_2016-11-04_17h17m_1478276226//001_Fulltest_m20//readbackCal_C15.dat
[18:13:27.944] <TB3> INFO: readGainPedestalParameters /home/l_tester/david/FullQualification/data/M1033_FullQualification_2016-11-04_17h17m_1478276226//001_Fulltest_m20//phCalibrationFitErr_C0.dat .. /home/l_tester/david/FullQualification/data/M1033_FullQualification_2016-11-04_17h17m_1478276226//001_Fulltest_m20//phCalibrationFitErr_C15.dat

[18:13:27.944] <TB3> INFO: ######################################################################
[18:13:27.944] <TB3> INFO: PixTestReadback::doTest()
[18:13:27.944] <TB3> INFO: ######################################################################
[18:13:27.944] <TB3> INFO: ----------------------------------------------------------------------
[18:13:27.944] <TB3> INFO: PixTestReadback::CalibrateVd()
[18:13:27.944] <TB3> INFO: ----------------------------------------------------------------------
[18:13:37.918] <TB3> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1033_FullQualification_2016-11-04_17h17m_1478276226//001_Fulltest_m20//readbackCal_C0.dat
[18:13:37.918] <TB3> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1033_FullQualification_2016-11-04_17h17m_1478276226//001_Fulltest_m20//readbackCal_C1.dat
[18:13:37.918] <TB3> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1033_FullQualification_2016-11-04_17h17m_1478276226//001_Fulltest_m20//readbackCal_C2.dat
[18:13:37.918] <TB3> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1033_FullQualification_2016-11-04_17h17m_1478276226//001_Fulltest_m20//readbackCal_C3.dat
[18:13:37.919] <TB3> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1033_FullQualification_2016-11-04_17h17m_1478276226//001_Fulltest_m20//readbackCal_C4.dat
[18:13:37.919] <TB3> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1033_FullQualification_2016-11-04_17h17m_1478276226//001_Fulltest_m20//readbackCal_C5.dat
[18:13:37.919] <TB3> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1033_FullQualification_2016-11-04_17h17m_1478276226//001_Fulltest_m20//readbackCal_C6.dat
[18:13:37.919] <TB3> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1033_FullQualification_2016-11-04_17h17m_1478276226//001_Fulltest_m20//readbackCal_C7.dat
[18:13:37.919] <TB3> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1033_FullQualification_2016-11-04_17h17m_1478276226//001_Fulltest_m20//readbackCal_C8.dat
[18:13:37.919] <TB3> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1033_FullQualification_2016-11-04_17h17m_1478276226//001_Fulltest_m20//readbackCal_C9.dat
[18:13:37.919] <TB3> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1033_FullQualification_2016-11-04_17h17m_1478276226//001_Fulltest_m20//readbackCal_C10.dat
[18:13:37.919] <TB3> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1033_FullQualification_2016-11-04_17h17m_1478276226//001_Fulltest_m20//readbackCal_C11.dat
[18:13:37.919] <TB3> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1033_FullQualification_2016-11-04_17h17m_1478276226//001_Fulltest_m20//readbackCal_C12.dat
[18:13:37.919] <TB3> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1033_FullQualification_2016-11-04_17h17m_1478276226//001_Fulltest_m20//readbackCal_C13.dat
[18:13:37.919] <TB3> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1033_FullQualification_2016-11-04_17h17m_1478276226//001_Fulltest_m20//readbackCal_C14.dat
[18:13:37.919] <TB3> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1033_FullQualification_2016-11-04_17h17m_1478276226//001_Fulltest_m20//readbackCal_C15.dat
[18:13:37.951] <TB3> INFO: PixTestPattern:: pg_setup set to default.
[18:13:37.951] <TB3> INFO: ----------------------------------------------------------------------
[18:13:37.951] <TB3> INFO: PixTestReadback::CalibrateVa()
[18:13:37.951] <TB3> INFO: ----------------------------------------------------------------------
[18:13:47.890] <TB3> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1033_FullQualification_2016-11-04_17h17m_1478276226//001_Fulltest_m20//readbackCal_C0.dat
[18:13:47.891] <TB3> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1033_FullQualification_2016-11-04_17h17m_1478276226//001_Fulltest_m20//readbackCal_C1.dat
[18:13:47.891] <TB3> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1033_FullQualification_2016-11-04_17h17m_1478276226//001_Fulltest_m20//readbackCal_C2.dat
[18:13:47.891] <TB3> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1033_FullQualification_2016-11-04_17h17m_1478276226//001_Fulltest_m20//readbackCal_C3.dat
[18:13:47.891] <TB3> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1033_FullQualification_2016-11-04_17h17m_1478276226//001_Fulltest_m20//readbackCal_C4.dat
[18:13:47.891] <TB3> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1033_FullQualification_2016-11-04_17h17m_1478276226//001_Fulltest_m20//readbackCal_C5.dat
[18:13:47.891] <TB3> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1033_FullQualification_2016-11-04_17h17m_1478276226//001_Fulltest_m20//readbackCal_C6.dat
[18:13:47.891] <TB3> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1033_FullQualification_2016-11-04_17h17m_1478276226//001_Fulltest_m20//readbackCal_C7.dat
[18:13:47.891] <TB3> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1033_FullQualification_2016-11-04_17h17m_1478276226//001_Fulltest_m20//readbackCal_C8.dat
[18:13:47.891] <TB3> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1033_FullQualification_2016-11-04_17h17m_1478276226//001_Fulltest_m20//readbackCal_C9.dat
[18:13:47.891] <TB3> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1033_FullQualification_2016-11-04_17h17m_1478276226//001_Fulltest_m20//readbackCal_C10.dat
[18:13:47.891] <TB3> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1033_FullQualification_2016-11-04_17h17m_1478276226//001_Fulltest_m20//readbackCal_C11.dat
[18:13:47.891] <TB3> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1033_FullQualification_2016-11-04_17h17m_1478276226//001_Fulltest_m20//readbackCal_C12.dat
[18:13:47.891] <TB3> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1033_FullQualification_2016-11-04_17h17m_1478276226//001_Fulltest_m20//readbackCal_C13.dat
[18:13:47.891] <TB3> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1033_FullQualification_2016-11-04_17h17m_1478276226//001_Fulltest_m20//readbackCal_C14.dat
[18:13:47.891] <TB3> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1033_FullQualification_2016-11-04_17h17m_1478276226//001_Fulltest_m20//readbackCal_C15.dat
[18:13:47.920] <TB3> INFO: PixTestPattern:: pg_setup set to default.
[18:13:47.920] <TB3> INFO: ----------------------------------------------------------------------
[18:13:47.920] <TB3> INFO: PixTestReadback::readbackVbg()
[18:13:47.920] <TB3> INFO: ----------------------------------------------------------------------
[18:13:55.597] <TB3> INFO: PixTestPattern:: pg_setup set to default.
[18:13:55.597] <TB3> INFO: ----------------------------------------------------------------------
[18:13:55.597] <TB3> INFO: PixTestReadback::getCalibratedVbg()
[18:13:55.597] <TB3> INFO: ----------------------------------------------------------------------
[18:13:55.597] <TB3> INFO: Vbg will be calibrated using Vd calibration
[18:13:55.597] <TB3> INFO: /*/*/*/*::: ROC 0: uncalibrated Vbg = 153.2calibrated Vbg = 1.1885 :::*/*/*/*/
[18:13:55.597] <TB3> INFO: /*/*/*/*::: ROC 1: uncalibrated Vbg = 162.2calibrated Vbg = 1.19619 :::*/*/*/*/
[18:13:55.597] <TB3> INFO: /*/*/*/*::: ROC 2: uncalibrated Vbg = 166.3calibrated Vbg = 1.18895 :::*/*/*/*/
[18:13:55.597] <TB3> INFO: /*/*/*/*::: ROC 3: uncalibrated Vbg = 153.1calibrated Vbg = 1.18926 :::*/*/*/*/
[18:13:55.597] <TB3> INFO: /*/*/*/*::: ROC 4: uncalibrated Vbg = 151.8calibrated Vbg = 1.19149 :::*/*/*/*/
[18:13:55.597] <TB3> INFO: /*/*/*/*::: ROC 5: uncalibrated Vbg = 160.2calibrated Vbg = 1.19429 :::*/*/*/*/
[18:13:55.597] <TB3> INFO: /*/*/*/*::: ROC 6: uncalibrated Vbg = 161.1calibrated Vbg = 1.19545 :::*/*/*/*/
[18:13:55.598] <TB3> INFO: /*/*/*/*::: ROC 7: uncalibrated Vbg = 161.9calibrated Vbg = 1.19839 :::*/*/*/*/
[18:13:55.598] <TB3> INFO: /*/*/*/*::: ROC 8: uncalibrated Vbg = 158.2calibrated Vbg = 1.18606 :::*/*/*/*/
[18:13:55.598] <TB3> INFO: /*/*/*/*::: ROC 9: uncalibrated Vbg = 157.5calibrated Vbg = 1.19239 :::*/*/*/*/
[18:13:55.598] <TB3> INFO: /*/*/*/*::: ROC 10: uncalibrated Vbg = 161.8calibrated Vbg = 1.18533 :::*/*/*/*/
[18:13:55.598] <TB3> INFO: /*/*/*/*::: ROC 11: uncalibrated Vbg = 159.2calibrated Vbg = 1.18123 :::*/*/*/*/
[18:13:55.598] <TB3> INFO: /*/*/*/*::: ROC 12: uncalibrated Vbg = 162.7calibrated Vbg = 1.18198 :::*/*/*/*/
[18:13:55.598] <TB3> INFO: /*/*/*/*::: ROC 13: uncalibrated Vbg = 152.1calibrated Vbg = 1.19134 :::*/*/*/*/
[18:13:55.598] <TB3> INFO: /*/*/*/*::: ROC 14: uncalibrated Vbg = 154.4calibrated Vbg = 1.18863 :::*/*/*/*/
[18:13:55.598] <TB3> INFO: /*/*/*/*::: ROC 15: uncalibrated Vbg = 156.8calibrated Vbg = 1.19173 :::*/*/*/*/
[18:13:55.600] <TB3> INFO: ----------------------------------------------------------------------
[18:13:55.600] <TB3> INFO: PixTestReadback::CalibrateIa()
[18:13:55.600] <TB3> INFO: ----------------------------------------------------------------------
[18:16:36.404] <TB3> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1033_FullQualification_2016-11-04_17h17m_1478276226//001_Fulltest_m20//readbackCal_C0.dat
[18:16:36.405] <TB3> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1033_FullQualification_2016-11-04_17h17m_1478276226//001_Fulltest_m20//readbackCal_C1.dat
[18:16:36.405] <TB3> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1033_FullQualification_2016-11-04_17h17m_1478276226//001_Fulltest_m20//readbackCal_C2.dat
[18:16:36.405] <TB3> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1033_FullQualification_2016-11-04_17h17m_1478276226//001_Fulltest_m20//readbackCal_C3.dat
[18:16:36.405] <TB3> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1033_FullQualification_2016-11-04_17h17m_1478276226//001_Fulltest_m20//readbackCal_C4.dat
[18:16:36.405] <TB3> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1033_FullQualification_2016-11-04_17h17m_1478276226//001_Fulltest_m20//readbackCal_C5.dat
[18:16:36.405] <TB3> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1033_FullQualification_2016-11-04_17h17m_1478276226//001_Fulltest_m20//readbackCal_C6.dat
[18:16:36.405] <TB3> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1033_FullQualification_2016-11-04_17h17m_1478276226//001_Fulltest_m20//readbackCal_C7.dat
[18:16:36.405] <TB3> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1033_FullQualification_2016-11-04_17h17m_1478276226//001_Fulltest_m20//readbackCal_C8.dat
[18:16:36.405] <TB3> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1033_FullQualification_2016-11-04_17h17m_1478276226//001_Fulltest_m20//readbackCal_C9.dat
[18:16:36.405] <TB3> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1033_FullQualification_2016-11-04_17h17m_1478276226//001_Fulltest_m20//readbackCal_C10.dat
[18:16:36.405] <TB3> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1033_FullQualification_2016-11-04_17h17m_1478276226//001_Fulltest_m20//readbackCal_C11.dat
[18:16:36.405] <TB3> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1033_FullQualification_2016-11-04_17h17m_1478276226//001_Fulltest_m20//readbackCal_C12.dat
[18:16:36.405] <TB3> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1033_FullQualification_2016-11-04_17h17m_1478276226//001_Fulltest_m20//readbackCal_C13.dat
[18:16:36.406] <TB3> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1033_FullQualification_2016-11-04_17h17m_1478276226//001_Fulltest_m20//readbackCal_C14.dat
[18:16:36.406] <TB3> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1033_FullQualification_2016-11-04_17h17m_1478276226//001_Fulltest_m20//readbackCal_C15.dat
[18:16:36.440] <TB3> INFO: PixTestPattern:: pg_setup set to default.
[18:16:36.441] <TB3> INFO: PixTestReadback::doTest() done
[18:16:36.441] <TB3> INFO: Decoding statistics:
[18:16:36.441] <TB3> INFO: General information:
[18:16:36.441] <TB3> INFO: 16bit words read: 1536
[18:16:36.441] <TB3> INFO: valid events total: 256
[18:16:36.441] <TB3> INFO: empty events: 256
[18:16:36.441] <TB3> INFO: valid events with pixels: 0
[18:16:36.441] <TB3> INFO: valid pixel hits: 0
[18:16:36.441] <TB3> INFO: Event errors: 0
[18:16:36.441] <TB3> INFO: start marker: 0
[18:16:36.441] <TB3> INFO: stop marker: 0
[18:16:36.441] <TB3> INFO: overflow: 0
[18:16:36.441] <TB3> INFO: invalid 5bit words: 0
[18:16:36.441] <TB3> INFO: invalid XOR eye diagram: 0
[18:16:36.441] <TB3> INFO: frame (failed synchr.): 0
[18:16:36.441] <TB3> INFO: idle data (no TBM trl): 0
[18:16:36.441] <TB3> INFO: no data (only TBM hdr): 0
[18:16:36.441] <TB3> INFO: TBM errors: 0
[18:16:36.441] <TB3> INFO: flawed TBM headers: 0
[18:16:36.441] <TB3> INFO: flawed TBM trailers: 0
[18:16:36.441] <TB3> INFO: event ID mismatches: 0
[18:16:36.441] <TB3> INFO: ROC errors: 0
[18:16:36.441] <TB3> INFO: missing ROC header(s): 0
[18:16:36.441] <TB3> INFO: misplaced readback start: 0
[18:16:36.441] <TB3> INFO: Pixel decoding errors: 0
[18:16:36.441] <TB3> INFO: pixel data incomplete: 0
[18:16:36.441] <TB3> INFO: pixel address: 0
[18:16:36.441] <TB3> INFO: pulse height fill bit: 0
[18:16:36.441] <TB3> INFO: buffer corruption: 0
[18:16:36.494] <TB3> INFO: ######################################################################
[18:16:36.494] <TB3> INFO: PixTestBBMap::doTest() Ntrig = 5, VcalS = 250 (high range)
[18:16:36.494] <TB3> INFO: ######################################################################
[18:16:36.497] <TB3> INFO: ---> dac: VthrComp name: calSMap ntrig: 5 dacrange: 0 .. 149 (-1/-1) hits flags = 514 (plus default)
[18:16:36.776] <TB3> INFO: dacScan split into 1 runs with ntrig = 5
[18:16:36.776] <TB3> INFO: run 1 of 1
[18:16:37.018] <TB3> INFO: Expecting 3120000 events.
[18:17:08.609] <TB3> INFO: 669495 events read in total (30999ms).











[18:17:39.105] <TB3> INFO: 1334360 events read in total (61495ms).











[18:18:09.524] <TB3> INFO: 1998605 events read in total (91914ms).











[18:18:40.593] <TB3> INFO: 2665575 events read in total (122983ms).











[18:19:01.455] <TB3> INFO: 3120000 events read in total (143845ms).
[18:19:01.554] <TB3> INFO: Test took 144779ms.
[18:19:26.690] <TB3> INFO: PixTestBBMap::doTest() done with 2 decoding errors: , duration: 170 seconds
[18:19:26.690] <TB3> INFO: number of dead bumps (per ROC): 1 1 0 1 1 1 1 1 4 0 2 0 0 0 0 0
[18:19:26.690] <TB3> INFO: separation cut (per ROC): 107 108 110 111 105 120 98 109 102 107 107 115 117 87 107 109
[18:19:26.690] <TB3> INFO: Decoding statistics:
[18:19:26.690] <TB3> INFO: General information:
[18:19:26.690] <TB3> INFO: 16bit words read: 0
[18:19:26.690] <TB3> INFO: valid events total: 0
[18:19:26.690] <TB3> INFO: empty events: 0
[18:19:26.690] <TB3> INFO: valid events with pixels: 0
[18:19:26.690] <TB3> INFO: valid pixel hits: 0
[18:19:26.690] <TB3> INFO: Event errors: 0
[18:19:26.690] <TB3> INFO: start marker: 0
[18:19:26.690] <TB3> INFO: stop marker: 0
[18:19:26.690] <TB3> INFO: overflow: 0
[18:19:26.690] <TB3> INFO: invalid 5bit words: 0
[18:19:26.690] <TB3> INFO: invalid XOR eye diagram: 0
[18:19:26.690] <TB3> INFO: frame (failed synchr.): 0
[18:19:26.690] <TB3> INFO: idle data (no TBM trl): 0
[18:19:26.690] <TB3> INFO: no data (only TBM hdr): 0
[18:19:26.690] <TB3> INFO: TBM errors: 0
[18:19:26.690] <TB3> INFO: flawed TBM headers: 0
[18:19:26.690] <TB3> INFO: flawed TBM trailers: 0
[18:19:26.690] <TB3> INFO: event ID mismatches: 0
[18:19:26.690] <TB3> INFO: ROC errors: 0
[18:19:26.690] <TB3> INFO: missing ROC header(s): 0
[18:19:26.690] <TB3> INFO: misplaced readback start: 0
[18:19:26.690] <TB3> INFO: Pixel decoding errors: 0
[18:19:26.690] <TB3> INFO: pixel data incomplete: 0
[18:19:26.690] <TB3> INFO: pixel address: 0
[18:19:26.690] <TB3> INFO: pulse height fill bit: 0
[18:19:26.690] <TB3> INFO: buffer corruption: 0
[18:19:26.740] <TB3> INFO: ######################################################################
[18:19:26.740] <TB3> INFO: PixTestScurves::fullTest() ntrig = 50, dacs/step = -1, ntrig/step = -1
[18:19:26.740] <TB3> INFO: ######################################################################
[18:19:26.740] <TB3> INFO: ----------------------------------------------------------------------
[18:19:26.740] <TB3> INFO: PixTestScurves::scurves(Vcal), ntrig = 50, dacs/step = -1, ntrig/step = -1
[18:19:26.740] <TB3> INFO: ----------------------------------------------------------------------
[18:19:26.740] <TB3> INFO: ---> dac: Vcal name: scurveVcal ntrig: 50 dacrange: 25 .. 200 (-1/-1) hits flags = 528 (plus default)
[18:19:26.755] <TB3> INFO: dacScan split into 1 runs with ntrig = 50
[18:19:26.755] <TB3> INFO: run 1 of 1
[18:19:27.007] <TB3> INFO: Expecting 36608000 events.
[18:19:50.519] <TB3> INFO: 676850 events read in total (22920ms).
[18:20:13.172] <TB3> INFO: 1338700 events read in total (45573ms).
[18:20:36.096] <TB3> INFO: 2002750 events read in total (68497ms).
[18:20:58.690] <TB3> INFO: 2663050 events read in total (91091ms).
[18:21:21.558] <TB3> INFO: 3325500 events read in total (113959ms).
[18:21:44.351] <TB3> INFO: 3986350 events read in total (136752ms).
[18:22:06.841] <TB3> INFO: 4646550 events read in total (159242ms).
[18:22:29.467] <TB3> INFO: 5307750 events read in total (181868ms).
[18:22:52.148] <TB3> INFO: 5966150 events read in total (204549ms).
[18:23:14.620] <TB3> INFO: 6624600 events read in total (227021ms).
[18:23:37.163] <TB3> INFO: 7282200 events read in total (249564ms).
[18:23:59.948] <TB3> INFO: 7940400 events read in total (272349ms).
[18:24:22.428] <TB3> INFO: 8597550 events read in total (294829ms).
[18:24:45.106] <TB3> INFO: 9256400 events read in total (317507ms).
[18:25:07.722] <TB3> INFO: 9911550 events read in total (340123ms).
[18:25:30.319] <TB3> INFO: 10570250 events read in total (362720ms).
[18:25:52.657] <TB3> INFO: 11226150 events read in total (385058ms).
[18:26:15.490] <TB3> INFO: 11883700 events read in total (407891ms).
[18:26:38.077] <TB3> INFO: 12537150 events read in total (430478ms).
[18:27:00.741] <TB3> INFO: 13194000 events read in total (453142ms).
[18:27:23.301] <TB3> INFO: 13849650 events read in total (475702ms).
[18:27:45.904] <TB3> INFO: 14506450 events read in total (498305ms).
[18:28:08.400] <TB3> INFO: 15161100 events read in total (520801ms).
[18:28:31.075] <TB3> INFO: 15815000 events read in total (543476ms).
[18:28:53.724] <TB3> INFO: 16469700 events read in total (566125ms).
[18:29:16.608] <TB3> INFO: 17122050 events read in total (589009ms).
[18:29:39.089] <TB3> INFO: 17776100 events read in total (611490ms).
[18:30:01.728] <TB3> INFO: 18428400 events read in total (634129ms).
[18:30:24.490] <TB3> INFO: 19085700 events read in total (656891ms).
[18:30:47.605] <TB3> INFO: 19737900 events read in total (680006ms).
[18:31:10.004] <TB3> INFO: 20390650 events read in total (702405ms).
[18:31:32.836] <TB3> INFO: 21039100 events read in total (725237ms).
[18:31:55.565] <TB3> INFO: 21693400 events read in total (747966ms).
[18:32:18.383] <TB3> INFO: 22345250 events read in total (770784ms).
[18:32:41.272] <TB3> INFO: 22999150 events read in total (793673ms).
[18:33:03.649] <TB3> INFO: 23651000 events read in total (816050ms).
[18:33:26.110] <TB3> INFO: 24301850 events read in total (838511ms).
[18:33:48.485] <TB3> INFO: 24950700 events read in total (860886ms).
[18:34:11.095] <TB3> INFO: 25600200 events read in total (883496ms).
[18:34:33.995] <TB3> INFO: 26251950 events read in total (906396ms).
[18:34:56.306] <TB3> INFO: 26900400 events read in total (928707ms).
[18:35:18.921] <TB3> INFO: 27552250 events read in total (951322ms).
[18:35:41.550] <TB3> INFO: 28201150 events read in total (973951ms).
[18:36:04.216] <TB3> INFO: 28851500 events read in total (996617ms).
[18:36:26.682] <TB3> INFO: 29497650 events read in total (1019083ms).
[18:36:49.234] <TB3> INFO: 30150350 events read in total (1041635ms).
[18:37:11.389] <TB3> INFO: 30799200 events read in total (1063790ms).
[18:37:33.893] <TB3> INFO: 31449850 events read in total (1086294ms).
[18:37:56.652] <TB3> INFO: 32099350 events read in total (1109053ms).
[18:38:19.081] <TB3> INFO: 32750650 events read in total (1131482ms).
[18:38:41.545] <TB3> INFO: 33401450 events read in total (1153946ms).
[18:39:04.013] <TB3> INFO: 34052850 events read in total (1176414ms).
[18:39:26.487] <TB3> INFO: 34704000 events read in total (1198888ms).
[18:39:49.296] <TB3> INFO: 35354600 events read in total (1221697ms).
[18:40:12.018] <TB3> INFO: 36009350 events read in total (1244419ms).
[18:40:32.606] <TB3> INFO: 36608000 events read in total (1265007ms).
[18:40:32.712] <TB3> INFO: Test took 1265957ms.
[18:40:33.233] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[18:40:35.387] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[18:40:37.353] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[18:40:39.615] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[18:40:42.070] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[18:40:44.228] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[18:40:46.318] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[18:40:48.492] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[18:40:50.627] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[18:40:53.033] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[18:40:54.952] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[18:40:56.958] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[18:40:58.530] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[18:41:00.089] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[18:41:01.695] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[18:41:03.220] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[18:41:04.998] <TB3> INFO: PixTestScurves::scurves() done
[18:41:04.998] <TB3> INFO: Vcal mean: 110.02 123.64 121.68 124.90 105.57 128.61 114.09 114.04 112.26 101.81 109.68 112.71 121.95 105.91 110.18 123.95
[18:41:04.998] <TB3> INFO: Vcal RMS: 4.95 7.14 7.14 7.18 5.31 6.56 6.37 5.42 5.06 5.87 4.83 4.90 6.72 6.27 5.42 6.11
[18:41:04.998] <TB3> INFO: PixTestScurves::fullTest() done, duration: 1298 seconds
[18:41:04.998] <TB3> INFO: Decoding statistics:
[18:41:04.998] <TB3> INFO: General information:
[18:41:04.998] <TB3> INFO: 16bit words read: 0
[18:41:04.998] <TB3> INFO: valid events total: 0
[18:41:04.998] <TB3> INFO: empty events: 0
[18:41:04.998] <TB3> INFO: valid events with pixels: 0
[18:41:04.998] <TB3> INFO: valid pixel hits: 0
[18:41:04.998] <TB3> INFO: Event errors: 0
[18:41:04.998] <TB3> INFO: start marker: 0
[18:41:04.998] <TB3> INFO: stop marker: 0
[18:41:04.998] <TB3> INFO: overflow: 0
[18:41:04.998] <TB3> INFO: invalid 5bit words: 0
[18:41:04.998] <TB3> INFO: invalid XOR eye diagram: 0
[18:41:04.998] <TB3> INFO: frame (failed synchr.): 0
[18:41:04.998] <TB3> INFO: idle data (no TBM trl): 0
[18:41:04.998] <TB3> INFO: no data (only TBM hdr): 0
[18:41:04.998] <TB3> INFO: TBM errors: 0
[18:41:04.998] <TB3> INFO: flawed TBM headers: 0
[18:41:04.998] <TB3> INFO: flawed TBM trailers: 0
[18:41:04.998] <TB3> INFO: event ID mismatches: 0
[18:41:04.998] <TB3> INFO: ROC errors: 0
[18:41:04.998] <TB3> INFO: missing ROC header(s): 0
[18:41:04.998] <TB3> INFO: misplaced readback start: 0
[18:41:04.998] <TB3> INFO: Pixel decoding errors: 0
[18:41:04.998] <TB3> INFO: pixel data incomplete: 0
[18:41:04.998] <TB3> INFO: pixel address: 0
[18:41:04.998] <TB3> INFO: pulse height fill bit: 0
[18:41:04.998] <TB3> INFO: buffer corruption: 0
[18:41:05.070] <TB3> INFO: ######################################################################
[18:41:05.070] <TB3> INFO: PixTestTrim::doTest()
[18:41:05.070] <TB3> INFO: ######################################################################
[18:41:05.071] <TB3> INFO: ----------------------------------------------------------------------
[18:41:05.071] <TB3> INFO: PixTestTrim::trimTest() ntrig = 8, vcal = 35
[18:41:05.071] <TB3> INFO: ----------------------------------------------------------------------
[18:41:05.119] <TB3> INFO: ---> VthrComp thr map (minimal VthrComp)
[18:41:05.119] <TB3> INFO: ---> dac: vthrcomp name: TrimThr0 ntrig: 8 dacrange: 10 .. 160 (-1/-1) hits flags = 528 (plus default)
[18:41:05.130] <TB3> INFO: dacScan split into 1 runs with ntrig = 8
[18:41:05.130] <TB3> INFO: run 1 of 1
[18:41:05.368] <TB3> INFO: Expecting 5025280 events.
[18:41:36.452] <TB3> INFO: 826320 events read in total (30485ms).
[18:42:06.444] <TB3> INFO: 1648104 events read in total (60477ms).
[18:42:36.550] <TB3> INFO: 2466056 events read in total (90583ms).
[18:43:06.667] <TB3> INFO: 3282664 events read in total (120700ms).
[18:43:36.586] <TB3> INFO: 4095728 events read in total (150620ms).
[18:44:06.388] <TB3> INFO: 4907744 events read in total (180421ms).
[18:44:11.129] <TB3> INFO: 5025280 events read in total (185162ms).
[18:44:11.210] <TB3> INFO: Test took 186081ms.
[18:44:28.702] <TB3> INFO: ROC 0 VthrComp = 115
[18:44:28.702] <TB3> INFO: ROC 1 VthrComp = 130
[18:44:28.702] <TB3> INFO: ROC 2 VthrComp = 125
[18:44:28.702] <TB3> INFO: ROC 3 VthrComp = 123
[18:44:28.702] <TB3> INFO: ROC 4 VthrComp = 107
[18:44:28.702] <TB3> INFO: ROC 5 VthrComp = 132
[18:44:28.702] <TB3> INFO: ROC 6 VthrComp = 108
[18:44:28.703] <TB3> INFO: ROC 7 VthrComp = 115
[18:44:28.703] <TB3> INFO: ROC 8 VthrComp = 118
[18:44:28.704] <TB3> INFO: ROC 9 VthrComp = 105
[18:44:28.704] <TB3> INFO: ROC 10 VthrComp = 114
[18:44:28.704] <TB3> INFO: ROC 11 VthrComp = 125
[18:44:28.704] <TB3> INFO: ROC 12 VthrComp = 129
[18:44:28.704] <TB3> INFO: ROC 13 VthrComp = 102
[18:44:28.704] <TB3> INFO: ROC 14 VthrComp = 114
[18:44:28.705] <TB3> INFO: ROC 15 VthrComp = 129
[18:44:28.969] <TB3> INFO: Expecting 41600 events.
[18:44:32.462] <TB3> INFO: 41600 events read in total (2901ms).
[18:44:32.462] <TB3> INFO: Test took 3756ms.
[18:44:32.471] <TB3> INFO: ---> Vcal thr map (pixel with maximum Vcal thr)
[18:44:32.471] <TB3> INFO: ---> dac: vcal name: TrimThr1 ntrig: 8 dacrange: 10 .. 160 (-1/-1) hits flags = 528 (plus default)
[18:44:32.482] <TB3> INFO: dacScan split into 1 runs with ntrig = 8
[18:44:32.482] <TB3> INFO: run 1 of 1
[18:44:32.760] <TB3> INFO: Expecting 5025280 events.
[18:44:58.822] <TB3> INFO: 592592 events read in total (25470ms).
[18:45:24.457] <TB3> INFO: 1183056 events read in total (51105ms).
[18:45:50.095] <TB3> INFO: 1772880 events read in total (76743ms).
[18:46:15.865] <TB3> INFO: 2361584 events read in total (102513ms).
[18:46:41.939] <TB3> INFO: 2948368 events read in total (128587ms).
[18:47:07.587] <TB3> INFO: 3534008 events read in total (154235ms).
[18:47:32.957] <TB3> INFO: 4118768 events read in total (179605ms).
[18:47:58.297] <TB3> INFO: 4703584 events read in total (204945ms).
[18:48:12.880] <TB3> INFO: 5025280 events read in total (219528ms).
[18:48:12.960] <TB3> INFO: Test took 220478ms.
[18:48:40.611] <TB3> INFO: roc 0 with ID = 0 has maximal Vcal 59.4243 for pixel 0/3 mean/min/max = 45.5749/31.5833/59.5665
[18:48:40.612] <TB3> INFO: roc 1 with ID = 1 has maximal Vcal 65.6903 for pixel 20/15 mean/min/max = 48.8841/32.0619/65.7062
[18:48:40.612] <TB3> INFO: roc 2 with ID = 2 has maximal Vcal 60.7288 for pixel 1/29 mean/min/max = 46.319/31.5264/61.1116
[18:48:40.612] <TB3> INFO: roc 3 with ID = 3 has maximal Vcal 62.9036 for pixel 16/59 mean/min/max = 46.5823/30.147/63.0177
[18:48:40.613] <TB3> INFO: roc 4 with ID = 4 has maximal Vcal 61.4708 for pixel 7/73 mean/min/max = 47.8647/34.0102/61.7191
[18:48:40.613] <TB3> INFO: roc 5 with ID = 5 has maximal Vcal 62.7827 for pixel 43/12 mean/min/max = 47.1221/31.1536/63.0907
[18:48:40.614] <TB3> INFO: roc 6 with ID = 6 has maximal Vcal 66.5252 for pixel 16/3 mean/min/max = 49.9909/33.4102/66.5715
[18:48:40.614] <TB3> INFO: roc 7 with ID = 7 has maximal Vcal 62.426 for pixel 3/20 mean/min/max = 46.9207/31.3048/62.5366
[18:48:40.615] <TB3> INFO: roc 8 with ID = 8 has maximal Vcal 59.3 for pixel 14/0 mean/min/max = 45.3095/31.2717/59.3472
[18:48:40.615] <TB3> INFO: roc 9 with ID = 9 has maximal Vcal 62.91 for pixel 0/20 mean/min/max = 48.6341/34.0737/63.1945
[18:48:40.616] <TB3> INFO: roc 10 with ID = 10 has maximal Vcal 60.0491 for pixel 48/6 mean/min/max = 45.9363/31.7891/60.0835
[18:48:40.616] <TB3> INFO: roc 11 with ID = 11 has maximal Vcal 58.5452 for pixel 2/59 mean/min/max = 44.9928/31.3343/58.6513
[18:48:40.616] <TB3> INFO: roc 12 with ID = 12 has maximal Vcal 61.6969 for pixel 1/74 mean/min/max = 46.6673/31.581/61.7536
[18:48:40.617] <TB3> INFO: roc 13 with ID = 13 has maximal Vcal 63.81 for pixel 6/9 mean/min/max = 48.083/32.2517/63.9142
[18:48:40.617] <TB3> INFO: roc 14 with ID = 14 has maximal Vcal 60.1654 for pixel 12/4 mean/min/max = 46.0752/31.8422/60.3081
[18:48:40.618] <TB3> INFO: roc 15 with ID = 15 has maximal Vcal 65.4268 for pixel 2/2 mean/min/max = 47.3634/29.0241/65.7028
[18:48:40.618] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[18:48:40.707] <TB3> INFO: Expecting 411648 events.
[18:48:50.409] <TB3> INFO: 411648 events read in total (9110ms).
[18:48:50.419] <TB3> INFO: Expecting 411648 events.
[18:48:59.916] <TB3> INFO: 411648 events read in total (9094ms).
[18:48:59.928] <TB3> INFO: Expecting 411648 events.
[18:49:09.470] <TB3> INFO: 411648 events read in total (9139ms).
[18:49:09.483] <TB3> INFO: Expecting 411648 events.
[18:49:18.996] <TB3> INFO: 411648 events read in total (9109ms).
[18:49:19.013] <TB3> INFO: Expecting 411648 events.
[18:49:28.489] <TB3> INFO: 411648 events read in total (9073ms).
[18:49:28.510] <TB3> INFO: Expecting 411648 events.
[18:49:38.031] <TB3> INFO: 411648 events read in total (9118ms).
[18:49:38.053] <TB3> INFO: Expecting 411648 events.
[18:49:47.533] <TB3> INFO: 411648 events read in total (9077ms).
[18:49:47.565] <TB3> INFO: Expecting 411648 events.
[18:49:56.977] <TB3> INFO: 411648 events read in total (9009ms).
[18:49:57.010] <TB3> INFO: Expecting 411648 events.
[18:50:06.459] <TB3> INFO: 411648 events read in total (9046ms).
[18:50:06.489] <TB3> INFO: Expecting 411648 events.
[18:50:15.909] <TB3> INFO: 411648 events read in total (9017ms).
[18:50:15.940] <TB3> INFO: Expecting 411648 events.
[18:50:25.335] <TB3> INFO: 411648 events read in total (8992ms).
[18:50:25.370] <TB3> INFO: Expecting 411648 events.
[18:50:34.814] <TB3> INFO: 411648 events read in total (9042ms).
[18:50:34.853] <TB3> INFO: Expecting 411648 events.
[18:50:44.265] <TB3> INFO: 411648 events read in total (9009ms).
[18:50:44.311] <TB3> INFO: Expecting 411648 events.
[18:50:53.838] <TB3> INFO: 411648 events read in total (9124ms).
[18:50:53.880] <TB3> INFO: Expecting 411648 events.
[18:51:03.132] <TB3> INFO: 411648 events read in total (8849ms).
[18:51:03.254] <TB3> INFO: Expecting 411648 events.
[18:51:12.541] <TB3> INFO: 411648 events read in total (8884ms).
[18:51:12.593] <TB3> INFO: Test took 151975ms.
[18:51:13.364] <TB3> INFO: ---> dac: vcal name: TrimThr2 ntrig: 8 dacrange: 0 .. 150 (-1/-1) hits flags = 528 (plus default)
[18:51:13.377] <TB3> INFO: dacScan split into 1 runs with ntrig = 8
[18:51:13.377] <TB3> INFO: run 1 of 1
[18:51:13.618] <TB3> INFO: Expecting 5025280 events.
[18:51:39.994] <TB3> INFO: 585736 events read in total (25784ms).
[18:52:05.836] <TB3> INFO: 1171640 events read in total (51627ms).
[18:52:31.954] <TB3> INFO: 1757472 events read in total (77744ms).
[18:52:57.862] <TB3> INFO: 2342288 events read in total (103652ms).
[18:53:23.830] <TB3> INFO: 2927680 events read in total (129620ms).
[18:53:49.687] <TB3> INFO: 3513992 events read in total (155477ms).
[18:54:15.940] <TB3> INFO: 4099464 events read in total (181730ms).
[18:54:42.508] <TB3> INFO: 4685216 events read in total (208298ms).
[18:54:58.008] <TB3> INFO: 5025280 events read in total (223798ms).
[18:54:58.223] <TB3> INFO: Test took 224847ms.
[18:55:23.147] <TB3> INFO: ---> TrimStepCorr4 extremal thresholds: 0.239304 .. 145.472964
[18:55:23.388] <TB3> INFO: Expecting 208000 events.
[18:55:33.080] <TB3> INFO: 208000 events read in total (9101ms).
[18:55:33.081] <TB3> INFO: Test took 9932ms.
[18:55:33.133] <TB3> INFO: ---> dac: vcal name: trimStepCorr4 ntrig: 8 dacrange: 0 .. 155 (-1/-1) hits flags = 528 (plus default)
[18:55:33.147] <TB3> INFO: dacScan split into 1 runs with ntrig = 8
[18:55:33.147] <TB3> INFO: run 1 of 1
[18:55:33.426] <TB3> INFO: Expecting 5191680 events.
[18:56:00.225] <TB3> INFO: 585608 events read in total (26207ms).
[18:56:26.376] <TB3> INFO: 1171408 events read in total (52358ms).
[18:56:52.609] <TB3> INFO: 1757416 events read in total (78591ms).
[18:57:18.142] <TB3> INFO: 2343552 events read in total (104124ms).
[18:57:43.794] <TB3> INFO: 2928864 events read in total (129776ms).
[18:58:09.402] <TB3> INFO: 3514200 events read in total (155384ms).
[18:58:35.331] <TB3> INFO: 4098648 events read in total (181313ms).
[18:59:01.278] <TB3> INFO: 4683032 events read in total (207260ms).
[18:59:24.118] <TB3> INFO: 5191680 events read in total (230100ms).
[18:59:24.224] <TB3> INFO: Test took 231078ms.
[18:59:52.051] <TB3> INFO: ---> TrimStepCorr2 extremal thresholds: 26.982856 .. 45.226564
[18:59:52.290] <TB3> INFO: Expecting 208000 events.
[19:00:02.018] <TB3> INFO: 208000 events read in total (9137ms).
[19:00:02.019] <TB3> INFO: Test took 9967ms.
[19:00:02.069] <TB3> INFO: ---> dac: vcal name: trimStepCorr2 ntrig: 8 dacrange: 16 .. 55 (-1/-1) hits flags = 528 (plus default)
[19:00:02.084] <TB3> INFO: dacScan split into 1 runs with ntrig = 8
[19:00:02.084] <TB3> INFO: run 1 of 1
[19:00:02.364] <TB3> INFO: Expecting 1331200 events.
[19:00:31.304] <TB3> INFO: 663096 events read in total (28348ms).
[19:00:58.899] <TB3> INFO: 1324240 events read in total (55944ms).
[19:00:59.628] <TB3> INFO: 1331200 events read in total (56672ms).
[19:00:59.660] <TB3> INFO: Test took 57575ms.
[19:01:14.565] <TB3> INFO: ---> TrimStepCorr1a extremal thresholds: 26.765064 .. 46.965443
[19:01:14.839] <TB3> INFO: Expecting 208000 events.
[19:01:24.750] <TB3> INFO: 208000 events read in total (9319ms).
[19:01:24.751] <TB3> INFO: Test took 10185ms.
[19:01:24.822] <TB3> INFO: ---> dac: vcal name: trimStepCorr1a ntrig: 8 dacrange: 16 .. 56 (-1/-1) hits flags = 528 (plus default)
[19:01:24.840] <TB3> INFO: dacScan split into 1 runs with ntrig = 8
[19:01:24.840] <TB3> INFO: run 1 of 1
[19:01:25.118] <TB3> INFO: Expecting 1364480 events.
[19:01:53.394] <TB3> INFO: 658960 events read in total (27684ms).
[19:02:20.757] <TB3> INFO: 1317264 events read in total (55048ms).
[19:02:23.094] <TB3> INFO: 1364480 events read in total (57384ms).
[19:02:23.124] <TB3> INFO: Test took 58284ms.
[19:02:38.007] <TB3> INFO: ---> TrimStepCorr1b extremal thresholds: 23.608091 .. 45.762374
[19:02:38.302] <TB3> INFO: Expecting 208000 events.
[19:02:48.139] <TB3> INFO: 208000 events read in total (9245ms).
[19:02:48.139] <TB3> INFO: Test took 10130ms.
[19:02:48.209] <TB3> INFO: ---> dac: vcal name: trimStepCorr1b ntrig: 8 dacrange: 13 .. 55 (-1/-1) hits flags = 528 (plus default)
[19:02:48.222] <TB3> INFO: dacScan split into 1 runs with ntrig = 8
[19:02:48.222] <TB3> INFO: run 1 of 1
[19:02:48.500] <TB3> INFO: Expecting 1431040 events.
[19:03:18.088] <TB3> INFO: 674936 events read in total (28996ms).
[19:03:46.032] <TB3> INFO: 1349416 events read in total (56941ms).
[19:03:49.796] <TB3> INFO: 1431040 events read in total (60705ms).
[19:03:49.840] <TB3> INFO: Test took 61618ms.
[19:04:03.841] <TB3> INFO: ---> TrimThrFinal extremal thresholds: 15 .. 55
[19:04:03.841] <TB3> INFO: ---> dac: vcal name: TrimThrFinal ntrig: 8 dacrange: 15 .. 55 (-1/-1) hits flags = 528 (plus default)
[19:04:03.856] <TB3> INFO: dacScan split into 1 runs with ntrig = 8
[19:04:03.856] <TB3> INFO: run 1 of 1
[19:04:04.096] <TB3> INFO: Expecting 1364480 events.
[19:04:32.775] <TB3> INFO: 667344 events read in total (28088ms).
[19:05:00.192] <TB3> INFO: 1334144 events read in total (55505ms).
[19:05:01.951] <TB3> INFO: 1364480 events read in total (57265ms).
[19:05:01.989] <TB3> INFO: Test took 58133ms.
[19:05:19.515] <TB3> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1033_FullQualification_2016-11-04_17h17m_1478276226//001_Fulltest_m20//dacParameters35_C0.dat
[19:05:19.521] <TB3> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1033_FullQualification_2016-11-04_17h17m_1478276226//001_Fulltest_m20//dacParameters35_C1.dat
[19:05:19.523] <TB3> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1033_FullQualification_2016-11-04_17h17m_1478276226//001_Fulltest_m20//dacParameters35_C2.dat
[19:05:19.524] <TB3> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1033_FullQualification_2016-11-04_17h17m_1478276226//001_Fulltest_m20//dacParameters35_C3.dat
[19:05:19.525] <TB3> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1033_FullQualification_2016-11-04_17h17m_1478276226//001_Fulltest_m20//dacParameters35_C4.dat
[19:05:19.526] <TB3> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1033_FullQualification_2016-11-04_17h17m_1478276226//001_Fulltest_m20//dacParameters35_C5.dat
[19:05:19.528] <TB3> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1033_FullQualification_2016-11-04_17h17m_1478276226//001_Fulltest_m20//dacParameters35_C6.dat
[19:05:19.529] <TB3> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1033_FullQualification_2016-11-04_17h17m_1478276226//001_Fulltest_m20//dacParameters35_C7.dat
[19:05:19.530] <TB3> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1033_FullQualification_2016-11-04_17h17m_1478276226//001_Fulltest_m20//dacParameters35_C8.dat
[19:05:19.531] <TB3> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1033_FullQualification_2016-11-04_17h17m_1478276226//001_Fulltest_m20//dacParameters35_C9.dat
[19:05:19.532] <TB3> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1033_FullQualification_2016-11-04_17h17m_1478276226//001_Fulltest_m20//dacParameters35_C10.dat
[19:05:19.537] <TB3> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1033_FullQualification_2016-11-04_17h17m_1478276226//001_Fulltest_m20//dacParameters35_C11.dat
[19:05:19.538] <TB3> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1033_FullQualification_2016-11-04_17h17m_1478276226//001_Fulltest_m20//dacParameters35_C12.dat
[19:05:19.539] <TB3> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1033_FullQualification_2016-11-04_17h17m_1478276226//001_Fulltest_m20//dacParameters35_C13.dat
[19:05:19.540] <TB3> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1033_FullQualification_2016-11-04_17h17m_1478276226//001_Fulltest_m20//dacParameters35_C14.dat
[19:05:19.542] <TB3> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1033_FullQualification_2016-11-04_17h17m_1478276226//001_Fulltest_m20//dacParameters35_C15.dat
[19:05:19.543] <TB3> INFO: write trim parameters into /home/l_tester/david/FullQualification/data/M1033_FullQualification_2016-11-04_17h17m_1478276226//001_Fulltest_m20//trimParameters35_C0.dat
[19:05:19.553] <TB3> INFO: write trim parameters into /home/l_tester/david/FullQualification/data/M1033_FullQualification_2016-11-04_17h17m_1478276226//001_Fulltest_m20//trimParameters35_C1.dat
[19:05:19.585] <TB3> INFO: write trim parameters into /home/l_tester/david/FullQualification/data/M1033_FullQualification_2016-11-04_17h17m_1478276226//001_Fulltest_m20//trimParameters35_C2.dat
[19:05:19.607] <TB3> INFO: write trim parameters into /home/l_tester/david/FullQualification/data/M1033_FullQualification_2016-11-04_17h17m_1478276226//001_Fulltest_m20//trimParameters35_C3.dat
[19:05:19.627] <TB3> INFO: write trim parameters into /home/l_tester/david/FullQualification/data/M1033_FullQualification_2016-11-04_17h17m_1478276226//001_Fulltest_m20//trimParameters35_C4.dat
[19:05:19.653] <TB3> INFO: write trim parameters into /home/l_tester/david/FullQualification/data/M1033_FullQualification_2016-11-04_17h17m_1478276226//001_Fulltest_m20//trimParameters35_C5.dat
[19:05:19.684] <TB3> INFO: write trim parameters into /home/l_tester/david/FullQualification/data/M1033_FullQualification_2016-11-04_17h17m_1478276226//001_Fulltest_m20//trimParameters35_C6.dat
[19:05:19.702] <TB3> INFO: write trim parameters into /home/l_tester/david/FullQualification/data/M1033_FullQualification_2016-11-04_17h17m_1478276226//001_Fulltest_m20//trimParameters35_C7.dat
[19:05:19.726] <TB3> INFO: write trim parameters into /home/l_tester/david/FullQualification/data/M1033_FullQualification_2016-11-04_17h17m_1478276226//001_Fulltest_m20//trimParameters35_C8.dat
[19:05:19.759] <TB3> INFO: write trim parameters into /home/l_tester/david/FullQualification/data/M1033_FullQualification_2016-11-04_17h17m_1478276226//001_Fulltest_m20//trimParameters35_C9.dat
[19:05:19.782] <TB3> INFO: write trim parameters into /home/l_tester/david/FullQualification/data/M1033_FullQualification_2016-11-04_17h17m_1478276226//001_Fulltest_m20//trimParameters35_C10.dat
[19:05:19.807] <TB3> INFO: write trim parameters into /home/l_tester/david/FullQualification/data/M1033_FullQualification_2016-11-04_17h17m_1478276226//001_Fulltest_m20//trimParameters35_C11.dat
[19:05:19.814] <TB3> INFO: write trim parameters into /home/l_tester/david/FullQualification/data/M1033_FullQualification_2016-11-04_17h17m_1478276226//001_Fulltest_m20//trimParameters35_C12.dat
[19:05:19.856] <TB3> INFO: write trim parameters into /home/l_tester/david/FullQualification/data/M1033_FullQualification_2016-11-04_17h17m_1478276226//001_Fulltest_m20//trimParameters35_C13.dat
[19:05:19.867] <TB3> INFO: write trim parameters into /home/l_tester/david/FullQualification/data/M1033_FullQualification_2016-11-04_17h17m_1478276226//001_Fulltest_m20//trimParameters35_C14.dat
[19:05:19.882] <TB3> INFO: write trim parameters into /home/l_tester/david/FullQualification/data/M1033_FullQualification_2016-11-04_17h17m_1478276226//001_Fulltest_m20//trimParameters35_C15.dat
[19:05:19.898] <TB3> INFO: PixTestTrim::trimTest() done
[19:05:19.907] <TB3> INFO: vtrim: 116 149 112 132 119 131 144 114 131 112 130 138 129 116 130 148
[19:05:19.907] <TB3> INFO: vthrcomp: 115 130 125 123 107 132 108 115 118 105 114 125 129 102 114 129
[19:05:19.907] <TB3> INFO: vcal mean: 34.95 34.97 34.97 34.93 34.91 34.94 35.06 34.90 34.94 34.98 34.92 34.98 34.91 35.00 34.98 35.02
[19:05:19.907] <TB3> INFO: vcal RMS: 1.02 1.11 1.09 1.27 0.96 1.22 1.16 1.17 1.15 0.97 1.03 1.01 1.08 1.20 1.05 1.13
[19:05:19.907] <TB3> INFO: bits mean: 9.85 9.17 9.30 9.89 8.53 9.53 8.93 9.52 9.90 8.08 9.54 9.69 9.47 9.28 9.89 9.94
[19:05:19.907] <TB3> INFO: bits RMS: 2.63 2.65 2.80 2.71 2.76 2.71 2.53 2.71 2.65 2.74 2.71 2.76 2.70 2.65 2.55 2.44
[19:05:19.944] <TB3> INFO: ----------------------------------------------------------------------
[19:05:19.944] <TB3> INFO: PixTestTrim::trimBitTest() ntrig = 5, vtrims = 254 126 63 32
[19:05:19.944] <TB3> INFO: ----------------------------------------------------------------------
[19:05:19.946] <TB3> INFO: ---> dac: Vcal name: TrimBitsThr0 ntrig: 5 dacrange: 0 .. 199 (-1/-1) hits flags = 528 (plus default)
[19:05:19.976] <TB3> INFO: dacScan split into 1 runs with ntrig = 5
[19:05:19.983] <TB3> INFO: run 1 of 1
[19:05:20.582] <TB3> INFO: Expecting 4160000 events.
[19:06:30.388] <TB3> INFO: 742190 events read in total (69201ms).
[19:07:47.390] <TB3> INFO: 1475875 events read in total (146203ms).
[19:08:18.860] <TB3> INFO: 2205940 events read in total (177673ms).
[19:08:50.489] <TB3> INFO: 2932435 events read in total (209302ms).
[19:09:22.288] <TB3> INFO: 3656985 events read in total (241101ms).
[19:09:44.006] <TB3> INFO: 4160000 events read in total (262819ms).
[19:09:44.101] <TB3> INFO: Test took 264114ms.
[19:10:13.917] <TB3> INFO: ---> dac: Vcal name: TrimThr_trim14 ntrig: 5 dacrange: 0 .. 208 (-1/-1) hits flags = 528 (plus default)
[19:10:13.930] <TB3> INFO: dacScan split into 1 runs with ntrig = 5
[19:10:13.930] <TB3> INFO: run 1 of 1
[19:10:14.167] <TB3> INFO: Expecting 4347200 events.
[19:10:45.848] <TB3> INFO: 707650 events read in total (31089ms).
[19:11:16.556] <TB3> INFO: 1408555 events read in total (61797ms).
[19:11:47.382] <TB3> INFO: 2106485 events read in total (92623ms).
[19:12:18.422] <TB3> INFO: 2802440 events read in total (123663ms).
[19:12:49.519] <TB3> INFO: 3495445 events read in total (154760ms).
[19:13:20.078] <TB3> INFO: 4189705 events read in total (185319ms).
[19:13:26.991] <TB3> INFO: 4347200 events read in total (192232ms).
[19:13:27.140] <TB3> INFO: Test took 193210ms.
[19:13:53.338] <TB3> INFO: ---> dac: Vcal name: TrimThr_trim13 ntrig: 5 dacrange: 0 .. 196 (-1/-1) hits flags = 528 (plus default)
[19:13:53.352] <TB3> INFO: dacScan split into 1 runs with ntrig = 5
[19:13:53.352] <TB3> INFO: run 1 of 1
[19:13:53.587] <TB3> INFO: Expecting 4097600 events.
[19:14:25.481] <TB3> INFO: 722200 events read in total (31303ms).
[19:14:56.711] <TB3> INFO: 1436780 events read in total (62533ms).
[19:15:28.119] <TB3> INFO: 2148220 events read in total (93941ms).
[19:15:59.560] <TB3> INFO: 2856470 events read in total (125382ms).
[19:16:30.761] <TB3> INFO: 3563500 events read in total (156583ms).
[19:16:55.080] <TB3> INFO: 4097600 events read in total (180902ms).
[19:16:55.176] <TB3> INFO: Test took 181824ms.
[19:17:48.695] <TB3> INFO: ---> dac: Vcal name: TrimThr_trim11 ntrig: 5 dacrange: 0 .. 196 (-1/-1) hits flags = 528 (plus default)
[19:17:48.726] <TB3> INFO: dacScan split into 1 runs with ntrig = 5
[19:17:48.727] <TB3> INFO: run 1 of 1
[19:17:49.175] <TB3> INFO: Expecting 4097600 events.
[19:18:38.015] <TB3> INFO: 722580 events read in total (48248ms).
[19:19:59.827] <TB3> INFO: 1437015 events read in total (130060ms).
[19:20:31.307] <TB3> INFO: 2148735 events read in total (161540ms).
[19:21:39.229] <TB3> INFO: 2857200 events read in total (229462ms).
[19:22:41.927] <TB3> INFO: 3564405 events read in total (292160ms).
[19:23:17.495] <TB3> INFO: 4097600 events read in total (327728ms).
[19:23:17.589] <TB3> INFO: Test took 328854ms.
[19:24:18.851] <TB3> INFO: ---> dac: Vcal name: TrimThr_trim7 ntrig: 5 dacrange: 0 .. 196 (-1/-1) hits flags = 528 (plus default)
[19:24:18.884] <TB3> INFO: dacScan split into 1 runs with ntrig = 5
[19:24:18.884] <TB3> INFO: run 1 of 1
[19:24:19.614] <TB3> INFO: Expecting 4097600 events.
[19:24:52.065] <TB3> INFO: 722695 events read in total (31790ms).
[19:26:14.569] <TB3> INFO: 1437440 events read in total (114294ms).
[19:27:13.471] <TB3> INFO: 2149075 events read in total (173196ms).
[19:27:48.976] <TB3> INFO: 2857655 events read in total (208701ms).
[19:29:11.069] <TB3> INFO: 3565285 events read in total (290794ms).
[19:29:57.367] <TB3> INFO: 4097600 events read in total (337092ms).
[19:29:57.788] <TB3> INFO: Test took 338893ms.
[19:30:50.676] <TB3> INFO: PixTestTrim::trimBitTest() done
[19:30:50.680] <TB3> INFO: PixTestTrim::doTest() done, duration: 2985 seconds
[19:30:50.680] <TB3> INFO: Decoding statistics:
[19:30:50.680] <TB3> INFO: General information:
[19:30:50.680] <TB3> INFO: 16bit words read: 0
[19:30:50.680] <TB3> INFO: valid events total: 0
[19:30:50.680] <TB3> INFO: empty events: 0
[19:30:50.680] <TB3> INFO: valid events with pixels: 0
[19:30:50.680] <TB3> INFO: valid pixel hits: 0
[19:30:50.680] <TB3> INFO: Event errors: 0
[19:30:50.680] <TB3> INFO: start marker: 0
[19:30:50.680] <TB3> INFO: stop marker: 0
[19:30:50.680] <TB3> INFO: overflow: 0
[19:30:50.680] <TB3> INFO: invalid 5bit words: 0
[19:30:50.680] <TB3> INFO: invalid XOR eye diagram: 0
[19:30:50.680] <TB3> INFO: frame (failed synchr.): 0
[19:30:50.680] <TB3> INFO: idle data (no TBM trl): 0
[19:30:50.680] <TB3> INFO: no data (only TBM hdr): 0
[19:30:50.680] <TB3> INFO: TBM errors: 0
[19:30:50.680] <TB3> INFO: flawed TBM headers: 0
[19:30:50.680] <TB3> INFO: flawed TBM trailers: 0
[19:30:50.680] <TB3> INFO: event ID mismatches: 0
[19:30:50.680] <TB3> INFO: ROC errors: 0
[19:30:50.680] <TB3> INFO: missing ROC header(s): 0
[19:30:50.680] <TB3> INFO: misplaced readback start: 0
[19:30:50.680] <TB3> INFO: Pixel decoding errors: 0
[19:30:50.680] <TB3> INFO: pixel data incomplete: 0
[19:30:50.680] <TB3> INFO: pixel address: 0
[19:30:50.680] <TB3> INFO: pulse height fill bit: 0
[19:30:50.680] <TB3> INFO: buffer corruption: 0
[19:30:51.327] <TB3> INFO: ######################################################################
[19:30:51.327] <TB3> INFO: PixTestPhOptimization::doTest() Ntrig = 10
[19:30:51.327] <TB3> INFO: ######################################################################
[19:30:51.565] <TB3> INFO: Expecting 41600 events.
[19:30:55.077] <TB3> INFO: 41600 events read in total (2921ms).
[19:30:55.078] <TB3> INFO: Test took 3750ms.
[19:30:55.569] <TB3> INFO: Expecting 41600 events.
[19:30:59.145] <TB3> INFO: 41600 events read in total (2984ms).
[19:30:59.146] <TB3> INFO: Test took 3861ms.
[19:30:59.439] <TB3> INFO: Expecting 41600 events.
[19:31:03.055] <TB3> INFO: 41600 events read in total (3024ms).
[19:31:03.056] <TB3> INFO: Test took 3882ms.
[19:31:03.348] <TB3> INFO: Expecting 41600 events.
[19:31:14.048] <TB3> INFO: 41600 events read in total (10105ms).
[19:31:14.048] <TB3> INFO: Test took 10967ms.
[19:31:14.337] <TB3> INFO: Expecting 41600 events.
[19:31:20.204] <TB3> INFO: 41600 events read in total (5201ms).
[19:31:20.205] <TB3> INFO: Test took 6133ms.
[19:31:20.835] <TB3> INFO: Expecting 41600 events.
[19:31:41.047] <TB3> INFO: 41600 events read in total (19544ms).
[19:31:41.048] <TB3> INFO: Test took 20816ms.
[19:31:41.357] <TB3> INFO: Expecting 41600 events.
[19:31:47.821] <TB3> INFO: 41600 events read in total (5871ms).
[19:31:47.832] <TB3> INFO: Test took 6759ms.
[19:31:48.619] <TB3> INFO: Expecting 41600 events.
[19:32:03.518] <TB3> INFO: 41600 events read in total (14215ms).
[19:32:03.536] <TB3> INFO: Test took 15347ms.
[19:32:04.313] <TB3> INFO: Expecting 41600 events.
[19:32:07.928] <TB3> INFO: 41600 events read in total (3023ms).
[19:32:07.930] <TB3> INFO: Test took 4001ms.
[19:32:08.219] <TB3> INFO: Expecting 41600 events.
[19:32:11.791] <TB3> INFO: 41600 events read in total (2980ms).
[19:32:11.792] <TB3> INFO: Test took 3838ms.
[19:32:12.082] <TB3> INFO: Expecting 41600 events.
[19:32:15.585] <TB3> INFO: 41600 events read in total (2912ms).
[19:32:15.586] <TB3> INFO: Test took 3769ms.
[19:32:15.875] <TB3> INFO: Expecting 41600 events.
[19:32:19.445] <TB3> INFO: 41600 events read in total (2978ms).
[19:32:19.446] <TB3> INFO: Test took 3836ms.
[19:32:19.739] <TB3> INFO: Expecting 41600 events.
[19:32:23.340] <TB3> INFO: 41600 events read in total (3010ms).
[19:32:23.340] <TB3> INFO: Test took 3870ms.
[19:32:23.630] <TB3> INFO: Expecting 41600 events.
[19:32:27.162] <TB3> INFO: 41600 events read in total (2940ms).
[19:32:27.164] <TB3> INFO: Test took 3799ms.
[19:32:27.453] <TB3> INFO: Expecting 41600 events.
[19:32:31.075] <TB3> INFO: 41600 events read in total (3030ms).
[19:32:31.077] <TB3> INFO: Test took 3889ms.
[19:32:31.384] <TB3> INFO: Expecting 41600 events.
[19:32:40.949] <TB3> INFO: 41600 events read in total (8938ms).
[19:32:40.957] <TB3> INFO: Test took 9855ms.
[19:32:41.295] <TB3> INFO: Expecting 41600 events.
[19:32:47.956] <TB3> INFO: 41600 events read in total (6025ms).
[19:32:47.964] <TB3> INFO: Test took 6978ms.
[19:32:49.307] <TB3> INFO: Expecting 41600 events.
[19:33:00.159] <TB3> INFO: 41600 events read in total (10196ms).
[19:33:00.172] <TB3> INFO: Test took 11637ms.
[19:33:01.116] <TB3> INFO: Expecting 41600 events.
[19:33:09.101] <TB3> INFO: 41600 events read in total (7310ms).
[19:33:09.104] <TB3> INFO: Test took 8396ms.
[19:33:09.587] <TB3> INFO: Expecting 41600 events.
[19:33:13.094] <TB3> INFO: 41600 events read in total (2916ms).
[19:33:13.095] <TB3> INFO: Test took 3947ms.
[19:33:13.384] <TB3> INFO: Expecting 41600 events.
[19:33:33.932] <TB3> INFO: 41600 events read in total (19893ms).
[19:33:33.934] <TB3> INFO: Test took 20814ms.
[19:33:34.332] <TB3> INFO: Expecting 41600 events.
[19:33:38.270] <TB3> INFO: 41600 events read in total (3347ms).
[19:33:38.271] <TB3> INFO: Test took 4280ms.
[19:33:38.562] <TB3> INFO: Expecting 41600 events.
[19:33:42.077] <TB3> INFO: 41600 events read in total (2920ms).
[19:33:42.078] <TB3> INFO: Test took 3781ms.
[19:33:42.367] <TB3> INFO: Expecting 41600 events.
[19:33:45.889] <TB3> INFO: 41600 events read in total (2930ms).
[19:33:45.889] <TB3> INFO: Test took 3787ms.
[19:33:46.180] <TB3> INFO: Expecting 41600 events.
[19:33:49.769] <TB3> INFO: 41600 events read in total (2996ms).
[19:33:49.769] <TB3> INFO: Test took 3854ms.
[19:33:50.058] <TB3> INFO: Expecting 41600 events.
[19:33:53.538] <TB3> INFO: 41600 events read in total (2888ms).
[19:33:53.538] <TB3> INFO: Test took 3745ms.
[19:33:53.827] <TB3> INFO: Expecting 41600 events.
[19:33:57.392] <TB3> INFO: 41600 events read in total (2973ms).
[19:33:57.392] <TB3> INFO: Test took 3830ms.
[19:33:57.684] <TB3> INFO: Expecting 41600 events.
[19:34:01.201] <TB3> INFO: 41600 events read in total (2925ms).
[19:34:01.201] <TB3> INFO: Test took 3783ms.
[19:34:01.490] <TB3> INFO: Expecting 41600 events.
[19:34:07.934] <TB3> INFO: 41600 events read in total (5852ms).
[19:34:07.950] <TB3> INFO: Test took 6724ms.
[19:34:08.862] <TB3> INFO: Expecting 41600 events.
[19:34:14.914] <TB3> INFO: 41600 events read in total (5460ms).
[19:34:14.916] <TB3> INFO: Test took 6437ms.
[19:34:15.743] <TB3> INFO: Expecting 41600 events.
[19:34:36.412] <TB3> INFO: 41600 events read in total (20005ms).
[19:34:36.420] <TB3> INFO: Test took 21473ms.
[19:34:37.323] <TB3> INFO: Expecting 41600 events.
[19:34:42.710] <TB3> INFO: 41600 events read in total (4671ms).
[19:34:42.711] <TB3> INFO: Test took 5863ms.
[19:34:42.001] <TB3> INFO: Expecting 41600 events.
[19:35:05.055] <TB3> INFO: 41600 events read in total (21460ms).
[19:35:05.056] <TB3> INFO: Test took 22319ms.
[19:35:05.349] <TB3> INFO: Expecting 41600 events.
[19:35:08.920] <TB3> INFO: 41600 events read in total (2979ms).
[19:35:08.921] <TB3> INFO: Test took 3836ms.
[19:35:09.210] <TB3> INFO: Expecting 41600 events.
[19:35:12.780] <TB3> INFO: 41600 events read in total (2979ms).
[19:35:12.780] <TB3> INFO: Test took 3835ms.
[19:35:13.071] <TB3> INFO: Expecting 41600 events.
[19:35:16.675] <TB3> INFO: 41600 events read in total (3013ms).
[19:35:16.676] <TB3> INFO: Test took 3870ms.
[19:35:16.965] <TB3> INFO: Expecting 41600 events.
[19:35:20.548] <TB3> INFO: 41600 events read in total (2991ms).
[19:35:20.549] <TB3> INFO: Test took 3849ms.
[19:35:20.858] <TB3> INFO: Expecting 41600 events.
[19:35:24.338] <TB3> INFO: 41600 events read in total (2888ms).
[19:35:24.338] <TB3> INFO: Test took 3760ms.
[19:35:24.639] <TB3> INFO: Expecting 41600 events.
[19:35:28.160] <TB3> INFO: 41600 events read in total (2929ms).
[19:35:28.161] <TB3> INFO: Test took 3797ms.
[19:35:28.470] <TB3> INFO: Expecting 41600 events.
[19:35:32.040] <TB3> INFO: 41600 events read in total (2978ms).
[19:35:32.041] <TB3> INFO: Test took 3856ms.
[19:35:32.333] <TB3> INFO: Expecting 41600 events.
[19:35:41.953] <TB3> INFO: 41600 events read in total (9018ms).
[19:35:41.954] <TB3> INFO: Test took 9886ms.
[19:35:42.243] <TB3> INFO: Expecting 41600 events.
[19:35:48.524] <TB3> INFO: 41600 events read in total (5681ms).
[19:35:48.525] <TB3> INFO: Test took 6547ms.
[19:35:49.371] <TB3> INFO: Expecting 41600 events.
[19:36:09.486] <TB3> INFO: 41600 events read in total (19375ms).
[19:36:09.487] <TB3> INFO: Test took 20937ms.
[19:36:09.812] <TB3> INFO: Expecting 41600 events.
[19:36:17.226] <TB3> INFO: 41600 events read in total (6822ms).
[19:36:17.228] <TB3> INFO: Test took 7713ms.
[19:36:17.808] <TB3> INFO: Expecting 41600 events.
[19:36:32.935] <TB3> INFO: 41600 events read in total (14428ms).
[19:36:32.938] <TB3> INFO: Test took 15678ms.
[19:36:33.302] <TB3> INFO: Expecting 41600 events.
[19:36:36.934] <TB3> INFO: 41600 events read in total (3036ms).
[19:36:36.935] <TB3> INFO: Test took 3968ms.
[19:36:37.232] <TB3> INFO: Expecting 41600 events.
[19:36:40.783] <TB3> INFO: 41600 events read in total (2959ms).
[19:36:40.784] <TB3> INFO: Test took 3820ms.
[19:36:41.074] <TB3> INFO: Expecting 41600 events.
[19:36:44.601] <TB3> INFO: 41600 events read in total (2936ms).
[19:36:44.602] <TB3> INFO: Test took 3793ms.
[19:36:44.922] <TB3> INFO: Expecting 41600 events.
[19:36:48.573] <TB3> INFO: 41600 events read in total (3060ms).
[19:36:48.573] <TB3> INFO: Test took 3944ms.
[19:36:48.862] <TB3> INFO: Expecting 41600 events.
[19:36:52.401] <TB3> INFO: 41600 events read in total (2947ms).
[19:36:52.402] <TB3> INFO: Test took 3804ms.
[19:36:52.692] <TB3> INFO: Expecting 41600 events.
[19:36:56.270] <TB3> INFO: 41600 events read in total (2987ms).
[19:36:56.271] <TB3> INFO: Test took 3844ms.
[19:36:56.567] <TB3> INFO: Expecting 41600 events.
[19:37:00.156] <TB3> INFO: 41600 events read in total (2997ms).
[19:37:00.157] <TB3> INFO: Test took 3859ms.
[19:37:00.456] <TB3> INFO: Expecting 41600 events.
[19:37:07.149] <TB3> INFO: 41600 events read in total (6102ms).
[19:37:07.160] <TB3> INFO: Test took 6974ms.
[19:37:08.328] <TB3> INFO: Expecting 41600 events.
[19:37:12.939] <TB3> INFO: 41600 events read in total (3909ms).
[19:37:12.956] <TB3> INFO: Test took 5317ms.
[19:37:13.515] <TB3> INFO: Expecting 2560 events.
[19:37:14.513] <TB3> INFO: 2560 events read in total (298ms).
[19:37:14.514] <TB3> INFO: Test took 1375ms.
[19:37:14.828] <TB3> INFO: Expecting 2560 events.
[19:37:17.226] <TB3> INFO: 2560 events read in total (1759ms).
[19:37:17.226] <TB3> INFO: Test took 2712ms.
[19:37:17.549] <TB3> INFO: Expecting 2560 events.
[19:37:20.816] <TB3> INFO: 2560 events read in total (2597ms).
[19:37:20.827] <TB3> INFO: Test took 3598ms.
[19:37:21.415] <TB3> INFO: Expecting 2560 events.
[19:37:23.957] <TB3> INFO: 2560 events read in total (1834ms).
[19:37:23.969] <TB3> INFO: Test took 3118ms.
[19:37:24.511] <TB3> INFO: Expecting 2560 events.
[19:37:25.779] <TB3> INFO: 2560 events read in total (552ms).
[19:37:25.779] <TB3> INFO: Test took 1792ms.
[19:37:26.415] <TB3> INFO: Expecting 2560 events.
[19:37:28.988] <TB3> INFO: 2560 events read in total (1877ms).
[19:37:28.999] <TB3> INFO: Test took 3219ms.
[19:37:29.659] <TB3> INFO: Expecting 2560 events.
[19:37:32.132] <TB3> INFO: 2560 events read in total (1761ms).
[19:37:32.143] <TB3> INFO: Test took 3131ms.
[19:37:32.783] <TB3> INFO: Expecting 2560 events.
[19:37:35.696] <TB3> INFO: 2560 events read in total (2249ms).
[19:37:35.699] <TB3> INFO: Test took 3544ms.
[19:37:36.279] <TB3> INFO: Expecting 2560 events.
[19:37:37.601] <TB3> INFO: 2560 events read in total (610ms).
[19:37:37.601] <TB3> INFO: Test took 1894ms.
[19:37:37.933] <TB3> INFO: Expecting 2560 events.
[19:37:38.827] <TB3> INFO: 2560 events read in total (298ms).
[19:37:38.827] <TB3> INFO: Test took 1226ms.
[19:37:39.135] <TB3> INFO: Expecting 2560 events.
[19:37:40.021] <TB3> INFO: 2560 events read in total (294ms).
[19:37:40.022] <TB3> INFO: Test took 1195ms.
[19:37:40.329] <TB3> INFO: Expecting 2560 events.
[19:37:41.214] <TB3> INFO: 2560 events read in total (290ms).
[19:37:41.215] <TB3> INFO: Test took 1193ms.
[19:37:41.671] <TB3> INFO: Expecting 2560 events.
[19:37:44.512] <TB3> INFO: 2560 events read in total (2141ms).
[19:37:44.523] <TB3> INFO: Test took 3308ms.
[19:37:45.075] <TB3> INFO: Expecting 2560 events.
[19:37:47.828] <TB3> INFO: 2560 events read in total (2077ms).
[19:37:47.839] <TB3> INFO: Test took 3296ms.
[19:37:48.403] <TB3> INFO: Expecting 2560 events.
[19:37:51.397] <TB3> INFO: 2560 events read in total (2278ms).
[19:37:51.397] <TB3> INFO: Test took 3542ms.
[19:37:51.831] <TB3> INFO: Expecting 2560 events.
[19:37:54.588] <TB3> INFO: 2560 events read in total (2033ms).
[19:37:54.599] <TB3> INFO: Test took 3201ms.
[19:37:54.867] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[19:37:55.279] <TB3> INFO: Expecting 655360 events.
[19:38:11.176] <TB3> INFO: 655360 events read in total (15215ms).
[19:38:11.192] <TB3> INFO: Expecting 655360 events.
[19:38:25.558] <TB3> INFO: 655360 events read in total (13963ms).
[19:38:25.581] <TB3> INFO: Expecting 655360 events.
[19:39:00.560] <TB3> INFO: 655360 events read in total (34576ms).
[19:39:00.598] <TB3> INFO: Expecting 655360 events.
[19:39:31.057] <TB3> INFO: 655360 events read in total (30048ms).
[19:39:31.086] <TB3> INFO: Expecting 655360 events.
[19:39:45.412] <TB3> INFO: 655360 events read in total (13921ms).
[19:39:45.442] <TB3> INFO: Expecting 655360 events.
[19:40:31.862] <TB3> INFO: 655360 events read in total (46017ms).
[19:40:31.907] <TB3> INFO: Expecting 655360 events.
[19:41:02.443] <TB3> INFO: 655360 events read in total (30133ms).
[19:41:02.495] <TB3> INFO: Expecting 655360 events.
[19:41:16.839] <TB3> INFO: 655360 events read in total (13941ms).
[19:41:16.886] <TB3> INFO: Expecting 655360 events.
[19:42:18.816] <TB3> INFO: 655360 events read in total (61527ms).
[19:42:18.942] <TB3> INFO: Expecting 655360 events.
[19:42:33.288] <TB3> INFO: 655360 events read in total (13933ms).
[19:42:33.354] <TB3> INFO: Expecting 655360 events.
[19:42:47.753] <TB3> INFO: 655360 events read in total (13996ms).
[19:42:47.871] <TB3> INFO: Expecting 655360 events.
[19:43:30.200] <TB3> INFO: 655360 events read in total (41926ms).
[19:43:30.339] <TB3> INFO: Expecting 655360 events.
[19:43:54.571] <TB3> INFO: 655360 events read in total (23764ms).
[19:43:54.662] <TB3> INFO: Expecting 655360 events.
[19:44:08.000] <TB3> INFO: 655360 events read in total (13935ms).
[19:44:09.118] <TB3> INFO: Expecting 655360 events.
[19:44:52.919] <TB3> INFO: 655360 events read in total (43398ms).
[19:44:53.014] <TB3> INFO: Expecting 655360 events.
[19:45:22.452] <TB3> INFO: 655360 events read in total (29035ms).
[19:45:22.597] <TB3> INFO: Test took 447730ms.
[19:45:22.698] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[19:45:22.953] <TB3> INFO: Expecting 655360 events.
[19:45:37.510] <TB3> INFO: 655360 events read in total (13965ms).
[19:45:37.525] <TB3> INFO: Expecting 655360 events.
[19:46:37.385] <TB3> INFO: 655360 events read in total (59457ms).
[19:46:37.455] <TB3> INFO: Expecting 655360 events.
[19:46:52.986] <TB3> INFO: 655360 events read in total (15043ms).
[19:46:53.013] <TB3> INFO: Expecting 655360 events.
[19:47:07.361] <TB3> INFO: 655360 events read in total (13945ms).
[19:47:07.395] <TB3> INFO: Expecting 655360 events.
[19:48:09.731] <TB3> INFO: 655360 events read in total (61933ms).
[19:48:09.769] <TB3> INFO: Expecting 655360 events.
[19:48:24.182] <TB3> INFO: 655360 events read in total (14006ms).
[19:48:24.227] <TB3> INFO: Expecting 655360 events.
[19:48:38.627] <TB3> INFO: 655360 events read in total (13997ms).
[19:48:38.677] <TB3> INFO: Expecting 655360 events.
[19:49:28.294] <TB3> INFO: 655360 events read in total (49214ms).
[19:49:28.449] <TB3> INFO: Expecting 655360 events.
[19:49:50.115] <TB3> INFO: 655360 events read in total (21161ms).
[19:49:50.206] <TB3> INFO: Expecting 655360 events.
[19:50:04.695] <TB3> INFO: 655360 events read in total (14085ms).
[19:50:04.773] <TB3> INFO: Expecting 655360 events.
[19:50:33.795] <TB3> INFO: 655360 events read in total (28619ms).
[19:50:34.052] <TB3> INFO: Expecting 655360 events.
[19:51:08.965] <TB3> INFO: 655360 events read in total (34495ms).
[19:51:09.072] <TB3> INFO: Expecting 655360 events.
[19:51:23.428] <TB3> INFO: 655360 events read in total (13953ms).
[19:51:23.529] <TB3> INFO: Expecting 655360 events.
[19:51:41.216] <TB3> INFO: 655360 events read in total (17284ms).
[19:51:41.361] <TB3> INFO: Expecting 655360 events.
[19:52:19.073] <TB3> INFO: 655360 events read in total (37298ms).
[19:52:19.194] <TB3> INFO: Expecting 655360 events.
[19:52:48.552] <TB3> INFO: 655360 events read in total (28954ms).
[19:52:48.720] <TB3> INFO: Test took 446023ms.
[19:52:48.927] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[19:52:48.933] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[19:52:48.941] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[19:52:48.950] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[19:52:48.957] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[19:52:48.963] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[19:52:48.969] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[19:52:48.975] <TB3> INFO: safety margin for low PH: adding 1, margin is now 21
[19:52:48.982] <TB3> INFO: safety margin for low PH: adding 2, margin is now 22
[19:52:48.988] <TB3> INFO: safety margin for low PH: adding 3, margin is now 23
[19:52:48.994] <TB3> INFO: safety margin for low PH: adding 4, margin is now 24
[19:52:48.001] <TB3> INFO: safety margin for low PH: adding 5, margin is now 25
[19:52:49.007] <TB3> INFO: safety margin for low PH: adding 6, margin is now 26
[19:52:49.013] <TB3> INFO: safety margin for low PH: adding 7, margin is now 27
[19:52:49.019] <TB3> INFO: safety margin for low PH: adding 8, margin is now 28
[19:52:49.025] <TB3> INFO: safety margin for low PH: adding 9, margin is now 29
[19:52:49.032] <TB3> INFO: safety margin for low PH: adding 10, margin is now 30
[19:52:49.039] <TB3> INFO: safety margin for low PH: adding 11, margin is now 31
[19:52:49.045] <TB3> INFO: safety margin for low PH: adding 12, margin is now 32
[19:52:49.052] <TB3> INFO: safety margin for low PH: adding 13, margin is now 33
[19:52:49.057] <TB3> INFO: safety margin for low PH: adding 14, margin is now 34
[19:52:49.066] <TB3> INFO: safety margin for low PH: adding 15, margin is now 35
[19:52:49.074] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[19:52:49.084] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[19:52:49.092] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[19:52:49.099] <TB3> INFO: safety margin for low PH: adding 1, margin is now 21
[19:52:49.107] <TB3> INFO: safety margin for low PH: adding 2, margin is now 22
[19:52:49.115] <TB3> INFO: safety margin for low PH: adding 3, margin is now 23
[19:52:49.123] <TB3> INFO: safety margin for low PH: adding 4, margin is now 24
[19:52:49.132] <TB3> INFO: safety margin for low PH: adding 5, margin is now 25
[19:52:49.140] <TB3> INFO: safety margin for low PH: adding 6, margin is now 26
[19:52:49.148] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[19:52:49.156] <TB3> INFO: safety margin for low PH: adding 1, margin is now 21
[19:52:49.165] <TB3> INFO: safety margin for low PH: adding 2, margin is now 22
[19:52:49.171] <TB3> INFO: safety margin for low PH: adding 3, margin is now 23
[19:52:49.177] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[19:52:49.183] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[19:52:49.189] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[19:52:49.196] <TB3> INFO: safety margin for low PH: adding 1, margin is now 21
[19:52:49.202] <TB3> INFO: safety margin for low PH: adding 2, margin is now 22
[19:52:49.208] <TB3> INFO: safety margin for low PH: adding 3, margin is now 23
[19:52:49.214] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[19:52:49.221] <TB3> INFO: safety margin for low PH: adding 1, margin is now 21
[19:52:49.227] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[19:52:49.233] <TB3> INFO: safety margin for low PH: adding 1, margin is now 21
[19:52:49.240] <TB3> INFO: safety margin for low PH: adding 2, margin is now 22
[19:52:49.246] <TB3> INFO: safety margin for low PH: adding 3, margin is now 23
[19:52:49.252] <TB3> INFO: safety margin for low PH: adding 4, margin is now 24
[19:52:49.258] <TB3> INFO: safety margin for low PH: adding 5, margin is now 25
[19:52:49.265] <TB3> INFO: safety margin for low PH: adding 6, margin is now 26
[19:52:49.271] <TB3> INFO: safety margin for low PH: adding 7, margin is now 27
[19:52:49.277] <TB3> INFO: safety margin for low PH: adding 8, margin is now 28
[19:52:49.283] <TB3> INFO: safety margin for low PH: adding 9, margin is now 29
[19:52:49.327] <TB3> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1033_FullQualification_2016-11-04_17h17m_1478276226//001_Fulltest_m20//dacParameters35_C0.dat
[19:52:49.328] <TB3> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1033_FullQualification_2016-11-04_17h17m_1478276226//001_Fulltest_m20//dacParameters35_C1.dat
[19:52:49.328] <TB3> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1033_FullQualification_2016-11-04_17h17m_1478276226//001_Fulltest_m20//dacParameters35_C2.dat
[19:52:49.328] <TB3> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1033_FullQualification_2016-11-04_17h17m_1478276226//001_Fulltest_m20//dacParameters35_C3.dat
[19:52:49.328] <TB3> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1033_FullQualification_2016-11-04_17h17m_1478276226//001_Fulltest_m20//dacParameters35_C4.dat
[19:52:49.328] <TB3> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1033_FullQualification_2016-11-04_17h17m_1478276226//001_Fulltest_m20//dacParameters35_C5.dat
[19:52:49.329] <TB3> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1033_FullQualification_2016-11-04_17h17m_1478276226//001_Fulltest_m20//dacParameters35_C6.dat
[19:52:49.329] <TB3> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1033_FullQualification_2016-11-04_17h17m_1478276226//001_Fulltest_m20//dacParameters35_C7.dat
[19:52:49.329] <TB3> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1033_FullQualification_2016-11-04_17h17m_1478276226//001_Fulltest_m20//dacParameters35_C8.dat
[19:52:49.329] <TB3> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1033_FullQualification_2016-11-04_17h17m_1478276226//001_Fulltest_m20//dacParameters35_C9.dat
[19:52:49.329] <TB3> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1033_FullQualification_2016-11-04_17h17m_1478276226//001_Fulltest_m20//dacParameters35_C10.dat
[19:52:49.329] <TB3> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1033_FullQualification_2016-11-04_17h17m_1478276226//001_Fulltest_m20//dacParameters35_C11.dat
[19:52:49.329] <TB3> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1033_FullQualification_2016-11-04_17h17m_1478276226//001_Fulltest_m20//dacParameters35_C12.dat
[19:52:49.330] <TB3> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1033_FullQualification_2016-11-04_17h17m_1478276226//001_Fulltest_m20//dacParameters35_C13.dat
[19:52:49.330] <TB3> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1033_FullQualification_2016-11-04_17h17m_1478276226//001_Fulltest_m20//dacParameters35_C14.dat
[19:52:49.330] <TB3> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1033_FullQualification_2016-11-04_17h17m_1478276226//001_Fulltest_m20//dacParameters35_C15.dat
[19:52:49.576] <TB3> INFO: Expecting 41600 events.
[19:52:52.694] <TB3> INFO: 41600 events read in total (2526ms).
[19:52:52.695] <TB3> INFO: Test took 3362ms.
[19:52:53.146] <TB3> INFO: Expecting 41600 events.
[19:52:56.184] <TB3> INFO: 41600 events read in total (2446ms).
[19:52:56.185] <TB3> INFO: Test took 3280ms.
[19:52:56.637] <TB3> INFO: Expecting 41600 events.
[19:52:59.794] <TB3> INFO: 41600 events read in total (2565ms).
[19:52:59.794] <TB3> INFO: Test took 3396ms.
[19:53:00.011] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[19:53:00.099] <TB3> INFO: Expecting 2560 events.
[19:53:00.985] <TB3> INFO: 2560 events read in total (294ms).
[19:53:00.985] <TB3> INFO: Test took 974ms.
[19:53:00.988] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[19:53:01.294] <TB3> INFO: Expecting 2560 events.
[19:53:02.177] <TB3> INFO: 2560 events read in total (291ms).
[19:53:02.177] <TB3> INFO: Test took 1189ms.
[19:53:02.180] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[19:53:02.486] <TB3> INFO: Expecting 2560 events.
[19:53:03.373] <TB3> INFO: 2560 events read in total (295ms).
[19:53:03.373] <TB3> INFO: Test took 1193ms.
[19:53:03.375] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[19:53:03.681] <TB3> INFO: Expecting 2560 events.
[19:53:04.566] <TB3> INFO: 2560 events read in total (293ms).
[19:53:04.566] <TB3> INFO: Test took 1191ms.
[19:53:04.569] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[19:53:04.874] <TB3> INFO: Expecting 2560 events.
[19:53:05.762] <TB3> INFO: 2560 events read in total (296ms).
[19:53:05.762] <TB3> INFO: Test took 1193ms.
[19:53:05.765] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[19:53:06.070] <TB3> INFO: Expecting 2560 events.
[19:53:09.012] <TB3> INFO: 2560 events read in total (2237ms).
[19:53:09.023] <TB3> INFO: Test took 3258ms.
[19:53:09.171] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[19:53:09.569] <TB3> INFO: Expecting 2560 events.
[19:53:10.463] <TB3> INFO: 2560 events read in total (296ms).
[19:53:10.464] <TB3> INFO: Test took 1293ms.
[19:53:10.465] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[19:53:10.771] <TB3> INFO: Expecting 2560 events.
[19:53:13.904] <TB3> INFO: 2560 events read in total (2538ms).
[19:53:13.915] <TB3> INFO: Test took 3450ms.
[19:53:13.988] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[19:53:14.205] <TB3> INFO: Expecting 2560 events.
[19:53:15.269] <TB3> INFO: 2560 events read in total (394ms).
[19:53:15.270] <TB3> INFO: Test took 1282ms.
[19:53:15.274] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[19:53:15.578] <TB3> INFO: Expecting 2560 events.
[19:53:16.581] <TB3> INFO: 2560 events read in total (412ms).
[19:53:16.588] <TB3> INFO: Test took 1314ms.
[19:53:16.620] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[19:53:17.015] <TB3> INFO: Expecting 2560 events.
[19:53:18.096] <TB3> INFO: 2560 events read in total (441ms).
[19:53:18.115] <TB3> INFO: Test took 1495ms.
[19:53:18.136] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[19:53:18.417] <TB3> INFO: Expecting 2560 events.
[19:53:21.208] <TB3> INFO: 2560 events read in total (2184ms).
[19:53:21.209] <TB3> INFO: Test took 3073ms.
[19:53:21.217] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[19:53:21.515] <TB3> INFO: Expecting 2560 events.
[19:53:22.437] <TB3> INFO: 2560 events read in total (331ms).
[19:53:22.440] <TB3> INFO: Test took 1224ms.
[19:53:22.504] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[19:53:22.935] <TB3> INFO: Expecting 2560 events.
[19:53:25.423] <TB3> INFO: 2560 events read in total (1875ms).
[19:53:25.431] <TB3> INFO: Test took 2927ms.
[19:53:25.567] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[19:53:25.995] <TB3> INFO: Expecting 2560 events.
[19:53:27.884] <TB3> INFO: 2560 events read in total (1187ms).
[19:53:27.895] <TB3> INFO: Test took 2328ms.
[19:53:28.055] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[19:53:28.575] <TB3> INFO: Expecting 2560 events.
[19:53:31.684] <TB3> INFO: 2560 events read in total (2397ms).
[19:53:31.691] <TB3> INFO: Test took 3636ms.
[19:53:31.819] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[19:53:32.262] <TB3> INFO: Expecting 2560 events.
[19:53:34.727] <TB3> INFO: 2560 events read in total (1780ms).
[19:53:34.735] <TB3> INFO: Test took 2916ms.
[19:53:34.875] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[19:53:35.359] <TB3> INFO: Expecting 2560 events.
[19:53:38.180] <TB3> INFO: 2560 events read in total (2117ms).
[19:53:38.191] <TB3> INFO: Test took 3316ms.
[19:53:38.297] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[19:53:38.533] <TB3> INFO: Expecting 2560 events.
[19:53:41.495] <TB3> INFO: 2560 events read in total (2320ms).
[19:53:41.509] <TB3> INFO: Test took 3212ms.
[19:53:41.520] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[19:53:41.799] <TB3> INFO: Expecting 2560 events.
[19:53:42.701] <TB3> INFO: 2560 events read in total (294ms).
[19:53:42.701] <TB3> INFO: Test took 1181ms.
[19:53:42.703] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[19:53:43.009] <TB3> INFO: Expecting 2560 events.
[19:53:43.895] <TB3> INFO: 2560 events read in total (294ms).
[19:53:43.895] <TB3> INFO: Test took 1192ms.
[19:53:43.897] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[19:53:44.204] <TB3> INFO: Expecting 2560 events.
[19:53:45.082] <TB3> INFO: 2560 events read in total (286ms).
[19:53:45.083] <TB3> INFO: Test took 1186ms.
[19:53:45.085] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[19:53:45.391] <TB3> INFO: Expecting 2560 events.
[19:53:46.271] <TB3> INFO: 2560 events read in total (288ms).
[19:53:46.271] <TB3> INFO: Test took 1186ms.
[19:53:46.274] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[19:53:46.580] <TB3> INFO: Expecting 2560 events.
[19:53:47.458] <TB3> INFO: 2560 events read in total (286ms).
[19:53:47.458] <TB3> INFO: Test took 1185ms.
[19:53:47.460] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[19:53:48.015] <TB3> INFO: Expecting 2560 events.
[19:53:49.574] <TB3> INFO: 2560 events read in total (907ms).
[19:53:49.580] <TB3> INFO: Test took 2120ms.
[19:53:49.631] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[19:53:49.925] <TB3> INFO: Expecting 2560 events.
[19:53:52.310] <TB3> INFO: 2560 events read in total (1744ms).
[19:53:52.316] <TB3> INFO: Test took 2685ms.
[19:53:52.439] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[19:53:52.875] <TB3> INFO: Expecting 2560 events.
[19:53:56.032] <TB3> INFO: 2560 events read in total (2469ms).
[19:53:56.043] <TB3> INFO: Test took 3604ms.
[19:53:56.199] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[19:53:56.647] <TB3> INFO: Expecting 2560 events.
[19:53:58.443] <TB3> INFO: 2560 events read in total (1096ms).
[19:53:58.451] <TB3> INFO: Test took 2252ms.
[19:53:58.583] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[19:53:58.935] <TB3> INFO: Expecting 2560 events.
[19:54:01.648] <TB3> INFO: 2560 events read in total (2029ms).
[19:54:01.659] <TB3> INFO: Test took 3076ms.
[19:54:01.779] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[19:54:02.255] <TB3> INFO: Expecting 2560 events.
[19:54:05.215] <TB3> INFO: 2560 events read in total (2260ms).
[19:54:05.215] <TB3> INFO: Test took 3436ms.
[19:54:05.217] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[19:54:05.723] <TB3> INFO: Expecting 2560 events.
[19:54:06.646] <TB3> INFO: 2560 events read in total (298ms).
[19:54:06.653] <TB3> INFO: Test took 1436ms.
[19:54:06.658] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[19:54:06.983] <TB3> INFO: Expecting 2560 events.
[19:54:07.887] <TB3> INFO: 2560 events read in total (311ms).
[19:54:07.888] <TB3> INFO: Test took 1231ms.
[19:54:08.351] <TB3> INFO: PixTestPhOptimization::doTest() done, duration: 1397 seconds
[19:54:08.351] <TB3> INFO: PH scale (per ROC): 51 41 44 49 46 43 43 49 63 48 38 50 49 38 35 50
[19:54:08.351] <TB3> INFO: PH offset (per ROC): 134 100 121 137 132 87 112 124 132 139 98 138 132 90 104 125
[19:54:08.361] <TB3> INFO: Decoding statistics:
[19:54:08.361] <TB3> INFO: General information:
[19:54:08.361] <TB3> INFO: 16bit words read: 127888
[19:54:08.361] <TB3> INFO: valid events total: 20480
[19:54:08.361] <TB3> INFO: empty events: 17976
[19:54:08.361] <TB3> INFO: valid events with pixels: 2504
[19:54:08.361] <TB3> INFO: valid pixel hits: 2504
[19:54:08.361] <TB3> INFO: Event errors: 0
[19:54:08.361] <TB3> INFO: start marker: 0
[19:54:08.361] <TB3> INFO: stop marker: 0
[19:54:08.361] <TB3> INFO: overflow: 0
[19:54:08.361] <TB3> INFO: invalid 5bit words: 0
[19:54:08.361] <TB3> INFO: invalid XOR eye diagram: 0
[19:54:08.361] <TB3> INFO: frame (failed synchr.): 0
[19:54:08.361] <TB3> INFO: idle data (no TBM trl): 0
[19:54:08.361] <TB3> INFO: no data (only TBM hdr): 0
[19:54:08.361] <TB3> INFO: TBM errors: 0
[19:54:08.361] <TB3> INFO: flawed TBM headers: 0
[19:54:08.361] <TB3> INFO: flawed TBM trailers: 0
[19:54:08.361] <TB3> INFO: event ID mismatches: 0
[19:54:08.361] <TB3> INFO: ROC errors: 0
[19:54:08.361] <TB3> INFO: missing ROC header(s): 0
[19:54:08.361] <TB3> INFO: misplaced readback start: 0
[19:54:08.361] <TB3> INFO: Pixel decoding errors: 0
[19:54:08.361] <TB3> INFO: pixel data incomplete: 0
[19:54:08.361] <TB3> INFO: pixel address: 0
[19:54:08.361] <TB3> INFO: pulse height fill bit: 0
[19:54:08.361] <TB3> INFO: buffer corruption: 0
[19:54:08.552] <TB3> INFO: ######################################################################
[19:54:08.552] <TB3> INFO: PixTestGainPedestal::fullTest() ntrig = 10
[19:54:08.552] <TB3> INFO: ######################################################################
[19:54:08.567] <TB3> INFO: scanning low vcal = 10
[19:54:08.825] <TB3> INFO: Expecting 41600 events.
[19:54:12.432] <TB3> INFO: 41600 events read in total (3016ms).
[19:54:12.432] <TB3> INFO: Test took 3864ms.
[19:54:12.434] <TB3> INFO: scanning low vcal = 20
[19:54:12.729] <TB3> INFO: Expecting 41600 events.
[19:54:16.355] <TB3> INFO: 41600 events read in total (3035ms).
[19:54:16.356] <TB3> INFO: Test took 3922ms.
[19:54:16.357] <TB3> INFO: scanning low vcal = 30
[19:54:16.653] <TB3> INFO: Expecting 41600 events.
[19:54:20.316] <TB3> INFO: 41600 events read in total (3071ms).
[19:54:20.318] <TB3> INFO: Test took 3960ms.
[19:54:20.320] <TB3> INFO: scanning low vcal = 40
[19:54:20.597] <TB3> INFO: Expecting 41600 events.
[19:54:24.583] <TB3> INFO: 41600 events read in total (3394ms).
[19:54:24.585] <TB3> INFO: Test took 4264ms.
[19:54:24.588] <TB3> INFO: scanning low vcal = 50
[19:54:24.865] <TB3> INFO: Expecting 41600 events.
[19:54:28.839] <TB3> INFO: 41600 events read in total (3381ms).
[19:54:28.840] <TB3> INFO: Test took 4252ms.
[19:54:28.845] <TB3> INFO: scanning low vcal = 60
[19:54:29.121] <TB3> INFO: Expecting 41600 events.
[19:54:33.124] <TB3> INFO: 41600 events read in total (3412ms).
[19:54:33.125] <TB3> INFO: Test took 4280ms.
[19:54:33.128] <TB3> INFO: scanning low vcal = 70
[19:54:33.404] <TB3> INFO: Expecting 41600 events.
[19:54:37.402] <TB3> INFO: 41600 events read in total (3406ms).
[19:54:37.403] <TB3> INFO: Test took 4275ms.
[19:54:37.407] <TB3> INFO: scanning low vcal = 80
[19:54:37.700] <TB3> INFO: Expecting 41600 events.
[19:54:43.189] <TB3> INFO: 41600 events read in total (4897ms).
[19:54:43.190] <TB3> INFO: Test took 5783ms.
[19:54:43.194] <TB3> INFO: scanning low vcal = 90
[19:54:43.470] <TB3> INFO: Expecting 41600 events.
[19:54:48.382] <TB3> INFO: 41600 events read in total (4319ms).
[19:54:48.383] <TB3> INFO: Test took 5188ms.
[19:54:48.387] <TB3> INFO: scanning low vcal = 100
[19:54:48.739] <TB3> INFO: Expecting 41600 events.
[19:54:53.739] <TB3> INFO: 41600 events read in total (4408ms).
[19:54:53.741] <TB3> INFO: Test took 5354ms.
[19:54:53.746] <TB3> INFO: scanning low vcal = 110
[19:54:54.075] <TB3> INFO: Expecting 41600 events.
[19:55:10.433] <TB3> INFO: 41600 events read in total (15746ms).
[19:55:10.434] <TB3> INFO: Test took 16687ms.
[19:55:10.437] <TB3> INFO: scanning low vcal = 120
[19:55:10.780] <TB3> INFO: Expecting 41600 events.
[19:55:14.942] <TB3> INFO: 41600 events read in total (3567ms).
[19:55:14.943] <TB3> INFO: Test took 4507ms.
[19:55:14.947] <TB3> INFO: scanning low vcal = 130
[19:55:15.280] <TB3> INFO: Expecting 41600 events.
[19:55:24.679] <TB3> INFO: 41600 events read in total (8808ms).
[19:55:24.680] <TB3> INFO: Test took 9733ms.
[19:55:24.683] <TB3> INFO: scanning low vcal = 140
[19:55:25.099] <TB3> INFO: Expecting 41600 events.
[19:55:31.196] <TB3> INFO: 41600 events read in total (5499ms).
[19:55:31.208] <TB3> INFO: Test took 6525ms.
[19:55:31.339] <TB3> INFO: scanning low vcal = 150
[19:55:31.856] <TB3> INFO: Expecting 41600 events.
[19:55:37.022] <TB3> INFO: 41600 events read in total (4574ms).
[19:55:37.025] <TB3> INFO: Test took 5678ms.
[19:55:37.034] <TB3> INFO: scanning low vcal = 160
[19:55:37.597] <TB3> INFO: Expecting 41600 events.
[19:55:41.631] <TB3> INFO: 41600 events read in total (3426ms).
[19:55:41.631] <TB3> INFO: Test took 4598ms.
[19:55:41.635] <TB3> INFO: scanning low vcal = 170
[19:55:41.915] <TB3> INFO: Expecting 41600 events.
[19:55:45.925] <TB3> INFO: 41600 events read in total (3419ms).
[19:55:45.926] <TB3> INFO: Test took 4291ms.
[19:55:45.933] <TB3> INFO: scanning low vcal = 180
[19:55:46.205] <TB3> INFO: Expecting 41600 events.
[19:55:50.184] <TB3> INFO: 41600 events read in total (3387ms).
[19:55:50.185] <TB3> INFO: Test took 4251ms.
[19:55:50.188] <TB3> INFO: scanning low vcal = 190
[19:55:50.478] <TB3> INFO: Expecting 41600 events.
[19:55:54.479] <TB3> INFO: 41600 events read in total (3409ms).
[19:55:54.480] <TB3> INFO: Test took 4291ms.
[19:55:54.483] <TB3> INFO: scanning low vcal = 200
[19:55:54.759] <TB3> INFO: Expecting 41600 events.
[19:55:58.725] <TB3> INFO: 41600 events read in total (3374ms).
[19:55:58.726] <TB3> INFO: Test took 4243ms.
[19:55:58.729] <TB3> INFO: scanning low vcal = 210
[19:55:59.006] <TB3> INFO: Expecting 41600 events.
[19:56:02.001] <TB3> INFO: 41600 events read in total (3403ms).
[19:56:02.002] <TB3> INFO: Test took 4272ms.
[19:56:03.006] <TB3> INFO: scanning low vcal = 220
[19:56:03.281] <TB3> INFO: Expecting 41600 events.
[19:56:09.862] <TB3> INFO: 41600 events read in total (5989ms).
[19:56:09.864] <TB3> INFO: Test took 6858ms.
[19:56:09.895] <TB3> INFO: scanning low vcal = 230
[19:56:10.883] <TB3> INFO: Expecting 41600 events.
[19:56:16.683] <TB3> INFO: 41600 events read in total (5116ms).
[19:56:16.684] <TB3> INFO: Test took 6781ms.
[19:56:16.687] <TB3> INFO: scanning low vcal = 240
[19:56:16.975] <TB3> INFO: Expecting 41600 events.
[19:56:37.710] <TB3> INFO: 41600 events read in total (20130ms).
[19:56:37.713] <TB3> INFO: Test took 21026ms.
[19:56:37.718] <TB3> INFO: scanning low vcal = 250
[19:56:38.040] <TB3> INFO: Expecting 41600 events.
[19:56:43.829] <TB3> INFO: 41600 events read in total (5190ms).
[19:56:43.842] <TB3> INFO: Test took 6124ms.
[19:56:43.877] <TB3> INFO: scanning high vcal = 30 (= 210 in low range)
[19:56:44.224] <TB3> INFO: Expecting 41600 events.
[19:56:49.274] <TB3> INFO: 41600 events read in total (4415ms).
[19:56:49.276] <TB3> INFO: Test took 5398ms.
[19:56:49.403] <TB3> INFO: scanning high vcal = 50 (= 350 in low range)
[19:56:50.059] <TB3> INFO: Expecting 41600 events.
[19:56:59.758] <TB3> INFO: 41600 events read in total (9099ms).
[19:56:59.759] <TB3> INFO: Test took 10345ms.
[19:56:59.762] <TB3> INFO: scanning high vcal = 70 (= 490 in low range)
[19:57:00.089] <TB3> INFO: Expecting 41600 events.
[19:57:04.074] <TB3> INFO: 41600 events read in total (3393ms).
[19:57:04.075] <TB3> INFO: Test took 4313ms.
[19:57:04.080] <TB3> INFO: scanning high vcal = 90 (= 630 in low range)
[19:57:04.354] <TB3> INFO: Expecting 41600 events.
[19:57:08.371] <TB3> INFO: 41600 events read in total (3425ms).
[19:57:08.372] <TB3> INFO: Test took 4292ms.
[19:57:08.375] <TB3> INFO: scanning high vcal = 200 (= 1400 in low range)
[19:57:08.652] <TB3> INFO: Expecting 41600 events.
[19:57:12.757] <TB3> INFO: 41600 events read in total (3514ms).
[19:57:12.758] <TB3> INFO: Test took 4383ms.
[19:57:13.418] <TB3> INFO: PixTestGainPedestal::measure() done
[19:57:54.707] <TB3> INFO: PixTestGainPedestal::fit() done
[19:57:54.707] <TB3> INFO: non-linearity mean: 0.972 0.907 0.970 0.972 0.970 0.960 0.945 0.977 0.979 0.976 0.939 0.967 0.975 0.970 0.915 0.977
[19:57:54.707] <TB3> INFO: non-linearity RMS: 0.004 0.127 0.005 0.006 0.004 0.170 0.040 0.002 0.003 0.004 0.158 0.005 0.005 0.187 0.068 0.004
[19:57:54.707] <TB3> INFO: write gain/ped parameters into /home/l_tester/david/FullQualification/data/M1033_FullQualification_2016-11-04_17h17m_1478276226//001_Fulltest_m20//phCalibrationFitErr35_C0.dat
[19:57:54.721] <TB3> INFO: write gain/ped parameters into /home/l_tester/david/FullQualification/data/M1033_FullQualification_2016-11-04_17h17m_1478276226//001_Fulltest_m20//phCalibrationFitErr35_C1.dat
[19:57:54.735] <TB3> INFO: write gain/ped parameters into /home/l_tester/david/FullQualification/data/M1033_FullQualification_2016-11-04_17h17m_1478276226//001_Fulltest_m20//phCalibrationFitErr35_C2.dat
[19:57:54.748] <TB3> INFO: write gain/ped parameters into /home/l_tester/david/FullQualification/data/M1033_FullQualification_2016-11-04_17h17m_1478276226//001_Fulltest_m20//phCalibrationFitErr35_C3.dat
[19:57:54.762] <TB3> INFO: write gain/ped parameters into /home/l_tester/david/FullQualification/data/M1033_FullQualification_2016-11-04_17h17m_1478276226//001_Fulltest_m20//phCalibrationFitErr35_C4.dat
[19:57:54.777] <TB3> INFO: write gain/ped parameters into /home/l_tester/david/FullQualification/data/M1033_FullQualification_2016-11-04_17h17m_1478276226//001_Fulltest_m20//phCalibrationFitErr35_C5.dat
[19:57:54.790] <TB3> INFO: write gain/ped parameters into /home/l_tester/david/FullQualification/data/M1033_FullQualification_2016-11-04_17h17m_1478276226//001_Fulltest_m20//phCalibrationFitErr35_C6.dat
[19:57:54.803] <TB3> INFO: write gain/ped parameters into /home/l_tester/david/FullQualification/data/M1033_FullQualification_2016-11-04_17h17m_1478276226//001_Fulltest_m20//phCalibrationFitErr35_C7.dat
[19:57:54.815] <TB3> INFO: write gain/ped parameters into /home/l_tester/david/FullQualification/data/M1033_FullQualification_2016-11-04_17h17m_1478276226//001_Fulltest_m20//phCalibrationFitErr35_C8.dat
[19:57:54.828] <TB3> INFO: write gain/ped parameters into /home/l_tester/david/FullQualification/data/M1033_FullQualification_2016-11-04_17h17m_1478276226//001_Fulltest_m20//phCalibrationFitErr35_C9.dat
[19:57:54.841] <TB3> INFO: write gain/ped parameters into /home/l_tester/david/FullQualification/data/M1033_FullQualification_2016-11-04_17h17m_1478276226//001_Fulltest_m20//phCalibrationFitErr35_C10.dat
[19:57:54.855] <TB3> INFO: write gain/ped parameters into /home/l_tester/david/FullQualification/data/M1033_FullQualification_2016-11-04_17h17m_1478276226//001_Fulltest_m20//phCalibrationFitErr35_C11.dat
[19:57:54.868] <TB3> INFO: write gain/ped parameters into /home/l_tester/david/FullQualification/data/M1033_FullQualification_2016-11-04_17h17m_1478276226//001_Fulltest_m20//phCalibrationFitErr35_C12.dat
[19:57:54.881] <TB3> INFO: write gain/ped parameters into /home/l_tester/david/FullQualification/data/M1033_FullQualification_2016-11-04_17h17m_1478276226//001_Fulltest_m20//phCalibrationFitErr35_C13.dat
[19:57:54.894] <TB3> INFO: write gain/ped parameters into /home/l_tester/david/FullQualification/data/M1033_FullQualification_2016-11-04_17h17m_1478276226//001_Fulltest_m20//phCalibrationFitErr35_C14.dat
[19:57:54.907] <TB3> INFO: write gain/ped parameters into /home/l_tester/david/FullQualification/data/M1033_FullQualification_2016-11-04_17h17m_1478276226//001_Fulltest_m20//phCalibrationFitErr35_C15.dat
[19:57:54.920] <TB3> INFO: PixTestGainPedestal::fullTest() done, duration: 226 seconds
[19:57:54.920] <TB3> INFO: Decoding statistics:
[19:57:54.920] <TB3> INFO: General information:
[19:57:54.920] <TB3> INFO: 16bit words read: 3327862
[19:57:54.920] <TB3> INFO: valid events total: 332800
[19:57:54.920] <TB3> INFO: empty events: 0
[19:57:54.920] <TB3> INFO: valid events with pixels: 332800
[19:57:54.920] <TB3> INFO: valid pixel hits: 665531
[19:57:54.920] <TB3> INFO: Event errors: 0
[19:57:54.920] <TB3> INFO: start marker: 0
[19:57:54.920] <TB3> INFO: stop marker: 0
[19:57:54.920] <TB3> INFO: overflow: 0
[19:57:54.920] <TB3> INFO: invalid 5bit words: 0
[19:57:54.920] <TB3> INFO: invalid XOR eye diagram: 0
[19:57:54.920] <TB3> INFO: frame (failed synchr.): 0
[19:57:54.920] <TB3> INFO: idle data (no TBM trl): 0
[19:57:54.920] <TB3> INFO: no data (only TBM hdr): 0
[19:57:54.920] <TB3> INFO: TBM errors: 0
[19:57:54.920] <TB3> INFO: flawed TBM headers: 0
[19:57:54.920] <TB3> INFO: flawed TBM trailers: 0
[19:57:54.920] <TB3> INFO: event ID mismatches: 0
[19:57:54.920] <TB3> INFO: ROC errors: 0
[19:57:54.920] <TB3> INFO: missing ROC header(s): 0
[19:57:54.920] <TB3> INFO: misplaced readback start: 0
[19:57:54.920] <TB3> INFO: Pixel decoding errors: 0
[19:57:54.920] <TB3> INFO: pixel data incomplete: 0
[19:57:54.920] <TB3> INFO: pixel address: 0
[19:57:54.920] <TB3> INFO: pulse height fill bit: 0
[19:57:54.920] <TB3> INFO: buffer corruption: 0
[19:57:54.937] <TB3> INFO: Decoding statistics:
[19:57:54.937] <TB3> INFO: General information:
[19:57:54.937] <TB3> INFO: 16bit words read: 3457286
[19:57:54.937] <TB3> INFO: valid events total: 353536
[19:57:54.937] <TB3> INFO: empty events: 18232
[19:57:54.937] <TB3> INFO: valid events with pixels: 335304
[19:57:54.937] <TB3> INFO: valid pixel hits: 668035
[19:57:54.937] <TB3> INFO: Event errors: 0
[19:57:54.937] <TB3> INFO: start marker: 0
[19:57:54.938] <TB3> INFO: stop marker: 0
[19:57:54.938] <TB3> INFO: overflow: 0
[19:57:54.938] <TB3> INFO: invalid 5bit words: 0
[19:57:54.938] <TB3> INFO: invalid XOR eye diagram: 0
[19:57:54.938] <TB3> INFO: frame (failed synchr.): 0
[19:57:54.938] <TB3> INFO: idle data (no TBM trl): 0
[19:57:54.938] <TB3> INFO: no data (only TBM hdr): 0
[19:57:54.938] <TB3> INFO: TBM errors: 0
[19:57:54.938] <TB3> INFO: flawed TBM headers: 0
[19:57:54.938] <TB3> INFO: flawed TBM trailers: 0
[19:57:54.938] <TB3> INFO: event ID mismatches: 0
[19:57:54.938] <TB3> INFO: ROC errors: 0
[19:57:54.938] <TB3> INFO: missing ROC header(s): 0
[19:57:54.938] <TB3> INFO: misplaced readback start: 0
[19:57:54.938] <TB3> INFO: Pixel decoding errors: 0
[19:57:54.938] <TB3> INFO: pixel data incomplete: 0
[19:57:54.938] <TB3> INFO: pixel address: 0
[19:57:54.938] <TB3> INFO: pulse height fill bit: 0
[19:57:54.938] <TB3> INFO: buffer corruption: 0
[19:57:54.938] <TB3> INFO: enter test to run
[19:57:54.938] <TB3> INFO: test: exit no parameter change
[19:57:55.080] <TB3> QUIET: Connection to board 126 closed.
[19:57:55.081] <TB3> INFO: pXar: this is the end, my friend