[18:56:43.043] <TB3> INFO: *** Welcome to pxar ***
[18:56:43.043] <TB3> INFO: *** Today: 2016/10/25
[18:56:43.049] <TB3> INFO: *** Version: c8ba-dirty
[18:56:43.049] <TB3> INFO: readRocDacs: /home/l_tester/david/FullQualification/data/M1080_FullQualification_2016-10-25_15h54m_1477403681//003_Fulltest_p17//dacParameters_C0.dat .. /home/l_tester/david/FullQualification/data/M1080_FullQualification_2016-10-25_15h54m_1477403681//003_Fulltest_p17//dacParameters_C15.dat
[18:56:43.050] <TB3> INFO: readTbmDacs: /home/l_tester/david/FullQualification/data/M1080_FullQualification_2016-10-25_15h54m_1477403681//003_Fulltest_p17//tbmParameters_C0a.dat .. /home/l_tester/david/FullQualification/data/M1080_FullQualification_2016-10-25_15h54m_1477403681//003_Fulltest_p17//tbmParameters_C1b.dat
[18:56:43.050] <TB3> INFO: readMaskFile: /home/l_tester/david/FullQualification/data/M1080_FullQualification_2016-10-25_15h54m_1477403681//003_Fulltest_p17//defaultMaskFile.dat
[18:56:43.050] <TB3> INFO: readTrimFile: /home/l_tester/david/FullQualification/data/M1080_FullQualification_2016-10-25_15h54m_1477403681//003_Fulltest_p17//trimParameters_C0.dat .. /home/l_tester/david/FullQualification/data/M1080_FullQualification_2016-10-25_15h54m_1477403681//003_Fulltest_p17//trimParameters_C15.dat
[18:56:43.106] <TB3> INFO: clk: 4
[18:56:43.106] <TB3> INFO: ctr: 4
[18:56:43.106] <TB3> INFO: sda: 19
[18:56:43.106] <TB3> INFO: tin: 9
[18:56:43.106] <TB3> INFO: level: 15
[18:56:43.106] <TB3> INFO: triggerdelay: 0
[18:56:43.106] <TB3> QUIET: Instanciating API for pxar v2.1.0+867~g2c7f7f2
[18:56:43.106] <TB3> INFO: Log level: INFO
[18:56:43.115] <TB3> INFO: Found DTB DTB_WZ4I6J
[18:56:43.123] <TB3> QUIET: Connection to board DTB_WZ4I6J opened.
[18:56:43.125] <TB3> INFO: DTB startup information
--- DTB info------------------------------------------
Board id: 170
HW version: DTB1.2
FW version: 4.6
SW version: 4.7
Options:
USB id: DTB_WZ4I6J
MAC address: 40D8551180AA
Hostname: pixelDTB170
Comment:
------------------------------------------------------
[18:56:43.127] <TB3> INFO: RPC call hashes of host and DTB match: 486171790
[18:56:44.622] <TB3> INFO: DUT info:
[18:56:44.622] <TB3> INFO: The DUT currently contains the following objects:
[18:56:44.622] <TB3> INFO: 4 TBM Cores tbm10c (4 ON)
[18:56:44.622] <TB3> INFO: TBM Core alpha (0): 7 registers set
[18:56:44.622] <TB3> INFO: TBM Core beta (1): 7 registers set
[18:56:44.622] <TB3> INFO: TBM Core alpha (2): 7 registers set
[18:56:44.622] <TB3> INFO: TBM Core beta (3): 7 registers set
[18:56:44.622] <TB3> INFO: 16 ROCs proc600 (16 ON) with 4160 pixelConfigs
[18:56:44.622] <TB3> INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[18:56:44.622] <TB3> INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[18:56:44.622] <TB3> INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[18:56:44.622] <TB3> INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[18:56:44.623] <TB3> INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[18:56:44.623] <TB3> INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[18:56:44.623] <TB3> INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[18:56:44.623] <TB3> INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[18:56:44.623] <TB3> INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[18:56:44.623] <TB3> INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[18:56:44.623] <TB3> INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[18:56:44.623] <TB3> INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[18:56:44.623] <TB3> INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[18:56:44.623] <TB3> INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[18:56:44.623] <TB3> INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[18:56:44.623] <TB3> INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[18:56:45.024] <TB3> INFO: enter 'restricted' command line mode
[18:56:45.024] <TB3> INFO: enter test to run
[18:56:45.024] <TB3> INFO: test: pretest no parameter change
[18:56:45.024] <TB3> INFO: running: pretest
[18:56:45.612] <TB3> INFO: ######################################################################
[18:56:45.612] <TB3> INFO: PixTestPretest::doTest()
[18:56:45.612] <TB3> INFO: ######################################################################
[18:56:45.613] <TB3> INFO: ----------------------------------------------------------------------
[18:56:45.613] <TB3> INFO: PixTestPretest::programROC()
[18:56:45.613] <TB3> INFO: ----------------------------------------------------------------------
[18:57:03.626] <TB3> INFO: PixTestPretest::programROC() done: ROCs are all programmable
[18:57:03.626] <TB3> INFO: IA differences per ROC: 18.5 19.3 17.7 18.5 19.3 18.5 18.5 19.3 19.3 19.3 17.7 20.1 16.1 19.3 18.5 20.9
[18:57:03.663] <TB3> INFO: ----------------------------------------------------------------------
[18:57:03.663] <TB3> INFO: PixTestPretest::setVana() target Ia = 24 mA/ROC
[18:57:03.663] <TB3> INFO: ----------------------------------------------------------------------
[18:57:24.913] <TB3> INFO: PixTestPretest::setVana() done, Module Ia 384.3 mA = 24.0187 mA/ROC
[18:57:24.913] <TB3> INFO: i(loss) [mA/ROC]: 19.3 19.3 19.3 19.3 19.3 18.5 18.5 18.5 19.3 19.3 18.5 19.3 17.7 18.5 20.1 19.3
[18:57:24.940] <TB3> INFO: ----------------------------------------------------------------------
[18:57:24.941] <TB3> INFO: PixTestPretest::findTiming()
[18:57:24.941] <TB3> INFO: ----------------------------------------------------------------------
[18:57:24.941] <TB3> INFO: PixTestCmd::init()

[18:57:56.358] <TB3> INFO: TBM phases: 160MHz: 0, 400MHz: 2, TBM delays: ROC(0/1):5, header/trailer: 1, token: 1
[18:57:56.358] <TB3> INFO: (success/tries = 100/100), width = 4
[18:57:57.862] <TB3> INFO: ----------------------------------------------------------------------
[18:57:57.862] <TB3> INFO: PixTestPretest::findWorkingPixel()
[18:57:57.862] <TB3> INFO: ----------------------------------------------------------------------
[18:57:57.953] <TB3> INFO: Expecting 231680 events.
[18:58:07.607] <TB3> INFO: 231680 events read in total (9062ms).
[18:58:07.613] <TB3> INFO: Test took 9749ms.
[18:58:07.858] <TB3> INFO: Found working pixel in all ROCs: col/row = 12/22
[18:58:07.887] <TB3> INFO: ----------------------------------------------------------------------
[18:58:07.887] <TB3> INFO: PixTestPretest::setVthrCompCalDel()
[18:58:07.887] <TB3> INFO: ----------------------------------------------------------------------
[18:58:07.979] <TB3> INFO: Expecting 231680 events.
[18:58:17.638] <TB3> INFO: 231680 events read in total (9067ms).
[18:58:17.647] <TB3> INFO: Test took 9756ms.
[18:58:17.907] <TB3> INFO: PixTestPretest::setVthrCompCalDel() done
[18:58:17.907] <TB3> INFO: CalDel: 105 79 107 94 87 86 93 100 94 86 102 93 89 88 83 101
[18:58:17.907] <TB3> INFO: VthrComp: 51 52 53 51 52 57 57 51 51 51 51 51 51 51 52 52
[18:58:17.910] <TB3> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1080_FullQualification_2016-10-25_15h54m_1477403681//003_Fulltest_p17//dacParameters_C0.dat
[18:58:17.910] <TB3> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1080_FullQualification_2016-10-25_15h54m_1477403681//003_Fulltest_p17//dacParameters_C1.dat
[18:58:17.910] <TB3> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1080_FullQualification_2016-10-25_15h54m_1477403681//003_Fulltest_p17//dacParameters_C2.dat
[18:58:17.910] <TB3> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1080_FullQualification_2016-10-25_15h54m_1477403681//003_Fulltest_p17//dacParameters_C3.dat
[18:58:17.910] <TB3> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1080_FullQualification_2016-10-25_15h54m_1477403681//003_Fulltest_p17//dacParameters_C4.dat
[18:58:17.910] <TB3> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1080_FullQualification_2016-10-25_15h54m_1477403681//003_Fulltest_p17//dacParameters_C5.dat
[18:58:17.910] <TB3> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1080_FullQualification_2016-10-25_15h54m_1477403681//003_Fulltest_p17//dacParameters_C6.dat
[18:58:17.910] <TB3> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1080_FullQualification_2016-10-25_15h54m_1477403681//003_Fulltest_p17//dacParameters_C7.dat
[18:58:17.911] <TB3> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1080_FullQualification_2016-10-25_15h54m_1477403681//003_Fulltest_p17//dacParameters_C8.dat
[18:58:17.911] <TB3> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1080_FullQualification_2016-10-25_15h54m_1477403681//003_Fulltest_p17//dacParameters_C9.dat
[18:58:17.911] <TB3> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1080_FullQualification_2016-10-25_15h54m_1477403681//003_Fulltest_p17//dacParameters_C10.dat
[18:58:17.911] <TB3> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1080_FullQualification_2016-10-25_15h54m_1477403681//003_Fulltest_p17//dacParameters_C11.dat
[18:58:17.911] <TB3> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1080_FullQualification_2016-10-25_15h54m_1477403681//003_Fulltest_p17//dacParameters_C12.dat
[18:58:17.911] <TB3> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1080_FullQualification_2016-10-25_15h54m_1477403681//003_Fulltest_p17//dacParameters_C13.dat
[18:58:17.911] <TB3> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1080_FullQualification_2016-10-25_15h54m_1477403681//003_Fulltest_p17//dacParameters_C14.dat
[18:58:17.911] <TB3> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1080_FullQualification_2016-10-25_15h54m_1477403681//003_Fulltest_p17//dacParameters_C15.dat
[18:58:17.912] <TB3> INFO: write tbm parameters into /home/l_tester/david/FullQualification/data/M1080_FullQualification_2016-10-25_15h54m_1477403681//003_Fulltest_p17//tbmParameters_C0a.dat
[18:58:17.912] <TB3> INFO: write tbm parameters into /home/l_tester/david/FullQualification/data/M1080_FullQualification_2016-10-25_15h54m_1477403681//003_Fulltest_p17//tbmParameters_C0b.dat
[18:58:17.912] <TB3> INFO: write tbm parameters into /home/l_tester/david/FullQualification/data/M1080_FullQualification_2016-10-25_15h54m_1477403681//003_Fulltest_p17//tbmParameters_C1a.dat
[18:58:17.912] <TB3> INFO: write tbm parameters into /home/l_tester/david/FullQualification/data/M1080_FullQualification_2016-10-25_15h54m_1477403681//003_Fulltest_p17//tbmParameters_C1b.dat
[18:58:17.912] <TB3> INFO: PixTestPretest::doTest() done, duration: 92 seconds
[18:58:17.007] <TB3> INFO: enter test to run
[18:58:17.007] <TB3> INFO: test: FullTest no parameter change
[18:58:17.007] <TB3> INFO: running: fulltest
[18:58:17.007] <TB3> INFO: ######################################################################
[18:58:17.007] <TB3> INFO: PixTestFullTest::doTest()
[18:58:17.007] <TB3> INFO: ######################################################################
[18:58:18.008] <TB3> INFO: ######################################################################
[18:58:18.008] <TB3> INFO: PixTestAlive::doTest()
[18:58:18.008] <TB3> INFO: ######################################################################
[18:58:18.009] <TB3> INFO: ----------------------------------------------------------------------
[18:58:18.009] <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:58:18.009] <TB3> INFO: ----------------------------------------------------------------------
[18:58:18.243] <TB3> INFO: Expecting 41600 events.
[18:58:21.702] <TB3> INFO: 41600 events read in total (2868ms).
[18:58:21.702] <TB3> INFO: Test took 3691ms.
[18:58:21.930] <TB3> INFO: PixTestAlive::aliveTest() done
[18:58:21.930] <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:58:21.931] <TB3> INFO: ----------------------------------------------------------------------
[18:58:21.931] <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:58:21.931] <TB3> INFO: ----------------------------------------------------------------------
[18:58:22.164] <TB3> INFO: Expecting 41600 events.
[18:58:25.134] <TB3> INFO: 41600 events read in total (2378ms).
[18:58:25.134] <TB3> INFO: Test took 3202ms.
[18:58:25.134] <TB3> INFO: mask vs. old pixelAlive PixelAlive_C0_V0 .. PixelAlive_C15_V0
[18:58:25.375] <TB3> INFO: PixTestAlive::maskTest() done
[18:58:25.375] <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:58:25.376] <TB3> INFO: ----------------------------------------------------------------------
[18:58:25.376] <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:58:25.376] <TB3> INFO: ----------------------------------------------------------------------
[18:58:25.609] <TB3> INFO: Expecting 41600 events.
[18:58:29.099] <TB3> INFO: 41600 events read in total (2898ms).
[18:58:29.100] <TB3> INFO: Test took 3722ms.
[18:58:29.327] <TB3> INFO: PixTestAlive::addressDecodingTest() done
[18:58:29.327] <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:58:29.327] <TB3> INFO: PixTestAlive::doTest() done, duration: 11 seconds
[18:58:29.327] <TB3> INFO: Decoding statistics:
[18:58:29.327] <TB3> INFO: General information:
[18:58:29.327] <TB3> INFO: 16bit words read: 0
[18:58:29.327] <TB3> INFO: valid events total: 0
[18:58:29.327] <TB3> INFO: empty events: 0
[18:58:29.327] <TB3> INFO: valid events with pixels: 0
[18:58:29.327] <TB3> INFO: valid pixel hits: 0
[18:58:29.327] <TB3> INFO: Event errors: 0
[18:58:29.327] <TB3> INFO: start marker: 0
[18:58:29.327] <TB3> INFO: stop marker: 0
[18:58:29.327] <TB3> INFO: overflow: 0
[18:58:29.327] <TB3> INFO: invalid 5bit words: 0
[18:58:29.327] <TB3> INFO: invalid XOR eye diagram: 0
[18:58:29.327] <TB3> INFO: frame (failed synchr.): 0
[18:58:29.327] <TB3> INFO: idle data (no TBM trl): 0
[18:58:29.327] <TB3> INFO: no data (only TBM hdr): 0
[18:58:29.327] <TB3> INFO: TBM errors: 0
[18:58:29.327] <TB3> INFO: flawed TBM headers: 0
[18:58:29.327] <TB3> INFO: flawed TBM trailers: 0
[18:58:29.327] <TB3> INFO: event ID mismatches: 0
[18:58:29.327] <TB3> INFO: ROC errors: 0
[18:58:29.327] <TB3> INFO: missing ROC header(s): 0
[18:58:29.327] <TB3> INFO: misplaced readback start: 0
[18:58:29.327] <TB3> INFO: Pixel decoding errors: 0
[18:58:29.327] <TB3> INFO: pixel data incomplete: 0
[18:58:29.327] <TB3> INFO: pixel address: 0
[18:58:29.327] <TB3> INFO: pulse height fill bit: 0
[18:58:29.327] <TB3> INFO: buffer corruption: 0
[18:58:29.334] <TB3> INFO: readReadbackCal: /home/l_tester/david/FullQualification/data/M1080_FullQualification_2016-10-25_15h54m_1477403681//003_Fulltest_p17//readbackCal_C0.dat .. /home/l_tester/david/FullQualification/data/M1080_FullQualification_2016-10-25_15h54m_1477403681//003_Fulltest_p17//readbackCal_C15.dat
[18:58:29.334] <TB3> INFO: readGainPedestalParameters /home/l_tester/david/FullQualification/data/M1080_FullQualification_2016-10-25_15h54m_1477403681//003_Fulltest_p17//phCalibrationFitErr_C0.dat .. /home/l_tester/david/FullQualification/data/M1080_FullQualification_2016-10-25_15h54m_1477403681//003_Fulltest_p17//phCalibrationFitErr_C15.dat

[18:58:29.334] <TB3> INFO: ######################################################################
[18:58:29.334] <TB3> INFO: PixTestReadback::doTest()
[18:58:29.334] <TB3> INFO: ######################################################################
[18:58:29.334] <TB3> INFO: ----------------------------------------------------------------------
[18:58:29.334] <TB3> INFO: PixTestReadback::CalibrateVd()
[18:58:29.334] <TB3> INFO: ----------------------------------------------------------------------
[18:58:39.298] <TB3> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1080_FullQualification_2016-10-25_15h54m_1477403681//003_Fulltest_p17//readbackCal_C0.dat
[18:58:39.298] <TB3> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1080_FullQualification_2016-10-25_15h54m_1477403681//003_Fulltest_p17//readbackCal_C1.dat
[18:58:39.298] <TB3> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1080_FullQualification_2016-10-25_15h54m_1477403681//003_Fulltest_p17//readbackCal_C2.dat
[18:58:39.298] <TB3> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1080_FullQualification_2016-10-25_15h54m_1477403681//003_Fulltest_p17//readbackCal_C3.dat
[18:58:39.298] <TB3> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1080_FullQualification_2016-10-25_15h54m_1477403681//003_Fulltest_p17//readbackCal_C4.dat
[18:58:39.298] <TB3> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1080_FullQualification_2016-10-25_15h54m_1477403681//003_Fulltest_p17//readbackCal_C5.dat
[18:58:39.298] <TB3> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1080_FullQualification_2016-10-25_15h54m_1477403681//003_Fulltest_p17//readbackCal_C6.dat
[18:58:39.298] <TB3> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1080_FullQualification_2016-10-25_15h54m_1477403681//003_Fulltest_p17//readbackCal_C7.dat
[18:58:39.298] <TB3> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1080_FullQualification_2016-10-25_15h54m_1477403681//003_Fulltest_p17//readbackCal_C8.dat
[18:58:39.299] <TB3> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1080_FullQualification_2016-10-25_15h54m_1477403681//003_Fulltest_p17//readbackCal_C9.dat
[18:58:39.299] <TB3> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1080_FullQualification_2016-10-25_15h54m_1477403681//003_Fulltest_p17//readbackCal_C10.dat
[18:58:39.299] <TB3> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1080_FullQualification_2016-10-25_15h54m_1477403681//003_Fulltest_p17//readbackCal_C11.dat
[18:58:39.299] <TB3> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1080_FullQualification_2016-10-25_15h54m_1477403681//003_Fulltest_p17//readbackCal_C12.dat
[18:58:39.299] <TB3> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1080_FullQualification_2016-10-25_15h54m_1477403681//003_Fulltest_p17//readbackCal_C13.dat
[18:58:39.299] <TB3> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1080_FullQualification_2016-10-25_15h54m_1477403681//003_Fulltest_p17//readbackCal_C14.dat
[18:58:39.299] <TB3> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1080_FullQualification_2016-10-25_15h54m_1477403681//003_Fulltest_p17//readbackCal_C15.dat
[18:58:39.328] <TB3> INFO: PixTestPattern:: pg_setup set to default.
[18:58:39.328] <TB3> INFO: ----------------------------------------------------------------------
[18:58:39.328] <TB3> INFO: PixTestReadback::CalibrateVa()
[18:58:39.328] <TB3> INFO: ----------------------------------------------------------------------
[18:58:49.221] <TB3> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1080_FullQualification_2016-10-25_15h54m_1477403681//003_Fulltest_p17//readbackCal_C0.dat
[18:58:49.221] <TB3> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1080_FullQualification_2016-10-25_15h54m_1477403681//003_Fulltest_p17//readbackCal_C1.dat
[18:58:49.221] <TB3> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1080_FullQualification_2016-10-25_15h54m_1477403681//003_Fulltest_p17//readbackCal_C2.dat
[18:58:49.221] <TB3> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1080_FullQualification_2016-10-25_15h54m_1477403681//003_Fulltest_p17//readbackCal_C3.dat
[18:58:49.221] <TB3> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1080_FullQualification_2016-10-25_15h54m_1477403681//003_Fulltest_p17//readbackCal_C4.dat
[18:58:49.221] <TB3> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1080_FullQualification_2016-10-25_15h54m_1477403681//003_Fulltest_p17//readbackCal_C5.dat
[18:58:49.222] <TB3> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1080_FullQualification_2016-10-25_15h54m_1477403681//003_Fulltest_p17//readbackCal_C6.dat
[18:58:49.222] <TB3> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1080_FullQualification_2016-10-25_15h54m_1477403681//003_Fulltest_p17//readbackCal_C7.dat
[18:58:49.222] <TB3> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1080_FullQualification_2016-10-25_15h54m_1477403681//003_Fulltest_p17//readbackCal_C8.dat
[18:58:49.222] <TB3> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1080_FullQualification_2016-10-25_15h54m_1477403681//003_Fulltest_p17//readbackCal_C9.dat
[18:58:49.222] <TB3> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1080_FullQualification_2016-10-25_15h54m_1477403681//003_Fulltest_p17//readbackCal_C10.dat
[18:58:49.222] <TB3> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1080_FullQualification_2016-10-25_15h54m_1477403681//003_Fulltest_p17//readbackCal_C11.dat
[18:58:49.222] <TB3> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1080_FullQualification_2016-10-25_15h54m_1477403681//003_Fulltest_p17//readbackCal_C12.dat
[18:58:49.222] <TB3> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1080_FullQualification_2016-10-25_15h54m_1477403681//003_Fulltest_p17//readbackCal_C13.dat
[18:58:49.222] <TB3> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1080_FullQualification_2016-10-25_15h54m_1477403681//003_Fulltest_p17//readbackCal_C14.dat
[18:58:49.222] <TB3> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1080_FullQualification_2016-10-25_15h54m_1477403681//003_Fulltest_p17//readbackCal_C15.dat
[18:58:49.255] <TB3> INFO: PixTestPattern:: pg_setup set to default.
[18:58:49.255] <TB3> INFO: ----------------------------------------------------------------------
[18:58:49.255] <TB3> INFO: PixTestReadback::readbackVbg()
[18:58:49.255] <TB3> INFO: ----------------------------------------------------------------------
[18:58:56.895] <TB3> INFO: PixTestPattern:: pg_setup set to default.
[18:58:56.895] <TB3> INFO: ----------------------------------------------------------------------
[18:58:56.895] <TB3> INFO: PixTestReadback::getCalibratedVbg()
[18:58:56.895] <TB3> INFO: ----------------------------------------------------------------------
[18:58:56.895] <TB3> INFO: Vbg will be calibrated using Vd calibration
[18:58:56.895] <TB3> INFO: /*/*/*/*::: ROC 0: uncalibrated Vbg = 149.4calibrated Vbg = 1.18318 :::*/*/*/*/
[18:58:56.895] <TB3> INFO: /*/*/*/*::: ROC 1: uncalibrated Vbg = 159calibrated Vbg = 1.17252 :::*/*/*/*/
[18:58:56.895] <TB3> INFO: /*/*/*/*::: ROC 2: uncalibrated Vbg = 157.1calibrated Vbg = 1.17598 :::*/*/*/*/
[18:58:56.895] <TB3> INFO: /*/*/*/*::: ROC 3: uncalibrated Vbg = 159calibrated Vbg = 1.17107 :::*/*/*/*/
[18:58:56.895] <TB3> INFO: /*/*/*/*::: ROC 4: uncalibrated Vbg = 147calibrated Vbg = 1.1759 :::*/*/*/*/
[18:58:56.895] <TB3> INFO: /*/*/*/*::: ROC 5: uncalibrated Vbg = 155.8calibrated Vbg = 1.17825 :::*/*/*/*/
[18:58:56.895] <TB3> INFO: /*/*/*/*::: ROC 6: uncalibrated Vbg = 153.9calibrated Vbg = 1.18422 :::*/*/*/*/
[18:58:56.895] <TB3> INFO: /*/*/*/*::: ROC 7: uncalibrated Vbg = 155.4calibrated Vbg = 1.17598 :::*/*/*/*/
[18:58:56.895] <TB3> INFO: /*/*/*/*::: ROC 8: uncalibrated Vbg = 150.3calibrated Vbg = 1.16789 :::*/*/*/*/
[18:58:56.895] <TB3> INFO: /*/*/*/*::: ROC 9: uncalibrated Vbg = 158.6calibrated Vbg = 1.17441 :::*/*/*/*/
[18:58:56.895] <TB3> INFO: /*/*/*/*::: ROC 10: uncalibrated Vbg = 151.3calibrated Vbg = 1.1698 :::*/*/*/*/
[18:58:56.895] <TB3> INFO: /*/*/*/*::: ROC 11: uncalibrated Vbg = 154.5calibrated Vbg = 1.16346 :::*/*/*/*/
[18:58:56.895] <TB3> INFO: /*/*/*/*::: ROC 12: uncalibrated Vbg = 155.4calibrated Vbg = 1.16182 :::*/*/*/*/
[18:58:56.895] <TB3> INFO: /*/*/*/*::: ROC 13: uncalibrated Vbg = 149.8calibrated Vbg = 1.17464 :::*/*/*/*/
[18:58:56.895] <TB3> INFO: /*/*/*/*::: ROC 14: uncalibrated Vbg = 161calibrated Vbg = 1.17325 :::*/*/*/*/
[18:58:56.895] <TB3> INFO: /*/*/*/*::: ROC 15: uncalibrated Vbg = 152.4calibrated Vbg = 1.17398 :::*/*/*/*/
[18:58:56.897] <TB3> INFO: ----------------------------------------------------------------------
[18:58:56.897] <TB3> INFO: PixTestReadback::CalibrateIa()
[18:58:56.897] <TB3> INFO: ----------------------------------------------------------------------
[19:01:37.269] <TB3> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1080_FullQualification_2016-10-25_15h54m_1477403681//003_Fulltest_p17//readbackCal_C0.dat
[19:01:37.269] <TB3> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1080_FullQualification_2016-10-25_15h54m_1477403681//003_Fulltest_p17//readbackCal_C1.dat
[19:01:37.269] <TB3> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1080_FullQualification_2016-10-25_15h54m_1477403681//003_Fulltest_p17//readbackCal_C2.dat
[19:01:37.269] <TB3> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1080_FullQualification_2016-10-25_15h54m_1477403681//003_Fulltest_p17//readbackCal_C3.dat
[19:01:37.269] <TB3> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1080_FullQualification_2016-10-25_15h54m_1477403681//003_Fulltest_p17//readbackCal_C4.dat
[19:01:37.269] <TB3> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1080_FullQualification_2016-10-25_15h54m_1477403681//003_Fulltest_p17//readbackCal_C5.dat
[19:01:37.269] <TB3> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1080_FullQualification_2016-10-25_15h54m_1477403681//003_Fulltest_p17//readbackCal_C6.dat
[19:01:37.269] <TB3> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1080_FullQualification_2016-10-25_15h54m_1477403681//003_Fulltest_p17//readbackCal_C7.dat
[19:01:37.269] <TB3> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1080_FullQualification_2016-10-25_15h54m_1477403681//003_Fulltest_p17//readbackCal_C8.dat
[19:01:37.269] <TB3> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1080_FullQualification_2016-10-25_15h54m_1477403681//003_Fulltest_p17//readbackCal_C9.dat
[19:01:37.269] <TB3> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1080_FullQualification_2016-10-25_15h54m_1477403681//003_Fulltest_p17//readbackCal_C10.dat
[19:01:37.270] <TB3> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1080_FullQualification_2016-10-25_15h54m_1477403681//003_Fulltest_p17//readbackCal_C11.dat
[19:01:37.270] <TB3> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1080_FullQualification_2016-10-25_15h54m_1477403681//003_Fulltest_p17//readbackCal_C12.dat
[19:01:37.270] <TB3> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1080_FullQualification_2016-10-25_15h54m_1477403681//003_Fulltest_p17//readbackCal_C13.dat
[19:01:37.270] <TB3> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1080_FullQualification_2016-10-25_15h54m_1477403681//003_Fulltest_p17//readbackCal_C14.dat
[19:01:37.270] <TB3> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1080_FullQualification_2016-10-25_15h54m_1477403681//003_Fulltest_p17//readbackCal_C15.dat
[19:01:37.296] <TB3> INFO: PixTestPattern:: pg_setup set to default.
[19:01:37.297] <TB3> INFO: PixTestReadback::doTest() done
[19:01:37.297] <TB3> INFO: Decoding statistics:
[19:01:37.297] <TB3> INFO: General information:
[19:01:37.297] <TB3> INFO: 16bit words read: 1536
[19:01:37.297] <TB3> INFO: valid events total: 256
[19:01:37.297] <TB3> INFO: empty events: 256
[19:01:37.297] <TB3> INFO: valid events with pixels: 0
[19:01:37.297] <TB3> INFO: valid pixel hits: 0
[19:01:37.297] <TB3> INFO: Event errors: 0
[19:01:37.297] <TB3> INFO: start marker: 0
[19:01:37.297] <TB3> INFO: stop marker: 0
[19:01:37.297] <TB3> INFO: overflow: 0
[19:01:37.297] <TB3> INFO: invalid 5bit words: 0
[19:01:37.297] <TB3> INFO: invalid XOR eye diagram: 0
[19:01:37.297] <TB3> INFO: frame (failed synchr.): 0
[19:01:37.297] <TB3> INFO: idle data (no TBM trl): 0
[19:01:37.297] <TB3> INFO: no data (only TBM hdr): 0
[19:01:37.297] <TB3> INFO: TBM errors: 0
[19:01:37.297] <TB3> INFO: flawed TBM headers: 0
[19:01:37.298] <TB3> INFO: flawed TBM trailers: 0
[19:01:37.298] <TB3> INFO: event ID mismatches: 0
[19:01:37.298] <TB3> INFO: ROC errors: 0
[19:01:37.298] <TB3> INFO: missing ROC header(s): 0
[19:01:37.298] <TB3> INFO: misplaced readback start: 0
[19:01:37.298] <TB3> INFO: Pixel decoding errors: 0
[19:01:37.298] <TB3> INFO: pixel data incomplete: 0
[19:01:37.298] <TB3> INFO: pixel address: 0
[19:01:37.298] <TB3> INFO: pulse height fill bit: 0
[19:01:37.298] <TB3> INFO: buffer corruption: 0
[19:01:37.332] <TB3> INFO: ######################################################################
[19:01:37.332] <TB3> INFO: PixTestBBMap::doTest() Ntrig = 5, VcalS = 250 (high range)
[19:01:37.332] <TB3> INFO: ######################################################################
[19:01:37.335] <TB3> INFO: ---> dac: VthrComp name: calSMap ntrig: 5 dacrange: 0 .. 149 (-1/-1) hits flags = 514 (plus default)
[19:01:37.346] <TB3> INFO: dacScan split into 1 runs with ntrig = 5
[19:01:37.346] <TB3> INFO: run 1 of 1
[19:01:37.578] <TB3> INFO: Expecting 3120000 events.
[19:02:07.476] <TB3> INFO: 659740 events read in total (29306ms).











[19:02:36.917] <TB3> INFO: 1319005 events read in total (58747ms).











[19:03:06.407] <TB3> INFO: 1977875 events read in total (88237ms).











[19:03:36.281] <TB3> INFO: 2638245 events read in total (118111ms).











[19:03:58.236] <TB3> INFO: 3120000 events read in total (140066ms).
[19:03:58.308] <TB3> INFO: Test took 140962ms.
[19:04:22.584] <TB3> INFO: PixTestBBMap::doTest() done with 3 decoding errors: , duration: 165 seconds
[19:04:22.584] <TB3> INFO: number of dead bumps (per ROC): 2 1 1 0 1 0 0 0 0 1 0 0 1 0 1 2
[19:04:22.584] <TB3> INFO: separation cut (per ROC): 104 117 118 124 109 125 118 103 105 107 97 109 100 107 108 106
[19:04:22.584] <TB3> INFO: Decoding statistics:
[19:04:22.584] <TB3> INFO: General information:
[19:04:22.584] <TB3> INFO: 16bit words read: 0
[19:04:22.584] <TB3> INFO: valid events total: 0
[19:04:22.584] <TB3> INFO: empty events: 0
[19:04:22.584] <TB3> INFO: valid events with pixels: 0
[19:04:22.584] <TB3> INFO: valid pixel hits: 0
[19:04:22.584] <TB3> INFO: Event errors: 0
[19:04:22.584] <TB3> INFO: start marker: 0
[19:04:22.584] <TB3> INFO: stop marker: 0
[19:04:22.584] <TB3> INFO: overflow: 0
[19:04:22.584] <TB3> INFO: invalid 5bit words: 0
[19:04:22.584] <TB3> INFO: invalid XOR eye diagram: 0
[19:04:22.584] <TB3> INFO: frame (failed synchr.): 0
[19:04:22.584] <TB3> INFO: idle data (no TBM trl): 0
[19:04:22.584] <TB3> INFO: no data (only TBM hdr): 0
[19:04:22.584] <TB3> INFO: TBM errors: 0
[19:04:22.584] <TB3> INFO: flawed TBM headers: 0
[19:04:22.584] <TB3> INFO: flawed TBM trailers: 0
[19:04:22.584] <TB3> INFO: event ID mismatches: 0
[19:04:22.584] <TB3> INFO: ROC errors: 0
[19:04:22.584] <TB3> INFO: missing ROC header(s): 0
[19:04:22.584] <TB3> INFO: misplaced readback start: 0
[19:04:22.584] <TB3> INFO: Pixel decoding errors: 0
[19:04:22.584] <TB3> INFO: pixel data incomplete: 0
[19:04:22.584] <TB3> INFO: pixel address: 0
[19:04:22.584] <TB3> INFO: pulse height fill bit: 0
[19:04:22.584] <TB3> INFO: buffer corruption: 0
[19:04:22.624] <TB3> INFO: ######################################################################
[19:04:22.625] <TB3> INFO: PixTestScurves::fullTest() ntrig = 50, dacs/step = -1, ntrig/step = -1
[19:04:22.625] <TB3> INFO: ######################################################################
[19:04:22.625] <TB3> INFO: ----------------------------------------------------------------------
[19:04:22.625] <TB3> INFO: PixTestScurves::scurves(Vcal), ntrig = 50, dacs/step = -1, ntrig/step = -1
[19:04:22.625] <TB3> INFO: ----------------------------------------------------------------------
[19:04:22.625] <TB3> INFO: ---> dac: Vcal name: scurveVcal ntrig: 50 dacrange: 25 .. 200 (-1/-1) hits flags = 528 (plus default)
[19:04:22.634] <TB3> INFO: dacScan split into 1 runs with ntrig = 50
[19:04:22.634] <TB3> INFO: run 1 of 1
[19:04:22.867] <TB3> INFO: Expecting 36608000 events.
[19:04:46.375] <TB3> INFO: 697500 events read in total (22916ms).
[19:05:09.177] <TB3> INFO: 1374800 events read in total (45718ms).
[19:05:31.775] <TB3> INFO: 2054750 events read in total (68316ms).
[19:05:54.227] <TB3> INFO: 2728050 events read in total (90769ms).
[19:06:16.874] <TB3> INFO: 3408100 events read in total (113415ms).
[19:06:39.376] <TB3> INFO: 4083600 events read in total (135917ms).
[19:07:01.838] <TB3> INFO: 4762150 events read in total (158379ms).
[19:07:24.299] <TB3> INFO: 5437300 events read in total (180840ms).
[19:07:46.760] <TB3> INFO: 6114650 events read in total (203301ms).
[19:08:09.307] <TB3> INFO: 6789750 events read in total (225848ms).
[19:08:31.836] <TB3> INFO: 7464600 events read in total (248377ms).
[19:08:54.035] <TB3> INFO: 8137800 events read in total (270576ms).
[19:09:16.680] <TB3> INFO: 8812150 events read in total (293221ms).
[19:09:38.953] <TB3> INFO: 9485600 events read in total (315494ms).
[19:10:01.353] <TB3> INFO: 10161250 events read in total (337894ms).
[19:10:24.144] <TB3> INFO: 10835300 events read in total (360685ms).
[19:10:46.776] <TB3> INFO: 11508200 events read in total (383317ms).
[19:11:09.362] <TB3> INFO: 12180750 events read in total (405903ms).
[19:11:31.674] <TB3> INFO: 12852400 events read in total (428215ms).
[19:11:54.285] <TB3> INFO: 13526200 events read in total (450826ms).
[19:12:16.687] <TB3> INFO: 14198550 events read in total (473228ms).
[19:12:39.237] <TB3> INFO: 14873150 events read in total (495778ms).
[19:13:01.252] <TB3> INFO: 15544100 events read in total (517793ms).
[19:13:23.688] <TB3> INFO: 16216050 events read in total (540229ms).
[19:13:46.129] <TB3> INFO: 16884400 events read in total (562670ms).
[19:14:08.577] <TB3> INFO: 17554650 events read in total (585118ms).
[19:14:30.910] <TB3> INFO: 18222000 events read in total (607451ms).
[19:14:53.253] <TB3> INFO: 18890900 events read in total (629794ms).
[19:15:15.555] <TB3> INFO: 19558600 events read in total (652096ms).
[19:15:38.195] <TB3> INFO: 20227300 events read in total (674736ms).
[19:16:00.377] <TB3> INFO: 20894250 events read in total (696918ms).
[19:16:22.910] <TB3> INFO: 21564250 events read in total (719451ms).
[19:16:45.318] <TB3> INFO: 22230350 events read in total (741859ms).
[19:17:07.721] <TB3> INFO: 22897050 events read in total (764262ms).
[19:17:30.151] <TB3> INFO: 23564650 events read in total (786692ms).
[19:17:52.739] <TB3> INFO: 24232400 events read in total (809280ms).
[19:18:15.084] <TB3> INFO: 24900900 events read in total (831625ms).
[19:18:37.223] <TB3> INFO: 25568950 events read in total (853765ms).
[19:18:59.579] <TB3> INFO: 26234650 events read in total (876120ms).
[19:19:22.008] <TB3> INFO: 26899800 events read in total (898549ms).
[19:19:44.714] <TB3> INFO: 27567650 events read in total (921255ms).
[19:20:07.234] <TB3> INFO: 28231000 events read in total (943775ms).
[19:20:29.677] <TB3> INFO: 28897300 events read in total (966218ms).
[19:20:52.074] <TB3> INFO: 29562200 events read in total (988615ms).
[19:21:14.444] <TB3> INFO: 30229550 events read in total (1010985ms).
[19:21:37.063] <TB3> INFO: 30893300 events read in total (1033604ms).
[19:21:59.612] <TB3> INFO: 31560150 events read in total (1056153ms).
[19:22:21.792] <TB3> INFO: 32223700 events read in total (1078333ms).
[19:22:44.221] <TB3> INFO: 32891400 events read in total (1100762ms).
[19:23:06.607] <TB3> INFO: 33557350 events read in total (1123148ms).
[19:23:29.105] <TB3> INFO: 34224050 events read in total (1145646ms).
[19:23:51.652] <TB3> INFO: 34890650 events read in total (1168193ms).
[19:24:14.160] <TB3> INFO: 35559250 events read in total (1190701ms).
[19:24:37.002] <TB3> INFO: 36235000 events read in total (1214543ms).
[19:24:50.448] <TB3> INFO: 36608000 events read in total (1226989ms).
[19:24:50.497] <TB3> INFO: Test took 1227863ms.
[19:24:50.836] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[19:24:52.485] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[19:24:54.045] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[19:24:55.462] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[19:24:56.871] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[19:24:58.351] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[19:24:59.986] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[19:25:01.605] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[19:25:03.025] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[19:25:04.652] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[19:25:06.436] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[19:25:08.369] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[19:25:10.361] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[19:25:12.237] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[19:25:14.150] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[19:25:15.689] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[19:25:17.504] <TB3> INFO: PixTestScurves::scurves() done
[19:25:17.505] <TB3> INFO: Vcal mean: 133.15 136.79 130.77 133.83 129.74 134.85 134.28 118.71 120.43 120.37 120.55 116.37 116.27 115.11 131.08 133.33
[19:25:17.505] <TB3> INFO: Vcal RMS: 6.32 6.28 7.17 6.50 5.81 5.98 6.81 5.90 6.59 6.18 6.23 5.19 5.53 5.49 6.48 5.97
[19:25:17.505] <TB3> INFO: PixTestScurves::fullTest() done, duration: 1254 seconds
[19:25:17.505] <TB3> INFO: Decoding statistics:
[19:25:17.505] <TB3> INFO: General information:
[19:25:17.505] <TB3> INFO: 16bit words read: 0
[19:25:17.505] <TB3> INFO: valid events total: 0
[19:25:17.505] <TB3> INFO: empty events: 0
[19:25:17.505] <TB3> INFO: valid events with pixels: 0
[19:25:17.505] <TB3> INFO: valid pixel hits: 0
[19:25:17.505] <TB3> INFO: Event errors: 0
[19:25:17.505] <TB3> INFO: start marker: 0
[19:25:17.505] <TB3> INFO: stop marker: 0
[19:25:17.505] <TB3> INFO: overflow: 0
[19:25:17.505] <TB3> INFO: invalid 5bit words: 0
[19:25:17.505] <TB3> INFO: invalid XOR eye diagram: 0
[19:25:17.505] <TB3> INFO: frame (failed synchr.): 0
[19:25:17.505] <TB3> INFO: idle data (no TBM trl): 0
[19:25:17.505] <TB3> INFO: no data (only TBM hdr): 0
[19:25:17.505] <TB3> INFO: TBM errors: 0
[19:25:17.505] <TB3> INFO: flawed TBM headers: 0
[19:25:17.505] <TB3> INFO: flawed TBM trailers: 0
[19:25:17.505] <TB3> INFO: event ID mismatches: 0
[19:25:17.505] <TB3> INFO: ROC errors: 0
[19:25:17.505] <TB3> INFO: missing ROC header(s): 0
[19:25:17.505] <TB3> INFO: misplaced readback start: 0
[19:25:17.505] <TB3> INFO: Pixel decoding errors: 0
[19:25:17.505] <TB3> INFO: pixel data incomplete: 0
[19:25:17.505] <TB3> INFO: pixel address: 0
[19:25:17.505] <TB3> INFO: pulse height fill bit: 0
[19:25:17.505] <TB3> INFO: buffer corruption: 0
[19:25:17.581] <TB3> INFO: ######################################################################
[19:25:17.581] <TB3> INFO: PixTestTrim::doTest()
[19:25:17.581] <TB3> INFO: ######################################################################
[19:25:17.582] <TB3> INFO: ----------------------------------------------------------------------
[19:25:17.582] <TB3> INFO: PixTestTrim::trimTest() ntrig = 8, vcal = 35
[19:25:17.582] <TB3> INFO: ----------------------------------------------------------------------
[19:25:17.626] <TB3> INFO: ---> VthrComp thr map (minimal VthrComp)
[19:25:17.626] <TB3> INFO: ---> dac: vthrcomp name: TrimThr0 ntrig: 8 dacrange: 10 .. 160 (-1/-1) hits flags = 528 (plus default)
[19:25:17.634] <TB3> INFO: dacScan split into 1 runs with ntrig = 8
[19:25:17.634] <TB3> INFO: run 1 of 1
[19:25:17.908] <TB3> INFO: Expecting 5025280 events.
[19:25:48.281] <TB3> INFO: 829496 events read in total (29781ms).
[19:26:18.205] <TB3> INFO: 1656360 events read in total (59705ms).
[19:26:47.946] <TB3> INFO: 2480680 events read in total (89447ms).
[19:27:17.556] <TB3> INFO: 3301336 events read in total (119056ms).
[19:27:47.212] <TB3> INFO: 4118680 events read in total (148713ms).
[19:28:16.879] <TB3> INFO: 4934544 events read in total (178379ms).
[19:28:20.381] <TB3> INFO: 5025280 events read in total (181881ms).
[19:28:20.426] <TB3> INFO: Test took 182792ms.
[19:28:37.072] <TB3> INFO: ROC 0 VthrComp = 127
[19:28:37.072] <TB3> INFO: ROC 1 VthrComp = 132
[19:28:37.072] <TB3> INFO: ROC 2 VthrComp = 124
[19:28:37.072] <TB3> INFO: ROC 3 VthrComp = 130
[19:28:37.072] <TB3> INFO: ROC 4 VthrComp = 125
[19:28:37.072] <TB3> INFO: ROC 5 VthrComp = 133
[19:28:37.072] <TB3> INFO: ROC 6 VthrComp = 129
[19:28:37.072] <TB3> INFO: ROC 7 VthrComp = 118
[19:28:37.072] <TB3> INFO: ROC 8 VthrComp = 122
[19:28:37.073] <TB3> INFO: ROC 9 VthrComp = 126
[19:28:37.073] <TB3> INFO: ROC 10 VthrComp = 109
[19:28:37.073] <TB3> INFO: ROC 11 VthrComp = 121
[19:28:37.073] <TB3> INFO: ROC 12 VthrComp = 117
[19:28:37.073] <TB3> INFO: ROC 13 VthrComp = 123
[19:28:37.073] <TB3> INFO: ROC 14 VthrComp = 134
[19:28:37.073] <TB3> INFO: ROC 15 VthrComp = 127
[19:28:37.309] <TB3> INFO: Expecting 41600 events.
[19:28:40.780] <TB3> INFO: 41600 events read in total (2880ms).
[19:28:40.781] <TB3> INFO: Test took 3706ms.
[19:28:40.792] <TB3> INFO: ---> Vcal thr map (pixel with maximum Vcal thr)
[19:28:40.792] <TB3> INFO: ---> dac: vcal name: TrimThr1 ntrig: 8 dacrange: 10 .. 160 (-1/-1) hits flags = 528 (plus default)
[19:28:40.803] <TB3> INFO: dacScan split into 1 runs with ntrig = 8
[19:28:40.803] <TB3> INFO: run 1 of 1
[19:28:41.081] <TB3> INFO: Expecting 5025280 events.
[19:29:07.024] <TB3> INFO: 590680 events read in total (25351ms).
[19:29:32.746] <TB3> INFO: 1180712 events read in total (51073ms).
[19:29:58.095] <TB3> INFO: 1770872 events read in total (76422ms).
[19:30:23.473] <TB3> INFO: 2360856 events read in total (101800ms).
[19:30:49.392] <TB3> INFO: 2948832 events read in total (127719ms).
[19:31:14.763] <TB3> INFO: 3535792 events read in total (153090ms).
[19:31:40.328] <TB3> INFO: 4121344 events read in total (178655ms).
[19:32:07.030] <TB3> INFO: 4706648 events read in total (205357ms).
[19:32:20.963] <TB3> INFO: 5025280 events read in total (219290ms).
[19:32:21.022] <TB3> INFO: Test took 220219ms.
[19:32:46.033] <TB3> INFO: roc 0 with ID = 0 has maximal Vcal 62.0798 for pixel 1/12 mean/min/max = 47.3433/32.4259/62.2606
[19:32:46.033] <TB3> INFO: roc 1 with ID = 1 has maximal Vcal 63.8471 for pixel 21/78 mean/min/max = 48.8261/33.6917/63.9604
[19:32:46.033] <TB3> INFO: roc 2 with ID = 2 has maximal Vcal 63.636 for pixel 7/6 mean/min/max = 48.1179/32.3656/63.8702
[19:32:46.034] <TB3> INFO: roc 3 with ID = 3 has maximal Vcal 63.2702 for pixel 3/69 mean/min/max = 47.6688/32.0654/63.2722
[19:32:46.034] <TB3> INFO: roc 4 with ID = 4 has maximal Vcal 62.6952 for pixel 18/3 mean/min/max = 47.8016/32.8064/62.7967
[19:32:46.035] <TB3> INFO: roc 5 with ID = 5 has maximal Vcal 62.6285 for pixel 23/1 mean/min/max = 47.1714/31.5441/62.7986
[19:32:46.035] <TB3> INFO: roc 6 with ID = 6 has maximal Vcal 64.2712 for pixel 3/72 mean/min/max = 47.9107/31.5162/64.3053
[19:32:46.035] <TB3> INFO: roc 7 with ID = 7 has maximal Vcal 60.5744 for pixel 0/0 mean/min/max = 46.3867/32.1487/60.6247
[19:32:46.036] <TB3> INFO: roc 8 with ID = 8 has maximal Vcal 61.6936 for pixel 0/53 mean/min/max = 46.913/32.1132/61.7127
[19:32:46.036] <TB3> INFO: roc 9 with ID = 9 has maximal Vcal 59.1206 for pixel 17/21 mean/min/max = 46.1435/33.1526/59.1344
[19:32:46.036] <TB3> INFO: roc 10 with ID = 10 has maximal Vcal 65.8409 for pixel 9/24 mean/min/max = 50.3336/34.5607/66.1064
[19:32:46.037] <TB3> INFO: roc 11 with ID = 11 has maximal Vcal 57.4883 for pixel 22/2 mean/min/max = 45.1263/32.7442/57.5084
[19:32:46.037] <TB3> INFO: roc 12 with ID = 12 has maximal Vcal 60.0666 for pixel 47/38 mean/min/max = 46.2421/32.2606/60.2237
[19:32:46.037] <TB3> INFO: roc 13 with ID = 13 has maximal Vcal 57.4063 for pixel 8/17 mean/min/max = 45.3025/33.0929/57.5122
[19:32:46.038] <TB3> INFO: roc 14 with ID = 14 has maximal Vcal 62.4872 for pixel 19/0 mean/min/max = 47.9618/33.4078/62.5158
[19:32:46.038] <TB3> INFO: roc 15 with ID = 15 has maximal Vcal 61.7707 for pixel 9/15 mean/min/max = 46.7006/31.4095/61.9918
[19:32:46.038] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[19:32:46.127] <TB3> INFO: Expecting 411648 events.
[19:32:55.325] <TB3> INFO: 411648 events read in total (8606ms).
[19:32:55.331] <TB3> INFO: Expecting 411648 events.
[19:33:04.357] <TB3> INFO: 411648 events read in total (8622ms).
[19:33:04.365] <TB3> INFO: Expecting 411648 events.
[19:33:13.450] <TB3> INFO: 411648 events read in total (8682ms).
[19:33:13.461] <TB3> INFO: Expecting 411648 events.
[19:33:22.467] <TB3> INFO: 411648 events read in total (8603ms).
[19:33:22.481] <TB3> INFO: Expecting 411648 events.
[19:33:31.454] <TB3> INFO: 411648 events read in total (8570ms).
[19:33:31.470] <TB3> INFO: Expecting 411648 events.
[19:33:40.535] <TB3> INFO: 411648 events read in total (8662ms).
[19:33:40.555] <TB3> INFO: Expecting 411648 events.
[19:33:49.506] <TB3> INFO: 411648 events read in total (8549ms).
[19:33:49.533] <TB3> INFO: Expecting 411648 events.
[19:33:58.537] <TB3> INFO: 411648 events read in total (8601ms).
[19:33:58.560] <TB3> INFO: Expecting 411648 events.
[19:34:07.618] <TB3> INFO: 411648 events read in total (8655ms).
[19:34:07.644] <TB3> INFO: Expecting 411648 events.
[19:34:16.739] <TB3> INFO: 411648 events read in total (8692ms).
[19:34:16.782] <TB3> INFO: Expecting 411648 events.
[19:34:25.808] <TB3> INFO: 411648 events read in total (8623ms).
[19:34:25.840] <TB3> INFO: Expecting 411648 events.
[19:34:34.858] <TB3> INFO: 411648 events read in total (8615ms).
[19:34:34.892] <TB3> INFO: Expecting 411648 events.
[19:34:43.959] <TB3> INFO: 411648 events read in total (8664ms).
[19:34:43.996] <TB3> INFO: Expecting 411648 events.
[19:34:53.214] <TB3> INFO: 411648 events read in total (8815ms).
[19:34:53.255] <TB3> INFO: Expecting 411648 events.
[19:35:02.390] <TB3> INFO: 411648 events read in total (8732ms).
[19:35:02.437] <TB3> INFO: Expecting 411648 events.
[19:35:11.565] <TB3> INFO: 411648 events read in total (8725ms).
[19:35:11.613] <TB3> INFO: Test took 145575ms.
[19:35:12.296] <TB3> INFO: ---> dac: vcal name: TrimThr2 ntrig: 8 dacrange: 0 .. 150 (-1/-1) hits flags = 528 (plus default)
[19:35:12.306] <TB3> INFO: dacScan split into 1 runs with ntrig = 8
[19:35:12.307] <TB3> INFO: run 1 of 1
[19:35:12.555] <TB3> INFO: Expecting 5025280 events.
[19:35:38.300] <TB3> INFO: 589928 events read in total (25153ms).
[19:36:03.920] <TB3> INFO: 1178544 events read in total (50773ms).
[19:36:29.402] <TB3> INFO: 1767088 events read in total (76255ms).
[19:36:54.902] <TB3> INFO: 2356584 events read in total (101755ms).
[19:37:20.188] <TB3> INFO: 2946160 events read in total (127041ms).
[19:37:45.686] <TB3> INFO: 3536616 events read in total (152539ms).
[19:38:11.551] <TB3> INFO: 4132088 events read in total (178404ms).
[19:38:37.538] <TB3> INFO: 4727728 events read in total (204392ms).
[19:38:50.424] <TB3> INFO: 5025280 events read in total (217277ms).
[19:38:50.536] <TB3> INFO: Test took 218230ms.
[19:39:13.314] <TB3> INFO: ---> TrimStepCorr4 extremal thresholds: 2.709493 .. 147.843326
[19:39:13.549] <TB3> INFO: Expecting 208000 events.
[19:39:22.911] <TB3> INFO: 208000 events read in total (8770ms).
[19:39:22.912] <TB3> INFO: Test took 9597ms.
[19:39:22.962] <TB3> INFO: ---> dac: vcal name: trimStepCorr4 ntrig: 8 dacrange: 2 .. 157 (-1/-1) hits flags = 528 (plus default)
[19:39:22.971] <TB3> INFO: dacScan split into 1 runs with ntrig = 8
[19:39:22.971] <TB3> INFO: run 1 of 1
[19:39:23.249] <TB3> INFO: Expecting 5191680 events.
[19:39:49.320] <TB3> INFO: 583520 events read in total (25479ms).
[19:40:14.845] <TB3> INFO: 1166896 events read in total (51005ms).
[19:40:40.312] <TB3> INFO: 1750104 events read in total (76471ms).
[19:41:05.295] <TB3> INFO: 2333040 events read in total (101454ms).
[19:41:30.664] <TB3> INFO: 2916408 events read in total (126824ms).
[19:41:55.962] <TB3> INFO: 3499136 events read in total (152121ms).
[19:42:21.212] <TB3> INFO: 4081240 events read in total (177371ms).
[19:42:47.055] <TB3> INFO: 4662936 events read in total (203214ms).
[19:43:11.157] <TB3> INFO: 5191680 events read in total (227317ms).
[19:43:11.235] <TB3> INFO: Test took 228265ms.
[19:43:36.675] <TB3> INFO: ---> TrimStepCorr2 extremal thresholds: 26.537231 .. 46.847398
[19:43:36.908] <TB3> INFO: Expecting 208000 events.
[19:43:46.529] <TB3> INFO: 208000 events read in total (9028ms).
[19:43:46.530] <TB3> INFO: Test took 9853ms.
[19:43:46.578] <TB3> INFO: ---> dac: vcal name: trimStepCorr2 ntrig: 8 dacrange: 16 .. 56 (-1/-1) hits flags = 528 (plus default)
[19:43:46.587] <TB3> INFO: dacScan split into 1 runs with ntrig = 8
[19:43:46.587] <TB3> INFO: run 1 of 1
[19:43:46.866] <TB3> INFO: Expecting 1364480 events.
[19:44:15.182] <TB3> INFO: 662736 events read in total (27725ms).
[19:44:42.484] <TB3> INFO: 1322544 events read in total (55027ms).
[19:44:44.627] <TB3> INFO: 1364480 events read in total (57171ms).
[19:44:44.653] <TB3> INFO: Test took 58066ms.
[19:44:58.289] <TB3> INFO: ---> TrimStepCorr1a extremal thresholds: 26.823599 .. 50.093657
[19:44:58.560] <TB3> INFO: Expecting 208000 events.
[19:45:08.406] <TB3> INFO: 208000 events read in total (9255ms).
[19:45:08.407] <TB3> INFO: Test took 10116ms.
[19:45:08.475] <TB3> INFO: ---> dac: vcal name: trimStepCorr1a ntrig: 8 dacrange: 16 .. 60 (-1/-1) hits flags = 528 (plus default)
[19:45:08.486] <TB3> INFO: dacScan split into 1 runs with ntrig = 8
[19:45:08.486] <TB3> INFO: run 1 of 1
[19:45:08.764] <TB3> INFO: Expecting 1497600 events.
[19:45:36.309] <TB3> INFO: 647872 events read in total (26954ms).
[19:46:03.935] <TB3> INFO: 1294688 events read in total (54581ms).
[19:46:12.682] <TB3> INFO: 1497600 events read in total (63327ms).
[19:46:12.709] <TB3> INFO: Test took 64224ms.
[19:46:27.068] <TB3> INFO: ---> TrimStepCorr1b extremal thresholds: 25.963938 .. 55.235157
[19:46:27.303] <TB3> INFO: Expecting 208000 events.
[19:46:37.175] <TB3> INFO: 208000 events read in total (9281ms).
[19:46:37.176] <TB3> INFO: Test took 10106ms.
[19:46:37.225] <TB3> INFO: ---> dac: vcal name: trimStepCorr1b ntrig: 8 dacrange: 15 .. 65 (-1/-1) hits flags = 528 (plus default)
[19:46:37.234] <TB3> INFO: dacScan split into 1 runs with ntrig = 8
[19:46:37.234] <TB3> INFO: run 1 of 1
[19:46:37.512] <TB3> INFO: Expecting 1697280 events.
[19:47:04.844] <TB3> INFO: 636704 events read in total (26741ms).
[19:47:32.306] <TB3> INFO: 1272840 events read in total (54203ms).
[19:47:50.008] <TB3> INFO: 1697280 events read in total (71905ms).
[19:47:50.037] <TB3> INFO: Test took 72803ms.
[19:48:05.034] <TB3> INFO: ---> TrimThrFinal extremal thresholds: 15 .. 55
[19:48:05.034] <TB3> INFO: ---> dac: vcal name: TrimThrFinal ntrig: 8 dacrange: 15 .. 55 (-1/-1) hits flags = 528 (plus default)
[19:48:05.046] <TB3> INFO: dacScan split into 1 runs with ntrig = 8
[19:48:05.046] <TB3> INFO: run 1 of 1
[19:48:05.304] <TB3> INFO: Expecting 1364480 events.
[19:48:33.869] <TB3> INFO: 669072 events read in total (27974ms).
[19:49:01.122] <TB3> INFO: 1337400 events read in total (55228ms).
[19:49:02.629] <TB3> INFO: 1364480 events read in total (56735ms).
[19:49:02.654] <TB3> INFO: Test took 57608ms.
[19:49:16.156] <TB3> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1080_FullQualification_2016-10-25_15h54m_1477403681//003_Fulltest_p17//dacParameters35_C0.dat
[19:49:16.156] <TB3> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1080_FullQualification_2016-10-25_15h54m_1477403681//003_Fulltest_p17//dacParameters35_C1.dat
[19:49:16.156] <TB3> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1080_FullQualification_2016-10-25_15h54m_1477403681//003_Fulltest_p17//dacParameters35_C2.dat
[19:49:16.156] <TB3> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1080_FullQualification_2016-10-25_15h54m_1477403681//003_Fulltest_p17//dacParameters35_C3.dat
[19:49:16.156] <TB3> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1080_FullQualification_2016-10-25_15h54m_1477403681//003_Fulltest_p17//dacParameters35_C4.dat
[19:49:16.156] <TB3> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1080_FullQualification_2016-10-25_15h54m_1477403681//003_Fulltest_p17//dacParameters35_C5.dat
[19:49:16.156] <TB3> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1080_FullQualification_2016-10-25_15h54m_1477403681//003_Fulltest_p17//dacParameters35_C6.dat
[19:49:16.156] <TB3> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1080_FullQualification_2016-10-25_15h54m_1477403681//003_Fulltest_p17//dacParameters35_C7.dat
[19:49:16.156] <TB3> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1080_FullQualification_2016-10-25_15h54m_1477403681//003_Fulltest_p17//dacParameters35_C8.dat
[19:49:16.156] <TB3> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1080_FullQualification_2016-10-25_15h54m_1477403681//003_Fulltest_p17//dacParameters35_C9.dat
[19:49:16.156] <TB3> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1080_FullQualification_2016-10-25_15h54m_1477403681//003_Fulltest_p17//dacParameters35_C10.dat
[19:49:16.156] <TB3> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1080_FullQualification_2016-10-25_15h54m_1477403681//003_Fulltest_p17//dacParameters35_C11.dat
[19:49:16.156] <TB3> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1080_FullQualification_2016-10-25_15h54m_1477403681//003_Fulltest_p17//dacParameters35_C12.dat
[19:49:16.157] <TB3> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1080_FullQualification_2016-10-25_15h54m_1477403681//003_Fulltest_p17//dacParameters35_C13.dat
[19:49:16.157] <TB3> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1080_FullQualification_2016-10-25_15h54m_1477403681//003_Fulltest_p17//dacParameters35_C14.dat
[19:49:16.157] <TB3> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1080_FullQualification_2016-10-25_15h54m_1477403681//003_Fulltest_p17//dacParameters35_C15.dat
[19:49:16.157] <TB3> INFO: write trim parameters into /home/l_tester/david/FullQualification/data/M1080_FullQualification_2016-10-25_15h54m_1477403681//003_Fulltest_p17//trimParameters35_C0.dat
[19:49:16.162] <TB3> INFO: write trim parameters into /home/l_tester/david/FullQualification/data/M1080_FullQualification_2016-10-25_15h54m_1477403681//003_Fulltest_p17//trimParameters35_C1.dat
[19:49:16.168] <TB3> INFO: write trim parameters into /home/l_tester/david/FullQualification/data/M1080_FullQualification_2016-10-25_15h54m_1477403681//003_Fulltest_p17//trimParameters35_C2.dat
[19:49:16.173] <TB3> INFO: write trim parameters into /home/l_tester/david/FullQualification/data/M1080_FullQualification_2016-10-25_15h54m_1477403681//003_Fulltest_p17//trimParameters35_C3.dat
[19:49:16.179] <TB3> INFO: write trim parameters into /home/l_tester/david/FullQualification/data/M1080_FullQualification_2016-10-25_15h54m_1477403681//003_Fulltest_p17//trimParameters35_C4.dat
[19:49:16.184] <TB3> INFO: write trim parameters into /home/l_tester/david/FullQualification/data/M1080_FullQualification_2016-10-25_15h54m_1477403681//003_Fulltest_p17//trimParameters35_C5.dat
[19:49:16.190] <TB3> INFO: write trim parameters into /home/l_tester/david/FullQualification/data/M1080_FullQualification_2016-10-25_15h54m_1477403681//003_Fulltest_p17//trimParameters35_C6.dat
[19:49:16.195] <TB3> INFO: write trim parameters into /home/l_tester/david/FullQualification/data/M1080_FullQualification_2016-10-25_15h54m_1477403681//003_Fulltest_p17//trimParameters35_C7.dat
[19:49:16.200] <TB3> INFO: write trim parameters into /home/l_tester/david/FullQualification/data/M1080_FullQualification_2016-10-25_15h54m_1477403681//003_Fulltest_p17//trimParameters35_C8.dat
[19:49:16.206] <TB3> INFO: write trim parameters into /home/l_tester/david/FullQualification/data/M1080_FullQualification_2016-10-25_15h54m_1477403681//003_Fulltest_p17//trimParameters35_C9.dat
[19:49:16.211] <TB3> INFO: write trim parameters into /home/l_tester/david/FullQualification/data/M1080_FullQualification_2016-10-25_15h54m_1477403681//003_Fulltest_p17//trimParameters35_C10.dat
[19:49:16.217] <TB3> INFO: write trim parameters into /home/l_tester/david/FullQualification/data/M1080_FullQualification_2016-10-25_15h54m_1477403681//003_Fulltest_p17//trimParameters35_C11.dat
[19:49:16.222] <TB3> INFO: write trim parameters into /home/l_tester/david/FullQualification/data/M1080_FullQualification_2016-10-25_15h54m_1477403681//003_Fulltest_p17//trimParameters35_C12.dat
[19:49:16.228] <TB3> INFO: write trim parameters into /home/l_tester/david/FullQualification/data/M1080_FullQualification_2016-10-25_15h54m_1477403681//003_Fulltest_p17//trimParameters35_C13.dat
[19:49:16.233] <TB3> INFO: write trim parameters into /home/l_tester/david/FullQualification/data/M1080_FullQualification_2016-10-25_15h54m_1477403681//003_Fulltest_p17//trimParameters35_C14.dat
[19:49:16.239] <TB3> INFO: write trim parameters into /home/l_tester/david/FullQualification/data/M1080_FullQualification_2016-10-25_15h54m_1477403681//003_Fulltest_p17//trimParameters35_C15.dat
[19:49:16.244] <TB3> INFO: PixTestTrim::trimTest() done
[19:49:16.244] <TB3> INFO: vtrim: 145 163 147 145 121 144 143 114 124 146 137 135 143 144 172 140
[19:49:16.244] <TB3> INFO: vthrcomp: 127 132 124 130 125 133 129 118 122 126 109 121 117 123 134 127
[19:49:16.244] <TB3> INFO: vcal mean: 35.29 35.99 35.49 35.40 35.12 35.29 35.99 35.02 35.08 34.99 36.54 34.97 35.55 35.01 35.23 35.25
[19:49:16.244] <TB3> INFO: vcal RMS: 1.58 2.16 1.67 1.58 1.29 1.42 2.19 1.11 1.11 1.02 2.62 1.05 1.68 0.97 1.35 1.48
[19:49:16.244] <TB3> INFO: bits mean: 9.89 10.11 9.90 10.14 9.27 9.95 10.15 8.60 8.91 9.83 10.21 9.98 10.64 9.95 10.19 9.90
[19:49:16.244] <TB3> INFO: bits RMS: 2.51 2.23 2.43 2.39 2.63 2.56 2.55 3.09 2.91 2.44 2.18 2.44 2.23 2.40 2.10 2.62
[19:49:16.251] <TB3> INFO: ----------------------------------------------------------------------
[19:49:16.251] <TB3> INFO: PixTestTrim::trimBitTest() ntrig = 5, vtrims = 254 126 63 32
[19:49:16.251] <TB3> INFO: ----------------------------------------------------------------------
[19:49:16.253] <TB3> INFO: ---> dac: Vcal name: TrimBitsThr0 ntrig: 5 dacrange: 0 .. 199 (-1/-1) hits flags = 528 (plus default)
[19:49:16.263] <TB3> INFO: dacScan split into 1 runs with ntrig = 5
[19:49:16.263] <TB3> INFO: run 1 of 1
[19:49:16.536] <TB3> INFO: Expecting 4160000 events.
[19:49:48.907] <TB3> INFO: 763475 events read in total (31779ms).
[19:50:20.386] <TB3> INFO: 1519105 events read in total (63258ms).
[19:50:51.599] <TB3> INFO: 2269870 events read in total (94471ms).
[19:51:22.830] <TB3> INFO: 3017310 events read in total (125702ms).
[19:51:55.842] <TB3> INFO: 3762820 events read in total (158714ms).
[19:52:12.249] <TB3> INFO: 4160000 events read in total (175121ms).
[19:52:12.296] <TB3> INFO: Test took 176034ms.
[19:52:38.512] <TB3> INFO: ---> dac: Vcal name: TrimThr_trim14 ntrig: 5 dacrange: 0 .. 211 (-1/-1) hits flags = 528 (plus default)
[19:52:38.522] <TB3> INFO: dacScan split into 1 runs with ntrig = 5
[19:52:38.522] <TB3> INFO: run 1 of 1
[19:52:38.754] <TB3> INFO: Expecting 4409600 events.
[19:53:10.190] <TB3> INFO: 724040 events read in total (30844ms).
[19:53:41.015] <TB3> INFO: 1442620 events read in total (61669ms).
[19:54:11.984] <TB3> INFO: 2157885 events read in total (92639ms).
[19:54:42.352] <TB3> INFO: 2869045 events read in total (123006ms).
[19:55:12.869] <TB3> INFO: 3578935 events read in total (153523ms).
[19:55:43.817] <TB3> INFO: 4288520 events read in total (184471ms).
[19:55:49.507] <TB3> INFO: 4409600 events read in total (190161ms).
[19:55:49.579] <TB3> INFO: Test took 191057ms.
[19:56:20.273] <TB3> INFO: ---> dac: Vcal name: TrimThr_trim13 ntrig: 5 dacrange: 0 .. 206 (-1/-1) hits flags = 528 (plus default)
[19:56:20.282] <TB3> INFO: dacScan split into 1 runs with ntrig = 5
[19:56:20.282] <TB3> INFO: run 1 of 1
[19:56:20.514] <TB3> INFO: Expecting 4305600 events.
[19:56:52.185] <TB3> INFO: 731255 events read in total (31079ms).
[19:57:23.067] <TB3> INFO: 1456070 events read in total (61961ms).
[19:57:54.259] <TB3> INFO: 2177575 events read in total (93153ms).
[19:58:25.174] <TB3> INFO: 2895650 events read in total (124068ms).
[19:58:55.822] <TB3> INFO: 3611060 events read in total (154716ms).
[19:59:26.193] <TB3> INFO: 4305600 events read in total (185087ms).
[19:59:26.264] <TB3> INFO: Test took 185982ms.
[19:59:56.402] <TB3> INFO: ---> dac: Vcal name: TrimThr_trim11 ntrig: 5 dacrange: 0 .. 210 (-1/-1) hits flags = 528 (plus default)
[19:59:56.411] <TB3> INFO: dacScan split into 1 runs with ntrig = 5
[19:59:56.411] <TB3> INFO: run 1 of 1
[19:59:56.643] <TB3> INFO: Expecting 4388800 events.
[20:00:27.989] <TB3> INFO: 726205 events read in total (30754ms).
[20:00:58.606] <TB3> INFO: 1446700 events read in total (61371ms).
[20:01:29.269] <TB3> INFO: 2163730 events read in total (92034ms).
[20:01:59.965] <TB3> INFO: 2876975 events read in total (122730ms).
[20:02:30.638] <TB3> INFO: 3588735 events read in total (153403ms).
[20:03:01.737] <TB3> INFO: 4300495 events read in total (184502ms).
[20:03:05.806] <TB3> INFO: 4388800 events read in total (188571ms).
[20:03:05.859] <TB3> INFO: Test took 189449ms.
[20:03:34.826] <TB3> INFO: ---> dac: Vcal name: TrimThr_trim7 ntrig: 5 dacrange: 0 .. 207 (-1/-1) hits flags = 528 (plus default)
[20:03:34.835] <TB3> INFO: dacScan split into 1 runs with ntrig = 5
[20:03:34.835] <TB3> INFO: run 1 of 1
[20:03:35.067] <TB3> INFO: Expecting 4326400 events.
[20:04:06.346] <TB3> INFO: 730030 events read in total (30688ms).
[20:04:36.762] <TB3> INFO: 1453905 events read in total (61104ms).
[20:05:07.308] <TB3> INFO: 2174560 events read in total (91650ms).
[20:05:37.006] <TB3> INFO: 2891615 events read in total (122348ms).
[20:06:08.481] <TB3> INFO: 3606660 events read in total (152823ms).
[20:06:39.293] <TB3> INFO: 4324035 events read in total (183635ms).
[20:06:39.812] <TB3> INFO: 4326400 events read in total (184154ms).
[20:06:39.864] <TB3> INFO: Test took 185029ms.
[20:07:08.514] <TB3> INFO: PixTestTrim::trimBitTest() done
[20:07:08.515] <TB3> INFO: PixTestTrim::doTest() done, duration: 2510 seconds
[20:07:08.515] <TB3> INFO: Decoding statistics:
[20:07:08.515] <TB3> INFO: General information:
[20:07:08.515] <TB3> INFO: 16bit words read: 0
[20:07:08.515] <TB3> INFO: valid events total: 0
[20:07:08.515] <TB3> INFO: empty events: 0
[20:07:08.515] <TB3> INFO: valid events with pixels: 0
[20:07:08.515] <TB3> INFO: valid pixel hits: 0
[20:07:08.515] <TB3> INFO: Event errors: 0
[20:07:08.515] <TB3> INFO: start marker: 0
[20:07:08.515] <TB3> INFO: stop marker: 0
[20:07:08.515] <TB3> INFO: overflow: 0
[20:07:08.515] <TB3> INFO: invalid 5bit words: 0
[20:07:08.515] <TB3> INFO: invalid XOR eye diagram: 0
[20:07:08.515] <TB3> INFO: frame (failed synchr.): 0
[20:07:08.515] <TB3> INFO: idle data (no TBM trl): 0
[20:07:08.515] <TB3> INFO: no data (only TBM hdr): 0
[20:07:08.515] <TB3> INFO: TBM errors: 0
[20:07:08.515] <TB3> INFO: flawed TBM headers: 0
[20:07:08.515] <TB3> INFO: flawed TBM trailers: 0
[20:07:08.515] <TB3> INFO: event ID mismatches: 0
[20:07:08.515] <TB3> INFO: ROC errors: 0
[20:07:08.515] <TB3> INFO: missing ROC header(s): 0
[20:07:08.515] <TB3> INFO: misplaced readback start: 0
[20:07:08.515] <TB3> INFO: Pixel decoding errors: 0
[20:07:08.515] <TB3> INFO: pixel data incomplete: 0
[20:07:08.515] <TB3> INFO: pixel address: 0
[20:07:08.515] <TB3> INFO: pulse height fill bit: 0
[20:07:08.515] <TB3> INFO: buffer corruption: 0
[20:07:09.155] <TB3> INFO: ######################################################################
[20:07:09.155] <TB3> INFO: PixTestPhOptimization::doTest() Ntrig = 10
[20:07:09.156] <TB3> INFO: ######################################################################
[20:07:09.390] <TB3> INFO: Expecting 41600 events.
[20:07:12.880] <TB3> INFO: 41600 events read in total (2898ms).
[20:07:12.881] <TB3> INFO: Test took 3724ms.
[20:07:13.318] <TB3> INFO: Expecting 41600 events.
[20:07:16.830] <TB3> INFO: 41600 events read in total (2920ms).
[20:07:16.831] <TB3> INFO: Test took 3748ms.
[20:07:17.119] <TB3> INFO: Expecting 41600 events.
[20:07:20.662] <TB3> INFO: 41600 events read in total (2952ms).
[20:07:20.662] <TB3> INFO: Test took 3808ms.
[20:07:20.953] <TB3> INFO: Expecting 41600 events.
[20:07:24.486] <TB3> INFO: 41600 events read in total (2941ms).
[20:07:24.487] <TB3> INFO: Test took 3799ms.
[20:07:24.777] <TB3> INFO: Expecting 41600 events.
[20:07:28.292] <TB3> INFO: 41600 events read in total (2923ms).
[20:07:28.293] <TB3> INFO: Test took 3780ms.
[20:07:28.617] <TB3> INFO: Expecting 41600 events.
[20:07:32.132] <TB3> INFO: 41600 events read in total (2924ms).
[20:07:32.133] <TB3> INFO: Test took 3813ms.
[20:07:32.426] <TB3> INFO: Expecting 41600 events.
[20:07:35.870] <TB3> INFO: 41600 events read in total (2850ms).
[20:07:35.871] <TB3> INFO: Test took 3712ms.
[20:07:36.159] <TB3> INFO: Expecting 41600 events.
[20:07:39.615] <TB3> INFO: 41600 events read in total (2864ms).
[20:07:39.616] <TB3> INFO: Test took 3722ms.
[20:07:39.905] <TB3> INFO: Expecting 41600 events.
[20:07:43.377] <TB3> INFO: 41600 events read in total (2881ms).
[20:07:43.378] <TB3> INFO: Test took 3739ms.
[20:07:43.667] <TB3> INFO: Expecting 41600 events.
[20:07:47.137] <TB3> INFO: 41600 events read in total (2878ms).
[20:07:47.137] <TB3> INFO: Test took 3735ms.
[20:07:47.425] <TB3> INFO: Expecting 41600 events.
[20:07:50.880] <TB3> INFO: 41600 events read in total (2855ms).
[20:07:50.881] <TB3> INFO: Test took 3720ms.
[20:07:51.176] <TB3> INFO: Expecting 41600 events.
[20:07:54.676] <TB3> INFO: 41600 events read in total (2907ms).
[20:07:54.677] <TB3> INFO: Test took 3773ms.
[20:07:54.965] <TB3> INFO: Expecting 41600 events.
[20:07:58.535] <TB3> INFO: 41600 events read in total (2979ms).
[20:07:58.536] <TB3> INFO: Test took 3836ms.
[20:07:58.826] <TB3> INFO: Expecting 41600 events.
[20:08:02.320] <TB3> INFO: 41600 events read in total (2902ms).
[20:08:02.321] <TB3> INFO: Test took 3759ms.
[20:08:02.610] <TB3> INFO: Expecting 41600 events.
[20:08:06.056] <TB3> INFO: 41600 events read in total (2849ms).
[20:08:06.056] <TB3> INFO: Test took 3711ms.
[20:08:06.345] <TB3> INFO: Expecting 41600 events.
[20:08:09.840] <TB3> INFO: 41600 events read in total (2903ms).
[20:08:09.841] <TB3> INFO: Test took 3761ms.
[20:08:10.131] <TB3> INFO: Expecting 41600 events.
[20:08:13.634] <TB3> INFO: 41600 events read in total (2911ms).
[20:08:13.635] <TB3> INFO: Test took 3769ms.
[20:08:13.925] <TB3> INFO: Expecting 41600 events.
[20:08:17.379] <TB3> INFO: 41600 events read in total (2860ms).
[20:08:17.380] <TB3> INFO: Test took 3722ms.
[20:08:17.669] <TB3> INFO: Expecting 41600 events.
[20:08:21.226] <TB3> INFO: 41600 events read in total (2966ms).
[20:08:21.227] <TB3> INFO: Test took 3823ms.
[20:08:21.515] <TB3> INFO: Expecting 41600 events.
[20:08:24.966] <TB3> INFO: 41600 events read in total (2859ms).
[20:08:24.967] <TB3> INFO: Test took 3717ms.
[20:08:25.255] <TB3> INFO: Expecting 41600 events.
[20:08:28.698] <TB3> INFO: 41600 events read in total (2851ms).
[20:08:28.699] <TB3> INFO: Test took 3709ms.
[20:08:28.988] <TB3> INFO: Expecting 41600 events.
[20:08:32.597] <TB3> INFO: 41600 events read in total (3018ms).
[20:08:32.598] <TB3> INFO: Test took 3875ms.
[20:08:32.886] <TB3> INFO: Expecting 41600 events.
[20:08:36.340] <TB3> INFO: 41600 events read in total (2863ms).
[20:08:36.340] <TB3> INFO: Test took 3720ms.
[20:08:36.628] <TB3> INFO: Expecting 41600 events.
[20:08:40.224] <TB3> INFO: 41600 events read in total (3004ms).
[20:08:40.225] <TB3> INFO: Test took 3861ms.
[20:08:40.522] <TB3> INFO: Expecting 41600 events.
[20:08:43.986] <TB3> INFO: 41600 events read in total (2873ms).
[20:08:43.987] <TB3> INFO: Test took 3739ms.
[20:08:44.275] <TB3> INFO: Expecting 41600 events.
[20:08:47.757] <TB3> INFO: 41600 events read in total (2890ms).
[20:08:47.758] <TB3> INFO: Test took 3747ms.
[20:08:48.090] <TB3> INFO: Expecting 41600 events.
[20:08:51.644] <TB3> INFO: 41600 events read in total (2962ms).
[20:08:51.645] <TB3> INFO: Test took 3858ms.
[20:08:51.933] <TB3> INFO: Expecting 41600 events.
[20:08:55.371] <TB3> INFO: 41600 events read in total (2846ms).
[20:08:55.372] <TB3> INFO: Test took 3704ms.
[20:08:55.665] <TB3> INFO: Expecting 41600 events.
[20:08:59.158] <TB3> INFO: 41600 events read in total (2902ms).
[20:08:59.159] <TB3> INFO: Test took 3759ms.
[20:08:59.447] <TB3> INFO: Expecting 2560 events.
[20:09:00.330] <TB3> INFO: 2560 events read in total (291ms).
[20:09:00.331] <TB3> INFO: Test took 1160ms.
[20:09:00.638] <TB3> INFO: Expecting 2560 events.
[20:09:01.521] <TB3> INFO: 2560 events read in total (291ms).
[20:09:01.521] <TB3> INFO: Test took 1190ms.
[20:09:01.829] <TB3> INFO: Expecting 2560 events.
[20:09:02.711] <TB3> INFO: 2560 events read in total (290ms).
[20:09:02.711] <TB3> INFO: Test took 1190ms.
[20:09:03.019] <TB3> INFO: Expecting 2560 events.
[20:09:03.904] <TB3> INFO: 2560 events read in total (294ms).
[20:09:03.905] <TB3> INFO: Test took 1194ms.
[20:09:04.212] <TB3> INFO: Expecting 2560 events.
[20:09:05.096] <TB3> INFO: 2560 events read in total (292ms).
[20:09:05.096] <TB3> INFO: Test took 1191ms.
[20:09:05.404] <TB3> INFO: Expecting 2560 events.
[20:09:06.283] <TB3> INFO: 2560 events read in total (288ms).
[20:09:06.283] <TB3> INFO: Test took 1187ms.
[20:09:06.591] <TB3> INFO: Expecting 2560 events.
[20:09:07.469] <TB3> INFO: 2560 events read in total (287ms).
[20:09:07.469] <TB3> INFO: Test took 1186ms.
[20:09:07.777] <TB3> INFO: Expecting 2560 events.
[20:09:08.655] <TB3> INFO: 2560 events read in total (286ms).
[20:09:08.656] <TB3> INFO: Test took 1186ms.
[20:09:08.964] <TB3> INFO: Expecting 2560 events.
[20:09:09.841] <TB3> INFO: 2560 events read in total (286ms).
[20:09:09.842] <TB3> INFO: Test took 1186ms.
[20:09:10.149] <TB3> INFO: Expecting 2560 events.
[20:09:11.028] <TB3> INFO: 2560 events read in total (287ms).
[20:09:11.028] <TB3> INFO: Test took 1186ms.
[20:09:11.336] <TB3> INFO: Expecting 2560 events.
[20:09:12.215] <TB3> INFO: 2560 events read in total (287ms).
[20:09:12.215] <TB3> INFO: Test took 1187ms.
[20:09:12.523] <TB3> INFO: Expecting 2560 events.
[20:09:13.405] <TB3> INFO: 2560 events read in total (291ms).
[20:09:13.405] <TB3> INFO: Test took 1190ms.
[20:09:13.713] <TB3> INFO: Expecting 2560 events.
[20:09:14.596] <TB3> INFO: 2560 events read in total (291ms).
[20:09:14.596] <TB3> INFO: Test took 1190ms.
[20:09:14.905] <TB3> INFO: Expecting 2560 events.
[20:09:15.790] <TB3> INFO: 2560 events read in total (294ms).
[20:09:15.790] <TB3> INFO: Test took 1193ms.
[20:09:16.098] <TB3> INFO: Expecting 2560 events.
[20:09:16.981] <TB3> INFO: 2560 events read in total (291ms).
[20:09:16.981] <TB3> INFO: Test took 1190ms.
[20:09:17.289] <TB3> INFO: Expecting 2560 events.
[20:09:18.174] <TB3> INFO: 2560 events read in total (293ms).
[20:09:18.174] <TB3> INFO: Test took 1193ms.
[20:09:18.177] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[20:09:18.483] <TB3> INFO: Expecting 655360 events.
[20:09:32.829] <TB3> INFO: 655360 events read in total (13754ms).
[20:09:32.843] <TB3> INFO: Expecting 655360 events.
[20:09:46.918] <TB3> INFO: 655360 events read in total (13672ms).
[20:09:46.937] <TB3> INFO: Expecting 655360 events.
[20:10:01.091] <TB3> INFO: 655360 events read in total (13751ms).
[20:10:01.110] <TB3> INFO: Expecting 655360 events.
[20:10:15.192] <TB3> INFO: 655360 events read in total (13679ms).
[20:10:15.215] <TB3> INFO: Expecting 655360 events.
[20:10:29.190] <TB3> INFO: 655360 events read in total (13572ms).
[20:10:29.225] <TB3> INFO: Expecting 655360 events.
[20:10:43.313] <TB3> INFO: 655360 events read in total (13685ms).
[20:10:43.343] <TB3> INFO: Expecting 655360 events.
[20:10:57.236] <TB3> INFO: 655360 events read in total (13490ms).
[20:10:57.270] <TB3> INFO: Expecting 655360 events.
[20:11:11.236] <TB3> INFO: 655360 events read in total (13563ms).
[20:11:11.274] <TB3> INFO: Expecting 655360 events.
[20:11:25.175] <TB3> INFO: 655360 events read in total (13497ms).
[20:11:25.218] <TB3> INFO: Expecting 655360 events.
[20:11:39.207] <TB3> INFO: 655360 events read in total (13586ms).
[20:11:39.260] <TB3> INFO: Expecting 655360 events.
[20:11:53.312] <TB3> INFO: 655360 events read in total (13649ms).
[20:11:53.385] <TB3> INFO: Expecting 655360 events.
[20:12:07.417] <TB3> INFO: 655360 events read in total (13629ms).
[20:12:07.493] <TB3> INFO: Expecting 655360 events.
[20:12:21.633] <TB3> INFO: 655360 events read in total (13737ms).
[20:12:21.715] <TB3> INFO: Expecting 655360 events.
[20:12:35.691] <TB3> INFO: 655360 events read in total (13573ms).
[20:12:35.762] <TB3> INFO: Expecting 655360 events.
[20:12:49.826] <TB3> INFO: 655360 events read in total (13661ms).
[20:12:49.920] <TB3> INFO: Expecting 655360 events.
[20:13:03.968] <TB3> INFO: 655360 events read in total (13645ms).
[20:13:04.050] <TB3> INFO: Test took 225873ms.
[20:13:04.129] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[20:13:04.394] <TB3> INFO: Expecting 655360 events.
[20:13:18.370] <TB3> INFO: 655360 events read in total (13384ms).
[20:13:18.381] <TB3> INFO: Expecting 655360 events.
[20:13:32.416] <TB3> INFO: 655360 events read in total (13632ms).
[20:13:32.435] <TB3> INFO: Expecting 655360 events.
[20:13:46.129] <TB3> INFO: 655360 events read in total (13291ms).
[20:13:46.147] <TB3> INFO: Expecting 655360 events.
[20:14:00.109] <TB3> INFO: 655360 events read in total (13559ms).
[20:14:00.133] <TB3> INFO: Expecting 655360 events.
[20:14:14.306] <TB3> INFO: 655360 events read in total (13770ms).
[20:14:14.342] <TB3> INFO: Expecting 655360 events.
[20:14:28.222] <TB3> INFO: 655360 events read in total (13477ms).
[20:14:28.253] <TB3> INFO: Expecting 655360 events.
[20:14:42.169] <TB3> INFO: 655360 events read in total (13513ms).
[20:14:42.204] <TB3> INFO: Expecting 655360 events.
[20:14:56.108] <TB3> INFO: 655360 events read in total (13501ms).
[20:14:56.146] <TB3> INFO: Expecting 655360 events.
[20:15:09.876] <TB3> INFO: 655360 events read in total (13327ms).
[20:15:09.924] <TB3> INFO: Expecting 655360 events.
[20:15:23.950] <TB3> INFO: 655360 events read in total (13623ms).
[20:15:24.013] <TB3> INFO: Expecting 655360 events.
[20:15:37.915] <TB3> INFO: 655360 events read in total (13499ms).
[20:15:37.970] <TB3> INFO: Expecting 655360 events.
[20:15:52.085] <TB3> INFO: 655360 events read in total (13712ms).
[20:15:52.157] <TB3> INFO: Expecting 655360 events.
[20:16:06.127] <TB3> INFO: 655360 events read in total (13567ms).
[20:16:06.187] <TB3> INFO: Expecting 655360 events.
[20:16:20.103] <TB3> INFO: 655360 events read in total (13512ms).
[20:16:20.196] <TB3> INFO: Expecting 655360 events.
[20:16:34.193] <TB3> INFO: 655360 events read in total (13594ms).
[20:16:34.287] <TB3> INFO: Expecting 655360 events.
[20:16:48.660] <TB3> INFO: 655360 events read in total (13970ms).
[20:16:48.733] <TB3> INFO: Test took 224604ms.
[20:16:48.923] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[20:16:48.928] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[20:16:48.933] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[20:16:48.937] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[20:16:48.942] <TB3> INFO: safety margin for low PH: adding 1, margin is now 21
[20:16:48.947] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[20:16:48.951] <TB3> INFO: safety margin for low PH: adding 1, margin is now 21
[20:16:48.956] <TB3> INFO: safety margin for low PH: adding 2, margin is now 22
[20:16:48.962] <TB3> INFO: safety margin for low PH: adding 3, margin is now 23
[20:16:48.966] <TB3> INFO: safety margin for low PH: adding 4, margin is now 24
[20:16:48.971] <TB3> INFO: safety margin for low PH: adding 5, margin is now 25
[20:16:48.976] <TB3> INFO: safety margin for low PH: adding 6, margin is now 26
[20:16:48.980] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[20:16:48.985] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[20:16:48.990] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[20:16:48.995] <TB3> INFO: safety margin for low PH: adding 1, margin is now 21
[20:16:48.999] <TB3> INFO: safety margin for low PH: adding 2, margin is now 22
[20:16:48.004] <TB3> INFO: safety margin for low PH: adding 3, margin is now 23
[20:16:49.008] <TB3> INFO: safety margin for low PH: adding 4, margin is now 24
[20:16:49.013] <TB3> INFO: safety margin for low PH: adding 5, margin is now 25
[20:16:49.018] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[20:16:49.022] <TB3> INFO: safety margin for low PH: adding 1, margin is now 21
[20:16:49.027] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[20:16:49.032] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[20:16:49.036] <TB3> INFO: safety margin for low PH: adding 1, margin is now 21
[20:16:49.041] <TB3> INFO: safety margin for low PH: adding 2, margin is now 22
[20:16:49.045] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[20:16:49.050] <TB3> INFO: safety margin for low PH: adding 1, margin is now 21
[20:16:49.054] <TB3> INFO: safety margin for low PH: adding 2, margin is now 22
[20:16:49.059] <TB3> INFO: safety margin for low PH: adding 3, margin is now 23
[20:16:49.064] <TB3> INFO: safety margin for low PH: adding 4, margin is now 24
[20:16:49.069] <TB3> INFO: safety margin for low PH: adding 5, margin is now 25
[20:16:49.073] <TB3> INFO: safety margin for low PH: adding 6, margin is now 26
[20:16:49.078] <TB3> INFO: safety margin for low PH: adding 7, margin is now 27
[20:16:49.082] <TB3> INFO: safety margin for low PH: adding 8, margin is now 28
[20:16:49.086] <TB3> INFO: safety margin for low PH: adding 9, margin is now 29
[20:16:49.091] <TB3> INFO: safety margin for low PH: adding 10, margin is now 30
[20:16:49.096] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[20:16:49.101] <TB3> INFO: safety margin for low PH: adding 1, margin is now 21
[20:16:49.105] <TB3> INFO: safety margin for low PH: adding 2, margin is now 22
[20:16:49.110] <TB3> INFO: safety margin for low PH: adding 3, margin is now 23
[20:16:49.114] <TB3> INFO: safety margin for low PH: adding 4, margin is now 24
[20:16:49.119] <TB3> INFO: safety margin for low PH: adding 5, margin is now 25
[20:16:49.123] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[20:16:49.128] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[20:16:49.133] <TB3> INFO: safety margin for low PH: adding 1, margin is now 21
[20:16:49.137] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[20:16:49.170] <TB3> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1080_FullQualification_2016-10-25_15h54m_1477403681//003_Fulltest_p17//dacParameters35_C0.dat
[20:16:49.170] <TB3> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1080_FullQualification_2016-10-25_15h54m_1477403681//003_Fulltest_p17//dacParameters35_C1.dat
[20:16:49.170] <TB3> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1080_FullQualification_2016-10-25_15h54m_1477403681//003_Fulltest_p17//dacParameters35_C2.dat
[20:16:49.170] <TB3> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1080_FullQualification_2016-10-25_15h54m_1477403681//003_Fulltest_p17//dacParameters35_C3.dat
[20:16:49.170] <TB3> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1080_FullQualification_2016-10-25_15h54m_1477403681//003_Fulltest_p17//dacParameters35_C4.dat
[20:16:49.170] <TB3> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1080_FullQualification_2016-10-25_15h54m_1477403681//003_Fulltest_p17//dacParameters35_C5.dat
[20:16:49.170] <TB3> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1080_FullQualification_2016-10-25_15h54m_1477403681//003_Fulltest_p17//dacParameters35_C6.dat
[20:16:49.170] <TB3> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1080_FullQualification_2016-10-25_15h54m_1477403681//003_Fulltest_p17//dacParameters35_C7.dat
[20:16:49.171] <TB3> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1080_FullQualification_2016-10-25_15h54m_1477403681//003_Fulltest_p17//dacParameters35_C8.dat
[20:16:49.171] <TB3> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1080_FullQualification_2016-10-25_15h54m_1477403681//003_Fulltest_p17//dacParameters35_C9.dat
[20:16:49.171] <TB3> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1080_FullQualification_2016-10-25_15h54m_1477403681//003_Fulltest_p17//dacParameters35_C10.dat
[20:16:49.171] <TB3> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1080_FullQualification_2016-10-25_15h54m_1477403681//003_Fulltest_p17//dacParameters35_C11.dat
[20:16:49.171] <TB3> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1080_FullQualification_2016-10-25_15h54m_1477403681//003_Fulltest_p17//dacParameters35_C12.dat
[20:16:49.171] <TB3> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1080_FullQualification_2016-10-25_15h54m_1477403681//003_Fulltest_p17//dacParameters35_C13.dat
[20:16:49.171] <TB3> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1080_FullQualification_2016-10-25_15h54m_1477403681//003_Fulltest_p17//dacParameters35_C14.dat
[20:16:49.171] <TB3> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1080_FullQualification_2016-10-25_15h54m_1477403681//003_Fulltest_p17//dacParameters35_C15.dat
[20:16:49.431] <TB3> INFO: Expecting 41600 events.
[20:16:52.519] <TB3> INFO: 41600 events read in total (2496ms).
[20:16:52.520] <TB3> INFO: Test took 3346ms.
[20:16:52.962] <TB3> INFO: Expecting 41600 events.
[20:16:55.981] <TB3> INFO: 41600 events read in total (2428ms).
[20:16:55.981] <TB3> INFO: Test took 3251ms.
[20:16:56.438] <TB3> INFO: Expecting 41600 events.
[20:16:59.577] <TB3> INFO: 41600 events read in total (2548ms).
[20:16:59.578] <TB3> INFO: Test took 3383ms.
[20:16:59.792] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[20:16:59.880] <TB3> INFO: Expecting 2560 events.
[20:17:00.762] <TB3> INFO: 2560 events read in total (290ms).
[20:17:00.763] <TB3> INFO: Test took 971ms.
[20:17:00.765] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[20:17:01.071] <TB3> INFO: Expecting 2560 events.
[20:17:01.957] <TB3> INFO: 2560 events read in total (294ms).
[20:17:01.958] <TB3> INFO: Test took 1193ms.
[20:17:01.960] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[20:17:02.267] <TB3> INFO: Expecting 2560 events.
[20:17:03.150] <TB3> INFO: 2560 events read in total (292ms).
[20:17:03.151] <TB3> INFO: Test took 1191ms.
[20:17:03.152] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[20:17:03.459] <TB3> INFO: Expecting 2560 events.
[20:17:04.342] <TB3> INFO: 2560 events read in total (292ms).
[20:17:04.342] <TB3> INFO: Test took 1190ms.
[20:17:04.344] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[20:17:04.651] <TB3> INFO: Expecting 2560 events.
[20:17:05.534] <TB3> INFO: 2560 events read in total (292ms).
[20:17:05.535] <TB3> INFO: Test took 1191ms.
[20:17:05.536] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[20:17:05.843] <TB3> INFO: Expecting 2560 events.
[20:17:06.726] <TB3> INFO: 2560 events read in total (292ms).
[20:17:06.727] <TB3> INFO: Test took 1191ms.
[20:17:06.728] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[20:17:07.035] <TB3> INFO: Expecting 2560 events.
[20:17:07.917] <TB3> INFO: 2560 events read in total (290ms).
[20:17:07.917] <TB3> INFO: Test took 1189ms.
[20:17:07.919] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[20:17:08.225] <TB3> INFO: Expecting 2560 events.
[20:17:09.113] <TB3> INFO: 2560 events read in total (296ms).
[20:17:09.113] <TB3> INFO: Test took 1194ms.
[20:17:09.115] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[20:17:09.422] <TB3> INFO: Expecting 2560 events.
[20:17:10.302] <TB3> INFO: 2560 events read in total (289ms).
[20:17:10.302] <TB3> INFO: Test took 1187ms.
[20:17:10.304] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[20:17:10.610] <TB3> INFO: Expecting 2560 events.
[20:17:11.493] <TB3> INFO: 2560 events read in total (291ms).
[20:17:11.493] <TB3> INFO: Test took 1189ms.
[20:17:11.495] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[20:17:11.801] <TB3> INFO: Expecting 2560 events.
[20:17:12.681] <TB3> INFO: 2560 events read in total (288ms).
[20:17:12.681] <TB3> INFO: Test took 1186ms.
[20:17:12.683] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[20:17:12.990] <TB3> INFO: Expecting 2560 events.
[20:17:13.869] <TB3> INFO: 2560 events read in total (288ms).
[20:17:13.869] <TB3> INFO: Test took 1186ms.
[20:17:13.871] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[20:17:14.178] <TB3> INFO: Expecting 2560 events.
[20:17:15.060] <TB3> INFO: 2560 events read in total (291ms).
[20:17:15.061] <TB3> INFO: Test took 1190ms.
[20:17:15.063] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[20:17:15.369] <TB3> INFO: Expecting 2560 events.
[20:17:16.248] <TB3> INFO: 2560 events read in total (288ms).
[20:17:16.249] <TB3> INFO: Test took 1186ms.
[20:17:16.251] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[20:17:16.557] <TB3> INFO: Expecting 2560 events.
[20:17:17.439] <TB3> INFO: 2560 events read in total (291ms).
[20:17:17.439] <TB3> INFO: Test took 1189ms.
[20:17:17.441] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[20:17:17.747] <TB3> INFO: Expecting 2560 events.
[20:17:18.630] <TB3> INFO: 2560 events read in total (291ms).
[20:17:18.630] <TB3> INFO: Test took 1189ms.
[20:17:18.632] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[20:17:18.938] <TB3> INFO: Expecting 2560 events.
[20:17:19.818] <TB3> INFO: 2560 events read in total (288ms).
[20:17:19.818] <TB3> INFO: Test took 1186ms.
[20:17:19.820] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[20:17:20.127] <TB3> INFO: Expecting 2560 events.
[20:17:20.007] <TB3> INFO: 2560 events read in total (289ms).
[20:17:21.007] <TB3> INFO: Test took 1187ms.
[20:17:21.009] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[20:17:21.315] <TB3> INFO: Expecting 2560 events.
[20:17:22.198] <TB3> INFO: 2560 events read in total (291ms).
[20:17:22.198] <TB3> INFO: Test took 1189ms.
[20:17:22.200] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[20:17:22.507] <TB3> INFO: Expecting 2560 events.
[20:17:23.388] <TB3> INFO: 2560 events read in total (290ms).
[20:17:23.388] <TB3> INFO: Test took 1188ms.
[20:17:23.390] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[20:17:23.697] <TB3> INFO: Expecting 2560 events.
[20:17:24.577] <TB3> INFO: 2560 events read in total (289ms).
[20:17:24.577] <TB3> INFO: Test took 1187ms.
[20:17:24.579] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[20:17:24.886] <TB3> INFO: Expecting 2560 events.
[20:17:25.765] <TB3> INFO: 2560 events read in total (288ms).
[20:17:25.765] <TB3> INFO: Test took 1186ms.
[20:17:25.767] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[20:17:26.073] <TB3> INFO: Expecting 2560 events.
[20:17:26.953] <TB3> INFO: 2560 events read in total (288ms).
[20:17:26.953] <TB3> INFO: Test took 1186ms.
[20:17:26.955] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[20:17:27.261] <TB3> INFO: Expecting 2560 events.
[20:17:28.144] <TB3> INFO: 2560 events read in total (291ms).
[20:17:28.145] <TB3> INFO: Test took 1190ms.
[20:17:28.147] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[20:17:28.453] <TB3> INFO: Expecting 2560 events.
[20:17:29.338] <TB3> INFO: 2560 events read in total (294ms).
[20:17:29.338] <TB3> INFO: Test took 1192ms.
[20:17:29.341] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[20:17:29.647] <TB3> INFO: Expecting 2560 events.
[20:17:30.533] <TB3> INFO: 2560 events read in total (295ms).
[20:17:30.534] <TB3> INFO: Test took 1193ms.
[20:17:30.536] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[20:17:30.842] <TB3> INFO: Expecting 2560 events.
[20:17:31.728] <TB3> INFO: 2560 events read in total (295ms).
[20:17:31.728] <TB3> INFO: Test took 1192ms.
[20:17:31.730] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[20:17:32.037] <TB3> INFO: Expecting 2560 events.
[20:17:32.924] <TB3> INFO: 2560 events read in total (295ms).
[20:17:32.924] <TB3> INFO: Test took 1194ms.
[20:17:32.926] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[20:17:33.232] <TB3> INFO: Expecting 2560 events.
[20:17:34.115] <TB3> INFO: 2560 events read in total (292ms).
[20:17:34.115] <TB3> INFO: Test took 1189ms.
[20:17:34.117] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[20:17:34.424] <TB3> INFO: Expecting 2560 events.
[20:17:35.308] <TB3> INFO: 2560 events read in total (292ms).
[20:17:35.308] <TB3> INFO: Test took 1191ms.
[20:17:35.311] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[20:17:35.617] <TB3> INFO: Expecting 2560 events.
[20:17:36.500] <TB3> INFO: 2560 events read in total (292ms).
[20:17:36.500] <TB3> INFO: Test took 1189ms.
[20:17:36.503] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[20:17:36.809] <TB3> INFO: Expecting 2560 events.
[20:17:37.693] <TB3> INFO: 2560 events read in total (293ms).
[20:17:37.693] <TB3> INFO: Test took 1191ms.
[20:17:38.157] <TB3> INFO: PixTestPhOptimization::doTest() done, duration: 629 seconds
[20:17:38.157] <TB3> INFO: PH scale (per ROC): 42 35 38 36 38 43 28 31 43 47 48 47 41 44 47 48
[20:17:38.157] <TB3> INFO: PH offset (per ROC): 92 76 94 96 93 103 101 103 108 128 129 112 98 118 101 132
[20:17:38.162] <TB3> INFO: Decoding statistics:
[20:17:38.162] <TB3> INFO: General information:
[20:17:38.162] <TB3> INFO: 16bit words read: 127884
[20:17:38.162] <TB3> INFO: valid events total: 20480
[20:17:38.162] <TB3> INFO: empty events: 17978
[20:17:38.162] <TB3> INFO: valid events with pixels: 2502
[20:17:38.162] <TB3> INFO: valid pixel hits: 2502
[20:17:38.162] <TB3> INFO: Event errors: 0
[20:17:38.162] <TB3> INFO: start marker: 0
[20:17:38.162] <TB3> INFO: stop marker: 0
[20:17:38.162] <TB3> INFO: overflow: 0
[20:17:38.162] <TB3> INFO: invalid 5bit words: 0
[20:17:38.162] <TB3> INFO: invalid XOR eye diagram: 0
[20:17:38.162] <TB3> INFO: frame (failed synchr.): 0
[20:17:38.162] <TB3> INFO: idle data (no TBM trl): 0
[20:17:38.162] <TB3> INFO: no data (only TBM hdr): 0
[20:17:38.162] <TB3> INFO: TBM errors: 0
[20:17:38.162] <TB3> INFO: flawed TBM headers: 0
[20:17:38.162] <TB3> INFO: flawed TBM trailers: 0
[20:17:38.162] <TB3> INFO: event ID mismatches: 0
[20:17:38.162] <TB3> INFO: ROC errors: 0
[20:17:38.162] <TB3> INFO: missing ROC header(s): 0
[20:17:38.162] <TB3> INFO: misplaced readback start: 0
[20:17:38.162] <TB3> INFO: Pixel decoding errors: 0
[20:17:38.162] <TB3> INFO: pixel data incomplete: 0
[20:17:38.162] <TB3> INFO: pixel address: 0
[20:17:38.162] <TB3> INFO: pulse height fill bit: 0
[20:17:38.162] <TB3> INFO: buffer corruption: 0
[20:17:38.430] <TB3> INFO: ######################################################################
[20:17:38.431] <TB3> INFO: PixTestGainPedestal::fullTest() ntrig = 10
[20:17:38.431] <TB3> INFO: ######################################################################
[20:17:38.442] <TB3> INFO: scanning low vcal = 10
[20:17:38.674] <TB3> INFO: Expecting 41600 events.
[20:17:42.230] <TB3> INFO: 41600 events read in total (2965ms).
[20:17:42.230] <TB3> INFO: Test took 3788ms.
[20:17:42.231] <TB3> INFO: scanning low vcal = 20
[20:17:42.531] <TB3> INFO: Expecting 41600 events.
[20:17:46.082] <TB3> INFO: 41600 events read in total (2959ms).
[20:17:46.082] <TB3> INFO: Test took 3850ms.
[20:17:46.084] <TB3> INFO: scanning low vcal = 30
[20:17:46.383] <TB3> INFO: Expecting 41600 events.
[20:17:50.009] <TB3> INFO: 41600 events read in total (3035ms).
[20:17:50.009] <TB3> INFO: Test took 3925ms.
[20:17:50.012] <TB3> INFO: scanning low vcal = 40
[20:17:50.291] <TB3> INFO: Expecting 41600 events.
[20:17:54.204] <TB3> INFO: 41600 events read in total (3321ms).
[20:17:54.206] <TB3> INFO: Test took 4194ms.
[20:17:54.208] <TB3> INFO: scanning low vcal = 50
[20:17:54.485] <TB3> INFO: Expecting 41600 events.
[20:17:58.411] <TB3> INFO: 41600 events read in total (3334ms).
[20:17:58.412] <TB3> INFO: Test took 4204ms.
[20:17:58.415] <TB3> INFO: scanning low vcal = 60
[20:17:58.692] <TB3> INFO: Expecting 41600 events.
[20:18:02.629] <TB3> INFO: 41600 events read in total (3345ms).
[20:18:02.630] <TB3> INFO: Test took 4215ms.
[20:18:02.632] <TB3> INFO: scanning low vcal = 70
[20:18:02.909] <TB3> INFO: Expecting 41600 events.
[20:18:06.856] <TB3> INFO: 41600 events read in total (3355ms).
[20:18:06.857] <TB3> INFO: Test took 4224ms.
[20:18:06.860] <TB3> INFO: scanning low vcal = 80
[20:18:07.137] <TB3> INFO: Expecting 41600 events.
[20:18:11.099] <TB3> INFO: 41600 events read in total (3371ms).
[20:18:11.100] <TB3> INFO: Test took 4240ms.
[20:18:11.103] <TB3> INFO: scanning low vcal = 90
[20:18:11.380] <TB3> INFO: Expecting 41600 events.
[20:18:15.300] <TB3> INFO: 41600 events read in total (3329ms).
[20:18:15.301] <TB3> INFO: Test took 4198ms.
[20:18:15.304] <TB3> INFO: scanning low vcal = 100
[20:18:15.580] <TB3> INFO: Expecting 41600 events.
[20:18:19.517] <TB3> INFO: 41600 events read in total (3345ms).
[20:18:19.518] <TB3> INFO: Test took 4214ms.
[20:18:19.520] <TB3> INFO: scanning low vcal = 110
[20:18:19.797] <TB3> INFO: Expecting 41600 events.
[20:18:23.756] <TB3> INFO: 41600 events read in total (3368ms).
[20:18:23.757] <TB3> INFO: Test took 4237ms.
[20:18:23.759] <TB3> INFO: scanning low vcal = 120
[20:18:24.036] <TB3> INFO: Expecting 41600 events.
[20:18:27.992] <TB3> INFO: 41600 events read in total (3364ms).
[20:18:27.992] <TB3> INFO: Test took 4233ms.
[20:18:27.995] <TB3> INFO: scanning low vcal = 130
[20:18:28.272] <TB3> INFO: Expecting 41600 events.
[20:18:32.189] <TB3> INFO: 41600 events read in total (3326ms).
[20:18:32.190] <TB3> INFO: Test took 4195ms.
[20:18:32.192] <TB3> INFO: scanning low vcal = 140
[20:18:32.469] <TB3> INFO: Expecting 41600 events.
[20:18:36.391] <TB3> INFO: 41600 events read in total (3330ms).
[20:18:36.392] <TB3> INFO: Test took 4199ms.
[20:18:36.394] <TB3> INFO: scanning low vcal = 150
[20:18:36.671] <TB3> INFO: Expecting 41600 events.
[20:18:40.665] <TB3> INFO: 41600 events read in total (3402ms).
[20:18:40.666] <TB3> INFO: Test took 4272ms.
[20:18:40.669] <TB3> INFO: scanning low vcal = 160
[20:18:40.945] <TB3> INFO: Expecting 41600 events.
[20:18:44.885] <TB3> INFO: 41600 events read in total (3348ms).
[20:18:44.886] <TB3> INFO: Test took 4217ms.
[20:18:44.889] <TB3> INFO: scanning low vcal = 170
[20:18:45.165] <TB3> INFO: Expecting 41600 events.
[20:18:49.117] <TB3> INFO: 41600 events read in total (3360ms).
[20:18:49.118] <TB3> INFO: Test took 4229ms.
[20:18:49.120] <TB3> INFO: scanning low vcal = 180
[20:18:49.397] <TB3> INFO: Expecting 41600 events.
[20:18:53.319] <TB3> INFO: 41600 events read in total (3330ms).
[20:18:53.320] <TB3> INFO: Test took 4199ms.
[20:18:53.322] <TB3> INFO: scanning low vcal = 190
[20:18:53.599] <TB3> INFO: Expecting 41600 events.
[20:18:57.556] <TB3> INFO: 41600 events read in total (3365ms).
[20:18:57.557] <TB3> INFO: Test took 4235ms.
[20:18:57.560] <TB3> INFO: scanning low vcal = 200
[20:18:57.836] <TB3> INFO: Expecting 41600 events.
[20:19:01.785] <TB3> INFO: 41600 events read in total (3357ms).
[20:19:01.785] <TB3> INFO: Test took 4225ms.
[20:19:01.788] <TB3> INFO: scanning low vcal = 210
[20:19:02.065] <TB3> INFO: Expecting 41600 events.
[20:19:05.006] <TB3> INFO: 41600 events read in total (3350ms).
[20:19:06.007] <TB3> INFO: Test took 4219ms.
[20:19:06.010] <TB3> INFO: scanning low vcal = 220
[20:19:06.287] <TB3> INFO: Expecting 41600 events.
[20:19:10.213] <TB3> INFO: 41600 events read in total (3335ms).
[20:19:10.214] <TB3> INFO: Test took 4205ms.
[20:19:10.218] <TB3> INFO: scanning low vcal = 230
[20:19:10.494] <TB3> INFO: Expecting 41600 events.
[20:19:14.449] <TB3> INFO: 41600 events read in total (3363ms).
[20:19:14.449] <TB3> INFO: Test took 4230ms.
[20:19:14.452] <TB3> INFO: scanning low vcal = 240
[20:19:14.729] <TB3> INFO: Expecting 41600 events.
[20:19:18.703] <TB3> INFO: 41600 events read in total (3382ms).
[20:19:18.704] <TB3> INFO: Test took 4252ms.
[20:19:18.707] <TB3> INFO: scanning low vcal = 250
[20:19:18.984] <TB3> INFO: Expecting 41600 events.
[20:19:22.921] <TB3> INFO: 41600 events read in total (3346ms).
[20:19:22.922] <TB3> INFO: Test took 4215ms.
[20:19:22.926] <TB3> INFO: scanning high vcal = 30 (= 210 in low range)
[20:19:23.202] <TB3> INFO: Expecting 41600 events.
[20:19:27.148] <TB3> INFO: 41600 events read in total (3355ms).
[20:19:27.149] <TB3> INFO: Test took 4223ms.
[20:19:27.151] <TB3> INFO: scanning high vcal = 50 (= 350 in low range)
[20:19:27.428] <TB3> INFO: Expecting 41600 events.
[20:19:31.381] <TB3> INFO: 41600 events read in total (3361ms).
[20:19:31.382] <TB3> INFO: Test took 4231ms.
[20:19:31.385] <TB3> INFO: scanning high vcal = 70 (= 490 in low range)
[20:19:31.661] <TB3> INFO: Expecting 41600 events.
[20:19:35.674] <TB3> INFO: 41600 events read in total (3421ms).
[20:19:35.675] <TB3> INFO: Test took 4290ms.
[20:19:35.678] <TB3> INFO: scanning high vcal = 90 (= 630 in low range)
[20:19:35.955] <TB3> INFO: Expecting 41600 events.
[20:19:39.917] <TB3> INFO: 41600 events read in total (3371ms).
[20:19:39.918] <TB3> INFO: Test took 4240ms.
[20:19:39.921] <TB3> INFO: scanning high vcal = 200 (= 1400 in low range)
[20:19:40.204] <TB3> INFO: Expecting 41600 events.
[20:19:44.193] <TB3> INFO: 41600 events read in total (3398ms).
[20:19:44.194] <TB3> INFO: Test took 4273ms.
[20:19:44.876] <TB3> INFO: PixTestGainPedestal::measure() done
[20:20:26.596] <TB3> INFO: PixTestGainPedestal::fit() done
[20:20:26.596] <TB3> INFO: non-linearity mean: 0.959 0.932 0.950 0.946 0.945 0.952 1.033 1.050 0.943 0.975 0.985 0.956 0.945 0.933 0.950 0.984
[20:20:26.596] <TB3> INFO: non-linearity RMS: 0.035 0.174 0.170 0.178 0.151 0.057 0.133 0.165 0.078 0.007 0.003 0.057 0.081 0.071 0.033 0.004
[20:20:26.596] <TB3> INFO: write gain/ped parameters into /home/l_tester/david/FullQualification/data/M1080_FullQualification_2016-10-25_15h54m_1477403681//003_Fulltest_p17//phCalibrationFitErr35_C0.dat
[20:20:26.612] <TB3> INFO: write gain/ped parameters into /home/l_tester/david/FullQualification/data/M1080_FullQualification_2016-10-25_15h54m_1477403681//003_Fulltest_p17//phCalibrationFitErr35_C1.dat
[20:20:26.633] <TB3> INFO: write gain/ped parameters into /home/l_tester/david/FullQualification/data/M1080_FullQualification_2016-10-25_15h54m_1477403681//003_Fulltest_p17//phCalibrationFitErr35_C2.dat
[20:20:26.654] <TB3> INFO: write gain/ped parameters into /home/l_tester/david/FullQualification/data/M1080_FullQualification_2016-10-25_15h54m_1477403681//003_Fulltest_p17//phCalibrationFitErr35_C3.dat
[20:20:26.670] <TB3> INFO: write gain/ped parameters into /home/l_tester/david/FullQualification/data/M1080_FullQualification_2016-10-25_15h54m_1477403681//003_Fulltest_p17//phCalibrationFitErr35_C4.dat
[20:20:26.684] <TB3> INFO: write gain/ped parameters into /home/l_tester/david/FullQualification/data/M1080_FullQualification_2016-10-25_15h54m_1477403681//003_Fulltest_p17//phCalibrationFitErr35_C5.dat
[20:20:26.698] <TB3> INFO: write gain/ped parameters into /home/l_tester/david/FullQualification/data/M1080_FullQualification_2016-10-25_15h54m_1477403681//003_Fulltest_p17//phCalibrationFitErr35_C6.dat
[20:20:26.712] <TB3> INFO: write gain/ped parameters into /home/l_tester/david/FullQualification/data/M1080_FullQualification_2016-10-25_15h54m_1477403681//003_Fulltest_p17//phCalibrationFitErr35_C7.dat
[20:20:26.726] <TB3> INFO: write gain/ped parameters into /home/l_tester/david/FullQualification/data/M1080_FullQualification_2016-10-25_15h54m_1477403681//003_Fulltest_p17//phCalibrationFitErr35_C8.dat
[20:20:26.740] <TB3> INFO: write gain/ped parameters into /home/l_tester/david/FullQualification/data/M1080_FullQualification_2016-10-25_15h54m_1477403681//003_Fulltest_p17//phCalibrationFitErr35_C9.dat
[20:20:26.754] <TB3> INFO: write gain/ped parameters into /home/l_tester/david/FullQualification/data/M1080_FullQualification_2016-10-25_15h54m_1477403681//003_Fulltest_p17//phCalibrationFitErr35_C10.dat
[20:20:26.767] <TB3> INFO: write gain/ped parameters into /home/l_tester/david/FullQualification/data/M1080_FullQualification_2016-10-25_15h54m_1477403681//003_Fulltest_p17//phCalibrationFitErr35_C11.dat
[20:20:26.781] <TB3> INFO: write gain/ped parameters into /home/l_tester/david/FullQualification/data/M1080_FullQualification_2016-10-25_15h54m_1477403681//003_Fulltest_p17//phCalibrationFitErr35_C12.dat
[20:20:26.795] <TB3> INFO: write gain/ped parameters into /home/l_tester/david/FullQualification/data/M1080_FullQualification_2016-10-25_15h54m_1477403681//003_Fulltest_p17//phCalibrationFitErr35_C13.dat
[20:20:26.809] <TB3> INFO: write gain/ped parameters into /home/l_tester/david/FullQualification/data/M1080_FullQualification_2016-10-25_15h54m_1477403681//003_Fulltest_p17//phCalibrationFitErr35_C14.dat
[20:20:26.823] <TB3> INFO: write gain/ped parameters into /home/l_tester/david/FullQualification/data/M1080_FullQualification_2016-10-25_15h54m_1477403681//003_Fulltest_p17//phCalibrationFitErr35_C15.dat
[20:20:26.837] <TB3> INFO: PixTestGainPedestal::fullTest() done, duration: 168 seconds
[20:20:26.837] <TB3> INFO: Decoding statistics:
[20:20:26.837] <TB3> INFO: General information:
[20:20:26.837] <TB3> INFO: 16bit words read: 3305506
[20:20:26.837] <TB3> INFO: valid events total: 332800
[20:20:26.837] <TB3> INFO: empty events: 339
[20:20:26.837] <TB3> INFO: valid events with pixels: 332461
[20:20:26.838] <TB3> INFO: valid pixel hits: 654353
[20:20:26.838] <TB3> INFO: Event errors: 0
[20:20:26.838] <TB3> INFO: start marker: 0
[20:20:26.838] <TB3> INFO: stop marker: 0
[20:20:26.838] <TB3> INFO: overflow: 0
[20:20:26.838] <TB3> INFO: invalid 5bit words: 0
[20:20:26.838] <TB3> INFO: invalid XOR eye diagram: 0
[20:20:26.838] <TB3> INFO: frame (failed synchr.): 0
[20:20:26.838] <TB3> INFO: idle data (no TBM trl): 0
[20:20:26.838] <TB3> INFO: no data (only TBM hdr): 0
[20:20:26.838] <TB3> INFO: TBM errors: 0
[20:20:26.838] <TB3> INFO: flawed TBM headers: 0
[20:20:26.838] <TB3> INFO: flawed TBM trailers: 0
[20:20:26.838] <TB3> INFO: event ID mismatches: 0
[20:20:26.838] <TB3> INFO: ROC errors: 0
[20:20:26.838] <TB3> INFO: missing ROC header(s): 0
[20:20:26.838] <TB3> INFO: misplaced readback start: 0
[20:20:26.838] <TB3> INFO: Pixel decoding errors: 0
[20:20:26.838] <TB3> INFO: pixel data incomplete: 0
[20:20:26.838] <TB3> INFO: pixel address: 0
[20:20:26.838] <TB3> INFO: pulse height fill bit: 0
[20:20:26.838] <TB3> INFO: buffer corruption: 0
[20:20:26.853] <TB3> INFO: Decoding statistics:
[20:20:26.853] <TB3> INFO: General information:
[20:20:26.854] <TB3> INFO: 16bit words read: 3434926
[20:20:26.854] <TB3> INFO: valid events total: 353536
[20:20:26.854] <TB3> INFO: empty events: 18573
[20:20:26.854] <TB3> INFO: valid events with pixels: 334963
[20:20:26.854] <TB3> INFO: valid pixel hits: 656855
[20:20:26.854] <TB3> INFO: Event errors: 0
[20:20:26.854] <TB3> INFO: start marker: 0
[20:20:26.854] <TB3> INFO: stop marker: 0
[20:20:26.854] <TB3> INFO: overflow: 0
[20:20:26.854] <TB3> INFO: invalid 5bit words: 0
[20:20:26.854] <TB3> INFO: invalid XOR eye diagram: 0
[20:20:26.854] <TB3> INFO: frame (failed synchr.): 0
[20:20:26.854] <TB3> INFO: idle data (no TBM trl): 0
[20:20:26.854] <TB3> INFO: no data (only TBM hdr): 0
[20:20:26.854] <TB3> INFO: TBM errors: 0
[20:20:26.854] <TB3> INFO: flawed TBM headers: 0
[20:20:26.854] <TB3> INFO: flawed TBM trailers: 0
[20:20:26.854] <TB3> INFO: event ID mismatches: 0
[20:20:26.854] <TB3> INFO: ROC errors: 0
[20:20:26.854] <TB3> INFO: missing ROC header(s): 0
[20:20:26.854] <TB3> INFO: misplaced readback start: 0
[20:20:26.854] <TB3> INFO: Pixel decoding errors: 0
[20:20:26.854] <TB3> INFO: pixel data incomplete: 0
[20:20:26.854] <TB3> INFO: pixel address: 0
[20:20:26.854] <TB3> INFO: pulse height fill bit: 0
[20:20:26.854] <TB3> INFO: buffer corruption: 0
[20:20:26.854] <TB3> INFO: enter test to run
[20:20:26.854] <TB3> INFO: test: Trim80 no parameter change
[20:20:26.854] <TB3> INFO: running: trim80
[20:20:26.870] <TB3> INFO: ######################################################################
[20:20:26.870] <TB3> INFO: PixTestTrim80::doTest()
[20:20:26.870] <TB3> INFO: ######################################################################
[20:20:26.871] <TB3> INFO: ----------------------------------------------------------------------
[20:20:26.871] <TB3> INFO: PixTestTrim80::trimTest() ntrig = 8, vcal = 80
[20:20:26.871] <TB3> INFO: ----------------------------------------------------------------------
[20:20:26.911] <TB3> INFO: ---> VthrComp thr map (minimal VthrComp)
[20:20:26.911] <TB3> INFO: ---> dac: vthrcomp name: TrimThr0 ntrig: 8 dacrange: 10 .. 160 (-1/-1) hits flags = 528 (plus default)
[20:20:26.920] <TB3> INFO: dacScan split into 1 runs with ntrig = 8
[20:20:26.920] <TB3> INFO: run 1 of 1
[20:20:27.166] <TB3> INFO: Expecting 5025280 events.
[20:20:54.640] <TB3> INFO: 682240 events read in total (26883ms).
[20:21:21.304] <TB3> INFO: 1359136 events read in total (53547ms).
[20:21:47.771] <TB3> INFO: 2033856 events read in total (80014ms).
[20:22:14.421] <TB3> INFO: 2706472 events read in total (106664ms).
[20:22:40.868] <TB3> INFO: 3379008 events read in total (133111ms).
[20:23:07.508] <TB3> INFO: 4050936 events read in total (159751ms).
[20:23:34.524] <TB3> INFO: 4721992 events read in total (186767ms).
[20:23:46.812] <TB3> INFO: 5025280 events read in total (199055ms).
[20:23:46.879] <TB3> INFO: Test took 199960ms.
[20:24:10.156] <TB3> INFO: ROC 0 VthrComp = 81
[20:24:10.157] <TB3> INFO: ROC 1 VthrComp = 85
[20:24:10.157] <TB3> INFO: ROC 2 VthrComp = 79
[20:24:10.157] <TB3> INFO: ROC 3 VthrComp = 82
[20:24:10.157] <TB3> INFO: ROC 4 VthrComp = 79
[20:24:10.157] <TB3> INFO: ROC 5 VthrComp = 87
[20:24:10.157] <TB3> INFO: ROC 6 VthrComp = 84
[20:24:10.157] <TB3> INFO: ROC 7 VthrComp = 72
[20:24:10.157] <TB3> INFO: ROC 8 VthrComp = 73
[20:24:10.157] <TB3> INFO: ROC 9 VthrComp = 74
[20:24:10.157] <TB3> INFO: ROC 10 VthrComp = 72
[20:24:10.158] <TB3> INFO: ROC 11 VthrComp = 71
[20:24:10.158] <TB3> INFO: ROC 12 VthrComp = 72
[20:24:10.158] <TB3> INFO: ROC 13 VthrComp = 71
[20:24:10.158] <TB3> INFO: ROC 14 VthrComp = 82
[20:24:10.158] <TB3> INFO: ROC 15 VthrComp = 81
[20:24:10.429] <TB3> INFO: Expecting 41600 events.
[20:24:13.943] <TB3> INFO: 41600 events read in total (2921ms).
[20:24:13.943] <TB3> INFO: Test took 3784ms.
[20:24:13.952] <TB3> INFO: ---> Vcal thr map (pixel with maximum Vcal thr)
[20:24:13.952] <TB3> INFO: ---> dac: vcal name: TrimThr1 ntrig: 8 dacrange: 10 .. 160 (-1/-1) hits flags = 528 (plus default)
[20:24:13.961] <TB3> INFO: dacScan split into 1 runs with ntrig = 8
[20:24:13.961] <TB3> INFO: run 1 of 1
[20:24:14.239] <TB3> INFO: Expecting 5025280 events.
[20:24:41.744] <TB3> INFO: 685816 events read in total (26914ms).
[20:25:09.079] <TB3> INFO: 1367928 events read in total (54249ms).
[20:25:36.300] <TB3> INFO: 2049480 events read in total (81470ms).
[20:26:03.203] <TB3> INFO: 2728176 events read in total (108373ms).
[20:26:29.894] <TB3> INFO: 3403800 events read in total (135064ms).
[20:26:56.338] <TB3> INFO: 4078088 events read in total (161508ms).
[20:27:22.745] <TB3> INFO: 4736616 events read in total (187915ms).
[20:27:34.694] <TB3> INFO: 5025280 events read in total (199864ms).
[20:27:34.755] <TB3> INFO: Test took 200794ms.
[20:28:00.691] <TB3> INFO: roc 0 with ID = 0 has maximal Vcal 107.444 for pixel 12/79 mean/min/max = 91.3078/74.8258/107.79
[20:28:00.691] <TB3> INFO: roc 1 with ID = 1 has maximal Vcal 108.625 for pixel 8/9 mean/min/max = 91.4495/74.2446/108.654
[20:28:00.692] <TB3> INFO: roc 2 with ID = 2 has maximal Vcal 113.448 for pixel 1/48 mean/min/max = 95.3413/77.0153/113.667
[20:28:00.692] <TB3> INFO: roc 3 with ID = 3 has maximal Vcal 109.625 for pixel 1/7 mean/min/max = 91.7005/73.7572/109.644
[20:28:00.692] <TB3> INFO: roc 4 with ID = 4 has maximal Vcal 110.608 for pixel 11/5 mean/min/max = 94.123/77.6317/110.614
[20:28:00.693] <TB3> INFO: roc 5 with ID = 5 has maximal Vcal 107.684 for pixel 30/1 mean/min/max = 91.0875/74.4523/107.723
[20:28:00.693] <TB3> INFO: roc 6 with ID = 6 has maximal Vcal 111.42 for pixel 1/3 mean/min/max = 93.0767/74.6663/111.487
[20:28:00.694] <TB3> INFO: roc 7 with ID = 7 has maximal Vcal 110.925 for pixel 0/9 mean/min/max = 93.5894/76.1641/111.015
[20:28:00.694] <TB3> INFO: roc 8 with ID = 8 has maximal Vcal 111.855 for pixel 20/0 mean/min/max = 94.3052/76.3196/112.291
[20:28:00.695] <TB3> INFO: roc 9 with ID = 9 has maximal Vcal 110.159 for pixel 4/79 mean/min/max = 93.8048/77.3937/110.216
[20:28:00.695] <TB3> INFO: roc 10 with ID = 10 has maximal Vcal 109.217 for pixel 51/77 mean/min/max = 92.7154/76.0587/109.372
[20:28:00.696] <TB3> INFO: roc 11 with ID = 11 has maximal Vcal 105.94 for pixel 0/6 mean/min/max = 90.274/74.5401/106.008
[20:28:00.696] <TB3> INFO: roc 12 with ID = 12 has maximal Vcal 108.661 for pixel 0/28 mean/min/max = 92.551/76.1302/108.972
[20:28:00.696] <TB3> INFO: roc 13 with ID = 13 has maximal Vcal 106.151 for pixel 21/75 mean/min/max = 90.6928/74.9231/106.462
[20:28:00.697] <TB3> INFO: roc 14 with ID = 14 has maximal Vcal 108.424 for pixel 0/23 mean/min/max = 91.9112/75.3861/108.436
[20:28:00.697] <TB3> INFO: roc 15 with ID = 15 has maximal Vcal 108.695 for pixel 9/8 mean/min/max = 92.2468/75.5625/108.931
[20:28:00.698] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[20:28:00.786] <TB3> INFO: Expecting 411648 events.
[20:28:10.112] <TB3> INFO: 411648 events read in total (8734ms).
[20:28:10.119] <TB3> INFO: Expecting 411648 events.
[20:28:19.269] <TB3> INFO: 411648 events read in total (8747ms).
[20:28:19.282] <TB3> INFO: Expecting 411648 events.
[20:28:28.335] <TB3> INFO: 411648 events read in total (8650ms).
[20:28:28.348] <TB3> INFO: Expecting 411648 events.
[20:28:37.406] <TB3> INFO: 411648 events read in total (8655ms).
[20:28:37.420] <TB3> INFO: Expecting 411648 events.
[20:28:46.522] <TB3> INFO: 411648 events read in total (8699ms).
[20:28:46.538] <TB3> INFO: Expecting 411648 events.
[20:28:55.619] <TB3> INFO: 411648 events read in total (8678ms).
[20:28:55.640] <TB3> INFO: Expecting 411648 events.
[20:29:04.660] <TB3> INFO: 411648 events read in total (8617ms).
[20:29:04.681] <TB3> INFO: Expecting 411648 events.
[20:29:13.674] <TB3> INFO: 411648 events read in total (8590ms).
[20:29:13.698] <TB3> INFO: Expecting 411648 events.
[20:29:22.883] <TB3> INFO: 411648 events read in total (8782ms).
[20:29:22.921] <TB3> INFO: Expecting 411648 events.
[20:29:31.965] <TB3> INFO: 411648 events read in total (8641ms).
[20:29:31.996] <TB3> INFO: Expecting 411648 events.
[20:29:41.109] <TB3> INFO: 411648 events read in total (8710ms).
[20:29:41.143] <TB3> INFO: Expecting 411648 events.
[20:29:50.227] <TB3> INFO: 411648 events read in total (8681ms).
[20:29:50.277] <TB3> INFO: Expecting 411648 events.
[20:29:59.368] <TB3> INFO: 411648 events read in total (8688ms).
[20:29:59.406] <TB3> INFO: Expecting 411648 events.
[20:30:08.566] <TB3> INFO: 411648 events read in total (8757ms).
[20:30:08.608] <TB3> INFO: Expecting 411648 events.
[20:30:17.727] <TB3> INFO: 411648 events read in total (8716ms).
[20:30:17.787] <TB3> INFO: Expecting 411648 events.
[20:30:26.878] <TB3> INFO: 411648 events read in total (8688ms).
[20:30:26.924] <TB3> INFO: Test took 146226ms.
[20:30:28.522] <TB3> INFO: ---> dac: vcal name: TrimThr2 ntrig: 8 dacrange: 0 .. 150 (-1/-1) hits flags = 528 (plus default)
[20:30:28.532] <TB3> INFO: dacScan split into 1 runs with ntrig = 8
[20:30:28.532] <TB3> INFO: run 1 of 1
[20:30:28.793] <TB3> INFO: Expecting 5025280 events.
[20:30:56.272] <TB3> INFO: 665200 events read in total (26887ms).
[20:31:22.786] <TB3> INFO: 1328536 events read in total (53401ms).
[20:31:49.489] <TB3> INFO: 1991328 events read in total (80104ms).
[20:32:15.906] <TB3> INFO: 2650976 events read in total (106521ms).
[20:32:42.590] <TB3> INFO: 3306744 events read in total (133205ms).
[20:33:08.836] <TB3> INFO: 3960072 events read in total (159451ms).
[20:33:35.135] <TB3> INFO: 4601168 events read in total (185750ms).
[20:33:52.977] <TB3> INFO: 5025280 events read in total (203592ms).
[20:33:53.028] <TB3> INFO: Test took 204495ms.
[20:34:17.657] <TB3> INFO: ---> TrimStepCorr4 extremal thresholds: 47.749690 .. 103.184051
[20:34:17.894] <TB3> INFO: Expecting 208000 events.
[20:34:27.786] <TB3> INFO: 208000 events read in total (9298ms).
[20:34:27.786] <TB3> INFO: Test took 10128ms.
[20:34:27.833] <TB3> INFO: ---> dac: vcal name: trimStepCorr4 ntrig: 8 dacrange: 37 .. 113 (-1/-1) hits flags = 528 (plus default)
[20:34:27.842] <TB3> INFO: dacScan split into 1 runs with ntrig = 8
[20:34:27.842] <TB3> INFO: run 1 of 1
[20:34:28.120] <TB3> INFO: Expecting 2562560 events.
[20:34:56.036] <TB3> INFO: 693976 events read in total (27324ms).
[20:35:22.897] <TB3> INFO: 1385832 events read in total (54185ms).
[20:35:50.466] <TB3> INFO: 2071192 events read in total (81754ms).
[20:36:10.287] <TB3> INFO: 2562560 events read in total (101575ms).
[20:36:10.323] <TB3> INFO: Test took 102481ms.
[20:36:29.368] <TB3> INFO: ---> TrimStepCorr2 extremal thresholds: 58.917513 .. 90.378144
[20:36:29.601] <TB3> INFO: Expecting 208000 events.
[20:36:39.248] <TB3> INFO: 208000 events read in total (9055ms).
[20:36:39.248] <TB3> INFO: Test took 9878ms.
[20:36:39.294] <TB3> INFO: ---> dac: vcal name: trimStepCorr2 ntrig: 8 dacrange: 48 .. 100 (-1/-1) hits flags = 528 (plus default)
[20:36:39.303] <TB3> INFO: dacScan split into 1 runs with ntrig = 8
[20:36:39.303] <TB3> INFO: run 1 of 1
[20:36:39.581] <TB3> INFO: Expecting 1763840 events.
[20:37:08.243] <TB3> INFO: 708816 events read in total (28070ms).
[20:37:36.360] <TB3> INFO: 1416912 events read in total (56188ms).
[20:37:50.518] <TB3> INFO: 1763840 events read in total (70345ms).
[20:37:50.546] <TB3> INFO: Test took 71244ms.
[20:38:09.644] <TB3> INFO: ---> TrimStepCorr1a extremal thresholds: 64.383428 .. 89.986870
[20:38:09.878] <TB3> INFO: Expecting 208000 events.
[20:38:19.702] <TB3> INFO: 208000 events read in total (9232ms).
[20:38:19.703] <TB3> INFO: Test took 10058ms.
[20:38:19.748] <TB3> INFO: ---> dac: vcal name: trimStepCorr1a ntrig: 8 dacrange: 54 .. 99 (-1/-1) hits flags = 528 (plus default)
[20:38:19.757] <TB3> INFO: dacScan split into 1 runs with ntrig = 8
[20:38:19.757] <TB3> INFO: run 1 of 1
[20:38:20.035] <TB3> INFO: Expecting 1530880 events.
[20:38:48.707] <TB3> INFO: 695240 events read in total (28081ms).
[20:39:17.197] <TB3> INFO: 1387640 events read in total (56571ms).
[20:39:23.308] <TB3> INFO: 1530880 events read in total (62682ms).
[20:39:23.331] <TB3> INFO: Test took 63574ms.
[20:39:41.311] <TB3> INFO: ---> TrimStepCorr1b extremal thresholds: 67.199841 .. 89.449373
[20:39:41.545] <TB3> INFO: Expecting 208000 events.
[20:39:51.113] <TB3> INFO: 208000 events read in total (8976ms).
[20:39:51.114] <TB3> INFO: Test took 9802ms.
[20:39:51.163] <TB3> INFO: ---> dac: vcal name: trimStepCorr1b ntrig: 8 dacrange: 57 .. 99 (-1/-1) hits flags = 528 (plus default)
[20:39:51.171] <TB3> INFO: dacScan split into 1 runs with ntrig = 8
[20:39:51.171] <TB3> INFO: run 1 of 1
[20:39:51.449] <TB3> INFO: Expecting 1431040 events.
[20:40:20.681] <TB3> INFO: 686248 events read in total (28640ms).
[20:40:48.061] <TB3> INFO: 1368080 events read in total (56020ms).
[20:40:50.980] <TB3> INFO: 1431040 events read in total (58939ms).
[20:40:50.002] <TB3> INFO: Test took 59832ms.
[20:41:08.285] <TB3> INFO: ---> TrimThrFinal extremal thresholds: 60 .. 100
[20:41:08.285] <TB3> INFO: ---> dac: vcal name: TrimThrFinal ntrig: 8 dacrange: 60 .. 100 (-1/-1) hits flags = 528 (plus default)
[20:41:08.293] <TB3> INFO: dacScan split into 1 runs with ntrig = 8
[20:41:08.293] <TB3> INFO: run 1 of 1
[20:41:08.531] <TB3> INFO: Expecting 1364480 events.
[20:41:36.810] <TB3> INFO: 668336 events read in total (27687ms).
[20:42:04.284] <TB3> INFO: 1332224 events read in total (55161ms).
[20:42:06.093] <TB3> INFO: 1364480 events read in total (56970ms).
[20:42:06.113] <TB3> INFO: Test took 57818ms.
[20:42:24.626] <TB3> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1080_FullQualification_2016-10-25_15h54m_1477403681//003_Fulltest_p17//dacParameters80_C0.dat
[20:42:24.626] <TB3> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1080_FullQualification_2016-10-25_15h54m_1477403681//003_Fulltest_p17//dacParameters80_C1.dat
[20:42:24.626] <TB3> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1080_FullQualification_2016-10-25_15h54m_1477403681//003_Fulltest_p17//dacParameters80_C2.dat
[20:42:24.626] <TB3> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1080_FullQualification_2016-10-25_15h54m_1477403681//003_Fulltest_p17//dacParameters80_C3.dat
[20:42:24.626] <TB3> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1080_FullQualification_2016-10-25_15h54m_1477403681//003_Fulltest_p17//dacParameters80_C4.dat
[20:42:24.626] <TB3> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1080_FullQualification_2016-10-25_15h54m_1477403681//003_Fulltest_p17//dacParameters80_C5.dat
[20:42:24.626] <TB3> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1080_FullQualification_2016-10-25_15h54m_1477403681//003_Fulltest_p17//dacParameters80_C6.dat
[20:42:24.626] <TB3> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1080_FullQualification_2016-10-25_15h54m_1477403681//003_Fulltest_p17//dacParameters80_C7.dat
[20:42:24.626] <TB3> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1080_FullQualification_2016-10-25_15h54m_1477403681//003_Fulltest_p17//dacParameters80_C8.dat
[20:42:24.626] <TB3> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1080_FullQualification_2016-10-25_15h54m_1477403681//003_Fulltest_p17//dacParameters80_C9.dat
[20:42:24.626] <TB3> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1080_FullQualification_2016-10-25_15h54m_1477403681//003_Fulltest_p17//dacParameters80_C10.dat
[20:42:24.627] <TB3> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1080_FullQualification_2016-10-25_15h54m_1477403681//003_Fulltest_p17//dacParameters80_C11.dat
[20:42:24.627] <TB3> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1080_FullQualification_2016-10-25_15h54m_1477403681//003_Fulltest_p17//dacParameters80_C12.dat
[20:42:24.627] <TB3> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1080_FullQualification_2016-10-25_15h54m_1477403681//003_Fulltest_p17//dacParameters80_C13.dat
[20:42:24.627] <TB3> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1080_FullQualification_2016-10-25_15h54m_1477403681//003_Fulltest_p17//dacParameters80_C14.dat
[20:42:24.627] <TB3> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1080_FullQualification_2016-10-25_15h54m_1477403681//003_Fulltest_p17//dacParameters80_C15.dat
[20:42:24.627] <TB3> INFO: write trim parameters into /home/l_tester/david/FullQualification/data/M1080_FullQualification_2016-10-25_15h54m_1477403681//003_Fulltest_p17//trimParameters80_C0.dat
[20:42:24.634] <TB3> INFO: write trim parameters into /home/l_tester/david/FullQualification/data/M1080_FullQualification_2016-10-25_15h54m_1477403681//003_Fulltest_p17//trimParameters80_C1.dat
[20:42:24.640] <TB3> INFO: write trim parameters into /home/l_tester/david/FullQualification/data/M1080_FullQualification_2016-10-25_15h54m_1477403681//003_Fulltest_p17//trimParameters80_C2.dat
[20:42:24.646] <TB3> INFO: write trim parameters into /home/l_tester/david/FullQualification/data/M1080_FullQualification_2016-10-25_15h54m_1477403681//003_Fulltest_p17//trimParameters80_C3.dat
[20:42:24.652] <TB3> INFO: write trim parameters into /home/l_tester/david/FullQualification/data/M1080_FullQualification_2016-10-25_15h54m_1477403681//003_Fulltest_p17//trimParameters80_C4.dat
[20:42:24.657] <TB3> INFO: write trim parameters into /home/l_tester/david/FullQualification/data/M1080_FullQualification_2016-10-25_15h54m_1477403681//003_Fulltest_p17//trimParameters80_C5.dat
[20:42:24.663] <TB3> INFO: write trim parameters into /home/l_tester/david/FullQualification/data/M1080_FullQualification_2016-10-25_15h54m_1477403681//003_Fulltest_p17//trimParameters80_C6.dat
[20:42:24.668] <TB3> INFO: write trim parameters into /home/l_tester/david/FullQualification/data/M1080_FullQualification_2016-10-25_15h54m_1477403681//003_Fulltest_p17//trimParameters80_C7.dat
[20:42:24.674] <TB3> INFO: write trim parameters into /home/l_tester/david/FullQualification/data/M1080_FullQualification_2016-10-25_15h54m_1477403681//003_Fulltest_p17//trimParameters80_C8.dat
[20:42:24.679] <TB3> INFO: write trim parameters into /home/l_tester/david/FullQualification/data/M1080_FullQualification_2016-10-25_15h54m_1477403681//003_Fulltest_p17//trimParameters80_C9.dat
[20:42:24.685] <TB3> INFO: write trim parameters into /home/l_tester/david/FullQualification/data/M1080_FullQualification_2016-10-25_15h54m_1477403681//003_Fulltest_p17//trimParameters80_C10.dat
[20:42:24.690] <TB3> INFO: write trim parameters into /home/l_tester/david/FullQualification/data/M1080_FullQualification_2016-10-25_15h54m_1477403681//003_Fulltest_p17//trimParameters80_C11.dat
[20:42:24.697] <TB3> INFO: write trim parameters into /home/l_tester/david/FullQualification/data/M1080_FullQualification_2016-10-25_15h54m_1477403681//003_Fulltest_p17//trimParameters80_C12.dat
[20:42:24.704] <TB3> INFO: write trim parameters into /home/l_tester/david/FullQualification/data/M1080_FullQualification_2016-10-25_15h54m_1477403681//003_Fulltest_p17//trimParameters80_C13.dat
[20:42:24.711] <TB3> INFO: write trim parameters into /home/l_tester/david/FullQualification/data/M1080_FullQualification_2016-10-25_15h54m_1477403681//003_Fulltest_p17//trimParameters80_C14.dat
[20:42:24.718] <TB3> INFO: write trim parameters into /home/l_tester/david/FullQualification/data/M1080_FullQualification_2016-10-25_15h54m_1477403681//003_Fulltest_p17//trimParameters80_C15.dat
[20:42:24.726] <TB3> INFO: PixTestTrim80::trimTest() done
[20:42:24.726] <TB3> INFO: vtrim: 115 110 125 114 100 115 123 109 121 104 85 96 99 100 108 116
[20:42:24.726] <TB3> INFO: vthrcomp: 81 85 79 82 79 87 84 72 73 74 72 71 72 71 82 81
[20:42:24.726] <TB3> INFO: vcal mean: 80.00 80.02 79.95 79.99 80.01 79.99 79.96 79.99 79.93 79.99 79.96 79.95 79.97 79.99 79.94 79.95
[20:42:24.726] <TB3> INFO: vcal RMS: 0.80 0.92 0.80 0.83 0.77 0.80 0.81 0.72 0.77 0.70 0.80 0.71 0.75 0.71 0.79 0.80
[20:42:24.726] <TB3> INFO: bits mean: 10.68 10.49 9.88 10.81 9.68 10.78 10.42 9.74 10.00 9.08 9.81 10.20 10.00 10.00 10.14 10.33
[20:42:24.726] <TB3> INFO: bits RMS: 2.12 2.29 2.07 2.12 2.13 2.13 2.16 2.27 2.12 2.39 2.30 2.32 2.19 2.42 2.30 2.15
[20:42:24.733] <TB3> INFO: ----------------------------------------------------------------------
[20:42:24.733] <TB3> INFO: PixTestTrim80::trimBitTest() ntrig = 5, vtrims = 254 126 63 32
[20:42:24.733] <TB3> INFO: ----------------------------------------------------------------------
[20:42:24.735] <TB3> INFO: ---> dac: Vcal name: TrimBitsThr0 ntrig: 5 dacrange: 0 .. 199 (-1/-1) hits flags = 528 (plus default)
[20:42:24.745] <TB3> INFO: dacScan split into 1 runs with ntrig = 5
[20:42:24.745] <TB3> INFO: run 1 of 1
[20:42:24.982] <TB3> INFO: Expecting 4160000 events.
[20:42:56.977] <TB3> INFO: 763780 events read in total (31403ms).
[20:43:28.392] <TB3> INFO: 1519505 events read in total (62818ms).
[20:43:59.276] <TB3> INFO: 2270340 events read in total (93702ms).
[20:44:30.516] <TB3> INFO: 3017955 events read in total (124942ms).
[20:45:02.786] <TB3> INFO: 3763725 events read in total (157212ms).
[20:45:19.342] <TB3> INFO: 4160000 events read in total (173768ms).
[20:45:19.397] <TB3> INFO: Test took 174651ms.
[20:45:43.523] <TB3> INFO: ---> dac: Vcal name: TrimThr_trim14 ntrig: 5 dacrange: 0 .. 209 (-1/-1) hits flags = 528 (plus default)
[20:45:43.534] <TB3> INFO: dacScan split into 1 runs with ntrig = 5
[20:45:43.534] <TB3> INFO: run 1 of 1
[20:45:43.810] <TB3> INFO: Expecting 4368000 events.
[20:46:14.882] <TB3> INFO: 726600 events read in total (30480ms).
[20:46:45.107] <TB3> INFO: 1447505 events read in total (60705ms).
[20:47:15.390] <TB3> INFO: 2164840 events read in total (90988ms).
[20:47:45.514] <TB3> INFO: 2878570 events read in total (121112ms).
[20:48:15.865] <TB3> INFO: 3590555 events read in total (151463ms).
[20:48:46.331] <TB3> INFO: 4303175 events read in total (181929ms).
[20:48:49.382] <TB3> INFO: 4368000 events read in total (184980ms).
[20:48:49.443] <TB3> INFO: Test took 185908ms.
[20:49:22.078] <TB3> INFO: ---> dac: Vcal name: TrimThr_trim13 ntrig: 5 dacrange: 0 .. 206 (-1/-1) hits flags = 528 (plus default)
[20:49:22.086] <TB3> INFO: dacScan split into 1 runs with ntrig = 5
[20:49:22.086] <TB3> INFO: run 1 of 1
[20:49:22.319] <TB3> INFO: Expecting 4305600 events.
[20:49:53.443] <TB3> INFO: 730850 events read in total (30532ms).
[20:50:24.013] <TB3> INFO: 1455780 events read in total (61102ms).
[20:50:54.397] <TB3> INFO: 2177110 events read in total (91486ms).
[20:51:24.803] <TB3> INFO: 2894870 events read in total (121892ms).
[20:51:55.184] <TB3> INFO: 3610360 events read in total (152273ms).
[20:52:24.748] <TB3> INFO: 4305600 events read in total (181837ms).
[20:52:24.804] <TB3> INFO: Test took 182718ms.
[20:52:54.998] <TB3> INFO: ---> dac: Vcal name: TrimThr_trim11 ntrig: 5 dacrange: 0 .. 207 (-1/-1) hits flags = 528 (plus default)
[20:52:55.008] <TB3> INFO: dacScan split into 1 runs with ntrig = 5
[20:52:55.008] <TB3> INFO: run 1 of 1
[20:52:55.245] <TB3> INFO: Expecting 4326400 events.
[20:53:26.766] <TB3> INFO: 729925 events read in total (30929ms).
[20:53:57.323] <TB3> INFO: 1453710 events read in total (61486ms).
[20:54:27.873] <TB3> INFO: 2174395 events read in total (92036ms).
[20:54:58.621] <TB3> INFO: 2891330 events read in total (122784ms).
[20:55:29.351] <TB3> INFO: 3606430 events read in total (153514ms).
[20:55:59.996] <TB3> INFO: 4323580 events read in total (184159ms).
[20:56:00.534] <TB3> INFO: 4326400 events read in total (184697ms).
[20:56:00.585] <TB3> INFO: Test took 185577ms.
[20:56:30.695] <TB3> INFO: ---> dac: Vcal name: TrimThr_trim7 ntrig: 5 dacrange: 0 .. 207 (-1/-1) hits flags = 528 (plus default)
[20:56:30.707] <TB3> INFO: dacScan split into 1 runs with ntrig = 5
[20:56:30.707] <TB3> INFO: run 1 of 1
[20:56:30.969] <TB3> INFO: Expecting 4326400 events.
[20:57:02.514] <TB3> INFO: 730220 events read in total (30954ms).
[20:57:33.115] <TB3> INFO: 1454190 events read in total (61555ms).
[20:58:03.621] <TB3> INFO: 2174875 events read in total (92061ms).
[20:58:34.361] <TB3> INFO: 2891940 events read in total (122801ms).
[20:59:05.492] <TB3> INFO: 3607120 events read in total (153932ms).
[20:59:36.086] <TB3> INFO: 4324545 events read in total (184526ms).
[20:59:36.587] <TB3> INFO: 4326400 events read in total (185027ms).
[20:59:36.639] <TB3> INFO: Test took 185932ms.
[21:00:06.021] <TB3> INFO: PixTestTrim80::trimBitTest() done
[21:00:06.023] <TB3> INFO: PixTestTrim80::doTest() done, duration: 2379 seconds
[21:00:06.818] <TB3> INFO: enter test to run
[21:00:06.818] <TB3> INFO: test: exit no parameter change
[21:00:06.936] <TB3> QUIET: Connection to board 170 closed.
[21:00:06.937] <TB3> INFO: pXar: this is the end, my friend