[09:51:15.672] <TB0> INFO: *** Welcome to pxar ***
[09:51:15.672] <TB0> INFO: *** Today: 2016/10/21
[09:51:15.677] <TB0> INFO: *** Version: c8ba-dirty
[09:51:15.677] <TB0> INFO: readRocDacs: /home/l_tester/david/FullQualification/data/M1032_FullQualification_2016-10-21_09h47m_1477036052//000_Fulltest_m20//dacParameters_C0.dat .. /home/l_tester/david/FullQualification/data/M1032_FullQualification_2016-10-21_09h47m_1477036052//000_Fulltest_m20//dacParameters_C15.dat
[09:51:15.678] <TB0> INFO: readTbmDacs: /home/l_tester/david/FullQualification/data/M1032_FullQualification_2016-10-21_09h47m_1477036052//000_Fulltest_m20//tbmParameters_C0a.dat .. /home/l_tester/david/FullQualification/data/M1032_FullQualification_2016-10-21_09h47m_1477036052//000_Fulltest_m20//tbmParameters_C1b.dat
[09:51:15.678] <TB0> INFO: readMaskFile: /home/l_tester/david/FullQualification/data/M1032_FullQualification_2016-10-21_09h47m_1477036052//000_Fulltest_m20//defaultMaskFile.dat
[09:51:15.678] <TB0> INFO: readTrimFile: /home/l_tester/david/FullQualification/data/M1032_FullQualification_2016-10-21_09h47m_1477036052//000_Fulltest_m20//trimParameters_C0.dat .. /home/l_tester/david/FullQualification/data/M1032_FullQualification_2016-10-21_09h47m_1477036052//000_Fulltest_m20//trimParameters_C15.dat
[09:51:15.738] <TB0> INFO: clk: 4
[09:51:15.738] <TB0> INFO: ctr: 4
[09:51:15.738] <TB0> INFO: sda: 19
[09:51:15.738] <TB0> INFO: tin: 9
[09:51:15.738] <TB0> INFO: level: 15
[09:51:15.738] <TB0> INFO: triggerdelay: 0
[09:51:15.738] <TB0> QUIET: Instanciating API for pxar v2.7.6+55~gafdbfd9
[09:51:15.738] <TB0> INFO: Log level: INFO
[09:51:15.746] <TB0> INFO: Found DTB DTB_WRQ4OZ
[09:51:15.765] <TB0> QUIET: Connection to board DTB_WRQ4OZ opened.
[09:51:15.767] <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:
------------------------------------------------------
[09:51:15.768] <TB0> INFO: RPC call hashes of host and DTB match: 486171790
[09:51:17.257] <TB0> INFO: DUT info:
[09:51:17.257] <TB0> INFO: The DUT currently contains the following objects:
[09:51:17.257] <TB0> INFO: 4 TBM Cores tbm10c (4 ON)
[09:51:17.257] <TB0> INFO: TBM Core alpha (0): 7 registers set
[09:51:17.257] <TB0> INFO: TBM Core beta (1): 7 registers set
[09:51:17.257] <TB0> INFO: TBM Core alpha (2): 7 registers set
[09:51:17.257] <TB0> INFO: TBM Core beta (3): 7 registers set
[09:51:17.257] <TB0> INFO: 16 ROCs proc600 (16 ON) with 4160 pixelConfigs
[09:51:17.257] <TB0> INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[09:51:17.257] <TB0> INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[09:51:17.257] <TB0> INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[09:51:17.257] <TB0> INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[09:51:17.257] <TB0> INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[09:51:17.257] <TB0> INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[09:51:17.257] <TB0> INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[09:51:17.257] <TB0> INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[09:51:17.257] <TB0> INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[09:51:17.257] <TB0> INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[09:51:17.258] <TB0> INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[09:51:17.258] <TB0> INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[09:51:17.258] <TB0> INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[09:51:17.258] <TB0> INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[09:51:17.258] <TB0> INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[09:51:17.258] <TB0> INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[09:51:17.658] <TB0> INFO: enter 'restricted' command line mode
[09:51:17.658] <TB0> INFO: enter test to run
[09:51:17.658] <TB0> INFO: test: pretest no parameter change
[09:51:17.658] <TB0> INFO: running: pretest
[09:51:17.663] <TB0> INFO: ######################################################################
[09:51:17.663] <TB0> INFO: PixTestPretest::doTest()
[09:51:17.663] <TB0> INFO: ######################################################################
[09:51:17.664] <TB0> INFO: ----------------------------------------------------------------------
[09:51:17.664] <TB0> INFO: PixTestPretest::programROC()
[09:51:17.664] <TB0> INFO: ----------------------------------------------------------------------
[09:51:35.677] <TB0> INFO: PixTestPretest::programROC() done: ROCs are all programmable
[09:51:35.677] <TB0> INFO: IA differences per ROC: 16.1 18.5 17.7 19.3 20.9 19.3 18.5 20.9 16.1 17.7 16.9 20.9 19.3 20.1 19.3 20.9
[09:51:35.745] <TB0> INFO: ----------------------------------------------------------------------
[09:51:35.746] <TB0> INFO: PixTestPretest::setVana() target Ia = 24 mA/ROC
[09:51:35.746] <TB0> INFO: ----------------------------------------------------------------------
[09:51:57.027] <TB0> INFO: PixTestPretest::setVana() done, Module Ia 384.3 mA = 24.0187 mA/ROC
[09:51:57.027] <TB0> INFO: i(loss) [mA/ROC]: 19.3 20.9 20.1 19.3 19.3 20.1 20.1 19.3 19.3 20.1 19.3 18.5 19.3 19.3 20.9 18.5
[09:51:57.062] <TB0> INFO: ----------------------------------------------------------------------
[09:51:57.062] <TB0> INFO: PixTestPretest::findTiming()
[09:51:57.062] <TB0> INFO: ----------------------------------------------------------------------
[09:51:57.062] <TB0> INFO: PixTestCmd::init()

[09:52:29.423] <TB0> INFO: TBM phases: 160MHz: 0, 400MHz: 2, TBM delays: ROC(0/1):5, header/trailer: 1, token: 1
[09:52:29.423] <TB0> INFO: (success/tries = 100/100), width = 3
[09:52:30.928] <TB0> INFO: ----------------------------------------------------------------------
[09:52:30.929] <TB0> INFO: PixTestPretest::findWorkingPixel()
[09:52:30.929] <TB0> INFO: ----------------------------------------------------------------------
[09:52:31.022] <TB0> INFO: Expecting 231680 events.
[09:52:41.032] <TB0> INFO: 231680 events read in total (9417ms).
[09:52:41.043] <TB0> INFO: Test took 10111ms.
[09:52:41.288] <TB0> INFO: Found working pixel in all ROCs: col/row = 12/22
[09:52:41.321] <TB0> INFO: ----------------------------------------------------------------------
[09:52:41.321] <TB0> INFO: PixTestPretest::setVthrCompCalDel()
[09:52:41.321] <TB0> INFO: ----------------------------------------------------------------------
[09:52:41.415] <TB0> INFO: Expecting 231680 events.
[09:52:51.535] <TB0> INFO: 231680 events read in total (9529ms).
[09:52:51.547] <TB0> INFO: Test took 10221ms.
[09:52:51.814] <TB0> INFO: PixTestPretest::setVthrCompCalDel() done
[09:52:51.814] <TB0> INFO: CalDel: 92 83 111 82 96 94 81 100 107 111 132 111 112 108 119 91
[09:52:51.814] <TB0> INFO: VthrComp: 51 51 51 51 51 51 51 51 51 51 51 51 52 51 51 51
[09:52:51.819] <TB0> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1032_FullQualification_2016-10-21_09h47m_1477036052//000_Fulltest_m20//dacParameters_C0.dat
[09:52:51.819] <TB0> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1032_FullQualification_2016-10-21_09h47m_1477036052//000_Fulltest_m20//dacParameters_C1.dat
[09:52:51.819] <TB0> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1032_FullQualification_2016-10-21_09h47m_1477036052//000_Fulltest_m20//dacParameters_C2.dat
[09:52:51.820] <TB0> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1032_FullQualification_2016-10-21_09h47m_1477036052//000_Fulltest_m20//dacParameters_C3.dat
[09:52:51.820] <TB0> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1032_FullQualification_2016-10-21_09h47m_1477036052//000_Fulltest_m20//dacParameters_C4.dat
[09:52:51.820] <TB0> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1032_FullQualification_2016-10-21_09h47m_1477036052//000_Fulltest_m20//dacParameters_C5.dat
[09:52:51.820] <TB0> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1032_FullQualification_2016-10-21_09h47m_1477036052//000_Fulltest_m20//dacParameters_C6.dat
[09:52:51.820] <TB0> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1032_FullQualification_2016-10-21_09h47m_1477036052//000_Fulltest_m20//dacParameters_C7.dat
[09:52:51.820] <TB0> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1032_FullQualification_2016-10-21_09h47m_1477036052//000_Fulltest_m20//dacParameters_C8.dat
[09:52:51.821] <TB0> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1032_FullQualification_2016-10-21_09h47m_1477036052//000_Fulltest_m20//dacParameters_C9.dat
[09:52:51.821] <TB0> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1032_FullQualification_2016-10-21_09h47m_1477036052//000_Fulltest_m20//dacParameters_C10.dat
[09:52:51.821] <TB0> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1032_FullQualification_2016-10-21_09h47m_1477036052//000_Fulltest_m20//dacParameters_C11.dat
[09:52:51.821] <TB0> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1032_FullQualification_2016-10-21_09h47m_1477036052//000_Fulltest_m20//dacParameters_C12.dat
[09:52:51.821] <TB0> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1032_FullQualification_2016-10-21_09h47m_1477036052//000_Fulltest_m20//dacParameters_C13.dat
[09:52:51.821] <TB0> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1032_FullQualification_2016-10-21_09h47m_1477036052//000_Fulltest_m20//dacParameters_C14.dat
[09:52:51.821] <TB0> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1032_FullQualification_2016-10-21_09h47m_1477036052//000_Fulltest_m20//dacParameters_C15.dat
[09:52:51.822] <TB0> INFO: write tbm parameters into /home/l_tester/david/FullQualification/data/M1032_FullQualification_2016-10-21_09h47m_1477036052//000_Fulltest_m20//tbmParameters_C0a.dat
[09:52:51.822] <TB0> INFO: write tbm parameters into /home/l_tester/david/FullQualification/data/M1032_FullQualification_2016-10-21_09h47m_1477036052//000_Fulltest_m20//tbmParameters_C0b.dat
[09:52:51.823] <TB0> INFO: write tbm parameters into /home/l_tester/david/FullQualification/data/M1032_FullQualification_2016-10-21_09h47m_1477036052//000_Fulltest_m20//tbmParameters_C1a.dat
[09:52:51.823] <TB0> INFO: write tbm parameters into /home/l_tester/david/FullQualification/data/M1032_FullQualification_2016-10-21_09h47m_1477036052//000_Fulltest_m20//tbmParameters_C1b.dat
[09:52:51.823] <TB0> INFO: PixTestPretest::doTest() done, duration: 94 seconds
[09:52:51.877] <TB0> INFO: enter test to run
[09:52:51.877] <TB0> INFO: test: FullTest no parameter change
[09:52:51.877] <TB0> INFO: running: fulltest
[09:52:51.877] <TB0> INFO: ######################################################################
[09:52:51.877] <TB0> INFO: PixTestFullTest::doTest()
[09:52:51.877] <TB0> INFO: ######################################################################
[09:52:51.878] <TB0> INFO: ######################################################################
[09:52:51.878] <TB0> INFO: PixTestAlive::doTest()
[09:52:51.878] <TB0> INFO: ######################################################################
[09:52:51.879] <TB0> INFO: ----------------------------------------------------------------------
[09:52:51.879] <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)
[09:52:51.879] <TB0> INFO: ----------------------------------------------------------------------
[09:52:52.135] <TB0> INFO: Expecting 41600 events.
[09:52:55.740] <TB0> INFO: 41600 events read in total (3012ms).
[09:52:55.741] <TB0> INFO: Test took 3860ms.
[09:52:55.969] <TB0> INFO: PixTestAlive::aliveTest() done
[09:52:55.969] <TB0> INFO: number of dead pixels (per ROC): 0 0 0 0 0 0 0 1 0 0 0 0 0 0 0 0
[09:52:55.971] <TB0> INFO: ----------------------------------------------------------------------
[09:52:55.971] <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)
[09:52:55.971] <TB0> INFO: ----------------------------------------------------------------------
[09:52:56.240] <TB0> INFO: Expecting 41600 events.
[09:52:59.357] <TB0> INFO: 41600 events read in total (2525ms).
[09:52:59.359] <TB0> INFO: Test took 3387ms.
[09:52:59.360] <TB0> INFO: mask vs. old pixelAlive PixelAlive_C0_V0 .. PixelAlive_C15_V0
[09:52:59.598] <TB0> INFO: PixTestAlive::maskTest() done
[09:52:59.598] <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
[09:52:59.600] <TB0> INFO: ----------------------------------------------------------------------
[09:52:59.600] <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)
[09:52:59.600] <TB0> INFO: ----------------------------------------------------------------------
[09:52:59.859] <TB0> INFO: Expecting 41600 events.
[09:53:03.384] <TB0> INFO: 41600 events read in total (2931ms).
[09:53:03.385] <TB0> INFO: Test took 3784ms.
[09:53:03.612] <TB0> INFO: PixTestAlive::addressDecodingTest() done
[09:53:03.612] <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
[09:53:03.612] <TB0> INFO: PixTestAlive::doTest() done, duration: 11 seconds
[09:53:03.612] <TB0> INFO: Decoding statistics:
[09:53:03.612] <TB0> INFO: General information:
[09:53:03.612] <TB0> INFO: 16bit words read: 0
[09:53:03.612] <TB0> INFO: valid events total: 0
[09:53:03.612] <TB0> INFO: empty events: 0
[09:53:03.612] <TB0> INFO: valid events with pixels: 0
[09:53:03.612] <TB0> INFO: valid pixel hits: 0
[09:53:03.612] <TB0> INFO: Event errors: 0
[09:53:03.612] <TB0> INFO: start marker: 0
[09:53:03.613] <TB0> INFO: stop marker: 0
[09:53:03.613] <TB0> INFO: overflow: 0
[09:53:03.613] <TB0> INFO: invalid 5bit words: 0
[09:53:03.613] <TB0> INFO: invalid XOR eye diagram: 0
[09:53:03.613] <TB0> INFO: frame (failed synchr.): 0
[09:53:03.613] <TB0> INFO: idle data (no TBM trl): 0
[09:53:03.613] <TB0> INFO: no data (only TBM hdr): 0
[09:53:03.613] <TB0> INFO: TBM errors: 0
[09:53:03.613] <TB0> INFO: flawed TBM headers: 0
[09:53:03.613] <TB0> INFO: flawed TBM trailers: 0
[09:53:03.613] <TB0> INFO: event ID mismatches: 0
[09:53:03.613] <TB0> INFO: ROC errors: 0
[09:53:03.613] <TB0> INFO: missing ROC header(s): 0
[09:53:03.613] <TB0> INFO: misplaced readback start: 0
[09:53:03.613] <TB0> INFO: Pixel decoding errors: 0
[09:53:03.613] <TB0> INFO: pixel data incomplete: 0
[09:53:03.613] <TB0> INFO: pixel address: 0
[09:53:03.613] <TB0> INFO: pulse height fill bit: 0
[09:53:03.613] <TB0> INFO: buffer corruption: 0
[09:53:03.618] <TB0> INFO: readReadbackCal: /home/l_tester/david/FullQualification/data/M1032_FullQualification_2016-10-21_09h47m_1477036052//000_Fulltest_m20//readbackCal_C0.dat .. /home/l_tester/david/FullQualification/data/M1032_FullQualification_2016-10-21_09h47m_1477036052//000_Fulltest_m20//readbackCal_C15.dat
[09:53:03.618] <TB0> INFO: readGainPedestalParameters /home/l_tester/david/FullQualification/data/M1032_FullQualification_2016-10-21_09h47m_1477036052//000_Fulltest_m20//phCalibrationFitErr_C0.dat .. /home/l_tester/david/FullQualification/data/M1032_FullQualification_2016-10-21_09h47m_1477036052//000_Fulltest_m20//phCalibrationFitErr_C15.dat

[09:53:03.618] <TB0> INFO: ######################################################################
[09:53:03.618] <TB0> INFO: PixTestReadback::doTest()
[09:53:03.618] <TB0> INFO: ######################################################################
[09:53:03.618] <TB0> INFO: ----------------------------------------------------------------------
[09:53:03.618] <TB0> INFO: PixTestReadback::CalibrateVd()
[09:53:03.618] <TB0> INFO: ----------------------------------------------------------------------
[09:53:13.626] <TB0> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1032_FullQualification_2016-10-21_09h47m_1477036052//000_Fulltest_m20//readbackCal_C0.dat
[09:53:13.626] <TB0> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1032_FullQualification_2016-10-21_09h47m_1477036052//000_Fulltest_m20//readbackCal_C1.dat
[09:53:13.626] <TB0> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1032_FullQualification_2016-10-21_09h47m_1477036052//000_Fulltest_m20//readbackCal_C2.dat
[09:53:13.627] <TB0> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1032_FullQualification_2016-10-21_09h47m_1477036052//000_Fulltest_m20//readbackCal_C3.dat
[09:53:13.627] <TB0> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1032_FullQualification_2016-10-21_09h47m_1477036052//000_Fulltest_m20//readbackCal_C4.dat
[09:53:13.627] <TB0> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1032_FullQualification_2016-10-21_09h47m_1477036052//000_Fulltest_m20//readbackCal_C5.dat
[09:53:13.627] <TB0> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1032_FullQualification_2016-10-21_09h47m_1477036052//000_Fulltest_m20//readbackCal_C6.dat
[09:53:13.627] <TB0> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1032_FullQualification_2016-10-21_09h47m_1477036052//000_Fulltest_m20//readbackCal_C7.dat
[09:53:13.627] <TB0> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1032_FullQualification_2016-10-21_09h47m_1477036052//000_Fulltest_m20//readbackCal_C8.dat
[09:53:13.627] <TB0> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1032_FullQualification_2016-10-21_09h47m_1477036052//000_Fulltest_m20//readbackCal_C9.dat
[09:53:13.627] <TB0> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1032_FullQualification_2016-10-21_09h47m_1477036052//000_Fulltest_m20//readbackCal_C10.dat
[09:53:13.627] <TB0> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1032_FullQualification_2016-10-21_09h47m_1477036052//000_Fulltest_m20//readbackCal_C11.dat
[09:53:13.627] <TB0> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1032_FullQualification_2016-10-21_09h47m_1477036052//000_Fulltest_m20//readbackCal_C12.dat
[09:53:13.627] <TB0> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1032_FullQualification_2016-10-21_09h47m_1477036052//000_Fulltest_m20//readbackCal_C13.dat
[09:53:13.627] <TB0> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1032_FullQualification_2016-10-21_09h47m_1477036052//000_Fulltest_m20//readbackCal_C14.dat
[09:53:13.627] <TB0> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1032_FullQualification_2016-10-21_09h47m_1477036052//000_Fulltest_m20//readbackCal_C15.dat
[09:53:13.659] <TB0> INFO: PixTestPattern:: pg_setup set to default.
[09:53:13.659] <TB0> INFO: ----------------------------------------------------------------------
[09:53:13.659] <TB0> INFO: PixTestReadback::CalibrateVa()
[09:53:13.659] <TB0> INFO: ----------------------------------------------------------------------
[09:53:23.631] <TB0> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1032_FullQualification_2016-10-21_09h47m_1477036052//000_Fulltest_m20//readbackCal_C0.dat
[09:53:23.631] <TB0> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1032_FullQualification_2016-10-21_09h47m_1477036052//000_Fulltest_m20//readbackCal_C1.dat
[09:53:23.631] <TB0> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1032_FullQualification_2016-10-21_09h47m_1477036052//000_Fulltest_m20//readbackCal_C2.dat
[09:53:23.631] <TB0> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1032_FullQualification_2016-10-21_09h47m_1477036052//000_Fulltest_m20//readbackCal_C3.dat
[09:53:23.631] <TB0> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1032_FullQualification_2016-10-21_09h47m_1477036052//000_Fulltest_m20//readbackCal_C4.dat
[09:53:23.631] <TB0> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1032_FullQualification_2016-10-21_09h47m_1477036052//000_Fulltest_m20//readbackCal_C5.dat
[09:53:23.632] <TB0> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1032_FullQualification_2016-10-21_09h47m_1477036052//000_Fulltest_m20//readbackCal_C6.dat
[09:53:23.632] <TB0> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1032_FullQualification_2016-10-21_09h47m_1477036052//000_Fulltest_m20//readbackCal_C7.dat
[09:53:23.632] <TB0> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1032_FullQualification_2016-10-21_09h47m_1477036052//000_Fulltest_m20//readbackCal_C8.dat
[09:53:23.632] <TB0> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1032_FullQualification_2016-10-21_09h47m_1477036052//000_Fulltest_m20//readbackCal_C9.dat
[09:53:23.632] <TB0> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1032_FullQualification_2016-10-21_09h47m_1477036052//000_Fulltest_m20//readbackCal_C10.dat
[09:53:23.632] <TB0> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1032_FullQualification_2016-10-21_09h47m_1477036052//000_Fulltest_m20//readbackCal_C11.dat
[09:53:23.632] <TB0> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1032_FullQualification_2016-10-21_09h47m_1477036052//000_Fulltest_m20//readbackCal_C12.dat
[09:53:23.632] <TB0> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1032_FullQualification_2016-10-21_09h47m_1477036052//000_Fulltest_m20//readbackCal_C13.dat
[09:53:23.632] <TB0> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1032_FullQualification_2016-10-21_09h47m_1477036052//000_Fulltest_m20//readbackCal_C14.dat
[09:53:23.632] <TB0> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1032_FullQualification_2016-10-21_09h47m_1477036052//000_Fulltest_m20//readbackCal_C15.dat
[09:53:23.663] <TB0> INFO: PixTestPattern:: pg_setup set to default.
[09:53:23.663] <TB0> INFO: ----------------------------------------------------------------------
[09:53:23.663] <TB0> INFO: PixTestReadback::readbackVbg()
[09:53:23.663] <TB0> INFO: ----------------------------------------------------------------------
[09:53:31.336] <TB0> INFO: PixTestPattern:: pg_setup set to default.
[09:53:31.337] <TB0> INFO: ----------------------------------------------------------------------
[09:53:31.337] <TB0> INFO: PixTestReadback::getCalibratedVbg()
[09:53:31.337] <TB0> INFO: ----------------------------------------------------------------------
[09:53:31.337] <TB0> INFO: Vbg will be calibrated using Vd calibration
[09:53:31.337] <TB0> INFO: /*/*/*/*::: ROC 0: uncalibrated Vbg = 152.3calibrated Vbg = 1.17288 :::*/*/*/*/
[09:53:31.337] <TB0> INFO: /*/*/*/*::: ROC 1: uncalibrated Vbg = 148calibrated Vbg = 1.16877 :::*/*/*/*/
[09:53:31.337] <TB0> INFO: /*/*/*/*::: ROC 2: uncalibrated Vbg = 143.5calibrated Vbg = 1.16675 :::*/*/*/*/
[09:53:31.337] <TB0> INFO: /*/*/*/*::: ROC 3: uncalibrated Vbg = 160.8calibrated Vbg = 1.16351 :::*/*/*/*/
[09:53:31.337] <TB0> INFO: /*/*/*/*::: ROC 4: uncalibrated Vbg = 163.6calibrated Vbg = 1.1674 :::*/*/*/*/
[09:53:31.337] <TB0> INFO: /*/*/*/*::: ROC 5: uncalibrated Vbg = 148.5calibrated Vbg = 1.17218 :::*/*/*/*/
[09:53:31.337] <TB0> INFO: /*/*/*/*::: ROC 6: uncalibrated Vbg = 144calibrated Vbg = 1.17384 :::*/*/*/*/
[09:53:31.337] <TB0> INFO: /*/*/*/*::: ROC 7: uncalibrated Vbg = 148.4calibrated Vbg = 1.17918 :::*/*/*/*/
[09:53:31.337] <TB0> INFO: /*/*/*/*::: ROC 8: uncalibrated Vbg = 155.1calibrated Vbg = 1.17276 :::*/*/*/*/
[09:53:31.337] <TB0> INFO: /*/*/*/*::: ROC 9: uncalibrated Vbg = 153.6calibrated Vbg = 1.16423 :::*/*/*/*/
[09:53:31.337] <TB0> INFO: /*/*/*/*::: ROC 10: uncalibrated Vbg = 159.3calibrated Vbg = 1.16187 :::*/*/*/*/
[09:53:31.337] <TB0> INFO: /*/*/*/*::: ROC 11: uncalibrated Vbg = 150calibrated Vbg = 1.16063 :::*/*/*/*/
[09:53:31.337] <TB0> INFO: /*/*/*/*::: ROC 12: uncalibrated Vbg = 162.2calibrated Vbg = 1.1613 :::*/*/*/*/
[09:53:31.337] <TB0> INFO: /*/*/*/*::: ROC 13: uncalibrated Vbg = 149.1calibrated Vbg = 1.16842 :::*/*/*/*/
[09:53:31.337] <TB0> INFO: /*/*/*/*::: ROC 14: uncalibrated Vbg = 166.5calibrated Vbg = 1.17467 :::*/*/*/*/
[09:53:31.337] <TB0> INFO: /*/*/*/*::: ROC 15: uncalibrated Vbg = 159.5calibrated Vbg = 1.16868 :::*/*/*/*/
[09:53:31.339] <TB0> INFO: ----------------------------------------------------------------------
[09:53:31.339] <TB0> INFO: PixTestReadback::CalibrateIa()
[09:53:31.339] <TB0> INFO: ----------------------------------------------------------------------
[09:56:12.191] <TB0> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1032_FullQualification_2016-10-21_09h47m_1477036052//000_Fulltest_m20//readbackCal_C0.dat
[09:56:12.191] <TB0> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1032_FullQualification_2016-10-21_09h47m_1477036052//000_Fulltest_m20//readbackCal_C1.dat
[09:56:12.191] <TB0> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1032_FullQualification_2016-10-21_09h47m_1477036052//000_Fulltest_m20//readbackCal_C2.dat
[09:56:12.191] <TB0> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1032_FullQualification_2016-10-21_09h47m_1477036052//000_Fulltest_m20//readbackCal_C3.dat
[09:56:12.191] <TB0> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1032_FullQualification_2016-10-21_09h47m_1477036052//000_Fulltest_m20//readbackCal_C4.dat
[09:56:12.191] <TB0> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1032_FullQualification_2016-10-21_09h47m_1477036052//000_Fulltest_m20//readbackCal_C5.dat
[09:56:12.191] <TB0> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1032_FullQualification_2016-10-21_09h47m_1477036052//000_Fulltest_m20//readbackCal_C6.dat
[09:56:12.192] <TB0> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1032_FullQualification_2016-10-21_09h47m_1477036052//000_Fulltest_m20//readbackCal_C7.dat
[09:56:12.192] <TB0> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1032_FullQualification_2016-10-21_09h47m_1477036052//000_Fulltest_m20//readbackCal_C8.dat
[09:56:12.192] <TB0> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1032_FullQualification_2016-10-21_09h47m_1477036052//000_Fulltest_m20//readbackCal_C9.dat
[09:56:12.192] <TB0> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1032_FullQualification_2016-10-21_09h47m_1477036052//000_Fulltest_m20//readbackCal_C10.dat
[09:56:12.192] <TB0> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1032_FullQualification_2016-10-21_09h47m_1477036052//000_Fulltest_m20//readbackCal_C11.dat
[09:56:12.192] <TB0> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1032_FullQualification_2016-10-21_09h47m_1477036052//000_Fulltest_m20//readbackCal_C12.dat
[09:56:12.192] <TB0> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1032_FullQualification_2016-10-21_09h47m_1477036052//000_Fulltest_m20//readbackCal_C13.dat
[09:56:12.192] <TB0> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1032_FullQualification_2016-10-21_09h47m_1477036052//000_Fulltest_m20//readbackCal_C14.dat
[09:56:12.192] <TB0> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1032_FullQualification_2016-10-21_09h47m_1477036052//000_Fulltest_m20//readbackCal_C15.dat
[09:56:12.221] <TB0> INFO: PixTestPattern:: pg_setup set to default.
[09:56:12.223] <TB0> INFO: PixTestReadback::doTest() done
[09:56:12.223] <TB0> INFO: Decoding statistics:
[09:56:12.223] <TB0> INFO: General information:
[09:56:12.223] <TB0> INFO: 16bit words read: 1536
[09:56:12.223] <TB0> INFO: valid events total: 256
[09:56:12.223] <TB0> INFO: empty events: 256
[09:56:12.223] <TB0> INFO: valid events with pixels: 0
[09:56:12.223] <TB0> INFO: valid pixel hits: 0
[09:56:12.223] <TB0> INFO: Event errors: 0
[09:56:12.223] <TB0> INFO: start marker: 0
[09:56:12.223] <TB0> INFO: stop marker: 0
[09:56:12.223] <TB0> INFO: overflow: 0
[09:56:12.223] <TB0> INFO: invalid 5bit words: 0
[09:56:12.223] <TB0> INFO: invalid XOR eye diagram: 0
[09:56:12.223] <TB0> INFO: frame (failed synchr.): 0
[09:56:12.223] <TB0> INFO: idle data (no TBM trl): 0
[09:56:12.223] <TB0> INFO: no data (only TBM hdr): 0
[09:56:12.223] <TB0> INFO: TBM errors: 0
[09:56:12.223] <TB0> INFO: flawed TBM headers: 0
[09:56:12.223] <TB0> INFO: flawed TBM trailers: 0
[09:56:12.223] <TB0> INFO: event ID mismatches: 0
[09:56:12.223] <TB0> INFO: ROC errors: 0
[09:56:12.223] <TB0> INFO: missing ROC header(s): 0
[09:56:12.223] <TB0> INFO: misplaced readback start: 0
[09:56:12.223] <TB0> INFO: Pixel decoding errors: 0
[09:56:12.223] <TB0> INFO: pixel data incomplete: 0
[09:56:12.223] <TB0> INFO: pixel address: 0
[09:56:12.224] <TB0> INFO: pulse height fill bit: 0
[09:56:12.224] <TB0> INFO: buffer corruption: 0
[09:56:12.273] <TB0> INFO: ######################################################################
[09:56:12.273] <TB0> INFO: PixTestBBMap::doTest() Ntrig = 5, VcalS = 250 (high range)
[09:56:12.273] <TB0> INFO: ######################################################################
[09:56:12.276] <TB0> INFO: ---> dac: VthrComp name: calSMap ntrig: 5 dacrange: 0 .. 149 (-1/-1) hits flags = 514 (plus default)
[09:56:12.290] <TB0> INFO: dacScan split into 1 runs with ntrig = 5
[09:56:12.290] <TB0> INFO: run 1 of 1
[09:56:12.527] <TB0> INFO: Expecting 3120000 events.
[09:56:43.966] <TB0> INFO: 658480 events read in total (30847ms).











[09:57:16.672] <TB0> INFO: 1314525 events read in total (63554ms).











[09:57:48.695] <TB0> INFO: 1967995 events read in total (95576ms).











[09:58:20.649] <TB0> INFO: 2622050 events read in total (127530ms).











[09:58:45.845] <TB0> INFO: 3120000 events read in total (152726ms).
[09:58:45.937] <TB0> INFO: Test took 153646ms.
[09:59:12.905] <TB0> INFO: PixTestBBMap::doTest() done with 3 decoding errors: , duration: 180 seconds
[09:59:12.905] <TB0> INFO: number of dead bumps (per ROC): 0 0 0 0 0 0 0 1 1 1 0 0 2 0 0 0
[09:59:12.905] <TB0> INFO: separation cut (per ROC): 106 107 103 105 104 104 109 106 99 111 89 109 100 99 112 103
[09:59:12.905] <TB0> INFO: Decoding statistics:
[09:59:12.905] <TB0> INFO: General information:
[09:59:12.905] <TB0> INFO: 16bit words read: 0
[09:59:12.905] <TB0> INFO: valid events total: 0
[09:59:12.905] <TB0> INFO: empty events: 0
[09:59:12.905] <TB0> INFO: valid events with pixels: 0
[09:59:12.905] <TB0> INFO: valid pixel hits: 0
[09:59:12.905] <TB0> INFO: Event errors: 0
[09:59:12.905] <TB0> INFO: start marker: 0
[09:59:12.905] <TB0> INFO: stop marker: 0
[09:59:12.905] <TB0> INFO: overflow: 0
[09:59:12.905] <TB0> INFO: invalid 5bit words: 0
[09:59:12.905] <TB0> INFO: invalid XOR eye diagram: 0
[09:59:12.905] <TB0> INFO: frame (failed synchr.): 0
[09:59:12.905] <TB0> INFO: idle data (no TBM trl): 0
[09:59:12.905] <TB0> INFO: no data (only TBM hdr): 0
[09:59:12.905] <TB0> INFO: TBM errors: 0
[09:59:12.905] <TB0> INFO: flawed TBM headers: 0
[09:59:12.905] <TB0> INFO: flawed TBM trailers: 0
[09:59:12.905] <TB0> INFO: event ID mismatches: 0
[09:59:12.905] <TB0> INFO: ROC errors: 0
[09:59:12.905] <TB0> INFO: missing ROC header(s): 0
[09:59:12.905] <TB0> INFO: misplaced readback start: 0
[09:59:12.905] <TB0> INFO: Pixel decoding errors: 0
[09:59:12.905] <TB0> INFO: pixel data incomplete: 0
[09:59:12.905] <TB0> INFO: pixel address: 0
[09:59:12.905] <TB0> INFO: pulse height fill bit: 0
[09:59:12.905] <TB0> INFO: buffer corruption: 0
[09:59:12.941] <TB0> INFO: ######################################################################
[09:59:12.941] <TB0> INFO: PixTestScurves::fullTest() ntrig = 50, dacs/step = -1, ntrig/step = -1
[09:59:12.941] <TB0> INFO: ######################################################################
[09:59:12.941] <TB0> INFO: ----------------------------------------------------------------------
[09:59:12.941] <TB0> INFO: PixTestScurves::scurves(Vcal), ntrig = 50, dacs/step = -1, ntrig/step = -1
[09:59:12.941] <TB0> INFO: ----------------------------------------------------------------------
[09:59:12.941] <TB0> INFO: ---> dac: Vcal name: scurveVcal ntrig: 50 dacrange: 25 .. 200 (-1/-1) hits flags = 528 (plus default)
[09:59:12.954] <TB0> INFO: dacScan split into 1 runs with ntrig = 50
[09:59:12.954] <TB0> INFO: run 1 of 1
[09:59:13.206] <TB0> INFO: Expecting 36608000 events.
[09:59:39.212] <TB0> INFO: 680500 events read in total (25415ms).
[10:00:03.664] <TB0> INFO: 1343500 events read in total (49867ms).
[10:00:28.926] <TB0> INFO: 2008350 events read in total (75129ms).
[10:00:51.686] <TB0> INFO: 2671200 events read in total (97889ms).
[10:01:15.904] <TB0> INFO: 3334500 events read in total (122107ms).
[10:01:41.323] <TB0> INFO: 3997100 events read in total (147526ms).
[10:02:06.851] <TB0> INFO: 4660950 events read in total (173054ms).
[10:02:31.421] <TB0> INFO: 5323800 events read in total (197624ms).
[10:02:56.771] <TB0> INFO: 5988350 events read in total (222974ms).
[10:03:21.640] <TB0> INFO: 6650050 events read in total (247843ms).
[10:03:46.931] <TB0> INFO: 7312250 events read in total (273134ms).
[10:04:11.559] <TB0> INFO: 7974750 events read in total (297762ms).
[10:04:36.535] <TB0> INFO: 8637750 events read in total (322738ms).
[10:05:01.955] <TB0> INFO: 9302100 events read in total (348158ms).
[10:05:26.492] <TB0> INFO: 9963350 events read in total (372695ms).
[10:05:51.886] <TB0> INFO: 10626150 events read in total (398089ms).
[10:06:16.561] <TB0> INFO: 11284500 events read in total (422764ms).
[10:06:42.086] <TB0> INFO: 11948950 events read in total (448289ms).
[10:07:07.763] <TB0> INFO: 12611300 events read in total (473966ms).
[10:07:32.704] <TB0> INFO: 13275550 events read in total (498907ms).
[10:07:58.181] <TB0> INFO: 13938150 events read in total (524384ms).
[10:08:23.026] <TB0> INFO: 14600550 events read in total (549229ms).
[10:08:48.569] <TB0> INFO: 15261000 events read in total (574772ms).
[10:09:13.927] <TB0> INFO: 15924000 events read in total (600130ms).
[10:09:39.271] <TB0> INFO: 16585400 events read in total (625474ms).
[10:10:03.425] <TB0> INFO: 17246600 events read in total (649628ms).
[10:10:27.347] <TB0> INFO: 17909150 events read in total (673550ms).
[10:10:51.293] <TB0> INFO: 18569150 events read in total (697496ms).
[10:11:14.987] <TB0> INFO: 19230900 events read in total (721190ms).
[10:11:38.935] <TB0> INFO: 19890700 events read in total (745138ms).
[10:12:02.615] <TB0> INFO: 20551250 events read in total (768818ms).
[10:12:26.169] <TB0> INFO: 21211400 events read in total (792372ms).
[10:12:50.087] <TB0> INFO: 21872850 events read in total (816290ms).
[10:13:13.617] <TB0> INFO: 22531100 events read in total (839820ms).
[10:13:37.224] <TB0> INFO: 23189900 events read in total (863427ms).
[10:14:00.927] <TB0> INFO: 23846550 events read in total (887130ms).
[10:14:24.444] <TB0> INFO: 24506150 events read in total (910647ms).
[10:14:48.221] <TB0> INFO: 25164950 events read in total (934424ms).
[10:15:12.176] <TB0> INFO: 25825200 events read in total (958379ms).
[10:15:36.256] <TB0> INFO: 26485100 events read in total (982459ms).
[10:15:59.931] <TB0> INFO: 27145450 events read in total (1006134ms).
[10:16:23.247] <TB0> INFO: 27805550 events read in total (1029450ms).
[10:16:46.965] <TB0> INFO: 28465200 events read in total (1053168ms).
[10:17:10.728] <TB0> INFO: 29125300 events read in total (1076931ms).
[10:17:34.086] <TB0> INFO: 29784750 events read in total (1100289ms).
[10:17:57.869] <TB0> INFO: 30445600 events read in total (1124072ms).
[10:18:21.961] <TB0> INFO: 31104200 events read in total (1148164ms).
[10:18:45.868] <TB0> INFO: 31766100 events read in total (1172071ms).
[10:19:09.474] <TB0> INFO: 32423650 events read in total (1195677ms).
[10:19:32.976] <TB0> INFO: 33085350 events read in total (1219179ms).
[10:19:56.910] <TB0> INFO: 33745450 events read in total (1243113ms).
[10:20:20.570] <TB0> INFO: 34406800 events read in total (1266773ms).
[10:20:44.272] <TB0> INFO: 35065800 events read in total (1290475ms).
[10:21:08.344] <TB0> INFO: 35727350 events read in total (1314547ms).
[10:21:31.961] <TB0> INFO: 36395900 events read in total (1338164ms).
[10:21:39.461] <TB0> INFO: 36608000 events read in total (1345664ms).
[10:21:39.544] <TB0> INFO: Test took 1346589ms.
[10:21:40.195] <TB0> INFO: dumping ASCII scurve output file: SCurveData
[10:21:42.535] <TB0> INFO: dumping ASCII scurve output file: SCurveData
[10:21:44.811] <TB0> INFO: dumping ASCII scurve output file: SCurveData
[10:21:46.514] <TB0> INFO: dumping ASCII scurve output file: SCurveData
[10:21:48.162] <TB0> INFO: dumping ASCII scurve output file: SCurveData
[10:21:49.602] <TB0> INFO: dumping ASCII scurve output file: SCurveData
[10:21:51.104] <TB0> INFO: dumping ASCII scurve output file: SCurveData
[10:21:52.591] <TB0> INFO: dumping ASCII scurve output file: SCurveData
[10:21:54.088] <TB0> INFO: dumping ASCII scurve output file: SCurveData
[10:21:55.582] <TB0> INFO: dumping ASCII scurve output file: SCurveData
[10:21:57.040] <TB0> INFO: dumping ASCII scurve output file: SCurveData
[10:21:59.054] <TB0> INFO: dumping ASCII scurve output file: SCurveData
[10:22:00.835] <TB0> INFO: dumping ASCII scurve output file: SCurveData
[10:22:02.654] <TB0> INFO: dumping ASCII scurve output file: SCurveData
[10:22:04.584] <TB0> INFO: dumping ASCII scurve output file: SCurveData
[10:22:06.254] <TB0> INFO: dumping ASCII scurve output file: SCurveData
[10:22:07.772] <TB0> INFO: PixTestScurves::scurves() done
[10:22:07.773] <TB0> INFO: Vcal mean: 110.61 119.24 118.95 120.42 111.92 109.70 115.97 117.97 114.89 122.30 113.34 111.76 115.35 114.66 126.65 121.40
[10:22:07.773] <TB0> INFO: Vcal RMS: 5.07 6.16 6.12 6.37 4.78 4.60 5.83 6.25 6.02 6.87 6.14 5.13 5.22 5.18 6.47 5.97
[10:22:07.773] <TB0> INFO: PixTestScurves::fullTest() done, duration: 1374 seconds
[10:22:07.773] <TB0> INFO: Decoding statistics:
[10:22:07.773] <TB0> INFO: General information:
[10:22:07.773] <TB0> INFO: 16bit words read: 0
[10:22:07.773] <TB0> INFO: valid events total: 0
[10:22:07.773] <TB0> INFO: empty events: 0
[10:22:07.773] <TB0> INFO: valid events with pixels: 0
[10:22:07.773] <TB0> INFO: valid pixel hits: 0
[10:22:07.773] <TB0> INFO: Event errors: 0
[10:22:07.773] <TB0> INFO: start marker: 0
[10:22:07.773] <TB0> INFO: stop marker: 0
[10:22:07.773] <TB0> INFO: overflow: 0
[10:22:07.773] <TB0> INFO: invalid 5bit words: 0
[10:22:07.773] <TB0> INFO: invalid XOR eye diagram: 0
[10:22:07.773] <TB0> INFO: frame (failed synchr.): 0
[10:22:07.773] <TB0> INFO: idle data (no TBM trl): 0
[10:22:07.773] <TB0> INFO: no data (only TBM hdr): 0
[10:22:07.773] <TB0> INFO: TBM errors: 0
[10:22:07.773] <TB0> INFO: flawed TBM headers: 0
[10:22:07.773] <TB0> INFO: flawed TBM trailers: 0
[10:22:07.773] <TB0> INFO: event ID mismatches: 0
[10:22:07.773] <TB0> INFO: ROC errors: 0
[10:22:07.773] <TB0> INFO: missing ROC header(s): 0
[10:22:07.773] <TB0> INFO: misplaced readback start: 0
[10:22:07.773] <TB0> INFO: Pixel decoding errors: 0
[10:22:07.773] <TB0> INFO: pixel data incomplete: 0
[10:22:07.773] <TB0> INFO: pixel address: 0
[10:22:07.773] <TB0> INFO: pulse height fill bit: 0
[10:22:07.773] <TB0> INFO: buffer corruption: 0
[10:22:07.848] <TB0> INFO: ######################################################################
[10:22:07.848] <TB0> INFO: PixTestTrim::doTest()
[10:22:07.848] <TB0> INFO: ######################################################################
[10:22:07.849] <TB0> INFO: ----------------------------------------------------------------------
[10:22:07.849] <TB0> INFO: PixTestTrim::trimTest() ntrig = 8, vcal = 35
[10:22:07.849] <TB0> INFO: ----------------------------------------------------------------------
[10:22:07.890] <TB0> INFO: ---> VthrComp thr map (minimal VthrComp)
[10:22:07.890] <TB0> INFO: ---> dac: vthrcomp name: TrimThr0 ntrig: 8 dacrange: 10 .. 160 (-1/-1) hits flags = 528 (plus default)
[10:22:07.902] <TB0> INFO: dacScan split into 1 runs with ntrig = 8
[10:22:07.902] <TB0> INFO: run 1 of 1
[10:22:08.138] <TB0> INFO: Expecting 5025280 events.
[10:22:39.993] <TB0> INFO: 821184 events read in total (31259ms).
[10:23:11.044] <TB0> INFO: 1640224 events read in total (62311ms).
[10:23:42.194] <TB0> INFO: 2459304 events read in total (93460ms).
[10:24:13.238] <TB0> INFO: 3273032 events read in total (124504ms).
[10:24:43.801] <TB0> INFO: 4084520 events read in total (155067ms).
[10:25:14.891] <TB0> INFO: 4895584 events read in total (186157ms).
[10:25:19.897] <TB0> INFO: 5025280 events read in total (191163ms).
[10:25:19.947] <TB0> INFO: Test took 192045ms.
[10:25:36.028] <TB0> INFO: ROC 0 VthrComp = 120
[10:25:36.028] <TB0> INFO: ROC 1 VthrComp = 129
[10:25:36.028] <TB0> INFO: ROC 2 VthrComp = 117
[10:25:36.028] <TB0> INFO: ROC 3 VthrComp = 124
[10:25:36.028] <TB0> INFO: ROC 4 VthrComp = 118
[10:25:36.028] <TB0> INFO: ROC 5 VthrComp = 122
[10:25:36.029] <TB0> INFO: ROC 6 VthrComp = 131
[10:25:36.029] <TB0> INFO: ROC 7 VthrComp = 124
[10:25:36.029] <TB0> INFO: ROC 8 VthrComp = 113
[10:25:36.029] <TB0> INFO: ROC 9 VthrComp = 126
[10:25:36.029] <TB0> INFO: ROC 10 VthrComp = 104
[10:25:36.029] <TB0> INFO: ROC 11 VthrComp = 116
[10:25:36.030] <TB0> INFO: ROC 12 VthrComp = 118
[10:25:36.030] <TB0> INFO: ROC 13 VthrComp = 118
[10:25:36.030] <TB0> INFO: ROC 14 VthrComp = 129
[10:25:36.030] <TB0> INFO: ROC 15 VthrComp = 128
[10:25:36.269] <TB0> INFO: Expecting 41600 events.
[10:25:39.797] <TB0> INFO: 41600 events read in total (2936ms).
[10:25:39.798] <TB0> INFO: Test took 3766ms.
[10:25:39.810] <TB0> INFO: ---> Vcal thr map (pixel with maximum Vcal thr)
[10:25:39.810] <TB0> INFO: ---> dac: vcal name: TrimThr1 ntrig: 8 dacrange: 10 .. 160 (-1/-1) hits flags = 528 (plus default)
[10:25:39.823] <TB0> INFO: dacScan split into 1 runs with ntrig = 8
[10:25:39.823] <TB0> INFO: run 1 of 1
[10:25:40.102] <TB0> INFO: Expecting 5025280 events.
[10:26:07.219] <TB0> INFO: 588728 events read in total (26526ms).
[10:26:33.736] <TB0> INFO: 1176568 events read in total (53043ms).
[10:27:00.291] <TB0> INFO: 1764768 events read in total (79598ms).
[10:27:27.084] <TB0> INFO: 2352984 events read in total (106391ms).
[10:27:53.934] <TB0> INFO: 2939928 events read in total (133241ms).
[10:28:20.431] <TB0> INFO: 3525720 events read in total (159738ms).
[10:28:47.063] <TB0> INFO: 4110416 events read in total (186370ms).
[10:29:13.494] <TB0> INFO: 4694968 events read in total (212801ms).
[10:29:28.488] <TB0> INFO: 5025280 events read in total (227795ms).
[10:29:28.559] <TB0> INFO: Test took 228736ms.
[10:29:50.033] <TB0> INFO: roc 0 with ID = 0 has maximal Vcal 60.1754 for pixel 14/12 mean/min/max = 45.6696/31.1002/60.239
[10:29:50.033] <TB0> INFO: roc 1 with ID = 1 has maximal Vcal 60.032 for pixel 21/8 mean/min/max = 45.6754/31.3091/60.0416
[10:29:50.034] <TB0> INFO: roc 2 with ID = 2 has maximal Vcal 66.265 for pixel 2/3 mean/min/max = 48.4434/30.1595/66.7272
[10:29:50.034] <TB0> INFO: roc 3 with ID = 3 has maximal Vcal 60.7725 for pixel 2/0 mean/min/max = 45.5564/30.2622/60.8506
[10:29:50.035] <TB0> INFO: roc 4 with ID = 4 has maximal Vcal 59.1752 for pixel 32/0 mean/min/max = 45.5587/31.8701/59.2474
[10:29:50.035] <TB0> INFO: roc 5 with ID = 5 has maximal Vcal 57.631 for pixel 19/1 mean/min/max = 44.7029/31.6017/57.8041
[10:29:50.036] <TB0> INFO: roc 6 with ID = 6 has maximal Vcal 57.8898 for pixel 2/12 mean/min/max = 44.5947/31.2383/57.951
[10:29:50.036] <TB0> INFO: roc 7 with ID = 7 has maximal Vcal 60.4326 for pixel 0/10 mean/min/max = 46.2049/31.9548/60.4551
[10:29:50.037] <TB0> INFO: roc 8 with ID = 8 has maximal Vcal 68.1066 for pixel 36/0 mean/min/max = 49.3491/30.5854/68.1128
[10:29:50.037] <TB0> INFO: roc 9 with ID = 9 has maximal Vcal 66.0426 for pixel 6/11 mean/min/max = 48.0139/29.7723/66.2555
[10:29:50.038] <TB0> INFO: roc 10 with ID = 10 has maximal Vcal 70.4082 for pixel 14/10 mean/min/max = 51.7744/33.1204/70.4284
[10:29:50.038] <TB0> INFO: roc 11 with ID = 11 has maximal Vcal 59.9323 for pixel 0/16 mean/min/max = 45.6448/31.1669/60.1227
[10:29:50.039] <TB0> INFO: roc 12 with ID = 12 has maximal Vcal 61.9138 for pixel 50/12 mean/min/max = 46.6967/31.4197/61.9737
[10:29:50.040] <TB0> INFO: roc 13 with ID = 13 has maximal Vcal 60.2248 for pixel 0/4 mean/min/max = 45.7592/31.2619/60.2565
[10:29:50.040] <TB0> INFO: roc 14 with ID = 14 has maximal Vcal 67.3042 for pixel 10/2 mean/min/max = 48.7145/29.4455/67.9835
[10:29:50.041] <TB0> INFO: roc 15 with ID = 15 has maximal Vcal 60.7538 for pixel 22/5 mean/min/max = 45.9537/31.0113/60.896
[10:29:50.041] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[10:29:50.130] <TB0> INFO: Expecting 411648 events.
[10:29:59.870] <TB0> INFO: 411648 events read in total (9148ms).
[10:29:59.877] <TB0> INFO: Expecting 411648 events.
[10:30:09.541] <TB0> INFO: 411648 events read in total (9260ms).
[10:30:09.551] <TB0> INFO: Expecting 411648 events.
[10:30:19.048] <TB0> INFO: 411648 events read in total (9093ms).
[10:30:19.061] <TB0> INFO: Expecting 411648 events.
[10:30:28.562] <TB0> INFO: 411648 events read in total (9098ms).
[10:30:28.577] <TB0> INFO: Expecting 411648 events.
[10:30:38.220] <TB0> INFO: 411648 events read in total (9239ms).
[10:30:38.245] <TB0> INFO: Expecting 411648 events.
[10:30:47.869] <TB0> INFO: 411648 events read in total (9221ms).
[10:30:47.891] <TB0> INFO: Expecting 411648 events.
[10:30:57.421] <TB0> INFO: 411648 events read in total (9127ms).
[10:30:57.454] <TB0> INFO: Expecting 411648 events.
[10:31:06.901] <TB0> INFO: 411648 events read in total (9044ms).
[10:31:06.928] <TB0> INFO: Expecting 411648 events.
[10:31:16.636] <TB0> INFO: 411648 events read in total (9305ms).
[10:31:16.678] <TB0> INFO: Expecting 411648 events.
[10:31:26.277] <TB0> INFO: 411648 events read in total (9196ms).
[10:31:26.323] <TB0> INFO: Expecting 411648 events.
[10:31:35.894] <TB0> INFO: 411648 events read in total (9168ms).
[10:31:35.930] <TB0> INFO: Expecting 411648 events.
[10:31:45.570] <TB0> INFO: 411648 events read in total (9237ms).
[10:31:45.608] <TB0> INFO: Expecting 411648 events.
[10:31:55.243] <TB0> INFO: 411648 events read in total (9232ms).
[10:31:55.303] <TB0> INFO: Expecting 411648 events.
[10:32:04.840] <TB0> INFO: 411648 events read in total (9134ms).
[10:32:04.886] <TB0> INFO: Expecting 411648 events.
[10:32:14.496] <TB0> INFO: 411648 events read in total (9207ms).
[10:32:14.546] <TB0> INFO: Expecting 411648 events.
[10:32:23.942] <TB0> INFO: 411648 events read in total (8993ms).
[10:32:23.001] <TB0> INFO: Test took 153960ms.
[10:32:24.779] <TB0> INFO: ---> dac: vcal name: TrimThr2 ntrig: 8 dacrange: 0 .. 150 (-1/-1) hits flags = 528 (plus default)
[10:32:24.792] <TB0> INFO: dacScan split into 1 runs with ntrig = 8
[10:32:24.792] <TB0> INFO: run 1 of 1
[10:32:25.027] <TB0> INFO: Expecting 5025280 events.
[10:32:52.264] <TB0> INFO: 585712 events read in total (26646ms).
[10:33:18.696] <TB0> INFO: 1170928 events read in total (53078ms).
[10:33:45.591] <TB0> INFO: 1755512 events read in total (79973ms).
[10:34:12.416] <TB0> INFO: 2338680 events read in total (106798ms).
[10:34:39.270] <TB0> INFO: 2922560 events read in total (133652ms).
[10:35:05.949] <TB0> INFO: 3505216 events read in total (160331ms).
[10:35:32.622] <TB0> INFO: 4087064 events read in total (187004ms).
[10:35:59.101] <TB0> INFO: 4668624 events read in total (213483ms).
[10:36:15.893] <TB0> INFO: 5025280 events read in total (230275ms).
[10:36:15.992] <TB0> INFO: Test took 231201ms.
[10:36:37.190] <TB0> INFO: ---> TrimStepCorr4 extremal thresholds: 0.916261 .. 147.179506
[10:36:37.427] <TB0> INFO: Expecting 208000 events.
[10:36:47.253] <TB0> INFO: 208000 events read in total (9234ms).
[10:36:47.255] <TB0> INFO: Test took 10064ms.
[10:36:47.303] <TB0> INFO: ---> dac: vcal name: trimStepCorr4 ntrig: 8 dacrange: 0 .. 157 (-1/-1) hits flags = 528 (plus default)
[10:36:47.313] <TB0> INFO: dacScan split into 1 runs with ntrig = 8
[10:36:47.313] <TB0> INFO: run 1 of 1
[10:36:47.608] <TB0> INFO: Expecting 5258240 events.
[10:37:14.975] <TB0> INFO: 584368 events read in total (26776ms).
[10:37:41.323] <TB0> INFO: 1169240 events read in total (53124ms).
[10:38:08.091] <TB0> INFO: 1753856 events read in total (79892ms).
[10:38:34.823] <TB0> INFO: 2338840 events read in total (106624ms).
[10:39:01.311] <TB0> INFO: 2923488 events read in total (133112ms).
[10:39:27.772] <TB0> INFO: 3507464 events read in total (159573ms).
[10:39:54.559] <TB0> INFO: 4091232 events read in total (186360ms).
[10:40:21.610] <TB0> INFO: 4674480 events read in total (213411ms).
[10:40:47.877] <TB0> INFO: 5258240 events read in total (239678ms).
[10:40:47.981] <TB0> INFO: Test took 240668ms.
[10:41:09.382] <TB0> INFO: ---> TrimStepCorr2 extremal thresholds: 27.009043 .. 45.698724
[10:41:09.619] <TB0> INFO: Expecting 208000 events.
[10:41:20.109] <TB0> INFO: 208000 events read in total (9898ms).
[10:41:20.110] <TB0> INFO: Test took 10727ms.
[10:41:20.190] <TB0> INFO: ---> dac: vcal name: trimStepCorr2 ntrig: 8 dacrange: 17 .. 55 (-1/-1) hits flags = 528 (plus default)
[10:41:20.204] <TB0> INFO: dacScan split into 1 runs with ntrig = 8
[10:41:20.204] <TB0> INFO: run 1 of 1
[10:41:20.483] <TB0> INFO: Expecting 1297920 events.
[10:41:50.010] <TB0> INFO: 658192 events read in total (28936ms).
[10:42:17.513] <TB0> INFO: 1297920 events read in total (56439ms).
[10:42:17.544] <TB0> INFO: Test took 57341ms.
[10:42:29.623] <TB0> INFO: ---> TrimStepCorr1a extremal thresholds: 25.721100 .. 47.819645
[10:42:29.860] <TB0> INFO: Expecting 208000 events.
[10:42:39.955] <TB0> INFO: 208000 events read in total (9504ms).
[10:42:39.956] <TB0> INFO: Test took 10332ms.
[10:42:39.002] <TB0> INFO: ---> dac: vcal name: trimStepCorr1a ntrig: 8 dacrange: 15 .. 57 (-1/-1) hits flags = 528 (plus default)
[10:42:40.015] <TB0> INFO: dacScan split into 1 runs with ntrig = 8
[10:42:40.015] <TB0> INFO: run 1 of 1
[10:42:40.312] <TB0> INFO: Expecting 1431040 events.
[10:43:09.400] <TB0> INFO: 659640 events read in total (28497ms).
[10:43:38.318] <TB0> INFO: 1318208 events read in total (57416ms).
[10:43:43.617] <TB0> INFO: 1431040 events read in total (62714ms).
[10:43:43.645] <TB0> INFO: Test took 63630ms.
[10:43:56.607] <TB0> INFO: ---> TrimStepCorr1b extremal thresholds: 23.818618 .. 48.047943
[10:43:56.844] <TB0> INFO: Expecting 208000 events.
[10:44:06.604] <TB0> INFO: 208000 events read in total (9168ms).
[10:44:06.604] <TB0> INFO: Test took 9997ms.
[10:44:06.687] <TB0> INFO: ---> dac: vcal name: trimStepCorr1b ntrig: 8 dacrange: 13 .. 58 (-1/-1) hits flags = 528 (plus default)
[10:44:06.701] <TB0> INFO: dacScan split into 1 runs with ntrig = 8
[10:44:06.701] <TB0> INFO: run 1 of 1
[10:44:06.002] <TB0> INFO: Expecting 1530880 events.
[10:44:35.973] <TB0> INFO: 663032 events read in total (28380ms).
[10:45:04.723] <TB0> INFO: 1325904 events read in total (57131ms).
[10:45:13.806] <TB0> INFO: 1530880 events read in total (66213ms).
[10:45:13.833] <TB0> INFO: Test took 67133ms.
[10:45:25.698] <TB0> INFO: ---> TrimThrFinal extremal thresholds: 15 .. 55
[10:45:25.698] <TB0> INFO: ---> dac: vcal name: TrimThrFinal ntrig: 8 dacrange: 15 .. 55 (-1/-1) hits flags = 528 (plus default)
[10:45:25.710] <TB0> INFO: dacScan split into 1 runs with ntrig = 8
[10:45:25.711] <TB0> INFO: run 1 of 1
[10:45:25.949] <TB0> INFO: Expecting 1364480 events.
[10:45:55.093] <TB0> INFO: 667192 events read in total (28552ms).
[10:46:23.117] <TB0> INFO: 1333872 events read in total (56577ms).
[10:46:24.937] <TB0> INFO: 1364480 events read in total (58397ms).
[10:46:24.972] <TB0> INFO: Test took 59261ms.
[10:46:36.547] <TB0> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1032_FullQualification_2016-10-21_09h47m_1477036052//000_Fulltest_m20//dacParameters35_C0.dat
[10:46:36.547] <TB0> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1032_FullQualification_2016-10-21_09h47m_1477036052//000_Fulltest_m20//dacParameters35_C1.dat
[10:46:36.547] <TB0> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1032_FullQualification_2016-10-21_09h47m_1477036052//000_Fulltest_m20//dacParameters35_C2.dat
[10:46:36.547] <TB0> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1032_FullQualification_2016-10-21_09h47m_1477036052//000_Fulltest_m20//dacParameters35_C3.dat
[10:46:36.547] <TB0> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1032_FullQualification_2016-10-21_09h47m_1477036052//000_Fulltest_m20//dacParameters35_C4.dat
[10:46:36.547] <TB0> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1032_FullQualification_2016-10-21_09h47m_1477036052//000_Fulltest_m20//dacParameters35_C5.dat
[10:46:36.547] <TB0> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1032_FullQualification_2016-10-21_09h47m_1477036052//000_Fulltest_m20//dacParameters35_C6.dat
[10:46:36.547] <TB0> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1032_FullQualification_2016-10-21_09h47m_1477036052//000_Fulltest_m20//dacParameters35_C7.dat
[10:46:36.547] <TB0> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1032_FullQualification_2016-10-21_09h47m_1477036052//000_Fulltest_m20//dacParameters35_C8.dat
[10:46:36.547] <TB0> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1032_FullQualification_2016-10-21_09h47m_1477036052//000_Fulltest_m20//dacParameters35_C9.dat
[10:46:36.548] <TB0> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1032_FullQualification_2016-10-21_09h47m_1477036052//000_Fulltest_m20//dacParameters35_C10.dat
[10:46:36.548] <TB0> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1032_FullQualification_2016-10-21_09h47m_1477036052//000_Fulltest_m20//dacParameters35_C11.dat
[10:46:36.548] <TB0> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1032_FullQualification_2016-10-21_09h47m_1477036052//000_Fulltest_m20//dacParameters35_C12.dat
[10:46:36.548] <TB0> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1032_FullQualification_2016-10-21_09h47m_1477036052//000_Fulltest_m20//dacParameters35_C13.dat
[10:46:36.548] <TB0> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1032_FullQualification_2016-10-21_09h47m_1477036052//000_Fulltest_m20//dacParameters35_C14.dat
[10:46:36.548] <TB0> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1032_FullQualification_2016-10-21_09h47m_1477036052//000_Fulltest_m20//dacParameters35_C15.dat
[10:46:36.548] <TB0> INFO: write trim parameters into /home/l_tester/david/FullQualification/data/M1032_FullQualification_2016-10-21_09h47m_1477036052//000_Fulltest_m20//trimParameters35_C0.dat
[10:46:36.555] <TB0> INFO: write trim parameters into /home/l_tester/david/FullQualification/data/M1032_FullQualification_2016-10-21_09h47m_1477036052//000_Fulltest_m20//trimParameters35_C1.dat
[10:46:36.561] <TB0> INFO: write trim parameters into /home/l_tester/david/FullQualification/data/M1032_FullQualification_2016-10-21_09h47m_1477036052//000_Fulltest_m20//trimParameters35_C2.dat
[10:46:36.568] <TB0> INFO: write trim parameters into /home/l_tester/david/FullQualification/data/M1032_FullQualification_2016-10-21_09h47m_1477036052//000_Fulltest_m20//trimParameters35_C3.dat
[10:46:36.575] <TB0> INFO: write trim parameters into /home/l_tester/david/FullQualification/data/M1032_FullQualification_2016-10-21_09h47m_1477036052//000_Fulltest_m20//trimParameters35_C4.dat
[10:46:36.582] <TB0> INFO: write trim parameters into /home/l_tester/david/FullQualification/data/M1032_FullQualification_2016-10-21_09h47m_1477036052//000_Fulltest_m20//trimParameters35_C5.dat
[10:46:36.588] <TB0> INFO: write trim parameters into /home/l_tester/david/FullQualification/data/M1032_FullQualification_2016-10-21_09h47m_1477036052//000_Fulltest_m20//trimParameters35_C6.dat
[10:46:36.595] <TB0> INFO: write trim parameters into /home/l_tester/david/FullQualification/data/M1032_FullQualification_2016-10-21_09h47m_1477036052//000_Fulltest_m20//trimParameters35_C7.dat
[10:46:36.601] <TB0> INFO: write trim parameters into /home/l_tester/david/FullQualification/data/M1032_FullQualification_2016-10-21_09h47m_1477036052//000_Fulltest_m20//trimParameters35_C8.dat
[10:46:36.608] <TB0> INFO: write trim parameters into /home/l_tester/david/FullQualification/data/M1032_FullQualification_2016-10-21_09h47m_1477036052//000_Fulltest_m20//trimParameters35_C9.dat
[10:46:36.615] <TB0> INFO: write trim parameters into /home/l_tester/david/FullQualification/data/M1032_FullQualification_2016-10-21_09h47m_1477036052//000_Fulltest_m20//trimParameters35_C10.dat
[10:46:36.621] <TB0> INFO: write trim parameters into /home/l_tester/david/FullQualification/data/M1032_FullQualification_2016-10-21_09h47m_1477036052//000_Fulltest_m20//trimParameters35_C11.dat
[10:46:36.628] <TB0> INFO: write trim parameters into /home/l_tester/david/FullQualification/data/M1032_FullQualification_2016-10-21_09h47m_1477036052//000_Fulltest_m20//trimParameters35_C12.dat
[10:46:36.635] <TB0> INFO: write trim parameters into /home/l_tester/david/FullQualification/data/M1032_FullQualification_2016-10-21_09h47m_1477036052//000_Fulltest_m20//trimParameters35_C13.dat
[10:46:36.641] <TB0> INFO: write trim parameters into /home/l_tester/david/FullQualification/data/M1032_FullQualification_2016-10-21_09h47m_1477036052//000_Fulltest_m20//trimParameters35_C14.dat
[10:46:36.648] <TB0> INFO: write trim parameters into /home/l_tester/david/FullQualification/data/M1032_FullQualification_2016-10-21_09h47m_1477036052//000_Fulltest_m20//trimParameters35_C15.dat
[10:46:36.655] <TB0> INFO: PixTestTrim::trimTest() done
[10:46:36.655] <TB0> INFO: vtrim: 129 135 140 116 114 121 120 141 150 165 142 116 131 129 157 131
[10:46:36.655] <TB0> INFO: vthrcomp: 120 129 117 124 118 122 131 124 113 126 104 116 118 118 129 128
[10:46:36.655] <TB0> INFO: vcal mean: 34.97 34.94 34.97 34.93 34.94 34.97 34.88 34.94 35.13 34.97 35.43 34.96 34.99 34.95 35.00 35.22
[10:46:36.655] <TB0> INFO: vcal RMS: 1.02 1.03 1.19 1.15 1.01 0.94 1.01 1.18 1.29 1.15 1.69 1.00 1.08 1.12 1.18 1.27
[10:46:36.655] <TB0> INFO: bits mean: 10.10 9.51 9.56 9.55 9.26 9.58 9.65 9.34 9.65 9.62 9.12 9.34 9.55 9.64 9.67 9.90
[10:46:36.655] <TB0> INFO: bits RMS: 2.54 2.74 2.75 2.90 2.85 2.79 2.78 2.76 2.66 2.78 2.55 2.84 2.68 2.73 2.79 2.64
[10:46:36.663] <TB0> INFO: ----------------------------------------------------------------------
[10:46:36.663] <TB0> INFO: PixTestTrim::trimBitTest() ntrig = 5, vtrims = 254 126 63 32
[10:46:36.663] <TB0> INFO: ----------------------------------------------------------------------
[10:46:36.665] <TB0> INFO: ---> dac: Vcal name: TrimBitsThr0 ntrig: 5 dacrange: 0 .. 199 (-1/-1) hits flags = 528 (plus default)
[10:46:36.680] <TB0> INFO: dacScan split into 1 runs with ntrig = 5
[10:46:36.680] <TB0> INFO: run 1 of 1
[10:46:36.917] <TB0> INFO: Expecting 4160000 events.
[10:47:10.915] <TB0> INFO: 748185 events read in total (33406ms).
[10:47:43.832] <TB0> INFO: 1492030 events read in total (66323ms).
[10:48:16.899] <TB0> INFO: 2234120 events read in total (99390ms).
[10:48:49.726] <TB0> INFO: 2972445 events read in total (132217ms).
[10:49:22.056] <TB0> INFO: 3709895 events read in total (164547ms).
[10:49:41.816] <TB0> INFO: 4160000 events read in total (184307ms).
[10:49:41.870] <TB0> INFO: Test took 185190ms.
[10:50:03.889] <TB0> INFO: ---> dac: Vcal name: TrimThr_trim14 ntrig: 5 dacrange: 0 .. 200 (-1/-1) hits flags = 528 (plus default)
[10:50:03.902] <TB0> INFO: dacScan split into 1 runs with ntrig = 5
[10:50:03.902] <TB0> INFO: run 1 of 1
[10:50:04.137] <TB0> INFO: Expecting 4180800 events.
[10:50:37.183] <TB0> INFO: 722095 events read in total (32454ms).
[10:51:09.678] <TB0> INFO: 1440050 events read in total (64949ms).
[10:51:42.249] <TB0> INFO: 2157430 events read in total (97520ms).
[10:52:14.805] <TB0> INFO: 2870125 events read in total (130076ms).
[10:52:47.540] <TB0> INFO: 3582910 events read in total (162811ms).
[10:53:14.572] <TB0> INFO: 4180800 events read in total (189843ms).
[10:53:14.652] <TB0> INFO: Test took 190750ms.
[10:53:37.488] <TB0> INFO: ---> dac: Vcal name: TrimThr_trim13 ntrig: 5 dacrange: 0 .. 185 (-1/-1) hits flags = 528 (plus default)
[10:53:37.501] <TB0> INFO: dacScan split into 1 runs with ntrig = 5
[10:53:37.502] <TB0> INFO: run 1 of 1
[10:53:37.773] <TB0> INFO: Expecting 3868800 events.
[10:54:10.796] <TB0> INFO: 743335 events read in total (32431ms).
[10:54:43.478] <TB0> INFO: 1483340 events read in total (65113ms).
[10:55:16.160] <TB0> INFO: 2220280 events read in total (97795ms).
[10:55:48.598] <TB0> INFO: 2952755 events read in total (130233ms).
[10:56:21.488] <TB0> INFO: 3686300 events read in total (163123ms).
[10:56:30.015] <TB0> INFO: 3868800 events read in total (171650ms).
[10:56:30.082] <TB0> INFO: Test took 172580ms.
[10:56:53.268] <TB0> INFO: ---> dac: Vcal name: TrimThr_trim11 ntrig: 5 dacrange: 0 .. 184 (-1/-1) hits flags = 528 (plus default)
[10:56:53.280] <TB0> INFO: dacScan split into 1 runs with ntrig = 5
[10:56:53.280] <TB0> INFO: run 1 of 1
[10:56:53.516] <TB0> INFO: Expecting 3848000 events.
[10:57:26.850] <TB0> INFO: 745540 events read in total (32742ms).
[10:57:59.308] <TB0> INFO: 1487630 events read in total (65200ms).
[10:58:31.948] <TB0> INFO: 2226165 events read in total (97840ms).
[10:59:04.949] <TB0> INFO: 2960185 events read in total (130841ms).
[10:59:37.976] <TB0> INFO: 3695210 events read in total (163868ms).
[10:59:45.017] <TB0> INFO: 3848000 events read in total (170909ms).
[10:59:45.066] <TB0> INFO: Test took 171785ms.
[11:00:10.050] <TB0> INFO: ---> dac: Vcal name: TrimThr_trim7 ntrig: 5 dacrange: 0 .. 185 (-1/-1) hits flags = 528 (plus default)
[11:00:10.064] <TB0> INFO: dacScan split into 1 runs with ntrig = 5
[11:00:10.064] <TB0> INFO: run 1 of 1
[11:00:10.324] <TB0> INFO: Expecting 3868800 events.
[11:00:43.853] <TB0> INFO: 743975 events read in total (32937ms).
[11:01:16.789] <TB0> INFO: 1484535 events read in total (65873ms).
[11:01:49.610] <TB0> INFO: 2221855 events read in total (98695ms).
[11:02:22.550] <TB0> INFO: 2954870 events read in total (131634ms).
[11:02:55.231] <TB0> INFO: 3688985 events read in total (164315ms).
[11:03:03.514] <TB0> INFO: 3868800 events read in total (172598ms).
[11:03:03.564] <TB0> INFO: Test took 173500ms.
[11:03:25.900] <TB0> INFO: PixTestTrim::trimBitTest() done
[11:03:25.902] <TB0> INFO: PixTestTrim::doTest() done, duration: 2478 seconds
[11:03:25.902] <TB0> INFO: Decoding statistics:
[11:03:25.902] <TB0> INFO: General information:
[11:03:25.902] <TB0> INFO: 16bit words read: 0
[11:03:25.902] <TB0> INFO: valid events total: 0
[11:03:25.902] <TB0> INFO: empty events: 0
[11:03:25.902] <TB0> INFO: valid events with pixels: 0
[11:03:25.902] <TB0> INFO: valid pixel hits: 0
[11:03:25.902] <TB0> INFO: Event errors: 0
[11:03:25.902] <TB0> INFO: start marker: 0
[11:03:25.902] <TB0> INFO: stop marker: 0
[11:03:25.902] <TB0> INFO: overflow: 0
[11:03:25.902] <TB0> INFO: invalid 5bit words: 0
[11:03:25.902] <TB0> INFO: invalid XOR eye diagram: 0
[11:03:25.902] <TB0> INFO: frame (failed synchr.): 0
[11:03:25.902] <TB0> INFO: idle data (no TBM trl): 0
[11:03:25.902] <TB0> INFO: no data (only TBM hdr): 0
[11:03:25.902] <TB0> INFO: TBM errors: 0
[11:03:25.902] <TB0> INFO: flawed TBM headers: 0
[11:03:25.902] <TB0> INFO: flawed TBM trailers: 0
[11:03:25.902] <TB0> INFO: event ID mismatches: 0
[11:03:25.902] <TB0> INFO: ROC errors: 0
[11:03:25.902] <TB0> INFO: missing ROC header(s): 0
[11:03:25.902] <TB0> INFO: misplaced readback start: 0
[11:03:25.902] <TB0> INFO: Pixel decoding errors: 0
[11:03:25.902] <TB0> INFO: pixel data incomplete: 0
[11:03:25.903] <TB0> INFO: pixel address: 0
[11:03:25.903] <TB0> INFO: pulse height fill bit: 0
[11:03:25.903] <TB0> INFO: buffer corruption: 0
[11:03:26.530] <TB0> INFO: ######################################################################
[11:03:26.530] <TB0> INFO: PixTestPhOptimization::doTest() Ntrig = 10
[11:03:26.530] <TB0> INFO: ######################################################################
[11:03:26.768] <TB0> INFO: Expecting 41600 events.
[11:03:30.240] <TB0> INFO: 41600 events read in total (2880ms).
[11:03:30.241] <TB0> INFO: Test took 3710ms.
[11:03:30.737] <TB0> INFO: Expecting 41600 events.
[11:03:34.389] <TB0> INFO: 41600 events read in total (3060ms).
[11:03:34.389] <TB0> INFO: Test took 3943ms.
[11:03:34.677] <TB0> INFO: Expecting 41600 events.
[11:03:38.432] <TB0> INFO: 41600 events read in total (3163ms).
[11:03:38.432] <TB0> INFO: Test took 4019ms.
[11:03:38.721] <TB0> INFO: Expecting 41600 events.
[11:03:42.387] <TB0> INFO: 41600 events read in total (3074ms).
[11:03:42.388] <TB0> INFO: Test took 3931ms.
[11:03:42.676] <TB0> INFO: Expecting 41600 events.
[11:03:46.569] <TB0> INFO: 41600 events read in total (3301ms).
[11:03:46.570] <TB0> INFO: Test took 4159ms.
[11:03:46.859] <TB0> INFO: Expecting 41600 events.
[11:03:50.434] <TB0> INFO: 41600 events read in total (2983ms).
[11:03:50.435] <TB0> INFO: Test took 3841ms.
[11:03:50.724] <TB0> INFO: Expecting 41600 events.
[11:03:54.264] <TB0> INFO: 41600 events read in total (2948ms).
[11:03:54.264] <TB0> INFO: Test took 3805ms.
[11:03:54.585] <TB0> INFO: Expecting 41600 events.
[11:03:58.215] <TB0> INFO: 41600 events read in total (3038ms).
[11:03:58.216] <TB0> INFO: Test took 3928ms.
[11:03:58.549] <TB0> INFO: Expecting 41600 events.
[11:04:02.158] <TB0> INFO: 41600 events read in total (3018ms).
[11:04:02.159] <TB0> INFO: Test took 3914ms.
[11:04:02.482] <TB0> INFO: Expecting 41600 events.
[11:04:06.041] <TB0> INFO: 41600 events read in total (2967ms).
[11:04:06.041] <TB0> INFO: Test took 3858ms.
[11:04:06.330] <TB0> INFO: Expecting 41600 events.
[11:04:09.888] <TB0> INFO: 41600 events read in total (2966ms).
[11:04:09.889] <TB0> INFO: Test took 3824ms.
[11:04:10.181] <TB0> INFO: Expecting 41600 events.
[11:04:14.015] <TB0> INFO: 41600 events read in total (3242ms).
[11:04:14.016] <TB0> INFO: Test took 4100ms.
[11:04:14.308] <TB0> INFO: Expecting 41600 events.
[11:04:17.989] <TB0> INFO: 41600 events read in total (3089ms).
[11:04:17.990] <TB0> INFO: Test took 3947ms.
[11:04:18.280] <TB0> INFO: Expecting 41600 events.
[11:04:22.091] <TB0> INFO: 41600 events read in total (3219ms).
[11:04:22.092] <TB0> INFO: Test took 4078ms.
[11:04:22.389] <TB0> INFO: Expecting 41600 events.
[11:04:26.033] <TB0> INFO: 41600 events read in total (3052ms).
[11:04:26.033] <TB0> INFO: Test took 3917ms.
[11:04:26.322] <TB0> INFO: Expecting 41600 events.
[11:04:29.953] <TB0> INFO: 41600 events read in total (3040ms).
[11:04:29.953] <TB0> INFO: Test took 3896ms.
[11:04:30.243] <TB0> INFO: Expecting 41600 events.
[11:04:33.898] <TB0> INFO: 41600 events read in total (3064ms).
[11:04:33.898] <TB0> INFO: Test took 3921ms.
[11:04:34.187] <TB0> INFO: Expecting 41600 events.
[11:04:37.891] <TB0> INFO: 41600 events read in total (3112ms).
[11:04:37.892] <TB0> INFO: Test took 3970ms.
[11:04:38.219] <TB0> INFO: Expecting 41600 events.
[11:04:42.060] <TB0> INFO: 41600 events read in total (3250ms).
[11:04:42.061] <TB0> INFO: Test took 4145ms.
[11:04:42.388] <TB0> INFO: Expecting 41600 events.
[11:04:45.948] <TB0> INFO: 41600 events read in total (2968ms).
[11:04:45.948] <TB0> INFO: Test took 3859ms.
[11:04:46.237] <TB0> INFO: Expecting 41600 events.
[11:04:49.933] <TB0> INFO: 41600 events read in total (3105ms).
[11:04:49.933] <TB0> INFO: Test took 3961ms.
[11:04:50.226] <TB0> INFO: Expecting 41600 events.
[11:04:53.967] <TB0> INFO: 41600 events read in total (3149ms).
[11:04:53.968] <TB0> INFO: Test took 4007ms.
[11:04:54.290] <TB0> INFO: Expecting 41600 events.
[11:04:57.889] <TB0> INFO: 41600 events read in total (3007ms).
[11:04:57.890] <TB0> INFO: Test took 3898ms.
[11:04:58.219] <TB0> INFO: Expecting 41600 events.
[11:05:01.766] <TB0> INFO: 41600 events read in total (2956ms).
[11:05:01.767] <TB0> INFO: Test took 3853ms.
[11:05:02.055] <TB0> INFO: Expecting 41600 events.
[11:05:05.590] <TB0> INFO: 41600 events read in total (2943ms).
[11:05:05.590] <TB0> INFO: Test took 3800ms.
[11:05:05.883] <TB0> INFO: Expecting 41600 events.
[11:05:09.434] <TB0> INFO: 41600 events read in total (2959ms).
[11:05:09.435] <TB0> INFO: Test took 3817ms.
[11:05:09.723] <TB0> INFO: Expecting 41600 events.
[11:05:13.288] <TB0> INFO: 41600 events read in total (2973ms).
[11:05:13.289] <TB0> INFO: Test took 3830ms.
[11:05:13.577] <TB0> INFO: Expecting 41600 events.
[11:05:17.162] <TB0> INFO: 41600 events read in total (2993ms).
[11:05:17.163] <TB0> INFO: Test took 3850ms.
[11:05:17.455] <TB0> INFO: Expecting 41600 events.
[11:05:21.257] <TB0> INFO: 41600 events read in total (3210ms).
[11:05:21.258] <TB0> INFO: Test took 4068ms.
[11:05:21.547] <TB0> INFO: Expecting 41600 events.
[11:05:25.112] <TB0> INFO: 41600 events read in total (2973ms).
[11:05:25.113] <TB0> INFO: Test took 3831ms.
[11:05:25.421] <TB0> INFO: Expecting 41600 events.
[11:05:29.251] <TB0> INFO: 41600 events read in total (3238ms).
[11:05:29.252] <TB0> INFO: Test took 4115ms.
[11:05:29.541] <TB0> INFO: Expecting 41600 events.
[11:05:33.101] <TB0> INFO: 41600 events read in total (2969ms).
[11:05:33.102] <TB0> INFO: Test took 3826ms.
[11:05:33.393] <TB0> INFO: Expecting 41600 events.
[11:05:36.960] <TB0> INFO: 41600 events read in total (2976ms).
[11:05:36.961] <TB0> INFO: Test took 3833ms.
[11:05:37.249] <TB0> INFO: Expecting 41600 events.
[11:05:40.810] <TB0> INFO: 41600 events read in total (2969ms).
[11:05:40.810] <TB0> INFO: Test took 3825ms.
[11:05:41.128] <TB0> INFO: Expecting 41600 events.
[11:05:44.802] <TB0> INFO: 41600 events read in total (3082ms).
[11:05:44.802] <TB0> INFO: Test took 3968ms.
[11:05:45.094] <TB0> INFO: Expecting 41600 events.
[11:05:48.877] <TB0> INFO: 41600 events read in total (3192ms).
[11:05:48.877] <TB0> INFO: Test took 4050ms.
[11:05:49.166] <TB0> INFO: Expecting 41600 events.
[11:05:52.723] <TB0> INFO: 41600 events read in total (2965ms).
[11:05:52.724] <TB0> INFO: Test took 3823ms.
[11:05:53.013] <TB0> INFO: Expecting 41600 events.
[11:05:56.552] <TB0> INFO: 41600 events read in total (2948ms).
[11:05:56.552] <TB0> INFO: Test took 3804ms.
[11:05:56.864] <TB0> INFO: Expecting 41600 events.
[11:06:00.564] <TB0> INFO: 41600 events read in total (3108ms).
[11:06:00.564] <TB0> INFO: Test took 3988ms.
[11:06:00.873] <TB0> INFO: Expecting 41600 events.
[11:06:04.563] <TB0> INFO: 41600 events read in total (3099ms).
[11:06:04.563] <TB0> INFO: Test took 3971ms.
[11:06:04.852] <TB0> INFO: Expecting 41600 events.
[11:06:08.502] <TB0> INFO: 41600 events read in total (3058ms).
[11:06:08.503] <TB0> INFO: Test took 3916ms.
[11:06:08.792] <TB0> INFO: Expecting 41600 events.
[11:06:12.392] <TB0> INFO: 41600 events read in total (3009ms).
[11:06:12.393] <TB0> INFO: Test took 3866ms.
[11:06:12.685] <TB0> INFO: Expecting 41600 events.
[11:06:16.319] <TB0> INFO: 41600 events read in total (3042ms).
[11:06:16.320] <TB0> INFO: Test took 3900ms.
[11:06:16.612] <TB0> INFO: Expecting 41600 events.
[11:06:20.167] <TB0> INFO: 41600 events read in total (2964ms).
[11:06:20.168] <TB0> INFO: Test took 3821ms.
[11:06:20.457] <TB0> INFO: Expecting 41600 events.
[11:06:24.032] <TB0> INFO: 41600 events read in total (2984ms).
[11:06:24.033] <TB0> INFO: Test took 3841ms.
[11:06:24.321] <TB0> INFO: Expecting 41600 events.
[11:06:27.989] <TB0> INFO: 41600 events read in total (3076ms).
[11:06:27.989] <TB0> INFO: Test took 3932ms.
[11:06:28.278] <TB0> INFO: Expecting 41600 events.
[11:06:31.873] <TB0> INFO: 41600 events read in total (3003ms).
[11:06:31.873] <TB0> INFO: Test took 3860ms.
[11:06:32.182] <TB0> INFO: Expecting 41600 events.
[11:06:35.710] <TB0> INFO: 41600 events read in total (2936ms).
[11:06:35.711] <TB0> INFO: Test took 3813ms.
[11:06:35.999] <TB0> INFO: Expecting 41600 events.
[11:06:39.576] <TB0> INFO: 41600 events read in total (2985ms).
[11:06:39.577] <TB0> INFO: Test took 3842ms.
[11:06:39.866] <TB0> INFO: Expecting 41600 events.
[11:06:43.564] <TB0> INFO: 41600 events read in total (3107ms).
[11:06:43.565] <TB0> INFO: Test took 3964ms.
[11:06:43.858] <TB0> INFO: Expecting 41600 events.
[11:06:47.552] <TB0> INFO: 41600 events read in total (3103ms).
[11:06:47.553] <TB0> INFO: Test took 3960ms.
[11:06:47.842] <TB0> INFO: Expecting 41600 events.
[11:06:51.526] <TB0> INFO: 41600 events read in total (3092ms).
[11:06:51.526] <TB0> INFO: Test took 3949ms.
[11:06:51.815] <TB0> INFO: Expecting 41600 events.
[11:06:55.395] <TB0> INFO: 41600 events read in total (2988ms).
[11:06:55.396] <TB0> INFO: Test took 3846ms.
[11:06:55.687] <TB0> INFO: Expecting 41600 events.
[11:06:59.262] <TB0> INFO: 41600 events read in total (2983ms).
[11:06:59.263] <TB0> INFO: Test took 3840ms.
[11:06:59.552] <TB0> INFO: Expecting 2560 events.
[11:07:00.442] <TB0> INFO: 2560 events read in total (298ms).
[11:07:00.443] <TB0> INFO: Test took 1167ms.
[11:07:00.750] <TB0> INFO: Expecting 2560 events.
[11:07:01.640] <TB0> INFO: 2560 events read in total (298ms).
[11:07:01.640] <TB0> INFO: Test took 1197ms.
[11:07:01.948] <TB0> INFO: Expecting 2560 events.
[11:07:02.831] <TB0> INFO: 2560 events read in total (291ms).
[11:07:02.831] <TB0> INFO: Test took 1191ms.
[11:07:03.139] <TB0> INFO: Expecting 2560 events.
[11:07:04.028] <TB0> INFO: 2560 events read in total (297ms).
[11:07:04.028] <TB0> INFO: Test took 1196ms.
[11:07:04.336] <TB0> INFO: Expecting 2560 events.
[11:07:05.220] <TB0> INFO: 2560 events read in total (293ms).
[11:07:05.220] <TB0> INFO: Test took 1192ms.
[11:07:05.528] <TB0> INFO: Expecting 2560 events.
[11:07:06.410] <TB0> INFO: 2560 events read in total (290ms).
[11:07:06.410] <TB0> INFO: Test took 1189ms.
[11:07:06.719] <TB0> INFO: Expecting 2560 events.
[11:07:07.600] <TB0> INFO: 2560 events read in total (290ms).
[11:07:07.600] <TB0> INFO: Test took 1189ms.
[11:07:07.908] <TB0> INFO: Expecting 2560 events.
[11:07:08.798] <TB0> INFO: 2560 events read in total (298ms).
[11:07:08.798] <TB0> INFO: Test took 1198ms.
[11:07:09.106] <TB0> INFO: Expecting 2560 events.
[11:07:09.994] <TB0> INFO: 2560 events read in total (296ms).
[11:07:09.995] <TB0> INFO: Test took 1196ms.
[11:07:10.302] <TB0> INFO: Expecting 2560 events.
[11:07:11.183] <TB0> INFO: 2560 events read in total (289ms).
[11:07:11.183] <TB0> INFO: Test took 1188ms.
[11:07:11.491] <TB0> INFO: Expecting 2560 events.
[11:07:12.376] <TB0> INFO: 2560 events read in total (293ms).
[11:07:12.377] <TB0> INFO: Test took 1193ms.
[11:07:12.684] <TB0> INFO: Expecting 2560 events.
[11:07:13.575] <TB0> INFO: 2560 events read in total (299ms).
[11:07:13.575] <TB0> INFO: Test took 1198ms.
[11:07:13.882] <TB0> INFO: Expecting 2560 events.
[11:07:14.766] <TB0> INFO: 2560 events read in total (292ms).
[11:07:14.766] <TB0> INFO: Test took 1190ms.
[11:07:15.074] <TB0> INFO: Expecting 2560 events.
[11:07:15.967] <TB0> INFO: 2560 events read in total (302ms).
[11:07:15.967] <TB0> INFO: Test took 1201ms.
[11:07:16.275] <TB0> INFO: Expecting 2560 events.
[11:07:17.164] <TB0> INFO: 2560 events read in total (297ms).
[11:07:17.164] <TB0> INFO: Test took 1197ms.
[11:07:17.472] <TB0> INFO: Expecting 2560 events.
[11:07:18.361] <TB0> INFO: 2560 events read in total (298ms).
[11:07:18.361] <TB0> INFO: Test took 1196ms.
[11:07:18.365] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:07:18.670] <TB0> INFO: Expecting 655360 events.
[11:07:33.926] <TB0> INFO: 655360 events read in total (14665ms).
[11:07:33.937] <TB0> INFO: Expecting 655360 events.
[11:07:48.938] <TB0> INFO: 655360 events read in total (14598ms).
[11:07:48.955] <TB0> INFO: Expecting 655360 events.
[11:08:03.816] <TB0> INFO: 655360 events read in total (14459ms).
[11:08:03.836] <TB0> INFO: Expecting 655360 events.
[11:08:18.561] <TB0> INFO: 655360 events read in total (14322ms).
[11:08:18.586] <TB0> INFO: Expecting 655360 events.
[11:08:33.453] <TB0> INFO: 655360 events read in total (14464ms).
[11:08:33.493] <TB0> INFO: Expecting 655360 events.
[11:08:48.283] <TB0> INFO: 655360 events read in total (14387ms).
[11:08:48.316] <TB0> INFO: Expecting 655360 events.
[11:09:03.009] <TB0> INFO: 655360 events read in total (14290ms).
[11:09:03.046] <TB0> INFO: Expecting 655360 events.
[11:09:17.247] <TB0> INFO: 655360 events read in total (13798ms).
[11:09:17.282] <TB0> INFO: Expecting 655360 events.
[11:09:32.103] <TB0> INFO: 655360 events read in total (14418ms).
[11:09:32.148] <TB0> INFO: Expecting 655360 events.
[11:09:47.027] <TB0> INFO: 655360 events read in total (14476ms).
[11:09:47.077] <TB0> INFO: Expecting 655360 events.
[11:10:01.957] <TB0> INFO: 655360 events read in total (14477ms).
[11:10:02.015] <TB0> INFO: Expecting 655360 events.
[11:10:16.769] <TB0> INFO: 655360 events read in total (14351ms).
[11:10:16.827] <TB0> INFO: Expecting 655360 events.
[11:10:31.585] <TB0> INFO: 655360 events read in total (14355ms).
[11:10:31.648] <TB0> INFO: Expecting 655360 events.
[11:10:46.343] <TB0> INFO: 655360 events read in total (14292ms).
[11:10:46.413] <TB0> INFO: Expecting 655360 events.
[11:11:01.254] <TB0> INFO: 655360 events read in total (14438ms).
[11:11:01.326] <TB0> INFO: Expecting 655360 events.
[11:11:16.008] <TB0> INFO: 655360 events read in total (14279ms).
[11:11:16.086] <TB0> INFO: Test took 237722ms.
[11:11:16.175] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:11:16.437] <TB0> INFO: Expecting 655360 events.
[11:11:31.293] <TB0> INFO: 655360 events read in total (14265ms).
[11:11:31.305] <TB0> INFO: Expecting 655360 events.
[11:11:45.002] <TB0> INFO: 655360 events read in total (14294ms).
[11:11:46.021] <TB0> INFO: Expecting 655360 events.
[11:12:00.732] <TB0> INFO: 655360 events read in total (14308ms).
[11:12:00.752] <TB0> INFO: Expecting 655360 events.
[11:12:15.662] <TB0> INFO: 655360 events read in total (14507ms).
[11:12:15.686] <TB0> INFO: Expecting 655360 events.
[11:12:30.682] <TB0> INFO: 655360 events read in total (14593ms).
[11:12:30.716] <TB0> INFO: Expecting 655360 events.
[11:12:45.823] <TB0> INFO: 655360 events read in total (14704ms).
[11:12:45.855] <TB0> INFO: Expecting 655360 events.
[11:13:00.732] <TB0> INFO: 655360 events read in total (14474ms).
[11:13:00.769] <TB0> INFO: Expecting 655360 events.
[11:13:15.630] <TB0> INFO: 655360 events read in total (14458ms).
[11:13:15.672] <TB0> INFO: Expecting 655360 events.
[11:13:30.607] <TB0> INFO: 655360 events read in total (14532ms).
[11:13:30.655] <TB0> INFO: Expecting 655360 events.
[11:13:45.331] <TB0> INFO: 655360 events read in total (14273ms).
[11:13:45.383] <TB0> INFO: Expecting 655360 events.
[11:14:00.123] <TB0> INFO: 655360 events read in total (14337ms).
[11:14:00.179] <TB0> INFO: Expecting 655360 events.
[11:14:14.894] <TB0> INFO: 655360 events read in total (14312ms).
[11:14:14.958] <TB0> INFO: Expecting 655360 events.
[11:14:29.814] <TB0> INFO: 655360 events read in total (14452ms).
[11:14:29.879] <TB0> INFO: Expecting 655360 events.
[11:14:44.750] <TB0> INFO: 655360 events read in total (14468ms).
[11:14:44.820] <TB0> INFO: Expecting 655360 events.
[11:14:59.752] <TB0> INFO: 655360 events read in total (14529ms).
[11:14:59.864] <TB0> INFO: Expecting 655360 events.
[11:15:14.201] <TB0> INFO: 655360 events read in total (13935ms).
[11:15:14.281] <TB0> INFO: Test took 238106ms.
[11:15:14.441] <TB0> INFO: safety margin for low PH: adding 0, margin is now 20
[11:15:14.447] <TB0> INFO: safety margin for low PH: adding 1, margin is now 21
[11:15:14.453] <TB0> INFO: safety margin for low PH: adding 0, margin is now 20
[11:15:14.459] <TB0> INFO: safety margin for low PH: adding 1, margin is now 21
[11:15:14.466] <TB0> INFO: safety margin for low PH: adding 0, margin is now 20
[11:15:14.472] <TB0> INFO: safety margin for low PH: adding 1, margin is now 21
[11:15:14.477] <TB0> INFO: safety margin for low PH: adding 0, margin is now 20
[11:15:14.483] <TB0> INFO: safety margin for low PH: adding 1, margin is now 21
[11:15:14.492] <TB0> INFO: safety margin for low PH: adding 0, margin is now 20
[11:15:14.500] <TB0> INFO: safety margin for low PH: adding 1, margin is now 21
[11:15:14.509] <TB0> INFO: safety margin for low PH: adding 2, margin is now 22
[11:15:14.518] <TB0> INFO: safety margin for low PH: adding 3, margin is now 23
[11:15:14.527] <TB0> INFO: safety margin for low PH: adding 4, margin is now 24
[11:15:14.536] <TB0> INFO: safety margin for low PH: adding 5, margin is now 25
[11:15:14.545] <TB0> INFO: safety margin for low PH: adding 6, margin is now 26
[11:15:14.554] <TB0> INFO: safety margin for low PH: adding 7, margin is now 27
[11:15:14.563] <TB0> INFO: safety margin for low PH: adding 0, margin is now 20
[11:15:14.572] <TB0> INFO: safety margin for low PH: adding 1, margin is now 21
[11:15:14.581] <TB0> INFO: safety margin for low PH: adding 0, margin is now 20
[11:15:14.590] <TB0> INFO: safety margin for low PH: adding 1, margin is now 21
[11:15:14.599] <TB0> INFO: safety margin for low PH: adding 2, margin is now 22
[11:15:14.608] <TB0> INFO: safety margin for low PH: adding 3, margin is now 23
[11:15:14.616] <TB0> INFO: safety margin for low PH: adding 4, margin is now 24
[11:15:14.622] <TB0> INFO: safety margin for low PH: adding 0, margin is now 20
[11:15:14.628] <TB0> INFO: safety margin for low PH: adding 1, margin is now 21
[11:15:14.633] <TB0> INFO: safety margin for low PH: adding 2, margin is now 22
[11:15:14.639] <TB0> INFO: safety margin for low PH: adding 3, margin is now 23
[11:15:14.645] <TB0> INFO: safety margin for low PH: adding 4, margin is now 24
[11:15:14.654] <TB0> INFO: safety margin for low PH: adding 5, margin is now 25
[11:15:14.663] <TB0> INFO: safety margin for low PH: adding 6, margin is now 26
[11:15:14.672] <TB0> INFO: safety margin for low PH: adding 7, margin is now 27
[11:15:14.681] <TB0> INFO: safety margin for low PH: adding 8, margin is now 28
[11:15:14.690] <TB0> INFO: safety margin for low PH: adding 9, margin is now 29
[11:15:14.699] <TB0> INFO: safety margin for low PH: adding 10, margin is now 30
[11:15:14.708] <TB0> INFO: safety margin for low PH: adding 11, margin is now 31
[11:15:14.718] <TB0> INFO: safety margin for low PH: adding 12, margin is now 32
[11:15:14.728] <TB0> INFO: safety margin for low PH: adding 13, margin is now 33
[11:15:14.739] <TB0> INFO: safety margin for low PH: adding 14, margin is now 34
[11:15:14.748] <TB0> INFO: safety margin for low PH: adding 15, margin is now 35
[11:15:14.759] <TB0> INFO: safety margin for low PH: adding 16, margin is now 36
[11:15:14.768] <TB0> INFO: safety margin for low PH: adding 17, margin is now 37
[11:15:14.777] <TB0> INFO: safety margin for low PH: adding 18, margin is now 38
[11:15:14.786] <TB0> INFO: safety margin for low PH: adding 19, margin is now 39
[11:15:14.795] <TB0> INFO: safety margin for low PH: adding 20, margin is now 40
[11:15:14.805] <TB0> INFO: safety margin for low PH: adding 21, margin is now 41
[11:15:14.814] <TB0> INFO: safety margin for low PH: adding 22, margin is now 42
[11:15:14.823] <TB0> INFO: safety margin for low PH: adding 23, margin is now 43
[11:15:14.832] <TB0> INFO: safety margin for low PH: adding 24, margin is now 44
[11:15:14.842] <TB0> INFO: safety margin for low PH: adding 25, margin is now 45
[11:15:14.851] <TB0> INFO: safety margin for low PH: adding 26, margin is now 46
[11:15:14.860] <TB0> INFO: safety margin for low PH: adding 27, margin is now 47
[11:15:14.869] <TB0> INFO: safety margin for low PH: adding 28, margin is now 48
[11:15:14.878] <TB0> INFO: safety margin for low PH: adding 29, margin is now 49
[11:15:14.887] <TB0> INFO: safety margin for low PH: adding 30, margin is now 50
[11:15:14.894] <TB0> INFO: safety margin for low PH: adding 31, margin is now 51
[11:15:14.901] <TB0> INFO: safety margin for low PH: adding 32, margin is now 52
[11:15:14.907] <TB0> INFO: safety margin for low PH: adding 33, margin is now 53
[11:15:14.912] <TB0> INFO: safety margin for low PH: adding 34, margin is now 54
[11:15:14.919] <TB0> INFO: safety margin for low PH: adding 35, margin is now 55
[11:15:14.925] <TB0> INFO: safety margin for low PH: adding 36, margin is now 56
[11:15:14.931] <TB0> INFO: safety margin for low PH: adding 37, margin is now 57
[11:15:14.937] <TB0> INFO: safety margin for low PH: adding 38, margin is now 58
[11:15:14.943] <TB0> INFO: safety margin for low PH: adding 39, margin is now 59
[11:15:14.948] <TB0> INFO: safety margin for low PH: adding 40, margin is now 60
[11:15:14.954] <TB0> INFO: safety margin for low PH: adding 41, margin is now 61
[11:15:14.960] <TB0> INFO: safety margin for low PH: adding 42, margin is now 62
[11:15:14.966] <TB0> INFO: safety margin for low PH: adding 43, margin is now 63
[11:15:14.972] <TB0> INFO: safety margin for low PH: adding 44, margin is now 64
[11:15:14.978] <TB0> INFO: safety margin for low PH: adding 45, margin is now 65
[11:15:14.984] <TB0> INFO: safety margin for low PH: adding 46, margin is now 66
[11:15:14.990] <TB0> INFO: safety margin for low PH: adding 47, margin is now 67
[11:15:14.996] <TB0> INFO: safety margin for low PH: adding 48, margin is now 68
[11:15:14.002] <TB0> INFO: safety margin for low PH: adding 49, margin is now 69
[11:15:15.008] <TB0> INFO: safety margin for low PH: adding 50, margin is now 70
[11:15:15.015] <TB0> INFO: safety margin for low PH: adding 51, margin is now 71
[11:15:15.024] <TB0> INFO: safety margin for low PH: adding 52, margin is now 72
[11:15:15.033] <TB0> INFO: safety margin for low PH: adding 53, margin is now 73
[11:15:15.042] <TB0> INFO: safety margin for low PH: adding 54, margin is now 74
[11:15:15.051] <TB0> INFO: safety margin for low PH: adding 55, margin is now 75
[11:15:15.060] <TB0> INFO: safety margin for low PH: adding 56, margin is now 76
[11:15:15.069] <TB0> INFO: safety margin for low PH: adding 57, margin is now 77
[11:15:15.078] <TB0> INFO: safety margin for low PH: adding 58, margin is now 78
[11:15:15.087] <TB0> INFO: safety margin for low PH: adding 59, margin is now 79
[11:15:15.096] <TB0> INFO: safety margin for low PH: adding 60, margin is now 80
[11:15:15.106] <TB0> INFO: safety margin for low PH: adding 61, margin is now 81
[11:15:15.114] <TB0> INFO: safety margin for low PH: adding 62, margin is now 82
[11:15:15.123] <TB0> INFO: safety margin for low PH: adding 63, margin is now 83
[11:15:15.132] <TB0> INFO: safety margin for low PH: adding 64, margin is now 84
[11:15:15.141] <TB0> INFO: safety margin for low PH: adding 65, margin is now 85
[11:15:15.149] <TB0> INFO: safety margin for low PH: adding 66, margin is now 86
[11:15:15.158] <TB0> INFO: safety margin for low PH: adding 67, margin is now 87
[11:15:15.166] <TB0> INFO: safety margin for low PH: adding 68, margin is now 88
[11:15:15.175] <TB0> INFO: safety margin for low PH: adding 69, margin is now 89
[11:15:15.184] <TB0> INFO: safety margin for low PH: adding 70, margin is now 90
[11:15:15.193] <TB0> INFO: safety margin for low PH: adding 71, margin is now 91
[11:15:15.201] <TB0> INFO: safety margin for low PH: adding 72, margin is now 92
[11:15:15.210] <TB0> INFO: safety margin for low PH: adding 73, margin is now 93
[11:15:15.219] <TB0> INFO: safety margin for low PH: adding 74, margin is now 94
[11:15:15.228] <TB0> INFO: safety margin for low PH: adding 75, margin is now 95
[11:15:15.237] <TB0> INFO: safety margin for low PH: adding 76, margin is now 96
[11:15:15.246] <TB0> INFO: safety margin for low PH: adding 77, margin is now 97
[11:15:15.256] <TB0> INFO: safety margin for low PH: adding 78, margin is now 98
[11:15:15.265] <TB0> INFO: safety margin for low PH: adding 79, margin is now 99
[11:15:15.274] <TB0> INFO: safety margin for low PH: adding 80, margin is now 100
[11:15:15.283] <TB0> INFO: safety margin for low PH: adding 81, margin is now 101
[11:15:15.292] <TB0> INFO: safety margin for low PH: adding 82, margin is now 102
[11:15:15.301] <TB0> INFO: safety margin for low PH: adding 83, margin is now 103
[11:15:15.310] <TB0> INFO: safety margin for low PH: adding 84, margin is now 104
[11:15:15.319] <TB0> INFO: safety margin for low PH: adding 85, margin is now 105
[11:15:15.328] <TB0> INFO: safety margin for low PH: adding 86, margin is now 106
[11:15:15.337] <TB0> INFO: safety margin for low PH: adding 87, margin is now 107
[11:15:15.346] <TB0> INFO: safety margin for low PH: adding 88, margin is now 108
[11:15:15.356] <TB0> INFO: safety margin for low PH: adding 89, margin is now 109
[11:15:15.364] <TB0> INFO: safety margin for low PH: adding 90, margin is now 110
[11:15:15.374] <TB0> INFO: safety margin for low PH: adding 91, margin is now 111
[11:15:15.382] <TB0> INFO: safety margin for low PH: adding 92, margin is now 112
[11:15:15.392] <TB0> INFO: safety margin for low PH: adding 93, margin is now 113
[11:15:15.401] <TB0> INFO: safety margin for low PH: adding 94, margin is now 114
[11:15:15.410] <TB0> INFO: safety margin for low PH: adding 95, margin is now 115
[11:15:15.420] <TB0> INFO: safety margin for low PH: adding 96, margin is now 116
[11:15:15.428] <TB0> INFO: safety margin for low PH: adding 97, margin is now 117
[11:15:15.438] <TB0> INFO: safety margin for low PH: adding 98, margin is now 118
[11:15:15.447] <TB0> INFO: safety margin for low PH: adding 99, margin is now 119
[11:15:15.456] <TB0> INFO: safety margin for low PH: adding 100, margin is now 120
[11:15:15.465] <TB0> INFO: safety margin for low PH: adding 101, margin is now 121
[11:15:15.474] <TB0> INFO: safety margin for low PH: adding 102, margin is now 122
[11:15:15.483] <TB0> INFO: safety margin for low PH: adding 103, margin is now 123
[11:15:15.492] <TB0> INFO: safety margin for low PH: adding 104, margin is now 124
[11:15:15.502] <TB0> INFO: safety margin for low PH: adding 105, margin is now 125
[11:15:15.511] <TB0> INFO: safety margin for low PH: adding 106, margin is now 126
[11:15:15.520] <TB0> INFO: safety margin for low PH: adding 107, margin is now 127
[11:15:15.529] <TB0> INFO: safety margin for low PH: adding 108, margin is now 128
[11:15:15.539] <TB0> INFO: safety margin for low PH: adding 109, margin is now 129
[11:15:15.548] <TB0> INFO: safety margin for low PH: adding 110, margin is now 130
[11:15:15.557] <TB0> INFO: safety margin for low PH: adding 111, margin is now 131
[11:15:15.566] <TB0> INFO: safety margin for low PH: adding 112, margin is now 132
[11:15:15.576] <TB0> INFO: safety margin for low PH: adding 113, margin is now 133
[11:15:15.585] <TB0> INFO: safety margin for low PH: adding 114, margin is now 134
[11:15:15.594] <TB0> INFO: safety margin for low PH: adding 115, margin is now 135
[11:15:15.600] <TB0> INFO: safety margin for low PH: adding 116, margin is now 136
[11:15:15.606] <TB0> INFO: safety margin for low PH: adding 117, margin is now 137
[11:15:15.612] <TB0> INFO: safety margin for low PH: adding 118, margin is now 138
[11:15:15.619] <TB0> INFO: safety margin for low PH: adding 119, margin is now 139
[11:15:15.625] <TB0> INFO: safety margin for low PH: adding 120, margin is now 140
[11:15:15.631] <TB0> INFO: safety margin for low PH: adding 121, margin is now 141
[11:15:15.637] <TB0> INFO: safety margin for low PH: adding 122, margin is now 142
[11:15:15.645] <TB0> INFO: safety margin for low PH: adding 123, margin is now 143
[11:15:15.655] <TB0> INFO: safety margin for low PH: adding 124, margin is now 144
[11:15:15.664] <TB0> INFO: safety margin for low PH: adding 125, margin is now 145
[11:15:15.673] <TB0> INFO: safety margin for low PH: adding 126, margin is now 146
[11:15:15.682] <TB0> INFO: safety margin for low PH: adding 127, margin is now 147
[11:15:15.691] <TB0> INFO: safety margin for low PH: adding 128, margin is now 148
[11:15:15.699] <TB0> INFO: safety margin for low PH: adding 129, margin is now 149
[11:15:15.708] <TB0> INFO: safety margin for low PH: adding 130, margin is now 150
[11:15:15.717] <TB0> INFO: safety margin for low PH: adding 131, margin is now 151
[11:15:15.726] <TB0> INFO: safety margin for low PH: adding 132, margin is now 152
[11:15:15.735] <TB0> INFO: safety margin for low PH: adding 133, margin is now 153
[11:15:15.743] <TB0> INFO: safety margin for low PH: adding 134, margin is now 154
[11:15:15.752] <TB0> INFO: safety margin for low PH: adding 135, margin is now 155
[11:15:15.761] <TB0> INFO: safety margin for low PH: adding 136, margin is now 156
[11:15:15.769] <TB0> INFO: safety margin for low PH: adding 137, margin is now 157
[11:15:15.778] <TB0> INFO: safety margin for low PH: adding 138, margin is now 158
[11:15:15.787] <TB0> INFO: safety margin for low PH: adding 139, margin is now 159
[11:15:15.795] <TB0> INFO: safety margin for low PH: adding 140, margin is now 160
[11:15:15.804] <TB0> INFO: safety margin for low PH: adding 141, margin is now 161
[11:15:15.813] <TB0> INFO: safety margin for low PH: adding 142, margin is now 162
[11:15:15.822] <TB0> INFO: safety margin for low PH: adding 143, margin is now 163
[11:15:15.830] <TB0> INFO: safety margin for low PH: adding 144, margin is now 164
[11:15:15.840] <TB0> INFO: safety margin for low PH: adding 145, margin is now 165
[11:15:15.848] <TB0> INFO: safety margin for low PH: adding 146, margin is now 166
[11:15:15.857] <TB0> INFO: safety margin for low PH: adding 147, margin is now 167
[11:15:15.865] <TB0> INFO: safety margin for low PH: adding 148, margin is now 168
[11:15:15.874] <TB0> INFO: safety margin for low PH: adding 149, margin is now 169
[11:15:15.883] <TB0> INFO: safety margin for low PH: adding 150, margin is now 170
[11:15:15.892] <TB0> INFO: safety margin for low PH: adding 151, margin is now 171
[11:15:15.901] <TB0> INFO: safety margin for low PH: adding 152, margin is now 172
[11:15:15.909] <TB0> INFO: safety margin for low PH: adding 153, margin is now 173
[11:15:15.918] <TB0> INFO: safety margin for low PH: adding 154, margin is now 174
[11:15:15.927] <TB0> INFO: safety margin for low PH: adding 155, margin is now 175
[11:15:15.936] <TB0> INFO: safety margin for low PH: adding 156, margin is now 176
[11:15:15.944] <TB0> INFO: safety margin for low PH: adding 157, margin is now 177
[11:15:15.953] <TB0> INFO: safety margin for low PH: adding 158, margin is now 178
[11:15:15.962] <TB0> INFO: safety margin for low PH: adding 159, margin is now 179
[11:15:15.971] <TB0> INFO: safety margin for low PH: adding 160, margin is now 180
[11:15:15.979] <TB0> INFO: safety margin for low PH: adding 161, margin is now 181
[11:15:15.989] <TB0> INFO: safety margin for low PH: adding 162, margin is now 182
[11:15:15.997] <TB0> INFO: safety margin for low PH: adding 163, margin is now 183
[11:15:16.007] <TB0> INFO: safety margin for low PH: adding 164, margin is now 184
[11:15:16.016] <TB0> INFO: safety margin for low PH: adding 165, margin is now 185
[11:15:16.025] <TB0> INFO: safety margin for low PH: adding 166, margin is now 186
[11:15:16.034] <TB0> INFO: safety margin for low PH: adding 167, margin is now 187
[11:15:16.043] <TB0> INFO: safety margin for low PH: adding 168, margin is now 188
[11:15:16.052] <TB0> INFO: safety margin for low PH: adding 169, margin is now 189
[11:15:16.061] <TB0> INFO: safety margin for low PH: adding 170, margin is now 190
[11:15:16.070] <TB0> INFO: safety margin for low PH: adding 171, margin is now 191
[11:15:16.079] <TB0> INFO: safety margin for low PH: adding 172, margin is now 192
[11:15:16.088] <TB0> INFO: safety margin for low PH: adding 173, margin is now 193
[11:15:16.097] <TB0> INFO: safety margin for low PH: adding 174, margin is now 194
[11:15:16.106] <TB0> INFO: safety margin for low PH: adding 175, margin is now 195
[11:15:16.115] <TB0> INFO: safety margin for low PH: adding 176, margin is now 196
[11:15:16.124] <TB0> INFO: safety margin for low PH: adding 177, margin is now 197
[11:15:16.133] <TB0> INFO: safety margin for low PH: adding 178, margin is now 198
[11:15:16.142] <TB0> INFO: safety margin for low PH: adding 179, margin is now 199
[11:15:16.151] <TB0> INFO: safety margin for low PH: adding 180, margin is now 200
[11:15:16.160] <TB0> INFO: safety margin for low PH: adding 181, margin is now 201
[11:15:16.169] <TB0> INFO: safety margin for low PH: adding 182, margin is now 202
[11:15:16.178] <TB0> INFO: safety margin for low PH: adding 183, margin is now 203
[11:15:16.187] <TB0> INFO: safety margin for low PH: adding 184, margin is now 204
[11:15:16.196] <TB0> INFO: safety margin for low PH: adding 185, margin is now 205
[11:15:16.205] <TB0> INFO: safety margin for low PH: adding 186, margin is now 206
[11:15:16.214] <TB0> INFO: safety margin for low PH: adding 187, margin is now 207
[11:15:16.223] <TB0> INFO: safety margin for low PH: adding 188, margin is now 208
[11:15:16.232] <TB0> INFO: safety margin for low PH: adding 189, margin is now 209
[11:15:16.241] <TB0> INFO: safety margin for low PH: adding 190, margin is now 210
[11:15:16.249] <TB0> INFO: safety margin for low PH: adding 191, margin is now 211
[11:15:16.258] <TB0> INFO: safety margin for low PH: adding 192, margin is now 212
[11:15:16.267] <TB0> INFO: safety margin for low PH: adding 193, margin is now 213
[11:15:16.276] <TB0> INFO: safety margin for low PH: adding 194, margin is now 214
[11:15:16.285] <TB0> INFO: safety margin for low PH: adding 195, margin is now 215
[11:15:16.294] <TB0> INFO: safety margin for low PH: adding 196, margin is now 216
[11:15:16.304] <TB0> INFO: safety margin for low PH: adding 197, margin is now 217
[11:15:16.312] <TB0> INFO: safety margin for low PH: adding 198, margin is now 218
[11:15:16.321] <TB0> INFO: safety margin for low PH: adding 199, margin is now 219
[11:15:16.330] <TB0> INFO: safety margin for low PH: adding 200, margin is now 220
[11:15:16.339] <TB0> INFO: safety margin for low PH: adding 201, margin is now 221
[11:15:16.348] <TB0> INFO: safety margin for low PH: adding 202, margin is now 222
[11:15:16.357] <TB0> INFO: safety margin for low PH: adding 203, margin is now 223
[11:15:16.366] <TB0> INFO: safety margin for low PH: adding 204, margin is now 224
[11:15:16.375] <TB0> INFO: safety margin for low PH: adding 205, margin is now 225
[11:15:16.384] <TB0> INFO: safety margin for low PH: adding 206, margin is now 226
[11:15:16.393] <TB0> INFO: safety margin for low PH: adding 207, margin is now 227
[11:15:16.402] <TB0> INFO: safety margin for low PH: adding 208, margin is now 228
[11:15:16.410] <TB0> INFO: safety margin for low PH: adding 209, margin is now 229
[11:15:16.416] <TB0> INFO: safety margin for low PH: adding 210, margin is now 230
[11:15:16.422] <TB0> INFO: safety margin for low PH: adding 211, margin is now 231
[11:15:16.428] <TB0> INFO: safety margin for low PH: adding 212, margin is now 232
[11:15:16.434] <TB0> INFO: safety margin for low PH: adding 213, margin is now 233
[11:15:16.440] <TB0> INFO: safety margin for low PH: adding 214, margin is now 234
[11:15:16.446] <TB0> INFO: safety margin for low PH: adding 215, margin is now 235
[11:15:16.452] <TB0> INFO: safety margin for low PH: adding 216, margin is now 236
[11:15:16.458] <TB0> INFO: safety margin for low PH: adding 217, margin is now 237
[11:15:16.464] <TB0> INFO: safety margin for low PH: adding 218, margin is now 238
[11:15:16.470] <TB0> INFO: safety margin for low PH: adding 219, margin is now 239
[11:15:16.476] <TB0> INFO: safety margin for low PH: adding 220, margin is now 240
[11:15:16.482] <TB0> INFO: safety margin for low PH: adding 221, margin is now 241
[11:15:16.488] <TB0> INFO: safety margin for low PH: adding 222, margin is now 242
[11:15:16.494] <TB0> INFO: safety margin for low PH: adding 223, margin is now 243
[11:15:16.500] <TB0> INFO: safety margin for low PH: adding 224, margin is now 244
[11:15:16.506] <TB0> INFO: safety margin for low PH: adding 225, margin is now 245
[11:15:16.512] <TB0> INFO: safety margin for low PH: adding 226, margin is now 246
[11:15:16.518] <TB0> INFO: safety margin for low PH: adding 227, margin is now 247
[11:15:16.526] <TB0> INFO: safety margin for low PH: adding 228, margin is now 248
[11:15:16.533] <TB0> INFO: safety margin for low PH: adding 229, margin is now 249
[11:15:16.539] <TB0> INFO: safety margin for low PH: adding 230, margin is now 250
[11:15:16.544] <TB0> INFO: safety margin for low PH: adding 231, margin is now 251
[11:15:16.550] <TB0> INFO: safety margin for low PH: adding 232, margin is now 252
[11:15:16.557] <TB0> INFO: safety margin for low PH: adding 233, margin is now 253
[11:15:16.564] <TB0> INFO: safety margin for low PH: adding 234, margin is now 254
[11:15:16.573] <TB0> INFO: safety margin for low PH: adding 235, margin is now 255
[11:15:16.582] <TB0> INFO: For ROC 7: No solutions with PhScale > 20 could be found. Looking for a solution with PhScale < 20.
[11:15:16.582] <TB0> INFO: safety margin for low PH: adding 0, margin is now 20
[11:15:16.591] <TB0> INFO: safety margin for low PH: adding 1, margin is now 21
[11:15:16.600] <TB0> INFO: safety margin for low PH: adding 2, margin is now 22
[11:15:16.609] <TB0> INFO: safety margin for low PH: adding 3, margin is now 23
[11:15:16.618] <TB0> INFO: safety margin for low PH: adding 4, margin is now 24
[11:15:16.628] <TB0> INFO: safety margin for low PH: adding 5, margin is now 25
[11:15:16.637] <TB0> INFO: safety margin for low PH: adding 6, margin is now 26
[11:15:16.646] <TB0> INFO: safety margin for low PH: adding 7, margin is now 27
[11:15:16.655] <TB0> INFO: safety margin for low PH: adding 8, margin is now 28
[11:15:16.664] <TB0> INFO: safety margin for low PH: adding 9, margin is now 29
[11:15:16.674] <TB0> INFO: safety margin for low PH: adding 10, margin is now 30
[11:15:16.682] <TB0> INFO: safety margin for low PH: adding 11, margin is now 31
[11:15:16.691] <TB0> INFO: safety margin for low PH: adding 12, margin is now 32
[11:15:16.700] <TB0> INFO: safety margin for low PH: adding 13, margin is now 33
[11:15:16.710] <TB0> INFO: safety margin for low PH: adding 14, margin is now 34
[11:15:16.719] <TB0> INFO: safety margin for low PH: adding 15, margin is now 35
[11:15:16.728] <TB0> INFO: safety margin for low PH: adding 16, margin is now 36
[11:15:16.737] <TB0> INFO: safety margin for low PH: adding 17, margin is now 37
[11:15:16.746] <TB0> INFO: safety margin for low PH: adding 18, margin is now 38
[11:15:16.756] <TB0> INFO: safety margin for low PH: adding 19, margin is now 39
[11:15:16.764] <TB0> INFO: safety margin for low PH: adding 20, margin is now 40
[11:15:16.774] <TB0> INFO: safety margin for low PH: adding 21, margin is now 41
[11:15:16.783] <TB0> INFO: safety margin for low PH: adding 22, margin is now 42
[11:15:16.792] <TB0> INFO: safety margin for low PH: adding 23, margin is now 43
[11:15:16.801] <TB0> INFO: safety margin for low PH: adding 24, margin is now 44
[11:15:16.810] <TB0> INFO: safety margin for low PH: adding 25, margin is now 45
[11:15:16.819] <TB0> INFO: safety margin for low PH: adding 26, margin is now 46
[11:15:16.828] <TB0> INFO: safety margin for low PH: adding 27, margin is now 47
[11:15:16.838] <TB0> INFO: safety margin for low PH: adding 28, margin is now 48
[11:15:16.847] <TB0> INFO: safety margin for low PH: adding 29, margin is now 49
[11:15:16.856] <TB0> INFO: safety margin for low PH: adding 30, margin is now 50
[11:15:16.865] <TB0> INFO: safety margin for low PH: adding 31, margin is now 51
[11:15:16.874] <TB0> INFO: safety margin for low PH: adding 32, margin is now 52
[11:15:16.883] <TB0> INFO: safety margin for low PH: adding 33, margin is now 53
[11:15:16.892] <TB0> INFO: safety margin for low PH: adding 34, margin is now 54
[11:15:16.902] <TB0> INFO: safety margin for low PH: adding 35, margin is now 55
[11:15:16.911] <TB0> INFO: safety margin for low PH: adding 36, margin is now 56
[11:15:16.920] <TB0> INFO: safety margin for low PH: adding 37, margin is now 57
[11:15:16.929] <TB0> INFO: safety margin for low PH: adding 38, margin is now 58
[11:15:16.938] <TB0> INFO: safety margin for low PH: adding 39, margin is now 59
[11:15:16.947] <TB0> INFO: safety margin for low PH: adding 40, margin is now 60
[11:15:16.957] <TB0> INFO: safety margin for low PH: adding 41, margin is now 61
[11:15:16.965] <TB0> INFO: safety margin for low PH: adding 42, margin is now 62
[11:15:16.975] <TB0> INFO: safety margin for low PH: adding 43, margin is now 63
[11:15:16.984] <TB0> INFO: safety margin for low PH: adding 44, margin is now 64
[11:15:16.993] <TB0> INFO: safety margin for low PH: adding 45, margin is now 65
[11:15:16.002] <TB0> INFO: safety margin for low PH: adding 46, margin is now 66
[11:15:17.011] <TB0> INFO: safety margin for low PH: adding 47, margin is now 67
[11:15:17.021] <TB0> INFO: safety margin for low PH: adding 48, margin is now 68
[11:15:17.029] <TB0> INFO: safety margin for low PH: adding 49, margin is now 69
[11:15:17.039] <TB0> INFO: safety margin for low PH: adding 50, margin is now 70
[11:15:17.048] <TB0> INFO: safety margin for low PH: adding 51, margin is now 71
[11:15:17.057] <TB0> INFO: safety margin for low PH: adding 52, margin is now 72
[11:15:17.066] <TB0> INFO: safety margin for low PH: adding 53, margin is now 73
[11:15:17.075] <TB0> INFO: safety margin for low PH: adding 54, margin is now 74
[11:15:17.085] <TB0> INFO: safety margin for low PH: adding 55, margin is now 75
[11:15:17.093] <TB0> INFO: safety margin for low PH: adding 56, margin is now 76
[11:15:17.102] <TB0> INFO: safety margin for low PH: adding 57, margin is now 77
[11:15:17.111] <TB0> INFO: safety margin for low PH: adding 58, margin is now 78
[11:15:17.120] <TB0> INFO: safety margin for low PH: adding 59, margin is now 79
[11:15:17.129] <TB0> INFO: safety margin for low PH: adding 60, margin is now 80
[11:15:17.138] <TB0> INFO: safety margin for low PH: adding 61, margin is now 81
[11:15:17.147] <TB0> INFO: safety margin for low PH: adding 62, margin is now 82
[11:15:17.156] <TB0> INFO: safety margin for low PH: adding 63, margin is now 83
[11:15:17.165] <TB0> INFO: safety margin for low PH: adding 64, margin is now 84
[11:15:17.174] <TB0> INFO: safety margin for low PH: adding 65, margin is now 85
[11:15:17.183] <TB0> INFO: safety margin for low PH: adding 66, margin is now 86
[11:15:17.192] <TB0> INFO: safety margin for low PH: adding 67, margin is now 87
[11:15:17.201] <TB0> INFO: safety margin for low PH: adding 68, margin is now 88
[11:15:17.210] <TB0> INFO: safety margin for low PH: adding 69, margin is now 89
[11:15:17.220] <TB0> INFO: safety margin for low PH: adding 70, margin is now 90
[11:15:17.229] <TB0> INFO: safety margin for low PH: adding 71, margin is now 91
[11:15:17.238] <TB0> INFO: safety margin for low PH: adding 72, margin is now 92
[11:15:17.247] <TB0> INFO: safety margin for low PH: adding 73, margin is now 93
[11:15:17.256] <TB0> INFO: safety margin for low PH: adding 74, margin is now 94
[11:15:17.265] <TB0> INFO: safety margin for low PH: adding 75, margin is now 95
[11:15:17.274] <TB0> INFO: safety margin for low PH: adding 76, margin is now 96
[11:15:17.284] <TB0> INFO: safety margin for low PH: adding 77, margin is now 97
[11:15:17.293] <TB0> INFO: safety margin for low PH: adding 78, margin is now 98
[11:15:17.302] <TB0> INFO: safety margin for low PH: adding 79, margin is now 99
[11:15:17.311] <TB0> INFO: safety margin for low PH: adding 80, margin is now 100
[11:15:17.321] <TB0> INFO: safety margin for low PH: adding 81, margin is now 101
[11:15:17.330] <TB0> INFO: safety margin for low PH: adding 82, margin is now 102
[11:15:17.340] <TB0> INFO: safety margin for low PH: adding 83, margin is now 103
[11:15:17.349] <TB0> INFO: safety margin for low PH: adding 84, margin is now 104
[11:15:17.358] <TB0> INFO: safety margin for low PH: adding 85, margin is now 105
[11:15:17.367] <TB0> INFO: safety margin for low PH: adding 86, margin is now 106
[11:15:17.376] <TB0> INFO: safety margin for low PH: adding 87, margin is now 107
[11:15:17.386] <TB0> INFO: safety margin for low PH: adding 88, margin is now 108
[11:15:17.395] <TB0> INFO: safety margin for low PH: adding 89, margin is now 109
[11:15:17.404] <TB0> INFO: safety margin for low PH: adding 90, margin is now 110
[11:15:17.413] <TB0> INFO: safety margin for low PH: adding 91, margin is now 111
[11:15:17.423] <TB0> INFO: safety margin for low PH: adding 92, margin is now 112
[11:15:17.432] <TB0> INFO: safety margin for low PH: adding 93, margin is now 113
[11:15:17.441] <TB0> INFO: safety margin for low PH: adding 94, margin is now 114
[11:15:17.450] <TB0> INFO: safety margin for low PH: adding 95, margin is now 115
[11:15:17.460] <TB0> INFO: safety margin for low PH: adding 96, margin is now 116
[11:15:17.469] <TB0> INFO: safety margin for low PH: adding 97, margin is now 117
[11:15:17.478] <TB0> INFO: safety margin for low PH: adding 98, margin is now 118
[11:15:17.488] <TB0> INFO: safety margin for low PH: adding 99, margin is now 119
[11:15:17.497] <TB0> INFO: safety margin for low PH: adding 100, margin is now 120
[11:15:17.506] <TB0> INFO: safety margin for low PH: adding 101, margin is now 121
[11:15:17.515] <TB0> INFO: safety margin for low PH: adding 102, margin is now 122
[11:15:17.525] <TB0> INFO: safety margin for low PH: adding 103, margin is now 123
[11:15:17.534] <TB0> INFO: safety margin for low PH: adding 104, margin is now 124
[11:15:17.543] <TB0> INFO: safety margin for low PH: adding 105, margin is now 125
[11:15:17.553] <TB0> INFO: safety margin for low PH: adding 106, margin is now 126
[11:15:17.562] <TB0> INFO: safety margin for low PH: adding 107, margin is now 127
[11:15:17.571] <TB0> INFO: safety margin for low PH: adding 108, margin is now 128
[11:15:17.580] <TB0> INFO: safety margin for low PH: adding 109, margin is now 129
[11:15:17.590] <TB0> INFO: safety margin for low PH: adding 110, margin is now 130
[11:15:17.599] <TB0> INFO: safety margin for low PH: adding 111, margin is now 131
[11:15:17.608] <TB0> INFO: safety margin for low PH: adding 112, margin is now 132
[11:15:17.618] <TB0> INFO: safety margin for low PH: adding 113, margin is now 133
[11:15:17.627] <TB0> INFO: safety margin for low PH: adding 114, margin is now 134
[11:15:17.636] <TB0> INFO: safety margin for low PH: adding 115, margin is now 135
[11:15:17.645] <TB0> INFO: safety margin for low PH: adding 116, margin is now 136
[11:15:17.656] <TB0> INFO: safety margin for low PH: adding 117, margin is now 137
[11:15:17.665] <TB0> INFO: safety margin for low PH: adding 118, margin is now 138
[11:15:17.674] <TB0> INFO: safety margin for low PH: adding 119, margin is now 139
[11:15:17.683] <TB0> INFO: safety margin for low PH: adding 120, margin is now 140
[11:15:17.693] <TB0> INFO: safety margin for low PH: adding 121, margin is now 141
[11:15:17.702] <TB0> INFO: safety margin for low PH: adding 122, margin is now 142
[11:15:17.711] <TB0> INFO: safety margin for low PH: adding 123, margin is now 143
[11:15:17.720] <TB0> INFO: safety margin for low PH: adding 124, margin is now 144
[11:15:17.729] <TB0> INFO: safety margin for low PH: adding 125, margin is now 145
[11:15:17.738] <TB0> INFO: safety margin for low PH: adding 126, margin is now 146
[11:15:17.747] <TB0> INFO: safety margin for low PH: adding 127, margin is now 147
[11:15:17.756] <TB0> INFO: safety margin for low PH: adding 128, margin is now 148
[11:15:17.765] <TB0> INFO: safety margin for low PH: adding 129, margin is now 149
[11:15:17.774] <TB0> INFO: safety margin for low PH: adding 130, margin is now 150
[11:15:17.783] <TB0> INFO: safety margin for low PH: adding 131, margin is now 151
[11:15:17.792] <TB0> INFO: safety margin for low PH: adding 132, margin is now 152
[11:15:17.801] <TB0> INFO: safety margin for low PH: adding 133, margin is now 153
[11:15:17.810] <TB0> INFO: safety margin for low PH: adding 134, margin is now 154
[11:15:17.820] <TB0> INFO: safety margin for low PH: adding 135, margin is now 155
[11:15:17.829] <TB0> INFO: safety margin for low PH: adding 136, margin is now 156
[11:15:17.839] <TB0> INFO: safety margin for low PH: adding 137, margin is now 157
[11:15:17.848] <TB0> INFO: safety margin for low PH: adding 138, margin is now 158
[11:15:17.857] <TB0> INFO: safety margin for low PH: adding 139, margin is now 159
[11:15:17.866] <TB0> INFO: safety margin for low PH: adding 140, margin is now 160
[11:15:17.876] <TB0> INFO: safety margin for low PH: adding 141, margin is now 161
[11:15:17.885] <TB0> INFO: safety margin for low PH: adding 142, margin is now 162
[11:15:17.894] <TB0> INFO: safety margin for low PH: adding 143, margin is now 163
[11:15:17.904] <TB0> INFO: safety margin for low PH: adding 144, margin is now 164
[11:15:17.913] <TB0> INFO: safety margin for low PH: adding 145, margin is now 165
[11:15:17.922] <TB0> INFO: safety margin for low PH: adding 146, margin is now 166
[11:15:17.931] <TB0> INFO: safety margin for low PH: adding 147, margin is now 167
[11:15:17.941] <TB0> INFO: safety margin for low PH: adding 148, margin is now 168
[11:15:17.950] <TB0> INFO: safety margin for low PH: adding 149, margin is now 169
[11:15:17.959] <TB0> INFO: safety margin for low PH: adding 150, margin is now 170
[11:15:17.969] <TB0> INFO: safety margin for low PH: adding 151, margin is now 171
[11:15:17.978] <TB0> INFO: safety margin for low PH: adding 152, margin is now 172
[11:15:17.987] <TB0> INFO: safety margin for low PH: adding 153, margin is now 173
[11:15:17.996] <TB0> INFO: safety margin for low PH: adding 154, margin is now 174
[11:15:18.006] <TB0> INFO: safety margin for low PH: adding 155, margin is now 175
[11:15:18.015] <TB0> INFO: safety margin for low PH: adding 156, margin is now 176
[11:15:18.024] <TB0> INFO: safety margin for low PH: adding 157, margin is now 177
[11:15:18.033] <TB0> INFO: safety margin for low PH: adding 158, margin is now 178
[11:15:18.042] <TB0> INFO: safety margin for low PH: adding 159, margin is now 179
[11:15:18.051] <TB0> INFO: safety margin for low PH: adding 160, margin is now 180
[11:15:18.061] <TB0> INFO: safety margin for low PH: adding 161, margin is now 181
[11:15:18.070] <TB0> INFO: safety margin for low PH: adding 162, margin is now 182
[11:15:18.079] <TB0> INFO: safety margin for low PH: adding 163, margin is now 183
[11:15:18.089] <TB0> INFO: safety margin for low PH: adding 164, margin is now 184
[11:15:18.098] <TB0> INFO: safety margin for low PH: adding 165, margin is now 185
[11:15:18.107] <TB0> INFO: safety margin for low PH: adding 166, margin is now 186
[11:15:18.117] <TB0> INFO: safety margin for low PH: adding 167, margin is now 187
[11:15:18.126] <TB0> INFO: safety margin for low PH: adding 168, margin is now 188
[11:15:18.136] <TB0> INFO: safety margin for low PH: adding 169, margin is now 189
[11:15:18.145] <TB0> INFO: safety margin for low PH: adding 170, margin is now 190
[11:15:18.154] <TB0> INFO: safety margin for low PH: adding 171, margin is now 191
[11:15:18.164] <TB0> INFO: safety margin for low PH: adding 172, margin is now 192
[11:15:18.173] <TB0> INFO: safety margin for low PH: adding 173, margin is now 193
[11:15:18.182] <TB0> INFO: safety margin for low PH: adding 174, margin is now 194
[11:15:18.192] <TB0> INFO: safety margin for low PH: adding 175, margin is now 195
[11:15:18.201] <TB0> INFO: safety margin for low PH: adding 176, margin is now 196
[11:15:18.210] <TB0> INFO: safety margin for low PH: adding 177, margin is now 197
[11:15:18.220] <TB0> INFO: safety margin for low PH: adding 178, margin is now 198
[11:15:18.229] <TB0> INFO: safety margin for low PH: adding 179, margin is now 199
[11:15:18.239] <TB0> INFO: safety margin for low PH: adding 180, margin is now 200
[11:15:18.247] <TB0> INFO: safety margin for low PH: adding 181, margin is now 201
[11:15:18.254] <TB0> INFO: safety margin for low PH: adding 182, margin is now 202
[11:15:18.259] <TB0> INFO: safety margin for low PH: adding 183, margin is now 203
[11:15:18.266] <TB0> INFO: safety margin for low PH: adding 184, margin is now 204
[11:15:18.273] <TB0> INFO: safety margin for low PH: adding 185, margin is now 205
[11:15:18.280] <TB0> INFO: safety margin for low PH: adding 186, margin is now 206
[11:15:18.288] <TB0> INFO: safety margin for low PH: adding 187, margin is now 207
[11:15:18.294] <TB0> INFO: safety margin for low PH: adding 188, margin is now 208
[11:15:18.303] <TB0> INFO: safety margin for low PH: adding 189, margin is now 209
[11:15:18.309] <TB0> INFO: safety margin for low PH: adding 190, margin is now 210
[11:15:18.317] <TB0> INFO: safety margin for low PH: adding 191, margin is now 211
[11:15:18.324] <TB0> INFO: safety margin for low PH: adding 192, margin is now 212
[11:15:18.331] <TB0> INFO: safety margin for low PH: adding 193, margin is now 213
[11:15:18.338] <TB0> INFO: safety margin for low PH: adding 194, margin is now 214
[11:15:18.345] <TB0> INFO: safety margin for low PH: adding 195, margin is now 215
[11:15:18.353] <TB0> INFO: safety margin for low PH: adding 196, margin is now 216
[11:15:18.359] <TB0> INFO: safety margin for low PH: adding 197, margin is now 217
[11:15:18.369] <TB0> INFO: safety margin for low PH: adding 198, margin is now 218
[11:15:18.377] <TB0> INFO: safety margin for low PH: adding 199, margin is now 219
[11:15:18.387] <TB0> INFO: safety margin for low PH: adding 200, margin is now 220
[11:15:18.396] <TB0> INFO: safety margin for low PH: adding 201, margin is now 221
[11:15:18.405] <TB0> INFO: safety margin for low PH: adding 202, margin is now 222
[11:15:18.413] <TB0> INFO: safety margin for low PH: adding 203, margin is now 223
[11:15:18.423] <TB0> INFO: safety margin for low PH: adding 204, margin is now 224
[11:15:18.431] <TB0> INFO: safety margin for low PH: adding 205, margin is now 225
[11:15:18.440] <TB0> INFO: safety margin for low PH: adding 206, margin is now 226
[11:15:18.449] <TB0> INFO: safety margin for low PH: adding 207, margin is now 227
[11:15:18.458] <TB0> INFO: safety margin for low PH: adding 208, margin is now 228
[11:15:18.467] <TB0> INFO: safety margin for low PH: adding 209, margin is now 229
[11:15:18.476] <TB0> INFO: safety margin for low PH: adding 210, margin is now 230
[11:15:18.486] <TB0> INFO: safety margin for low PH: adding 211, margin is now 231
[11:15:18.494] <TB0> INFO: safety margin for low PH: adding 212, margin is now 232
[11:15:18.504] <TB0> INFO: safety margin for low PH: adding 213, margin is now 233
[11:15:18.513] <TB0> INFO: safety margin for low PH: adding 214, margin is now 234
[11:15:18.522] <TB0> INFO: safety margin for low PH: adding 215, margin is now 235
[11:15:18.530] <TB0> INFO: safety margin for low PH: adding 216, margin is now 236
[11:15:18.540] <TB0> INFO: safety margin for low PH: adding 217, margin is now 237
[11:15:18.549] <TB0> INFO: safety margin for low PH: adding 218, margin is now 238
[11:15:18.558] <TB0> INFO: safety margin for low PH: adding 219, margin is now 239
[11:15:18.566] <TB0> INFO: safety margin for low PH: adding 220, margin is now 240
[11:15:18.575] <TB0> INFO: safety margin for low PH: adding 221, margin is now 241
[11:15:18.585] <TB0> INFO: safety margin for low PH: adding 222, margin is now 242
[11:15:18.593] <TB0> INFO: safety margin for low PH: adding 223, margin is now 243
[11:15:18.602] <TB0> INFO: safety margin for low PH: adding 224, margin is now 244
[11:15:18.611] <TB0> INFO: safety margin for low PH: adding 225, margin is now 245
[11:15:18.620] <TB0> INFO: safety margin for low PH: adding 226, margin is now 246
[11:15:18.629] <TB0> INFO: safety margin for low PH: adding 227, margin is now 247
[11:15:18.638] <TB0> INFO: safety margin for low PH: adding 228, margin is now 248
[11:15:18.647] <TB0> INFO: safety margin for low PH: adding 229, margin is now 249
[11:15:18.657] <TB0> INFO: safety margin for low PH: adding 230, margin is now 250
[11:15:18.665] <TB0> INFO: safety margin for low PH: adding 231, margin is now 251
[11:15:18.674] <TB0> INFO: safety margin for low PH: adding 232, margin is now 252
[11:15:18.683] <TB0> INFO: safety margin for low PH: adding 233, margin is now 253
[11:15:18.692] <TB0> INFO: safety margin for low PH: adding 234, margin is now 254
[11:15:18.701] <TB0> INFO: safety margin for low PH: adding 235, margin is now 255
[11:15:18.710] <TB0> INFO: safety margin for low PH: adding 0, margin is now 20
[11:15:18.719] <TB0> INFO: safety margin for low PH: adding 0, margin is now 20
[11:15:18.728] <TB0> INFO: safety margin for low PH: adding 1, margin is now 21
[11:15:18.737] <TB0> INFO: safety margin for low PH: adding 2, margin is now 22
[11:15:18.746] <TB0> INFO: safety margin for low PH: adding 3, margin is now 23
[11:15:18.755] <TB0> INFO: safety margin for low PH: adding 4, margin is now 24
[11:15:18.764] <TB0> INFO: safety margin for low PH: adding 5, margin is now 25
[11:15:18.773] <TB0> INFO: safety margin for low PH: adding 6, margin is now 26
[11:15:18.781] <TB0> INFO: safety margin for low PH: adding 7, margin is now 27
[11:15:18.790] <TB0> INFO: safety margin for low PH: adding 8, margin is now 28
[11:15:18.799] <TB0> INFO: safety margin for low PH: adding 0, margin is now 20
[11:15:18.808] <TB0> INFO: safety margin for low PH: adding 0, margin is now 20
[11:15:18.817] <TB0> INFO: safety margin for low PH: adding 1, margin is now 21
[11:15:18.826] <TB0> INFO: safety margin for low PH: adding 2, margin is now 22
[11:15:18.835] <TB0> INFO: safety margin for low PH: adding 3, margin is now 23
[11:15:18.844] <TB0> INFO: safety margin for low PH: adding 4, margin is now 24
[11:15:18.853] <TB0> INFO: safety margin for low PH: adding 5, margin is now 25
[11:15:18.862] <TB0> INFO: safety margin for low PH: adding 6, margin is now 26
[11:15:18.871] <TB0> INFO: safety margin for low PH: adding 7, margin is now 27
[11:15:18.879] <TB0> INFO: safety margin for low PH: adding 8, margin is now 28
[11:15:18.888] <TB0> INFO: safety margin for low PH: adding 9, margin is now 29
[11:15:18.897] <TB0> INFO: safety margin for low PH: adding 10, margin is now 30
[11:15:18.906] <TB0> INFO: safety margin for low PH: adding 0, margin is now 20
[11:15:18.915] <TB0> INFO: safety margin for low PH: adding 0, margin is now 20
[11:15:18.924] <TB0> INFO: safety margin for low PH: adding 1, margin is now 21
[11:15:18.934] <TB0> INFO: safety margin for low PH: adding 2, margin is now 22
[11:15:18.943] <TB0> INFO: safety margin for low PH: adding 3, margin is now 23
[11:15:18.952] <TB0> INFO: safety margin for low PH: adding 4, margin is now 24
[11:15:18.961] <TB0> INFO: safety margin for low PH: adding 0, margin is now 20
[11:15:18.970] <TB0> INFO: safety margin for low PH: adding 0, margin is now 20
[11:15:19.011] <TB0> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1032_FullQualification_2016-10-21_09h47m_1477036052//000_Fulltest_m20//dacParameters35_C0.dat
[11:15:19.011] <TB0> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1032_FullQualification_2016-10-21_09h47m_1477036052//000_Fulltest_m20//dacParameters35_C1.dat
[11:15:19.011] <TB0> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1032_FullQualification_2016-10-21_09h47m_1477036052//000_Fulltest_m20//dacParameters35_C2.dat
[11:15:19.011] <TB0> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1032_FullQualification_2016-10-21_09h47m_1477036052//000_Fulltest_m20//dacParameters35_C3.dat
[11:15:19.011] <TB0> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1032_FullQualification_2016-10-21_09h47m_1477036052//000_Fulltest_m20//dacParameters35_C4.dat
[11:15:19.011] <TB0> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1032_FullQualification_2016-10-21_09h47m_1477036052//000_Fulltest_m20//dacParameters35_C5.dat
[11:15:19.011] <TB0> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1032_FullQualification_2016-10-21_09h47m_1477036052//000_Fulltest_m20//dacParameters35_C6.dat
[11:15:19.012] <TB0> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1032_FullQualification_2016-10-21_09h47m_1477036052//000_Fulltest_m20//dacParameters35_C7.dat
[11:15:19.012] <TB0> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1032_FullQualification_2016-10-21_09h47m_1477036052//000_Fulltest_m20//dacParameters35_C8.dat
[11:15:19.012] <TB0> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1032_FullQualification_2016-10-21_09h47m_1477036052//000_Fulltest_m20//dacParameters35_C9.dat
[11:15:19.012] <TB0> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1032_FullQualification_2016-10-21_09h47m_1477036052//000_Fulltest_m20//dacParameters35_C10.dat
[11:15:19.012] <TB0> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1032_FullQualification_2016-10-21_09h47m_1477036052//000_Fulltest_m20//dacParameters35_C11.dat
[11:15:19.012] <TB0> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1032_FullQualification_2016-10-21_09h47m_1477036052//000_Fulltest_m20//dacParameters35_C12.dat
[11:15:19.012] <TB0> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1032_FullQualification_2016-10-21_09h47m_1477036052//000_Fulltest_m20//dacParameters35_C13.dat
[11:15:19.012] <TB0> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1032_FullQualification_2016-10-21_09h47m_1477036052//000_Fulltest_m20//dacParameters35_C14.dat
[11:15:19.012] <TB0> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1032_FullQualification_2016-10-21_09h47m_1477036052//000_Fulltest_m20//dacParameters35_C15.dat
[11:15:19.251] <TB0> INFO: Expecting 41600 events.
[11:15:22.424] <TB0> INFO: 41600 events read in total (2581ms).
[11:15:22.424] <TB0> INFO: Test took 3408ms.
[11:15:22.871] <TB0> INFO: Expecting 41600 events.
[11:15:25.945] <TB0> INFO: 41600 events read in total (2482ms).
[11:15:25.946] <TB0> INFO: Test took 3310ms.
[11:15:26.451] <TB0> INFO: Expecting 41600 events.
[11:15:29.614] <TB0> INFO: 41600 events read in total (2571ms).
[11:15:29.615] <TB0> INFO: Test took 3458ms.
[11:15:29.831] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:15:29.919] <TB0> INFO: Expecting 2560 events.
[11:15:30.805] <TB0> INFO: 2560 events read in total (294ms).
[11:15:30.806] <TB0> INFO: Test took 975ms.
[11:15:30.808] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:15:31.114] <TB0> INFO: Expecting 2560 events.
[11:15:31.997] <TB0> INFO: 2560 events read in total (292ms).
[11:15:31.997] <TB0> INFO: Test took 1189ms.
[11:15:31.999] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:15:32.306] <TB0> INFO: Expecting 2560 events.
[11:15:33.190] <TB0> INFO: 2560 events read in total (293ms).
[11:15:33.191] <TB0> INFO: Test took 1192ms.
[11:15:33.194] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:15:33.499] <TB0> INFO: Expecting 2560 events.
[11:15:34.386] <TB0> INFO: 2560 events read in total (296ms).
[11:15:34.387] <TB0> INFO: Test took 1193ms.
[11:15:34.389] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:15:34.695] <TB0> INFO: Expecting 2560 events.
[11:15:35.585] <TB0> INFO: 2560 events read in total (299ms).
[11:15:35.585] <TB0> INFO: Test took 1196ms.
[11:15:35.587] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:15:35.894] <TB0> INFO: Expecting 2560 events.
[11:15:36.780] <TB0> INFO: 2560 events read in total (295ms).
[11:15:36.781] <TB0> INFO: Test took 1194ms.
[11:15:36.783] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:15:37.089] <TB0> INFO: Expecting 2560 events.
[11:15:37.977] <TB0> INFO: 2560 events read in total (297ms).
[11:15:37.977] <TB0> INFO: Test took 1194ms.
[11:15:37.979] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:15:38.285] <TB0> INFO: Expecting 2560 events.
[11:15:39.172] <TB0> INFO: 2560 events read in total (295ms).
[11:15:39.172] <TB0> INFO: Test took 1193ms.
[11:15:39.175] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:15:39.481] <TB0> INFO: Expecting 2560 events.
[11:15:40.370] <TB0> INFO: 2560 events read in total (298ms).
[11:15:40.370] <TB0> INFO: Test took 1195ms.
[11:15:40.373] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:15:40.679] <TB0> INFO: Expecting 2560 events.
[11:15:41.562] <TB0> INFO: 2560 events read in total (291ms).
[11:15:41.562] <TB0> INFO: Test took 1189ms.
[11:15:41.564] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:15:41.871] <TB0> INFO: Expecting 2560 events.
[11:15:42.754] <TB0> INFO: 2560 events read in total (291ms).
[11:15:42.754] <TB0> INFO: Test took 1190ms.
[11:15:42.758] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:15:43.063] <TB0> INFO: Expecting 2560 events.
[11:15:43.945] <TB0> INFO: 2560 events read in total (291ms).
[11:15:43.945] <TB0> INFO: Test took 1187ms.
[11:15:43.947] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:15:44.254] <TB0> INFO: Expecting 2560 events.
[11:15:45.138] <TB0> INFO: 2560 events read in total (292ms).
[11:15:45.138] <TB0> INFO: Test took 1191ms.
[11:15:45.141] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:15:45.446] <TB0> INFO: Expecting 2560 events.
[11:15:46.326] <TB0> INFO: 2560 events read in total (289ms).
[11:15:46.326] <TB0> INFO: Test took 1185ms.
[11:15:46.329] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:15:46.634] <TB0> INFO: Expecting 2560 events.
[11:15:47.515] <TB0> INFO: 2560 events read in total (289ms).
[11:15:47.516] <TB0> INFO: Test took 1187ms.
[11:15:47.518] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:15:47.825] <TB0> INFO: Expecting 2560 events.
[11:15:48.705] <TB0> INFO: 2560 events read in total (289ms).
[11:15:48.705] <TB0> INFO: Test took 1187ms.
[11:15:48.708] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:15:49.014] <TB0> INFO: Expecting 2560 events.
[11:15:49.896] <TB0> INFO: 2560 events read in total (291ms).
[11:15:49.896] <TB0> INFO: Test took 1189ms.
[11:15:49.898] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:15:50.205] <TB0> INFO: Expecting 2560 events.
[11:15:51.088] <TB0> INFO: 2560 events read in total (291ms).
[11:15:51.088] <TB0> INFO: Test took 1190ms.
[11:15:51.090] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:15:51.396] <TB0> INFO: Expecting 2560 events.
[11:15:52.277] <TB0> INFO: 2560 events read in total (289ms).
[11:15:52.278] <TB0> INFO: Test took 1188ms.
[11:15:52.279] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:15:52.586] <TB0> INFO: Expecting 2560 events.
[11:15:53.474] <TB0> INFO: 2560 events read in total (297ms).
[11:15:53.475] <TB0> INFO: Test took 1196ms.
[11:15:53.477] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:15:53.783] <TB0> INFO: Expecting 2560 events.
[11:15:54.670] <TB0> INFO: 2560 events read in total (295ms).
[11:15:54.670] <TB0> INFO: Test took 1193ms.
[11:15:54.672] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:15:54.979] <TB0> INFO: Expecting 2560 events.
[11:15:55.860] <TB0> INFO: 2560 events read in total (290ms).
[11:15:55.861] <TB0> INFO: Test took 1189ms.
[11:15:55.863] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:15:56.170] <TB0> INFO: Expecting 2560 events.
[11:15:57.052] <TB0> INFO: 2560 events read in total (291ms).
[11:15:57.052] <TB0> INFO: Test took 1189ms.
[11:15:57.054] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:15:57.360] <TB0> INFO: Expecting 2560 events.
[11:15:58.244] <TB0> INFO: 2560 events read in total (292ms).
[11:15:58.244] <TB0> INFO: Test took 1190ms.
[11:15:58.247] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:15:58.553] <TB0> INFO: Expecting 2560 events.
[11:15:59.444] <TB0> INFO: 2560 events read in total (299ms).
[11:15:59.444] <TB0> INFO: Test took 1198ms.
[11:15:59.447] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:15:59.753] <TB0> INFO: Expecting 2560 events.
[11:16:00.639] <TB0> INFO: 2560 events read in total (294ms).
[11:16:00.640] <TB0> INFO: Test took 1193ms.
[11:16:00.642] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:16:00.948] <TB0> INFO: Expecting 2560 events.
[11:16:01.841] <TB0> INFO: 2560 events read in total (301ms).
[11:16:01.842] <TB0> INFO: Test took 1200ms.
[11:16:01.844] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:16:02.150] <TB0> INFO: Expecting 2560 events.
[11:16:03.037] <TB0> INFO: 2560 events read in total (296ms).
[11:16:03.037] <TB0> INFO: Test took 1193ms.
[11:16:03.039] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:16:03.346] <TB0> INFO: Expecting 2560 events.
[11:16:04.239] <TB0> INFO: 2560 events read in total (301ms).
[11:16:04.239] <TB0> INFO: Test took 1200ms.
[11:16:04.241] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:16:04.548] <TB0> INFO: Expecting 2560 events.
[11:16:05.432] <TB0> INFO: 2560 events read in total (293ms).
[11:16:05.433] <TB0> INFO: Test took 1192ms.
[11:16:05.436] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:16:05.741] <TB0> INFO: Expecting 2560 events.
[11:16:06.632] <TB0> INFO: 2560 events read in total (299ms).
[11:16:06.632] <TB0> INFO: Test took 1196ms.
[11:16:06.635] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:16:06.940] <TB0> INFO: Expecting 2560 events.
[11:16:07.831] <TB0> INFO: 2560 events read in total (299ms).
[11:16:07.831] <TB0> INFO: Test took 1197ms.
[11:16:08.294] <TB0> INFO: PixTestPhOptimization::doTest() done, duration: 761 seconds
[11:16:08.294] <TB0> INFO: PH scale (per ROC): 42 51 38 37 37 41 43 0 35 39 33 48 45 36 63 26
[11:16:08.294] <TB0> INFO: PH offset (per ROC): 89 128 99 117 109 130 111 0 98 110 101 132 109 106 122 94
[11:16:08.301] <TB0> INFO: Decoding statistics:
[11:16:08.302] <TB0> INFO: General information:
[11:16:08.302] <TB0> INFO: 16bit words read: 127876
[11:16:08.302] <TB0> INFO: valid events total: 20480
[11:16:08.302] <TB0> INFO: empty events: 17982
[11:16:08.302] <TB0> INFO: valid events with pixels: 2498
[11:16:08.302] <TB0> INFO: valid pixel hits: 2498
[11:16:08.302] <TB0> INFO: Event errors: 0
[11:16:08.302] <TB0> INFO: start marker: 0
[11:16:08.302] <TB0> INFO: stop marker: 0
[11:16:08.302] <TB0> INFO: overflow: 0
[11:16:08.302] <TB0> INFO: invalid 5bit words: 0
[11:16:08.302] <TB0> INFO: invalid XOR eye diagram: 0
[11:16:08.302] <TB0> INFO: frame (failed synchr.): 0
[11:16:08.302] <TB0> INFO: idle data (no TBM trl): 0
[11:16:08.302] <TB0> INFO: no data (only TBM hdr): 0
[11:16:08.302] <TB0> INFO: TBM errors: 0
[11:16:08.302] <TB0> INFO: flawed TBM headers: 0
[11:16:08.302] <TB0> INFO: flawed TBM trailers: 0
[11:16:08.302] <TB0> INFO: event ID mismatches: 0
[11:16:08.302] <TB0> INFO: ROC errors: 0
[11:16:08.302] <TB0> INFO: missing ROC header(s): 0
[11:16:08.302] <TB0> INFO: misplaced readback start: 0
[11:16:08.302] <TB0> INFO: Pixel decoding errors: 0
[11:16:08.302] <TB0> INFO: pixel data incomplete: 0
[11:16:08.302] <TB0> INFO: pixel address: 0
[11:16:08.302] <TB0> INFO: pulse height fill bit: 0
[11:16:08.302] <TB0> INFO: buffer corruption: 0
[11:16:08.462] <TB0> INFO: ######################################################################
[11:16:08.462] <TB0> INFO: PixTestGainPedestal::fullTest() ntrig = 10
[11:16:08.462] <TB0> INFO: ######################################################################
[11:16:08.476] <TB0> INFO: scanning low vcal = 10
[11:16:08.717] <TB0> INFO: Expecting 41600 events.
[11:16:12.314] <TB0> INFO: 41600 events read in total (3006ms).
[11:16:12.314] <TB0> INFO: Test took 3838ms.
[11:16:12.316] <TB0> INFO: scanning low vcal = 20
[11:16:12.613] <TB0> INFO: Expecting 41600 events.
[11:16:16.248] <TB0> INFO: 41600 events read in total (3043ms).
[11:16:16.248] <TB0> INFO: Test took 3933ms.
[11:16:16.251] <TB0> INFO: scanning low vcal = 30
[11:16:16.544] <TB0> INFO: Expecting 41600 events.
[11:16:20.286] <TB0> INFO: 41600 events read in total (3151ms).
[11:16:20.287] <TB0> INFO: Test took 4036ms.
[11:16:20.290] <TB0> INFO: scanning low vcal = 40
[11:16:20.567] <TB0> INFO: Expecting 41600 events.
[11:16:24.558] <TB0> INFO: 41600 events read in total (3400ms).
[11:16:24.559] <TB0> INFO: Test took 4269ms.
[11:16:24.562] <TB0> INFO: scanning low vcal = 50
[11:16:24.838] <TB0> INFO: Expecting 41600 events.
[11:16:28.894] <TB0> INFO: 41600 events read in total (3464ms).
[11:16:28.895] <TB0> INFO: Test took 4333ms.
[11:16:28.898] <TB0> INFO: scanning low vcal = 60
[11:16:29.174] <TB0> INFO: Expecting 41600 events.
[11:16:33.172] <TB0> INFO: 41600 events read in total (3406ms).
[11:16:33.172] <TB0> INFO: Test took 4274ms.
[11:16:33.175] <TB0> INFO: scanning low vcal = 70
[11:16:33.452] <TB0> INFO: Expecting 41600 events.
[11:16:37.422] <TB0> INFO: 41600 events read in total (3378ms).
[11:16:37.423] <TB0> INFO: Test took 4248ms.
[11:16:37.426] <TB0> INFO: scanning low vcal = 80
[11:16:37.703] <TB0> INFO: Expecting 41600 events.
[11:16:41.763] <TB0> INFO: 41600 events read in total (3469ms).
[11:16:41.764] <TB0> INFO: Test took 4338ms.
[11:16:41.767] <TB0> INFO: scanning low vcal = 90
[11:16:42.043] <TB0> INFO: Expecting 41600 events.
[11:16:46.047] <TB0> INFO: 41600 events read in total (3412ms).
[11:16:46.048] <TB0> INFO: Test took 4281ms.
[11:16:46.052] <TB0> INFO: scanning low vcal = 100
[11:16:46.327] <TB0> INFO: Expecting 41600 events.
[11:16:50.335] <TB0> INFO: 41600 events read in total (3416ms).
[11:16:50.336] <TB0> INFO: Test took 4284ms.
[11:16:50.339] <TB0> INFO: scanning low vcal = 110
[11:16:50.655] <TB0> INFO: Expecting 41600 events.
[11:16:54.673] <TB0> INFO: 41600 events read in total (3426ms).
[11:16:54.674] <TB0> INFO: Test took 4335ms.
[11:16:54.677] <TB0> INFO: scanning low vcal = 120
[11:16:54.954] <TB0> INFO: Expecting 41600 events.
[11:16:58.000] <TB0> INFO: 41600 events read in total (3455ms).
[11:16:58.000] <TB0> INFO: Test took 4323ms.
[11:16:59.003] <TB0> INFO: scanning low vcal = 130
[11:16:59.280] <TB0> INFO: Expecting 41600 events.
[11:17:03.284] <TB0> INFO: 41600 events read in total (3412ms).
[11:17:03.284] <TB0> INFO: Test took 4281ms.
[11:17:03.287] <TB0> INFO: scanning low vcal = 140
[11:17:03.564] <TB0> INFO: Expecting 41600 events.
[11:17:07.628] <TB0> INFO: 41600 events read in total (3472ms).
[11:17:07.629] <TB0> INFO: Test took 4342ms.
[11:17:07.632] <TB0> INFO: scanning low vcal = 150
[11:17:07.909] <TB0> INFO: Expecting 41600 events.
[11:17:11.891] <TB0> INFO: 41600 events read in total (3390ms).
[11:17:11.892] <TB0> INFO: Test took 4260ms.
[11:17:11.895] <TB0> INFO: scanning low vcal = 160
[11:17:12.171] <TB0> INFO: Expecting 41600 events.
[11:17:16.148] <TB0> INFO: 41600 events read in total (3385ms).
[11:17:16.149] <TB0> INFO: Test took 4254ms.
[11:17:16.151] <TB0> INFO: scanning low vcal = 170
[11:17:16.428] <TB0> INFO: Expecting 41600 events.
[11:17:20.398] <TB0> INFO: 41600 events read in total (3378ms).
[11:17:20.399] <TB0> INFO: Test took 4247ms.
[11:17:20.404] <TB0> INFO: scanning low vcal = 180
[11:17:20.678] <TB0> INFO: Expecting 41600 events.
[11:17:24.741] <TB0> INFO: 41600 events read in total (3471ms).
[11:17:24.741] <TB0> INFO: Test took 4337ms.
[11:17:24.744] <TB0> INFO: scanning low vcal = 190
[11:17:25.062] <TB0> INFO: Expecting 41600 events.
[11:17:29.111] <TB0> INFO: 41600 events read in total (3458ms).
[11:17:29.111] <TB0> INFO: Test took 4366ms.
[11:17:29.114] <TB0> INFO: scanning low vcal = 200
[11:17:29.432] <TB0> INFO: Expecting 41600 events.
[11:17:33.467] <TB0> INFO: 41600 events read in total (3444ms).
[11:17:33.467] <TB0> INFO: Test took 4353ms.
[11:17:33.470] <TB0> INFO: scanning low vcal = 210
[11:17:33.786] <TB0> INFO: Expecting 41600 events.
[11:17:37.851] <TB0> INFO: 41600 events read in total (3474ms).
[11:17:37.852] <TB0> INFO: Test took 4382ms.
[11:17:37.855] <TB0> INFO: scanning low vcal = 220
[11:17:38.132] <TB0> INFO: Expecting 41600 events.
[11:17:42.108] <TB0> INFO: 41600 events read in total (3384ms).
[11:17:42.108] <TB0> INFO: Test took 4253ms.
[11:17:42.111] <TB0> INFO: scanning low vcal = 230
[11:17:42.387] <TB0> INFO: Expecting 41600 events.
[11:17:46.391] <TB0> INFO: 41600 events read in total (3412ms).
[11:17:46.391] <TB0> INFO: Test took 4280ms.
[11:17:46.394] <TB0> INFO: scanning low vcal = 240
[11:17:46.671] <TB0> INFO: Expecting 41600 events.
[11:17:50.729] <TB0> INFO: 41600 events read in total (3467ms).
[11:17:50.730] <TB0> INFO: Test took 4336ms.
[11:17:50.733] <TB0> INFO: scanning low vcal = 250
[11:17:51.010] <TB0> INFO: Expecting 41600 events.
[11:17:54.992] <TB0> INFO: 41600 events read in total (3390ms).
[11:17:54.993] <TB0> INFO: Test took 4260ms.
[11:17:54.997] <TB0> INFO: scanning high vcal = 30 (= 210 in low range)
[11:17:55.273] <TB0> INFO: Expecting 41600 events.
[11:17:59.261] <TB0> INFO: 41600 events read in total (3396ms).
[11:17:59.262] <TB0> INFO: Test took 4265ms.
[11:17:59.265] <TB0> INFO: scanning high vcal = 50 (= 350 in low range)
[11:17:59.541] <TB0> INFO: Expecting 41600 events.
[11:18:03.544] <TB0> INFO: 41600 events read in total (3411ms).
[11:18:03.544] <TB0> INFO: Test took 4279ms.
[11:18:03.547] <TB0> INFO: scanning high vcal = 70 (= 490 in low range)
[11:18:03.824] <TB0> INFO: Expecting 41600 events.
[11:18:07.837] <TB0> INFO: 41600 events read in total (3421ms).
[11:18:07.838] <TB0> INFO: Test took 4290ms.
[11:18:07.841] <TB0> INFO: scanning high vcal = 90 (= 630 in low range)
[11:18:08.157] <TB0> INFO: Expecting 41600 events.
[11:18:12.141] <TB0> INFO: 41600 events read in total (3392ms).
[11:18:12.142] <TB0> INFO: Test took 4301ms.
[11:18:12.144] <TB0> INFO: scanning high vcal = 200 (= 1400 in low range)
[11:18:12.462] <TB0> INFO: Expecting 41600 events.
[11:18:16.484] <TB0> INFO: 41600 events read in total (3430ms).
[11:18:16.484] <TB0> INFO: Test took 4340ms.
[11:18:16.878] <TB0> INFO: PixTestGainPedestal::measure() done
[11:18:47.369] <TB0> INFO: PixTestGainPedestal::fit() done
[11:18:47.370] <TB0> INFO: non-linearity mean: 0.919 0.971 0.939 0.944 0.917 0.949 0.911 0.000 0.936 0.949 0.938 0.970 0.946 0.999 0.984 1.007
[11:18:47.370] <TB0> INFO: non-linearity RMS: 0.130 0.004 0.163 0.041 0.082 0.015 0.080 0.000 0.048 0.037 0.085 0.003 0.032 0.170 0.003 0.216
[11:18:47.370] <TB0> INFO: write gain/ped parameters into /home/l_tester/david/FullQualification/data/M1032_FullQualification_2016-10-21_09h47m_1477036052//000_Fulltest_m20//phCalibrationFitErr35_C0.dat
[11:18:47.383] <TB0> INFO: write gain/ped parameters into /home/l_tester/david/FullQualification/data/M1032_FullQualification_2016-10-21_09h47m_1477036052//000_Fulltest_m20//phCalibrationFitErr35_C1.dat
[11:18:47.395] <TB0> INFO: write gain/ped parameters into /home/l_tester/david/FullQualification/data/M1032_FullQualification_2016-10-21_09h47m_1477036052//000_Fulltest_m20//phCalibrationFitErr35_C2.dat
[11:18:47.408] <TB0> INFO: write gain/ped parameters into /home/l_tester/david/FullQualification/data/M1032_FullQualification_2016-10-21_09h47m_1477036052//000_Fulltest_m20//phCalibrationFitErr35_C3.dat
[11:18:47.422] <TB0> INFO: write gain/ped parameters into /home/l_tester/david/FullQualification/data/M1032_FullQualification_2016-10-21_09h47m_1477036052//000_Fulltest_m20//phCalibrationFitErr35_C4.dat
[11:18:47.445] <TB0> INFO: write gain/ped parameters into /home/l_tester/david/FullQualification/data/M1032_FullQualification_2016-10-21_09h47m_1477036052//000_Fulltest_m20//phCalibrationFitErr35_C5.dat
[11:18:47.467] <TB0> INFO: write gain/ped parameters into /home/l_tester/david/FullQualification/data/M1032_FullQualification_2016-10-21_09h47m_1477036052//000_Fulltest_m20//phCalibrationFitErr35_C6.dat
[11:18:47.489] <TB0> INFO: write gain/ped parameters into /home/l_tester/david/FullQualification/data/M1032_FullQualification_2016-10-21_09h47m_1477036052//000_Fulltest_m20//phCalibrationFitErr35_C7.dat
[11:18:47.506] <TB0> INFO: write gain/ped parameters into /home/l_tester/david/FullQualification/data/M1032_FullQualification_2016-10-21_09h47m_1477036052//000_Fulltest_m20//phCalibrationFitErr35_C8.dat
[11:18:47.528] <TB0> INFO: write gain/ped parameters into /home/l_tester/david/FullQualification/data/M1032_FullQualification_2016-10-21_09h47m_1477036052//000_Fulltest_m20//phCalibrationFitErr35_C9.dat
[11:18:47.550] <TB0> INFO: write gain/ped parameters into /home/l_tester/david/FullQualification/data/M1032_FullQualification_2016-10-21_09h47m_1477036052//000_Fulltest_m20//phCalibrationFitErr35_C10.dat
[11:18:47.572] <TB0> INFO: write gain/ped parameters into /home/l_tester/david/FullQualification/data/M1032_FullQualification_2016-10-21_09h47m_1477036052//000_Fulltest_m20//phCalibrationFitErr35_C11.dat
[11:18:47.594] <TB0> INFO: write gain/ped parameters into /home/l_tester/david/FullQualification/data/M1032_FullQualification_2016-10-21_09h47m_1477036052//000_Fulltest_m20//phCalibrationFitErr35_C12.dat
[11:18:47.616] <TB0> INFO: write gain/ped parameters into /home/l_tester/david/FullQualification/data/M1032_FullQualification_2016-10-21_09h47m_1477036052//000_Fulltest_m20//phCalibrationFitErr35_C13.dat
[11:18:47.639] <TB0> INFO: write gain/ped parameters into /home/l_tester/david/FullQualification/data/M1032_FullQualification_2016-10-21_09h47m_1477036052//000_Fulltest_m20//phCalibrationFitErr35_C14.dat
[11:18:47.661] <TB0> INFO: write gain/ped parameters into /home/l_tester/david/FullQualification/data/M1032_FullQualification_2016-10-21_09h47m_1477036052//000_Fulltest_m20//phCalibrationFitErr35_C15.dat
[11:18:47.684] <TB0> INFO: PixTestGainPedestal::fullTest() done, duration: 159 seconds
[11:18:47.684] <TB0> INFO: Decoding statistics:
[11:18:47.684] <TB0> INFO: General information:
[11:18:47.684] <TB0> INFO: 16bit words read: 3326510
[11:18:47.684] <TB0> INFO: valid events total: 332800
[11:18:47.684] <TB0> INFO: empty events: 0
[11:18:47.684] <TB0> INFO: valid events with pixels: 332800
[11:18:47.684] <TB0> INFO: valid pixel hits: 664855
[11:18:47.684] <TB0> INFO: Event errors: 0
[11:18:47.684] <TB0> INFO: start marker: 0
[11:18:47.684] <TB0> INFO: stop marker: 0
[11:18:47.684] <TB0> INFO: overflow: 0
[11:18:47.684] <TB0> INFO: invalid 5bit words: 0
[11:18:47.684] <TB0> INFO: invalid XOR eye diagram: 0
[11:18:47.684] <TB0> INFO: frame (failed synchr.): 0
[11:18:47.684] <TB0> INFO: idle data (no TBM trl): 0
[11:18:47.684] <TB0> INFO: no data (only TBM hdr): 0
[11:18:47.684] <TB0> INFO: TBM errors: 0
[11:18:47.684] <TB0> INFO: flawed TBM headers: 0
[11:18:47.684] <TB0> INFO: flawed TBM trailers: 0
[11:18:47.684] <TB0> INFO: event ID mismatches: 0
[11:18:47.684] <TB0> INFO: ROC errors: 0
[11:18:47.684] <TB0> INFO: missing ROC header(s): 0
[11:18:47.684] <TB0> INFO: misplaced readback start: 0
[11:18:47.684] <TB0> INFO: Pixel decoding errors: 0
[11:18:47.684] <TB0> INFO: pixel data incomplete: 0
[11:18:47.684] <TB0> INFO: pixel address: 0
[11:18:47.684] <TB0> INFO: pulse height fill bit: 0
[11:18:47.684] <TB0> INFO: buffer corruption: 0
[11:18:47.713] <TB0> INFO: Decoding statistics:
[11:18:47.713] <TB0> INFO: General information:
[11:18:47.713] <TB0> INFO: 16bit words read: 3455922
[11:18:47.713] <TB0> INFO: valid events total: 353536
[11:18:47.713] <TB0> INFO: empty events: 18238
[11:18:47.713] <TB0> INFO: valid events with pixels: 335298
[11:18:47.713] <TB0> INFO: valid pixel hits: 667353
[11:18:47.713] <TB0> INFO: Event errors: 0
[11:18:47.713] <TB0> INFO: start marker: 0
[11:18:47.713] <TB0> INFO: stop marker: 0
[11:18:47.713] <TB0> INFO: overflow: 0
[11:18:47.713] <TB0> INFO: invalid 5bit words: 0
[11:18:47.713] <TB0> INFO: invalid XOR eye diagram: 0
[11:18:47.713] <TB0> INFO: frame (failed synchr.): 0
[11:18:47.713] <TB0> INFO: idle data (no TBM trl): 0
[11:18:47.713] <TB0> INFO: no data (only TBM hdr): 0
[11:18:47.713] <TB0> INFO: TBM errors: 0
[11:18:47.713] <TB0> INFO: flawed TBM headers: 0
[11:18:47.713] <TB0> INFO: flawed TBM trailers: 0
[11:18:47.713] <TB0> INFO: event ID mismatches: 0
[11:18:47.713] <TB0> INFO: ROC errors: 0
[11:18:47.713] <TB0> INFO: missing ROC header(s): 0
[11:18:47.713] <TB0> INFO: misplaced readback start: 0
[11:18:47.713] <TB0> INFO: Pixel decoding errors: 0
[11:18:47.713] <TB0> INFO: pixel data incomplete: 0
[11:18:47.713] <TB0> INFO: pixel address: 0
[11:18:47.713] <TB0> INFO: pulse height fill bit: 0
[11:18:47.713] <TB0> INFO: buffer corruption: 0
[11:18:47.713] <TB0> INFO: enter test to run
[11:18:47.713] <TB0> INFO: test: exit no parameter change
[11:18:47.910] <TB0> QUIET: Connection to board 71 closed.
[11:18:47.911] <TB0> INFO: pXar: this is the end, my friend