[20:17:00.109] <TB3> INFO: *** Welcome to pxar ***
[20:17:00.109] <TB3> INFO: *** Today: 2016/10/16
[20:17:00.118] <TB3> INFO: *** Version: c8ba-dirty
[20:17:00.118] <TB3> INFO: readRocDacs: /home/l_tester/david/FullQualification/data/M1008_FullQualification_2016-10-16_17h47m_1476632865//003_Fulltest_p17//dacParameters_C0.dat .. /home/l_tester/david/FullQualification/data/M1008_FullQualification_2016-10-16_17h47m_1476632865//003_Fulltest_p17//dacParameters_C15.dat
[20:17:00.118] <TB3> INFO: readTbmDacs: /home/l_tester/david/FullQualification/data/M1008_FullQualification_2016-10-16_17h47m_1476632865//003_Fulltest_p17//tbmParameters_C0a.dat .. /home/l_tester/david/FullQualification/data/M1008_FullQualification_2016-10-16_17h47m_1476632865//003_Fulltest_p17//tbmParameters_C1b.dat
[20:17:00.119] <TB3> INFO: readMaskFile: /home/l_tester/david/FullQualification/data/M1008_FullQualification_2016-10-16_17h47m_1476632865//003_Fulltest_p17//defaultMaskFile.dat
[20:17:00.119] <TB3> INFO: readTrimFile: /home/l_tester/david/FullQualification/data/M1008_FullQualification_2016-10-16_17h47m_1476632865//003_Fulltest_p17//trimParameters_C0.dat .. /home/l_tester/david/FullQualification/data/M1008_FullQualification_2016-10-16_17h47m_1476632865//003_Fulltest_p17//trimParameters_C15.dat
[20:17:00.185] <TB3> INFO: clk: 4
[20:17:00.185] <TB3> INFO: ctr: 4
[20:17:00.185] <TB3> INFO: sda: 19
[20:17:00.185] <TB3> INFO: tin: 9
[20:17:00.185] <TB3> INFO: level: 15
[20:17:00.185] <TB3> INFO: triggerdelay: 0
[20:17:00.185] <TB3> QUIET: Instanciating API for pxar v2.7.6+55~gafdbfd9
[20:17:00.185] <TB3> INFO: Log level: INFO
[20:17:00.194] <TB3> INFO: Found DTB DTB_WWVASW
[20:17:00.203] <TB3> QUIET: Connection to board DTB_WWVASW opened.
[20:17:00.205] <TB3> INFO: DTB startup information
--- DTB info------------------------------------------
Board id: 126
HW version: DTB1.2
FW version: 4.6
SW version: 4.7
Options:
USB id: DTB_WWVASW
MAC address: 40D85511807E
Hostname: pixelDTB126
Comment:
------------------------------------------------------
[20:17:00.207] <TB3> INFO: RPC call hashes of host and DTB match: 486171790
[20:17:01.694] <TB3> INFO: DUT info:
[20:17:01.694] <TB3> INFO: The DUT currently contains the following objects:
[20:17:01.694] <TB3> INFO: 4 TBM Cores tbm10c (4 ON)
[20:17:01.694] <TB3> INFO: TBM Core alpha (0): 7 registers set
[20:17:01.694] <TB3> INFO: TBM Core beta (1): 7 registers set
[20:17:01.694] <TB3> INFO: TBM Core alpha (2): 7 registers set
[20:17:01.694] <TB3> INFO: TBM Core beta (3): 7 registers set
[20:17:01.694] <TB3> INFO: 16 ROCs proc600 (16 ON) with 4160 pixelConfigs
[20:17:01.694] <TB3> INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[20:17:01.694] <TB3> INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[20:17:01.694] <TB3> INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[20:17:01.694] <TB3> INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[20:17:01.694] <TB3> INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[20:17:01.694] <TB3> INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[20:17:01.694] <TB3> INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[20:17:01.694] <TB3> INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[20:17:01.694] <TB3> INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[20:17:01.694] <TB3> INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[20:17:01.694] <TB3> INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[20:17:01.694] <TB3> INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[20:17:01.694] <TB3> INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[20:17:01.694] <TB3> INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[20:17:01.694] <TB3> INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[20:17:01.694] <TB3> INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[20:17:02.095] <TB3> INFO: enter 'restricted' command line mode
[20:17:02.095] <TB3> INFO: enter test to run
[20:17:02.095] <TB3> INFO: test: pretest no parameter change
[20:17:02.095] <TB3> INFO: running: pretest
[20:17:02.100] <TB3> INFO: ######################################################################
[20:17:02.100] <TB3> INFO: PixTestPretest::doTest()
[20:17:02.100] <TB3> INFO: ######################################################################
[20:17:02.101] <TB3> INFO: ----------------------------------------------------------------------
[20:17:02.101] <TB3> INFO: PixTestPretest::programROC()
[20:17:02.101] <TB3> INFO: ----------------------------------------------------------------------
[20:17:20.115] <TB3> INFO: PixTestPretest::programROC() done: ROCs are all programmable
[20:17:20.115] <TB3> INFO: IA differences per ROC: 18.5 20.1 19.3 18.5 20.1 17.7 17.7 17.7 18.5 18.5 19.3 20.9 20.9 20.1 20.1 17.7
[20:17:20.183] <TB3> INFO: ----------------------------------------------------------------------
[20:17:20.183] <TB3> INFO: PixTestPretest::setVana() target Ia = 24 mA/ROC
[20:17:20.183] <TB3> INFO: ----------------------------------------------------------------------
[20:17:41.474] <TB3> INFO: PixTestPretest::setVana() done, Module Ia 373 mA = 23.3125 mA/ROC
[20:17:41.475] <TB3> INFO: i(loss) [mA/ROC]: 17.7 17.7 18.5 17.7 17.7 17.7 16.9 17.7 17.7 18.5 18.5 17.7 20.1 19.3 17.7 18.5
[20:17:41.508] <TB3> INFO: ----------------------------------------------------------------------
[20:17:41.508] <TB3> INFO: PixTestPretest::findTiming()
[20:17:41.508] <TB3> INFO: ----------------------------------------------------------------------
[20:17:41.508] <TB3> INFO: PixTestCmd::init()

[20:18:13.530] <TB3> INFO: TBM phases: 160MHz: 7, 400MHz: 7, TBM delays: ROC(0/1):5, header/trailer: 1, token: 1
[20:18:13.530] <TB3> INFO: (success/tries = 100/100), width = 4
[20:18:15.046] <TB3> INFO: ----------------------------------------------------------------------
[20:18:15.046] <TB3> INFO: PixTestPretest::findWorkingPixel()
[20:18:15.046] <TB3> INFO: ----------------------------------------------------------------------
[20:18:15.140] <TB3> INFO: Expecting 231680 events.
[20:18:25.168] <TB3> INFO: 231680 events read in total (9436ms).
[20:18:25.177] <TB3> INFO: Test took 10127ms.
[20:18:25.428] <TB3> INFO: Found working pixel in all ROCs: col/row = 12/22
[20:18:25.465] <TB3> INFO: ----------------------------------------------------------------------
[20:18:25.465] <TB3> INFO: PixTestPretest::setVthrCompCalDel()
[20:18:25.465] <TB3> INFO: ----------------------------------------------------------------------
[20:18:25.560] <TB3> INFO: Expecting 231680 events.
[20:18:35.649] <TB3> INFO: 231680 events read in total (9497ms).
[20:18:35.658] <TB3> INFO: Test took 10188ms.
[20:18:35.928] <TB3> INFO: PixTestPretest::setVthrCompCalDel() done
[20:18:35.928] <TB3> INFO: CalDel: 92 87 71 82 87 96 77 81 98 99 78 85 79 91 79 71
[20:18:35.928] <TB3> INFO: VthrComp: 51 52 51 51 51 51 51 51 51 51 51 51 51 51 51 51
[20:18:35.931] <TB3> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1008_FullQualification_2016-10-16_17h47m_1476632865//003_Fulltest_p17//dacParameters_C0.dat
[20:18:35.932] <TB3> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1008_FullQualification_2016-10-16_17h47m_1476632865//003_Fulltest_p17//dacParameters_C1.dat
[20:18:35.932] <TB3> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1008_FullQualification_2016-10-16_17h47m_1476632865//003_Fulltest_p17//dacParameters_C2.dat
[20:18:35.932] <TB3> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1008_FullQualification_2016-10-16_17h47m_1476632865//003_Fulltest_p17//dacParameters_C3.dat
[20:18:35.932] <TB3> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1008_FullQualification_2016-10-16_17h47m_1476632865//003_Fulltest_p17//dacParameters_C4.dat
[20:18:35.932] <TB3> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1008_FullQualification_2016-10-16_17h47m_1476632865//003_Fulltest_p17//dacParameters_C5.dat
[20:18:35.932] <TB3> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1008_FullQualification_2016-10-16_17h47m_1476632865//003_Fulltest_p17//dacParameters_C6.dat
[20:18:35.932] <TB3> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1008_FullQualification_2016-10-16_17h47m_1476632865//003_Fulltest_p17//dacParameters_C7.dat
[20:18:35.933] <TB3> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1008_FullQualification_2016-10-16_17h47m_1476632865//003_Fulltest_p17//dacParameters_C8.dat
[20:18:35.933] <TB3> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1008_FullQualification_2016-10-16_17h47m_1476632865//003_Fulltest_p17//dacParameters_C9.dat
[20:18:35.933] <TB3> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1008_FullQualification_2016-10-16_17h47m_1476632865//003_Fulltest_p17//dacParameters_C10.dat
[20:18:35.933] <TB3> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1008_FullQualification_2016-10-16_17h47m_1476632865//003_Fulltest_p17//dacParameters_C11.dat
[20:18:35.933] <TB3> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1008_FullQualification_2016-10-16_17h47m_1476632865//003_Fulltest_p17//dacParameters_C12.dat
[20:18:35.933] <TB3> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1008_FullQualification_2016-10-16_17h47m_1476632865//003_Fulltest_p17//dacParameters_C13.dat
[20:18:35.933] <TB3> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1008_FullQualification_2016-10-16_17h47m_1476632865//003_Fulltest_p17//dacParameters_C14.dat
[20:18:35.934] <TB3> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1008_FullQualification_2016-10-16_17h47m_1476632865//003_Fulltest_p17//dacParameters_C15.dat
[20:18:35.934] <TB3> INFO: write tbm parameters into /home/l_tester/david/FullQualification/data/M1008_FullQualification_2016-10-16_17h47m_1476632865//003_Fulltest_p17//tbmParameters_C0a.dat
[20:18:35.934] <TB3> INFO: write tbm parameters into /home/l_tester/david/FullQualification/data/M1008_FullQualification_2016-10-16_17h47m_1476632865//003_Fulltest_p17//tbmParameters_C0b.dat
[20:18:35.934] <TB3> INFO: write tbm parameters into /home/l_tester/david/FullQualification/data/M1008_FullQualification_2016-10-16_17h47m_1476632865//003_Fulltest_p17//tbmParameters_C1a.dat
[20:18:35.934] <TB3> INFO: write tbm parameters into /home/l_tester/david/FullQualification/data/M1008_FullQualification_2016-10-16_17h47m_1476632865//003_Fulltest_p17//tbmParameters_C1b.dat
[20:18:35.934] <TB3> INFO: PixTestPretest::doTest() done, duration: 93 seconds
[20:18:35.989] <TB3> INFO: enter test to run
[20:18:35.989] <TB3> INFO: test: FullTest no parameter change
[20:18:35.989] <TB3> INFO: running: fulltest
[20:18:35.989] <TB3> INFO: ######################################################################
[20:18:35.989] <TB3> INFO: PixTestFullTest::doTest()
[20:18:35.989] <TB3> INFO: ######################################################################
[20:18:35.990] <TB3> INFO: ######################################################################
[20:18:35.990] <TB3> INFO: PixTestAlive::doTest()
[20:18:35.990] <TB3> INFO: ######################################################################
[20:18:35.991] <TB3> INFO: ----------------------------------------------------------------------
[20:18:35.991] <TB3> INFO: PixTestAlive::aliveTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[20:18:35.991] <TB3> INFO: ----------------------------------------------------------------------
[20:18:36.230] <TB3> INFO: Expecting 41600 events.
[20:18:39.776] <TB3> INFO: 41600 events read in total (2954ms).
[20:18:39.777] <TB3> INFO: Test took 3784ms.
[20:18:40.008] <TB3> INFO: PixTestAlive::aliveTest() done
[20:18:40.008] <TB3> INFO: number of dead pixels (per ROC): 1 0 0 1 0 0 0 0 0 1 1 0 0 0 0 0
[20:18:40.010] <TB3> INFO: ----------------------------------------------------------------------
[20:18:40.010] <TB3> INFO: PixTestAlive::maskTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[20:18:40.010] <TB3> INFO: ----------------------------------------------------------------------
[20:18:40.253] <TB3> INFO: Expecting 41600 events.
[20:18:43.247] <TB3> INFO: 41600 events read in total (2402ms).
[20:18:43.247] <TB3> INFO: Test took 3235ms.
[20:18:43.248] <TB3> INFO: mask vs. old pixelAlive PixelAlive_C0_V0 .. PixelAlive_C15_V0
[20:18:43.489] <TB3> INFO: PixTestAlive::maskTest() done
[20:18:43.489] <TB3> INFO: number of mask-defect pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[20:18:43.490] <TB3> INFO: ----------------------------------------------------------------------
[20:18:43.490] <TB3> INFO: PixTestAlive::addressDecodingTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[20:18:43.490] <TB3> INFO: ----------------------------------------------------------------------
[20:18:43.776] <TB3> INFO: Expecting 41600 events.
[20:18:47.528] <TB3> INFO: 41600 events read in total (3160ms).
[20:18:47.528] <TB3> INFO: Test took 4036ms.
[20:18:47.758] <TB3> INFO: PixTestAlive::addressDecodingTest() done
[20:18:47.758] <TB3> INFO: number of address-decoding pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[20:18:47.758] <TB3> INFO: PixTestAlive::doTest() done, duration: 11 seconds
[20:18:47.758] <TB3> INFO: Decoding statistics:
[20:18:47.758] <TB3> INFO: General information:
[20:18:47.758] <TB3> INFO: 16bit words read: 0
[20:18:47.758] <TB3> INFO: valid events total: 0
[20:18:47.758] <TB3> INFO: empty events: 0
[20:18:47.758] <TB3> INFO: valid events with pixels: 0
[20:18:47.758] <TB3> INFO: valid pixel hits: 0
[20:18:47.758] <TB3> INFO: Event errors: 0
[20:18:47.758] <TB3> INFO: start marker: 0
[20:18:47.758] <TB3> INFO: stop marker: 0
[20:18:47.758] <TB3> INFO: overflow: 0
[20:18:47.758] <TB3> INFO: invalid 5bit words: 0
[20:18:47.758] <TB3> INFO: invalid XOR eye diagram: 0
[20:18:47.758] <TB3> INFO: frame (failed synchr.): 0
[20:18:47.758] <TB3> INFO: idle data (no TBM trl): 0
[20:18:47.758] <TB3> INFO: no data (only TBM hdr): 0
[20:18:47.758] <TB3> INFO: TBM errors: 0
[20:18:47.758] <TB3> INFO: flawed TBM headers: 0
[20:18:47.758] <TB3> INFO: flawed TBM trailers: 0
[20:18:47.758] <TB3> INFO: event ID mismatches: 0
[20:18:47.758] <TB3> INFO: ROC errors: 0
[20:18:47.758] <TB3> INFO: missing ROC header(s): 0
[20:18:47.758] <TB3> INFO: misplaced readback start: 0
[20:18:47.758] <TB3> INFO: Pixel decoding errors: 0
[20:18:47.758] <TB3> INFO: pixel data incomplete: 0
[20:18:47.758] <TB3> INFO: pixel address: 0
[20:18:47.758] <TB3> INFO: pulse height fill bit: 0
[20:18:47.758] <TB3> INFO: buffer corruption: 0
[20:18:47.764] <TB3> INFO: readReadbackCal: /home/l_tester/david/FullQualification/data/M1008_FullQualification_2016-10-16_17h47m_1476632865//003_Fulltest_p17//readbackCal_C0.dat .. /home/l_tester/david/FullQualification/data/M1008_FullQualification_2016-10-16_17h47m_1476632865//003_Fulltest_p17//readbackCal_C15.dat
[20:18:47.765] <TB3> INFO: readGainPedestalParameters /home/l_tester/david/FullQualification/data/M1008_FullQualification_2016-10-16_17h47m_1476632865//003_Fulltest_p17//phCalibrationFitErr_C0.dat .. /home/l_tester/david/FullQualification/data/M1008_FullQualification_2016-10-16_17h47m_1476632865//003_Fulltest_p17//phCalibrationFitErr_C15.dat

[20:18:47.765] <TB3> INFO: ######################################################################
[20:18:47.765] <TB3> INFO: PixTestReadback::doTest()
[20:18:47.765] <TB3> INFO: ######################################################################
[20:18:47.765] <TB3> INFO: ----------------------------------------------------------------------
[20:18:47.765] <TB3> INFO: PixTestReadback::CalibrateVd()
[20:18:47.765] <TB3> INFO: ----------------------------------------------------------------------
[20:18:57.709] <TB3> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1008_FullQualification_2016-10-16_17h47m_1476632865//003_Fulltest_p17//readbackCal_C0.dat
[20:18:57.709] <TB3> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1008_FullQualification_2016-10-16_17h47m_1476632865//003_Fulltest_p17//readbackCal_C1.dat
[20:18:57.709] <TB3> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1008_FullQualification_2016-10-16_17h47m_1476632865//003_Fulltest_p17//readbackCal_C2.dat
[20:18:57.709] <TB3> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1008_FullQualification_2016-10-16_17h47m_1476632865//003_Fulltest_p17//readbackCal_C3.dat
[20:18:57.709] <TB3> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1008_FullQualification_2016-10-16_17h47m_1476632865//003_Fulltest_p17//readbackCal_C4.dat
[20:18:57.709] <TB3> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1008_FullQualification_2016-10-16_17h47m_1476632865//003_Fulltest_p17//readbackCal_C5.dat
[20:18:57.709] <TB3> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1008_FullQualification_2016-10-16_17h47m_1476632865//003_Fulltest_p17//readbackCal_C6.dat
[20:18:57.709] <TB3> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1008_FullQualification_2016-10-16_17h47m_1476632865//003_Fulltest_p17//readbackCal_C7.dat
[20:18:57.709] <TB3> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1008_FullQualification_2016-10-16_17h47m_1476632865//003_Fulltest_p17//readbackCal_C8.dat
[20:18:57.709] <TB3> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1008_FullQualification_2016-10-16_17h47m_1476632865//003_Fulltest_p17//readbackCal_C9.dat
[20:18:57.710] <TB3> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1008_FullQualification_2016-10-16_17h47m_1476632865//003_Fulltest_p17//readbackCal_C10.dat
[20:18:57.710] <TB3> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1008_FullQualification_2016-10-16_17h47m_1476632865//003_Fulltest_p17//readbackCal_C11.dat
[20:18:57.710] <TB3> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1008_FullQualification_2016-10-16_17h47m_1476632865//003_Fulltest_p17//readbackCal_C12.dat
[20:18:57.710] <TB3> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1008_FullQualification_2016-10-16_17h47m_1476632865//003_Fulltest_p17//readbackCal_C13.dat
[20:18:57.710] <TB3> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1008_FullQualification_2016-10-16_17h47m_1476632865//003_Fulltest_p17//readbackCal_C14.dat
[20:18:57.710] <TB3> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1008_FullQualification_2016-10-16_17h47m_1476632865//003_Fulltest_p17//readbackCal_C15.dat
[20:18:57.739] <TB3> INFO: PixTestPattern:: pg_setup set to default.
[20:18:57.739] <TB3> INFO: ----------------------------------------------------------------------
[20:18:57.739] <TB3> INFO: PixTestReadback::CalibrateVa()
[20:18:57.739] <TB3> INFO: ----------------------------------------------------------------------
[20:19:07.656] <TB3> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1008_FullQualification_2016-10-16_17h47m_1476632865//003_Fulltest_p17//readbackCal_C0.dat
[20:19:07.656] <TB3> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1008_FullQualification_2016-10-16_17h47m_1476632865//003_Fulltest_p17//readbackCal_C1.dat
[20:19:07.656] <TB3> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1008_FullQualification_2016-10-16_17h47m_1476632865//003_Fulltest_p17//readbackCal_C2.dat
[20:19:07.656] <TB3> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1008_FullQualification_2016-10-16_17h47m_1476632865//003_Fulltest_p17//readbackCal_C3.dat
[20:19:07.656] <TB3> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1008_FullQualification_2016-10-16_17h47m_1476632865//003_Fulltest_p17//readbackCal_C4.dat
[20:19:07.656] <TB3> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1008_FullQualification_2016-10-16_17h47m_1476632865//003_Fulltest_p17//readbackCal_C5.dat
[20:19:07.656] <TB3> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1008_FullQualification_2016-10-16_17h47m_1476632865//003_Fulltest_p17//readbackCal_C6.dat
[20:19:07.656] <TB3> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1008_FullQualification_2016-10-16_17h47m_1476632865//003_Fulltest_p17//readbackCal_C7.dat
[20:19:07.656] <TB3> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1008_FullQualification_2016-10-16_17h47m_1476632865//003_Fulltest_p17//readbackCal_C8.dat
[20:19:07.656] <TB3> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1008_FullQualification_2016-10-16_17h47m_1476632865//003_Fulltest_p17//readbackCal_C9.dat
[20:19:07.656] <TB3> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1008_FullQualification_2016-10-16_17h47m_1476632865//003_Fulltest_p17//readbackCal_C10.dat
[20:19:07.656] <TB3> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1008_FullQualification_2016-10-16_17h47m_1476632865//003_Fulltest_p17//readbackCal_C11.dat
[20:19:07.656] <TB3> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1008_FullQualification_2016-10-16_17h47m_1476632865//003_Fulltest_p17//readbackCal_C12.dat
[20:19:07.657] <TB3> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1008_FullQualification_2016-10-16_17h47m_1476632865//003_Fulltest_p17//readbackCal_C13.dat
[20:19:07.657] <TB3> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1008_FullQualification_2016-10-16_17h47m_1476632865//003_Fulltest_p17//readbackCal_C14.dat
[20:19:07.657] <TB3> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1008_FullQualification_2016-10-16_17h47m_1476632865//003_Fulltest_p17//readbackCal_C15.dat
[20:19:07.685] <TB3> INFO: PixTestPattern:: pg_setup set to default.
[20:19:07.685] <TB3> INFO: ----------------------------------------------------------------------
[20:19:07.685] <TB3> INFO: PixTestReadback::readbackVbg()
[20:19:07.685] <TB3> INFO: ----------------------------------------------------------------------
[20:19:15.339] <TB3> INFO: PixTestPattern:: pg_setup set to default.
[20:19:15.339] <TB3> INFO: ----------------------------------------------------------------------
[20:19:15.339] <TB3> INFO: PixTestReadback::getCalibratedVbg()
[20:19:15.339] <TB3> INFO: ----------------------------------------------------------------------
[20:19:15.339] <TB3> INFO: Vbg will be calibrated using Vd calibration
[20:19:15.339] <TB3> INFO: /*/*/*/*::: ROC 0: uncalibrated Vbg = 148.2calibrated Vbg = 1.20809 :::*/*/*/*/
[20:19:15.339] <TB3> INFO: /*/*/*/*::: ROC 1: uncalibrated Vbg = 151.7calibrated Vbg = 1.20548 :::*/*/*/*/
[20:19:15.339] <TB3> INFO: /*/*/*/*::: ROC 2: uncalibrated Vbg = 150.8calibrated Vbg = 1.20685 :::*/*/*/*/
[20:19:15.339] <TB3> INFO: /*/*/*/*::: ROC 3: uncalibrated Vbg = 153.1calibrated Vbg = 1.19676 :::*/*/*/*/
[20:19:15.339] <TB3> INFO: /*/*/*/*::: ROC 4: uncalibrated Vbg = 156.3calibrated Vbg = 1.20605 :::*/*/*/*/
[20:19:15.339] <TB3> INFO: /*/*/*/*::: ROC 5: uncalibrated Vbg = 153.7calibrated Vbg = 1.20253 :::*/*/*/*/
[20:19:15.339] <TB3> INFO: /*/*/*/*::: ROC 6: uncalibrated Vbg = 148.7calibrated Vbg = 1.20877 :::*/*/*/*/
[20:19:15.339] <TB3> INFO: /*/*/*/*::: ROC 7: uncalibrated Vbg = 156.9calibrated Vbg = 1.20725 :::*/*/*/*/
[20:19:15.339] <TB3> INFO: /*/*/*/*::: ROC 8: uncalibrated Vbg = 154.4calibrated Vbg = 1.19917 :::*/*/*/*/
[20:19:15.339] <TB3> INFO: /*/*/*/*::: ROC 9: uncalibrated Vbg = 152.4calibrated Vbg = 1.20273 :::*/*/*/*/
[20:19:15.339] <TB3> INFO: /*/*/*/*::: ROC 10: uncalibrated Vbg = 156.4calibrated Vbg = 1.19177 :::*/*/*/*/
[20:19:15.339] <TB3> INFO: /*/*/*/*::: ROC 11: uncalibrated Vbg = 151.1calibrated Vbg = 1.19962 :::*/*/*/*/
[20:19:15.339] <TB3> INFO: /*/*/*/*::: ROC 12: uncalibrated Vbg = 144.1calibrated Vbg = 1.20071 :::*/*/*/*/
[20:19:15.339] <TB3> INFO: /*/*/*/*::: ROC 13: uncalibrated Vbg = 147.5calibrated Vbg = 1.2063 :::*/*/*/*/
[20:19:15.339] <TB3> INFO: /*/*/*/*::: ROC 14: uncalibrated Vbg = 150.7calibrated Vbg = 1.20724 :::*/*/*/*/
[20:19:15.339] <TB3> INFO: /*/*/*/*::: ROC 15: uncalibrated Vbg = 156.7calibrated Vbg = 1.20669 :::*/*/*/*/
[20:19:15.342] <TB3> INFO: ----------------------------------------------------------------------
[20:19:15.342] <TB3> INFO: PixTestReadback::CalibrateIa()
[20:19:15.342] <TB3> INFO: ----------------------------------------------------------------------
[20:21:55.803] <TB3> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1008_FullQualification_2016-10-16_17h47m_1476632865//003_Fulltest_p17//readbackCal_C0.dat
[20:21:55.803] <TB3> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1008_FullQualification_2016-10-16_17h47m_1476632865//003_Fulltest_p17//readbackCal_C1.dat
[20:21:55.803] <TB3> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1008_FullQualification_2016-10-16_17h47m_1476632865//003_Fulltest_p17//readbackCal_C2.dat
[20:21:55.803] <TB3> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1008_FullQualification_2016-10-16_17h47m_1476632865//003_Fulltest_p17//readbackCal_C3.dat
[20:21:55.803] <TB3> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1008_FullQualification_2016-10-16_17h47m_1476632865//003_Fulltest_p17//readbackCal_C4.dat
[20:21:55.803] <TB3> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1008_FullQualification_2016-10-16_17h47m_1476632865//003_Fulltest_p17//readbackCal_C5.dat
[20:21:55.803] <TB3> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1008_FullQualification_2016-10-16_17h47m_1476632865//003_Fulltest_p17//readbackCal_C6.dat
[20:21:55.803] <TB3> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1008_FullQualification_2016-10-16_17h47m_1476632865//003_Fulltest_p17//readbackCal_C7.dat
[20:21:55.804] <TB3> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1008_FullQualification_2016-10-16_17h47m_1476632865//003_Fulltest_p17//readbackCal_C8.dat
[20:21:55.804] <TB3> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1008_FullQualification_2016-10-16_17h47m_1476632865//003_Fulltest_p17//readbackCal_C9.dat
[20:21:55.804] <TB3> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1008_FullQualification_2016-10-16_17h47m_1476632865//003_Fulltest_p17//readbackCal_C10.dat
[20:21:55.804] <TB3> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1008_FullQualification_2016-10-16_17h47m_1476632865//003_Fulltest_p17//readbackCal_C11.dat
[20:21:55.804] <TB3> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1008_FullQualification_2016-10-16_17h47m_1476632865//003_Fulltest_p17//readbackCal_C12.dat
[20:21:55.804] <TB3> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1008_FullQualification_2016-10-16_17h47m_1476632865//003_Fulltest_p17//readbackCal_C13.dat
[20:21:55.804] <TB3> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1008_FullQualification_2016-10-16_17h47m_1476632865//003_Fulltest_p17//readbackCal_C14.dat
[20:21:55.804] <TB3> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1008_FullQualification_2016-10-16_17h47m_1476632865//003_Fulltest_p17//readbackCal_C15.dat
[20:21:55.832] <TB3> INFO: PixTestPattern:: pg_setup set to default.
[20:21:55.834] <TB3> INFO: PixTestReadback::doTest() done
[20:21:55.834] <TB3> INFO: Decoding statistics:
[20:21:55.834] <TB3> INFO: General information:
[20:21:55.834] <TB3> INFO: 16bit words read: 1536
[20:21:55.834] <TB3> INFO: valid events total: 256
[20:21:55.834] <TB3> INFO: empty events: 256
[20:21:55.834] <TB3> INFO: valid events with pixels: 0
[20:21:55.834] <TB3> INFO: valid pixel hits: 0
[20:21:55.834] <TB3> INFO: Event errors: 0
[20:21:55.834] <TB3> INFO: start marker: 0
[20:21:55.834] <TB3> INFO: stop marker: 0
[20:21:55.835] <TB3> INFO: overflow: 0
[20:21:55.835] <TB3> INFO: invalid 5bit words: 0
[20:21:55.835] <TB3> INFO: invalid XOR eye diagram: 0
[20:21:55.835] <TB3> INFO: frame (failed synchr.): 0
[20:21:55.835] <TB3> INFO: idle data (no TBM trl): 0
[20:21:55.835] <TB3> INFO: no data (only TBM hdr): 0
[20:21:55.835] <TB3> INFO: TBM errors: 0
[20:21:55.835] <TB3> INFO: flawed TBM headers: 0
[20:21:55.835] <TB3> INFO: flawed TBM trailers: 0
[20:21:55.835] <TB3> INFO: event ID mismatches: 0
[20:21:55.835] <TB3> INFO: ROC errors: 0
[20:21:55.835] <TB3> INFO: missing ROC header(s): 0
[20:21:55.835] <TB3> INFO: misplaced readback start: 0
[20:21:55.835] <TB3> INFO: Pixel decoding errors: 0
[20:21:55.835] <TB3> INFO: pixel data incomplete: 0
[20:21:55.835] <TB3> INFO: pixel address: 0
[20:21:55.835] <TB3> INFO: pulse height fill bit: 0
[20:21:55.835] <TB3> INFO: buffer corruption: 0
[20:21:55.887] <TB3> INFO: ######################################################################
[20:21:55.887] <TB3> INFO: PixTestBBMap::doTest() Ntrig = 5, VcalS = 250 (high range)
[20:21:55.887] <TB3> INFO: ######################################################################
[20:21:55.890] <TB3> INFO: ---> dac: VthrComp name: calSMap ntrig: 5 dacrange: 0 .. 149 (-1/-1) hits flags = 514 (plus default)
[20:21:55.909] <TB3> INFO: dacScan split into 1 runs with ntrig = 5
[20:21:55.909] <TB3> INFO: run 1 of 1
[20:21:56.179] <TB3> INFO: Expecting 3120000 events.
[20:22:27.855] <TB3> INFO: 671855 events read in total (31084ms).











[20:22:58.724] <TB3> INFO: 1339995 events read in total (61953ms).




















[20:23:29.956] <TB3> INFO: 2003690 events read in total (93185ms).











[20:24:01.141] <TB3> INFO: 2667445 events read in total (124370ms).











[20:24:22.142] <TB3> INFO: 3120000 events read in total (145371ms).
[20:24:22.206] <TB3> INFO: Test took 146298ms.
[20:24:42.356] <TB3> INFO: PixTestBBMap::doTest() done with 4 decoding errors: , duration: 166 seconds
[20:24:42.356] <TB3> INFO: number of dead bumps (per ROC): 2 0 1 0 0 0 0 0 0 1 0 0 0 0 0 0
[20:24:42.356] <TB3> INFO: separation cut (per ROC): 96 110 100 107 102 104 103 104 99 104 104 105 113 116 105 105
[20:24:42.356] <TB3> INFO: Decoding statistics:
[20:24:42.356] <TB3> INFO: General information:
[20:24:42.356] <TB3> INFO: 16bit words read: 0
[20:24:42.356] <TB3> INFO: valid events total: 0
[20:24:42.356] <TB3> INFO: empty events: 0
[20:24:42.356] <TB3> INFO: valid events with pixels: 0
[20:24:42.356] <TB3> INFO: valid pixel hits: 0
[20:24:42.356] <TB3> INFO: Event errors: 0
[20:24:42.356] <TB3> INFO: start marker: 0
[20:24:42.356] <TB3> INFO: stop marker: 0
[20:24:42.356] <TB3> INFO: overflow: 0
[20:24:42.356] <TB3> INFO: invalid 5bit words: 0
[20:24:42.356] <TB3> INFO: invalid XOR eye diagram: 0
[20:24:42.356] <TB3> INFO: frame (failed synchr.): 0
[20:24:42.356] <TB3> INFO: idle data (no TBM trl): 0
[20:24:42.356] <TB3> INFO: no data (only TBM hdr): 0
[20:24:42.356] <TB3> INFO: TBM errors: 0
[20:24:42.356] <TB3> INFO: flawed TBM headers: 0
[20:24:42.356] <TB3> INFO: flawed TBM trailers: 0
[20:24:42.356] <TB3> INFO: event ID mismatches: 0
[20:24:42.356] <TB3> INFO: ROC errors: 0
[20:24:42.356] <TB3> INFO: missing ROC header(s): 0
[20:24:42.356] <TB3> INFO: misplaced readback start: 0
[20:24:42.356] <TB3> INFO: Pixel decoding errors: 0
[20:24:42.356] <TB3> INFO: pixel data incomplete: 0
[20:24:42.356] <TB3> INFO: pixel address: 0
[20:24:42.356] <TB3> INFO: pulse height fill bit: 0
[20:24:42.356] <TB3> INFO: buffer corruption: 0
[20:24:42.392] <TB3> INFO: ######################################################################
[20:24:42.392] <TB3> INFO: PixTestScurves::fullTest() ntrig = 50, dacs/step = -1, ntrig/step = -1
[20:24:42.392] <TB3> INFO: ######################################################################
[20:24:42.392] <TB3> INFO: ----------------------------------------------------------------------
[20:24:42.392] <TB3> INFO: PixTestScurves::scurves(Vcal), ntrig = 50, dacs/step = -1, ntrig/step = -1
[20:24:42.392] <TB3> INFO: ----------------------------------------------------------------------
[20:24:42.392] <TB3> INFO: ---> dac: Vcal name: scurveVcal ntrig: 50 dacrange: 25 .. 200 (-1/-1) hits flags = 528 (plus default)
[20:24:42.406] <TB3> INFO: dacScan split into 1 runs with ntrig = 50
[20:24:42.406] <TB3> INFO: run 1 of 1
[20:24:42.663] <TB3> INFO: Expecting 36608000 events.
[20:25:07.256] <TB3> INFO: 692800 events read in total (24001ms).
[20:25:30.911] <TB3> INFO: 1373100 events read in total (47656ms).
[20:25:54.440] <TB3> INFO: 2051800 events read in total (71185ms).
[20:26:18.136] <TB3> INFO: 2732250 events read in total (94881ms).
[20:26:41.440] <TB3> INFO: 3412850 events read in total (118185ms).
[20:27:05.247] <TB3> INFO: 4090150 events read in total (141992ms).
[20:27:28.689] <TB3> INFO: 4769150 events read in total (165434ms).
[20:27:51.906] <TB3> INFO: 5448300 events read in total (188651ms).
[20:28:15.775] <TB3> INFO: 6126950 events read in total (212520ms).
[20:28:39.245] <TB3> INFO: 6805500 events read in total (235990ms).
[20:29:02.450] <TB3> INFO: 7484600 events read in total (259195ms).
[20:29:26.243] <TB3> INFO: 8161450 events read in total (282988ms).
[20:29:49.586] <TB3> INFO: 8838150 events read in total (306331ms).
[20:30:13.260] <TB3> INFO: 9515450 events read in total (330005ms).
[20:30:36.880] <TB3> INFO: 10193250 events read in total (353625ms).
[20:31:00.341] <TB3> INFO: 10869800 events read in total (377086ms).
[20:31:24.027] <TB3> INFO: 11547150 events read in total (400772ms).
[20:31:47.827] <TB3> INFO: 12223200 events read in total (424572ms).
[20:32:11.861] <TB3> INFO: 12900550 events read in total (448606ms).
[20:32:35.328] <TB3> INFO: 13576950 events read in total (472073ms).
[20:32:58.775] <TB3> INFO: 14253950 events read in total (495520ms).
[20:33:22.500] <TB3> INFO: 14930900 events read in total (519245ms).
[20:33:45.838] <TB3> INFO: 15605700 events read in total (542583ms).
[20:34:09.454] <TB3> INFO: 16278500 events read in total (566199ms).
[20:34:32.799] <TB3> INFO: 16952700 events read in total (589544ms).
[20:34:56.287] <TB3> INFO: 17623400 events read in total (613032ms).
[20:35:19.796] <TB3> INFO: 18292750 events read in total (636541ms).
[20:35:43.570] <TB3> INFO: 18964250 events read in total (660315ms).
[20:36:07.044] <TB3> INFO: 19637050 events read in total (683789ms).
[20:36:30.882] <TB3> INFO: 20306400 events read in total (707627ms).
[20:36:54.593] <TB3> INFO: 20975050 events read in total (731338ms).
[20:37:18.209] <TB3> INFO: 21644750 events read in total (754954ms).
[20:37:41.962] <TB3> INFO: 22313550 events read in total (778708ms).
[20:38:05.354] <TB3> INFO: 22981500 events read in total (802099ms).
[20:38:28.939] <TB3> INFO: 23646650 events read in total (825684ms).
[20:38:52.522] <TB3> INFO: 24313900 events read in total (849267ms).
[20:39:16.012] <TB3> INFO: 24982050 events read in total (872757ms).
[20:39:39.657] <TB3> INFO: 25651250 events read in total (896402ms).
[20:40:03.118] <TB3> INFO: 26319500 events read in total (919863ms).
[20:40:26.339] <TB3> INFO: 26987950 events read in total (943084ms).
[20:40:49.907] <TB3> INFO: 27655850 events read in total (966652ms).
[20:41:13.629] <TB3> INFO: 28321950 events read in total (990374ms).
[20:41:37.145] <TB3> INFO: 28986550 events read in total (1013890ms).
[20:42:01.135] <TB3> INFO: 29652550 events read in total (1037880ms).
[20:42:24.571] <TB3> INFO: 30317700 events read in total (1061316ms).
[20:42:47.854] <TB3> INFO: 30984450 events read in total (1084599ms).
[20:43:10.860] <TB3> INFO: 31649750 events read in total (1107605ms).
[20:43:34.547] <TB3> INFO: 32314500 events read in total (1131292ms).
[20:43:58.057] <TB3> INFO: 32979350 events read in total (1154802ms).
[20:44:21.800] <TB3> INFO: 33644150 events read in total (1178545ms).
[20:44:44.979] <TB3> INFO: 34310050 events read in total (1201724ms).
[20:45:08.302] <TB3> INFO: 34978000 events read in total (1225047ms).
[20:45:32.371] <TB3> INFO: 35645900 events read in total (1249116ms).
[20:45:56.098] <TB3> INFO: 36321650 events read in total (1272843ms).
[20:46:06.320] <TB3> INFO: 36608000 events read in total (1283065ms).
[20:46:06.402] <TB3> INFO: Test took 1283995ms.
[20:46:06.855] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[20:46:08.356] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[20:46:10.355] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[20:46:12.788] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[20:46:14.795] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[20:46:16.210] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[20:46:18.172] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[20:46:19.687] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[20:46:21.615] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[20:46:23.016] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[20:46:24.407] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[20:46:26.502] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[20:46:28.873] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[20:46:31.243] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[20:46:32.785] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[20:46:34.189] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[20:46:35.617] <TB3> INFO: PixTestScurves::scurves() done
[20:46:35.617] <TB3> INFO: Vcal mean: 110.49 128.76 112.90 126.43 118.41 124.26 121.85 112.06 118.50 130.05 119.38 116.15 128.05 123.65 116.62 120.08
[20:46:35.617] <TB3> INFO: Vcal RMS: 5.26 6.70 5.51 6.10 6.95 5.83 6.36 5.47 5.92 6.79 6.28 5.48 6.50 6.04 5.40 6.30
[20:46:35.617] <TB3> INFO: PixTestScurves::fullTest() done, duration: 1313 seconds
[20:46:35.617] <TB3> INFO: Decoding statistics:
[20:46:35.617] <TB3> INFO: General information:
[20:46:35.617] <TB3> INFO: 16bit words read: 0
[20:46:35.617] <TB3> INFO: valid events total: 0
[20:46:35.617] <TB3> INFO: empty events: 0
[20:46:35.617] <TB3> INFO: valid events with pixels: 0
[20:46:35.617] <TB3> INFO: valid pixel hits: 0
[20:46:35.617] <TB3> INFO: Event errors: 0
[20:46:35.617] <TB3> INFO: start marker: 0
[20:46:35.617] <TB3> INFO: stop marker: 0
[20:46:35.617] <TB3> INFO: overflow: 0
[20:46:35.617] <TB3> INFO: invalid 5bit words: 0
[20:46:35.617] <TB3> INFO: invalid XOR eye diagram: 0
[20:46:35.617] <TB3> INFO: frame (failed synchr.): 0
[20:46:35.617] <TB3> INFO: idle data (no TBM trl): 0
[20:46:35.617] <TB3> INFO: no data (only TBM hdr): 0
[20:46:35.617] <TB3> INFO: TBM errors: 0
[20:46:35.617] <TB3> INFO: flawed TBM headers: 0
[20:46:35.617] <TB3> INFO: flawed TBM trailers: 0
[20:46:35.617] <TB3> INFO: event ID mismatches: 0
[20:46:35.617] <TB3> INFO: ROC errors: 0
[20:46:35.617] <TB3> INFO: missing ROC header(s): 0
[20:46:35.617] <TB3> INFO: misplaced readback start: 0
[20:46:35.617] <TB3> INFO: Pixel decoding errors: 0
[20:46:35.617] <TB3> INFO: pixel data incomplete: 0
[20:46:35.617] <TB3> INFO: pixel address: 0
[20:46:35.617] <TB3> INFO: pulse height fill bit: 0
[20:46:35.617] <TB3> INFO: buffer corruption: 0
[20:46:35.683] <TB3> INFO: ######################################################################
[20:46:35.683] <TB3> INFO: PixTestTrim::doTest()
[20:46:35.683] <TB3> INFO: ######################################################################
[20:46:35.684] <TB3> INFO: ----------------------------------------------------------------------
[20:46:35.684] <TB3> INFO: PixTestTrim::trimTest() ntrig = 8, vcal = 35
[20:46:35.684] <TB3> INFO: ----------------------------------------------------------------------
[20:46:35.726] <TB3> INFO: ---> VthrComp thr map (minimal VthrComp)
[20:46:35.726] <TB3> INFO: ---> dac: vthrcomp name: TrimThr0 ntrig: 8 dacrange: 10 .. 160 (-1/-1) hits flags = 528 (plus default)
[20:46:35.738] <TB3> INFO: dacScan split into 1 runs with ntrig = 8
[20:46:35.738] <TB3> INFO: run 1 of 1
[20:46:36.030] <TB3> INFO: Expecting 5025280 events.
[20:47:07.364] <TB3> INFO: 831736 events read in total (30741ms).
[20:47:38.146] <TB3> INFO: 1660912 events read in total (61524ms).
[20:48:08.603] <TB3> INFO: 2488624 events read in total (91981ms).
[20:48:39.297] <TB3> INFO: 3314120 events read in total (122674ms).
[20:49:10.056] <TB3> INFO: 4135944 events read in total (153433ms).
[20:49:40.892] <TB3> INFO: 4956328 events read in total (184269ms).
[20:49:43.857] <TB3> INFO: 5025280 events read in total (187234ms).
[20:49:43.945] <TB3> INFO: Test took 188207ms.
[20:49:56.861] <TB3> INFO: ROC 0 VthrComp = 109
[20:49:56.861] <TB3> INFO: ROC 1 VthrComp = 126
[20:49:56.861] <TB3> INFO: ROC 2 VthrComp = 113
[20:49:56.861] <TB3> INFO: ROC 3 VthrComp = 127
[20:49:56.861] <TB3> INFO: ROC 4 VthrComp = 114
[20:49:56.861] <TB3> INFO: ROC 5 VthrComp = 122
[20:49:56.862] <TB3> INFO: ROC 6 VthrComp = 115
[20:49:56.862] <TB3> INFO: ROC 7 VthrComp = 113
[20:49:56.862] <TB3> INFO: ROC 8 VthrComp = 109
[20:49:56.862] <TB3> INFO: ROC 9 VthrComp = 123
[20:49:56.862] <TB3> INFO: ROC 10 VthrComp = 123
[20:49:56.862] <TB3> INFO: ROC 11 VthrComp = 110
[20:49:56.862] <TB3> INFO: ROC 12 VthrComp = 135
[20:49:56.863] <TB3> INFO: ROC 13 VthrComp = 132
[20:49:56.863] <TB3> INFO: ROC 14 VthrComp = 118
[20:49:56.863] <TB3> INFO: ROC 15 VthrComp = 118
[20:49:56.863] <TB3> INFO: ---> Vcal thr map (pixel with maximum Vcal thr)
[20:49:56.863] <TB3> INFO: ---> dac: vcal name: TrimThr1 ntrig: 8 dacrange: 10 .. 160 (-1/-1) hits flags = 528 (plus default)
[20:49:56.877] <TB3> INFO: dacScan split into 1 runs with ntrig = 8
[20:49:56.877] <TB3> INFO: run 1 of 1
[20:49:57.114] <TB3> INFO: Expecting 5025280 events.
[20:50:24.497] <TB3> INFO: 593032 events read in total (26791ms).
[20:50:50.564] <TB3> INFO: 1184888 events read in total (52858ms).
[20:51:16.893] <TB3> INFO: 1776240 events read in total (79187ms).
[20:51:43.145] <TB3> INFO: 2367304 events read in total (105439ms).
[20:52:09.940] <TB3> INFO: 2956152 events read in total (132234ms).
[20:52:36.459] <TB3> INFO: 3543320 events read in total (158753ms).
[20:53:03.072] <TB3> INFO: 4130016 events read in total (185366ms).
[20:53:29.731] <TB3> INFO: 4715792 events read in total (212025ms).
[20:53:44.220] <TB3> INFO: 5025280 events read in total (226515ms).
[20:53:44.284] <TB3> INFO: Test took 227407ms.
[20:54:05.654] <TB3> INFO: roc 0 with ID = 0 has maximal Vcal 59.2101 for pixel 0/74 mean/min/max = 47.373/35.4935/59.2524
[20:54:05.655] <TB3> INFO: roc 1 with ID = 1 has maximal Vcal 64.9622 for pixel 16/10 mean/min/max = 48.2235/31.3668/65.0802
[20:54:05.655] <TB3> INFO: roc 2 with ID = 2 has maximal Vcal 61.1221 for pixel 13/42 mean/min/max = 47.1131/33.0561/61.1702
[20:54:05.656] <TB3> INFO: roc 3 with ID = 3 has maximal Vcal 61.1075 for pixel 1/78 mean/min/max = 47.3704/33.3492/61.3915
[20:54:05.657] <TB3> INFO: roc 4 with ID = 4 has maximal Vcal 64.4869 for pixel 14/6 mean/min/max = 48.2792/31.9217/64.6368
[20:54:05.657] <TB3> INFO: roc 5 with ID = 5 has maximal Vcal 60.0296 for pixel 24/74 mean/min/max = 46.6521/33.1813/60.1229
[20:54:05.657] <TB3> INFO: roc 6 with ID = 6 has maximal Vcal 63.3398 for pixel 0/59 mean/min/max = 48.2017/33.0207/63.3827
[20:54:05.658] <TB3> INFO: roc 7 with ID = 7 has maximal Vcal 59.5005 for pixel 0/53 mean/min/max = 46.1225/32.5872/59.6578
[20:54:05.658] <TB3> INFO: roc 8 with ID = 8 has maximal Vcal 66.588 for pixel 2/78 mean/min/max = 51.2828/35.7597/66.8059
[20:54:05.659] <TB3> INFO: roc 9 with ID = 9 has maximal Vcal 62.1361 for pixel 0/43 mean/min/max = 47.8685/33.4661/62.2708
[20:54:05.659] <TB3> INFO: roc 10 with ID = 10 has maximal Vcal 58.2931 for pixel 18/56 mean/min/max = 45.8095/33.2421/58.3769
[20:54:05.660] <TB3> INFO: roc 11 with ID = 11 has maximal Vcal 63.5946 for pixel 10/77 mean/min/max = 49.5296/35.4183/63.641
[20:54:05.660] <TB3> INFO: roc 12 with ID = 12 has maximal Vcal 59.3004 for pixel 0/5 mean/min/max = 46.9939/34.6371/59.3507
[20:54:05.661] <TB3> INFO: roc 13 with ID = 13 has maximal Vcal 59.1214 for pixel 30/75 mean/min/max = 46.5034/33.8543/59.1525
[20:54:05.661] <TB3> INFO: roc 14 with ID = 14 has maximal Vcal 58.7431 for pixel 6/79 mean/min/max = 46.1486/33.4445/58.8526
[20:54:05.661] <TB3> INFO: roc 15 with ID = 15 has maximal Vcal 60.2973 for pixel 0/10 mean/min/max = 46.7704/33.1052/60.4357
[20:54:05.662] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[20:54:05.750] <TB3> INFO: Expecting 411648 events.
[20:54:15.294] <TB3> INFO: 411648 events read in total (8952ms).
[20:54:15.300] <TB3> INFO: Expecting 411648 events.
[20:54:24.881] <TB3> INFO: 411648 events read in total (9178ms).
[20:54:24.891] <TB3> INFO: Expecting 411648 events.
[20:54:34.524] <TB3> INFO: 411648 events read in total (9230ms).
[20:54:34.543] <TB3> INFO: Expecting 411648 events.
[20:54:44.037] <TB3> INFO: 411648 events read in total (9091ms).
[20:54:44.054] <TB3> INFO: Expecting 411648 events.
[20:54:53.642] <TB3> INFO: 411648 events read in total (9185ms).
[20:54:53.662] <TB3> INFO: Expecting 411648 events.
[20:55:03.270] <TB3> INFO: 411648 events read in total (9205ms).
[20:55:03.291] <TB3> INFO: Expecting 411648 events.
[20:55:12.853] <TB3> INFO: 411648 events read in total (9159ms).
[20:55:12.876] <TB3> INFO: Expecting 411648 events.
[20:55:22.591] <TB3> INFO: 411648 events read in total (9312ms).
[20:55:22.617] <TB3> INFO: Expecting 411648 events.
[20:55:32.215] <TB3> INFO: 411648 events read in total (9195ms).
[20:55:32.243] <TB3> INFO: Expecting 411648 events.
[20:55:41.733] <TB3> INFO: 411648 events read in total (9087ms).
[20:55:41.763] <TB3> INFO: Expecting 411648 events.
[20:55:51.419] <TB3> INFO: 411648 events read in total (9253ms).
[20:55:51.466] <TB3> INFO: Expecting 411648 events.
[20:56:01.177] <TB3> INFO: 411648 events read in total (9308ms).
[20:56:01.227] <TB3> INFO: Expecting 411648 events.
[20:56:10.804] <TB3> INFO: 411648 events read in total (9174ms).
[20:56:10.843] <TB3> INFO: Expecting 411648 events.
[20:56:20.304] <TB3> INFO: 411648 events read in total (9058ms).
[20:56:20.346] <TB3> INFO: Expecting 411648 events.
[20:56:29.984] <TB3> INFO: 411648 events read in total (9235ms).
[20:56:30.028] <TB3> INFO: Expecting 411648 events.
[20:56:39.641] <TB3> INFO: 411648 events read in total (9210ms).
[20:56:39.708] <TB3> INFO: Test took 154046ms.
[20:56:40.410] <TB3> INFO: ---> dac: vcal name: TrimThr2 ntrig: 8 dacrange: 0 .. 150 (-1/-1) hits flags = 528 (plus default)
[20:56:40.425] <TB3> INFO: dacScan split into 1 runs with ntrig = 8
[20:56:40.425] <TB3> INFO: run 1 of 1
[20:56:40.723] <TB3> INFO: Expecting 5025280 events.
[20:57:08.320] <TB3> INFO: 587816 events read in total (27005ms).
[20:57:35.146] <TB3> INFO: 1174480 events read in total (53831ms).
[20:58:02.082] <TB3> INFO: 1760416 events read in total (80767ms).
[20:58:29.079] <TB3> INFO: 2345280 events read in total (107764ms).
[20:58:56.314] <TB3> INFO: 2928912 events read in total (135000ms).
[20:59:23.385] <TB3> INFO: 3511576 events read in total (162071ms).
[20:59:50.364] <TB3> INFO: 4094280 events read in total (189049ms).
[21:00:17.319] <TB3> INFO: 4677912 events read in total (216004ms).
[21:00:33.607] <TB3> INFO: 5025280 events read in total (232293ms).
[21:00:33.730] <TB3> INFO: Test took 233306ms.
[21:00:54.446] <TB3> INFO: ---> TrimStepCorr4 extremal thresholds: 4.944553 .. 144.415356
[21:00:54.686] <TB3> INFO: Expecting 208000 events.
[21:01:04.782] <TB3> INFO: 208000 events read in total (9505ms).
[21:01:04.784] <TB3> INFO: Test took 10337ms.
[21:01:04.875] <TB3> INFO: ---> dac: vcal name: trimStepCorr4 ntrig: 8 dacrange: 4 .. 154 (-1/-1) hits flags = 528 (plus default)
[21:01:04.889] <TB3> INFO: dacScan split into 1 runs with ntrig = 8
[21:01:04.889] <TB3> INFO: run 1 of 1
[21:01:05.207] <TB3> INFO: Expecting 5025280 events.
[21:01:32.685] <TB3> INFO: 580912 events read in total (26886ms).
[21:01:59.332] <TB3> INFO: 1162040 events read in total (53535ms).
[21:02:26.316] <TB3> INFO: 1743232 events read in total (80517ms).
[21:02:53.148] <TB3> INFO: 2324208 events read in total (107349ms).
[21:03:19.383] <TB3> INFO: 2905536 events read in total (133584ms).
[21:03:46.778] <TB3> INFO: 3486136 events read in total (160979ms).
[21:04:14.686] <TB3> INFO: 4067184 events read in total (188887ms).
[21:04:41.834] <TB3> INFO: 4647656 events read in total (216035ms).
[21:04:58.900] <TB3> INFO: 5025280 events read in total (233101ms).
[21:04:59.003] <TB3> INFO: Test took 234113ms.
[21:05:20.685] <TB3> INFO: ---> TrimStepCorr2 extremal thresholds: 25.900619 .. 47.229644
[21:05:20.001] <TB3> INFO: Expecting 208000 events.
[21:05:31.563] <TB3> INFO: 208000 events read in total (9970ms).
[21:05:31.564] <TB3> INFO: Test took 10878ms.
[21:05:31.654] <TB3> INFO: ---> dac: vcal name: trimStepCorr2 ntrig: 8 dacrange: 15 .. 57 (-1/-1) hits flags = 528 (plus default)
[21:05:31.669] <TB3> INFO: dacScan split into 1 runs with ntrig = 8
[21:05:31.669] <TB3> INFO: run 1 of 1
[21:05:31.983] <TB3> INFO: Expecting 1431040 events.
[21:06:01.343] <TB3> INFO: 660384 events read in total (28768ms).
[21:06:30.236] <TB3> INFO: 1320536 events read in total (57661ms).
[21:06:35.427] <TB3> INFO: 1431040 events read in total (62853ms).
[21:06:35.473] <TB3> INFO: Test took 63805ms.
[21:06:47.683] <TB3> INFO: ---> TrimStepCorr1a extremal thresholds: 28.457760 .. 49.724248
[21:06:47.920] <TB3> INFO: Expecting 208000 events.
[21:06:58.155] <TB3> INFO: 208000 events read in total (9643ms).
[21:06:58.155] <TB3> INFO: Test took 10470ms.
[21:06:58.202] <TB3> INFO: ---> dac: vcal name: trimStepCorr1a ntrig: 8 dacrange: 18 .. 59 (-1/-1) hits flags = 528 (plus default)
[21:06:58.215] <TB3> INFO: dacScan split into 1 runs with ntrig = 8
[21:06:58.215] <TB3> INFO: run 1 of 1
[21:06:58.528] <TB3> INFO: Expecting 1397760 events.
[21:07:27.478] <TB3> INFO: 641680 events read in total (28359ms).
[21:07:56.283] <TB3> INFO: 1283040 events read in total (57165ms).
[21:08:01.869] <TB3> INFO: 1397760 events read in total (62750ms).
[21:08:01.901] <TB3> INFO: Test took 63686ms.
[21:08:13.857] <TB3> INFO: ---> TrimStepCorr1b extremal thresholds: 27.155151 .. 50.696205
[21:08:14.173] <TB3> INFO: Expecting 208000 events.
[21:08:24.501] <TB3> INFO: 208000 events read in total (9736ms).
[21:08:24.502] <TB3> INFO: Test took 10644ms.
[21:08:24.549] <TB3> INFO: ---> dac: vcal name: trimStepCorr1b ntrig: 8 dacrange: 17 .. 60 (-1/-1) hits flags = 528 (plus default)
[21:08:24.563] <TB3> INFO: dacScan split into 1 runs with ntrig = 8
[21:08:24.563] <TB3> INFO: run 1 of 1
[21:08:24.841] <TB3> INFO: Expecting 1464320 events.
[21:08:53.752] <TB3> INFO: 642424 events read in total (28319ms).
[21:09:22.422] <TB3> INFO: 1284344 events read in total (56989ms).
[21:09:30.852] <TB3> INFO: 1464320 events read in total (65420ms).
[21:09:30.884] <TB3> INFO: Test took 66322ms.
[21:09:43.077] <TB3> INFO: ---> TrimThrFinal extremal thresholds: 15 .. 55
[21:09:43.077] <TB3> INFO: ---> dac: vcal name: TrimThrFinal ntrig: 8 dacrange: 15 .. 55 (-1/-1) hits flags = 528 (plus default)
[21:09:43.090] <TB3> INFO: dacScan split into 1 runs with ntrig = 8
[21:09:43.090] <TB3> INFO: run 1 of 1
[21:09:43.326] <TB3> INFO: Expecting 1364480 events.
[21:10:13.065] <TB3> INFO: 668216 events read in total (29147ms).
[21:10:42.706] <TB3> INFO: 1336288 events read in total (58788ms).
[21:10:44.415] <TB3> INFO: 1364480 events read in total (60498ms).
[21:10:44.453] <TB3> INFO: Test took 61364ms.
[21:10:56.403] <TB3> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1008_FullQualification_2016-10-16_17h47m_1476632865//003_Fulltest_p17//dacParameters35_C0.dat
[21:10:56.403] <TB3> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1008_FullQualification_2016-10-16_17h47m_1476632865//003_Fulltest_p17//dacParameters35_C1.dat
[21:10:56.403] <TB3> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1008_FullQualification_2016-10-16_17h47m_1476632865//003_Fulltest_p17//dacParameters35_C2.dat
[21:10:56.403] <TB3> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1008_FullQualification_2016-10-16_17h47m_1476632865//003_Fulltest_p17//dacParameters35_C3.dat
[21:10:56.403] <TB3> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1008_FullQualification_2016-10-16_17h47m_1476632865//003_Fulltest_p17//dacParameters35_C4.dat
[21:10:56.403] <TB3> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1008_FullQualification_2016-10-16_17h47m_1476632865//003_Fulltest_p17//dacParameters35_C5.dat
[21:10:56.403] <TB3> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1008_FullQualification_2016-10-16_17h47m_1476632865//003_Fulltest_p17//dacParameters35_C6.dat
[21:10:56.404] <TB3> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1008_FullQualification_2016-10-16_17h47m_1476632865//003_Fulltest_p17//dacParameters35_C7.dat
[21:10:56.404] <TB3> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1008_FullQualification_2016-10-16_17h47m_1476632865//003_Fulltest_p17//dacParameters35_C8.dat
[21:10:56.404] <TB3> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1008_FullQualification_2016-10-16_17h47m_1476632865//003_Fulltest_p17//dacParameters35_C9.dat
[21:10:56.404] <TB3> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1008_FullQualification_2016-10-16_17h47m_1476632865//003_Fulltest_p17//dacParameters35_C10.dat
[21:10:56.404] <TB3> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1008_FullQualification_2016-10-16_17h47m_1476632865//003_Fulltest_p17//dacParameters35_C11.dat
[21:10:56.404] <TB3> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1008_FullQualification_2016-10-16_17h47m_1476632865//003_Fulltest_p17//dacParameters35_C12.dat
[21:10:56.404] <TB3> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1008_FullQualification_2016-10-16_17h47m_1476632865//003_Fulltest_p17//dacParameters35_C13.dat
[21:10:56.404] <TB3> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1008_FullQualification_2016-10-16_17h47m_1476632865//003_Fulltest_p17//dacParameters35_C14.dat
[21:10:56.404] <TB3> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1008_FullQualification_2016-10-16_17h47m_1476632865//003_Fulltest_p17//dacParameters35_C15.dat
[21:10:56.404] <TB3> INFO: write trim parameters into /home/l_tester/david/FullQualification/data/M1008_FullQualification_2016-10-16_17h47m_1476632865//003_Fulltest_p17//trimParameters35_C0.dat
[21:10:56.410] <TB3> INFO: write trim parameters into /home/l_tester/david/FullQualification/data/M1008_FullQualification_2016-10-16_17h47m_1476632865//003_Fulltest_p17//trimParameters35_C1.dat
[21:10:56.416] <TB3> INFO: write trim parameters into /home/l_tester/david/FullQualification/data/M1008_FullQualification_2016-10-16_17h47m_1476632865//003_Fulltest_p17//trimParameters35_C2.dat
[21:10:56.422] <TB3> INFO: write trim parameters into /home/l_tester/david/FullQualification/data/M1008_FullQualification_2016-10-16_17h47m_1476632865//003_Fulltest_p17//trimParameters35_C3.dat
[21:10:56.426] <TB3> INFO: write trim parameters into /home/l_tester/david/FullQualification/data/M1008_FullQualification_2016-10-16_17h47m_1476632865//003_Fulltest_p17//trimParameters35_C4.dat
[21:10:56.431] <TB3> INFO: write trim parameters into /home/l_tester/david/FullQualification/data/M1008_FullQualification_2016-10-16_17h47m_1476632865//003_Fulltest_p17//trimParameters35_C5.dat
[21:10:56.436] <TB3> INFO: write trim parameters into /home/l_tester/david/FullQualification/data/M1008_FullQualification_2016-10-16_17h47m_1476632865//003_Fulltest_p17//trimParameters35_C6.dat
[21:10:56.440] <TB3> INFO: write trim parameters into /home/l_tester/david/FullQualification/data/M1008_FullQualification_2016-10-16_17h47m_1476632865//003_Fulltest_p17//trimParameters35_C7.dat
[21:10:56.445] <TB3> INFO: write trim parameters into /home/l_tester/david/FullQualification/data/M1008_FullQualification_2016-10-16_17h47m_1476632865//003_Fulltest_p17//trimParameters35_C8.dat
[21:10:56.450] <TB3> INFO: write trim parameters into /home/l_tester/david/FullQualification/data/M1008_FullQualification_2016-10-16_17h47m_1476632865//003_Fulltest_p17//trimParameters35_C9.dat
[21:10:56.455] <TB3> INFO: write trim parameters into /home/l_tester/david/FullQualification/data/M1008_FullQualification_2016-10-16_17h47m_1476632865//003_Fulltest_p17//trimParameters35_C10.dat
[21:10:56.459] <TB3> INFO: write trim parameters into /home/l_tester/david/FullQualification/data/M1008_FullQualification_2016-10-16_17h47m_1476632865//003_Fulltest_p17//trimParameters35_C11.dat
[21:10:56.464] <TB3> INFO: write trim parameters into /home/l_tester/david/FullQualification/data/M1008_FullQualification_2016-10-16_17h47m_1476632865//003_Fulltest_p17//trimParameters35_C12.dat
[21:10:56.469] <TB3> INFO: write trim parameters into /home/l_tester/david/FullQualification/data/M1008_FullQualification_2016-10-16_17h47m_1476632865//003_Fulltest_p17//trimParameters35_C13.dat
[21:10:56.473] <TB3> INFO: write trim parameters into /home/l_tester/david/FullQualification/data/M1008_FullQualification_2016-10-16_17h47m_1476632865//003_Fulltest_p17//trimParameters35_C14.dat
[21:10:56.478] <TB3> INFO: write trim parameters into /home/l_tester/david/FullQualification/data/M1008_FullQualification_2016-10-16_17h47m_1476632865//003_Fulltest_p17//trimParameters35_C15.dat
[21:10:56.483] <TB3> INFO: PixTestTrim::trimTest() done
[21:10:56.483] <TB3> INFO: vtrim: 103 152 151 130 121 122 106 113 132 113 117 119 119 134 111 103
[21:10:56.483] <TB3> INFO: vthrcomp: 109 126 113 127 114 122 115 113 109 123 123 110 135 132 118 118
[21:10:56.483] <TB3> INFO: vcal mean: 35.03 35.38 35.22 35.44 35.10 35.11 35.51 34.93 36.42 35.18 35.01 35.11 35.02 35.00 35.06 35.06
[21:10:56.483] <TB3> INFO: vcal RMS: 1.23 1.51 1.28 1.75 1.24 1.26 1.92 1.00 2.75 1.41 1.23 1.32 1.00 0.98 1.02 1.20
[21:10:56.483] <TB3> INFO: bits mean: 7.62 9.71 9.65 9.33 9.05 9.29 8.82 9.29 9.08 8.79 9.44 8.28 7.89 9.20 8.71 8.67
[21:10:56.483] <TB3> INFO: bits RMS: 2.90 2.64 2.46 2.73 2.76 2.66 2.97 2.71 2.54 2.77 2.64 2.62 2.86 2.55 2.89 2.91
[21:10:56.490] <TB3> INFO: ----------------------------------------------------------------------
[21:10:56.490] <TB3> INFO: PixTestTrim::trimBitTest() ntrig = 5, vtrims = 254 126 63 32
[21:10:56.490] <TB3> INFO: ----------------------------------------------------------------------
[21:10:56.493] <TB3> INFO: ---> dac: Vcal name: TrimBitsThr0 ntrig: 5 dacrange: 0 .. 199 (-1/-1) hits flags = 528 (plus default)
[21:10:56.506] <TB3> INFO: dacScan split into 1 runs with ntrig = 5
[21:10:56.506] <TB3> INFO: run 1 of 1
[21:10:56.745] <TB3> INFO: Expecting 4160000 events.
[21:11:31.258] <TB3> INFO: 768955 events read in total (33921ms).
[21:12:04.399] <TB3> INFO: 1533625 events read in total (67062ms).
[21:12:38.150] <TB3> INFO: 2293660 events read in total (100813ms).
[21:13:10.946] <TB3> INFO: 3049015 events read in total (133609ms).
[21:13:44.305] <TB3> INFO: 3800825 events read in total (166968ms).
[21:14:00.593] <TB3> INFO: 4160000 events read in total (183256ms).
[21:14:00.646] <TB3> INFO: Test took 184140ms.
[21:14:22.759] <TB3> INFO: ---> dac: Vcal name: TrimThr_trim14 ntrig: 5 dacrange: 0 .. 209 (-1/-1) hits flags = 528 (plus default)
[21:14:22.772] <TB3> INFO: dacScan split into 1 runs with ntrig = 5
[21:14:22.772] <TB3> INFO: run 1 of 1
[21:14:23.087] <TB3> INFO: Expecting 4368000 events.
[21:14:56.516] <TB3> INFO: 729260 events read in total (32837ms).
[21:15:29.115] <TB3> INFO: 1454935 events read in total (65436ms).
[21:16:01.641] <TB3> INFO: 2177730 events read in total (97962ms).
[21:16:34.144] <TB3> INFO: 2895280 events read in total (130465ms).
[21:17:06.475] <TB3> INFO: 3610245 events read in total (162796ms).
[21:17:38.259] <TB3> INFO: 4324775 events read in total (194580ms).
[21:17:40.618] <TB3> INFO: 4368000 events read in total (196939ms).
[21:17:40.678] <TB3> INFO: Test took 197906ms.
[21:18:04.494] <TB3> INFO: ---> dac: Vcal name: TrimThr_trim13 ntrig: 5 dacrange: 0 .. 202 (-1/-1) hits flags = 528 (plus default)
[21:18:04.506] <TB3> INFO: dacScan split into 1 runs with ntrig = 5
[21:18:04.506] <TB3> INFO: run 1 of 1
[21:18:04.743] <TB3> INFO: Expecting 4222400 events.
[21:18:38.283] <TB3> INFO: 738960 events read in total (32949ms).
[21:19:11.429] <TB3> INFO: 1474070 events read in total (66095ms).
[21:19:43.733] <TB3> INFO: 2205705 events read in total (98399ms).
[21:20:16.100] <TB3> INFO: 2931215 events read in total (130766ms).
[21:20:48.292] <TB3> INFO: 3654090 events read in total (162958ms).
[21:21:14.229] <TB3> INFO: 4222400 events read in total (188895ms).
[21:21:14.292] <TB3> INFO: Test took 189785ms.
[21:21:38.092] <TB3> INFO: ---> dac: Vcal name: TrimThr_trim11 ntrig: 5 dacrange: 0 .. 197 (-1/-1) hits flags = 528 (plus default)
[21:21:38.107] <TB3> INFO: dacScan split into 1 runs with ntrig = 5
[21:21:38.107] <TB3> INFO: run 1 of 1
[21:21:38.422] <TB3> INFO: Expecting 4118400 events.
[21:22:12.700] <TB3> INFO: 746400 events read in total (33687ms).
[21:22:45.971] <TB3> INFO: 1488750 events read in total (66958ms).
[21:23:19.555] <TB3> INFO: 2227225 events read in total (100542ms).
[21:23:52.007] <TB3> INFO: 2959170 events read in total (132994ms).
[21:24:24.775] <TB3> INFO: 3688385 events read in total (165762ms).
[21:24:44.315] <TB3> INFO: 4118400 events read in total (185302ms).
[21:24:44.372] <TB3> INFO: Test took 186265ms.
[21:25:06.997] <TB3> INFO: ---> dac: Vcal name: TrimThr_trim7 ntrig: 5 dacrange: 0 .. 196 (-1/-1) hits flags = 528 (plus default)
[21:25:07.008] <TB3> INFO: dacScan split into 1 runs with ntrig = 5
[21:25:07.008] <TB3> INFO: run 1 of 1
[21:25:07.246] <TB3> INFO: Expecting 4097600 events.
[21:25:41.640] <TB3> INFO: 748025 events read in total (33803ms).
[21:26:15.140] <TB3> INFO: 1491965 events read in total (67303ms).
[21:26:47.865] <TB3> INFO: 2231855 events read in total (100028ms).
[21:27:20.892] <TB3> INFO: 2965670 events read in total (133055ms).
[21:27:53.938] <TB3> INFO: 3696170 events read in total (166101ms).
[21:28:12.355] <TB3> INFO: 4097600 events read in total (184518ms).
[21:28:12.410] <TB3> INFO: Test took 185402ms.
[21:28:35.009] <TB3> INFO: PixTestTrim::trimBitTest() done
[21:28:35.010] <TB3> INFO: PixTestTrim::doTest() done, duration: 2519 seconds
[21:28:35.010] <TB3> INFO: Decoding statistics:
[21:28:35.010] <TB3> INFO: General information:
[21:28:35.010] <TB3> INFO: 16bit words read: 0
[21:28:35.010] <TB3> INFO: valid events total: 0
[21:28:35.010] <TB3> INFO: empty events: 0
[21:28:35.010] <TB3> INFO: valid events with pixels: 0
[21:28:35.010] <TB3> INFO: valid pixel hits: 0
[21:28:35.010] <TB3> INFO: Event errors: 0
[21:28:35.010] <TB3> INFO: start marker: 0
[21:28:35.010] <TB3> INFO: stop marker: 0
[21:28:35.010] <TB3> INFO: overflow: 0
[21:28:35.010] <TB3> INFO: invalid 5bit words: 0
[21:28:35.011] <TB3> INFO: invalid XOR eye diagram: 0
[21:28:35.011] <TB3> INFO: frame (failed synchr.): 0
[21:28:35.011] <TB3> INFO: idle data (no TBM trl): 0
[21:28:35.011] <TB3> INFO: no data (only TBM hdr): 0
[21:28:35.011] <TB3> INFO: TBM errors: 0
[21:28:35.011] <TB3> INFO: flawed TBM headers: 0
[21:28:35.011] <TB3> INFO: flawed TBM trailers: 0
[21:28:35.011] <TB3> INFO: event ID mismatches: 0
[21:28:35.011] <TB3> INFO: ROC errors: 0
[21:28:35.011] <TB3> INFO: missing ROC header(s): 0
[21:28:35.011] <TB3> INFO: misplaced readback start: 0
[21:28:35.011] <TB3> INFO: Pixel decoding errors: 0
[21:28:35.011] <TB3> INFO: pixel data incomplete: 0
[21:28:35.011] <TB3> INFO: pixel address: 0
[21:28:35.011] <TB3> INFO: pulse height fill bit: 0
[21:28:35.011] <TB3> INFO: buffer corruption: 0
[21:28:35.635] <TB3> INFO: ######################################################################
[21:28:35.635] <TB3> INFO: PixTestPhOptimization::doTest() Ntrig = 10
[21:28:35.635] <TB3> INFO: ######################################################################
[21:28:35.877] <TB3> INFO: Expecting 41600 events.
[21:28:39.336] <TB3> INFO: 41600 events read in total (2867ms).
[21:28:39.336] <TB3> INFO: Test took 3700ms.
[21:28:39.859] <TB3> INFO: Expecting 41600 events.
[21:28:43.394] <TB3> INFO: 41600 events read in total (2944ms).
[21:28:43.394] <TB3> INFO: Test took 3855ms.
[21:28:43.684] <TB3> INFO: Expecting 41600 events.
[21:28:47.244] <TB3> INFO: 41600 events read in total (2969ms).
[21:28:47.244] <TB3> INFO: Test took 3826ms.
[21:28:47.533] <TB3> INFO: Expecting 41600 events.
[21:28:51.105] <TB3> INFO: 41600 events read in total (2980ms).
[21:28:51.106] <TB3> INFO: Test took 3837ms.
[21:28:51.395] <TB3> INFO: Expecting 41600 events.
[21:28:55.162] <TB3> INFO: 41600 events read in total (3175ms).
[21:28:55.162] <TB3> INFO: Test took 4032ms.
[21:28:55.451] <TB3> INFO: Expecting 41600 events.
[21:28:59.105] <TB3> INFO: 41600 events read in total (3062ms).
[21:28:59.106] <TB3> INFO: Test took 3920ms.
[21:28:59.400] <TB3> INFO: Expecting 41600 events.
[21:29:03.146] <TB3> INFO: 41600 events read in total (3154ms).
[21:29:03.147] <TB3> INFO: Test took 4012ms.
[21:29:03.449] <TB3> INFO: Expecting 41600 events.
[21:29:07.024] <TB3> INFO: 41600 events read in total (2983ms).
[21:29:07.025] <TB3> INFO: Test took 3849ms.
[21:29:07.370] <TB3> INFO: Expecting 41600 events.
[21:29:11.023] <TB3> INFO: 41600 events read in total (3061ms).
[21:29:11.024] <TB3> INFO: Test took 3970ms.
[21:29:11.322] <TB3> INFO: Expecting 41600 events.
[21:29:14.887] <TB3> INFO: 41600 events read in total (2973ms).
[21:29:14.888] <TB3> INFO: Test took 3840ms.
[21:29:15.234] <TB3> INFO: Expecting 41600 events.
[21:29:18.827] <TB3> INFO: 41600 events read in total (3001ms).
[21:29:18.829] <TB3> INFO: Test took 3912ms.
[21:29:19.173] <TB3> INFO: Expecting 41600 events.
[21:29:22.946] <TB3> INFO: 41600 events read in total (3181ms).
[21:29:22.946] <TB3> INFO: Test took 4088ms.
[21:29:23.287] <TB3> INFO: Expecting 41600 events.
[21:29:26.846] <TB3> INFO: 41600 events read in total (2968ms).
[21:29:26.847] <TB3> INFO: Test took 3877ms.
[21:29:27.193] <TB3> INFO: Expecting 41600 events.
[21:29:30.829] <TB3> INFO: 41600 events read in total (3044ms).
[21:29:30.830] <TB3> INFO: Test took 3954ms.
[21:29:31.118] <TB3> INFO: Expecting 41600 events.
[21:29:34.621] <TB3> INFO: 41600 events read in total (2911ms).
[21:29:34.622] <TB3> INFO: Test took 3768ms.
[21:29:34.910] <TB3> INFO: Expecting 41600 events.
[21:29:38.597] <TB3> INFO: 41600 events read in total (3095ms).
[21:29:38.598] <TB3> INFO: Test took 3952ms.
[21:29:38.888] <TB3> INFO: Expecting 41600 events.
[21:29:42.661] <TB3> INFO: 41600 events read in total (3181ms).
[21:29:42.662] <TB3> INFO: Test took 4038ms.
[21:29:43.007] <TB3> INFO: Expecting 41600 events.
[21:29:46.702] <TB3> INFO: 41600 events read in total (3104ms).
[21:29:46.703] <TB3> INFO: Test took 4012ms.
[21:29:47.048] <TB3> INFO: Expecting 41600 events.
[21:29:50.864] <TB3> INFO: 41600 events read in total (3224ms).
[21:29:50.865] <TB3> INFO: Test took 4134ms.
[21:29:51.159] <TB3> INFO: Expecting 41600 events.
[21:29:54.783] <TB3> INFO: 41600 events read in total (3032ms).
[21:29:54.784] <TB3> INFO: Test took 3890ms.
[21:29:55.073] <TB3> INFO: Expecting 41600 events.
[21:29:58.891] <TB3> INFO: 41600 events read in total (3226ms).
[21:29:58.892] <TB3> INFO: Test took 4084ms.
[21:29:59.235] <TB3> INFO: Expecting 41600 events.
[21:30:02.940] <TB3> INFO: 41600 events read in total (3113ms).
[21:30:02.941] <TB3> INFO: Test took 4020ms.
[21:30:03.235] <TB3> INFO: Expecting 41600 events.
[21:30:07.008] <TB3> INFO: 41600 events read in total (3182ms).
[21:30:07.008] <TB3> INFO: Test took 4038ms.
[21:30:07.297] <TB3> INFO: Expecting 41600 events.
[21:30:10.984] <TB3> INFO: 41600 events read in total (3095ms).
[21:30:10.985] <TB3> INFO: Test took 3952ms.
[21:30:11.274] <TB3> INFO: Expecting 41600 events.
[21:30:14.834] <TB3> INFO: 41600 events read in total (2968ms).
[21:30:14.835] <TB3> INFO: Test took 3826ms.
[21:30:15.128] <TB3> INFO: Expecting 41600 events.
[21:30:18.770] <TB3> INFO: 41600 events read in total (3050ms).
[21:30:18.771] <TB3> INFO: Test took 3908ms.
[21:30:19.112] <TB3> INFO: Expecting 41600 events.
[21:30:22.918] <TB3> INFO: 41600 events read in total (3214ms).
[21:30:22.918] <TB3> INFO: Test took 4123ms.
[21:30:23.261] <TB3> INFO: Expecting 41600 events.
[21:30:26.002] <TB3> INFO: 41600 events read in total (3149ms).
[21:30:27.003] <TB3> INFO: Test took 4059ms.
[21:30:27.292] <TB3> INFO: Expecting 2560 events.
[21:30:28.179] <TB3> INFO: 2560 events read in total (295ms).
[21:30:28.179] <TB3> INFO: Test took 1164ms.
[21:30:28.487] <TB3> INFO: Expecting 2560 events.
[21:30:29.373] <TB3> INFO: 2560 events read in total (294ms).
[21:30:29.373] <TB3> INFO: Test took 1193ms.
[21:30:29.680] <TB3> INFO: Expecting 2560 events.
[21:30:30.570] <TB3> INFO: 2560 events read in total (298ms).
[21:30:30.570] <TB3> INFO: Test took 1197ms.
[21:30:30.878] <TB3> INFO: Expecting 2560 events.
[21:30:31.771] <TB3> INFO: 2560 events read in total (301ms).
[21:30:31.771] <TB3> INFO: Test took 1201ms.
[21:30:32.079] <TB3> INFO: Expecting 2560 events.
[21:30:32.960] <TB3> INFO: 2560 events read in total (289ms).
[21:30:32.961] <TB3> INFO: Test took 1189ms.
[21:30:33.268] <TB3> INFO: Expecting 2560 events.
[21:30:34.150] <TB3> INFO: 2560 events read in total (290ms).
[21:30:34.150] <TB3> INFO: Test took 1189ms.
[21:30:34.458] <TB3> INFO: Expecting 2560 events.
[21:30:35.342] <TB3> INFO: 2560 events read in total (292ms).
[21:30:35.342] <TB3> INFO: Test took 1191ms.
[21:30:35.650] <TB3> INFO: Expecting 2560 events.
[21:30:36.532] <TB3> INFO: 2560 events read in total (290ms).
[21:30:36.532] <TB3> INFO: Test took 1190ms.
[21:30:36.840] <TB3> INFO: Expecting 2560 events.
[21:30:37.728] <TB3> INFO: 2560 events read in total (296ms).
[21:30:37.728] <TB3> INFO: Test took 1195ms.
[21:30:38.036] <TB3> INFO: Expecting 2560 events.
[21:30:38.918] <TB3> INFO: 2560 events read in total (291ms).
[21:30:38.919] <TB3> INFO: Test took 1190ms.
[21:30:39.227] <TB3> INFO: Expecting 2560 events.
[21:30:40.110] <TB3> INFO: 2560 events read in total (291ms).
[21:30:40.110] <TB3> INFO: Test took 1191ms.
[21:30:40.418] <TB3> INFO: Expecting 2560 events.
[21:30:41.298] <TB3> INFO: 2560 events read in total (288ms).
[21:30:41.298] <TB3> INFO: Test took 1188ms.
[21:30:41.606] <TB3> INFO: Expecting 2560 events.
[21:30:42.492] <TB3> INFO: 2560 events read in total (294ms).
[21:30:42.492] <TB3> INFO: Test took 1193ms.
[21:30:42.800] <TB3> INFO: Expecting 2560 events.
[21:30:43.693] <TB3> INFO: 2560 events read in total (301ms).
[21:30:43.694] <TB3> INFO: Test took 1202ms.
[21:30:43.002] <TB3> INFO: Expecting 2560 events.
[21:30:44.891] <TB3> INFO: 2560 events read in total (298ms).
[21:30:44.891] <TB3> INFO: Test took 1197ms.
[21:30:45.199] <TB3> INFO: Expecting 2560 events.
[21:30:46.086] <TB3> INFO: 2560 events read in total (295ms).
[21:30:46.086] <TB3> INFO: Test took 1195ms.
[21:30:46.090] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[21:30:46.395] <TB3> INFO: Expecting 655360 events.
[21:31:01.375] <TB3> INFO: 655360 events read in total (14388ms).
[21:31:01.387] <TB3> INFO: Expecting 655360 events.
[21:31:16.243] <TB3> INFO: 655360 events read in total (14453ms).
[21:31:16.260] <TB3> INFO: Expecting 655360 events.
[21:31:31.195] <TB3> INFO: 655360 events read in total (14532ms).
[21:31:31.215] <TB3> INFO: Expecting 655360 events.
[21:31:45.989] <TB3> INFO: 655360 events read in total (14371ms).
[21:31:46.015] <TB3> INFO: Expecting 655360 events.
[21:32:00.993] <TB3> INFO: 655360 events read in total (14575ms).
[21:32:01.036] <TB3> INFO: Expecting 655360 events.
[21:32:15.915] <TB3> INFO: 655360 events read in total (14476ms).
[21:32:15.949] <TB3> INFO: Expecting 655360 events.
[21:32:30.905] <TB3> INFO: 655360 events read in total (14553ms).
[21:32:30.944] <TB3> INFO: Expecting 655360 events.
[21:32:45.768] <TB3> INFO: 655360 events read in total (14421ms).
[21:32:45.812] <TB3> INFO: Expecting 655360 events.
[21:33:00.777] <TB3> INFO: 655360 events read in total (14562ms).
[21:33:00.829] <TB3> INFO: Expecting 655360 events.
[21:33:15.616] <TB3> INFO: 655360 events read in total (14384ms).
[21:33:15.672] <TB3> INFO: Expecting 655360 events.
[21:33:30.593] <TB3> INFO: 655360 events read in total (14518ms).
[21:33:30.648] <TB3> INFO: Expecting 655360 events.
[21:33:45.576] <TB3> INFO: 655360 events read in total (14525ms).
[21:33:45.669] <TB3> INFO: Expecting 655360 events.
[21:34:00.679] <TB3> INFO: 655360 events read in total (14607ms).
[21:34:00.746] <TB3> INFO: Expecting 655360 events.
[21:34:15.519] <TB3> INFO: 655360 events read in total (14370ms).
[21:34:15.591] <TB3> INFO: Expecting 655360 events.
[21:34:30.708] <TB3> INFO: 655360 events read in total (14714ms).
[21:34:30.820] <TB3> INFO: Expecting 655360 events.
[21:34:45.823] <TB3> INFO: 655360 events read in total (14600ms).
[21:34:45.905] <TB3> INFO: Test took 239815ms.
[21:34:45.995] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[21:34:46.256] <TB3> INFO: Expecting 655360 events.
[21:35:00.918] <TB3> INFO: 655360 events read in total (14070ms).
[21:35:00.931] <TB3> INFO: Expecting 655360 events.
[21:35:15.606] <TB3> INFO: 655360 events read in total (14272ms).
[21:35:15.622] <TB3> INFO: Expecting 655360 events.
[21:35:30.054] <TB3> INFO: 655360 events read in total (14029ms).
[21:35:30.075] <TB3> INFO: Expecting 655360 events.
[21:35:45.089] <TB3> INFO: 655360 events read in total (14611ms).
[21:35:45.125] <TB3> INFO: Expecting 655360 events.
[21:35:59.999] <TB3> INFO: 655360 events read in total (14471ms).
[21:36:00.027] <TB3> INFO: Expecting 655360 events.
[21:36:14.696] <TB3> INFO: 655360 events read in total (14266ms).
[21:36:14.730] <TB3> INFO: Expecting 655360 events.
[21:36:29.418] <TB3> INFO: 655360 events read in total (14285ms).
[21:36:29.455] <TB3> INFO: Expecting 655360 events.
[21:36:44.263] <TB3> INFO: 655360 events read in total (14405ms).
[21:36:44.326] <TB3> INFO: Expecting 655360 events.
[21:36:59.192] <TB3> INFO: 655360 events read in total (14462ms).
[21:36:59.265] <TB3> INFO: Expecting 655360 events.
[21:37:13.795] <TB3> INFO: 655360 events read in total (14127ms).
[21:37:13.849] <TB3> INFO: Expecting 655360 events.
[21:37:28.734] <TB3> INFO: 655360 events read in total (14482ms).
[21:37:28.819] <TB3> INFO: Expecting 655360 events.
[21:37:43.387] <TB3> INFO: 655360 events read in total (14165ms).
[21:37:43.447] <TB3> INFO: Expecting 655360 events.
[21:37:58.059] <TB3> INFO: 655360 events read in total (14209ms).
[21:37:58.123] <TB3> INFO: Expecting 655360 events.
[21:38:13.021] <TB3> INFO: 655360 events read in total (14495ms).
[21:38:13.091] <TB3> INFO: Expecting 655360 events.
[21:38:27.950] <TB3> INFO: 655360 events read in total (14456ms).
[21:38:28.024] <TB3> INFO: Expecting 655360 events.
[21:38:42.555] <TB3> INFO: 655360 events read in total (14128ms).
[21:38:42.640] <TB3> INFO: Test took 236645ms.
[21:38:42.804] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[21:38:42.810] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[21:38:42.816] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[21:38:42.822] <TB3> INFO: safety margin for low PH: adding 1, margin is now 21
[21:38:42.827] <TB3> INFO: safety margin for low PH: adding 2, margin is now 22
[21:38:42.833] <TB3> INFO: safety margin for low PH: adding 3, margin is now 23
[21:38:42.839] <TB3> INFO: safety margin for low PH: adding 4, margin is now 24
[21:38:42.846] <TB3> INFO: safety margin for low PH: adding 5, margin is now 25
[21:38:42.852] <TB3> INFO: safety margin for low PH: adding 6, margin is now 26
[21:38:42.858] <TB3> INFO: safety margin for low PH: adding 7, margin is now 27
[21:38:42.864] <TB3> INFO: safety margin for low PH: adding 8, margin is now 28
[21:38:42.870] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[21:38:42.876] <TB3> INFO: safety margin for low PH: adding 1, margin is now 21
[21:38:42.882] <TB3> INFO: safety margin for low PH: adding 2, margin is now 22
[21:38:42.888] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[21:38:42.895] <TB3> INFO: safety margin for low PH: adding 1, margin is now 21
[21:38:42.901] <TB3> INFO: safety margin for low PH: adding 2, margin is now 22
[21:38:42.908] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[21:38:42.914] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[21:38:42.920] <TB3> INFO: safety margin for low PH: adding 1, margin is now 21
[21:38:42.926] <TB3> INFO: safety margin for low PH: adding 2, margin is now 22
[21:38:42.932] <TB3> INFO: safety margin for low PH: adding 3, margin is now 23
[21:38:42.939] <TB3> INFO: safety margin for low PH: adding 4, margin is now 24
[21:38:42.945] <TB3> INFO: safety margin for low PH: adding 5, margin is now 25
[21:38:42.951] <TB3> INFO: safety margin for low PH: adding 6, margin is now 26
[21:38:42.957] <TB3> INFO: safety margin for low PH: adding 7, margin is now 27
[21:38:42.963] <TB3> INFO: safety margin for low PH: adding 8, margin is now 28
[21:38:42.969] <TB3> INFO: safety margin for low PH: adding 9, margin is now 29
[21:38:42.975] <TB3> INFO: safety margin for low PH: adding 10, margin is now 30
[21:38:42.981] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[21:38:42.987] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[21:38:42.993] <TB3> INFO: safety margin for low PH: adding 1, margin is now 21
[21:38:42.999] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[21:38:43.005] <TB3> INFO: safety margin for low PH: adding 1, margin is now 21
[21:38:43.011] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[21:38:43.017] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[21:38:43.023] <TB3> INFO: safety margin for low PH: adding 1, margin is now 21
[21:38:43.029] <TB3> INFO: safety margin for low PH: adding 2, margin is now 22
[21:38:43.035] <TB3> INFO: safety margin for low PH: adding 3, margin is now 23
[21:38:43.041] <TB3> INFO: safety margin for low PH: adding 4, margin is now 24
[21:38:43.047] <TB3> INFO: safety margin for low PH: adding 5, margin is now 25
[21:38:43.053] <TB3> INFO: safety margin for low PH: adding 6, margin is now 26
[21:38:43.059] <TB3> INFO: safety margin for low PH: adding 7, margin is now 27
[21:38:43.065] <TB3> INFO: safety margin for low PH: adding 8, margin is now 28
[21:38:43.071] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[21:38:43.076] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[21:38:43.083] <TB3> INFO: safety margin for low PH: adding 1, margin is now 21
[21:38:43.089] <TB3> INFO: safety margin for low PH: adding 2, margin is now 22
[21:38:43.094] <TB3> INFO: safety margin for low PH: adding 3, margin is now 23
[21:38:43.100] <TB3> INFO: safety margin for low PH: adding 4, margin is now 24
[21:38:43.106] <TB3> INFO: safety margin for low PH: adding 5, margin is now 25
[21:38:43.112] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[21:38:43.118] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[21:38:43.124] <TB3> INFO: safety margin for low PH: adding 1, margin is now 21
[21:38:43.130] <TB3> INFO: safety margin for low PH: adding 2, margin is now 22
[21:38:43.136] <TB3> INFO: safety margin for low PH: adding 3, margin is now 23
[21:38:43.142] <TB3> INFO: safety margin for low PH: adding 4, margin is now 24
[21:38:43.147] <TB3> INFO: safety margin for low PH: adding 5, margin is now 25
[21:38:43.153] <TB3> INFO: safety margin for low PH: adding 6, margin is now 26
[21:38:43.159] <TB3> INFO: safety margin for low PH: adding 7, margin is now 27
[21:38:43.194] <TB3> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1008_FullQualification_2016-10-16_17h47m_1476632865//003_Fulltest_p17//dacParameters35_C0.dat
[21:38:43.194] <TB3> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1008_FullQualification_2016-10-16_17h47m_1476632865//003_Fulltest_p17//dacParameters35_C1.dat
[21:38:43.195] <TB3> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1008_FullQualification_2016-10-16_17h47m_1476632865//003_Fulltest_p17//dacParameters35_C2.dat
[21:38:43.195] <TB3> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1008_FullQualification_2016-10-16_17h47m_1476632865//003_Fulltest_p17//dacParameters35_C3.dat
[21:38:43.195] <TB3> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1008_FullQualification_2016-10-16_17h47m_1476632865//003_Fulltest_p17//dacParameters35_C4.dat
[21:38:43.195] <TB3> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1008_FullQualification_2016-10-16_17h47m_1476632865//003_Fulltest_p17//dacParameters35_C5.dat
[21:38:43.195] <TB3> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1008_FullQualification_2016-10-16_17h47m_1476632865//003_Fulltest_p17//dacParameters35_C6.dat
[21:38:43.195] <TB3> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1008_FullQualification_2016-10-16_17h47m_1476632865//003_Fulltest_p17//dacParameters35_C7.dat
[21:38:43.195] <TB3> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1008_FullQualification_2016-10-16_17h47m_1476632865//003_Fulltest_p17//dacParameters35_C8.dat
[21:38:43.195] <TB3> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1008_FullQualification_2016-10-16_17h47m_1476632865//003_Fulltest_p17//dacParameters35_C9.dat
[21:38:43.195] <TB3> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1008_FullQualification_2016-10-16_17h47m_1476632865//003_Fulltest_p17//dacParameters35_C10.dat
[21:38:43.195] <TB3> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1008_FullQualification_2016-10-16_17h47m_1476632865//003_Fulltest_p17//dacParameters35_C11.dat
[21:38:43.196] <TB3> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1008_FullQualification_2016-10-16_17h47m_1476632865//003_Fulltest_p17//dacParameters35_C12.dat
[21:38:43.196] <TB3> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1008_FullQualification_2016-10-16_17h47m_1476632865//003_Fulltest_p17//dacParameters35_C13.dat
[21:38:43.196] <TB3> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1008_FullQualification_2016-10-16_17h47m_1476632865//003_Fulltest_p17//dacParameters35_C14.dat
[21:38:43.196] <TB3> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1008_FullQualification_2016-10-16_17h47m_1476632865//003_Fulltest_p17//dacParameters35_C15.dat
[21:38:43.440] <TB3> INFO: Expecting 41600 events.
[21:38:46.587] <TB3> INFO: 41600 events read in total (2555ms).
[21:38:46.588] <TB3> INFO: Test took 3389ms.
[21:38:47.034] <TB3> INFO: Expecting 41600 events.
[21:38:50.094] <TB3> INFO: 41600 events read in total (2468ms).
[21:38:50.095] <TB3> INFO: Test took 3296ms.
[21:38:50.542] <TB3> INFO: Expecting 41600 events.
[21:38:53.682] <TB3> INFO: 41600 events read in total (2548ms).
[21:38:53.682] <TB3> INFO: Test took 3376ms.
[21:38:53.898] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[21:38:53.987] <TB3> INFO: Expecting 2560 events.
[21:38:54.873] <TB3> INFO: 2560 events read in total (294ms).
[21:38:54.873] <TB3> INFO: Test took 975ms.
[21:38:54.875] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[21:38:55.182] <TB3> INFO: Expecting 2560 events.
[21:38:56.069] <TB3> INFO: 2560 events read in total (296ms).
[21:38:56.069] <TB3> INFO: Test took 1194ms.
[21:38:56.072] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[21:38:56.377] <TB3> INFO: Expecting 2560 events.
[21:38:57.264] <TB3> INFO: 2560 events read in total (296ms).
[21:38:57.264] <TB3> INFO: Test took 1193ms.
[21:38:57.267] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[21:38:57.573] <TB3> INFO: Expecting 2560 events.
[21:38:58.466] <TB3> INFO: 2560 events read in total (301ms).
[21:38:58.467] <TB3> INFO: Test took 1200ms.
[21:38:58.469] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[21:38:58.775] <TB3> INFO: Expecting 2560 events.
[21:38:59.664] <TB3> INFO: 2560 events read in total (297ms).
[21:38:59.664] <TB3> INFO: Test took 1195ms.
[21:38:59.666] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[21:38:59.972] <TB3> INFO: Expecting 2560 events.
[21:39:00.859] <TB3> INFO: 2560 events read in total (295ms).
[21:39:00.859] <TB3> INFO: Test took 1194ms.
[21:39:00.861] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[21:39:01.168] <TB3> INFO: Expecting 2560 events.
[21:39:02.055] <TB3> INFO: 2560 events read in total (295ms).
[21:39:02.055] <TB3> INFO: Test took 1194ms.
[21:39:02.057] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[21:39:02.364] <TB3> INFO: Expecting 2560 events.
[21:39:03.251] <TB3> INFO: 2560 events read in total (295ms).
[21:39:03.251] <TB3> INFO: Test took 1194ms.
[21:39:03.253] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[21:39:03.559] <TB3> INFO: Expecting 2560 events.
[21:39:04.445] <TB3> INFO: 2560 events read in total (294ms).
[21:39:04.446] <TB3> INFO: Test took 1193ms.
[21:39:04.448] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[21:39:04.754] <TB3> INFO: Expecting 2560 events.
[21:39:05.636] <TB3> INFO: 2560 events read in total (291ms).
[21:39:05.637] <TB3> INFO: Test took 1189ms.
[21:39:05.638] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[21:39:05.945] <TB3> INFO: Expecting 2560 events.
[21:39:06.834] <TB3> INFO: 2560 events read in total (297ms).
[21:39:06.834] <TB3> INFO: Test took 1196ms.
[21:39:06.837] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[21:39:07.142] <TB3> INFO: Expecting 2560 events.
[21:39:08.023] <TB3> INFO: 2560 events read in total (289ms).
[21:39:08.023] <TB3> INFO: Test took 1186ms.
[21:39:08.026] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[21:39:08.331] <TB3> INFO: Expecting 2560 events.
[21:39:09.212] <TB3> INFO: 2560 events read in total (289ms).
[21:39:09.212] <TB3> INFO: Test took 1187ms.
[21:39:09.214] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[21:39:09.520] <TB3> INFO: Expecting 2560 events.
[21:39:10.406] <TB3> INFO: 2560 events read in total (294ms).
[21:39:10.407] <TB3> INFO: Test took 1193ms.
[21:39:10.409] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[21:39:10.714] <TB3> INFO: Expecting 2560 events.
[21:39:11.604] <TB3> INFO: 2560 events read in total (298ms).
[21:39:11.604] <TB3> INFO: Test took 1195ms.
[21:39:11.606] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[21:39:11.913] <TB3> INFO: Expecting 2560 events.
[21:39:12.792] <TB3> INFO: 2560 events read in total (288ms).
[21:39:12.793] <TB3> INFO: Test took 1187ms.
[21:39:12.795] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[21:39:13.101] <TB3> INFO: Expecting 2560 events.
[21:39:13.984] <TB3> INFO: 2560 events read in total (291ms).
[21:39:13.984] <TB3> INFO: Test took 1189ms.
[21:39:13.986] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[21:39:14.292] <TB3> INFO: Expecting 2560 events.
[21:39:15.180] <TB3> INFO: 2560 events read in total (296ms).
[21:39:15.181] <TB3> INFO: Test took 1195ms.
[21:39:15.182] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[21:39:15.489] <TB3> INFO: Expecting 2560 events.
[21:39:16.376] <TB3> INFO: 2560 events read in total (295ms).
[21:39:16.376] <TB3> INFO: Test took 1194ms.
[21:39:16.378] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[21:39:16.685] <TB3> INFO: Expecting 2560 events.
[21:39:17.572] <TB3> INFO: 2560 events read in total (296ms).
[21:39:17.572] <TB3> INFO: Test took 1194ms.
[21:39:17.575] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[21:39:17.881] <TB3> INFO: Expecting 2560 events.
[21:39:18.762] <TB3> INFO: 2560 events read in total (289ms).
[21:39:18.762] <TB3> INFO: Test took 1188ms.
[21:39:18.764] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[21:39:19.070] <TB3> INFO: Expecting 2560 events.
[21:39:19.959] <TB3> INFO: 2560 events read in total (297ms).
[21:39:19.960] <TB3> INFO: Test took 1196ms.
[21:39:19.962] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[21:39:20.268] <TB3> INFO: Expecting 2560 events.
[21:39:21.156] <TB3> INFO: 2560 events read in total (297ms).
[21:39:21.156] <TB3> INFO: Test took 1194ms.
[21:39:21.159] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[21:39:21.464] <TB3> INFO: Expecting 2560 events.
[21:39:22.351] <TB3> INFO: 2560 events read in total (295ms).
[21:39:22.352] <TB3> INFO: Test took 1193ms.
[21:39:22.353] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[21:39:22.660] <TB3> INFO: Expecting 2560 events.
[21:39:23.549] <TB3> INFO: 2560 events read in total (297ms).
[21:39:23.550] <TB3> INFO: Test took 1197ms.
[21:39:23.551] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[21:39:23.858] <TB3> INFO: Expecting 2560 events.
[21:39:24.745] <TB3> INFO: 2560 events read in total (295ms).
[21:39:24.745] <TB3> INFO: Test took 1194ms.
[21:39:24.747] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[21:39:25.053] <TB3> INFO: Expecting 2560 events.
[21:39:25.947] <TB3> INFO: 2560 events read in total (302ms).
[21:39:25.947] <TB3> INFO: Test took 1200ms.
[21:39:25.950] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[21:39:26.255] <TB3> INFO: Expecting 2560 events.
[21:39:27.143] <TB3> INFO: 2560 events read in total (296ms).
[21:39:27.143] <TB3> INFO: Test took 1193ms.
[21:39:27.145] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[21:39:27.451] <TB3> INFO: Expecting 2560 events.
[21:39:28.339] <TB3> INFO: 2560 events read in total (296ms).
[21:39:28.339] <TB3> INFO: Test took 1194ms.
[21:39:28.341] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[21:39:28.647] <TB3> INFO: Expecting 2560 events.
[21:39:29.539] <TB3> INFO: 2560 events read in total (300ms).
[21:39:29.539] <TB3> INFO: Test took 1198ms.
[21:39:29.541] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[21:39:29.848] <TB3> INFO: Expecting 2560 events.
[21:39:30.735] <TB3> INFO: 2560 events read in total (296ms).
[21:39:30.735] <TB3> INFO: Test took 1194ms.
[21:39:30.737] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[21:39:31.043] <TB3> INFO: Expecting 2560 events.
[21:39:31.931] <TB3> INFO: 2560 events read in total (296ms).
[21:39:31.931] <TB3> INFO: Test took 1194ms.
[21:39:32.396] <TB3> INFO: PixTestPhOptimization::doTest() done, duration: 656 seconds
[21:39:32.397] <TB3> INFO: PH scale (per ROC): 38 52 42 41 31 44 30 47 47 37 38 43 48 43 46 36
[21:39:32.397] <TB3> INFO: PH offset (per ROC): 99 122 107 109 91 85 105 122 119 96 94 112 125 96 134 107
[21:39:32.406] <TB3> INFO: Decoding statistics:
[21:39:32.407] <TB3> INFO: General information:
[21:39:32.407] <TB3> INFO: 16bit words read: 127882
[21:39:32.407] <TB3> INFO: valid events total: 20480
[21:39:32.407] <TB3> INFO: empty events: 17979
[21:39:32.407] <TB3> INFO: valid events with pixels: 2501
[21:39:32.407] <TB3> INFO: valid pixel hits: 2501
[21:39:32.407] <TB3> INFO: Event errors: 0
[21:39:32.407] <TB3> INFO: start marker: 0
[21:39:32.407] <TB3> INFO: stop marker: 0
[21:39:32.407] <TB3> INFO: overflow: 0
[21:39:32.407] <TB3> INFO: invalid 5bit words: 0
[21:39:32.407] <TB3> INFO: invalid XOR eye diagram: 0
[21:39:32.407] <TB3> INFO: frame (failed synchr.): 0
[21:39:32.407] <TB3> INFO: idle data (no TBM trl): 0
[21:39:32.407] <TB3> INFO: no data (only TBM hdr): 0
[21:39:32.407] <TB3> INFO: TBM errors: 0
[21:39:32.407] <TB3> INFO: flawed TBM headers: 0
[21:39:32.407] <TB3> INFO: flawed TBM trailers: 0
[21:39:32.407] <TB3> INFO: event ID mismatches: 0
[21:39:32.407] <TB3> INFO: ROC errors: 0
[21:39:32.407] <TB3> INFO: missing ROC header(s): 0
[21:39:32.407] <TB3> INFO: misplaced readback start: 0
[21:39:32.407] <TB3> INFO: Pixel decoding errors: 0
[21:39:32.407] <TB3> INFO: pixel data incomplete: 0
[21:39:32.407] <TB3> INFO: pixel address: 0
[21:39:32.407] <TB3> INFO: pulse height fill bit: 0
[21:39:32.407] <TB3> INFO: buffer corruption: 0
[21:39:32.654] <TB3> INFO: ######################################################################
[21:39:32.654] <TB3> INFO: PixTestGainPedestal::fullTest() ntrig = 10
[21:39:32.654] <TB3> INFO: ######################################################################
[21:39:32.671] <TB3> INFO: scanning low vcal = 10
[21:39:32.962] <TB3> INFO: Expecting 41600 events.
[21:39:36.548] <TB3> INFO: 41600 events read in total (2994ms).
[21:39:36.548] <TB3> INFO: Test took 3877ms.
[21:39:36.549] <TB3> INFO: scanning low vcal = 20
[21:39:36.866] <TB3> INFO: Expecting 41600 events.
[21:39:40.488] <TB3> INFO: 41600 events read in total (3030ms).
[21:39:40.488] <TB3> INFO: Test took 3939ms.
[21:39:40.490] <TB3> INFO: scanning low vcal = 30
[21:39:40.788] <TB3> INFO: Expecting 41600 events.
[21:39:44.526] <TB3> INFO: 41600 events read in total (3146ms).
[21:39:44.527] <TB3> INFO: Test took 4037ms.
[21:39:44.529] <TB3> INFO: scanning low vcal = 40
[21:39:44.806] <TB3> INFO: Expecting 41600 events.
[21:39:48.787] <TB3> INFO: 41600 events read in total (3389ms).
[21:39:48.788] <TB3> INFO: Test took 4259ms.
[21:39:48.790] <TB3> INFO: scanning low vcal = 50
[21:39:49.070] <TB3> INFO: Expecting 41600 events.
[21:39:53.137] <TB3> INFO: 41600 events read in total (3475ms).
[21:39:53.138] <TB3> INFO: Test took 4347ms.
[21:39:53.141] <TB3> INFO: scanning low vcal = 60
[21:39:53.417] <TB3> INFO: Expecting 41600 events.
[21:39:57.396] <TB3> INFO: 41600 events read in total (3387ms).
[21:39:57.397] <TB3> INFO: Test took 4256ms.
[21:39:57.400] <TB3> INFO: scanning low vcal = 70
[21:39:57.677] <TB3> INFO: Expecting 41600 events.
[21:40:01.697] <TB3> INFO: 41600 events read in total (3428ms).
[21:40:01.698] <TB3> INFO: Test took 4298ms.
[21:40:01.701] <TB3> INFO: scanning low vcal = 80
[21:40:01.977] <TB3> INFO: Expecting 41600 events.
[21:40:06.041] <TB3> INFO: 41600 events read in total (3472ms).
[21:40:06.042] <TB3> INFO: Test took 4341ms.
[21:40:06.045] <TB3> INFO: scanning low vcal = 90
[21:40:06.359] <TB3> INFO: Expecting 41600 events.
[21:40:10.345] <TB3> INFO: 41600 events read in total (3394ms).
[21:40:10.346] <TB3> INFO: Test took 4301ms.
[21:40:10.349] <TB3> INFO: scanning low vcal = 100
[21:40:10.625] <TB3> INFO: Expecting 41600 events.
[21:40:14.649] <TB3> INFO: 41600 events read in total (3432ms).
[21:40:14.649] <TB3> INFO: Test took 4300ms.
[21:40:14.652] <TB3> INFO: scanning low vcal = 110
[21:40:14.929] <TB3> INFO: Expecting 41600 events.
[21:40:19.023] <TB3> INFO: 41600 events read in total (3503ms).
[21:40:19.024] <TB3> INFO: Test took 4372ms.
[21:40:19.027] <TB3> INFO: scanning low vcal = 120
[21:40:19.303] <TB3> INFO: Expecting 41600 events.
[21:40:23.378] <TB3> INFO: 41600 events read in total (3483ms).
[21:40:23.378] <TB3> INFO: Test took 4351ms.
[21:40:23.381] <TB3> INFO: scanning low vcal = 130
[21:40:23.658] <TB3> INFO: Expecting 41600 events.
[21:40:27.640] <TB3> INFO: 41600 events read in total (3390ms).
[21:40:27.640] <TB3> INFO: Test took 4259ms.
[21:40:27.643] <TB3> INFO: scanning low vcal = 140
[21:40:27.920] <TB3> INFO: Expecting 41600 events.
[21:40:31.995] <TB3> INFO: 41600 events read in total (3483ms).
[21:40:31.996] <TB3> INFO: Test took 4353ms.
[21:40:31.999] <TB3> INFO: scanning low vcal = 150
[21:40:32.312] <TB3> INFO: Expecting 41600 events.
[21:40:36.350] <TB3> INFO: 41600 events read in total (3446ms).
[21:40:36.351] <TB3> INFO: Test took 4352ms.
[21:40:36.354] <TB3> INFO: scanning low vcal = 160
[21:40:36.630] <TB3> INFO: Expecting 41600 events.
[21:40:40.731] <TB3> INFO: 41600 events read in total (3509ms).
[21:40:40.732] <TB3> INFO: Test took 4378ms.
[21:40:40.735] <TB3> INFO: scanning low vcal = 170
[21:40:41.012] <TB3> INFO: Expecting 41600 events.
[21:40:45.034] <TB3> INFO: 41600 events read in total (3430ms).
[21:40:45.034] <TB3> INFO: Test took 4299ms.
[21:40:45.039] <TB3> INFO: scanning low vcal = 180
[21:40:45.313] <TB3> INFO: Expecting 41600 events.
[21:40:49.316] <TB3> INFO: 41600 events read in total (3411ms).
[21:40:49.316] <TB3> INFO: Test took 4277ms.
[21:40:49.319] <TB3> INFO: scanning low vcal = 190
[21:40:49.596] <TB3> INFO: Expecting 41600 events.
[21:40:53.608] <TB3> INFO: 41600 events read in total (3420ms).
[21:40:53.608] <TB3> INFO: Test took 4289ms.
[21:40:53.611] <TB3> INFO: scanning low vcal = 200
[21:40:53.888] <TB3> INFO: Expecting 41600 events.
[21:40:57.977] <TB3> INFO: 41600 events read in total (3497ms).
[21:40:57.978] <TB3> INFO: Test took 4366ms.
[21:40:57.981] <TB3> INFO: scanning low vcal = 210
[21:40:58.257] <TB3> INFO: Expecting 41600 events.
[21:41:02.279] <TB3> INFO: 41600 events read in total (3430ms).
[21:41:02.279] <TB3> INFO: Test took 4298ms.
[21:41:02.282] <TB3> INFO: scanning low vcal = 220
[21:41:02.599] <TB3> INFO: Expecting 41600 events.
[21:41:06.582] <TB3> INFO: 41600 events read in total (3392ms).
[21:41:06.583] <TB3> INFO: Test took 4301ms.
[21:41:06.586] <TB3> INFO: scanning low vcal = 230
[21:41:06.862] <TB3> INFO: Expecting 41600 events.
[21:41:10.893] <TB3> INFO: 41600 events read in total (3439ms).
[21:41:10.893] <TB3> INFO: Test took 4307ms.
[21:41:10.896] <TB3> INFO: scanning low vcal = 240
[21:41:11.173] <TB3> INFO: Expecting 41600 events.
[21:41:15.243] <TB3> INFO: 41600 events read in total (3478ms).
[21:41:15.244] <TB3> INFO: Test took 4347ms.
[21:41:15.247] <TB3> INFO: scanning low vcal = 250
[21:41:15.563] <TB3> INFO: Expecting 41600 events.
[21:41:19.546] <TB3> INFO: 41600 events read in total (3391ms).
[21:41:19.547] <TB3> INFO: Test took 4300ms.
[21:41:19.551] <TB3> INFO: scanning high vcal = 30 (= 210 in low range)
[21:41:19.827] <TB3> INFO: Expecting 41600 events.
[21:41:23.879] <TB3> INFO: 41600 events read in total (3460ms).
[21:41:23.879] <TB3> INFO: Test took 4328ms.
[21:41:23.882] <TB3> INFO: scanning high vcal = 50 (= 350 in low range)
[21:41:24.197] <TB3> INFO: Expecting 41600 events.
[21:41:28.250] <TB3> INFO: 41600 events read in total (3461ms).
[21:41:28.250] <TB3> INFO: Test took 4367ms.
[21:41:28.254] <TB3> INFO: scanning high vcal = 70 (= 490 in low range)
[21:41:28.530] <TB3> INFO: Expecting 41600 events.
[21:41:32.547] <TB3> INFO: 41600 events read in total (3425ms).
[21:41:32.548] <TB3> INFO: Test took 4294ms.
[21:41:32.551] <TB3> INFO: scanning high vcal = 90 (= 630 in low range)
[21:41:32.842] <TB3> INFO: Expecting 41600 events.
[21:41:36.857] <TB3> INFO: 41600 events read in total (3423ms).
[21:41:36.858] <TB3> INFO: Test took 4306ms.
[21:41:36.860] <TB3> INFO: scanning high vcal = 200 (= 1400 in low range)
[21:41:37.137] <TB3> INFO: Expecting 41600 events.
[21:41:41.196] <TB3> INFO: 41600 events read in total (3467ms).
[21:41:41.197] <TB3> INFO: Test took 4337ms.
[21:41:41.603] <TB3> INFO: PixTestGainPedestal::measure() done
[21:42:15.034] <TB3> INFO: PixTestGainPedestal::fit() done
[21:42:15.034] <TB3> INFO: non-linearity mean: 0.936 0.983 0.937 0.943 0.922 0.932 0.978 0.973 0.982 0.924 0.926 0.935 0.977 0.957 0.976 0.942
[21:42:15.034] <TB3> INFO: non-linearity RMS: 0.133 0.004 0.072 0.085 0.171 0.119 0.181 0.006 0.003 0.130 0.159 0.115 0.005 0.180 0.006 0.128
[21:42:15.034] <TB3> INFO: write gain/ped parameters into /home/l_tester/david/FullQualification/data/M1008_FullQualification_2016-10-16_17h47m_1476632865//003_Fulltest_p17//phCalibrationFitErr35_C0.dat
[21:42:15.047] <TB3> INFO: write gain/ped parameters into /home/l_tester/david/FullQualification/data/M1008_FullQualification_2016-10-16_17h47m_1476632865//003_Fulltest_p17//phCalibrationFitErr35_C1.dat
[21:42:15.061] <TB3> INFO: write gain/ped parameters into /home/l_tester/david/FullQualification/data/M1008_FullQualification_2016-10-16_17h47m_1476632865//003_Fulltest_p17//phCalibrationFitErr35_C2.dat
[21:42:15.074] <TB3> INFO: write gain/ped parameters into /home/l_tester/david/FullQualification/data/M1008_FullQualification_2016-10-16_17h47m_1476632865//003_Fulltest_p17//phCalibrationFitErr35_C3.dat
[21:42:15.087] <TB3> INFO: write gain/ped parameters into /home/l_tester/david/FullQualification/data/M1008_FullQualification_2016-10-16_17h47m_1476632865//003_Fulltest_p17//phCalibrationFitErr35_C4.dat
[21:42:15.101] <TB3> INFO: write gain/ped parameters into /home/l_tester/david/FullQualification/data/M1008_FullQualification_2016-10-16_17h47m_1476632865//003_Fulltest_p17//phCalibrationFitErr35_C5.dat
[21:42:15.114] <TB3> INFO: write gain/ped parameters into /home/l_tester/david/FullQualification/data/M1008_FullQualification_2016-10-16_17h47m_1476632865//003_Fulltest_p17//phCalibrationFitErr35_C6.dat
[21:42:15.127] <TB3> INFO: write gain/ped parameters into /home/l_tester/david/FullQualification/data/M1008_FullQualification_2016-10-16_17h47m_1476632865//003_Fulltest_p17//phCalibrationFitErr35_C7.dat
[21:42:15.140] <TB3> INFO: write gain/ped parameters into /home/l_tester/david/FullQualification/data/M1008_FullQualification_2016-10-16_17h47m_1476632865//003_Fulltest_p17//phCalibrationFitErr35_C8.dat
[21:42:15.153] <TB3> INFO: write gain/ped parameters into /home/l_tester/david/FullQualification/data/M1008_FullQualification_2016-10-16_17h47m_1476632865//003_Fulltest_p17//phCalibrationFitErr35_C9.dat
[21:42:15.166] <TB3> INFO: write gain/ped parameters into /home/l_tester/david/FullQualification/data/M1008_FullQualification_2016-10-16_17h47m_1476632865//003_Fulltest_p17//phCalibrationFitErr35_C10.dat
[21:42:15.179] <TB3> INFO: write gain/ped parameters into /home/l_tester/david/FullQualification/data/M1008_FullQualification_2016-10-16_17h47m_1476632865//003_Fulltest_p17//phCalibrationFitErr35_C11.dat
[21:42:15.192] <TB3> INFO: write gain/ped parameters into /home/l_tester/david/FullQualification/data/M1008_FullQualification_2016-10-16_17h47m_1476632865//003_Fulltest_p17//phCalibrationFitErr35_C12.dat
[21:42:15.205] <TB3> INFO: write gain/ped parameters into /home/l_tester/david/FullQualification/data/M1008_FullQualification_2016-10-16_17h47m_1476632865//003_Fulltest_p17//phCalibrationFitErr35_C13.dat
[21:42:15.218] <TB3> INFO: write gain/ped parameters into /home/l_tester/david/FullQualification/data/M1008_FullQualification_2016-10-16_17h47m_1476632865//003_Fulltest_p17//phCalibrationFitErr35_C14.dat
[21:42:15.231] <TB3> INFO: write gain/ped parameters into /home/l_tester/david/FullQualification/data/M1008_FullQualification_2016-10-16_17h47m_1476632865//003_Fulltest_p17//phCalibrationFitErr35_C15.dat
[21:42:15.244] <TB3> INFO: PixTestGainPedestal::fullTest() done, duration: 162 seconds
[21:42:15.244] <TB3> INFO: Decoding statistics:
[21:42:15.244] <TB3> INFO: General information:
[21:42:15.244] <TB3> INFO: 16bit words read: 3304932
[21:42:15.244] <TB3> INFO: valid events total: 332800
[21:42:15.244] <TB3> INFO: empty events: 631
[21:42:15.244] <TB3> INFO: valid events with pixels: 332169
[21:42:15.244] <TB3> INFO: valid pixel hits: 654066
[21:42:15.244] <TB3> INFO: Event errors: 0
[21:42:15.244] <TB3> INFO: start marker: 0
[21:42:15.244] <TB3> INFO: stop marker: 0
[21:42:15.244] <TB3> INFO: overflow: 0
[21:42:15.244] <TB3> INFO: invalid 5bit words: 0
[21:42:15.245] <TB3> INFO: invalid XOR eye diagram: 0
[21:42:15.245] <TB3> INFO: frame (failed synchr.): 0
[21:42:15.245] <TB3> INFO: idle data (no TBM trl): 0
[21:42:15.245] <TB3> INFO: no data (only TBM hdr): 0
[21:42:15.245] <TB3> INFO: TBM errors: 0
[21:42:15.245] <TB3> INFO: flawed TBM headers: 0
[21:42:15.245] <TB3> INFO: flawed TBM trailers: 0
[21:42:15.245] <TB3> INFO: event ID mismatches: 0
[21:42:15.245] <TB3> INFO: ROC errors: 0
[21:42:15.245] <TB3> INFO: missing ROC header(s): 0
[21:42:15.245] <TB3> INFO: misplaced readback start: 0
[21:42:15.245] <TB3> INFO: Pixel decoding errors: 0
[21:42:15.245] <TB3> INFO: pixel data incomplete: 0
[21:42:15.245] <TB3> INFO: pixel address: 0
[21:42:15.245] <TB3> INFO: pulse height fill bit: 0
[21:42:15.245] <TB3> INFO: buffer corruption: 0
[21:42:15.260] <TB3> INFO: Decoding statistics:
[21:42:15.260] <TB3> INFO: General information:
[21:42:15.260] <TB3> INFO: 16bit words read: 3434350
[21:42:15.260] <TB3> INFO: valid events total: 353536
[21:42:15.260] <TB3> INFO: empty events: 18866
[21:42:15.260] <TB3> INFO: valid events with pixels: 334670
[21:42:15.260] <TB3> INFO: valid pixel hits: 656567
[21:42:15.260] <TB3> INFO: Event errors: 0
[21:42:15.260] <TB3> INFO: start marker: 0
[21:42:15.260] <TB3> INFO: stop marker: 0
[21:42:15.260] <TB3> INFO: overflow: 0
[21:42:15.260] <TB3> INFO: invalid 5bit words: 0
[21:42:15.260] <TB3> INFO: invalid XOR eye diagram: 0
[21:42:15.260] <TB3> INFO: frame (failed synchr.): 0
[21:42:15.260] <TB3> INFO: idle data (no TBM trl): 0
[21:42:15.260] <TB3> INFO: no data (only TBM hdr): 0
[21:42:15.260] <TB3> INFO: TBM errors: 0
[21:42:15.260] <TB3> INFO: flawed TBM headers: 0
[21:42:15.260] <TB3> INFO: flawed TBM trailers: 0
[21:42:15.260] <TB3> INFO: event ID mismatches: 0
[21:42:15.260] <TB3> INFO: ROC errors: 0
[21:42:15.260] <TB3> INFO: missing ROC header(s): 0
[21:42:15.260] <TB3> INFO: misplaced readback start: 0
[21:42:15.260] <TB3> INFO: Pixel decoding errors: 0
[21:42:15.260] <TB3> INFO: pixel data incomplete: 0
[21:42:15.260] <TB3> INFO: pixel address: 0
[21:42:15.260] <TB3> INFO: pulse height fill bit: 0
[21:42:15.260] <TB3> INFO: buffer corruption: 0
[21:42:15.260] <TB3> INFO: enter test to run
[21:42:15.260] <TB3> INFO: test: exit no parameter change
[21:42:15.383] <TB3> QUIET: Connection to board 126 closed.
[21:42:15.384] <TB3> INFO: pXar: this is the end, my friend