[13:27:07.145] <TB0> INFO: *** Welcome to pxar ***
[13:27:07.145] <TB0> INFO: *** Today: 2016/10/25
[13:27:07.150] <TB0> INFO: *** Version: c8ba-dirty
[13:27:07.150] <TB0> INFO: readRocDacs: /home/l_tester/david/FullQualification/data/M1064_FullQualification_2016-10-25_13h17m_1477394256//000_Fulltest_m20//dacParameters_C0.dat .. /home/l_tester/david/FullQualification/data/M1064_FullQualification_2016-10-25_13h17m_1477394256//000_Fulltest_m20//dacParameters_C15.dat
[13:27:07.151] <TB0> INFO: readTbmDacs: /home/l_tester/david/FullQualification/data/M1064_FullQualification_2016-10-25_13h17m_1477394256//000_Fulltest_m20//tbmParameters_C0a.dat .. /home/l_tester/david/FullQualification/data/M1064_FullQualification_2016-10-25_13h17m_1477394256//000_Fulltest_m20//tbmParameters_C1b.dat
[13:27:07.151] <TB0> INFO: readMaskFile: /home/l_tester/david/FullQualification/data/M1064_FullQualification_2016-10-25_13h17m_1477394256//000_Fulltest_m20//defaultMaskFile.dat
[13:27:07.151] <TB0> INFO: readTrimFile: /home/l_tester/david/FullQualification/data/M1064_FullQualification_2016-10-25_13h17m_1477394256//000_Fulltest_m20//trimParameters_C0.dat .. /home/l_tester/david/FullQualification/data/M1064_FullQualification_2016-10-25_13h17m_1477394256//000_Fulltest_m20//trimParameters_C15.dat
[13:27:07.211] <TB0> INFO: clk: 4
[13:27:07.211] <TB0> INFO: ctr: 4
[13:27:07.211] <TB0> INFO: sda: 19
[13:27:07.211] <TB0> INFO: tin: 9
[13:27:07.211] <TB0> INFO: level: 15
[13:27:07.211] <TB0> INFO: triggerdelay: 0
[13:27:07.211] <TB0> QUIET: Instanciating API for pxar v2.7.6+55~gafdbfd9
[13:27:07.211] <TB0> INFO: Log level: INFO
[13:27:07.220] <TB0> INFO: Found DTB DTB_WRQ4OZ
[13:27:07.230] <TB0> QUIET: Connection to board DTB_WRQ4OZ opened.
[13:27:07.232] <TB0> INFO: DTB startup information
--- DTB info------------------------------------------
Board id: 71
HW version: DTB1.2
FW version: 4.6
SW version: 4.7
Options:
USB id: DTB_WRQ4OZ
MAC address: 40D855118047
Hostname: pixelDTB071
Comment:
------------------------------------------------------
[13:27:07.233] <TB0> INFO: RPC call hashes of host and DTB match: 486171790
[13:27:08.722] <TB0> INFO: DUT info:
[13:27:08.722] <TB0> INFO: The DUT currently contains the following objects:
[13:27:08.722] <TB0> INFO: 4 TBM Cores tbm10c (4 ON)
[13:27:08.722] <TB0> INFO: TBM Core alpha (0): 7 registers set
[13:27:08.722] <TB0> INFO: TBM Core beta (1): 7 registers set
[13:27:08.722] <TB0> INFO: TBM Core alpha (2): 7 registers set
[13:27:08.722] <TB0> INFO: TBM Core beta (3): 7 registers set
[13:27:08.722] <TB0> INFO: 16 ROCs proc600 (16 ON) with 4160 pixelConfigs
[13:27:08.722] <TB0> INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[13:27:08.722] <TB0> INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[13:27:08.722] <TB0> INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[13:27:08.722] <TB0> INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[13:27:08.722] <TB0> INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[13:27:08.722] <TB0> INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[13:27:08.722] <TB0> INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[13:27:08.722] <TB0> INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[13:27:08.722] <TB0> INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[13:27:08.722] <TB0> INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[13:27:08.722] <TB0> INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[13:27:08.722] <TB0> INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[13:27:08.722] <TB0> INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[13:27:08.722] <TB0> INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[13:27:08.722] <TB0> INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[13:27:08.722] <TB0> INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[13:27:09.123] <TB0> INFO: enter 'restricted' command line mode
[13:27:09.123] <TB0> INFO: enter test to run
[13:27:09.123] <TB0> INFO: test: pretest no parameter change
[13:27:09.123] <TB0> INFO: running: pretest
[13:27:09.127] <TB0> INFO: ######################################################################
[13:27:09.127] <TB0> INFO: PixTestPretest::doTest()
[13:27:09.127] <TB0> INFO: ######################################################################
[13:27:09.128] <TB0> INFO: ----------------------------------------------------------------------
[13:27:09.128] <TB0> INFO: PixTestPretest::programROC()
[13:27:09.128] <TB0> INFO: ----------------------------------------------------------------------
[13:27:27.142] <TB0> INFO: PixTestPretest::programROC() done: ROCs are all programmable
[13:27:27.142] <TB0> INFO: IA differences per ROC: 20.9 19.3 17.7 21.7 20.9 18.5 17.7 19.3 22.5 20.1 19.3 21.7 19.3 20.9 19.3 21.7
[13:27:27.204] <TB0> INFO: ----------------------------------------------------------------------
[13:27:27.204] <TB0> INFO: PixTestPretest::setVana() target Ia = 24 mA/ROC
[13:27:27.204] <TB0> INFO: ----------------------------------------------------------------------
[13:27:35.094] <TB0> INFO: PixTestPretest::setVana() done, Module Ia 381.9 mA = 23.8688 mA/ROC
[13:27:35.094] <TB0> INFO: i(loss) [mA/ROC]: 18.5 18.5 18.5 18.5 18.5 18.5 17.7 18.5 17.7 18.5 17.7 18.5 18.5 18.5 18.5 18.5
[13:27:35.123] <TB0> INFO: ----------------------------------------------------------------------
[13:27:35.123] <TB0> INFO: PixTestPretest::findTiming()
[13:27:35.123] <TB0> INFO: ----------------------------------------------------------------------
[13:27:35.123] <TB0> INFO: PixTestCmd::init()

[13:28:07.714] <TB0> INFO: TBM phases: 160MHz: 0, 400MHz: 2, TBM delays: ROC(0/1):5, header/trailer: 1, token: 1
[13:28:07.714] <TB0> INFO: (success/tries = 100/100), width = 3
[13:28:09.209] <TB0> INFO: ----------------------------------------------------------------------
[13:28:09.209] <TB0> INFO: PixTestPretest::findWorkingPixel()
[13:28:09.209] <TB0> INFO: ----------------------------------------------------------------------
[13:28:09.305] <TB0> INFO: Expecting 231680 events.
[13:28:19.393] <TB0> INFO: 231680 events read in total (9496ms).
[13:28:19.403] <TB0> INFO: Test took 10188ms.
[13:28:19.654] <TB0> INFO: Found working pixel in all ROCs: col/row = 12/22
[13:28:19.689] <TB0> INFO: ----------------------------------------------------------------------
[13:28:19.689] <TB0> INFO: PixTestPretest::setVthrCompCalDel()
[13:28:19.689] <TB0> INFO: ----------------------------------------------------------------------
[13:28:19.784] <TB0> INFO: Expecting 231680 events.
[13:28:29.903] <TB0> INFO: 231680 events read in total (9527ms).
[13:28:29.911] <TB0> INFO: Test took 10216ms.
[13:28:30.181] <TB0> INFO: PixTestPretest::setVthrCompCalDel() done
[13:28:30.181] <TB0> INFO: CalDel: 98 106 97 125 113 105 103 102 126 111 122 116 98 86 79 115
[13:28:30.181] <TB0> INFO: VthrComp: 51 51 51 51 51 51 51 51 51 51 51 51 51 51 51 51
[13:28:30.185] <TB0> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1064_FullQualification_2016-10-25_13h17m_1477394256//000_Fulltest_m20//dacParameters_C0.dat
[13:28:30.186] <TB0> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1064_FullQualification_2016-10-25_13h17m_1477394256//000_Fulltest_m20//dacParameters_C1.dat
[13:28:30.186] <TB0> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1064_FullQualification_2016-10-25_13h17m_1477394256//000_Fulltest_m20//dacParameters_C2.dat
[13:28:30.186] <TB0> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1064_FullQualification_2016-10-25_13h17m_1477394256//000_Fulltest_m20//dacParameters_C3.dat
[13:28:30.186] <TB0> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1064_FullQualification_2016-10-25_13h17m_1477394256//000_Fulltest_m20//dacParameters_C4.dat
[13:28:30.187] <TB0> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1064_FullQualification_2016-10-25_13h17m_1477394256//000_Fulltest_m20//dacParameters_C5.dat
[13:28:30.187] <TB0> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1064_FullQualification_2016-10-25_13h17m_1477394256//000_Fulltest_m20//dacParameters_C6.dat
[13:28:30.187] <TB0> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1064_FullQualification_2016-10-25_13h17m_1477394256//000_Fulltest_m20//dacParameters_C7.dat
[13:28:30.187] <TB0> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1064_FullQualification_2016-10-25_13h17m_1477394256//000_Fulltest_m20//dacParameters_C8.dat
[13:28:30.187] <TB0> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1064_FullQualification_2016-10-25_13h17m_1477394256//000_Fulltest_m20//dacParameters_C9.dat
[13:28:30.187] <TB0> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1064_FullQualification_2016-10-25_13h17m_1477394256//000_Fulltest_m20//dacParameters_C10.dat
[13:28:30.187] <TB0> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1064_FullQualification_2016-10-25_13h17m_1477394256//000_Fulltest_m20//dacParameters_C11.dat
[13:28:30.187] <TB0> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1064_FullQualification_2016-10-25_13h17m_1477394256//000_Fulltest_m20//dacParameters_C12.dat
[13:28:30.188] <TB0> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1064_FullQualification_2016-10-25_13h17m_1477394256//000_Fulltest_m20//dacParameters_C13.dat
[13:28:30.188] <TB0> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1064_FullQualification_2016-10-25_13h17m_1477394256//000_Fulltest_m20//dacParameters_C14.dat
[13:28:30.188] <TB0> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1064_FullQualification_2016-10-25_13h17m_1477394256//000_Fulltest_m20//dacParameters_C15.dat
[13:28:30.188] <TB0> INFO: write tbm parameters into /home/l_tester/david/FullQualification/data/M1064_FullQualification_2016-10-25_13h17m_1477394256//000_Fulltest_m20//tbmParameters_C0a.dat
[13:28:30.188] <TB0> INFO: write tbm parameters into /home/l_tester/david/FullQualification/data/M1064_FullQualification_2016-10-25_13h17m_1477394256//000_Fulltest_m20//tbmParameters_C0b.dat
[13:28:30.188] <TB0> INFO: write tbm parameters into /home/l_tester/david/FullQualification/data/M1064_FullQualification_2016-10-25_13h17m_1477394256//000_Fulltest_m20//tbmParameters_C1a.dat
[13:28:30.188] <TB0> INFO: write tbm parameters into /home/l_tester/david/FullQualification/data/M1064_FullQualification_2016-10-25_13h17m_1477394256//000_Fulltest_m20//tbmParameters_C1b.dat
[13:28:30.188] <TB0> INFO: PixTestPretest::doTest() done, duration: 81 seconds
[13:28:30.243] <TB0> INFO: enter test to run
[13:28:30.243] <TB0> INFO: test: FullTest no parameter change
[13:28:30.243] <TB0> INFO: running: fulltest
[13:28:30.243] <TB0> INFO: ######################################################################
[13:28:30.243] <TB0> INFO: PixTestFullTest::doTest()
[13:28:30.243] <TB0> INFO: ######################################################################
[13:28:30.244] <TB0> INFO: ######################################################################
[13:28:30.244] <TB0> INFO: PixTestAlive::doTest()
[13:28:30.244] <TB0> INFO: ######################################################################
[13:28:30.246] <TB0> INFO: ----------------------------------------------------------------------
[13:28:30.246] <TB0> INFO: PixTestAlive::aliveTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[13:28:30.246] <TB0> INFO: ----------------------------------------------------------------------
[13:28:30.492] <TB0> INFO: Expecting 41600 events.
[13:28:34.027] <TB0> INFO: 41600 events read in total (2944ms).
[13:28:34.028] <TB0> INFO: Test took 3781ms.
[13:28:34.260] <TB0> INFO: PixTestAlive::aliveTest() done
[13:28:34.260] <TB0> INFO: number of dead pixels (per ROC): 0 0 0 0 0 0 0 0 1 0 0 0 0 0 0 0
[13:28:34.262] <TB0> INFO: ----------------------------------------------------------------------
[13:28:34.262] <TB0> INFO: PixTestAlive::maskTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[13:28:34.262] <TB0> INFO: ----------------------------------------------------------------------
[13:28:34.504] <TB0> INFO: Expecting 41600 events.
[13:28:37.489] <TB0> INFO: 41600 events read in total (2393ms).
[13:28:37.489] <TB0> INFO: Test took 3225ms.
[13:28:37.489] <TB0> INFO: mask vs. old pixelAlive PixelAlive_C0_V0 .. PixelAlive_C15_V0
[13:28:37.732] <TB0> INFO: PixTestAlive::maskTest() done
[13:28:37.732] <TB0> INFO: number of mask-defect pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[13:28:37.734] <TB0> INFO: ----------------------------------------------------------------------
[13:28:37.734] <TB0> INFO: PixTestAlive::addressDecodingTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[13:28:37.734] <TB0> INFO: ----------------------------------------------------------------------
[13:28:37.976] <TB0> INFO: Expecting 41600 events.
[13:28:41.469] <TB0> INFO: 41600 events read in total (2901ms).
[13:28:41.470] <TB0> INFO: Test took 3734ms.
[13:28:41.705] <TB0> INFO: PixTestAlive::addressDecodingTest() done
[13:28:41.705] <TB0> INFO: number of address-decoding pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[13:28:41.705] <TB0> INFO: PixTestAlive::doTest() done, duration: 11 seconds
[13:28:41.705] <TB0> INFO: Decoding statistics:
[13:28:41.705] <TB0> INFO: General information:
[13:28:41.705] <TB0> INFO: 16bit words read: 0
[13:28:41.705] <TB0> INFO: valid events total: 0
[13:28:41.705] <TB0> INFO: empty events: 0
[13:28:41.705] <TB0> INFO: valid events with pixels: 0
[13:28:41.705] <TB0> INFO: valid pixel hits: 0
[13:28:41.705] <TB0> INFO: Event errors: 0
[13:28:41.705] <TB0> INFO: start marker: 0
[13:28:41.705] <TB0> INFO: stop marker: 0
[13:28:41.705] <TB0> INFO: overflow: 0
[13:28:41.705] <TB0> INFO: invalid 5bit words: 0
[13:28:41.705] <TB0> INFO: invalid XOR eye diagram: 0
[13:28:41.705] <TB0> INFO: frame (failed synchr.): 0
[13:28:41.705] <TB0> INFO: idle data (no TBM trl): 0
[13:28:41.705] <TB0> INFO: no data (only TBM hdr): 0
[13:28:41.705] <TB0> INFO: TBM errors: 0
[13:28:41.705] <TB0> INFO: flawed TBM headers: 0
[13:28:41.705] <TB0> INFO: flawed TBM trailers: 0
[13:28:41.705] <TB0> INFO: event ID mismatches: 0
[13:28:41.705] <TB0> INFO: ROC errors: 0
[13:28:41.705] <TB0> INFO: missing ROC header(s): 0
[13:28:41.705] <TB0> INFO: misplaced readback start: 0
[13:28:41.705] <TB0> INFO: Pixel decoding errors: 0
[13:28:41.705] <TB0> INFO: pixel data incomplete: 0
[13:28:41.705] <TB0> INFO: pixel address: 0
[13:28:41.705] <TB0> INFO: pulse height fill bit: 0
[13:28:41.705] <TB0> INFO: buffer corruption: 0
[13:28:41.712] <TB0> INFO: readReadbackCal: /home/l_tester/david/FullQualification/data/M1064_FullQualification_2016-10-25_13h17m_1477394256//000_Fulltest_m20//readbackCal_C0.dat .. /home/l_tester/david/FullQualification/data/M1064_FullQualification_2016-10-25_13h17m_1477394256//000_Fulltest_m20//readbackCal_C15.dat
[13:28:41.712] <TB0> INFO: readGainPedestalParameters /home/l_tester/david/FullQualification/data/M1064_FullQualification_2016-10-25_13h17m_1477394256//000_Fulltest_m20//phCalibrationFitErr_C0.dat .. /home/l_tester/david/FullQualification/data/M1064_FullQualification_2016-10-25_13h17m_1477394256//000_Fulltest_m20//phCalibrationFitErr_C15.dat

[13:28:41.712] <TB0> INFO: ######################################################################
[13:28:41.712] <TB0> INFO: PixTestReadback::doTest()
[13:28:41.712] <TB0> INFO: ######################################################################
[13:28:41.712] <TB0> INFO: ----------------------------------------------------------------------
[13:28:41.712] <TB0> INFO: PixTestReadback::CalibrateVd()
[13:28:41.712] <TB0> INFO: ----------------------------------------------------------------------
[13:28:51.675] <TB0> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1064_FullQualification_2016-10-25_13h17m_1477394256//000_Fulltest_m20//readbackCal_C0.dat
[13:28:51.675] <TB0> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1064_FullQualification_2016-10-25_13h17m_1477394256//000_Fulltest_m20//readbackCal_C1.dat
[13:28:51.675] <TB0> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1064_FullQualification_2016-10-25_13h17m_1477394256//000_Fulltest_m20//readbackCal_C2.dat
[13:28:51.675] <TB0> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1064_FullQualification_2016-10-25_13h17m_1477394256//000_Fulltest_m20//readbackCal_C3.dat
[13:28:51.675] <TB0> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1064_FullQualification_2016-10-25_13h17m_1477394256//000_Fulltest_m20//readbackCal_C4.dat
[13:28:51.675] <TB0> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1064_FullQualification_2016-10-25_13h17m_1477394256//000_Fulltest_m20//readbackCal_C5.dat
[13:28:51.675] <TB0> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1064_FullQualification_2016-10-25_13h17m_1477394256//000_Fulltest_m20//readbackCal_C6.dat
[13:28:51.676] <TB0> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1064_FullQualification_2016-10-25_13h17m_1477394256//000_Fulltest_m20//readbackCal_C7.dat
[13:28:51.676] <TB0> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1064_FullQualification_2016-10-25_13h17m_1477394256//000_Fulltest_m20//readbackCal_C8.dat
[13:28:51.676] <TB0> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1064_FullQualification_2016-10-25_13h17m_1477394256//000_Fulltest_m20//readbackCal_C9.dat
[13:28:51.676] <TB0> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1064_FullQualification_2016-10-25_13h17m_1477394256//000_Fulltest_m20//readbackCal_C10.dat
[13:28:51.676] <TB0> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1064_FullQualification_2016-10-25_13h17m_1477394256//000_Fulltest_m20//readbackCal_C11.dat
[13:28:51.676] <TB0> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1064_FullQualification_2016-10-25_13h17m_1477394256//000_Fulltest_m20//readbackCal_C12.dat
[13:28:51.676] <TB0> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1064_FullQualification_2016-10-25_13h17m_1477394256//000_Fulltest_m20//readbackCal_C13.dat
[13:28:51.676] <TB0> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1064_FullQualification_2016-10-25_13h17m_1477394256//000_Fulltest_m20//readbackCal_C14.dat
[13:28:51.676] <TB0> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1064_FullQualification_2016-10-25_13h17m_1477394256//000_Fulltest_m20//readbackCal_C15.dat
[13:28:51.705] <TB0> INFO: PixTestPattern:: pg_setup set to default.
[13:28:51.705] <TB0> INFO: ----------------------------------------------------------------------
[13:28:51.705] <TB0> INFO: PixTestReadback::CalibrateVa()
[13:28:51.705] <TB0> INFO: ----------------------------------------------------------------------
[13:29:01.631] <TB0> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1064_FullQualification_2016-10-25_13h17m_1477394256//000_Fulltest_m20//readbackCal_C0.dat
[13:29:01.631] <TB0> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1064_FullQualification_2016-10-25_13h17m_1477394256//000_Fulltest_m20//readbackCal_C1.dat
[13:29:01.631] <TB0> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1064_FullQualification_2016-10-25_13h17m_1477394256//000_Fulltest_m20//readbackCal_C2.dat
[13:29:01.631] <TB0> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1064_FullQualification_2016-10-25_13h17m_1477394256//000_Fulltest_m20//readbackCal_C3.dat
[13:29:01.631] <TB0> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1064_FullQualification_2016-10-25_13h17m_1477394256//000_Fulltest_m20//readbackCal_C4.dat
[13:29:01.631] <TB0> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1064_FullQualification_2016-10-25_13h17m_1477394256//000_Fulltest_m20//readbackCal_C5.dat
[13:29:01.631] <TB0> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1064_FullQualification_2016-10-25_13h17m_1477394256//000_Fulltest_m20//readbackCal_C6.dat
[13:29:01.631] <TB0> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1064_FullQualification_2016-10-25_13h17m_1477394256//000_Fulltest_m20//readbackCal_C7.dat
[13:29:01.631] <TB0> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1064_FullQualification_2016-10-25_13h17m_1477394256//000_Fulltest_m20//readbackCal_C8.dat
[13:29:01.631] <TB0> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1064_FullQualification_2016-10-25_13h17m_1477394256//000_Fulltest_m20//readbackCal_C9.dat
[13:29:01.631] <TB0> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1064_FullQualification_2016-10-25_13h17m_1477394256//000_Fulltest_m20//readbackCal_C10.dat
[13:29:01.631] <TB0> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1064_FullQualification_2016-10-25_13h17m_1477394256//000_Fulltest_m20//readbackCal_C11.dat
[13:29:01.631] <TB0> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1064_FullQualification_2016-10-25_13h17m_1477394256//000_Fulltest_m20//readbackCal_C12.dat
[13:29:01.631] <TB0> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1064_FullQualification_2016-10-25_13h17m_1477394256//000_Fulltest_m20//readbackCal_C13.dat
[13:29:01.631] <TB0> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1064_FullQualification_2016-10-25_13h17m_1477394256//000_Fulltest_m20//readbackCal_C14.dat
[13:29:01.632] <TB0> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1064_FullQualification_2016-10-25_13h17m_1477394256//000_Fulltest_m20//readbackCal_C15.dat
[13:29:01.660] <TB0> INFO: PixTestPattern:: pg_setup set to default.
[13:29:01.660] <TB0> INFO: ----------------------------------------------------------------------
[13:29:01.660] <TB0> INFO: PixTestReadback::readbackVbg()
[13:29:01.660] <TB0> INFO: ----------------------------------------------------------------------
[13:29:09.313] <TB0> INFO: PixTestPattern:: pg_setup set to default.
[13:29:09.313] <TB0> INFO: ----------------------------------------------------------------------
[13:29:09.313] <TB0> INFO: PixTestReadback::getCalibratedVbg()
[13:29:09.313] <TB0> INFO: ----------------------------------------------------------------------
[13:29:09.313] <TB0> INFO: Vbg will be calibrated using Vd calibration
[13:29:09.313] <TB0> INFO: /*/*/*/*::: ROC 0: uncalibrated Vbg = 143.3calibrated Vbg = 1.17188 :::*/*/*/*/
[13:29:09.313] <TB0> INFO: /*/*/*/*::: ROC 1: uncalibrated Vbg = 155.6calibrated Vbg = 1.17444 :::*/*/*/*/
[13:29:09.313] <TB0> INFO: /*/*/*/*::: ROC 2: uncalibrated Vbg = 155calibrated Vbg = 1.16496 :::*/*/*/*/
[13:29:09.313] <TB0> INFO: /*/*/*/*::: ROC 3: uncalibrated Vbg = 153.7calibrated Vbg = 1.15838 :::*/*/*/*/
[13:29:09.313] <TB0> INFO: /*/*/*/*::: ROC 4: uncalibrated Vbg = 154calibrated Vbg = 1.16649 :::*/*/*/*/
[13:29:09.313] <TB0> INFO: /*/*/*/*::: ROC 5: uncalibrated Vbg = 152calibrated Vbg = 1.16718 :::*/*/*/*/
[13:29:09.313] <TB0> INFO: /*/*/*/*::: ROC 6: uncalibrated Vbg = 150.8calibrated Vbg = 1.16805 :::*/*/*/*/
[13:29:09.314] <TB0> INFO: /*/*/*/*::: ROC 7: uncalibrated Vbg = 164.6calibrated Vbg = 1.16177 :::*/*/*/*/
[13:29:09.314] <TB0> INFO: /*/*/*/*::: ROC 8: uncalibrated Vbg = 152.6calibrated Vbg = 1.1643 :::*/*/*/*/
[13:29:09.314] <TB0> INFO: /*/*/*/*::: ROC 9: uncalibrated Vbg = 149.8calibrated Vbg = 1.16817 :::*/*/*/*/
[13:29:09.314] <TB0> INFO: /*/*/*/*::: ROC 10: uncalibrated Vbg = 148.1calibrated Vbg = 1.15688 :::*/*/*/*/
[13:29:09.314] <TB0> INFO: /*/*/*/*::: ROC 11: uncalibrated Vbg = 152.6calibrated Vbg = 1.15487 :::*/*/*/*/
[13:29:09.314] <TB0> INFO: /*/*/*/*::: ROC 12: uncalibrated Vbg = 143.6calibrated Vbg = 1.16135 :::*/*/*/*/
[13:29:09.314] <TB0> INFO: /*/*/*/*::: ROC 13: uncalibrated Vbg = 164.7calibrated Vbg = 1.16363 :::*/*/*/*/
[13:29:09.314] <TB0> INFO: /*/*/*/*::: ROC 14: uncalibrated Vbg = 158.9calibrated Vbg = 1.16457 :::*/*/*/*/
[13:29:09.314] <TB0> INFO: /*/*/*/*::: ROC 15: uncalibrated Vbg = 143.3calibrated Vbg = 1.1551 :::*/*/*/*/
[13:29:09.315] <TB0> INFO: ----------------------------------------------------------------------
[13:29:09.315] <TB0> INFO: PixTestReadback::CalibrateIa()
[13:29:09.316] <TB0> INFO: ----------------------------------------------------------------------
[13:31:50.157] <TB0> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1064_FullQualification_2016-10-25_13h17m_1477394256//000_Fulltest_m20//readbackCal_C0.dat
[13:31:50.157] <TB0> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1064_FullQualification_2016-10-25_13h17m_1477394256//000_Fulltest_m20//readbackCal_C1.dat
[13:31:50.157] <TB0> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1064_FullQualification_2016-10-25_13h17m_1477394256//000_Fulltest_m20//readbackCal_C2.dat
[13:31:50.157] <TB0> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1064_FullQualification_2016-10-25_13h17m_1477394256//000_Fulltest_m20//readbackCal_C3.dat
[13:31:50.157] <TB0> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1064_FullQualification_2016-10-25_13h17m_1477394256//000_Fulltest_m20//readbackCal_C4.dat
[13:31:50.157] <TB0> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1064_FullQualification_2016-10-25_13h17m_1477394256//000_Fulltest_m20//readbackCal_C5.dat
[13:31:50.157] <TB0> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1064_FullQualification_2016-10-25_13h17m_1477394256//000_Fulltest_m20//readbackCal_C6.dat
[13:31:50.157] <TB0> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1064_FullQualification_2016-10-25_13h17m_1477394256//000_Fulltest_m20//readbackCal_C7.dat
[13:31:50.158] <TB0> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1064_FullQualification_2016-10-25_13h17m_1477394256//000_Fulltest_m20//readbackCal_C8.dat
[13:31:50.158] <TB0> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1064_FullQualification_2016-10-25_13h17m_1477394256//000_Fulltest_m20//readbackCal_C9.dat
[13:31:50.158] <TB0> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1064_FullQualification_2016-10-25_13h17m_1477394256//000_Fulltest_m20//readbackCal_C10.dat
[13:31:50.158] <TB0> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1064_FullQualification_2016-10-25_13h17m_1477394256//000_Fulltest_m20//readbackCal_C11.dat
[13:31:50.158] <TB0> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1064_FullQualification_2016-10-25_13h17m_1477394256//000_Fulltest_m20//readbackCal_C12.dat
[13:31:50.158] <TB0> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1064_FullQualification_2016-10-25_13h17m_1477394256//000_Fulltest_m20//readbackCal_C13.dat
[13:31:50.158] <TB0> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1064_FullQualification_2016-10-25_13h17m_1477394256//000_Fulltest_m20//readbackCal_C14.dat
[13:31:50.158] <TB0> INFO: write readback calibration parameters into /home/l_tester/david/FullQualification/data/M1064_FullQualification_2016-10-25_13h17m_1477394256//000_Fulltest_m20//readbackCal_C15.dat
[13:31:50.190] <TB0> INFO: PixTestPattern:: pg_setup set to default.
[13:31:50.192] <TB0> INFO: PixTestReadback::doTest() done
[13:31:50.192] <TB0> INFO: Decoding statistics:
[13:31:50.192] <TB0> INFO: General information:
[13:31:50.192] <TB0> INFO: 16bit words read: 1536
[13:31:50.192] <TB0> INFO: valid events total: 256
[13:31:50.192] <TB0> INFO: empty events: 256
[13:31:50.192] <TB0> INFO: valid events with pixels: 0
[13:31:50.192] <TB0> INFO: valid pixel hits: 0
[13:31:50.192] <TB0> INFO: Event errors: 0
[13:31:50.192] <TB0> INFO: start marker: 0
[13:31:50.192] <TB0> INFO: stop marker: 0
[13:31:50.192] <TB0> INFO: overflow: 0
[13:31:50.192] <TB0> INFO: invalid 5bit words: 0
[13:31:50.192] <TB0> INFO: invalid XOR eye diagram: 0
[13:31:50.193] <TB0> INFO: frame (failed synchr.): 0
[13:31:50.193] <TB0> INFO: idle data (no TBM trl): 0
[13:31:50.193] <TB0> INFO: no data (only TBM hdr): 0
[13:31:50.193] <TB0> INFO: TBM errors: 0
[13:31:50.193] <TB0> INFO: flawed TBM headers: 0
[13:31:50.193] <TB0> INFO: flawed TBM trailers: 0
[13:31:50.193] <TB0> INFO: event ID mismatches: 0
[13:31:50.193] <TB0> INFO: ROC errors: 0
[13:31:50.193] <TB0> INFO: missing ROC header(s): 0
[13:31:50.193] <TB0> INFO: misplaced readback start: 0
[13:31:50.193] <TB0> INFO: Pixel decoding errors: 0
[13:31:50.193] <TB0> INFO: pixel data incomplete: 0
[13:31:50.193] <TB0> INFO: pixel address: 0
[13:31:50.193] <TB0> INFO: pulse height fill bit: 0
[13:31:50.193] <TB0> INFO: buffer corruption: 0
[13:31:50.243] <TB0> INFO: ######################################################################
[13:31:50.243] <TB0> INFO: PixTestBBMap::doTest() Ntrig = 5, VcalS = 250 (high range)
[13:31:50.243] <TB0> INFO: ######################################################################
[13:31:50.246] <TB0> INFO: ---> dac: VthrComp name: calSMap ntrig: 5 dacrange: 0 .. 149 (-1/-1) hits flags = 514 (plus default)
[13:31:50.259] <TB0> INFO: dacScan split into 1 runs with ntrig = 5
[13:31:50.259] <TB0> INFO: run 1 of 1
[13:31:50.495] <TB0> INFO: Expecting 3120000 events.
[13:32:22.392] <TB0> INFO: 665800 events read in total (31305ms).
[13:32:52.559] <TB0> INFO: 1327500 events read in total (61472ms).











[13:33:22.552] <TB0> INFO: 1986655 events read in total (91465ms).











[13:33:52.673] <TB0> INFO: 2645640 events read in total (121586ms).











[13:34:14.672] <TB0> INFO: 3120000 events read in total (143585ms).
[13:34:14.746] <TB0> INFO: Test took 144488ms.
[13:34:35.618] <TB0> INFO: PixTestBBMap::doTest() done with 1 decoding errors: , duration: 165 seconds
[13:34:35.618] <TB0> INFO: number of dead bumps (per ROC): 0 0 0 0 1 0 0 0 0 0 0 0 0 0 0 0
[13:34:35.618] <TB0> INFO: separation cut (per ROC): 106 105 105 106 111 105 100 103 100 90 97 105 99 106 105 89
[13:34:35.618] <TB0> INFO: Decoding statistics:
[13:34:35.619] <TB0> INFO: General information:
[13:34:35.619] <TB0> INFO: 16bit words read: 0
[13:34:35.619] <TB0> INFO: valid events total: 0
[13:34:35.619] <TB0> INFO: empty events: 0
[13:34:35.619] <TB0> INFO: valid events with pixels: 0
[13:34:35.619] <TB0> INFO: valid pixel hits: 0
[13:34:35.619] <TB0> INFO: Event errors: 0
[13:34:35.619] <TB0> INFO: start marker: 0
[13:34:35.619] <TB0> INFO: stop marker: 0
[13:34:35.619] <TB0> INFO: overflow: 0
[13:34:35.619] <TB0> INFO: invalid 5bit words: 0
[13:34:35.619] <TB0> INFO: invalid XOR eye diagram: 0
[13:34:35.619] <TB0> INFO: frame (failed synchr.): 0
[13:34:35.619] <TB0> INFO: idle data (no TBM trl): 0
[13:34:35.619] <TB0> INFO: no data (only TBM hdr): 0
[13:34:35.619] <TB0> INFO: TBM errors: 0
[13:34:35.619] <TB0> INFO: flawed TBM headers: 0
[13:34:35.619] <TB0> INFO: flawed TBM trailers: 0
[13:34:35.619] <TB0> INFO: event ID mismatches: 0
[13:34:35.619] <TB0> INFO: ROC errors: 0
[13:34:35.619] <TB0> INFO: missing ROC header(s): 0
[13:34:35.619] <TB0> INFO: misplaced readback start: 0
[13:34:35.619] <TB0> INFO: Pixel decoding errors: 0
[13:34:35.619] <TB0> INFO: pixel data incomplete: 0
[13:34:35.619] <TB0> INFO: pixel address: 0
[13:34:35.619] <TB0> INFO: pulse height fill bit: 0
[13:34:35.619] <TB0> INFO: buffer corruption: 0
[13:34:35.655] <TB0> INFO: ######################################################################
[13:34:35.655] <TB0> INFO: PixTestScurves::fullTest() ntrig = 50, dacs/step = -1, ntrig/step = -1
[13:34:35.655] <TB0> INFO: ######################################################################
[13:34:35.656] <TB0> INFO: ----------------------------------------------------------------------
[13:34:35.656] <TB0> INFO: PixTestScurves::scurves(Vcal), ntrig = 50, dacs/step = -1, ntrig/step = -1
[13:34:35.656] <TB0> INFO: ----------------------------------------------------------------------
[13:34:35.656] <TB0> INFO: ---> dac: Vcal name: scurveVcal ntrig: 50 dacrange: 25 .. 200 (-1/-1) hits flags = 528 (plus default)
[13:34:35.669] <TB0> INFO: dacScan split into 1 runs with ntrig = 50
[13:34:35.669] <TB0> INFO: run 1 of 1
[13:34:35.945] <TB0> INFO: Expecting 36608000 events.
[13:35:00.402] <TB0> INFO: 653800 events read in total (23865ms).
[13:35:22.422] <TB0> INFO: 1299100 events read in total (45885ms).
[13:35:44.873] <TB0> INFO: 1942700 events read in total (68336ms).
[13:36:07.416] <TB0> INFO: 2586250 events read in total (90879ms).
[13:36:29.876] <TB0> INFO: 3229300 events read in total (113339ms).
[13:36:52.169] <TB0> INFO: 3872300 events read in total (135632ms).
[13:37:14.811] <TB0> INFO: 4515550 events read in total (158274ms).
[13:37:37.516] <TB0> INFO: 5157000 events read in total (180979ms).
[13:38:00.300] <TB0> INFO: 5798550 events read in total (203763ms).
[13:38:22.732] <TB0> INFO: 6442050 events read in total (226195ms).
[13:38:45.102] <TB0> INFO: 7083050 events read in total (248565ms).
[13:39:07.774] <TB0> INFO: 7725450 events read in total (271237ms).
[13:39:30.294] <TB0> INFO: 8367100 events read in total (293757ms).
[13:39:52.912] <TB0> INFO: 9007500 events read in total (316375ms).
[13:40:15.410] <TB0> INFO: 9647100 events read in total (338873ms).
[13:40:37.761] <TB0> INFO: 10286300 events read in total (361224ms).
[13:41:00.614] <TB0> INFO: 10926300 events read in total (384077ms).
[13:41:23.316] <TB0> INFO: 11568250 events read in total (406779ms).
[13:41:46.345] <TB0> INFO: 12210350 events read in total (429808ms).
[13:42:08.855] <TB0> INFO: 12853050 events read in total (452318ms).
[13:42:31.832] <TB0> INFO: 13497300 events read in total (475295ms).
[13:42:54.561] <TB0> INFO: 14140250 events read in total (498024ms).
[13:43:17.673] <TB0> INFO: 14782500 events read in total (521136ms).
[13:43:40.295] <TB0> INFO: 15423600 events read in total (543758ms).
[13:44:02.830] <TB0> INFO: 16067350 events read in total (566293ms).
[13:44:25.626] <TB0> INFO: 16712650 events read in total (589089ms).
[13:44:48.352] <TB0> INFO: 17353550 events read in total (611815ms).
[13:45:10.983] <TB0> INFO: 17994350 events read in total (634446ms).
[13:45:33.789] <TB0> INFO: 18635150 events read in total (657252ms).
[13:45:56.215] <TB0> INFO: 19274300 events read in total (679678ms).
[13:46:18.812] <TB0> INFO: 19912300 events read in total (702275ms).
[13:46:41.530] <TB0> INFO: 20550400 events read in total (724993ms).
[13:47:04.508] <TB0> INFO: 21189350 events read in total (747971ms).
[13:47:27.321] <TB0> INFO: 21828500 events read in total (770784ms).
[13:47:50.077] <TB0> INFO: 22466150 events read in total (793540ms).
[13:48:12.921] <TB0> INFO: 23104850 events read in total (816384ms).
[13:48:35.414] <TB0> INFO: 23741750 events read in total (838877ms).
[13:48:58.314] <TB0> INFO: 24380700 events read in total (861777ms).
[13:49:21.107] <TB0> INFO: 25018300 events read in total (884570ms).
[13:49:43.955] <TB0> INFO: 25656150 events read in total (907418ms).
[13:50:06.979] <TB0> INFO: 26291650 events read in total (930442ms).
[13:50:29.883] <TB0> INFO: 26927050 events read in total (953346ms).
[13:50:52.867] <TB0> INFO: 27565050 events read in total (976330ms).
[13:51:15.302] <TB0> INFO: 28200400 events read in total (998765ms).
[13:51:37.674] <TB0> INFO: 28836100 events read in total (1021137ms).
[13:51:59.917] <TB0> INFO: 29470950 events read in total (1043380ms).
[13:52:22.231] <TB0> INFO: 30103250 events read in total (1065694ms).
[13:52:44.791] <TB0> INFO: 30737600 events read in total (1088254ms).
[13:53:07.268] <TB0> INFO: 31373800 events read in total (1110731ms).
[13:53:29.673] <TB0> INFO: 32010000 events read in total (1133136ms).
[13:53:51.993] <TB0> INFO: 32645050 events read in total (1155456ms).
[13:54:14.358] <TB0> INFO: 33281550 events read in total (1177821ms).
[13:54:36.711] <TB0> INFO: 33919600 events read in total (1200174ms).
[13:54:59.299] <TB0> INFO: 34556100 events read in total (1222762ms).
[13:55:21.833] <TB0> INFO: 35193950 events read in total (1245296ms).
[13:55:44.242] <TB0> INFO: 35832150 events read in total (1267705ms).
[13:56:06.671] <TB0> INFO: 36478450 events read in total (1290134ms).
[13:56:11.356] <TB0> INFO: 36608000 events read in total (1294819ms).
[13:56:11.462] <TB0> INFO: Test took 1295792ms.
[13:56:11.940] <TB0> INFO: dumping ASCII scurve output file: SCurveData
[13:56:13.429] <TB0> INFO: dumping ASCII scurve output file: SCurveData
[13:56:15.030] <TB0> INFO: dumping ASCII scurve output file: SCurveData
[13:56:16.639] <TB0> INFO: dumping ASCII scurve output file: SCurveData
[13:56:18.446] <TB0> INFO: dumping ASCII scurve output file: SCurveData
[13:56:20.428] <TB0> INFO: dumping ASCII scurve output file: SCurveData
[13:56:22.391] <TB0> INFO: dumping ASCII scurve output file: SCurveData
[13:56:24.454] <TB0> INFO: dumping ASCII scurve output file: SCurveData
[13:56:26.122] <TB0> INFO: dumping ASCII scurve output file: SCurveData
[13:56:27.881] <TB0> INFO: dumping ASCII scurve output file: SCurveData
[13:56:29.462] <TB0> INFO: dumping ASCII scurve output file: SCurveData
[13:56:31.239] <TB0> INFO: dumping ASCII scurve output file: SCurveData
[13:56:33.406] <TB0> INFO: dumping ASCII scurve output file: SCurveData
[13:56:35.675] <TB0> INFO: dumping ASCII scurve output file: SCurveData
[13:56:37.206] <TB0> INFO: dumping ASCII scurve output file: SCurveData
[13:56:39.168] <TB0> INFO: dumping ASCII scurve output file: SCurveData
[13:56:41.371] <TB0> INFO: PixTestScurves::scurves() done
[13:56:41.371] <TB0> INFO: Vcal mean: 113.27 106.01 107.98 113.13 103.75 113.80 99.50 109.13 101.66 95.77 100.25 103.96 98.87 107.56 107.82 96.50
[13:56:41.371] <TB0> INFO: Vcal RMS: 5.13 5.66 4.70 5.22 4.84 5.09 5.33 4.44 5.41 5.30 5.46 5.09 5.09 4.89 4.97 5.24
[13:56:41.371] <TB0> INFO: PixTestScurves::fullTest() done, duration: 1325 seconds
[13:56:41.371] <TB0> INFO: Decoding statistics:
[13:56:41.371] <TB0> INFO: General information:
[13:56:41.371] <TB0> INFO: 16bit words read: 0
[13:56:41.371] <TB0> INFO: valid events total: 0
[13:56:41.371] <TB0> INFO: empty events: 0
[13:56:41.371] <TB0> INFO: valid events with pixels: 0
[13:56:41.371] <TB0> INFO: valid pixel hits: 0
[13:56:41.371] <TB0> INFO: Event errors: 0
[13:56:41.371] <TB0> INFO: start marker: 0
[13:56:41.371] <TB0> INFO: stop marker: 0
[13:56:41.371] <TB0> INFO: overflow: 0
[13:56:41.371] <TB0> INFO: invalid 5bit words: 0
[13:56:41.371] <TB0> INFO: invalid XOR eye diagram: 0
[13:56:41.371] <TB0> INFO: frame (failed synchr.): 0
[13:56:41.371] <TB0> INFO: idle data (no TBM trl): 0
[13:56:41.371] <TB0> INFO: no data (only TBM hdr): 0
[13:56:41.371] <TB0> INFO: TBM errors: 0
[13:56:41.371] <TB0> INFO: flawed TBM headers: 0
[13:56:41.371] <TB0> INFO: flawed TBM trailers: 0
[13:56:41.371] <TB0> INFO: event ID mismatches: 0
[13:56:41.371] <TB0> INFO: ROC errors: 0
[13:56:41.371] <TB0> INFO: missing ROC header(s): 0
[13:56:41.371] <TB0> INFO: misplaced readback start: 0
[13:56:41.371] <TB0> INFO: Pixel decoding errors: 0
[13:56:41.371] <TB0> INFO: pixel data incomplete: 0
[13:56:41.371] <TB0> INFO: pixel address: 0
[13:56:41.371] <TB0> INFO: pulse height fill bit: 0
[13:56:41.371] <TB0> INFO: buffer corruption: 0
[13:56:41.442] <TB0> INFO: ######################################################################
[13:56:41.442] <TB0> INFO: PixTestTrim::doTest()
[13:56:41.442] <TB0> INFO: ######################################################################
[13:56:41.444] <TB0> INFO: ----------------------------------------------------------------------
[13:56:41.444] <TB0> INFO: PixTestTrim::trimTest() ntrig = 8, vcal = 35
[13:56:41.444] <TB0> INFO: ----------------------------------------------------------------------
[13:56:41.487] <TB0> INFO: ---> VthrComp thr map (minimal VthrComp)
[13:56:41.487] <TB0> INFO: ---> dac: vthrcomp name: TrimThr0 ntrig: 8 dacrange: 10 .. 160 (-1/-1) hits flags = 528 (plus default)
[13:56:41.500] <TB0> INFO: dacScan split into 1 runs with ntrig = 8
[13:56:41.500] <TB0> INFO: run 1 of 1
[13:56:41.743] <TB0> INFO: Expecting 5025280 events.
[13:57:11.622] <TB0> INFO: 811488 events read in total (29275ms).
[13:57:41.119] <TB0> INFO: 1618872 events read in total (58772ms).
[13:58:10.731] <TB0> INFO: 2423912 events read in total (88385ms).
[13:58:40.357] <TB0> INFO: 3225200 events read in total (118010ms).
[13:59:10.050] <TB0> INFO: 4024608 events read in total (147703ms).
[13:59:39.924] <TB0> INFO: 4822600 events read in total (177577ms).
[13:59:47.892] <TB0> INFO: 5025280 events read in total (185545ms).
[13:59:47.964] <TB0> INFO: Test took 186464ms.
[14:00:06.156] <TB0> INFO: ROC 0 VthrComp = 122
[14:00:06.156] <TB0> INFO: ROC 1 VthrComp = 109
[14:00:06.156] <TB0> INFO: ROC 2 VthrComp = 115
[14:00:06.156] <TB0> INFO: ROC 3 VthrComp = 121
[14:00:06.156] <TB0> INFO: ROC 4 VthrComp = 116
[14:00:06.156] <TB0> INFO: ROC 5 VthrComp = 119
[14:00:06.156] <TB0> INFO: ROC 6 VthrComp = 103
[14:00:06.157] <TB0> INFO: ROC 7 VthrComp = 112
[14:00:06.159] <TB0> INFO: ROC 8 VthrComp = 106
[14:00:06.159] <TB0> INFO: ROC 9 VthrComp = 100
[14:00:06.159] <TB0> INFO: ROC 10 VthrComp = 103
[14:00:06.159] <TB0> INFO: ROC 11 VthrComp = 111
[14:00:06.159] <TB0> INFO: ROC 12 VthrComp = 109
[14:00:06.159] <TB0> INFO: ROC 13 VthrComp = 116
[14:00:06.160] <TB0> INFO: ROC 14 VthrComp = 117
[14:00:06.160] <TB0> INFO: ROC 15 VthrComp = 101
[14:00:06.397] <TB0> INFO: Expecting 41600 events.
[14:00:09.984] <TB0> INFO: 41600 events read in total (2995ms).
[14:00:09.985] <TB0> INFO: Test took 3824ms.
[14:00:09.996] <TB0> INFO: ---> Vcal thr map (pixel with maximum Vcal thr)
[14:00:09.996] <TB0> INFO: ---> dac: vcal name: TrimThr1 ntrig: 8 dacrange: 10 .. 160 (-1/-1) hits flags = 528 (plus default)
[14:00:10.009] <TB0> INFO: dacScan split into 1 runs with ntrig = 8
[14:00:10.009] <TB0> INFO: run 1 of 1
[14:00:10.287] <TB0> INFO: Expecting 5025280 events.
[14:00:36.232] <TB0> INFO: 589232 events read in total (25353ms).
[14:01:01.740] <TB0> INFO: 1177488 events read in total (50861ms).
[14:01:27.232] <TB0> INFO: 1765472 events read in total (76353ms).
[14:01:53.211] <TB0> INFO: 2353064 events read in total (102332ms).
[14:02:18.876] <TB0> INFO: 2939272 events read in total (127997ms).
[14:02:44.443] <TB0> INFO: 3524352 events read in total (153564ms).
[14:03:10.054] <TB0> INFO: 4109312 events read in total (179175ms).
[14:03:35.865] <TB0> INFO: 4694448 events read in total (204986ms).
[14:03:50.730] <TB0> INFO: 5025280 events read in total (219851ms).
[14:03:50.829] <TB0> INFO: Test took 220819ms.
[14:04:16.656] <TB0> INFO: roc 0 with ID = 0 has maximal Vcal 59.0907 for pixel 19/4 mean/min/max = 45.5578/31.923/59.1925
[14:04:16.657] <TB0> INFO: roc 1 with ID = 1 has maximal Vcal 62.3773 for pixel 21/57 mean/min/max = 48.0875/33.795/62.38
[14:04:16.657] <TB0> INFO: roc 2 with ID = 2 has maximal Vcal 58.3102 for pixel 1/77 mean/min/max = 45.1957/32.0035/58.388
[14:04:16.657] <TB0> INFO: roc 3 with ID = 3 has maximal Vcal 59.2497 for pixel 48/36 mean/min/max = 45.7651/31.8095/59.7207
[14:04:16.658] <TB0> INFO: roc 4 with ID = 4 has maximal Vcal 56.6916 for pixel 24/58 mean/min/max = 44.6188/32.5144/56.7233
[14:04:16.658] <TB0> INFO: roc 5 with ID = 5 has maximal Vcal 59.0247 for pixel 17/3 mean/min/max = 45.2008/31.3725/59.0292
[14:04:16.659] <TB0> INFO: roc 6 with ID = 6 has maximal Vcal 59.6104 for pixel 37/45 mean/min/max = 46.8047/33.9852/59.6242
[14:04:16.659] <TB0> INFO: roc 7 with ID = 7 has maximal Vcal 58.7739 for pixel 38/9 mean/min/max = 45.6296/32.4184/58.8409
[14:04:16.660] <TB0> INFO: roc 8 with ID = 8 has maximal Vcal 61.5764 for pixel 23/2 mean/min/max = 47.8527/34.129/61.5764
[14:04:16.660] <TB0> INFO: roc 9 with ID = 9 has maximal Vcal 59.886 for pixel 23/6 mean/min/max = 46.4518/32.9687/59.9349
[14:04:16.661] <TB0> INFO: roc 10 with ID = 10 has maximal Vcal 61.3287 for pixel 33/7 mean/min/max = 47.5825/33.7393/61.4256
[14:04:16.661] <TB0> INFO: roc 11 with ID = 11 has maximal Vcal 59.7184 for pixel 32/79 mean/min/max = 47.068/34.3356/59.8005
[14:04:16.662] <TB0> INFO: roc 12 with ID = 12 has maximal Vcal 58.4948 for pixel 8/44 mean/min/max = 46.4209/34.3345/58.5074
[14:04:16.662] <TB0> INFO: roc 13 with ID = 13 has maximal Vcal 57.7455 for pixel 19/78 mean/min/max = 44.9953/32.2249/57.7657
[14:04:16.662] <TB0> INFO: roc 14 with ID = 14 has maximal Vcal 57.941 for pixel 6/0 mean/min/max = 44.8682/31.7059/58.0306
[14:04:16.663] <TB0> INFO: roc 15 with ID = 15 has maximal Vcal 59.8316 for pixel 32/3 mean/min/max = 46.6374/33.1896/60.0852
[14:04:16.663] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[14:04:16.753] <TB0> INFO: Expecting 411648 events.
[14:04:26.297] <TB0> INFO: 411648 events read in total (8946ms).
[14:04:26.307] <TB0> INFO: Expecting 411648 events.
[14:04:35.469] <TB0> INFO: 411648 events read in total (8759ms).
[14:04:35.479] <TB0> INFO: Expecting 411648 events.
[14:04:44.770] <TB0> INFO: 411648 events read in total (8888ms).
[14:04:44.783] <TB0> INFO: Expecting 411648 events.
[14:04:54.151] <TB0> INFO: 411648 events read in total (8965ms).
[14:04:54.168] <TB0> INFO: Expecting 411648 events.
[14:05:03.535] <TB0> INFO: 411648 events read in total (8964ms).
[14:05:03.555] <TB0> INFO: Expecting 411648 events.
[14:05:12.862] <TB0> INFO: 411648 events read in total (8904ms).
[14:05:12.884] <TB0> INFO: Expecting 411648 events.
[14:05:22.244] <TB0> INFO: 411648 events read in total (8957ms).
[14:05:22.269] <TB0> INFO: Expecting 411648 events.
[14:05:31.679] <TB0> INFO: 411648 events read in total (9006ms).
[14:05:31.708] <TB0> INFO: Expecting 411648 events.
[14:05:40.995] <TB0> INFO: 411648 events read in total (8883ms).
[14:05:41.050] <TB0> INFO: Expecting 411648 events.
[14:05:50.431] <TB0> INFO: 411648 events read in total (8978ms).
[14:05:50.466] <TB0> INFO: Expecting 411648 events.
[14:05:59.887] <TB0> INFO: 411648 events read in total (9018ms).
[14:05:59.943] <TB0> INFO: Expecting 411648 events.
[14:06:09.296] <TB0> INFO: 411648 events read in total (8949ms).
[14:06:09.336] <TB0> INFO: Expecting 411648 events.
[14:06:18.733] <TB0> INFO: 411648 events read in total (8994ms).
[14:06:18.837] <TB0> INFO: Expecting 411648 events.
[14:06:28.158] <TB0> INFO: 411648 events read in total (8918ms).
[14:06:28.223] <TB0> INFO: Expecting 411648 events.
[14:06:37.708] <TB0> INFO: 411648 events read in total (9082ms).
[14:06:37.768] <TB0> INFO: Expecting 411648 events.
[14:06:47.053] <TB0> INFO: 411648 events read in total (8882ms).
[14:06:47.109] <TB0> INFO: Test took 150447ms.
[14:06:47.897] <TB0> INFO: ---> dac: vcal name: TrimThr2 ntrig: 8 dacrange: 0 .. 150 (-1/-1) hits flags = 528 (plus default)
[14:06:47.911] <TB0> INFO: dacScan split into 1 runs with ntrig = 8
[14:06:47.911] <TB0> INFO: run 1 of 1
[14:06:48.188] <TB0> INFO: Expecting 5025280 events.
[14:07:14.722] <TB0> INFO: 585832 events read in total (25942ms).
[14:07:40.632] <TB0> INFO: 1169848 events read in total (51853ms).
[14:08:06.399] <TB0> INFO: 1753240 events read in total (77619ms).
[14:08:32.116] <TB0> INFO: 2336272 events read in total (103336ms).
[14:08:58.182] <TB0> INFO: 2917464 events read in total (129403ms).
[14:09:24.020] <TB0> INFO: 3498104 events read in total (155240ms).
[14:09:49.822] <TB0> INFO: 4080416 events read in total (181042ms).
[14:10:15.631] <TB0> INFO: 4661368 events read in total (206851ms).
[14:10:32.088] <TB0> INFO: 5025280 events read in total (223308ms).
[14:10:32.191] <TB0> INFO: Test took 224281ms.
[14:10:56.109] <TB0> INFO: ---> TrimStepCorr4 extremal thresholds: 1.051440 .. 141.039537
[14:10:56.349] <TB0> INFO: Expecting 208000 events.
[14:11:06.244] <TB0> INFO: 208000 events read in total (9303ms).
[14:11:06.246] <TB0> INFO: Test took 10135ms.
[14:11:06.308] <TB0> INFO: ---> dac: vcal name: trimStepCorr4 ntrig: 8 dacrange: 1 .. 151 (-1/-1) hits flags = 528 (plus default)
[14:11:06.321] <TB0> INFO: dacScan split into 1 runs with ntrig = 8
[14:11:06.321] <TB0> INFO: run 1 of 1
[14:11:06.599] <TB0> INFO: Expecting 5025280 events.
[14:11:32.931] <TB0> INFO: 585656 events read in total (25740ms).
[14:11:58.338] <TB0> INFO: 1171920 events read in total (51148ms).
[14:12:23.919] <TB0> INFO: 1758200 events read in total (76728ms).
[14:12:49.159] <TB0> INFO: 2344288 events read in total (101968ms).
[14:13:14.866] <TB0> INFO: 2929976 events read in total (127675ms).
[14:13:40.746] <TB0> INFO: 3515136 events read in total (153555ms).
[14:14:06.069] <TB0> INFO: 4100296 events read in total (178878ms).
[14:14:31.875] <TB0> INFO: 4685040 events read in total (204684ms).
[14:14:46.782] <TB0> INFO: 5025280 events read in total (219591ms).
[14:14:46.897] <TB0> INFO: Test took 220576ms.
[14:15:13.494] <TB0> INFO: ---> TrimStepCorr2 extremal thresholds: 28.169794 .. 46.999006
[14:15:13.734] <TB0> INFO: Expecting 208000 events.
[14:15:24.166] <TB0> INFO: 208000 events read in total (9840ms).
[14:15:24.167] <TB0> INFO: Test took 10672ms.
[14:15:24.220] <TB0> INFO: ---> dac: vcal name: trimStepCorr2 ntrig: 8 dacrange: 18 .. 56 (-1/-1) hits flags = 528 (plus default)
[14:15:24.234] <TB0> INFO: dacScan split into 1 runs with ntrig = 8
[14:15:24.234] <TB0> INFO: run 1 of 1
[14:15:24.512] <TB0> INFO: Expecting 1297920 events.
[14:15:53.840] <TB0> INFO: 650264 events read in total (28736ms).
[14:16:21.285] <TB0> INFO: 1297920 events read in total (56182ms).
[14:16:21.318] <TB0> INFO: Test took 57085ms.
[14:16:33.869] <TB0> INFO: ---> TrimStepCorr1a extremal thresholds: 25.677985 .. 44.389234
[14:16:34.118] <TB0> INFO: Expecting 208000 events.
[14:16:44.027] <TB0> INFO: 208000 events read in total (9317ms).
[14:16:44.028] <TB0> INFO: Test took 10158ms.
[14:16:44.099] <TB0> INFO: ---> dac: vcal name: trimStepCorr1a ntrig: 8 dacrange: 15 .. 54 (-1/-1) hits flags = 528 (plus default)
[14:16:44.117] <TB0> INFO: dacScan split into 1 runs with ntrig = 8
[14:16:44.117] <TB0> INFO: run 1 of 1
[14:16:44.396] <TB0> INFO: Expecting 1331200 events.
[14:17:12.585] <TB0> INFO: 671320 events read in total (27598ms).
[14:17:40.369] <TB0> INFO: 1331200 events read in total (55382ms).
[14:17:40.407] <TB0> INFO: Test took 56290ms.
[14:17:53.775] <TB0> INFO: ---> TrimStepCorr1b extremal thresholds: 24.897447 .. 42.661488
[14:17:54.021] <TB0> INFO: Expecting 208000 events.
[14:18:03.839] <TB0> INFO: 208000 events read in total (9226ms).
[14:18:03.840] <TB0> INFO: Test took 10064ms.
[14:18:03.889] <TB0> INFO: ---> dac: vcal name: trimStepCorr1b ntrig: 8 dacrange: 14 .. 52 (-1/-1) hits flags = 528 (plus default)
[14:18:03.901] <TB0> INFO: dacScan split into 1 runs with ntrig = 8
[14:18:03.902] <TB0> INFO: run 1 of 1
[14:18:04.180] <TB0> INFO: Expecting 1297920 events.
[14:18:34.164] <TB0> INFO: 685256 events read in total (29392ms).
[14:18:59.962] <TB0> INFO: 1297920 events read in total (55190ms).
[14:18:59.993] <TB0> INFO: Test took 56092ms.
[14:19:13.553] <TB0> INFO: ---> TrimThrFinal extremal thresholds: 15 .. 55
[14:19:13.553] <TB0> INFO: ---> dac: vcal name: TrimThrFinal ntrig: 8 dacrange: 15 .. 55 (-1/-1) hits flags = 528 (plus default)
[14:19:13.566] <TB0> INFO: dacScan split into 1 runs with ntrig = 8
[14:19:13.566] <TB0> INFO: run 1 of 1
[14:19:13.804] <TB0> INFO: Expecting 1364480 events.
[14:19:42.872] <TB0> INFO: 667088 events read in total (28477ms).
[14:20:11.094] <TB0> INFO: 1333640 events read in total (56699ms).
[14:20:12.823] <TB0> INFO: 1364480 events read in total (58428ms).
[14:20:12.848] <TB0> INFO: Test took 59282ms.
[14:20:25.953] <TB0> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1064_FullQualification_2016-10-25_13h17m_1477394256//000_Fulltest_m20//dacParameters35_C0.dat
[14:20:25.953] <TB0> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1064_FullQualification_2016-10-25_13h17m_1477394256//000_Fulltest_m20//dacParameters35_C1.dat
[14:20:25.953] <TB0> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1064_FullQualification_2016-10-25_13h17m_1477394256//000_Fulltest_m20//dacParameters35_C2.dat
[14:20:25.953] <TB0> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1064_FullQualification_2016-10-25_13h17m_1477394256//000_Fulltest_m20//dacParameters35_C3.dat
[14:20:25.953] <TB0> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1064_FullQualification_2016-10-25_13h17m_1477394256//000_Fulltest_m20//dacParameters35_C4.dat
[14:20:25.953] <TB0> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1064_FullQualification_2016-10-25_13h17m_1477394256//000_Fulltest_m20//dacParameters35_C5.dat
[14:20:25.953] <TB0> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1064_FullQualification_2016-10-25_13h17m_1477394256//000_Fulltest_m20//dacParameters35_C6.dat
[14:20:25.953] <TB0> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1064_FullQualification_2016-10-25_13h17m_1477394256//000_Fulltest_m20//dacParameters35_C7.dat
[14:20:25.953] <TB0> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1064_FullQualification_2016-10-25_13h17m_1477394256//000_Fulltest_m20//dacParameters35_C8.dat
[14:20:25.954] <TB0> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1064_FullQualification_2016-10-25_13h17m_1477394256//000_Fulltest_m20//dacParameters35_C9.dat
[14:20:25.954] <TB0> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1064_FullQualification_2016-10-25_13h17m_1477394256//000_Fulltest_m20//dacParameters35_C10.dat
[14:20:25.954] <TB0> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1064_FullQualification_2016-10-25_13h17m_1477394256//000_Fulltest_m20//dacParameters35_C11.dat
[14:20:25.954] <TB0> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1064_FullQualification_2016-10-25_13h17m_1477394256//000_Fulltest_m20//dacParameters35_C12.dat
[14:20:25.954] <TB0> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1064_FullQualification_2016-10-25_13h17m_1477394256//000_Fulltest_m20//dacParameters35_C13.dat
[14:20:25.954] <TB0> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1064_FullQualification_2016-10-25_13h17m_1477394256//000_Fulltest_m20//dacParameters35_C14.dat
[14:20:25.954] <TB0> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1064_FullQualification_2016-10-25_13h17m_1477394256//000_Fulltest_m20//dacParameters35_C15.dat
[14:20:25.954] <TB0> INFO: write trim parameters into /home/l_tester/david/FullQualification/data/M1064_FullQualification_2016-10-25_13h17m_1477394256//000_Fulltest_m20//trimParameters35_C0.dat
[14:20:25.960] <TB0> INFO: write trim parameters into /home/l_tester/david/FullQualification/data/M1064_FullQualification_2016-10-25_13h17m_1477394256//000_Fulltest_m20//trimParameters35_C1.dat
[14:20:25.965] <TB0> INFO: write trim parameters into /home/l_tester/david/FullQualification/data/M1064_FullQualification_2016-10-25_13h17m_1477394256//000_Fulltest_m20//trimParameters35_C2.dat
[14:20:25.970] <TB0> INFO: write trim parameters into /home/l_tester/david/FullQualification/data/M1064_FullQualification_2016-10-25_13h17m_1477394256//000_Fulltest_m20//trimParameters35_C3.dat
[14:20:25.975] <TB0> INFO: write trim parameters into /home/l_tester/david/FullQualification/data/M1064_FullQualification_2016-10-25_13h17m_1477394256//000_Fulltest_m20//trimParameters35_C4.dat
[14:20:25.979] <TB0> INFO: write trim parameters into /home/l_tester/david/FullQualification/data/M1064_FullQualification_2016-10-25_13h17m_1477394256//000_Fulltest_m20//trimParameters35_C5.dat
[14:20:25.984] <TB0> INFO: write trim parameters into /home/l_tester/david/FullQualification/data/M1064_FullQualification_2016-10-25_13h17m_1477394256//000_Fulltest_m20//trimParameters35_C6.dat
[14:20:25.989] <TB0> INFO: write trim parameters into /home/l_tester/david/FullQualification/data/M1064_FullQualification_2016-10-25_13h17m_1477394256//000_Fulltest_m20//trimParameters35_C7.dat
[14:20:25.993] <TB0> INFO: write trim parameters into /home/l_tester/david/FullQualification/data/M1064_FullQualification_2016-10-25_13h17m_1477394256//000_Fulltest_m20//trimParameters35_C8.dat
[14:20:25.998] <TB0> INFO: write trim parameters into /home/l_tester/david/FullQualification/data/M1064_FullQualification_2016-10-25_13h17m_1477394256//000_Fulltest_m20//trimParameters35_C9.dat
[14:20:25.003] <TB0> INFO: write trim parameters into /home/l_tester/david/FullQualification/data/M1064_FullQualification_2016-10-25_13h17m_1477394256//000_Fulltest_m20//trimParameters35_C10.dat
[14:20:26.007] <TB0> INFO: write trim parameters into /home/l_tester/david/FullQualification/data/M1064_FullQualification_2016-10-25_13h17m_1477394256//000_Fulltest_m20//trimParameters35_C11.dat
[14:20:26.012] <TB0> INFO: write trim parameters into /home/l_tester/david/FullQualification/data/M1064_FullQualification_2016-10-25_13h17m_1477394256//000_Fulltest_m20//trimParameters35_C12.dat
[14:20:26.017] <TB0> INFO: write trim parameters into /home/l_tester/david/FullQualification/data/M1064_FullQualification_2016-10-25_13h17m_1477394256//000_Fulltest_m20//trimParameters35_C13.dat
[14:20:26.022] <TB0> INFO: write trim parameters into /home/l_tester/david/FullQualification/data/M1064_FullQualification_2016-10-25_13h17m_1477394256//000_Fulltest_m20//trimParameters35_C14.dat
[14:20:26.026] <TB0> INFO: write trim parameters into /home/l_tester/david/FullQualification/data/M1064_FullQualification_2016-10-25_13h17m_1477394256//000_Fulltest_m20//trimParameters35_C15.dat
[14:20:26.031] <TB0> INFO: PixTestTrim::trimTest() done
[14:20:26.031] <TB0> INFO: vtrim: 132 135 110 142 121 114 119 125 130 127 127 129 131 117 127 127
[14:20:26.031] <TB0> INFO: vthrcomp: 122 109 115 121 116 119 103 112 106 100 103 111 109 116 117 101
[14:20:26.031] <TB0> INFO: vcal mean: 34.95 34.96 34.98 34.95 34.97 34.93 34.98 34.97 34.94 34.93 34.97 34.99 34.97 34.91 34.93 35.01
[14:20:26.031] <TB0> INFO: vcal RMS: 0.97 1.01 1.00 1.08 0.94 1.16 0.96 0.98 1.07 1.01 0.97 0.96 0.94 0.99 1.01 1.00
[14:20:26.031] <TB0> INFO: bits mean: 9.71 9.35 9.45 9.89 9.85 9.76 9.20 9.56 8.69 9.15 9.08 8.66 9.00 9.57 9.60 9.18
[14:20:26.031] <TB0> INFO: bits RMS: 2.61 2.38 2.76 2.53 2.52 2.68 2.52 2.64 2.64 2.70 2.53 2.65 2.52 2.69 2.77 2.64
[14:20:26.038] <TB0> INFO: ----------------------------------------------------------------------
[14:20:26.038] <TB0> INFO: PixTestTrim::trimBitTest() ntrig = 5, vtrims = 254 126 63 32
[14:20:26.038] <TB0> INFO: ----------------------------------------------------------------------
[14:20:26.043] <TB0> INFO: ---> dac: Vcal name: TrimBitsThr0 ntrig: 5 dacrange: 0 .. 199 (-1/-1) hits flags = 528 (plus default)
[14:20:26.055] <TB0> INFO: dacScan split into 1 runs with ntrig = 5
[14:20:26.055] <TB0> INFO: run 1 of 1
[14:20:26.294] <TB0> INFO: Expecting 4160000 events.
[14:20:58.197] <TB0> INFO: 720630 events read in total (31311ms).
[14:21:29.346] <TB0> INFO: 1436045 events read in total (62460ms).
[14:22:01.188] <TB0> INFO: 2153905 events read in total (94303ms).
[14:22:32.488] <TB0> INFO: 2866100 events read in total (125602ms).
[14:23:03.975] <TB0> INFO: 3573815 events read in total (157089ms).
[14:23:29.894] <TB0> INFO: 4160000 events read in total (183008ms).
[14:23:29.997] <TB0> INFO: Test took 183942ms.
[14:23:55.917] <TB0> INFO: ---> dac: Vcal name: TrimThr_trim14 ntrig: 5 dacrange: 0 .. 188 (-1/-1) hits flags = 528 (plus default)
[14:23:55.932] <TB0> INFO: dacScan split into 1 runs with ntrig = 5
[14:23:55.932] <TB0> INFO: run 1 of 1
[14:23:56.182] <TB0> INFO: Expecting 3931200 events.
[14:24:28.561] <TB0> INFO: 710800 events read in total (31787ms).
[14:24:59.603] <TB0> INFO: 1417430 events read in total (62829ms).
[14:25:30.755] <TB0> INFO: 2125240 events read in total (93981ms).
[14:26:01.673] <TB0> INFO: 2828730 events read in total (124899ms).
[14:26:32.508] <TB0> INFO: 3528965 events read in total (155734ms).
[14:26:50.578] <TB0> INFO: 3931200 events read in total (173804ms).
[14:26:50.677] <TB0> INFO: Test took 174745ms.
[14:27:15.855] <TB0> INFO: ---> dac: Vcal name: TrimThr_trim13 ntrig: 5 dacrange: 0 .. 172 (-1/-1) hits flags = 528 (plus default)
[14:27:15.868] <TB0> INFO: dacScan split into 1 runs with ntrig = 5
[14:27:15.868] <TB0> INFO: run 1 of 1
[14:27:16.104] <TB0> INFO: Expecting 3598400 events.
[14:27:48.259] <TB0> INFO: 733420 events read in total (31564ms).
[14:28:19.102] <TB0> INFO: 1463585 events read in total (62407ms).
[14:28:50.461] <TB0> INFO: 2192205 events read in total (93767ms).
[14:29:21.754] <TB0> INFO: 2916010 events read in total (125059ms).
[14:29:51.207] <TB0> INFO: 3598400 events read in total (154512ms).
[14:29:51.270] <TB0> INFO: Test took 155402ms.
[14:30:14.229] <TB0> INFO: ---> dac: Vcal name: TrimThr_trim11 ntrig: 5 dacrange: 0 .. 172 (-1/-1) hits flags = 528 (plus default)
[14:30:14.241] <TB0> INFO: dacScan split into 1 runs with ntrig = 5
[14:30:14.242] <TB0> INFO: run 1 of 1
[14:30:14.478] <TB0> INFO: Expecting 3598400 events.
[14:30:46.931] <TB0> INFO: 734020 events read in total (31861ms).
[14:31:18.204] <TB0> INFO: 1464390 events read in total (63134ms).
[14:31:49.736] <TB0> INFO: 2193120 events read in total (94666ms).
[14:32:20.862] <TB0> INFO: 2917205 events read in total (125792ms).
[14:32:50.101] <TB0> INFO: 3598400 events read in total (155031ms).
[14:32:50.171] <TB0> INFO: Test took 155930ms.
[14:33:12.448] <TB0> INFO: ---> dac: Vcal name: TrimThr_trim7 ntrig: 5 dacrange: 0 .. 172 (-1/-1) hits flags = 528 (plus default)
[14:33:12.461] <TB0> INFO: dacScan split into 1 runs with ntrig = 5
[14:33:12.461] <TB0> INFO: run 1 of 1
[14:33:12.697] <TB0> INFO: Expecting 3598400 events.
[14:33:44.581] <TB0> INFO: 734150 events read in total (31292ms).
[14:34:17.875] <TB0> INFO: 1464955 events read in total (64586ms).
[14:34:49.173] <TB0> INFO: 2193970 events read in total (95884ms).
[14:35:20.455] <TB0> INFO: 2918390 events read in total (127166ms).
[14:35:49.750] <TB0> INFO: 3598400 events read in total (156461ms).
[14:35:49.835] <TB0> INFO: Test took 157375ms.
[14:36:12.346] <TB0> INFO: PixTestTrim::trimBitTest() done
[14:36:12.348] <TB0> INFO: PixTestTrim::doTest() done, duration: 2370 seconds
[14:36:12.348] <TB0> INFO: Decoding statistics:
[14:36:12.348] <TB0> INFO: General information:
[14:36:12.348] <TB0> INFO: 16bit words read: 0
[14:36:12.348] <TB0> INFO: valid events total: 0
[14:36:12.348] <TB0> INFO: empty events: 0
[14:36:12.348] <TB0> INFO: valid events with pixels: 0
[14:36:12.348] <TB0> INFO: valid pixel hits: 0
[14:36:12.348] <TB0> INFO: Event errors: 0
[14:36:12.348] <TB0> INFO: start marker: 0
[14:36:12.348] <TB0> INFO: stop marker: 0
[14:36:12.348] <TB0> INFO: overflow: 0
[14:36:12.348] <TB0> INFO: invalid 5bit words: 0
[14:36:12.348] <TB0> INFO: invalid XOR eye diagram: 0
[14:36:12.348] <TB0> INFO: frame (failed synchr.): 0
[14:36:12.348] <TB0> INFO: idle data (no TBM trl): 0
[14:36:12.348] <TB0> INFO: no data (only TBM hdr): 0
[14:36:12.348] <TB0> INFO: TBM errors: 0
[14:36:12.348] <TB0> INFO: flawed TBM headers: 0
[14:36:12.348] <TB0> INFO: flawed TBM trailers: 0
[14:36:12.348] <TB0> INFO: event ID mismatches: 0
[14:36:12.348] <TB0> INFO: ROC errors: 0
[14:36:12.348] <TB0> INFO: missing ROC header(s): 0
[14:36:12.348] <TB0> INFO: misplaced readback start: 0
[14:36:12.348] <TB0> INFO: Pixel decoding errors: 0
[14:36:12.348] <TB0> INFO: pixel data incomplete: 0
[14:36:12.348] <TB0> INFO: pixel address: 0
[14:36:12.348] <TB0> INFO: pulse height fill bit: 0
[14:36:12.348] <TB0> INFO: buffer corruption: 0
[14:36:12.984] <TB0> INFO: ######################################################################
[14:36:12.984] <TB0> INFO: PixTestPhOptimization::doTest() Ntrig = 10
[14:36:12.984] <TB0> INFO: ######################################################################
[14:36:13.224] <TB0> INFO: Expecting 41600 events.
[14:36:16.743] <TB0> INFO: 41600 events read in total (2927ms).
[14:36:16.743] <TB0> INFO: Test took 3757ms.
[14:36:17.223] <TB0> INFO: Expecting 41600 events.
[14:36:20.791] <TB0> INFO: 41600 events read in total (2976ms).
[14:36:20.792] <TB0> INFO: Test took 3846ms.
[14:36:21.083] <TB0> INFO: Expecting 41600 events.
[14:36:24.628] <TB0> INFO: 41600 events read in total (2953ms).
[14:36:24.629] <TB0> INFO: Test took 3811ms.
[14:36:24.918] <TB0> INFO: Expecting 41600 events.
[14:36:28.431] <TB0> INFO: 41600 events read in total (2922ms).
[14:36:28.432] <TB0> INFO: Test took 3779ms.
[14:36:28.724] <TB0> INFO: Expecting 41600 events.
[14:36:32.192] <TB0> INFO: 41600 events read in total (2877ms).
[14:36:32.193] <TB0> INFO: Test took 3734ms.
[14:36:32.482] <TB0> INFO: Expecting 41600 events.
[14:36:36.093] <TB0> INFO: 41600 events read in total (3019ms).
[14:36:36.094] <TB0> INFO: Test took 3877ms.
[14:36:36.386] <TB0> INFO: Expecting 41600 events.
[14:36:39.880] <TB0> INFO: 41600 events read in total (2903ms).
[14:36:39.881] <TB0> INFO: Test took 3760ms.
[14:36:40.171] <TB0> INFO: Expecting 41600 events.
[14:36:43.716] <TB0> INFO: 41600 events read in total (2953ms).
[14:36:43.717] <TB0> INFO: Test took 3811ms.
[14:36:43.006] <TB0> INFO: Expecting 41600 events.
[14:36:47.592] <TB0> INFO: 41600 events read in total (2995ms).
[14:36:47.593] <TB0> INFO: Test took 3852ms.
[14:36:47.885] <TB0> INFO: Expecting 41600 events.
[14:36:51.458] <TB0> INFO: 41600 events read in total (2981ms).
[14:36:51.458] <TB0> INFO: Test took 3838ms.
[14:36:51.748] <TB0> INFO: Expecting 41600 events.
[14:36:55.234] <TB0> INFO: 41600 events read in total (2895ms).
[14:36:55.235] <TB0> INFO: Test took 3752ms.
[14:36:55.526] <TB0> INFO: Expecting 41600 events.
[14:36:59.090] <TB0> INFO: 41600 events read in total (2972ms).
[14:36:59.091] <TB0> INFO: Test took 3830ms.
[14:36:59.383] <TB0> INFO: Expecting 41600 events.
[14:37:02.867] <TB0> INFO: 41600 events read in total (2893ms).
[14:37:02.868] <TB0> INFO: Test took 3750ms.
[14:37:03.157] <TB0> INFO: Expecting 41600 events.
[14:37:06.673] <TB0> INFO: 41600 events read in total (2924ms).
[14:37:06.674] <TB0> INFO: Test took 3781ms.
[14:37:07.012] <TB0> INFO: Expecting 41600 events.
[14:37:10.594] <TB0> INFO: 41600 events read in total (2991ms).
[14:37:10.595] <TB0> INFO: Test took 3894ms.
[14:37:10.886] <TB0> INFO: Expecting 41600 events.
[14:37:14.501] <TB0> INFO: 41600 events read in total (3023ms).
[14:37:14.501] <TB0> INFO: Test took 3881ms.
[14:37:14.795] <TB0> INFO: Expecting 41600 events.
[14:37:18.358] <TB0> INFO: 41600 events read in total (2971ms).
[14:37:18.359] <TB0> INFO: Test took 3828ms.
[14:37:18.678] <TB0> INFO: Expecting 41600 events.
[14:37:22.248] <TB0> INFO: 41600 events read in total (2979ms).
[14:37:22.249] <TB0> INFO: Test took 3863ms.
[14:37:22.560] <TB0> INFO: Expecting 41600 events.
[14:37:26.082] <TB0> INFO: 41600 events read in total (2930ms).
[14:37:26.083] <TB0> INFO: Test took 3809ms.
[14:37:26.372] <TB0> INFO: Expecting 41600 events.
[14:37:30.034] <TB0> INFO: 41600 events read in total (3070ms).
[14:37:30.035] <TB0> INFO: Test took 3928ms.
[14:37:30.324] <TB0> INFO: Expecting 41600 events.
[14:37:34.083] <TB0> INFO: 41600 events read in total (3167ms).
[14:37:34.084] <TB0> INFO: Test took 4025ms.
[14:37:34.395] <TB0> INFO: Expecting 41600 events.
[14:37:38.119] <TB0> INFO: 41600 events read in total (3132ms).
[14:37:38.119] <TB0> INFO: Test took 4007ms.
[14:37:38.413] <TB0> INFO: Expecting 41600 events.
[14:37:42.020] <TB0> INFO: 41600 events read in total (3015ms).
[14:37:42.021] <TB0> INFO: Test took 3878ms.
[14:37:42.371] <TB0> INFO: Expecting 41600 events.
[14:37:45.961] <TB0> INFO: 41600 events read in total (2999ms).
[14:37:45.962] <TB0> INFO: Test took 3916ms.
[14:37:46.251] <TB0> INFO: Expecting 41600 events.
[14:37:49.844] <TB0> INFO: 41600 events read in total (3001ms).
[14:37:49.845] <TB0> INFO: Test took 3858ms.
[14:37:50.134] <TB0> INFO: Expecting 41600 events.
[14:37:53.830] <TB0> INFO: 41600 events read in total (3104ms).
[14:37:53.831] <TB0> INFO: Test took 3962ms.
[14:37:54.186] <TB0> INFO: Expecting 41600 events.
[14:37:57.688] <TB0> INFO: 41600 events read in total (2910ms).
[14:37:57.689] <TB0> INFO: Test took 3829ms.
[14:37:57.978] <TB0> INFO: Expecting 41600 events.
[14:38:01.554] <TB0> INFO: 41600 events read in total (2984ms).
[14:38:01.555] <TB0> INFO: Test took 3842ms.
[14:38:01.845] <TB0> INFO: Expecting 41600 events.
[14:38:05.443] <TB0> INFO: 41600 events read in total (3007ms).
[14:38:05.444] <TB0> INFO: Test took 3864ms.
[14:38:05.735] <TB0> INFO: Expecting 41600 events.
[14:38:09.305] <TB0> INFO: 41600 events read in total (2978ms).
[14:38:09.306] <TB0> INFO: Test took 3836ms.
[14:38:09.600] <TB0> INFO: Expecting 2560 events.
[14:38:10.485] <TB0> INFO: 2560 events read in total (294ms).
[14:38:10.485] <TB0> INFO: Test took 1163ms.
[14:38:10.796] <TB0> INFO: Expecting 2560 events.
[14:38:11.683] <TB0> INFO: 2560 events read in total (296ms).
[14:38:11.684] <TB0> INFO: Test took 1199ms.
[14:38:11.992] <TB0> INFO: Expecting 2560 events.
[14:38:12.888] <TB0> INFO: 2560 events read in total (304ms).
[14:38:12.889] <TB0> INFO: Test took 1205ms.
[14:38:13.196] <TB0> INFO: Expecting 2560 events.
[14:38:14.079] <TB0> INFO: 2560 events read in total (291ms).
[14:38:14.079] <TB0> INFO: Test took 1189ms.
[14:38:14.387] <TB0> INFO: Expecting 2560 events.
[14:38:15.268] <TB0> INFO: 2560 events read in total (289ms).
[14:38:15.268] <TB0> INFO: Test took 1188ms.
[14:38:15.576] <TB0> INFO: Expecting 2560 events.
[14:38:16.459] <TB0> INFO: 2560 events read in total (291ms).
[14:38:16.459] <TB0> INFO: Test took 1191ms.
[14:38:16.767] <TB0> INFO: Expecting 2560 events.
[14:38:17.656] <TB0> INFO: 2560 events read in total (297ms).
[14:38:17.656] <TB0> INFO: Test took 1196ms.
[14:38:17.964] <TB0> INFO: Expecting 2560 events.
[14:38:18.848] <TB0> INFO: 2560 events read in total (293ms).
[14:38:18.849] <TB0> INFO: Test took 1193ms.
[14:38:19.155] <TB0> INFO: Expecting 2560 events.
[14:38:20.043] <TB0> INFO: 2560 events read in total (296ms).
[14:38:20.043] <TB0> INFO: Test took 1194ms.
[14:38:20.352] <TB0> INFO: Expecting 2560 events.
[14:38:21.246] <TB0> INFO: 2560 events read in total (302ms).
[14:38:21.246] <TB0> INFO: Test took 1202ms.
[14:38:21.554] <TB0> INFO: Expecting 2560 events.
[14:38:22.445] <TB0> INFO: 2560 events read in total (299ms).
[14:38:22.445] <TB0> INFO: Test took 1198ms.
[14:38:22.753] <TB0> INFO: Expecting 2560 events.
[14:38:23.636] <TB0> INFO: 2560 events read in total (291ms).
[14:38:23.636] <TB0> INFO: Test took 1190ms.
[14:38:23.944] <TB0> INFO: Expecting 2560 events.
[14:38:24.838] <TB0> INFO: 2560 events read in total (302ms).
[14:38:24.838] <TB0> INFO: Test took 1201ms.
[14:38:25.146] <TB0> INFO: Expecting 2560 events.
[14:38:26.033] <TB0> INFO: 2560 events read in total (295ms).
[14:38:26.033] <TB0> INFO: Test took 1194ms.
[14:38:26.340] <TB0> INFO: Expecting 2560 events.
[14:38:27.224] <TB0> INFO: 2560 events read in total (292ms).
[14:38:27.225] <TB0> INFO: Test took 1191ms.
[14:38:27.532] <TB0> INFO: Expecting 2560 events.
[14:38:28.427] <TB0> INFO: 2560 events read in total (303ms).
[14:38:28.427] <TB0> INFO: Test took 1202ms.
[14:38:28.431] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[14:38:28.736] <TB0> INFO: Expecting 655360 events.
[14:38:43.714] <TB0> INFO: 655360 events read in total (14386ms).
[14:38:43.725] <TB0> INFO: Expecting 655360 events.
[14:38:58.506] <TB0> INFO: 655360 events read in total (14377ms).
[14:38:58.522] <TB0> INFO: Expecting 655360 events.
[14:39:13.188] <TB0> INFO: 655360 events read in total (14263ms).
[14:39:13.218] <TB0> INFO: Expecting 655360 events.
[14:39:28.025] <TB0> INFO: 655360 events read in total (14404ms).
[14:39:28.050] <TB0> INFO: Expecting 655360 events.
[14:39:42.785] <TB0> INFO: 655360 events read in total (14332ms).
[14:39:42.816] <TB0> INFO: Expecting 655360 events.
[14:39:57.896] <TB0> INFO: 655360 events read in total (14677ms).
[14:39:57.941] <TB0> INFO: Expecting 655360 events.
[14:40:12.732] <TB0> INFO: 655360 events read in total (14388ms).
[14:40:12.781] <TB0> INFO: Expecting 655360 events.
[14:40:27.238] <TB0> INFO: 655360 events read in total (14054ms).
[14:40:27.280] <TB0> INFO: Expecting 655360 events.
[14:40:41.848] <TB0> INFO: 655360 events read in total (14165ms).
[14:40:41.897] <TB0> INFO: Expecting 655360 events.
[14:40:56.388] <TB0> INFO: 655360 events read in total (14087ms).
[14:40:56.438] <TB0> INFO: Expecting 655360 events.
[14:41:11.066] <TB0> INFO: 655360 events read in total (14225ms).
[14:41:11.189] <TB0> INFO: Expecting 655360 events.
[14:41:25.785] <TB0> INFO: 655360 events read in total (14192ms).
[14:41:25.846] <TB0> INFO: Expecting 655360 events.
[14:41:40.500] <TB0> INFO: 655360 events read in total (14251ms).
[14:41:40.615] <TB0> INFO: Expecting 655360 events.
[14:41:55.204] <TB0> INFO: 655360 events read in total (14186ms).
[14:41:55.330] <TB0> INFO: Expecting 655360 events.
[14:42:09.868] <TB0> INFO: 655360 events read in total (14135ms).
[14:42:09.002] <TB0> INFO: Expecting 655360 events.
[14:42:24.590] <TB0> INFO: 655360 events read in total (14185ms).
[14:42:24.690] <TB0> INFO: Test took 236259ms.
[14:42:24.783] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[14:42:25.043] <TB0> INFO: Expecting 655360 events.
[14:42:39.805] <TB0> INFO: 655360 events read in total (14170ms).
[14:42:39.819] <TB0> INFO: Expecting 655360 events.
[14:42:54.383] <TB0> INFO: 655360 events read in total (14160ms).
[14:42:54.405] <TB0> INFO: Expecting 655360 events.
[14:43:08.998] <TB0> INFO: 655360 events read in total (14190ms).
[14:43:09.025] <TB0> INFO: Expecting 655360 events.
[14:43:23.484] <TB0> INFO: 655360 events read in total (14056ms).
[14:43:23.509] <TB0> INFO: Expecting 655360 events.
[14:43:38.070] <TB0> INFO: 655360 events read in total (14157ms).
[14:43:38.103] <TB0> INFO: Expecting 655360 events.
[14:43:52.374] <TB0> INFO: 655360 events read in total (13868ms).
[14:43:52.408] <TB0> INFO: Expecting 655360 events.
[14:44:06.850] <TB0> INFO: 655360 events read in total (14039ms).
[14:44:06.889] <TB0> INFO: Expecting 655360 events.
[14:44:21.261] <TB0> INFO: 655360 events read in total (13969ms).
[14:44:21.303] <TB0> INFO: Expecting 655360 events.
[14:44:35.751] <TB0> INFO: 655360 events read in total (14045ms).
[14:44:35.813] <TB0> INFO: Expecting 655360 events.
[14:44:50.255] <TB0> INFO: 655360 events read in total (14039ms).
[14:44:50.318] <TB0> INFO: Expecting 655360 events.
[14:45:04.957] <TB0> INFO: 655360 events read in total (14236ms).
[14:45:05.050] <TB0> INFO: Expecting 655360 events.
[14:45:19.715] <TB0> INFO: 655360 events read in total (14262ms).
[14:45:19.779] <TB0> INFO: Expecting 655360 events.
[14:45:34.333] <TB0> INFO: 655360 events read in total (14151ms).
[14:45:34.411] <TB0> INFO: Expecting 655360 events.
[14:45:48.915] <TB0> INFO: 655360 events read in total (14101ms).
[14:45:49.085] <TB0> INFO: Expecting 655360 events.
[14:46:03.497] <TB0> INFO: 655360 events read in total (14009ms).
[14:46:03.629] <TB0> INFO: Expecting 655360 events.
[14:46:18.214] <TB0> INFO: 655360 events read in total (14182ms).
[14:46:18.313] <TB0> INFO: Test took 233530ms.
[14:46:18.481] <TB0> INFO: safety margin for low PH: adding 0, margin is now 20
[14:46:18.487] <TB0> INFO: safety margin for low PH: adding 1, margin is now 21
[14:46:18.493] <TB0> INFO: safety margin for low PH: adding 2, margin is now 22
[14:46:18.499] <TB0> INFO: safety margin for low PH: adding 3, margin is now 23
[14:46:18.505] <TB0> INFO: safety margin for low PH: adding 4, margin is now 24
[14:46:18.511] <TB0> INFO: safety margin for low PH: adding 5, margin is now 25
[14:46:18.516] <TB0> INFO: safety margin for low PH: adding 6, margin is now 26
[14:46:18.522] <TB0> INFO: safety margin for low PH: adding 7, margin is now 27
[14:46:18.528] <TB0> INFO: safety margin for low PH: adding 8, margin is now 28
[14:46:18.534] <TB0> INFO: safety margin for low PH: adding 9, margin is now 29
[14:46:18.540] <TB0> INFO: safety margin for low PH: adding 10, margin is now 30
[14:46:18.546] <TB0> INFO: safety margin for low PH: adding 11, margin is now 31
[14:46:18.552] <TB0> INFO: safety margin for low PH: adding 12, margin is now 32
[14:46:18.558] <TB0> INFO: safety margin for low PH: adding 0, margin is now 20
[14:46:18.564] <TB0> INFO: safety margin for low PH: adding 1, margin is now 21
[14:46:18.570] <TB0> INFO: safety margin for low PH: adding 2, margin is now 22
[14:46:18.576] <TB0> INFO: safety margin for low PH: adding 3, margin is now 23
[14:46:18.582] <TB0> INFO: safety margin for low PH: adding 0, margin is now 20
[14:46:18.590] <TB0> INFO: safety margin for low PH: adding 1, margin is now 21
[14:46:18.596] <TB0> INFO: safety margin for low PH: adding 0, margin is now 20
[14:46:18.602] <TB0> INFO: safety margin for low PH: adding 1, margin is now 21
[14:46:18.608] <TB0> INFO: safety margin for low PH: adding 2, margin is now 22
[14:46:18.614] <TB0> INFO: safety margin for low PH: adding 3, margin is now 23
[14:46:18.621] <TB0> INFO: safety margin for low PH: adding 4, margin is now 24
[14:46:18.626] <TB0> INFO: safety margin for low PH: adding 5, margin is now 25
[14:46:18.632] <TB0> INFO: safety margin for low PH: adding 6, margin is now 26
[14:46:18.638] <TB0> INFO: safety margin for low PH: adding 7, margin is now 27
[14:46:18.644] <TB0> INFO: safety margin for low PH: adding 8, margin is now 28
[14:46:18.650] <TB0> INFO: safety margin for low PH: adding 0, margin is now 20
[14:46:18.656] <TB0> INFO: safety margin for low PH: adding 0, margin is now 20
[14:46:18.662] <TB0> INFO: safety margin for low PH: adding 0, margin is now 20
[14:46:18.668] <TB0> INFO: safety margin for low PH: adding 0, margin is now 20
[14:46:18.674] <TB0> INFO: safety margin for low PH: adding 0, margin is now 20
[14:46:18.680] <TB0> INFO: safety margin for low PH: adding 1, margin is now 21
[14:46:18.686] <TB0> INFO: safety margin for low PH: adding 2, margin is now 22
[14:46:18.692] <TB0> INFO: safety margin for low PH: adding 3, margin is now 23
[14:46:18.698] <TB0> INFO: safety margin for low PH: adding 4, margin is now 24
[14:46:18.705] <TB0> INFO: safety margin for low PH: adding 0, margin is now 20
[14:46:18.711] <TB0> INFO: safety margin for low PH: adding 0, margin is now 20
[14:46:18.717] <TB0> INFO: safety margin for low PH: adding 0, margin is now 20
[14:46:18.724] <TB0> INFO: safety margin for low PH: adding 0, margin is now 20
[14:46:18.729] <TB0> INFO: safety margin for low PH: adding 1, margin is now 21
[14:46:18.736] <TB0> INFO: safety margin for low PH: adding 2, margin is now 22
[14:46:18.742] <TB0> INFO: safety margin for low PH: adding 3, margin is now 23
[14:46:18.748] <TB0> INFO: safety margin for low PH: adding 4, margin is now 24
[14:46:18.755] <TB0> INFO: safety margin for low PH: adding 5, margin is now 25
[14:46:18.761] <TB0> INFO: safety margin for low PH: adding 6, margin is now 26
[14:46:18.768] <TB0> INFO: safety margin for low PH: adding 7, margin is now 27
[14:46:18.774] <TB0> INFO: safety margin for low PH: adding 8, margin is now 28
[14:46:18.780] <TB0> INFO: safety margin for low PH: adding 0, margin is now 20
[14:46:18.787] <TB0> INFO: safety margin for low PH: adding 0, margin is now 20
[14:46:18.793] <TB0> INFO: safety margin for low PH: adding 0, margin is now 20
[14:46:18.833] <TB0> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1064_FullQualification_2016-10-25_13h17m_1477394256//000_Fulltest_m20//dacParameters35_C0.dat
[14:46:18.834] <TB0> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1064_FullQualification_2016-10-25_13h17m_1477394256//000_Fulltest_m20//dacParameters35_C1.dat
[14:46:18.834] <TB0> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1064_FullQualification_2016-10-25_13h17m_1477394256//000_Fulltest_m20//dacParameters35_C2.dat
[14:46:18.834] <TB0> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1064_FullQualification_2016-10-25_13h17m_1477394256//000_Fulltest_m20//dacParameters35_C3.dat
[14:46:18.834] <TB0> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1064_FullQualification_2016-10-25_13h17m_1477394256//000_Fulltest_m20//dacParameters35_C4.dat
[14:46:18.834] <TB0> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1064_FullQualification_2016-10-25_13h17m_1477394256//000_Fulltest_m20//dacParameters35_C5.dat
[14:46:18.834] <TB0> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1064_FullQualification_2016-10-25_13h17m_1477394256//000_Fulltest_m20//dacParameters35_C6.dat
[14:46:18.835] <TB0> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1064_FullQualification_2016-10-25_13h17m_1477394256//000_Fulltest_m20//dacParameters35_C7.dat
[14:46:18.835] <TB0> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1064_FullQualification_2016-10-25_13h17m_1477394256//000_Fulltest_m20//dacParameters35_C8.dat
[14:46:18.835] <TB0> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1064_FullQualification_2016-10-25_13h17m_1477394256//000_Fulltest_m20//dacParameters35_C9.dat
[14:46:18.835] <TB0> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1064_FullQualification_2016-10-25_13h17m_1477394256//000_Fulltest_m20//dacParameters35_C10.dat
[14:46:18.835] <TB0> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1064_FullQualification_2016-10-25_13h17m_1477394256//000_Fulltest_m20//dacParameters35_C11.dat
[14:46:18.836] <TB0> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1064_FullQualification_2016-10-25_13h17m_1477394256//000_Fulltest_m20//dacParameters35_C12.dat
[14:46:18.836] <TB0> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1064_FullQualification_2016-10-25_13h17m_1477394256//000_Fulltest_m20//dacParameters35_C13.dat
[14:46:18.836] <TB0> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1064_FullQualification_2016-10-25_13h17m_1477394256//000_Fulltest_m20//dacParameters35_C14.dat
[14:46:18.836] <TB0> INFO: write dac parameters into /home/l_tester/david/FullQualification/data/M1064_FullQualification_2016-10-25_13h17m_1477394256//000_Fulltest_m20//dacParameters35_C15.dat
[14:46:19.086] <TB0> INFO: Expecting 41600 events.
[14:46:22.222] <TB0> INFO: 41600 events read in total (2544ms).
[14:46:22.223] <TB0> INFO: Test took 3383ms.
[14:46:22.674] <TB0> INFO: Expecting 41600 events.
[14:46:25.782] <TB0> INFO: 41600 events read in total (2516ms).
[14:46:25.784] <TB0> INFO: Test took 3349ms.
[14:46:26.241] <TB0> INFO: Expecting 41600 events.
[14:46:29.358] <TB0> INFO: 41600 events read in total (2525ms).
[14:46:29.359] <TB0> INFO: Test took 3364ms.
[14:46:29.575] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[14:46:29.665] <TB0> INFO: Expecting 2560 events.
[14:46:30.555] <TB0> INFO: 2560 events read in total (298ms).
[14:46:30.555] <TB0> INFO: Test took 980ms.
[14:46:30.559] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[14:46:30.863] <TB0> INFO: Expecting 2560 events.
[14:46:31.756] <TB0> INFO: 2560 events read in total (302ms).
[14:46:31.757] <TB0> INFO: Test took 1198ms.
[14:46:31.760] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[14:46:32.065] <TB0> INFO: Expecting 2560 events.
[14:46:32.951] <TB0> INFO: 2560 events read in total (294ms).
[14:46:32.951] <TB0> INFO: Test took 1191ms.
[14:46:32.954] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[14:46:33.260] <TB0> INFO: Expecting 2560 events.
[14:46:34.146] <TB0> INFO: 2560 events read in total (295ms).
[14:46:34.146] <TB0> INFO: Test took 1192ms.
[14:46:34.148] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[14:46:34.455] <TB0> INFO: Expecting 2560 events.
[14:46:35.344] <TB0> INFO: 2560 events read in total (297ms).
[14:46:35.344] <TB0> INFO: Test took 1196ms.
[14:46:35.346] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[14:46:35.652] <TB0> INFO: Expecting 2560 events.
[14:46:36.538] <TB0> INFO: 2560 events read in total (294ms).
[14:46:36.538] <TB0> INFO: Test took 1192ms.
[14:46:36.540] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[14:46:36.846] <TB0> INFO: Expecting 2560 events.
[14:46:37.733] <TB0> INFO: 2560 events read in total (296ms).
[14:46:37.733] <TB0> INFO: Test took 1193ms.
[14:46:37.735] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[14:46:38.042] <TB0> INFO: Expecting 2560 events.
[14:46:38.933] <TB0> INFO: 2560 events read in total (300ms).
[14:46:38.933] <TB0> INFO: Test took 1198ms.
[14:46:38.936] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[14:46:39.241] <TB0> INFO: Expecting 2560 events.
[14:46:40.123] <TB0> INFO: 2560 events read in total (290ms).
[14:46:40.123] <TB0> INFO: Test took 1187ms.
[14:46:40.125] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[14:46:40.431] <TB0> INFO: Expecting 2560 events.
[14:46:41.317] <TB0> INFO: 2560 events read in total (294ms).
[14:46:41.317] <TB0> INFO: Test took 1192ms.
[14:46:41.319] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[14:46:41.625] <TB0> INFO: Expecting 2560 events.
[14:46:42.504] <TB0> INFO: 2560 events read in total (287ms).
[14:46:42.505] <TB0> INFO: Test took 1186ms.
[14:46:42.507] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[14:46:42.813] <TB0> INFO: Expecting 2560 events.
[14:46:43.697] <TB0> INFO: 2560 events read in total (292ms).
[14:46:43.698] <TB0> INFO: Test took 1191ms.
[14:46:43.701] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[14:46:43.007] <TB0> INFO: Expecting 2560 events.
[14:46:44.895] <TB0> INFO: 2560 events read in total (297ms).
[14:46:44.895] <TB0> INFO: Test took 1194ms.
[14:46:44.899] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[14:46:45.204] <TB0> INFO: Expecting 2560 events.
[14:46:46.089] <TB0> INFO: 2560 events read in total (294ms).
[14:46:46.090] <TB0> INFO: Test took 1191ms.
[14:46:46.095] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[14:46:46.398] <TB0> INFO: Expecting 2560 events.
[14:46:47.290] <TB0> INFO: 2560 events read in total (299ms).
[14:46:47.290] <TB0> INFO: Test took 1195ms.
[14:46:47.294] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[14:46:47.598] <TB0> INFO: Expecting 2560 events.
[14:46:48.487] <TB0> INFO: 2560 events read in total (297ms).
[14:46:48.488] <TB0> INFO: Test took 1194ms.
[14:46:48.491] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[14:46:48.796] <TB0> INFO: Expecting 2560 events.
[14:46:49.684] <TB0> INFO: 2560 events read in total (296ms).
[14:46:49.685] <TB0> INFO: Test took 1194ms.
[14:46:49.688] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[14:46:49.992] <TB0> INFO: Expecting 2560 events.
[14:46:50.874] <TB0> INFO: 2560 events read in total (290ms).
[14:46:50.875] <TB0> INFO: Test took 1188ms.
[14:46:50.879] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[14:46:51.182] <TB0> INFO: Expecting 2560 events.
[14:46:52.070] <TB0> INFO: 2560 events read in total (296ms).
[14:46:52.070] <TB0> INFO: Test took 1191ms.
[14:46:52.074] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[14:46:52.379] <TB0> INFO: Expecting 2560 events.
[14:46:53.267] <TB0> INFO: 2560 events read in total (296ms).
[14:46:53.267] <TB0> INFO: Test took 1193ms.
[14:46:53.271] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[14:46:53.576] <TB0> INFO: Expecting 2560 events.
[14:46:54.462] <TB0> INFO: 2560 events read in total (294ms).
[14:46:54.462] <TB0> INFO: Test took 1191ms.
[14:46:54.465] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[14:46:54.769] <TB0> INFO: Expecting 2560 events.
[14:46:55.657] <TB0> INFO: 2560 events read in total (296ms).
[14:46:55.657] <TB0> INFO: Test took 1192ms.
[14:46:55.659] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[14:46:55.966] <TB0> INFO: Expecting 2560 events.
[14:46:56.845] <TB0> INFO: 2560 events read in total (288ms).
[14:46:56.845] <TB0> INFO: Test took 1186ms.
[14:46:56.848] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[14:46:57.154] <TB0> INFO: Expecting 2560 events.
[14:46:58.034] <TB0> INFO: 2560 events read in total (288ms).
[14:46:58.034] <TB0> INFO: Test took 1186ms.
[14:46:58.036] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[14:46:58.343] <TB0> INFO: Expecting 2560 events.
[14:46:59.242] <TB0> INFO: 2560 events read in total (307ms).
[14:46:59.242] <TB0> INFO: Test took 1206ms.
[14:46:59.245] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[14:46:59.551] <TB0> INFO: Expecting 2560 events.
[14:47:00.445] <TB0> INFO: 2560 events read in total (303ms).
[14:47:00.446] <TB0> INFO: Test took 1201ms.
[14:47:00.450] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[14:47:00.753] <TB0> INFO: Expecting 2560 events.
[14:47:01.651] <TB0> INFO: 2560 events read in total (306ms).
[14:47:01.652] <TB0> INFO: Test took 1203ms.
[14:47:01.656] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[14:47:01.960] <TB0> INFO: Expecting 2560 events.
[14:47:02.849] <TB0> INFO: 2560 events read in total (298ms).
[14:47:02.849] <TB0> INFO: Test took 1193ms.
[14:47:02.852] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[14:47:03.159] <TB0> INFO: Expecting 2560 events.
[14:47:04.050] <TB0> INFO: 2560 events read in total (299ms).
[14:47:04.051] <TB0> INFO: Test took 1199ms.
[14:47:04.053] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[14:47:04.360] <TB0> INFO: Expecting 2560 events.
[14:47:05.247] <TB0> INFO: 2560 events read in total (295ms).
[14:47:05.247] <TB0> INFO: Test took 1194ms.
[14:47:05.249] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[14:47:05.556] <TB0> INFO: Expecting 2560 events.
[14:47:06.440] <TB0> INFO: 2560 events read in total (292ms).
[14:47:06.440] <TB0> INFO: Test took 1191ms.
[14:47:06.443] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[14:47:06.749] <TB0> INFO: Expecting 2560 events.
[14:47:07.637] <TB0> INFO: 2560 events read in total (297ms).
[14:47:07.638] <TB0> INFO: Test took 1195ms.
[14:47:08.108] <TB0> INFO: PixTestPhOptimization::doTest() done, duration: 655 seconds
[14:47:08.108] <TB0> INFO: PH scale (per ROC): 49 57 48 48 62 47 48 44 50 46 53 61 48 52 48 44
[14:47:08.108] <TB0> INFO: PH offset (per ROC): 112 112 107 112 131 102 103 106 111 91 132 124 91 101 104 110
[14:47:08.115] <TB0> INFO: Decoding statistics:
[14:47:08.115] <TB0> INFO: General information:
[14:47:08.115] <TB0> INFO: 16bit words read: 127878
[14:47:08.115] <TB0> INFO: valid events total: 20480
[14:47:08.115] <TB0> INFO: empty events: 17981
[14:47:08.115] <TB0> INFO: valid events with pixels: 2499
[14:47:08.115] <TB0> INFO: valid pixel hits: 2499
[14:47:08.115] <TB0> INFO: Event errors: 0
[14:47:08.115] <TB0> INFO: start marker: 0
[14:47:08.115] <TB0> INFO: stop marker: 0
[14:47:08.115] <TB0> INFO: overflow: 0
[14:47:08.115] <TB0> INFO: invalid 5bit words: 0
[14:47:08.115] <TB0> INFO: invalid XOR eye diagram: 0
[14:47:08.115] <TB0> INFO: frame (failed synchr.): 0
[14:47:08.115] <TB0> INFO: idle data (no TBM trl): 0
[14:47:08.115] <TB0> INFO: no data (only TBM hdr): 0
[14:47:08.115] <TB0> INFO: TBM errors: 0
[14:47:08.115] <TB0> INFO: flawed TBM headers: 0
[14:47:08.115] <TB0> INFO: flawed TBM trailers: 0
[14:47:08.115] <TB0> INFO: event ID mismatches: 0
[14:47:08.115] <TB0> INFO: ROC errors: 0
[14:47:08.115] <TB0> INFO: missing ROC header(s): 0
[14:47:08.115] <TB0> INFO: misplaced readback start: 0
[14:47:08.115] <TB0> INFO: Pixel decoding errors: 0
[14:47:08.115] <TB0> INFO: pixel data incomplete: 0
[14:47:08.115] <TB0> INFO: pixel address: 0
[14:47:08.115] <TB0> INFO: pulse height fill bit: 0
[14:47:08.115] <TB0> INFO: buffer corruption: 0
[14:47:08.271] <TB0> INFO: ######################################################################
[14:47:08.271] <TB0> INFO: PixTestGainPedestal::fullTest() ntrig = 10
[14:47:08.271] <TB0> INFO: ######################################################################
[14:47:08.285] <TB0> INFO: scanning low vcal = 10
[14:47:08.525] <TB0> INFO: Expecting 41600 events.
[14:47:12.097] <TB0> INFO: 41600 events read in total (2981ms).
[14:47:12.097] <TB0> INFO: Test took 3812ms.
[14:47:12.100] <TB0> INFO: scanning low vcal = 20
[14:47:12.389] <TB0> INFO: Expecting 41600 events.
[14:47:15.962] <TB0> INFO: 41600 events read in total (2981ms).
[14:47:15.962] <TB0> INFO: Test took 3862ms.
[14:47:15.964] <TB0> INFO: scanning low vcal = 30
[14:47:16.258] <TB0> INFO: Expecting 41600 events.
[14:47:19.897] <TB0> INFO: 41600 events read in total (3047ms).
[14:47:19.898] <TB0> INFO: Test took 3934ms.
[14:47:19.900] <TB0> INFO: scanning low vcal = 40
[14:47:20.180] <TB0> INFO: Expecting 41600 events.
[14:47:24.125] <TB0> INFO: 41600 events read in total (3354ms).
[14:47:24.127] <TB0> INFO: Test took 4226ms.
[14:47:24.130] <TB0> INFO: scanning low vcal = 50
[14:47:24.407] <TB0> INFO: Expecting 41600 events.
[14:47:28.388] <TB0> INFO: 41600 events read in total (3389ms).
[14:47:28.388] <TB0> INFO: Test took 4257ms.
[14:47:28.391] <TB0> INFO: scanning low vcal = 60
[14:47:28.668] <TB0> INFO: Expecting 41600 events.
[14:47:32.672] <TB0> INFO: 41600 events read in total (3412ms).
[14:47:32.673] <TB0> INFO: Test took 4282ms.
[14:47:32.678] <TB0> INFO: scanning low vcal = 70
[14:47:32.954] <TB0> INFO: Expecting 41600 events.
[14:47:36.928] <TB0> INFO: 41600 events read in total (3382ms).
[14:47:36.930] <TB0> INFO: Test took 4252ms.
[14:47:36.933] <TB0> INFO: scanning low vcal = 80
[14:47:37.213] <TB0> INFO: Expecting 41600 events.
[14:47:41.222] <TB0> INFO: 41600 events read in total (3417ms).
[14:47:41.223] <TB0> INFO: Test took 4290ms.
[14:47:41.227] <TB0> INFO: scanning low vcal = 90
[14:47:41.531] <TB0> INFO: Expecting 41600 events.
[14:47:45.507] <TB0> INFO: 41600 events read in total (3384ms).
[14:47:45.507] <TB0> INFO: Test took 4279ms.
[14:47:45.512] <TB0> INFO: scanning low vcal = 100
[14:47:45.787] <TB0> INFO: Expecting 41600 events.
[14:47:49.748] <TB0> INFO: 41600 events read in total (3369ms).
[14:47:49.748] <TB0> INFO: Test took 4235ms.
[14:47:49.752] <TB0> INFO: scanning low vcal = 110
[14:47:50.028] <TB0> INFO: Expecting 41600 events.
[14:47:53.979] <TB0> INFO: 41600 events read in total (3359ms).
[14:47:53.980] <TB0> INFO: Test took 4228ms.
[14:47:53.983] <TB0> INFO: scanning low vcal = 120
[14:47:54.259] <TB0> INFO: Expecting 41600 events.
[14:47:58.226] <TB0> INFO: 41600 events read in total (3375ms).
[14:47:58.226] <TB0> INFO: Test took 4243ms.
[14:47:58.230] <TB0> INFO: scanning low vcal = 130
[14:47:58.506] <TB0> INFO: Expecting 41600 events.
[14:48:02.516] <TB0> INFO: 41600 events read in total (3418ms).
[14:48:02.516] <TB0> INFO: Test took 4286ms.
[14:48:02.520] <TB0> INFO: scanning low vcal = 140
[14:48:02.796] <TB0> INFO: Expecting 41600 events.
[14:48:06.805] <TB0> INFO: 41600 events read in total (3417ms).
[14:48:06.805] <TB0> INFO: Test took 4285ms.
[14:48:06.808] <TB0> INFO: scanning low vcal = 150
[14:48:07.091] <TB0> INFO: Expecting 41600 events.
[14:48:11.120] <TB0> INFO: 41600 events read in total (3437ms).
[14:48:11.121] <TB0> INFO: Test took 4313ms.
[14:48:11.124] <TB0> INFO: scanning low vcal = 160
[14:48:11.400] <TB0> INFO: Expecting 41600 events.
[14:48:15.427] <TB0> INFO: 41600 events read in total (3435ms).
[14:48:15.428] <TB0> INFO: Test took 4304ms.
[14:48:15.431] <TB0> INFO: scanning low vcal = 170
[14:48:15.707] <TB0> INFO: Expecting 41600 events.
[14:48:19.741] <TB0> INFO: 41600 events read in total (3442ms).
[14:48:19.742] <TB0> INFO: Test took 4311ms.
[14:48:19.747] <TB0> INFO: scanning low vcal = 180
[14:48:20.021] <TB0> INFO: Expecting 41600 events.
[14:48:24.042] <TB0> INFO: 41600 events read in total (3429ms).
[14:48:24.042] <TB0> INFO: Test took 4295ms.
[14:48:24.046] <TB0> INFO: scanning low vcal = 190
[14:48:24.322] <TB0> INFO: Expecting 41600 events.
[14:48:28.328] <TB0> INFO: 41600 events read in total (3414ms).
[14:48:28.329] <TB0> INFO: Test took 4283ms.
[14:48:28.331] <TB0> INFO: scanning low vcal = 200
[14:48:28.608] <TB0> INFO: Expecting 41600 events.
[14:48:32.602] <TB0> INFO: 41600 events read in total (3402ms).
[14:48:32.603] <TB0> INFO: Test took 4271ms.
[14:48:32.606] <TB0> INFO: scanning low vcal = 210
[14:48:32.882] <TB0> INFO: Expecting 41600 events.
[14:48:36.895] <TB0> INFO: 41600 events read in total (3421ms).
[14:48:36.896] <TB0> INFO: Test took 4290ms.
[14:48:36.899] <TB0> INFO: scanning low vcal = 220
[14:48:37.175] <TB0> INFO: Expecting 41600 events.
[14:48:41.226] <TB0> INFO: 41600 events read in total (3459ms).
[14:48:41.227] <TB0> INFO: Test took 4328ms.
[14:48:41.230] <TB0> INFO: scanning low vcal = 230
[14:48:41.506] <TB0> INFO: Expecting 41600 events.
[14:48:45.536] <TB0> INFO: 41600 events read in total (3438ms).
[14:48:45.537] <TB0> INFO: Test took 4307ms.
[14:48:45.540] <TB0> INFO: scanning low vcal = 240
[14:48:45.816] <TB0> INFO: Expecting 41600 events.
[14:48:49.830] <TB0> INFO: 41600 events read in total (3422ms).
[14:48:49.831] <TB0> INFO: Test took 4291ms.
[14:48:49.834] <TB0> INFO: scanning low vcal = 250
[14:48:50.111] <TB0> INFO: Expecting 41600 events.
[14:48:54.137] <TB0> INFO: 41600 events read in total (3435ms).
[14:48:54.137] <TB0> INFO: Test took 4303ms.
[14:48:54.142] <TB0> INFO: scanning high vcal = 30 (= 210 in low range)
[14:48:54.417] <TB0> INFO: Expecting 41600 events.
[14:48:58.364] <TB0> INFO: 41600 events read in total (3355ms).
[14:48:58.365] <TB0> INFO: Test took 4223ms.
[14:48:58.369] <TB0> INFO: scanning high vcal = 50 (= 350 in low range)
[14:48:58.645] <TB0> INFO: Expecting 41600 events.
[14:49:02.614] <TB0> INFO: 41600 events read in total (3377ms).
[14:49:02.615] <TB0> INFO: Test took 4246ms.
[14:49:02.619] <TB0> INFO: scanning high vcal = 70 (= 490 in low range)
[14:49:02.895] <TB0> INFO: Expecting 41600 events.
[14:49:06.854] <TB0> INFO: 41600 events read in total (3368ms).
[14:49:06.855] <TB0> INFO: Test took 4235ms.
[14:49:06.859] <TB0> INFO: scanning high vcal = 90 (= 630 in low range)
[14:49:07.134] <TB0> INFO: Expecting 41600 events.
[14:49:11.065] <TB0> INFO: 41600 events read in total (3339ms).
[14:49:11.066] <TB0> INFO: Test took 4207ms.
[14:49:11.069] <TB0> INFO: scanning high vcal = 200 (= 1400 in low range)
[14:49:11.345] <TB0> INFO: Expecting 41600 events.
[14:49:15.284] <TB0> INFO: 41600 events read in total (3346ms).
[14:49:15.285] <TB0> INFO: Test took 4216ms.
[14:49:15.701] <TB0> INFO: PixTestGainPedestal::measure() done
[14:49:48.742] <TB0> INFO: PixTestGainPedestal::fit() done
[14:49:48.742] <TB0> INFO: non-linearity mean: 0.942 0.976 0.931 0.960 0.982 0.940 0.928 0.900 0.947 0.931 0.978 0.981 0.934 0.934 0.919 0.940
[14:49:48.742] <TB0> INFO: non-linearity RMS: 0.059 0.019 0.082 0.029 0.004 0.070 0.072 0.181 0.078 0.111 0.006 0.005 0.153 0.084 0.082 0.087
[14:49:48.742] <TB0> INFO: write gain/ped parameters into /home/l_tester/david/FullQualification/data/M1064_FullQualification_2016-10-25_13h17m_1477394256//000_Fulltest_m20//phCalibrationFitErr35_C0.dat
[14:49:48.756] <TB0> INFO: write gain/ped parameters into /home/l_tester/david/FullQualification/data/M1064_FullQualification_2016-10-25_13h17m_1477394256//000_Fulltest_m20//phCalibrationFitErr35_C1.dat
[14:49:48.769] <TB0> INFO: write gain/ped parameters into /home/l_tester/david/FullQualification/data/M1064_FullQualification_2016-10-25_13h17m_1477394256//000_Fulltest_m20//phCalibrationFitErr35_C2.dat
[14:49:48.782] <TB0> INFO: write gain/ped parameters into /home/l_tester/david/FullQualification/data/M1064_FullQualification_2016-10-25_13h17m_1477394256//000_Fulltest_m20//phCalibrationFitErr35_C3.dat
[14:49:48.795] <TB0> INFO: write gain/ped parameters into /home/l_tester/david/FullQualification/data/M1064_FullQualification_2016-10-25_13h17m_1477394256//000_Fulltest_m20//phCalibrationFitErr35_C4.dat
[14:49:48.808] <TB0> INFO: write gain/ped parameters into /home/l_tester/david/FullQualification/data/M1064_FullQualification_2016-10-25_13h17m_1477394256//000_Fulltest_m20//phCalibrationFitErr35_C5.dat
[14:49:48.821] <TB0> INFO: write gain/ped parameters into /home/l_tester/david/FullQualification/data/M1064_FullQualification_2016-10-25_13h17m_1477394256//000_Fulltest_m20//phCalibrationFitErr35_C6.dat
[14:49:48.835] <TB0> INFO: write gain/ped parameters into /home/l_tester/david/FullQualification/data/M1064_FullQualification_2016-10-25_13h17m_1477394256//000_Fulltest_m20//phCalibrationFitErr35_C7.dat
[14:49:48.848] <TB0> INFO: write gain/ped parameters into /home/l_tester/david/FullQualification/data/M1064_FullQualification_2016-10-25_13h17m_1477394256//000_Fulltest_m20//phCalibrationFitErr35_C8.dat
[14:49:48.861] <TB0> INFO: write gain/ped parameters into /home/l_tester/david/FullQualification/data/M1064_FullQualification_2016-10-25_13h17m_1477394256//000_Fulltest_m20//phCalibrationFitErr35_C9.dat
[14:49:48.874] <TB0> INFO: write gain/ped parameters into /home/l_tester/david/FullQualification/data/M1064_FullQualification_2016-10-25_13h17m_1477394256//000_Fulltest_m20//phCalibrationFitErr35_C10.dat
[14:49:48.887] <TB0> INFO: write gain/ped parameters into /home/l_tester/david/FullQualification/data/M1064_FullQualification_2016-10-25_13h17m_1477394256//000_Fulltest_m20//phCalibrationFitErr35_C11.dat
[14:49:48.900] <TB0> INFO: write gain/ped parameters into /home/l_tester/david/FullQualification/data/M1064_FullQualification_2016-10-25_13h17m_1477394256//000_Fulltest_m20//phCalibrationFitErr35_C12.dat
[14:49:48.914] <TB0> INFO: write gain/ped parameters into /home/l_tester/david/FullQualification/data/M1064_FullQualification_2016-10-25_13h17m_1477394256//000_Fulltest_m20//phCalibrationFitErr35_C13.dat
[14:49:48.927] <TB0> INFO: write gain/ped parameters into /home/l_tester/david/FullQualification/data/M1064_FullQualification_2016-10-25_13h17m_1477394256//000_Fulltest_m20//phCalibrationFitErr35_C14.dat
[14:49:48.940] <TB0> INFO: write gain/ped parameters into /home/l_tester/david/FullQualification/data/M1064_FullQualification_2016-10-25_13h17m_1477394256//000_Fulltest_m20//phCalibrationFitErr35_C15.dat
[14:49:48.954] <TB0> INFO: PixTestGainPedestal::fullTest() done, duration: 160 seconds
[14:49:48.954] <TB0> INFO: Decoding statistics:
[14:49:48.954] <TB0> INFO: General information:
[14:49:48.954] <TB0> INFO: 16bit words read: 3327920
[14:49:48.954] <TB0> INFO: valid events total: 332800
[14:49:48.954] <TB0> INFO: empty events: 0
[14:49:48.954] <TB0> INFO: valid events with pixels: 332800
[14:49:48.954] <TB0> INFO: valid pixel hits: 665560
[14:49:48.954] <TB0> INFO: Event errors: 0
[14:49:48.954] <TB0> INFO: start marker: 0
[14:49:48.954] <TB0> INFO: stop marker: 0
[14:49:48.954] <TB0> INFO: overflow: 0
[14:49:48.954] <TB0> INFO: invalid 5bit words: 0
[14:49:48.954] <TB0> INFO: invalid XOR eye diagram: 0
[14:49:48.954] <TB0> INFO: frame (failed synchr.): 0
[14:49:48.954] <TB0> INFO: idle data (no TBM trl): 0
[14:49:48.954] <TB0> INFO: no data (only TBM hdr): 0
[14:49:48.954] <TB0> INFO: TBM errors: 0
[14:49:48.954] <TB0> INFO: flawed TBM headers: 0
[14:49:48.954] <TB0> INFO: flawed TBM trailers: 0
[14:49:48.954] <TB0> INFO: event ID mismatches: 0
[14:49:48.954] <TB0> INFO: ROC errors: 0
[14:49:48.954] <TB0> INFO: missing ROC header(s): 0
[14:49:48.954] <TB0> INFO: misplaced readback start: 0
[14:49:48.954] <TB0> INFO: Pixel decoding errors: 0
[14:49:48.954] <TB0> INFO: pixel data incomplete: 0
[14:49:48.954] <TB0> INFO: pixel address: 0
[14:49:48.954] <TB0> INFO: pulse height fill bit: 0
[14:49:48.954] <TB0> INFO: buffer corruption: 0
[14:49:48.970] <TB0> INFO: Decoding statistics:
[14:49:48.970] <TB0> INFO: General information:
[14:49:48.970] <TB0> INFO: 16bit words read: 3457334
[14:49:48.970] <TB0> INFO: valid events total: 353536
[14:49:48.970] <TB0> INFO: empty events: 18237
[14:49:48.970] <TB0> INFO: valid events with pixels: 335299
[14:49:48.970] <TB0> INFO: valid pixel hits: 668059
[14:49:48.970] <TB0> INFO: Event errors: 0
[14:49:48.970] <TB0> INFO: start marker: 0
[14:49:48.970] <TB0> INFO: stop marker: 0
[14:49:48.970] <TB0> INFO: overflow: 0
[14:49:48.970] <TB0> INFO: invalid 5bit words: 0
[14:49:48.970] <TB0> INFO: invalid XOR eye diagram: 0
[14:49:48.970] <TB0> INFO: frame (failed synchr.): 0
[14:49:48.970] <TB0> INFO: idle data (no TBM trl): 0
[14:49:48.970] <TB0> INFO: no data (only TBM hdr): 0
[14:49:48.970] <TB0> INFO: TBM errors: 0
[14:49:48.970] <TB0> INFO: flawed TBM headers: 0
[14:49:48.970] <TB0> INFO: flawed TBM trailers: 0
[14:49:48.970] <TB0> INFO: event ID mismatches: 0
[14:49:48.970] <TB0> INFO: ROC errors: 0
[14:49:48.970] <TB0> INFO: missing ROC header(s): 0
[14:49:48.970] <TB0> INFO: misplaced readback start: 0
[14:49:48.970] <TB0> INFO: Pixel decoding errors: 0
[14:49:48.970] <TB0> INFO: pixel data incomplete: 0
[14:49:48.970] <TB0> INFO: pixel address: 0
[14:49:48.970] <TB0> INFO: pulse height fill bit: 0
[14:49:48.970] <TB0> INFO: buffer corruption: 0
[14:49:48.970] <TB0> INFO: enter test to run
[14:49:48.970] <TB0> INFO: test: exit no parameter change
[14:49:49.095] <TB0> QUIET: Connection to board 71 closed.
[14:49:49.096] <TB0> INFO: pXar: this is the end, my friend