[17:46:15.835] <TB3> INFO: *** Welcome to pxar ***
[17:46:15.839] <TB3> INFO: *** Today: 2016/10/19
[17:46:15.848] <TB3> INFO: *** Version: c8ba-dirty
[17:46:15.848] <TB3> INFO: readRocDacs: /home/sensor/david/FullQualification/data/M1023_FullQualification_2016-10-19_14h52m_1476881545//003_Fulltest_p17//dacParameters_C0.dat .. /home/sensor/david/FullQualification/data/M1023_FullQualification_2016-10-19_14h52m_1476881545//003_Fulltest_p17//dacParameters_C15.dat
[17:46:15.849] <TB3> INFO: readTbmDacs: /home/sensor/david/FullQualification/data/M1023_FullQualification_2016-10-19_14h52m_1476881545//003_Fulltest_p17//tbmParameters_C0a.dat .. /home/sensor/david/FullQualification/data/M1023_FullQualification_2016-10-19_14h52m_1476881545//003_Fulltest_p17//tbmParameters_C1b.dat
[17:46:15.849] <TB3> INFO: readMaskFile: /home/sensor/david/FullQualification/data/M1023_FullQualification_2016-10-19_14h52m_1476881545//003_Fulltest_p17//defaultMaskFile.dat
[17:46:15.849] <TB3> INFO: readTrimFile: /home/sensor/david/FullQualification/data/M1023_FullQualification_2016-10-19_14h52m_1476881545//003_Fulltest_p17//trimParameters_C0.dat .. /home/sensor/david/FullQualification/data/M1023_FullQualification_2016-10-19_14h52m_1476881545//003_Fulltest_p17//trimParameters_C15.dat
[17:46:15.931] <TB3> INFO: clk: 4
[17:46:15.931] <TB3> INFO: ctr: 4
[17:46:15.931] <TB3> INFO: sda: 19
[17:46:15.931] <TB3> INFO: tin: 9
[17:46:15.931] <TB3> INFO: level: 15
[17:46:15.931] <TB3> INFO: triggerdelay: 0
[17:46:15.931] <TB3> QUIET: Instanciating API for pxar v2.1.0+868~g7f4a123
[17:46:15.931] <TB3> INFO: Log level: INFO
[17:46:15.939] <TB3> INFO: Found DTB DTB_WZ4I6J
[17:46:15.950] <TB3> QUIET: Connection to board DTB_WZ4I6J opened.
[17:46:15.952] <TB3> INFO: DTB startup information
--- DTB info------------------------------------------
Board id: 170
HW version: DTB1.2
FW version: 4.6
SW version: 4.7
Options:
USB id: DTB_WZ4I6J
MAC address: 40D8551180AA
Hostname: pixelDTB170
Comment:
------------------------------------------------------
[17:46:15.955] <TB3> INFO: RPC call hashes of host and DTB match: 486171790
[17:46:17.565] <TB3> INFO: DUT info:
[17:46:17.566] <TB3> INFO: The DUT currently contains the following objects:
[17:46:17.566] <TB3> INFO: 4 TBM Cores tbm10c (4 ON)
[17:46:17.566] <TB3> INFO: TBM Core alpha (0): 7 registers set
[17:46:17.566] <TB3> INFO: TBM Core beta (1): 7 registers set
[17:46:17.566] <TB3> INFO: TBM Core alpha (2): 7 registers set
[17:46:17.566] <TB3> INFO: TBM Core beta (3): 7 registers set
[17:46:17.566] <TB3> INFO: 16 ROCs proc600 (16 ON) with 4160 pixelConfigs
[17:46:17.566] <TB3> INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[17:46:17.566] <TB3> INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[17:46:17.566] <TB3> INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[17:46:17.566] <TB3> INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[17:46:17.566] <TB3> INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[17:46:17.566] <TB3> INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[17:46:17.566] <TB3> INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[17:46:17.566] <TB3> INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[17:46:17.566] <TB3> INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[17:46:17.566] <TB3> INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[17:46:17.566] <TB3> INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[17:46:17.566] <TB3> INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[17:46:17.566] <TB3> INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[17:46:17.566] <TB3> INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[17:46:17.567] <TB3> INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[17:46:17.567] <TB3> INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[17:46:17.968] <TB3> INFO: enter 'restricted' command line mode
[17:46:17.968] <TB3> INFO: enter test to run
[17:46:17.968] <TB3> INFO: test: pretest no parameter change
[17:46:17.968] <TB3> INFO: running: pretest
[17:46:17.975] <TB3> INFO: ######################################################################
[17:46:17.975] <TB3> INFO: PixTestPretest::doTest()
[17:46:17.975] <TB3> INFO: ######################################################################
[17:46:17.980] <TB3> INFO: ----------------------------------------------------------------------
[17:46:17.980] <TB3> INFO: PixTestPretest::programROC()
[17:46:17.980] <TB3> INFO: ----------------------------------------------------------------------
[17:46:35.001] <TB3> INFO: PixTestPretest::programROC() done: ROCs are all programmable
[17:46:35.001] <TB3> INFO: IA differences per ROC: 18.5 17.7 17.7 19.3 18.5 19.3 20.9 18.5 18.5 16.9 19.3 17.7 18.5 20.1 20.1 17.7
[17:46:36.106] <TB3> INFO: ----------------------------------------------------------------------
[17:46:36.106] <TB3> INFO: PixTestPretest::setVana() target Ia = 24 mA/ROC
[17:46:36.106] <TB3> INFO: ----------------------------------------------------------------------
[17:46:57.447] <TB3> INFO: PixTestPretest::setVana() done, Module Ia 389.1 mA = 24.3188 mA/ROC
[17:46:57.447] <TB3> INFO: i(loss) [mA/ROC]: 20.1 20.9 20.1 20.9 20.1 20.1 20.1 19.3 19.3 20.1 19.3 19.3 19.3 19.3 20.1 20.9
[17:46:57.499] <TB3> INFO: ----------------------------------------------------------------------
[17:46:57.499] <TB3> INFO: PixTestPretest::findTiming()
[17:46:57.499] <TB3> INFO: ----------------------------------------------------------------------
[17:46:57.499] <TB3> INFO: PixTestCmd::init()

[17:47:32.079] <TB3> INFO: TBM phases: 160MHz: 7, 400MHz: 2, TBM delays: ROC(0/1):5, header/trailer: 1, token: 1
[17:47:32.079] <TB3> INFO: (success/tries = 100/100), width = 3
[17:47:33.594] <TB3> INFO: ----------------------------------------------------------------------
[17:47:33.595] <TB3> INFO: PixTestPretest::findWorkingPixel()
[17:47:33.595] <TB3> INFO: ----------------------------------------------------------------------
[17:47:33.696] <TB3> INFO: Expecting 231680 events.
[17:47:44.030] <TB3> INFO: 231680 events read in total (9736ms).
[17:47:44.041] <TB3> INFO: Test took 10443ms.
[17:47:44.309] <TB3> INFO: Found working pixel in all ROCs: col/row = 12/22
[17:47:44.360] <TB3> INFO: ----------------------------------------------------------------------
[17:47:44.361] <TB3> INFO: PixTestPretest::setVthrCompCalDel()
[17:47:44.361] <TB3> INFO: ----------------------------------------------------------------------
[17:47:44.464] <TB3> INFO: Expecting 231680 events.
[17:47:54.827] <TB3> INFO: 231680 events read in total (9770ms).
[17:47:54.838] <TB3> INFO: Test took 10465ms.
[17:47:55.130] <TB3> INFO: PixTestPretest::setVthrCompCalDel() done
[17:47:55.130] <TB3> INFO: CalDel: 106 87 110 79 95 92 106 111 79 104 82 87 97 122 80 83
[17:47:55.130] <TB3> INFO: VthrComp: 51 52 51 52 51 52 51 51 51 51 51 51 52 51 51 51
[17:47:55.135] <TB3> INFO: write dac parameters into /home/sensor/david/FullQualification/data/M1023_FullQualification_2016-10-19_14h52m_1476881545//003_Fulltest_p17//dacParameters_C0.dat
[17:47:55.135] <TB3> INFO: write dac parameters into /home/sensor/david/FullQualification/data/M1023_FullQualification_2016-10-19_14h52m_1476881545//003_Fulltest_p17//dacParameters_C1.dat
[17:47:55.135] <TB3> INFO: write dac parameters into /home/sensor/david/FullQualification/data/M1023_FullQualification_2016-10-19_14h52m_1476881545//003_Fulltest_p17//dacParameters_C2.dat
[17:47:55.136] <TB3> INFO: write dac parameters into /home/sensor/david/FullQualification/data/M1023_FullQualification_2016-10-19_14h52m_1476881545//003_Fulltest_p17//dacParameters_C3.dat
[17:47:55.136] <TB3> INFO: write dac parameters into /home/sensor/david/FullQualification/data/M1023_FullQualification_2016-10-19_14h52m_1476881545//003_Fulltest_p17//dacParameters_C4.dat
[17:47:55.136] <TB3> INFO: write dac parameters into /home/sensor/david/FullQualification/data/M1023_FullQualification_2016-10-19_14h52m_1476881545//003_Fulltest_p17//dacParameters_C5.dat
[17:47:55.136] <TB3> INFO: write dac parameters into /home/sensor/david/FullQualification/data/M1023_FullQualification_2016-10-19_14h52m_1476881545//003_Fulltest_p17//dacParameters_C6.dat
[17:47:55.136] <TB3> INFO: write dac parameters into /home/sensor/david/FullQualification/data/M1023_FullQualification_2016-10-19_14h52m_1476881545//003_Fulltest_p17//dacParameters_C7.dat
[17:47:55.137] <TB3> INFO: write dac parameters into /home/sensor/david/FullQualification/data/M1023_FullQualification_2016-10-19_14h52m_1476881545//003_Fulltest_p17//dacParameters_C8.dat
[17:47:55.137] <TB3> INFO: write dac parameters into /home/sensor/david/FullQualification/data/M1023_FullQualification_2016-10-19_14h52m_1476881545//003_Fulltest_p17//dacParameters_C9.dat
[17:47:55.137] <TB3> INFO: write dac parameters into /home/sensor/david/FullQualification/data/M1023_FullQualification_2016-10-19_14h52m_1476881545//003_Fulltest_p17//dacParameters_C10.dat
[17:47:55.137] <TB3> INFO: write dac parameters into /home/sensor/david/FullQualification/data/M1023_FullQualification_2016-10-19_14h52m_1476881545//003_Fulltest_p17//dacParameters_C11.dat
[17:47:55.137] <TB3> INFO: write dac parameters into /home/sensor/david/FullQualification/data/M1023_FullQualification_2016-10-19_14h52m_1476881545//003_Fulltest_p17//dacParameters_C12.dat
[17:47:55.138] <TB3> INFO: write dac parameters into /home/sensor/david/FullQualification/data/M1023_FullQualification_2016-10-19_14h52m_1476881545//003_Fulltest_p17//dacParameters_C13.dat
[17:47:55.138] <TB3> INFO: write dac parameters into /home/sensor/david/FullQualification/data/M1023_FullQualification_2016-10-19_14h52m_1476881545//003_Fulltest_p17//dacParameters_C14.dat
[17:47:55.138] <TB3> INFO: write dac parameters into /home/sensor/david/FullQualification/data/M1023_FullQualification_2016-10-19_14h52m_1476881545//003_Fulltest_p17//dacParameters_C15.dat
[17:47:55.138] <TB3> INFO: write tbm parameters into /home/sensor/david/FullQualification/data/M1023_FullQualification_2016-10-19_14h52m_1476881545//003_Fulltest_p17//tbmParameters_C0a.dat
[17:47:55.138] <TB3> INFO: write tbm parameters into /home/sensor/david/FullQualification/data/M1023_FullQualification_2016-10-19_14h52m_1476881545//003_Fulltest_p17//tbmParameters_C0b.dat
[17:47:55.139] <TB3> INFO: write tbm parameters into /home/sensor/david/FullQualification/data/M1023_FullQualification_2016-10-19_14h52m_1476881545//003_Fulltest_p17//tbmParameters_C1a.dat
[17:47:55.139] <TB3> INFO: write tbm parameters into /home/sensor/david/FullQualification/data/M1023_FullQualification_2016-10-19_14h52m_1476881545//003_Fulltest_p17//tbmParameters_C1b.dat
[17:47:55.139] <TB3> INFO: PixTestPretest::doTest() done, duration: 97 seconds
[17:47:55.222] <TB3> INFO: enter test to run
[17:47:55.222] <TB3> INFO: test: FullTest no parameter change
[17:47:55.222] <TB3> INFO: running: fulltest
[17:47:55.222] <TB3> INFO: ######################################################################
[17:47:55.222] <TB3> INFO: PixTestFullTest::doTest()
[17:47:55.222] <TB3> INFO: ######################################################################
[17:47:55.223] <TB3> INFO: ######################################################################
[17:47:55.223] <TB3> INFO: PixTestAlive::doTest()
[17:47:55.223] <TB3> INFO: ######################################################################
[17:47:55.227] <TB3> INFO: ----------------------------------------------------------------------
[17:47:55.227] <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)
[17:47:55.227] <TB3> INFO: ----------------------------------------------------------------------
[17:47:55.546] <TB3> INFO: Expecting 41600 events.
[17:47:59.402] <TB3> INFO: 41600 events read in total (3263ms).
[17:47:59.403] <TB3> INFO: Test took 4172ms.
[17:47:59.656] <TB3> INFO: PixTestAlive::aliveTest() done
[17:47:59.656] <TB3> INFO: number of dead pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[17:47:59.658] <TB3> INFO: ----------------------------------------------------------------------
[17:47:59.658] <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)
[17:47:59.658] <TB3> INFO: ----------------------------------------------------------------------
[17:47:59.975] <TB3> INFO: Expecting 41600 events.
[17:48:03.203] <TB3> INFO: 41600 events read in total (2635ms).
[17:48:03.204] <TB3> INFO: Test took 3544ms.
[17:48:03.204] <TB3> INFO: mask vs. old pixelAlive PixelAlive_C0_V0 .. PixelAlive_C15_V0
[17:48:03.458] <TB3> INFO: PixTestAlive::maskTest() done
[17:48:03.458] <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
[17:48:03.462] <TB3> INFO: ----------------------------------------------------------------------
[17:48:03.462] <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)
[17:48:03.462] <TB3> INFO: ----------------------------------------------------------------------
[17:48:03.786] <TB3> INFO: Expecting 41600 events.
[17:48:07.665] <TB3> INFO: 41600 events read in total (3286ms).
[17:48:07.665] <TB3> INFO: Test took 4201ms.
[17:48:07.923] <TB3> INFO: PixTestAlive::addressDecodingTest() done
[17:48:07.923] <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
[17:48:07.923] <TB3> INFO: PixTestAlive::doTest() done, duration: 12 seconds
[17:48:07.923] <TB3> INFO: Decoding statistics:
[17:48:07.923] <TB3> INFO: General information:
[17:48:07.923] <TB3> INFO: 16bit words read: 0
[17:48:07.923] <TB3> INFO: valid events total: 0
[17:48:07.923] <TB3> INFO: empty events: 0
[17:48:07.924] <TB3> INFO: valid events with pixels: 0
[17:48:07.924] <TB3> INFO: valid pixel hits: 0
[17:48:07.924] <TB3> INFO: Event errors: 0
[17:48:07.924] <TB3> INFO: start marker: 0
[17:48:07.928] <TB3> INFO: stop marker: 0
[17:48:07.928] <TB3> INFO: overflow: 0
[17:48:07.928] <TB3> INFO: invalid 5bit words: 0
[17:48:07.928] <TB3> INFO: invalid XOR eye diagram: 0
[17:48:07.928] <TB3> INFO: frame (failed synchr.): 0
[17:48:07.928] <TB3> INFO: idle data (no TBM trl): 0
[17:48:07.928] <TB3> INFO: no data (only TBM hdr): 0
[17:48:07.928] <TB3> INFO: TBM errors: 0
[17:48:07.928] <TB3> INFO: flawed TBM headers: 0
[17:48:07.928] <TB3> INFO: flawed TBM trailers: 0
[17:48:07.928] <TB3> INFO: event ID mismatches: 0
[17:48:07.928] <TB3> INFO: ROC errors: 0
[17:48:07.928] <TB3> INFO: missing ROC header(s): 0
[17:48:07.928] <TB3> INFO: misplaced readback start: 0
[17:48:07.928] <TB3> INFO: Pixel decoding errors: 0
[17:48:07.928] <TB3> INFO: pixel data incomplete: 0
[17:48:07.928] <TB3> INFO: pixel address: 0
[17:48:07.928] <TB3> INFO: pulse height fill bit: 0
[17:48:07.928] <TB3> INFO: buffer corruption: 0
[17:48:07.937] <TB3> INFO: readReadbackCal: /home/sensor/david/FullQualification/data/M1023_FullQualification_2016-10-19_14h52m_1476881545//003_Fulltest_p17//readbackCal_C0.dat .. /home/sensor/david/FullQualification/data/M1023_FullQualification_2016-10-19_14h52m_1476881545//003_Fulltest_p17//readbackCal_C15.dat
[17:48:07.937] <TB3> INFO: readGainPedestalParameters /home/sensor/david/FullQualification/data/M1023_FullQualification_2016-10-19_14h52m_1476881545//003_Fulltest_p17//phCalibrationFitErr_C0.dat .. /home/sensor/david/FullQualification/data/M1023_FullQualification_2016-10-19_14h52m_1476881545//003_Fulltest_p17//phCalibrationFitErr_C15.dat

[17:48:07.937] <TB3> INFO: ######################################################################
[17:48:07.937] <TB3> INFO: PixTestReadback::doTest()
[17:48:07.937] <TB3> INFO: ######################################################################
[17:48:07.937] <TB3> INFO: ----------------------------------------------------------------------
[17:48:07.937] <TB3> INFO: PixTestReadback::CalibrateVd()
[17:48:07.937] <TB3> INFO: ----------------------------------------------------------------------
[17:48:18.102] <TB3> INFO: write readback calibration parameters into /home/sensor/david/FullQualification/data/M1023_FullQualification_2016-10-19_14h52m_1476881545//003_Fulltest_p17//readbackCal_C0.dat
[17:48:18.102] <TB3> INFO: write readback calibration parameters into /home/sensor/david/FullQualification/data/M1023_FullQualification_2016-10-19_14h52m_1476881545//003_Fulltest_p17//readbackCal_C1.dat
[17:48:18.102] <TB3> INFO: write readback calibration parameters into /home/sensor/david/FullQualification/data/M1023_FullQualification_2016-10-19_14h52m_1476881545//003_Fulltest_p17//readbackCal_C2.dat
[17:48:18.102] <TB3> INFO: write readback calibration parameters into /home/sensor/david/FullQualification/data/M1023_FullQualification_2016-10-19_14h52m_1476881545//003_Fulltest_p17//readbackCal_C3.dat
[17:48:18.102] <TB3> INFO: write readback calibration parameters into /home/sensor/david/FullQualification/data/M1023_FullQualification_2016-10-19_14h52m_1476881545//003_Fulltest_p17//readbackCal_C4.dat
[17:48:18.102] <TB3> INFO: write readback calibration parameters into /home/sensor/david/FullQualification/data/M1023_FullQualification_2016-10-19_14h52m_1476881545//003_Fulltest_p17//readbackCal_C5.dat
[17:48:18.103] <TB3> INFO: write readback calibration parameters into /home/sensor/david/FullQualification/data/M1023_FullQualification_2016-10-19_14h52m_1476881545//003_Fulltest_p17//readbackCal_C6.dat
[17:48:18.103] <TB3> INFO: write readback calibration parameters into /home/sensor/david/FullQualification/data/M1023_FullQualification_2016-10-19_14h52m_1476881545//003_Fulltest_p17//readbackCal_C7.dat
[17:48:18.103] <TB3> INFO: write readback calibration parameters into /home/sensor/david/FullQualification/data/M1023_FullQualification_2016-10-19_14h52m_1476881545//003_Fulltest_p17//readbackCal_C8.dat
[17:48:18.103] <TB3> INFO: write readback calibration parameters into /home/sensor/david/FullQualification/data/M1023_FullQualification_2016-10-19_14h52m_1476881545//003_Fulltest_p17//readbackCal_C9.dat
[17:48:18.103] <TB3> INFO: write readback calibration parameters into /home/sensor/david/FullQualification/data/M1023_FullQualification_2016-10-19_14h52m_1476881545//003_Fulltest_p17//readbackCal_C10.dat
[17:48:18.103] <TB3> INFO: write readback calibration parameters into /home/sensor/david/FullQualification/data/M1023_FullQualification_2016-10-19_14h52m_1476881545//003_Fulltest_p17//readbackCal_C11.dat
[17:48:18.103] <TB3> INFO: write readback calibration parameters into /home/sensor/david/FullQualification/data/M1023_FullQualification_2016-10-19_14h52m_1476881545//003_Fulltest_p17//readbackCal_C12.dat
[17:48:18.103] <TB3> INFO: write readback calibration parameters into /home/sensor/david/FullQualification/data/M1023_FullQualification_2016-10-19_14h52m_1476881545//003_Fulltest_p17//readbackCal_C13.dat
[17:48:18.103] <TB3> INFO: write readback calibration parameters into /home/sensor/david/FullQualification/data/M1023_FullQualification_2016-10-19_14h52m_1476881545//003_Fulltest_p17//readbackCal_C14.dat
[17:48:18.104] <TB3> INFO: write readback calibration parameters into /home/sensor/david/FullQualification/data/M1023_FullQualification_2016-10-19_14h52m_1476881545//003_Fulltest_p17//readbackCal_C15.dat
[17:48:18.163] <TB3> INFO: PixTestPattern:: pg_setup set to default.
[17:48:18.163] <TB3> INFO: ----------------------------------------------------------------------
[17:48:18.163] <TB3> INFO: PixTestReadback::CalibrateVa()
[17:48:18.163] <TB3> INFO: ----------------------------------------------------------------------
[17:48:28.269] <TB3> INFO: write readback calibration parameters into /home/sensor/david/FullQualification/data/M1023_FullQualification_2016-10-19_14h52m_1476881545//003_Fulltest_p17//readbackCal_C0.dat
[17:48:28.269] <TB3> INFO: write readback calibration parameters into /home/sensor/david/FullQualification/data/M1023_FullQualification_2016-10-19_14h52m_1476881545//003_Fulltest_p17//readbackCal_C1.dat
[17:48:28.269] <TB3> INFO: write readback calibration parameters into /home/sensor/david/FullQualification/data/M1023_FullQualification_2016-10-19_14h52m_1476881545//003_Fulltest_p17//readbackCal_C2.dat
[17:48:28.270] <TB3> INFO: write readback calibration parameters into /home/sensor/david/FullQualification/data/M1023_FullQualification_2016-10-19_14h52m_1476881545//003_Fulltest_p17//readbackCal_C3.dat
[17:48:28.270] <TB3> INFO: write readback calibration parameters into /home/sensor/david/FullQualification/data/M1023_FullQualification_2016-10-19_14h52m_1476881545//003_Fulltest_p17//readbackCal_C4.dat
[17:48:28.270] <TB3> INFO: write readback calibration parameters into /home/sensor/david/FullQualification/data/M1023_FullQualification_2016-10-19_14h52m_1476881545//003_Fulltest_p17//readbackCal_C5.dat
[17:48:28.270] <TB3> INFO: write readback calibration parameters into /home/sensor/david/FullQualification/data/M1023_FullQualification_2016-10-19_14h52m_1476881545//003_Fulltest_p17//readbackCal_C6.dat
[17:48:28.270] <TB3> INFO: write readback calibration parameters into /home/sensor/david/FullQualification/data/M1023_FullQualification_2016-10-19_14h52m_1476881545//003_Fulltest_p17//readbackCal_C7.dat
[17:48:28.270] <TB3> INFO: write readback calibration parameters into /home/sensor/david/FullQualification/data/M1023_FullQualification_2016-10-19_14h52m_1476881545//003_Fulltest_p17//readbackCal_C8.dat
[17:48:28.270] <TB3> INFO: write readback calibration parameters into /home/sensor/david/FullQualification/data/M1023_FullQualification_2016-10-19_14h52m_1476881545//003_Fulltest_p17//readbackCal_C9.dat
[17:48:28.270] <TB3> INFO: write readback calibration parameters into /home/sensor/david/FullQualification/data/M1023_FullQualification_2016-10-19_14h52m_1476881545//003_Fulltest_p17//readbackCal_C10.dat
[17:48:28.271] <TB3> INFO: write readback calibration parameters into /home/sensor/david/FullQualification/data/M1023_FullQualification_2016-10-19_14h52m_1476881545//003_Fulltest_p17//readbackCal_C11.dat
[17:48:28.271] <TB3> INFO: write readback calibration parameters into /home/sensor/david/FullQualification/data/M1023_FullQualification_2016-10-19_14h52m_1476881545//003_Fulltest_p17//readbackCal_C12.dat
[17:48:28.271] <TB3> INFO: write readback calibration parameters into /home/sensor/david/FullQualification/data/M1023_FullQualification_2016-10-19_14h52m_1476881545//003_Fulltest_p17//readbackCal_C13.dat
[17:48:28.271] <TB3> INFO: write readback calibration parameters into /home/sensor/david/FullQualification/data/M1023_FullQualification_2016-10-19_14h52m_1476881545//003_Fulltest_p17//readbackCal_C14.dat
[17:48:28.271] <TB3> INFO: write readback calibration parameters into /home/sensor/david/FullQualification/data/M1023_FullQualification_2016-10-19_14h52m_1476881545//003_Fulltest_p17//readbackCal_C15.dat
[17:48:28.324] <TB3> INFO: PixTestPattern:: pg_setup set to default.
[17:48:28.325] <TB3> INFO: ----------------------------------------------------------------------
[17:48:28.325] <TB3> INFO: PixTestReadback::readbackVbg()
[17:48:28.325] <TB3> INFO: ----------------------------------------------------------------------
[17:48:36.103] <TB3> INFO: PixTestPattern:: pg_setup set to default.
[17:48:36.103] <TB3> INFO: ----------------------------------------------------------------------
[17:48:36.104] <TB3> INFO: PixTestReadback::getCalibratedVbg()
[17:48:36.104] <TB3> INFO: ----------------------------------------------------------------------
[17:48:36.104] <TB3> INFO: Vbg will be calibrated using Vd calibration
[17:48:36.104] <TB3> INFO: /*/*/*/*::: ROC 0: uncalibrated Vbg = 151calibrated Vbg = 1.18262 :::*/*/*/*/
[17:48:36.104] <TB3> INFO: /*/*/*/*::: ROC 1: uncalibrated Vbg = 159calibrated Vbg = 1.18231 :::*/*/*/*/
[17:48:36.104] <TB3> INFO: /*/*/*/*::: ROC 2: uncalibrated Vbg = 160.7calibrated Vbg = 1.17379 :::*/*/*/*/
[17:48:36.104] <TB3> INFO: /*/*/*/*::: ROC 3: uncalibrated Vbg = 156.1calibrated Vbg = 1.16897 :::*/*/*/*/
[17:48:36.104] <TB3> INFO: /*/*/*/*::: ROC 4: uncalibrated Vbg = 157.1calibrated Vbg = 1.17742 :::*/*/*/*/
[17:48:36.104] <TB3> INFO: /*/*/*/*::: ROC 5: uncalibrated Vbg = 158.5calibrated Vbg = 1.18654 :::*/*/*/*/
[17:48:36.104] <TB3> INFO: /*/*/*/*::: ROC 6: uncalibrated Vbg = 161.1calibrated Vbg = 1.18294 :::*/*/*/*/
[17:48:36.104] <TB3> INFO: /*/*/*/*::: ROC 7: uncalibrated Vbg = 159calibrated Vbg = 1.18679 :::*/*/*/*/
[17:48:36.104] <TB3> INFO: /*/*/*/*::: ROC 8: uncalibrated Vbg = 153.9calibrated Vbg = 1.17169 :::*/*/*/*/
[17:48:36.104] <TB3> INFO: /*/*/*/*::: ROC 9: uncalibrated Vbg = 150.9calibrated Vbg = 1.17474 :::*/*/*/*/
[17:48:36.104] <TB3> INFO: /*/*/*/*::: ROC 10: uncalibrated Vbg = 161.6calibrated Vbg = 1.16519 :::*/*/*/*/
[17:48:36.104] <TB3> INFO: /*/*/*/*::: ROC 11: uncalibrated Vbg = 162.6calibrated Vbg = 1.16211 :::*/*/*/*/
[17:48:36.104] <TB3> INFO: /*/*/*/*::: ROC 12: uncalibrated Vbg = 166.1calibrated Vbg = 1.17088 :::*/*/*/*/
[17:48:36.104] <TB3> INFO: /*/*/*/*::: ROC 13: uncalibrated Vbg = 150.5calibrated Vbg = 1.18286 :::*/*/*/*/
[17:48:36.104] <TB3> INFO: /*/*/*/*::: ROC 14: uncalibrated Vbg = 156.6calibrated Vbg = 1.1777 :::*/*/*/*/
[17:48:36.104] <TB3> INFO: /*/*/*/*::: ROC 15: uncalibrated Vbg = 170calibrated Vbg = 1.16946 :::*/*/*/*/
[17:48:36.107] <TB3> INFO: ----------------------------------------------------------------------
[17:48:36.107] <TB3> INFO: PixTestReadback::CalibrateIa()
[17:48:36.107] <TB3> INFO: ----------------------------------------------------------------------
[17:51:18.249] <TB3> INFO: write readback calibration parameters into /home/sensor/david/FullQualification/data/M1023_FullQualification_2016-10-19_14h52m_1476881545//003_Fulltest_p17//readbackCal_C0.dat
[17:51:18.249] <TB3> INFO: write readback calibration parameters into /home/sensor/david/FullQualification/data/M1023_FullQualification_2016-10-19_14h52m_1476881545//003_Fulltest_p17//readbackCal_C1.dat
[17:51:18.250] <TB3> INFO: write readback calibration parameters into /home/sensor/david/FullQualification/data/M1023_FullQualification_2016-10-19_14h52m_1476881545//003_Fulltest_p17//readbackCal_C2.dat
[17:51:18.250] <TB3> INFO: write readback calibration parameters into /home/sensor/david/FullQualification/data/M1023_FullQualification_2016-10-19_14h52m_1476881545//003_Fulltest_p17//readbackCal_C3.dat
[17:51:18.250] <TB3> INFO: write readback calibration parameters into /home/sensor/david/FullQualification/data/M1023_FullQualification_2016-10-19_14h52m_1476881545//003_Fulltest_p17//readbackCal_C4.dat
[17:51:18.250] <TB3> INFO: write readback calibration parameters into /home/sensor/david/FullQualification/data/M1023_FullQualification_2016-10-19_14h52m_1476881545//003_Fulltest_p17//readbackCal_C5.dat
[17:51:18.250] <TB3> INFO: write readback calibration parameters into /home/sensor/david/FullQualification/data/M1023_FullQualification_2016-10-19_14h52m_1476881545//003_Fulltest_p17//readbackCal_C6.dat
[17:51:18.250] <TB3> INFO: write readback calibration parameters into /home/sensor/david/FullQualification/data/M1023_FullQualification_2016-10-19_14h52m_1476881545//003_Fulltest_p17//readbackCal_C7.dat
[17:51:18.251] <TB3> INFO: write readback calibration parameters into /home/sensor/david/FullQualification/data/M1023_FullQualification_2016-10-19_14h52m_1476881545//003_Fulltest_p17//readbackCal_C8.dat
[17:51:18.251] <TB3> INFO: write readback calibration parameters into /home/sensor/david/FullQualification/data/M1023_FullQualification_2016-10-19_14h52m_1476881545//003_Fulltest_p17//readbackCal_C9.dat
[17:51:18.251] <TB3> INFO: write readback calibration parameters into /home/sensor/david/FullQualification/data/M1023_FullQualification_2016-10-19_14h52m_1476881545//003_Fulltest_p17//readbackCal_C10.dat
[17:51:18.251] <TB3> INFO: write readback calibration parameters into /home/sensor/david/FullQualification/data/M1023_FullQualification_2016-10-19_14h52m_1476881545//003_Fulltest_p17//readbackCal_C11.dat
[17:51:18.252] <TB3> INFO: write readback calibration parameters into /home/sensor/david/FullQualification/data/M1023_FullQualification_2016-10-19_14h52m_1476881545//003_Fulltest_p17//readbackCal_C12.dat
[17:51:18.252] <TB3> INFO: write readback calibration parameters into /home/sensor/david/FullQualification/data/M1023_FullQualification_2016-10-19_14h52m_1476881545//003_Fulltest_p17//readbackCal_C13.dat
[17:51:18.252] <TB3> INFO: write readback calibration parameters into /home/sensor/david/FullQualification/data/M1023_FullQualification_2016-10-19_14h52m_1476881545//003_Fulltest_p17//readbackCal_C14.dat
[17:51:18.252] <TB3> INFO: write readback calibration parameters into /home/sensor/david/FullQualification/data/M1023_FullQualification_2016-10-19_14h52m_1476881545//003_Fulltest_p17//readbackCal_C15.dat
[17:51:18.304] <TB3> INFO: PixTestPattern:: pg_setup set to default.
[17:51:18.306] <TB3> INFO: PixTestReadback::doTest() done
[17:51:18.306] <TB3> INFO: Decoding statistics:
[17:51:18.306] <TB3> INFO: General information:
[17:51:18.306] <TB3> INFO: 16bit words read: 1536
[17:51:18.306] <TB3> INFO: valid events total: 256
[17:51:18.306] <TB3> INFO: empty events: 256
[17:51:18.306] <TB3> INFO: valid events with pixels: 0
[17:51:18.306] <TB3> INFO: valid pixel hits: 0
[17:51:18.306] <TB3> INFO: Event errors: 0
[17:51:18.306] <TB3> INFO: start marker: 0
[17:51:18.306] <TB3> INFO: stop marker: 0
[17:51:18.306] <TB3> INFO: overflow: 0
[17:51:18.306] <TB3> INFO: invalid 5bit words: 0
[17:51:18.306] <TB3> INFO: invalid XOR eye diagram: 0
[17:51:18.306] <TB3> INFO: frame (failed synchr.): 0
[17:51:18.306] <TB3> INFO: idle data (no TBM trl): 0
[17:51:18.306] <TB3> INFO: no data (only TBM hdr): 0
[17:51:18.306] <TB3> INFO: TBM errors: 0
[17:51:18.307] <TB3> INFO: flawed TBM headers: 0
[17:51:18.307] <TB3> INFO: flawed TBM trailers: 0
[17:51:18.307] <TB3> INFO: event ID mismatches: 0
[17:51:18.307] <TB3> INFO: ROC errors: 0
[17:51:18.307] <TB3> INFO: missing ROC header(s): 0
[17:51:18.307] <TB3> INFO: misplaced readback start: 0
[17:51:18.307] <TB3> INFO: Pixel decoding errors: 0
[17:51:18.307] <TB3> INFO: pixel data incomplete: 0
[17:51:18.307] <TB3> INFO: pixel address: 0
[17:51:18.307] <TB3> INFO: pulse height fill bit: 0
[17:51:18.307] <TB3> INFO: buffer corruption: 0
[17:51:18.377] <TB3> INFO: ######################################################################
[17:51:18.377] <TB3> INFO: PixTestBBMap::doTest() Ntrig = 5, VcalS = 250 (high range)
[17:51:18.377] <TB3> INFO: ######################################################################
[17:51:18.383] <TB3> INFO: ---> dac: VthrComp name: calSMap ntrig: 5 dacrange: 0 .. 149 (-1/-1) hits flags = 514 (plus default)
[17:51:18.406] <TB3> INFO: dacScan split into 1 runs with ntrig = 5
[17:51:18.406] <TB3> INFO: run 1 of 1
[17:51:18.728] <TB3> INFO: Expecting 3120000 events.
[17:51:53.675] <TB3> INFO: 660290 events read in total (34355ms).











[17:52:27.559] <TB3> INFO: 1312905 events read in total (68239ms).











[17:53:01.494] <TB3> INFO: 1962380 events read in total (102174ms).




















[17:53:35.713] <TB3> INFO: 2613455 events read in total (136393ms).











[17:54:08.097] <TB3> INFO: 3120000 events read in total (168777ms).
[17:54:08.257] <TB3> INFO: Test took 169853ms.
[17:54:50.519] <TB3> INFO: PixTestBBMap::doTest() done with 3 decoding errors: , duration: 212 seconds
[17:54:50.519] <TB3> INFO: number of dead bumps (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[17:54:50.519] <TB3> INFO: separation cut (per ROC): 99 109 95 111 106 110 102 96 109 101 106 110 93 89 103 112
[17:54:50.519] <TB3> INFO: Decoding statistics:
[17:54:50.519] <TB3> INFO: General information:
[17:54:50.519] <TB3> INFO: 16bit words read: 0
[17:54:50.519] <TB3> INFO: valid events total: 0
[17:54:50.519] <TB3> INFO: empty events: 0
[17:54:50.519] <TB3> INFO: valid events with pixels: 0
[17:54:50.519] <TB3> INFO: valid pixel hits: 0
[17:54:50.519] <TB3> INFO: Event errors: 0
[17:54:50.519] <TB3> INFO: start marker: 0
[17:54:50.519] <TB3> INFO: stop marker: 0
[17:54:50.519] <TB3> INFO: overflow: 0
[17:54:50.519] <TB3> INFO: invalid 5bit words: 0
[17:54:50.519] <TB3> INFO: invalid XOR eye diagram: 0
[17:54:50.519] <TB3> INFO: frame (failed synchr.): 0
[17:54:50.519] <TB3> INFO: idle data (no TBM trl): 0
[17:54:50.519] <TB3> INFO: no data (only TBM hdr): 0
[17:54:50.519] <TB3> INFO: TBM errors: 0
[17:54:50.519] <TB3> INFO: flawed TBM headers: 0
[17:54:50.519] <TB3> INFO: flawed TBM trailers: 0
[17:54:50.519] <TB3> INFO: event ID mismatches: 0
[17:54:50.519] <TB3> INFO: ROC errors: 0
[17:54:50.519] <TB3> INFO: missing ROC header(s): 0
[17:54:50.519] <TB3> INFO: misplaced readback start: 0
[17:54:50.519] <TB3> INFO: Pixel decoding errors: 0
[17:54:50.519] <TB3> INFO: pixel data incomplete: 0
[17:54:50.519] <TB3> INFO: pixel address: 0
[17:54:50.520] <TB3> INFO: pulse height fill bit: 0
[17:54:50.520] <TB3> INFO: buffer corruption: 0
[17:54:50.580] <TB3> INFO: ######################################################################
[17:54:50.580] <TB3> INFO: PixTestScurves::fullTest() ntrig = 50, dacs/step = -1, ntrig/step = -1
[17:54:50.580] <TB3> INFO: ######################################################################
[17:54:50.581] <TB3> INFO: ----------------------------------------------------------------------
[17:54:50.581] <TB3> INFO: PixTestScurves::scurves(Vcal), ntrig = 50, dacs/step = -1, ntrig/step = -1
[17:54:50.581] <TB3> INFO: ----------------------------------------------------------------------
[17:54:50.581] <TB3> INFO: ---> dac: Vcal name: scurveVcal ntrig: 50 dacrange: 25 .. 200 (-1/-1) hits flags = 528 (plus default)
[17:54:50.594] <TB3> INFO: dacScan split into 1 runs with ntrig = 50
[17:54:50.595] <TB3> INFO: run 1 of 1
[17:54:50.915] <TB3> INFO: Expecting 36608000 events.
[17:55:19.237] <TB3> INFO: 691550 events read in total (27727ms).
[17:55:46.853] <TB3> INFO: 1365900 events read in total (55343ms).
[17:56:14.643] <TB3> INFO: 2040800 events read in total (83133ms).
[17:56:42.291] <TB3> INFO: 2716350 events read in total (110781ms).
[17:57:10.007] <TB3> INFO: 3389500 events read in total (138497ms).
[17:57:37.781] <TB3> INFO: 4063800 events read in total (166271ms).
[17:58:05.530] <TB3> INFO: 4738300 events read in total (194020ms).
[17:58:33.385] <TB3> INFO: 5410300 events read in total (221875ms).
[17:59:01.318] <TB3> INFO: 6085000 events read in total (249808ms).
[17:59:29.255] <TB3> INFO: 6759200 events read in total (277745ms).
[17:59:57.532] <TB3> INFO: 7433850 events read in total (306022ms).
[18:00:25.244] <TB3> INFO: 8108300 events read in total (333734ms).
[18:00:53.058] <TB3> INFO: 8782050 events read in total (361548ms).
[18:01:20.896] <TB3> INFO: 9457950 events read in total (389386ms).
[18:01:48.743] <TB3> INFO: 10133500 events read in total (417233ms).
[18:02:16.449] <TB3> INFO: 10807100 events read in total (444939ms).
[18:02:44.234] <TB3> INFO: 11482400 events read in total (472724ms).
[18:03:12.078] <TB3> INFO: 12156450 events read in total (500568ms).
[18:03:39.882] <TB3> INFO: 12829050 events read in total (528372ms).
[18:04:07.670] <TB3> INFO: 13504100 events read in total (556160ms).
[18:04:35.600] <TB3> INFO: 14178450 events read in total (584091ms).
[18:05:03.464] <TB3> INFO: 14849100 events read in total (611954ms).
[18:05:31.304] <TB3> INFO: 15519250 events read in total (639794ms).
[18:05:59.185] <TB3> INFO: 16189900 events read in total (667675ms).
[18:06:27.007] <TB3> INFO: 16861250 events read in total (695498ms).
[18:06:54.727] <TB3> INFO: 17531700 events read in total (723217ms).
[18:07:23.040] <TB3> INFO: 18203100 events read in total (751530ms).
[18:07:50.718] <TB3> INFO: 18872500 events read in total (779208ms).
[18:08:18.555] <TB3> INFO: 19541850 events read in total (807045ms).
[18:08:46.406] <TB3> INFO: 20209000 events read in total (834896ms).
[18:09:14.299] <TB3> INFO: 20876700 events read in total (862789ms).
[18:09:41.894] <TB3> INFO: 21545350 events read in total (890384ms).
[18:10:09.787] <TB3> INFO: 22212300 events read in total (918277ms).
[18:10:37.242] <TB3> INFO: 22879400 events read in total (945732ms).
[18:11:04.908] <TB3> INFO: 23546950 events read in total (973398ms).
[18:11:32.491] <TB3> INFO: 24213550 events read in total (1000981ms).
[18:12:00.365] <TB3> INFO: 24876750 events read in total (1028855ms).
[18:12:28.195] <TB3> INFO: 25543500 events read in total (1056685ms).
[18:12:55.830] <TB3> INFO: 26211050 events read in total (1084320ms).
[18:13:23.744] <TB3> INFO: 26876600 events read in total (1112234ms).
[18:13:51.592] <TB3> INFO: 27542600 events read in total (1140082ms).
[18:14:19.594] <TB3> INFO: 28209000 events read in total (1168084ms).
[18:14:47.155] <TB3> INFO: 28872150 events read in total (1195645ms).
[18:15:15.037] <TB3> INFO: 29537650 events read in total (1223527ms).
[18:15:42.639] <TB3> INFO: 30200150 events read in total (1251129ms).
[18:16:10.166] <TB3> INFO: 30862900 events read in total (1278656ms).
[18:16:38.042] <TB3> INFO: 31525450 events read in total (1306532ms).
[18:17:05.784] <TB3> INFO: 32187650 events read in total (1334274ms).
[18:17:33.647] <TB3> INFO: 32851400 events read in total (1362137ms).
[18:18:01.427] <TB3> INFO: 33518050 events read in total (1389917ms).
[18:18:29.174] <TB3> INFO: 34184250 events read in total (1417664ms).
[18:18:56.911] <TB3> INFO: 34849450 events read in total (1445401ms).
[18:19:24.526] <TB3> INFO: 35515300 events read in total (1473016ms).
[18:19:57.020] <TB3> INFO: 36185800 events read in total (1505510ms).

[18:22:35.657] <TB3> INFO: Expecting 36608000 events.
[18:23:03.726] <TB3> INFO: 691050 events read in total (27469ms).
[18:23:30.867] <TB3> INFO: 1364800 events read in total (54610ms).
[18:24:00.070] <TB3> INFO: 2039750 events read in total (83813ms).
[18:24:29.057] <TB3> INFO: 2714750 events read in total (112800ms).
[18:24:56.020] <TB3> INFO: 3387300 events read in total (139763ms).
[18:25:22.976] <TB3> INFO: 4062050 events read in total (166719ms).
[18:25:49.828] <TB3> INFO: 4734800 events read in total (193571ms).
[18:26:16.934] <TB3> INFO: 5407800 events read in total (220677ms).
[18:26:44.098] <TB3> INFO: 6080700 events read in total (247842ms).
[18:27:11.759] <TB3> INFO: 6756100 events read in total (275502ms).
[18:27:39.175] <TB3> INFO: 7430200 events read in total (302918ms).
[18:28:06.651] <TB3> INFO: 8103550 events read in total (330394ms).
[18:28:34.053] <TB3> INFO: 8778450 events read in total (357796ms).
[18:29:03.461] <TB3> INFO: 9452900 events read in total (387204ms).
[18:29:34.293] <TB3> INFO: 10127600 events read in total (418036ms).
[18:30:01.822] <TB3> INFO: 10802500 events read in total (445565ms).
[18:30:29.165] <TB3> INFO: 11476450 events read in total (472908ms).
[18:30:56.318] <TB3> INFO: 12150350 events read in total (500061ms).
[18:31:23.593] <TB3> INFO: 12823100 events read in total (527336ms).
[18:31:50.897] <TB3> INFO: 13497650 events read in total (554640ms).
[18:32:18.136] <TB3> INFO: 14172850 events read in total (581879ms).
[18:32:45.212] <TB3> INFO: 14842550 events read in total (608955ms).
[18:33:12.381] <TB3> INFO: 15512100 events read in total (636124ms).
[18:33:39.643] <TB3> INFO: 16182500 events read in total (663386ms).
[18:34:07.103] <TB3> INFO: 16854600 events read in total (690846ms).
[18:34:34.347] <TB3> INFO: 17524500 events read in total (718090ms).
[18:35:02.319] <TB3> INFO: 18195150 events read in total (746062ms).
[18:35:29.919] <TB3> INFO: 18864250 events read in total (773662ms).
[18:35:57.370] <TB3> INFO: 19533350 events read in total (801113ms).
[18:36:24.560] <TB3> INFO: 20200250 events read in total (828303ms).
[18:36:53.561] <TB3> INFO: 20867300 events read in total (857304ms).
[18:37:23.458] <TB3> INFO: 21536000 events read in total (887201ms).
[18:37:51.611] <TB3> INFO: 22202600 events read in total (915354ms).
[18:38:19.601] <TB3> INFO: 22870000 events read in total (943344ms).
[18:38:47.552] <TB3> INFO: 23537300 events read in total (971295ms).
[18:39:16.528] <TB3> INFO: 24203250 events read in total (1000271ms).
[18:39:45.385] <TB3> INFO: 24866900 events read in total (1029128ms).
[18:40:12.779] <TB3> INFO: 25533400 events read in total (1056522ms).
[18:40:40.368] <TB3> INFO: 26200100 events read in total (1084111ms).
[18:41:07.605] <TB3> INFO: 26865650 events read in total (1111348ms).
[18:41:34.900] <TB3> INFO: 27532000 events read in total (1138643ms).
[18:42:02.576] <TB3> INFO: 28197250 events read in total (1166319ms).
[18:42:33.801] <TB3> INFO: 28861250 events read in total (1197544ms).
[18:43:01.836] <TB3> INFO: 29525550 events read in total (1225579ms).
[18:43:29.163] <TB3> INFO: 30189150 events read in total (1252906ms).
[18:43:56.902] <TB3> INFO: 30851400 events read in total (1280645ms).
[18:44:26.307] <TB3> INFO: 31512850 events read in total (1310050ms).
[18:44:55.521] <TB3> INFO: 32176050 events read in total (1339264ms).
[18:45:23.341] <TB3> INFO: 32839700 events read in total (1367084ms).
[18:45:50.659] <TB3> INFO: 33506800 events read in total (1394402ms).
[18:46:18.683] <TB3> INFO: 34171700 events read in total (1422426ms).
[18:46:46.774] <TB3> INFO: 34837500 events read in total (1450517ms).
[18:47:14.583] <TB3> INFO: 35504100 events read in total (1478326ms).
[18:47:42.139] <TB3> INFO: 36174350 events read in total (1505882ms).
[18:48:00.446] <TB3> INFO: 36608000 events read in total (1524189ms).
[18:48:00.519] <TB3> INFO: Test took 1525192ms.
[18:48:01.079] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[18:48:03.817] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[18:48:06.630] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[18:48:09.693] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[18:48:12.487] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[18:48:15.172] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[18:48:17.925] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[18:48:20.724] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[18:48:23.552] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[18:48:26.330] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[18:48:29.054] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[18:48:31.779] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[18:48:34.589] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[18:48:37.310] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[18:48:40.095] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[18:48:43.143] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[18:48:46.113] <TB3> INFO: PixTestScurves::scurves() done
[18:48:46.113] <TB3> INFO: Vcal mean: 113.71 128.05 123.23 129.12 130.67 132.37 120.12 113.77 113.18 118.06 125.69 123.01 123.08 111.15 119.22 126.71
[18:48:46.113] <TB3> INFO: Vcal RMS: 5.84 5.84 7.83 6.48 6.49 6.74 7.11 5.36 5.18 6.17 6.08 6.15 5.56 4.69 5.63 5.96
[18:48:46.113] <TB3> INFO: PixTestScurves::fullTest() done, duration: 3235 seconds
[18:48:46.113] <TB3> INFO: Decoding statistics:
[18:48:46.113] <TB3> INFO: General information:
[18:48:46.113] <TB3> INFO: 16bit words read: 0
[18:48:46.113] <TB3> INFO: valid events total: 0
[18:48:46.113] <TB3> INFO: empty events: 0
[18:48:46.113] <TB3> INFO: valid events with pixels: 0
[18:48:46.113] <TB3> INFO: valid pixel hits: 0
[18:48:46.113] <TB3> INFO: Event errors: 0
[18:48:46.114] <TB3> INFO: start marker: 0
[18:48:46.114] <TB3> INFO: stop marker: 0
[18:48:46.114] <TB3> INFO: overflow: 0
[18:48:46.114] <TB3> INFO: invalid 5bit words: 0
[18:48:46.114] <TB3> INFO: invalid XOR eye diagram: 0
[18:48:46.114] <TB3> INFO: frame (failed synchr.): 0
[18:48:46.114] <TB3> INFO: idle data (no TBM trl): 0
[18:48:46.114] <TB3> INFO: no data (only TBM hdr): 0
[18:48:46.114] <TB3> INFO: TBM errors: 0
[18:48:46.114] <TB3> INFO: flawed TBM headers: 0
[18:48:46.114] <TB3> INFO: flawed TBM trailers: 0
[18:48:46.114] <TB3> INFO: event ID mismatches: 0
[18:48:46.114] <TB3> INFO: ROC errors: 0
[18:48:46.114] <TB3> INFO: missing ROC header(s): 0
[18:48:46.114] <TB3> INFO: misplaced readback start: 0
[18:48:46.114] <TB3> INFO: Pixel decoding errors: 0
[18:48:46.114] <TB3> INFO: pixel data incomplete: 0
[18:48:46.114] <TB3> INFO: pixel address: 0
[18:48:46.114] <TB3> INFO: pulse height fill bit: 0
[18:48:46.114] <TB3> INFO: buffer corruption: 0
[18:48:46.225] <TB3> INFO: ######################################################################
[18:48:46.225] <TB3> INFO: PixTestTrim::doTest()
[18:48:46.225] <TB3> INFO: ######################################################################
[18:48:46.228] <TB3> INFO: ----------------------------------------------------------------------
[18:48:46.228] <TB3> INFO: PixTestTrim::trimTest() ntrig = 8, vcal = 35
[18:48:46.228] <TB3> INFO: ----------------------------------------------------------------------
[18:48:46.318] <TB3> INFO: ---> VthrComp thr map (minimal VthrComp)
[18:48:46.318] <TB3> INFO: ---> dac: vthrcomp name: TrimThr0 ntrig: 8 dacrange: 10 .. 160 (-1/-1) hits flags = 528 (plus default)
[18:48:46.332] <TB3> INFO: dacScan split into 1 runs with ntrig = 8
[18:48:46.332] <TB3> INFO: run 1 of 1
[18:48:46.671] <TB3> INFO: Expecting 5025280 events.
[18:49:23.044] <TB3> INFO: 831920 events read in total (35774ms).
[18:49:58.042] <TB3> INFO: 1660136 events read in total (70772ms).
[18:50:35.401] <TB3> INFO: 2485048 events read in total (108132ms).
[18:51:10.546] <TB3> INFO: 3305888 events read in total (143276ms).
[18:51:46.198] <TB3> INFO: 4122400 events read in total (178928ms).
[18:52:21.640] <TB3> INFO: 4937584 events read in total (214370ms).
[18:52:25.680] <TB3> INFO: 5025280 events read in total (218410ms).
[18:52:25.732] <TB3> INFO: Test took 219400ms.
[18:52:52.868] <TB3> INFO: ROC 0 VthrComp = 112
[18:52:52.868] <TB3> INFO: ROC 1 VthrComp = 129
[18:52:52.869] <TB3> INFO: ROC 2 VthrComp = 110
[18:52:52.869] <TB3> INFO: ROC 3 VthrComp = 130
[18:52:52.870] <TB3> INFO: ROC 4 VthrComp = 131
[18:52:52.870] <TB3> INFO: ROC 5 VthrComp = 130
[18:52:52.870] <TB3> INFO: ROC 6 VthrComp = 119
[18:52:52.870] <TB3> INFO: ROC 7 VthrComp = 111
[18:52:52.871] <TB3> INFO: ROC 8 VthrComp = 127
[18:52:52.871] <TB3> INFO: ROC 9 VthrComp = 115
[18:52:52.871] <TB3> INFO: ROC 10 VthrComp = 130
[18:52:52.871] <TB3> INFO: ROC 11 VthrComp = 125
[18:52:52.871] <TB3> INFO: ROC 12 VthrComp = 116
[18:52:52.872] <TB3> INFO: ROC 13 VthrComp = 108
[18:52:52.872] <TB3> INFO: ROC 14 VthrComp = 126
[18:52:52.873] <TB3> INFO: ROC 15 VthrComp = 133
[18:52:53.201] <TB3> INFO: Expecting 41600 events.
[18:52:57.043] <TB3> INFO: 41600 events read in total (3249ms).
[18:52:57.044] <TB3> INFO: Test took 4168ms.
[18:52:57.060] <TB3> INFO: ---> Vcal thr map (pixel with maximum Vcal thr)
[18:52:57.060] <TB3> INFO: ---> dac: vcal name: TrimThr1 ntrig: 8 dacrange: 10 .. 160 (-1/-1) hits flags = 528 (plus default)
[18:52:57.074] <TB3> INFO: dacScan split into 1 runs with ntrig = 8
[18:52:57.074] <TB3> INFO: run 1 of 1
[18:52:57.389] <TB3> INFO: Expecting 5025280 events.
[18:53:29.728] <TB3> INFO: 591256 events read in total (31747ms).
[18:54:00.723] <TB3> INFO: 1181128 events read in total (62742ms).
[18:54:32.320] <TB3> INFO: 1771424 events read in total (94339ms).
[18:55:02.454] <TB3> INFO: 2360816 events read in total (124473ms).
[18:55:32.265] <TB3> INFO: 2948128 events read in total (154284ms).
[18:56:02.351] <TB3> INFO: 3533904 events read in total (184370ms).
[18:56:32.512] <TB3> INFO: 4119072 events read in total (214531ms).
[18:57:02.447] <TB3> INFO: 4703672 events read in total (244466ms).
[18:57:19.017] <TB3> INFO: 5025280 events read in total (261036ms).
[18:57:19.095] <TB3> INFO: Test took 262021ms.
[18:58:00.915] <TB3> INFO: roc 0 with ID = 0 has maximal Vcal 64.6934 for pixel 5/4 mean/min/max = 48.2021/31.2153/65.189
[18:58:00.916] <TB3> INFO: roc 1 with ID = 1 has maximal Vcal 64.047 for pixel 15/17 mean/min/max = 48.0179/31.9386/64.0972
[18:58:00.916] <TB3> INFO: roc 2 with ID = 2 has maximal Vcal 74.234 for pixel 4/5 mean/min/max = 53.7913/33.1864/74.3962
[18:58:00.917] <TB3> INFO: roc 3 with ID = 3 has maximal Vcal 64.5844 for pixel 19/11 mean/min/max = 48.9332/32.8405/65.0259
[18:58:00.917] <TB3> INFO: roc 4 with ID = 4 has maximal Vcal 59.9688 for pixel 0/66 mean/min/max = 45.7127/31.3841/60.0412
[18:58:00.918] <TB3> INFO: roc 5 with ID = 5 has maximal Vcal 65.031 for pixel 5/2 mean/min/max = 48.3951/31.7308/65.0594
[18:58:00.918] <TB3> INFO: roc 6 with ID = 6 has maximal Vcal 63.1199 for pixel 0/11 mean/min/max = 46.8427/30.5198/63.1657
[18:58:00.919] <TB3> INFO: roc 7 with ID = 7 has maximal Vcal 65.6759 for pixel 20/5 mean/min/max = 49.6723/33.4495/65.8951
[18:58:00.919] <TB3> INFO: roc 8 with ID = 8 has maximal Vcal 56.8388 for pixel 21/79 mean/min/max = 44.366/31.683/57.049
[18:58:00.920] <TB3> INFO: roc 9 with ID = 9 has maximal Vcal 60.925 for pixel 46/63 mean/min/max = 46.6818/32.4009/60.9627
[18:58:00.920] <TB3> INFO: roc 10 with ID = 10 has maximal Vcal 59.7449 for pixel 51/5 mean/min/max = 45.4625/30.9651/59.9598
[18:58:00.921] <TB3> INFO: roc 11 with ID = 11 has maximal Vcal 61.7827 for pixel 9/12 mean/min/max = 47.4515/32.9622/61.9409
[18:58:00.921] <TB3> INFO: roc 12 with ID = 12 has maximal Vcal 65.2579 for pixel 26/15 mean/min/max = 48.6025/31.7914/65.4136
[18:58:00.922] <TB3> INFO: roc 13 with ID = 13 has maximal Vcal 63.7317 for pixel 21/1 mean/min/max = 49.1316/34.3298/63.9334
[18:58:00.922] <TB3> INFO: roc 14 with ID = 14 has maximal Vcal 58.3255 for pixel 23/12 mean/min/max = 45.2709/32.1248/58.4171
[18:58:00.923] <TB3> INFO: roc 15 with ID = 15 has maximal Vcal 59.5053 for pixel 18/79 mean/min/max = 46.6803/33.8061/59.5545
[18:58:00.931] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[18:58:01.023] <TB3> INFO: Expecting 411648 events.
[18:58:11.262] <TB3> INFO: 411648 events read in total (9646ms).
[18:58:11.273] <TB3> INFO: Expecting 411648 events.
[18:58:21.407] <TB3> INFO: 411648 events read in total (9730ms).
[18:58:21.422] <TB3> INFO: Expecting 411648 events.
[18:58:31.740] <TB3> INFO: 411648 events read in total (9910ms).
[18:58:31.760] <TB3> INFO: Expecting 411648 events.
[18:58:42.351] <TB3> INFO: 411648 events read in total (10187ms).
[18:58:42.379] <TB3> INFO: Expecting 411648 events.
[18:58:53.297] <TB3> INFO: 411648 events read in total (10510ms).
[18:58:53.351] <TB3> INFO: Expecting 411648 events.
[18:59:04.630] <TB3> INFO: 411648 events read in total (10871ms).
[18:59:04.662] <TB3> INFO: Expecting 411648 events.
[18:59:15.706] <TB3> INFO: 411648 events read in total (10636ms).
[18:59:15.759] <TB3> INFO: Expecting 411648 events.
[18:59:26.063] <TB3> INFO: 411648 events read in total (9897ms).
[18:59:26.099] <TB3> INFO: Expecting 411648 events.
[18:59:36.101] <TB3> INFO: 411648 events read in total (9598ms).
[18:59:36.148] <TB3> INFO: Expecting 411648 events.
[18:59:46.566] <TB3> INFO: 411648 events read in total (10013ms).
[18:59:46.621] <TB3> INFO: Expecting 411648 events.
[18:59:56.647] <TB3> INFO: 411648 events read in total (9618ms).
[18:59:56.698] <TB3> INFO: Expecting 411648 events.
[19:00:06.655] <TB3> INFO: 411648 events read in total (9553ms).
[19:00:06.705] <TB3> INFO: Expecting 411648 events.
[19:00:16.754] <TB3> INFO: 411648 events read in total (9644ms).
[19:00:16.833] <TB3> INFO: Expecting 411648 events.
[19:00:27.112] <TB3> INFO: 411648 events read in total (9871ms).
[19:00:27.176] <TB3> INFO: Expecting 411648 events.
[19:00:37.230] <TB3> INFO: 411648 events read in total (9650ms).
[19:00:37.297] <TB3> INFO: Expecting 411648 events.
[19:00:47.282] <TB3> INFO: 411648 events read in total (9580ms).
[19:00:47.347] <TB3> INFO: Test took 166416ms.
[19:00:48.391] <TB3> INFO: ---> dac: vcal name: TrimThr2 ntrig: 8 dacrange: 0 .. 150 (-1/-1) hits flags = 528 (plus default)
[19:00:48.405] <TB3> INFO: dacScan split into 1 runs with ntrig = 8
[19:00:48.405] <TB3> INFO: run 1 of 1
[19:00:48.720] <TB3> INFO: Expecting 5025280 events.
[19:01:20.425] <TB3> INFO: 588304 events read in total (31112ms).
[19:01:52.212] <TB3> INFO: 1174240 events read in total (62899ms).
[19:02:23.349] <TB3> INFO: 1760520 events read in total (94037ms).
[19:02:54.007] <TB3> INFO: 2346976 events read in total (124694ms).
[19:03:25.832] <TB3> INFO: 2932776 events read in total (156519ms).
[19:03:58.206] <TB3> INFO: 3518216 events read in total (188893ms).
[19:04:28.416] <TB3> INFO: 4103088 events read in total (219103ms).
[19:04:58.216] <TB3> INFO: 4688672 events read in total (248903ms).
[19:05:15.464] <TB3> INFO: 5025280 events read in total (266151ms).
[19:05:15.588] <TB3> INFO: Test took 267182ms.
[19:05:51.292] <TB3> INFO: ---> TrimStepCorr4 extremal thresholds: 10.993304 .. 145.053570
[19:05:51.609] <TB3> INFO: Expecting 208000 events.
[19:06:03.288] <TB3> INFO: 208000 events read in total (11086ms).
[19:06:03.291] <TB3> INFO: Test took 11994ms.
[19:06:03.391] <TB3> INFO: ---> dac: vcal name: trimStepCorr4 ntrig: 8 dacrange: 0 .. 155 (-1/-1) hits flags = 528 (plus default)
[19:06:03.407] <TB3> INFO: dacScan split into 1 runs with ntrig = 8
[19:06:03.410] <TB3> INFO: run 1 of 1
[19:06:03.751] <TB3> INFO: Expecting 5191680 events.
[19:06:34.916] <TB3> INFO: 586312 events read in total (30572ms).
[19:07:04.914] <TB3> INFO: 1172608 events read in total (60571ms).
[19:07:34.786] <TB3> INFO: 1758728 events read in total (90443ms).
[19:08:05.898] <TB3> INFO: 2344672 events read in total (121554ms).
[19:08:36.712] <TB3> INFO: 2930432 events read in total (152368ms).
[19:09:07.351] <TB3> INFO: 3515992 events read in total (183007ms).
[19:09:37.342] <TB3> INFO: 4101024 events read in total (212998ms).
[19:10:07.262] <TB3> INFO: 4685800 events read in total (242918ms).
[19:10:32.838] <TB3> INFO: 5191680 events read in total (268494ms).
[19:10:32.933] <TB3> INFO: Test took 269522ms.
[19:11:14.175] <TB3> INFO: ---> TrimStepCorr2 extremal thresholds: 26.632112 .. 47.417106
[19:11:14.513] <TB3> INFO: Expecting 208000 events.
[19:11:26.472] <TB3> INFO: 208000 events read in total (11366ms).
[19:11:26.478] <TB3> INFO: Test took 12299ms.
[19:11:26.582] <TB3> INFO: ---> dac: vcal name: trimStepCorr2 ntrig: 8 dacrange: 16 .. 57 (-1/-1) hits flags = 528 (plus default)
[19:11:26.601] <TB3> INFO: dacScan split into 1 runs with ntrig = 8
[19:11:26.602] <TB3> INFO: run 1 of 1
[19:11:26.930] <TB3> INFO: Expecting 1397760 events.
[19:12:00.956] <TB3> INFO: 655736 events read in total (33432ms).
[19:12:34.688] <TB3> INFO: 1310952 events read in total (67164ms).
[19:12:39.287] <TB3> INFO: 1397760 events read in total (71764ms).
[19:12:39.324] <TB3> INFO: Test took 72723ms.
[19:13:01.496] <TB3> INFO: ---> TrimStepCorr1a extremal thresholds: 27.116883 .. 52.586983
[19:13:01.829] <TB3> INFO: Expecting 208000 events.
[19:13:13.359] <TB3> INFO: 208000 events read in total (10937ms).
[19:13:13.360] <TB3> INFO: Test took 11860ms.
[19:13:13.455] <TB3> INFO: ---> dac: vcal name: trimStepCorr1a ntrig: 8 dacrange: 17 .. 62 (-1/-1) hits flags = 528 (plus default)
[19:13:13.469] <TB3> INFO: dacScan split into 1 runs with ntrig = 8
[19:13:13.469] <TB3> INFO: run 1 of 1
[19:13:13.787] <TB3> INFO: Expecting 1530880 events.
[19:13:46.966] <TB3> INFO: 636760 events read in total (32585ms).
[19:14:18.568] <TB3> INFO: 1272832 events read in total (64188ms).
[19:14:31.457] <TB3> INFO: 1530880 events read in total (77076ms).
[19:14:31.489] <TB3> INFO: Test took 78019ms.
[19:14:54.338] <TB3> INFO: ---> TrimStepCorr1b extremal thresholds: 26.255442 .. 56.067670
[19:14:54.660] <TB3> INFO: Expecting 208000 events.
[19:15:06.031] <TB3> INFO: 208000 events read in total (10774ms).
[19:15:06.033] <TB3> INFO: Test took 11692ms.
[19:15:06.126] <TB3> INFO: ---> dac: vcal name: trimStepCorr1b ntrig: 8 dacrange: 16 .. 66 (-1/-1) hits flags = 528 (plus default)
[19:15:06.139] <TB3> INFO: dacScan split into 1 runs with ntrig = 8
[19:15:06.140] <TB3> INFO: run 1 of 1
[19:15:06.455] <TB3> INFO: Expecting 1697280 events.
[19:15:38.210] <TB3> INFO: 630312 events read in total (31161ms).
[19:16:09.390] <TB3> INFO: 1260312 events read in total (62341ms).
[19:16:30.959] <TB3> INFO: 1697280 events read in total (83910ms).
[19:16:30.997] <TB3> INFO: Test took 84857ms.
[19:16:54.947] <TB3> INFO: ---> TrimThrFinal extremal thresholds: 15 .. 55
[19:16:54.948] <TB3> INFO: ---> dac: vcal name: TrimThrFinal ntrig: 8 dacrange: 15 .. 55 (-1/-1) hits flags = 528 (plus default)
[19:16:54.971] <TB3> INFO: dacScan split into 1 runs with ntrig = 8
[19:16:54.971] <TB3> INFO: run 1 of 1
[19:16:55.287] <TB3> INFO: Expecting 1364480 events.
[19:17:27.997] <TB3> INFO: 668128 events read in total (32114ms).
[19:18:00.104] <TB3> INFO: 1335488 events read in total (64222ms).
[19:18:01.911] <TB3> INFO: 1364480 events read in total (66028ms).
[19:18:01.938] <TB3> INFO: Test took 66968ms.
[19:18:22.626] <TB3> INFO: write dac parameters into /home/sensor/david/FullQualification/data/M1023_FullQualification_2016-10-19_14h52m_1476881545//003_Fulltest_p17//dacParameters35_C0.dat
[19:18:22.626] <TB3> INFO: write dac parameters into /home/sensor/david/FullQualification/data/M1023_FullQualification_2016-10-19_14h52m_1476881545//003_Fulltest_p17//dacParameters35_C1.dat
[19:18:22.627] <TB3> INFO: write dac parameters into /home/sensor/david/FullQualification/data/M1023_FullQualification_2016-10-19_14h52m_1476881545//003_Fulltest_p17//dacParameters35_C2.dat
[19:18:22.627] <TB3> INFO: write dac parameters into /home/sensor/david/FullQualification/data/M1023_FullQualification_2016-10-19_14h52m_1476881545//003_Fulltest_p17//dacParameters35_C3.dat
[19:18:22.627] <TB3> INFO: write dac parameters into /home/sensor/david/FullQualification/data/M1023_FullQualification_2016-10-19_14h52m_1476881545//003_Fulltest_p17//dacParameters35_C4.dat
[19:18:22.627] <TB3> INFO: write dac parameters into /home/sensor/david/FullQualification/data/M1023_FullQualification_2016-10-19_14h52m_1476881545//003_Fulltest_p17//dacParameters35_C5.dat
[19:18:22.627] <TB3> INFO: write dac parameters into /home/sensor/david/FullQualification/data/M1023_FullQualification_2016-10-19_14h52m_1476881545//003_Fulltest_p17//dacParameters35_C6.dat
[19:18:22.628] <TB3> INFO: write dac parameters into /home/sensor/david/FullQualification/data/M1023_FullQualification_2016-10-19_14h52m_1476881545//003_Fulltest_p17//dacParameters35_C7.dat
[19:18:22.628] <TB3> INFO: write dac parameters into /home/sensor/david/FullQualification/data/M1023_FullQualification_2016-10-19_14h52m_1476881545//003_Fulltest_p17//dacParameters35_C8.dat
[19:18:22.628] <TB3> INFO: write dac parameters into /home/sensor/david/FullQualification/data/M1023_FullQualification_2016-10-19_14h52m_1476881545//003_Fulltest_p17//dacParameters35_C9.dat
[19:18:22.628] <TB3> INFO: write dac parameters into /home/sensor/david/FullQualification/data/M1023_FullQualification_2016-10-19_14h52m_1476881545//003_Fulltest_p17//dacParameters35_C10.dat
[19:18:22.628] <TB3> INFO: write dac parameters into /home/sensor/david/FullQualification/data/M1023_FullQualification_2016-10-19_14h52m_1476881545//003_Fulltest_p17//dacParameters35_C11.dat
[19:18:22.628] <TB3> INFO: write dac parameters into /home/sensor/david/FullQualification/data/M1023_FullQualification_2016-10-19_14h52m_1476881545//003_Fulltest_p17//dacParameters35_C12.dat
[19:18:22.629] <TB3> INFO: write dac parameters into /home/sensor/david/FullQualification/data/M1023_FullQualification_2016-10-19_14h52m_1476881545//003_Fulltest_p17//dacParameters35_C13.dat
[19:18:22.629] <TB3> INFO: write dac parameters into /home/sensor/david/FullQualification/data/M1023_FullQualification_2016-10-19_14h52m_1476881545//003_Fulltest_p17//dacParameters35_C14.dat
[19:18:22.629] <TB3> INFO: write dac parameters into /home/sensor/david/FullQualification/data/M1023_FullQualification_2016-10-19_14h52m_1476881545//003_Fulltest_p17//dacParameters35_C15.dat
[19:18:22.629] <TB3> INFO: write trim parameters into /home/sensor/david/FullQualification/data/M1023_FullQualification_2016-10-19_14h52m_1476881545//003_Fulltest_p17//trimParameters35_C0.dat
[19:18:22.641] <TB3> INFO: write trim parameters into /home/sensor/david/FullQualification/data/M1023_FullQualification_2016-10-19_14h52m_1476881545//003_Fulltest_p17//trimParameters35_C1.dat
[19:18:22.651] <TB3> INFO: write trim parameters into /home/sensor/david/FullQualification/data/M1023_FullQualification_2016-10-19_14h52m_1476881545//003_Fulltest_p17//trimParameters35_C2.dat
[19:18:22.662] <TB3> INFO: write trim parameters into /home/sensor/david/FullQualification/data/M1023_FullQualification_2016-10-19_14h52m_1476881545//003_Fulltest_p17//trimParameters35_C3.dat
[19:18:22.672] <TB3> INFO: write trim parameters into /home/sensor/david/FullQualification/data/M1023_FullQualification_2016-10-19_14h52m_1476881545//003_Fulltest_p17//trimParameters35_C4.dat
[19:18:22.682] <TB3> INFO: write trim parameters into /home/sensor/david/FullQualification/data/M1023_FullQualification_2016-10-19_14h52m_1476881545//003_Fulltest_p17//trimParameters35_C5.dat
[19:18:22.692] <TB3> INFO: write trim parameters into /home/sensor/david/FullQualification/data/M1023_FullQualification_2016-10-19_14h52m_1476881545//003_Fulltest_p17//trimParameters35_C6.dat
[19:18:22.702] <TB3> INFO: write trim parameters into /home/sensor/david/FullQualification/data/M1023_FullQualification_2016-10-19_14h52m_1476881545//003_Fulltest_p17//trimParameters35_C7.dat
[19:18:22.712] <TB3> INFO: write trim parameters into /home/sensor/david/FullQualification/data/M1023_FullQualification_2016-10-19_14h52m_1476881545//003_Fulltest_p17//trimParameters35_C8.dat
[19:18:22.722] <TB3> INFO: write trim parameters into /home/sensor/david/FullQualification/data/M1023_FullQualification_2016-10-19_14h52m_1476881545//003_Fulltest_p17//trimParameters35_C9.dat
[19:18:22.733] <TB3> INFO: write trim parameters into /home/sensor/david/FullQualification/data/M1023_FullQualification_2016-10-19_14h52m_1476881545//003_Fulltest_p17//trimParameters35_C10.dat
[19:18:22.743] <TB3> INFO: write trim parameters into /home/sensor/david/FullQualification/data/M1023_FullQualification_2016-10-19_14h52m_1476881545//003_Fulltest_p17//trimParameters35_C11.dat
[19:18:22.780] <TB3> INFO: write trim parameters into /home/sensor/david/FullQualification/data/M1023_FullQualification_2016-10-19_14h52m_1476881545//003_Fulltest_p17//trimParameters35_C12.dat
[19:18:22.790] <TB3> INFO: write trim parameters into /home/sensor/david/FullQualification/data/M1023_FullQualification_2016-10-19_14h52m_1476881545//003_Fulltest_p17//trimParameters35_C13.dat
[19:18:22.800] <TB3> INFO: write trim parameters into /home/sensor/david/FullQualification/data/M1023_FullQualification_2016-10-19_14h52m_1476881545//003_Fulltest_p17//trimParameters35_C14.dat
[19:18:22.810] <TB3> INFO: write trim parameters into /home/sensor/david/FullQualification/data/M1023_FullQualification_2016-10-19_14h52m_1476881545//003_Fulltest_p17//trimParameters35_C15.dat
[19:18:22.820] <TB3> INFO: PixTestTrim::trimTest() done
[19:18:22.821] <TB3> INFO: vtrim: 157 159 153 142 122 145 128 142 129 131 123 127 149 130 123 144
[19:18:22.821] <TB3> INFO: vthrcomp: 112 129 110 130 131 130 119 111 127 115 130 125 116 108 126 133
[19:18:22.821] <TB3> INFO: vcal mean: 35.02 35.09 36.72 35.15 35.06 35.15 35.05 35.19 34.94 35.02 34.93 35.04 35.35 35.02 34.93 34.96
[19:18:22.821] <TB3> INFO: vcal RMS: 1.09 1.21 2.89 1.35 1.20 1.26 1.24 1.30 0.92 1.11 1.15 1.11 1.56 1.10 0.99 1.00
[19:18:22.821] <TB3> INFO: bits mean: 9.49 9.50 9.26 9.04 9.48 9.29 9.45 9.04 9.98 9.68 9.43 8.80 9.56 8.90 9.73 9.07
[19:18:22.821] <TB3> INFO: bits RMS: 2.67 2.68 2.71 2.58 2.87 2.72 2.86 2.55 2.60 2.51 2.92 2.82 2.69 2.45 2.64 2.57
[19:18:22.834] <TB3> INFO: ----------------------------------------------------------------------
[19:18:22.834] <TB3> INFO: PixTestTrim::trimBitTest() ntrig = 5, vtrims = 254 126 63 32
[19:18:22.834] <TB3> INFO: ----------------------------------------------------------------------
[19:18:22.839] <TB3> INFO: ---> dac: Vcal name: TrimBitsThr0 ntrig: 5 dacrange: 0 .. 199 (-1/-1) hits flags = 528 (plus default)
[19:18:22.856] <TB3> INFO: dacScan split into 1 runs with ntrig = 5
[19:18:22.856] <TB3> INFO: run 1 of 1
[19:18:23.174] <TB3> INFO: Expecting 4160000 events.
[19:19:00.439] <TB3> INFO: 766685 events read in total (36672ms).
[19:19:36.932] <TB3> INFO: 1527015 events read in total (73165ms).
[19:20:13.166] <TB3> INFO: 2280710 events read in total (109399ms).
[19:20:49.405] <TB3> INFO: 3029165 events read in total (145638ms).
[19:21:25.404] <TB3> INFO: 3773290 events read in total (181637ms).
[19:21:44.183] <TB3> INFO: 4160000 events read in total (200416ms).
[19:21:44.250] <TB3> INFO: Test took 201394ms.
[19:22:26.198] <TB3> INFO: ---> dac: Vcal name: TrimThr_trim14 ntrig: 5 dacrange: 0 .. 203 (-1/-1) hits flags = 528 (plus default)
[19:22:26.213] <TB3> INFO: dacScan split into 1 runs with ntrig = 5
[19:22:26.213] <TB3> INFO: run 1 of 1
[19:22:26.527] <TB3> INFO: Expecting 4243200 events.
[19:23:02.960] <TB3> INFO: 734170 events read in total (35840ms).
[19:23:38.681] <TB3> INFO: 1464425 events read in total (71561ms).
[19:24:14.450] <TB3> INFO: 2189840 events read in total (107330ms).
[19:24:50.062] <TB3> INFO: 2910090 events read in total (142942ms).
[19:25:25.622] <TB3> INFO: 3627125 events read in total (178502ms).
[19:25:56.127] <TB3> INFO: 4243200 events read in total (209007ms).
[19:25:56.203] <TB3> INFO: Test took 209990ms.
[19:26:39.546] <TB3> INFO: ---> dac: Vcal name: TrimThr_trim13 ntrig: 5 dacrange: 0 .. 196 (-1/-1) hits flags = 528 (plus default)
[19:26:39.560] <TB3> INFO: dacScan split into 1 runs with ntrig = 5
[19:26:39.560] <TB3> INFO: run 1 of 1
[19:26:39.882] <TB3> INFO: Expecting 4097600 events.
[19:27:16.881] <TB3> INFO: 744935 events read in total (36406ms).
[19:27:53.509] <TB3> INFO: 1485585 events read in total (73034ms).
[19:28:28.813] <TB3> INFO: 2220310 events read in total (108338ms).
[19:29:04.456] <TB3> INFO: 2949520 events read in total (143981ms).
[19:29:40.253] <TB3> INFO: 3675375 events read in total (179778ms).
[19:30:00.996] <TB3> INFO: 4097600 events read in total (200521ms).
[19:30:01.067] <TB3> INFO: Test took 201508ms.
[19:30:43.032] <TB3> INFO: ---> dac: Vcal name: TrimThr_trim11 ntrig: 5 dacrange: 0 .. 196 (-1/-1) hits flags = 528 (plus default)
[19:30:43.047] <TB3> INFO: dacScan split into 1 runs with ntrig = 5
[19:30:43.047] <TB3> INFO: run 1 of 1
[19:30:43.365] <TB3> INFO: Expecting 4097600 events.
[19:31:19.717] <TB3> INFO: 747335 events read in total (35760ms).
[19:31:54.899] <TB3> INFO: 1489860 events read in total (70942ms).
[19:32:29.376] <TB3> INFO: 2226095 events read in total (105419ms).
[19:33:03.806] <TB3> INFO: 2956995 events read in total (139849ms).
[19:33:38.106] <TB3> INFO: 3683920 events read in total (174149ms).
[19:33:57.669] <TB3> INFO: 4097600 events read in total (193712ms).
[19:33:57.733] <TB3> INFO: Test took 194686ms.
[19:34:35.788] <TB3> INFO: ---> dac: Vcal name: TrimThr_trim7 ntrig: 5 dacrange: 0 .. 198 (-1/-1) hits flags = 528 (plus default)
[19:34:35.801] <TB3> INFO: dacScan split into 1 runs with ntrig = 5
[19:34:35.801] <TB3> INFO: run 1 of 1
[19:34:36.095] <TB3> INFO: Expecting 4139200 events.
[19:35:11.416] <TB3> INFO: 744210 events read in total (34728ms).
[19:35:46.066] <TB3> INFO: 1483525 events read in total (69378ms).
[19:36:20.497] <TB3> INFO: 2217605 events read in total (103809ms).
[19:36:54.840] <TB3> INFO: 2946070 events read in total (138152ms).
[19:37:29.106] <TB3> INFO: 3670930 events read in total (172418ms).
[19:37:51.269] <TB3> INFO: 4139200 events read in total (194581ms).
[19:37:51.335] <TB3> INFO: Test took 195534ms.
[19:38:30.048] <TB3> INFO: PixTestTrim::trimBitTest() done
[19:38:30.049] <TB3> INFO: PixTestTrim::doTest() done, duration: 2983 seconds
[19:38:30.049] <TB3> INFO: Decoding statistics:
[19:38:30.049] <TB3> INFO: General information:
[19:38:30.050] <TB3> INFO: 16bit words read: 0
[19:38:30.050] <TB3> INFO: valid events total: 0
[19:38:30.050] <TB3> INFO: empty events: 0
[19:38:30.050] <TB3> INFO: valid events with pixels: 0
[19:38:30.050] <TB3> INFO: valid pixel hits: 0
[19:38:30.050] <TB3> INFO: Event errors: 0
[19:38:30.050] <TB3> INFO: start marker: 0
[19:38:30.050] <TB3> INFO: stop marker: 0
[19:38:30.050] <TB3> INFO: overflow: 0
[19:38:30.050] <TB3> INFO: invalid 5bit words: 0
[19:38:30.050] <TB3> INFO: invalid XOR eye diagram: 0
[19:38:30.050] <TB3> INFO: frame (failed synchr.): 0
[19:38:30.050] <TB3> INFO: idle data (no TBM trl): 0
[19:38:30.050] <TB3> INFO: no data (only TBM hdr): 0
[19:38:30.050] <TB3> INFO: TBM errors: 0
[19:38:30.050] <TB3> INFO: flawed TBM headers: 0
[19:38:30.050] <TB3> INFO: flawed TBM trailers: 0
[19:38:30.050] <TB3> INFO: event ID mismatches: 0
[19:38:30.050] <TB3> INFO: ROC errors: 0
[19:38:30.050] <TB3> INFO: missing ROC header(s): 0
[19:38:30.050] <TB3> INFO: misplaced readback start: 0
[19:38:30.050] <TB3> INFO: Pixel decoding errors: 0
[19:38:30.050] <TB3> INFO: pixel data incomplete: 0
[19:38:30.050] <TB3> INFO: pixel address: 0
[19:38:30.050] <TB3> INFO: pulse height fill bit: 0
[19:38:30.050] <TB3> INFO: buffer corruption: 0
[19:38:31.009] <TB3> INFO: ######################################################################
[19:38:31.009] <TB3> INFO: PixTestPhOptimization::doTest() Ntrig = 10
[19:38:31.009] <TB3> INFO: ######################################################################
[19:38:31.323] <TB3> INFO: Expecting 41600 events.
[19:38:35.062] <TB3> INFO: 41600 events read in total (3146ms).
[19:38:35.063] <TB3> INFO: Test took 4052ms.
[19:38:35.570] <TB3> INFO: Expecting 41600 events.
[19:38:39.388] <TB3> INFO: 41600 events read in total (3225ms).
[19:38:39.389] <TB3> INFO: Test took 4113ms.
[19:38:39.710] <TB3> INFO: Expecting 41600 events.
[19:38:43.526] <TB3> INFO: 41600 events read in total (3223ms).
[19:38:43.527] <TB3> INFO: Test took 4110ms.
[19:38:43.850] <TB3> INFO: Expecting 41600 events.
[19:38:47.670] <TB3> INFO: 41600 events read in total (3226ms).
[19:38:47.671] <TB3> INFO: Test took 4115ms.
[19:38:47.993] <TB3> INFO: Expecting 41600 events.
[19:38:51.818] <TB3> INFO: 41600 events read in total (3231ms).
[19:38:51.819] <TB3> INFO: Test took 4120ms.
[19:38:52.143] <TB3> INFO: Expecting 41600 events.
[19:38:55.969] <TB3> INFO: 41600 events read in total (3232ms).
[19:38:55.970] <TB3> INFO: Test took 4122ms.
[19:38:56.292] <TB3> INFO: Expecting 41600 events.
[19:39:00.119] <TB3> INFO: 41600 events read in total (3233ms).
[19:39:00.120] <TB3> INFO: Test took 4123ms.
[19:39:00.443] <TB3> INFO: Expecting 41600 events.
[19:39:04.258] <TB3> INFO: 41600 events read in total (3222ms).
[19:39:04.259] <TB3> INFO: Test took 4110ms.
[19:39:04.582] <TB3> INFO: Expecting 41600 events.
[19:39:08.405] <TB3> INFO: 41600 events read in total (3229ms).
[19:39:08.406] <TB3> INFO: Test took 4117ms.
[19:39:08.728] <TB3> INFO: Expecting 41600 events.
[19:39:12.545] <TB3> INFO: 41600 events read in total (3224ms).
[19:39:12.546] <TB3> INFO: Test took 4112ms.
[19:39:12.868] <TB3> INFO: Expecting 41600 events.
[19:39:16.690] <TB3> INFO: 41600 events read in total (3230ms).
[19:39:16.691] <TB3> INFO: Test took 4116ms.
[19:39:17.015] <TB3> INFO: Expecting 41600 events.
[19:39:20.830] <TB3> INFO: 41600 events read in total (3221ms).
[19:39:20.831] <TB3> INFO: Test took 4110ms.
[19:39:21.152] <TB3> INFO: Expecting 41600 events.
[19:39:24.970] <TB3> INFO: 41600 events read in total (3225ms).
[19:39:24.971] <TB3> INFO: Test took 4112ms.
[19:39:25.293] <TB3> INFO: Expecting 41600 events.
[19:39:29.096] <TB3> INFO: 41600 events read in total (3210ms).
[19:39:29.097] <TB3> INFO: Test took 4096ms.
[19:39:29.417] <TB3> INFO: Expecting 41600 events.
[19:39:33.245] <TB3> INFO: 41600 events read in total (3235ms).
[19:39:33.246] <TB3> INFO: Test took 4121ms.
[19:39:33.568] <TB3> INFO: Expecting 41600 events.
[19:39:37.369] <TB3> INFO: 41600 events read in total (3208ms).
[19:39:37.370] <TB3> INFO: Test took 4096ms.
[19:39:37.690] <TB3> INFO: Expecting 41600 events.
[19:39:41.499] <TB3> INFO: 41600 events read in total (3216ms).
[19:39:41.500] <TB3> INFO: Test took 4102ms.
[19:39:41.823] <TB3> INFO: Expecting 41600 events.
[19:39:45.624] <TB3> INFO: 41600 events read in total (3208ms).
[19:39:45.625] <TB3> INFO: Test took 4097ms.
[19:39:45.945] <TB3> INFO: Expecting 41600 events.
[19:39:49.769] <TB3> INFO: 41600 events read in total (3231ms).
[19:39:49.770] <TB3> INFO: Test took 4117ms.
[19:39:50.095] <TB3> INFO: Expecting 41600 events.
[19:39:53.923] <TB3> INFO: 41600 events read in total (3235ms).
[19:39:53.924] <TB3> INFO: Test took 4125ms.
[19:39:54.248] <TB3> INFO: Expecting 41600 events.
[19:39:58.075] <TB3> INFO: 41600 events read in total (3234ms).
[19:39:58.076] <TB3> INFO: Test took 4124ms.
[19:39:58.398] <TB3> INFO: Expecting 41600 events.
[19:40:02.217] <TB3> INFO: 41600 events read in total (3226ms).
[19:40:02.218] <TB3> INFO: Test took 4114ms.
[19:40:02.539] <TB3> INFO: Expecting 41600 events.
[19:40:06.364] <TB3> INFO: 41600 events read in total (3232ms).
[19:40:06.365] <TB3> INFO: Test took 4119ms.
[19:40:06.688] <TB3> INFO: Expecting 41600 events.
[19:40:10.524] <TB3> INFO: 41600 events read in total (3243ms).
[19:40:10.525] <TB3> INFO: Test took 4132ms.
[19:40:10.847] <TB3> INFO: Expecting 41600 events.
[19:40:14.674] <TB3> INFO: 41600 events read in total (3234ms).
[19:40:14.675] <TB3> INFO: Test took 4122ms.
[19:40:14.997] <TB3> INFO: Expecting 41600 events.
[19:40:18.823] <TB3> INFO: 41600 events read in total (3233ms).
[19:40:18.824] <TB3> INFO: Test took 4121ms.
[19:40:19.146] <TB3> INFO: Expecting 41600 events.
[19:40:22.980] <TB3> INFO: 41600 events read in total (3241ms).
[19:40:22.981] <TB3> INFO: Test took 4129ms.
[19:40:23.307] <TB3> INFO: Expecting 41600 events.
[19:40:27.130] <TB3> INFO: 41600 events read in total (3230ms).
[19:40:27.131] <TB3> INFO: Test took 4120ms.
[19:40:27.428] <TB3> INFO: Expecting 2560 events.
[19:40:28.321] <TB3> INFO: 2560 events read in total (300ms).
[19:40:28.321] <TB3> INFO: Test took 1169ms.
[19:40:28.631] <TB3> INFO: Expecting 2560 events.
[19:40:29.524] <TB3> INFO: 2560 events read in total (299ms).
[19:40:29.524] <TB3> INFO: Test took 1199ms.
[19:40:29.834] <TB3> INFO: Expecting 2560 events.
[19:40:30.727] <TB3> INFO: 2560 events read in total (299ms).
[19:40:30.728] <TB3> INFO: Test took 1202ms.
[19:40:31.038] <TB3> INFO: Expecting 2560 events.
[19:40:31.930] <TB3> INFO: 2560 events read in total (299ms).
[19:40:31.931] <TB3> INFO: Test took 1201ms.
[19:40:32.241] <TB3> INFO: Expecting 2560 events.
[19:40:33.129] <TB3> INFO: 2560 events read in total (295ms).
[19:40:33.130] <TB3> INFO: Test took 1195ms.
[19:40:33.440] <TB3> INFO: Expecting 2560 events.
[19:40:34.328] <TB3> INFO: 2560 events read in total (295ms).
[19:40:34.329] <TB3> INFO: Test took 1195ms.
[19:40:34.639] <TB3> INFO: Expecting 2560 events.
[19:40:35.527] <TB3> INFO: 2560 events read in total (295ms).
[19:40:35.528] <TB3> INFO: Test took 1198ms.
[19:40:35.838] <TB3> INFO: Expecting 2560 events.
[19:40:36.726] <TB3> INFO: 2560 events read in total (295ms).
[19:40:36.727] <TB3> INFO: Test took 1197ms.
[19:40:37.036] <TB3> INFO: Expecting 2560 events.
[19:40:37.924] <TB3> INFO: 2560 events read in total (295ms).
[19:40:37.925] <TB3> INFO: Test took 1198ms.
[19:40:38.235] <TB3> INFO: Expecting 2560 events.
[19:40:39.123] <TB3> INFO: 2560 events read in total (295ms).
[19:40:39.123] <TB3> INFO: Test took 1197ms.
[19:40:39.433] <TB3> INFO: Expecting 2560 events.
[19:40:40.321] <TB3> INFO: 2560 events read in total (295ms).
[19:40:40.321] <TB3> INFO: Test took 1197ms.
[19:40:40.631] <TB3> INFO: Expecting 2560 events.
[19:40:41.520] <TB3> INFO: 2560 events read in total (296ms).
[19:40:41.520] <TB3> INFO: Test took 1194ms.
[19:40:41.830] <TB3> INFO: Expecting 2560 events.
[19:40:42.723] <TB3> INFO: 2560 events read in total (299ms).
[19:40:42.724] <TB3> INFO: Test took 1203ms.
[19:40:43.034] <TB3> INFO: Expecting 2560 events.
[19:40:43.927] <TB3> INFO: 2560 events read in total (300ms).
[19:40:43.927] <TB3> INFO: Test took 1201ms.
[19:40:44.237] <TB3> INFO: Expecting 2560 events.
[19:40:45.130] <TB3> INFO: 2560 events read in total (300ms).
[19:40:45.130] <TB3> INFO: Test took 1202ms.
[19:40:45.440] <TB3> INFO: Expecting 2560 events.
[19:40:46.333] <TB3> INFO: 2560 events read in total (300ms).
[19:40:46.333] <TB3> INFO: Test took 1198ms.
[19:40:46.342] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[19:40:46.644] <TB3> INFO: Expecting 655360 events.
[19:41:02.419] <TB3> INFO: 655360 events read in total (15182ms).
[19:41:02.432] <TB3> INFO: Expecting 655360 events.
[19:41:17.004] <TB3> INFO: 655360 events read in total (15167ms).
[19:41:18.023] <TB3> INFO: Expecting 655360 events.
[19:41:33.598] <TB3> INFO: 655360 events read in total (15171ms).
[19:41:33.622] <TB3> INFO: Expecting 655360 events.
[19:41:49.210] <TB3> INFO: 655360 events read in total (15183ms).
[19:41:49.239] <TB3> INFO: Expecting 655360 events.
[19:42:04.790] <TB3> INFO: 655360 events read in total (15146ms).
[19:42:04.825] <TB3> INFO: Expecting 655360 events.
[19:42:20.371] <TB3> INFO: 655360 events read in total (15142ms).
[19:42:20.411] <TB3> INFO: Expecting 655360 events.
[19:42:35.968] <TB3> INFO: 655360 events read in total (15152ms).
[19:42:36.016] <TB3> INFO: Expecting 655360 events.
[19:42:51.564] <TB3> INFO: 655360 events read in total (15144ms).
[19:42:51.620] <TB3> INFO: Expecting 655360 events.
[19:43:07.149] <TB3> INFO: 655360 events read in total (15125ms).
[19:43:07.204] <TB3> INFO: Expecting 655360 events.
[19:43:22.749] <TB3> INFO: 655360 events read in total (15140ms).
[19:43:22.816] <TB3> INFO: Expecting 655360 events.
[19:43:38.348] <TB3> INFO: 655360 events read in total (15127ms).
[19:43:38.414] <TB3> INFO: Expecting 655360 events.
[19:43:53.994] <TB3> INFO: 655360 events read in total (15175ms).
[19:43:54.072] <TB3> INFO: Expecting 655360 events.
[19:44:09.597] <TB3> INFO: 655360 events read in total (15120ms).
[19:44:09.674] <TB3> INFO: Expecting 655360 events.
[19:44:25.211] <TB3> INFO: 655360 events read in total (15132ms).
[19:44:25.302] <TB3> INFO: Expecting 655360 events.
[19:44:40.835] <TB3> INFO: 655360 events read in total (15128ms).
[19:44:40.930] <TB3> INFO: Expecting 655360 events.
[19:44:56.482] <TB3> INFO: 655360 events read in total (15147ms).
[19:44:56.583] <TB3> INFO: Test took 250241ms.
[19:44:56.695] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[19:44:56.949] <TB3> INFO: Expecting 655360 events.
[19:45:12.606] <TB3> INFO: 655360 events read in total (15063ms).
[19:45:12.619] <TB3> INFO: Expecting 655360 events.
[19:45:28.088] <TB3> INFO: 655360 events read in total (15064ms).
[19:45:28.107] <TB3> INFO: Expecting 655360 events.
[19:45:43.403] <TB3> INFO: 655360 events read in total (14892ms).
[19:45:43.427] <TB3> INFO: Expecting 655360 events.
[19:45:58.822] <TB3> INFO: 655360 events read in total (14990ms).
[19:45:58.851] <TB3> INFO: Expecting 655360 events.
[19:46:14.270] <TB3> INFO: 655360 events read in total (15014ms).
[19:46:14.304] <TB3> INFO: Expecting 655360 events.
[19:46:29.627] <TB3> INFO: 655360 events read in total (14918ms).
[19:46:29.667] <TB3> INFO: Expecting 655360 events.
[19:46:45.187] <TB3> INFO: 655360 events read in total (15116ms).
[19:46:45.235] <TB3> INFO: Expecting 655360 events.
[19:47:00.696] <TB3> INFO: 655360 events read in total (15056ms).
[19:47:00.751] <TB3> INFO: Expecting 655360 events.
[19:47:15.962] <TB3> INFO: 655360 events read in total (14807ms).
[19:47:16.018] <TB3> INFO: Expecting 655360 events.
[19:47:31.486] <TB3> INFO: 655360 events read in total (15064ms).
[19:47:31.552] <TB3> INFO: Expecting 655360 events.
[19:47:46.957] <TB3> INFO: 655360 events read in total (15000ms).
[19:47:47.023] <TB3> INFO: Expecting 655360 events.
[19:48:02.518] <TB3> INFO: 655360 events read in total (15091ms).
[19:48:02.596] <TB3> INFO: Expecting 655360 events.
[19:48:17.678] <TB3> INFO: 655360 events read in total (14677ms).
[19:48:17.754] <TB3> INFO: Expecting 655360 events.
[19:48:33.291] <TB3> INFO: 655360 events read in total (15132ms).
[19:48:33.381] <TB3> INFO: Expecting 655360 events.
[19:48:48.778] <TB3> INFO: 655360 events read in total (14993ms).
[19:48:48.873] <TB3> INFO: Expecting 655360 events.
[19:49:04.341] <TB3> INFO: 655360 events read in total (15063ms).
[19:49:04.443] <TB3> INFO: Test took 247748ms.
[19:49:04.743] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[19:49:04.751] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[19:49:04.759] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[19:49:04.766] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[19:49:04.774] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[19:49:04.781] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[19:49:04.788] <TB3> INFO: safety margin for low PH: adding 1, margin is now 21
[19:49:04.796] <TB3> INFO: safety margin for low PH: adding 2, margin is now 22
[19:49:04.803] <TB3> INFO: safety margin for low PH: adding 3, margin is now 23
[19:49:04.811] <TB3> INFO: safety margin for low PH: adding 4, margin is now 24
[19:49:04.818] <TB3> INFO: safety margin for low PH: adding 5, margin is now 25
[19:49:04.825] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[19:49:04.833] <TB3> INFO: safety margin for low PH: adding 1, margin is now 21
[19:49:04.840] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[19:49:04.848] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[19:49:04.855] <TB3> INFO: safety margin for low PH: adding 1, margin is now 21
[19:49:04.862] <TB3> INFO: safety margin for low PH: adding 2, margin is now 22
[19:49:04.870] <TB3> INFO: safety margin for low PH: adding 3, margin is now 23
[19:49:04.877] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[19:49:04.885] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[19:49:04.892] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[19:49:04.899] <TB3> INFO: safety margin for low PH: adding 1, margin is now 21
[19:49:04.907] <TB3> INFO: safety margin for low PH: adding 2, margin is now 22
[19:49:04.914] <TB3> INFO: safety margin for low PH: adding 3, margin is now 23
[19:49:04.922] <TB3> INFO: safety margin for low PH: adding 4, margin is now 24
[19:49:04.929] <TB3> INFO: safety margin for low PH: adding 5, margin is now 25
[19:49:04.936] <TB3> INFO: safety margin for low PH: adding 6, margin is now 26
[19:49:04.944] <TB3> INFO: safety margin for low PH: adding 7, margin is now 27
[19:49:04.951] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[19:49:04.959] <TB3> INFO: safety margin for low PH: adding 1, margin is now 21
[19:49:04.966] <TB3> INFO: safety margin for low PH: adding 2, margin is now 22
[19:49:04.973] <TB3> INFO: safety margin for low PH: adding 3, margin is now 23
[19:49:04.981] <TB3> INFO: safety margin for low PH: adding 4, margin is now 24
[19:49:04.988] <TB3> INFO: safety margin for low PH: adding 5, margin is now 25
[19:49:04.995] <TB3> INFO: safety margin for low PH: adding 6, margin is now 26
[19:49:04.003] <TB3> INFO: safety margin for low PH: adding 7, margin is now 27
[19:49:05.010] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[19:49:05.018] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[19:49:05.025] <TB3> INFO: safety margin for low PH: adding 1, margin is now 21
[19:49:05.032] <TB3> INFO: safety margin for low PH: adding 2, margin is now 22
[19:49:05.040] <TB3> INFO: safety margin for low PH: adding 3, margin is now 23
[19:49:05.047] <TB3> INFO: safety margin for low PH: adding 4, margin is now 24
[19:49:05.055] <TB3> INFO: safety margin for low PH: adding 5, margin is now 25
[19:49:05.062] <TB3> INFO: safety margin for low PH: adding 6, margin is now 26
[19:49:05.069] <TB3> INFO: safety margin for low PH: adding 7, margin is now 27
[19:49:05.077] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[19:49:05.084] <TB3> INFO: safety margin for low PH: adding 1, margin is now 21
[19:49:05.092] <TB3> INFO: safety margin for low PH: adding 2, margin is now 22
[19:49:05.099] <TB3> INFO: safety margin for low PH: adding 3, margin is now 23
[19:49:05.107] <TB3> INFO: safety margin for low PH: adding 4, margin is now 24
[19:49:05.114] <TB3> INFO: safety margin for low PH: adding 5, margin is now 25
[19:49:05.122] <TB3> INFO: safety margin for low PH: adding 6, margin is now 26
[19:49:05.129] <TB3> INFO: safety margin for low PH: adding 7, margin is now 27
[19:49:05.136] <TB3> INFO: safety margin for low PH: adding 8, margin is now 28
[19:49:05.144] <TB3> INFO: safety margin for low PH: adding 9, margin is now 29
[19:49:05.151] <TB3> INFO: safety margin for low PH: adding 10, margin is now 30
[19:49:05.159] <TB3> INFO: safety margin for low PH: adding 11, margin is now 31
[19:49:05.166] <TB3> INFO: safety margin for low PH: adding 12, margin is now 32
[19:49:05.240] <TB3> INFO: write dac parameters into /home/sensor/david/FullQualification/data/M1023_FullQualification_2016-10-19_14h52m_1476881545//003_Fulltest_p17//dacParameters35_C0.dat
[19:49:05.240] <TB3> INFO: write dac parameters into /home/sensor/david/FullQualification/data/M1023_FullQualification_2016-10-19_14h52m_1476881545//003_Fulltest_p17//dacParameters35_C1.dat
[19:49:05.240] <TB3> INFO: write dac parameters into /home/sensor/david/FullQualification/data/M1023_FullQualification_2016-10-19_14h52m_1476881545//003_Fulltest_p17//dacParameters35_C2.dat
[19:49:05.240] <TB3> INFO: write dac parameters into /home/sensor/david/FullQualification/data/M1023_FullQualification_2016-10-19_14h52m_1476881545//003_Fulltest_p17//dacParameters35_C3.dat
[19:49:05.240] <TB3> INFO: write dac parameters into /home/sensor/david/FullQualification/data/M1023_FullQualification_2016-10-19_14h52m_1476881545//003_Fulltest_p17//dacParameters35_C4.dat
[19:49:05.241] <TB3> INFO: write dac parameters into /home/sensor/david/FullQualification/data/M1023_FullQualification_2016-10-19_14h52m_1476881545//003_Fulltest_p17//dacParameters35_C5.dat
[19:49:05.241] <TB3> INFO: write dac parameters into /home/sensor/david/FullQualification/data/M1023_FullQualification_2016-10-19_14h52m_1476881545//003_Fulltest_p17//dacParameters35_C6.dat
[19:49:05.241] <TB3> INFO: write dac parameters into /home/sensor/david/FullQualification/data/M1023_FullQualification_2016-10-19_14h52m_1476881545//003_Fulltest_p17//dacParameters35_C7.dat
[19:49:05.241] <TB3> INFO: write dac parameters into /home/sensor/david/FullQualification/data/M1023_FullQualification_2016-10-19_14h52m_1476881545//003_Fulltest_p17//dacParameters35_C8.dat
[19:49:05.241] <TB3> INFO: write dac parameters into /home/sensor/david/FullQualification/data/M1023_FullQualification_2016-10-19_14h52m_1476881545//003_Fulltest_p17//dacParameters35_C9.dat
[19:49:05.241] <TB3> INFO: write dac parameters into /home/sensor/david/FullQualification/data/M1023_FullQualification_2016-10-19_14h52m_1476881545//003_Fulltest_p17//dacParameters35_C10.dat
[19:49:05.241] <TB3> INFO: write dac parameters into /home/sensor/david/FullQualification/data/M1023_FullQualification_2016-10-19_14h52m_1476881545//003_Fulltest_p17//dacParameters35_C11.dat
[19:49:05.242] <TB3> INFO: write dac parameters into /home/sensor/david/FullQualification/data/M1023_FullQualification_2016-10-19_14h52m_1476881545//003_Fulltest_p17//dacParameters35_C12.dat
[19:49:05.242] <TB3> INFO: write dac parameters into /home/sensor/david/FullQualification/data/M1023_FullQualification_2016-10-19_14h52m_1476881545//003_Fulltest_p17//dacParameters35_C13.dat
[19:49:05.242] <TB3> INFO: write dac parameters into /home/sensor/david/FullQualification/data/M1023_FullQualification_2016-10-19_14h52m_1476881545//003_Fulltest_p17//dacParameters35_C14.dat
[19:49:05.242] <TB3> INFO: write dac parameters into /home/sensor/david/FullQualification/data/M1023_FullQualification_2016-10-19_14h52m_1476881545//003_Fulltest_p17//dacParameters35_C15.dat
[19:49:05.543] <TB3> INFO: Expecting 41600 events.
[19:49:08.828] <TB3> INFO: 41600 events read in total (2692ms).
[19:49:08.829] <TB3> INFO: Test took 3582ms.
[19:49:09.365] <TB3> INFO: Expecting 41600 events.
[19:49:12.524] <TB3> INFO: 41600 events read in total (2566ms).
[19:49:12.526] <TB3> INFO: Test took 3456ms.
[19:49:13.053] <TB3> INFO: Expecting 41600 events.
[19:49:16.339] <TB3> INFO: 41600 events read in total (2693ms).
[19:49:16.340] <TB3> INFO: Test took 3581ms.
[19:49:16.580] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[19:49:16.671] <TB3> INFO: Expecting 2560 events.
[19:49:17.564] <TB3> INFO: 2560 events read in total (300ms).
[19:49:17.564] <TB3> INFO: Test took 984ms.
[19:49:17.570] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[19:49:17.874] <TB3> INFO: Expecting 2560 events.
[19:49:18.768] <TB3> INFO: 2560 events read in total (301ms).
[19:49:18.769] <TB3> INFO: Test took 1199ms.
[19:49:18.774] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[19:49:19.079] <TB3> INFO: Expecting 2560 events.
[19:49:19.971] <TB3> INFO: 2560 events read in total (299ms).
[19:49:19.972] <TB3> INFO: Test took 1198ms.
[19:49:19.978] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[19:49:20.283] <TB3> INFO: Expecting 2560 events.
[19:49:21.175] <TB3> INFO: 2560 events read in total (299ms).
[19:49:21.176] <TB3> INFO: Test took 1198ms.
[19:49:21.180] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[19:49:21.486] <TB3> INFO: Expecting 2560 events.
[19:49:22.379] <TB3> INFO: 2560 events read in total (300ms).
[19:49:22.380] <TB3> INFO: Test took 1200ms.
[19:49:22.383] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[19:49:22.690] <TB3> INFO: Expecting 2560 events.
[19:49:23.583] <TB3> INFO: 2560 events read in total (300ms).
[19:49:23.583] <TB3> INFO: Test took 1200ms.
[19:49:23.591] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[19:49:23.894] <TB3> INFO: Expecting 2560 events.
[19:49:24.787] <TB3> INFO: 2560 events read in total (300ms).
[19:49:24.788] <TB3> INFO: Test took 1197ms.
[19:49:24.794] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[19:49:25.098] <TB3> INFO: Expecting 2560 events.
[19:49:25.991] <TB3> INFO: 2560 events read in total (299ms).
[19:49:25.992] <TB3> INFO: Test took 1198ms.
[19:49:25.997] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[19:49:26.302] <TB3> INFO: Expecting 2560 events.
[19:49:27.190] <TB3> INFO: 2560 events read in total (295ms).
[19:49:27.191] <TB3> INFO: Test took 1194ms.
[19:49:27.197] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[19:49:27.501] <TB3> INFO: Expecting 2560 events.
[19:49:28.390] <TB3> INFO: 2560 events read in total (295ms).
[19:49:28.390] <TB3> INFO: Test took 1193ms.
[19:49:28.397] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[19:49:28.701] <TB3> INFO: Expecting 2560 events.
[19:49:29.589] <TB3> INFO: 2560 events read in total (296ms).
[19:49:29.589] <TB3> INFO: Test took 1192ms.
[19:49:29.594] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[19:49:29.900] <TB3> INFO: Expecting 2560 events.
[19:49:30.788] <TB3> INFO: 2560 events read in total (296ms).
[19:49:30.788] <TB3> INFO: Test took 1194ms.
[19:49:30.795] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[19:49:31.099] <TB3> INFO: Expecting 2560 events.
[19:49:31.988] <TB3> INFO: 2560 events read in total (296ms).
[19:49:31.988] <TB3> INFO: Test took 1193ms.
[19:49:31.997] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[19:49:32.299] <TB3> INFO: Expecting 2560 events.
[19:49:33.187] <TB3> INFO: 2560 events read in total (295ms).
[19:49:33.188] <TB3> INFO: Test took 1192ms.
[19:49:33.192] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[19:49:33.498] <TB3> INFO: Expecting 2560 events.
[19:49:34.387] <TB3> INFO: 2560 events read in total (295ms).
[19:49:34.388] <TB3> INFO: Test took 1196ms.
[19:49:34.394] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[19:49:34.698] <TB3> INFO: Expecting 2560 events.
[19:49:35.587] <TB3> INFO: 2560 events read in total (295ms).
[19:49:35.588] <TB3> INFO: Test took 1194ms.
[19:49:35.591] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[19:49:35.898] <TB3> INFO: Expecting 2560 events.
[19:49:36.787] <TB3> INFO: 2560 events read in total (295ms).
[19:49:36.788] <TB3> INFO: Test took 1197ms.
[19:49:36.794] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[19:49:37.099] <TB3> INFO: Expecting 2560 events.
[19:49:37.987] <TB3> INFO: 2560 events read in total (295ms).
[19:49:37.987] <TB3> INFO: Test took 1193ms.
[19:49:37.993] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[19:49:38.298] <TB3> INFO: Expecting 2560 events.
[19:49:39.186] <TB3> INFO: 2560 events read in total (295ms).
[19:49:39.186] <TB3> INFO: Test took 1193ms.
[19:49:39.193] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[19:49:39.497] <TB3> INFO: Expecting 2560 events.
[19:49:40.386] <TB3> INFO: 2560 events read in total (296ms).
[19:49:40.386] <TB3> INFO: Test took 1193ms.
[19:49:40.392] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[19:49:40.697] <TB3> INFO: Expecting 2560 events.
[19:49:41.585] <TB3> INFO: 2560 events read in total (295ms).
[19:49:41.585] <TB3> INFO: Test took 1193ms.
[19:49:41.590] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[19:49:41.896] <TB3> INFO: Expecting 2560 events.
[19:49:42.785] <TB3> INFO: 2560 events read in total (296ms).
[19:49:42.786] <TB3> INFO: Test took 1196ms.
[19:49:42.791] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[19:49:43.096] <TB3> INFO: Expecting 2560 events.
[19:49:43.984] <TB3> INFO: 2560 events read in total (295ms).
[19:49:43.984] <TB3> INFO: Test took 1193ms.
[19:49:43.000] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[19:49:44.295] <TB3> INFO: Expecting 2560 events.
[19:49:45.183] <TB3> INFO: 2560 events read in total (295ms).
[19:49:45.183] <TB3> INFO: Test took 1183ms.
[19:49:45.188] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[19:49:45.493] <TB3> INFO: Expecting 2560 events.
[19:49:46.386] <TB3> INFO: 2560 events read in total (299ms).
[19:49:46.386] <TB3> INFO: Test took 1198ms.
[19:49:46.391] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[19:49:46.697] <TB3> INFO: Expecting 2560 events.
[19:49:47.589] <TB3> INFO: 2560 events read in total (299ms).
[19:49:47.590] <TB3> INFO: Test took 1199ms.
[19:49:47.598] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[19:49:47.900] <TB3> INFO: Expecting 2560 events.
[19:49:48.793] <TB3> INFO: 2560 events read in total (300ms).
[19:49:48.793] <TB3> INFO: Test took 1196ms.
[19:49:48.801] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[19:49:49.104] <TB3> INFO: Expecting 2560 events.
[19:49:49.997] <TB3> INFO: 2560 events read in total (300ms).
[19:49:49.998] <TB3> INFO: Test took 1197ms.
[19:49:50.005] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[19:49:50.308] <TB3> INFO: Expecting 2560 events.
[19:49:51.201] <TB3> INFO: 2560 events read in total (299ms).
[19:49:51.202] <TB3> INFO: Test took 1197ms.
[19:49:51.207] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[19:49:51.512] <TB3> INFO: Expecting 2560 events.
[19:49:52.404] <TB3> INFO: 2560 events read in total (298ms).
[19:49:52.405] <TB3> INFO: Test took 1198ms.
[19:49:52.411] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[19:49:52.715] <TB3> INFO: Expecting 2560 events.
[19:49:53.607] <TB3> INFO: 2560 events read in total (299ms).
[19:49:53.607] <TB3> INFO: Test took 1196ms.
[19:49:53.614] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[19:49:53.918] <TB3> INFO: Expecting 2560 events.
[19:49:54.810] <TB3> INFO: 2560 events read in total (299ms).
[19:49:54.811] <TB3> INFO: Test took 1197ms.
[19:49:55.376] <TB3> INFO: PixTestPhOptimization::doTest() done, duration: 684 seconds
[19:49:55.376] <TB3> INFO: PH scale (per ROC): 50 40 33 39 47 48 37 55 34 33 58 42 29 42 42 49
[19:49:55.376] <TB3> INFO: PH offset (per ROC): 111 101 98 89 113 124 92 110 80 93 128 114 100 97 109 112
[19:49:55.381] <TB3> INFO: Decoding statistics:
[19:49:55.381] <TB3> INFO: General information:
[19:49:55.381] <TB3> INFO: 16bit words read: 127892
[19:49:55.381] <TB3> INFO: valid events total: 20480
[19:49:55.381] <TB3> INFO: empty events: 17974
[19:49:55.382] <TB3> INFO: valid events with pixels: 2506
[19:49:55.382] <TB3> INFO: valid pixel hits: 2506
[19:49:55.382] <TB3> INFO: Event errors: 0
[19:49:55.382] <TB3> INFO: start marker: 0
[19:49:55.382] <TB3> INFO: stop marker: 0
[19:49:55.382] <TB3> INFO: overflow: 0
[19:49:55.382] <TB3> INFO: invalid 5bit words: 0
[19:49:55.382] <TB3> INFO: invalid XOR eye diagram: 0
[19:49:55.382] <TB3> INFO: frame (failed synchr.): 0
[19:49:55.382] <TB3> INFO: idle data (no TBM trl): 0
[19:49:55.382] <TB3> INFO: no data (only TBM hdr): 0
[19:49:55.382] <TB3> INFO: TBM errors: 0
[19:49:55.382] <TB3> INFO: flawed TBM headers: 0
[19:49:55.382] <TB3> INFO: flawed TBM trailers: 0
[19:49:55.382] <TB3> INFO: event ID mismatches: 0
[19:49:55.382] <TB3> INFO: ROC errors: 0
[19:49:55.382] <TB3> INFO: missing ROC header(s): 0
[19:49:55.382] <TB3> INFO: misplaced readback start: 0
[19:49:55.382] <TB3> INFO: Pixel decoding errors: 0
[19:49:55.382] <TB3> INFO: pixel data incomplete: 0
[19:49:55.382] <TB3> INFO: pixel address: 0
[19:49:55.382] <TB3> INFO: pulse height fill bit: 0
[19:49:55.382] <TB3> INFO: buffer corruption: 0
[19:49:55.625] <TB3> INFO: ######################################################################
[19:49:55.625] <TB3> INFO: PixTestGainPedestal::fullTest() ntrig = 10
[19:49:55.625] <TB3> INFO: ######################################################################
[19:49:55.642] <TB3> INFO: scanning low vcal = 10
[19:49:55.937] <TB3> INFO: Expecting 41600 events.
[19:49:59.583] <TB3> INFO: 41600 events read in total (3052ms).
[19:49:59.583] <TB3> INFO: Test took 3940ms.
[19:49:59.589] <TB3> INFO: scanning low vcal = 20
[19:49:59.889] <TB3> INFO: Expecting 41600 events.
[19:50:03.535] <TB3> INFO: 41600 events read in total (3053ms).
[19:50:03.536] <TB3> INFO: Test took 3940ms.
[19:50:03.541] <TB3> INFO: scanning low vcal = 30
[19:50:03.843] <TB3> INFO: Expecting 41600 events.
[19:50:07.565] <TB3> INFO: 41600 events read in total (3129ms).
[19:50:07.566] <TB3> INFO: Test took 4019ms.
[19:50:07.571] <TB3> INFO: scanning low vcal = 40
[19:50:07.864] <TB3> INFO: Expecting 41600 events.
[19:50:11.947] <TB3> INFO: 41600 events read in total (3489ms).
[19:50:11.948] <TB3> INFO: Test took 4377ms.
[19:50:11.956] <TB3> INFO: scanning low vcal = 50
[19:50:12.254] <TB3> INFO: Expecting 41600 events.
[19:50:16.382] <TB3> INFO: 41600 events read in total (3535ms).
[19:50:16.383] <TB3> INFO: Test took 4422ms.
[19:50:16.388] <TB3> INFO: scanning low vcal = 60
[19:50:16.685] <TB3> INFO: Expecting 41600 events.
[19:50:20.818] <TB3> INFO: 41600 events read in total (3540ms).
[19:50:20.819] <TB3> INFO: Test took 4431ms.
[19:50:20.824] <TB3> INFO: scanning low vcal = 70
[19:50:21.118] <TB3> INFO: Expecting 41600 events.
[19:50:25.251] <TB3> INFO: 41600 events read in total (3540ms).
[19:50:25.252] <TB3> INFO: Test took 4428ms.
[19:50:25.257] <TB3> INFO: scanning low vcal = 80
[19:50:25.551] <TB3> INFO: Expecting 41600 events.
[19:50:29.678] <TB3> INFO: 41600 events read in total (3533ms).
[19:50:29.679] <TB3> INFO: Test took 4421ms.
[19:50:29.686] <TB3> INFO: scanning low vcal = 90
[19:50:29.981] <TB3> INFO: Expecting 41600 events.
[19:50:34.112] <TB3> INFO: 41600 events read in total (3538ms).
[19:50:34.113] <TB3> INFO: Test took 4427ms.
[19:50:34.120] <TB3> INFO: scanning low vcal = 100
[19:50:34.416] <TB3> INFO: Expecting 41600 events.
[19:50:38.543] <TB3> INFO: 41600 events read in total (3534ms).
[19:50:38.544] <TB3> INFO: Test took 4423ms.
[19:50:38.550] <TB3> INFO: scanning low vcal = 110
[19:50:38.846] <TB3> INFO: Expecting 41600 events.
[19:50:42.984] <TB3> INFO: 41600 events read in total (3545ms).
[19:50:42.985] <TB3> INFO: Test took 4435ms.
[19:50:42.991] <TB3> INFO: scanning low vcal = 120
[19:50:43.291] <TB3> INFO: Expecting 41600 events.
[19:50:47.423] <TB3> INFO: 41600 events read in total (3539ms).
[19:50:47.424] <TB3> INFO: Test took 4427ms.
[19:50:47.432] <TB3> INFO: scanning low vcal = 130
[19:50:47.729] <TB3> INFO: Expecting 41600 events.
[19:50:51.861] <TB3> INFO: 41600 events read in total (3539ms).
[19:50:51.862] <TB3> INFO: Test took 4430ms.
[19:50:51.870] <TB3> INFO: scanning low vcal = 140
[19:50:52.171] <TB3> INFO: Expecting 41600 events.
[19:50:56.308] <TB3> INFO: 41600 events read in total (3544ms).
[19:50:56.309] <TB3> INFO: Test took 4435ms.
[19:50:56.315] <TB3> INFO: scanning low vcal = 150
[19:50:56.615] <TB3> INFO: Expecting 41600 events.
[19:51:00.745] <TB3> INFO: 41600 events read in total (3538ms).
[19:51:00.746] <TB3> INFO: Test took 4425ms.
[19:51:00.753] <TB3> INFO: scanning low vcal = 160
[19:51:01.048] <TB3> INFO: Expecting 41600 events.
[19:51:05.177] <TB3> INFO: 41600 events read in total (3535ms).
[19:51:05.178] <TB3> INFO: Test took 4424ms.
[19:51:05.183] <TB3> INFO: scanning low vcal = 170
[19:51:05.479] <TB3> INFO: Expecting 41600 events.
[19:51:09.613] <TB3> INFO: 41600 events read in total (3540ms).
[19:51:09.614] <TB3> INFO: Test took 4431ms.
[19:51:09.623] <TB3> INFO: scanning low vcal = 180
[19:51:09.921] <TB3> INFO: Expecting 41600 events.
[19:51:14.054] <TB3> INFO: 41600 events read in total (3539ms).
[19:51:14.055] <TB3> INFO: Test took 4429ms.
[19:51:14.059] <TB3> INFO: scanning low vcal = 190
[19:51:14.356] <TB3> INFO: Expecting 41600 events.
[19:51:18.492] <TB3> INFO: 41600 events read in total (3542ms).
[19:51:18.493] <TB3> INFO: Test took 4432ms.
[19:51:18.498] <TB3> INFO: scanning low vcal = 200
[19:51:18.793] <TB3> INFO: Expecting 41600 events.
[19:51:22.930] <TB3> INFO: 41600 events read in total (3543ms).
[19:51:22.930] <TB3> INFO: Test took 4432ms.
[19:51:22.935] <TB3> INFO: scanning low vcal = 210
[19:51:23.230] <TB3> INFO: Expecting 41600 events.
[19:51:27.365] <TB3> INFO: 41600 events read in total (3543ms).
[19:51:27.366] <TB3> INFO: Test took 4431ms.
[19:51:27.371] <TB3> INFO: scanning low vcal = 220
[19:51:27.666] <TB3> INFO: Expecting 41600 events.
[19:51:31.797] <TB3> INFO: 41600 events read in total (3537ms).
[19:51:31.798] <TB3> INFO: Test took 4427ms.
[19:51:31.804] <TB3> INFO: scanning low vcal = 230
[19:51:32.100] <TB3> INFO: Expecting 41600 events.
[19:51:36.235] <TB3> INFO: 41600 events read in total (3541ms).
[19:51:36.236] <TB3> INFO: Test took 4431ms.
[19:51:36.242] <TB3> INFO: scanning low vcal = 240
[19:51:36.537] <TB3> INFO: Expecting 41600 events.
[19:51:40.670] <TB3> INFO: 41600 events read in total (3540ms).
[19:51:40.671] <TB3> INFO: Test took 4429ms.
[19:51:40.678] <TB3> INFO: scanning low vcal = 250
[19:51:40.979] <TB3> INFO: Expecting 41600 events.
[19:51:45.110] <TB3> INFO: 41600 events read in total (3538ms).
[19:51:45.111] <TB3> INFO: Test took 4427ms.
[19:51:45.120] <TB3> INFO: scanning high vcal = 30 (= 210 in low range)
[19:51:45.420] <TB3> INFO: Expecting 41600 events.
[19:51:49.562] <TB3> INFO: 41600 events read in total (3549ms).
[19:51:49.563] <TB3> INFO: Test took 4439ms.
[19:51:49.569] <TB3> INFO: scanning high vcal = 50 (= 350 in low range)
[19:51:49.870] <TB3> INFO: Expecting 41600 events.
[19:51:53.001] <TB3> INFO: 41600 events read in total (3538ms).
[19:51:53.002] <TB3> INFO: Test took 4427ms.
[19:51:54.006] <TB3> INFO: scanning high vcal = 70 (= 490 in low range)
[19:51:54.304] <TB3> INFO: Expecting 41600 events.
[19:51:58.433] <TB3> INFO: 41600 events read in total (3535ms).
[19:51:58.434] <TB3> INFO: Test took 4427ms.
[19:51:58.440] <TB3> INFO: scanning high vcal = 90 (= 630 in low range)
[19:51:58.733] <TB3> INFO: Expecting 41600 events.
[19:52:02.848] <TB3> INFO: 41600 events read in total (3521ms).
[19:52:02.849] <TB3> INFO: Test took 4409ms.
[19:52:02.856] <TB3> INFO: scanning high vcal = 200 (= 1400 in low range)
[19:52:03.154] <TB3> INFO: Expecting 41600 events.
[19:52:07.267] <TB3> INFO: 41600 events read in total (3520ms).
[19:52:07.268] <TB3> INFO: Test took 4407ms.
[19:52:07.951] <TB3> INFO: PixTestGainPedestal::measure() done
[19:52:59.774] <TB3> INFO: PixTestGainPedestal::fit() done
[19:52:59.775] <TB3> INFO: non-linearity mean: 0.961 0.923 0.946 0.943 0.957 0.979 0.940 0.987 1.030 0.923 0.984 0.941 0.931 0.938 0.921 0.973
[19:52:59.775] <TB3> INFO: non-linearity RMS: 0.029 0.078 0.109 0.181 0.032 0.004 0.100 0.002 0.166 0.139 0.004 0.127 0.142 0.091 0.123 0.007
[19:52:59.775] <TB3> INFO: write gain/ped parameters into /home/sensor/david/FullQualification/data/M1023_FullQualification_2016-10-19_14h52m_1476881545//003_Fulltest_p17//phCalibrationFitErr35_C0.dat
[19:52:59.799] <TB3> INFO: write gain/ped parameters into /home/sensor/david/FullQualification/data/M1023_FullQualification_2016-10-19_14h52m_1476881545//003_Fulltest_p17//phCalibrationFitErr35_C1.dat
[19:52:59.824] <TB3> INFO: write gain/ped parameters into /home/sensor/david/FullQualification/data/M1023_FullQualification_2016-10-19_14h52m_1476881545//003_Fulltest_p17//phCalibrationFitErr35_C2.dat
[19:52:59.848] <TB3> INFO: write gain/ped parameters into /home/sensor/david/FullQualification/data/M1023_FullQualification_2016-10-19_14h52m_1476881545//003_Fulltest_p17//phCalibrationFitErr35_C3.dat
[19:52:59.872] <TB3> INFO: write gain/ped parameters into /home/sensor/david/FullQualification/data/M1023_FullQualification_2016-10-19_14h52m_1476881545//003_Fulltest_p17//phCalibrationFitErr35_C4.dat
[19:52:59.897] <TB3> INFO: write gain/ped parameters into /home/sensor/david/FullQualification/data/M1023_FullQualification_2016-10-19_14h52m_1476881545//003_Fulltest_p17//phCalibrationFitErr35_C5.dat
[19:52:59.921] <TB3> INFO: write gain/ped parameters into /home/sensor/david/FullQualification/data/M1023_FullQualification_2016-10-19_14h52m_1476881545//003_Fulltest_p17//phCalibrationFitErr35_C6.dat
[19:52:59.946] <TB3> INFO: write gain/ped parameters into /home/sensor/david/FullQualification/data/M1023_FullQualification_2016-10-19_14h52m_1476881545//003_Fulltest_p17//phCalibrationFitErr35_C7.dat
[19:52:59.970] <TB3> INFO: write gain/ped parameters into /home/sensor/david/FullQualification/data/M1023_FullQualification_2016-10-19_14h52m_1476881545//003_Fulltest_p17//phCalibrationFitErr35_C8.dat
[19:52:59.994] <TB3> INFO: write gain/ped parameters into /home/sensor/david/FullQualification/data/M1023_FullQualification_2016-10-19_14h52m_1476881545//003_Fulltest_p17//phCalibrationFitErr35_C9.dat
[19:53:00.019] <TB3> INFO: write gain/ped parameters into /home/sensor/david/FullQualification/data/M1023_FullQualification_2016-10-19_14h52m_1476881545//003_Fulltest_p17//phCalibrationFitErr35_C10.dat
[19:53:00.043] <TB3> INFO: write gain/ped parameters into /home/sensor/david/FullQualification/data/M1023_FullQualification_2016-10-19_14h52m_1476881545//003_Fulltest_p17//phCalibrationFitErr35_C11.dat
[19:53:00.068] <TB3> INFO: write gain/ped parameters into /home/sensor/david/FullQualification/data/M1023_FullQualification_2016-10-19_14h52m_1476881545//003_Fulltest_p17//phCalibrationFitErr35_C12.dat
[19:53:00.092] <TB3> INFO: write gain/ped parameters into /home/sensor/david/FullQualification/data/M1023_FullQualification_2016-10-19_14h52m_1476881545//003_Fulltest_p17//phCalibrationFitErr35_C13.dat
[19:53:00.117] <TB3> INFO: write gain/ped parameters into /home/sensor/david/FullQualification/data/M1023_FullQualification_2016-10-19_14h52m_1476881545//003_Fulltest_p17//phCalibrationFitErr35_C14.dat
[19:53:00.141] <TB3> INFO: write gain/ped parameters into /home/sensor/david/FullQualification/data/M1023_FullQualification_2016-10-19_14h52m_1476881545//003_Fulltest_p17//phCalibrationFitErr35_C15.dat
[19:53:00.165] <TB3> INFO: PixTestGainPedestal::fullTest() done, duration: 184 seconds
[19:53:00.165] <TB3> INFO: Decoding statistics:
[19:53:00.165] <TB3> INFO: General information:
[19:53:00.166] <TB3> INFO: 16bit words read: 3282106
[19:53:00.166] <TB3> INFO: valid events total: 332800
[19:53:00.166] <TB3> INFO: empty events: 3550
[19:53:00.166] <TB3> INFO: valid events with pixels: 329250
[19:53:00.166] <TB3> INFO: valid pixel hits: 642653
[19:53:00.166] <TB3> INFO: Event errors: 0
[19:53:00.166] <TB3> INFO: start marker: 0
[19:53:00.166] <TB3> INFO: stop marker: 0
[19:53:00.166] <TB3> INFO: overflow: 0
[19:53:00.166] <TB3> INFO: invalid 5bit words: 0
[19:53:00.166] <TB3> INFO: invalid XOR eye diagram: 0
[19:53:00.166] <TB3> INFO: frame (failed synchr.): 0
[19:53:00.166] <TB3> INFO: idle data (no TBM trl): 0
[19:53:00.166] <TB3> INFO: no data (only TBM hdr): 0
[19:53:00.166] <TB3> INFO: TBM errors: 0
[19:53:00.166] <TB3> INFO: flawed TBM headers: 0
[19:53:00.166] <TB3> INFO: flawed TBM trailers: 0
[19:53:00.166] <TB3> INFO: event ID mismatches: 0
[19:53:00.166] <TB3> INFO: ROC errors: 0
[19:53:00.166] <TB3> INFO: missing ROC header(s): 0
[19:53:00.166] <TB3> INFO: misplaced readback start: 0
[19:53:00.166] <TB3> INFO: Pixel decoding errors: 0
[19:53:00.166] <TB3> INFO: pixel data incomplete: 0
[19:53:00.166] <TB3> INFO: pixel address: 0
[19:53:00.166] <TB3> INFO: pulse height fill bit: 0
[19:53:00.166] <TB3> INFO: buffer corruption: 0
[19:53:00.190] <TB3> INFO: Decoding statistics:
[19:53:00.190] <TB3> INFO: General information:
[19:53:00.190] <TB3> INFO: 16bit words read: 3411534
[19:53:00.190] <TB3> INFO: valid events total: 353536
[19:53:00.190] <TB3> INFO: empty events: 21780
[19:53:00.190] <TB3> INFO: valid events with pixels: 331756
[19:53:00.190] <TB3> INFO: valid pixel hits: 645159
[19:53:00.190] <TB3> INFO: Event errors: 0
[19:53:00.190] <TB3> INFO: start marker: 0
[19:53:00.190] <TB3> INFO: stop marker: 0
[19:53:00.191] <TB3> INFO: overflow: 0
[19:53:00.191] <TB3> INFO: invalid 5bit words: 0
[19:53:00.191] <TB3> INFO: invalid XOR eye diagram: 0
[19:53:00.191] <TB3> INFO: frame (failed synchr.): 0
[19:53:00.191] <TB3> INFO: idle data (no TBM trl): 0
[19:53:00.191] <TB3> INFO: no data (only TBM hdr): 0
[19:53:00.191] <TB3> INFO: TBM errors: 0
[19:53:00.191] <TB3> INFO: flawed TBM headers: 0
[19:53:00.191] <TB3> INFO: flawed TBM trailers: 0
[19:53:00.191] <TB3> INFO: event ID mismatches: 0
[19:53:00.191] <TB3> INFO: ROC errors: 0
[19:53:00.191] <TB3> INFO: missing ROC header(s): 0
[19:53:00.191] <TB3> INFO: misplaced readback start: 0
[19:53:00.191] <TB3> INFO: Pixel decoding errors: 0
[19:53:00.191] <TB3> INFO: pixel data incomplete: 0
[19:53:00.191] <TB3> INFO: pixel address: 0
[19:53:00.191] <TB3> INFO: pulse height fill bit: 0
[19:53:00.191] <TB3> INFO: buffer corruption: 0
[19:53:00.191] <TB3> INFO: enter test to run
[19:53:00.191] <TB3> INFO: test: exit no parameter change
[19:53:00.775] <TB3> QUIET: Connection to board 170 closed.
[19:53:00.779] <TB3> INFO: pXar: this is the end, my friend