[20:43:46.608] <TB1> INFO: *** Welcome to pxar ***
[20:43:46.608] <TB1> INFO: *** Today: 2016/10/19
[20:43:46.614] <TB1> INFO: *** Version: c8ba-dirty
[20:43:46.614] <TB1> INFO: readRocDacs: /home/l_tester/david/FullQualification/data/M1025_FullQualification_2016-10-19_19h52m_1476899523//001_Fulltest_m20//dacParameters_C0.dat .. /home/l_tester/david/FullQualification/data/M1025_FullQualification_2016-10-19_19h52m_1476899523//001_Fulltest_m20//dacParameters_C15.dat
[20:43:46.615] <TB1> INFO: readTbmDacs: /home/l_tester/david/FullQualification/data/M1025_FullQualification_2016-10-19_19h52m_1476899523//001_Fulltest_m20//tbmParameters_C0a.dat .. /home/l_tester/david/FullQualification/data/M1025_FullQualification_2016-10-19_19h52m_1476899523//001_Fulltest_m20//tbmParameters_C1b.dat
[20:43:46.616] <TB1> INFO: readMaskFile: /home/l_tester/david/FullQualification/data/M1025_FullQualification_2016-10-19_19h52m_1476899523//001_Fulltest_m20//defaultMaskFile.dat
[20:43:46.616] <TB1> INFO: readTrimFile: /home/l_tester/david/FullQualification/data/M1025_FullQualification_2016-10-19_19h52m_1476899523//001_Fulltest_m20//trimParameters_C0.dat .. /home/l_tester/david/FullQualification/data/M1025_FullQualification_2016-10-19_19h52m_1476899523//001_Fulltest_m20//trimParameters_C15.dat
[20:43:46.723] <TB1> INFO: clk: 4
[20:43:46.723] <TB1> INFO: ctr: 4
[20:43:46.723] <TB1> INFO: sda: 19
[20:43:46.723] <TB1> INFO: tin: 9
[20:43:46.723] <TB1> INFO: level: 15
[20:43:46.723] <TB1> INFO: triggerdelay: 0
[20:43:46.723] <TB1> QUIET: Instanciating API for pxar v2.7.6+55~gafdbfd9
[20:43:46.723] <TB1> INFO: Log level: INFO
[20:43:46.733] <TB1> INFO: Found DTB DTB_WXC03A
[20:43:46.744] <TB1> QUIET: Connection to board DTB_WXC03A opened.
[20:43:46.746] <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:
------------------------------------------------------
[20:43:46.748] <TB1> INFO: RPC call hashes of host and DTB match: 486171790
[20:43:48.307] <TB1> INFO: DUT info:
[20:43:48.307] <TB1> INFO: The DUT currently contains the following objects:
[20:43:48.307] <TB1> INFO: 4 TBM Cores tbm10c (4 ON)
[20:43:48.307] <TB1> INFO: TBM Core alpha (0): 7 registers set
[20:43:48.307] <TB1> INFO: TBM Core beta (1): 7 registers set
[20:43:48.307] <TB1> INFO: TBM Core alpha (2): 7 registers set
[20:43:48.307] <TB1> INFO: TBM Core beta (3): 7 registers set
[20:43:48.307] <TB1> INFO: 16 ROCs proc600 (16 ON) with 4160 pixelConfigs
[20:43:48.307] <TB1> INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[20:43:48.307] <TB1> INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[20:43:48.307] <TB1> INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[20:43:48.307] <TB1> INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[20:43:48.307] <TB1> INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[20:43:48.307] <TB1> INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[20:43:48.307] <TB1> INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[20:43:48.307] <TB1> INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[20:43:48.307] <TB1> INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[20:43:48.307] <TB1> INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[20:43:48.307] <TB1> INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[20:43:48.308] <TB1> INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[20:43:48.308] <TB1> INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[20:43:48.308] <TB1> INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[20:43:48.308] <TB1> INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[20:43:48.308] <TB1> INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[20:43:48.708] <TB1> INFO: enter 'restricted' command line mode
[20:43:48.708] <TB1> INFO: enter test to run
[20:43:48.709] <TB1> INFO: test: pretest no parameter change
[20:43:48.709] <TB1> INFO: running: pretest
[20:43:48.713] <TB1> INFO: ######################################################################
[20:43:48.713] <TB1> INFO: PixTestPretest::doTest()
[20:43:48.713] <TB1> INFO: ######################################################################
[20:43:48.714] <TB1> INFO: ----------------------------------------------------------------------
[20:43:48.714] <TB1> INFO: PixTestPretest::programROC()
[20:43:48.714] <TB1> INFO: ----------------------------------------------------------------------
[20:44:06.728] <TB1> INFO: PixTestPretest::programROC() done: ROCs are all programmable
[20:44:06.728] <TB1> INFO: IA differences per ROC: 18.5 20.1 18.5 20.9 22.5 19.3 20.1 20.1 20.1 20.1 18.5 19.3 20.1 18.5 18.5 17.7
[20:44:06.792] <TB1> INFO: ----------------------------------------------------------------------
[20:44:06.792] <TB1> INFO: PixTestPretest::setVana() target Ia = 24 mA/ROC
[20:44:06.792] <TB1> INFO: ----------------------------------------------------------------------
[20:44:28.087] <TB1> INFO: PixTestPretest::setVana() done, Module Ia 397.1 mA = 24.8188 mA/ROC
[20:44:28.087] <TB1> INFO: i(loss) [mA/ROC]: 20.1 20.1 20.9 20.9 20.1 20.1 19.3 20.1 19.3 20.9 19.3 20.1 20.1 20.9 20.9 20.1
[20:44:28.123] <TB1> INFO: ----------------------------------------------------------------------
[20:44:28.123] <TB1> INFO: PixTestPretest::findTiming()
[20:44:28.123] <TB1> INFO: ----------------------------------------------------------------------
[20:44:28.123] <TB1> INFO: PixTestCmd::init()

[20:44:59.587] <TB1> INFO: TBM phases: 160MHz: 4, 400MHz: 6, TBM delays: ROC(0/1):3, header/trailer: 1, token: 0
[20:44:59.587] <TB1> INFO: (success/tries = 100/100), width = 5
[20:45:01.100] <TB1> INFO: ----------------------------------------------------------------------
[20:45:01.101] <TB1> INFO: PixTestPretest::findWorkingPixel()
[20:45:01.101] <TB1> INFO: ----------------------------------------------------------------------
[20:45:01.195] <TB1> INFO: Expecting 231680 events.
[20:45:11.141] <TB1> INFO: 231680 events read in total (9354ms).
[20:45:11.150] <TB1> INFO: Test took 10044ms.
[20:45:11.391] <TB1> INFO: Found working pixel in all ROCs: col/row = 12/22
[20:45:11.426] <TB1> INFO: ----------------------------------------------------------------------
[20:45:11.426] <TB1> INFO: PixTestPretest::setVthrCompCalDel()
[20:45:11.426] <TB1> INFO: ----------------------------------------------------------------------
[20:45:11.520] <TB1> INFO: Expecting 231680 events.
[20:45:21.441] <TB1> INFO: 231680 events read in total (9329ms).
[20:45:21.449] <TB1> INFO: Test took 10017ms.
[20:45:21.699] <TB1> INFO: PixTestPretest::setVthrCompCalDel() done
[20:45:21.699] <TB1> INFO: CalDel: 107 115 107 103 125 127 115 100 119 116 110 92 109 104 99 116
[20:45:21.699] <TB1> INFO: VthrComp: 51 51 51 51 51 51 51 51 51 51 51 51 51 51 51 51
[20:45:21.702] <TB1> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1025_FullQualification_2016-10-19_19h52m_1476899523//001_Fulltest_m20//dacParameters_C0.dat
[20:45:21.702] <TB1> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1025_FullQualification_2016-10-19_19h52m_1476899523//001_Fulltest_m20//dacParameters_C1.dat
[20:45:21.703] <TB1> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1025_FullQualification_2016-10-19_19h52m_1476899523//001_Fulltest_m20//dacParameters_C2.dat
[20:45:21.703] <TB1> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1025_FullQualification_2016-10-19_19h52m_1476899523//001_Fulltest_m20//dacParameters_C3.dat
[20:45:21.703] <TB1> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1025_FullQualification_2016-10-19_19h52m_1476899523//001_Fulltest_m20//dacParameters_C4.dat
[20:45:21.703] <TB1> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1025_FullQualification_2016-10-19_19h52m_1476899523//001_Fulltest_m20//dacParameters_C5.dat
[20:45:21.703] <TB1> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1025_FullQualification_2016-10-19_19h52m_1476899523//001_Fulltest_m20//dacParameters_C6.dat
[20:45:21.703] <TB1> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1025_FullQualification_2016-10-19_19h52m_1476899523//001_Fulltest_m20//dacParameters_C7.dat
[20:45:21.704] <TB1> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1025_FullQualification_2016-10-19_19h52m_1476899523//001_Fulltest_m20//dacParameters_C8.dat
[20:45:21.704] <TB1> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1025_FullQualification_2016-10-19_19h52m_1476899523//001_Fulltest_m20//dacParameters_C9.dat
[20:45:21.704] <TB1> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1025_FullQualification_2016-10-19_19h52m_1476899523//001_Fulltest_m20//dacParameters_C10.dat
[20:45:21.704] <TB1> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1025_FullQualification_2016-10-19_19h52m_1476899523//001_Fulltest_m20//dacParameters_C11.dat
[20:45:21.704] <TB1> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1025_FullQualification_2016-10-19_19h52m_1476899523//001_Fulltest_m20//dacParameters_C12.dat
[20:45:21.704] <TB1> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1025_FullQualification_2016-10-19_19h52m_1476899523//001_Fulltest_m20//dacParameters_C13.dat
[20:45:21.704] <TB1> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1025_FullQualification_2016-10-19_19h52m_1476899523//001_Fulltest_m20//dacParameters_C14.dat
[20:45:21.704] <TB1> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1025_FullQualification_2016-10-19_19h52m_1476899523//001_Fulltest_m20//dacParameters_C15.dat
[20:45:21.704] <TB1> INFO: write tbm parameters into /home/l_tester/david/FullQualification/data/M1025_FullQualification_2016-10-19_19h52m_1476899523//001_Fulltest_m20//tbmParameters_C0a.dat
[20:45:21.704] <TB1> INFO: write tbm parameters into /home/l_tester/david/FullQualification/data/M1025_FullQualification_2016-10-19_19h52m_1476899523//001_Fulltest_m20//tbmParameters_C0b.dat
[20:45:21.704] <TB1> INFO: write tbm parameters into /home/l_tester/david/FullQualification/data/M1025_FullQualification_2016-10-19_19h52m_1476899523//001_Fulltest_m20//tbmParameters_C1a.dat
[20:45:21.704] <TB1> INFO: write tbm parameters into /home/l_tester/david/FullQualification/data/M1025_FullQualification_2016-10-19_19h52m_1476899523//001_Fulltest_m20//tbmParameters_C1b.dat
[20:45:21.704] <TB1> INFO: PixTestPretest::doTest() done, duration: 92 seconds
[20:45:21.759] <TB1> INFO: enter test to run
[20:45:21.759] <TB1> INFO: test: FullTest no parameter change
[20:45:21.759] <TB1> INFO: running: fulltest
[20:45:21.759] <TB1> INFO: ######################################################################
[20:45:21.759] <TB1> INFO: PixTestFullTest::doTest()
[20:45:21.759] <TB1> INFO: ######################################################################
[20:45:21.760] <TB1> INFO: ######################################################################
[20:45:21.760] <TB1> INFO: PixTestAlive::doTest()
[20:45:21.760] <TB1> INFO: ######################################################################
[20:45:21.761] <TB1> INFO: ----------------------------------------------------------------------
[20:45:21.761] <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)
[20:45:21.761] <TB1> INFO: ----------------------------------------------------------------------
[20:45:22.044] <TB1> INFO: Expecting 41600 events.
[20:45:25.616] <TB1> INFO: 41600 events read in total (2981ms).
[20:45:25.617] <TB1> INFO: Test took 3854ms.
[20:45:25.847] <TB1> INFO: PixTestAlive::aliveTest() done
[20:45:25.848] <TB1> INFO: number of dead pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[20:45:25.849] <TB1> INFO: ----------------------------------------------------------------------
[20:45:25.849] <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)
[20:45:25.849] <TB1> INFO: ----------------------------------------------------------------------
[20:45:26.092] <TB1> INFO: Expecting 41600 events.
[20:45:29.067] <TB1> INFO: 41600 events read in total (2383ms).
[20:45:29.067] <TB1> INFO: Test took 3216ms.
[20:45:29.068] <TB1> INFO: mask vs. old pixelAlive PixelAlive_C0_V0 .. PixelAlive_C15_V0
[20:45:29.298] <TB1> INFO: PixTestAlive::maskTest() done
[20:45:29.298] <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
[20:45:29.299] <TB1> INFO: ----------------------------------------------------------------------
[20:45:29.299] <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)
[20:45:29.299] <TB1> INFO: ----------------------------------------------------------------------
[20:45:29.544] <TB1> INFO: Expecting 41600 events.
[20:45:33.017] <TB1> INFO: 41600 events read in total (2882ms).
[20:45:33.018] <TB1> INFO: Test took 3718ms.
[20:45:33.255] <TB1> INFO: PixTestAlive::addressDecodingTest() done
[20:45:33.255] <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
[20:45:33.255] <TB1> INFO: PixTestAlive::doTest() done, duration: 11 seconds
[20:45:33.255] <TB1> INFO: Decoding statistics:
[20:45:33.255] <TB1> INFO: General information:
[20:45:33.255] <TB1> INFO: 16bit words read: 0
[20:45:33.255] <TB1> INFO: valid events total: 0
[20:45:33.256] <TB1> INFO: empty events: 0
[20:45:33.256] <TB1> INFO: valid events with pixels: 0
[20:45:33.256] <TB1> INFO: valid pixel hits: 0
[20:45:33.256] <TB1> INFO: Event errors: 0
[20:45:33.256] <TB1> INFO: start marker: 0
[20:45:33.256] <TB1> INFO: stop marker: 0
[20:45:33.256] <TB1> INFO: overflow: 0
[20:45:33.256] <TB1> INFO: invalid 5bit words: 0
[20:45:33.256] <TB1> INFO: invalid XOR eye diagram: 0
[20:45:33.256] <TB1> INFO: frame (failed synchr.): 0
[20:45:33.256] <TB1> INFO: idle data (no TBM trl): 0
[20:45:33.256] <TB1> INFO: no data (only TBM hdr): 0
[20:45:33.256] <TB1> INFO: TBM errors: 0
[20:45:33.256] <TB1> INFO: flawed TBM headers: 0
[20:45:33.256] <TB1> INFO: flawed TBM trailers: 0
[20:45:33.256] <TB1> INFO: event ID mismatches: 0
[20:45:33.256] <TB1> INFO: ROC errors: 0
[20:45:33.256] <TB1> INFO: missing ROC header(s): 0
[20:45:33.256] <TB1> INFO: misplaced readback start: 0
[20:45:33.256] <TB1> INFO: Pixel decoding errors: 0
[20:45:33.256] <TB1> INFO: pixel data incomplete: 0
[20:45:33.256] <TB1> INFO: pixel address: 0
[20:45:33.256] <TB1> INFO: pulse height fill bit: 0
[20:45:33.256] <TB1> INFO: buffer corruption: 0
[20:45:33.262] <TB1> INFO: readReadbackCal: /home/l_tester/david/FullQualification/data/M1025_FullQualification_2016-10-19_19h52m_1476899523//001_Fulltest_m20//readbackCal_C0.dat .. /home/l_tester/david/FullQualification/data/M1025_FullQualification_2016-10-19_19h52m_1476899523//001_Fulltest_m20//readbackCal_C15.dat
[20:45:33.262] <TB1> INFO: readGainPedestalParameters /home/l_tester/david/FullQualification/data/M1025_FullQualification_2016-10-19_19h52m_1476899523//001_Fulltest_m20//phCalibrationFitErr_C0.dat .. /home/l_tester/david/FullQualification/data/M1025_FullQualification_2016-10-19_19h52m_1476899523//001_Fulltest_m20//phCalibrationFitErr_C15.dat

[20:45:33.262] <TB1> INFO: ######################################################################
[20:45:33.262] <TB1> INFO: PixTestReadback::doTest()
[20:45:33.262] <TB1> INFO: ######################################################################
[20:45:33.262] <TB1> INFO: ----------------------------------------------------------------------
[20:45:33.262] <TB1> INFO: PixTestReadback::CalibrateVd()
[20:45:33.262] <TB1> INFO: ----------------------------------------------------------------------
[20:45:43.222] <TB1> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1025_FullQualification_2016-10-19_19h52m_1476899523//001_Fulltest_m20//readbackCal_C0.dat
[20:45:43.222] <TB1> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1025_FullQualification_2016-10-19_19h52m_1476899523//001_Fulltest_m20//readbackCal_C1.dat
[20:45:43.222] <TB1> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1025_FullQualification_2016-10-19_19h52m_1476899523//001_Fulltest_m20//readbackCal_C2.dat
[20:45:43.222] <TB1> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1025_FullQualification_2016-10-19_19h52m_1476899523//001_Fulltest_m20//readbackCal_C3.dat
[20:45:43.222] <TB1> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1025_FullQualification_2016-10-19_19h52m_1476899523//001_Fulltest_m20//readbackCal_C4.dat
[20:45:43.222] <TB1> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1025_FullQualification_2016-10-19_19h52m_1476899523//001_Fulltest_m20//readbackCal_C5.dat
[20:45:43.222] <TB1> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1025_FullQualification_2016-10-19_19h52m_1476899523//001_Fulltest_m20//readbackCal_C6.dat
[20:45:43.222] <TB1> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1025_FullQualification_2016-10-19_19h52m_1476899523//001_Fulltest_m20//readbackCal_C7.dat
[20:45:43.222] <TB1> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1025_FullQualification_2016-10-19_19h52m_1476899523//001_Fulltest_m20//readbackCal_C8.dat
[20:45:43.222] <TB1> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1025_FullQualification_2016-10-19_19h52m_1476899523//001_Fulltest_m20//readbackCal_C9.dat
[20:45:43.222] <TB1> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1025_FullQualification_2016-10-19_19h52m_1476899523//001_Fulltest_m20//readbackCal_C10.dat
[20:45:43.222] <TB1> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1025_FullQualification_2016-10-19_19h52m_1476899523//001_Fulltest_m20//readbackCal_C11.dat
[20:45:43.222] <TB1> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1025_FullQualification_2016-10-19_19h52m_1476899523//001_Fulltest_m20//readbackCal_C12.dat
[20:45:43.222] <TB1> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1025_FullQualification_2016-10-19_19h52m_1476899523//001_Fulltest_m20//readbackCal_C13.dat
[20:45:43.223] <TB1> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1025_FullQualification_2016-10-19_19h52m_1476899523//001_Fulltest_m20//readbackCal_C14.dat
[20:45:43.223] <TB1> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1025_FullQualification_2016-10-19_19h52m_1476899523//001_Fulltest_m20//readbackCal_C15.dat
[20:45:43.250] <TB1> INFO: PixTestPattern:: pg_setup set to default.
[20:45:43.250] <TB1> INFO: ----------------------------------------------------------------------
[20:45:43.250] <TB1> INFO: PixTestReadback::CalibrateVa()
[20:45:43.250] <TB1> INFO: ----------------------------------------------------------------------
[20:45:53.181] <TB1> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1025_FullQualification_2016-10-19_19h52m_1476899523//001_Fulltest_m20//readbackCal_C0.dat
[20:45:53.181] <TB1> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1025_FullQualification_2016-10-19_19h52m_1476899523//001_Fulltest_m20//readbackCal_C1.dat
[20:45:53.181] <TB1> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1025_FullQualification_2016-10-19_19h52m_1476899523//001_Fulltest_m20//readbackCal_C2.dat
[20:45:53.181] <TB1> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1025_FullQualification_2016-10-19_19h52m_1476899523//001_Fulltest_m20//readbackCal_C3.dat
[20:45:53.181] <TB1> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1025_FullQualification_2016-10-19_19h52m_1476899523//001_Fulltest_m20//readbackCal_C4.dat
[20:45:53.181] <TB1> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1025_FullQualification_2016-10-19_19h52m_1476899523//001_Fulltest_m20//readbackCal_C5.dat
[20:45:53.181] <TB1> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1025_FullQualification_2016-10-19_19h52m_1476899523//001_Fulltest_m20//readbackCal_C6.dat
[20:45:53.182] <TB1> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1025_FullQualification_2016-10-19_19h52m_1476899523//001_Fulltest_m20//readbackCal_C7.dat
[20:45:53.182] <TB1> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1025_FullQualification_2016-10-19_19h52m_1476899523//001_Fulltest_m20//readbackCal_C8.dat
[20:45:53.182] <TB1> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1025_FullQualification_2016-10-19_19h52m_1476899523//001_Fulltest_m20//readbackCal_C9.dat
[20:45:53.182] <TB1> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1025_FullQualification_2016-10-19_19h52m_1476899523//001_Fulltest_m20//readbackCal_C10.dat
[20:45:53.182] <TB1> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1025_FullQualification_2016-10-19_19h52m_1476899523//001_Fulltest_m20//readbackCal_C11.dat
[20:45:53.182] <TB1> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1025_FullQualification_2016-10-19_19h52m_1476899523//001_Fulltest_m20//readbackCal_C12.dat
[20:45:53.182] <TB1> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1025_FullQualification_2016-10-19_19h52m_1476899523//001_Fulltest_m20//readbackCal_C13.dat
[20:45:53.182] <TB1> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1025_FullQualification_2016-10-19_19h52m_1476899523//001_Fulltest_m20//readbackCal_C14.dat
[20:45:53.182] <TB1> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1025_FullQualification_2016-10-19_19h52m_1476899523//001_Fulltest_m20//readbackCal_C15.dat
[20:45:53.211] <TB1> INFO: PixTestPattern:: pg_setup set to default.
[20:45:53.211] <TB1> INFO: ----------------------------------------------------------------------
[20:45:53.211] <TB1> INFO: PixTestReadback::readbackVbg()
[20:45:53.211] <TB1> INFO: ----------------------------------------------------------------------
[20:46:00.885] <TB1> INFO: PixTestPattern:: pg_setup set to default.
[20:46:00.885] <TB1> INFO: ----------------------------------------------------------------------
[20:46:00.885] <TB1> INFO: PixTestReadback::getCalibratedVbg()
[20:46:00.885] <TB1> INFO: ----------------------------------------------------------------------
[20:46:00.885] <TB1> INFO: Vbg will be calibrated using Vd calibration
[20:46:00.885] <TB1> INFO: /*/*/*/*::: ROC 0: uncalibrated Vbg = 156.4calibrated Vbg = 1.18645 :::*/*/*/*/
[20:46:00.885] <TB1> INFO: /*/*/*/*::: ROC 1: uncalibrated Vbg = 144.4calibrated Vbg = 1.18306 :::*/*/*/*/
[20:46:00.885] <TB1> INFO: /*/*/*/*::: ROC 2: uncalibrated Vbg = 158.2calibrated Vbg = 1.18095 :::*/*/*/*/
[20:46:00.885] <TB1> INFO: /*/*/*/*::: ROC 3: uncalibrated Vbg = 140.2calibrated Vbg = 1.17829 :::*/*/*/*/
[20:46:00.885] <TB1> INFO: /*/*/*/*::: ROC 4: uncalibrated Vbg = 148.9calibrated Vbg = 1.17466 :::*/*/*/*/
[20:46:00.885] <TB1> INFO: /*/*/*/*::: ROC 5: uncalibrated Vbg = 158.7calibrated Vbg = 1.17861 :::*/*/*/*/
[20:46:00.885] <TB1> INFO: /*/*/*/*::: ROC 6: uncalibrated Vbg = 154.3calibrated Vbg = 1.18616 :::*/*/*/*/
[20:46:00.885] <TB1> INFO: /*/*/*/*::: ROC 7: uncalibrated Vbg = 149calibrated Vbg = 1.18135 :::*/*/*/*/
[20:46:00.885] <TB1> INFO: /*/*/*/*::: ROC 8: uncalibrated Vbg = 162calibrated Vbg = 1.18201 :::*/*/*/*/
[20:46:00.885] <TB1> INFO: /*/*/*/*::: ROC 9: uncalibrated Vbg = 147.9calibrated Vbg = 1.17846 :::*/*/*/*/
[20:46:00.885] <TB1> INFO: /*/*/*/*::: ROC 10: uncalibrated Vbg = 149.2calibrated Vbg = 1.17103 :::*/*/*/*/
[20:46:00.885] <TB1> INFO: /*/*/*/*::: ROC 11: uncalibrated Vbg = 153.9calibrated Vbg = 1.17073 :::*/*/*/*/
[20:46:00.885] <TB1> INFO: /*/*/*/*::: ROC 12: uncalibrated Vbg = 155calibrated Vbg = 1.1784 :::*/*/*/*/
[20:46:00.885] <TB1> INFO: /*/*/*/*::: ROC 13: uncalibrated Vbg = 156.3calibrated Vbg = 1.17952 :::*/*/*/*/
[20:46:00.885] <TB1> INFO: /*/*/*/*::: ROC 14: uncalibrated Vbg = 155.5calibrated Vbg = 1.18079 :::*/*/*/*/
[20:46:00.885] <TB1> INFO: /*/*/*/*::: ROC 15: uncalibrated Vbg = 158.1calibrated Vbg = 1.18926 :::*/*/*/*/
[20:46:00.888] <TB1> INFO: ----------------------------------------------------------------------
[20:46:00.888] <TB1> INFO: PixTestReadback::CalibrateIa()
[20:46:00.888] <TB1> INFO: ----------------------------------------------------------------------
[20:48:41.687] <TB1> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1025_FullQualification_2016-10-19_19h52m_1476899523//001_Fulltest_m20//readbackCal_C0.dat
[20:48:41.688] <TB1> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1025_FullQualification_2016-10-19_19h52m_1476899523//001_Fulltest_m20//readbackCal_C1.dat
[20:48:41.688] <TB1> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1025_FullQualification_2016-10-19_19h52m_1476899523//001_Fulltest_m20//readbackCal_C2.dat
[20:48:41.688] <TB1> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1025_FullQualification_2016-10-19_19h52m_1476899523//001_Fulltest_m20//readbackCal_C3.dat
[20:48:41.688] <TB1> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1025_FullQualification_2016-10-19_19h52m_1476899523//001_Fulltest_m20//readbackCal_C4.dat
[20:48:41.688] <TB1> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1025_FullQualification_2016-10-19_19h52m_1476899523//001_Fulltest_m20//readbackCal_C5.dat
[20:48:41.688] <TB1> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1025_FullQualification_2016-10-19_19h52m_1476899523//001_Fulltest_m20//readbackCal_C6.dat
[20:48:41.688] <TB1> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1025_FullQualification_2016-10-19_19h52m_1476899523//001_Fulltest_m20//readbackCal_C7.dat
[20:48:41.688] <TB1> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1025_FullQualification_2016-10-19_19h52m_1476899523//001_Fulltest_m20//readbackCal_C8.dat
[20:48:41.688] <TB1> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1025_FullQualification_2016-10-19_19h52m_1476899523//001_Fulltest_m20//readbackCal_C9.dat
[20:48:41.688] <TB1> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1025_FullQualification_2016-10-19_19h52m_1476899523//001_Fulltest_m20//readbackCal_C10.dat
[20:48:41.688] <TB1> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1025_FullQualification_2016-10-19_19h52m_1476899523//001_Fulltest_m20//readbackCal_C11.dat
[20:48:41.688] <TB1> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1025_FullQualification_2016-10-19_19h52m_1476899523//001_Fulltest_m20//readbackCal_C12.dat
[20:48:41.689] <TB1> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1025_FullQualification_2016-10-19_19h52m_1476899523//001_Fulltest_m20//readbackCal_C13.dat
[20:48:41.689] <TB1> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1025_FullQualification_2016-10-19_19h52m_1476899523//001_Fulltest_m20//readbackCal_C14.dat
[20:48:41.689] <TB1> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1025_FullQualification_2016-10-19_19h52m_1476899523//001_Fulltest_m20//readbackCal_C15.dat
[20:48:41.720] <TB1> INFO: PixTestPattern:: pg_setup set to default.
[20:48:41.721] <TB1> INFO: PixTestReadback::doTest() done
[20:48:41.721] <TB1> INFO: Decoding statistics:
[20:48:41.721] <TB1> INFO: General information:
[20:48:41.721] <TB1> INFO: 16bit words read: 1536
[20:48:41.721] <TB1> INFO: valid events total: 256
[20:48:41.721] <TB1> INFO: empty events: 256
[20:48:41.721] <TB1> INFO: valid events with pixels: 0
[20:48:41.721] <TB1> INFO: valid pixel hits: 0
[20:48:41.721] <TB1> INFO: Event errors: 0
[20:48:41.721] <TB1> INFO: start marker: 0
[20:48:41.721] <TB1> INFO: stop marker: 0
[20:48:41.721] <TB1> INFO: overflow: 0
[20:48:41.722] <TB1> INFO: invalid 5bit words: 0
[20:48:41.722] <TB1> INFO: invalid XOR eye diagram: 0
[20:48:41.722] <TB1> INFO: frame (failed synchr.): 0
[20:48:41.722] <TB1> INFO: idle data (no TBM trl): 0
[20:48:41.722] <TB1> INFO: no data (only TBM hdr): 0
[20:48:41.722] <TB1> INFO: TBM errors: 0
[20:48:41.722] <TB1> INFO: flawed TBM headers: 0
[20:48:41.722] <TB1> INFO: flawed TBM trailers: 0
[20:48:41.722] <TB1> INFO: event ID mismatches: 0
[20:48:41.722] <TB1> INFO: ROC errors: 0
[20:48:41.722] <TB1> INFO: missing ROC header(s): 0
[20:48:41.722] <TB1> INFO: misplaced readback start: 0
[20:48:41.722] <TB1> INFO: Pixel decoding errors: 0
[20:48:41.722] <TB1> INFO: pixel data incomplete: 0
[20:48:41.722] <TB1> INFO: pixel address: 0
[20:48:41.722] <TB1> INFO: pulse height fill bit: 0
[20:48:41.722] <TB1> INFO: buffer corruption: 0
[20:48:41.768] <TB1> INFO: ######################################################################
[20:48:41.768] <TB1> INFO: PixTestBBMap::doTest() Ntrig = 5, VcalS = 250 (high range)
[20:48:41.768] <TB1> INFO: ######################################################################
[20:48:41.770] <TB1> INFO: ---> dac: VthrComp name: calSMap ntrig: 5 dacrange: 0 .. 149 (-1/-1) hits flags = 514 (plus default)
[20:48:41.783] <TB1> INFO: dacScan split into 1 runs with ntrig = 5
[20:48:41.783] <TB1> INFO: run 1 of 1
[20:48:42.019] <TB1> INFO: Expecting 3120000 events.
[20:49:13.161] <TB1> INFO: 671250 events read in total (30550ms).











[20:49:43.841] <TB1> INFO: 1350555 events read in total (61230ms).
[20:50:14.656] <TB1> INFO: 2029420 events read in total (92046ms).











[20:50:45.190] <TB1> INFO: 2707355 events read in total (122579ms).
[20:51:03.474] <TB1> INFO: 3120000 events read in total (140863ms).
[20:51:03.557] <TB1> INFO: Test took 141775ms.
[20:51:30.221] <TB1> INFO: PixTestBBMap::doTest() done, duration: 168 seconds
[20:51:30.221] <TB1> INFO: number of dead bumps (per ROC): 3 2 1 0 3 1 0 0 1 4 0 0 1 1 0 0
[20:51:30.221] <TB1> INFO: separation cut (per ROC): 104 113 110 105 111 107 104 120 105 117 106 108 101 104 125 106
[20:51:30.221] <TB1> INFO: Decoding statistics:
[20:51:30.221] <TB1> INFO: General information:
[20:51:30.221] <TB1> INFO: 16bit words read: 0
[20:51:30.222] <TB1> INFO: valid events total: 0
[20:51:30.222] <TB1> INFO: empty events: 0
[20:51:30.222] <TB1> INFO: valid events with pixels: 0
[20:51:30.222] <TB1> INFO: valid pixel hits: 0
[20:51:30.222] <TB1> INFO: Event errors: 0
[20:51:30.222] <TB1> INFO: start marker: 0
[20:51:30.222] <TB1> INFO: stop marker: 0
[20:51:30.222] <TB1> INFO: overflow: 0
[20:51:30.222] <TB1> INFO: invalid 5bit words: 0
[20:51:30.222] <TB1> INFO: invalid XOR eye diagram: 0
[20:51:30.222] <TB1> INFO: frame (failed synchr.): 0
[20:51:30.222] <TB1> INFO: idle data (no TBM trl): 0
[20:51:30.222] <TB1> INFO: no data (only TBM hdr): 0
[20:51:30.222] <TB1> INFO: TBM errors: 0
[20:51:30.222] <TB1> INFO: flawed TBM headers: 0
[20:51:30.222] <TB1> INFO: flawed TBM trailers: 0
[20:51:30.222] <TB1> INFO: event ID mismatches: 0
[20:51:30.222] <TB1> INFO: ROC errors: 0
[20:51:30.222] <TB1> INFO: missing ROC header(s): 0
[20:51:30.222] <TB1> INFO: misplaced readback start: 0
[20:51:30.222] <TB1> INFO: Pixel decoding errors: 0
[20:51:30.222] <TB1> INFO: pixel data incomplete: 0
[20:51:30.222] <TB1> INFO: pixel address: 0
[20:51:30.222] <TB1> INFO: pulse height fill bit: 0
[20:51:30.222] <TB1> INFO: buffer corruption: 0
[20:51:30.269] <TB1> INFO: ######################################################################
[20:51:30.269] <TB1> INFO: PixTestScurves::fullTest() ntrig = 50, dacs/step = -1, ntrig/step = -1
[20:51:30.269] <TB1> INFO: ######################################################################
[20:51:30.269] <TB1> INFO: ----------------------------------------------------------------------
[20:51:30.269] <TB1> INFO: PixTestScurves::scurves(Vcal), ntrig = 50, dacs/step = -1, ntrig/step = -1
[20:51:30.269] <TB1> INFO: ----------------------------------------------------------------------
[20:51:30.270] <TB1> INFO: ---> dac: Vcal name: scurveVcal ntrig: 50 dacrange: 25 .. 200 (-1/-1) hits flags = 528 (plus default)
[20:51:30.283] <TB1> INFO: dacScan split into 1 runs with ntrig = 50
[20:51:30.283] <TB1> INFO: run 1 of 1
[20:51:30.522] <TB1> INFO: Expecting 36608000 events.
[20:51:53.988] <TB1> INFO: 670200 events read in total (22875ms).
[20:52:16.676] <TB1> INFO: 1331400 events read in total (45563ms).
[20:52:39.212] <TB1> INFO: 1988950 events read in total (68099ms).
[20:53:02.090] <TB1> INFO: 2647700 events read in total (90977ms).
[20:53:24.715] <TB1> INFO: 3305800 events read in total (113602ms).
[20:53:47.324] <TB1> INFO: 3964850 events read in total (136211ms).
[20:54:10.068] <TB1> INFO: 4619200 events read in total (158955ms).
[20:54:32.899] <TB1> INFO: 5276100 events read in total (181786ms).
[20:54:55.524] <TB1> INFO: 5931400 events read in total (204411ms).
[20:55:18.285] <TB1> INFO: 6588250 events read in total (227172ms).
[20:55:41.207] <TB1> INFO: 7245500 events read in total (250094ms).
[20:56:03.839] <TB1> INFO: 7901800 events read in total (272726ms).
[20:56:26.916] <TB1> INFO: 8559750 events read in total (295803ms).
[20:56:49.671] <TB1> INFO: 9214300 events read in total (318558ms).
[20:57:12.376] <TB1> INFO: 9869950 events read in total (341263ms).
[20:57:35.078] <TB1> INFO: 10523600 events read in total (363965ms).
[20:57:57.570] <TB1> INFO: 11178850 events read in total (386457ms).
[20:58:20.248] <TB1> INFO: 11834900 events read in total (409135ms).
[20:58:42.797] <TB1> INFO: 12492700 events read in total (431684ms).
[20:59:05.588] <TB1> INFO: 13147950 events read in total (454475ms).
[20:59:28.116] <TB1> INFO: 13803850 events read in total (477003ms).
[20:59:50.589] <TB1> INFO: 14458250 events read in total (499476ms).
[21:00:12.998] <TB1> INFO: 15113850 events read in total (521885ms).
[21:00:35.846] <TB1> INFO: 15766650 events read in total (544733ms).
[21:00:58.261] <TB1> INFO: 16419550 events read in total (567148ms).
[21:01:20.980] <TB1> INFO: 17072100 events read in total (589867ms).
[21:01:43.637] <TB1> INFO: 17725150 events read in total (612524ms).
[21:02:06.156] <TB1> INFO: 18377900 events read in total (635043ms).
[21:02:29.066] <TB1> INFO: 19029900 events read in total (657953ms).
[21:02:51.684] <TB1> INFO: 19680350 events read in total (680571ms).
[21:03:14.187] <TB1> INFO: 20329650 events read in total (703074ms).
[21:03:36.429] <TB1> INFO: 20981550 events read in total (725316ms).
[21:03:59.093] <TB1> INFO: 21633400 events read in total (747980ms).
[21:04:21.587] <TB1> INFO: 22284950 events read in total (770474ms).
[21:04:44.023] <TB1> INFO: 22935800 events read in total (792910ms).
[21:05:06.940] <TB1> INFO: 23587850 events read in total (815827ms).
[21:05:29.469] <TB1> INFO: 24237750 events read in total (838356ms).
[21:05:51.920] <TB1> INFO: 24887650 events read in total (860807ms).
[21:06:14.378] <TB1> INFO: 25539100 events read in total (883265ms).
[21:06:37.078] <TB1> INFO: 26188600 events read in total (905965ms).
[21:06:59.611] <TB1> INFO: 26838050 events read in total (928498ms).
[21:07:22.167] <TB1> INFO: 27487400 events read in total (951054ms).
[21:07:44.558] <TB1> INFO: 28137700 events read in total (973445ms).
[21:08:07.244] <TB1> INFO: 28785600 events read in total (996131ms).
[21:08:29.864] <TB1> INFO: 29434850 events read in total (1018751ms).
[21:08:52.343] <TB1> INFO: 30083150 events read in total (1041230ms).
[21:09:14.734] <TB1> INFO: 30730400 events read in total (1063621ms).
[21:09:37.467] <TB1> INFO: 31378600 events read in total (1086354ms).
[21:10:00.064] <TB1> INFO: 32028250 events read in total (1108951ms).
[21:10:22.381] <TB1> INFO: 32678700 events read in total (1131268ms).
[21:10:44.870] <TB1> INFO: 33328550 events read in total (1153757ms).
[21:11:07.402] <TB1> INFO: 33980750 events read in total (1176289ms).
[21:11:30.192] <TB1> INFO: 34632950 events read in total (1199079ms).
[21:11:52.714] <TB1> INFO: 35285650 events read in total (1221601ms).
[21:12:15.385] <TB1> INFO: 35937750 events read in total (1244272ms).
[21:12:38.285] <TB1> INFO: 36600350 events read in total (1267172ms).
[21:12:38.940] <TB1> INFO: 36608000 events read in total (1267827ms).
[21:12:39.054] <TB1> INFO: Test took 1268770ms.
[21:12:39.660] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[21:12:41.883] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[21:12:44.317] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[21:12:46.138] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[21:12:47.720] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[21:12:49.231] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[21:12:51.300] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[21:12:53.329] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[21:12:55.525] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[21:12:57.667] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[21:13:00.050] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[21:13:02.272] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[21:13:04.704] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[21:13:06.954] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[21:13:08.604] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[21:13:10.064] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[21:13:11.670] <TB1> INFO: PixTestScurves::scurves() done
[21:13:11.670] <TB1> INFO: Vcal mean: 107.08 115.09 119.18 109.62 112.79 116.22 113.32 114.91 106.22 114.82 111.86 103.12 105.00 108.40 121.51 110.22
[21:13:11.670] <TB1> INFO: Vcal RMS: 4.71 5.58 6.36 4.94 4.80 5.79 5.24 5.17 4.88 7.20 5.74 5.56 5.11 4.52 5.98 4.84
[21:13:11.670] <TB1> INFO: PixTestScurves::fullTest() done, duration: 1301 seconds
[21:13:11.670] <TB1> INFO: Decoding statistics:
[21:13:11.670] <TB1> INFO: General information:
[21:13:11.670] <TB1> INFO: 16bit words read: 0
[21:13:11.670] <TB1> INFO: valid events total: 0
[21:13:11.670] <TB1> INFO: empty events: 0
[21:13:11.670] <TB1> INFO: valid events with pixels: 0
[21:13:11.670] <TB1> INFO: valid pixel hits: 0
[21:13:11.670] <TB1> INFO: Event errors: 0
[21:13:11.670] <TB1> INFO: start marker: 0
[21:13:11.670] <TB1> INFO: stop marker: 0
[21:13:11.670] <TB1> INFO: overflow: 0
[21:13:11.670] <TB1> INFO: invalid 5bit words: 0
[21:13:11.670] <TB1> INFO: invalid XOR eye diagram: 0
[21:13:11.670] <TB1> INFO: frame (failed synchr.): 0
[21:13:11.670] <TB1> INFO: idle data (no TBM trl): 0
[21:13:11.670] <TB1> INFO: no data (only TBM hdr): 0
[21:13:11.670] <TB1> INFO: TBM errors: 0
[21:13:11.670] <TB1> INFO: flawed TBM headers: 0
[21:13:11.670] <TB1> INFO: flawed TBM trailers: 0
[21:13:11.670] <TB1> INFO: event ID mismatches: 0
[21:13:11.670] <TB1> INFO: ROC errors: 0
[21:13:11.670] <TB1> INFO: missing ROC header(s): 0
[21:13:11.670] <TB1> INFO: misplaced readback start: 0
[21:13:11.670] <TB1> INFO: Pixel decoding errors: 0
[21:13:11.670] <TB1> INFO: pixel data incomplete: 0
[21:13:11.670] <TB1> INFO: pixel address: 0
[21:13:11.670] <TB1> INFO: pulse height fill bit: 0
[21:13:11.670] <TB1> INFO: buffer corruption: 0
[21:13:11.736] <TB1> INFO: ######################################################################
[21:13:11.736] <TB1> INFO: PixTestTrim::doTest()
[21:13:11.736] <TB1> INFO: ######################################################################
[21:13:11.737] <TB1> INFO: ----------------------------------------------------------------------
[21:13:11.738] <TB1> INFO: PixTestTrim::trimTest() ntrig = 8, vcal = 35
[21:13:11.738] <TB1> INFO: ----------------------------------------------------------------------
[21:13:11.782] <TB1> INFO: ---> VthrComp thr map (minimal VthrComp)
[21:13:11.782] <TB1> INFO: ---> dac: vthrcomp name: TrimThr0 ntrig: 8 dacrange: 10 .. 160 (-1/-1) hits flags = 528 (plus default)
[21:13:11.795] <TB1> INFO: dacScan split into 1 runs with ntrig = 8
[21:13:11.795] <TB1> INFO: run 1 of 1
[21:13:12.043] <TB1> INFO: Expecting 5025280 events.
[21:13:42.200] <TB1> INFO: 820072 events read in total (29557ms).
[21:14:12.090] <TB1> INFO: 1637224 events read in total (59448ms).
[21:14:41.731] <TB1> INFO: 2452032 events read in total (89088ms).
[21:15:12.132] <TB1> INFO: 3264456 events read in total (119489ms).
[21:15:41.768] <TB1> INFO: 4074800 events read in total (149126ms).
[21:16:11.926] <TB1> INFO: 4884864 events read in total (179283ms).
[21:16:17.807] <TB1> INFO: 5025280 events read in total (185164ms).
[21:16:17.860] <TB1> INFO: Test took 186065ms.
[21:16:40.176] <TB1> INFO: ROC 0 VthrComp = 110
[21:16:40.177] <TB1> INFO: ROC 1 VthrComp = 120
[21:16:40.177] <TB1> INFO: ROC 2 VthrComp = 125
[21:16:40.177] <TB1> INFO: ROC 3 VthrComp = 117
[21:16:40.177] <TB1> INFO: ROC 4 VthrComp = 114
[21:16:40.177] <TB1> INFO: ROC 5 VthrComp = 115
[21:16:40.177] <TB1> INFO: ROC 6 VthrComp = 109
[21:16:40.177] <TB1> INFO: ROC 7 VthrComp = 121
[21:16:40.177] <TB1> INFO: ROC 8 VthrComp = 107
[21:16:40.177] <TB1> INFO: ROC 9 VthrComp = 119
[21:16:40.177] <TB1> INFO: ROC 10 VthrComp = 109
[21:16:40.178] <TB1> INFO: ROC 11 VthrComp = 105
[21:16:40.178] <TB1> INFO: ROC 12 VthrComp = 109
[21:16:40.178] <TB1> INFO: ROC 13 VthrComp = 116
[21:16:40.178] <TB1> INFO: ROC 14 VthrComp = 128
[21:16:40.179] <TB1> INFO: ROC 15 VthrComp = 110
[21:16:40.468] <TB1> INFO: Expecting 41600 events.
[21:16:44.175] <TB1> INFO: 41600 events read in total (3115ms).
[21:16:44.176] <TB1> INFO: Test took 3996ms.
[21:16:44.184] <TB1> INFO: ---> Vcal thr map (pixel with maximum Vcal thr)
[21:16:44.184] <TB1> INFO: ---> dac: vcal name: TrimThr1 ntrig: 8 dacrange: 10 .. 160 (-1/-1) hits flags = 528 (plus default)
[21:16:44.196] <TB1> INFO: dacScan split into 1 runs with ntrig = 8
[21:16:44.196] <TB1> INFO: run 1 of 1
[21:16:44.474] <TB1> INFO: Expecting 5025280 events.
[21:17:11.266] <TB1> INFO: 591656 events read in total (26200ms).
[21:17:37.301] <TB1> INFO: 1182320 events read in total (52235ms).
[21:18:03.160] <TB1> INFO: 1773120 events read in total (78094ms).
[21:18:28.974] <TB1> INFO: 2363048 events read in total (103908ms).
[21:18:54.895] <TB1> INFO: 2950328 events read in total (129829ms).
[21:19:21.188] <TB1> INFO: 3535592 events read in total (156122ms).
[21:19:47.475] <TB1> INFO: 4119200 events read in total (182409ms).
[21:20:13.146] <TB1> INFO: 4702296 events read in total (208080ms).
[21:20:27.171] <TB1> INFO: 5025280 events read in total (222105ms).
[21:20:27.272] <TB1> INFO: Test took 223076ms.
[21:20:53.461] <TB1> INFO: roc 0 with ID = 0 has maximal Vcal 61.4728 for pixel 33/1 mean/min/max = 48.0488/34.5871/61.5106
[21:20:53.461] <TB1> INFO: roc 1 with ID = 1 has maximal Vcal 60.4978 for pixel 19/10 mean/min/max = 46.0236/31.4366/60.6107
[21:20:53.462] <TB1> INFO: roc 2 with ID = 2 has maximal Vcal 60.8425 for pixel 28/50 mean/min/max = 45.5564/30.1606/60.9522
[21:20:53.462] <TB1> INFO: roc 3 with ID = 3 has maximal Vcal 59.0742 for pixel 13/10 mean/min/max = 45.4128/31.6454/59.1803
[21:20:53.462] <TB1> INFO: roc 4 with ID = 4 has maximal Vcal 59.8835 for pixel 45/12 mean/min/max = 46.1995/32.3961/60.0028
[21:20:53.463] <TB1> INFO: roc 5 with ID = 5 has maximal Vcal 60.8066 for pixel 50/60 mean/min/max = 46.3583/31.8485/60.8681
[21:20:53.463] <TB1> INFO: roc 6 with ID = 6 has maximal Vcal 64.3586 for pixel 28/4 mean/min/max = 49.3733/34.332/64.4147
[21:20:53.464] <TB1> INFO: roc 7 with ID = 7 has maximal Vcal 59.0537 for pixel 0/10 mean/min/max = 46.0224/32.8414/59.2034
[21:20:53.464] <TB1> INFO: roc 8 with ID = 8 has maximal Vcal 60.5439 for pixel 1/65 mean/min/max = 47.6443/34.7153/60.5733
[21:20:53.465] <TB1> INFO: roc 9 with ID = 9 has maximal Vcal 60.3169 for pixel 2/4 mean/min/max = 45.2243/30.0401/60.4085
[21:20:53.465] <TB1> INFO: roc 10 with ID = 10 has maximal Vcal 62.9556 for pixel 6/42 mean/min/max = 48.3638/33.5169/63.2107
[21:20:53.465] <TB1> INFO: roc 11 with ID = 11 has maximal Vcal 60.7672 for pixel 13/0 mean/min/max = 47.5732/34.3104/60.8361
[21:20:53.466] <TB1> INFO: roc 12 with ID = 12 has maximal Vcal 60.0391 for pixel 9/0 mean/min/max = 47.3375/34.626/60.049
[21:20:53.466] <TB1> INFO: roc 13 with ID = 13 has maximal Vcal 57.7139 for pixel 47/17 mean/min/max = 45.2139/32.7105/57.7173
[21:20:53.467] <TB1> INFO: roc 14 with ID = 14 has maximal Vcal 59.0558 for pixel 51/29 mean/min/max = 45.2069/31.0962/59.3175
[21:20:53.467] <TB1> INFO: roc 15 with ID = 15 has maximal Vcal 62.9452 for pixel 48/8 mean/min/max = 48.3013/33.4897/63.1129
[21:20:53.468] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[21:20:53.556] <TB1> INFO: Expecting 411648 events.
[21:21:02.951] <TB1> INFO: 411648 events read in total (8803ms).
[21:21:02.960] <TB1> INFO: Expecting 411648 events.
[21:21:12.225] <TB1> INFO: 411648 events read in total (8862ms).
[21:21:12.235] <TB1> INFO: Expecting 411648 events.
[21:21:21.497] <TB1> INFO: 411648 events read in total (8859ms).
[21:21:21.509] <TB1> INFO: Expecting 411648 events.
[21:21:30.792] <TB1> INFO: 411648 events read in total (8879ms).
[21:21:30.808] <TB1> INFO: Expecting 411648 events.
[21:21:40.079] <TB1> INFO: 411648 events read in total (8868ms).
[21:21:40.097] <TB1> INFO: Expecting 411648 events.
[21:21:49.375] <TB1> INFO: 411648 events read in total (8874ms).
[21:21:49.396] <TB1> INFO: Expecting 411648 events.
[21:21:58.769] <TB1> INFO: 411648 events read in total (8970ms).
[21:21:58.793] <TB1> INFO: Expecting 411648 events.
[21:22:08.077] <TB1> INFO: 411648 events read in total (8881ms).
[21:22:08.107] <TB1> INFO: Expecting 411648 events.
[21:22:17.505] <TB1> INFO: 411648 events read in total (8995ms).
[21:22:17.534] <TB1> INFO: Expecting 411648 events.
[21:22:26.793] <TB1> INFO: 411648 events read in total (8856ms).
[21:22:26.825] <TB1> INFO: Expecting 411648 events.
[21:22:36.204] <TB1> INFO: 411648 events read in total (8976ms).
[21:22:36.239] <TB1> INFO: Expecting 411648 events.
[21:22:45.631] <TB1> INFO: 411648 events read in total (8989ms).
[21:22:45.690] <TB1> INFO: Expecting 411648 events.
[21:22:55.099] <TB1> INFO: 411648 events read in total (9006ms).
[21:22:55.150] <TB1> INFO: Expecting 411648 events.
[21:23:04.422] <TB1> INFO: 411648 events read in total (8868ms).
[21:23:04.476] <TB1> INFO: Expecting 411648 events.
[21:23:13.682] <TB1> INFO: 411648 events read in total (8803ms).
[21:23:13.858] <TB1> INFO: Expecting 411648 events.
[21:23:23.106] <TB1> INFO: 411648 events read in total (8845ms).
[21:23:23.162] <TB1> INFO: Test took 149694ms.
[21:23:23.928] <TB1> INFO: ---> dac: vcal name: TrimThr2 ntrig: 8 dacrange: 0 .. 150 (-1/-1) hits flags = 528 (plus default)
[21:23:23.941] <TB1> INFO: dacScan split into 1 runs with ntrig = 8
[21:23:23.941] <TB1> INFO: run 1 of 1
[21:23:24.178] <TB1> INFO: Expecting 5025280 events.
[21:23:50.977] <TB1> INFO: 586896 events read in total (26208ms).
[21:24:16.650] <TB1> INFO: 1172112 events read in total (51881ms).
[21:24:42.642] <TB1> INFO: 1757576 events read in total (77873ms).
[21:25:08.666] <TB1> INFO: 2341816 events read in total (103897ms).
[21:25:34.582] <TB1> INFO: 2926232 events read in total (129813ms).
[21:26:00.658] <TB1> INFO: 3511040 events read in total (155889ms).
[21:26:26.859] <TB1> INFO: 4095072 events read in total (182090ms).
[21:26:53.039] <TB1> INFO: 4679280 events read in total (208270ms).
[21:27:08.422] <TB1> INFO: 5025280 events read in total (223653ms).
[21:27:08.694] <TB1> INFO: Test took 224754ms.
[21:27:34.951] <TB1> INFO: ---> TrimStepCorr4 extremal thresholds: 5.649212 .. 145.568989
[21:27:35.240] <TB1> INFO: Expecting 208000 events.
[21:27:44.861] <TB1> INFO: 208000 events read in total (9030ms).
[21:27:44.863] <TB1> INFO: Test took 9910ms.
[21:27:44.925] <TB1> INFO: ---> dac: vcal name: trimStepCorr4 ntrig: 8 dacrange: 5 .. 155 (-1/-1) hits flags = 528 (plus default)
[21:27:44.939] <TB1> INFO: dacScan split into 1 runs with ntrig = 8
[21:27:44.939] <TB1> INFO: run 1 of 1
[21:27:45.219] <TB1> INFO: Expecting 5025280 events.
[21:28:10.876] <TB1> INFO: 579264 events read in total (25065ms).
[21:28:36.154] <TB1> INFO: 1158728 events read in total (50343ms).
[21:29:01.262] <TB1> INFO: 1737728 events read in total (75451ms).
[21:29:26.595] <TB1> INFO: 2316584 events read in total (100784ms).
[21:29:51.913] <TB1> INFO: 2895984 events read in total (126102ms).
[21:30:17.162] <TB1> INFO: 3474576 events read in total (151351ms).
[21:30:42.731] <TB1> INFO: 4052440 events read in total (176920ms).
[21:31:08.364] <TB1> INFO: 4629584 events read in total (202553ms).
[21:31:26.219] <TB1> INFO: 5025280 events read in total (220408ms).
[21:31:26.306] <TB1> INFO: Test took 221367ms.
[21:31:54.660] <TB1> INFO: ---> TrimStepCorr2 extremal thresholds: 27.299797 .. 49.804345
[21:31:54.942] <TB1> INFO: Expecting 208000 events.
[21:32:05.163] <TB1> INFO: 208000 events read in total (9629ms).
[21:32:05.164] <TB1> INFO: Test took 10502ms.
[21:32:05.212] <TB1> INFO: ---> dac: vcal name: trimStepCorr2 ntrig: 8 dacrange: 17 .. 59 (-1/-1) hits flags = 528 (plus default)
[21:32:05.224] <TB1> INFO: dacScan split into 1 runs with ntrig = 8
[21:32:05.224] <TB1> INFO: run 1 of 1
[21:32:05.502] <TB1> INFO: Expecting 1431040 events.
[21:32:33.638] <TB1> INFO: 644464 events read in total (27544ms).
[21:33:01.062] <TB1> INFO: 1286528 events read in total (54968ms).
[21:33:07.958] <TB1> INFO: 1431040 events read in total (61864ms).
[21:33:07.995] <TB1> INFO: Test took 62772ms.
[21:33:24.224] <TB1> INFO: ---> TrimStepCorr1a extremal thresholds: 26.402811 .. 54.070956
[21:33:24.463] <TB1> INFO: Expecting 208000 events.
[21:33:34.289] <TB1> INFO: 208000 events read in total (9235ms).
[21:33:34.290] <TB1> INFO: Test took 10065ms.
[21:33:34.338] <TB1> INFO: ---> dac: vcal name: trimStepCorr1a ntrig: 8 dacrange: 16 .. 64 (-1/-1) hits flags = 528 (plus default)
[21:33:34.350] <TB1> INFO: dacScan split into 1 runs with ntrig = 8
[21:33:34.350] <TB1> INFO: run 1 of 1
[21:33:34.629] <TB1> INFO: Expecting 1630720 events.
[21:34:02.197] <TB1> INFO: 634504 events read in total (26976ms).
[21:34:29.181] <TB1> INFO: 1268336 events read in total (53960ms).
[21:34:45.982] <TB1> INFO: 1630720 events read in total (70761ms).
[21:34:46.019] <TB1> INFO: Test took 71670ms.
[21:34:59.658] <TB1> INFO: ---> TrimStepCorr1b extremal thresholds: 24.366371 .. 49.554466
[21:34:59.901] <TB1> INFO: Expecting 208000 events.
[21:35:10.009] <TB1> INFO: 208000 events read in total (9516ms).
[21:35:10.010] <TB1> INFO: Test took 10350ms.
[21:35:10.059] <TB1> INFO: ---> dac: vcal name: trimStepCorr1b ntrig: 8 dacrange: 14 .. 59 (-1/-1) hits flags = 528 (plus default)
[21:35:10.072] <TB1> INFO: dacScan split into 1 runs with ntrig = 8
[21:35:10.072] <TB1> INFO: run 1 of 1
[21:35:10.351] <TB1> INFO: Expecting 1530880 events.
[21:35:38.092] <TB1> INFO: 656952 events read in total (27150ms).
[21:36:06.431] <TB1> INFO: 1313616 events read in total (55489ms).
[21:36:15.838] <TB1> INFO: 1530880 events read in total (64896ms).
[21:36:15.867] <TB1> INFO: Test took 65795ms.
[21:36:30.743] <TB1> INFO: ---> TrimThrFinal extremal thresholds: 15 .. 55
[21:36:30.743] <TB1> INFO: ---> dac: vcal name: TrimThrFinal ntrig: 8 dacrange: 15 .. 55 (-1/-1) hits flags = 528 (plus default)
[21:36:30.756] <TB1> INFO: dacScan split into 1 runs with ntrig = 8
[21:36:30.756] <TB1> INFO: run 1 of 1
[21:36:30.997] <TB1> INFO: Expecting 1364480 events.
[21:36:59.702] <TB1> INFO: 667360 events read in total (28113ms).
[21:37:26.904] <TB1> INFO: 1334272 events read in total (55315ms).
[21:37:28.571] <TB1> INFO: 1364480 events read in total (56983ms).
[21:37:28.599] <TB1> INFO: Test took 57842ms.
[21:37:41.907] <TB1> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1025_FullQualification_2016-10-19_19h52m_1476899523//001_Fulltest_m20//dacParameters35_C0.dat
[21:37:41.907] <TB1> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1025_FullQualification_2016-10-19_19h52m_1476899523//001_Fulltest_m20//dacParameters35_C1.dat
[21:37:41.907] <TB1> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1025_FullQualification_2016-10-19_19h52m_1476899523//001_Fulltest_m20//dacParameters35_C2.dat
[21:37:41.907] <TB1> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1025_FullQualification_2016-10-19_19h52m_1476899523//001_Fulltest_m20//dacParameters35_C3.dat
[21:37:41.907] <TB1> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1025_FullQualification_2016-10-19_19h52m_1476899523//001_Fulltest_m20//dacParameters35_C4.dat
[21:37:41.907] <TB1> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1025_FullQualification_2016-10-19_19h52m_1476899523//001_Fulltest_m20//dacParameters35_C5.dat
[21:37:41.907] <TB1> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1025_FullQualification_2016-10-19_19h52m_1476899523//001_Fulltest_m20//dacParameters35_C6.dat
[21:37:41.908] <TB1> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1025_FullQualification_2016-10-19_19h52m_1476899523//001_Fulltest_m20//dacParameters35_C7.dat
[21:37:41.908] <TB1> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1025_FullQualification_2016-10-19_19h52m_1476899523//001_Fulltest_m20//dacParameters35_C8.dat
[21:37:41.908] <TB1> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1025_FullQualification_2016-10-19_19h52m_1476899523//001_Fulltest_m20//dacParameters35_C9.dat
[21:37:41.908] <TB1> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1025_FullQualification_2016-10-19_19h52m_1476899523//001_Fulltest_m20//dacParameters35_C10.dat
[21:37:41.908] <TB1> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1025_FullQualification_2016-10-19_19h52m_1476899523//001_Fulltest_m20//dacParameters35_C11.dat
[21:37:41.908] <TB1> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1025_FullQualification_2016-10-19_19h52m_1476899523//001_Fulltest_m20//dacParameters35_C12.dat
[21:37:41.908] <TB1> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1025_FullQualification_2016-10-19_19h52m_1476899523//001_Fulltest_m20//dacParameters35_C13.dat
[21:37:41.908] <TB1> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1025_FullQualification_2016-10-19_19h52m_1476899523//001_Fulltest_m20//dacParameters35_C14.dat
[21:37:41.908] <TB1> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1025_FullQualification_2016-10-19_19h52m_1476899523//001_Fulltest_m20//dacParameters35_C15.dat
[21:37:41.908] <TB1> INFO: write trim parameters into /home/l_tester/david/FullQualification/data/M1025_FullQualification_2016-10-19_19h52m_1476899523//001_Fulltest_m20//trimParameters35_C0.dat
[21:37:41.914] <TB1> INFO: write trim parameters into /home/l_tester/david/FullQualification/data/M1025_FullQualification_2016-10-19_19h52m_1476899523//001_Fulltest_m20//trimParameters35_C1.dat
[21:37:41.919] <TB1> INFO: write trim parameters into /home/l_tester/david/FullQualification/data/M1025_FullQualification_2016-10-19_19h52m_1476899523//001_Fulltest_m20//trimParameters35_C2.dat
[21:37:41.925] <TB1> INFO: write trim parameters into /home/l_tester/david/FullQualification/data/M1025_FullQualification_2016-10-19_19h52m_1476899523//001_Fulltest_m20//trimParameters35_C3.dat
[21:37:41.930] <TB1> INFO: write trim parameters into /home/l_tester/david/FullQualification/data/M1025_FullQualification_2016-10-19_19h52m_1476899523//001_Fulltest_m20//trimParameters35_C4.dat
[21:37:41.934] <TB1> INFO: write trim parameters into /home/l_tester/david/FullQualification/data/M1025_FullQualification_2016-10-19_19h52m_1476899523//001_Fulltest_m20//trimParameters35_C5.dat
[21:37:41.939] <TB1> INFO: write trim parameters into /home/l_tester/david/FullQualification/data/M1025_FullQualification_2016-10-19_19h52m_1476899523//001_Fulltest_m20//trimParameters35_C6.dat
[21:37:41.944] <TB1> INFO: write trim parameters into /home/l_tester/david/FullQualification/data/M1025_FullQualification_2016-10-19_19h52m_1476899523//001_Fulltest_m20//trimParameters35_C7.dat
[21:37:41.948] <TB1> INFO: write trim parameters into /home/l_tester/david/FullQualification/data/M1025_FullQualification_2016-10-19_19h52m_1476899523//001_Fulltest_m20//trimParameters35_C8.dat
[21:37:41.953] <TB1> INFO: write trim parameters into /home/l_tester/david/FullQualification/data/M1025_FullQualification_2016-10-19_19h52m_1476899523//001_Fulltest_m20//trimParameters35_C9.dat
[21:37:41.957] <TB1> INFO: write trim parameters into /home/l_tester/david/FullQualification/data/M1025_FullQualification_2016-10-19_19h52m_1476899523//001_Fulltest_m20//trimParameters35_C10.dat
[21:37:41.962] <TB1> INFO: write trim parameters into /home/l_tester/david/FullQualification/data/M1025_FullQualification_2016-10-19_19h52m_1476899523//001_Fulltest_m20//trimParameters35_C11.dat
[21:37:41.967] <TB1> INFO: write trim parameters into /home/l_tester/david/FullQualification/data/M1025_FullQualification_2016-10-19_19h52m_1476899523//001_Fulltest_m20//trimParameters35_C12.dat
[21:37:41.972] <TB1> INFO: write trim parameters into /home/l_tester/david/FullQualification/data/M1025_FullQualification_2016-10-19_19h52m_1476899523//001_Fulltest_m20//trimParameters35_C13.dat
[21:37:41.977] <TB1> INFO: write trim parameters into /home/l_tester/david/FullQualification/data/M1025_FullQualification_2016-10-19_19h52m_1476899523//001_Fulltest_m20//trimParameters35_C14.dat
[21:37:41.981] <TB1> INFO: write trim parameters into /home/l_tester/david/FullQualification/data/M1025_FullQualification_2016-10-19_19h52m_1476899523//001_Fulltest_m20//trimParameters35_C15.dat
[21:37:41.986] <TB1> INFO: PixTestTrim::trimTest() done
[21:37:41.986] <TB1> INFO: vtrim: 125 123 130 132 124 123 138 122 111 127 130 132 115 130 139 141
[21:37:41.986] <TB1> INFO: vthrcomp: 110 120 125 117 114 115 109 121 107 119 109 105 109 116 128 110
[21:37:41.986] <TB1> INFO: vcal mean: 35.00 34.96 34.95 34.95 34.99 34.96 34.98 34.96 35.02 34.97 35.02 34.97 35.02 34.93 34.95 34.99
[21:37:41.986] <TB1> INFO: vcal RMS: 0.94 0.99 1.16 0.98 0.97 1.10 1.08 0.96 0.96 1.28 0.98 0.91 0.93 1.01 1.08 1.01
[21:37:41.986] <TB1> INFO: bits mean: 8.30 9.19 9.90 9.56 9.32 9.37 8.79 8.75 8.56 10.23 8.63 8.92 8.73 9.49 9.21 9.20
[21:37:41.986] <TB1> INFO: bits RMS: 2.68 2.91 2.72 2.69 2.71 2.79 2.48 2.96 2.62 2.52 2.67 2.52 2.54 2.64 3.03 2.44
[21:37:41.993] <TB1> INFO: ----------------------------------------------------------------------
[21:37:41.993] <TB1> INFO: PixTestTrim::trimBitTest() ntrig = 5, vtrims = 254 126 63 32
[21:37:41.993] <TB1> INFO: ----------------------------------------------------------------------
[21:37:41.996] <TB1> INFO: ---> dac: Vcal name: TrimBitsThr0 ntrig: 5 dacrange: 0 .. 199 (-1/-1) hits flags = 528 (plus default)
[21:37:42.009] <TB1> INFO: dacScan split into 1 runs with ntrig = 5
[21:37:42.009] <TB1> INFO: run 1 of 1
[21:37:42.245] <TB1> INFO: Expecting 4160000 events.
[21:38:14.112] <TB1> INFO: 738615 events read in total (31275ms).
[21:38:45.533] <TB1> INFO: 1472850 events read in total (62696ms).
[21:39:16.529] <TB1> INFO: 2202795 events read in total (93692ms).
[21:39:47.858] <TB1> INFO: 2929895 events read in total (125021ms).
[21:40:19.135] <TB1> INFO: 3655325 events read in total (156298ms).
[21:40:42.161] <TB1> INFO: 4160000 events read in total (179324ms).
[21:40:42.233] <TB1> INFO: Test took 180224ms.
[21:41:08.075] <TB1> INFO: ---> dac: Vcal name: TrimThr_trim14 ntrig: 5 dacrange: 0 .. 197 (-1/-1) hits flags = 528 (plus default)
[21:41:08.090] <TB1> INFO: dacScan split into 1 runs with ntrig = 5
[21:41:08.090] <TB1> INFO: run 1 of 1
[21:41:08.348] <TB1> INFO: Expecting 4118400 events.
[21:41:40.210] <TB1> INFO: 717305 events read in total (31271ms).
[21:42:11.429] <TB1> INFO: 1430540 events read in total (62490ms).
[21:42:42.333] <TB1> INFO: 2140070 events read in total (93394ms).
[21:43:13.196] <TB1> INFO: 2847065 events read in total (124257ms).
[21:43:44.069] <TB1> INFO: 3553065 events read in total (155130ms).
[21:44:10.317] <TB1> INFO: 4118400 events read in total (181378ms).
[21:44:10.476] <TB1> INFO: Test took 182386ms.
[21:44:41.572] <TB1> INFO: ---> dac: Vcal name: TrimThr_trim13 ntrig: 5 dacrange: 0 .. 203 (-1/-1) hits flags = 528 (plus default)
[21:44:41.585] <TB1> INFO: dacScan split into 1 runs with ntrig = 5
[21:44:41.585] <TB1> INFO: run 1 of 1
[21:44:41.822] <TB1> INFO: Expecting 4243200 events.
[21:45:13.373] <TB1> INFO: 710585 events read in total (30960ms).
[21:45:44.138] <TB1> INFO: 1416890 events read in total (61725ms).
[21:46:15.054] <TB1> INFO: 2120430 events read in total (92641ms).
[21:46:45.553] <TB1> INFO: 2820875 events read in total (123140ms).
[21:47:16.918] <TB1> INFO: 3520785 events read in total (154505ms).
[21:47:47.770] <TB1> INFO: 4224160 events read in total (185357ms).
[21:47:48.988] <TB1> INFO: 4243200 events read in total (186575ms).
[21:47:49.119] <TB1> INFO: Test took 187534ms.
[21:48:16.445] <TB1> INFO: ---> dac: Vcal name: TrimThr_trim11 ntrig: 5 dacrange: 0 .. 210 (-1/-1) hits flags = 528 (plus default)
[21:48:16.459] <TB1> INFO: dacScan split into 1 runs with ntrig = 5
[21:48:16.459] <TB1> INFO: run 1 of 1
[21:48:16.695] <TB1> INFO: Expecting 4388800 events.
[21:48:48.194] <TB1> INFO: 702910 events read in total (30907ms).
[21:49:18.888] <TB1> INFO: 1401590 events read in total (61601ms).
[21:49:49.960] <TB1> INFO: 2098280 events read in total (92673ms).
[21:50:20.902] <TB1> INFO: 2792030 events read in total (123615ms).
[21:50:52.124] <TB1> INFO: 3484605 events read in total (154837ms).
[21:51:23.164] <TB1> INFO: 4178565 events read in total (185877ms).
[21:51:32.666] <TB1> INFO: 4388800 events read in total (195379ms).
[21:51:32.946] <TB1> INFO: Test took 196486ms.
[21:51:59.609] <TB1> INFO: ---> dac: Vcal name: TrimThr_trim7 ntrig: 5 dacrange: 0 .. 228 (-1/-1) hits flags = 528 (plus default)
[21:51:59.624] <TB1> INFO: dacScan split into 1 runs with ntrig = 5
[21:51:59.624] <TB1> INFO: run 1 of 1
[21:51:59.906] <TB1> INFO: Expecting 4763200 events.
[21:52:31.031] <TB1> INFO: 686135 events read in total (30534ms).
[21:53:01.406] <TB1> INFO: 1368185 events read in total (60909ms).
[21:53:31.996] <TB1> INFO: 2049325 events read in total (91499ms).
[21:54:03.039] <TB1> INFO: 2727070 events read in total (122542ms).
[21:54:33.523] <TB1> INFO: 3404070 events read in total (153026ms).
[21:55:03.766] <TB1> INFO: 4080690 events read in total (183269ms).
[21:55:34.351] <TB1> INFO: 4761305 events read in total (213854ms).
[21:55:34.863] <TB1> INFO: 4763200 events read in total (214366ms).
[21:55:34.954] <TB1> INFO: Test took 215330ms.
[21:56:01.020] <TB1> INFO: PixTestTrim::trimBitTest() done
[21:56:01.022] <TB1> INFO: PixTestTrim::doTest() done, duration: 2569 seconds
[21:56:01.022] <TB1> INFO: Decoding statistics:
[21:56:01.022] <TB1> INFO: General information:
[21:56:01.022] <TB1> INFO: 16bit words read: 0
[21:56:01.022] <TB1> INFO: valid events total: 0
[21:56:01.022] <TB1> INFO: empty events: 0
[21:56:01.022] <TB1> INFO: valid events with pixels: 0
[21:56:01.022] <TB1> INFO: valid pixel hits: 0
[21:56:01.022] <TB1> INFO: Event errors: 0
[21:56:01.022] <TB1> INFO: start marker: 0
[21:56:01.022] <TB1> INFO: stop marker: 0
[21:56:01.022] <TB1> INFO: overflow: 0
[21:56:01.022] <TB1> INFO: invalid 5bit words: 0
[21:56:01.022] <TB1> INFO: invalid XOR eye diagram: 0
[21:56:01.022] <TB1> INFO: frame (failed synchr.): 0
[21:56:01.022] <TB1> INFO: idle data (no TBM trl): 0
[21:56:01.022] <TB1> INFO: no data (only TBM hdr): 0
[21:56:01.022] <TB1> INFO: TBM errors: 0
[21:56:01.022] <TB1> INFO: flawed TBM headers: 0
[21:56:01.022] <TB1> INFO: flawed TBM trailers: 0
[21:56:01.022] <TB1> INFO: event ID mismatches: 0
[21:56:01.022] <TB1> INFO: ROC errors: 0
[21:56:01.022] <TB1> INFO: missing ROC header(s): 0
[21:56:01.022] <TB1> INFO: misplaced readback start: 0
[21:56:01.022] <TB1> INFO: Pixel decoding errors: 0
[21:56:01.022] <TB1> INFO: pixel data incomplete: 0
[21:56:01.022] <TB1> INFO: pixel address: 0
[21:56:01.022] <TB1> INFO: pulse height fill bit: 0
[21:56:01.022] <TB1> INFO: buffer corruption: 0
[21:56:01.627] <TB1> INFO: ######################################################################
[21:56:01.628] <TB1> INFO: PixTestPhOptimization::doTest() Ntrig = 10
[21:56:01.628] <TB1> INFO: ######################################################################
[21:56:01.866] <TB1> INFO: Expecting 41600 events.
[21:56:05.368] <TB1> INFO: 41600 events read in total (2910ms).
[21:56:05.369] <TB1> INFO: Test took 3740ms.
[21:56:05.819] <TB1> INFO: Expecting 41600 events.
[21:56:09.423] <TB1> INFO: 41600 events read in total (3013ms).
[21:56:09.424] <TB1> INFO: Test took 3852ms.
[21:56:09.716] <TB1> INFO: Expecting 41600 events.
[21:56:13.288] <TB1> INFO: 41600 events read in total (2980ms).
[21:56:13.289] <TB1> INFO: Test took 3839ms.
[21:56:13.583] <TB1> INFO: Expecting 41600 events.
[21:56:17.131] <TB1> INFO: 41600 events read in total (2956ms).
[21:56:17.131] <TB1> INFO: Test took 3814ms.
[21:56:17.421] <TB1> INFO: Expecting 41600 events.
[21:56:20.981] <TB1> INFO: 41600 events read in total (2968ms).
[21:56:20.982] <TB1> INFO: Test took 3827ms.
[21:56:21.274] <TB1> INFO: Expecting 41600 events.
[21:56:24.753] <TB1> INFO: 41600 events read in total (2887ms).
[21:56:24.754] <TB1> INFO: Test took 3746ms.
[21:56:25.043] <TB1> INFO: Expecting 41600 events.
[21:56:28.549] <TB1> INFO: 41600 events read in total (2915ms).
[21:56:28.550] <TB1> INFO: Test took 3772ms.
[21:56:28.840] <TB1> INFO: Expecting 41600 events.
[21:56:32.350] <TB1> INFO: 41600 events read in total (2918ms).
[21:56:32.350] <TB1> INFO: Test took 3776ms.
[21:56:32.640] <TB1> INFO: Expecting 41600 events.
[21:56:36.134] <TB1> INFO: 41600 events read in total (2902ms).
[21:56:36.135] <TB1> INFO: Test took 3760ms.
[21:56:36.450] <TB1> INFO: Expecting 41600 events.
[21:56:40.034] <TB1> INFO: 41600 events read in total (2992ms).
[21:56:40.035] <TB1> INFO: Test took 3873ms.
[21:56:40.339] <TB1> INFO: Expecting 41600 events.
[21:56:43.941] <TB1> INFO: 41600 events read in total (3010ms).
[21:56:43.942] <TB1> INFO: Test took 3880ms.
[21:56:44.231] <TB1> INFO: Expecting 41600 events.
[21:56:47.743] <TB1> INFO: 41600 events read in total (2920ms).
[21:56:47.744] <TB1> INFO: Test took 3778ms.
[21:56:48.034] <TB1> INFO: Expecting 41600 events.
[21:56:51.561] <TB1> INFO: 41600 events read in total (2936ms).
[21:56:51.562] <TB1> INFO: Test took 3793ms.
[21:56:51.851] <TB1> INFO: Expecting 41600 events.
[21:56:55.376] <TB1> INFO: 41600 events read in total (2933ms).
[21:56:55.377] <TB1> INFO: Test took 3791ms.
[21:56:55.665] <TB1> INFO: Expecting 41600 events.
[21:56:59.166] <TB1> INFO: 41600 events read in total (2909ms).
[21:56:59.166] <TB1> INFO: Test took 3765ms.
[21:56:59.456] <TB1> INFO: Expecting 41600 events.
[21:57:03.100] <TB1> INFO: 41600 events read in total (3052ms).
[21:57:03.101] <TB1> INFO: Test took 3911ms.
[21:57:03.392] <TB1> INFO: Expecting 41600 events.
[21:57:06.878] <TB1> INFO: 41600 events read in total (2894ms).
[21:57:06.879] <TB1> INFO: Test took 3753ms.
[21:57:07.169] <TB1> INFO: Expecting 41600 events.
[21:57:10.670] <TB1> INFO: 41600 events read in total (2909ms).
[21:57:10.671] <TB1> INFO: Test took 3767ms.
[21:57:10.962] <TB1> INFO: Expecting 41600 events.
[21:57:14.451] <TB1> INFO: 41600 events read in total (2897ms).
[21:57:14.452] <TB1> INFO: Test took 3755ms.
[21:57:14.743] <TB1> INFO: Expecting 41600 events.
[21:57:18.269] <TB1> INFO: 41600 events read in total (2934ms).
[21:57:18.270] <TB1> INFO: Test took 3793ms.
[21:57:18.560] <TB1> INFO: Expecting 41600 events.
[21:57:22.077] <TB1> INFO: 41600 events read in total (2925ms).
[21:57:22.079] <TB1> INFO: Test took 3784ms.
[21:57:22.370] <TB1> INFO: Expecting 41600 events.
[21:57:25.862] <TB1> INFO: 41600 events read in total (2901ms).
[21:57:25.863] <TB1> INFO: Test took 3758ms.
[21:57:26.155] <TB1> INFO: Expecting 41600 events.
[21:57:29.710] <TB1> INFO: 41600 events read in total (2963ms).
[21:57:29.711] <TB1> INFO: Test took 3821ms.
[21:57:30.019] <TB1> INFO: Expecting 41600 events.
[21:57:33.580] <TB1> INFO: 41600 events read in total (2969ms).
[21:57:33.581] <TB1> INFO: Test took 3845ms.
[21:57:33.871] <TB1> INFO: Expecting 41600 events.
[21:57:37.412] <TB1> INFO: 41600 events read in total (2950ms).
[21:57:37.413] <TB1> INFO: Test took 3808ms.
[21:57:37.705] <TB1> INFO: Expecting 41600 events.
[21:57:41.273] <TB1> INFO: 41600 events read in total (2976ms).
[21:57:41.273] <TB1> INFO: Test took 3833ms.
[21:57:41.563] <TB1> INFO: Expecting 41600 events.
[21:57:45.075] <TB1> INFO: 41600 events read in total (2920ms).
[21:57:45.075] <TB1> INFO: Test took 3777ms.
[21:57:45.365] <TB1> INFO: Expecting 41600 events.
[21:57:48.918] <TB1> INFO: 41600 events read in total (2962ms).
[21:57:48.919] <TB1> INFO: Test took 3820ms.
[21:57:49.208] <TB1> INFO: Expecting 41600 events.
[21:57:52.767] <TB1> INFO: 41600 events read in total (2967ms).
[21:57:52.768] <TB1> INFO: Test took 3825ms.
[21:57:53.059] <TB1> INFO: Expecting 41600 events.
[21:57:56.581] <TB1> INFO: 41600 events read in total (2930ms).
[21:57:56.582] <TB1> INFO: Test took 3788ms.
[21:57:56.873] <TB1> INFO: Expecting 41600 events.
[21:58:00.422] <TB1> INFO: 41600 events read in total (2957ms).
[21:58:00.423] <TB1> INFO: Test took 3816ms.
[21:58:00.713] <TB1> INFO: Expecting 2560 events.
[21:58:01.598] <TB1> INFO: 2560 events read in total (293ms).
[21:58:01.598] <TB1> INFO: Test took 1162ms.
[21:58:01.906] <TB1> INFO: Expecting 2560 events.
[21:58:02.798] <TB1> INFO: 2560 events read in total (300ms).
[21:58:02.799] <TB1> INFO: Test took 1200ms.
[21:58:03.106] <TB1> INFO: Expecting 2560 events.
[21:58:03.999] <TB1> INFO: 2560 events read in total (301ms).
[21:58:03.000] <TB1> INFO: Test took 1201ms.
[21:58:04.307] <TB1> INFO: Expecting 2560 events.
[21:58:05.189] <TB1> INFO: 2560 events read in total (290ms).
[21:58:05.190] <TB1> INFO: Test took 1190ms.
[21:58:05.498] <TB1> INFO: Expecting 2560 events.
[21:58:06.382] <TB1> INFO: 2560 events read in total (293ms).
[21:58:06.383] <TB1> INFO: Test took 1193ms.
[21:58:06.691] <TB1> INFO: Expecting 2560 events.
[21:58:07.577] <TB1> INFO: 2560 events read in total (294ms).
[21:58:07.578] <TB1> INFO: Test took 1194ms.
[21:58:07.885] <TB1> INFO: Expecting 2560 events.
[21:58:08.765] <TB1> INFO: 2560 events read in total (288ms).
[21:58:08.765] <TB1> INFO: Test took 1187ms.
[21:58:09.073] <TB1> INFO: Expecting 2560 events.
[21:58:09.959] <TB1> INFO: 2560 events read in total (295ms).
[21:58:09.959] <TB1> INFO: Test took 1194ms.
[21:58:10.268] <TB1> INFO: Expecting 2560 events.
[21:58:11.146] <TB1> INFO: 2560 events read in total (286ms).
[21:58:11.146] <TB1> INFO: Test took 1186ms.
[21:58:11.455] <TB1> INFO: Expecting 2560 events.
[21:58:12.335] <TB1> INFO: 2560 events read in total (289ms).
[21:58:12.335] <TB1> INFO: Test took 1188ms.
[21:58:12.643] <TB1> INFO: Expecting 2560 events.
[21:58:13.527] <TB1> INFO: 2560 events read in total (292ms).
[21:58:13.527] <TB1> INFO: Test took 1192ms.
[21:58:13.834] <TB1> INFO: Expecting 2560 events.
[21:58:14.714] <TB1> INFO: 2560 events read in total (288ms).
[21:58:14.715] <TB1> INFO: Test took 1187ms.
[21:58:15.023] <TB1> INFO: Expecting 2560 events.
[21:58:15.906] <TB1> INFO: 2560 events read in total (292ms).
[21:58:15.906] <TB1> INFO: Test took 1190ms.
[21:58:16.214] <TB1> INFO: Expecting 2560 events.
[21:58:17.100] <TB1> INFO: 2560 events read in total (294ms).
[21:58:17.101] <TB1> INFO: Test took 1194ms.
[21:58:17.409] <TB1> INFO: Expecting 2560 events.
[21:58:18.291] <TB1> INFO: 2560 events read in total (290ms).
[21:58:18.291] <TB1> INFO: Test took 1190ms.
[21:58:18.600] <TB1> INFO: Expecting 2560 events.
[21:58:19.488] <TB1> INFO: 2560 events read in total (296ms).
[21:58:19.488] <TB1> INFO: Test took 1196ms.
[21:58:19.492] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[21:58:19.797] <TB1> INFO: Expecting 655360 events.
[21:58:34.752] <TB1> INFO: 655360 events read in total (14363ms).
[21:58:34.768] <TB1> INFO: Expecting 655360 events.
[21:58:49.569] <TB1> INFO: 655360 events read in total (14398ms).
[21:58:49.589] <TB1> INFO: Expecting 655360 events.
[21:59:04.253] <TB1> INFO: 655360 events read in total (14261ms).
[21:59:04.283] <TB1> INFO: Expecting 655360 events.
[21:59:19.217] <TB1> INFO: 655360 events read in total (14531ms).
[21:59:19.252] <TB1> INFO: Expecting 655360 events.
[21:59:33.888] <TB1> INFO: 655360 events read in total (14232ms).
[21:59:33.923] <TB1> INFO: Expecting 655360 events.
[21:59:48.744] <TB1> INFO: 655360 events read in total (14418ms).
[21:59:48.781] <TB1> INFO: Expecting 655360 events.
[22:00:03.449] <TB1> INFO: 655360 events read in total (14264ms).
[22:00:03.490] <TB1> INFO: Expecting 655360 events.
[22:00:18.339] <TB1> INFO: 655360 events read in total (14446ms).
[22:00:18.393] <TB1> INFO: Expecting 655360 events.
[22:00:33.120] <TB1> INFO: 655360 events read in total (14324ms).
[22:00:33.173] <TB1> INFO: Expecting 655360 events.
[22:00:47.948] <TB1> INFO: 655360 events read in total (14372ms).
[22:00:48.013] <TB1> INFO: Expecting 655360 events.
[22:01:02.818] <TB1> INFO: 655360 events read in total (14403ms).
[22:01:02.890] <TB1> INFO: Expecting 655360 events.
[22:01:17.448] <TB1> INFO: 655360 events read in total (14155ms).
[22:01:17.654] <TB1> INFO: Expecting 655360 events.
[22:01:32.379] <TB1> INFO: 655360 events read in total (14322ms).
[22:01:32.494] <TB1> INFO: Expecting 655360 events.
[22:01:47.155] <TB1> INFO: 655360 events read in total (14258ms).
[22:01:47.245] <TB1> INFO: Expecting 655360 events.
[22:02:01.856] <TB1> INFO: 655360 events read in total (14208ms).
[22:02:01.952] <TB1> INFO: Expecting 655360 events.
[22:02:16.611] <TB1> INFO: 655360 events read in total (14256ms).
[22:02:16.758] <TB1> INFO: Test took 237266ms.
[22:02:16.853] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[22:02:17.111] <TB1> INFO: Expecting 655360 events.
[22:02:31.826] <TB1> INFO: 655360 events read in total (14123ms).
[22:02:31.841] <TB1> INFO: Expecting 655360 events.
[22:02:46.594] <TB1> INFO: 655360 events read in total (14350ms).
[22:02:46.614] <TB1> INFO: Expecting 655360 events.
[22:03:01.153] <TB1> INFO: 655360 events read in total (14136ms).
[22:03:01.176] <TB1> INFO: Expecting 655360 events.
[22:03:15.756] <TB1> INFO: 655360 events read in total (14177ms).
[22:03:15.784] <TB1> INFO: Expecting 655360 events.
[22:03:30.365] <TB1> INFO: 655360 events read in total (14178ms).
[22:03:30.400] <TB1> INFO: Expecting 655360 events.
[22:03:44.856] <TB1> INFO: 655360 events read in total (14053ms).
[22:03:44.892] <TB1> INFO: Expecting 655360 events.
[22:03:59.398] <TB1> INFO: 655360 events read in total (14103ms).
[22:03:59.440] <TB1> INFO: Expecting 655360 events.
[22:04:14.048] <TB1> INFO: 655360 events read in total (14205ms).
[22:04:14.095] <TB1> INFO: Expecting 655360 events.
[22:04:28.729] <TB1> INFO: 655360 events read in total (14231ms).
[22:04:28.782] <TB1> INFO: Expecting 655360 events.
[22:04:43.477] <TB1> INFO: 655360 events read in total (14292ms).
[22:04:43.546] <TB1> INFO: Expecting 655360 events.
[22:04:58.199] <TB1> INFO: 655360 events read in total (14250ms).
[22:04:58.274] <TB1> INFO: Expecting 655360 events.
[22:05:13.045] <TB1> INFO: 655360 events read in total (14368ms).
[22:05:13.159] <TB1> INFO: Expecting 655360 events.
[22:05:54.906] <TB1> INFO: 655360 events read in total (41344ms).
[22:05:55.162] <TB1> INFO: Expecting 655360 events.
[22:06:23.134] <TB1> INFO: 655360 events read in total (27507ms).
[22:06:23.212] <TB1> INFO: Expecting 655360 events.
[22:06:37.657] <TB1> INFO: 655360 events read in total (14042ms).
[22:06:37.750] <TB1> INFO: Expecting 655360 events.
[22:07:37.355] <TB1> INFO: 655360 events read in total (59202ms).
[22:07:37.521] <TB1> INFO: Test took 320668ms.
[22:07:38.301] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[22:07:38.327] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[22:07:38.350] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[22:07:38.373] <TB1> INFO: safety margin for low PH: adding 1, margin is now 21
[22:07:38.397] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[22:07:38.421] <TB1> INFO: safety margin for low PH: adding 1, margin is now 21
[22:07:38.444] <TB1> INFO: safety margin for low PH: adding 2, margin is now 22
[22:07:38.468] <TB1> INFO: safety margin for low PH: adding 3, margin is now 23
[22:07:38.483] <TB1> INFO: safety margin for low PH: adding 4, margin is now 24
[22:07:38.495] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[22:07:38.508] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[22:07:38.543] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[22:07:38.567] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[22:07:38.589] <TB1> INFO: safety margin for low PH: adding 1, margin is now 21
[22:07:38.612] <TB1> INFO: safety margin for low PH: adding 2, margin is now 22
[22:07:38.634] <TB1> INFO: safety margin for low PH: adding 3, margin is now 23
[22:07:38.656] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[22:07:38.668] <TB1> INFO: safety margin for low PH: adding 1, margin is now 21
[22:07:38.680] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[22:07:38.692] <TB1> INFO: safety margin for low PH: adding 1, margin is now 21
[22:07:38.698] <TB1> INFO: safety margin for low PH: adding 2, margin is now 22
[22:07:38.704] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[22:07:38.710] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[22:07:38.720] <TB1> INFO: safety margin for low PH: adding 1, margin is now 21
[22:07:38.730] <TB1> INFO: safety margin for low PH: adding 2, margin is now 22
[22:07:38.741] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[22:07:38.752] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[22:07:38.762] <TB1> INFO: safety margin for low PH: adding 1, margin is now 21
[22:07:38.777] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[22:07:38.787] <TB1> INFO: safety margin for low PH: adding 1, margin is now 21
[22:07:38.797] <TB1> INFO: safety margin for low PH: adding 2, margin is now 22
[22:07:38.807] <TB1> INFO: safety margin for low PH: adding 3, margin is now 23
[22:07:38.818] <TB1> INFO: safety margin for low PH: adding 4, margin is now 24
[22:07:38.827] <TB1> INFO: safety margin for low PH: adding 5, margin is now 25
[22:07:38.837] <TB1> INFO: safety margin for low PH: adding 6, margin is now 26
[22:07:38.848] <TB1> INFO: safety margin for low PH: adding 7, margin is now 27
[22:07:38.858] <TB1> INFO: safety margin for low PH: adding 8, margin is now 28
[22:07:38.868] <TB1> INFO: safety margin for low PH: adding 9, margin is now 29
[22:07:38.878] <TB1> INFO: safety margin for low PH: adding 10, margin is now 30
[22:07:38.889] <TB1> INFO: safety margin for low PH: adding 11, margin is now 31
[22:07:38.899] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[22:07:38.909] <TB1> INFO: safety margin for low PH: adding 1, margin is now 21
[22:07:38.920] <TB1> INFO: safety margin for low PH: adding 2, margin is now 22
[22:07:38.930] <TB1> INFO: safety margin for low PH: adding 3, margin is now 23
[22:07:38.940] <TB1> INFO: safety margin for low PH: adding 4, margin is now 24
[22:07:38.950] <TB1> INFO: safety margin for low PH: adding 5, margin is now 25
[22:07:38.989] <TB1> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1025_FullQualification_2016-10-19_19h52m_1476899523//001_Fulltest_m20//dacParameters35_C0.dat
[22:07:38.989] <TB1> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1025_FullQualification_2016-10-19_19h52m_1476899523//001_Fulltest_m20//dacParameters35_C1.dat
[22:07:38.989] <TB1> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1025_FullQualification_2016-10-19_19h52m_1476899523//001_Fulltest_m20//dacParameters35_C2.dat
[22:07:38.990] <TB1> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1025_FullQualification_2016-10-19_19h52m_1476899523//001_Fulltest_m20//dacParameters35_C3.dat
[22:07:38.990] <TB1> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1025_FullQualification_2016-10-19_19h52m_1476899523//001_Fulltest_m20//dacParameters35_C4.dat
[22:07:38.990] <TB1> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1025_FullQualification_2016-10-19_19h52m_1476899523//001_Fulltest_m20//dacParameters35_C5.dat
[22:07:38.990] <TB1> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1025_FullQualification_2016-10-19_19h52m_1476899523//001_Fulltest_m20//dacParameters35_C6.dat
[22:07:38.990] <TB1> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1025_FullQualification_2016-10-19_19h52m_1476899523//001_Fulltest_m20//dacParameters35_C7.dat
[22:07:38.990] <TB1> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1025_FullQualification_2016-10-19_19h52m_1476899523//001_Fulltest_m20//dacParameters35_C8.dat
[22:07:38.990] <TB1> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1025_FullQualification_2016-10-19_19h52m_1476899523//001_Fulltest_m20//dacParameters35_C9.dat
[22:07:38.990] <TB1> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1025_FullQualification_2016-10-19_19h52m_1476899523//001_Fulltest_m20//dacParameters35_C10.dat
[22:07:38.990] <TB1> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1025_FullQualification_2016-10-19_19h52m_1476899523//001_Fulltest_m20//dacParameters35_C11.dat
[22:07:38.990] <TB1> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1025_FullQualification_2016-10-19_19h52m_1476899523//001_Fulltest_m20//dacParameters35_C12.dat
[22:07:38.990] <TB1> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1025_FullQualification_2016-10-19_19h52m_1476899523//001_Fulltest_m20//dacParameters35_C13.dat
[22:07:38.991] <TB1> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1025_FullQualification_2016-10-19_19h52m_1476899523//001_Fulltest_m20//dacParameters35_C14.dat
[22:07:38.991] <TB1> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1025_FullQualification_2016-10-19_19h52m_1476899523//001_Fulltest_m20//dacParameters35_C15.dat
[22:07:39.347] <TB1> INFO: Expecting 41600 events.
[22:07:42.526] <TB1> INFO: 41600 events read in total (2584ms).
[22:07:42.527] <TB1> INFO: Test took 3534ms.
[22:07:42.989] <TB1> INFO: Expecting 41600 events.
[22:07:46.079] <TB1> INFO: 41600 events read in total (2499ms).
[22:07:46.080] <TB1> INFO: Test took 3336ms.
[22:07:46.540] <TB1> INFO: Expecting 41600 events.
[22:07:49.660] <TB1> INFO: 41600 events read in total (2528ms).
[22:07:49.661] <TB1> INFO: Test took 3366ms.
[22:07:49.877] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[22:07:49.966] <TB1> INFO: Expecting 2560 events.
[22:07:50.850] <TB1> INFO: 2560 events read in total (292ms).
[22:07:50.851] <TB1> INFO: Test took 974ms.
[22:07:50.853] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[22:07:51.159] <TB1> INFO: Expecting 2560 events.
[22:07:52.041] <TB1> INFO: 2560 events read in total (291ms).
[22:07:52.041] <TB1> INFO: Test took 1188ms.
[22:07:52.044] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[22:07:52.350] <TB1> INFO: Expecting 2560 events.
[22:07:53.233] <TB1> INFO: 2560 events read in total (292ms).
[22:07:53.234] <TB1> INFO: Test took 1190ms.
[22:07:53.240] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[22:07:53.543] <TB1> INFO: Expecting 2560 events.
[22:07:54.431] <TB1> INFO: 2560 events read in total (297ms).
[22:07:54.431] <TB1> INFO: Test took 1191ms.
[22:07:54.435] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[22:07:54.740] <TB1> INFO: Expecting 2560 events.
[22:07:55.638] <TB1> INFO: 2560 events read in total (307ms).
[22:07:55.639] <TB1> INFO: Test took 1204ms.
[22:07:55.641] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[22:07:55.948] <TB1> INFO: Expecting 2560 events.
[22:07:56.832] <TB1> INFO: 2560 events read in total (293ms).
[22:07:56.833] <TB1> INFO: Test took 1192ms.
[22:07:56.835] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[22:07:57.141] <TB1> INFO: Expecting 2560 events.
[22:07:58.025] <TB1> INFO: 2560 events read in total (292ms).
[22:07:58.025] <TB1> INFO: Test took 1190ms.
[22:07:58.027] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[22:07:58.333] <TB1> INFO: Expecting 2560 events.
[22:07:59.228] <TB1> INFO: 2560 events read in total (303ms).
[22:07:59.228] <TB1> INFO: Test took 1201ms.
[22:07:59.230] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[22:07:59.537] <TB1> INFO: Expecting 2560 events.
[22:08:00.418] <TB1> INFO: 2560 events read in total (290ms).
[22:08:00.418] <TB1> INFO: Test took 1188ms.
[22:08:00.421] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[22:08:00.732] <TB1> INFO: Expecting 2560 events.
[22:08:01.620] <TB1> INFO: 2560 events read in total (296ms).
[22:08:01.620] <TB1> INFO: Test took 1199ms.
[22:08:01.623] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[22:08:01.929] <TB1> INFO: Expecting 2560 events.
[22:08:02.812] <TB1> INFO: 2560 events read in total (292ms).
[22:08:02.812] <TB1> INFO: Test took 1189ms.
[22:08:02.816] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[22:08:03.120] <TB1> INFO: Expecting 2560 events.
[22:08:03.002] <TB1> INFO: 2560 events read in total (291ms).
[22:08:03.002] <TB1> INFO: Test took 1187ms.
[22:08:03.004] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[22:08:04.310] <TB1> INFO: Expecting 2560 events.
[22:08:05.192] <TB1> INFO: 2560 events read in total (290ms).
[22:08:05.193] <TB1> INFO: Test took 1189ms.
[22:08:05.195] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[22:08:05.501] <TB1> INFO: Expecting 2560 events.
[22:08:06.380] <TB1> INFO: 2560 events read in total (287ms).
[22:08:06.381] <TB1> INFO: Test took 1186ms.
[22:08:06.383] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[22:08:06.689] <TB1> INFO: Expecting 2560 events.
[22:08:07.776] <TB1> INFO: 2560 events read in total (485ms).
[22:08:07.777] <TB1> INFO: Test took 1394ms.
[22:08:07.778] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[22:08:08.117] <TB1> INFO: Expecting 2560 events.
[22:08:09.063] <TB1> INFO: 2560 events read in total (348ms).
[22:08:09.063] <TB1> INFO: Test took 1285ms.
[22:08:09.079] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[22:08:09.641] <TB1> INFO: Expecting 2560 events.
[22:08:11.132] <TB1> INFO: 2560 events read in total (834ms).
[22:08:11.132] <TB1> INFO: Test took 2053ms.
[22:08:11.134] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[22:08:11.440] <TB1> INFO: Expecting 2560 events.
[22:08:14.232] <TB1> INFO: 2560 events read in total (2169ms).
[22:08:14.243] <TB1> INFO: Test took 3109ms.
[22:08:14.379] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[22:08:14.765] <TB1> INFO: Expecting 2560 events.
[22:08:15.750] <TB1> INFO: 2560 events read in total (311ms).
[22:08:15.751] <TB1> INFO: Test took 1372ms.
[22:08:15.755] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[22:08:16.059] <TB1> INFO: Expecting 2560 events.
[22:08:16.964] <TB1> INFO: 2560 events read in total (314ms).
[22:08:16.970] <TB1> INFO: Test took 1215ms.
[22:08:17.052] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[22:08:17.567] <TB1> INFO: Expecting 2560 events.
[22:08:18.924] <TB1> INFO: 2560 events read in total (711ms).
[22:08:18.935] <TB1> INFO: Test took 1883ms.
[22:08:18.956] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[22:08:19.311] <TB1> INFO: Expecting 2560 events.
[22:08:21.732] <TB1> INFO: 2560 events read in total (1828ms).
[22:08:21.733] <TB1> INFO: Test took 2777ms.
[22:08:21.735] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[22:08:22.159] <TB1> INFO: Expecting 2560 events.
[22:08:24.420] <TB1> INFO: 2560 events read in total (1644ms).
[22:08:24.431] <TB1> INFO: Test took 2696ms.
[22:08:24.543] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[22:08:24.935] <TB1> INFO: Expecting 2560 events.
[22:08:26.252] <TB1> INFO: 2560 events read in total (673ms).
[22:08:26.259] <TB1> INFO: Test took 1716ms.
[22:08:26.359] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[22:08:26.799] <TB1> INFO: Expecting 2560 events.
[22:08:27.728] <TB1> INFO: 2560 events read in total (303ms).
[22:08:27.729] <TB1> INFO: Test took 1370ms.
[22:08:27.745] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[22:08:28.045] <TB1> INFO: Expecting 2560 events.
[22:08:29.114] <TB1> INFO: 2560 events read in total (459ms).
[22:08:29.114] <TB1> INFO: Test took 1369ms.
[22:08:29.116] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[22:08:29.463] <TB1> INFO: Expecting 2560 events.
[22:08:30.369] <TB1> INFO: 2560 events read in total (304ms).
[22:08:30.369] <TB1> INFO: Test took 1254ms.
[22:08:30.371] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[22:08:30.695] <TB1> INFO: Expecting 2560 events.
[22:08:33.172] <TB1> INFO: 2560 events read in total (1845ms).
[22:08:33.175] <TB1> INFO: Test took 2804ms.
[22:08:33.284] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[22:08:33.686] <TB1> INFO: Expecting 2560 events.
[22:08:34.820] <TB1> INFO: 2560 events read in total (489ms).
[22:08:34.821] <TB1> INFO: Test took 1537ms.
[22:08:34.837] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[22:08:35.143] <TB1> INFO: Expecting 2560 events.
[22:08:36.716] <TB1> INFO: 2560 events read in total (957ms).
[22:08:36.719] <TB1> INFO: Test took 1882ms.
[22:08:36.783] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[22:08:37.167] <TB1> INFO: Expecting 2560 events.
[22:08:38.158] <TB1> INFO: 2560 events read in total (336ms).
[22:08:38.159] <TB1> INFO: Test took 1376ms.
[22:08:38.178] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[22:08:38.483] <TB1> INFO: Expecting 2560 events.
[22:08:39.640] <TB1> INFO: 2560 events read in total (561ms).
[22:08:39.641] <TB1> INFO: Test took 1463ms.
[22:08:41.363] <TB1> INFO: PixTestPhOptimization::doTest() done, duration: 759 seconds
[22:08:41.363] <TB1> INFO: PH scale (per ROC): 52 41 60 43 61 43 46 37 48 47 42 43 59 62 48 38
[22:08:41.363] <TB1> INFO: PH offset (per ROC): 118 108 129 90 121 113 105 97 109 108 106 112 130 119 109 99
[22:08:41.372] <TB1> INFO: Decoding statistics:
[22:08:41.372] <TB1> INFO: General information:
[22:08:41.372] <TB1> INFO: 16bit words read: 127880
[22:08:41.372] <TB1> INFO: valid events total: 20480
[22:08:41.372] <TB1> INFO: empty events: 17980
[22:08:41.372] <TB1> INFO: valid events with pixels: 2500
[22:08:41.372] <TB1> INFO: valid pixel hits: 2500
[22:08:41.372] <TB1> INFO: Event errors: 0
[22:08:41.372] <TB1> INFO: start marker: 0
[22:08:41.372] <TB1> INFO: stop marker: 0
[22:08:41.372] <TB1> INFO: overflow: 0
[22:08:41.372] <TB1> INFO: invalid 5bit words: 0
[22:08:41.372] <TB1> INFO: invalid XOR eye diagram: 0
[22:08:41.372] <TB1> INFO: frame (failed synchr.): 0
[22:08:41.372] <TB1> INFO: idle data (no TBM trl): 0
[22:08:41.372] <TB1> INFO: no data (only TBM hdr): 0
[22:08:41.372] <TB1> INFO: TBM errors: 0
[22:08:41.372] <TB1> INFO: flawed TBM headers: 0
[22:08:41.372] <TB1> INFO: flawed TBM trailers: 0
[22:08:41.372] <TB1> INFO: event ID mismatches: 0
[22:08:41.372] <TB1> INFO: ROC errors: 0
[22:08:41.372] <TB1> INFO: missing ROC header(s): 0
[22:08:41.372] <TB1> INFO: misplaced readback start: 0
[22:08:41.372] <TB1> INFO: Pixel decoding errors: 0
[22:08:41.372] <TB1> INFO: pixel data incomplete: 0
[22:08:41.372] <TB1> INFO: pixel address: 0
[22:08:41.372] <TB1> INFO: pulse height fill bit: 0
[22:08:41.372] <TB1> INFO: buffer corruption: 0
[22:08:42.028] <TB1> INFO: ######################################################################
[22:08:42.028] <TB1> INFO: PixTestGainPedestal::fullTest() ntrig = 10
[22:08:42.028] <TB1> INFO: ######################################################################
[22:08:42.067] <TB1> INFO: scanning low vcal = 10
[22:08:42.447] <TB1> INFO: Expecting 41600 events.
[22:08:48.642] <TB1> INFO: 41600 events read in total (5571ms).
[22:08:48.645] <TB1> INFO: Test took 6575ms.
[22:08:48.649] <TB1> INFO: scanning low vcal = 20
[22:08:48.977] <TB1> INFO: Expecting 41600 events.
[22:08:54.931] <TB1> INFO: 41600 events read in total (5363ms).
[22:08:54.943] <TB1> INFO: Test took 6293ms.
[22:08:54.948] <TB1> INFO: scanning low vcal = 30
[22:08:55.495] <TB1> INFO: Expecting 41600 events.
[22:09:13.011] <TB1> INFO: 41600 events read in total (16916ms).
[22:09:13.020] <TB1> INFO: Test took 18066ms.
[22:09:13.033] <TB1> INFO: scanning low vcal = 40
[22:09:13.666] <TB1> INFO: Expecting 41600 events.
[22:09:17.739] <TB1> INFO: 41600 events read in total (3481ms).
[22:09:17.745] <TB1> INFO: Test took 4708ms.
[22:09:17.749] <TB1> INFO: scanning low vcal = 50
[22:09:18.041] <TB1> INFO: Expecting 41600 events.
[22:09:22.064] <TB1> INFO: 41600 events read in total (3432ms).
[22:09:22.064] <TB1> INFO: Test took 4314ms.
[22:09:22.069] <TB1> INFO: scanning low vcal = 60
[22:09:22.344] <TB1> INFO: Expecting 41600 events.
[22:09:26.436] <TB1> INFO: 41600 events read in total (3500ms).
[22:09:26.437] <TB1> INFO: Test took 4368ms.
[22:09:26.441] <TB1> INFO: scanning low vcal = 70
[22:09:26.765] <TB1> INFO: Expecting 41600 events.
[22:09:30.762] <TB1> INFO: 41600 events read in total (3405ms).
[22:09:30.763] <TB1> INFO: Test took 4322ms.
[22:09:30.766] <TB1> INFO: scanning low vcal = 80
[22:09:31.043] <TB1> INFO: Expecting 41600 events.
[22:09:35.067] <TB1> INFO: 41600 events read in total (3426ms).
[22:09:35.067] <TB1> INFO: Test took 4301ms.
[22:09:35.071] <TB1> INFO: scanning low vcal = 90
[22:09:35.347] <TB1> INFO: Expecting 41600 events.
[22:09:39.343] <TB1> INFO: 41600 events read in total (3405ms).
[22:09:39.344] <TB1> INFO: Test took 4273ms.
[22:09:39.348] <TB1> INFO: scanning low vcal = 100
[22:09:39.623] <TB1> INFO: Expecting 41600 events.
[22:09:47.285] <TB1> INFO: 41600 events read in total (7070ms).
[22:09:47.288] <TB1> INFO: Test took 7940ms.
[22:09:47.311] <TB1> INFO: scanning low vcal = 110
[22:09:47.733] <TB1> INFO: Expecting 41600 events.
[22:09:53.825] <TB1> INFO: 41600 events read in total (5486ms).
[22:09:53.826] <TB1> INFO: Test took 6514ms.
[22:09:53.829] <TB1> INFO: scanning low vcal = 120
[22:09:54.191] <TB1> INFO: Expecting 41600 events.
[22:10:05.944] <TB1> INFO: 41600 events read in total (11107ms).
[22:10:05.959] <TB1> INFO: Test took 12131ms.
[22:10:06.024] <TB1> INFO: scanning low vcal = 130
[22:10:06.695] <TB1> INFO: Expecting 41600 events.
[22:10:18.356] <TB1> INFO: 41600 events read in total (10990ms).
[22:10:18.357] <TB1> INFO: Test took 12330ms.
[22:10:18.360] <TB1> INFO: scanning low vcal = 140
[22:10:18.673] <TB1> INFO: Expecting 41600 events.
[22:10:40.534] <TB1> INFO: 41600 events read in total (21270ms).
[22:10:40.536] <TB1> INFO: Test took 22176ms.
[22:10:40.539] <TB1> INFO: scanning low vcal = 150
[22:10:40.838] <TB1> INFO: Expecting 41600 events.
[22:10:44.832] <TB1> INFO: 41600 events read in total (3402ms).
[22:10:44.833] <TB1> INFO: Test took 4294ms.
[22:10:44.836] <TB1> INFO: scanning low vcal = 160
[22:10:45.112] <TB1> INFO: Expecting 41600 events.
[22:10:49.074] <TB1> INFO: 41600 events read in total (3370ms).
[22:10:49.074] <TB1> INFO: Test took 4237ms.
[22:10:49.077] <TB1> INFO: scanning low vcal = 170
[22:10:49.354] <TB1> INFO: Expecting 41600 events.
[22:10:53.321] <TB1> INFO: 41600 events read in total (3375ms).
[22:10:53.322] <TB1> INFO: Test took 4244ms.
[22:10:53.327] <TB1> INFO: scanning low vcal = 180
[22:10:53.601] <TB1> INFO: Expecting 41600 events.
[22:10:57.688] <TB1> INFO: 41600 events read in total (3495ms).
[22:10:57.689] <TB1> INFO: Test took 4362ms.
[22:10:57.692] <TB1> INFO: scanning low vcal = 190
[22:10:57.968] <TB1> INFO: Expecting 41600 events.
[22:11:02.024] <TB1> INFO: 41600 events read in total (3464ms).
[22:11:02.025] <TB1> INFO: Test took 4333ms.
[22:11:02.029] <TB1> INFO: scanning low vcal = 200
[22:11:02.334] <TB1> INFO: Expecting 41600 events.
[22:11:06.391] <TB1> INFO: 41600 events read in total (3465ms).
[22:11:06.392] <TB1> INFO: Test took 4363ms.
[22:11:06.396] <TB1> INFO: scanning low vcal = 210
[22:11:06.694] <TB1> INFO: Expecting 41600 events.
[22:11:10.696] <TB1> INFO: 41600 events read in total (3409ms).
[22:11:10.698] <TB1> INFO: Test took 4302ms.
[22:11:10.701] <TB1> INFO: scanning low vcal = 220
[22:11:10.977] <TB1> INFO: Expecting 41600 events.
[22:11:18.401] <TB1> INFO: 41600 events read in total (6831ms).
[22:11:18.403] <TB1> INFO: Test took 7702ms.
[22:11:18.407] <TB1> INFO: scanning low vcal = 230
[22:11:18.691] <TB1> INFO: Expecting 41600 events.
[22:11:24.085] <TB1> INFO: 41600 events read in total (4795ms).
[22:11:24.086] <TB1> INFO: Test took 5679ms.
[22:11:24.090] <TB1> INFO: scanning low vcal = 240
[22:11:24.719] <TB1> INFO: Expecting 41600 events.
[22:11:30.076] <TB1> INFO: 41600 events read in total (4701ms).
[22:11:30.077] <TB1> INFO: Test took 5987ms.
[22:11:30.100] <TB1> INFO: scanning low vcal = 250
[22:11:30.616] <TB1> INFO: Expecting 41600 events.
[22:11:38.319] <TB1> INFO: 41600 events read in total (7060ms).
[22:11:38.320] <TB1> INFO: Test took 8219ms.
[22:11:38.324] <TB1> INFO: scanning high vcal = 30 (= 210 in low range)
[22:11:38.807] <TB1> INFO: Expecting 41600 events.
[22:11:50.601] <TB1> INFO: 41600 events read in total (11146ms).
[22:11:50.602] <TB1> INFO: Test took 12277ms.
[22:11:50.605] <TB1> INFO: scanning high vcal = 50 (= 350 in low range)
[22:11:50.934] <TB1> INFO: Expecting 41600 events.
[22:11:55.103] <TB1> INFO: 41600 events read in total (3578ms).
[22:11:55.104] <TB1> INFO: Test took 4499ms.
[22:11:55.107] <TB1> INFO: scanning high vcal = 70 (= 490 in low range)
[22:11:55.575] <TB1> INFO: Expecting 41600 events.
[22:11:59.820] <TB1> INFO: 41600 events read in total (3579ms).
[22:11:59.828] <TB1> INFO: Test took 4720ms.
[22:11:59.835] <TB1> INFO: scanning high vcal = 90 (= 630 in low range)
[22:12:00.176] <TB1> INFO: Expecting 41600 events.
[22:12:04.321] <TB1> INFO: 41600 events read in total (3554ms).
[22:12:04.322] <TB1> INFO: Test took 4487ms.
[22:12:04.324] <TB1> INFO: scanning high vcal = 200 (= 1400 in low range)
[22:12:04.653] <TB1> INFO: Expecting 41600 events.
[22:12:08.790] <TB1> INFO: 41600 events read in total (3546ms).
[22:12:08.791] <TB1> INFO: Test took 4466ms.
[22:12:09.559] <TB1> INFO: PixTestGainPedestal::measure() done
[22:12:49.348] <TB1> INFO: PixTestGainPedestal::fit() done
[22:12:49.348] <TB1> INFO: non-linearity mean: 0.979 0.927 0.981 0.975 0.981 0.946 0.920 0.911 0.946 0.935 0.950 0.913 0.983 0.984 0.950 0.990
[22:12:49.348] <TB1> INFO: non-linearity RMS: 0.005 0.114 0.004 0.175 0.004 0.046 0.144 0.127 0.056 0.084 0.122 0.141 0.004 0.003 0.050 0.169
[22:12:49.348] <TB1> INFO: write gain/ped parameters into /home/l_tester/david/FullQualification/data/M1025_FullQualification_2016-10-19_19h52m_1476899523//001_Fulltest_m20//phCalibrationFitErr35_C0.dat
[22:12:49.373] <TB1> INFO: write gain/ped parameters into /home/l_tester/david/FullQualification/data/M1025_FullQualification_2016-10-19_19h52m_1476899523//001_Fulltest_m20//phCalibrationFitErr35_C1.dat
[22:12:49.404] <TB1> INFO: write gain/ped parameters into /home/l_tester/david/FullQualification/data/M1025_FullQualification_2016-10-19_19h52m_1476899523//001_Fulltest_m20//phCalibrationFitErr35_C2.dat
[22:12:49.436] <TB1> INFO: write gain/ped parameters into /home/l_tester/david/FullQualification/data/M1025_FullQualification_2016-10-19_19h52m_1476899523//001_Fulltest_m20//phCalibrationFitErr35_C3.dat
[22:12:49.458] <TB1> INFO: write gain/ped parameters into /home/l_tester/david/FullQualification/data/M1025_FullQualification_2016-10-19_19h52m_1476899523//001_Fulltest_m20//phCalibrationFitErr35_C4.dat
[22:12:49.479] <TB1> INFO: write gain/ped parameters into /home/l_tester/david/FullQualification/data/M1025_FullQualification_2016-10-19_19h52m_1476899523//001_Fulltest_m20//phCalibrationFitErr35_C5.dat
[22:12:49.501] <TB1> INFO: write gain/ped parameters into /home/l_tester/david/FullQualification/data/M1025_FullQualification_2016-10-19_19h52m_1476899523//001_Fulltest_m20//phCalibrationFitErr35_C6.dat
[22:12:49.522] <TB1> INFO: write gain/ped parameters into /home/l_tester/david/FullQualification/data/M1025_FullQualification_2016-10-19_19h52m_1476899523//001_Fulltest_m20//phCalibrationFitErr35_C7.dat
[22:12:49.544] <TB1> INFO: write gain/ped parameters into /home/l_tester/david/FullQualification/data/M1025_FullQualification_2016-10-19_19h52m_1476899523//001_Fulltest_m20//phCalibrationFitErr35_C8.dat
[22:12:49.565] <TB1> INFO: write gain/ped parameters into /home/l_tester/david/FullQualification/data/M1025_FullQualification_2016-10-19_19h52m_1476899523//001_Fulltest_m20//phCalibrationFitErr35_C9.dat
[22:12:49.586] <TB1> INFO: write gain/ped parameters into /home/l_tester/david/FullQualification/data/M1025_FullQualification_2016-10-19_19h52m_1476899523//001_Fulltest_m20//phCalibrationFitErr35_C10.dat
[22:12:49.608] <TB1> INFO: write gain/ped parameters into /home/l_tester/david/FullQualification/data/M1025_FullQualification_2016-10-19_19h52m_1476899523//001_Fulltest_m20//phCalibrationFitErr35_C11.dat
[22:12:49.629] <TB1> INFO: write gain/ped parameters into /home/l_tester/david/FullQualification/data/M1025_FullQualification_2016-10-19_19h52m_1476899523//001_Fulltest_m20//phCalibrationFitErr35_C12.dat
[22:12:49.651] <TB1> INFO: write gain/ped parameters into /home/l_tester/david/FullQualification/data/M1025_FullQualification_2016-10-19_19h52m_1476899523//001_Fulltest_m20//phCalibrationFitErr35_C13.dat
[22:12:49.672] <TB1> INFO: write gain/ped parameters into /home/l_tester/david/FullQualification/data/M1025_FullQualification_2016-10-19_19h52m_1476899523//001_Fulltest_m20//phCalibrationFitErr35_C14.dat
[22:12:49.694] <TB1> INFO: write gain/ped parameters into /home/l_tester/david/FullQualification/data/M1025_FullQualification_2016-10-19_19h52m_1476899523//001_Fulltest_m20//phCalibrationFitErr35_C15.dat
[22:12:49.715] <TB1> INFO: PixTestGainPedestal::fullTest() done, duration: 247 seconds
[22:12:49.715] <TB1> INFO: Decoding statistics:
[22:12:49.715] <TB1> INFO: General information:
[22:12:49.715] <TB1> INFO: 16bit words read: 3327928
[22:12:49.715] <TB1> INFO: valid events total: 332800
[22:12:49.715] <TB1> INFO: empty events: 0
[22:12:49.715] <TB1> INFO: valid events with pixels: 332800
[22:12:49.715] <TB1> INFO: valid pixel hits: 665564
[22:12:49.715] <TB1> INFO: Event errors: 0
[22:12:49.715] <TB1> INFO: start marker: 0
[22:12:49.715] <TB1> INFO: stop marker: 0
[22:12:49.715] <TB1> INFO: overflow: 0
[22:12:49.715] <TB1> INFO: invalid 5bit words: 0
[22:12:49.715] <TB1> INFO: invalid XOR eye diagram: 0
[22:12:49.715] <TB1> INFO: frame (failed synchr.): 0
[22:12:49.715] <TB1> INFO: idle data (no TBM trl): 0
[22:12:49.715] <TB1> INFO: no data (only TBM hdr): 0
[22:12:49.715] <TB1> INFO: TBM errors: 0
[22:12:49.715] <TB1> INFO: flawed TBM headers: 0
[22:12:49.715] <TB1> INFO: flawed TBM trailers: 0
[22:12:49.715] <TB1> INFO: event ID mismatches: 0
[22:12:49.715] <TB1> INFO: ROC errors: 0
[22:12:49.715] <TB1> INFO: missing ROC header(s): 0
[22:12:49.715] <TB1> INFO: misplaced readback start: 0
[22:12:49.715] <TB1> INFO: Pixel decoding errors: 0
[22:12:49.715] <TB1> INFO: pixel data incomplete: 0
[22:12:49.715] <TB1> INFO: pixel address: 0
[22:12:49.715] <TB1> INFO: pulse height fill bit: 0
[22:12:49.715] <TB1> INFO: buffer corruption: 0
[22:12:49.736] <TB1> INFO: Decoding statistics:
[22:12:49.736] <TB1> INFO: General information:
[22:12:49.736] <TB1> INFO: 16bit words read: 3457344
[22:12:49.736] <TB1> INFO: valid events total: 353536
[22:12:49.736] <TB1> INFO: empty events: 18236
[22:12:49.736] <TB1> INFO: valid events with pixels: 335300
[22:12:49.736] <TB1> INFO: valid pixel hits: 668064
[22:12:49.736] <TB1> INFO: Event errors: 0
[22:12:49.736] <TB1> INFO: start marker: 0
[22:12:49.736] <TB1> INFO: stop marker: 0
[22:12:49.736] <TB1> INFO: overflow: 0
[22:12:49.736] <TB1> INFO: invalid 5bit words: 0
[22:12:49.736] <TB1> INFO: invalid XOR eye diagram: 0
[22:12:49.736] <TB1> INFO: frame (failed synchr.): 0
[22:12:49.736] <TB1> INFO: idle data (no TBM trl): 0
[22:12:49.736] <TB1> INFO: no data (only TBM hdr): 0
[22:12:49.736] <TB1> INFO: TBM errors: 0
[22:12:49.736] <TB1> INFO: flawed TBM headers: 0
[22:12:49.736] <TB1> INFO: flawed TBM trailers: 0
[22:12:49.737] <TB1> INFO: event ID mismatches: 0
[22:12:49.737] <TB1> INFO: ROC errors: 0
[22:12:49.737] <TB1> INFO: missing ROC header(s): 0
[22:12:49.737] <TB1> INFO: misplaced readback start: 0
[22:12:49.737] <TB1> INFO: Pixel decoding errors: 0
[22:12:49.737] <TB1> INFO: pixel data incomplete: 0
[22:12:49.737] <TB1> INFO: pixel address: 0
[22:12:49.737] <TB1> INFO: pulse height fill bit: 0
[22:12:49.737] <TB1> INFO: buffer corruption: 0
[22:12:49.737] <TB1> INFO: enter test to run
[22:12:49.737] <TB1> INFO: test: exit no parameter change
[22:12:49.921] <TB1> QUIET: Connection to board 154 closed.
[22:12:49.926] <TB1> INFO: pXar: this is the end, my friend