[11:02:47.322] <TB0> INFO: *** Welcome to pxar ***
[11:02:47.322] <TB0> INFO: *** Today: 2016/10/19
[11:02:47.328] <TB0> INFO: *** Version: c8ba-dirty
[11:02:47.328] <TB0> INFO: readRocDacs: /home/l_tester/david/FullQualification/data/M1009_FullQualification_2016-10-19_09h44m_1476863092//001_Fulltest_m20//dacParameters_C0.dat .. /home/l_tester/david/FullQualification/data/M1009_FullQualification_2016-10-19_09h44m_1476863092//001_Fulltest_m20//dacParameters_C15.dat
[11:02:47.328] <TB0> INFO: readTbmDacs: /home/l_tester/david/FullQualification/data/M1009_FullQualification_2016-10-19_09h44m_1476863092//001_Fulltest_m20//tbmParameters_C0a.dat .. /home/l_tester/david/FullQualification/data/M1009_FullQualification_2016-10-19_09h44m_1476863092//001_Fulltest_m20//tbmParameters_C1b.dat
[11:02:47.328] <TB0> INFO: readMaskFile: /home/l_tester/david/FullQualification/data/M1009_FullQualification_2016-10-19_09h44m_1476863092//001_Fulltest_m20//defaultMaskFile.dat
[11:02:47.328] <TB0> INFO: readTrimFile: /home/l_tester/david/FullQualification/data/M1009_FullQualification_2016-10-19_09h44m_1476863092//001_Fulltest_m20//trimParameters_C0.dat .. /home/l_tester/david/FullQualification/data/M1009_FullQualification_2016-10-19_09h44m_1476863092//001_Fulltest_m20//trimParameters_C15.dat
[11:02:47.388] <TB0> INFO: clk: 4
[11:02:47.388] <TB0> INFO: ctr: 4
[11:02:47.388] <TB0> INFO: sda: 19
[11:02:47.388] <TB0> INFO: tin: 9
[11:02:47.388] <TB0> INFO: level: 15
[11:02:47.388] <TB0> INFO: triggerdelay: 0
[11:02:47.388] <TB0> QUIET: Instanciating API for pxar v2.7.6+55~gafdbfd9
[11:02:47.388] <TB0> INFO: Log level: INFO
[11:02:47.397] <TB0> INFO: Found DTB DTB_WRQ4OZ
[11:02:47.407] <TB0> QUIET: Connection to board DTB_WRQ4OZ opened.
[11:02:47.409] <TB0> INFO: DTB startup information
--- DTB info------------------------------------------
Board id: 71
HW version: DTB1.2
FW version: 4.6
SW version: 4.7
Options:
USB id: DTB_WRQ4OZ
MAC address: 40D855118047
Hostname: pixelDTB071
Comment:
------------------------------------------------------
[11:02:47.411] <TB0> INFO: RPC call hashes of host and DTB match: 486171790
[11:02:48.898] <TB0> INFO: DUT info:
[11:02:48.898] <TB0> INFO: The DUT currently contains the following objects:
[11:02:48.898] <TB0> INFO: 4 TBM Cores tbm10c (4 ON)
[11:02:48.898] <TB0> INFO: TBM Core alpha (0): 7 registers set
[11:02:48.898] <TB0> INFO: TBM Core beta (1): 7 registers set
[11:02:48.898] <TB0> INFO: TBM Core alpha (2): 7 registers set
[11:02:48.898] <TB0> INFO: TBM Core beta (3): 7 registers set
[11:02:48.898] <TB0> INFO: 16 ROCs proc600 (16 ON) with 4160 pixelConfigs
[11:02:48.898] <TB0> INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[11:02:48.898] <TB0> INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[11:02:48.898] <TB0> INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[11:02:48.898] <TB0> INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[11:02:48.898] <TB0> INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[11:02:48.898] <TB0> INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[11:02:48.898] <TB0> INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[11:02:48.898] <TB0> INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[11:02:48.898] <TB0> INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[11:02:48.898] <TB0> INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[11:02:48.898] <TB0> INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[11:02:48.898] <TB0> INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[11:02:48.898] <TB0> INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[11:02:48.898] <TB0> INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[11:02:48.898] <TB0> INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[11:02:48.898] <TB0> INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[11:02:49.299] <TB0> INFO: enter 'restricted' command line mode
[11:02:49.299] <TB0> INFO: enter test to run
[11:02:49.299] <TB0> INFO: test: pretest no parameter change
[11:02:49.299] <TB0> INFO: running: pretest
[11:02:49.304] <TB0> INFO: ######################################################################
[11:02:49.304] <TB0> INFO: PixTestPretest::doTest()
[11:02:49.304] <TB0> INFO: ######################################################################
[11:02:49.305] <TB0> INFO: ----------------------------------------------------------------------
[11:02:49.305] <TB0> INFO: PixTestPretest::programROC()
[11:02:49.305] <TB0> INFO: ----------------------------------------------------------------------
[11:03:07.319] <TB0> INFO: PixTestPretest::programROC() done: ROCs are all programmable
[11:03:07.319] <TB0> INFO: IA differences per ROC: 19.3 17.7 19.3 18.5 16.9 19.3 16.9 19.3 19.3 19.3 19.3 20.1 16.9 19.3 20.1 19.3
[11:03:07.382] <TB0> INFO: ----------------------------------------------------------------------
[11:03:07.382] <TB0> INFO: PixTestPretest::setVana() target Ia = 24 mA/ROC
[11:03:07.382] <TB0> INFO: ----------------------------------------------------------------------
[11:03:28.659] <TB0> INFO: PixTestPretest::setVana() done, Module Ia 387.5 mA = 24.2188 mA/ROC
[11:03:28.659] <TB0> INFO: i(loss) [mA/ROC]: 19.3 19.3 19.3 19.3 19.3 20.1 19.3 19.3 20.1 19.3 19.3 20.1 19.3 20.1 19.3 19.3
[11:03:28.695] <TB0> INFO: ----------------------------------------------------------------------
[11:03:28.695] <TB0> INFO: PixTestPretest::findTiming()
[11:03:28.695] <TB0> INFO: ----------------------------------------------------------------------
[11:03:28.695] <TB0> INFO: PixTestCmd::init()

[11:04:01.105] <TB0> INFO: TBM phases: 160MHz: 0, 400MHz: 2, TBM delays: ROC(0/1):5, header/trailer: 1, token: 1
[11:04:01.105] <TB0> INFO: (success/tries = 100/100), width = 3
[11:04:02.608] <TB0> INFO: ----------------------------------------------------------------------
[11:04:02.608] <TB0> INFO: PixTestPretest::findWorkingPixel()
[11:04:02.608] <TB0> INFO: ----------------------------------------------------------------------
[11:04:02.702] <TB0> INFO: Expecting 231680 events.
[11:04:12.664] <TB0> INFO: 231680 events read in total (9370ms).
[11:04:12.673] <TB0> INFO: Test took 10061ms.
[11:04:12.921] <TB0> INFO: Found working pixel in all ROCs: col/row = 12/22
[11:04:12.957] <TB0> INFO: ----------------------------------------------------------------------
[11:04:12.957] <TB0> INFO: PixTestPretest::setVthrCompCalDel()
[11:04:12.957] <TB0> INFO: ----------------------------------------------------------------------
[11:04:13.051] <TB0> INFO: Expecting 231680 events.
[11:04:22.987] <TB0> INFO: 231680 events read in total (9344ms).
[11:04:22.997] <TB0> INFO: Test took 10036ms.
[11:04:23.267] <TB0> INFO: PixTestPretest::setVthrCompCalDel() done
[11:04:23.267] <TB0> INFO: CalDel: 111 112 107 88 107 98 98 103 94 103 94 106 87 83 86 100
[11:04:23.267] <TB0> INFO: VthrComp: 51 51 51 51 51 51 51 51 52 51 51 51 51 51 51 51
[11:04:23.271] <TB0> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1009_FullQualification_2016-10-19_09h44m_1476863092//001_Fulltest_m20//dacParameters_C0.dat
[11:04:23.271] <TB0> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1009_FullQualification_2016-10-19_09h44m_1476863092//001_Fulltest_m20//dacParameters_C1.dat
[11:04:23.271] <TB0> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1009_FullQualification_2016-10-19_09h44m_1476863092//001_Fulltest_m20//dacParameters_C2.dat
[11:04:23.272] <TB0> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1009_FullQualification_2016-10-19_09h44m_1476863092//001_Fulltest_m20//dacParameters_C3.dat
[11:04:23.272] <TB0> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1009_FullQualification_2016-10-19_09h44m_1476863092//001_Fulltest_m20//dacParameters_C4.dat
[11:04:23.272] <TB0> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1009_FullQualification_2016-10-19_09h44m_1476863092//001_Fulltest_m20//dacParameters_C5.dat
[11:04:23.272] <TB0> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1009_FullQualification_2016-10-19_09h44m_1476863092//001_Fulltest_m20//dacParameters_C6.dat
[11:04:23.272] <TB0> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1009_FullQualification_2016-10-19_09h44m_1476863092//001_Fulltest_m20//dacParameters_C7.dat
[11:04:23.272] <TB0> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1009_FullQualification_2016-10-19_09h44m_1476863092//001_Fulltest_m20//dacParameters_C8.dat
[11:04:23.273] <TB0> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1009_FullQualification_2016-10-19_09h44m_1476863092//001_Fulltest_m20//dacParameters_C9.dat
[11:04:23.273] <TB0> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1009_FullQualification_2016-10-19_09h44m_1476863092//001_Fulltest_m20//dacParameters_C10.dat
[11:04:23.273] <TB0> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1009_FullQualification_2016-10-19_09h44m_1476863092//001_Fulltest_m20//dacParameters_C11.dat
[11:04:23.273] <TB0> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1009_FullQualification_2016-10-19_09h44m_1476863092//001_Fulltest_m20//dacParameters_C12.dat
[11:04:23.273] <TB0> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1009_FullQualification_2016-10-19_09h44m_1476863092//001_Fulltest_m20//dacParameters_C13.dat
[11:04:23.273] <TB0> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1009_FullQualification_2016-10-19_09h44m_1476863092//001_Fulltest_m20//dacParameters_C14.dat
[11:04:23.274] <TB0> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1009_FullQualification_2016-10-19_09h44m_1476863092//001_Fulltest_m20//dacParameters_C15.dat
[11:04:23.274] <TB0> INFO: write tbm parameters into /home/l_tester/david/FullQualification/data/M1009_FullQualification_2016-10-19_09h44m_1476863092//001_Fulltest_m20//tbmParameters_C0a.dat
[11:04:23.274] <TB0> INFO: write tbm parameters into /home/l_tester/david/FullQualification/data/M1009_FullQualification_2016-10-19_09h44m_1476863092//001_Fulltest_m20//tbmParameters_C0b.dat
[11:04:23.274] <TB0> INFO: write tbm parameters into /home/l_tester/david/FullQualification/data/M1009_FullQualification_2016-10-19_09h44m_1476863092//001_Fulltest_m20//tbmParameters_C1a.dat
[11:04:23.274] <TB0> INFO: write tbm parameters into /home/l_tester/david/FullQualification/data/M1009_FullQualification_2016-10-19_09h44m_1476863092//001_Fulltest_m20//tbmParameters_C1b.dat
[11:04:23.274] <TB0> INFO: PixTestPretest::doTest() done, duration: 93 seconds
[11:04:23.325] <TB0> INFO: enter test to run
[11:04:23.325] <TB0> INFO: test: FullTest no parameter change
[11:04:23.325] <TB0> INFO: running: fulltest
[11:04:23.325] <TB0> INFO: ######################################################################
[11:04:23.325] <TB0> INFO: PixTestFullTest::doTest()
[11:04:23.325] <TB0> INFO: ######################################################################
[11:04:23.326] <TB0> INFO: ######################################################################
[11:04:23.326] <TB0> INFO: PixTestAlive::doTest()
[11:04:23.326] <TB0> INFO: ######################################################################
[11:04:23.327] <TB0> INFO: ----------------------------------------------------------------------
[11:04:23.327] <TB0> INFO: PixTestAlive::aliveTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[11:04:23.327] <TB0> INFO: ----------------------------------------------------------------------
[11:04:23.567] <TB0> INFO: Expecting 41600 events.
[11:04:27.052] <TB0> INFO: 41600 events read in total (2893ms).
[11:04:27.053] <TB0> INFO: Test took 3724ms.
[11:04:27.286] <TB0> INFO: PixTestAlive::aliveTest() done
[11:04:27.286] <TB0> INFO: number of dead pixels (per ROC): 0 0 0 0 0 0 1 0 0 0 0 1 0 0 0 0
[11:04:27.288] <TB0> INFO: ----------------------------------------------------------------------
[11:04:27.288] <TB0> INFO: PixTestAlive::maskTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[11:04:27.288] <TB0> INFO: ----------------------------------------------------------------------
[11:04:27.536] <TB0> INFO: Expecting 41600 events.
[11:04:30.512] <TB0> INFO: 41600 events read in total (2384ms).
[11:04:30.513] <TB0> INFO: Test took 3222ms.
[11:04:30.513] <TB0> INFO: mask vs. old pixelAlive PixelAlive_C0_V0 .. PixelAlive_C15_V0
[11:04:30.746] <TB0> INFO: PixTestAlive::maskTest() done
[11:04:30.746] <TB0> INFO: number of mask-defect pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[11:04:30.748] <TB0> INFO: ----------------------------------------------------------------------
[11:04:30.748] <TB0> INFO: PixTestAlive::addressDecodingTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[11:04:30.748] <TB0> INFO: ----------------------------------------------------------------------
[11:04:30.991] <TB0> INFO: Expecting 41600 events.
[11:04:34.565] <TB0> INFO: 41600 events read in total (2982ms).
[11:04:34.566] <TB0> INFO: Test took 3816ms.
[11:04:34.799] <TB0> INFO: PixTestAlive::addressDecodingTest() done
[11:04:34.799] <TB0> INFO: number of address-decoding pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[11:04:34.799] <TB0> INFO: PixTestAlive::doTest() done, duration: 11 seconds
[11:04:34.799] <TB0> INFO: Decoding statistics:
[11:04:34.799] <TB0> INFO: General information:
[11:04:34.799] <TB0> INFO: 16bit words read: 0
[11:04:34.799] <TB0> INFO: valid events total: 0
[11:04:34.799] <TB0> INFO: empty events: 0
[11:04:34.799] <TB0> INFO: valid events with pixels: 0
[11:04:34.799] <TB0> INFO: valid pixel hits: 0
[11:04:34.799] <TB0> INFO: Event errors: 0
[11:04:34.799] <TB0> INFO: start marker: 0
[11:04:34.799] <TB0> INFO: stop marker: 0
[11:04:34.799] <TB0> INFO: overflow: 0
[11:04:34.800] <TB0> INFO: invalid 5bit words: 0
[11:04:34.800] <TB0> INFO: invalid XOR eye diagram: 0
[11:04:34.800] <TB0> INFO: frame (failed synchr.): 0
[11:04:34.800] <TB0> INFO: idle data (no TBM trl): 0
[11:04:34.800] <TB0> INFO: no data (only TBM hdr): 0
[11:04:34.800] <TB0> INFO: TBM errors: 0
[11:04:34.800] <TB0> INFO: flawed TBM headers: 0
[11:04:34.800] <TB0> INFO: flawed TBM trailers: 0
[11:04:34.800] <TB0> INFO: event ID mismatches: 0
[11:04:34.800] <TB0> INFO: ROC errors: 0
[11:04:34.800] <TB0> INFO: missing ROC header(s): 0
[11:04:34.800] <TB0> INFO: misplaced readback start: 0
[11:04:34.800] <TB0> INFO: Pixel decoding errors: 0
[11:04:34.800] <TB0> INFO: pixel data incomplete: 0
[11:04:34.800] <TB0> INFO: pixel address: 0
[11:04:34.800] <TB0> INFO: pulse height fill bit: 0
[11:04:34.800] <TB0> INFO: buffer corruption: 0
[11:04:34.807] <TB0> INFO: readReadbackCal: /home/l_tester/david/FullQualification/data/M1009_FullQualification_2016-10-19_09h44m_1476863092//001_Fulltest_m20//readbackCal_C0.dat .. /home/l_tester/david/FullQualification/data/M1009_FullQualification_2016-10-19_09h44m_1476863092//001_Fulltest_m20//readbackCal_C15.dat
[11:04:34.808] <TB0> INFO: readGainPedestalParameters /home/l_tester/david/FullQualification/data/M1009_FullQualification_2016-10-19_09h44m_1476863092//001_Fulltest_m20//phCalibrationFitErr_C0.dat .. /home/l_tester/david/FullQualification/data/M1009_FullQualification_2016-10-19_09h44m_1476863092//001_Fulltest_m20//phCalibrationFitErr_C15.dat

[11:04:34.809] <TB0> INFO: ######################################################################
[11:04:34.809] <TB0> INFO: PixTestReadback::doTest()
[11:04:34.809] <TB0> INFO: ######################################################################
[11:04:34.809] <TB0> INFO: ----------------------------------------------------------------------
[11:04:34.809] <TB0> INFO: PixTestReadback::CalibrateVd()
[11:04:34.809] <TB0> INFO: ----------------------------------------------------------------------
[11:04:44.764] <TB0> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1009_FullQualification_2016-10-19_09h44m_1476863092//001_Fulltest_m20//readbackCal_C0.dat
[11:04:44.764] <TB0> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1009_FullQualification_2016-10-19_09h44m_1476863092//001_Fulltest_m20//readbackCal_C1.dat
[11:04:44.764] <TB0> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1009_FullQualification_2016-10-19_09h44m_1476863092//001_Fulltest_m20//readbackCal_C2.dat
[11:04:44.765] <TB0> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1009_FullQualification_2016-10-19_09h44m_1476863092//001_Fulltest_m20//readbackCal_C3.dat
[11:04:44.765] <TB0> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1009_FullQualification_2016-10-19_09h44m_1476863092//001_Fulltest_m20//readbackCal_C4.dat
[11:04:44.765] <TB0> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1009_FullQualification_2016-10-19_09h44m_1476863092//001_Fulltest_m20//readbackCal_C5.dat
[11:04:44.765] <TB0> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1009_FullQualification_2016-10-19_09h44m_1476863092//001_Fulltest_m20//readbackCal_C6.dat
[11:04:44.765] <TB0> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1009_FullQualification_2016-10-19_09h44m_1476863092//001_Fulltest_m20//readbackCal_C7.dat
[11:04:44.765] <TB0> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1009_FullQualification_2016-10-19_09h44m_1476863092//001_Fulltest_m20//readbackCal_C8.dat
[11:04:44.765] <TB0> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1009_FullQualification_2016-10-19_09h44m_1476863092//001_Fulltest_m20//readbackCal_C9.dat
[11:04:44.765] <TB0> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1009_FullQualification_2016-10-19_09h44m_1476863092//001_Fulltest_m20//readbackCal_C10.dat
[11:04:44.765] <TB0> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1009_FullQualification_2016-10-19_09h44m_1476863092//001_Fulltest_m20//readbackCal_C11.dat
[11:04:44.765] <TB0> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1009_FullQualification_2016-10-19_09h44m_1476863092//001_Fulltest_m20//readbackCal_C12.dat
[11:04:44.765] <TB0> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1009_FullQualification_2016-10-19_09h44m_1476863092//001_Fulltest_m20//readbackCal_C13.dat
[11:04:44.765] <TB0> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1009_FullQualification_2016-10-19_09h44m_1476863092//001_Fulltest_m20//readbackCal_C14.dat
[11:04:44.765] <TB0> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1009_FullQualification_2016-10-19_09h44m_1476863092//001_Fulltest_m20//readbackCal_C15.dat
[11:04:44.797] <TB0> INFO: PixTestPattern:: pg_setup set to default.
[11:04:44.797] <TB0> INFO: ----------------------------------------------------------------------
[11:04:44.797] <TB0> INFO: PixTestReadback::CalibrateVa()
[11:04:44.797] <TB0> INFO: ----------------------------------------------------------------------
[11:04:54.726] <TB0> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1009_FullQualification_2016-10-19_09h44m_1476863092//001_Fulltest_m20//readbackCal_C0.dat
[11:04:54.726] <TB0> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1009_FullQualification_2016-10-19_09h44m_1476863092//001_Fulltest_m20//readbackCal_C1.dat
[11:04:54.726] <TB0> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1009_FullQualification_2016-10-19_09h44m_1476863092//001_Fulltest_m20//readbackCal_C2.dat
[11:04:54.726] <TB0> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1009_FullQualification_2016-10-19_09h44m_1476863092//001_Fulltest_m20//readbackCal_C3.dat
[11:04:54.727] <TB0> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1009_FullQualification_2016-10-19_09h44m_1476863092//001_Fulltest_m20//readbackCal_C4.dat
[11:04:54.727] <TB0> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1009_FullQualification_2016-10-19_09h44m_1476863092//001_Fulltest_m20//readbackCal_C5.dat
[11:04:54.727] <TB0> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1009_FullQualification_2016-10-19_09h44m_1476863092//001_Fulltest_m20//readbackCal_C6.dat
[11:04:54.727] <TB0> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1009_FullQualification_2016-10-19_09h44m_1476863092//001_Fulltest_m20//readbackCal_C7.dat
[11:04:54.727] <TB0> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1009_FullQualification_2016-10-19_09h44m_1476863092//001_Fulltest_m20//readbackCal_C8.dat
[11:04:54.727] <TB0> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1009_FullQualification_2016-10-19_09h44m_1476863092//001_Fulltest_m20//readbackCal_C9.dat
[11:04:54.727] <TB0> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1009_FullQualification_2016-10-19_09h44m_1476863092//001_Fulltest_m20//readbackCal_C10.dat
[11:04:54.727] <TB0> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1009_FullQualification_2016-10-19_09h44m_1476863092//001_Fulltest_m20//readbackCal_C11.dat
[11:04:54.727] <TB0> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1009_FullQualification_2016-10-19_09h44m_1476863092//001_Fulltest_m20//readbackCal_C12.dat
[11:04:54.727] <TB0> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1009_FullQualification_2016-10-19_09h44m_1476863092//001_Fulltest_m20//readbackCal_C13.dat
[11:04:54.727] <TB0> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1009_FullQualification_2016-10-19_09h44m_1476863092//001_Fulltest_m20//readbackCal_C14.dat
[11:04:54.727] <TB0> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1009_FullQualification_2016-10-19_09h44m_1476863092//001_Fulltest_m20//readbackCal_C15.dat
[11:04:54.759] <TB0> INFO: PixTestPattern:: pg_setup set to default.
[11:04:54.759] <TB0> INFO: ----------------------------------------------------------------------
[11:04:54.759] <TB0> INFO: PixTestReadback::readbackVbg()
[11:04:54.759] <TB0> INFO: ----------------------------------------------------------------------
[11:05:02.431] <TB0> INFO: PixTestPattern:: pg_setup set to default.
[11:05:02.431] <TB0> INFO: ----------------------------------------------------------------------
[11:05:02.431] <TB0> INFO: PixTestReadback::getCalibratedVbg()
[11:05:02.431] <TB0> INFO: ----------------------------------------------------------------------
[11:05:02.432] <TB0> INFO: Vbg will be calibrated using Vd calibration
[11:05:02.432] <TB0> INFO: /*/*/*/*::: ROC 0: uncalibrated Vbg = 146calibrated Vbg = 1.16938 :::*/*/*/*/
[11:05:02.432] <TB0> INFO: /*/*/*/*::: ROC 1: uncalibrated Vbg = 153.1calibrated Vbg = 1.16474 :::*/*/*/*/
[11:05:02.432] <TB0> INFO: /*/*/*/*::: ROC 2: uncalibrated Vbg = 151.8calibrated Vbg = 1.16492 :::*/*/*/*/
[11:05:02.432] <TB0> INFO: /*/*/*/*::: ROC 3: uncalibrated Vbg = 146.4calibrated Vbg = 1.1667 :::*/*/*/*/
[11:05:02.432] <TB0> INFO: /*/*/*/*::: ROC 4: uncalibrated Vbg = 151.4calibrated Vbg = 1.16585 :::*/*/*/*/
[11:05:02.432] <TB0> INFO: /*/*/*/*::: ROC 5: uncalibrated Vbg = 154.1calibrated Vbg = 1.16682 :::*/*/*/*/
[11:05:02.432] <TB0> INFO: /*/*/*/*::: ROC 6: uncalibrated Vbg = 151.7calibrated Vbg = 1.17124 :::*/*/*/*/
[11:05:02.432] <TB0> INFO: /*/*/*/*::: ROC 7: uncalibrated Vbg = 153.1calibrated Vbg = 1.1684 :::*/*/*/*/
[11:05:02.432] <TB0> INFO: /*/*/*/*::: ROC 8: uncalibrated Vbg = 156.9calibrated Vbg = 1.16672 :::*/*/*/*/
[11:05:02.432] <TB0> INFO: /*/*/*/*::: ROC 9: uncalibrated Vbg = 160.7calibrated Vbg = 1.16471 :::*/*/*/*/
[11:05:02.432] <TB0> INFO: /*/*/*/*::: ROC 10: uncalibrated Vbg = 156.7calibrated Vbg = 1.15989 :::*/*/*/*/
[11:05:02.432] <TB0> INFO: /*/*/*/*::: ROC 11: uncalibrated Vbg = 142calibrated Vbg = 1.15497 :::*/*/*/*/
[11:05:02.432] <TB0> INFO: /*/*/*/*::: ROC 12: uncalibrated Vbg = 156.9calibrated Vbg = 1.16527 :::*/*/*/*/
[11:05:02.432] <TB0> INFO: /*/*/*/*::: ROC 13: uncalibrated Vbg = 152.9calibrated Vbg = 1.16798 :::*/*/*/*/
[11:05:02.432] <TB0> INFO: /*/*/*/*::: ROC 14: uncalibrated Vbg = 151.8calibrated Vbg = 1.16053 :::*/*/*/*/
[11:05:02.432] <TB0> INFO: /*/*/*/*::: ROC 15: uncalibrated Vbg = 155.9calibrated Vbg = 1.16796 :::*/*/*/*/
[11:05:02.435] <TB0> INFO: ----------------------------------------------------------------------
[11:05:02.435] <TB0> INFO: PixTestReadback::CalibrateIa()
[11:05:02.435] <TB0> INFO: ----------------------------------------------------------------------
[11:07:43.259] <TB0> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1009_FullQualification_2016-10-19_09h44m_1476863092//001_Fulltest_m20//readbackCal_C0.dat
[11:07:43.259] <TB0> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1009_FullQualification_2016-10-19_09h44m_1476863092//001_Fulltest_m20//readbackCal_C1.dat
[11:07:43.259] <TB0> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1009_FullQualification_2016-10-19_09h44m_1476863092//001_Fulltest_m20//readbackCal_C2.dat
[11:07:43.259] <TB0> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1009_FullQualification_2016-10-19_09h44m_1476863092//001_Fulltest_m20//readbackCal_C3.dat
[11:07:43.259] <TB0> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1009_FullQualification_2016-10-19_09h44m_1476863092//001_Fulltest_m20//readbackCal_C4.dat
[11:07:43.259] <TB0> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1009_FullQualification_2016-10-19_09h44m_1476863092//001_Fulltest_m20//readbackCal_C5.dat
[11:07:43.259] <TB0> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1009_FullQualification_2016-10-19_09h44m_1476863092//001_Fulltest_m20//readbackCal_C6.dat
[11:07:43.259] <TB0> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1009_FullQualification_2016-10-19_09h44m_1476863092//001_Fulltest_m20//readbackCal_C7.dat
[11:07:43.259] <TB0> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1009_FullQualification_2016-10-19_09h44m_1476863092//001_Fulltest_m20//readbackCal_C8.dat
[11:07:43.259] <TB0> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1009_FullQualification_2016-10-19_09h44m_1476863092//001_Fulltest_m20//readbackCal_C9.dat
[11:07:43.260] <TB0> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1009_FullQualification_2016-10-19_09h44m_1476863092//001_Fulltest_m20//readbackCal_C10.dat
[11:07:43.260] <TB0> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1009_FullQualification_2016-10-19_09h44m_1476863092//001_Fulltest_m20//readbackCal_C11.dat
[11:07:43.260] <TB0> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1009_FullQualification_2016-10-19_09h44m_1476863092//001_Fulltest_m20//readbackCal_C12.dat
[11:07:43.260] <TB0> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1009_FullQualification_2016-10-19_09h44m_1476863092//001_Fulltest_m20//readbackCal_C13.dat
[11:07:43.260] <TB0> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1009_FullQualification_2016-10-19_09h44m_1476863092//001_Fulltest_m20//readbackCal_C14.dat
[11:07:43.260] <TB0> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1009_FullQualification_2016-10-19_09h44m_1476863092//001_Fulltest_m20//readbackCal_C15.dat
[11:07:43.292] <TB0> INFO: PixTestPattern:: pg_setup set to default.
[11:07:43.293] <TB0> INFO: PixTestReadback::doTest() done
[11:07:43.294] <TB0> INFO: Decoding statistics:
[11:07:43.294] <TB0> INFO: General information:
[11:07:43.294] <TB0> INFO: 16bit words read: 1536
[11:07:43.294] <TB0> INFO: valid events total: 256
[11:07:43.294] <TB0> INFO: empty events: 256
[11:07:43.294] <TB0> INFO: valid events with pixels: 0
[11:07:43.294] <TB0> INFO: valid pixel hits: 0
[11:07:43.294] <TB0> INFO: Event errors: 0
[11:07:43.294] <TB0> INFO: start marker: 0
[11:07:43.294] <TB0> INFO: stop marker: 0
[11:07:43.294] <TB0> INFO: overflow: 0
[11:07:43.294] <TB0> INFO: invalid 5bit words: 0
[11:07:43.294] <TB0> INFO: invalid XOR eye diagram: 0
[11:07:43.294] <TB0> INFO: frame (failed synchr.): 0
[11:07:43.294] <TB0> INFO: idle data (no TBM trl): 0
[11:07:43.294] <TB0> INFO: no data (only TBM hdr): 0
[11:07:43.294] <TB0> INFO: TBM errors: 0
[11:07:43.294] <TB0> INFO: flawed TBM headers: 0
[11:07:43.294] <TB0> INFO: flawed TBM trailers: 0
[11:07:43.294] <TB0> INFO: event ID mismatches: 0
[11:07:43.294] <TB0> INFO: ROC errors: 0
[11:07:43.294] <TB0> INFO: missing ROC header(s): 0
[11:07:43.294] <TB0> INFO: misplaced readback start: 0
[11:07:43.294] <TB0> INFO: Pixel decoding errors: 0
[11:07:43.294] <TB0> INFO: pixel data incomplete: 0
[11:07:43.294] <TB0> INFO: pixel address: 0
[11:07:43.294] <TB0> INFO: pulse height fill bit: 0
[11:07:43.294] <TB0> INFO: buffer corruption: 0
[11:07:43.347] <TB0> INFO: ######################################################################
[11:07:43.347] <TB0> INFO: PixTestBBMap::doTest() Ntrig = 5, VcalS = 250 (high range)
[11:07:43.347] <TB0> INFO: ######################################################################
[11:07:43.350] <TB0> INFO: ---> dac: VthrComp name: calSMap ntrig: 5 dacrange: 0 .. 149 (-1/-1) hits flags = 514 (plus default)
[11:07:43.405] <TB0> INFO: dacScan split into 1 runs with ntrig = 5
[11:07:43.405] <TB0> INFO: run 1 of 1
[11:07:43.689] <TB0> INFO: Expecting 3120000 events.
[11:08:14.993] <TB0> INFO: 671990 events read in total (30712ms).











[11:08:45.449] <TB0> INFO: 1344125 events read in total (61168ms).











[11:09:15.588] <TB0> INFO: 2013495 events read in total (91308ms).











[11:09:45.886] <TB0> INFO: 2681785 events read in total (121605ms).
[11:10:05.931] <TB0> INFO: 3120000 events read in total (141650ms).
[11:10:05.992] <TB0> INFO: Test took 142588ms.
[11:10:32.195] <TB0> INFO: PixTestBBMap::doTest() done with 2 decoding errors: , duration: 168 seconds
[11:10:32.196] <TB0> INFO: number of dead bumps (per ROC): 0 0 1 0 0 0 0 1 0 0 0 0 1 0 1 0
[11:10:32.196] <TB0> INFO: separation cut (per ROC): 104 103 109 107 109 114 104 101 108 106 108 115 107 109 113 104
[11:10:32.196] <TB0> INFO: Decoding statistics:
[11:10:32.196] <TB0> INFO: General information:
[11:10:32.196] <TB0> INFO: 16bit words read: 0
[11:10:32.196] <TB0> INFO: valid events total: 0
[11:10:32.196] <TB0> INFO: empty events: 0
[11:10:32.196] <TB0> INFO: valid events with pixels: 0
[11:10:32.196] <TB0> INFO: valid pixel hits: 0
[11:10:32.196] <TB0> INFO: Event errors: 0
[11:10:32.196] <TB0> INFO: start marker: 0
[11:10:32.196] <TB0> INFO: stop marker: 0
[11:10:32.196] <TB0> INFO: overflow: 0
[11:10:32.196] <TB0> INFO: invalid 5bit words: 0
[11:10:32.196] <TB0> INFO: invalid XOR eye diagram: 0
[11:10:32.196] <TB0> INFO: frame (failed synchr.): 0
[11:10:32.196] <TB0> INFO: idle data (no TBM trl): 0
[11:10:32.196] <TB0> INFO: no data (only TBM hdr): 0
[11:10:32.196] <TB0> INFO: TBM errors: 0
[11:10:32.196] <TB0> INFO: flawed TBM headers: 0
[11:10:32.196] <TB0> INFO: flawed TBM trailers: 0
[11:10:32.196] <TB0> INFO: event ID mismatches: 0
[11:10:32.196] <TB0> INFO: ROC errors: 0
[11:10:32.196] <TB0> INFO: missing ROC header(s): 0
[11:10:32.196] <TB0> INFO: misplaced readback start: 0
[11:10:32.196] <TB0> INFO: Pixel decoding errors: 0
[11:10:32.196] <TB0> INFO: pixel data incomplete: 0
[11:10:32.196] <TB0> INFO: pixel address: 0
[11:10:32.196] <TB0> INFO: pulse height fill bit: 0
[11:10:32.196] <TB0> INFO: buffer corruption: 0
[11:10:32.235] <TB0> INFO: ######################################################################
[11:10:32.235] <TB0> INFO: PixTestScurves::fullTest() ntrig = 50, dacs/step = -1, ntrig/step = -1
[11:10:32.235] <TB0> INFO: ######################################################################
[11:10:32.235] <TB0> INFO: ----------------------------------------------------------------------
[11:10:32.235] <TB0> INFO: PixTestScurves::scurves(Vcal), ntrig = 50, dacs/step = -1, ntrig/step = -1
[11:10:32.235] <TB0> INFO: ----------------------------------------------------------------------
[11:10:32.235] <TB0> INFO: ---> dac: Vcal name: scurveVcal ntrig: 50 dacrange: 25 .. 200 (-1/-1) hits flags = 528 (plus default)
[11:10:32.252] <TB0> INFO: dacScan split into 1 runs with ntrig = 50
[11:10:32.252] <TB0> INFO: run 1 of 1
[11:10:32.497] <TB0> INFO: Expecting 36608000 events.
[11:10:55.772] <TB0> INFO: 685050 events read in total (22683ms).
[11:11:18.600] <TB0> INFO: 1354450 events read in total (45511ms).
[11:11:41.498] <TB0> INFO: 2023750 events read in total (68409ms).
[11:12:04.549] <TB0> INFO: 2691950 events read in total (91460ms).
[11:12:27.201] <TB0> INFO: 3361100 events read in total (114112ms).
[11:12:50.318] <TB0> INFO: 4030450 events read in total (137229ms).
[11:13:13.182] <TB0> INFO: 4698500 events read in total (160093ms).
[11:13:35.998] <TB0> INFO: 5365750 events read in total (182909ms).
[11:13:58.747] <TB0> INFO: 6033250 events read in total (205658ms).
[11:14:21.732] <TB0> INFO: 6700950 events read in total (228643ms).
[11:14:44.338] <TB0> INFO: 7366050 events read in total (251249ms).
[11:15:07.362] <TB0> INFO: 8033350 events read in total (274273ms).
[11:15:30.197] <TB0> INFO: 8700750 events read in total (297108ms).
[11:15:53.214] <TB0> INFO: 9368600 events read in total (320125ms).
[11:16:15.929] <TB0> INFO: 10033650 events read in total (342840ms).
[11:16:38.633] <TB0> INFO: 10699300 events read in total (365544ms).
[11:17:01.429] <TB0> INFO: 11365700 events read in total (388340ms).
[11:17:24.644] <TB0> INFO: 12030350 events read in total (411555ms).
[11:17:47.334] <TB0> INFO: 12696350 events read in total (434245ms).
[11:18:10.088] <TB0> INFO: 13360950 events read in total (456999ms).
[11:18:32.952] <TB0> INFO: 14024000 events read in total (479863ms).
[11:18:55.747] <TB0> INFO: 14687100 events read in total (502658ms).
[11:19:18.637] <TB0> INFO: 15352450 events read in total (525548ms).
[11:19:41.621] <TB0> INFO: 16016050 events read in total (548532ms).
[11:20:04.402] <TB0> INFO: 16681450 events read in total (571313ms).
[11:20:27.215] <TB0> INFO: 17343150 events read in total (594126ms).
[11:20:50.083] <TB0> INFO: 18003900 events read in total (616994ms).
[11:21:12.865] <TB0> INFO: 18665800 events read in total (639776ms).
[11:21:35.578] <TB0> INFO: 19329050 events read in total (662489ms).
[11:21:58.533] <TB0> INFO: 19989900 events read in total (685444ms).
[11:22:21.273] <TB0> INFO: 20651400 events read in total (708184ms).
[11:22:43.873] <TB0> INFO: 21312200 events read in total (730784ms).
[11:23:06.680] <TB0> INFO: 21975300 events read in total (753591ms).
[11:23:29.597] <TB0> INFO: 22637700 events read in total (776508ms).
[11:23:52.551] <TB0> INFO: 23297650 events read in total (799462ms).
[11:24:15.303] <TB0> INFO: 23957900 events read in total (822214ms).
[11:24:38.020] <TB0> INFO: 24617350 events read in total (844931ms).
[11:25:00.681] <TB0> INFO: 25278600 events read in total (867592ms).
[11:25:23.443] <TB0> INFO: 25938250 events read in total (890354ms).
[11:25:46.034] <TB0> INFO: 26597050 events read in total (912945ms).
[11:26:08.773] <TB0> INFO: 27255900 events read in total (935684ms).
[11:26:31.395] <TB0> INFO: 27915150 events read in total (958306ms).
[11:26:54.058] <TB0> INFO: 28574600 events read in total (980969ms).
[11:27:17.166] <TB0> INFO: 29232950 events read in total (1004077ms).
[11:27:40.142] <TB0> INFO: 29892700 events read in total (1027053ms).
[11:28:02.826] <TB0> INFO: 30552850 events read in total (1049737ms).
[11:28:25.400] <TB0> INFO: 31213100 events read in total (1072311ms).
[11:28:48.398] <TB0> INFO: 31872600 events read in total (1095309ms).
[11:29:11.068] <TB0> INFO: 32531800 events read in total (1117979ms).
[11:29:33.801] <TB0> INFO: 33191800 events read in total (1140712ms).
[11:29:56.824] <TB0> INFO: 33852450 events read in total (1163735ms).
[11:30:19.801] <TB0> INFO: 34512350 events read in total (1186712ms).
[11:30:42.815] <TB0> INFO: 35173150 events read in total (1209726ms).
[11:31:05.473] <TB0> INFO: 35833550 events read in total (1232384ms).
[11:31:28.626] <TB0> INFO: 36506250 events read in total (1255537ms).
[11:31:32.600] <TB0> INFO: 36608000 events read in total (1259511ms).
[11:31:32.671] <TB0> INFO: Test took 1260419ms.
[11:31:33.218] <TB0> INFO: dumping ASCII scurve output file: SCurveData
[11:31:35.204] <TB0> INFO: dumping ASCII scurve output file: SCurveData
[11:31:36.682] <TB0> INFO: dumping ASCII scurve output file: SCurveData
[11:31:38.438] <TB0> INFO: dumping ASCII scurve output file: SCurveData
[11:31:40.283] <TB0> INFO: dumping ASCII scurve output file: SCurveData
[11:31:42.064] <TB0> INFO: dumping ASCII scurve output file: SCurveData
[11:31:43.549] <TB0> INFO: dumping ASCII scurve output file: SCurveData
[11:31:44.966] <TB0> INFO: dumping ASCII scurve output file: SCurveData
[11:31:46.597] <TB0> INFO: dumping ASCII scurve output file: SCurveData
[11:31:48.530] <TB0> INFO: dumping ASCII scurve output file: SCurveData
[11:31:50.463] <TB0> INFO: dumping ASCII scurve output file: SCurveData
[11:31:52.466] <TB0> INFO: dumping ASCII scurve output file: SCurveData
[11:31:54.252] <TB0> INFO: dumping ASCII scurve output file: SCurveData
[11:31:55.887] <TB0> INFO: dumping ASCII scurve output file: SCurveData
[11:31:57.700] <TB0> INFO: dumping ASCII scurve output file: SCurveData
[11:31:59.387] <TB0> INFO: dumping ASCII scurve output file: SCurveData
[11:32:00.816] <TB0> INFO: PixTestScurves::scurves() done
[11:32:00.816] <TB0> INFO: Vcal mean: 114.07 113.99 119.56 112.83 113.13 129.80 116.14 107.48 120.46 111.80 117.60 120.04 117.86 114.09 116.39 110.85
[11:32:00.816] <TB0> INFO: Vcal RMS: 5.70 5.64 5.73 5.61 4.82 6.62 6.00 5.32 5.98 5.67 5.75 6.54 5.58 5.14 5.75 4.87
[11:32:00.816] <TB0> INFO: PixTestScurves::fullTest() done, duration: 1288 seconds
[11:32:00.816] <TB0> INFO: Decoding statistics:
[11:32:00.816] <TB0> INFO: General information:
[11:32:00.816] <TB0> INFO: 16bit words read: 0
[11:32:00.816] <TB0> INFO: valid events total: 0
[11:32:00.816] <TB0> INFO: empty events: 0
[11:32:00.816] <TB0> INFO: valid events with pixels: 0
[11:32:00.816] <TB0> INFO: valid pixel hits: 0
[11:32:00.816] <TB0> INFO: Event errors: 0
[11:32:00.816] <TB0> INFO: start marker: 0
[11:32:00.816] <TB0> INFO: stop marker: 0
[11:32:00.816] <TB0> INFO: overflow: 0
[11:32:00.816] <TB0> INFO: invalid 5bit words: 0
[11:32:00.816] <TB0> INFO: invalid XOR eye diagram: 0
[11:32:00.816] <TB0> INFO: frame (failed synchr.): 0
[11:32:00.816] <TB0> INFO: idle data (no TBM trl): 0
[11:32:00.816] <TB0> INFO: no data (only TBM hdr): 0
[11:32:00.816] <TB0> INFO: TBM errors: 0
[11:32:00.816] <TB0> INFO: flawed TBM headers: 0
[11:32:00.816] <TB0> INFO: flawed TBM trailers: 0
[11:32:00.816] <TB0> INFO: event ID mismatches: 0
[11:32:00.816] <TB0> INFO: ROC errors: 0
[11:32:00.817] <TB0> INFO: missing ROC header(s): 0
[11:32:00.817] <TB0> INFO: misplaced readback start: 0
[11:32:00.817] <TB0> INFO: Pixel decoding errors: 0
[11:32:00.817] <TB0> INFO: pixel data incomplete: 0
[11:32:00.817] <TB0> INFO: pixel address: 0
[11:32:00.817] <TB0> INFO: pulse height fill bit: 0
[11:32:00.817] <TB0> INFO: buffer corruption: 0
[11:32:00.883] <TB0> INFO: ######################################################################
[11:32:00.883] <TB0> INFO: PixTestTrim::doTest()
[11:32:00.883] <TB0> INFO: ######################################################################
[11:32:00.884] <TB0> INFO: ----------------------------------------------------------------------
[11:32:00.884] <TB0> INFO: PixTestTrim::trimTest() ntrig = 8, vcal = 35
[11:32:00.884] <TB0> INFO: ----------------------------------------------------------------------
[11:32:00.926] <TB0> INFO: ---> VthrComp thr map (minimal VthrComp)
[11:32:00.926] <TB0> INFO: ---> dac: vthrcomp name: TrimThr0 ntrig: 8 dacrange: 10 .. 160 (-1/-1) hits flags = 528 (plus default)
[11:32:00.944] <TB0> INFO: dacScan split into 1 runs with ntrig = 8
[11:32:00.944] <TB0> INFO: run 1 of 1
[11:32:01.182] <TB0> INFO: Expecting 5025280 events.
[11:32:33.114] <TB0> INFO: 827520 events read in total (31329ms).
[11:33:03.296] <TB0> INFO: 1652720 events read in total (61511ms).
[11:33:33.810] <TB0> INFO: 2475640 events read in total (92025ms).
[11:34:04.430] <TB0> INFO: 3294856 events read in total (122645ms).
[11:34:34.871] <TB0> INFO: 4111376 events read in total (153086ms).
[11:35:05.620] <TB0> INFO: 4924688 events read in total (183835ms).
[11:35:09.673] <TB0> INFO: 5025280 events read in total (187888ms).
[11:35:09.721] <TB0> INFO: Test took 188777ms.
[11:35:29.609] <TB0> INFO: ROC 0 VthrComp = 116
[11:35:29.609] <TB0> INFO: ROC 1 VthrComp = 110
[11:35:29.609] <TB0> INFO: ROC 2 VthrComp = 122
[11:35:29.610] <TB0> INFO: ROC 3 VthrComp = 116
[11:35:29.610] <TB0> INFO: ROC 4 VthrComp = 118
[11:35:29.610] <TB0> INFO: ROC 5 VthrComp = 128
[11:35:29.610] <TB0> INFO: ROC 6 VthrComp = 114
[11:35:29.610] <TB0> INFO: ROC 7 VthrComp = 107
[11:35:29.610] <TB0> INFO: ROC 8 VthrComp = 127
[11:35:29.610] <TB0> INFO: ROC 9 VthrComp = 110
[11:35:29.610] <TB0> INFO: ROC 10 VthrComp = 124
[11:35:29.610] <TB0> INFO: ROC 11 VthrComp = 123
[11:35:29.610] <TB0> INFO: ROC 12 VthrComp = 122
[11:35:29.611] <TB0> INFO: ROC 13 VthrComp = 120
[11:35:29.611] <TB0> INFO: ROC 14 VthrComp = 124
[11:35:29.611] <TB0> INFO: ROC 15 VthrComp = 114
[11:35:29.881] <TB0> INFO: Expecting 41600 events.
[11:35:33.391] <TB0> INFO: 41600 events read in total (2918ms).
[11:35:33.392] <TB0> INFO: Test took 3780ms.
[11:35:33.401] <TB0> INFO: ---> Vcal thr map (pixel with maximum Vcal thr)
[11:35:33.401] <TB0> INFO: ---> dac: vcal name: TrimThr1 ntrig: 8 dacrange: 10 .. 160 (-1/-1) hits flags = 528 (plus default)
[11:35:33.415] <TB0> INFO: dacScan split into 1 runs with ntrig = 8
[11:35:33.415] <TB0> INFO: run 1 of 1
[11:35:33.693] <TB0> INFO: Expecting 5025280 events.
[11:36:00.558] <TB0> INFO: 591376 events read in total (26273ms).
[11:36:26.725] <TB0> INFO: 1181368 events read in total (52440ms).
[11:36:52.249] <TB0> INFO: 1771520 events read in total (77964ms).
[11:37:18.021] <TB0> INFO: 2360456 events read in total (103736ms).
[11:37:43.842] <TB0> INFO: 2947336 events read in total (129557ms).
[11:38:09.249] <TB0> INFO: 3533064 events read in total (154964ms).
[11:38:34.878] <TB0> INFO: 4118304 events read in total (180593ms).
[11:39:00.327] <TB0> INFO: 4702368 events read in total (206042ms).
[11:39:14.535] <TB0> INFO: 5025280 events read in total (220250ms).
[11:39:14.604] <TB0> INFO: Test took 221189ms.
[11:39:37.964] <TB0> INFO: roc 0 with ID = 0 has maximal Vcal 61.2344 for pixel 13/8 mean/min/max = 46.3657/31.2082/61.5231
[11:39:37.964] <TB0> INFO: roc 1 with ID = 1 has maximal Vcal 63.1996 for pixel 28/79 mean/min/max = 48.6538/33.7536/63.5539
[11:39:37.965] <TB0> INFO: roc 2 with ID = 2 has maximal Vcal 59.8079 for pixel 23/68 mean/min/max = 46.103/32.3555/59.8506
[11:39:37.965] <TB0> INFO: roc 3 with ID = 3 has maximal Vcal 60.4481 for pixel 20/52 mean/min/max = 46.125/31.735/60.515
[11:39:37.966] <TB0> INFO: roc 4 with ID = 4 has maximal Vcal 58.2697 for pixel 0/23 mean/min/max = 45.1774/31.897/58.4579
[11:39:37.966] <TB0> INFO: roc 5 with ID = 5 has maximal Vcal 62.9911 for pixel 26/0 mean/min/max = 47.3223/31.6178/63.0268
[11:39:37.967] <TB0> INFO: roc 6 with ID = 6 has maximal Vcal 61.0868 for pixel 26/6 mean/min/max = 46.8203/32.3225/61.3181
[11:39:37.968] <TB0> INFO: roc 7 with ID = 7 has maximal Vcal 61.1269 for pixel 19/74 mean/min/max = 47.9935/34.7217/61.2654
[11:39:37.968] <TB0> INFO: roc 8 with ID = 8 has maximal Vcal 58.3025 for pixel 8/44 mean/min/max = 44.9069/31.1132/58.7005
[11:39:37.969] <TB0> INFO: roc 9 with ID = 9 has maximal Vcal 66.3782 for pixel 11/8 mean/min/max = 49.5575/32.7366/66.3785
[11:39:37.969] <TB0> INFO: roc 10 with ID = 10 has maximal Vcal 58.685 for pixel 0/22 mean/min/max = 45.2129/31.6906/58.7352
[11:39:37.970] <TB0> INFO: roc 11 with ID = 11 has maximal Vcal 61.9884 for pixel 0/66 mean/min/max = 46.248/30.453/62.0431
[11:39:37.970] <TB0> INFO: roc 12 with ID = 12 has maximal Vcal 59.1274 for pixel 0/21 mean/min/max = 45.5552/31.8421/59.2683
[11:39:37.971] <TB0> INFO: roc 13 with ID = 13 has maximal Vcal 58.8209 for pixel 44/9 mean/min/max = 45.3172/31.4999/59.1345
[11:39:37.971] <TB0> INFO: roc 14 with ID = 14 has maximal Vcal 60.0398 for pixel 12/70 mean/min/max = 45.7506/31.4097/60.0915
[11:39:37.972] <TB0> INFO: roc 15 with ID = 15 has maximal Vcal 58.9936 for pixel 50/2 mean/min/max = 45.7446/32.4867/59.0024
[11:39:37.972] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:39:38.061] <TB0> INFO: Expecting 411648 events.
[11:39:47.595] <TB0> INFO: 411648 events read in total (8942ms).
[11:39:47.603] <TB0> INFO: Expecting 411648 events.
[11:39:57.059] <TB0> INFO: 411648 events read in total (9053ms).
[11:39:57.069] <TB0> INFO: Expecting 411648 events.
[11:40:06.238] <TB0> INFO: 411648 events read in total (8766ms).
[11:40:06.256] <TB0> INFO: Expecting 411648 events.
[11:40:15.544] <TB0> INFO: 411648 events read in total (8885ms).
[11:40:15.564] <TB0> INFO: Expecting 411648 events.
[11:40:24.606] <TB0> INFO: 411648 events read in total (8639ms).
[11:40:24.630] <TB0> INFO: Expecting 411648 events.
[11:40:33.831] <TB0> INFO: 411648 events read in total (8798ms).
[11:40:33.856] <TB0> INFO: Expecting 411648 events.
[11:40:43.213] <TB0> INFO: 411648 events read in total (8954ms).
[11:40:43.240] <TB0> INFO: Expecting 411648 events.
[11:40:52.720] <TB0> INFO: 411648 events read in total (9077ms).
[11:40:52.752] <TB0> INFO: Expecting 411648 events.
[11:41:02.076] <TB0> INFO: 411648 events read in total (8921ms).
[11:41:02.110] <TB0> INFO: Expecting 411648 events.
[11:41:11.423] <TB0> INFO: 411648 events read in total (8910ms).
[11:41:11.458] <TB0> INFO: Expecting 411648 events.
[11:41:20.768] <TB0> INFO: 411648 events read in total (8907ms).
[11:41:20.806] <TB0> INFO: Expecting 411648 events.
[11:41:30.118] <TB0> INFO: 411648 events read in total (8909ms).
[11:41:30.160] <TB0> INFO: Expecting 411648 events.
[11:41:39.452] <TB0> INFO: 411648 events read in total (8889ms).
[11:41:39.502] <TB0> INFO: Expecting 411648 events.
[11:41:48.872] <TB0> INFO: 411648 events read in total (8968ms).
[11:41:48.922] <TB0> INFO: Expecting 411648 events.
[11:41:58.310] <TB0> INFO: 411648 events read in total (8985ms).
[11:41:58.418] <TB0> INFO: Expecting 411648 events.
[11:42:07.750] <TB0> INFO: 411648 events read in total (8929ms).
[11:42:07.807] <TB0> INFO: Test took 149835ms.
[11:42:08.546] <TB0> INFO: ---> dac: vcal name: TrimThr2 ntrig: 8 dacrange: 0 .. 150 (-1/-1) hits flags = 528 (plus default)
[11:42:08.560] <TB0> INFO: dacScan split into 1 runs with ntrig = 8
[11:42:08.560] <TB0> INFO: run 1 of 1
[11:42:08.797] <TB0> INFO: Expecting 5025280 events.
[11:42:35.828] <TB0> INFO: 587184 events read in total (26439ms).
[11:43:01.589] <TB0> INFO: 1172736 events read in total (52200ms).
[11:43:27.216] <TB0> INFO: 1758936 events read in total (77827ms).
[11:43:53.362] <TB0> INFO: 2345448 events read in total (103973ms).
[11:44:19.431] <TB0> INFO: 2932536 events read in total (130042ms).
[11:44:45.544] <TB0> INFO: 3521448 events read in total (156155ms).
[11:45:11.407] <TB0> INFO: 4107576 events read in total (182018ms).
[11:45:37.816] <TB0> INFO: 4696568 events read in total (208427ms).
[11:45:52.626] <TB0> INFO: 5025280 events read in total (223237ms).
[11:45:52.876] <TB0> INFO: Test took 224316ms.
[11:46:17.527] <TB0> INFO: ---> TrimStepCorr4 extremal thresholds: 9.500000 .. 147.905971
[11:46:17.766] <TB0> INFO: Expecting 208000 events.
[11:46:27.264] <TB0> INFO: 208000 events read in total (8906ms).
[11:46:27.265] <TB0> INFO: Test took 9735ms.
[11:46:27.332] <TB0> INFO: ---> dac: vcal name: trimStepCorr4 ntrig: 8 dacrange: 9 .. 157 (-1/-1) hits flags = 528 (plus default)
[11:46:27.347] <TB0> INFO: dacScan split into 1 runs with ntrig = 8
[11:46:27.347] <TB0> INFO: run 1 of 1
[11:46:27.625] <TB0> INFO: Expecting 4958720 events.
[11:46:54.003] <TB0> INFO: 572952 events read in total (25787ms).
[11:47:19.208] <TB0> INFO: 1146208 events read in total (50993ms).
[11:47:44.265] <TB0> INFO: 1719144 events read in total (76049ms).
[11:48:09.656] <TB0> INFO: 2292056 events read in total (101440ms).
[11:48:35.049] <TB0> INFO: 2864784 events read in total (126833ms).
[11:49:00.392] <TB0> INFO: 3437240 events read in total (152176ms).
[11:49:25.579] <TB0> INFO: 4009176 events read in total (177363ms).
[11:49:51.208] <TB0> INFO: 4580664 events read in total (202992ms).
[11:50:08.281] <TB0> INFO: 4958720 events read in total (220065ms).
[11:50:08.501] <TB0> INFO: Test took 221155ms.
[11:50:35.315] <TB0> INFO: ---> TrimStepCorr2 extremal thresholds: 27.937520 .. 59.489981
[11:50:35.585] <TB0> INFO: Expecting 208000 events.
[11:50:45.826] <TB0> INFO: 208000 events read in total (9649ms).
[11:50:45.827] <TB0> INFO: Test took 10508ms.
[11:50:45.875] <TB0> INFO: ---> dac: vcal name: trimStepCorr2 ntrig: 8 dacrange: 17 .. 69 (-1/-1) hits flags = 528 (plus default)
[11:50:45.888] <TB0> INFO: dacScan split into 1 runs with ntrig = 8
[11:50:45.888] <TB0> INFO: run 1 of 1
[11:50:46.215] <TB0> INFO: Expecting 1763840 events.
[11:51:13.078] <TB0> INFO: 619264 events read in total (26272ms).
[11:51:40.435] <TB0> INFO: 1237720 events read in total (53629ms).
[11:52:03.285] <TB0> INFO: 1763840 events read in total (76479ms).
[11:52:03.342] <TB0> INFO: Test took 77454ms.
[11:52:20.441] <TB0> INFO: ---> TrimStepCorr1a extremal thresholds: 25.780176 .. 51.623146
[11:52:20.683] <TB0> INFO: Expecting 208000 events.
[11:52:30.480] <TB0> INFO: 208000 events read in total (9206ms).
[11:52:30.481] <TB0> INFO: Test took 10039ms.
[11:52:30.528] <TB0> INFO: ---> dac: vcal name: trimStepCorr1a ntrig: 8 dacrange: 15 .. 61 (-1/-1) hits flags = 528 (plus default)
[11:52:30.541] <TB0> INFO: dacScan split into 1 runs with ntrig = 8
[11:52:30.541] <TB0> INFO: run 1 of 1
[11:52:30.832] <TB0> INFO: Expecting 1564160 events.
[11:52:59.139] <TB0> INFO: 646368 events read in total (27715ms).
[11:53:26.574] <TB0> INFO: 1291624 events read in total (55150ms).
[11:53:38.158] <TB0> INFO: 1564160 events read in total (66734ms).
[11:53:38.192] <TB0> INFO: Test took 67651ms.
[11:53:52.265] <TB0> INFO: ---> TrimStepCorr1b extremal thresholds: 25.038608 .. 49.481351
[11:53:52.507] <TB0> INFO: Expecting 208000 events.
[11:54:02.641] <TB0> INFO: 208000 events read in total (9542ms).
[11:54:02.642] <TB0> INFO: Test took 10375ms.
[11:54:02.692] <TB0> INFO: ---> dac: vcal name: trimStepCorr1b ntrig: 8 dacrange: 15 .. 59 (-1/-1) hits flags = 528 (plus default)
[11:54:02.705] <TB0> INFO: dacScan split into 1 runs with ntrig = 8
[11:54:02.705] <TB0> INFO: run 1 of 1
[11:54:02.983] <TB0> INFO: Expecting 1497600 events.
[11:54:30.537] <TB0> INFO: 651872 events read in total (26963ms).
[11:54:57.851] <TB0> INFO: 1303616 events read in total (54277ms).
[11:55:06.175] <TB0> INFO: 1497600 events read in total (62601ms).
[11:55:06.204] <TB0> INFO: Test took 63499ms.
[11:55:20.650] <TB0> INFO: ---> TrimThrFinal extremal thresholds: 15 .. 55
[11:55:20.650] <TB0> INFO: ---> dac: vcal name: TrimThrFinal ntrig: 8 dacrange: 15 .. 55 (-1/-1) hits flags = 528 (plus default)
[11:55:20.662] <TB0> INFO: dacScan split into 1 runs with ntrig = 8
[11:55:20.662] <TB0> INFO: run 1 of 1
[11:55:20.899] <TB0> INFO: Expecting 1364480 events.
[11:55:49.820] <TB0> INFO: 667616 events read in total (28329ms).
[11:56:17.630] <TB0> INFO: 1334168 events read in total (56139ms).
[11:56:19.276] <TB0> INFO: 1364480 events read in total (57785ms).
[11:56:19.303] <TB0> INFO: Test took 58641ms.
[11:56:32.113] <TB0> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1009_FullQualification_2016-10-19_09h44m_1476863092//001_Fulltest_m20//dacParameters35_C0.dat
[11:56:32.113] <TB0> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1009_FullQualification_2016-10-19_09h44m_1476863092//001_Fulltest_m20//dacParameters35_C1.dat
[11:56:32.113] <TB0> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1009_FullQualification_2016-10-19_09h44m_1476863092//001_Fulltest_m20//dacParameters35_C2.dat
[11:56:32.113] <TB0> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1009_FullQualification_2016-10-19_09h44m_1476863092//001_Fulltest_m20//dacParameters35_C3.dat
[11:56:32.113] <TB0> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1009_FullQualification_2016-10-19_09h44m_1476863092//001_Fulltest_m20//dacParameters35_C4.dat
[11:56:32.113] <TB0> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1009_FullQualification_2016-10-19_09h44m_1476863092//001_Fulltest_m20//dacParameters35_C5.dat
[11:56:32.113] <TB0> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1009_FullQualification_2016-10-19_09h44m_1476863092//001_Fulltest_m20//dacParameters35_C6.dat
[11:56:32.113] <TB0> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1009_FullQualification_2016-10-19_09h44m_1476863092//001_Fulltest_m20//dacParameters35_C7.dat
[11:56:32.113] <TB0> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1009_FullQualification_2016-10-19_09h44m_1476863092//001_Fulltest_m20//dacParameters35_C8.dat
[11:56:32.114] <TB0> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1009_FullQualification_2016-10-19_09h44m_1476863092//001_Fulltest_m20//dacParameters35_C9.dat
[11:56:32.114] <TB0> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1009_FullQualification_2016-10-19_09h44m_1476863092//001_Fulltest_m20//dacParameters35_C10.dat
[11:56:32.114] <TB0> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1009_FullQualification_2016-10-19_09h44m_1476863092//001_Fulltest_m20//dacParameters35_C11.dat
[11:56:32.114] <TB0> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1009_FullQualification_2016-10-19_09h44m_1476863092//001_Fulltest_m20//dacParameters35_C12.dat
[11:56:32.114] <TB0> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1009_FullQualification_2016-10-19_09h44m_1476863092//001_Fulltest_m20//dacParameters35_C13.dat
[11:56:32.114] <TB0> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1009_FullQualification_2016-10-19_09h44m_1476863092//001_Fulltest_m20//dacParameters35_C14.dat
[11:56:32.114] <TB0> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1009_FullQualification_2016-10-19_09h44m_1476863092//001_Fulltest_m20//dacParameters35_C15.dat
[11:56:32.114] <TB0> INFO: write trim parameters into /home/l_tester/david/FullQualification/data/M1009_FullQualification_2016-10-19_09h44m_1476863092//001_Fulltest_m20//trimParameters35_C0.dat
[11:56:32.120] <TB0> INFO: write trim parameters into /home/l_tester/david/FullQualification/data/M1009_FullQualification_2016-10-19_09h44m_1476863092//001_Fulltest_m20//trimParameters35_C1.dat
[11:56:32.125] <TB0> INFO: write trim parameters into /home/l_tester/david/FullQualification/data/M1009_FullQualification_2016-10-19_09h44m_1476863092//001_Fulltest_m20//trimParameters35_C2.dat
[11:56:32.129] <TB0> INFO: write trim parameters into /home/l_tester/david/FullQualification/data/M1009_FullQualification_2016-10-19_09h44m_1476863092//001_Fulltest_m20//trimParameters35_C3.dat
[11:56:32.134] <TB0> INFO: write trim parameters into /home/l_tester/david/FullQualification/data/M1009_FullQualification_2016-10-19_09h44m_1476863092//001_Fulltest_m20//trimParameters35_C4.dat
[11:56:32.139] <TB0> INFO: write trim parameters into /home/l_tester/david/FullQualification/data/M1009_FullQualification_2016-10-19_09h44m_1476863092//001_Fulltest_m20//trimParameters35_C5.dat
[11:56:32.144] <TB0> INFO: write trim parameters into /home/l_tester/david/FullQualification/data/M1009_FullQualification_2016-10-19_09h44m_1476863092//001_Fulltest_m20//trimParameters35_C6.dat
[11:56:32.148] <TB0> INFO: write trim parameters into /home/l_tester/david/FullQualification/data/M1009_FullQualification_2016-10-19_09h44m_1476863092//001_Fulltest_m20//trimParameters35_C7.dat
[11:56:32.153] <TB0> INFO: write trim parameters into /home/l_tester/david/FullQualification/data/M1009_FullQualification_2016-10-19_09h44m_1476863092//001_Fulltest_m20//trimParameters35_C8.dat
[11:56:32.158] <TB0> INFO: write trim parameters into /home/l_tester/david/FullQualification/data/M1009_FullQualification_2016-10-19_09h44m_1476863092//001_Fulltest_m20//trimParameters35_C9.dat
[11:56:32.162] <TB0> INFO: write trim parameters into /home/l_tester/david/FullQualification/data/M1009_FullQualification_2016-10-19_09h44m_1476863092//001_Fulltest_m20//trimParameters35_C10.dat
[11:56:32.167] <TB0> INFO: write trim parameters into /home/l_tester/david/FullQualification/data/M1009_FullQualification_2016-10-19_09h44m_1476863092//001_Fulltest_m20//trimParameters35_C11.dat
[11:56:32.172] <TB0> INFO: write trim parameters into /home/l_tester/david/FullQualification/data/M1009_FullQualification_2016-10-19_09h44m_1476863092//001_Fulltest_m20//trimParameters35_C12.dat
[11:56:32.177] <TB0> INFO: write trim parameters into /home/l_tester/david/FullQualification/data/M1009_FullQualification_2016-10-19_09h44m_1476863092//001_Fulltest_m20//trimParameters35_C13.dat
[11:56:32.181] <TB0> INFO: write trim parameters into /home/l_tester/david/FullQualification/data/M1009_FullQualification_2016-10-19_09h44m_1476863092//001_Fulltest_m20//trimParameters35_C14.dat
[11:56:32.186] <TB0> INFO: write trim parameters into /home/l_tester/david/FullQualification/data/M1009_FullQualification_2016-10-19_09h44m_1476863092//001_Fulltest_m20//trimParameters35_C15.dat
[11:56:32.191] <TB0> INFO: PixTestTrim::trimTest() done
[11:56:32.191] <TB0> INFO: vtrim: 127 110 132 117 105 149 129 118 126 147 110 124 112 134 126 91
[11:56:32.191] <TB0> INFO: vthrcomp: 116 110 122 116 118 128 114 107 127 110 124 123 122 120 124 114
[11:56:32.191] <TB0> INFO: vcal mean: 34.96 35.08 35.01 34.99 34.99 35.09 34.98 35.02 34.94 34.94 34.94 34.93 34.93 34.94 34.94 35.02
[11:56:32.191] <TB0> INFO: vcal RMS: 1.07 1.13 1.13 1.02 0.96 1.23 1.27 0.96 1.09 1.12 1.05 1.31 1.02 1.00 1.04 1.08
[11:56:32.191] <TB0> INFO: bits mean: 9.65 8.40 9.53 9.73 8.91 9.76 9.49 8.86 10.06 9.14 8.93 9.32 9.12 9.61 9.67 7.74
[11:56:32.191] <TB0> INFO: bits RMS: 2.69 2.79 2.65 2.60 2.97 2.58 2.65 2.45 2.62 2.52 3.06 2.95 2.90 2.77 2.69 3.43
[11:56:32.198] <TB0> INFO: ----------------------------------------------------------------------
[11:56:32.198] <TB0> INFO: PixTestTrim::trimBitTest() ntrig = 5, vtrims = 254 126 63 32
[11:56:32.198] <TB0> INFO: ----------------------------------------------------------------------
[11:56:32.201] <TB0> INFO: ---> dac: Vcal name: TrimBitsThr0 ntrig: 5 dacrange: 0 .. 199 (-1/-1) hits flags = 528 (plus default)
[11:56:32.214] <TB0> INFO: dacScan split into 1 runs with ntrig = 5
[11:56:32.214] <TB0> INFO: run 1 of 1
[11:56:32.485] <TB0> INFO: Expecting 4160000 events.
[11:57:04.861] <TB0> INFO: 755835 events read in total (31784ms).
[11:57:37.060] <TB0> INFO: 1505710 events read in total (63983ms).
[11:58:08.994] <TB0> INFO: 2251785 events read in total (95918ms).
[11:58:40.003] <TB0> INFO: 2994380 events read in total (127926ms).
[11:59:12.516] <TB0> INFO: 3734970 events read in total (159439ms).
[11:59:30.819] <TB0> INFO: 4160000 events read in total (177742ms).
[11:59:31.013] <TB0> INFO: Test took 178798ms.
[11:59:53.780] <TB0> INFO: ---> dac: Vcal name: TrimThr_trim14 ntrig: 5 dacrange: 0 .. 202 (-1/-1) hits flags = 528 (plus default)
[11:59:53.797] <TB0> INFO: dacScan split into 1 runs with ntrig = 5
[11:59:53.797] <TB0> INFO: run 1 of 1
[11:59:54.044] <TB0> INFO: Expecting 4222400 events.
[12:00:26.513] <TB0> INFO: 726890 events read in total (31878ms).
[12:00:57.633] <TB0> INFO: 1448685 events read in total (62998ms).
[12:01:29.301] <TB0> INFO: 2167235 events read in total (94666ms).
[12:02:00.586] <TB0> INFO: 2882835 events read in total (125951ms).
[12:02:31.752] <TB0> INFO: 3596540 events read in total (157117ms).
[12:02:59.064] <TB0> INFO: 4222400 events read in total (184429ms).
[12:02:59.236] <TB0> INFO: Test took 185440ms.
[12:03:24.590] <TB0> INFO: ---> dac: Vcal name: TrimThr_trim13 ntrig: 5 dacrange: 0 .. 202 (-1/-1) hits flags = 528 (plus default)
[12:03:24.604] <TB0> INFO: dacScan split into 1 runs with ntrig = 5
[12:03:24.604] <TB0> INFO: run 1 of 1
[12:03:24.840] <TB0> INFO: Expecting 4222400 events.
[12:03:57.496] <TB0> INFO: 727325 events read in total (32064ms).
[12:04:28.774] <TB0> INFO: 1449355 events read in total (63342ms).
[12:04:59.991] <TB0> INFO: 2168310 events read in total (94559ms).
[12:05:31.099] <TB0> INFO: 2884295 events read in total (125667ms).
[12:06:02.640] <TB0> INFO: 3598195 events read in total (157208ms).
[12:06:29.873] <TB0> INFO: 4222400 events read in total (184441ms).
[12:06:30.045] <TB0> INFO: Test took 185441ms.
[12:06:56.868] <TB0> INFO: ---> dac: Vcal name: TrimThr_trim11 ntrig: 5 dacrange: 0 .. 201 (-1/-1) hits flags = 528 (plus default)
[12:06:56.882] <TB0> INFO: dacScan split into 1 runs with ntrig = 5
[12:06:56.882] <TB0> INFO: run 1 of 1
[12:06:57.124] <TB0> INFO: Expecting 4201600 events.
[12:07:29.495] <TB0> INFO: 728815 events read in total (31779ms).
[12:08:00.545] <TB0> INFO: 1452170 events read in total (62829ms).
[12:08:31.908] <TB0> INFO: 2172340 events read in total (94192ms).
[12:09:03.120] <TB0> INFO: 2889285 events read in total (125404ms).
[12:09:34.796] <TB0> INFO: 3604270 events read in total (157080ms).
[12:10:01.139] <TB0> INFO: 4201600 events read in total (183423ms).
[12:10:01.238] <TB0> INFO: Test took 184356ms.
[12:10:26.194] <TB0> INFO: ---> dac: Vcal name: TrimThr_trim7 ntrig: 5 dacrange: 0 .. 202 (-1/-1) hits flags = 528 (plus default)
[12:10:26.209] <TB0> INFO: dacScan split into 1 runs with ntrig = 5
[12:10:26.209] <TB0> INFO: run 1 of 1
[12:10:26.446] <TB0> INFO: Expecting 4222400 events.
[12:10:58.455] <TB0> INFO: 727560 events read in total (31418ms).
[12:11:29.743] <TB0> INFO: 1449800 events read in total (62706ms).
[12:12:01.218] <TB0> INFO: 2168875 events read in total (94181ms).
[12:12:32.422] <TB0> INFO: 2884750 events read in total (125385ms).
[12:13:03.909] <TB0> INFO: 3598955 events read in total (156872ms).
[12:13:31.138] <TB0> INFO: 4222400 events read in total (184101ms).
[12:13:31.238] <TB0> INFO: Test took 185029ms.
[12:13:58.110] <TB0> INFO: PixTestTrim::trimBitTest() done
[12:13:58.111] <TB0> INFO: PixTestTrim::doTest() done, duration: 2517 seconds
[12:13:58.111] <TB0> INFO: Decoding statistics:
[12:13:58.111] <TB0> INFO: General information:
[12:13:58.111] <TB0> INFO: 16bit words read: 0
[12:13:58.111] <TB0> INFO: valid events total: 0
[12:13:58.111] <TB0> INFO: empty events: 0
[12:13:58.111] <TB0> INFO: valid events with pixels: 0
[12:13:58.111] <TB0> INFO: valid pixel hits: 0
[12:13:58.112] <TB0> INFO: Event errors: 0
[12:13:58.112] <TB0> INFO: start marker: 0
[12:13:58.112] <TB0> INFO: stop marker: 0
[12:13:58.112] <TB0> INFO: overflow: 0
[12:13:58.112] <TB0> INFO: invalid 5bit words: 0
[12:13:58.112] <TB0> INFO: invalid XOR eye diagram: 0
[12:13:58.112] <TB0> INFO: frame (failed synchr.): 0
[12:13:58.112] <TB0> INFO: idle data (no TBM trl): 0
[12:13:58.112] <TB0> INFO: no data (only TBM hdr): 0
[12:13:58.112] <TB0> INFO: TBM errors: 0
[12:13:58.112] <TB0> INFO: flawed TBM headers: 0
[12:13:58.112] <TB0> INFO: flawed TBM trailers: 0
[12:13:58.112] <TB0> INFO: event ID mismatches: 0
[12:13:58.112] <TB0> INFO: ROC errors: 0
[12:13:58.112] <TB0> INFO: missing ROC header(s): 0
[12:13:58.112] <TB0> INFO: misplaced readback start: 0
[12:13:58.112] <TB0> INFO: Pixel decoding errors: 0
[12:13:58.112] <TB0> INFO: pixel data incomplete: 0
[12:13:58.112] <TB0> INFO: pixel address: 0
[12:13:58.112] <TB0> INFO: pulse height fill bit: 0
[12:13:58.112] <TB0> INFO: buffer corruption: 0
[12:13:58.767] <TB0> INFO: ######################################################################
[12:13:58.767] <TB0> INFO: PixTestPhOptimization::doTest() Ntrig = 10
[12:13:58.767] <TB0> INFO: ######################################################################
[12:13:59.005] <TB0> INFO: Expecting 41600 events.
[12:14:02.464] <TB0> INFO: 41600 events read in total (2867ms).
[12:14:02.465] <TB0> INFO: Test took 3697ms.
[12:14:02.906] <TB0> INFO: Expecting 41600 events.
[12:14:06.455] <TB0> INFO: 41600 events read in total (2957ms).
[12:14:06.455] <TB0> INFO: Test took 3787ms.
[12:14:06.810] <TB0> INFO: Expecting 41600 events.
[12:14:10.325] <TB0> INFO: 41600 events read in total (2923ms).
[12:14:10.326] <TB0> INFO: Test took 3842ms.
[12:14:10.614] <TB0> INFO: Expecting 41600 events.
[12:14:14.159] <TB0> INFO: 41600 events read in total (2953ms).
[12:14:14.160] <TB0> INFO: Test took 3810ms.
[12:14:14.451] <TB0> INFO: Expecting 41600 events.
[12:14:17.931] <TB0> INFO: 41600 events read in total (2888ms).
[12:14:17.932] <TB0> INFO: Test took 3746ms.
[12:14:18.221] <TB0> INFO: Expecting 41600 events.
[12:14:21.802] <TB0> INFO: 41600 events read in total (2990ms).
[12:14:21.803] <TB0> INFO: Test took 3847ms.
[12:14:22.092] <TB0> INFO: Expecting 41600 events.
[12:14:25.567] <TB0> INFO: 41600 events read in total (2883ms).
[12:14:25.568] <TB0> INFO: Test took 3741ms.
[12:14:25.861] <TB0> INFO: Expecting 41600 events.
[12:14:29.417] <TB0> INFO: 41600 events read in total (2964ms).
[12:14:29.418] <TB0> INFO: Test took 3826ms.
[12:14:29.708] <TB0> INFO: Expecting 41600 events.
[12:14:33.256] <TB0> INFO: 41600 events read in total (2957ms).
[12:14:33.257] <TB0> INFO: Test took 3815ms.
[12:14:33.546] <TB0> INFO: Expecting 41600 events.
[12:14:37.163] <TB0> INFO: 41600 events read in total (3026ms).
[12:14:37.164] <TB0> INFO: Test took 3883ms.
[12:14:37.455] <TB0> INFO: Expecting 41600 events.
[12:14:41.061] <TB0> INFO: 41600 events read in total (3014ms).
[12:14:41.062] <TB0> INFO: Test took 3872ms.
[12:14:41.351] <TB0> INFO: Expecting 41600 events.
[12:14:44.911] <TB0> INFO: 41600 events read in total (2970ms).
[12:14:44.912] <TB0> INFO: Test took 3826ms.
[12:14:45.201] <TB0> INFO: Expecting 41600 events.
[12:14:48.814] <TB0> INFO: 41600 events read in total (3021ms).
[12:14:48.815] <TB0> INFO: Test took 3879ms.
[12:14:49.104] <TB0> INFO: Expecting 41600 events.
[12:14:52.622] <TB0> INFO: 41600 events read in total (2927ms).
[12:14:52.623] <TB0> INFO: Test took 3784ms.
[12:14:52.913] <TB0> INFO: Expecting 41600 events.
[12:14:56.465] <TB0> INFO: 41600 events read in total (2960ms).
[12:14:56.466] <TB0> INFO: Test took 3819ms.
[12:14:56.756] <TB0> INFO: Expecting 41600 events.
[12:15:00.282] <TB0> INFO: 41600 events read in total (2934ms).
[12:15:00.282] <TB0> INFO: Test took 3792ms.
[12:15:00.571] <TB0> INFO: Expecting 41600 events.
[12:15:04.150] <TB0> INFO: 41600 events read in total (2987ms).
[12:15:04.150] <TB0> INFO: Test took 3844ms.
[12:15:04.440] <TB0> INFO: Expecting 41600 events.
[12:15:08.011] <TB0> INFO: 41600 events read in total (2979ms).
[12:15:08.011] <TB0> INFO: Test took 3836ms.
[12:15:08.300] <TB0> INFO: Expecting 41600 events.
[12:15:11.858] <TB0> INFO: 41600 events read in total (2967ms).
[12:15:11.859] <TB0> INFO: Test took 3824ms.
[12:15:12.151] <TB0> INFO: Expecting 41600 events.
[12:15:15.675] <TB0> INFO: 41600 events read in total (2933ms).
[12:15:15.676] <TB0> INFO: Test took 3790ms.
[12:15:15.966] <TB0> INFO: Expecting 41600 events.
[12:15:19.543] <TB0> INFO: 41600 events read in total (2985ms).
[12:15:19.543] <TB0> INFO: Test took 3842ms.
[12:15:19.832] <TB0> INFO: Expecting 41600 events.
[12:15:23.350] <TB0> INFO: 41600 events read in total (2926ms).
[12:15:23.351] <TB0> INFO: Test took 3783ms.
[12:15:23.668] <TB0> INFO: Expecting 41600 events.
[12:15:27.164] <TB0> INFO: 41600 events read in total (2904ms).
[12:15:27.164] <TB0> INFO: Test took 3789ms.
[12:15:27.453] <TB0> INFO: Expecting 41600 events.
[12:15:30.987] <TB0> INFO: 41600 events read in total (2942ms).
[12:15:30.988] <TB0> INFO: Test took 3800ms.
[12:15:31.293] <TB0> INFO: Expecting 41600 events.
[12:15:34.895] <TB0> INFO: 41600 events read in total (3010ms).
[12:15:34.897] <TB0> INFO: Test took 3885ms.
[12:15:35.189] <TB0> INFO: Expecting 41600 events.
[12:15:38.869] <TB0> INFO: 41600 events read in total (3088ms).
[12:15:38.870] <TB0> INFO: Test took 3945ms.
[12:15:39.160] <TB0> INFO: Expecting 41600 events.
[12:15:42.712] <TB0> INFO: 41600 events read in total (2960ms).
[12:15:42.713] <TB0> INFO: Test took 3819ms.
[12:15:42.002] <TB0> INFO: Expecting 41600 events.
[12:15:46.541] <TB0> INFO: 41600 events read in total (2947ms).
[12:15:46.542] <TB0> INFO: Test took 3805ms.
[12:15:46.839] <TB0> INFO: Expecting 41600 events.
[12:15:50.360] <TB0> INFO: 41600 events read in total (2929ms).
[12:15:50.361] <TB0> INFO: Test took 3794ms.
[12:15:50.651] <TB0> INFO: Expecting 41600 events.
[12:15:54.190] <TB0> INFO: 41600 events read in total (2947ms).
[12:15:54.191] <TB0> INFO: Test took 3805ms.
[12:15:54.481] <TB0> INFO: Expecting 2560 events.
[12:15:55.372] <TB0> INFO: 2560 events read in total (299ms).
[12:15:55.372] <TB0> INFO: Test took 1168ms.
[12:15:55.680] <TB0> INFO: Expecting 2560 events.
[12:15:56.573] <TB0> INFO: 2560 events read in total (302ms).
[12:15:56.573] <TB0> INFO: Test took 1200ms.
[12:15:56.881] <TB0> INFO: Expecting 2560 events.
[12:15:57.763] <TB0> INFO: 2560 events read in total (291ms).
[12:15:57.763] <TB0> INFO: Test took 1189ms.
[12:15:58.072] <TB0> INFO: Expecting 2560 events.
[12:15:58.956] <TB0> INFO: 2560 events read in total (293ms).
[12:15:58.956] <TB0> INFO: Test took 1192ms.
[12:15:59.263] <TB0> INFO: Expecting 2560 events.
[12:16:00.150] <TB0> INFO: 2560 events read in total (295ms).
[12:16:00.150] <TB0> INFO: Test took 1193ms.
[12:16:00.456] <TB0> INFO: Expecting 2560 events.
[12:16:01.341] <TB0> INFO: 2560 events read in total (293ms).
[12:16:01.342] <TB0> INFO: Test took 1191ms.
[12:16:01.649] <TB0> INFO: Expecting 2560 events.
[12:16:02.534] <TB0> INFO: 2560 events read in total (294ms).
[12:16:02.534] <TB0> INFO: Test took 1191ms.
[12:16:02.843] <TB0> INFO: Expecting 2560 events.
[12:16:03.728] <TB0> INFO: 2560 events read in total (294ms).
[12:16:03.729] <TB0> INFO: Test took 1194ms.
[12:16:04.036] <TB0> INFO: Expecting 2560 events.
[12:16:04.927] <TB0> INFO: 2560 events read in total (292ms).
[12:16:04.928] <TB0> INFO: Test took 1198ms.
[12:16:05.236] <TB0> INFO: Expecting 2560 events.
[12:16:06.125] <TB0> INFO: 2560 events read in total (297ms).
[12:16:06.125] <TB0> INFO: Test took 1196ms.
[12:16:06.433] <TB0> INFO: Expecting 2560 events.
[12:16:07.326] <TB0> INFO: 2560 events read in total (301ms).
[12:16:07.326] <TB0> INFO: Test took 1200ms.
[12:16:07.633] <TB0> INFO: Expecting 2560 events.
[12:16:08.520] <TB0> INFO: 2560 events read in total (295ms).
[12:16:08.520] <TB0> INFO: Test took 1193ms.
[12:16:08.827] <TB0> INFO: Expecting 2560 events.
[12:16:09.713] <TB0> INFO: 2560 events read in total (294ms).
[12:16:09.713] <TB0> INFO: Test took 1192ms.
[12:16:10.022] <TB0> INFO: Expecting 2560 events.
[12:16:10.912] <TB0> INFO: 2560 events read in total (298ms).
[12:16:10.912] <TB0> INFO: Test took 1199ms.
[12:16:11.219] <TB0> INFO: Expecting 2560 events.
[12:16:12.112] <TB0> INFO: 2560 events read in total (301ms).
[12:16:12.112] <TB0> INFO: Test took 1199ms.
[12:16:12.419] <TB0> INFO: Expecting 2560 events.
[12:16:13.305] <TB0> INFO: 2560 events read in total (294ms).
[12:16:13.305] <TB0> INFO: Test took 1192ms.
[12:16:13.308] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[12:16:13.614] <TB0> INFO: Expecting 655360 events.
[12:16:28.700] <TB0> INFO: 655360 events read in total (14494ms).
[12:16:28.716] <TB0> INFO: Expecting 655360 events.
[12:16:43.449] <TB0> INFO: 655360 events read in total (14330ms).
[12:16:43.468] <TB0> INFO: Expecting 655360 events.
[12:16:58.175] <TB0> INFO: 655360 events read in total (14304ms).
[12:16:58.197] <TB0> INFO: Expecting 655360 events.
[12:17:12.971] <TB0> INFO: 655360 events read in total (14371ms).
[12:17:12.999] <TB0> INFO: Expecting 655360 events.
[12:17:27.559] <TB0> INFO: 655360 events read in total (14158ms).
[12:17:27.591] <TB0> INFO: Expecting 655360 events.
[12:17:42.340] <TB0> INFO: 655360 events read in total (14346ms).
[12:17:42.382] <TB0> INFO: Expecting 655360 events.
[12:17:57.098] <TB0> INFO: 655360 events read in total (14313ms).
[12:17:57.147] <TB0> INFO: Expecting 655360 events.
[12:18:11.813] <TB0> INFO: 655360 events read in total (14264ms).
[12:18:11.857] <TB0> INFO: Expecting 655360 events.
[12:18:26.397] <TB0> INFO: 655360 events read in total (14137ms).
[12:18:26.595] <TB0> INFO: Expecting 655360 events.
[12:18:41.196] <TB0> INFO: 655360 events read in total (14198ms).
[12:18:41.255] <TB0> INFO: Expecting 655360 events.
[12:18:56.019] <TB0> INFO: 655360 events read in total (14361ms).
[12:18:56.219] <TB0> INFO: Expecting 655360 events.
[12:19:11.104] <TB0> INFO: 655360 events read in total (14482ms).
[12:19:11.184] <TB0> INFO: Expecting 655360 events.
[12:19:25.867] <TB0> INFO: 655360 events read in total (14279ms).
[12:19:25.954] <TB0> INFO: Expecting 655360 events.
[12:19:40.587] <TB0> INFO: 655360 events read in total (14229ms).
[12:19:40.700] <TB0> INFO: Expecting 655360 events.
[12:19:55.364] <TB0> INFO: 655360 events read in total (14261ms).
[12:19:55.466] <TB0> INFO: Expecting 655360 events.
[12:20:10.150] <TB0> INFO: 655360 events read in total (14281ms).
[12:20:10.297] <TB0> INFO: Test took 236989ms.
[12:20:10.400] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[12:20:10.653] <TB0> INFO: Expecting 655360 events.
[12:20:25.223] <TB0> INFO: 655360 events read in total (13978ms).
[12:20:25.238] <TB0> INFO: Expecting 655360 events.
[12:20:39.747] <TB0> INFO: 655360 events read in total (14106ms).
[12:20:39.763] <TB0> INFO: Expecting 655360 events.
[12:20:54.333] <TB0> INFO: 655360 events read in total (14167ms).
[12:20:54.353] <TB0> INFO: Expecting 655360 events.
[12:21:08.824] <TB0> INFO: 655360 events read in total (14068ms).
[12:21:08.849] <TB0> INFO: Expecting 655360 events.
[12:21:23.331] <TB0> INFO: 655360 events read in total (14079ms).
[12:21:23.369] <TB0> INFO: Expecting 655360 events.
[12:21:37.873] <TB0> INFO: 655360 events read in total (14101ms).
[12:21:37.910] <TB0> INFO: Expecting 655360 events.
[12:21:52.508] <TB0> INFO: 655360 events read in total (14195ms).
[12:21:52.691] <TB0> INFO: Expecting 655360 events.
[12:22:07.413] <TB0> INFO: 655360 events read in total (14318ms).
[12:22:07.459] <TB0> INFO: Expecting 655360 events.
[12:22:21.996] <TB0> INFO: 655360 events read in total (14133ms).
[12:22:22.043] <TB0> INFO: Expecting 655360 events.
[12:22:36.473] <TB0> INFO: 655360 events read in total (14027ms).
[12:22:36.565] <TB0> INFO: Expecting 655360 events.
[12:22:51.348] <TB0> INFO: 655360 events read in total (14380ms).
[12:22:51.419] <TB0> INFO: Expecting 655360 events.
[12:23:06.080] <TB0> INFO: 655360 events read in total (14258ms).
[12:23:06.157] <TB0> INFO: Expecting 655360 events.
[12:23:20.394] <TB0> INFO: 655360 events read in total (13834ms).
[12:23:20.476] <TB0> INFO: Expecting 655360 events.
[12:23:35.040] <TB0> INFO: 655360 events read in total (14161ms).
[12:23:35.129] <TB0> INFO: Expecting 655360 events.
[12:23:50.070] <TB0> INFO: 655360 events read in total (14538ms).
[12:23:50.165] <TB0> INFO: Expecting 655360 events.
[12:24:05.042] <TB0> INFO: 655360 events read in total (14474ms).
[12:24:05.278] <TB0> INFO: Test took 234878ms.
[12:24:05.447] <TB0> INFO: safety margin for low PH: adding 0, margin is now 20
[12:24:05.453] <TB0> INFO: safety margin for low PH: adding 0, margin is now 20
[12:24:05.459] <TB0> INFO: safety margin for low PH: adding 0, margin is now 20
[12:24:05.465] <TB0> INFO: safety margin for low PH: adding 0, margin is now 20
[12:24:05.471] <TB0> INFO: safety margin for low PH: adding 1, margin is now 21
[12:24:05.477] <TB0> INFO: safety margin for low PH: adding 2, margin is now 22
[12:24:05.483] <TB0> INFO: safety margin for low PH: adding 3, margin is now 23
[12:24:05.492] <TB0> INFO: safety margin for low PH: adding 4, margin is now 24
[12:24:05.500] <TB0> INFO: safety margin for low PH: adding 5, margin is now 25
[12:24:05.508] <TB0> INFO: safety margin for low PH: adding 6, margin is now 26
[12:24:05.516] <TB0> INFO: safety margin for low PH: adding 7, margin is now 27
[12:24:05.524] <TB0> INFO: safety margin for low PH: adding 8, margin is now 28
[12:24:05.530] <TB0> INFO: safety margin for low PH: adding 9, margin is now 29
[12:24:05.535] <TB0> INFO: safety margin for low PH: adding 0, margin is now 20
[12:24:05.541] <TB0> INFO: safety margin for low PH: adding 0, margin is now 20
[12:24:05.547] <TB0> INFO: safety margin for low PH: adding 0, margin is now 20
[12:24:05.552] <TB0> INFO: safety margin for low PH: adding 0, margin is now 20
[12:24:05.558] <TB0> INFO: safety margin for low PH: adding 0, margin is now 20
[12:24:05.564] <TB0> INFO: safety margin for low PH: adding 1, margin is now 21
[12:24:05.569] <TB0> INFO: safety margin for low PH: adding 2, margin is now 22
[12:24:05.575] <TB0> INFO: safety margin for low PH: adding 0, margin is now 20
[12:24:05.581] <TB0> INFO: safety margin for low PH: adding 0, margin is now 20
[12:24:05.587] <TB0> INFO: safety margin for low PH: adding 1, margin is now 21
[12:24:05.593] <TB0> INFO: safety margin for low PH: adding 2, margin is now 22
[12:24:05.598] <TB0> INFO: safety margin for low PH: adding 3, margin is now 23
[12:24:05.604] <TB0> INFO: safety margin for low PH: adding 0, margin is now 20
[12:24:05.610] <TB0> INFO: safety margin for low PH: adding 0, margin is now 20
[12:24:05.616] <TB0> INFO: safety margin for low PH: adding 0, margin is now 20
[12:24:05.622] <TB0> INFO: safety margin for low PH: adding 0, margin is now 20
[12:24:05.628] <TB0> INFO: safety margin for low PH: adding 0, margin is now 20
[12:24:05.670] <TB0> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1009_FullQualification_2016-10-19_09h44m_1476863092//001_Fulltest_m20//dacParameters35_C0.dat
[12:24:05.670] <TB0> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1009_FullQualification_2016-10-19_09h44m_1476863092//001_Fulltest_m20//dacParameters35_C1.dat
[12:24:05.670] <TB0> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1009_FullQualification_2016-10-19_09h44m_1476863092//001_Fulltest_m20//dacParameters35_C2.dat
[12:24:05.670] <TB0> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1009_FullQualification_2016-10-19_09h44m_1476863092//001_Fulltest_m20//dacParameters35_C3.dat
[12:24:05.671] <TB0> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1009_FullQualification_2016-10-19_09h44m_1476863092//001_Fulltest_m20//dacParameters35_C4.dat
[12:24:05.671] <TB0> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1009_FullQualification_2016-10-19_09h44m_1476863092//001_Fulltest_m20//dacParameters35_C5.dat
[12:24:05.671] <TB0> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1009_FullQualification_2016-10-19_09h44m_1476863092//001_Fulltest_m20//dacParameters35_C6.dat
[12:24:05.671] <TB0> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1009_FullQualification_2016-10-19_09h44m_1476863092//001_Fulltest_m20//dacParameters35_C7.dat
[12:24:05.671] <TB0> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1009_FullQualification_2016-10-19_09h44m_1476863092//001_Fulltest_m20//dacParameters35_C8.dat
[12:24:05.672] <TB0> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1009_FullQualification_2016-10-19_09h44m_1476863092//001_Fulltest_m20//dacParameters35_C9.dat
[12:24:05.672] <TB0> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1009_FullQualification_2016-10-19_09h44m_1476863092//001_Fulltest_m20//dacParameters35_C10.dat
[12:24:05.672] <TB0> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1009_FullQualification_2016-10-19_09h44m_1476863092//001_Fulltest_m20//dacParameters35_C11.dat
[12:24:05.672] <TB0> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1009_FullQualification_2016-10-19_09h44m_1476863092//001_Fulltest_m20//dacParameters35_C12.dat
[12:24:05.672] <TB0> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1009_FullQualification_2016-10-19_09h44m_1476863092//001_Fulltest_m20//dacParameters35_C13.dat
[12:24:05.672] <TB0> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1009_FullQualification_2016-10-19_09h44m_1476863092//001_Fulltest_m20//dacParameters35_C14.dat
[12:24:05.672] <TB0> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1009_FullQualification_2016-10-19_09h44m_1476863092//001_Fulltest_m20//dacParameters35_C15.dat
[12:24:05.920] <TB0> INFO: Expecting 41600 events.
[12:24:09.115] <TB0> INFO: 41600 events read in total (2603ms).
[12:24:09.115] <TB0> INFO: Test took 3439ms.
[12:24:09.572] <TB0> INFO: Expecting 41600 events.
[12:24:12.639] <TB0> INFO: 41600 events read in total (2475ms).
[12:24:12.640] <TB0> INFO: Test took 3310ms.
[12:24:13.132] <TB0> INFO: Expecting 41600 events.
[12:24:16.325] <TB0> INFO: 41600 events read in total (2601ms).
[12:24:16.326] <TB0> INFO: Test took 3473ms.
[12:24:16.548] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[12:24:16.638] <TB0> INFO: Expecting 2560 events.
[12:24:17.525] <TB0> INFO: 2560 events read in total (295ms).
[12:24:17.526] <TB0> INFO: Test took 978ms.
[12:24:17.530] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[12:24:17.834] <TB0> INFO: Expecting 2560 events.
[12:24:18.725] <TB0> INFO: 2560 events read in total (299ms).
[12:24:18.725] <TB0> INFO: Test took 1195ms.
[12:24:18.727] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[12:24:19.034] <TB0> INFO: Expecting 2560 events.
[12:24:19.928] <TB0> INFO: 2560 events read in total (303ms).
[12:24:19.929] <TB0> INFO: Test took 1202ms.
[12:24:19.932] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[12:24:20.236] <TB0> INFO: Expecting 2560 events.
[12:24:21.130] <TB0> INFO: 2560 events read in total (302ms).
[12:24:21.130] <TB0> INFO: Test took 1198ms.
[12:24:21.133] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[12:24:21.438] <TB0> INFO: Expecting 2560 events.
[12:24:22.338] <TB0> INFO: 2560 events read in total (307ms).
[12:24:22.338] <TB0> INFO: Test took 1205ms.
[12:24:22.341] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[12:24:22.646] <TB0> INFO: Expecting 2560 events.
[12:24:23.531] <TB0> INFO: 2560 events read in total (293ms).
[12:24:23.532] <TB0> INFO: Test took 1191ms.
[12:24:23.534] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[12:24:23.840] <TB0> INFO: Expecting 2560 events.
[12:24:24.737] <TB0> INFO: 2560 events read in total (305ms).
[12:24:24.738] <TB0> INFO: Test took 1204ms.
[12:24:24.741] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[12:24:25.046] <TB0> INFO: Expecting 2560 events.
[12:24:25.939] <TB0> INFO: 2560 events read in total (302ms).
[12:24:25.939] <TB0> INFO: Test took 1198ms.
[12:24:25.941] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[12:24:26.248] <TB0> INFO: Expecting 2560 events.
[12:24:27.138] <TB0> INFO: 2560 events read in total (298ms).
[12:24:27.138] <TB0> INFO: Test took 1197ms.
[12:24:27.141] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[12:24:27.448] <TB0> INFO: Expecting 2560 events.
[12:24:28.335] <TB0> INFO: 2560 events read in total (295ms).
[12:24:28.336] <TB0> INFO: Test took 1195ms.
[12:24:28.338] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[12:24:28.645] <TB0> INFO: Expecting 2560 events.
[12:24:29.534] <TB0> INFO: 2560 events read in total (297ms).
[12:24:29.534] <TB0> INFO: Test took 1196ms.
[12:24:29.536] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[12:24:29.844] <TB0> INFO: Expecting 2560 events.
[12:24:30.734] <TB0> INFO: 2560 events read in total (299ms).
[12:24:30.735] <TB0> INFO: Test took 1199ms.
[12:24:30.738] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[12:24:31.042] <TB0> INFO: Expecting 2560 events.
[12:24:31.925] <TB0> INFO: 2560 events read in total (291ms).
[12:24:31.926] <TB0> INFO: Test took 1189ms.
[12:24:31.928] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[12:24:32.235] <TB0> INFO: Expecting 2560 events.
[12:24:33.125] <TB0> INFO: 2560 events read in total (298ms).
[12:24:33.125] <TB0> INFO: Test took 1197ms.
[12:24:33.128] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[12:24:33.433] <TB0> INFO: Expecting 2560 events.
[12:24:34.324] <TB0> INFO: 2560 events read in total (299ms).
[12:24:34.325] <TB0> INFO: Test took 1197ms.
[12:24:34.326] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[12:24:34.635] <TB0> INFO: Expecting 2560 events.
[12:24:35.517] <TB0> INFO: 2560 events read in total (291ms).
[12:24:35.518] <TB0> INFO: Test took 1192ms.
[12:24:35.520] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[12:24:35.826] <TB0> INFO: Expecting 2560 events.
[12:24:36.717] <TB0> INFO: 2560 events read in total (299ms).
[12:24:36.717] <TB0> INFO: Test took 1197ms.
[12:24:36.720] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[12:24:37.025] <TB0> INFO: Expecting 2560 events.
[12:24:37.912] <TB0> INFO: 2560 events read in total (295ms).
[12:24:37.912] <TB0> INFO: Test took 1193ms.
[12:24:37.914] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[12:24:38.221] <TB0> INFO: Expecting 2560 events.
[12:24:39.112] <TB0> INFO: 2560 events read in total (299ms).
[12:24:39.112] <TB0> INFO: Test took 1198ms.
[12:24:39.116] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[12:24:39.420] <TB0> INFO: Expecting 2560 events.
[12:24:40.311] <TB0> INFO: 2560 events read in total (299ms).
[12:24:40.311] <TB0> INFO: Test took 1195ms.
[12:24:40.315] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[12:24:40.622] <TB0> INFO: Expecting 2560 events.
[12:24:41.511] <TB0> INFO: 2560 events read in total (297ms).
[12:24:41.512] <TB0> INFO: Test took 1198ms.
[12:24:41.514] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[12:24:41.821] <TB0> INFO: Expecting 2560 events.
[12:24:42.701] <TB0> INFO: 2560 events read in total (288ms).
[12:24:42.702] <TB0> INFO: Test took 1188ms.
[12:24:42.704] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[12:24:43.010] <TB0> INFO: Expecting 2560 events.
[12:24:43.900] <TB0> INFO: 2560 events read in total (298ms).
[12:24:43.901] <TB0> INFO: Test took 1197ms.
[12:24:43.903] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[12:24:44.209] <TB0> INFO: Expecting 2560 events.
[12:24:45.092] <TB0> INFO: 2560 events read in total (291ms).
[12:24:45.092] <TB0> INFO: Test took 1189ms.
[12:24:45.095] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[12:24:45.402] <TB0> INFO: Expecting 2560 events.
[12:24:46.297] <TB0> INFO: 2560 events read in total (303ms).
[12:24:46.297] <TB0> INFO: Test took 1202ms.
[12:24:46.300] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[12:24:46.607] <TB0> INFO: Expecting 2560 events.
[12:24:47.500] <TB0> INFO: 2560 events read in total (302ms).
[12:24:47.500] <TB0> INFO: Test took 1201ms.
[12:24:47.503] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[12:24:47.809] <TB0> INFO: Expecting 2560 events.
[12:24:48.700] <TB0> INFO: 2560 events read in total (299ms).
[12:24:48.700] <TB0> INFO: Test took 1198ms.
[12:24:48.702] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[12:24:49.009] <TB0> INFO: Expecting 2560 events.
[12:24:49.901] <TB0> INFO: 2560 events read in total (301ms).
[12:24:49.901] <TB0> INFO: Test took 1199ms.
[12:24:49.903] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[12:24:50.210] <TB0> INFO: Expecting 2560 events.
[12:24:51.102] <TB0> INFO: 2560 events read in total (300ms).
[12:24:51.102] <TB0> INFO: Test took 1200ms.
[12:24:51.108] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[12:24:51.411] <TB0> INFO: Expecting 2560 events.
[12:24:52.305] <TB0> INFO: 2560 events read in total (302ms).
[12:24:52.305] <TB0> INFO: Test took 1197ms.
[12:24:52.307] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[12:24:52.615] <TB0> INFO: Expecting 2560 events.
[12:24:53.502] <TB0> INFO: 2560 events read in total (295ms).
[12:24:53.502] <TB0> INFO: Test took 1195ms.
[12:24:53.504] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[12:24:53.811] <TB0> INFO: Expecting 2560 events.
[12:24:54.707] <TB0> INFO: 2560 events read in total (305ms).
[12:24:54.708] <TB0> INFO: Test took 1204ms.
[12:24:55.183] <TB0> INFO: PixTestPhOptimization::doTest() done, duration: 656 seconds
[12:24:55.183] <TB0> INFO: PH scale (per ROC): 42 51 64 48 47 62 59 64 61 54 47 40 63 58 52 56
[12:24:55.183] <TB0> INFO: PH offset (per ROC): 111 131 126 117 108 132 134 132 124 101 99 106 135 119 105 128
[12:24:55.194] <TB0> INFO: Decoding statistics:
[12:24:55.195] <TB0> INFO: General information:
[12:24:55.195] <TB0> INFO: 16bit words read: 127884
[12:24:55.195] <TB0> INFO: valid events total: 20480
[12:24:55.195] <TB0> INFO: empty events: 17978
[12:24:55.195] <TB0> INFO: valid events with pixels: 2502
[12:24:55.195] <TB0> INFO: valid pixel hits: 2502
[12:24:55.195] <TB0> INFO: Event errors: 0
[12:24:55.195] <TB0> INFO: start marker: 0
[12:24:55.195] <TB0> INFO: stop marker: 0
[12:24:55.195] <TB0> INFO: overflow: 0
[12:24:55.195] <TB0> INFO: invalid 5bit words: 0
[12:24:55.195] <TB0> INFO: invalid XOR eye diagram: 0
[12:24:55.195] <TB0> INFO: frame (failed synchr.): 0
[12:24:55.195] <TB0> INFO: idle data (no TBM trl): 0
[12:24:55.195] <TB0> INFO: no data (only TBM hdr): 0
[12:24:55.195] <TB0> INFO: TBM errors: 0
[12:24:55.195] <TB0> INFO: flawed TBM headers: 0
[12:24:55.195] <TB0> INFO: flawed TBM trailers: 0
[12:24:55.195] <TB0> INFO: event ID mismatches: 0
[12:24:55.195] <TB0> INFO: ROC errors: 0
[12:24:55.195] <TB0> INFO: missing ROC header(s): 0
[12:24:55.195] <TB0> INFO: misplaced readback start: 0
[12:24:55.195] <TB0> INFO: Pixel decoding errors: 0
[12:24:55.195] <TB0> INFO: pixel data incomplete: 0
[12:24:55.195] <TB0> INFO: pixel address: 0
[12:24:55.195] <TB0> INFO: pulse height fill bit: 0
[12:24:55.195] <TB0> INFO: buffer corruption: 0
[12:24:55.352] <TB0> INFO: ######################################################################
[12:24:55.352] <TB0> INFO: PixTestGainPedestal::fullTest() ntrig = 10
[12:24:55.352] <TB0> INFO: ######################################################################
[12:24:55.366] <TB0> INFO: scanning low vcal = 10
[12:24:55.605] <TB0> INFO: Expecting 41600 events.
[12:24:59.217] <TB0> INFO: 41600 events read in total (3020ms).
[12:24:59.217] <TB0> INFO: Test took 3851ms.
[12:24:59.219] <TB0> INFO: scanning low vcal = 20
[12:24:59.511] <TB0> INFO: Expecting 41600 events.
[12:25:03.107] <TB0> INFO: 41600 events read in total (3004ms).
[12:25:03.107] <TB0> INFO: Test took 3888ms.
[12:25:03.109] <TB0> INFO: scanning low vcal = 30
[12:25:03.408] <TB0> INFO: Expecting 41600 events.
[12:25:07.051] <TB0> INFO: 41600 events read in total (3051ms).
[12:25:07.052] <TB0> INFO: Test took 3943ms.
[12:25:07.055] <TB0> INFO: scanning low vcal = 40
[12:25:07.332] <TB0> INFO: Expecting 41600 events.
[12:25:11.324] <TB0> INFO: 41600 events read in total (3400ms).
[12:25:11.326] <TB0> INFO: Test took 4271ms.
[12:25:11.329] <TB0> INFO: scanning low vcal = 50
[12:25:11.606] <TB0> INFO: Expecting 41600 events.
[12:25:15.605] <TB0> INFO: 41600 events read in total (3407ms).
[12:25:15.606] <TB0> INFO: Test took 4277ms.
[12:25:15.610] <TB0> INFO: scanning low vcal = 60
[12:25:15.887] <TB0> INFO: Expecting 41600 events.
[12:25:19.866] <TB0> INFO: 41600 events read in total (3388ms).
[12:25:19.867] <TB0> INFO: Test took 4257ms.
[12:25:19.870] <TB0> INFO: scanning low vcal = 70
[12:25:20.148] <TB0> INFO: Expecting 41600 events.
[12:25:24.130] <TB0> INFO: 41600 events read in total (3391ms).
[12:25:24.131] <TB0> INFO: Test took 4261ms.
[12:25:24.134] <TB0> INFO: scanning low vcal = 80
[12:25:24.411] <TB0> INFO: Expecting 41600 events.
[12:25:28.410] <TB0> INFO: 41600 events read in total (3407ms).
[12:25:28.411] <TB0> INFO: Test took 4276ms.
[12:25:28.415] <TB0> INFO: scanning low vcal = 90
[12:25:28.692] <TB0> INFO: Expecting 41600 events.
[12:25:32.732] <TB0> INFO: 41600 events read in total (3448ms).
[12:25:32.733] <TB0> INFO: Test took 4318ms.
[12:25:32.738] <TB0> INFO: scanning low vcal = 100
[12:25:33.036] <TB0> INFO: Expecting 41600 events.
[12:25:37.053] <TB0> INFO: 41600 events read in total (3426ms).
[12:25:37.053] <TB0> INFO: Test took 4315ms.
[12:25:37.057] <TB0> INFO: scanning low vcal = 110
[12:25:37.334] <TB0> INFO: Expecting 41600 events.
[12:25:41.314] <TB0> INFO: 41600 events read in total (3388ms).
[12:25:41.315] <TB0> INFO: Test took 4258ms.
[12:25:41.318] <TB0> INFO: scanning low vcal = 120
[12:25:41.596] <TB0> INFO: Expecting 41600 events.
[12:25:45.573] <TB0> INFO: 41600 events read in total (3386ms).
[12:25:45.574] <TB0> INFO: Test took 4255ms.
[12:25:45.577] <TB0> INFO: scanning low vcal = 130
[12:25:45.853] <TB0> INFO: Expecting 41600 events.
[12:25:49.946] <TB0> INFO: 41600 events read in total (3501ms).
[12:25:49.947] <TB0> INFO: Test took 4370ms.
[12:25:49.950] <TB0> INFO: scanning low vcal = 140
[12:25:50.275] <TB0> INFO: Expecting 41600 events.
[12:25:54.210] <TB0> INFO: 41600 events read in total (3343ms).
[12:25:54.211] <TB0> INFO: Test took 4261ms.
[12:25:54.214] <TB0> INFO: scanning low vcal = 150
[12:25:54.490] <TB0> INFO: Expecting 41600 events.
[12:25:58.419] <TB0> INFO: 41600 events read in total (3337ms).
[12:25:58.420] <TB0> INFO: Test took 4206ms.
[12:25:58.423] <TB0> INFO: scanning low vcal = 160
[12:25:58.700] <TB0> INFO: Expecting 41600 events.
[12:26:02.639] <TB0> INFO: 41600 events read in total (3348ms).
[12:26:02.640] <TB0> INFO: Test took 4216ms.
[12:26:02.643] <TB0> INFO: scanning low vcal = 170
[12:26:02.919] <TB0> INFO: Expecting 41600 events.
[12:26:06.868] <TB0> INFO: 41600 events read in total (3357ms).
[12:26:06.868] <TB0> INFO: Test took 4225ms.
[12:26:06.874] <TB0> INFO: scanning low vcal = 180
[12:26:07.148] <TB0> INFO: Expecting 41600 events.
[12:26:11.093] <TB0> INFO: 41600 events read in total (3353ms).
[12:26:11.094] <TB0> INFO: Test took 4220ms.
[12:26:11.097] <TB0> INFO: scanning low vcal = 190
[12:26:11.373] <TB0> INFO: Expecting 41600 events.
[12:26:15.354] <TB0> INFO: 41600 events read in total (3389ms).
[12:26:15.355] <TB0> INFO: Test took 4258ms.
[12:26:15.358] <TB0> INFO: scanning low vcal = 200
[12:26:15.637] <TB0> INFO: Expecting 41600 events.
[12:26:19.690] <TB0> INFO: 41600 events read in total (3461ms).
[12:26:19.691] <TB0> INFO: Test took 4333ms.
[12:26:19.694] <TB0> INFO: scanning low vcal = 210
[12:26:19.971] <TB0> INFO: Expecting 41600 events.
[12:26:23.968] <TB0> INFO: 41600 events read in total (3405ms).
[12:26:23.969] <TB0> INFO: Test took 4275ms.
[12:26:23.972] <TB0> INFO: scanning low vcal = 220
[12:26:24.249] <TB0> INFO: Expecting 41600 events.
[12:26:28.243] <TB0> INFO: 41600 events read in total (3402ms).
[12:26:28.243] <TB0> INFO: Test took 4271ms.
[12:26:28.246] <TB0> INFO: scanning low vcal = 230
[12:26:28.523] <TB0> INFO: Expecting 41600 events.
[12:26:32.550] <TB0> INFO: 41600 events read in total (3435ms).
[12:26:32.551] <TB0> INFO: Test took 4305ms.
[12:26:32.554] <TB0> INFO: scanning low vcal = 240
[12:26:32.830] <TB0> INFO: Expecting 41600 events.
[12:26:36.781] <TB0> INFO: 41600 events read in total (3359ms).
[12:26:36.782] <TB0> INFO: Test took 4228ms.
[12:26:36.784] <TB0> INFO: scanning low vcal = 250
[12:26:37.061] <TB0> INFO: Expecting 41600 events.
[12:26:41.067] <TB0> INFO: 41600 events read in total (3415ms).
[12:26:41.068] <TB0> INFO: Test took 4283ms.
[12:26:41.073] <TB0> INFO: scanning high vcal = 30 (= 210 in low range)
[12:26:41.368] <TB0> INFO: Expecting 41600 events.
[12:26:45.361] <TB0> INFO: 41600 events read in total (3401ms).
[12:26:45.362] <TB0> INFO: Test took 4289ms.
[12:26:45.365] <TB0> INFO: scanning high vcal = 50 (= 350 in low range)
[12:26:45.641] <TB0> INFO: Expecting 41600 events.
[12:26:49.572] <TB0> INFO: 41600 events read in total (3339ms).
[12:26:49.572] <TB0> INFO: Test took 4207ms.
[12:26:49.576] <TB0> INFO: scanning high vcal = 70 (= 490 in low range)
[12:26:49.867] <TB0> INFO: Expecting 41600 events.
[12:26:53.812] <TB0> INFO: 41600 events read in total (3353ms).
[12:26:53.813] <TB0> INFO: Test took 4237ms.
[12:26:53.816] <TB0> INFO: scanning high vcal = 90 (= 630 in low range)
[12:26:54.092] <TB0> INFO: Expecting 41600 events.
[12:26:58.079] <TB0> INFO: 41600 events read in total (3395ms).
[12:26:58.080] <TB0> INFO: Test took 4264ms.
[12:26:58.083] <TB0> INFO: scanning high vcal = 200 (= 1400 in low range)
[12:26:58.359] <TB0> INFO: Expecting 41600 events.
[12:27:02.312] <TB0> INFO: 41600 events read in total (3361ms).
[12:27:02.313] <TB0> INFO: Test took 4230ms.
[12:27:03.020] <TB0> INFO: PixTestGainPedestal::measure() done
[12:27:36.651] <TB0> INFO: PixTestGainPedestal::fit() done
[12:27:36.651] <TB0> INFO: non-linearity mean: 0.930 0.983 0.983 0.964 0.932 0.985 0.987 0.982 0.982 0.961 0.918 0.938 0.983 0.976 0.939 0.983
[12:27:36.651] <TB0> INFO: non-linearity RMS: 0.080 0.004 0.003 0.018 0.104 0.002 0.004 0.005 0.004 0.025 0.146 0.167 0.004 0.004 0.082 0.004
[12:27:36.651] <TB0> INFO: write gain/ped parameters into /home/l_tester/david/FullQualification/data/M1009_FullQualification_2016-10-19_09h44m_1476863092//001_Fulltest_m20//phCalibrationFitErr35_C0.dat
[12:27:36.664] <TB0> INFO: write gain/ped parameters into /home/l_tester/david/FullQualification/data/M1009_FullQualification_2016-10-19_09h44m_1476863092//001_Fulltest_m20//phCalibrationFitErr35_C1.dat
[12:27:36.677] <TB0> INFO: write gain/ped parameters into /home/l_tester/david/FullQualification/data/M1009_FullQualification_2016-10-19_09h44m_1476863092//001_Fulltest_m20//phCalibrationFitErr35_C2.dat
[12:27:36.689] <TB0> INFO: write gain/ped parameters into /home/l_tester/david/FullQualification/data/M1009_FullQualification_2016-10-19_09h44m_1476863092//001_Fulltest_m20//phCalibrationFitErr35_C3.dat
[12:27:36.702] <TB0> INFO: write gain/ped parameters into /home/l_tester/david/FullQualification/data/M1009_FullQualification_2016-10-19_09h44m_1476863092//001_Fulltest_m20//phCalibrationFitErr35_C4.dat
[12:27:36.715] <TB0> INFO: write gain/ped parameters into /home/l_tester/david/FullQualification/data/M1009_FullQualification_2016-10-19_09h44m_1476863092//001_Fulltest_m20//phCalibrationFitErr35_C5.dat
[12:27:36.728] <TB0> INFO: write gain/ped parameters into /home/l_tester/david/FullQualification/data/M1009_FullQualification_2016-10-19_09h44m_1476863092//001_Fulltest_m20//phCalibrationFitErr35_C6.dat
[12:27:36.741] <TB0> INFO: write gain/ped parameters into /home/l_tester/david/FullQualification/data/M1009_FullQualification_2016-10-19_09h44m_1476863092//001_Fulltest_m20//phCalibrationFitErr35_C7.dat
[12:27:36.754] <TB0> INFO: write gain/ped parameters into /home/l_tester/david/FullQualification/data/M1009_FullQualification_2016-10-19_09h44m_1476863092//001_Fulltest_m20//phCalibrationFitErr35_C8.dat
[12:27:36.767] <TB0> INFO: write gain/ped parameters into /home/l_tester/david/FullQualification/data/M1009_FullQualification_2016-10-19_09h44m_1476863092//001_Fulltest_m20//phCalibrationFitErr35_C9.dat
[12:27:36.780] <TB0> INFO: write gain/ped parameters into /home/l_tester/david/FullQualification/data/M1009_FullQualification_2016-10-19_09h44m_1476863092//001_Fulltest_m20//phCalibrationFitErr35_C10.dat
[12:27:36.793] <TB0> INFO: write gain/ped parameters into /home/l_tester/david/FullQualification/data/M1009_FullQualification_2016-10-19_09h44m_1476863092//001_Fulltest_m20//phCalibrationFitErr35_C11.dat
[12:27:36.806] <TB0> INFO: write gain/ped parameters into /home/l_tester/david/FullQualification/data/M1009_FullQualification_2016-10-19_09h44m_1476863092//001_Fulltest_m20//phCalibrationFitErr35_C12.dat
[12:27:36.818] <TB0> INFO: write gain/ped parameters into /home/l_tester/david/FullQualification/data/M1009_FullQualification_2016-10-19_09h44m_1476863092//001_Fulltest_m20//phCalibrationFitErr35_C13.dat
[12:27:36.831] <TB0> INFO: write gain/ped parameters into /home/l_tester/david/FullQualification/data/M1009_FullQualification_2016-10-19_09h44m_1476863092//001_Fulltest_m20//phCalibrationFitErr35_C14.dat
[12:27:36.844] <TB0> INFO: write gain/ped parameters into /home/l_tester/david/FullQualification/data/M1009_FullQualification_2016-10-19_09h44m_1476863092//001_Fulltest_m20//phCalibrationFitErr35_C15.dat
[12:27:36.857] <TB0> INFO: PixTestGainPedestal::fullTest() done, duration: 161 seconds
[12:27:36.857] <TB0> INFO: Decoding statistics:
[12:27:36.857] <TB0> INFO: General information:
[12:27:36.857] <TB0> INFO: 16bit words read: 3326892
[12:27:36.857] <TB0> INFO: valid events total: 332800
[12:27:36.857] <TB0> INFO: empty events: 0
[12:27:36.857] <TB0> INFO: valid events with pixels: 332800
[12:27:36.857] <TB0> INFO: valid pixel hits: 665046
[12:27:36.857] <TB0> INFO: Event errors: 0
[12:27:36.857] <TB0> INFO: start marker: 0
[12:27:36.857] <TB0> INFO: stop marker: 0
[12:27:36.857] <TB0> INFO: overflow: 0
[12:27:36.857] <TB0> INFO: invalid 5bit words: 0
[12:27:36.857] <TB0> INFO: invalid XOR eye diagram: 0
[12:27:36.857] <TB0> INFO: frame (failed synchr.): 0
[12:27:36.857] <TB0> INFO: idle data (no TBM trl): 0
[12:27:36.857] <TB0> INFO: no data (only TBM hdr): 0
[12:27:36.857] <TB0> INFO: TBM errors: 0
[12:27:36.857] <TB0> INFO: flawed TBM headers: 0
[12:27:36.857] <TB0> INFO: flawed TBM trailers: 0
[12:27:36.857] <TB0> INFO: event ID mismatches: 0
[12:27:36.857] <TB0> INFO: ROC errors: 0
[12:27:36.857] <TB0> INFO: missing ROC header(s): 0
[12:27:36.857] <TB0> INFO: misplaced readback start: 0
[12:27:36.857] <TB0> INFO: Pixel decoding errors: 0
[12:27:36.857] <TB0> INFO: pixel data incomplete: 0
[12:27:36.857] <TB0> INFO: pixel address: 0
[12:27:36.857] <TB0> INFO: pulse height fill bit: 0
[12:27:36.857] <TB0> INFO: buffer corruption: 0
[12:27:36.872] <TB0> INFO: Decoding statistics:
[12:27:36.872] <TB0> INFO: General information:
[12:27:36.872] <TB0> INFO: 16bit words read: 3456312
[12:27:36.872] <TB0> INFO: valid events total: 353536
[12:27:36.872] <TB0> INFO: empty events: 18234
[12:27:36.872] <TB0> INFO: valid events with pixels: 335302
[12:27:36.872] <TB0> INFO: valid pixel hits: 667548
[12:27:36.872] <TB0> INFO: Event errors: 0
[12:27:36.872] <TB0> INFO: start marker: 0
[12:27:36.872] <TB0> INFO: stop marker: 0
[12:27:36.872] <TB0> INFO: overflow: 0
[12:27:36.872] <TB0> INFO: invalid 5bit words: 0
[12:27:36.872] <TB0> INFO: invalid XOR eye diagram: 0
[12:27:36.872] <TB0> INFO: frame (failed synchr.): 0
[12:27:36.872] <TB0> INFO: idle data (no TBM trl): 0
[12:27:36.872] <TB0> INFO: no data (only TBM hdr): 0
[12:27:36.872] <TB0> INFO: TBM errors: 0
[12:27:36.872] <TB0> INFO: flawed TBM headers: 0
[12:27:36.872] <TB0> INFO: flawed TBM trailers: 0
[12:27:36.872] <TB0> INFO: event ID mismatches: 0
[12:27:36.872] <TB0> INFO: ROC errors: 0
[12:27:36.872] <TB0> INFO: missing ROC header(s): 0
[12:27:36.872] <TB0> INFO: misplaced readback start: 0
[12:27:36.872] <TB0> INFO: Pixel decoding errors: 0
[12:27:36.872] <TB0> INFO: pixel data incomplete: 0
[12:27:36.872] <TB0> INFO: pixel address: 0
[12:27:36.872] <TB0> INFO: pulse height fill bit: 0
[12:27:36.872] <TB0> INFO: buffer corruption: 0
[12:27:36.872] <TB0> INFO: enter test to run
[12:27:36.872] <TB0> INFO: test: exit no parameter change
[12:27:37.014] <TB0> QUIET: Connection to board 71 closed.
[12:27:37.015] <TB0> INFO: pXar: this is the end, my friend