[18:38:01.151] <TB1> INFO: *** Welcome to pxar ***
[18:38:01.151] <TB1> INFO: *** Today: 2016/10/16
[18:38:01.157] <TB1> INFO: *** Version: c8ba-dirty
[18:38:01.157] <TB1> INFO: readRocDacs: /home/l_tester/david/FullQualification/data/M1005_FullQualification_2016-10-16_17h47m_1476632865//001_Fulltest_m20//dacParameters_C0.dat .. /home/l_tester/david/FullQualification/data/M1005_FullQualification_2016-10-16_17h47m_1476632865//001_Fulltest_m20//dacParameters_C15.dat
[18:38:01.158] <TB1> INFO: readTbmDacs: /home/l_tester/david/FullQualification/data/M1005_FullQualification_2016-10-16_17h47m_1476632865//001_Fulltest_m20//tbmParameters_C0a.dat .. /home/l_tester/david/FullQualification/data/M1005_FullQualification_2016-10-16_17h47m_1476632865//001_Fulltest_m20//tbmParameters_C1b.dat
[18:38:01.158] <TB1> INFO: readMaskFile: /home/l_tester/david/FullQualification/data/M1005_FullQualification_2016-10-16_17h47m_1476632865//001_Fulltest_m20//defaultMaskFile.dat
[18:38:01.158] <TB1> INFO: readTrimFile: /home/l_tester/david/FullQualification/data/M1005_FullQualification_2016-10-16_17h47m_1476632865//001_Fulltest_m20//trimParameters_C0.dat .. /home/l_tester/david/FullQualification/data/M1005_FullQualification_2016-10-16_17h47m_1476632865//001_Fulltest_m20//trimParameters_C15.dat
[18:38:01.219] <TB1> INFO: clk: 4
[18:38:01.219] <TB1> INFO: ctr: 4
[18:38:01.219] <TB1> INFO: sda: 19
[18:38:01.219] <TB1> INFO: tin: 9
[18:38:01.219] <TB1> INFO: level: 15
[18:38:01.219] <TB1> INFO: triggerdelay: 0
[18:38:01.219] <TB1> QUIET: Instanciating API for pxar v2.7.6+55~gafdbfd9
[18:38:01.219] <TB1> INFO: Log level: INFO
[18:38:01.228] <TB1> INFO: Found DTB DTB_WXC03A
[18:38:01.239] <TB1> QUIET: Connection to board DTB_WXC03A opened.
[18:38:01.241] <TB1> INFO: DTB startup information
--- DTB info------------------------------------------
Board id: 154
HW version: DTB1.2
FW version: 4.6
SW version: 4.7
Options:
USB id: DTB_WXC03A
MAC address: 40D85511809A
Hostname: pixelDTB154
Comment:
------------------------------------------------------
[18:38:01.243] <TB1> INFO: RPC call hashes of host and DTB match: 486171790
[18:38:02.732] <TB1> INFO: DUT info:
[18:38:02.732] <TB1> INFO: The DUT currently contains the following objects:
[18:38:02.732] <TB1> INFO: 4 TBM Cores tbm10c (4 ON)
[18:38:02.732] <TB1> INFO: TBM Core alpha (0): 7 registers set
[18:38:02.732] <TB1> INFO: TBM Core beta (1): 7 registers set
[18:38:02.732] <TB1> INFO: TBM Core alpha (2): 7 registers set
[18:38:02.732] <TB1> INFO: TBM Core beta (3): 7 registers set
[18:38:02.732] <TB1> INFO: 16 ROCs proc600 (16 ON) with 4160 pixelConfigs
[18:38:02.732] <TB1> INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[18:38:02.732] <TB1> INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[18:38:02.732] <TB1> INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[18:38:02.732] <TB1> INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[18:38:02.732] <TB1> INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[18:38:02.732] <TB1> INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[18:38:02.732] <TB1> INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[18:38:02.732] <TB1> INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[18:38:02.732] <TB1> INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[18:38:02.732] <TB1> INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[18:38:02.732] <TB1> INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[18:38:02.732] <TB1> INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[18:38:02.732] <TB1> INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[18:38:02.732] <TB1> INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[18:38:02.732] <TB1> INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[18:38:02.732] <TB1> INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[18:38:03.133] <TB1> INFO: enter 'restricted' command line mode
[18:38:03.133] <TB1> INFO: enter test to run
[18:38:03.133] <TB1> INFO: test: pretest no parameter change
[18:38:03.133] <TB1> INFO: running: pretest
[18:38:03.138] <TB1> INFO: ######################################################################
[18:38:03.138] <TB1> INFO: PixTestPretest::doTest()
[18:38:03.138] <TB1> INFO: ######################################################################
[18:38:03.139] <TB1> INFO: ----------------------------------------------------------------------
[18:38:03.139] <TB1> INFO: PixTestPretest::programROC()
[18:38:03.139] <TB1> INFO: ----------------------------------------------------------------------
[18:38:21.152] <TB1> INFO: PixTestPretest::programROC() done: ROCs are all programmable
[18:38:21.152] <TB1> INFO: IA differences per ROC: 20.1 19.3 20.1 16.9 17.7 16.9 18.5 17.7 16.9 19.3 15.3 19.3 16.9 18.5 18.5 18.5
[18:38:21.207] <TB1> INFO: ----------------------------------------------------------------------
[18:38:21.207] <TB1> INFO: PixTestPretest::setVana() target Ia = 24 mA/ROC
[18:38:21.207] <TB1> INFO: ----------------------------------------------------------------------
[18:38:28.699] <TB1> INFO: PixTestPretest::setVana() done, Module Ia 392.3 mA = 24.5187 mA/ROC
[18:38:28.699] <TB1> INFO: i(loss) [mA/ROC]: 20.9 20.1 20.9 20.1 20.9 20.1 20.1 20.1 20.9 20.1 20.1 20.1 20.1 20.1 20.1 20.1
[18:38:28.733] <TB1> INFO: ----------------------------------------------------------------------
[18:38:28.733] <TB1> INFO: PixTestPretest::findTiming()
[18:38:28.733] <TB1> INFO: ----------------------------------------------------------------------
[18:38:28.733] <TB1> INFO: PixTestCmd::init()

[18:39:00.988] <TB1> INFO: TBM phases: 160MHz: 7, 400MHz: 2, TBM delays: ROC(0/1):5, header/trailer: 1, token: 1
[18:39:00.988] <TB1> INFO: (success/tries = 100/100), width = 4
[18:39:02.492] <TB1> INFO: ----------------------------------------------------------------------
[18:39:02.492] <TB1> INFO: PixTestPretest::findWorkingPixel()
[18:39:02.492] <TB1> INFO: ----------------------------------------------------------------------
[18:39:02.587] <TB1> INFO: Expecting 231680 events.
[18:39:12.746] <TB1> INFO: 231680 events read in total (9567ms).
[18:39:12.754] <TB1> INFO: Test took 10257ms.
[18:39:12.997] <TB1> INFO: Found working pixel in all ROCs: col/row = 12/22
[18:39:13.034] <TB1> INFO: ----------------------------------------------------------------------
[18:39:13.034] <TB1> INFO: PixTestPretest::setVthrCompCalDel()
[18:39:13.034] <TB1> INFO: ----------------------------------------------------------------------
[18:39:13.129] <TB1> INFO: Expecting 231680 events.
[18:39:23.272] <TB1> INFO: 231680 events read in total (9552ms).
[18:39:23.284] <TB1> INFO: Test took 10245ms.
[18:39:23.548] <TB1> INFO: PixTestPretest::setVthrCompCalDel() done
[18:39:23.548] <TB1> INFO: CalDel: 87 78 92 92 102 98 70 94 91 93 83 96 83 90 113 107
[18:39:23.548] <TB1> INFO: VthrComp: 51 51 51 51 51 51 51 51 51 51 51 51 51 51 51 52
[18:39:23.552] <TB1> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1005_FullQualification_2016-10-16_17h47m_1476632865//001_Fulltest_m20//dacParameters_C0.dat
[18:39:23.552] <TB1> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1005_FullQualification_2016-10-16_17h47m_1476632865//001_Fulltest_m20//dacParameters_C1.dat
[18:39:23.552] <TB1> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1005_FullQualification_2016-10-16_17h47m_1476632865//001_Fulltest_m20//dacParameters_C2.dat
[18:39:23.553] <TB1> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1005_FullQualification_2016-10-16_17h47m_1476632865//001_Fulltest_m20//dacParameters_C3.dat
[18:39:23.553] <TB1> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1005_FullQualification_2016-10-16_17h47m_1476632865//001_Fulltest_m20//dacParameters_C4.dat
[18:39:23.553] <TB1> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1005_FullQualification_2016-10-16_17h47m_1476632865//001_Fulltest_m20//dacParameters_C5.dat
[18:39:23.553] <TB1> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1005_FullQualification_2016-10-16_17h47m_1476632865//001_Fulltest_m20//dacParameters_C6.dat
[18:39:23.554] <TB1> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1005_FullQualification_2016-10-16_17h47m_1476632865//001_Fulltest_m20//dacParameters_C7.dat
[18:39:23.554] <TB1> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1005_FullQualification_2016-10-16_17h47m_1476632865//001_Fulltest_m20//dacParameters_C8.dat
[18:39:23.554] <TB1> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1005_FullQualification_2016-10-16_17h47m_1476632865//001_Fulltest_m20//dacParameters_C9.dat
[18:39:23.554] <TB1> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1005_FullQualification_2016-10-16_17h47m_1476632865//001_Fulltest_m20//dacParameters_C10.dat
[18:39:23.554] <TB1> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1005_FullQualification_2016-10-16_17h47m_1476632865//001_Fulltest_m20//dacParameters_C11.dat
[18:39:23.554] <TB1> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1005_FullQualification_2016-10-16_17h47m_1476632865//001_Fulltest_m20//dacParameters_C12.dat
[18:39:23.555] <TB1> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1005_FullQualification_2016-10-16_17h47m_1476632865//001_Fulltest_m20//dacParameters_C13.dat
[18:39:23.555] <TB1> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1005_FullQualification_2016-10-16_17h47m_1476632865//001_Fulltest_m20//dacParameters_C14.dat
[18:39:23.555] <TB1> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1005_FullQualification_2016-10-16_17h47m_1476632865//001_Fulltest_m20//dacParameters_C15.dat
[18:39:23.555] <TB1> INFO: write tbm parameters into /home/l_tester/david/FullQualification/data/M1005_FullQualification_2016-10-16_17h47m_1476632865//001_Fulltest_m20//tbmParameters_C0a.dat
[18:39:23.555] <TB1> INFO: write tbm parameters into /home/l_tester/david/FullQualification/data/M1005_FullQualification_2016-10-16_17h47m_1476632865//001_Fulltest_m20//tbmParameters_C0b.dat
[18:39:23.555] <TB1> INFO: write tbm parameters into /home/l_tester/david/FullQualification/data/M1005_FullQualification_2016-10-16_17h47m_1476632865//001_Fulltest_m20//tbmParameters_C1a.dat
[18:39:23.555] <TB1> INFO: write tbm parameters into /home/l_tester/david/FullQualification/data/M1005_FullQualification_2016-10-16_17h47m_1476632865//001_Fulltest_m20//tbmParameters_C1b.dat
[18:39:23.555] <TB1> INFO: PixTestPretest::doTest() done, duration: 80 seconds
[18:39:23.608] <TB1> INFO: enter test to run
[18:39:23.608] <TB1> INFO: test: FullTest no parameter change
[18:39:23.608] <TB1> INFO: running: fulltest
[18:39:23.609] <TB1> INFO: ######################################################################
[18:39:23.609] <TB1> INFO: PixTestFullTest::doTest()
[18:39:23.609] <TB1> INFO: ######################################################################
[18:39:23.610] <TB1> INFO: ######################################################################
[18:39:23.610] <TB1> INFO: PixTestAlive::doTest()
[18:39:23.610] <TB1> INFO: ######################################################################
[18:39:23.611] <TB1> INFO: ----------------------------------------------------------------------
[18:39:23.611] <TB1> INFO: PixTestAlive::aliveTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[18:39:23.611] <TB1> INFO: ----------------------------------------------------------------------
[18:39:23.848] <TB1> INFO: Expecting 41600 events.
[18:39:27.347] <TB1> INFO: 41600 events read in total (2907ms).
[18:39:27.347] <TB1> INFO: Test took 3735ms.
[18:39:27.581] <TB1> INFO: PixTestAlive::aliveTest() done
[18:39:27.581] <TB1> INFO: number of dead pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 1 0 0 0 0 0
[18:39:27.583] <TB1> INFO: ----------------------------------------------------------------------
[18:39:27.583] <TB1> INFO: PixTestAlive::maskTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[18:39:27.583] <TB1> INFO: ----------------------------------------------------------------------
[18:39:27.828] <TB1> INFO: Expecting 41600 events.
[18:39:30.842] <TB1> INFO: 41600 events read in total (2422ms).
[18:39:30.842] <TB1> INFO: Test took 3257ms.
[18:39:30.842] <TB1> INFO: mask vs. old pixelAlive PixelAlive_C0_V0 .. PixelAlive_C15_V0
[18:39:31.079] <TB1> INFO: PixTestAlive::maskTest() done
[18:39:31.079] <TB1> INFO: number of mask-defect pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[18:39:31.081] <TB1> INFO: ----------------------------------------------------------------------
[18:39:31.081] <TB1> INFO: PixTestAlive::addressDecodingTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[18:39:31.081] <TB1> INFO: ----------------------------------------------------------------------
[18:39:31.328] <TB1> INFO: Expecting 41600 events.
[18:39:34.853] <TB1> INFO: 41600 events read in total (2933ms).
[18:39:34.854] <TB1> INFO: Test took 3771ms.
[18:39:35.083] <TB1> INFO: PixTestAlive::addressDecodingTest() done
[18:39:35.083] <TB1> INFO: number of address-decoding pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[18:39:35.083] <TB1> INFO: PixTestAlive::doTest() done, duration: 11 seconds
[18:39:35.083] <TB1> INFO: Decoding statistics:
[18:39:35.083] <TB1> INFO: General information:
[18:39:35.083] <TB1> INFO: 16bit words read: 0
[18:39:35.083] <TB1> INFO: valid events total: 0
[18:39:35.083] <TB1> INFO: empty events: 0
[18:39:35.083] <TB1> INFO: valid events with pixels: 0
[18:39:35.083] <TB1> INFO: valid pixel hits: 0
[18:39:35.083] <TB1> INFO: Event errors: 0
[18:39:35.083] <TB1> INFO: start marker: 0
[18:39:35.083] <TB1> INFO: stop marker: 0
[18:39:35.083] <TB1> INFO: overflow: 0
[18:39:35.083] <TB1> INFO: invalid 5bit words: 0
[18:39:35.083] <TB1> INFO: invalid XOR eye diagram: 0
[18:39:35.083] <TB1> INFO: frame (failed synchr.): 0
[18:39:35.083] <TB1> INFO: idle data (no TBM trl): 0
[18:39:35.083] <TB1> INFO: no data (only TBM hdr): 0
[18:39:35.083] <TB1> INFO: TBM errors: 0
[18:39:35.083] <TB1> INFO: flawed TBM headers: 0
[18:39:35.083] <TB1> INFO: flawed TBM trailers: 0
[18:39:35.083] <TB1> INFO: event ID mismatches: 0
[18:39:35.083] <TB1> INFO: ROC errors: 0
[18:39:35.083] <TB1> INFO: missing ROC header(s): 0
[18:39:35.083] <TB1> INFO: misplaced readback start: 0
[18:39:35.083] <TB1> INFO: Pixel decoding errors: 0
[18:39:35.083] <TB1> INFO: pixel data incomplete: 0
[18:39:35.083] <TB1> INFO: pixel address: 0
[18:39:35.083] <TB1> INFO: pulse height fill bit: 0
[18:39:35.083] <TB1> INFO: buffer corruption: 0
[18:39:35.088] <TB1> INFO: readReadbackCal: /home/l_tester/david/FullQualification/data/M1005_FullQualification_2016-10-16_17h47m_1476632865//001_Fulltest_m20//readbackCal_C0.dat .. /home/l_tester/david/FullQualification/data/M1005_FullQualification_2016-10-16_17h47m_1476632865//001_Fulltest_m20//readbackCal_C15.dat
[18:39:35.088] <TB1> INFO: readGainPedestalParameters /home/l_tester/david/FullQualification/data/M1005_FullQualification_2016-10-16_17h47m_1476632865//001_Fulltest_m20//phCalibrationFitErr_C0.dat .. /home/l_tester/david/FullQualification/data/M1005_FullQualification_2016-10-16_17h47m_1476632865//001_Fulltest_m20//phCalibrationFitErr_C15.dat

[18:39:35.088] <TB1> INFO: ######################################################################
[18:39:35.088] <TB1> INFO: PixTestReadback::doTest()
[18:39:35.088] <TB1> INFO: ######################################################################
[18:39:35.088] <TB1> INFO: ----------------------------------------------------------------------
[18:39:35.088] <TB1> INFO: PixTestReadback::CalibrateVd()
[18:39:35.088] <TB1> INFO: ----------------------------------------------------------------------
[18:39:45.052] <TB1> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1005_FullQualification_2016-10-16_17h47m_1476632865//001_Fulltest_m20//readbackCal_C0.dat
[18:39:45.052] <TB1> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1005_FullQualification_2016-10-16_17h47m_1476632865//001_Fulltest_m20//readbackCal_C1.dat
[18:39:45.052] <TB1> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1005_FullQualification_2016-10-16_17h47m_1476632865//001_Fulltest_m20//readbackCal_C2.dat
[18:39:45.052] <TB1> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1005_FullQualification_2016-10-16_17h47m_1476632865//001_Fulltest_m20//readbackCal_C3.dat
[18:39:45.052] <TB1> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1005_FullQualification_2016-10-16_17h47m_1476632865//001_Fulltest_m20//readbackCal_C4.dat
[18:39:45.052] <TB1> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1005_FullQualification_2016-10-16_17h47m_1476632865//001_Fulltest_m20//readbackCal_C5.dat
[18:39:45.052] <TB1> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1005_FullQualification_2016-10-16_17h47m_1476632865//001_Fulltest_m20//readbackCal_C6.dat
[18:39:45.052] <TB1> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1005_FullQualification_2016-10-16_17h47m_1476632865//001_Fulltest_m20//readbackCal_C7.dat
[18:39:45.053] <TB1> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1005_FullQualification_2016-10-16_17h47m_1476632865//001_Fulltest_m20//readbackCal_C8.dat
[18:39:45.053] <TB1> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1005_FullQualification_2016-10-16_17h47m_1476632865//001_Fulltest_m20//readbackCal_C9.dat
[18:39:45.053] <TB1> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1005_FullQualification_2016-10-16_17h47m_1476632865//001_Fulltest_m20//readbackCal_C10.dat
[18:39:45.053] <TB1> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1005_FullQualification_2016-10-16_17h47m_1476632865//001_Fulltest_m20//readbackCal_C11.dat
[18:39:45.053] <TB1> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1005_FullQualification_2016-10-16_17h47m_1476632865//001_Fulltest_m20//readbackCal_C12.dat
[18:39:45.053] <TB1> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1005_FullQualification_2016-10-16_17h47m_1476632865//001_Fulltest_m20//readbackCal_C13.dat
[18:39:45.053] <TB1> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1005_FullQualification_2016-10-16_17h47m_1476632865//001_Fulltest_m20//readbackCal_C14.dat
[18:39:45.053] <TB1> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1005_FullQualification_2016-10-16_17h47m_1476632865//001_Fulltest_m20//readbackCal_C15.dat
[18:39:45.082] <TB1> INFO: PixTestPattern:: pg_setup set to default.
[18:39:45.082] <TB1> INFO: ----------------------------------------------------------------------
[18:39:45.082] <TB1> INFO: PixTestReadback::CalibrateVa()
[18:39:45.082] <TB1> INFO: ----------------------------------------------------------------------
[18:39:55.004] <TB1> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1005_FullQualification_2016-10-16_17h47m_1476632865//001_Fulltest_m20//readbackCal_C0.dat
[18:39:55.004] <TB1> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1005_FullQualification_2016-10-16_17h47m_1476632865//001_Fulltest_m20//readbackCal_C1.dat
[18:39:55.004] <TB1> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1005_FullQualification_2016-10-16_17h47m_1476632865//001_Fulltest_m20//readbackCal_C2.dat
[18:39:55.004] <TB1> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1005_FullQualification_2016-10-16_17h47m_1476632865//001_Fulltest_m20//readbackCal_C3.dat
[18:39:55.004] <TB1> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1005_FullQualification_2016-10-16_17h47m_1476632865//001_Fulltest_m20//readbackCal_C4.dat
[18:39:55.004] <TB1> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1005_FullQualification_2016-10-16_17h47m_1476632865//001_Fulltest_m20//readbackCal_C5.dat
[18:39:55.004] <TB1> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1005_FullQualification_2016-10-16_17h47m_1476632865//001_Fulltest_m20//readbackCal_C6.dat
[18:39:55.004] <TB1> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1005_FullQualification_2016-10-16_17h47m_1476632865//001_Fulltest_m20//readbackCal_C7.dat
[18:39:55.004] <TB1> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1005_FullQualification_2016-10-16_17h47m_1476632865//001_Fulltest_m20//readbackCal_C8.dat
[18:39:55.004] <TB1> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1005_FullQualification_2016-10-16_17h47m_1476632865//001_Fulltest_m20//readbackCal_C9.dat
[18:39:55.005] <TB1> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1005_FullQualification_2016-10-16_17h47m_1476632865//001_Fulltest_m20//readbackCal_C10.dat
[18:39:55.005] <TB1> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1005_FullQualification_2016-10-16_17h47m_1476632865//001_Fulltest_m20//readbackCal_C11.dat
[18:39:55.005] <TB1> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1005_FullQualification_2016-10-16_17h47m_1476632865//001_Fulltest_m20//readbackCal_C12.dat
[18:39:55.005] <TB1> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1005_FullQualification_2016-10-16_17h47m_1476632865//001_Fulltest_m20//readbackCal_C13.dat
[18:39:55.005] <TB1> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1005_FullQualification_2016-10-16_17h47m_1476632865//001_Fulltest_m20//readbackCal_C14.dat
[18:39:55.005] <TB1> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1005_FullQualification_2016-10-16_17h47m_1476632865//001_Fulltest_m20//readbackCal_C15.dat
[18:39:55.038] <TB1> INFO: PixTestPattern:: pg_setup set to default.
[18:39:55.038] <TB1> INFO: ----------------------------------------------------------------------
[18:39:55.038] <TB1> INFO: PixTestReadback::readbackVbg()
[18:39:55.038] <TB1> INFO: ----------------------------------------------------------------------
[18:40:02.700] <TB1> INFO: PixTestPattern:: pg_setup set to default.
[18:40:02.700] <TB1> INFO: ----------------------------------------------------------------------
[18:40:02.700] <TB1> INFO: PixTestReadback::getCalibratedVbg()
[18:40:02.700] <TB1> INFO: ----------------------------------------------------------------------
[18:40:02.700] <TB1> INFO: Vbg will be calibrated using Vd calibration
[18:40:02.700] <TB1> INFO: /*/*/*/*::: ROC 0: uncalibrated Vbg = 155.3calibrated Vbg = 1.17651 :::*/*/*/*/
[18:40:02.700] <TB1> INFO: /*/*/*/*::: ROC 1: uncalibrated Vbg = 146.5calibrated Vbg = 1.17202 :::*/*/*/*/
[18:40:02.700] <TB1> INFO: /*/*/*/*::: ROC 2: uncalibrated Vbg = 169.4calibrated Vbg = 1.17454 :::*/*/*/*/
[18:40:02.700] <TB1> INFO: /*/*/*/*::: ROC 3: uncalibrated Vbg = 149.5calibrated Vbg = 1.15942 :::*/*/*/*/
[18:40:02.700] <TB1> INFO: /*/*/*/*::: ROC 4: uncalibrated Vbg = 153.4calibrated Vbg = 1.17668 :::*/*/*/*/
[18:40:02.700] <TB1> INFO: /*/*/*/*::: ROC 5: uncalibrated Vbg = 154.8calibrated Vbg = 1.17244 :::*/*/*/*/
[18:40:02.700] <TB1> INFO: /*/*/*/*::: ROC 6: uncalibrated Vbg = 164.7calibrated Vbg = 1.17852 :::*/*/*/*/
[18:40:02.700] <TB1> INFO: /*/*/*/*::: ROC 7: uncalibrated Vbg = 161.4calibrated Vbg = 1.1781 :::*/*/*/*/
[18:40:02.700] <TB1> INFO: /*/*/*/*::: ROC 8: uncalibrated Vbg = 154.8calibrated Vbg = 1.17688 :::*/*/*/*/
[18:40:02.700] <TB1> INFO: /*/*/*/*::: ROC 9: uncalibrated Vbg = 152.8calibrated Vbg = 1.16136 :::*/*/*/*/
[18:40:02.700] <TB1> INFO: /*/*/*/*::: ROC 10: uncalibrated Vbg = 149.9calibrated Vbg = 1.16806 :::*/*/*/*/
[18:40:02.700] <TB1> INFO: /*/*/*/*::: ROC 11: uncalibrated Vbg = 150.3calibrated Vbg = 1.16517 :::*/*/*/*/
[18:40:02.700] <TB1> INFO: /*/*/*/*::: ROC 12: uncalibrated Vbg = 154.9calibrated Vbg = 1.17087 :::*/*/*/*/
[18:40:02.700] <TB1> INFO: /*/*/*/*::: ROC 13: uncalibrated Vbg = 148.5calibrated Vbg = 1.17096 :::*/*/*/*/
[18:40:02.700] <TB1> INFO: /*/*/*/*::: ROC 14: uncalibrated Vbg = 162calibrated Vbg = 1.17542 :::*/*/*/*/
[18:40:02.700] <TB1> INFO: /*/*/*/*::: ROC 15: uncalibrated Vbg = 161calibrated Vbg = 1.17557 :::*/*/*/*/
[18:40:02.703] <TB1> INFO: ----------------------------------------------------------------------
[18:40:02.703] <TB1> INFO: PixTestReadback::CalibrateIa()
[18:40:02.703] <TB1> INFO: ----------------------------------------------------------------------
[18:42:43.525] <TB1> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1005_FullQualification_2016-10-16_17h47m_1476632865//001_Fulltest_m20//readbackCal_C0.dat
[18:42:43.525] <TB1> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1005_FullQualification_2016-10-16_17h47m_1476632865//001_Fulltest_m20//readbackCal_C1.dat
[18:42:43.525] <TB1> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1005_FullQualification_2016-10-16_17h47m_1476632865//001_Fulltest_m20//readbackCal_C2.dat
[18:42:43.525] <TB1> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1005_FullQualification_2016-10-16_17h47m_1476632865//001_Fulltest_m20//readbackCal_C3.dat
[18:42:43.525] <TB1> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1005_FullQualification_2016-10-16_17h47m_1476632865//001_Fulltest_m20//readbackCal_C4.dat
[18:42:43.525] <TB1> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1005_FullQualification_2016-10-16_17h47m_1476632865//001_Fulltest_m20//readbackCal_C5.dat
[18:42:43.526] <TB1> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1005_FullQualification_2016-10-16_17h47m_1476632865//001_Fulltest_m20//readbackCal_C6.dat
[18:42:43.526] <TB1> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1005_FullQualification_2016-10-16_17h47m_1476632865//001_Fulltest_m20//readbackCal_C7.dat
[18:42:43.526] <TB1> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1005_FullQualification_2016-10-16_17h47m_1476632865//001_Fulltest_m20//readbackCal_C8.dat
[18:42:43.526] <TB1> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1005_FullQualification_2016-10-16_17h47m_1476632865//001_Fulltest_m20//readbackCal_C9.dat
[18:42:43.526] <TB1> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1005_FullQualification_2016-10-16_17h47m_1476632865//001_Fulltest_m20//readbackCal_C10.dat
[18:42:43.526] <TB1> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1005_FullQualification_2016-10-16_17h47m_1476632865//001_Fulltest_m20//readbackCal_C11.dat
[18:42:43.526] <TB1> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1005_FullQualification_2016-10-16_17h47m_1476632865//001_Fulltest_m20//readbackCal_C12.dat
[18:42:43.526] <TB1> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1005_FullQualification_2016-10-16_17h47m_1476632865//001_Fulltest_m20//readbackCal_C13.dat
[18:42:43.526] <TB1> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1005_FullQualification_2016-10-16_17h47m_1476632865//001_Fulltest_m20//readbackCal_C14.dat
[18:42:43.526] <TB1> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1005_FullQualification_2016-10-16_17h47m_1476632865//001_Fulltest_m20//readbackCal_C15.dat
[18:42:43.554] <TB1> INFO: PixTestPattern:: pg_setup set to default.
[18:42:43.555] <TB1> INFO: PixTestReadback::doTest() done
[18:42:43.555] <TB1> INFO: Decoding statistics:
[18:42:43.555] <TB1> INFO: General information:
[18:42:43.555] <TB1> INFO: 16bit words read: 1536
[18:42:43.555] <TB1> INFO: valid events total: 256
[18:42:43.555] <TB1> INFO: empty events: 256
[18:42:43.555] <TB1> INFO: valid events with pixels: 0
[18:42:43.555] <TB1> INFO: valid pixel hits: 0
[18:42:43.555] <TB1> INFO: Event errors: 0
[18:42:43.555] <TB1> INFO: start marker: 0
[18:42:43.555] <TB1> INFO: stop marker: 0
[18:42:43.555] <TB1> INFO: overflow: 0
[18:42:43.555] <TB1> INFO: invalid 5bit words: 0
[18:42:43.555] <TB1> INFO: invalid XOR eye diagram: 0
[18:42:43.555] <TB1> INFO: frame (failed synchr.): 0
[18:42:43.555] <TB1> INFO: idle data (no TBM trl): 0
[18:42:43.555] <TB1> INFO: no data (only TBM hdr): 0
[18:42:43.555] <TB1> INFO: TBM errors: 0
[18:42:43.555] <TB1> INFO: flawed TBM headers: 0
[18:42:43.555] <TB1> INFO: flawed TBM trailers: 0
[18:42:43.555] <TB1> INFO: event ID mismatches: 0
[18:42:43.555] <TB1> INFO: ROC errors: 0
[18:42:43.555] <TB1> INFO: missing ROC header(s): 0
[18:42:43.555] <TB1> INFO: misplaced readback start: 0
[18:42:43.555] <TB1> INFO: Pixel decoding errors: 0
[18:42:43.555] <TB1> INFO: pixel data incomplete: 0
[18:42:43.555] <TB1> INFO: pixel address: 0
[18:42:43.555] <TB1> INFO: pulse height fill bit: 0
[18:42:43.555] <TB1> INFO: buffer corruption: 0
[18:42:43.610] <TB1> INFO: ######################################################################
[18:42:43.610] <TB1> INFO: PixTestBBMap::doTest() Ntrig = 5, VcalS = 250 (high range)
[18:42:43.610] <TB1> INFO: ######################################################################
[18:42:43.612] <TB1> INFO: ---> dac: VthrComp name: calSMap ntrig: 5 dacrange: 0 .. 149 (-1/-1) hits flags = 514 (plus default)
[18:42:43.627] <TB1> INFO: dacScan split into 1 runs with ntrig = 5
[18:42:43.627] <TB1> INFO: run 1 of 1
[18:42:43.864] <TB1> INFO: Expecting 3120000 events.
[18:43:15.723] <TB1> INFO: 664935 events read in total (31267ms).











[18:43:45.973] <TB1> INFO: 1323745 events read in total (61517ms).











[18:44:16.208] <TB1> INFO: 1978235 events read in total (91752ms).











[18:44:46.372] <TB1> INFO: 2630920 events read in total (121916ms).











[18:45:08.928] <TB1> INFO: 3120000 events read in total (144472ms).
[18:45:09.004] <TB1> INFO: Test took 145378ms.
[18:45:34.285] <TB1> INFO: PixTestBBMap::doTest() done with 4 decoding errors: , duration: 170 seconds
[18:45:34.286] <TB1> INFO: number of dead bumps (per ROC): 1 0 0 4 0 0 2 0 0 2 0 1 20 4 3 0
[18:45:34.286] <TB1> INFO: separation cut (per ROC): 108 105 115 111 106 110 109 107 104 108 108 107 108 104 109 112
[18:45:34.286] <TB1> INFO: Decoding statistics:
[18:45:34.286] <TB1> INFO: General information:
[18:45:34.286] <TB1> INFO: 16bit words read: 0
[18:45:34.286] <TB1> INFO: valid events total: 0
[18:45:34.286] <TB1> INFO: empty events: 0
[18:45:34.286] <TB1> INFO: valid events with pixels: 0
[18:45:34.286] <TB1> INFO: valid pixel hits: 0
[18:45:34.286] <TB1> INFO: Event errors: 0
[18:45:34.286] <TB1> INFO: start marker: 0
[18:45:34.286] <TB1> INFO: stop marker: 0
[18:45:34.286] <TB1> INFO: overflow: 0
[18:45:34.286] <TB1> INFO: invalid 5bit words: 0
[18:45:34.286] <TB1> INFO: invalid XOR eye diagram: 0
[18:45:34.286] <TB1> INFO: frame (failed synchr.): 0
[18:45:34.286] <TB1> INFO: idle data (no TBM trl): 0
[18:45:34.286] <TB1> INFO: no data (only TBM hdr): 0
[18:45:34.286] <TB1> INFO: TBM errors: 0
[18:45:34.286] <TB1> INFO: flawed TBM headers: 0
[18:45:34.286] <TB1> INFO: flawed TBM trailers: 0
[18:45:34.286] <TB1> INFO: event ID mismatches: 0
[18:45:34.286] <TB1> INFO: ROC errors: 0
[18:45:34.286] <TB1> INFO: missing ROC header(s): 0
[18:45:34.286] <TB1> INFO: misplaced readback start: 0
[18:45:34.286] <TB1> INFO: Pixel decoding errors: 0
[18:45:34.286] <TB1> INFO: pixel data incomplete: 0
[18:45:34.286] <TB1> INFO: pixel address: 0
[18:45:34.286] <TB1> INFO: pulse height fill bit: 0
[18:45:34.286] <TB1> INFO: buffer corruption: 0
[18:45:34.330] <TB1> INFO: ######################################################################
[18:45:34.330] <TB1> INFO: PixTestScurves::fullTest() ntrig = 50, dacs/step = -1, ntrig/step = -1
[18:45:34.330] <TB1> INFO: ######################################################################
[18:45:34.330] <TB1> INFO: ----------------------------------------------------------------------
[18:45:34.330] <TB1> INFO: PixTestScurves::scurves(Vcal), ntrig = 50, dacs/step = -1, ntrig/step = -1
[18:45:34.330] <TB1> INFO: ----------------------------------------------------------------------
[18:45:34.330] <TB1> INFO: ---> dac: Vcal name: scurveVcal ntrig: 50 dacrange: 25 .. 200 (-1/-1) hits flags = 528 (plus default)
[18:45:34.346] <TB1> INFO: dacScan split into 1 runs with ntrig = 50
[18:45:34.346] <TB1> INFO: run 1 of 1
[18:45:34.587] <TB1> INFO: Expecting 36608000 events.
[18:45:59.605] <TB1> INFO: 683200 events read in total (24427ms).
[18:46:23.509] <TB1> INFO: 1351800 events read in total (48331ms).
[18:46:46.997] <TB1> INFO: 2020050 events read in total (71819ms).
[18:47:10.517] <TB1> INFO: 2688150 events read in total (95339ms).
[18:47:33.811] <TB1> INFO: 3354650 events read in total (118633ms).
[18:47:57.024] <TB1> INFO: 4020850 events read in total (141846ms).
[18:48:20.062] <TB1> INFO: 4686500 events read in total (164885ms).
[18:48:43.214] <TB1> INFO: 5349600 events read in total (188036ms).
[18:49:06.537] <TB1> INFO: 6016050 events read in total (211359ms).
[18:49:29.589] <TB1> INFO: 6682900 events read in total (234411ms).
[18:49:52.799] <TB1> INFO: 7347650 events read in total (257621ms).
[18:50:15.749] <TB1> INFO: 8013900 events read in total (280571ms).
[18:50:38.814] <TB1> INFO: 8679850 events read in total (303636ms).
[18:51:02.034] <TB1> INFO: 9344050 events read in total (326856ms).
[18:51:25.187] <TB1> INFO: 10008850 events read in total (350009ms).
[18:51:48.348] <TB1> INFO: 10672000 events read in total (373170ms).
[18:52:11.744] <TB1> INFO: 11335000 events read in total (396567ms).
[18:52:35.040] <TB1> INFO: 11998850 events read in total (419862ms).
[18:52:57.805] <TB1> INFO: 12660250 events read in total (442627ms).
[18:53:21.018] <TB1> INFO: 13321000 events read in total (465840ms).
[18:53:44.139] <TB1> INFO: 13982050 events read in total (488961ms).
[18:54:07.265] <TB1> INFO: 14643250 events read in total (512087ms).
[18:54:30.388] <TB1> INFO: 15305500 events read in total (535210ms).
[18:54:53.314] <TB1> INFO: 15966450 events read in total (558136ms).
[18:55:16.435] <TB1> INFO: 16626500 events read in total (581257ms).
[18:55:39.790] <TB1> INFO: 17286350 events read in total (604612ms).
[18:56:02.832] <TB1> INFO: 17944550 events read in total (627654ms).
[18:56:25.934] <TB1> INFO: 18604050 events read in total (650756ms).
[18:56:49.055] <TB1> INFO: 19262400 events read in total (673877ms).
[18:57:12.175] <TB1> INFO: 19920550 events read in total (696997ms).
[18:57:35.071] <TB1> INFO: 20577500 events read in total (719893ms).
[18:57:58.161] <TB1> INFO: 21234050 events read in total (742983ms).
[18:58:21.321] <TB1> INFO: 21891150 events read in total (766143ms).
[18:58:44.638] <TB1> INFO: 22546500 events read in total (789460ms).
[18:59:07.548] <TB1> INFO: 23201750 events read in total (812370ms).
[18:59:30.497] <TB1> INFO: 23854850 events read in total (835319ms).
[18:59:53.571] <TB1> INFO: 24508650 events read in total (858393ms).
[19:00:16.377] <TB1> INFO: 25164250 events read in total (881199ms).
[19:00:39.326] <TB1> INFO: 25817500 events read in total (904148ms).
[19:01:02.517] <TB1> INFO: 26470300 events read in total (927339ms).
[19:01:25.692] <TB1> INFO: 27125250 events read in total (950515ms).
[19:01:48.804] <TB1> INFO: 27779600 events read in total (973626ms).
[19:02:11.569] <TB1> INFO: 28434100 events read in total (996391ms).
[19:02:34.919] <TB1> INFO: 29088650 events read in total (1019741ms).
[19:02:57.626] <TB1> INFO: 29741650 events read in total (1042448ms).
[19:03:20.699] <TB1> INFO: 30394300 events read in total (1065521ms).
[19:03:43.684] <TB1> INFO: 31049200 events read in total (1088506ms).
[19:04:06.410] <TB1> INFO: 31701750 events read in total (1111232ms).
[19:04:29.266] <TB1> INFO: 32354750 events read in total (1134088ms).
[19:04:52.103] <TB1> INFO: 33006900 events read in total (1156925ms).
[19:05:14.939] <TB1> INFO: 33659550 events read in total (1179761ms).
[19:05:37.829] <TB1> INFO: 34314950 events read in total (1202651ms).
[19:06:00.593] <TB1> INFO: 34969300 events read in total (1225415ms).
[19:06:23.617] <TB1> INFO: 35624250 events read in total (1248439ms).
[19:06:46.977] <TB1> INFO: 36285050 events read in total (1271799ms).
[19:06:58.168] <TB1> INFO: 36608000 events read in total (1282990ms).
[19:06:58.253] <TB1> INFO: Test took 1283908ms.
[19:06:58.841] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[19:07:00.866] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[19:07:02.991] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[19:07:05.042] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[19:07:06.991] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[19:07:08.868] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[19:07:10.814] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[19:07:12.803] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[19:07:14.352] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[19:07:16.302] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[19:07:18.213] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[19:07:20.114] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[19:07:21.693] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[19:07:23.702] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[19:07:25.218] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[19:07:26.718] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[19:07:28.394] <TB1> INFO: PixTestScurves::scurves() done
[19:07:28.394] <TB1> INFO: Vcal mean: 117.18 118.85 116.70 121.28 114.94 123.24 112.55 126.24 111.53 123.92 111.90 107.48 117.89 115.60 109.92 119.89
[19:07:28.394] <TB1> INFO: Vcal RMS: 5.64 5.65 6.14 5.91 4.98 6.08 4.97 6.59 4.94 6.30 5.57 5.23 6.08 5.80 4.54 7.21
[19:07:28.394] <TB1> INFO: PixTestScurves::fullTest() done, duration: 1314 seconds
[19:07:28.394] <TB1> INFO: Decoding statistics:
[19:07:28.394] <TB1> INFO: General information:
[19:07:28.394] <TB1> INFO: 16bit words read: 0
[19:07:28.394] <TB1> INFO: valid events total: 0
[19:07:28.394] <TB1> INFO: empty events: 0
[19:07:28.394] <TB1> INFO: valid events with pixels: 0
[19:07:28.394] <TB1> INFO: valid pixel hits: 0
[19:07:28.394] <TB1> INFO: Event errors: 0
[19:07:28.394] <TB1> INFO: start marker: 0
[19:07:28.394] <TB1> INFO: stop marker: 0
[19:07:28.394] <TB1> INFO: overflow: 0
[19:07:28.394] <TB1> INFO: invalid 5bit words: 0
[19:07:28.394] <TB1> INFO: invalid XOR eye diagram: 0
[19:07:28.394] <TB1> INFO: frame (failed synchr.): 0
[19:07:28.394] <TB1> INFO: idle data (no TBM trl): 0
[19:07:28.394] <TB1> INFO: no data (only TBM hdr): 0
[19:07:28.394] <TB1> INFO: TBM errors: 0
[19:07:28.394] <TB1> INFO: flawed TBM headers: 0
[19:07:28.394] <TB1> INFO: flawed TBM trailers: 0
[19:07:28.394] <TB1> INFO: event ID mismatches: 0
[19:07:28.394] <TB1> INFO: ROC errors: 0
[19:07:28.394] <TB1> INFO: missing ROC header(s): 0
[19:07:28.394] <TB1> INFO: misplaced readback start: 0
[19:07:28.394] <TB1> INFO: Pixel decoding errors: 0
[19:07:28.394] <TB1> INFO: pixel data incomplete: 0
[19:07:28.394] <TB1> INFO: pixel address: 0
[19:07:28.394] <TB1> INFO: pulse height fill bit: 0
[19:07:28.394] <TB1> INFO: buffer corruption: 0
[19:07:28.460] <TB1> INFO: ######################################################################
[19:07:28.460] <TB1> INFO: PixTestTrim::doTest()
[19:07:28.460] <TB1> INFO: ######################################################################
[19:07:28.461] <TB1> INFO: ----------------------------------------------------------------------
[19:07:28.461] <TB1> INFO: PixTestTrim::trimTest() ntrig = 8, vcal = 35
[19:07:28.461] <TB1> INFO: ----------------------------------------------------------------------
[19:07:28.502] <TB1> INFO: ---> VthrComp thr map (minimal VthrComp)
[19:07:28.502] <TB1> INFO: ---> dac: vthrcomp name: TrimThr0 ntrig: 8 dacrange: 10 .. 160 (-1/-1) hits flags = 528 (plus default)
[19:07:28.514] <TB1> INFO: dacScan split into 1 runs with ntrig = 8
[19:07:28.514] <TB1> INFO: run 1 of 1
[19:07:28.755] <TB1> INFO: Expecting 5025280 events.
[19:08:00.258] <TB1> INFO: 825000 events read in total (30905ms).
[19:08:30.678] <TB1> INFO: 1647480 events read in total (61325ms).
[19:09:01.210] <TB1> INFO: 2464912 events read in total (91857ms).
[19:09:31.437] <TB1> INFO: 3277208 events read in total (122084ms).
[19:10:01.583] <TB1> INFO: 4084640 events read in total (152230ms).
[19:10:31.735] <TB1> INFO: 4890048 events read in total (182382ms).
[19:10:36.983] <TB1> INFO: 5025280 events read in total (187630ms).
[19:10:37.030] <TB1> INFO: Test took 188516ms.
[19:10:55.699] <TB1> INFO: ROC 0 VthrComp = 129
[19:10:55.699] <TB1> INFO: ROC 1 VthrComp = 129
[19:10:55.699] <TB1> INFO: ROC 2 VthrComp = 132
[19:10:55.699] <TB1> INFO: ROC 3 VthrComp = 128
[19:10:55.699] <TB1> INFO: ROC 4 VthrComp = 121
[19:10:55.700] <TB1> INFO: ROC 5 VthrComp = 127
[19:10:55.700] <TB1> INFO: ROC 6 VthrComp = 128
[19:10:55.700] <TB1> INFO: ROC 7 VthrComp = 129
[19:10:55.700] <TB1> INFO: ROC 8 VthrComp = 117
[19:10:55.700] <TB1> INFO: ROC 9 VthrComp = 127
[19:10:55.700] <TB1> INFO: ROC 10 VthrComp = 124
[19:10:55.700] <TB1> INFO: ROC 11 VthrComp = 119
[19:10:55.700] <TB1> INFO: ROC 12 VthrComp = 123
[19:10:55.700] <TB1> INFO: ROC 13 VthrComp = 120
[19:10:55.700] <TB1> INFO: ROC 14 VthrComp = 121
[19:10:55.701] <TB1> INFO: ROC 15 VthrComp = 124
[19:10:55.701] <TB1> INFO: ---> Vcal thr map (pixel with maximum Vcal thr)
[19:10:55.701] <TB1> INFO: ---> dac: vcal name: TrimThr1 ntrig: 8 dacrange: 10 .. 160 (-1/-1) hits flags = 528 (plus default)
[19:10:55.714] <TB1> INFO: dacScan split into 1 runs with ntrig = 8
[19:10:55.714] <TB1> INFO: run 1 of 1
[19:10:55.953] <TB1> INFO: Expecting 5025280 events.
[19:11:22.934] <TB1> INFO: 589864 events read in total (26389ms).
[19:11:48.832] <TB1> INFO: 1178768 events read in total (52287ms).
[19:12:14.888] <TB1> INFO: 1767040 events read in total (78343ms).
[19:12:40.774] <TB1> INFO: 2354072 events read in total (104229ms).
[19:13:07.097] <TB1> INFO: 2939064 events read in total (130552ms).
[19:13:32.996] <TB1> INFO: 3522144 events read in total (156451ms).
[19:13:59.015] <TB1> INFO: 4104824 events read in total (182470ms).
[19:14:25.346] <TB1> INFO: 4687536 events read in total (208801ms).
[19:14:40.519] <TB1> INFO: 5025280 events read in total (223974ms).
[19:14:40.588] <TB1> INFO: Test took 224874ms.
[19:15:03.559] <TB1> INFO: roc 0 with ID = 0 has maximal Vcal 58.2346 for pixel 0/61 mean/min/max = 45.6248/32.962/58.2876
[19:15:03.559] <TB1> INFO: roc 1 with ID = 1 has maximal Vcal 58.1972 for pixel 3/18 mean/min/max = 44.9187/31.6038/58.2336
[19:15:03.560] <TB1> INFO: roc 2 with ID = 2 has maximal Vcal 59.731 for pixel 13/77 mean/min/max = 46.7744/33.5071/60.0417
[19:15:03.560] <TB1> INFO: roc 3 with ID = 3 has maximal Vcal 59.7253 for pixel 21/76 mean/min/max = 45.8262/31.7011/59.9513
[19:15:03.561] <TB1> INFO: roc 4 with ID = 4 has maximal Vcal 57.9446 for pixel 37/7 mean/min/max = 45.4634/32.8346/58.0922
[19:15:03.561] <TB1> INFO: roc 5 with ID = 5 has maximal Vcal 60.6405 for pixel 0/2 mean/min/max = 45.7522/30.7593/60.7452
[19:15:03.561] <TB1> INFO: roc 6 with ID = 6 has maximal Vcal 57.7555 for pixel 1/77 mean/min/max = 44.7363/31.6282/57.8444
[19:15:03.562] <TB1> INFO: roc 7 with ID = 7 has maximal Vcal 62.7217 for pixel 2/0 mean/min/max = 47.4144/32.0827/62.7461
[19:15:03.562] <TB1> INFO: roc 8 with ID = 8 has maximal Vcal 60.7848 for pixel 33/3 mean/min/max = 46.0007/31.1033/60.8982
[19:15:03.563] <TB1> INFO: roc 9 with ID = 9 has maximal Vcal 61.8857 for pixel 5/79 mean/min/max = 47.0966/32.1748/62.0184
[19:15:03.563] <TB1> INFO: roc 10 with ID = 10 has maximal Vcal 57.9462 for pixel 8/79 mean/min/max = 44.5642/31.1212/58.0072
[19:15:03.563] <TB1> INFO: roc 11 with ID = 11 has maximal Vcal 56.7541 for pixel 47/3 mean/min/max = 44.2171/31.6271/56.8072
[19:15:03.564] <TB1> INFO: roc 12 with ID = 12 has maximal Vcal 60.1526 for pixel 22/68 mean/min/max = 46.2072/32.0351/60.3793
[19:15:03.564] <TB1> INFO: roc 13 with ID = 13 has maximal Vcal 61.5335 for pixel 12/67 mean/min/max = 46.8052/32.0729/61.5375
[19:15:03.564] <TB1> INFO: roc 14 with ID = 14 has maximal Vcal 57.9238 for pixel 50/17 mean/min/max = 45.3403/32.6736/58.0069
[19:15:03.565] <TB1> INFO: roc 15 with ID = 15 has maximal Vcal 65.277 for pixel 13/2 mean/min/max = 47.1953/29.0151/65.3755
[19:15:03.565] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[19:15:03.654] <TB1> INFO: Expecting 411648 events.
[19:15:13.024] <TB1> INFO: 411648 events read in total (8779ms).
[19:15:13.032] <TB1> INFO: Expecting 411648 events.
[19:15:22.211] <TB1> INFO: 411648 events read in total (8776ms).
[19:15:22.222] <TB1> INFO: Expecting 411648 events.
[19:15:31.676] <TB1> INFO: 411648 events read in total (9051ms).
[19:15:31.690] <TB1> INFO: Expecting 411648 events.
[19:15:41.151] <TB1> INFO: 411648 events read in total (9058ms).
[19:15:41.167] <TB1> INFO: Expecting 411648 events.
[19:15:50.595] <TB1> INFO: 411648 events read in total (9025ms).
[19:15:50.615] <TB1> INFO: Expecting 411648 events.
[19:16:00.101] <TB1> INFO: 411648 events read in total (9083ms).
[19:16:00.128] <TB1> INFO: Expecting 411648 events.
[19:16:09.600] <TB1> INFO: 411648 events read in total (9069ms).
[19:16:09.624] <TB1> INFO: Expecting 411648 events.
[19:16:18.877] <TB1> INFO: 411648 events read in total (8850ms).
[19:16:18.912] <TB1> INFO: Expecting 411648 events.
[19:16:28.141] <TB1> INFO: 411648 events read in total (8826ms).
[19:16:28.171] <TB1> INFO: Expecting 411648 events.
[19:16:37.567] <TB1> INFO: 411648 events read in total (8993ms).
[19:16:37.600] <TB1> INFO: Expecting 411648 events.
[19:16:46.796] <TB1> INFO: 411648 events read in total (8793ms).
[19:16:46.840] <TB1> INFO: Expecting 411648 events.
[19:16:56.059] <TB1> INFO: 411648 events read in total (8816ms).
[19:16:56.098] <TB1> INFO: Expecting 411648 events.
[19:17:05.578] <TB1> INFO: 411648 events read in total (9076ms).
[19:17:05.621] <TB1> INFO: Expecting 411648 events.
[19:17:14.842] <TB1> INFO: 411648 events read in total (8818ms).
[19:17:14.888] <TB1> INFO: Expecting 411648 events.
[19:17:24.191] <TB1> INFO: 411648 events read in total (8900ms).
[19:17:24.241] <TB1> INFO: Expecting 411648 events.
[19:17:33.672] <TB1> INFO: 411648 events read in total (9028ms).
[19:17:33.724] <TB1> INFO: Test took 150159ms.
[19:17:34.556] <TB1> INFO: ---> dac: vcal name: TrimThr2 ntrig: 8 dacrange: 0 .. 150 (-1/-1) hits flags = 528 (plus default)
[19:17:34.569] <TB1> INFO: dacScan split into 1 runs with ntrig = 8
[19:17:34.569] <TB1> INFO: run 1 of 1
[19:17:34.807] <TB1> INFO: Expecting 5025280 events.
[19:18:01.869] <TB1> INFO: 585200 events read in total (26471ms).
[19:18:28.086] <TB1> INFO: 1168992 events read in total (52688ms).
[19:18:54.603] <TB1> INFO: 1752520 events read in total (79205ms).
[19:19:21.136] <TB1> INFO: 2337032 events read in total (105738ms).
[19:19:47.766] <TB1> INFO: 2921384 events read in total (132368ms).
[19:20:14.204] <TB1> INFO: 3506568 events read in total (158806ms).
[19:20:40.499] <TB1> INFO: 4089504 events read in total (185101ms).
[19:21:06.784] <TB1> INFO: 4671312 events read in total (211386ms).
[19:21:22.863] <TB1> INFO: 5025280 events read in total (227465ms).
[19:21:22.977] <TB1> INFO: Test took 228408ms.
[19:21:48.492] <TB1> INFO: ---> TrimStepCorr4 extremal thresholds: 1.500000 .. 144.712515
[19:21:48.730] <TB1> INFO: Expecting 208000 events.
[19:21:58.334] <TB1> INFO: 208000 events read in total (9012ms).
[19:21:58.336] <TB1> INFO: Test took 9843ms.
[19:21:58.383] <TB1> INFO: ---> dac: vcal name: trimStepCorr4 ntrig: 8 dacrange: 1 .. 154 (-1/-1) hits flags = 528 (plus default)
[19:21:58.396] <TB1> INFO: dacScan split into 1 runs with ntrig = 8
[19:21:58.396] <TB1> INFO: run 1 of 1
[19:21:58.675] <TB1> INFO: Expecting 5125120 events.
[19:22:24.958] <TB1> INFO: 584216 events read in total (25691ms).
[19:22:50.816] <TB1> INFO: 1168944 events read in total (51549ms).
[19:23:16.654] <TB1> INFO: 1753576 events read in total (77387ms).
[19:23:42.753] <TB1> INFO: 2338432 events read in total (103487ms).
[19:24:08.874] <TB1> INFO: 2922696 events read in total (129607ms).
[19:24:35.115] <TB1> INFO: 3505880 events read in total (155848ms).
[19:25:00.865] <TB1> INFO: 4088808 events read in total (181598ms).
[19:25:26.694] <TB1> INFO: 4671448 events read in total (207427ms).
[19:25:47.033] <TB1> INFO: 5125120 events read in total (227766ms).
[19:25:47.136] <TB1> INFO: Test took 228739ms.
[19:26:15.127] <TB1> INFO: ---> TrimStepCorr2 extremal thresholds: 27.570512 .. 44.302016
[19:26:15.365] <TB1> INFO: Expecting 208000 events.
[19:26:25.499] <TB1> INFO: 208000 events read in total (9542ms).
[19:26:25.499] <TB1> INFO: Test took 10371ms.
[19:26:25.547] <TB1> INFO: ---> dac: vcal name: trimStepCorr2 ntrig: 8 dacrange: 17 .. 54 (-1/-1) hits flags = 528 (plus default)
[19:26:25.560] <TB1> INFO: dacScan split into 1 runs with ntrig = 8
[19:26:25.560] <TB1> INFO: run 1 of 1
[19:26:25.838] <TB1> INFO: Expecting 1264640 events.
[19:26:54.574] <TB1> INFO: 662056 events read in total (28144ms).
[19:27:20.879] <TB1> INFO: 1264640 events read in total (54449ms).
[19:27:20.921] <TB1> INFO: Test took 55361ms.
[19:27:35.835] <TB1> INFO: ---> TrimStepCorr1a extremal thresholds: 25.316308 .. 47.124594
[19:27:36.160] <TB1> INFO: Expecting 208000 events.
[19:27:45.980] <TB1> INFO: 208000 events read in total (9229ms).
[19:27:45.981] <TB1> INFO: Test took 10145ms.
[19:27:46.028] <TB1> INFO: ---> dac: vcal name: trimStepCorr1a ntrig: 8 dacrange: 15 .. 57 (-1/-1) hits flags = 528 (plus default)
[19:27:46.041] <TB1> INFO: dacScan split into 1 runs with ntrig = 8
[19:27:46.042] <TB1> INFO: run 1 of 1
[19:27:46.320] <TB1> INFO: Expecting 1431040 events.
[19:28:14.906] <TB1> INFO: 659408 events read in total (27995ms).
[19:28:42.914] <TB1> INFO: 1317736 events read in total (56003ms).
[19:28:48.227] <TB1> INFO: 1431040 events read in total (61316ms).
[19:28:48.254] <TB1> INFO: Test took 62213ms.
[19:29:03.634] <TB1> INFO: ---> TrimStepCorr1b extremal thresholds: 24.874765 .. 47.550252
[19:29:03.960] <TB1> INFO: Expecting 208000 events.
[19:29:14.286] <TB1> INFO: 208000 events read in total (9735ms).
[19:29:14.287] <TB1> INFO: Test took 10651ms.
[19:29:14.351] <TB1> INFO: ---> dac: vcal name: trimStepCorr1b ntrig: 8 dacrange: 14 .. 57 (-1/-1) hits flags = 528 (plus default)
[19:29:14.364] <TB1> INFO: dacScan split into 1 runs with ntrig = 8
[19:29:14.364] <TB1> INFO: run 1 of 1
[19:29:14.642] <TB1> INFO: Expecting 1464320 events.
[19:29:44.218] <TB1> INFO: 663456 events read in total (28984ms).
[19:30:12.540] <TB1> INFO: 1326272 events read in total (57306ms).
[19:30:18.769] <TB1> INFO: 1464320 events read in total (63535ms).
[19:30:18.810] <TB1> INFO: Test took 64447ms.
[19:30:32.887] <TB1> INFO: ---> TrimThrFinal extremal thresholds: 15 .. 55
[19:30:32.887] <TB1> INFO: ---> dac: vcal name: TrimThrFinal ntrig: 8 dacrange: 15 .. 55 (-1/-1) hits flags = 528 (plus default)
[19:30:32.900] <TB1> INFO: dacScan split into 1 runs with ntrig = 8
[19:30:32.900] <TB1> INFO: run 1 of 1
[19:30:33.162] <TB1> INFO: Expecting 1364480 events.
[19:31:01.619] <TB1> INFO: 667192 events read in total (27866ms).
[19:31:29.105] <TB1> INFO: 1333728 events read in total (55353ms).
[19:31:30.790] <TB1> INFO: 1364480 events read in total (57037ms).
[19:31:30.822] <TB1> INFO: Test took 57923ms.
[19:31:43.063] <TB1> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1005_FullQualification_2016-10-16_17h47m_1476632865//001_Fulltest_m20//dacParameters35_C0.dat
[19:31:43.063] <TB1> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1005_FullQualification_2016-10-16_17h47m_1476632865//001_Fulltest_m20//dacParameters35_C1.dat
[19:31:43.063] <TB1> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1005_FullQualification_2016-10-16_17h47m_1476632865//001_Fulltest_m20//dacParameters35_C2.dat
[19:31:43.063] <TB1> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1005_FullQualification_2016-10-16_17h47m_1476632865//001_Fulltest_m20//dacParameters35_C3.dat
[19:31:43.063] <TB1> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1005_FullQualification_2016-10-16_17h47m_1476632865//001_Fulltest_m20//dacParameters35_C4.dat
[19:31:43.063] <TB1> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1005_FullQualification_2016-10-16_17h47m_1476632865//001_Fulltest_m20//dacParameters35_C5.dat
[19:31:43.063] <TB1> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1005_FullQualification_2016-10-16_17h47m_1476632865//001_Fulltest_m20//dacParameters35_C6.dat
[19:31:43.063] <TB1> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1005_FullQualification_2016-10-16_17h47m_1476632865//001_Fulltest_m20//dacParameters35_C7.dat
[19:31:43.063] <TB1> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1005_FullQualification_2016-10-16_17h47m_1476632865//001_Fulltest_m20//dacParameters35_C8.dat
[19:31:43.064] <TB1> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1005_FullQualification_2016-10-16_17h47m_1476632865//001_Fulltest_m20//dacParameters35_C9.dat
[19:31:43.064] <TB1> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1005_FullQualification_2016-10-16_17h47m_1476632865//001_Fulltest_m20//dacParameters35_C10.dat
[19:31:43.064] <TB1> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1005_FullQualification_2016-10-16_17h47m_1476632865//001_Fulltest_m20//dacParameters35_C11.dat
[19:31:43.064] <TB1> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1005_FullQualification_2016-10-16_17h47m_1476632865//001_Fulltest_m20//dacParameters35_C12.dat
[19:31:43.064] <TB1> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1005_FullQualification_2016-10-16_17h47m_1476632865//001_Fulltest_m20//dacParameters35_C13.dat
[19:31:43.064] <TB1> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1005_FullQualification_2016-10-16_17h47m_1476632865//001_Fulltest_m20//dacParameters35_C14.dat
[19:31:43.064] <TB1> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1005_FullQualification_2016-10-16_17h47m_1476632865//001_Fulltest_m20//dacParameters35_C15.dat
[19:31:43.064] <TB1> INFO: write trim parameters into /home/l_tester/david/FullQualification/data/M1005_FullQualification_2016-10-16_17h47m_1476632865//001_Fulltest_m20//trimParameters35_C0.dat
[19:31:43.070] <TB1> INFO: write trim parameters into /home/l_tester/david/FullQualification/data/M1005_FullQualification_2016-10-16_17h47m_1476632865//001_Fulltest_m20//trimParameters35_C1.dat
[19:31:43.076] <TB1> INFO: write trim parameters into /home/l_tester/david/FullQualification/data/M1005_FullQualification_2016-10-16_17h47m_1476632865//001_Fulltest_m20//trimParameters35_C2.dat
[19:31:43.081] <TB1> INFO: write trim parameters into /home/l_tester/david/FullQualification/data/M1005_FullQualification_2016-10-16_17h47m_1476632865//001_Fulltest_m20//trimParameters35_C3.dat
[19:31:43.087] <TB1> INFO: write trim parameters into /home/l_tester/david/FullQualification/data/M1005_FullQualification_2016-10-16_17h47m_1476632865//001_Fulltest_m20//trimParameters35_C4.dat
[19:31:43.093] <TB1> INFO: write trim parameters into /home/l_tester/david/FullQualification/data/M1005_FullQualification_2016-10-16_17h47m_1476632865//001_Fulltest_m20//trimParameters35_C5.dat
[19:31:43.099] <TB1> INFO: write trim parameters into /home/l_tester/david/FullQualification/data/M1005_FullQualification_2016-10-16_17h47m_1476632865//001_Fulltest_m20//trimParameters35_C6.dat
[19:31:43.105] <TB1> INFO: write trim parameters into /home/l_tester/david/FullQualification/data/M1005_FullQualification_2016-10-16_17h47m_1476632865//001_Fulltest_m20//trimParameters35_C7.dat
[19:31:43.110] <TB1> INFO: write trim parameters into /home/l_tester/david/FullQualification/data/M1005_FullQualification_2016-10-16_17h47m_1476632865//001_Fulltest_m20//trimParameters35_C8.dat
[19:31:43.115] <TB1> INFO: write trim parameters into /home/l_tester/david/FullQualification/data/M1005_FullQualification_2016-10-16_17h47m_1476632865//001_Fulltest_m20//trimParameters35_C9.dat
[19:31:43.120] <TB1> INFO: write trim parameters into /home/l_tester/david/FullQualification/data/M1005_FullQualification_2016-10-16_17h47m_1476632865//001_Fulltest_m20//trimParameters35_C10.dat
[19:31:43.125] <TB1> INFO: write trim parameters into /home/l_tester/david/FullQualification/data/M1005_FullQualification_2016-10-16_17h47m_1476632865//001_Fulltest_m20//trimParameters35_C11.dat
[19:31:43.129] <TB1> INFO: write trim parameters into /home/l_tester/david/FullQualification/data/M1005_FullQualification_2016-10-16_17h47m_1476632865//001_Fulltest_m20//trimParameters35_C12.dat
[19:31:43.134] <TB1> INFO: write trim parameters into /home/l_tester/david/FullQualification/data/M1005_FullQualification_2016-10-16_17h47m_1476632865//001_Fulltest_m20//trimParameters35_C13.dat
[19:31:43.139] <TB1> INFO: write trim parameters into /home/l_tester/david/FullQualification/data/M1005_FullQualification_2016-10-16_17h47m_1476632865//001_Fulltest_m20//trimParameters35_C14.dat
[19:31:43.144] <TB1> INFO: write trim parameters into /home/l_tester/david/FullQualification/data/M1005_FullQualification_2016-10-16_17h47m_1476632865//001_Fulltest_m20//trimParameters35_C15.dat
[19:31:43.150] <TB1> INFO: PixTestTrim::trimTest() done
[19:31:43.150] <TB1> INFO: vtrim: 125 128 143 128 144 114 136 150 133 138 118 135 136 138 135 156
[19:31:43.150] <TB1> INFO: vthrcomp: 129 129 132 128 121 127 128 129 117 127 124 119 123 120 121 124
[19:31:43.150] <TB1> INFO: vcal mean: 34.97 34.95 34.99 34.96 34.99 34.96 34.94 35.09 35.02 35.00 34.90 34.97 34.97 34.99 35.00 34.96
[19:31:43.150] <TB1> INFO: vcal RMS: 0.93 1.04 0.99 1.07 1.02 1.11 0.99 1.26 1.02 1.10 1.22 0.99 1.13 1.19 0.98 1.14
[19:31:43.151] <TB1> INFO: bits mean: 8.95 10.06 8.64 9.29 9.66 9.39 10.17 9.63 9.87 9.11 9.86 10.01 9.94 9.88 9.81 9.84
[19:31:43.151] <TB1> INFO: bits RMS: 2.81 2.55 2.68 2.80 2.51 2.94 2.46 2.57 2.65 2.73 2.71 2.57 2.42 2.44 2.48 2.78
[19:31:43.158] <TB1> INFO: ----------------------------------------------------------------------
[19:31:43.158] <TB1> INFO: PixTestTrim::trimBitTest() ntrig = 5, vtrims = 254 126 63 32
[19:31:43.158] <TB1> INFO: ----------------------------------------------------------------------
[19:31:43.160] <TB1> INFO: ---> dac: Vcal name: TrimBitsThr0 ntrig: 5 dacrange: 0 .. 199 (-1/-1) hits flags = 528 (plus default)
[19:31:43.174] <TB1> INFO: dacScan split into 1 runs with ntrig = 5
[19:31:43.174] <TB1> INFO: run 1 of 1
[19:31:43.411] <TB1> INFO: Expecting 4160000 events.
[19:32:15.824] <TB1> INFO: 748915 events read in total (31821ms).
[19:32:47.951] <TB1> INFO: 1491865 events read in total (63948ms).
[19:33:19.808] <TB1> INFO: 2229285 events read in total (95806ms).
[19:33:51.353] <TB1> INFO: 2960135 events read in total (127350ms).
[19:34:22.941] <TB1> INFO: 3687885 events read in total (158938ms).
[19:34:43.852] <TB1> INFO: 4160000 events read in total (179849ms).
[19:34:43.923] <TB1> INFO: Test took 180749ms.
[19:35:08.539] <TB1> INFO: ---> dac: Vcal name: TrimThr_trim14 ntrig: 5 dacrange: 0 .. 201 (-1/-1) hits flags = 528 (plus default)
[19:35:08.552] <TB1> INFO: dacScan split into 1 runs with ntrig = 5
[19:35:08.553] <TB1> INFO: run 1 of 1
[19:35:08.788] <TB1> INFO: Expecting 4201600 events.
[19:35:41.110] <TB1> INFO: 722655 events read in total (31730ms).
[19:36:12.403] <TB1> INFO: 1439850 events read in total (63023ms).
[19:36:43.499] <TB1> INFO: 2151715 events read in total (94119ms).
[19:37:14.510] <TB1> INFO: 2857355 events read in total (125130ms).
[19:37:45.875] <TB1> INFO: 3560230 events read in total (156495ms).
[19:38:13.970] <TB1> INFO: 4201600 events read in total (184590ms).
[19:38:14.126] <TB1> INFO: Test took 185573ms.
[19:38:40.454] <TB1> INFO: ---> dac: Vcal name: TrimThr_trim13 ntrig: 5 dacrange: 0 .. 194 (-1/-1) hits flags = 528 (plus default)
[19:38:40.467] <TB1> INFO: dacScan split into 1 runs with ntrig = 5
[19:38:40.467] <TB1> INFO: run 1 of 1
[19:38:40.705] <TB1> INFO: Expecting 4056000 events.
[19:39:13.365] <TB1> INFO: 732150 events read in total (32069ms).
[19:39:45.622] <TB1> INFO: 1458195 events read in total (64326ms).
[19:40:17.768] <TB1> INFO: 2178235 events read in total (96472ms).
[19:40:49.898] <TB1> INFO: 2891755 events read in total (128602ms).
[19:41:21.531] <TB1> INFO: 3602735 events read in total (160235ms).
[19:41:41.604] <TB1> INFO: 4056000 events read in total (180308ms).
[19:41:41.684] <TB1> INFO: Test took 181217ms.
[19:42:05.671] <TB1> INFO: ---> dac: Vcal name: TrimThr_trim11 ntrig: 5 dacrange: 0 .. 194 (-1/-1) hits flags = 528 (plus default)
[19:42:05.684] <TB1> INFO: dacScan split into 1 runs with ntrig = 5
[19:42:05.684] <TB1> INFO: run 1 of 1
[19:42:05.924] <TB1> INFO: Expecting 4056000 events.
[19:42:37.862] <TB1> INFO: 732360 events read in total (31347ms).
[19:43:09.380] <TB1> INFO: 1458425 events read in total (62865ms).
[19:43:40.905] <TB1> INFO: 2178490 events read in total (94390ms).
[19:44:12.164] <TB1> INFO: 2892050 events read in total (125649ms).
[19:44:43.387] <TB1> INFO: 3602960 events read in total (156872ms).
[19:45:02.967] <TB1> INFO: 4056000 events read in total (176452ms).
[19:45:03.028] <TB1> INFO: Test took 177344ms.
[19:45:29.954] <TB1> INFO: ---> dac: Vcal name: TrimThr_trim7 ntrig: 5 dacrange: 0 .. 193 (-1/-1) hits flags = 528 (plus default)
[19:45:29.967] <TB1> INFO: dacScan split into 1 runs with ntrig = 5
[19:45:29.967] <TB1> INFO: run 1 of 1
[19:45:30.206] <TB1> INFO: Expecting 4035200 events.
[19:46:02.579] <TB1> INFO: 733840 events read in total (31781ms).
[19:46:34.413] <TB1> INFO: 1461310 events read in total (63615ms).
[19:47:06.180] <TB1> INFO: 2182820 events read in total (95382ms).
[19:47:37.588] <TB1> INFO: 2897360 events read in total (126790ms).
[19:48:09.199] <TB1> INFO: 3609405 events read in total (158401ms).
[19:48:27.483] <TB1> INFO: 4035200 events read in total (176685ms).
[19:48:27.541] <TB1> INFO: Test took 177573ms.
[19:48:53.192] <TB1> INFO: PixTestTrim::trimBitTest() done
[19:48:53.193] <TB1> INFO: PixTestTrim::doTest() done, duration: 2484 seconds
[19:48:53.193] <TB1> INFO: Decoding statistics:
[19:48:53.193] <TB1> INFO: General information:
[19:48:53.193] <TB1> INFO: 16bit words read: 0
[19:48:53.193] <TB1> INFO: valid events total: 0
[19:48:53.193] <TB1> INFO: empty events: 0
[19:48:53.193] <TB1> INFO: valid events with pixels: 0
[19:48:53.193] <TB1> INFO: valid pixel hits: 0
[19:48:53.193] <TB1> INFO: Event errors: 0
[19:48:53.193] <TB1> INFO: start marker: 0
[19:48:53.193] <TB1> INFO: stop marker: 0
[19:48:53.193] <TB1> INFO: overflow: 0
[19:48:53.193] <TB1> INFO: invalid 5bit words: 0
[19:48:53.193] <TB1> INFO: invalid XOR eye diagram: 0
[19:48:53.193] <TB1> INFO: frame (failed synchr.): 0
[19:48:53.193] <TB1> INFO: idle data (no TBM trl): 0
[19:48:53.193] <TB1> INFO: no data (only TBM hdr): 0
[19:48:53.193] <TB1> INFO: TBM errors: 0
[19:48:53.193] <TB1> INFO: flawed TBM headers: 0
[19:48:53.193] <TB1> INFO: flawed TBM trailers: 0
[19:48:53.193] <TB1> INFO: event ID mismatches: 0
[19:48:53.193] <TB1> INFO: ROC errors: 0
[19:48:53.193] <TB1> INFO: missing ROC header(s): 0
[19:48:53.193] <TB1> INFO: misplaced readback start: 0
[19:48:53.193] <TB1> INFO: Pixel decoding errors: 0
[19:48:53.193] <TB1> INFO: pixel data incomplete: 0
[19:48:53.193] <TB1> INFO: pixel address: 0
[19:48:53.193] <TB1> INFO: pulse height fill bit: 0
[19:48:53.193] <TB1> INFO: buffer corruption: 0
[19:48:53.790] <TB1> INFO: ######################################################################
[19:48:53.790] <TB1> INFO: PixTestPhOptimization::doTest() Ntrig = 10
[19:48:53.790] <TB1> INFO: ######################################################################
[19:48:54.029] <TB1> INFO: Expecting 41600 events.
[19:48:57.554] <TB1> INFO: 41600 events read in total (2933ms).
[19:48:57.555] <TB1> INFO: Test took 3764ms.
[19:48:57.995] <TB1> INFO: Expecting 41600 events.
[19:49:01.606] <TB1> INFO: 41600 events read in total (3019ms).
[19:49:01.607] <TB1> INFO: Test took 3849ms.
[19:49:01.908] <TB1> INFO: Expecting 41600 events.
[19:49:05.535] <TB1> INFO: 41600 events read in total (3036ms).
[19:49:05.536] <TB1> INFO: Test took 3905ms.
[19:49:05.825] <TB1> INFO: Expecting 41600 events.
[19:49:09.413] <TB1> INFO: 41600 events read in total (2997ms).
[19:49:09.414] <TB1> INFO: Test took 3854ms.
[19:49:09.758] <TB1> INFO: Expecting 41600 events.
[19:49:13.286] <TB1> INFO: 41600 events read in total (2936ms).
[19:49:13.287] <TB1> INFO: Test took 3849ms.
[19:49:13.575] <TB1> INFO: Expecting 41600 events.
[19:49:17.154] <TB1> INFO: 41600 events read in total (2987ms).
[19:49:17.155] <TB1> INFO: Test took 3845ms.
[19:49:17.446] <TB1> INFO: Expecting 41600 events.
[19:49:20.970] <TB1> INFO: 41600 events read in total (2932ms).
[19:49:20.971] <TB1> INFO: Test took 3790ms.
[19:49:21.259] <TB1> INFO: Expecting 41600 events.
[19:49:24.854] <TB1> INFO: 41600 events read in total (3003ms).
[19:49:24.855] <TB1> INFO: Test took 3860ms.
[19:49:25.143] <TB1> INFO: Expecting 41600 events.
[19:49:28.785] <TB1> INFO: 41600 events read in total (3050ms).
[19:49:28.786] <TB1> INFO: Test took 3907ms.
[19:49:29.075] <TB1> INFO: Expecting 41600 events.
[19:49:32.699] <TB1> INFO: 41600 events read in total (3033ms).
[19:49:32.700] <TB1> INFO: Test took 3890ms.
[19:49:32.989] <TB1> INFO: Expecting 41600 events.
[19:49:36.536] <TB1> INFO: 41600 events read in total (2956ms).
[19:49:36.538] <TB1> INFO: Test took 3814ms.
[19:49:36.833] <TB1> INFO: Expecting 41600 events.
[19:49:40.346] <TB1> INFO: 41600 events read in total (2922ms).
[19:49:40.347] <TB1> INFO: Test took 3779ms.
[19:49:40.636] <TB1> INFO: Expecting 41600 events.
[19:49:44.202] <TB1> INFO: 41600 events read in total (2975ms).
[19:49:44.203] <TB1> INFO: Test took 3832ms.
[19:49:44.494] <TB1> INFO: Expecting 41600 events.
[19:49:48.079] <TB1> INFO: 41600 events read in total (2993ms).
[19:49:48.080] <TB1> INFO: Test took 3851ms.
[19:49:48.372] <TB1> INFO: Expecting 41600 events.
[19:49:51.914] <TB1> INFO: 41600 events read in total (2950ms).
[19:49:51.914] <TB1> INFO: Test took 3807ms.
[19:49:52.220] <TB1> INFO: Expecting 41600 events.
[19:49:55.772] <TB1> INFO: 41600 events read in total (2960ms).
[19:49:55.773] <TB1> INFO: Test took 3835ms.
[19:49:56.062] <TB1> INFO: Expecting 41600 events.
[19:49:59.583] <TB1> INFO: 41600 events read in total (2930ms).
[19:49:59.584] <TB1> INFO: Test took 3787ms.
[19:49:59.873] <TB1> INFO: Expecting 41600 events.
[19:50:03.460] <TB1> INFO: 41600 events read in total (2995ms).
[19:50:03.460] <TB1> INFO: Test took 3852ms.
[19:50:03.758] <TB1> INFO: Expecting 41600 events.
[19:50:07.343] <TB1> INFO: 41600 events read in total (2993ms).
[19:50:07.344] <TB1> INFO: Test took 3857ms.
[19:50:07.633] <TB1> INFO: Expecting 41600 events.
[19:50:11.272] <TB1> INFO: 41600 events read in total (3047ms).
[19:50:11.273] <TB1> INFO: Test took 3904ms.
[19:50:11.576] <TB1> INFO: Expecting 41600 events.
[19:50:15.174] <TB1> INFO: 41600 events read in total (3006ms).
[19:50:15.175] <TB1> INFO: Test took 3878ms.
[19:50:15.465] <TB1> INFO: Expecting 41600 events.
[19:50:19.017] <TB1> INFO: 41600 events read in total (2960ms).
[19:50:19.018] <TB1> INFO: Test took 3818ms.
[19:50:19.310] <TB1> INFO: Expecting 41600 events.
[19:50:22.869] <TB1> INFO: 41600 events read in total (2967ms).
[19:50:22.869] <TB1> INFO: Test took 3825ms.
[19:50:23.173] <TB1> INFO: Expecting 41600 events.
[19:50:26.759] <TB1> INFO: 41600 events read in total (2994ms).
[19:50:26.759] <TB1> INFO: Test took 3866ms.
[19:50:27.051] <TB1> INFO: Expecting 41600 events.
[19:50:30.610] <TB1> INFO: 41600 events read in total (2968ms).
[19:50:30.611] <TB1> INFO: Test took 3826ms.
[19:50:30.925] <TB1> INFO: Expecting 41600 events.
[19:50:34.498] <TB1> INFO: 41600 events read in total (2982ms).
[19:50:34.499] <TB1> INFO: Test took 3859ms.
[19:50:34.788] <TB1> INFO: Expecting 41600 events.
[19:50:38.358] <TB1> INFO: 41600 events read in total (2978ms).
[19:50:38.359] <TB1> INFO: Test took 3836ms.
[19:50:38.677] <TB1> INFO: Expecting 41600 events.
[19:50:42.236] <TB1> INFO: 41600 events read in total (2968ms).
[19:50:42.237] <TB1> INFO: Test took 3853ms.
[19:50:42.530] <TB1> INFO: Expecting 41600 events.
[19:50:46.055] <TB1> INFO: 41600 events read in total (2934ms).
[19:50:46.056] <TB1> INFO: Test took 3791ms.
[19:50:46.344] <TB1> INFO: Expecting 41600 events.
[19:50:49.884] <TB1> INFO: 41600 events read in total (2948ms).
[19:50:49.884] <TB1> INFO: Test took 3804ms.
[19:50:50.174] <TB1> INFO: Expecting 41600 events.
[19:50:53.775] <TB1> INFO: 41600 events read in total (3009ms).
[19:50:53.776] <TB1> INFO: Test took 3867ms.
[19:50:54.083] <TB1> INFO: Expecting 41600 events.
[19:50:57.664] <TB1> INFO: 41600 events read in total (2989ms).
[19:50:57.665] <TB1> INFO: Test took 3865ms.
[19:50:57.954] <TB1> INFO: Expecting 41600 events.
[19:51:01.505] <TB1> INFO: 41600 events read in total (2959ms).
[19:51:01.505] <TB1> INFO: Test took 3816ms.
[19:51:01.796] <TB1> INFO: Expecting 41600 events.
[19:51:05.336] <TB1> INFO: 41600 events read in total (2948ms).
[19:51:05.337] <TB1> INFO: Test took 3807ms.
[19:51:05.627] <TB1> INFO: Expecting 41600 events.
[19:51:09.180] <TB1> INFO: 41600 events read in total (2961ms).
[19:51:09.181] <TB1> INFO: Test took 3820ms.
[19:51:09.471] <TB1> INFO: Expecting 41600 events.
[19:51:12.973] <TB1> INFO: 41600 events read in total (2910ms).
[19:51:12.974] <TB1> INFO: Test took 3769ms.
[19:51:13.282] <TB1> INFO: Expecting 41600 events.
[19:51:16.913] <TB1> INFO: 41600 events read in total (3039ms).
[19:51:16.914] <TB1> INFO: Test took 3913ms.
[19:51:17.206] <TB1> INFO: Expecting 41600 events.
[19:51:20.814] <TB1> INFO: 41600 events read in total (3016ms).
[19:51:20.814] <TB1> INFO: Test took 3873ms.
[19:51:21.105] <TB1> INFO: Expecting 41600 events.
[19:51:24.742] <TB1> INFO: 41600 events read in total (3045ms).
[19:51:24.743] <TB1> INFO: Test took 3904ms.
[19:51:25.032] <TB1> INFO: Expecting 41600 events.
[19:51:28.540] <TB1> INFO: 41600 events read in total (2916ms).
[19:51:28.540] <TB1> INFO: Test took 3773ms.
[19:51:28.847] <TB1> INFO: Expecting 41600 events.
[19:51:32.467] <TB1> INFO: 41600 events read in total (3028ms).
[19:51:32.468] <TB1> INFO: Test took 3903ms.
[19:51:32.758] <TB1> INFO: Expecting 41600 events.
[19:51:36.292] <TB1> INFO: 41600 events read in total (2942ms).
[19:51:36.293] <TB1> INFO: Test took 3801ms.
[19:51:36.583] <TB1> INFO: Expecting 41600 events.
[19:51:40.172] <TB1> INFO: 41600 events read in total (2997ms).
[19:51:40.173] <TB1> INFO: Test took 3855ms.
[19:51:40.470] <TB1> INFO: Expecting 41600 events.
[19:51:44.070] <TB1> INFO: 41600 events read in total (3009ms).
[19:51:44.070] <TB1> INFO: Test took 3873ms.
[19:51:44.359] <TB1> INFO: Expecting 41600 events.
[19:51:47.944] <TB1> INFO: 41600 events read in total (2993ms).
[19:51:47.945] <TB1> INFO: Test took 3851ms.
[19:51:48.234] <TB1> INFO: Expecting 41600 events.
[19:51:51.879] <TB1> INFO: 41600 events read in total (3053ms).
[19:51:51.880] <TB1> INFO: Test took 3911ms.
[19:51:52.174] <TB1> INFO: Expecting 41600 events.
[19:51:55.795] <TB1> INFO: 41600 events read in total (3029ms).
[19:51:55.796] <TB1> INFO: Test took 3888ms.
[19:51:56.085] <TB1> INFO: Expecting 41600 events.
[19:51:59.692] <TB1> INFO: 41600 events read in total (3015ms).
[19:51:59.693] <TB1> INFO: Test took 3873ms.
[19:51:59.982] <TB1> INFO: Expecting 41600 events.
[19:52:03.684] <TB1> INFO: 41600 events read in total (3110ms).
[19:52:03.685] <TB1> INFO: Test took 3968ms.
[19:52:03.975] <TB1> INFO: Expecting 41600 events.
[19:52:07.553] <TB1> INFO: 41600 events read in total (2986ms).
[19:52:07.553] <TB1> INFO: Test took 3844ms.
[19:52:07.846] <TB1> INFO: Expecting 41600 events.
[19:52:11.376] <TB1> INFO: 41600 events read in total (2939ms).
[19:52:11.376] <TB1> INFO: Test took 3796ms.
[19:52:11.667] <TB1> INFO: Expecting 41600 events.
[19:52:15.301] <TB1> INFO: 41600 events read in total (3042ms).
[19:52:15.302] <TB1> INFO: Test took 3901ms.
[19:52:15.591] <TB1> INFO: Expecting 41600 events.
[19:52:19.216] <TB1> INFO: 41600 events read in total (3033ms).
[19:52:19.216] <TB1> INFO: Test took 3890ms.
[19:52:19.508] <TB1> INFO: Expecting 41600 events.
[19:52:23.202] <TB1> INFO: 41600 events read in total (3102ms).
[19:52:23.203] <TB1> INFO: Test took 3961ms.
[19:52:23.496] <TB1> INFO: Expecting 2560 events.
[19:52:24.379] <TB1> INFO: 2560 events read in total (291ms).
[19:52:24.379] <TB1> INFO: Test took 1160ms.
[19:52:24.687] <TB1> INFO: Expecting 2560 events.
[19:52:25.574] <TB1> INFO: 2560 events read in total (295ms).
[19:52:25.574] <TB1> INFO: Test took 1194ms.
[19:52:25.882] <TB1> INFO: Expecting 2560 events.
[19:52:26.766] <TB1> INFO: 2560 events read in total (292ms).
[19:52:26.766] <TB1> INFO: Test took 1191ms.
[19:52:27.075] <TB1> INFO: Expecting 2560 events.
[19:52:27.959] <TB1> INFO: 2560 events read in total (292ms).
[19:52:27.960] <TB1> INFO: Test took 1193ms.
[19:52:28.267] <TB1> INFO: Expecting 2560 events.
[19:52:29.146] <TB1> INFO: 2560 events read in total (287ms).
[19:52:29.146] <TB1> INFO: Test took 1186ms.
[19:52:29.455] <TB1> INFO: Expecting 2560 events.
[19:52:30.342] <TB1> INFO: 2560 events read in total (295ms).
[19:52:30.342] <TB1> INFO: Test took 1195ms.
[19:52:30.650] <TB1> INFO: Expecting 2560 events.
[19:52:31.537] <TB1> INFO: 2560 events read in total (295ms).
[19:52:31.537] <TB1> INFO: Test took 1194ms.
[19:52:31.846] <TB1> INFO: Expecting 2560 events.
[19:52:32.732] <TB1> INFO: 2560 events read in total (295ms).
[19:52:32.732] <TB1> INFO: Test took 1194ms.
[19:52:33.039] <TB1> INFO: Expecting 2560 events.
[19:52:33.928] <TB1> INFO: 2560 events read in total (297ms).
[19:52:33.929] <TB1> INFO: Test took 1197ms.
[19:52:34.235] <TB1> INFO: Expecting 2560 events.
[19:52:35.114] <TB1> INFO: 2560 events read in total (287ms).
[19:52:35.114] <TB1> INFO: Test took 1184ms.
[19:52:35.421] <TB1> INFO: Expecting 2560 events.
[19:52:36.302] <TB1> INFO: 2560 events read in total (289ms).
[19:52:36.302] <TB1> INFO: Test took 1187ms.
[19:52:36.611] <TB1> INFO: Expecting 2560 events.
[19:52:37.492] <TB1> INFO: 2560 events read in total (289ms).
[19:52:37.493] <TB1> INFO: Test took 1190ms.
[19:52:37.801] <TB1> INFO: Expecting 2560 events.
[19:52:38.695] <TB1> INFO: 2560 events read in total (303ms).
[19:52:38.695] <TB1> INFO: Test took 1202ms.
[19:52:38.002] <TB1> INFO: Expecting 2560 events.
[19:52:39.894] <TB1> INFO: 2560 events read in total (300ms).
[19:52:39.894] <TB1> INFO: Test took 1198ms.
[19:52:40.202] <TB1> INFO: Expecting 2560 events.
[19:52:41.091] <TB1> INFO: 2560 events read in total (297ms).
[19:52:41.092] <TB1> INFO: Test took 1197ms.
[19:52:41.400] <TB1> INFO: Expecting 2560 events.
[19:52:42.288] <TB1> INFO: 2560 events read in total (297ms).
[19:52:42.288] <TB1> INFO: Test took 1196ms.


[19:52:42.292] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[19:52:42.597] <TB1> INFO: Expecting 655360 events.
[19:52:57.473] <TB1> INFO: 655360 events read in total (14284ms).
[19:52:57.485] <TB1> INFO: Expecting 655360 events.
[19:53:12.019] <TB1> INFO: 655360 events read in total (14131ms).
[19:53:12.035] <TB1> INFO: Expecting 655360 events.
[19:53:26.555] <TB1> INFO: 655360 events read in total (14117ms).
[19:53:26.577] <TB1> INFO: Expecting 655360 events.
[19:53:41.124] <TB1> INFO: 655360 events read in total (14144ms).
[19:53:41.149] <TB1> INFO: Expecting 655360 events.
[19:53:55.698] <TB1> INFO: 655360 events read in total (14146ms).
[19:53:55.726] <TB1> INFO: Expecting 655360 events.
[19:54:10.325] <TB1> INFO: 655360 events read in total (14196ms).
[19:54:10.363] <TB1> INFO: Expecting 655360 events.
[19:54:24.949] <TB1> INFO: 655360 events read in total (14183ms).
[19:54:24.989] <TB1> INFO: Expecting 655360 events.
[19:54:38.995] <TB1> INFO: 655360 events read in total (13603ms).
[19:54:39.036] <TB1> INFO: Expecting 655360 events.
[19:54:53.542] <TB1> INFO: 655360 events read in total (14103ms).
[19:54:53.587] <TB1> INFO: Expecting 655360 events.
[19:55:08.083] <TB1> INFO: 655360 events read in total (14093ms).
[19:55:08.134] <TB1> INFO: Expecting 655360 events.
[19:55:22.712] <TB1> INFO: 655360 events read in total (14175ms).
[19:55:22.777] <TB1> INFO: Expecting 655360 events.
[19:55:37.387] <TB1> INFO: 655360 events read in total (14207ms).
[19:55:37.468] <TB1> INFO: Expecting 655360 events.
[19:55:52.163] <TB1> INFO: 655360 events read in total (14292ms).
[19:55:52.268] <TB1> INFO: Expecting 655360 events.
[19:56:06.863] <TB1> INFO: 655360 events read in total (14192ms).
[19:56:06.936] <TB1> INFO: Expecting 655360 events.
[19:56:21.537] <TB1> INFO: 655360 events read in total (14198ms).
[19:56:21.680] <TB1> INFO: Test took 219388ms.
[19:56:21.787] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[19:56:22.046] <TB1> INFO: Expecting 655360 events.
[19:56:36.752] <TB1> INFO: 655360 events read in total (14114ms).
[19:56:36.766] <TB1> INFO: Expecting 655360 events.
[19:56:50.889] <TB1> INFO: 655360 events read in total (13719ms).
[19:56:50.908] <TB1> INFO: Expecting 655360 events.
[19:57:05.420] <TB1> INFO: 655360 events read in total (14109ms).
[19:57:05.441] <TB1> INFO: Expecting 655360 events.
[19:57:19.674] <TB1> INFO: 655360 events read in total (13830ms).
[19:57:19.699] <TB1> INFO: Expecting 655360 events.
[19:57:34.230] <TB1> INFO: 655360 events read in total (14128ms).
[19:57:34.259] <TB1> INFO: Expecting 655360 events.
[19:57:48.820] <TB1> INFO: 655360 events read in total (14158ms).
[19:57:48.856] <TB1> INFO: Expecting 655360 events.
[19:58:03.336] <TB1> INFO: 655360 events read in total (14077ms).
[19:58:03.374] <TB1> INFO: Expecting 655360 events.
[19:58:17.659] <TB1> INFO: 655360 events read in total (13882ms).
[19:58:17.706] <TB1> INFO: Expecting 655360 events.
[19:58:32.303] <TB1> INFO: 655360 events read in total (14194ms).
[19:58:32.351] <TB1> INFO: Expecting 655360 events.
[19:58:46.828] <TB1> INFO: 655360 events read in total (14074ms).
[19:58:46.909] <TB1> INFO: Expecting 655360 events.
[19:59:01.451] <TB1> INFO: 655360 events read in total (14139ms).
[19:59:01.511] <TB1> INFO: Expecting 655360 events.
[19:59:16.274] <TB1> INFO: 655360 events read in total (14359ms).
[19:59:16.348] <TB1> INFO: Expecting 655360 events.
[19:59:31.103] <TB1> INFO: 655360 events read in total (14352ms).
[19:59:31.175] <TB1> INFO: Expecting 655360 events.
[19:59:45.478] <TB1> INFO: 655360 events read in total (13900ms).
[19:59:45.551] <TB1> INFO: Expecting 655360 events.
[20:00:00.436] <TB1> INFO: 655360 events read in total (14482ms).
[20:00:00.513] <TB1> INFO: Expecting 655360 events.
[20:00:15.368] <TB1> INFO: 655360 events read in total (14453ms).
[20:00:15.520] <TB1> INFO: Test took 233733ms.
[20:00:15.750] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[20:00:15.758] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[20:00:15.766] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[20:00:15.774] <TB1> INFO: safety margin for low PH: adding 1, margin is now 21
[20:00:15.783] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[20:00:15.791] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[20:00:15.801] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[20:00:15.809] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[20:00:15.817] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[20:00:15.825] <TB1> INFO: safety margin for low PH: adding 1, margin is now 21
[20:00:15.833] <TB1> INFO: safety margin for low PH: adding 2, margin is now 22
[20:00:15.841] <TB1> INFO: safety margin for low PH: adding 3, margin is now 23
[20:00:15.850] <TB1> INFO: safety margin for low PH: adding 4, margin is now 24
[20:00:15.858] <TB1> INFO: safety margin for low PH: adding 5, margin is now 25
[20:00:15.866] <TB1> INFO: safety margin for low PH: adding 6, margin is now 26
[20:00:15.872] <TB1> INFO: safety margin for low PH: adding 7, margin is now 27
[20:00:15.878] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[20:00:15.884] <TB1> INFO: safety margin for low PH: adding 1, margin is now 21
[20:00:15.889] <TB1> INFO: safety margin for low PH: adding 2, margin is now 22
[20:00:15.895] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[20:00:15.901] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[20:00:15.906] <TB1> INFO: safety margin for low PH: adding 1, margin is now 21
[20:00:15.912] <TB1> INFO: safety margin for low PH: adding 2, margin is now 22
[20:00:15.918] <TB1> INFO: safety margin for low PH: adding 3, margin is now 23
[20:00:15.924] <TB1> INFO: safety margin for low PH: adding 4, margin is now 24
[20:00:15.929] <TB1> INFO: safety margin for low PH: adding 5, margin is now 25
[20:00:15.935] <TB1> INFO: safety margin for low PH: adding 6, margin is now 26
[20:00:15.941] <TB1> INFO: safety margin for low PH: adding 7, margin is now 27
[20:00:15.946] <TB1> INFO: safety margin for low PH: adding 8, margin is now 28
[20:00:15.952] <TB1> INFO: safety margin for low PH: adding 9, margin is now 29
[20:00:15.958] <TB1> INFO: safety margin for low PH: adding 10, margin is now 30
[20:00:15.964] <TB1> INFO: safety margin for low PH: adding 11, margin is now 31
[20:00:15.970] <TB1> INFO: safety margin for low PH: adding 12, margin is now 32
[20:00:15.976] <TB1> INFO: safety margin for low PH: adding 13, margin is now 33
[20:00:15.981] <TB1> INFO: safety margin for low PH: adding 14, margin is now 34
[20:00:15.987] <TB1> INFO: safety margin for low PH: adding 15, margin is now 35
[20:00:15.993] <TB1> INFO: safety margin for low PH: adding 16, margin is now 36
[20:00:15.999] <TB1> INFO: safety margin for low PH: adding 17, margin is now 37
[20:00:16.005] <TB1> INFO: safety margin for low PH: adding 18, margin is now 38
[20:00:16.011] <TB1> INFO: safety margin for low PH: adding 19, margin is now 39
[20:00:16.016] <TB1> INFO: safety margin for low PH: adding 20, margin is now 40
[20:00:16.022] <TB1> INFO: safety margin for low PH: adding 21, margin is now 41
[20:00:16.028] <TB1> INFO: safety margin for low PH: adding 22, margin is now 42
[20:00:16.034] <TB1> INFO: safety margin for low PH: adding 23, margin is now 43
[20:00:16.040] <TB1> INFO: safety margin for low PH: adding 24, margin is now 44
[20:00:16.045] <TB1> INFO: safety margin for low PH: adding 25, margin is now 45
[20:00:16.051] <TB1> INFO: safety margin for low PH: adding 26, margin is now 46
[20:00:16.057] <TB1> INFO: safety margin for low PH: adding 27, margin is now 47
[20:00:16.063] <TB1> INFO: safety margin for low PH: adding 28, margin is now 48
[20:00:16.070] <TB1> INFO: safety margin for low PH: adding 29, margin is now 49
[20:00:16.076] <TB1> INFO: safety margin for low PH: adding 30, margin is now 50
[20:00:16.082] <TB1> INFO: safety margin for low PH: adding 31, margin is now 51
[20:00:16.088] <TB1> INFO: safety margin for low PH: adding 32, margin is now 52
[20:00:16.094] <TB1> INFO: safety margin for low PH: adding 33, margin is now 53
[20:00:16.100] <TB1> INFO: safety margin for low PH: adding 34, margin is now 54
[20:00:16.106] <TB1> INFO: safety margin for low PH: adding 35, margin is now 55
[20:00:16.111] <TB1> INFO: safety margin for low PH: adding 36, margin is now 56
[20:00:16.117] <TB1> INFO: safety margin for low PH: adding 37, margin is now 57
[20:00:16.123] <TB1> INFO: safety margin for low PH: adding 38, margin is now 58
[20:00:16.129] <TB1> INFO: safety margin for low PH: adding 39, margin is now 59
[20:00:16.134] <TB1> INFO: safety margin for low PH: adding 40, margin is now 60
[20:00:16.140] <TB1> INFO: safety margin for low PH: adding 41, margin is now 61
[20:00:16.146] <TB1> INFO: safety margin for low PH: adding 42, margin is now 62
[20:00:16.151] <TB1> INFO: safety margin for low PH: adding 43, margin is now 63
[20:00:16.157] <TB1> INFO: safety margin for low PH: adding 44, margin is now 64
[20:00:16.162] <TB1> INFO: safety margin for low PH: adding 45, margin is now 65
[20:00:16.168] <TB1> INFO: safety margin for low PH: adding 46, margin is now 66
[20:00:16.174] <TB1> INFO: safety margin for low PH: adding 47, margin is now 67
[20:00:16.180] <TB1> INFO: safety margin for low PH: adding 48, margin is now 68
[20:00:16.186] <TB1> INFO: safety margin for low PH: adding 49, margin is now 69
[20:00:16.192] <TB1> INFO: safety margin for low PH: adding 50, margin is now 70
[20:00:16.201] <TB1> INFO: safety margin for low PH: adding 51, margin is now 71
[20:00:16.210] <TB1> INFO: safety margin for low PH: adding 52, margin is now 72
[20:00:16.218] <TB1> INFO: safety margin for low PH: adding 53, margin is now 73
[20:00:16.226] <TB1> INFO: safety margin for low PH: adding 54, margin is now 74
[20:00:16.235] <TB1> INFO: safety margin for low PH: adding 55, margin is now 75
[20:00:16.243] <TB1> INFO: safety margin for low PH: adding 56, margin is now 76
[20:00:16.251] <TB1> INFO: safety margin for low PH: adding 57, margin is now 77
[20:00:16.260] <TB1> INFO: safety margin for low PH: adding 58, margin is now 78
[20:00:16.268] <TB1> INFO: safety margin for low PH: adding 59, margin is now 79
[20:00:16.276] <TB1> INFO: safety margin for low PH: adding 60, margin is now 80
[20:00:16.285] <TB1> INFO: safety margin for low PH: adding 61, margin is now 81
[20:00:16.293] <TB1> INFO: safety margin for low PH: adding 62, margin is now 82
[20:00:16.301] <TB1> INFO: safety margin for low PH: adding 63, margin is now 83
[20:00:16.309] <TB1> INFO: safety margin for low PH: adding 64, margin is now 84
[20:00:16.318] <TB1> INFO: safety margin for low PH: adding 65, margin is now 85
[20:00:16.326] <TB1> INFO: safety margin for low PH: adding 66, margin is now 86
[20:00:16.334] <TB1> INFO: safety margin for low PH: adding 67, margin is now 87
[20:00:16.342] <TB1> INFO: safety margin for low PH: adding 68, margin is now 88
[20:00:16.350] <TB1> INFO: safety margin for low PH: adding 69, margin is now 89
[20:00:16.359] <TB1> INFO: safety margin for low PH: adding 70, margin is now 90
[20:00:16.368] <TB1> INFO: safety margin for low PH: adding 71, margin is now 91
[20:00:16.377] <TB1> INFO: safety margin for low PH: adding 72, margin is now 92
[20:00:16.385] <TB1> INFO: safety margin for low PH: adding 73, margin is now 93
[20:00:16.393] <TB1> INFO: safety margin for low PH: adding 74, margin is now 94
[20:00:16.401] <TB1> INFO: safety margin for low PH: adding 75, margin is now 95
[20:00:16.409] <TB1> INFO: safety margin for low PH: adding 76, margin is now 96
[20:00:16.417] <TB1> INFO: safety margin for low PH: adding 77, margin is now 97
[20:00:16.426] <TB1> INFO: safety margin for low PH: adding 78, margin is now 98
[20:00:16.437] <TB1> INFO: safety margin for low PH: adding 79, margin is now 99
[20:00:16.445] <TB1> INFO: safety margin for low PH: adding 80, margin is now 100
[20:00:16.454] <TB1> INFO: safety margin for low PH: adding 81, margin is now 101
[20:00:16.461] <TB1> INFO: safety margin for low PH: adding 82, margin is now 102
[20:00:16.470] <TB1> INFO: safety margin for low PH: adding 83, margin is now 103
[20:00:16.477] <TB1> INFO: safety margin for low PH: adding 84, margin is now 104
[20:00:16.486] <TB1> INFO: safety margin for low PH: adding 85, margin is now 105
[20:00:16.493] <TB1> INFO: safety margin for low PH: adding 86, margin is now 106
[20:00:16.501] <TB1> INFO: safety margin for low PH: adding 87, margin is now 107
[20:00:16.510] <TB1> INFO: safety margin for low PH: adding 88, margin is now 108
[20:00:16.518] <TB1> INFO: safety margin for low PH: adding 89, margin is now 109
[20:00:16.526] <TB1> INFO: safety margin for low PH: adding 90, margin is now 110
[20:00:16.533] <TB1> INFO: safety margin for low PH: adding 91, margin is now 111
[20:00:16.541] <TB1> INFO: safety margin for low PH: adding 92, margin is now 112
[20:00:16.549] <TB1> INFO: safety margin for low PH: adding 93, margin is now 113
[20:00:16.558] <TB1> INFO: safety margin for low PH: adding 94, margin is now 114
[20:00:16.565] <TB1> INFO: safety margin for low PH: adding 95, margin is now 115
[20:00:16.574] <TB1> INFO: safety margin for low PH: adding 96, margin is now 116
[20:00:16.581] <TB1> INFO: safety margin for low PH: adding 97, margin is now 117
[20:00:16.590] <TB1> INFO: safety margin for low PH: adding 98, margin is now 118
[20:00:16.597] <TB1> INFO: safety margin for low PH: adding 99, margin is now 119
[20:00:16.606] <TB1> INFO: safety margin for low PH: adding 100, margin is now 120
[20:00:16.614] <TB1> INFO: safety margin for low PH: adding 101, margin is now 121
[20:00:16.622] <TB1> INFO: safety margin for low PH: adding 102, margin is now 122
[20:00:16.630] <TB1> INFO: safety margin for low PH: adding 103, margin is now 123
[20:00:16.639] <TB1> INFO: safety margin for low PH: adding 104, margin is now 124
[20:00:16.647] <TB1> INFO: safety margin for low PH: adding 105, margin is now 125
[20:00:16.655] <TB1> INFO: safety margin for low PH: adding 106, margin is now 126
[20:00:16.663] <TB1> INFO: safety margin for low PH: adding 107, margin is now 127
[20:00:16.670] <TB1> INFO: safety margin for low PH: adding 108, margin is now 128
[20:00:16.676] <TB1> INFO: safety margin for low PH: adding 109, margin is now 129
[20:00:16.682] <TB1> INFO: safety margin for low PH: adding 110, margin is now 130
[20:00:16.688] <TB1> INFO: safety margin for low PH: adding 111, margin is now 131
[20:00:16.694] <TB1> INFO: safety margin for low PH: adding 112, margin is now 132
[20:00:16.700] <TB1> INFO: safety margin for low PH: adding 113, margin is now 133
[20:00:16.706] <TB1> INFO: safety margin for low PH: adding 114, margin is now 134
[20:00:16.711] <TB1> INFO: safety margin for low PH: adding 115, margin is now 135
[20:00:16.717] <TB1> INFO: safety margin for low PH: adding 116, margin is now 136
[20:00:16.723] <TB1> INFO: safety margin for low PH: adding 117, margin is now 137
[20:00:16.729] <TB1> INFO: safety margin for low PH: adding 118, margin is now 138
[20:00:16.735] <TB1> INFO: safety margin for low PH: adding 119, margin is now 139
[20:00:16.741] <TB1> INFO: safety margin for low PH: adding 120, margin is now 140
[20:00:16.746] <TB1> INFO: safety margin for low PH: adding 121, margin is now 141
[20:00:16.752] <TB1> INFO: safety margin for low PH: adding 122, margin is now 142
[20:00:16.758] <TB1> INFO: safety margin for low PH: adding 123, margin is now 143
[20:00:16.763] <TB1> INFO: safety margin for low PH: adding 124, margin is now 144
[20:00:16.769] <TB1> INFO: safety margin for low PH: adding 125, margin is now 145
[20:00:16.775] <TB1> INFO: safety margin for low PH: adding 126, margin is now 146
[20:00:16.781] <TB1> INFO: safety margin for low PH: adding 127, margin is now 147
[20:00:16.787] <TB1> INFO: safety margin for low PH: adding 128, margin is now 148
[20:00:16.793] <TB1> INFO: safety margin for low PH: adding 129, margin is now 149
[20:00:16.799] <TB1> INFO: safety margin for low PH: adding 130, margin is now 150
[20:00:16.805] <TB1> INFO: safety margin for low PH: adding 131, margin is now 151
[20:00:16.810] <TB1> INFO: safety margin for low PH: adding 132, margin is now 152
[20:00:16.816] <TB1> INFO: safety margin for low PH: adding 133, margin is now 153
[20:00:16.822] <TB1> INFO: safety margin for low PH: adding 134, margin is now 154
[20:00:16.828] <TB1> INFO: safety margin for low PH: adding 135, margin is now 155
[20:00:16.834] <TB1> INFO: safety margin for low PH: adding 136, margin is now 156
[20:00:16.839] <TB1> INFO: safety margin for low PH: adding 137, margin is now 157
[20:00:16.845] <TB1> INFO: safety margin for low PH: adding 138, margin is now 158
[20:00:16.851] <TB1> INFO: safety margin for low PH: adding 139, margin is now 159
[20:00:16.856] <TB1> INFO: safety margin for low PH: adding 140, margin is now 160
[20:00:16.862] <TB1> INFO: safety margin for low PH: adding 141, margin is now 161
[20:00:16.868] <TB1> INFO: safety margin for low PH: adding 142, margin is now 162
[20:00:16.874] <TB1> INFO: safety margin for low PH: adding 143, margin is now 163
[20:00:16.879] <TB1> INFO: safety margin for low PH: adding 144, margin is now 164
[20:00:16.885] <TB1> INFO: safety margin for low PH: adding 145, margin is now 165
[20:00:16.891] <TB1> INFO: safety margin for low PH: adding 146, margin is now 166
[20:00:16.896] <TB1> INFO: safety margin for low PH: adding 147, margin is now 167
[20:00:16.903] <TB1> INFO: safety margin for low PH: adding 148, margin is now 168
[20:00:16.909] <TB1> INFO: safety margin for low PH: adding 149, margin is now 169
[20:00:16.914] <TB1> INFO: safety margin for low PH: adding 150, margin is now 170
[20:00:16.920] <TB1> INFO: safety margin for low PH: adding 151, margin is now 171
[20:00:16.926] <TB1> INFO: safety margin for low PH: adding 152, margin is now 172
[20:00:16.932] <TB1> INFO: safety margin for low PH: adding 153, margin is now 173
[20:00:16.938] <TB1> INFO: safety margin for low PH: adding 154, margin is now 174
[20:00:16.943] <TB1> INFO: safety margin for low PH: adding 155, margin is now 175
[20:00:16.949] <TB1> INFO: safety margin for low PH: adding 156, margin is now 176
[20:00:16.955] <TB1> INFO: safety margin for low PH: adding 157, margin is now 177
[20:00:16.961] <TB1> INFO: safety margin for low PH: adding 158, margin is now 178
[20:00:16.967] <TB1> INFO: safety margin for low PH: adding 159, margin is now 179
[20:00:16.972] <TB1> INFO: safety margin for low PH: adding 160, margin is now 180
[20:00:16.978] <TB1> INFO: safety margin for low PH: adding 161, margin is now 181
[20:00:16.984] <TB1> INFO: safety margin for low PH: adding 162, margin is now 182
[20:00:16.990] <TB1> INFO: safety margin for low PH: adding 163, margin is now 183
[20:00:16.995] <TB1> INFO: safety margin for low PH: adding 164, margin is now 184
[20:00:16.002] <TB1> INFO: safety margin for low PH: adding 165, margin is now 185
[20:00:17.010] <TB1> INFO: safety margin for low PH: adding 166, margin is now 186
[20:00:17.019] <TB1> INFO: safety margin for low PH: adding 167, margin is now 187
[20:00:17.027] <TB1> INFO: safety margin for low PH: adding 168, margin is now 188
[20:00:17.035] <TB1> INFO: safety margin for low PH: adding 169, margin is now 189
[20:00:17.043] <TB1> INFO: safety margin for low PH: adding 170, margin is now 190
[20:00:17.051] <TB1> INFO: safety margin for low PH: adding 171, margin is now 191
[20:00:17.059] <TB1> INFO: safety margin for low PH: adding 172, margin is now 192
[20:00:17.067] <TB1> INFO: safety margin for low PH: adding 173, margin is now 193
[20:00:17.075] <TB1> INFO: safety margin for low PH: adding 174, margin is now 194
[20:00:17.083] <TB1> INFO: safety margin for low PH: adding 175, margin is now 195
[20:00:17.091] <TB1> INFO: safety margin for low PH: adding 176, margin is now 196
[20:00:17.099] <TB1> INFO: safety margin for low PH: adding 177, margin is now 197
[20:00:17.108] <TB1> INFO: safety margin for low PH: adding 178, margin is now 198
[20:00:17.116] <TB1> INFO: safety margin for low PH: adding 179, margin is now 199
[20:00:17.124] <TB1> INFO: safety margin for low PH: adding 180, margin is now 200
[20:00:17.132] <TB1> INFO: safety margin for low PH: adding 181, margin is now 201
[20:00:17.140] <TB1> INFO: safety margin for low PH: adding 182, margin is now 202
[20:00:17.148] <TB1> INFO: safety margin for low PH: adding 183, margin is now 203
[20:00:17.156] <TB1> INFO: safety margin for low PH: adding 184, margin is now 204
[20:00:17.164] <TB1> INFO: safety margin for low PH: adding 185, margin is now 205
[20:00:17.173] <TB1> INFO: safety margin for low PH: adding 186, margin is now 206
[20:00:17.181] <TB1> INFO: safety margin for low PH: adding 187, margin is now 207
[20:00:17.189] <TB1> INFO: safety margin for low PH: adding 188, margin is now 208
[20:00:17.197] <TB1> INFO: safety margin for low PH: adding 189, margin is now 209
[20:00:17.206] <TB1> INFO: safety margin for low PH: adding 190, margin is now 210
[20:00:17.214] <TB1> INFO: safety margin for low PH: adding 191, margin is now 211
[20:00:17.222] <TB1> INFO: safety margin for low PH: adding 192, margin is now 212
[20:00:17.228] <TB1> INFO: safety margin for low PH: adding 193, margin is now 213
[20:00:17.234] <TB1> INFO: safety margin for low PH: adding 194, margin is now 214
[20:00:17.240] <TB1> INFO: safety margin for low PH: adding 195, margin is now 215
[20:00:17.245] <TB1> INFO: safety margin for low PH: adding 196, margin is now 216
[20:00:17.251] <TB1> INFO: safety margin for low PH: adding 197, margin is now 217
[20:00:17.257] <TB1> INFO: safety margin for low PH: adding 198, margin is now 218
[20:00:17.262] <TB1> INFO: safety margin for low PH: adding 199, margin is now 219
[20:00:17.268] <TB1> INFO: safety margin for low PH: adding 200, margin is now 220
[20:00:17.274] <TB1> INFO: safety margin for low PH: adding 201, margin is now 221
[20:00:17.279] <TB1> INFO: safety margin for low PH: adding 202, margin is now 222
[20:00:17.285] <TB1> INFO: safety margin for low PH: adding 203, margin is now 223
[20:00:17.291] <TB1> INFO: safety margin for low PH: adding 204, margin is now 224
[20:00:17.297] <TB1> INFO: safety margin for low PH: adding 205, margin is now 225
[20:00:17.303] <TB1> INFO: safety margin for low PH: adding 206, margin is now 226
[20:00:17.309] <TB1> INFO: safety margin for low PH: adding 207, margin is now 227
[20:00:17.314] <TB1> INFO: safety margin for low PH: adding 208, margin is now 228
[20:00:17.320] <TB1> INFO: safety margin for low PH: adding 209, margin is now 229
[20:00:17.326] <TB1> INFO: safety margin for low PH: adding 210, margin is now 230
[20:00:17.332] <TB1> INFO: safety margin for low PH: adding 211, margin is now 231
[20:00:17.338] <TB1> INFO: safety margin for low PH: adding 212, margin is now 232
[20:00:17.343] <TB1> INFO: safety margin for low PH: adding 213, margin is now 233
[20:00:17.349] <TB1> INFO: safety margin for low PH: adding 214, margin is now 234
[20:00:17.355] <TB1> INFO: safety margin for low PH: adding 215, margin is now 235
[20:00:17.360] <TB1> INFO: safety margin for low PH: adding 216, margin is now 236
[20:00:17.366] <TB1> INFO: safety margin for low PH: adding 217, margin is now 237
[20:00:17.372] <TB1> INFO: safety margin for low PH: adding 218, margin is now 238
[20:00:17.378] <TB1> INFO: safety margin for low PH: adding 219, margin is now 239
[20:00:17.384] <TB1> INFO: safety margin for low PH: adding 220, margin is now 240
[20:00:17.389] <TB1> INFO: safety margin for low PH: adding 221, margin is now 241
[20:00:17.395] <TB1> INFO: safety margin for low PH: adding 222, margin is now 242
[20:00:17.401] <TB1> INFO: safety margin for low PH: adding 223, margin is now 243
[20:00:17.407] <TB1> INFO: safety margin for low PH: adding 224, margin is now 244
[20:00:17.412] <TB1> INFO: safety margin for low PH: adding 225, margin is now 245
[20:00:17.418] <TB1> INFO: safety margin for low PH: adding 226, margin is now 246
[20:00:17.424] <TB1> INFO: safety margin for low PH: adding 227, margin is now 247
[20:00:17.429] <TB1> INFO: safety margin for low PH: adding 228, margin is now 248
[20:00:17.435] <TB1> INFO: safety margin for low PH: adding 229, margin is now 249
[20:00:17.441] <TB1> INFO: safety margin for low PH: adding 230, margin is now 250
[20:00:17.446] <TB1> INFO: safety margin for low PH: adding 231, margin is now 251
[20:00:17.453] <TB1> INFO: safety margin for low PH: adding 232, margin is now 252
[20:00:17.458] <TB1> INFO: safety margin for low PH: adding 233, margin is now 253
[20:00:17.464] <TB1> INFO: safety margin for low PH: adding 234, margin is now 254
[20:00:17.470] <TB1> INFO: safety margin for low PH: adding 235, margin is now 255
[20:00:17.476] <TB1> INFO: For ROC 10: No solutions with PhScale > 20 could be found. Looking for a solution with PhScale < 20.
[20:00:17.476] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[20:00:17.482] <TB1> INFO: safety margin for low PH: adding 1, margin is now 21
[20:00:17.488] <TB1> INFO: safety margin for low PH: adding 2, margin is now 22
[20:00:17.494] <TB1> INFO: safety margin for low PH: adding 3, margin is now 23
[20:00:17.500] <TB1> INFO: safety margin for low PH: adding 4, margin is now 24
[20:00:17.505] <TB1> INFO: safety margin for low PH: adding 5, margin is now 25
[20:00:17.511] <TB1> INFO: safety margin for low PH: adding 6, margin is now 26
[20:00:17.517] <TB1> INFO: safety margin for low PH: adding 7, margin is now 27
[20:00:17.523] <TB1> INFO: safety margin for low PH: adding 8, margin is now 28
[20:00:17.528] <TB1> INFO: safety margin for low PH: adding 9, margin is now 29
[20:00:17.534] <TB1> INFO: safety margin for low PH: adding 10, margin is now 30
[20:00:17.540] <TB1> INFO: safety margin for low PH: adding 11, margin is now 31
[20:00:17.546] <TB1> INFO: safety margin for low PH: adding 12, margin is now 32
[20:00:17.552] <TB1> INFO: safety margin for low PH: adding 13, margin is now 33
[20:00:17.558] <TB1> INFO: safety margin for low PH: adding 14, margin is now 34
[20:00:17.563] <TB1> INFO: safety margin for low PH: adding 15, margin is now 35
[20:00:17.569] <TB1> INFO: safety margin for low PH: adding 16, margin is now 36
[20:00:17.575] <TB1> INFO: safety margin for low PH: adding 17, margin is now 37
[20:00:17.581] <TB1> INFO: safety margin for low PH: adding 18, margin is now 38
[20:00:17.587] <TB1> INFO: safety margin for low PH: adding 19, margin is now 39
[20:00:17.593] <TB1> INFO: safety margin for low PH: adding 20, margin is now 40
[20:00:17.598] <TB1> INFO: safety margin for low PH: adding 21, margin is now 41
[20:00:17.604] <TB1> INFO: safety margin for low PH: adding 22, margin is now 42
[20:00:17.610] <TB1> INFO: safety margin for low PH: adding 23, margin is now 43
[20:00:17.616] <TB1> INFO: safety margin for low PH: adding 24, margin is now 44
[20:00:17.622] <TB1> INFO: safety margin for low PH: adding 25, margin is now 45
[20:00:17.628] <TB1> INFO: safety margin for low PH: adding 26, margin is now 46
[20:00:17.634] <TB1> INFO: safety margin for low PH: adding 27, margin is now 47
[20:00:17.640] <TB1> INFO: safety margin for low PH: adding 28, margin is now 48
[20:00:17.645] <TB1> INFO: safety margin for low PH: adding 29, margin is now 49
[20:00:17.651] <TB1> INFO: safety margin for low PH: adding 30, margin is now 50
[20:00:17.657] <TB1> INFO: safety margin for low PH: adding 31, margin is now 51
[20:00:17.662] <TB1> INFO: safety margin for low PH: adding 32, margin is now 52
[20:00:17.668] <TB1> INFO: safety margin for low PH: adding 33, margin is now 53
[20:00:17.674] <TB1> INFO: safety margin for low PH: adding 34, margin is now 54
[20:00:17.680] <TB1> INFO: safety margin for low PH: adding 35, margin is now 55
[20:00:17.686] <TB1> INFO: safety margin for low PH: adding 36, margin is now 56
[20:00:17.692] <TB1> INFO: safety margin for low PH: adding 37, margin is now 57
[20:00:17.697] <TB1> INFO: safety margin for low PH: adding 38, margin is now 58
[20:00:17.703] <TB1> INFO: safety margin for low PH: adding 39, margin is now 59
[20:00:17.709] <TB1> INFO: safety margin for low PH: adding 40, margin is now 60
[20:00:17.715] <TB1> INFO: safety margin for low PH: adding 41, margin is now 61
[20:00:17.721] <TB1> INFO: safety margin for low PH: adding 42, margin is now 62
[20:00:17.727] <TB1> INFO: safety margin for low PH: adding 43, margin is now 63
[20:00:17.733] <TB1> INFO: safety margin for low PH: adding 44, margin is now 64
[20:00:17.739] <TB1> INFO: safety margin for low PH: adding 45, margin is now 65
[20:00:17.744] <TB1> INFO: safety margin for low PH: adding 46, margin is now 66
[20:00:17.750] <TB1> INFO: safety margin for low PH: adding 47, margin is now 67
[20:00:17.756] <TB1> INFO: safety margin for low PH: adding 48, margin is now 68
[20:00:17.762] <TB1> INFO: safety margin for low PH: adding 49, margin is now 69
[20:00:17.768] <TB1> INFO: safety margin for low PH: adding 50, margin is now 70
[20:00:17.774] <TB1> INFO: safety margin for low PH: adding 51, margin is now 71
[20:00:17.780] <TB1> INFO: safety margin for low PH: adding 52, margin is now 72
[20:00:17.786] <TB1> INFO: safety margin for low PH: adding 53, margin is now 73
[20:00:17.792] <TB1> INFO: safety margin for low PH: adding 54, margin is now 74
[20:00:17.798] <TB1> INFO: safety margin for low PH: adding 55, margin is now 75
[20:00:17.804] <TB1> INFO: safety margin for low PH: adding 56, margin is now 76
[20:00:17.810] <TB1> INFO: safety margin for low PH: adding 57, margin is now 77
[20:00:17.816] <TB1> INFO: safety margin for low PH: adding 58, margin is now 78
[20:00:17.822] <TB1> INFO: safety margin for low PH: adding 59, margin is now 79
[20:00:17.827] <TB1> INFO: safety margin for low PH: adding 60, margin is now 80
[20:00:17.833] <TB1> INFO: safety margin for low PH: adding 61, margin is now 81
[20:00:17.839] <TB1> INFO: safety margin for low PH: adding 62, margin is now 82
[20:00:17.845] <TB1> INFO: safety margin for low PH: adding 63, margin is now 83
[20:00:17.851] <TB1> INFO: safety margin for low PH: adding 64, margin is now 84
[20:00:17.857] <TB1> INFO: safety margin for low PH: adding 65, margin is now 85
[20:00:17.862] <TB1> INFO: safety margin for low PH: adding 66, margin is now 86
[20:00:17.868] <TB1> INFO: safety margin for low PH: adding 67, margin is now 87
[20:00:17.874] <TB1> INFO: safety margin for low PH: adding 68, margin is now 88
[20:00:17.880] <TB1> INFO: safety margin for low PH: adding 69, margin is now 89
[20:00:17.886] <TB1> INFO: safety margin for low PH: adding 70, margin is now 90
[20:00:17.892] <TB1> INFO: safety margin for low PH: adding 71, margin is now 91
[20:00:17.898] <TB1> INFO: safety margin for low PH: adding 72, margin is now 92
[20:00:17.904] <TB1> INFO: safety margin for low PH: adding 73, margin is now 93
[20:00:17.910] <TB1> INFO: safety margin for low PH: adding 74, margin is now 94
[20:00:17.916] <TB1> INFO: safety margin for low PH: adding 75, margin is now 95
[20:00:17.922] <TB1> INFO: safety margin for low PH: adding 76, margin is now 96
[20:00:17.928] <TB1> INFO: safety margin for low PH: adding 77, margin is now 97
[20:00:17.934] <TB1> INFO: safety margin for low PH: adding 78, margin is now 98
[20:00:17.940] <TB1> INFO: safety margin for low PH: adding 79, margin is now 99
[20:00:17.946] <TB1> INFO: safety margin for low PH: adding 80, margin is now 100
[20:00:17.953] <TB1> INFO: safety margin for low PH: adding 81, margin is now 101
[20:00:17.959] <TB1> INFO: safety margin for low PH: adding 82, margin is now 102
[20:00:17.965] <TB1> INFO: safety margin for low PH: adding 83, margin is now 103
[20:00:17.971] <TB1> INFO: safety margin for low PH: adding 84, margin is now 104
[20:00:17.977] <TB1> INFO: safety margin for low PH: adding 85, margin is now 105
[20:00:17.983] <TB1> INFO: safety margin for low PH: adding 86, margin is now 106
[20:00:17.989] <TB1> INFO: safety margin for low PH: adding 87, margin is now 107
[20:00:17.995] <TB1> INFO: safety margin for low PH: adding 88, margin is now 108
[20:00:17.001] <TB1> INFO: safety margin for low PH: adding 89, margin is now 109
[20:00:18.007] <TB1> INFO: safety margin for low PH: adding 90, margin is now 110
[20:00:18.013] <TB1> INFO: safety margin for low PH: adding 91, margin is now 111
[20:00:18.020] <TB1> INFO: safety margin for low PH: adding 92, margin is now 112
[20:00:18.026] <TB1> INFO: safety margin for low PH: adding 93, margin is now 113
[20:00:18.031] <TB1> INFO: safety margin for low PH: adding 94, margin is now 114
[20:00:18.038] <TB1> INFO: safety margin for low PH: adding 95, margin is now 115
[20:00:18.043] <TB1> INFO: safety margin for low PH: adding 96, margin is now 116
[20:00:18.049] <TB1> INFO: safety margin for low PH: adding 97, margin is now 117
[20:00:18.055] <TB1> INFO: safety margin for low PH: adding 98, margin is now 118
[20:00:18.061] <TB1> INFO: safety margin for low PH: adding 99, margin is now 119
[20:00:18.067] <TB1> INFO: safety margin for low PH: adding 100, margin is now 120
[20:00:18.073] <TB1> INFO: safety margin for low PH: adding 101, margin is now 121
[20:00:18.079] <TB1> INFO: safety margin for low PH: adding 102, margin is now 122
[20:00:18.085] <TB1> INFO: safety margin for low PH: adding 103, margin is now 123
[20:00:18.091] <TB1> INFO: safety margin for low PH: adding 104, margin is now 124
[20:00:18.096] <TB1> INFO: safety margin for low PH: adding 105, margin is now 125
[20:00:18.103] <TB1> INFO: safety margin for low PH: adding 106, margin is now 126
[20:00:18.108] <TB1> INFO: safety margin for low PH: adding 107, margin is now 127
[20:00:18.114] <TB1> INFO: safety margin for low PH: adding 108, margin is now 128
[20:00:18.120] <TB1> INFO: safety margin for low PH: adding 109, margin is now 129
[20:00:18.126] <TB1> INFO: safety margin for low PH: adding 110, margin is now 130
[20:00:18.132] <TB1> INFO: safety margin for low PH: adding 111, margin is now 131
[20:00:18.138] <TB1> INFO: safety margin for low PH: adding 112, margin is now 132
[20:00:18.143] <TB1> INFO: safety margin for low PH: adding 113, margin is now 133
[20:00:18.149] <TB1> INFO: safety margin for low PH: adding 114, margin is now 134
[20:00:18.155] <TB1> INFO: safety margin for low PH: adding 115, margin is now 135
[20:00:18.161] <TB1> INFO: safety margin for low PH: adding 116, margin is now 136
[20:00:18.167] <TB1> INFO: safety margin for low PH: adding 117, margin is now 137
[20:00:18.173] <TB1> INFO: safety margin for low PH: adding 118, margin is now 138
[20:00:18.178] <TB1> INFO: safety margin for low PH: adding 119, margin is now 139
[20:00:18.184] <TB1> INFO: safety margin for low PH: adding 120, margin is now 140
[20:00:18.190] <TB1> INFO: safety margin for low PH: adding 121, margin is now 141
[20:00:18.196] <TB1> INFO: safety margin for low PH: adding 122, margin is now 142
[20:00:18.202] <TB1> INFO: safety margin for low PH: adding 123, margin is now 143
[20:00:18.208] <TB1> INFO: safety margin for low PH: adding 124, margin is now 144
[20:00:18.214] <TB1> INFO: safety margin for low PH: adding 125, margin is now 145
[20:00:18.220] <TB1> INFO: safety margin for low PH: adding 126, margin is now 146
[20:00:18.226] <TB1> INFO: safety margin for low PH: adding 127, margin is now 147
[20:00:18.232] <TB1> INFO: safety margin for low PH: adding 128, margin is now 148
[20:00:18.238] <TB1> INFO: safety margin for low PH: adding 129, margin is now 149
[20:00:18.244] <TB1> INFO: safety margin for low PH: adding 130, margin is now 150
[20:00:18.250] <TB1> INFO: safety margin for low PH: adding 131, margin is now 151
[20:00:18.256] <TB1> INFO: safety margin for low PH: adding 132, margin is now 152
[20:00:18.262] <TB1> INFO: safety margin for low PH: adding 133, margin is now 153
[20:00:18.268] <TB1> INFO: safety margin for low PH: adding 134, margin is now 154
[20:00:18.274] <TB1> INFO: safety margin for low PH: adding 135, margin is now 155
[20:00:18.280] <TB1> INFO: safety margin for low PH: adding 136, margin is now 156
[20:00:18.286] <TB1> INFO: safety margin for low PH: adding 137, margin is now 157
[20:00:18.292] <TB1> INFO: safety margin for low PH: adding 138, margin is now 158
[20:00:18.298] <TB1> INFO: safety margin for low PH: adding 139, margin is now 159
[20:00:18.304] <TB1> INFO: safety margin for low PH: adding 140, margin is now 160
[20:00:18.309] <TB1> INFO: safety margin for low PH: adding 141, margin is now 161
[20:00:18.315] <TB1> INFO: safety margin for low PH: adding 142, margin is now 162
[20:00:18.321] <TB1> INFO: safety margin for low PH: adding 143, margin is now 163
[20:00:18.327] <TB1> INFO: safety margin for low PH: adding 144, margin is now 164
[20:00:18.333] <TB1> INFO: safety margin for low PH: adding 145, margin is now 165
[20:00:18.339] <TB1> INFO: safety margin for low PH: adding 146, margin is now 166
[20:00:18.345] <TB1> INFO: safety margin for low PH: adding 147, margin is now 167
[20:00:18.351] <TB1> INFO: safety margin for low PH: adding 148, margin is now 168
[20:00:18.357] <TB1> INFO: safety margin for low PH: adding 149, margin is now 169
[20:00:18.362] <TB1> INFO: safety margin for low PH: adding 150, margin is now 170
[20:00:18.368] <TB1> INFO: safety margin for low PH: adding 151, margin is now 171
[20:00:18.374] <TB1> INFO: safety margin for low PH: adding 152, margin is now 172
[20:00:18.380] <TB1> INFO: safety margin for low PH: adding 153, margin is now 173
[20:00:18.386] <TB1> INFO: safety margin for low PH: adding 154, margin is now 174
[20:00:18.392] <TB1> INFO: safety margin for low PH: adding 155, margin is now 175
[20:00:18.398] <TB1> INFO: safety margin for low PH: adding 156, margin is now 176
[20:00:18.404] <TB1> INFO: safety margin for low PH: adding 157, margin is now 177
[20:00:18.410] <TB1> INFO: safety margin for low PH: adding 158, margin is now 178
[20:00:18.416] <TB1> INFO: safety margin for low PH: adding 159, margin is now 179
[20:00:18.422] <TB1> INFO: safety margin for low PH: adding 160, margin is now 180
[20:00:18.427] <TB1> INFO: safety margin for low PH: adding 161, margin is now 181
[20:00:18.433] <TB1> INFO: safety margin for low PH: adding 162, margin is now 182
[20:00:18.439] <TB1> INFO: safety margin for low PH: adding 163, margin is now 183
[20:00:18.445] <TB1> INFO: safety margin for low PH: adding 164, margin is now 184
[20:00:18.451] <TB1> INFO: safety margin for low PH: adding 165, margin is now 185
[20:00:18.457] <TB1> INFO: safety margin for low PH: adding 166, margin is now 186
[20:00:18.463] <TB1> INFO: safety margin for low PH: adding 167, margin is now 187
[20:00:18.469] <TB1> INFO: safety margin for low PH: adding 168, margin is now 188
[20:00:18.475] <TB1> INFO: safety margin for low PH: adding 169, margin is now 189
[20:00:18.480] <TB1> INFO: safety margin for low PH: adding 170, margin is now 190
[20:00:18.486] <TB1> INFO: safety margin for low PH: adding 171, margin is now 191
[20:00:18.492] <TB1> INFO: safety margin for low PH: adding 172, margin is now 192
[20:00:18.498] <TB1> INFO: safety margin for low PH: adding 173, margin is now 193
[20:00:18.504] <TB1> INFO: safety margin for low PH: adding 174, margin is now 194
[20:00:18.510] <TB1> INFO: safety margin for low PH: adding 175, margin is now 195
[20:00:18.516] <TB1> INFO: safety margin for low PH: adding 176, margin is now 196
[20:00:18.522] <TB1> INFO: safety margin for low PH: adding 177, margin is now 197
[20:00:18.527] <TB1> INFO: safety margin for low PH: adding 178, margin is now 198
[20:00:18.533] <TB1> INFO: safety margin for low PH: adding 179, margin is now 199
[20:00:18.539] <TB1> INFO: safety margin for low PH: adding 180, margin is now 200
[20:00:18.545] <TB1> INFO: safety margin for low PH: adding 181, margin is now 201
[20:00:18.551] <TB1> INFO: safety margin for low PH: adding 182, margin is now 202
[20:00:18.557] <TB1> INFO: safety margin for low PH: adding 183, margin is now 203
[20:00:18.563] <TB1> INFO: safety margin for low PH: adding 184, margin is now 204
[20:00:18.569] <TB1> INFO: safety margin for low PH: adding 185, margin is now 205
[20:00:18.576] <TB1> INFO: safety margin for low PH: adding 186, margin is now 206
[20:00:18.585] <TB1> INFO: safety margin for low PH: adding 187, margin is now 207
[20:00:18.593] <TB1> INFO: safety margin for low PH: adding 188, margin is now 208
[20:00:18.602] <TB1> INFO: safety margin for low PH: adding 189, margin is now 209
[20:00:18.611] <TB1> INFO: safety margin for low PH: adding 190, margin is now 210
[20:00:18.619] <TB1> INFO: safety margin for low PH: adding 191, margin is now 211
[20:00:18.627] <TB1> INFO: safety margin for low PH: adding 192, margin is now 212
[20:00:18.636] <TB1> INFO: safety margin for low PH: adding 193, margin is now 213
[20:00:18.644] <TB1> INFO: safety margin for low PH: adding 194, margin is now 214
[20:00:18.652] <TB1> INFO: safety margin for low PH: adding 195, margin is now 215
[20:00:18.658] <TB1> INFO: safety margin for low PH: adding 196, margin is now 216
[20:00:18.663] <TB1> INFO: safety margin for low PH: adding 197, margin is now 217
[20:00:18.669] <TB1> INFO: safety margin for low PH: adding 198, margin is now 218
[20:00:18.675] <TB1> INFO: safety margin for low PH: adding 199, margin is now 219
[20:00:18.681] <TB1> INFO: safety margin for low PH: adding 200, margin is now 220
[20:00:18.687] <TB1> INFO: safety margin for low PH: adding 201, margin is now 221
[20:00:18.693] <TB1> INFO: safety margin for low PH: adding 202, margin is now 222
[20:00:18.698] <TB1> INFO: safety margin for low PH: adding 203, margin is now 223
[20:00:18.704] <TB1> INFO: safety margin for low PH: adding 204, margin is now 224
[20:00:18.710] <TB1> INFO: safety margin for low PH: adding 205, margin is now 225
[20:00:18.716] <TB1> INFO: safety margin for low PH: adding 206, margin is now 226
[20:00:18.722] <TB1> INFO: safety margin for low PH: adding 207, margin is now 227
[20:00:18.727] <TB1> INFO: safety margin for low PH: adding 208, margin is now 228
[20:00:18.733] <TB1> INFO: safety margin for low PH: adding 209, margin is now 229
[20:00:18.739] <TB1> INFO: safety margin for low PH: adding 210, margin is now 230
[20:00:18.744] <TB1> INFO: safety margin for low PH: adding 211, margin is now 231
[20:00:18.750] <TB1> INFO: safety margin for low PH: adding 212, margin is now 232
[20:00:18.756] <TB1> INFO: safety margin for low PH: adding 213, margin is now 233
[20:00:18.762] <TB1> INFO: safety margin for low PH: adding 214, margin is now 234
[20:00:18.768] <TB1> INFO: safety margin for low PH: adding 215, margin is now 235
[20:00:18.773] <TB1> INFO: safety margin for low PH: adding 216, margin is now 236
[20:00:18.779] <TB1> INFO: safety margin for low PH: adding 217, margin is now 237
[20:00:18.785] <TB1> INFO: safety margin for low PH: adding 218, margin is now 238
[20:00:18.790] <TB1> INFO: safety margin for low PH: adding 219, margin is now 239
[20:00:18.796] <TB1> INFO: safety margin for low PH: adding 220, margin is now 240
[20:00:18.803] <TB1> INFO: safety margin for low PH: adding 221, margin is now 241
[20:00:18.809] <TB1> INFO: safety margin for low PH: adding 222, margin is now 242
[20:00:18.816] <TB1> INFO: safety margin for low PH: adding 223, margin is now 243
[20:00:18.822] <TB1> INFO: safety margin for low PH: adding 224, margin is now 244
[20:00:18.828] <TB1> INFO: safety margin for low PH: adding 225, margin is now 245
[20:00:18.834] <TB1> INFO: safety margin for low PH: adding 226, margin is now 246
[20:00:18.840] <TB1> INFO: safety margin for low PH: adding 227, margin is now 247
[20:00:18.846] <TB1> INFO: safety margin for low PH: adding 228, margin is now 248
[20:00:18.853] <TB1> INFO: safety margin for low PH: adding 229, margin is now 249
[20:00:18.858] <TB1> INFO: safety margin for low PH: adding 230, margin is now 250
[20:00:18.864] <TB1> INFO: safety margin for low PH: adding 231, margin is now 251
[20:00:18.870] <TB1> INFO: safety margin for low PH: adding 232, margin is now 252
[20:00:18.876] <TB1> INFO: safety margin for low PH: adding 233, margin is now 253
[20:00:18.882] <TB1> INFO: safety margin for low PH: adding 234, margin is now 254
[20:00:18.888] <TB1> INFO: safety margin for low PH: adding 235, margin is now 255
[20:00:18.894] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[20:00:18.901] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[20:00:18.906] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[20:00:18.912] <TB1> INFO: safety margin for low PH: adding 1, margin is now 21
[20:00:18.918] <TB1> INFO: safety margin for low PH: adding 2, margin is now 22
[20:00:18.924] <TB1> INFO: safety margin for low PH: adding 3, margin is now 23
[20:00:18.930] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[20:00:18.936] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[20:00:18.942] <TB1> INFO: safety margin for low PH: adding 1, margin is now 21
[20:00:18.982] <TB1> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1005_FullQualification_2016-10-16_17h47m_1476632865//001_Fulltest_m20//dacParameters35_C0.dat
[20:00:18.982] <TB1> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1005_FullQualification_2016-10-16_17h47m_1476632865//001_Fulltest_m20//dacParameters35_C1.dat
[20:00:18.982] <TB1> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1005_FullQualification_2016-10-16_17h47m_1476632865//001_Fulltest_m20//dacParameters35_C2.dat
[20:00:18.982] <TB1> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1005_FullQualification_2016-10-16_17h47m_1476632865//001_Fulltest_m20//dacParameters35_C3.dat
[20:00:18.982] <TB1> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1005_FullQualification_2016-10-16_17h47m_1476632865//001_Fulltest_m20//dacParameters35_C4.dat
[20:00:18.983] <TB1> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1005_FullQualification_2016-10-16_17h47m_1476632865//001_Fulltest_m20//dacParameters35_C5.dat
[20:00:18.983] <TB1> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1005_FullQualification_2016-10-16_17h47m_1476632865//001_Fulltest_m20//dacParameters35_C6.dat
[20:00:18.983] <TB1> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1005_FullQualification_2016-10-16_17h47m_1476632865//001_Fulltest_m20//dacParameters35_C7.dat
[20:00:18.983] <TB1> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1005_FullQualification_2016-10-16_17h47m_1476632865//001_Fulltest_m20//dacParameters35_C8.dat
[20:00:18.984] <TB1> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1005_FullQualification_2016-10-16_17h47m_1476632865//001_Fulltest_m20//dacParameters35_C9.dat
[20:00:18.984] <TB1> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1005_FullQualification_2016-10-16_17h47m_1476632865//001_Fulltest_m20//dacParameters35_C10.dat
[20:00:18.984] <TB1> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1005_FullQualification_2016-10-16_17h47m_1476632865//001_Fulltest_m20//dacParameters35_C11.dat
[20:00:18.984] <TB1> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1005_FullQualification_2016-10-16_17h47m_1476632865//001_Fulltest_m20//dacParameters35_C12.dat
[20:00:18.984] <TB1> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1005_FullQualification_2016-10-16_17h47m_1476632865//001_Fulltest_m20//dacParameters35_C13.dat
[20:00:18.985] <TB1> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1005_FullQualification_2016-10-16_17h47m_1476632865//001_Fulltest_m20//dacParameters35_C14.dat
[20:00:18.985] <TB1> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1005_FullQualification_2016-10-16_17h47m_1476632865//001_Fulltest_m20//dacParameters35_C15.dat
[20:00:19.230] <TB1> INFO: Expecting 41600 events.
[20:00:22.441] <TB1> INFO: 41600 events read in total (2620ms).
[20:00:22.442] <TB1> INFO: Test took 3454ms.
[20:00:22.897] <TB1> INFO: Expecting 41600 events.
[20:00:25.981] <TB1> INFO: 41600 events read in total (2492ms).
[20:00:25.982] <TB1> INFO: Test took 3326ms.
[20:00:26.436] <TB1> INFO: Expecting 41600 events.
[20:00:29.594] <TB1> INFO: 41600 events read in total (2566ms).
[20:00:29.594] <TB1> INFO: Test took 3397ms.
[20:00:29.810] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[20:00:29.899] <TB1> INFO: Expecting 2560 events.
[20:00:30.789] <TB1> INFO: 2560 events read in total (298ms).
[20:00:30.789] <TB1> INFO: Test took 979ms.
[20:00:30.792] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[20:00:31.097] <TB1> INFO: Expecting 2560 events.
[20:00:31.989] <TB1> INFO: 2560 events read in total (300ms).
[20:00:31.989] <TB1> INFO: Test took 1197ms.
[20:00:31.993] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[20:00:32.297] <TB1> INFO: Expecting 2560 events.
[20:00:33.180] <TB1> INFO: 2560 events read in total (292ms).
[20:00:33.181] <TB1> INFO: Test took 1188ms.
[20:00:33.184] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[20:00:33.489] <TB1> INFO: Expecting 2560 events.
[20:00:34.383] <TB1> INFO: 2560 events read in total (302ms).
[20:00:34.384] <TB1> INFO: Test took 1200ms.
[20:00:34.387] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[20:00:34.691] <TB1> INFO: Expecting 2560 events.
[20:00:35.578] <TB1> INFO: 2560 events read in total (295ms).
[20:00:35.578] <TB1> INFO: Test took 1191ms.
[20:00:35.581] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[20:00:35.887] <TB1> INFO: Expecting 2560 events.
[20:00:36.773] <TB1> INFO: 2560 events read in total (294ms).
[20:00:36.773] <TB1> INFO: Test took 1192ms.
[20:00:36.776] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[20:00:37.083] <TB1> INFO: Expecting 2560 events.
[20:00:37.967] <TB1> INFO: 2560 events read in total (293ms).
[20:00:37.968] <TB1> INFO: Test took 1192ms.
[20:00:37.970] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[20:00:38.277] <TB1> INFO: Expecting 2560 events.
[20:00:39.168] <TB1> INFO: 2560 events read in total (299ms).
[20:00:39.175] <TB1> INFO: Test took 1205ms.
[20:00:39.178] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[20:00:39.477] <TB1> INFO: Expecting 2560 events.
[20:00:40.356] <TB1> INFO: 2560 events read in total (288ms).
[20:00:40.357] <TB1> INFO: Test took 1179ms.
[20:00:40.359] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[20:00:40.665] <TB1> INFO: Expecting 2560 events.
[20:00:41.546] <TB1> INFO: 2560 events read in total (289ms).
[20:00:41.546] <TB1> INFO: Test took 1187ms.
[20:00:41.548] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[20:00:41.854] <TB1> INFO: Expecting 2560 events.
[20:00:42.736] <TB1> INFO: 2560 events read in total (289ms).
[20:00:42.737] <TB1> INFO: Test took 1189ms.
[20:00:42.739] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[20:00:43.191] <TB1> INFO: Expecting 2560 events.
[20:00:44.073] <TB1> INFO: 2560 events read in total (290ms).
[20:00:44.073] <TB1> INFO: Test took 1334ms.
[20:00:44.076] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[20:00:44.381] <TB1> INFO: Expecting 2560 events.
[20:00:45.270] <TB1> INFO: 2560 events read in total (297ms).
[20:00:45.270] <TB1> INFO: Test took 1194ms.
[20:00:45.274] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[20:00:45.578] <TB1> INFO: Expecting 2560 events.
[20:00:46.468] <TB1> INFO: 2560 events read in total (298ms).
[20:00:46.468] <TB1> INFO: Test took 1194ms.
[20:00:46.470] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[20:00:46.777] <TB1> INFO: Expecting 2560 events.
[20:00:47.664] <TB1> INFO: 2560 events read in total (296ms).
[20:00:47.664] <TB1> INFO: Test took 1194ms.
[20:00:47.666] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[20:00:47.974] <TB1> INFO: Expecting 2560 events.
[20:00:48.865] <TB1> INFO: 2560 events read in total (299ms).
[20:00:48.866] <TB1> INFO: Test took 1200ms.
[20:00:48.869] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[20:00:49.173] <TB1> INFO: Expecting 2560 events.
[20:00:50.055] <TB1> INFO: 2560 events read in total (290ms).
[20:00:50.055] <TB1> INFO: Test took 1186ms.
[20:00:50.059] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[20:00:50.364] <TB1> INFO: Expecting 2560 events.
[20:00:51.247] <TB1> INFO: 2560 events read in total (291ms).
[20:00:51.248] <TB1> INFO: Test took 1190ms.
[20:00:51.251] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[20:00:51.555] <TB1> INFO: Expecting 2560 events.
[20:00:52.448] <TB1> INFO: 2560 events read in total (301ms).
[20:00:52.448] <TB1> INFO: Test took 1197ms.
[20:00:52.452] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[20:00:52.756] <TB1> INFO: Expecting 2560 events.
[20:00:53.646] <TB1> INFO: 2560 events read in total (298ms).
[20:00:53.646] <TB1> INFO: Test took 1195ms.



[20:00:53.955] <TB1> INFO: Test took 307ms.
[20:00:53.957] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[20:00:54.422] <TB1> INFO: Expecting 2560 events.
[20:00:55.304] <TB1> INFO: 2560 events read in total (290ms).
[20:00:55.305] <TB1> INFO: Test took 1348ms.
[20:00:55.307] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[20:00:55.614] <TB1> INFO: Expecting 2560 events.
[20:00:56.496] <TB1> INFO: 2560 events read in total (290ms).
[20:00:56.496] <TB1> INFO: Test took 1189ms.
[20:00:56.499] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[20:00:56.804] <TB1> INFO: Expecting 2560 events.
[20:00:57.691] <TB1> INFO: 2560 events read in total (295ms).
[20:00:57.692] <TB1> INFO: Test took 1193ms.
[20:00:57.695] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[20:00:57.000] <TB1> INFO: Expecting 2560 events.
[20:00:58.895] <TB1> INFO: 2560 events read in total (304ms).
[20:00:58.895] <TB1> INFO: Test took 1200ms.
[20:00:58.898] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[20:00:59.203] <TB1> INFO: Expecting 2560 events.
[20:01:00.098] <TB1> INFO: 2560 events read in total (303ms).
[20:01:00.098] <TB1> INFO: Test took 1200ms.
[20:01:00.101] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[20:01:00.407] <TB1> INFO: Expecting 2560 events.
[20:01:01.301] <TB1> INFO: 2560 events read in total (303ms).
[20:01:01.301] <TB1> INFO: Test took 1200ms.
[20:01:01.304] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[20:01:01.610] <TB1> INFO: Expecting 2560 events.
[20:01:02.502] <TB1> INFO: 2560 events read in total (300ms).
[20:01:02.502] <TB1> INFO: Test took 1198ms.
[20:01:02.505] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[20:01:02.810] <TB1> INFO: Expecting 2560 events.
[20:01:03.701] <TB1> INFO: 2560 events read in total (299ms).
[20:01:03.701] <TB1> INFO: Test took 1196ms.
[20:01:03.703] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[20:01:04.010] <TB1> INFO: Expecting 2560 events.
[20:01:04.909] <TB1> INFO: 2560 events read in total (307ms).
[20:01:04.909] <TB1> INFO: Test took 1206ms.
[20:01:04.912] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[20:01:05.219] <TB1> INFO: Expecting 2560 events.
[20:01:06.113] <TB1> INFO: 2560 events read in total (302ms).
[20:01:06.113] <TB1> INFO: Test took 1201ms.
[20:01:06.116] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[20:01:06.421] <TB1> INFO: Expecting 2560 events.
[20:01:07.313] <TB1> INFO: 2560 events read in total (300ms).
[20:01:07.314] <TB1> INFO: Test took 1198ms.
[20:01:07.795] <TB1> INFO: PixTestPhOptimization::doTest() done, duration: 734 seconds
[20:01:07.795] <TB1> INFO: PH scale (per ROC): 47 41 47 49 65 60 61 35 54 42 0 40 42 45 52 47
[20:01:07.795] <TB1> INFO: PH offset (per ROC): 139 138 102 135 112 140 124 105 127 106 0 93 107 105 123 114
[20:01:07.806] <TB1> INFO: Decoding statistics:
[20:01:07.806] <TB1> INFO: General information:
[20:01:07.806] <TB1> INFO: 16bit words read: 127890
[20:01:07.806] <TB1> INFO: valid events total: 20480
[20:01:07.806] <TB1> INFO: empty events: 17975
[20:01:07.806] <TB1> INFO: valid events with pixels: 2505
[20:01:07.806] <TB1> INFO: valid pixel hits: 2505
[20:01:07.806] <TB1> INFO: Event errors: 0
[20:01:07.806] <TB1> INFO: start marker: 0
[20:01:07.806] <TB1> INFO: stop marker: 0
[20:01:07.806] <TB1> INFO: overflow: 0
[20:01:07.806] <TB1> INFO: invalid 5bit words: 0
[20:01:07.806] <TB1> INFO: invalid XOR eye diagram: 0
[20:01:07.806] <TB1> INFO: frame (failed synchr.): 0
[20:01:07.806] <TB1> INFO: idle data (no TBM trl): 0
[20:01:07.806] <TB1> INFO: no data (only TBM hdr): 0
[20:01:07.806] <TB1> INFO: TBM errors: 0
[20:01:07.806] <TB1> INFO: flawed TBM headers: 0
[20:01:07.806] <TB1> INFO: flawed TBM trailers: 0
[20:01:07.806] <TB1> INFO: event ID mismatches: 0
[20:01:07.806] <TB1> INFO: ROC errors: 0
[20:01:07.806] <TB1> INFO: missing ROC header(s): 0
[20:01:07.806] <TB1> INFO: misplaced readback start: 0
[20:01:07.806] <TB1> INFO: Pixel decoding errors: 0
[20:01:07.806] <TB1> INFO: pixel data incomplete: 0
[20:01:07.806] <TB1> INFO: pixel address: 0
[20:01:07.806] <TB1> INFO: pulse height fill bit: 0
[20:01:07.806] <TB1> INFO: buffer corruption: 0
[20:01:07.968] <TB1> INFO: ######################################################################
[20:01:07.968] <TB1> INFO: PixTestGainPedestal::fullTest() ntrig = 10
[20:01:07.968] <TB1> INFO: ######################################################################
[20:01:07.983] <TB1> INFO: scanning low vcal = 10
[20:01:08.229] <TB1> INFO: Expecting 41600 events.
[20:01:11.833] <TB1> INFO: 41600 events read in total (3012ms).
[20:01:11.834] <TB1> INFO: Test took 3851ms.
[20:01:11.835] <TB1> INFO: scanning low vcal = 20
[20:01:12.133] <TB1> INFO: Expecting 41600 events.
[20:01:15.760] <TB1> INFO: 41600 events read in total (3036ms).
[20:01:15.761] <TB1> INFO: Test took 3926ms.
[20:01:15.763] <TB1> INFO: scanning low vcal = 30
[20:01:16.055] <TB1> INFO: Expecting 41600 events.
[20:01:19.757] <TB1> INFO: 41600 events read in total (3110ms).
[20:01:19.758] <TB1> INFO: Test took 3995ms.
[20:01:19.761] <TB1> INFO: scanning low vcal = 40
[20:01:20.037] <TB1> INFO: Expecting 41600 events.
[20:01:24.054] <TB1> INFO: 41600 events read in total (3425ms).
[20:01:24.055] <TB1> INFO: Test took 4293ms.
[20:01:24.058] <TB1> INFO: scanning low vcal = 50
[20:01:24.338] <TB1> INFO: Expecting 41600 events.
[20:01:28.423] <TB1> INFO: 41600 events read in total (3494ms).
[20:01:28.424] <TB1> INFO: Test took 4366ms.
[20:01:28.427] <TB1> INFO: scanning low vcal = 60
[20:01:28.704] <TB1> INFO: Expecting 41600 events.
[20:01:32.766] <TB1> INFO: 41600 events read in total (3470ms).
[20:01:32.767] <TB1> INFO: Test took 4340ms.
[20:01:32.770] <TB1> INFO: scanning low vcal = 70
[20:01:33.046] <TB1> INFO: Expecting 41600 events.
[20:01:37.064] <TB1> INFO: 41600 events read in total (3426ms).
[20:01:37.065] <TB1> INFO: Test took 4295ms.
[20:01:37.068] <TB1> INFO: scanning low vcal = 80
[20:01:37.345] <TB1> INFO: Expecting 41600 events.
[20:01:41.348] <TB1> INFO: 41600 events read in total (3411ms).
[20:01:41.349] <TB1> INFO: Test took 4281ms.
[20:01:41.352] <TB1> INFO: scanning low vcal = 90
[20:01:41.629] <TB1> INFO: Expecting 41600 events.
[20:01:45.656] <TB1> INFO: 41600 events read in total (3435ms).
[20:01:45.657] <TB1> INFO: Test took 4305ms.
[20:01:45.661] <TB1> INFO: scanning low vcal = 100
[20:01:45.937] <TB1> INFO: Expecting 41600 events.
[20:01:49.905] <TB1> INFO: 41600 events read in total (3376ms).
[20:01:49.906] <TB1> INFO: Test took 4245ms.
[20:01:49.909] <TB1> INFO: scanning low vcal = 110
[20:01:50.186] <TB1> INFO: Expecting 41600 events.
[20:01:54.143] <TB1> INFO: 41600 events read in total (3366ms).
[20:01:54.144] <TB1> INFO: Test took 4235ms.
[20:01:54.147] <TB1> INFO: scanning low vcal = 120
[20:01:54.423] <TB1> INFO: Expecting 41600 events.
[20:01:58.376] <TB1> INFO: 41600 events read in total (3361ms).
[20:01:58.377] <TB1> INFO: Test took 4230ms.
[20:01:58.380] <TB1> INFO: scanning low vcal = 130
[20:01:58.656] <TB1> INFO: Expecting 41600 events.
[20:02:02.594] <TB1> INFO: 41600 events read in total (3346ms).
[20:02:02.595] <TB1> INFO: Test took 4215ms.
[20:02:02.598] <TB1> INFO: scanning low vcal = 140
[20:02:02.875] <TB1> INFO: Expecting 41600 events.
[20:02:06.814] <TB1> INFO: 41600 events read in total (3348ms).
[20:02:06.815] <TB1> INFO: Test took 4217ms.
[20:02:06.817] <TB1> INFO: scanning low vcal = 150
[20:02:07.094] <TB1> INFO: Expecting 41600 events.
[20:02:11.034] <TB1> INFO: 41600 events read in total (3348ms).
[20:02:11.035] <TB1> INFO: Test took 4218ms.
[20:02:11.039] <TB1> INFO: scanning low vcal = 160
[20:02:11.314] <TB1> INFO: Expecting 41600 events.
[20:02:15.249] <TB1> INFO: 41600 events read in total (3343ms).
[20:02:15.250] <TB1> INFO: Test took 4211ms.
[20:02:15.253] <TB1> INFO: scanning low vcal = 170
[20:02:15.532] <TB1> INFO: Expecting 41600 events.
[20:02:19.490] <TB1> INFO: 41600 events read in total (3367ms).
[20:02:19.491] <TB1> INFO: Test took 4238ms.
[20:02:19.496] <TB1> INFO: scanning low vcal = 180
[20:02:19.770] <TB1> INFO: Expecting 41600 events.
[20:02:23.753] <TB1> INFO: 41600 events read in total (3391ms).
[20:02:23.754] <TB1> INFO: Test took 4257ms.
[20:02:23.757] <TB1> INFO: scanning low vcal = 190
[20:02:24.034] <TB1> INFO: Expecting 41600 events.
[20:02:28.137] <TB1> INFO: 41600 events read in total (3512ms).
[20:02:28.138] <TB1> INFO: Test took 4381ms.
[20:02:28.141] <TB1> INFO: scanning low vcal = 200
[20:02:28.418] <TB1> INFO: Expecting 41600 events.
[20:02:32.471] <TB1> INFO: 41600 events read in total (3462ms).
[20:02:32.472] <TB1> INFO: Test took 4331ms.
[20:02:32.475] <TB1> INFO: scanning low vcal = 210
[20:02:32.751] <TB1> INFO: Expecting 41600 events.
[20:02:36.756] <TB1> INFO: 41600 events read in total (3413ms).
[20:02:36.757] <TB1> INFO: Test took 4282ms.
[20:02:36.759] <TB1> INFO: scanning low vcal = 220
[20:02:37.036] <TB1> INFO: Expecting 41600 events.
[20:02:40.993] <TB1> INFO: 41600 events read in total (3365ms).
[20:02:40.994] <TB1> INFO: Test took 4234ms.
[20:02:40.997] <TB1> INFO: scanning low vcal = 230
[20:02:41.285] <TB1> INFO: Expecting 41600 events.
[20:02:45.273] <TB1> INFO: 41600 events read in total (3397ms).
[20:02:45.273] <TB1> INFO: Test took 4276ms.
[20:02:45.276] <TB1> INFO: scanning low vcal = 240
[20:02:45.552] <TB1> INFO: Expecting 41600 events.
[20:02:49.516] <TB1> INFO: 41600 events read in total (3372ms).
[20:02:49.517] <TB1> INFO: Test took 4241ms.
[20:02:49.520] <TB1> INFO: scanning low vcal = 250
[20:02:49.800] <TB1> INFO: Expecting 41600 events.
[20:02:53.833] <TB1> INFO: 41600 events read in total (3441ms).
[20:02:53.834] <TB1> INFO: Test took 4314ms.
[20:02:53.838] <TB1> INFO: scanning high vcal = 30 (= 210 in low range)
[20:02:54.114] <TB1> INFO: Expecting 41600 events.
[20:02:58.154] <TB1> INFO: 41600 events read in total (3448ms).
[20:02:58.155] <TB1> INFO: Test took 4317ms.
[20:02:58.158] <TB1> INFO: scanning high vcal = 50 (= 350 in low range)
[20:02:58.457] <TB1> INFO: Expecting 41600 events.
[20:03:02.470] <TB1> INFO: 41600 events read in total (3423ms).
[20:03:02.471] <TB1> INFO: Test took 4313ms.
[20:03:02.474] <TB1> INFO: scanning high vcal = 70 (= 490 in low range)
[20:03:02.773] <TB1> INFO: Expecting 41600 events.
[20:03:06.731] <TB1> INFO: 41600 events read in total (3367ms).
[20:03:06.732] <TB1> INFO: Test took 4258ms.
[20:03:06.736] <TB1> INFO: scanning high vcal = 90 (= 630 in low range)
[20:03:07.012] <TB1> INFO: Expecting 41600 events.
[20:03:11.023] <TB1> INFO: 41600 events read in total (3420ms).
[20:03:11.023] <TB1> INFO: Test took 4287ms.
[20:03:11.026] <TB1> INFO: scanning high vcal = 200 (= 1400 in low range)
[20:03:11.303] <TB1> INFO: Expecting 41600 events.
[20:03:15.323] <TB1> INFO: 41600 events read in total (3428ms).
[20:03:15.324] <TB1> INFO: Test took 4298ms.
[20:03:15.858] <TB1> INFO: PixTestGainPedestal::measure() done
[20:03:49.454] <TB1> INFO: PixTestGainPedestal::fit() done
[20:03:49.454] <TB1> INFO: non-linearity mean: 0.966 0.963 0.940 0.973 0.985 0.980 0.978 1.014 0.975 0.909 0.000 0.927 0.990 0.941 0.971 0.951
[20:03:49.454] <TB1> INFO: non-linearity RMS: 0.009 0.008 0.174 0.005 0.003 0.003 0.003 0.175 0.003 0.127 0.000 0.184 0.226 0.037 0.004 0.018
[20:03:49.454] <TB1> INFO: write gain/ped parameters into /home/l_tester/david/FullQualification/data/M1005_FullQualification_2016-10-16_17h47m_1476632865//001_Fulltest_m20//phCalibrationFitErr35_C0.dat
[20:03:49.476] <TB1> INFO: write gain/ped parameters into /home/l_tester/david/FullQualification/data/M1005_FullQualification_2016-10-16_17h47m_1476632865//001_Fulltest_m20//phCalibrationFitErr35_C1.dat
[20:03:49.498] <TB1> INFO: write gain/ped parameters into /home/l_tester/david/FullQualification/data/M1005_FullQualification_2016-10-16_17h47m_1476632865//001_Fulltest_m20//phCalibrationFitErr35_C2.dat
[20:03:49.521] <TB1> INFO: write gain/ped parameters into /home/l_tester/david/FullQualification/data/M1005_FullQualification_2016-10-16_17h47m_1476632865//001_Fulltest_m20//phCalibrationFitErr35_C3.dat
[20:03:49.543] <TB1> INFO: write gain/ped parameters into /home/l_tester/david/FullQualification/data/M1005_FullQualification_2016-10-16_17h47m_1476632865//001_Fulltest_m20//phCalibrationFitErr35_C4.dat
[20:03:49.566] <TB1> INFO: write gain/ped parameters into /home/l_tester/david/FullQualification/data/M1005_FullQualification_2016-10-16_17h47m_1476632865//001_Fulltest_m20//phCalibrationFitErr35_C5.dat
[20:03:49.589] <TB1> INFO: write gain/ped parameters into /home/l_tester/david/FullQualification/data/M1005_FullQualification_2016-10-16_17h47m_1476632865//001_Fulltest_m20//phCalibrationFitErr35_C6.dat
[20:03:49.611] <TB1> INFO: write gain/ped parameters into /home/l_tester/david/FullQualification/data/M1005_FullQualification_2016-10-16_17h47m_1476632865//001_Fulltest_m20//phCalibrationFitErr35_C7.dat
[20:03:49.633] <TB1> INFO: write gain/ped parameters into /home/l_tester/david/FullQualification/data/M1005_FullQualification_2016-10-16_17h47m_1476632865//001_Fulltest_m20//phCalibrationFitErr35_C8.dat
[20:03:49.656] <TB1> INFO: write gain/ped parameters into /home/l_tester/david/FullQualification/data/M1005_FullQualification_2016-10-16_17h47m_1476632865//001_Fulltest_m20//phCalibrationFitErr35_C9.dat
[20:03:49.679] <TB1> INFO: write gain/ped parameters into /home/l_tester/david/FullQualification/data/M1005_FullQualification_2016-10-16_17h47m_1476632865//001_Fulltest_m20//phCalibrationFitErr35_C10.dat
[20:03:49.696] <TB1> INFO: write gain/ped parameters into /home/l_tester/david/FullQualification/data/M1005_FullQualification_2016-10-16_17h47m_1476632865//001_Fulltest_m20//phCalibrationFitErr35_C11.dat
[20:03:49.718] <TB1> INFO: write gain/ped parameters into /home/l_tester/david/FullQualification/data/M1005_FullQualification_2016-10-16_17h47m_1476632865//001_Fulltest_m20//phCalibrationFitErr35_C12.dat
[20:03:49.741] <TB1> INFO: write gain/ped parameters into /home/l_tester/david/FullQualification/data/M1005_FullQualification_2016-10-16_17h47m_1476632865//001_Fulltest_m20//phCalibrationFitErr35_C13.dat
[20:03:49.764] <TB1> INFO: write gain/ped parameters into /home/l_tester/david/FullQualification/data/M1005_FullQualification_2016-10-16_17h47m_1476632865//001_Fulltest_m20//phCalibrationFitErr35_C14.dat
[20:03:49.786] <TB1> INFO: write gain/ped parameters into /home/l_tester/david/FullQualification/data/M1005_FullQualification_2016-10-16_17h47m_1476632865//001_Fulltest_m20//phCalibrationFitErr35_C15.dat
[20:03:49.808] <TB1> INFO: PixTestGainPedestal::fullTest() done, duration: 161 seconds
[20:03:49.808] <TB1> INFO: Decoding statistics:
[20:03:49.808] <TB1> INFO: General information:
[20:03:49.808] <TB1> INFO: 16bit words read: 3328000
[20:03:49.808] <TB1> INFO: valid events total: 332800
[20:03:49.808] <TB1> INFO: empty events: 0
[20:03:49.808] <TB1> INFO: valid events with pixels: 332800
[20:03:49.808] <TB1> INFO: valid pixel hits: 665600
[20:03:49.808] <TB1> INFO: Event errors: 0
[20:03:49.808] <TB1> INFO: start marker: 0
[20:03:49.808] <TB1> INFO: stop marker: 0
[20:03:49.808] <TB1> INFO: overflow: 0
[20:03:49.808] <TB1> INFO: invalid 5bit words: 0
[20:03:49.808] <TB1> INFO: invalid XOR eye diagram: 0
[20:03:49.808] <TB1> INFO: frame (failed synchr.): 0
[20:03:49.808] <TB1> INFO: idle data (no TBM trl): 0
[20:03:49.808] <TB1> INFO: no data (only TBM hdr): 0
[20:03:49.808] <TB1> INFO: TBM errors: 0
[20:03:49.808] <TB1> INFO: flawed TBM headers: 0
[20:03:49.808] <TB1> INFO: flawed TBM trailers: 0
[20:03:49.808] <TB1> INFO: event ID mismatches: 0
[20:03:49.808] <TB1> INFO: ROC errors: 0
[20:03:49.808] <TB1> INFO: missing ROC header(s): 0
[20:03:49.808] <TB1> INFO: misplaced readback start: 0
[20:03:49.808] <TB1> INFO: Pixel decoding errors: 0
[20:03:49.808] <TB1> INFO: pixel data incomplete: 0
[20:03:49.808] <TB1> INFO: pixel address: 0
[20:03:49.808] <TB1> INFO: pulse height fill bit: 0
[20:03:49.808] <TB1> INFO: buffer corruption: 0
[20:03:49.836] <TB1> INFO: Decoding statistics:
[20:03:49.836] <TB1> INFO: General information:
[20:03:49.836] <TB1> INFO: 16bit words read: 3457426
[20:03:49.836] <TB1> INFO: valid events total: 353536
[20:03:49.836] <TB1> INFO: empty events: 18231
[20:03:49.836] <TB1> INFO: valid events with pixels: 335305
[20:03:49.836] <TB1> INFO: valid pixel hits: 668105
[20:03:49.836] <TB1> INFO: Event errors: 0
[20:03:49.836] <TB1> INFO: start marker: 0
[20:03:49.836] <TB1> INFO: stop marker: 0
[20:03:49.836] <TB1> INFO: overflow: 0
[20:03:49.836] <TB1> INFO: invalid 5bit words: 0
[20:03:49.836] <TB1> INFO: invalid XOR eye diagram: 0
[20:03:49.836] <TB1> INFO: frame (failed synchr.): 0
[20:03:49.836] <TB1> INFO: idle data (no TBM trl): 0
[20:03:49.836] <TB1> INFO: no data (only TBM hdr): 0
[20:03:49.836] <TB1> INFO: TBM errors: 0
[20:03:49.836] <TB1> INFO: flawed TBM headers: 0
[20:03:49.836] <TB1> INFO: flawed TBM trailers: 0
[20:03:49.836] <TB1> INFO: event ID mismatches: 0
[20:03:49.836] <TB1> INFO: ROC errors: 0
[20:03:49.836] <TB1> INFO: missing ROC header(s): 0
[20:03:49.836] <TB1> INFO: misplaced readback start: 0
[20:03:49.836] <TB1> INFO: Pixel decoding errors: 0
[20:03:49.836] <TB1> INFO: pixel data incomplete: 0
[20:03:49.836] <TB1> INFO: pixel address: 0
[20:03:49.836] <TB1> INFO: pulse height fill bit: 0
[20:03:49.836] <TB1> INFO: buffer corruption: 0
[20:03:49.836] <TB1> INFO: enter test to run
[20:03:49.836] <TB1> INFO: test: exit no parameter change
[20:03:50.072] <TB1> QUIET: Connection to board 154 closed.
[20:03:50.073] <TB1> INFO: pXar: this is the end, my friend