[16:24:40.291] <TB3> INFO: *** Welcome to pxar ***
[16:24:40.291] <TB3> INFO: *** Today: 2016/10/21
[16:24:40.302] <TB3> INFO: *** Version: c8ba-dirty
[16:24:40.302] <TB3> INFO: readRocDacs: /home/l_tester/david/FullQualification/data/M1039_FullQualification_2016-10-21_13h57m_1477051074//003_Fulltest_p17//dacParameters_C0.dat .. /home/l_tester/david/FullQualification/data/M1039_FullQualification_2016-10-21_13h57m_1477051074//003_Fulltest_p17//dacParameters_C15.dat
[16:24:40.303] <TB3> INFO: readTbmDacs: /home/l_tester/david/FullQualification/data/M1039_FullQualification_2016-10-21_13h57m_1477051074//003_Fulltest_p17//tbmParameters_C0a.dat .. /home/l_tester/david/FullQualification/data/M1039_FullQualification_2016-10-21_13h57m_1477051074//003_Fulltest_p17//tbmParameters_C1b.dat
[16:24:40.303] <TB3> INFO: readMaskFile: /home/l_tester/david/FullQualification/data/M1039_FullQualification_2016-10-21_13h57m_1477051074//003_Fulltest_p17//defaultMaskFile.dat
[16:24:40.303] <TB3> INFO: readTrimFile: /home/l_tester/david/FullQualification/data/M1039_FullQualification_2016-10-21_13h57m_1477051074//003_Fulltest_p17//trimParameters_C0.dat .. /home/l_tester/david/FullQualification/data/M1039_FullQualification_2016-10-21_13h57m_1477051074//003_Fulltest_p17//trimParameters_C15.dat
[16:24:40.364] <TB3> INFO: clk: 4
[16:24:40.365] <TB3> INFO: ctr: 4
[16:24:40.365] <TB3> INFO: sda: 19
[16:24:40.365] <TB3> INFO: tin: 9
[16:24:40.365] <TB3> INFO: level: 15
[16:24:40.365] <TB3> INFO: triggerdelay: 0
[16:24:40.365] <TB3> QUIET: Instanciating API for pxar v2.7.6+55~gafdbfd9
[16:24:40.365] <TB3> INFO: Log level: INFO
[16:24:40.373] <TB3> INFO: Found DTB DTB_WWVASW
[16:24:40.382] <TB3> QUIET: Connection to board DTB_WWVASW opened.
[16:24:40.384] <TB3> INFO: DTB startup information
--- DTB info------------------------------------------
Board id: 126
HW version: DTB1.2
FW version: 4.6
SW version: 4.7
Options:
USB id: DTB_WWVASW
MAC address: 40D85511807E
Hostname: pixelDTB126
Comment:
------------------------------------------------------
[16:24:40.386] <TB3> INFO: RPC call hashes of host and DTB match: 486171790
[16:24:41.884] <TB3> INFO: DUT info:
[16:24:41.884] <TB3> INFO: The DUT currently contains the following objects:
[16:24:41.884] <TB3> INFO: 4 TBM Cores tbm10c (4 ON)
[16:24:41.884] <TB3> INFO: TBM Core alpha (0): 7 registers set
[16:24:41.884] <TB3> INFO: TBM Core beta (1): 7 registers set
[16:24:41.884] <TB3> INFO: TBM Core alpha (2): 7 registers set
[16:24:41.884] <TB3> INFO: TBM Core beta (3): 7 registers set
[16:24:41.884] <TB3> INFO: 16 ROCs proc600 (16 ON) with 4160 pixelConfigs
[16:24:41.884] <TB3> INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[16:24:41.884] <TB3> INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[16:24:41.884] <TB3> INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[16:24:41.884] <TB3> INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[16:24:41.884] <TB3> INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[16:24:41.884] <TB3> INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[16:24:41.884] <TB3> INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[16:24:41.884] <TB3> INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[16:24:41.884] <TB3> INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[16:24:41.884] <TB3> INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[16:24:41.884] <TB3> INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[16:24:41.884] <TB3> INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[16:24:41.884] <TB3> INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[16:24:41.884] <TB3> INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[16:24:41.885] <TB3> INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[16:24:41.885] <TB3> INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[16:24:42.286] <TB3> INFO: enter 'restricted' command line mode
[16:24:42.286] <TB3> INFO: enter test to run
[16:24:42.286] <TB3> INFO: test: pretest no parameter change
[16:24:42.286] <TB3> INFO: running: pretest
[16:24:42.293] <TB3> INFO: ######################################################################
[16:24:42.293] <TB3> INFO: PixTestPretest::doTest()
[16:24:42.293] <TB3> INFO: ######################################################################
[16:24:42.294] <TB3> INFO: ----------------------------------------------------------------------
[16:24:42.295] <TB3> INFO: PixTestPretest::programROC()
[16:24:42.295] <TB3> INFO: ----------------------------------------------------------------------
[16:25:00.309] <TB3> INFO: PixTestPretest::programROC() done: ROCs are all programmable
[16:25:00.309] <TB3> INFO: IA differences per ROC: 17.7 20.1 18.5 16.1 20.9 16.9 19.3 17.7 20.1 21.7 18.5 17.7 20.1 19.3 18.5 18.5
[16:25:00.372] <TB3> INFO: ----------------------------------------------------------------------
[16:25:00.372] <TB3> INFO: PixTestPretest::setVana() target Ia = 24 mA/ROC
[16:25:00.372] <TB3> INFO: ----------------------------------------------------------------------
[16:25:09.288] <TB3> INFO: PixTestPretest::setVana() done, Module Ia 385.1 mA = 24.0688 mA/ROC
[16:25:09.288] <TB3> INFO: i(loss) [mA/ROC]: 20.1 20.9 21.7 20.9 21.7 21.7 20.9 21.7 21.7 20.9 20.9 21.7 21.7 20.9 21.7 20.1
[16:25:09.321] <TB3> INFO: ----------------------------------------------------------------------
[16:25:09.321] <TB3> INFO: PixTestPretest::findTiming()
[16:25:09.321] <TB3> INFO: ----------------------------------------------------------------------
[16:25:09.321] <TB3> INFO: PixTestCmd::init()

[16:25:41.087] <TB3> INFO: TBM phases: 160MHz: 7, 400MHz: 2, TBM delays: ROC(0/1):5, header/trailer: 1, token: 1
[16:25:41.087] <TB3> INFO: (success/tries = 100/100), width = 4
[16:25:42.596] <TB3> INFO: ----------------------------------------------------------------------
[16:25:42.596] <TB3> INFO: PixTestPretest::findWorkingPixel()
[16:25:42.596] <TB3> INFO: ----------------------------------------------------------------------
[16:25:42.689] <TB3> INFO: Expecting 231680 events.
[16:25:52.530] <TB3> INFO: 231680 events read in total (9250ms).
[16:25:52.540] <TB3> INFO: Test took 9940ms.
[16:25:52.787] <TB3> INFO: Found working pixel in all ROCs: col/row = 12/22
[16:25:52.824] <TB3> INFO: ----------------------------------------------------------------------
[16:25:52.824] <TB3> INFO: PixTestPretest::setVthrCompCalDel()
[16:25:52.824] <TB3> INFO: ----------------------------------------------------------------------
[16:25:52.920] <TB3> INFO: Expecting 231680 events.
[16:26:02.924] <TB3> INFO: 231680 events read in total (9413ms).
[16:26:02.936] <TB3> INFO: Test took 10106ms.
[16:26:03.204] <TB3> INFO: PixTestPretest::setVthrCompCalDel() done
[16:26:03.204] <TB3> INFO: CalDel: 93 89 92 78 81 95 84 95 81 84 107 97 111 96 90 93
[16:26:03.204] <TB3> INFO: VthrComp: 55 56 62 62 56 64 62 56 64 57 56 79 64 61 54 62
[16:26:03.208] <TB3> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1039_FullQualification_2016-10-21_13h57m_1477051074//003_Fulltest_p17//dacParameters_C0.dat
[16:26:03.208] <TB3> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1039_FullQualification_2016-10-21_13h57m_1477051074//003_Fulltest_p17//dacParameters_C1.dat
[16:26:03.208] <TB3> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1039_FullQualification_2016-10-21_13h57m_1477051074//003_Fulltest_p17//dacParameters_C2.dat
[16:26:03.208] <TB3> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1039_FullQualification_2016-10-21_13h57m_1477051074//003_Fulltest_p17//dacParameters_C3.dat
[16:26:03.208] <TB3> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1039_FullQualification_2016-10-21_13h57m_1477051074//003_Fulltest_p17//dacParameters_C4.dat
[16:26:03.208] <TB3> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1039_FullQualification_2016-10-21_13h57m_1477051074//003_Fulltest_p17//dacParameters_C5.dat
[16:26:03.208] <TB3> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1039_FullQualification_2016-10-21_13h57m_1477051074//003_Fulltest_p17//dacParameters_C6.dat
[16:26:03.208] <TB3> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1039_FullQualification_2016-10-21_13h57m_1477051074//003_Fulltest_p17//dacParameters_C7.dat
[16:26:03.209] <TB3> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1039_FullQualification_2016-10-21_13h57m_1477051074//003_Fulltest_p17//dacParameters_C8.dat
[16:26:03.209] <TB3> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1039_FullQualification_2016-10-21_13h57m_1477051074//003_Fulltest_p17//dacParameters_C9.dat
[16:26:03.209] <TB3> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1039_FullQualification_2016-10-21_13h57m_1477051074//003_Fulltest_p17//dacParameters_C10.dat
[16:26:03.209] <TB3> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1039_FullQualification_2016-10-21_13h57m_1477051074//003_Fulltest_p17//dacParameters_C11.dat
[16:26:03.209] <TB3> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1039_FullQualification_2016-10-21_13h57m_1477051074//003_Fulltest_p17//dacParameters_C12.dat
[16:26:03.209] <TB3> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1039_FullQualification_2016-10-21_13h57m_1477051074//003_Fulltest_p17//dacParameters_C13.dat
[16:26:03.209] <TB3> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1039_FullQualification_2016-10-21_13h57m_1477051074//003_Fulltest_p17//dacParameters_C14.dat
[16:26:03.209] <TB3> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1039_FullQualification_2016-10-21_13h57m_1477051074//003_Fulltest_p17//dacParameters_C15.dat
[16:26:03.209] <TB3> INFO: write tbm parameters into /home/l_tester/david/FullQualification/data/M1039_FullQualification_2016-10-21_13h57m_1477051074//003_Fulltest_p17//tbmParameters_C0a.dat
[16:26:03.209] <TB3> INFO: write tbm parameters into /home/l_tester/david/FullQualification/data/M1039_FullQualification_2016-10-21_13h57m_1477051074//003_Fulltest_p17//tbmParameters_C0b.dat
[16:26:03.209] <TB3> INFO: write tbm parameters into /home/l_tester/david/FullQualification/data/M1039_FullQualification_2016-10-21_13h57m_1477051074//003_Fulltest_p17//tbmParameters_C1a.dat
[16:26:03.210] <TB3> INFO: write tbm parameters into /home/l_tester/david/FullQualification/data/M1039_FullQualification_2016-10-21_13h57m_1477051074//003_Fulltest_p17//tbmParameters_C1b.dat
[16:26:03.210] <TB3> INFO: PixTestPretest::doTest() done, duration: 80 seconds
[16:26:03.266] <TB3> INFO: enter test to run
[16:26:03.266] <TB3> INFO: test: FullTest no parameter change
[16:26:03.266] <TB3> INFO: running: fulltest
[16:26:03.266] <TB3> INFO: ######################################################################
[16:26:03.266] <TB3> INFO: PixTestFullTest::doTest()
[16:26:03.266] <TB3> INFO: ######################################################################
[16:26:03.268] <TB3> INFO: ######################################################################
[16:26:03.268] <TB3> INFO: PixTestAlive::doTest()
[16:26:03.268] <TB3> INFO: ######################################################################
[16:26:03.269] <TB3> INFO: ----------------------------------------------------------------------
[16:26:03.269] <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)
[16:26:03.269] <TB3> INFO: ----------------------------------------------------------------------
[16:26:03.512] <TB3> INFO: Expecting 41600 events.
[16:26:07.077] <TB3> INFO: 41600 events read in total (2973ms).
[16:26:07.078] <TB3> INFO: Test took 3808ms.
[16:26:07.313] <TB3> INFO: PixTestAlive::aliveTest() done
[16:26:07.313] <TB3> INFO: number of dead pixels (per ROC): 0 1 1 0 0 1 0 0 0 0 0 0 0 0 0 0
[16:26:07.314] <TB3> INFO: ----------------------------------------------------------------------
[16:26:07.314] <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)
[16:26:07.314] <TB3> INFO: ----------------------------------------------------------------------
[16:26:07.558] <TB3> INFO: Expecting 41600 events.
[16:26:10.673] <TB3> INFO: 41600 events read in total (2524ms).
[16:26:10.673] <TB3> INFO: Test took 3356ms.
[16:26:10.674] <TB3> INFO: mask vs. old pixelAlive PixelAlive_C0_V0 .. PixelAlive_C15_V0
[16:26:10.914] <TB3> INFO: PixTestAlive::maskTest() done
[16:26:10.914] <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
[16:26:10.915] <TB3> INFO: ----------------------------------------------------------------------
[16:26:10.916] <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)
[16:26:10.916] <TB3> INFO: ----------------------------------------------------------------------
[16:26:11.162] <TB3> INFO: Expecting 41600 events.
[16:26:14.702] <TB3> INFO: 41600 events read in total (2948ms).
[16:26:14.703] <TB3> INFO: Test took 3785ms.
[16:26:14.938] <TB3> INFO: PixTestAlive::addressDecodingTest() done
[16:26:14.938] <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
[16:26:14.938] <TB3> INFO: PixTestAlive::doTest() done, duration: 11 seconds
[16:26:14.938] <TB3> INFO: Decoding statistics:
[16:26:14.938] <TB3> INFO: General information:
[16:26:14.938] <TB3> INFO: 16bit words read: 0
[16:26:14.938] <TB3> INFO: valid events total: 0
[16:26:14.938] <TB3> INFO: empty events: 0
[16:26:14.938] <TB3> INFO: valid events with pixels: 0
[16:26:14.938] <TB3> INFO: valid pixel hits: 0
[16:26:14.938] <TB3> INFO: Event errors: 0
[16:26:14.938] <TB3> INFO: start marker: 0
[16:26:14.938] <TB3> INFO: stop marker: 0
[16:26:14.938] <TB3> INFO: overflow: 0
[16:26:14.938] <TB3> INFO: invalid 5bit words: 0
[16:26:14.938] <TB3> INFO: invalid XOR eye diagram: 0
[16:26:14.938] <TB3> INFO: frame (failed synchr.): 0
[16:26:14.938] <TB3> INFO: idle data (no TBM trl): 0
[16:26:14.938] <TB3> INFO: no data (only TBM hdr): 0
[16:26:14.938] <TB3> INFO: TBM errors: 0
[16:26:14.938] <TB3> INFO: flawed TBM headers: 0
[16:26:14.938] <TB3> INFO: flawed TBM trailers: 0
[16:26:14.938] <TB3> INFO: event ID mismatches: 0
[16:26:14.938] <TB3> INFO: ROC errors: 0
[16:26:14.938] <TB3> INFO: missing ROC header(s): 0
[16:26:14.938] <TB3> INFO: misplaced readback start: 0
[16:26:14.938] <TB3> INFO: Pixel decoding errors: 0
[16:26:14.939] <TB3> INFO: pixel data incomplete: 0
[16:26:14.939] <TB3> INFO: pixel address: 0
[16:26:14.939] <TB3> INFO: pulse height fill bit: 0
[16:26:14.939] <TB3> INFO: buffer corruption: 0
[16:26:14.948] <TB3> INFO: readReadbackCal: /home/l_tester/david/FullQualification/data/M1039_FullQualification_2016-10-21_13h57m_1477051074//003_Fulltest_p17//readbackCal_C0.dat .. /home/l_tester/david/FullQualification/data/M1039_FullQualification_2016-10-21_13h57m_1477051074//003_Fulltest_p17//readbackCal_C15.dat
[16:26:14.948] <TB3> INFO: readGainPedestalParameters /home/l_tester/david/FullQualification/data/M1039_FullQualification_2016-10-21_13h57m_1477051074//003_Fulltest_p17//phCalibrationFitErr_C0.dat .. /home/l_tester/david/FullQualification/data/M1039_FullQualification_2016-10-21_13h57m_1477051074//003_Fulltest_p17//phCalibrationFitErr_C15.dat

[16:26:14.948] <TB3> INFO: ######################################################################
[16:26:14.948] <TB3> INFO: PixTestReadback::doTest()
[16:26:14.948] <TB3> INFO: ######################################################################
[16:26:14.948] <TB3> INFO: ----------------------------------------------------------------------
[16:26:14.948] <TB3> INFO: PixTestReadback::CalibrateVd()
[16:26:14.948] <TB3> INFO: ----------------------------------------------------------------------
[16:26:24.937] <TB3> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1039_FullQualification_2016-10-21_13h57m_1477051074//003_Fulltest_p17//readbackCal_C0.dat
[16:26:24.937] <TB3> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1039_FullQualification_2016-10-21_13h57m_1477051074//003_Fulltest_p17//readbackCal_C1.dat
[16:26:24.937] <TB3> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1039_FullQualification_2016-10-21_13h57m_1477051074//003_Fulltest_p17//readbackCal_C2.dat
[16:26:24.937] <TB3> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1039_FullQualification_2016-10-21_13h57m_1477051074//003_Fulltest_p17//readbackCal_C3.dat
[16:26:24.937] <TB3> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1039_FullQualification_2016-10-21_13h57m_1477051074//003_Fulltest_p17//readbackCal_C4.dat
[16:26:24.937] <TB3> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1039_FullQualification_2016-10-21_13h57m_1477051074//003_Fulltest_p17//readbackCal_C5.dat
[16:26:24.937] <TB3> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1039_FullQualification_2016-10-21_13h57m_1477051074//003_Fulltest_p17//readbackCal_C6.dat
[16:26:24.937] <TB3> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1039_FullQualification_2016-10-21_13h57m_1477051074//003_Fulltest_p17//readbackCal_C7.dat
[16:26:24.938] <TB3> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1039_FullQualification_2016-10-21_13h57m_1477051074//003_Fulltest_p17//readbackCal_C8.dat
[16:26:24.938] <TB3> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1039_FullQualification_2016-10-21_13h57m_1477051074//003_Fulltest_p17//readbackCal_C9.dat
[16:26:24.938] <TB3> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1039_FullQualification_2016-10-21_13h57m_1477051074//003_Fulltest_p17//readbackCal_C10.dat
[16:26:24.938] <TB3> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1039_FullQualification_2016-10-21_13h57m_1477051074//003_Fulltest_p17//readbackCal_C11.dat
[16:26:24.938] <TB3> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1039_FullQualification_2016-10-21_13h57m_1477051074//003_Fulltest_p17//readbackCal_C12.dat
[16:26:24.938] <TB3> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1039_FullQualification_2016-10-21_13h57m_1477051074//003_Fulltest_p17//readbackCal_C13.dat
[16:26:24.938] <TB3> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1039_FullQualification_2016-10-21_13h57m_1477051074//003_Fulltest_p17//readbackCal_C14.dat
[16:26:24.938] <TB3> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1039_FullQualification_2016-10-21_13h57m_1477051074//003_Fulltest_p17//readbackCal_C15.dat
[16:26:24.967] <TB3> INFO: PixTestPattern:: pg_setup set to default.
[16:26:24.967] <TB3> INFO: ----------------------------------------------------------------------
[16:26:24.967] <TB3> INFO: PixTestReadback::CalibrateVa()
[16:26:24.968] <TB3> INFO: ----------------------------------------------------------------------
[16:26:34.909] <TB3> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1039_FullQualification_2016-10-21_13h57m_1477051074//003_Fulltest_p17//readbackCal_C0.dat
[16:26:34.909] <TB3> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1039_FullQualification_2016-10-21_13h57m_1477051074//003_Fulltest_p17//readbackCal_C1.dat
[16:26:34.909] <TB3> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1039_FullQualification_2016-10-21_13h57m_1477051074//003_Fulltest_p17//readbackCal_C2.dat
[16:26:34.909] <TB3> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1039_FullQualification_2016-10-21_13h57m_1477051074//003_Fulltest_p17//readbackCal_C3.dat
[16:26:34.909] <TB3> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1039_FullQualification_2016-10-21_13h57m_1477051074//003_Fulltest_p17//readbackCal_C4.dat
[16:26:34.909] <TB3> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1039_FullQualification_2016-10-21_13h57m_1477051074//003_Fulltest_p17//readbackCal_C5.dat
[16:26:34.909] <TB3> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1039_FullQualification_2016-10-21_13h57m_1477051074//003_Fulltest_p17//readbackCal_C6.dat
[16:26:34.909] <TB3> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1039_FullQualification_2016-10-21_13h57m_1477051074//003_Fulltest_p17//readbackCal_C7.dat
[16:26:34.909] <TB3> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1039_FullQualification_2016-10-21_13h57m_1477051074//003_Fulltest_p17//readbackCal_C8.dat
[16:26:34.909] <TB3> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1039_FullQualification_2016-10-21_13h57m_1477051074//003_Fulltest_p17//readbackCal_C9.dat
[16:26:34.909] <TB3> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1039_FullQualification_2016-10-21_13h57m_1477051074//003_Fulltest_p17//readbackCal_C10.dat
[16:26:34.909] <TB3> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1039_FullQualification_2016-10-21_13h57m_1477051074//003_Fulltest_p17//readbackCal_C11.dat
[16:26:34.909] <TB3> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1039_FullQualification_2016-10-21_13h57m_1477051074//003_Fulltest_p17//readbackCal_C12.dat
[16:26:34.909] <TB3> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1039_FullQualification_2016-10-21_13h57m_1477051074//003_Fulltest_p17//readbackCal_C13.dat
[16:26:34.909] <TB3> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1039_FullQualification_2016-10-21_13h57m_1477051074//003_Fulltest_p17//readbackCal_C14.dat
[16:26:34.909] <TB3> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1039_FullQualification_2016-10-21_13h57m_1477051074//003_Fulltest_p17//readbackCal_C15.dat
[16:26:34.939] <TB3> INFO: PixTestPattern:: pg_setup set to default.
[16:26:34.939] <TB3> INFO: ----------------------------------------------------------------------
[16:26:34.939] <TB3> INFO: PixTestReadback::readbackVbg()
[16:26:34.939] <TB3> INFO: ----------------------------------------------------------------------
[16:26:42.614] <TB3> INFO: PixTestPattern:: pg_setup set to default.
[16:26:42.614] <TB3> INFO: ----------------------------------------------------------------------
[16:26:42.614] <TB3> INFO: PixTestReadback::getCalibratedVbg()
[16:26:42.614] <TB3> INFO: ----------------------------------------------------------------------
[16:26:42.614] <TB3> INFO: Vbg will be calibrated using Vd calibration
[16:26:42.614] <TB3> INFO: /*/*/*/*::: ROC 0: uncalibrated Vbg = 145.1calibrated Vbg = 1.21421 :::*/*/*/*/
[16:26:42.614] <TB3> INFO: /*/*/*/*::: ROC 1: uncalibrated Vbg = 156.9calibrated Vbg = 1.21246 :::*/*/*/*/
[16:26:42.615] <TB3> INFO: /*/*/*/*::: ROC 2: uncalibrated Vbg = 157.3calibrated Vbg = 1.20455 :::*/*/*/*/
[16:26:42.615] <TB3> INFO: /*/*/*/*::: ROC 3: uncalibrated Vbg = 157.1calibrated Vbg = 1.20112 :::*/*/*/*/
[16:26:42.615] <TB3> INFO: /*/*/*/*::: ROC 4: uncalibrated Vbg = 151.1calibrated Vbg = 1.20137 :::*/*/*/*/
[16:26:42.615] <TB3> INFO: /*/*/*/*::: ROC 5: uncalibrated Vbg = 162.8calibrated Vbg = 1.21415 :::*/*/*/*/
[16:26:42.615] <TB3> INFO: /*/*/*/*::: ROC 6: uncalibrated Vbg = 160.5calibrated Vbg = 1.20836 :::*/*/*/*/
[16:26:42.615] <TB3> INFO: /*/*/*/*::: ROC 7: uncalibrated Vbg = 155.1calibrated Vbg = 1.20913 :::*/*/*/*/
[16:26:42.615] <TB3> INFO: /*/*/*/*::: ROC 8: uncalibrated Vbg = 152.7calibrated Vbg = 1.20398 :::*/*/*/*/
[16:26:42.615] <TB3> INFO: /*/*/*/*::: ROC 9: uncalibrated Vbg = 161.7calibrated Vbg = 1.1985 :::*/*/*/*/
[16:26:42.615] <TB3> INFO: /*/*/*/*::: ROC 10: uncalibrated Vbg = 150.9calibrated Vbg = 1.20141 :::*/*/*/*/
[16:26:42.615] <TB3> INFO: /*/*/*/*::: ROC 11: uncalibrated Vbg = 163.5calibrated Vbg = 1.19457 :::*/*/*/*/
[16:26:42.615] <TB3> INFO: /*/*/*/*::: ROC 12: uncalibrated Vbg = 159calibrated Vbg = 1.19738 :::*/*/*/*/
[16:26:42.615] <TB3> INFO: /*/*/*/*::: ROC 13: uncalibrated Vbg = 156.6calibrated Vbg = 1.20462 :::*/*/*/*/
[16:26:42.615] <TB3> INFO: /*/*/*/*::: ROC 14: uncalibrated Vbg = 146.5calibrated Vbg = 1.20407 :::*/*/*/*/
[16:26:42.615] <TB3> INFO: /*/*/*/*::: ROC 15: uncalibrated Vbg = 153calibrated Vbg = 1.2071 :::*/*/*/*/
[16:26:42.617] <TB3> INFO: ----------------------------------------------------------------------
[16:26:42.618] <TB3> INFO: PixTestReadback::CalibrateIa()
[16:26:42.618] <TB3> INFO: ----------------------------------------------------------------------
[16:29:23.419] <TB3> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1039_FullQualification_2016-10-21_13h57m_1477051074//003_Fulltest_p17//readbackCal_C0.dat
[16:29:23.419] <TB3> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1039_FullQualification_2016-10-21_13h57m_1477051074//003_Fulltest_p17//readbackCal_C1.dat
[16:29:23.419] <TB3> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1039_FullQualification_2016-10-21_13h57m_1477051074//003_Fulltest_p17//readbackCal_C2.dat
[16:29:23.420] <TB3> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1039_FullQualification_2016-10-21_13h57m_1477051074//003_Fulltest_p17//readbackCal_C3.dat
[16:29:23.420] <TB3> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1039_FullQualification_2016-10-21_13h57m_1477051074//003_Fulltest_p17//readbackCal_C4.dat
[16:29:23.420] <TB3> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1039_FullQualification_2016-10-21_13h57m_1477051074//003_Fulltest_p17//readbackCal_C5.dat
[16:29:23.420] <TB3> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1039_FullQualification_2016-10-21_13h57m_1477051074//003_Fulltest_p17//readbackCal_C6.dat
[16:29:23.420] <TB3> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1039_FullQualification_2016-10-21_13h57m_1477051074//003_Fulltest_p17//readbackCal_C7.dat
[16:29:23.420] <TB3> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1039_FullQualification_2016-10-21_13h57m_1477051074//003_Fulltest_p17//readbackCal_C8.dat
[16:29:23.420] <TB3> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1039_FullQualification_2016-10-21_13h57m_1477051074//003_Fulltest_p17//readbackCal_C9.dat
[16:29:23.420] <TB3> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1039_FullQualification_2016-10-21_13h57m_1477051074//003_Fulltest_p17//readbackCal_C10.dat
[16:29:23.420] <TB3> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1039_FullQualification_2016-10-21_13h57m_1477051074//003_Fulltest_p17//readbackCal_C11.dat
[16:29:23.420] <TB3> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1039_FullQualification_2016-10-21_13h57m_1477051074//003_Fulltest_p17//readbackCal_C12.dat
[16:29:23.420] <TB3> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1039_FullQualification_2016-10-21_13h57m_1477051074//003_Fulltest_p17//readbackCal_C13.dat
[16:29:23.420] <TB3> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1039_FullQualification_2016-10-21_13h57m_1477051074//003_Fulltest_p17//readbackCal_C14.dat
[16:29:23.420] <TB3> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1039_FullQualification_2016-10-21_13h57m_1477051074//003_Fulltest_p17//readbackCal_C15.dat
[16:29:23.447] <TB3> INFO: PixTestPattern:: pg_setup set to default.
[16:29:23.448] <TB3> INFO: PixTestReadback::doTest() done
[16:29:23.448] <TB3> INFO: Decoding statistics:
[16:29:23.448] <TB3> INFO: General information:
[16:29:23.448] <TB3> INFO: 16bit words read: 1536
[16:29:23.448] <TB3> INFO: valid events total: 256
[16:29:23.448] <TB3> INFO: empty events: 256
[16:29:23.448] <TB3> INFO: valid events with pixels: 0
[16:29:23.448] <TB3> INFO: valid pixel hits: 0
[16:29:23.448] <TB3> INFO: Event errors: 0
[16:29:23.448] <TB3> INFO: start marker: 0
[16:29:23.448] <TB3> INFO: stop marker: 0
[16:29:23.448] <TB3> INFO: overflow: 0
[16:29:23.448] <TB3> INFO: invalid 5bit words: 0
[16:29:23.448] <TB3> INFO: invalid XOR eye diagram: 0
[16:29:23.448] <TB3> INFO: frame (failed synchr.): 0
[16:29:23.448] <TB3> INFO: idle data (no TBM trl): 0
[16:29:23.448] <TB3> INFO: no data (only TBM hdr): 0
[16:29:23.448] <TB3> INFO: TBM errors: 0
[16:29:23.448] <TB3> INFO: flawed TBM headers: 0
[16:29:23.448] <TB3> INFO: flawed TBM trailers: 0
[16:29:23.448] <TB3> INFO: event ID mismatches: 0
[16:29:23.448] <TB3> INFO: ROC errors: 0
[16:29:23.448] <TB3> INFO: missing ROC header(s): 0
[16:29:23.449] <TB3> INFO: misplaced readback start: 0
[16:29:23.449] <TB3> INFO: Pixel decoding errors: 0
[16:29:23.449] <TB3> INFO: pixel data incomplete: 0
[16:29:23.449] <TB3> INFO: pixel address: 0
[16:29:23.449] <TB3> INFO: pulse height fill bit: 0
[16:29:23.449] <TB3> INFO: buffer corruption: 0
[16:29:23.513] <TB3> INFO: ######################################################################
[16:29:23.513] <TB3> INFO: PixTestBBMap::doTest() Ntrig = 5, VcalS = 250 (high range)
[16:29:23.513] <TB3> INFO: ######################################################################
[16:29:23.516] <TB3> INFO: ---> dac: VthrComp name: calSMap ntrig: 5 dacrange: 0 .. 149 (-1/-1) hits flags = 514 (plus default)
[16:29:23.532] <TB3> INFO: dacScan split into 1 runs with ntrig = 5
[16:29:23.532] <TB3> INFO: run 1 of 1
[16:29:23.771] <TB3> INFO: Expecting 3120000 events.
[16:29:55.787] <TB3> INFO: 715710 events read in total (31424ms).











[16:30:26.970] <TB3> INFO: 1425295 events read in total (62608ms).




















[16:30:58.448] <TB3> INFO: 2131225 events read in total (94085ms).











[16:31:30.079] <TB3> INFO: 2836930 events read in total (125716ms).











[16:31:43.402] <TB3> INFO: 3120000 events read in total (139039ms).
[16:31:43.505] <TB3> INFO: Test took 139975ms.
[16:32:05.901] <TB3> INFO: PixTestBBMap::doTest() done, duration: 162 seconds
[16:32:05.901] <TB3> INFO: number of dead bumps (per ROC): 3 2 51 5 9 52 9 1 0 26 0 26 3 7 2 0
[16:32:05.901] <TB3> INFO: separation cut (per ROC): 135 122 143 130 144 137 140 124 127 119 107 129 129 127 128 124
[16:32:05.901] <TB3> INFO: Decoding statistics:
[16:32:05.901] <TB3> INFO: General information:
[16:32:05.901] <TB3> INFO: 16bit words read: 0
[16:32:05.901] <TB3> INFO: valid events total: 0
[16:32:05.901] <TB3> INFO: empty events: 0
[16:32:05.901] <TB3> INFO: valid events with pixels: 0
[16:32:05.901] <TB3> INFO: valid pixel hits: 0
[16:32:05.901] <TB3> INFO: Event errors: 0
[16:32:05.901] <TB3> INFO: start marker: 0
[16:32:05.901] <TB3> INFO: stop marker: 0
[16:32:05.901] <TB3> INFO: overflow: 0
[16:32:05.901] <TB3> INFO: invalid 5bit words: 0
[16:32:05.901] <TB3> INFO: invalid XOR eye diagram: 0
[16:32:05.901] <TB3> INFO: frame (failed synchr.): 0
[16:32:05.901] <TB3> INFO: idle data (no TBM trl): 0
[16:32:05.901] <TB3> INFO: no data (only TBM hdr): 0
[16:32:05.901] <TB3> INFO: TBM errors: 0
[16:32:05.901] <TB3> INFO: flawed TBM headers: 0
[16:32:05.901] <TB3> INFO: flawed TBM trailers: 0
[16:32:05.901] <TB3> INFO: event ID mismatches: 0
[16:32:05.901] <TB3> INFO: ROC errors: 0
[16:32:05.901] <TB3> INFO: missing ROC header(s): 0
[16:32:05.901] <TB3> INFO: misplaced readback start: 0
[16:32:05.901] <TB3> INFO: Pixel decoding errors: 0
[16:32:05.901] <TB3> INFO: pixel data incomplete: 0
[16:32:05.901] <TB3> INFO: pixel address: 0
[16:32:05.901] <TB3> INFO: pulse height fill bit: 0
[16:32:05.901] <TB3> INFO: buffer corruption: 0
[16:32:05.966] <TB3> INFO: ######################################################################
[16:32:05.966] <TB3> INFO: PixTestScurves::fullTest() ntrig = 50, dacs/step = -1, ntrig/step = -1
[16:32:05.966] <TB3> INFO: ######################################################################
[16:32:05.966] <TB3> INFO: ----------------------------------------------------------------------
[16:32:05.966] <TB3> INFO: PixTestScurves::scurves(Vcal), ntrig = 50, dacs/step = -1, ntrig/step = -1
[16:32:05.966] <TB3> INFO: ----------------------------------------------------------------------
[16:32:05.966] <TB3> INFO: ---> dac: Vcal name: scurveVcal ntrig: 50 dacrange: 25 .. 200 (-1/-1) hits flags = 528 (plus default)
[16:32:05.980] <TB3> INFO: dacScan split into 1 runs with ntrig = 50
[16:32:05.980] <TB3> INFO: run 1 of 1
[16:32:06.253] <TB3> INFO: Expecting 36608000 events.
[16:32:30.738] <TB3> INFO: 747650 events read in total (23893ms).
[16:32:54.142] <TB3> INFO: 1460850 events read in total (47297ms).
[16:33:17.883] <TB3> INFO: 2178100 events read in total (71038ms).
[16:33:41.017] <TB3> INFO: 2888050 events read in total (94172ms).
[16:34:04.459] <TB3> INFO: 3600400 events read in total (117614ms).
[16:34:27.942] <TB3> INFO: 4308200 events read in total (141097ms).
[16:34:51.468] <TB3> INFO: 5016800 events read in total (164623ms).
[16:35:14.747] <TB3> INFO: 5722600 events read in total (187902ms).
[16:35:38.206] <TB3> INFO: 6434800 events read in total (211361ms).
[16:36:01.689] <TB3> INFO: 7142850 events read in total (234844ms).
[16:36:25.020] <TB3> INFO: 7851350 events read in total (258175ms).
[16:36:48.487] <TB3> INFO: 8557400 events read in total (281642ms).
[16:37:11.891] <TB3> INFO: 9264000 events read in total (305046ms).
[16:37:35.437] <TB3> INFO: 9968600 events read in total (328592ms).
[16:37:58.683] <TB3> INFO: 10674600 events read in total (351838ms).
[16:38:21.989] <TB3> INFO: 11374550 events read in total (375144ms).
[16:38:45.224] <TB3> INFO: 12076350 events read in total (398379ms).
[16:39:08.597] <TB3> INFO: 12777700 events read in total (421752ms).
[16:39:32.058] <TB3> INFO: 13480450 events read in total (445213ms).
[16:39:55.110] <TB3> INFO: 14182200 events read in total (468265ms).
[16:40:18.549] <TB3> INFO: 14883450 events read in total (491704ms).
[16:40:41.521] <TB3> INFO: 15580700 events read in total (514676ms).
[16:41:04.793] <TB3> INFO: 16278450 events read in total (537948ms).
[16:41:27.001] <TB3> INFO: 16975000 events read in total (561156ms).
[16:41:51.516] <TB3> INFO: 17674900 events read in total (584671ms).
[16:42:14.726] <TB3> INFO: 18367100 events read in total (607881ms).
[16:42:37.989] <TB3> INFO: 19059800 events read in total (631144ms).
[16:43:01.038] <TB3> INFO: 19748950 events read in total (654193ms).
[16:43:24.488] <TB3> INFO: 20443850 events read in total (677643ms).
[16:43:47.469] <TB3> INFO: 21134250 events read in total (700624ms).
[16:44:10.767] <TB3> INFO: 21825500 events read in total (723922ms).
[16:44:33.850] <TB3> INFO: 22510900 events read in total (747005ms).
[16:44:56.910] <TB3> INFO: 23199300 events read in total (770066ms).
[16:45:20.085] <TB3> INFO: 23886950 events read in total (793240ms).
[16:45:43.034] <TB3> INFO: 24575350 events read in total (816189ms).
[16:46:05.883] <TB3> INFO: 25261700 events read in total (839038ms).
[16:46:29.059] <TB3> INFO: 25949850 events read in total (862214ms).
[16:46:52.248] <TB3> INFO: 26636500 events read in total (885403ms).
[16:47:15.459] <TB3> INFO: 27327300 events read in total (908614ms).
[16:47:38.485] <TB3> INFO: 28012000 events read in total (931640ms).
[16:48:01.545] <TB3> INFO: 28701750 events read in total (954701ms).
[16:48:24.491] <TB3> INFO: 29386400 events read in total (977646ms).
[16:48:47.900] <TB3> INFO: 30074850 events read in total (1001055ms).
[16:49:11.117] <TB3> INFO: 30761100 events read in total (1024272ms).
[16:49:34.628] <TB3> INFO: 31448700 events read in total (1047783ms).
[16:49:57.862] <TB3> INFO: 32136250 events read in total (1071017ms).
[16:50:21.130] <TB3> INFO: 32823200 events read in total (1094285ms).
[16:50:44.390] <TB3> INFO: 33510600 events read in total (1117545ms).
[16:51:07.842] <TB3> INFO: 34199800 events read in total (1140997ms).
[16:51:31.444] <TB3> INFO: 34884950 events read in total (1164599ms).
[16:51:54.625] <TB3> INFO: 35573600 events read in total (1187780ms).
[16:52:18.429] <TB3> INFO: 36275900 events read in total (1211584ms).
[16:52:30.151] <TB3> INFO: 36608000 events read in total (1223306ms).
[16:52:30.219] <TB3> INFO: Test took 1224239ms.
[16:52:30.718] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[16:52:32.779] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[16:52:34.343] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[16:52:35.834] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[16:52:37.393] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[16:52:38.905] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[16:52:40.460] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[16:52:42.006] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[16:52:43.568] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[16:52:45.108] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[16:52:46.715] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[16:52:48.437] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[16:52:50.156] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[16:52:52.286] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[16:52:54.263] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[16:52:56.255] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[16:52:58.306] <TB3> INFO: PixTestScurves::scurves() done
[16:52:58.306] <TB3> INFO: Vcal mean: 143.77 138.60 153.13 124.67 144.53 138.98 143.66 132.15 140.90 126.00 136.41 121.62 125.83 140.46 141.44 128.89
[16:52:58.306] <TB3> INFO: Vcal RMS: 8.33 7.78 8.04 9.48 8.12 8.85 7.32 7.21 7.97 8.22 8.96 9.67 7.74 8.25 9.17 8.47
[16:52:58.306] <TB3> INFO: PixTestScurves::fullTest() done, duration: 1252 seconds
[16:52:58.306] <TB3> INFO: Decoding statistics:
[16:52:58.306] <TB3> INFO: General information:
[16:52:58.306] <TB3> INFO: 16bit words read: 0
[16:52:58.306] <TB3> INFO: valid events total: 0
[16:52:58.306] <TB3> INFO: empty events: 0
[16:52:58.306] <TB3> INFO: valid events with pixels: 0
[16:52:58.307] <TB3> INFO: valid pixel hits: 0
[16:52:58.307] <TB3> INFO: Event errors: 0
[16:52:58.307] <TB3> INFO: start marker: 0
[16:52:58.307] <TB3> INFO: stop marker: 0
[16:52:58.307] <TB3> INFO: overflow: 0
[16:52:58.307] <TB3> INFO: invalid 5bit words: 0
[16:52:58.307] <TB3> INFO: invalid XOR eye diagram: 0
[16:52:58.307] <TB3> INFO: frame (failed synchr.): 0
[16:52:58.307] <TB3> INFO: idle data (no TBM trl): 0
[16:52:58.307] <TB3> INFO: no data (only TBM hdr): 0
[16:52:58.307] <TB3> INFO: TBM errors: 0
[16:52:58.307] <TB3> INFO: flawed TBM headers: 0
[16:52:58.307] <TB3> INFO: flawed TBM trailers: 0
[16:52:58.307] <TB3> INFO: event ID mismatches: 0
[16:52:58.307] <TB3> INFO: ROC errors: 0
[16:52:58.307] <TB3> INFO: missing ROC header(s): 0
[16:52:58.307] <TB3> INFO: misplaced readback start: 0
[16:52:58.307] <TB3> INFO: Pixel decoding errors: 0
[16:52:58.307] <TB3> INFO: pixel data incomplete: 0
[16:52:58.307] <TB3> INFO: pixel address: 0
[16:52:58.307] <TB3> INFO: pulse height fill bit: 0
[16:52:58.307] <TB3> INFO: buffer corruption: 0
[16:52:58.372] <TB3> INFO: ######################################################################
[16:52:58.372] <TB3> INFO: PixTestTrim::doTest()
[16:52:58.372] <TB3> INFO: ######################################################################
[16:52:58.373] <TB3> INFO: ----------------------------------------------------------------------
[16:52:58.373] <TB3> INFO: PixTestTrim::trimTest() ntrig = 8, vcal = 35
[16:52:58.373] <TB3> INFO: ----------------------------------------------------------------------
[16:52:58.416] <TB3> INFO: ---> VthrComp thr map (minimal VthrComp)
[16:52:58.416] <TB3> INFO: ---> dac: vthrcomp name: TrimThr0 ntrig: 8 dacrange: 10 .. 160 (-1/-1) hits flags = 528 (plus default)
[16:52:58.430] <TB3> INFO: dacScan split into 1 runs with ntrig = 8
[16:52:58.430] <TB3> INFO: run 1 of 1
[16:52:58.666] <TB3> INFO: Expecting 5025280 events.
[16:53:29.979] <TB3> INFO: 852328 events read in total (30713ms).
[16:54:00.348] <TB3> INFO: 1700688 events read in total (61082ms).
[16:54:31.215] <TB3> INFO: 2547392 events read in total (91950ms).
[16:55:02.058] <TB3> INFO: 3391920 events read in total (122792ms).
[16:55:33.224] <TB3> INFO: 4233008 events read in total (153958ms).
[16:56:02.138] <TB3> INFO: 5025280 events read in total (182872ms).
[16:56:02.205] <TB3> INFO: Test took 183775ms.
[16:56:06.906] <TB3> INFO: ROC 0 VthrComp = 123
[16:56:06.906] <TB3> INFO: ROC 1 VthrComp = 118
[16:56:06.906] <TB3> INFO: ROC 2 VthrComp = 129
[16:56:06.906] <TB3> INFO: ROC 3 VthrComp = 113
[16:56:06.906] <TB3> INFO: ROC 4 VthrComp = 125
[16:56:06.907] <TB3> INFO: ROC 5 VthrComp = 119
[16:56:06.907] <TB3> INFO: ROC 6 VthrComp = 131
[16:56:06.907] <TB3> INFO: ROC 7 VthrComp = 110
[16:56:06.907] <TB3> INFO: ROC 8 VthrComp = 128
[16:56:06.907] <TB3> INFO: ROC 9 VthrComp = 110
[16:56:06.907] <TB3> INFO: ROC 10 VthrComp = 106
[16:56:06.907] <TB3> INFO: ROC 11 VthrComp = 119
[16:56:06.907] <TB3> INFO: ROC 12 VthrComp = 120
[16:56:06.907] <TB3> INFO: ROC 13 VthrComp = 119
[16:56:06.907] <TB3> INFO: ROC 14 VthrComp = 123
[16:56:06.907] <TB3> INFO: ROC 15 VthrComp = 108
[16:56:07.156] <TB3> INFO: Expecting 41600 events.
[16:56:10.670] <TB3> INFO: 41600 events read in total (2923ms).
[16:56:10.671] <TB3> INFO: Test took 3762ms.
[16:56:10.682] <TB3> INFO: ---> Vcal thr map (pixel with maximum Vcal thr)
[16:56:10.682] <TB3> INFO: ---> dac: vcal name: TrimThr1 ntrig: 8 dacrange: 10 .. 160 (-1/-1) hits flags = 528 (plus default)
[16:56:10.696] <TB3> INFO: dacScan split into 1 runs with ntrig = 8
[16:56:10.696] <TB3> INFO: run 1 of 1
[16:56:10.974] <TB3> INFO: Expecting 5025280 events.
[16:56:38.132] <TB3> INFO: 611968 events read in total (26567ms).
[16:57:04.475] <TB3> INFO: 1219816 events read in total (52909ms).
[16:57:30.936] <TB3> INFO: 1829328 events read in total (79370ms).
[16:57:57.271] <TB3> INFO: 2437560 events read in total (105705ms).
[16:58:23.573] <TB3> INFO: 3042592 events read in total (132007ms).
[16:58:49.793] <TB3> INFO: 3643936 events read in total (158227ms).
[16:59:16.200] <TB3> INFO: 4246264 events read in total (184634ms).
[16:59:43.363] <TB3> INFO: 4849792 events read in total (211797ms).
[16:59:51.505] <TB3> INFO: 5025280 events read in total (219939ms).
[16:59:51.574] <TB3> INFO: Test took 220879ms.
[17:00:17.529] <TB3> INFO: roc 0 with ID = 0 has maximal Vcal 76.9994 for pixel 6/60 mean/min/max = 57.056/37.1093/77.0027
[17:00:17.529] <TB3> INFO: roc 1 with ID = 1 has maximal Vcal 72.7928 for pixel 0/13 mean/min/max = 53.4977/33.9514/73.0439
[17:00:17.530] <TB3> INFO: roc 2 with ID = 2 has maximal Vcal 83.5911 for pixel 0/18 mean/min/max = 61.9469/39.8203/84.0736
[17:00:17.531] <TB3> INFO: roc 3 with ID = 3 has maximal Vcal 80.5661 for pixel 0/21 mean/min/max = 57.8073/34.9287/80.6859
[17:00:17.531] <TB3> INFO: roc 4 with ID = 4 has maximal Vcal 80.678 for pixel 0/77 mean/min/max = 59.6468/38.4621/80.8315
[17:00:17.532] <TB3> INFO: roc 5 with ID = 5 has maximal Vcal 84.6884 for pixel 0/7 mean/min/max = 60.499/36.2873/84.7108
[17:00:17.532] <TB3> INFO: roc 6 with ID = 6 has maximal Vcal 76.6406 for pixel 0/42 mean/min/max = 57.2043/37.7116/76.697
[17:00:17.532] <TB3> INFO: roc 7 with ID = 7 has maximal Vcal 77.9556 for pixel 3/1 mean/min/max = 58.9654/39.7/78.2309
[17:00:17.533] <TB3> INFO: roc 8 with ID = 8 has maximal Vcal 81.3908 for pixel 47/0 mean/min/max = 59.7768/37.8601/81.6935
[17:00:17.534] <TB3> INFO: roc 9 with ID = 9 has maximal Vcal 79.3103 for pixel 15/12 mean/min/max = 59.2544/39.1956/79.3131
[17:00:17.535] <TB3> INFO: roc 10 with ID = 10 has maximal Vcal 86.7411 for pixel 4/75 mean/min/max = 63.134/38.6954/87.5726
[17:00:17.536] <TB3> INFO: roc 11 with ID = 11 has maximal Vcal 91.1109 for pixel 15/8 mean/min/max = 64.7277/38.3099/91.1456
[17:00:17.536] <TB3> INFO: roc 12 with ID = 12 has maximal Vcal 75.2009 for pixel 18/74 mean/min/max = 55.1185/34.825/75.4121
[17:00:17.537] <TB3> INFO: roc 13 with ID = 13 has maximal Vcal 80.7879 for pixel 0/78 mean/min/max = 60.9343/40.3478/81.5209
[17:00:17.538] <TB3> INFO: roc 14 with ID = 14 has maximal Vcal 72.7669 for pixel 4/15 mean/min/max = 53.1848/33.4416/72.9281
[17:00:17.538] <TB3> INFO: roc 15 with ID = 15 has maximal Vcal 87.381 for pixel 4/2 mean/min/max = 66.5154/45.6085/87.4223
[17:00:17.539] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[17:00:17.628] <TB3> INFO: Expecting 411648 events.
[17:00:27.222] <TB3> INFO: 411648 events read in total (9003ms).
[17:00:27.237] <TB3> INFO: Expecting 411648 events.
[17:00:36.501] <TB3> INFO: 411648 events read in total (8861ms).
[17:00:36.514] <TB3> INFO: Expecting 411648 events.
[17:00:45.824] <TB3> INFO: 411648 events read in total (8906ms).
[17:00:45.848] <TB3> INFO: Expecting 411648 events.
[17:00:55.207] <TB3> INFO: 411648 events read in total (8956ms).
[17:00:55.238] <TB3> INFO: Expecting 411648 events.
[17:01:04.538] <TB3> INFO: 411648 events read in total (8897ms).
[17:01:04.563] <TB3> INFO: Expecting 411648 events.
[17:01:13.871] <TB3> INFO: 411648 events read in total (8899ms).
[17:01:13.899] <TB3> INFO: Expecting 411648 events.
[17:01:23.204] <TB3> INFO: 411648 events read in total (8902ms).
[17:01:23.235] <TB3> INFO: Expecting 411648 events.
[17:01:32.572] <TB3> INFO: 411648 events read in total (8934ms).
[17:01:32.608] <TB3> INFO: Expecting 411648 events.
[17:01:41.898] <TB3> INFO: 411648 events read in total (8886ms).
[17:01:41.940] <TB3> INFO: Expecting 411648 events.
[17:01:51.203] <TB3> INFO: 411648 events read in total (8860ms).
[17:01:51.237] <TB3> INFO: Expecting 411648 events.
[17:02:00.488] <TB3> INFO: 411648 events read in total (8847ms).
[17:02:00.524] <TB3> INFO: Expecting 411648 events.
[17:02:09.767] <TB3> INFO: 411648 events read in total (8840ms).
[17:02:09.808] <TB3> INFO: Expecting 411648 events.
[17:02:19.108] <TB3> INFO: 411648 events read in total (8897ms).
[17:02:19.152] <TB3> INFO: Expecting 411648 events.
[17:02:28.770] <TB3> INFO: 411648 events read in total (9215ms).
[17:02:28.825] <TB3> INFO: Expecting 411648 events.
[17:02:38.113] <TB3> INFO: 411648 events read in total (8885ms).
[17:02:38.165] <TB3> INFO: Expecting 411648 events.
[17:02:47.486] <TB3> INFO: 411648 events read in total (8918ms).
[17:02:47.540] <TB3> INFO: Test took 150002ms.
[17:02:47.993] <TB3> INFO: ---> dac: vcal name: TrimThr2 ntrig: 8 dacrange: 0 .. 150 (-1/-1) hits flags = 528 (plus default)
[17:02:48.007] <TB3> INFO: dacScan split into 1 runs with ntrig = 8
[17:02:48.007] <TB3> INFO: run 1 of 1
[17:02:48.245] <TB3> INFO: Expecting 5025280 events.
[17:03:15.953] <TB3> INFO: 613080 events read in total (27116ms).
[17:03:42.884] <TB3> INFO: 1227160 events read in total (54047ms).
[17:04:09.910] <TB3> INFO: 1842720 events read in total (81074ms).
[17:04:37.122] <TB3> INFO: 2455304 events read in total (108286ms).
[17:05:04.342] <TB3> INFO: 3074104 events read in total (135505ms).
[17:05:31.687] <TB3> INFO: 3691752 events read in total (162851ms).
[17:05:58.778] <TB3> INFO: 4309344 events read in total (189941ms).
[17:06:25.876] <TB3> INFO: 4926544 events read in total (217039ms).
[17:06:30.347] <TB3> INFO: 5025280 events read in total (221510ms).
[17:06:30.490] <TB3> INFO: Test took 222483ms.
[17:06:48.961] <TB3> INFO: ---> TrimStepCorr4 extremal thresholds: 1.500000 .. 147.572793
[17:06:49.199] <TB3> INFO: Expecting 208000 events.
[17:06:58.653] <TB3> INFO: 208000 events read in total (8863ms).
[17:06:58.656] <TB3> INFO: Test took 9694ms.
[17:06:58.727] <TB3> INFO: ---> dac: vcal name: trimStepCorr4 ntrig: 8 dacrange: 1 .. 157 (-1/-1) hits flags = 528 (plus default)
[17:06:58.740] <TB3> INFO: dacScan split into 1 runs with ntrig = 8
[17:06:58.740] <TB3> INFO: run 1 of 1
[17:06:59.023] <TB3> INFO: Expecting 5224960 events.
[17:07:27.082] <TB3> INFO: 605736 events read in total (27467ms).
[17:07:54.361] <TB3> INFO: 1206960 events read in total (54748ms).
[17:08:21.716] <TB3> INFO: 1809288 events read in total (82102ms).
[17:08:49.097] <TB3> INFO: 2411680 events read in total (109482ms).
[17:09:15.988] <TB3> INFO: 3013304 events read in total (136373ms).
[17:09:42.927] <TB3> INFO: 3614176 events read in total (163312ms).
[17:10:09.497] <TB3> INFO: 4214792 events read in total (189882ms).
[17:10:37.022] <TB3> INFO: 4813872 events read in total (217407ms).
[17:10:55.345] <TB3> INFO: 5224960 events read in total (235730ms).
[17:10:55.514] <TB3> INFO: Test took 236774ms.
[17:11:18.060] <TB3> INFO: ---> TrimStepCorr2 extremal thresholds: 24.244769 .. 59.334739
[17:11:18.302] <TB3> INFO: Expecting 208000 events.
[17:11:28.391] <TB3> INFO: 208000 events read in total (9498ms).
[17:11:28.392] <TB3> INFO: Test took 10330ms.
[17:11:28.439] <TB3> INFO: ---> dac: vcal name: trimStepCorr2 ntrig: 8 dacrange: 14 .. 69 (-1/-1) hits flags = 528 (plus default)
[17:11:28.452] <TB3> INFO: dacScan split into 1 runs with ntrig = 8
[17:11:28.452] <TB3> INFO: run 1 of 1
[17:11:28.731] <TB3> INFO: Expecting 1863680 events.
[17:11:57.711] <TB3> INFO: 697192 events read in total (28388ms).
[17:12:26.186] <TB3> INFO: 1393656 events read in total (56864ms).
[17:12:45.570] <TB3> INFO: 1863680 events read in total (76248ms).
[17:12:45.638] <TB3> INFO: Test took 77187ms.
[17:12:57.006] <TB3> INFO: ---> TrimStepCorr1a extremal thresholds: 29.311145 .. 66.958070
[17:12:57.247] <TB3> INFO: Expecting 208000 events.
[17:13:07.021] <TB3> INFO: 208000 events read in total (9182ms).
[17:13:07.022] <TB3> INFO: Test took 10014ms.
[17:13:07.069] <TB3> INFO: ---> dac: vcal name: trimStepCorr1a ntrig: 8 dacrange: 19 .. 76 (-1/-1) hits flags = 528 (plus default)
[17:13:07.083] <TB3> INFO: dacScan split into 1 runs with ntrig = 8
[17:13:07.083] <TB3> INFO: run 1 of 1
[17:13:07.361] <TB3> INFO: Expecting 1930240 events.
[17:13:35.528] <TB3> INFO: 663176 events read in total (27576ms).
[17:14:03.356] <TB3> INFO: 1321416 events read in total (55406ms).
[17:14:29.285] <TB3> INFO: 1930240 events read in total (81333ms).
[17:14:29.353] <TB3> INFO: Test took 82271ms.
[17:14:43.691] <TB3> INFO: ---> TrimStepCorr1b extremal thresholds: 31.873044 .. 69.986976
[17:14:43.933] <TB3> INFO: Expecting 208000 events.
[17:14:54.134] <TB3> INFO: 208000 events read in total (9609ms).
[17:14:54.135] <TB3> INFO: Test took 10442ms.
[17:14:54.196] <TB3> INFO: ---> dac: vcal name: trimStepCorr1b ntrig: 8 dacrange: 21 .. 79 (-1/-1) hits flags = 528 (plus default)
[17:14:54.209] <TB3> INFO: dacScan split into 1 runs with ntrig = 8
[17:14:54.209] <TB3> INFO: run 1 of 1
[17:14:54.487] <TB3> INFO: Expecting 1963520 events.
[17:15:22.133] <TB3> INFO: 640520 events read in total (27054ms).
[17:15:49.159] <TB3> INFO: 1274632 events read in total (54081ms).
[17:16:16.109] <TB3> INFO: 1909832 events read in total (81030ms).
[17:16:18.775] <TB3> INFO: 1963520 events read in total (83696ms).
[17:16:18.835] <TB3> INFO: Test took 84627ms.
[17:16:35.518] <TB3> INFO: ---> TrimThrFinal extremal thresholds: 15 .. 55
[17:16:35.518] <TB3> INFO: ---> dac: vcal name: TrimThrFinal ntrig: 8 dacrange: 15 .. 55 (-1/-1) hits flags = 528 (plus default)
[17:16:35.530] <TB3> INFO: dacScan split into 1 runs with ntrig = 8
[17:16:35.530] <TB3> INFO: run 1 of 1
[17:16:35.769] <TB3> INFO: Expecting 1364480 events.
[17:17:06.023] <TB3> INFO: 746240 events read in total (29662ms).
[17:17:30.924] <TB3> INFO: 1364480 events read in total (54564ms).
[17:17:30.972] <TB3> INFO: Test took 55442ms.
[17:17:37.373] <TB3> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1039_FullQualification_2016-10-21_13h57m_1477051074//003_Fulltest_p17//dacParameters35_C0.dat
[17:17:37.373] <TB3> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1039_FullQualification_2016-10-21_13h57m_1477051074//003_Fulltest_p17//dacParameters35_C1.dat
[17:17:37.373] <TB3> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1039_FullQualification_2016-10-21_13h57m_1477051074//003_Fulltest_p17//dacParameters35_C2.dat
[17:17:37.373] <TB3> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1039_FullQualification_2016-10-21_13h57m_1477051074//003_Fulltest_p17//dacParameters35_C3.dat
[17:17:37.373] <TB3> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1039_FullQualification_2016-10-21_13h57m_1477051074//003_Fulltest_p17//dacParameters35_C4.dat
[17:17:37.373] <TB3> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1039_FullQualification_2016-10-21_13h57m_1477051074//003_Fulltest_p17//dacParameters35_C5.dat
[17:17:37.373] <TB3> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1039_FullQualification_2016-10-21_13h57m_1477051074//003_Fulltest_p17//dacParameters35_C6.dat
[17:17:37.373] <TB3> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1039_FullQualification_2016-10-21_13h57m_1477051074//003_Fulltest_p17//dacParameters35_C7.dat
[17:17:37.373] <TB3> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1039_FullQualification_2016-10-21_13h57m_1477051074//003_Fulltest_p17//dacParameters35_C8.dat
[17:17:37.374] <TB3> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1039_FullQualification_2016-10-21_13h57m_1477051074//003_Fulltest_p17//dacParameters35_C9.dat
[17:17:37.374] <TB3> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1039_FullQualification_2016-10-21_13h57m_1477051074//003_Fulltest_p17//dacParameters35_C10.dat
[17:17:37.374] <TB3> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1039_FullQualification_2016-10-21_13h57m_1477051074//003_Fulltest_p17//dacParameters35_C11.dat
[17:17:37.374] <TB3> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1039_FullQualification_2016-10-21_13h57m_1477051074//003_Fulltest_p17//dacParameters35_C12.dat
[17:17:37.374] <TB3> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1039_FullQualification_2016-10-21_13h57m_1477051074//003_Fulltest_p17//dacParameters35_C13.dat
[17:17:37.374] <TB3> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1039_FullQualification_2016-10-21_13h57m_1477051074//003_Fulltest_p17//dacParameters35_C14.dat
[17:17:37.374] <TB3> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1039_FullQualification_2016-10-21_13h57m_1477051074//003_Fulltest_p17//dacParameters35_C15.dat
[17:17:37.374] <TB3> INFO: write trim parameters into /home/l_tester/david/FullQualification/data/M1039_FullQualification_2016-10-21_13h57m_1477051074//003_Fulltest_p17//trimParameters35_C0.dat
[17:17:37.382] <TB3> INFO: write trim parameters into /home/l_tester/david/FullQualification/data/M1039_FullQualification_2016-10-21_13h57m_1477051074//003_Fulltest_p17//trimParameters35_C1.dat
[17:17:37.389] <TB3> INFO: write trim parameters into /home/l_tester/david/FullQualification/data/M1039_FullQualification_2016-10-21_13h57m_1477051074//003_Fulltest_p17//trimParameters35_C2.dat
[17:17:37.396] <TB3> INFO: write trim parameters into /home/l_tester/david/FullQualification/data/M1039_FullQualification_2016-10-21_13h57m_1477051074//003_Fulltest_p17//trimParameters35_C3.dat
[17:17:37.404] <TB3> INFO: write trim parameters into /home/l_tester/david/FullQualification/data/M1039_FullQualification_2016-10-21_13h57m_1477051074//003_Fulltest_p17//trimParameters35_C4.dat
[17:17:37.411] <TB3> INFO: write trim parameters into /home/l_tester/david/FullQualification/data/M1039_FullQualification_2016-10-21_13h57m_1477051074//003_Fulltest_p17//trimParameters35_C5.dat
[17:17:37.419] <TB3> INFO: write trim parameters into /home/l_tester/david/FullQualification/data/M1039_FullQualification_2016-10-21_13h57m_1477051074//003_Fulltest_p17//trimParameters35_C6.dat
[17:17:37.426] <TB3> INFO: write trim parameters into /home/l_tester/david/FullQualification/data/M1039_FullQualification_2016-10-21_13h57m_1477051074//003_Fulltest_p17//trimParameters35_C7.dat
[17:17:37.433] <TB3> INFO: write trim parameters into /home/l_tester/david/FullQualification/data/M1039_FullQualification_2016-10-21_13h57m_1477051074//003_Fulltest_p17//trimParameters35_C8.dat
[17:17:37.441] <TB3> INFO: write trim parameters into /home/l_tester/david/FullQualification/data/M1039_FullQualification_2016-10-21_13h57m_1477051074//003_Fulltest_p17//trimParameters35_C9.dat
[17:17:37.448] <TB3> INFO: write trim parameters into /home/l_tester/david/FullQualification/data/M1039_FullQualification_2016-10-21_13h57m_1477051074//003_Fulltest_p17//trimParameters35_C10.dat
[17:17:37.456] <TB3> INFO: write trim parameters into /home/l_tester/david/FullQualification/data/M1039_FullQualification_2016-10-21_13h57m_1477051074//003_Fulltest_p17//trimParameters35_C11.dat
[17:17:37.463] <TB3> INFO: write trim parameters into /home/l_tester/david/FullQualification/data/M1039_FullQualification_2016-10-21_13h57m_1477051074//003_Fulltest_p17//trimParameters35_C12.dat
[17:17:37.470] <TB3> INFO: write trim parameters into /home/l_tester/david/FullQualification/data/M1039_FullQualification_2016-10-21_13h57m_1477051074//003_Fulltest_p17//trimParameters35_C13.dat
[17:17:37.478] <TB3> INFO: write trim parameters into /home/l_tester/david/FullQualification/data/M1039_FullQualification_2016-10-21_13h57m_1477051074//003_Fulltest_p17//trimParameters35_C14.dat
[17:17:37.484] <TB3> INFO: write trim parameters into /home/l_tester/david/FullQualification/data/M1039_FullQualification_2016-10-21_13h57m_1477051074//003_Fulltest_p17//trimParameters35_C15.dat
[17:17:37.488] <TB3> INFO: PixTestTrim::trimTest() done
[17:17:37.488] <TB3> INFO: vtrim: 159 137 174 143 188 148 146 163 168 189 155 195 187 132 163 186
[17:17:37.488] <TB3> INFO: vthrcomp: 123 118 129 113 125 119 131 110 128 110 106 119 120 119 123 108
[17:17:37.488] <TB3> INFO: vcal mean: 44.78 41.85 47.53 43.03 41.54 45.61 41.50 42.61 46.23 42.83 50.27 45.38 41.84 48.29 42.85 43.20
[17:17:37.488] <TB3> INFO: vcal RMS: 8.17 3.69 13.77 6.40 6.39 12.24 3.48 8.43 12.43 9.62 25.87 24.99 5.98 16.89 9.24 20.86
[17:17:37.488] <TB3> INFO: bits mean: 12.21 11.44 12.31 11.10 10.60 11.99 10.02 10.94 12.29 11.52 13.19 13.03 11.99 11.61 12.44 12.12
[17:17:37.488] <TB3> INFO: bits RMS: 1.97 2.52 2.50 2.67 2.54 2.59 3.01 2.27 2.30 2.00 2.15 1.97 1.85 3.00 1.82 2.43
[17:17:37.496] <TB3> INFO: ----------------------------------------------------------------------
[17:17:37.496] <TB3> INFO: PixTestTrim::trimBitTest() ntrig = 5, vtrims = 254 126 63 32
[17:17:37.496] <TB3> INFO: ----------------------------------------------------------------------
[17:17:37.499] <TB3> INFO: ---> dac: Vcal name: TrimBitsThr0 ntrig: 5 dacrange: 0 .. 199 (-1/-1) hits flags = 528 (plus default)
[17:17:37.512] <TB3> INFO: dacScan split into 1 runs with ntrig = 5
[17:17:37.512] <TB3> INFO: run 1 of 1
[17:17:37.750] <TB3> INFO: Expecting 4160000 events.
[17:18:12.993] <TB3> INFO: 850480 events read in total (34651ms).
[17:18:47.581] <TB3> INFO: 1695370 events read in total (69240ms).
[17:19:21.746] <TB3> INFO: 2529110 events read in total (103404ms).
[17:19:55.789] <TB3> INFO: 3359505 events read in total (137447ms).
[17:20:29.145] <TB3> INFO: 4160000 events read in total (170803ms).
[17:20:29.199] <TB3> INFO: Test took 171688ms.
[17:20:45.767] <TB3> INFO: ---> dac: Vcal name: TrimThr_trim14 ntrig: 5 dacrange: 0 .. 255 (-1/-1) hits flags = 528 (plus default)
[17:20:45.781] <TB3> INFO: dacScan split into 1 runs with ntrig = 5
[17:20:45.781] <TB3> INFO: run 1 of 1
[17:20:46.017] <TB3> INFO: Expecting 5324800 events.
[17:21:18.659] <TB3> INFO: 739190 events read in total (32050ms).
[17:21:50.169] <TB3> INFO: 1466545 events read in total (63560ms).
[17:22:21.917] <TB3> INFO: 2194630 events read in total (95308ms).
[17:22:54.146] <TB3> INFO: 2919665 events read in total (127537ms).
[17:23:26.194] <TB3> INFO: 3640750 events read in total (159585ms).
[17:23:57.734] <TB3> INFO: 4359720 events read in total (191125ms).
[17:24:29.799] <TB3> INFO: 5079520 events read in total (223190ms).
[17:24:40.936] <TB3> INFO: 5324800 events read in total (234327ms).
[17:24:41.050] <TB3> INFO: Test took 235270ms.
[17:25:14.877] <TB3> INFO: ---> dac: Vcal name: TrimThr_trim13 ntrig: 5 dacrange: 0 .. 255 (-1/-1) hits flags = 528 (plus default)
[17:25:14.890] <TB3> INFO: dacScan split into 1 runs with ntrig = 5
[17:25:14.890] <TB3> INFO: run 1 of 1
[17:25:15.155] <TB3> INFO: Expecting 5324800 events.
[17:25:47.808] <TB3> INFO: 739395 events read in total (32061ms).
[17:26:20.094] <TB3> INFO: 1466965 events read in total (64347ms).
[17:26:52.378] <TB3> INFO: 2196205 events read in total (96631ms).
[17:27:25.020] <TB3> INFO: 2921725 events read in total (129273ms).
[17:27:57.147] <TB3> INFO: 3642875 events read in total (161400ms).
[17:28:29.457] <TB3> INFO: 4362740 events read in total (193710ms).
[17:29:01.453] <TB3> INFO: 5082710 events read in total (225706ms).
[17:29:12.813] <TB3> INFO: 5324800 events read in total (237066ms).
[17:29:12.946] <TB3> INFO: Test took 238056ms.
[17:29:47.916] <TB3> INFO: ---> dac: Vcal name: TrimThr_trim11 ntrig: 5 dacrange: 0 .. 255 (-1/-1) hits flags = 528 (plus default)
[17:29:47.931] <TB3> INFO: dacScan split into 1 runs with ntrig = 5
[17:29:47.931] <TB3> INFO: run 1 of 1
[17:29:48.174] <TB3> INFO: Expecting 5324800 events.
[17:30:20.530] <TB3> INFO: 739255 events read in total (31765ms).
[17:30:52.312] <TB3> INFO: 1466680 events read in total (63547ms).
[17:31:24.190] <TB3> INFO: 2195855 events read in total (95425ms).
[17:31:56.303] <TB3> INFO: 2921595 events read in total (127538ms).
[17:32:28.048] <TB3> INFO: 3642645 events read in total (159283ms).
[17:32:59.937] <TB3> INFO: 4362685 events read in total (191172ms).
[17:33:32.596] <TB3> INFO: 5082995 events read in total (223831ms).
[17:33:43.528] <TB3> INFO: 5324800 events read in total (234763ms).
[17:33:43.624] <TB3> INFO: Test took 235693ms.
[17:34:16.595] <TB3> INFO: ---> dac: Vcal name: TrimThr_trim7 ntrig: 5 dacrange: 0 .. 255 (-1/-1) hits flags = 528 (plus default)
[17:34:16.610] <TB3> INFO: dacScan split into 1 runs with ntrig = 5
[17:34:16.610] <TB3> INFO: run 1 of 1
[17:34:16.847] <TB3> INFO: Expecting 5324800 events.
[17:34:49.148] <TB3> INFO: 739510 events read in total (31710ms).
[17:35:20.450] <TB3> INFO: 1467120 events read in total (63012ms).
[17:35:52.455] <TB3> INFO: 2196185 events read in total (95017ms).
[17:36:24.151] <TB3> INFO: 2922000 events read in total (126713ms).
[17:36:56.265] <TB3> INFO: 3643525 events read in total (158827ms).
[17:37:27.585] <TB3> INFO: 4363680 events read in total (190147ms).
[17:37:58.986] <TB3> INFO: 5084245 events read in total (221548ms).
[17:38:10.079] <TB3> INFO: 5324800 events read in total (232641ms).
[17:38:10.185] <TB3> INFO: Test took 233574ms.
[17:38:45.488] <TB3> INFO: PixTestTrim::trimBitTest() done
[17:38:45.489] <TB3> INFO: PixTestTrim::doTest() done, duration: 2747 seconds
[17:38:45.490] <TB3> INFO: Decoding statistics:
[17:38:45.490] <TB3> INFO: General information:
[17:38:45.490] <TB3> INFO: 16bit words read: 0
[17:38:45.490] <TB3> INFO: valid events total: 0
[17:38:45.490] <TB3> INFO: empty events: 0
[17:38:45.490] <TB3> INFO: valid events with pixels: 0
[17:38:45.490] <TB3> INFO: valid pixel hits: 0
[17:38:45.490] <TB3> INFO: Event errors: 0
[17:38:45.490] <TB3> INFO: start marker: 0
[17:38:45.490] <TB3> INFO: stop marker: 0
[17:38:45.490] <TB3> INFO: overflow: 0
[17:38:45.490] <TB3> INFO: invalid 5bit words: 0
[17:38:45.490] <TB3> INFO: invalid XOR eye diagram: 0
[17:38:45.490] <TB3> INFO: frame (failed synchr.): 0
[17:38:45.490] <TB3> INFO: idle data (no TBM trl): 0
[17:38:45.490] <TB3> INFO: no data (only TBM hdr): 0
[17:38:45.490] <TB3> INFO: TBM errors: 0
[17:38:45.490] <TB3> INFO: flawed TBM headers: 0
[17:38:45.490] <TB3> INFO: flawed TBM trailers: 0
[17:38:45.490] <TB3> INFO: event ID mismatches: 0
[17:38:45.490] <TB3> INFO: ROC errors: 0
[17:38:45.490] <TB3> INFO: missing ROC header(s): 0
[17:38:45.490] <TB3> INFO: misplaced readback start: 0
[17:38:45.490] <TB3> INFO: Pixel decoding errors: 0
[17:38:45.490] <TB3> INFO: pixel data incomplete: 0
[17:38:45.490] <TB3> INFO: pixel address: 0
[17:38:45.490] <TB3> INFO: pulse height fill bit: 0
[17:38:45.490] <TB3> INFO: buffer corruption: 0
[17:38:45.002] <TB3> INFO: ######################################################################
[17:38:45.002] <TB3> INFO: PixTestPhOptimization::doTest() Ntrig = 10
[17:38:45.002] <TB3> INFO: ######################################################################
[17:38:46.250] <TB3> INFO: Expecting 41600 events.
[17:38:49.816] <TB3> INFO: 41600 events read in total (2975ms).
[17:38:49.817] <TB3> INFO: Test took 3814ms.
[17:38:50.270] <TB3> INFO: Expecting 41600 events.
[17:38:53.751] <TB3> INFO: 41600 events read in total (2889ms).
[17:38:53.753] <TB3> INFO: Test took 3732ms.
[17:38:54.042] <TB3> INFO: Expecting 41600 events.
[17:38:57.560] <TB3> INFO: 41600 events read in total (2926ms).
[17:38:57.561] <TB3> INFO: Test took 3784ms.
[17:38:57.850] <TB3> INFO: Expecting 41600 events.
[17:39:01.380] <TB3> INFO: 41600 events read in total (2938ms).
[17:39:01.382] <TB3> INFO: Test took 3797ms.
[17:39:01.674] <TB3> INFO: Expecting 41600 events.
[17:39:05.288] <TB3> INFO: 41600 events read in total (3023ms).
[17:39:05.289] <TB3> INFO: Test took 3881ms.
[17:39:05.581] <TB3> INFO: Expecting 41600 events.
[17:39:09.175] <TB3> INFO: 41600 events read in total (3002ms).
[17:39:09.176] <TB3> INFO: Test took 3860ms.
[17:39:09.465] <TB3> INFO: Expecting 41600 events.
[17:39:12.928] <TB3> INFO: 41600 events read in total (2871ms).
[17:39:12.929] <TB3> INFO: Test took 3728ms.
[17:39:13.219] <TB3> INFO: Expecting 41600 events.
[17:39:16.685] <TB3> INFO: 41600 events read in total (2875ms).
[17:39:16.686] <TB3> INFO: Test took 3732ms.
[17:39:16.976] <TB3> INFO: Expecting 41600 events.
[17:39:20.456] <TB3> INFO: 41600 events read in total (2889ms).
[17:39:20.457] <TB3> INFO: Test took 3746ms.
[17:39:20.747] <TB3> INFO: Expecting 41600 events.
[17:39:24.228] <TB3> INFO: 41600 events read in total (2889ms).
[17:39:24.229] <TB3> INFO: Test took 3747ms.
[17:39:24.521] <TB3> INFO: Expecting 41600 events.
[17:39:28.010] <TB3> INFO: 41600 events read in total (2897ms).
[17:39:28.011] <TB3> INFO: Test took 3756ms.
[17:39:28.300] <TB3> INFO: Expecting 41600 events.
[17:39:31.849] <TB3> INFO: 41600 events read in total (2957ms).
[17:39:31.850] <TB3> INFO: Test took 3816ms.
[17:39:32.145] <TB3> INFO: Expecting 41600 events.
[17:39:35.639] <TB3> INFO: 41600 events read in total (2903ms).
[17:39:35.640] <TB3> INFO: Test took 3761ms.
[17:39:35.929] <TB3> INFO: Expecting 41600 events.
[17:39:39.367] <TB3> INFO: 41600 events read in total (2846ms).
[17:39:39.368] <TB3> INFO: Test took 3704ms.
[17:39:39.658] <TB3> INFO: Expecting 41600 events.
[17:39:43.107] <TB3> INFO: 41600 events read in total (2858ms).
[17:39:43.108] <TB3> INFO: Test took 3715ms.
[17:39:43.407] <TB3> INFO: Expecting 41600 events.
[17:39:46.857] <TB3> INFO: 41600 events read in total (2858ms).
[17:39:46.858] <TB3> INFO: Test took 3724ms.
[17:39:47.147] <TB3> INFO: Expecting 41600 events.
[17:39:50.622] <TB3> INFO: 41600 events read in total (2883ms).
[17:39:50.623] <TB3> INFO: Test took 3741ms.
[17:39:50.915] <TB3> INFO: Expecting 41600 events.
[17:39:54.394] <TB3> INFO: 41600 events read in total (2887ms).
[17:39:54.395] <TB3> INFO: Test took 3745ms.
[17:39:54.686] <TB3> INFO: Expecting 41600 events.
[17:39:58.224] <TB3> INFO: 41600 events read in total (2946ms).
[17:39:58.225] <TB3> INFO: Test took 3804ms.
[17:39:58.515] <TB3> INFO: Expecting 41600 events.
[17:40:02.124] <TB3> INFO: 41600 events read in total (3017ms).
[17:40:02.125] <TB3> INFO: Test took 3875ms.
[17:40:02.419] <TB3> INFO: Expecting 41600 events.
[17:40:05.986] <TB3> INFO: 41600 events read in total (2975ms).
[17:40:05.987] <TB3> INFO: Test took 3833ms.
[17:40:06.282] <TB3> INFO: Expecting 41600 events.
[17:40:09.750] <TB3> INFO: 41600 events read in total (2874ms).
[17:40:09.752] <TB3> INFO: Test took 3738ms.
[17:40:10.041] <TB3> INFO: Expecting 41600 events.
[17:40:13.592] <TB3> INFO: 41600 events read in total (2959ms).
[17:40:13.594] <TB3> INFO: Test took 3818ms.
[17:40:13.902] <TB3> INFO: Expecting 41600 events.
[17:40:17.488] <TB3> INFO: 41600 events read in total (2995ms).
[17:40:17.488] <TB3> INFO: Test took 3870ms.
[17:40:17.778] <TB3> INFO: Expecting 41600 events.
[17:40:21.400] <TB3> INFO: 41600 events read in total (3030ms).
[17:40:21.402] <TB3> INFO: Test took 3889ms.
[17:40:21.693] <TB3> INFO: Expecting 41600 events.
[17:40:25.229] <TB3> INFO: 41600 events read in total (2944ms).
[17:40:25.231] <TB3> INFO: Test took 3803ms.
[17:40:25.521] <TB3> INFO: Expecting 41600 events.
[17:40:29.082] <TB3> INFO: 41600 events read in total (2969ms).
[17:40:29.083] <TB3> INFO: Test took 3828ms.
[17:40:29.379] <TB3> INFO: Expecting 41600 events.
[17:40:32.846] <TB3> INFO: 41600 events read in total (2876ms).
[17:40:32.848] <TB3> INFO: Test took 3739ms.
[17:40:33.138] <TB3> INFO: Expecting 2560 events.
[17:40:34.022] <TB3> INFO: 2560 events read in total (293ms).
[17:40:34.023] <TB3> INFO: Test took 1162ms.
[17:40:34.331] <TB3> INFO: Expecting 2560 events.
[17:40:35.222] <TB3> INFO: 2560 events read in total (299ms).
[17:40:35.222] <TB3> INFO: Test took 1199ms.
[17:40:35.530] <TB3> INFO: Expecting 2560 events.
[17:40:36.413] <TB3> INFO: 2560 events read in total (291ms).
[17:40:36.413] <TB3> INFO: Test took 1190ms.
[17:40:36.721] <TB3> INFO: Expecting 2560 events.
[17:40:37.610] <TB3> INFO: 2560 events read in total (297ms).
[17:40:37.610] <TB3> INFO: Test took 1197ms.
[17:40:37.918] <TB3> INFO: Expecting 2560 events.
[17:40:38.801] <TB3> INFO: 2560 events read in total (291ms).
[17:40:38.802] <TB3> INFO: Test took 1191ms.
[17:40:39.109] <TB3> INFO: Expecting 2560 events.
[17:40:39.992] <TB3> INFO: 2560 events read in total (291ms).
[17:40:39.992] <TB3> INFO: Test took 1190ms.
[17:40:40.301] <TB3> INFO: Expecting 2560 events.
[17:40:41.183] <TB3> INFO: 2560 events read in total (290ms).
[17:40:41.183] <TB3> INFO: Test took 1190ms.
[17:40:41.491] <TB3> INFO: Expecting 2560 events.
[17:40:42.379] <TB3> INFO: 2560 events read in total (296ms).
[17:40:42.379] <TB3> INFO: Test took 1195ms.
[17:40:42.687] <TB3> INFO: Expecting 2560 events.
[17:40:43.574] <TB3> INFO: 2560 events read in total (295ms).
[17:40:43.575] <TB3> INFO: Test took 1195ms.
[17:40:43.883] <TB3> INFO: Expecting 2560 events.
[17:40:44.774] <TB3> INFO: 2560 events read in total (299ms).
[17:40:44.774] <TB3> INFO: Test took 1199ms.
[17:40:45.082] <TB3> INFO: Expecting 2560 events.
[17:40:45.971] <TB3> INFO: 2560 events read in total (297ms).
[17:40:45.971] <TB3> INFO: Test took 1196ms.
[17:40:46.278] <TB3> INFO: Expecting 2560 events.
[17:40:47.163] <TB3> INFO: 2560 events read in total (293ms).
[17:40:47.164] <TB3> INFO: Test took 1192ms.
[17:40:47.472] <TB3> INFO: Expecting 2560 events.
[17:40:48.362] <TB3> INFO: 2560 events read in total (299ms).
[17:40:48.362] <TB3> INFO: Test took 1198ms.
[17:40:48.670] <TB3> INFO: Expecting 2560 events.
[17:40:49.561] <TB3> INFO: 2560 events read in total (299ms).
[17:40:49.561] <TB3> INFO: Test took 1198ms.
[17:40:49.869] <TB3> INFO: Expecting 2560 events.
[17:40:50.763] <TB3> INFO: 2560 events read in total (302ms).
[17:40:50.763] <TB3> INFO: Test took 1202ms.
[17:40:51.070] <TB3> INFO: Expecting 2560 events.
[17:40:51.964] <TB3> INFO: 2560 events read in total (302ms).
[17:40:51.965] <TB3> INFO: Test took 1202ms.
[17:40:51.969] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[17:40:52.272] <TB3> INFO: Expecting 655360 events.
[17:41:07.027] <TB3> INFO: 655360 events read in total (14163ms).
[17:41:07.042] <TB3> INFO: Expecting 655360 events.
[17:41:21.599] <TB3> INFO: 655360 events read in total (14154ms).
[17:41:21.616] <TB3> INFO: Expecting 655360 events.
[17:41:36.235] <TB3> INFO: 655360 events read in total (14216ms).
[17:41:36.257] <TB3> INFO: Expecting 655360 events.
[17:41:50.751] <TB3> INFO: 655360 events read in total (14091ms).
[17:41:50.781] <TB3> INFO: Expecting 655360 events.
[17:42:05.346] <TB3> INFO: 655360 events read in total (14162ms).
[17:42:05.390] <TB3> INFO: Expecting 655360 events.
[17:42:19.003] <TB3> INFO: 655360 events read in total (14210ms).
[17:42:20.044] <TB3> INFO: Expecting 655360 events.
[17:42:34.687] <TB3> INFO: 655360 events read in total (14240ms).
[17:42:34.736] <TB3> INFO: Expecting 655360 events.
[17:42:49.441] <TB3> INFO: 655360 events read in total (14302ms).
[17:42:49.517] <TB3> INFO: Expecting 655360 events.
[17:43:04.072] <TB3> INFO: 655360 events read in total (14152ms).
[17:43:04.131] <TB3> INFO: Expecting 655360 events.
[17:43:18.660] <TB3> INFO: 655360 events read in total (14126ms).
[17:43:18.752] <TB3> INFO: Expecting 655360 events.
[17:43:33.270] <TB3> INFO: 655360 events read in total (14115ms).
[17:43:33.360] <TB3> INFO: Expecting 655360 events.
[17:43:47.946] <TB3> INFO: 655360 events read in total (14183ms).
[17:43:48.049] <TB3> INFO: Expecting 655360 events.
[17:44:02.517] <TB3> INFO: 655360 events read in total (14065ms).
[17:44:02.593] <TB3> INFO: Expecting 655360 events.
[17:44:17.109] <TB3> INFO: 655360 events read in total (14113ms).
[17:44:17.209] <TB3> INFO: Expecting 655360 events.
[17:44:31.648] <TB3> INFO: 655360 events read in total (14037ms).
[17:44:31.724] <TB3> INFO: Expecting 655360 events.
[17:44:46.321] <TB3> INFO: 655360 events read in total (14190ms).
[17:44:46.413] <TB3> INFO: Test took 234444ms.
[17:44:46.509] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[17:44:46.766] <TB3> INFO: Expecting 655360 events.
[17:45:01.234] <TB3> INFO: 655360 events read in total (13876ms).
[17:45:01.245] <TB3> INFO: Expecting 655360 events.
[17:45:15.413] <TB3> INFO: 655360 events read in total (13764ms).
[17:45:15.427] <TB3> INFO: Expecting 655360 events.
[17:45:29.661] <TB3> INFO: 655360 events read in total (13831ms).
[17:45:29.681] <TB3> INFO: Expecting 655360 events.
[17:45:43.741] <TB3> INFO: 655360 events read in total (13657ms).
[17:45:43.764] <TB3> INFO: Expecting 655360 events.
[17:45:57.005] <TB3> INFO: 655360 events read in total (13838ms).
[17:45:58.039] <TB3> INFO: Expecting 655360 events.
[17:46:12.168] <TB3> INFO: 655360 events read in total (13726ms).
[17:46:12.199] <TB3> INFO: Expecting 655360 events.
[17:46:26.336] <TB3> INFO: 655360 events read in total (13734ms).
[17:46:26.369] <TB3> INFO: Expecting 655360 events.
[17:46:40.475] <TB3> INFO: 655360 events read in total (13703ms).
[17:46:40.516] <TB3> INFO: Expecting 655360 events.
[17:46:54.786] <TB3> INFO: 655360 events read in total (13867ms).
[17:46:54.827] <TB3> INFO: Expecting 655360 events.
[17:47:09.114] <TB3> INFO: 655360 events read in total (13884ms).
[17:47:09.210] <TB3> INFO: Expecting 655360 events.
[17:47:23.244] <TB3> INFO: 655360 events read in total (13631ms).
[17:47:23.314] <TB3> INFO: Expecting 655360 events.
[17:47:37.291] <TB3> INFO: 655360 events read in total (13574ms).
[17:47:37.391] <TB3> INFO: Expecting 655360 events.
[17:47:51.589] <TB3> INFO: 655360 events read in total (13795ms).
[17:47:51.664] <TB3> INFO: Expecting 655360 events.
[17:48:05.894] <TB3> INFO: 655360 events read in total (13827ms).
[17:48:05.966] <TB3> INFO: Expecting 655360 events.
[17:48:20.052] <TB3> INFO: 655360 events read in total (13683ms).
[17:48:20.118] <TB3> INFO: Expecting 655360 events.
[17:48:34.621] <TB3> INFO: 655360 events read in total (14100ms).
[17:48:34.798] <TB3> INFO: Test took 228289ms.
[17:48:34.986] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[17:48:34.992] <TB3> INFO: safety margin for low PH: adding 1, margin is now 21
[17:48:34.998] <TB3> INFO: safety margin for low PH: adding 2, margin is now 22
[17:48:34.004] <TB3> INFO: safety margin for low PH: adding 3, margin is now 23
[17:48:35.010] <TB3> INFO: safety margin for low PH: adding 4, margin is now 24
[17:48:35.016] <TB3> INFO: safety margin for low PH: adding 5, margin is now 25
[17:48:35.021] <TB3> INFO: safety margin for low PH: adding 6, margin is now 26
[17:48:35.028] <TB3> INFO: safety margin for low PH: adding 7, margin is now 27
[17:48:35.035] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[17:48:35.041] <TB3> INFO: safety margin for low PH: adding 1, margin is now 21
[17:48:35.048] <TB3> INFO: safety margin for low PH: adding 2, margin is now 22
[17:48:35.054] <TB3> INFO: safety margin for low PH: adding 3, margin is now 23
[17:48:35.060] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[17:48:35.067] <TB3> INFO: safety margin for low PH: adding 1, margin is now 21
[17:48:35.073] <TB3> INFO: safety margin for low PH: adding 2, margin is now 22
[17:48:35.080] <TB3> INFO: safety margin for low PH: adding 3, margin is now 23
[17:48:35.086] <TB3> INFO: safety margin for low PH: adding 4, margin is now 24
[17:48:35.091] <TB3> INFO: safety margin for low PH: adding 5, margin is now 25
[17:48:35.098] <TB3> INFO: safety margin for low PH: adding 6, margin is now 26
[17:48:35.104] <TB3> INFO: safety margin for low PH: adding 7, margin is now 27
[17:48:35.110] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[17:48:35.116] <TB3> INFO: safety margin for low PH: adding 1, margin is now 21
[17:48:35.121] <TB3> INFO: safety margin for low PH: adding 2, margin is now 22
[17:48:35.127] <TB3> INFO: safety margin for low PH: adding 3, margin is now 23
[17:48:35.134] <TB3> INFO: safety margin for low PH: adding 4, margin is now 24
[17:48:35.139] <TB3> INFO: safety margin for low PH: adding 5, margin is now 25
[17:48:35.146] <TB3> INFO: safety margin for low PH: adding 6, margin is now 26
[17:48:35.152] <TB3> INFO: safety margin for low PH: adding 7, margin is now 27
[17:48:35.158] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[17:48:35.164] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[17:48:35.170] <TB3> INFO: safety margin for low PH: adding 1, margin is now 21
[17:48:35.175] <TB3> INFO: safety margin for low PH: adding 2, margin is now 22
[17:48:35.181] <TB3> INFO: safety margin for low PH: adding 3, margin is now 23
[17:48:35.187] <TB3> INFO: safety margin for low PH: adding 4, margin is now 24
[17:48:35.193] <TB3> INFO: safety margin for low PH: adding 5, margin is now 25
[17:48:35.199] <TB3> INFO: safety margin for low PH: adding 6, margin is now 26
[17:48:35.206] <TB3> INFO: safety margin for low PH: adding 7, margin is now 27
[17:48:35.212] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[17:48:35.219] <TB3> INFO: safety margin for low PH: adding 1, margin is now 21
[17:48:35.225] <TB3> INFO: safety margin for low PH: adding 2, margin is now 22
[17:48:35.231] <TB3> INFO: safety margin for low PH: adding 3, margin is now 23
[17:48:35.237] <TB3> INFO: safety margin for low PH: adding 4, margin is now 24
[17:48:35.243] <TB3> INFO: safety margin for low PH: adding 5, margin is now 25
[17:48:35.250] <TB3> INFO: safety margin for low PH: adding 6, margin is now 26
[17:48:35.256] <TB3> INFO: safety margin for low PH: adding 7, margin is now 27
[17:48:35.262] <TB3> INFO: safety margin for low PH: adding 8, margin is now 28
[17:48:35.268] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[17:48:35.274] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[17:48:35.280] <TB3> INFO: safety margin for low PH: adding 1, margin is now 21
[17:48:35.287] <TB3> INFO: safety margin for low PH: adding 2, margin is now 22
[17:48:35.294] <TB3> INFO: safety margin for low PH: adding 3, margin is now 23
[17:48:35.302] <TB3> INFO: safety margin for low PH: adding 4, margin is now 24
[17:48:35.309] <TB3> INFO: safety margin for low PH: adding 5, margin is now 25
[17:48:35.315] <TB3> INFO: safety margin for low PH: adding 6, margin is now 26
[17:48:35.322] <TB3> INFO: safety margin for low PH: adding 7, margin is now 27
[17:48:35.329] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[17:48:35.336] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[17:48:35.341] <TB3> INFO: safety margin for low PH: adding 1, margin is now 21
[17:48:35.349] <TB3> INFO: safety margin for low PH: adding 2, margin is now 22
[17:48:35.355] <TB3> INFO: safety margin for low PH: adding 3, margin is now 23
[17:48:35.363] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[17:48:35.370] <TB3> INFO: safety margin for low PH: adding 1, margin is now 21
[17:48:35.376] <TB3> INFO: safety margin for low PH: adding 2, margin is now 22
[17:48:35.383] <TB3> INFO: safety margin for low PH: adding 3, margin is now 23
[17:48:35.389] <TB3> INFO: safety margin for low PH: adding 4, margin is now 24
[17:48:35.395] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[17:48:35.401] <TB3> INFO: safety margin for low PH: adding 1, margin is now 21
[17:48:35.407] <TB3> INFO: safety margin for low PH: adding 2, margin is now 22
[17:48:35.413] <TB3> INFO: safety margin for low PH: adding 3, margin is now 23
[17:48:35.419] <TB3> INFO: safety margin for low PH: adding 4, margin is now 24
[17:48:35.424] <TB3> INFO: safety margin for low PH: adding 5, margin is now 25
[17:48:35.431] <TB3> INFO: safety margin for low PH: adding 6, margin is now 26
[17:48:35.437] <TB3> INFO: safety margin for low PH: adding 7, margin is now 27
[17:48:35.443] <TB3> INFO: safety margin for low PH: adding 8, margin is now 28
[17:48:35.449] <TB3> INFO: safety margin for low PH: adding 9, margin is now 29
[17:48:35.455] <TB3> INFO: safety margin for low PH: adding 10, margin is now 30
[17:48:35.461] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[17:48:35.467] <TB3> INFO: safety margin for low PH: adding 1, margin is now 21
[17:48:35.473] <TB3> INFO: safety margin for low PH: adding 2, margin is now 22
[17:48:35.479] <TB3> INFO: safety margin for low PH: adding 3, margin is now 23
[17:48:35.485] <TB3> INFO: safety margin for low PH: adding 4, margin is now 24
[17:48:35.491] <TB3> INFO: safety margin for low PH: adding 5, margin is now 25
[17:48:35.497] <TB3> INFO: safety margin for low PH: adding 6, margin is now 26
[17:48:35.503] <TB3> INFO: safety margin for low PH: adding 7, margin is now 27
[17:48:35.509] <TB3> INFO: safety margin for low PH: adding 8, margin is now 28
[17:48:35.516] <TB3> INFO: safety margin for low PH: adding 9, margin is now 29
[17:48:35.522] <TB3> INFO: safety margin for low PH: adding 10, margin is now 30
[17:48:35.530] <TB3> INFO: safety margin for low PH: adding 11, margin is now 31
[17:48:35.536] <TB3> INFO: safety margin for low PH: adding 12, margin is now 32
[17:48:35.544] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[17:48:35.551] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[17:48:35.557] <TB3> INFO: safety margin for low PH: adding 1, margin is now 21
[17:48:35.563] <TB3> INFO: safety margin for low PH: adding 2, margin is now 22
[17:48:35.569] <TB3> INFO: safety margin for low PH: adding 3, margin is now 23
[17:48:35.575] <TB3> INFO: safety margin for low PH: adding 4, margin is now 24
[17:48:35.582] <TB3> INFO: safety margin for low PH: adding 5, margin is now 25
[17:48:35.587] <TB3> INFO: safety margin for low PH: adding 6, margin is now 26
[17:48:35.594] <TB3> INFO: safety margin for low PH: adding 7, margin is now 27
[17:48:35.600] <TB3> INFO: safety margin for low PH: adding 8, margin is now 28
[17:48:35.606] <TB3> INFO: safety margin for low PH: adding 9, margin is now 29
[17:48:35.612] <TB3> INFO: safety margin for low PH: adding 10, margin is now 30
[17:48:35.619] <TB3> INFO: safety margin for low PH: adding 11, margin is now 31
[17:48:35.624] <TB3> INFO: safety margin for low PH: adding 12, margin is now 32
[17:48:35.631] <TB3> INFO: safety margin for low PH: adding 13, margin is now 33
[17:48:35.637] <TB3> INFO: safety margin for low PH: adding 14, margin is now 34
[17:48:35.643] <TB3> INFO: safety margin for low PH: adding 15, margin is now 35
[17:48:35.649] <TB3> INFO: safety margin for low PH: adding 16, margin is now 36
[17:48:35.655] <TB3> INFO: safety margin for low PH: adding 17, margin is now 37
[17:48:35.661] <TB3> INFO: safety margin for low PH: adding 18, margin is now 38
[17:48:35.667] <TB3> INFO: safety margin for low PH: adding 19, margin is now 39
[17:48:35.673] <TB3> INFO: safety margin for low PH: adding 20, margin is now 40
[17:48:35.681] <TB3> INFO: safety margin for low PH: adding 21, margin is now 41
[17:48:35.687] <TB3> INFO: safety margin for low PH: adding 22, margin is now 42
[17:48:35.693] <TB3> INFO: safety margin for low PH: adding 23, margin is now 43
[17:48:35.700] <TB3> INFO: safety margin for low PH: adding 24, margin is now 44
[17:48:35.706] <TB3> INFO: safety margin for low PH: adding 25, margin is now 45
[17:48:35.712] <TB3> INFO: safety margin for low PH: adding 26, margin is now 46
[17:48:35.752] <TB3> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1039_FullQualification_2016-10-21_13h57m_1477051074//003_Fulltest_p17//dacParameters35_C0.dat
[17:48:35.752] <TB3> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1039_FullQualification_2016-10-21_13h57m_1477051074//003_Fulltest_p17//dacParameters35_C1.dat
[17:48:35.752] <TB3> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1039_FullQualification_2016-10-21_13h57m_1477051074//003_Fulltest_p17//dacParameters35_C2.dat
[17:48:35.752] <TB3> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1039_FullQualification_2016-10-21_13h57m_1477051074//003_Fulltest_p17//dacParameters35_C3.dat
[17:48:35.753] <TB3> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1039_FullQualification_2016-10-21_13h57m_1477051074//003_Fulltest_p17//dacParameters35_C4.dat
[17:48:35.753] <TB3> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1039_FullQualification_2016-10-21_13h57m_1477051074//003_Fulltest_p17//dacParameters35_C5.dat
[17:48:35.753] <TB3> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1039_FullQualification_2016-10-21_13h57m_1477051074//003_Fulltest_p17//dacParameters35_C6.dat
[17:48:35.753] <TB3> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1039_FullQualification_2016-10-21_13h57m_1477051074//003_Fulltest_p17//dacParameters35_C7.dat
[17:48:35.753] <TB3> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1039_FullQualification_2016-10-21_13h57m_1477051074//003_Fulltest_p17//dacParameters35_C8.dat
[17:48:35.753] <TB3> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1039_FullQualification_2016-10-21_13h57m_1477051074//003_Fulltest_p17//dacParameters35_C9.dat
[17:48:35.754] <TB3> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1039_FullQualification_2016-10-21_13h57m_1477051074//003_Fulltest_p17//dacParameters35_C10.dat
[17:48:35.754] <TB3> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1039_FullQualification_2016-10-21_13h57m_1477051074//003_Fulltest_p17//dacParameters35_C11.dat
[17:48:35.754] <TB3> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1039_FullQualification_2016-10-21_13h57m_1477051074//003_Fulltest_p17//dacParameters35_C12.dat
[17:48:35.754] <TB3> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1039_FullQualification_2016-10-21_13h57m_1477051074//003_Fulltest_p17//dacParameters35_C13.dat
[17:48:35.754] <TB3> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1039_FullQualification_2016-10-21_13h57m_1477051074//003_Fulltest_p17//dacParameters35_C14.dat
[17:48:35.754] <TB3> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1039_FullQualification_2016-10-21_13h57m_1477051074//003_Fulltest_p17//dacParameters35_C15.dat
[17:48:35.002] <TB3> INFO: Expecting 41600 events.
[17:48:39.181] <TB3> INFO: 41600 events read in total (2587ms).
[17:48:39.183] <TB3> INFO: Test took 3424ms.
[17:48:39.687] <TB3> INFO: Expecting 41600 events.
[17:48:42.671] <TB3> INFO: 41600 events read in total (2393ms).
[17:48:42.672] <TB3> INFO: Test took 3272ms.
[17:48:43.126] <TB3> INFO: Expecting 41600 events.
[17:48:46.267] <TB3> INFO: 41600 events read in total (2549ms).
[17:48:46.268] <TB3> INFO: Test took 3383ms.
[17:48:46.488] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[17:48:46.578] <TB3> INFO: Expecting 2560 events.
[17:48:47.476] <TB3> INFO: 2560 events read in total (306ms).
[17:48:47.477] <TB3> INFO: Test took 989ms.
[17:48:47.480] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[17:48:47.783] <TB3> INFO: Expecting 2560 events.
[17:48:48.673] <TB3> INFO: 2560 events read in total (298ms).
[17:48:48.674] <TB3> INFO: Test took 1194ms.
[17:48:48.677] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[17:48:48.980] <TB3> INFO: Expecting 2560 events.
[17:48:49.880] <TB3> INFO: 2560 events read in total (308ms).
[17:48:49.880] <TB3> INFO: Test took 1203ms.
[17:48:49.883] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[17:48:50.187] <TB3> INFO: Expecting 2560 events.
[17:48:51.082] <TB3> INFO: 2560 events read in total (303ms).
[17:48:51.083] <TB3> INFO: Test took 1200ms.
[17:48:51.086] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[17:48:51.389] <TB3> INFO: Expecting 2560 events.
[17:48:52.286] <TB3> INFO: 2560 events read in total (305ms).
[17:48:52.287] <TB3> INFO: Test took 1201ms.
[17:48:52.291] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[17:48:52.592] <TB3> INFO: Expecting 2560 events.
[17:48:53.489] <TB3> INFO: 2560 events read in total (305ms).
[17:48:53.490] <TB3> INFO: Test took 1199ms.
[17:48:53.493] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[17:48:53.797] <TB3> INFO: Expecting 2560 events.
[17:48:54.694] <TB3> INFO: 2560 events read in total (305ms).
[17:48:54.694] <TB3> INFO: Test took 1201ms.
[17:48:54.697] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[17:48:54.000] <TB3> INFO: Expecting 2560 events.
[17:48:55.891] <TB3> INFO: 2560 events read in total (299ms).
[17:48:55.892] <TB3> INFO: Test took 1195ms.
[17:48:55.895] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[17:48:56.199] <TB3> INFO: Expecting 2560 events.
[17:48:57.087] <TB3> INFO: 2560 events read in total (296ms).
[17:48:57.087] <TB3> INFO: Test took 1192ms.
[17:48:57.090] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[17:48:57.395] <TB3> INFO: Expecting 2560 events.
[17:48:58.288] <TB3> INFO: 2560 events read in total (301ms).
[17:48:58.288] <TB3> INFO: Test took 1198ms.
[17:48:58.292] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[17:48:58.595] <TB3> INFO: Expecting 2560 events.
[17:48:59.491] <TB3> INFO: 2560 events read in total (304ms).
[17:48:59.491] <TB3> INFO: Test took 1199ms.
[17:48:59.493] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[17:48:59.797] <TB3> INFO: Expecting 2560 events.
[17:49:00.680] <TB3> INFO: 2560 events read in total (291ms).
[17:49:00.680] <TB3> INFO: Test took 1187ms.
[17:49:00.682] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[17:49:00.986] <TB3> INFO: Expecting 2560 events.
[17:49:01.878] <TB3> INFO: 2560 events read in total (300ms).
[17:49:01.879] <TB3> INFO: Test took 1197ms.
[17:49:01.881] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[17:49:02.185] <TB3> INFO: Expecting 2560 events.
[17:49:03.080] <TB3> INFO: 2560 events read in total (303ms).
[17:49:03.080] <TB3> INFO: Test took 1199ms.
[17:49:03.083] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[17:49:03.387] <TB3> INFO: Expecting 2560 events.
[17:49:04.279] <TB3> INFO: 2560 events read in total (300ms).
[17:49:04.279] <TB3> INFO: Test took 1196ms.
[17:49:04.282] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[17:49:04.586] <TB3> INFO: Expecting 2560 events.
[17:49:05.476] <TB3> INFO: 2560 events read in total (299ms).
[17:49:05.477] <TB3> INFO: Test took 1195ms.
[17:49:05.480] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[17:49:05.783] <TB3> INFO: Expecting 2560 events.
[17:49:06.679] <TB3> INFO: 2560 events read in total (304ms).
[17:49:06.680] <TB3> INFO: Test took 1200ms.
[17:49:06.683] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[17:49:06.987] <TB3> INFO: Expecting 2560 events.
[17:49:07.877] <TB3> INFO: 2560 events read in total (298ms).
[17:49:07.878] <TB3> INFO: Test took 1195ms.
[17:49:07.881] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[17:49:08.185] <TB3> INFO: Expecting 2560 events.
[17:49:09.080] <TB3> INFO: 2560 events read in total (303ms).
[17:49:09.081] <TB3> INFO: Test took 1200ms.
[17:49:09.084] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[17:49:09.387] <TB3> INFO: Expecting 2560 events.
[17:49:10.278] <TB3> INFO: 2560 events read in total (299ms).
[17:49:10.278] <TB3> INFO: Test took 1194ms.
[17:49:10.281] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[17:49:10.585] <TB3> INFO: Expecting 2560 events.
[17:49:11.477] <TB3> INFO: 2560 events read in total (300ms).
[17:49:11.478] <TB3> INFO: Test took 1197ms.
[17:49:11.480] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[17:49:11.785] <TB3> INFO: Expecting 2560 events.
[17:49:12.677] <TB3> INFO: 2560 events read in total (300ms).
[17:49:12.677] <TB3> INFO: Test took 1197ms.
[17:49:12.679] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[17:49:12.985] <TB3> INFO: Expecting 2560 events.
[17:49:13.876] <TB3> INFO: 2560 events read in total (299ms).
[17:49:13.877] <TB3> INFO: Test took 1198ms.
[17:49:13.880] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[17:49:14.182] <TB3> INFO: Expecting 2560 events.
[17:49:15.077] <TB3> INFO: 2560 events read in total (303ms).
[17:49:15.078] <TB3> INFO: Test took 1198ms.
[17:49:15.082] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[17:49:15.383] <TB3> INFO: Expecting 2560 events.
[17:49:16.274] <TB3> INFO: 2560 events read in total (299ms).
[17:49:16.275] <TB3> INFO: Test took 1193ms.
[17:49:16.279] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[17:49:16.581] <TB3> INFO: Expecting 2560 events.
[17:49:17.479] <TB3> INFO: 2560 events read in total (307ms).
[17:49:17.480] <TB3> INFO: Test took 1201ms.
[17:49:17.482] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[17:49:17.787] <TB3> INFO: Expecting 2560 events.
[17:49:18.678] <TB3> INFO: 2560 events read in total (299ms).
[17:49:18.678] <TB3> INFO: Test took 1196ms.
[17:49:18.681] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[17:49:18.986] <TB3> INFO: Expecting 2560 events.
[17:49:19.881] <TB3> INFO: 2560 events read in total (304ms).
[17:49:19.881] <TB3> INFO: Test took 1201ms.
[17:49:19.883] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[17:49:20.188] <TB3> INFO: Expecting 2560 events.
[17:49:21.080] <TB3> INFO: 2560 events read in total (300ms).
[17:49:21.080] <TB3> INFO: Test took 1197ms.
[17:49:21.083] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[17:49:21.385] <TB3> INFO: Expecting 2560 events.
[17:49:22.279] <TB3> INFO: 2560 events read in total (302ms).
[17:49:22.279] <TB3> INFO: Test took 1196ms.
[17:49:22.282] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[17:49:22.585] <TB3> INFO: Expecting 2560 events.
[17:49:23.476] <TB3> INFO: 2560 events read in total (299ms).
[17:49:23.476] <TB3> INFO: Test took 1194ms.
[17:49:23.479] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[17:49:23.782] <TB3> INFO: Expecting 2560 events.
[17:49:24.678] <TB3> INFO: 2560 events read in total (305ms).
[17:49:24.679] <TB3> INFO: Test took 1200ms.
[17:49:25.153] <TB3> INFO: PixTestPhOptimization::doTest() done, duration: 639 seconds
[17:49:25.153] <TB3> INFO: PH scale (per ROC): 30 42 34 25 34 38 23 34 30 36 25 34 36 48 43 34
[17:49:25.153] <TB3> INFO: PH offset (per ROC): 114 110 107 95 106 110 96 110 100 107 91 97 94 131 127 102
[17:49:25.164] <TB3> INFO: Decoding statistics:
[17:49:25.164] <TB3> INFO: General information:
[17:49:25.164] <TB3> INFO: 16bit words read: 127194
[17:49:25.164] <TB3> INFO: valid events total: 20480
[17:49:25.164] <TB3> INFO: empty events: 18323
[17:49:25.164] <TB3> INFO: valid events with pixels: 2157
[17:49:25.164] <TB3> INFO: valid pixel hits: 2157
[17:49:25.164] <TB3> INFO: Event errors: 0
[17:49:25.164] <TB3> INFO: start marker: 0
[17:49:25.164] <TB3> INFO: stop marker: 0
[17:49:25.164] <TB3> INFO: overflow: 0
[17:49:25.164] <TB3> INFO: invalid 5bit words: 0
[17:49:25.164] <TB3> INFO: invalid XOR eye diagram: 0
[17:49:25.164] <TB3> INFO: frame (failed synchr.): 0
[17:49:25.164] <TB3> INFO: idle data (no TBM trl): 0
[17:49:25.164] <TB3> INFO: no data (only TBM hdr): 0
[17:49:25.164] <TB3> INFO: TBM errors: 0
[17:49:25.164] <TB3> INFO: flawed TBM headers: 0
[17:49:25.164] <TB3> INFO: flawed TBM trailers: 0
[17:49:25.164] <TB3> INFO: event ID mismatches: 0
[17:49:25.164] <TB3> INFO: ROC errors: 0
[17:49:25.164] <TB3> INFO: missing ROC header(s): 0
[17:49:25.164] <TB3> INFO: misplaced readback start: 0
[17:49:25.164] <TB3> INFO: Pixel decoding errors: 0
[17:49:25.164] <TB3> INFO: pixel data incomplete: 0
[17:49:25.164] <TB3> INFO: pixel address: 0
[17:49:25.164] <TB3> INFO: pulse height fill bit: 0
[17:49:25.164] <TB3> INFO: buffer corruption: 0
[17:49:25.324] <TB3> INFO: ######################################################################
[17:49:25.324] <TB3> INFO: PixTestGainPedestal::fullTest() ntrig = 10
[17:49:25.324] <TB3> INFO: ######################################################################
[17:49:25.338] <TB3> INFO: scanning low vcal = 10
[17:49:25.578] <TB3> INFO: Expecting 41600 events.
[17:49:29.193] <TB3> INFO: 41600 events read in total (3023ms).
[17:49:29.194] <TB3> INFO: Test took 3856ms.
[17:49:29.195] <TB3> INFO: scanning low vcal = 20
[17:49:29.486] <TB3> INFO: Expecting 41600 events.
[17:49:33.129] <TB3> INFO: 41600 events read in total (3051ms).
[17:49:33.129] <TB3> INFO: Test took 3934ms.
[17:49:33.131] <TB3> INFO: scanning low vcal = 30
[17:49:33.419] <TB3> INFO: Expecting 41600 events.
[17:49:37.094] <TB3> INFO: 41600 events read in total (3083ms).
[17:49:37.094] <TB3> INFO: Test took 3963ms.
[17:49:37.097] <TB3> INFO: scanning low vcal = 40
[17:49:37.375] <TB3> INFO: Expecting 41600 events.
[17:49:41.109] <TB3> INFO: 41600 events read in total (3142ms).
[17:49:41.110] <TB3> INFO: Test took 4013ms.
[17:49:41.113] <TB3> INFO: scanning low vcal = 50
[17:49:41.390] <TB3> INFO: Expecting 41600 events.
[17:49:45.248] <TB3> INFO: 41600 events read in total (3266ms).
[17:49:45.249] <TB3> INFO: Test took 4136ms.
[17:49:45.252] <TB3> INFO: scanning low vcal = 60
[17:49:45.529] <TB3> INFO: Expecting 41600 events.
[17:49:49.472] <TB3> INFO: 41600 events read in total (3351ms).
[17:49:49.473] <TB3> INFO: Test took 4221ms.
[17:49:49.476] <TB3> INFO: scanning low vcal = 70
[17:49:49.758] <TB3> INFO: Expecting 41600 events.
[17:49:53.719] <TB3> INFO: 41600 events read in total (3369ms).
[17:49:53.720] <TB3> INFO: Test took 4243ms.
[17:49:53.723] <TB3> INFO: scanning low vcal = 80
[17:49:53.000] <TB3> INFO: Expecting 41600 events.
[17:49:57.975] <TB3> INFO: 41600 events read in total (3383ms).
[17:49:57.976] <TB3> INFO: Test took 4253ms.
[17:49:57.979] <TB3> INFO: scanning low vcal = 90
[17:49:58.256] <TB3> INFO: Expecting 41600 events.
[17:50:02.247] <TB3> INFO: 41600 events read in total (3399ms).
[17:50:02.248] <TB3> INFO: Test took 4269ms.
[17:50:02.253] <TB3> INFO: scanning low vcal = 100
[17:50:02.528] <TB3> INFO: Expecting 41600 events.
[17:50:06.520] <TB3> INFO: 41600 events read in total (3400ms).
[17:50:06.521] <TB3> INFO: Test took 4268ms.
[17:50:06.524] <TB3> INFO: scanning low vcal = 110
[17:50:06.801] <TB3> INFO: Expecting 41600 events.
[17:50:10.816] <TB3> INFO: 41600 events read in total (3423ms).
[17:50:10.817] <TB3> INFO: Test took 4293ms.
[17:50:10.820] <TB3> INFO: scanning low vcal = 120
[17:50:11.097] <TB3> INFO: Expecting 41600 events.
[17:50:15.082] <TB3> INFO: 41600 events read in total (3393ms).
[17:50:15.083] <TB3> INFO: Test took 4263ms.
[17:50:15.085] <TB3> INFO: scanning low vcal = 130
[17:50:15.363] <TB3> INFO: Expecting 41600 events.
[17:50:19.340] <TB3> INFO: 41600 events read in total (3385ms).
[17:50:19.341] <TB3> INFO: Test took 4255ms.
[17:50:19.344] <TB3> INFO: scanning low vcal = 140
[17:50:19.620] <TB3> INFO: Expecting 41600 events.
[17:50:23.580] <TB3> INFO: 41600 events read in total (3368ms).
[17:50:23.580] <TB3> INFO: Test took 4236ms.
[17:50:23.584] <TB3> INFO: scanning low vcal = 150
[17:50:23.860] <TB3> INFO: Expecting 41600 events.
[17:50:27.819] <TB3> INFO: 41600 events read in total (3367ms).
[17:50:27.820] <TB3> INFO: Test took 4236ms.
[17:50:27.823] <TB3> INFO: scanning low vcal = 160
[17:50:28.099] <TB3> INFO: Expecting 41600 events.
[17:50:32.109] <TB3> INFO: 41600 events read in total (3418ms).
[17:50:32.110] <TB3> INFO: Test took 4287ms.
[17:50:32.113] <TB3> INFO: scanning low vcal = 170
[17:50:32.390] <TB3> INFO: Expecting 41600 events.
[17:50:36.331] <TB3> INFO: 41600 events read in total (3349ms).
[17:50:36.332] <TB3> INFO: Test took 4218ms.
[17:50:36.337] <TB3> INFO: scanning low vcal = 180
[17:50:36.612] <TB3> INFO: Expecting 41600 events.
[17:50:40.563] <TB3> INFO: 41600 events read in total (3360ms).
[17:50:40.563] <TB3> INFO: Test took 4225ms.
[17:50:40.567] <TB3> INFO: scanning low vcal = 190
[17:50:40.843] <TB3> INFO: Expecting 41600 events.
[17:50:44.829] <TB3> INFO: 41600 events read in total (3394ms).
[17:50:44.829] <TB3> INFO: Test took 4262ms.
[17:50:44.833] <TB3> INFO: scanning low vcal = 200
[17:50:45.109] <TB3> INFO: Expecting 41600 events.
[17:50:49.078] <TB3> INFO: 41600 events read in total (3377ms).
[17:50:49.079] <TB3> INFO: Test took 4246ms.
[17:50:49.082] <TB3> INFO: scanning low vcal = 210
[17:50:49.358] <TB3> INFO: Expecting 41600 events.
[17:50:53.342] <TB3> INFO: 41600 events read in total (3392ms).
[17:50:53.343] <TB3> INFO: Test took 4261ms.
[17:50:53.346] <TB3> INFO: scanning low vcal = 220
[17:50:53.624] <TB3> INFO: Expecting 41600 events.
[17:50:57.616] <TB3> INFO: 41600 events read in total (3400ms).
[17:50:57.616] <TB3> INFO: Test took 4270ms.
[17:50:57.619] <TB3> INFO: scanning low vcal = 230
[17:50:57.896] <TB3> INFO: Expecting 41600 events.
[17:51:01.917] <TB3> INFO: 41600 events read in total (3429ms).
[17:51:01.918] <TB3> INFO: Test took 4298ms.
[17:51:01.921] <TB3> INFO: scanning low vcal = 240
[17:51:02.197] <TB3> INFO: Expecting 41600 events.
[17:51:06.170] <TB3> INFO: 41600 events read in total (3381ms).
[17:51:06.171] <TB3> INFO: Test took 4250ms.
[17:51:06.174] <TB3> INFO: scanning low vcal = 250
[17:51:06.450] <TB3> INFO: Expecting 41600 events.
[17:51:10.494] <TB3> INFO: 41600 events read in total (3452ms).
[17:51:10.494] <TB3> INFO: Test took 4320ms.
[17:51:10.499] <TB3> INFO: scanning high vcal = 30 (= 210 in low range)
[17:51:10.774] <TB3> INFO: Expecting 41600 events.
[17:51:14.811] <TB3> INFO: 41600 events read in total (3445ms).
[17:51:14.812] <TB3> INFO: Test took 4313ms.
[17:51:14.815] <TB3> INFO: scanning high vcal = 50 (= 350 in low range)
[17:51:15.092] <TB3> INFO: Expecting 41600 events.
[17:51:19.146] <TB3> INFO: 41600 events read in total (3463ms).
[17:51:19.147] <TB3> INFO: Test took 4332ms.
[17:51:19.150] <TB3> INFO: scanning high vcal = 70 (= 490 in low range)
[17:51:19.432] <TB3> INFO: Expecting 41600 events.
[17:51:23.477] <TB3> INFO: 41600 events read in total (3452ms).
[17:51:23.478] <TB3> INFO: Test took 4328ms.
[17:51:23.482] <TB3> INFO: scanning high vcal = 90 (= 630 in low range)
[17:51:23.779] <TB3> INFO: Expecting 41600 events.
[17:51:27.727] <TB3> INFO: 41600 events read in total (3355ms).
[17:51:27.728] <TB3> INFO: Test took 4246ms.
[17:51:27.731] <TB3> INFO: scanning high vcal = 200 (= 1400 in low range)
[17:51:28.007] <TB3> INFO: Expecting 41600 events.
[17:51:32.039] <TB3> INFO: 41600 events read in total (3440ms).
[17:51:32.040] <TB3> INFO: Test took 4308ms.
[17:51:32.448] <TB3> INFO: PixTestGainPedestal::measure() done
[17:52:06.902] <TB3> INFO: PixTestGainPedestal::fit() done
[17:52:06.902] <TB3> INFO: non-linearity mean: 0.956 0.970 0.943 1.033 0.925 0.963 1.051 0.935 0.990 0.954 0.973 0.945 0.949 0.989 0.981 0.951
[17:52:06.902] <TB3> INFO: non-linearity RMS: 0.163 0.037 0.114 0.170 0.110 0.037 0.096 0.112 0.153 0.052 0.132 0.078 0.081 0.002 0.005 0.061
[17:52:06.903] <TB3> INFO: write gain/ped parameters into /home/l_tester/david/FullQualification/data/M1039_FullQualification_2016-10-21_13h57m_1477051074//003_Fulltest_p17//phCalibrationFitErr35_C0.dat
[17:52:06.916] <TB3> INFO: write gain/ped parameters into /home/l_tester/david/FullQualification/data/M1039_FullQualification_2016-10-21_13h57m_1477051074//003_Fulltest_p17//phCalibrationFitErr35_C1.dat
[17:52:06.929] <TB3> INFO: write gain/ped parameters into /home/l_tester/david/FullQualification/data/M1039_FullQualification_2016-10-21_13h57m_1477051074//003_Fulltest_p17//phCalibrationFitErr35_C2.dat
[17:52:06.942] <TB3> INFO: write gain/ped parameters into /home/l_tester/david/FullQualification/data/M1039_FullQualification_2016-10-21_13h57m_1477051074//003_Fulltest_p17//phCalibrationFitErr35_C3.dat
[17:52:06.956] <TB3> INFO: write gain/ped parameters into /home/l_tester/david/FullQualification/data/M1039_FullQualification_2016-10-21_13h57m_1477051074//003_Fulltest_p17//phCalibrationFitErr35_C4.dat
[17:52:06.969] <TB3> INFO: write gain/ped parameters into /home/l_tester/david/FullQualification/data/M1039_FullQualification_2016-10-21_13h57m_1477051074//003_Fulltest_p17//phCalibrationFitErr35_C5.dat
[17:52:06.983] <TB3> INFO: write gain/ped parameters into /home/l_tester/david/FullQualification/data/M1039_FullQualification_2016-10-21_13h57m_1477051074//003_Fulltest_p17//phCalibrationFitErr35_C6.dat
[17:52:06.996] <TB3> INFO: write gain/ped parameters into /home/l_tester/david/FullQualification/data/M1039_FullQualification_2016-10-21_13h57m_1477051074//003_Fulltest_p17//phCalibrationFitErr35_C7.dat
[17:52:07.009] <TB3> INFO: write gain/ped parameters into /home/l_tester/david/FullQualification/data/M1039_FullQualification_2016-10-21_13h57m_1477051074//003_Fulltest_p17//phCalibrationFitErr35_C8.dat
[17:52:07.023] <TB3> INFO: write gain/ped parameters into /home/l_tester/david/FullQualification/data/M1039_FullQualification_2016-10-21_13h57m_1477051074//003_Fulltest_p17//phCalibrationFitErr35_C9.dat
[17:52:07.036] <TB3> INFO: write gain/ped parameters into /home/l_tester/david/FullQualification/data/M1039_FullQualification_2016-10-21_13h57m_1477051074//003_Fulltest_p17//phCalibrationFitErr35_C10.dat
[17:52:07.049] <TB3> INFO: write gain/ped parameters into /home/l_tester/david/FullQualification/data/M1039_FullQualification_2016-10-21_13h57m_1477051074//003_Fulltest_p17//phCalibrationFitErr35_C11.dat
[17:52:07.063] <TB3> INFO: write gain/ped parameters into /home/l_tester/david/FullQualification/data/M1039_FullQualification_2016-10-21_13h57m_1477051074//003_Fulltest_p17//phCalibrationFitErr35_C12.dat
[17:52:07.076] <TB3> INFO: write gain/ped parameters into /home/l_tester/david/FullQualification/data/M1039_FullQualification_2016-10-21_13h57m_1477051074//003_Fulltest_p17//phCalibrationFitErr35_C13.dat
[17:52:07.089] <TB3> INFO: write gain/ped parameters into /home/l_tester/david/FullQualification/data/M1039_FullQualification_2016-10-21_13h57m_1477051074//003_Fulltest_p17//phCalibrationFitErr35_C14.dat
[17:52:07.103] <TB3> INFO: write gain/ped parameters into /home/l_tester/david/FullQualification/data/M1039_FullQualification_2016-10-21_13h57m_1477051074//003_Fulltest_p17//phCalibrationFitErr35_C15.dat
[17:52:07.116] <TB3> INFO: PixTestGainPedestal::fullTest() done, duration: 161 seconds
[17:52:07.116] <TB3> INFO: Decoding statistics:
[17:52:07.116] <TB3> INFO: General information:
[17:52:07.116] <TB3> INFO: 16bit words read: 3131762
[17:52:07.116] <TB3> INFO: valid events total: 332800
[17:52:07.116] <TB3> INFO: empty events: 11088
[17:52:07.116] <TB3> INFO: valid events with pixels: 321712
[17:52:07.116] <TB3> INFO: valid pixel hits: 567481
[17:52:07.116] <TB3> INFO: Event errors: 0
[17:52:07.116] <TB3> INFO: start marker: 0
[17:52:07.116] <TB3> INFO: stop marker: 0
[17:52:07.116] <TB3> INFO: overflow: 0
[17:52:07.116] <TB3> INFO: invalid 5bit words: 0
[17:52:07.116] <TB3> INFO: invalid XOR eye diagram: 0
[17:52:07.116] <TB3> INFO: frame (failed synchr.): 0
[17:52:07.116] <TB3> INFO: idle data (no TBM trl): 0
[17:52:07.116] <TB3> INFO: no data (only TBM hdr): 0
[17:52:07.116] <TB3> INFO: TBM errors: 0
[17:52:07.116] <TB3> INFO: flawed TBM headers: 0
[17:52:07.116] <TB3> INFO: flawed TBM trailers: 0
[17:52:07.116] <TB3> INFO: event ID mismatches: 0
[17:52:07.116] <TB3> INFO: ROC errors: 0
[17:52:07.116] <TB3> INFO: missing ROC header(s): 0
[17:52:07.116] <TB3> INFO: misplaced readback start: 0
[17:52:07.116] <TB3> INFO: Pixel decoding errors: 0
[17:52:07.116] <TB3> INFO: pixel data incomplete: 0
[17:52:07.116] <TB3> INFO: pixel address: 0
[17:52:07.116] <TB3> INFO: pulse height fill bit: 0
[17:52:07.116] <TB3> INFO: buffer corruption: 0
[17:52:07.132] <TB3> INFO: Decoding statistics:
[17:52:07.132] <TB3> INFO: General information:
[17:52:07.132] <TB3> INFO: 16bit words read: 3260492
[17:52:07.132] <TB3> INFO: valid events total: 353536
[17:52:07.132] <TB3> INFO: empty events: 29667
[17:52:07.132] <TB3> INFO: valid events with pixels: 323869
[17:52:07.132] <TB3> INFO: valid pixel hits: 569638
[17:52:07.132] <TB3> INFO: Event errors: 0
[17:52:07.132] <TB3> INFO: start marker: 0
[17:52:07.132] <TB3> INFO: stop marker: 0
[17:52:07.132] <TB3> INFO: overflow: 0
[17:52:07.132] <TB3> INFO: invalid 5bit words: 0
[17:52:07.132] <TB3> INFO: invalid XOR eye diagram: 0
[17:52:07.132] <TB3> INFO: frame (failed synchr.): 0
[17:52:07.132] <TB3> INFO: idle data (no TBM trl): 0
[17:52:07.132] <TB3> INFO: no data (only TBM hdr): 0
[17:52:07.132] <TB3> INFO: TBM errors: 0
[17:52:07.132] <TB3> INFO: flawed TBM headers: 0
[17:52:07.132] <TB3> INFO: flawed TBM trailers: 0
[17:52:07.132] <TB3> INFO: event ID mismatches: 0
[17:52:07.132] <TB3> INFO: ROC errors: 0
[17:52:07.132] <TB3> INFO: missing ROC header(s): 0
[17:52:07.132] <TB3> INFO: misplaced readback start: 0
[17:52:07.132] <TB3> INFO: Pixel decoding errors: 0
[17:52:07.132] <TB3> INFO: pixel data incomplete: 0
[17:52:07.132] <TB3> INFO: pixel address: 0
[17:52:07.132] <TB3> INFO: pulse height fill bit: 0
[17:52:07.132] <TB3> INFO: buffer corruption: 0
[17:52:07.132] <TB3> INFO: enter test to run
[17:52:07.132] <TB3> INFO: test: exit no parameter change
[17:52:07.259] <TB3> QUIET: Connection to board 126 closed.
[17:52:07.260] <TB3> INFO: pXar: this is the end, my friend