[15:34:20.376] <TB1> INFO: *** Welcome to pxar ***
[15:34:20.376] <TB1> INFO: *** Today: 2016/10/19
[15:34:20.383] <TB1> INFO: *** Version: c8ba-dirty
[15:34:20.383] <TB1> INFO: readRocDacs: /home/sensor/david/FullQualification/data/M1020_FullQualification_2016-10-19_14h52m_1476881545//001_Fulltest_m20//dacParameters_C0.dat .. /home/sensor/david/FullQualification/data/M1020_FullQualification_2016-10-19_14h52m_1476881545//001_Fulltest_m20//dacParameters_C15.dat
[15:34:20.384] <TB1> INFO: readTbmDacs: /home/sensor/david/FullQualification/data/M1020_FullQualification_2016-10-19_14h52m_1476881545//001_Fulltest_m20//tbmParameters_C0a.dat .. /home/sensor/david/FullQualification/data/M1020_FullQualification_2016-10-19_14h52m_1476881545//001_Fulltest_m20//tbmParameters_C1b.dat
[15:34:20.384] <TB1> INFO: readMaskFile: /home/sensor/david/FullQualification/data/M1020_FullQualification_2016-10-19_14h52m_1476881545//001_Fulltest_m20//defaultMaskFile.dat
[15:34:20.384] <TB1> INFO: readTrimFile: /home/sensor/david/FullQualification/data/M1020_FullQualification_2016-10-19_14h52m_1476881545//001_Fulltest_m20//trimParameters_C0.dat .. /home/sensor/david/FullQualification/data/M1020_FullQualification_2016-10-19_14h52m_1476881545//001_Fulltest_m20//trimParameters_C15.dat
[15:34:20.467] <TB1> INFO: clk: 4
[15:34:20.467] <TB1> INFO: ctr: 4
[15:34:20.467] <TB1> INFO: sda: 19
[15:34:20.467] <TB1> INFO: tin: 9
[15:34:20.467] <TB1> INFO: level: 15
[15:34:20.467] <TB1> INFO: triggerdelay: 0
[15:34:20.467] <TB1> QUIET: Instanciating API for pxar v2.1.0+868~g7f4a123
[15:34:20.467] <TB1> INFO: Log level: INFO
[15:34:20.477] <TB1> INFO: Found DTB DTB_WXBYFL
[15:34:20.485] <TB1> QUIET: Connection to board DTB_WXBYFL opened.
[15:34:20.492] <TB1> INFO: DTB startup information
--- DTB info------------------------------------------
Board id: 153
HW version: DTB1.2
FW version: 4.6
SW version: 4.7
Options:
USB id: DTB_WXBYFL
MAC address: 40D855118099
Hostname: pixelDTB153
Comment:
------------------------------------------------------
[15:34:20.494] <TB1> INFO: RPC call hashes of host and DTB match: 486171790
[15:34:22.109] <TB1> INFO: DUT info:
[15:34:22.109] <TB1> INFO: The DUT currently contains the following objects:
[15:34:22.109] <TB1> INFO: 4 TBM Cores tbm10c (4 ON)
[15:34:22.109] <TB1> INFO: TBM Core alpha (0): 7 registers set
[15:34:22.109] <TB1> INFO: TBM Core beta (1): 7 registers set
[15:34:22.109] <TB1> INFO: TBM Core alpha (2): 7 registers set
[15:34:22.109] <TB1> INFO: TBM Core beta (3): 7 registers set
[15:34:22.109] <TB1> INFO: 16 ROCs proc600 (16 ON) with 4160 pixelConfigs
[15:34:22.109] <TB1> INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[15:34:22.109] <TB1> INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[15:34:22.109] <TB1> INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[15:34:22.109] <TB1> INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[15:34:22.109] <TB1> INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[15:34:22.110] <TB1> INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[15:34:22.110] <TB1> INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[15:34:22.110] <TB1> INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[15:34:22.110] <TB1> INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[15:34:22.110] <TB1> INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[15:34:22.110] <TB1> INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[15:34:22.110] <TB1> INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[15:34:22.110] <TB1> INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[15:34:22.110] <TB1> INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[15:34:22.110] <TB1> INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[15:34:22.110] <TB1> INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[15:34:22.511] <TB1> INFO: enter 'restricted' command line mode
[15:34:22.511] <TB1> INFO: enter test to run
[15:34:22.511] <TB1> INFO: test: pretest no parameter change
[15:34:22.511] <TB1> INFO: running: pretest
[15:34:22.517] <TB1> INFO: ######################################################################
[15:34:22.517] <TB1> INFO: PixTestPretest::doTest()
[15:34:22.517] <TB1> INFO: ######################################################################
[15:34:22.525] <TB1> INFO: ----------------------------------------------------------------------
[15:34:22.525] <TB1> INFO: PixTestPretest::programROC()
[15:34:22.525] <TB1> INFO: ----------------------------------------------------------------------
[15:34:40.546] <TB1> INFO: PixTestPretest::programROC() done: ROCs are all programmable
[15:34:40.546] <TB1> INFO: IA differences per ROC: 19.3 20.9 20.1 17.7 17.7 20.9 20.1 16.9 20.1 20.9 18.5 19.3 20.1 18.5 20.1 20.1
[15:34:40.645] <TB1> INFO: ----------------------------------------------------------------------
[15:34:40.645] <TB1> INFO: PixTestPretest::setVana() target Ia = 24 mA/ROC
[15:34:40.645] <TB1> INFO: ----------------------------------------------------------------------
[15:35:01.993] <TB1> INFO: PixTestPretest::setVana() done, Module Ia 391.5 mA = 24.4688 mA/ROC
[15:35:01.993] <TB1> INFO: i(loss) [mA/ROC]: 20.1 20.1 19.3 20.1 20.1 20.1 19.3 19.3 20.1 20.1 19.3 20.9 20.1 18.5 20.1 20.1
[15:35:02.044] <TB1> INFO: ----------------------------------------------------------------------
[15:35:02.044] <TB1> INFO: PixTestPretest::findTiming()
[15:35:02.044] <TB1> INFO: ----------------------------------------------------------------------
[15:35:02.044] <TB1> INFO: PixTestCmd::init()

[15:35:37.817] <TB1> INFO: TBM phases: 160MHz: 0, 400MHz: 2, TBM delays: ROC(0/1):5, header/trailer: 1, token: 1
[15:35:37.817] <TB1> INFO: (success/tries = 100/100), width = 4
[15:35:39.326] <TB1> INFO: ----------------------------------------------------------------------
[15:35:39.326] <TB1> INFO: PixTestPretest::findWorkingPixel()
[15:35:39.326] <TB1> INFO: ----------------------------------------------------------------------
[15:35:39.424] <TB1> INFO: Expecting 231680 events.
[15:35:49.676] <TB1> INFO: 231680 events read in total (9659ms).
[15:35:49.684] <TB1> INFO: Test took 10354ms.
[15:35:49.941] <TB1> INFO: Found working pixel in all ROCs: col/row = 12/22
[15:35:49.996] <TB1> INFO: ----------------------------------------------------------------------
[15:35:49.996] <TB1> INFO: PixTestPretest::setVthrCompCalDel()
[15:35:49.996] <TB1> INFO: ----------------------------------------------------------------------
[15:35:50.096] <TB1> INFO: Expecting 231680 events.
[15:36:00.363] <TB1> INFO: 231680 events read in total (9674ms).
[15:36:00.373] <TB1> INFO: Test took 10369ms.
[15:36:00.664] <TB1> INFO: PixTestPretest::setVthrCompCalDel() done
[15:36:00.664] <TB1> INFO: CalDel: 103 87 89 106 101 107 91 101 112 100 93 101 108 108 107 94
[15:36:00.664] <TB1> INFO: VthrComp: 51 51 51 51 51 51 62 54 52 51 51 51 56 51 51 58
[15:36:00.668] <TB1> INFO: write dac parameters into /home/sensor/david/FullQualification/data/M1020_FullQualification_2016-10-19_14h52m_1476881545//001_Fulltest_m20//dacParameters_C0.dat
[15:36:00.668] <TB1> INFO: write dac parameters into /home/sensor/david/FullQualification/data/M1020_FullQualification_2016-10-19_14h52m_1476881545//001_Fulltest_m20//dacParameters_C1.dat
[15:36:00.668] <TB1> INFO: write dac parameters into /home/sensor/david/FullQualification/data/M1020_FullQualification_2016-10-19_14h52m_1476881545//001_Fulltest_m20//dacParameters_C2.dat
[15:36:00.668] <TB1> INFO: write dac parameters into /home/sensor/david/FullQualification/data/M1020_FullQualification_2016-10-19_14h52m_1476881545//001_Fulltest_m20//dacParameters_C3.dat
[15:36:00.669] <TB1> INFO: write dac parameters into /home/sensor/david/FullQualification/data/M1020_FullQualification_2016-10-19_14h52m_1476881545//001_Fulltest_m20//dacParameters_C4.dat
[15:36:00.669] <TB1> INFO: write dac parameters into /home/sensor/david/FullQualification/data/M1020_FullQualification_2016-10-19_14h52m_1476881545//001_Fulltest_m20//dacParameters_C5.dat
[15:36:00.669] <TB1> INFO: write dac parameters into /home/sensor/david/FullQualification/data/M1020_FullQualification_2016-10-19_14h52m_1476881545//001_Fulltest_m20//dacParameters_C6.dat
[15:36:00.669] <TB1> INFO: write dac parameters into /home/sensor/david/FullQualification/data/M1020_FullQualification_2016-10-19_14h52m_1476881545//001_Fulltest_m20//dacParameters_C7.dat
[15:36:00.670] <TB1> INFO: write dac parameters into /home/sensor/david/FullQualification/data/M1020_FullQualification_2016-10-19_14h52m_1476881545//001_Fulltest_m20//dacParameters_C8.dat
[15:36:00.670] <TB1> INFO: write dac parameters into /home/sensor/david/FullQualification/data/M1020_FullQualification_2016-10-19_14h52m_1476881545//001_Fulltest_m20//dacParameters_C9.dat
[15:36:00.670] <TB1> INFO: write dac parameters into /home/sensor/david/FullQualification/data/M1020_FullQualification_2016-10-19_14h52m_1476881545//001_Fulltest_m20//dacParameters_C10.dat
[15:36:00.670] <TB1> INFO: write dac parameters into /home/sensor/david/FullQualification/data/M1020_FullQualification_2016-10-19_14h52m_1476881545//001_Fulltest_m20//dacParameters_C11.dat
[15:36:00.670] <TB1> INFO: write dac parameters into /home/sensor/david/FullQualification/data/M1020_FullQualification_2016-10-19_14h52m_1476881545//001_Fulltest_m20//dacParameters_C12.dat
[15:36:00.671] <TB1> INFO: write dac parameters into /home/sensor/david/FullQualification/data/M1020_FullQualification_2016-10-19_14h52m_1476881545//001_Fulltest_m20//dacParameters_C13.dat
[15:36:00.671] <TB1> INFO: write dac parameters into /home/sensor/david/FullQualification/data/M1020_FullQualification_2016-10-19_14h52m_1476881545//001_Fulltest_m20//dacParameters_C14.dat
[15:36:00.671] <TB1> INFO: write dac parameters into /home/sensor/david/FullQualification/data/M1020_FullQualification_2016-10-19_14h52m_1476881545//001_Fulltest_m20//dacParameters_C15.dat
[15:36:00.671] <TB1> INFO: write tbm parameters into /home/sensor/david/FullQualification/data/M1020_FullQualification_2016-10-19_14h52m_1476881545//001_Fulltest_m20//tbmParameters_C0a.dat
[15:36:00.672] <TB1> INFO: write tbm parameters into /home/sensor/david/FullQualification/data/M1020_FullQualification_2016-10-19_14h52m_1476881545//001_Fulltest_m20//tbmParameters_C0b.dat
[15:36:00.672] <TB1> INFO: write tbm parameters into /home/sensor/david/FullQualification/data/M1020_FullQualification_2016-10-19_14h52m_1476881545//001_Fulltest_m20//tbmParameters_C1a.dat
[15:36:00.672] <TB1> INFO: write tbm parameters into /home/sensor/david/FullQualification/data/M1020_FullQualification_2016-10-19_14h52m_1476881545//001_Fulltest_m20//tbmParameters_C1b.dat
[15:36:00.672] <TB1> INFO: PixTestPretest::doTest() done, duration: 98 seconds
[15:36:00.758] <TB1> INFO: enter test to run
[15:36:00.758] <TB1> INFO: test: FullTest no parameter change
[15:36:00.758] <TB1> INFO: running: fulltest
[15:36:00.758] <TB1> INFO: ######################################################################
[15:36:00.758] <TB1> INFO: PixTestFullTest::doTest()
[15:36:00.758] <TB1> INFO: ######################################################################
[15:36:00.760] <TB1> INFO: ######################################################################
[15:36:00.760] <TB1> INFO: PixTestAlive::doTest()
[15:36:00.760] <TB1> INFO: ######################################################################
[15:36:00.761] <TB1> INFO: ----------------------------------------------------------------------
[15:36:00.761] <TB1> INFO: PixTestAlive::aliveTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[15:36:00.761] <TB1> INFO: ----------------------------------------------------------------------
[15:36:01.080] <TB1> INFO: Expecting 41600 events.
[15:36:04.916] <TB1> INFO: 41600 events read in total (3243ms).
[15:36:04.918] <TB1> INFO: Test took 4154ms.
[15:36:05.171] <TB1> INFO: PixTestAlive::aliveTest() done
[15:36:05.171] <TB1> INFO: number of dead pixels (per ROC): 1 0 0 1 0 0 1 0 0 0 0 0 0 0 0 0
[15:36:05.173] <TB1> INFO: ----------------------------------------------------------------------
[15:36:05.173] <TB1> INFO: PixTestAlive::maskTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[15:36:05.173] <TB1> INFO: ----------------------------------------------------------------------
[15:36:05.491] <TB1> INFO: Expecting 41600 events.
[15:36:08.695] <TB1> INFO: 41600 events read in total (2611ms).
[15:36:08.696] <TB1> INFO: Test took 3520ms.
[15:36:08.697] <TB1> INFO: mask vs. old pixelAlive PixelAlive_C0_V0 .. PixelAlive_C15_V0
[15:36:08.947] <TB1> INFO: PixTestAlive::maskTest() done
[15:36:08.947] <TB1> INFO: number of mask-defect pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[15:36:08.949] <TB1> INFO: ----------------------------------------------------------------------
[15:36:08.950] <TB1> INFO: PixTestAlive::addressDecodingTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[15:36:08.950] <TB1> INFO: ----------------------------------------------------------------------
[15:36:09.279] <TB1> INFO: Expecting 41600 events.
[15:36:13.133] <TB1> INFO: 41600 events read in total (3262ms).
[15:36:13.135] <TB1> INFO: Test took 4174ms.
[15:36:13.388] <TB1> INFO: PixTestAlive::addressDecodingTest() done
[15:36:13.388] <TB1> INFO: number of address-decoding pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[15:36:13.388] <TB1> INFO: PixTestAlive::doTest() done, duration: 12 seconds
[15:36:13.388] <TB1> INFO: Decoding statistics:
[15:36:13.389] <TB1> INFO: General information:
[15:36:13.389] <TB1> INFO: 16bit words read: 0
[15:36:13.389] <TB1> INFO: valid events total: 0
[15:36:13.389] <TB1> INFO: empty events: 0
[15:36:13.389] <TB1> INFO: valid events with pixels: 0
[15:36:13.389] <TB1> INFO: valid pixel hits: 0
[15:36:13.389] <TB1> INFO: Event errors: 0
[15:36:13.389] <TB1> INFO: start marker: 0
[15:36:13.389] <TB1> INFO: stop marker: 0
[15:36:13.389] <TB1> INFO: overflow: 0
[15:36:13.389] <TB1> INFO: invalid 5bit words: 0
[15:36:13.389] <TB1> INFO: invalid XOR eye diagram: 0
[15:36:13.389] <TB1> INFO: frame (failed synchr.): 0
[15:36:13.389] <TB1> INFO: idle data (no TBM trl): 0
[15:36:13.389] <TB1> INFO: no data (only TBM hdr): 0
[15:36:13.389] <TB1> INFO: TBM errors: 0
[15:36:13.389] <TB1> INFO: flawed TBM headers: 0
[15:36:13.389] <TB1> INFO: flawed TBM trailers: 0
[15:36:13.389] <TB1> INFO: event ID mismatches: 0
[15:36:13.389] <TB1> INFO: ROC errors: 0
[15:36:13.389] <TB1> INFO: missing ROC header(s): 0
[15:36:13.389] <TB1> INFO: misplaced readback start: 0
[15:36:13.389] <TB1> INFO: Pixel decoding errors: 0
[15:36:13.389] <TB1> INFO: pixel data incomplete: 0
[15:36:13.389] <TB1> INFO: pixel address: 0
[15:36:13.390] <TB1> INFO: pulse height fill bit: 0
[15:36:13.390] <TB1> INFO: buffer corruption: 0
[15:36:13.398] <TB1> INFO: readReadbackCal: /home/sensor/david/FullQualification/data/M1020_FullQualification_2016-10-19_14h52m_1476881545//001_Fulltest_m20//readbackCal_C0.dat .. /home/sensor/david/FullQualification/data/M1020_FullQualification_2016-10-19_14h52m_1476881545//001_Fulltest_m20//readbackCal_C15.dat
[15:36:13.398] <TB1> INFO: readGainPedestalParameters /home/sensor/david/FullQualification/data/M1020_FullQualification_2016-10-19_14h52m_1476881545//001_Fulltest_m20//phCalibrationFitErr_C0.dat .. /home/sensor/david/FullQualification/data/M1020_FullQualification_2016-10-19_14h52m_1476881545//001_Fulltest_m20//phCalibrationFitErr_C15.dat

[15:36:13.398] <TB1> INFO: ######################################################################
[15:36:13.398] <TB1> INFO: PixTestReadback::doTest()
[15:36:13.398] <TB1> INFO: ######################################################################
[15:36:13.398] <TB1> INFO: ----------------------------------------------------------------------
[15:36:13.398] <TB1> INFO: PixTestReadback::CalibrateVd()
[15:36:13.398] <TB1> INFO: ----------------------------------------------------------------------
[15:36:23.589] <TB1> INFO: write readback calibration parameters into /home/sensor/david/FullQualification/data/M1020_FullQualification_2016-10-19_14h52m_1476881545//001_Fulltest_m20//readbackCal_C0.dat
[15:36:23.589] <TB1> INFO: write readback calibration parameters into /home/sensor/david/FullQualification/data/M1020_FullQualification_2016-10-19_14h52m_1476881545//001_Fulltest_m20//readbackCal_C1.dat
[15:36:23.589] <TB1> INFO: write readback calibration parameters into /home/sensor/david/FullQualification/data/M1020_FullQualification_2016-10-19_14h52m_1476881545//001_Fulltest_m20//readbackCal_C2.dat
[15:36:23.589] <TB1> INFO: write readback calibration parameters into /home/sensor/david/FullQualification/data/M1020_FullQualification_2016-10-19_14h52m_1476881545//001_Fulltest_m20//readbackCal_C3.dat
[15:36:23.590] <TB1> INFO: write readback calibration parameters into /home/sensor/david/FullQualification/data/M1020_FullQualification_2016-10-19_14h52m_1476881545//001_Fulltest_m20//readbackCal_C4.dat
[15:36:23.590] <TB1> INFO: write readback calibration parameters into /home/sensor/david/FullQualification/data/M1020_FullQualification_2016-10-19_14h52m_1476881545//001_Fulltest_m20//readbackCal_C5.dat
[15:36:23.590] <TB1> INFO: write readback calibration parameters into /home/sensor/david/FullQualification/data/M1020_FullQualification_2016-10-19_14h52m_1476881545//001_Fulltest_m20//readbackCal_C6.dat
[15:36:23.590] <TB1> INFO: write readback calibration parameters into /home/sensor/david/FullQualification/data/M1020_FullQualification_2016-10-19_14h52m_1476881545//001_Fulltest_m20//readbackCal_C7.dat
[15:36:23.590] <TB1> INFO: write readback calibration parameters into /home/sensor/david/FullQualification/data/M1020_FullQualification_2016-10-19_14h52m_1476881545//001_Fulltest_m20//readbackCal_C8.dat
[15:36:23.590] <TB1> INFO: write readback calibration parameters into /home/sensor/david/FullQualification/data/M1020_FullQualification_2016-10-19_14h52m_1476881545//001_Fulltest_m20//readbackCal_C9.dat
[15:36:23.590] <TB1> INFO: write readback calibration parameters into /home/sensor/david/FullQualification/data/M1020_FullQualification_2016-10-19_14h52m_1476881545//001_Fulltest_m20//readbackCal_C10.dat
[15:36:23.590] <TB1> INFO: write readback calibration parameters into /home/sensor/david/FullQualification/data/M1020_FullQualification_2016-10-19_14h52m_1476881545//001_Fulltest_m20//readbackCal_C11.dat
[15:36:23.590] <TB1> INFO: write readback calibration parameters into /home/sensor/david/FullQualification/data/M1020_FullQualification_2016-10-19_14h52m_1476881545//001_Fulltest_m20//readbackCal_C12.dat
[15:36:23.590] <TB1> INFO: write readback calibration parameters into /home/sensor/david/FullQualification/data/M1020_FullQualification_2016-10-19_14h52m_1476881545//001_Fulltest_m20//readbackCal_C13.dat
[15:36:23.591] <TB1> INFO: write readback calibration parameters into /home/sensor/david/FullQualification/data/M1020_FullQualification_2016-10-19_14h52m_1476881545//001_Fulltest_m20//readbackCal_C14.dat
[15:36:23.591] <TB1> INFO: write readback calibration parameters into /home/sensor/david/FullQualification/data/M1020_FullQualification_2016-10-19_14h52m_1476881545//001_Fulltest_m20//readbackCal_C15.dat
[15:36:23.642] <TB1> INFO: PixTestPattern:: pg_setup set to default.
[15:36:23.642] <TB1> INFO: ----------------------------------------------------------------------
[15:36:23.642] <TB1> INFO: PixTestReadback::CalibrateVa()
[15:36:23.642] <TB1> INFO: ----------------------------------------------------------------------
[15:36:33.748] <TB1> INFO: write readback calibration parameters into /home/sensor/david/FullQualification/data/M1020_FullQualification_2016-10-19_14h52m_1476881545//001_Fulltest_m20//readbackCal_C0.dat
[15:36:33.748] <TB1> INFO: write readback calibration parameters into /home/sensor/david/FullQualification/data/M1020_FullQualification_2016-10-19_14h52m_1476881545//001_Fulltest_m20//readbackCal_C1.dat
[15:36:33.748] <TB1> INFO: write readback calibration parameters into /home/sensor/david/FullQualification/data/M1020_FullQualification_2016-10-19_14h52m_1476881545//001_Fulltest_m20//readbackCal_C2.dat
[15:36:33.748] <TB1> INFO: write readback calibration parameters into /home/sensor/david/FullQualification/data/M1020_FullQualification_2016-10-19_14h52m_1476881545//001_Fulltest_m20//readbackCal_C3.dat
[15:36:33.748] <TB1> INFO: write readback calibration parameters into /home/sensor/david/FullQualification/data/M1020_FullQualification_2016-10-19_14h52m_1476881545//001_Fulltest_m20//readbackCal_C4.dat
[15:36:33.748] <TB1> INFO: write readback calibration parameters into /home/sensor/david/FullQualification/data/M1020_FullQualification_2016-10-19_14h52m_1476881545//001_Fulltest_m20//readbackCal_C5.dat
[15:36:33.748] <TB1> INFO: write readback calibration parameters into /home/sensor/david/FullQualification/data/M1020_FullQualification_2016-10-19_14h52m_1476881545//001_Fulltest_m20//readbackCal_C6.dat
[15:36:33.748] <TB1> INFO: write readback calibration parameters into /home/sensor/david/FullQualification/data/M1020_FullQualification_2016-10-19_14h52m_1476881545//001_Fulltest_m20//readbackCal_C7.dat
[15:36:33.749] <TB1> INFO: write readback calibration parameters into /home/sensor/david/FullQualification/data/M1020_FullQualification_2016-10-19_14h52m_1476881545//001_Fulltest_m20//readbackCal_C8.dat
[15:36:33.749] <TB1> INFO: write readback calibration parameters into /home/sensor/david/FullQualification/data/M1020_FullQualification_2016-10-19_14h52m_1476881545//001_Fulltest_m20//readbackCal_C9.dat
[15:36:33.749] <TB1> INFO: write readback calibration parameters into /home/sensor/david/FullQualification/data/M1020_FullQualification_2016-10-19_14h52m_1476881545//001_Fulltest_m20//readbackCal_C10.dat
[15:36:33.749] <TB1> INFO: write readback calibration parameters into /home/sensor/david/FullQualification/data/M1020_FullQualification_2016-10-19_14h52m_1476881545//001_Fulltest_m20//readbackCal_C11.dat
[15:36:33.749] <TB1> INFO: write readback calibration parameters into /home/sensor/david/FullQualification/data/M1020_FullQualification_2016-10-19_14h52m_1476881545//001_Fulltest_m20//readbackCal_C12.dat
[15:36:33.749] <TB1> INFO: write readback calibration parameters into /home/sensor/david/FullQualification/data/M1020_FullQualification_2016-10-19_14h52m_1476881545//001_Fulltest_m20//readbackCal_C13.dat
[15:36:33.749] <TB1> INFO: write readback calibration parameters into /home/sensor/david/FullQualification/data/M1020_FullQualification_2016-10-19_14h52m_1476881545//001_Fulltest_m20//readbackCal_C14.dat
[15:36:33.749] <TB1> INFO: write readback calibration parameters into /home/sensor/david/FullQualification/data/M1020_FullQualification_2016-10-19_14h52m_1476881545//001_Fulltest_m20//readbackCal_C15.dat
[15:36:33.798] <TB1> INFO: PixTestPattern:: pg_setup set to default.
[15:36:33.798] <TB1> INFO: ----------------------------------------------------------------------
[15:36:33.798] <TB1> INFO: PixTestReadback::readbackVbg()
[15:36:33.798] <TB1> INFO: ----------------------------------------------------------------------
[15:36:41.571] <TB1> INFO: PixTestPattern:: pg_setup set to default.
[15:36:41.571] <TB1> INFO: ----------------------------------------------------------------------
[15:36:41.571] <TB1> INFO: PixTestReadback::getCalibratedVbg()
[15:36:41.571] <TB1> INFO: ----------------------------------------------------------------------
[15:36:41.571] <TB1> INFO: Vbg will be calibrated using Vd calibration
[15:36:41.571] <TB1> INFO: /*/*/*/*::: ROC 0: uncalibrated Vbg = 158calibrated Vbg = 1.14359 :::*/*/*/*/
[15:36:41.571] <TB1> INFO: /*/*/*/*::: ROC 1: uncalibrated Vbg = 159calibrated Vbg = 1.14475 :::*/*/*/*/
[15:36:41.571] <TB1> INFO: /*/*/*/*::: ROC 2: uncalibrated Vbg = 145.2calibrated Vbg = 1.13774 :::*/*/*/*/
[15:36:41.571] <TB1> INFO: /*/*/*/*::: ROC 3: uncalibrated Vbg = 153.5calibrated Vbg = 1.13622 :::*/*/*/*/
[15:36:41.571] <TB1> INFO: /*/*/*/*::: ROC 4: uncalibrated Vbg = 166.6calibrated Vbg = 1.13358 :::*/*/*/*/
[15:36:41.571] <TB1> INFO: /*/*/*/*::: ROC 5: uncalibrated Vbg = 153.6calibrated Vbg = 1.1445 :::*/*/*/*/
[15:36:41.571] <TB1> INFO: /*/*/*/*::: ROC 6: uncalibrated Vbg = 157.4calibrated Vbg = 1.14232 :::*/*/*/*/
[15:36:41.571] <TB1> INFO: /*/*/*/*::: ROC 7: uncalibrated Vbg = 172.2calibrated Vbg = 1.14376 :::*/*/*/*/
[15:36:41.571] <TB1> INFO: /*/*/*/*::: ROC 8: uncalibrated Vbg = 157.4calibrated Vbg = 1.13803 :::*/*/*/*/
[15:36:41.571] <TB1> INFO: /*/*/*/*::: ROC 9: uncalibrated Vbg = 150.9calibrated Vbg = 1.13567 :::*/*/*/*/
[15:36:41.572] <TB1> INFO: /*/*/*/*::: ROC 10: uncalibrated Vbg = 179.4calibrated Vbg = 1.13901 :::*/*/*/*/
[15:36:41.572] <TB1> INFO: /*/*/*/*::: ROC 11: uncalibrated Vbg = 153.6calibrated Vbg = 1.13048 :::*/*/*/*/
[15:36:41.572] <TB1> INFO: /*/*/*/*::: ROC 12: uncalibrated Vbg = 162calibrated Vbg = 1.13612 :::*/*/*/*/
[15:36:41.572] <TB1> INFO: /*/*/*/*::: ROC 13: uncalibrated Vbg = 152.6calibrated Vbg = 1.13791 :::*/*/*/*/
[15:36:41.572] <TB1> INFO: /*/*/*/*::: ROC 14: uncalibrated Vbg = 150.2calibrated Vbg = 1.14115 :::*/*/*/*/
[15:36:41.572] <TB1> INFO: /*/*/*/*::: ROC 15: uncalibrated Vbg = 167.2calibrated Vbg = 1.12821 :::*/*/*/*/
[15:36:41.580] <TB1> INFO: ----------------------------------------------------------------------
[15:36:41.580] <TB1> INFO: PixTestReadback::CalibrateIa()
[15:36:41.580] <TB1> INFO: ----------------------------------------------------------------------
[15:39:23.421] <TB1> INFO: write readback calibration parameters into /home/sensor/david/FullQualification/data/M1020_FullQualification_2016-10-19_14h52m_1476881545//001_Fulltest_m20//readbackCal_C0.dat
[15:39:23.421] <TB1> INFO: write readback calibration parameters into /home/sensor/david/FullQualification/data/M1020_FullQualification_2016-10-19_14h52m_1476881545//001_Fulltest_m20//readbackCal_C1.dat
[15:39:23.422] <TB1> INFO: write readback calibration parameters into /home/sensor/david/FullQualification/data/M1020_FullQualification_2016-10-19_14h52m_1476881545//001_Fulltest_m20//readbackCal_C2.dat
[15:39:23.422] <TB1> INFO: write readback calibration parameters into /home/sensor/david/FullQualification/data/M1020_FullQualification_2016-10-19_14h52m_1476881545//001_Fulltest_m20//readbackCal_C3.dat
[15:39:23.422] <TB1> INFO: write readback calibration parameters into /home/sensor/david/FullQualification/data/M1020_FullQualification_2016-10-19_14h52m_1476881545//001_Fulltest_m20//readbackCal_C4.dat
[15:39:23.422] <TB1> INFO: write readback calibration parameters into /home/sensor/david/FullQualification/data/M1020_FullQualification_2016-10-19_14h52m_1476881545//001_Fulltest_m20//readbackCal_C5.dat
[15:39:23.422] <TB1> INFO: write readback calibration parameters into /home/sensor/david/FullQualification/data/M1020_FullQualification_2016-10-19_14h52m_1476881545//001_Fulltest_m20//readbackCal_C6.dat
[15:39:23.422] <TB1> INFO: write readback calibration parameters into /home/sensor/david/FullQualification/data/M1020_FullQualification_2016-10-19_14h52m_1476881545//001_Fulltest_m20//readbackCal_C7.dat
[15:39:23.422] <TB1> INFO: write readback calibration parameters into /home/sensor/david/FullQualification/data/M1020_FullQualification_2016-10-19_14h52m_1476881545//001_Fulltest_m20//readbackCal_C8.dat
[15:39:23.423] <TB1> INFO: write readback calibration parameters into /home/sensor/david/FullQualification/data/M1020_FullQualification_2016-10-19_14h52m_1476881545//001_Fulltest_m20//readbackCal_C9.dat
[15:39:23.423] <TB1> INFO: write readback calibration parameters into /home/sensor/david/FullQualification/data/M1020_FullQualification_2016-10-19_14h52m_1476881545//001_Fulltest_m20//readbackCal_C10.dat
[15:39:23.423] <TB1> INFO: write readback calibration parameters into /home/sensor/david/FullQualification/data/M1020_FullQualification_2016-10-19_14h52m_1476881545//001_Fulltest_m20//readbackCal_C11.dat
[15:39:23.423] <TB1> INFO: write readback calibration parameters into /home/sensor/david/FullQualification/data/M1020_FullQualification_2016-10-19_14h52m_1476881545//001_Fulltest_m20//readbackCal_C12.dat
[15:39:23.423] <TB1> INFO: write readback calibration parameters into /home/sensor/david/FullQualification/data/M1020_FullQualification_2016-10-19_14h52m_1476881545//001_Fulltest_m20//readbackCal_C13.dat
[15:39:23.423] <TB1> INFO: write readback calibration parameters into /home/sensor/david/FullQualification/data/M1020_FullQualification_2016-10-19_14h52m_1476881545//001_Fulltest_m20//readbackCal_C14.dat
[15:39:23.423] <TB1> INFO: write readback calibration parameters into /home/sensor/david/FullQualification/data/M1020_FullQualification_2016-10-19_14h52m_1476881545//001_Fulltest_m20//readbackCal_C15.dat
[15:39:23.476] <TB1> INFO: PixTestPattern:: pg_setup set to default.
[15:39:23.478] <TB1> INFO: PixTestReadback::doTest() done
[15:39:23.478] <TB1> INFO: Decoding statistics:
[15:39:23.478] <TB1> INFO: General information:
[15:39:23.478] <TB1> INFO: 16bit words read: 1536
[15:39:23.478] <TB1> INFO: valid events total: 256
[15:39:23.479] <TB1> INFO: empty events: 256
[15:39:23.479] <TB1> INFO: valid events with pixels: 0
[15:39:23.479] <TB1> INFO: valid pixel hits: 0
[15:39:23.479] <TB1> INFO: Event errors: 0
[15:39:23.479] <TB1> INFO: start marker: 0
[15:39:23.479] <TB1> INFO: stop marker: 0
[15:39:23.479] <TB1> INFO: overflow: 0
[15:39:23.479] <TB1> INFO: invalid 5bit words: 0
[15:39:23.479] <TB1> INFO: invalid XOR eye diagram: 0
[15:39:23.479] <TB1> INFO: frame (failed synchr.): 0
[15:39:23.479] <TB1> INFO: idle data (no TBM trl): 0
[15:39:23.479] <TB1> INFO: no data (only TBM hdr): 0
[15:39:23.479] <TB1> INFO: TBM errors: 0
[15:39:23.479] <TB1> INFO: flawed TBM headers: 0
[15:39:23.479] <TB1> INFO: flawed TBM trailers: 0
[15:39:23.479] <TB1> INFO: event ID mismatches: 0
[15:39:23.479] <TB1> INFO: ROC errors: 0
[15:39:23.479] <TB1> INFO: missing ROC header(s): 0
[15:39:23.479] <TB1> INFO: misplaced readback start: 0
[15:39:23.479] <TB1> INFO: Pixel decoding errors: 0
[15:39:23.479] <TB1> INFO: pixel data incomplete: 0
[15:39:23.479] <TB1> INFO: pixel address: 0
[15:39:23.479] <TB1> INFO: pulse height fill bit: 0
[15:39:23.479] <TB1> INFO: buffer corruption: 0
[15:39:23.548] <TB1> INFO: ######################################################################
[15:39:23.548] <TB1> INFO: PixTestBBMap::doTest() Ntrig = 5, VcalS = 250 (high range)
[15:39:23.548] <TB1> INFO: ######################################################################
[15:39:23.553] <TB1> INFO: ---> dac: VthrComp name: calSMap ntrig: 5 dacrange: 0 .. 149 (-1/-1) hits flags = 514 (plus default)
[15:39:23.578] <TB1> INFO: dacScan split into 1 runs with ntrig = 5
[15:39:23.578] <TB1> INFO: run 1 of 1
[15:39:23.892] <TB1> INFO: Expecting 3120000 events.
[15:39:58.709] <TB1> INFO: 663470 events read in total (34225ms).





















[15:40:32.511] <TB1> INFO: 1320885 events read in total (68026ms).











[15:41:06.439] <TB1> INFO: 1975990 events read in total (101954ms).











[15:41:40.308] <TB1> INFO: 2632440 events read in total (135823ms).











[15:42:05.914] <TB1> INFO: 3120000 events read in total (161429ms).
[15:42:05.995] <TB1> INFO: Test took 162418ms.
[15:42:51.223] <TB1> INFO: PixTestBBMap::doTest() done with 3 decoding errors: , duration: 207 seconds
[15:42:51.223] <TB1> INFO: number of dead bumps (per ROC): 1 0 0 1 0 0 1 0 0 1 1 3 0 0 0 0
[15:42:51.223] <TB1> INFO: separation cut (per ROC): 107 112 105 105 105 105 131 101 120 126 111 104 119 108 108 132
[15:42:51.223] <TB1> INFO: Decoding statistics:
[15:42:51.223] <TB1> INFO: General information:
[15:42:51.223] <TB1> INFO: 16bit words read: 0
[15:42:51.223] <TB1> INFO: valid events total: 0
[15:42:51.223] <TB1> INFO: empty events: 0
[15:42:51.224] <TB1> INFO: valid events with pixels: 0
[15:42:51.224] <TB1> INFO: valid pixel hits: 0
[15:42:51.224] <TB1> INFO: Event errors: 0
[15:42:51.224] <TB1> INFO: start marker: 0
[15:42:51.224] <TB1> INFO: stop marker: 0
[15:42:51.224] <TB1> INFO: overflow: 0
[15:42:51.224] <TB1> INFO: invalid 5bit words: 0
[15:42:51.224] <TB1> INFO: invalid XOR eye diagram: 0
[15:42:51.224] <TB1> INFO: frame (failed synchr.): 0
[15:42:51.224] <TB1> INFO: idle data (no TBM trl): 0
[15:42:51.224] <TB1> INFO: no data (only TBM hdr): 0
[15:42:51.224] <TB1> INFO: TBM errors: 0
[15:42:51.224] <TB1> INFO: flawed TBM headers: 0
[15:42:51.224] <TB1> INFO: flawed TBM trailers: 0
[15:42:51.224] <TB1> INFO: event ID mismatches: 0
[15:42:51.224] <TB1> INFO: ROC errors: 0
[15:42:51.224] <TB1> INFO: missing ROC header(s): 0
[15:42:51.224] <TB1> INFO: misplaced readback start: 0
[15:42:51.224] <TB1> INFO: Pixel decoding errors: 0
[15:42:51.225] <TB1> INFO: pixel data incomplete: 0
[15:42:51.225] <TB1> INFO: pixel address: 0
[15:42:51.225] <TB1> INFO: pulse height fill bit: 0
[15:42:51.225] <TB1> INFO: buffer corruption: 0
[15:42:51.292] <TB1> INFO: ######################################################################
[15:42:51.292] <TB1> INFO: PixTestScurves::fullTest() ntrig = 50, dacs/step = -1, ntrig/step = -1
[15:42:51.292] <TB1> INFO: ######################################################################
[15:42:51.293] <TB1> INFO: ----------------------------------------------------------------------
[15:42:51.293] <TB1> INFO: PixTestScurves::scurves(Vcal), ntrig = 50, dacs/step = -1, ntrig/step = -1
[15:42:51.293] <TB1> INFO: ----------------------------------------------------------------------
[15:42:51.293] <TB1> INFO: ---> dac: Vcal name: scurveVcal ntrig: 50 dacrange: 25 .. 200 (-1/-1) hits flags = 528 (plus default)
[15:42:51.307] <TB1> INFO: dacScan split into 1 runs with ntrig = 50
[15:42:51.307] <TB1> INFO: run 1 of 1
[15:42:51.642] <TB1> INFO: Expecting 36608000 events.
[15:43:21.100] <TB1> INFO: 679300 events read in total (28862ms).
[15:43:48.566] <TB1> INFO: 1347350 events read in total (56328ms).
[15:44:15.952] <TB1> INFO: 2014700 events read in total (83714ms).
[15:44:43.370] <TB1> INFO: 2682050 events read in total (111132ms).
[15:45:10.811] <TB1> INFO: 3350050 events read in total (138573ms).
[15:45:38.257] <TB1> INFO: 4014750 events read in total (166019ms).
[15:46:05.584] <TB1> INFO: 4680050 events read in total (193346ms).
[15:46:32.692] <TB1> INFO: 5346650 events read in total (220454ms).
[15:46:59.987] <TB1> INFO: 6010000 events read in total (247749ms).
[15:47:27.250] <TB1> INFO: 6673950 events read in total (275012ms).
[15:47:54.513] <TB1> INFO: 7336800 events read in total (302275ms).
[15:48:22.025] <TB1> INFO: 8000050 events read in total (329787ms).
[15:48:49.499] <TB1> INFO: 8664000 events read in total (357261ms).
[15:49:16.731] <TB1> INFO: 9328350 events read in total (384493ms).
[15:49:44.049] <TB1> INFO: 9992650 events read in total (411811ms).
[15:50:11.438] <TB1> INFO: 10654650 events read in total (439200ms).
[15:50:38.776] <TB1> INFO: 11316700 events read in total (466538ms).
[15:51:06.123] <TB1> INFO: 11981850 events read in total (493885ms).

[15:53:49.317] <TB1> INFO: Expecting 36608000 events.
[15:54:17.266] <TB1> INFO: 679250 events read in total (27353ms).
[15:54:44.633] <TB1> INFO: 1347150 events read in total (54720ms).
[15:55:11.838] <TB1> INFO: 2014550 events read in total (81925ms).
[15:55:39.305] <TB1> INFO: 2681850 events read in total (109392ms).
[15:56:06.607] <TB1> INFO: 3349850 events read in total (136694ms).
[15:56:33.719] <TB1> INFO: 4014500 events read in total (163806ms).
[15:57:01.021] <TB1> INFO: 4680100 events read in total (191108ms).
[15:57:28.196] <TB1> INFO: 5346700 events read in total (218283ms).
[15:57:55.357] <TB1> INFO: 6010000 events read in total (245444ms).
[15:58:22.733] <TB1> INFO: 6674000 events read in total (272820ms).
[15:58:50.074] <TB1> INFO: 7337000 events read in total (300161ms).
[15:59:17.399] <TB1> INFO: 8000550 events read in total (327487ms).
[15:59:44.606] <TB1> INFO: 8664250 events read in total (354693ms).
[16:00:11.939] <TB1> INFO: 9328750 events read in total (382026ms).
[16:00:39.042] <TB1> INFO: 9992900 events read in total (409129ms).
[16:01:06.350] <TB1> INFO: 10654900 events read in total (436437ms).
[16:01:33.796] <TB1> INFO: 11316950 events read in total (463883ms).
[16:02:01.311] <TB1> INFO: 11981950 events read in total (491398ms).
[16:02:28.822] <TB1> INFO: 12645900 events read in total (518909ms).
[16:02:56.347] <TB1> INFO: 13311500 events read in total (546434ms).
[16:03:23.714] <TB1> INFO: 13974250 events read in total (573801ms).
[16:03:51.199] <TB1> INFO: 14638850 events read in total (601286ms).
[16:04:18.590] <TB1> INFO: 15300050 events read in total (628677ms).
[16:04:46.354] <TB1> INFO: 15963150 events read in total (656441ms).
[16:05:13.830] <TB1> INFO: 16627300 events read in total (683917ms).
[16:05:41.333] <TB1> INFO: 17289550 events read in total (711420ms).
[16:06:09.191] <TB1> INFO: 17952450 events read in total (739278ms).
[16:06:36.570] <TB1> INFO: 18614850 events read in total (766657ms).
[16:07:03.962] <TB1> INFO: 19276050 events read in total (794049ms).
[16:07:31.662] <TB1> INFO: 19935150 events read in total (821749ms).
[16:07:59.281] <TB1> INFO: 20594400 events read in total (849368ms).
[16:08:26.787] <TB1> INFO: 21251750 events read in total (876874ms).
[16:08:57.523] <TB1> INFO: 21911450 events read in total (907610ms).
[16:09:29.263] <TB1> INFO: 22569750 events read in total (939350ms).
[16:09:56.620] <TB1> INFO: 23228300 events read in total (966707ms).
[16:10:24.152] <TB1> INFO: 23885600 events read in total (994239ms).
[16:10:51.684] <TB1> INFO: 24544350 events read in total (1021771ms).
[16:11:18.798] <TB1> INFO: 25201950 events read in total (1048885ms).
[16:11:45.697] <TB1> INFO: 25860500 events read in total (1075784ms).
[16:12:12.824] <TB1> INFO: 26519050 events read in total (1102911ms).
[16:12:40.021] <TB1> INFO: 27176950 events read in total (1130108ms).
[16:13:08.468] <TB1> INFO: 27835150 events read in total (1158555ms).
[16:13:38.074] <TB1> INFO: 28492050 events read in total (1188161ms).
[16:14:05.449] <TB1> INFO: 29149150 events read in total (1215536ms).
[16:14:32.660] <TB1> INFO: 29804300 events read in total (1242747ms).
[16:14:59.896] <TB1> INFO: 30461600 events read in total (1269983ms).
[16:15:26.932] <TB1> INFO: 31116650 events read in total (1297019ms).
[16:15:53.917] <TB1> INFO: 31774250 events read in total (1324004ms).
[16:16:20.802] <TB1> INFO: 32427900 events read in total (1350889ms).
[16:16:48.246] <TB1> INFO: 33086050 events read in total (1378333ms).
[16:17:15.290] <TB1> INFO: 33744950 events read in total (1405377ms).
[16:17:42.180] <TB1> INFO: 34402750 events read in total (1432267ms).
[16:18:11.143] <TB1> INFO: 35059750 events read in total (1461230ms).
[16:18:42.215] <TB1> INFO: 35718550 events read in total (1492302ms).
[16:19:09.233] <TB1> INFO: 36384000 events read in total (1519320ms).
[16:19:18.442] <TB1> INFO: 36608000 events read in total (1528529ms).
[16:19:18.515] <TB1> INFO: Test took 1529521ms.
[16:19:19.114] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[16:19:21.834] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[16:19:24.556] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[16:19:27.334] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[16:19:30.096] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[16:19:32.832] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[16:19:35.801] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[16:19:38.543] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[16:19:41.376] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[16:19:44.166] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[16:19:46.941] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[16:19:49.630] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[16:19:52.355] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[16:19:55.033] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[16:19:57.767] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[16:20:00.430] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[16:20:03.112] <TB1> INFO: PixTestScurves::scurves() done
[16:20:03.112] <TB1> INFO: Vcal mean: 124.17 118.22 110.18 112.16 114.50 107.71 138.67 122.79 125.83 116.35 125.77 123.64 121.38 121.89 114.06 124.37
[16:20:03.112] <TB1> INFO: Vcal RMS: 7.64 5.49 4.98 5.80 5.32 4.78 7.04 8.18 6.24 5.71 5.75 6.27 6.77 6.26 5.06 5.85
[16:20:03.112] <TB1> INFO: PixTestScurves::fullTest() done, duration: 2231 seconds
[16:20:03.112] <TB1> INFO: Decoding statistics:
[16:20:03.112] <TB1> INFO: General information:
[16:20:03.112] <TB1> INFO: 16bit words read: 0
[16:20:03.112] <TB1> INFO: valid events total: 0
[16:20:03.112] <TB1> INFO: empty events: 0
[16:20:03.112] <TB1> INFO: valid events with pixels: 0
[16:20:03.112] <TB1> INFO: valid pixel hits: 0
[16:20:03.112] <TB1> INFO: Event errors: 0
[16:20:03.112] <TB1> INFO: start marker: 0
[16:20:03.112] <TB1> INFO: stop marker: 0
[16:20:03.112] <TB1> INFO: overflow: 0
[16:20:03.112] <TB1> INFO: invalid 5bit words: 0
[16:20:03.112] <TB1> INFO: invalid XOR eye diagram: 0
[16:20:03.112] <TB1> INFO: frame (failed synchr.): 0
[16:20:03.112] <TB1> INFO: idle data (no TBM trl): 0
[16:20:03.112] <TB1> INFO: no data (only TBM hdr): 0
[16:20:03.112] <TB1> INFO: TBM errors: 0
[16:20:03.112] <TB1> INFO: flawed TBM headers: 0
[16:20:03.112] <TB1> INFO: flawed TBM trailers: 0
[16:20:03.112] <TB1> INFO: event ID mismatches: 0
[16:20:03.112] <TB1> INFO: ROC errors: 0
[16:20:03.112] <TB1> INFO: missing ROC header(s): 0
[16:20:03.112] <TB1> INFO: misplaced readback start: 0
[16:20:03.112] <TB1> INFO: Pixel decoding errors: 0
[16:20:03.112] <TB1> INFO: pixel data incomplete: 0
[16:20:03.112] <TB1> INFO: pixel address: 0
[16:20:03.112] <TB1> INFO: pulse height fill bit: 0
[16:20:03.112] <TB1> INFO: buffer corruption: 0
[16:20:03.218] <TB1> INFO: ######################################################################
[16:20:03.218] <TB1> INFO: PixTestTrim::doTest()
[16:20:03.218] <TB1> INFO: ######################################################################
[16:20:03.220] <TB1> INFO: ----------------------------------------------------------------------
[16:20:03.220] <TB1> INFO: PixTestTrim::trimTest() ntrig = 8, vcal = 35
[16:20:03.220] <TB1> INFO: ----------------------------------------------------------------------
[16:20:03.305] <TB1> INFO: ---> VthrComp thr map (minimal VthrComp)
[16:20:03.305] <TB1> INFO: ---> dac: vthrcomp name: TrimThr0 ntrig: 8 dacrange: 10 .. 160 (-1/-1) hits flags = 528 (plus default)
[16:20:03.319] <TB1> INFO: dacScan split into 1 runs with ntrig = 8
[16:20:03.319] <TB1> INFO: run 1 of 1
[16:20:03.640] <TB1> INFO: Expecting 5025280 events.
[16:20:38.501] <TB1> INFO: 824136 events read in total (34264ms).
[16:21:12.799] <TB1> INFO: 1644272 events read in total (68562ms).
[16:21:47.047] <TB1> INFO: 2463808 events read in total (102811ms).
[16:22:21.595] <TB1> INFO: 3279024 events read in total (137358ms).
[16:22:56.162] <TB1> INFO: 4090536 events read in total (171926ms).
[16:23:30.329] <TB1> INFO: 4900504 events read in total (206092ms).
[16:23:35.998] <TB1> INFO: 5025280 events read in total (211761ms).
[16:23:36.054] <TB1> INFO: Test took 212736ms.
[16:24:02.878] <TB1> INFO: ROC 0 VthrComp = 121
[16:24:02.878] <TB1> INFO: ROC 1 VthrComp = 129
[16:24:02.879] <TB1> INFO: ROC 2 VthrComp = 117
[16:24:02.879] <TB1> INFO: ROC 3 VthrComp = 114
[16:24:02.879] <TB1> INFO: ROC 4 VthrComp = 118
[16:24:02.879] <TB1> INFO: ROC 5 VthrComp = 116
[16:24:02.880] <TB1> INFO: ROC 6 VthrComp = 139
[16:24:02.880] <TB1> INFO: ROC 7 VthrComp = 112
[16:24:02.880] <TB1> INFO: ROC 8 VthrComp = 132
[16:24:02.880] <TB1> INFO: ROC 9 VthrComp = 125
[16:24:02.892] <TB1> INFO: ROC 10 VthrComp = 127
[16:24:02.892] <TB1> INFO: ROC 11 VthrComp = 131
[16:24:02.893] <TB1> INFO: ROC 12 VthrComp = 127
[16:24:02.893] <TB1> INFO: ROC 13 VthrComp = 116
[16:24:02.894] <TB1> INFO: ROC 14 VthrComp = 124
[16:24:02.894] <TB1> INFO: ROC 15 VthrComp = 136
[16:24:03.212] <TB1> INFO: Expecting 41600 events.
[16:24:07.044] <TB1> INFO: 41600 events read in total (3236ms).
[16:24:07.045] <TB1> INFO: Test took 4149ms.
[16:24:07.059] <TB1> INFO: ---> Vcal thr map (pixel with maximum Vcal thr)
[16:24:07.059] <TB1> INFO: ---> dac: vcal name: TrimThr1 ntrig: 8 dacrange: 10 .. 160 (-1/-1) hits flags = 528 (plus default)
[16:24:07.072] <TB1> INFO: dacScan split into 1 runs with ntrig = 8
[16:24:07.072] <TB1> INFO: run 1 of 1
[16:24:07.392] <TB1> INFO: Expecting 5025280 events.
[16:24:38.219] <TB1> INFO: 589536 events read in total (30234ms).
[16:25:08.306] <TB1> INFO: 1178240 events read in total (60321ms).
[16:25:38.614] <TB1> INFO: 1767728 events read in total (90629ms).
[16:26:10.793] <TB1> INFO: 2357504 events read in total (122808ms).
[16:26:40.468] <TB1> INFO: 2945176 events read in total (152483ms).
[16:27:10.180] <TB1> INFO: 3531800 events read in total (182195ms).
[16:27:39.786] <TB1> INFO: 4117400 events read in total (211801ms).
[16:28:09.532] <TB1> INFO: 4702672 events read in total (241547ms).
[16:28:25.941] <TB1> INFO: 5025280 events read in total (257956ms).
[16:28:26.030] <TB1> INFO: Test took 258958ms.
[16:29:05.548] <TB1> INFO: roc 0 with ID = 0 has maximal Vcal 66.0749 for pixel 2/10 mean/min/max = 47.8683/29.6282/66.1084
[16:29:05.549] <TB1> INFO: roc 1 with ID = 1 has maximal Vcal 60.0458 for pixel 0/14 mean/min/max = 46.4638/32.8564/60.0711
[16:29:05.550] <TB1> INFO: roc 2 with ID = 2 has maximal Vcal 58.9568 for pixel 22/3 mean/min/max = 45.2793/31.4871/59.0715
[16:29:05.551] <TB1> INFO: roc 3 with ID = 3 has maximal Vcal 62.246 for pixel 41/12 mean/min/max = 46.6405/30.8778/62.4031
[16:29:05.551] <TB1> INFO: roc 4 with ID = 4 has maximal Vcal 60.0756 for pixel 19/1 mean/min/max = 45.9165/31.5102/60.3229
[16:29:05.552] <TB1> INFO: roc 5 with ID = 5 has maximal Vcal 58.3512 for pixel 21/7 mean/min/max = 45.2419/32.0069/58.477
[16:29:05.552] <TB1> INFO: roc 6 with ID = 6 has maximal Vcal 78.6055 for pixel 4/3 mean/min/max = 58.2211/37.5341/78.9082
[16:29:05.553] <TB1> INFO: roc 7 with ID = 7 has maximal Vcal 71.8173 for pixel 9/16 mean/min/max = 51.1043/30.1536/72.055
[16:29:05.554] <TB1> INFO: roc 8 with ID = 8 has maximal Vcal 60.9855 for pixel 2/45 mean/min/max = 46.5549/32.0281/61.0817
[16:29:05.554] <TB1> INFO: roc 9 with ID = 9 has maximal Vcal 60.6038 for pixel 8/79 mean/min/max = 46.116/31.5089/60.7231
[16:29:05.555] <TB1> INFO: roc 10 with ID = 10 has maximal Vcal 61.4765 for pixel 1/76 mean/min/max = 46.3272/31.0997/61.5548
[16:29:05.556] <TB1> INFO: roc 11 with ID = 11 has maximal Vcal 60.9439 for pixel 9/4 mean/min/max = 46.8091/32.6611/60.9571
[16:29:05.556] <TB1> INFO: roc 12 with ID = 12 has maximal Vcal 62.7514 for pixel 0/14 mean/min/max = 47.2949/31.797/62.7928
[16:29:05.557] <TB1> INFO: roc 13 with ID = 13 has maximal Vcal 64.6562 for pixel 18/7 mean/min/max = 48.1282/31.4592/64.7971
[16:29:05.557] <TB1> INFO: roc 14 with ID = 14 has maximal Vcal 58.4473 for pixel 1/15 mean/min/max = 45.0917/31.6868/58.4967
[16:29:05.558] <TB1> INFO: roc 15 with ID = 15 has maximal Vcal 63.1821 for pixel 3/14 mean/min/max = 48.6899/34.0904/63.2893
[16:29:05.559] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[16:29:05.653] <TB1> INFO: Expecting 411648 events.
[16:29:15.891] <TB1> INFO: 411648 events read in total (9645ms).
[16:29:15.900] <TB1> INFO: Expecting 411648 events.
[16:29:26.025] <TB1> INFO: 411648 events read in total (9721ms).
[16:29:26.040] <TB1> INFO: Expecting 411648 events.
[16:29:36.283] <TB1> INFO: 411648 events read in total (9836ms).
[16:29:36.305] <TB1> INFO: Expecting 411648 events.
[16:29:46.466] <TB1> INFO: 411648 events read in total (9757ms).
[16:29:46.499] <TB1> INFO: Expecting 411648 events.
[16:29:56.635] <TB1> INFO: 411648 events read in total (9726ms).
[16:29:56.660] <TB1> INFO: Expecting 411648 events.
[16:30:07.016] <TB1> INFO: 411648 events read in total (9952ms).
[16:30:07.049] <TB1> INFO: Expecting 411648 events.
[16:30:17.272] <TB1> INFO: 411648 events read in total (9819ms).
[16:30:17.308] <TB1> INFO: Expecting 411648 events.
[16:30:27.462] <TB1> INFO: 411648 events read in total (9750ms).
[16:30:27.499] <TB1> INFO: Expecting 411648 events.
[16:30:37.888] <TB1> INFO: 411648 events read in total (9981ms).
[16:30:37.934] <TB1> INFO: Expecting 411648 events.
[16:30:47.958] <TB1> INFO: 411648 events read in total (9616ms).
[16:30:48.006] <TB1> INFO: Expecting 411648 events.
[16:30:58.605] <TB1> INFO: 411648 events read in total (10195ms).
[16:30:58.661] <TB1> INFO: Expecting 411648 events.
[16:31:09.273] <TB1> INFO: 411648 events read in total (10208ms).
[16:31:09.353] <TB1> INFO: Expecting 411648 events.
[16:31:20.074] <TB1> INFO: 411648 events read in total (10316ms).
[16:31:20.228] <TB1> INFO: Expecting 411648 events.
[16:31:31.290] <TB1> INFO: 411648 events read in total (10654ms).
[16:31:31.354] <TB1> INFO: Expecting 411648 events.
[16:31:42.017] <TB1> INFO: 411648 events read in total (10254ms).
[16:31:42.094] <TB1> INFO: Expecting 411648 events.
[16:31:52.199] <TB1> INFO: 411648 events read in total (9697ms).
[16:31:52.275] <TB1> INFO: Test took 166716ms.
[16:31:53.341] <TB1> INFO: ---> dac: vcal name: TrimThr2 ntrig: 8 dacrange: 0 .. 150 (-1/-1) hits flags = 528 (plus default)
[16:31:53.364] <TB1> INFO: dacScan split into 1 runs with ntrig = 8
[16:31:53.364] <TB1> INFO: run 1 of 1
[16:31:53.681] <TB1> INFO: Expecting 5025280 events.
[16:32:24.433] <TB1> INFO: 587696 events read in total (30160ms).
[16:32:55.250] <TB1> INFO: 1174184 events read in total (60977ms).
[16:33:30.319] <TB1> INFO: 1761688 events read in total (96046ms).
[16:34:00.288] <TB1> INFO: 2346896 events read in total (126015ms).
[16:34:30.658] <TB1> INFO: 2932816 events read in total (156386ms).
[16:35:02.750] <TB1> INFO: 3519016 events read in total (188477ms).
[16:35:32.981] <TB1> INFO: 4105608 events read in total (218708ms).
[16:36:03.360] <TB1> INFO: 4690888 events read in total (249087ms).
[16:36:21.627] <TB1> INFO: 5025280 events read in total (267354ms).
[16:36:21.752] <TB1> INFO: Test took 268387ms.
[16:36:59.007] <TB1> INFO: ---> TrimStepCorr4 extremal thresholds: 4.500000 .. 144.960739
[16:36:59.338] <TB1> INFO: Expecting 208000 events.
[16:37:11.300] <TB1> INFO: 208000 events read in total (11370ms).
[16:37:11.301] <TB1> INFO: Test took 12290ms.
[16:37:11.395] <TB1> INFO: ---> dac: vcal name: trimStepCorr4 ntrig: 8 dacrange: 4 .. 154 (-1/-1) hits flags = 528 (plus default)
[16:37:11.409] <TB1> INFO: dacScan split into 1 runs with ntrig = 8
[16:37:11.409] <TB1> INFO: run 1 of 1
[16:37:11.727] <TB1> INFO: Expecting 5025280 events.
[16:37:42.521] <TB1> INFO: 580856 events read in total (30195ms).
[16:38:13.764] <TB1> INFO: 1161312 events read in total (61438ms).
[16:38:46.862] <TB1> INFO: 1741872 events read in total (94536ms).
[16:39:16.552] <TB1> INFO: 2322536 events read in total (124226ms).
[16:39:46.247] <TB1> INFO: 2903640 events read in total (153921ms).
[16:40:15.956] <TB1> INFO: 3483584 events read in total (183630ms).
[16:40:46.696] <TB1> INFO: 4063280 events read in total (214370ms).
[16:41:16.755] <TB1> INFO: 4642496 events read in total (244429ms).
[16:41:36.482] <TB1> INFO: 5025280 events read in total (264156ms).
[16:41:36.598] <TB1> INFO: Test took 265189ms.
[16:42:19.012] <TB1> INFO: ---> TrimStepCorr2 extremal thresholds: 26.284061 .. 46.573015
[16:42:19.349] <TB1> INFO: Expecting 208000 events.
[16:42:33.686] <TB1> INFO: 208000 events read in total (13744ms).
[16:42:33.689] <TB1> INFO: Test took 14672ms.
[16:42:33.786] <TB1> INFO: ---> dac: vcal name: trimStepCorr2 ntrig: 8 dacrange: 16 .. 56 (-1/-1) hits flags = 528 (plus default)
[16:42:33.805] <TB1> INFO: dacScan split into 1 runs with ntrig = 8
[16:42:33.807] <TB1> INFO: run 1 of 1
[16:42:34.164] <TB1> INFO: Expecting 1364480 events.
[16:43:07.456] <TB1> INFO: 658640 events read in total (32682ms).
[16:43:39.751] <TB1> INFO: 1315688 events read in total (64977ms).
[16:43:42.518] <TB1> INFO: 1364480 events read in total (67744ms).
[16:43:42.548] <TB1> INFO: Test took 68742ms.
[16:44:04.400] <TB1> INFO: ---> TrimStepCorr1a extremal thresholds: 26.085711 .. 51.228179
[16:44:04.727] <TB1> INFO: Expecting 208000 events.
[16:44:16.341] <TB1> INFO: 208000 events read in total (11018ms).
[16:44:16.342] <TB1> INFO: Test took 11937ms.
[16:44:16.434] <TB1> INFO: ---> dac: vcal name: trimStepCorr1a ntrig: 8 dacrange: 16 .. 61 (-1/-1) hits flags = 528 (plus default)
[16:44:16.447] <TB1> INFO: dacScan split into 1 runs with ntrig = 8
[16:44:16.447] <TB1> INFO: run 1 of 1
[16:44:16.767] <TB1> INFO: Expecting 1530880 events.
[16:44:49.292] <TB1> INFO: 642752 events read in total (31932ms).
[16:45:21.186] <TB1> INFO: 1284752 events read in total (63826ms).
[16:45:33.386] <TB1> INFO: 1530880 events read in total (76026ms).
[16:45:33.418] <TB1> INFO: Test took 76969ms.
[16:45:56.574] <TB1> INFO: ---> TrimStepCorr1b extremal thresholds: 24.626503 .. 55.500000
[16:45:56.950] <TB1> INFO: Expecting 208000 events.
[16:46:08.193] <TB1> INFO: 208000 events read in total (10650ms).
[16:46:08.194] <TB1> INFO: Test took 11605ms.
[16:46:08.286] <TB1> INFO: ---> dac: vcal name: trimStepCorr1b ntrig: 8 dacrange: 14 .. 65 (-1/-1) hits flags = 528 (plus default)
[16:46:08.301] <TB1> INFO: dacScan split into 1 runs with ntrig = 8
[16:46:08.301] <TB1> INFO: run 1 of 1
[16:46:08.615] <TB1> INFO: Expecting 1730560 events.
[16:46:41.827] <TB1> INFO: 639000 events read in total (32620ms).
[16:47:13.647] <TB1> INFO: 1277944 events read in total (64440ms).
[16:47:35.680] <TB1> INFO: 1730560 events read in total (86473ms).
[16:47:35.722] <TB1> INFO: Test took 87421ms.
[16:47:59.520] <TB1> INFO: ---> TrimThrFinal extremal thresholds: 15 .. 55
[16:47:59.520] <TB1> INFO: ---> dac: vcal name: TrimThrFinal ntrig: 8 dacrange: 15 .. 55 (-1/-1) hits flags = 528 (plus default)
[16:47:59.534] <TB1> INFO: dacScan split into 1 runs with ntrig = 8
[16:47:59.534] <TB1> INFO: run 1 of 1
[16:47:59.848] <TB1> INFO: Expecting 1364480 events.
[16:48:34.065] <TB1> INFO: 667056 events read in total (33621ms).
[16:49:06.560] <TB1> INFO: 1333856 events read in total (66116ms).
[16:49:08.451] <TB1> INFO: 1364480 events read in total (68008ms).
[16:49:08.477] <TB1> INFO: Test took 68943ms.
[16:49:28.897] <TB1> INFO: write dac parameters into /home/sensor/david/FullQualification/data/M1020_FullQualification_2016-10-19_14h52m_1476881545//001_Fulltest_m20//dacParameters35_C0.dat
[16:49:28.901] <TB1> INFO: write dac parameters into /home/sensor/david/FullQualification/data/M1020_FullQualification_2016-10-19_14h52m_1476881545//001_Fulltest_m20//dacParameters35_C1.dat
[16:49:28.902] <TB1> INFO: write dac parameters into /home/sensor/david/FullQualification/data/M1020_FullQualification_2016-10-19_14h52m_1476881545//001_Fulltest_m20//dacParameters35_C2.dat
[16:49:28.902] <TB1> INFO: write dac parameters into /home/sensor/david/FullQualification/data/M1020_FullQualification_2016-10-19_14h52m_1476881545//001_Fulltest_m20//dacParameters35_C3.dat
[16:49:28.903] <TB1> INFO: write dac parameters into /home/sensor/david/FullQualification/data/M1020_FullQualification_2016-10-19_14h52m_1476881545//001_Fulltest_m20//dacParameters35_C4.dat
[16:49:28.904] <TB1> INFO: write dac parameters into /home/sensor/david/FullQualification/data/M1020_FullQualification_2016-10-19_14h52m_1476881545//001_Fulltest_m20//dacParameters35_C5.dat
[16:49:28.904] <TB1> INFO: write dac parameters into /home/sensor/david/FullQualification/data/M1020_FullQualification_2016-10-19_14h52m_1476881545//001_Fulltest_m20//dacParameters35_C6.dat
[16:49:28.905] <TB1> INFO: write dac parameters into /home/sensor/david/FullQualification/data/M1020_FullQualification_2016-10-19_14h52m_1476881545//001_Fulltest_m20//dacParameters35_C7.dat
[16:49:28.905] <TB1> INFO: write dac parameters into /home/sensor/david/FullQualification/data/M1020_FullQualification_2016-10-19_14h52m_1476881545//001_Fulltest_m20//dacParameters35_C8.dat
[16:49:28.906] <TB1> INFO: write dac parameters into /home/sensor/david/FullQualification/data/M1020_FullQualification_2016-10-19_14h52m_1476881545//001_Fulltest_m20//dacParameters35_C9.dat
[16:49:28.907] <TB1> INFO: write dac parameters into /home/sensor/david/FullQualification/data/M1020_FullQualification_2016-10-19_14h52m_1476881545//001_Fulltest_m20//dacParameters35_C10.dat
[16:49:28.907] <TB1> INFO: write dac parameters into /home/sensor/david/FullQualification/data/M1020_FullQualification_2016-10-19_14h52m_1476881545//001_Fulltest_m20//dacParameters35_C11.dat
[16:49:28.908] <TB1> INFO: write dac parameters into /home/sensor/david/FullQualification/data/M1020_FullQualification_2016-10-19_14h52m_1476881545//001_Fulltest_m20//dacParameters35_C12.dat
[16:49:28.908] <TB1> INFO: write dac parameters into /home/sensor/david/FullQualification/data/M1020_FullQualification_2016-10-19_14h52m_1476881545//001_Fulltest_m20//dacParameters35_C13.dat
[16:49:28.908] <TB1> INFO: write dac parameters into /home/sensor/david/FullQualification/data/M1020_FullQualification_2016-10-19_14h52m_1476881545//001_Fulltest_m20//dacParameters35_C14.dat
[16:49:28.908] <TB1> INFO: write dac parameters into /home/sensor/david/FullQualification/data/M1020_FullQualification_2016-10-19_14h52m_1476881545//001_Fulltest_m20//dacParameters35_C15.dat
[16:49:28.908] <TB1> INFO: write trim parameters into /home/sensor/david/FullQualification/data/M1020_FullQualification_2016-10-19_14h52m_1476881545//001_Fulltest_m20//trimParameters35_C0.dat
[16:49:28.922] <TB1> INFO: write trim parameters into /home/sensor/david/FullQualification/data/M1020_FullQualification_2016-10-19_14h52m_1476881545//001_Fulltest_m20//trimParameters35_C1.dat
[16:49:28.932] <TB1> INFO: write trim parameters into /home/sensor/david/FullQualification/data/M1020_FullQualification_2016-10-19_14h52m_1476881545//001_Fulltest_m20//trimParameters35_C2.dat
[16:49:28.942] <TB1> INFO: write trim parameters into /home/sensor/david/FullQualification/data/M1020_FullQualification_2016-10-19_14h52m_1476881545//001_Fulltest_m20//trimParameters35_C3.dat
[16:49:28.953] <TB1> INFO: write trim parameters into /home/sensor/david/FullQualification/data/M1020_FullQualification_2016-10-19_14h52m_1476881545//001_Fulltest_m20//trimParameters35_C4.dat
[16:49:28.963] <TB1> INFO: write trim parameters into /home/sensor/david/FullQualification/data/M1020_FullQualification_2016-10-19_14h52m_1476881545//001_Fulltest_m20//trimParameters35_C5.dat
[16:49:28.973] <TB1> INFO: write trim parameters into /home/sensor/david/FullQualification/data/M1020_FullQualification_2016-10-19_14h52m_1476881545//001_Fulltest_m20//trimParameters35_C6.dat
[16:49:28.983] <TB1> INFO: write trim parameters into /home/sensor/david/FullQualification/data/M1020_FullQualification_2016-10-19_14h52m_1476881545//001_Fulltest_m20//trimParameters35_C7.dat
[16:49:28.993] <TB1> INFO: write trim parameters into /home/sensor/david/FullQualification/data/M1020_FullQualification_2016-10-19_14h52m_1476881545//001_Fulltest_m20//trimParameters35_C8.dat
[16:49:28.003] <TB1> INFO: write trim parameters into /home/sensor/david/FullQualification/data/M1020_FullQualification_2016-10-19_14h52m_1476881545//001_Fulltest_m20//trimParameters35_C9.dat
[16:49:29.013] <TB1> INFO: write trim parameters into /home/sensor/david/FullQualification/data/M1020_FullQualification_2016-10-19_14h52m_1476881545//001_Fulltest_m20//trimParameters35_C10.dat
[16:49:29.023] <TB1> INFO: write trim parameters into /home/sensor/david/FullQualification/data/M1020_FullQualification_2016-10-19_14h52m_1476881545//001_Fulltest_m20//trimParameters35_C11.dat
[16:49:29.034] <TB1> INFO: write trim parameters into /home/sensor/david/FullQualification/data/M1020_FullQualification_2016-10-19_14h52m_1476881545//001_Fulltest_m20//trimParameters35_C12.dat
[16:49:29.044] <TB1> INFO: write trim parameters into /home/sensor/david/FullQualification/data/M1020_FullQualification_2016-10-19_14h52m_1476881545//001_Fulltest_m20//trimParameters35_C13.dat
[16:49:29.055] <TB1> INFO: write trim parameters into /home/sensor/david/FullQualification/data/M1020_FullQualification_2016-10-19_14h52m_1476881545//001_Fulltest_m20//trimParameters35_C14.dat
[16:49:29.065] <TB1> INFO: write trim parameters into /home/sensor/david/FullQualification/data/M1020_FullQualification_2016-10-19_14h52m_1476881545//001_Fulltest_m20//trimParameters35_C15.dat
[16:49:29.075] <TB1> INFO: PixTestTrim::trimTest() done
[16:49:29.076] <TB1> INFO: vtrim: 134 129 117 145 132 133 236 170 143 124 122 143 124 118 139 150
[16:49:29.076] <TB1> INFO: vthrcomp: 121 129 117 114 118 116 139 112 132 125 127 131 127 116 124 136
[16:49:29.076] <TB1> INFO: vcal mean: 34.97 34.97 34.96 35.13 34.95 34.94 35.64 35.53 35.02 35.00 34.82 34.97 34.93 35.32 34.98 34.98
[16:49:29.076] <TB1> INFO: vcal RMS: 1.34 1.00 1.05 1.17 1.14 1.06 2.20 2.07 1.06 1.02 1.07 1.06 1.07 1.61 1.00 1.11
[16:49:29.076] <TB1> INFO: bits mean: 9.42 8.75 9.50 9.81 9.95 9.98 7.81 9.76 9.44 9.14 9.18 9.14 9.03 9.54 9.56 8.59
[16:49:29.076] <TB1> INFO: bits RMS: 2.84 2.86 2.85 2.56 2.54 2.54 2.45 2.58 2.63 2.87 2.92 2.70 2.81 2.74 2.80 2.54
[16:49:29.090] <TB1> INFO: ----------------------------------------------------------------------
[16:49:29.090] <TB1> INFO: PixTestTrim::trimBitTest() ntrig = 5, vtrims = 254 126 63 32
[16:49:29.090] <TB1> INFO: ----------------------------------------------------------------------
[16:49:29.104] <TB1> INFO: ---> dac: Vcal name: TrimBitsThr0 ntrig: 5 dacrange: 0 .. 199 (-1/-1) hits flags = 528 (plus default)
[16:49:29.124] <TB1> INFO: dacScan split into 1 runs with ntrig = 5
[16:49:29.126] <TB1> INFO: run 1 of 1
[16:49:29.454] <TB1> INFO: Expecting 4160000 events.
[16:50:08.012] <TB1> INFO: 749510 events read in total (37965ms).
[16:50:44.022] <TB1> INFO: 1492900 events read in total (73975ms).
[16:51:19.597] <TB1> INFO: 2234470 events read in total (109550ms).
[16:51:55.080] <TB1> INFO: 2971105 events read in total (145033ms).
[16:52:30.893] <TB1> INFO: 3706005 events read in total (180846ms).
[16:52:53.553] <TB1> INFO: 4160000 events read in total (203506ms).
[16:52:53.632] <TB1> INFO: Test took 204505ms.
[16:53:34.590] <TB1> INFO: ---> dac: Vcal name: TrimThr_trim14 ntrig: 5 dacrange: 0 .. 255 (-1/-1) hits flags = 528 (plus default)
[16:53:34.604] <TB1> INFO: dacScan split into 1 runs with ntrig = 5
[16:53:34.604] <TB1> INFO: run 1 of 1
[16:53:34.920] <TB1> INFO: Expecting 5324800 events.
[16:54:13.400] <TB1> INFO: 672740 events read in total (37887ms).
[16:54:47.550] <TB1> INFO: 1341080 events read in total (72037ms).
[16:55:21.721] <TB1> INFO: 2008790 events read in total (106208ms).
[16:55:55.830] <TB1> INFO: 2675960 events read in total (140317ms).
[16:56:32.455] <TB1> INFO: 3339810 events read in total (176942ms).
[16:57:08.214] <TB1> INFO: 4003830 events read in total (212701ms).
[16:57:44.715] <TB1> INFO: 4666325 events read in total (249202ms).
[16:58:24.664] <TB1> INFO: 5324800 events read in total (289151ms).
[16:58:24.809] <TB1> INFO: Test took 290205ms.
[16:59:16.954] <TB1> INFO: ---> dac: Vcal name: TrimThr_trim13 ntrig: 5 dacrange: 0 .. 238 (-1/-1) hits flags = 528 (plus default)
[16:59:16.970] <TB1> INFO: dacScan split into 1 runs with ntrig = 5
[16:59:16.970] <TB1> INFO: run 1 of 1
[16:59:17.289] <TB1> INFO: Expecting 4971200 events.
[16:59:52.332] <TB1> INFO: 685830 events read in total (34450ms).
[17:00:26.539] <TB1> INFO: 1366655 events read in total (68657ms).
[17:01:00.900] <TB1> INFO: 2046715 events read in total (103018ms).
[17:01:35.184] <TB1> INFO: 2725375 events read in total (137302ms).
[17:02:11.081] <TB1> INFO: 3400735 events read in total (173199ms).
[17:02:45.113] <TB1> INFO: 4075700 events read in total (207231ms).
[17:03:19.163] <TB1> INFO: 4750795 events read in total (241281ms).
[17:03:30.563] <TB1> INFO: 4971200 events read in total (252681ms).
[17:03:30.664] <TB1> INFO: Test took 253695ms.
[17:04:21.232] <TB1> INFO: ---> dac: Vcal name: TrimThr_trim11 ntrig: 5 dacrange: 0 .. 236 (-1/-1) hits flags = 528 (plus default)
[17:04:21.246] <TB1> INFO: dacScan split into 1 runs with ntrig = 5
[17:04:21.246] <TB1> INFO: run 1 of 1
[17:04:21.573] <TB1> INFO: Expecting 4929600 events.
[17:04:56.648] <TB1> INFO: 687440 events read in total (34482ms).
[17:05:30.847] <TB1> INFO: 1369495 events read in total (68681ms).
[17:06:05.474] <TB1> INFO: 2051080 events read in total (103308ms).
[17:06:39.879] <TB1> INFO: 2731405 events read in total (137713ms).
[17:07:14.013] <TB1> INFO: 3408370 events read in total (171847ms).
[17:07:48.221] <TB1> INFO: 4085015 events read in total (206055ms).
[17:08:22.420] <TB1> INFO: 4761740 events read in total (240254ms).
[17:08:31.087] <TB1> INFO: 4929600 events read in total (248921ms).
[17:08:31.195] <TB1> INFO: Test took 249940ms.
[17:09:19.905] <TB1> INFO: ---> dac: Vcal name: TrimThr_trim7 ntrig: 5 dacrange: 0 .. 236 (-1/-1) hits flags = 528 (plus default)
[17:09:19.922] <TB1> INFO: dacScan split into 1 runs with ntrig = 5
[17:09:19.922] <TB1> INFO: run 1 of 1
[17:09:20.244] <TB1> INFO: Expecting 4929600 events.
[17:09:55.288] <TB1> INFO: 687405 events read in total (34451ms).
[17:10:29.440] <TB1> INFO: 1369520 events read in total (68603ms).
[17:11:04.132] <TB1> INFO: 2051000 events read in total (103295ms).
[17:11:38.391] <TB1> INFO: 2731295 events read in total (137554ms).
[17:12:12.521] <TB1> INFO: 3408155 events read in total (171684ms).
[17:12:46.745] <TB1> INFO: 4085065 events read in total (205908ms).
[17:13:21.019] <TB1> INFO: 4761940 events read in total (240182ms).
[17:13:29.695] <TB1> INFO: 4929600 events read in total (248858ms).
[17:13:29.791] <TB1> INFO: Test took 249870ms.
[17:14:20.196] <TB1> INFO: PixTestTrim::trimBitTest() done
[17:14:20.197] <TB1> INFO: PixTestTrim::doTest() done, duration: 3256 seconds
[17:14:20.197] <TB1> INFO: Decoding statistics:
[17:14:20.197] <TB1> INFO: General information:
[17:14:20.197] <TB1> INFO: 16bit words read: 0
[17:14:20.197] <TB1> INFO: valid events total: 0
[17:14:20.197] <TB1> INFO: empty events: 0
[17:14:20.197] <TB1> INFO: valid events with pixels: 0
[17:14:20.197] <TB1> INFO: valid pixel hits: 0
[17:14:20.197] <TB1> INFO: Event errors: 0
[17:14:20.198] <TB1> INFO: start marker: 0
[17:14:20.198] <TB1> INFO: stop marker: 0
[17:14:20.198] <TB1> INFO: overflow: 0
[17:14:20.198] <TB1> INFO: invalid 5bit words: 0
[17:14:20.198] <TB1> INFO: invalid XOR eye diagram: 0
[17:14:20.198] <TB1> INFO: frame (failed synchr.): 0
[17:14:20.198] <TB1> INFO: idle data (no TBM trl): 0
[17:14:20.198] <TB1> INFO: no data (only TBM hdr): 0
[17:14:20.198] <TB1> INFO: TBM errors: 0
[17:14:20.198] <TB1> INFO: flawed TBM headers: 0
[17:14:20.198] <TB1> INFO: flawed TBM trailers: 0
[17:14:20.198] <TB1> INFO: event ID mismatches: 0
[17:14:20.198] <TB1> INFO: ROC errors: 0
[17:14:20.198] <TB1> INFO: missing ROC header(s): 0
[17:14:20.198] <TB1> INFO: misplaced readback start: 0
[17:14:20.198] <TB1> INFO: Pixel decoding errors: 0
[17:14:20.198] <TB1> INFO: pixel data incomplete: 0
[17:14:20.198] <TB1> INFO: pixel address: 0
[17:14:20.198] <TB1> INFO: pulse height fill bit: 0
[17:14:20.198] <TB1> INFO: buffer corruption: 0
[17:14:21.189] <TB1> INFO: ######################################################################
[17:14:21.189] <TB1> INFO: PixTestPhOptimization::doTest() Ntrig = 10
[17:14:21.189] <TB1> INFO: ######################################################################
[17:14:21.506] <TB1> INFO: Expecting 41600 events.
[17:14:25.374] <TB1> INFO: 41600 events read in total (3274ms).
[17:14:25.375] <TB1> INFO: Test took 4184ms.
[17:14:25.899] <TB1> INFO: Expecting 41600 events.
[17:14:29.854] <TB1> INFO: 41600 events read in total (3362ms).
[17:14:29.855] <TB1> INFO: Test took 4270ms.
[17:14:30.207] <TB1> INFO: Expecting 41600 events.
[17:14:34.164] <TB1> INFO: 41600 events read in total (3364ms).
[17:14:34.166] <TB1> INFO: Test took 4281ms.
[17:14:34.507] <TB1> INFO: Expecting 41600 events.
[17:14:38.395] <TB1> INFO: 41600 events read in total (3296ms).
[17:14:38.396] <TB1> INFO: Test took 4201ms.
[17:14:38.742] <TB1> INFO: Expecting 41600 events.
[17:14:42.699] <TB1> INFO: 41600 events read in total (3364ms).
[17:14:42.700] <TB1> INFO: Test took 4273ms.
[17:14:43.045] <TB1> INFO: Expecting 41600 events.
[17:14:46.965] <TB1> INFO: 41600 events read in total (3323ms).
[17:14:46.968] <TB1> INFO: Test took 4239ms.
[17:14:47.321] <TB1> INFO: Expecting 41600 events.
[17:14:51.225] <TB1> INFO: 41600 events read in total (3311ms).
[17:14:51.229] <TB1> INFO: Test took 4226ms.
[17:14:51.575] <TB1> INFO: Expecting 41600 events.
[17:14:55.477] <TB1> INFO: 41600 events read in total (3309ms).
[17:14:55.478] <TB1> INFO: Test took 4216ms.
[17:14:55.824] <TB1> INFO: Expecting 41600 events.
[17:14:59.798] <TB1> INFO: 41600 events read in total (3382ms).
[17:14:59.799] <TB1> INFO: Test took 4291ms.
[17:15:00.141] <TB1> INFO: Expecting 41600 events.
[17:15:04.036] <TB1> INFO: 41600 events read in total (3299ms).
[17:15:04.037] <TB1> INFO: Test took 4209ms.
[17:15:04.383] <TB1> INFO: Expecting 41600 events.
[17:15:08.295] <TB1> INFO: 41600 events read in total (3319ms).
[17:15:08.295] <TB1> INFO: Test took 4229ms.
[17:15:08.640] <TB1> INFO: Expecting 41600 events.
[17:15:12.548] <TB1> INFO: 41600 events read in total (3315ms).
[17:15:12.548] <TB1> INFO: Test took 4224ms.
[17:15:12.889] <TB1> INFO: Expecting 41600 events.
[17:15:16.773] <TB1> INFO: 41600 events read in total (3291ms).
[17:15:16.774] <TB1> INFO: Test took 4197ms.
[17:15:17.120] <TB1> INFO: Expecting 41600 events.
[17:15:20.002] <TB1> INFO: 41600 events read in total (3290ms).
[17:15:20.003] <TB1> INFO: Test took 4201ms.
[17:15:21.349] <TB1> INFO: Expecting 41600 events.
[17:15:25.263] <TB1> INFO: 41600 events read in total (3321ms).
[17:15:25.264] <TB1> INFO: Test took 4232ms.
[17:15:25.610] <TB1> INFO: Expecting 41600 events.
[17:15:29.531] <TB1> INFO: 41600 events read in total (3329ms).
[17:15:29.532] <TB1> INFO: Test took 4239ms.
[17:15:29.879] <TB1> INFO: Expecting 41600 events.
[17:15:33.766] <TB1> INFO: 41600 events read in total (3295ms).
[17:15:33.767] <TB1> INFO: Test took 4206ms.
[17:15:34.113] <TB1> INFO: Expecting 41600 events.
[17:15:38.033] <TB1> INFO: 41600 events read in total (3327ms).
[17:15:38.033] <TB1> INFO: Test took 4238ms.
[17:15:38.383] <TB1> INFO: Expecting 41600 events.
[17:15:42.277] <TB1> INFO: 41600 events read in total (3301ms).
[17:15:42.278] <TB1> INFO: Test took 4216ms.
[17:15:42.622] <TB1> INFO: Expecting 41600 events.
[17:15:46.514] <TB1> INFO: 41600 events read in total (3299ms).
[17:15:46.515] <TB1> INFO: Test took 4208ms.
[17:15:46.861] <TB1> INFO: Expecting 41600 events.
[17:15:50.768] <TB1> INFO: 41600 events read in total (3315ms).
[17:15:50.774] <TB1> INFO: Test took 4230ms.
[17:15:51.118] <TB1> INFO: Expecting 41600 events.
[17:15:55.024] <TB1> INFO: 41600 events read in total (3313ms).
[17:15:55.024] <TB1> INFO: Test took 4220ms.
[17:15:55.367] <TB1> INFO: Expecting 41600 events.
[17:15:59.265] <TB1> INFO: 41600 events read in total (3305ms).
[17:15:59.266] <TB1> INFO: Test took 4213ms.
[17:15:59.613] <TB1> INFO: Expecting 41600 events.
[17:16:03.501] <TB1> INFO: 41600 events read in total (3295ms).
[17:16:03.502] <TB1> INFO: Test took 4208ms.
[17:16:03.843] <TB1> INFO: Expecting 41600 events.
[17:16:07.736] <TB1> INFO: 41600 events read in total (3301ms).
[17:16:07.737] <TB1> INFO: Test took 4206ms.
[17:16:08.078] <TB1> INFO: Expecting 41600 events.
[17:16:11.994] <TB1> INFO: 41600 events read in total (3322ms).
[17:16:11.995] <TB1> INFO: Test took 4229ms.
[17:16:12.338] <TB1> INFO: Expecting 41600 events.
[17:16:16.255] <TB1> INFO: 41600 events read in total (3319ms).
[17:16:16.255] <TB1> INFO: Test took 4232ms.
[17:16:16.601] <TB1> INFO: Expecting 41600 events.
[17:16:20.492] <TB1> INFO: 41600 events read in total (3298ms).
[17:16:20.493] <TB1> INFO: Test took 4208ms.
[17:16:20.847] <TB1> INFO: Expecting 41600 events.
[17:16:24.757] <TB1> INFO: 41600 events read in total (3318ms).
[17:16:24.758] <TB1> INFO: Test took 4233ms.
[17:16:25.057] <TB1> INFO: Expecting 2560 events.
[17:16:25.954] <TB1> INFO: 2560 events read in total (304ms).
[17:16:25.955] <TB1> INFO: Test took 1173ms.
[17:16:26.271] <TB1> INFO: Expecting 2560 events.
[17:16:27.168] <TB1> INFO: 2560 events read in total (305ms).
[17:16:27.168] <TB1> INFO: Test took 1213ms.
[17:16:27.479] <TB1> INFO: Expecting 2560 events.
[17:16:28.378] <TB1> INFO: 2560 events read in total (306ms).
[17:16:28.378] <TB1> INFO: Test took 1209ms.
[17:16:28.689] <TB1> INFO: Expecting 2560 events.
[17:16:29.587] <TB1> INFO: 2560 events read in total (306ms).
[17:16:29.587] <TB1> INFO: Test took 1208ms.
[17:16:29.897] <TB1> INFO: Expecting 2560 events.
[17:16:30.789] <TB1> INFO: 2560 events read in total (300ms).
[17:16:30.793] <TB1> INFO: Test took 1204ms.
[17:16:31.104] <TB1> INFO: Expecting 2560 events.
[17:16:31.993] <TB1> INFO: 2560 events read in total (296ms).
[17:16:31.993] <TB1> INFO: Test took 1193ms.
[17:16:32.303] <TB1> INFO: Expecting 2560 events.
[17:16:33.193] <TB1> INFO: 2560 events read in total (298ms).
[17:16:33.194] <TB1> INFO: Test took 1197ms.
[17:16:33.503] <TB1> INFO: Expecting 2560 events.
[17:16:34.393] <TB1> INFO: 2560 events read in total (297ms).
[17:16:34.393] <TB1> INFO: Test took 1197ms.
[17:16:34.704] <TB1> INFO: Expecting 2560 events.
[17:16:35.604] <TB1> INFO: 2560 events read in total (308ms).
[17:16:35.604] <TB1> INFO: Test took 1210ms.
[17:16:35.914] <TB1> INFO: Expecting 2560 events.
[17:16:36.807] <TB1> INFO: 2560 events read in total (300ms).
[17:16:36.808] <TB1> INFO: Test took 1203ms.
[17:16:37.118] <TB1> INFO: Expecting 2560 events.
[17:16:38.007] <TB1> INFO: 2560 events read in total (296ms).
[17:16:38.008] <TB1> INFO: Test took 1199ms.
[17:16:38.317] <TB1> INFO: Expecting 2560 events.
[17:16:39.208] <TB1> INFO: 2560 events read in total (299ms).
[17:16:39.209] <TB1> INFO: Test took 1200ms.
[17:16:39.523] <TB1> INFO: Expecting 2560 events.
[17:16:40.418] <TB1> INFO: 2560 events read in total (302ms).
[17:16:40.418] <TB1> INFO: Test took 1205ms.
[17:16:40.729] <TB1> INFO: Expecting 2560 events.
[17:16:41.628] <TB1> INFO: 2560 events read in total (306ms).
[17:16:41.628] <TB1> INFO: Test took 1208ms.
[17:16:41.939] <TB1> INFO: Expecting 2560 events.
[17:16:42.837] <TB1> INFO: 2560 events read in total (307ms).
[17:16:42.838] <TB1> INFO: Test took 1209ms.
[17:16:43.148] <TB1> INFO: Expecting 2560 events.
[17:16:44.042] <TB1> INFO: 2560 events read in total (301ms).
[17:16:44.043] <TB1> INFO: Test took 1204ms.
[17:16:44.052] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[17:16:44.356] <TB1> INFO: Expecting 655360 events.
[17:17:00.252] <TB1> INFO: 655360 events read in total (15304ms).
[17:17:00.266] <TB1> INFO: Expecting 655360 events.
[17:17:16.141] <TB1> INFO: 655360 events read in total (15471ms).
[17:17:16.164] <TB1> INFO: Expecting 655360 events.
[17:17:31.812] <TB1> INFO: 655360 events read in total (15244ms).
[17:17:31.839] <TB1> INFO: Expecting 655360 events.
[17:17:47.442] <TB1> INFO: 655360 events read in total (15199ms).
[17:17:47.474] <TB1> INFO: Expecting 655360 events.
[17:18:03.392] <TB1> INFO: 655360 events read in total (15514ms).
[17:18:03.429] <TB1> INFO: Expecting 655360 events.
[17:18:19.220] <TB1> INFO: 655360 events read in total (15387ms).
[17:18:19.264] <TB1> INFO: Expecting 655360 events.
[17:18:35.070] <TB1> INFO: 655360 events read in total (15402ms).
[17:18:35.118] <TB1> INFO: Expecting 655360 events.
[17:18:50.915] <TB1> INFO: 655360 events read in total (15393ms).
[17:18:50.969] <TB1> INFO: Expecting 655360 events.
[17:19:06.719] <TB1> INFO: 655360 events read in total (15346ms).
[17:19:06.779] <TB1> INFO: Expecting 655360 events.
[17:19:22.569] <TB1> INFO: 655360 events read in total (15386ms).
[17:19:22.642] <TB1> INFO: Expecting 655360 events.
[17:19:38.366] <TB1> INFO: 655360 events read in total (15320ms).
[17:19:38.444] <TB1> INFO: Expecting 655360 events.
[17:19:54.239] <TB1> INFO: 655360 events read in total (15391ms).
[17:19:54.324] <TB1> INFO: Expecting 655360 events.
[17:20:10.066] <TB1> INFO: 655360 events read in total (15338ms).
[17:20:10.157] <TB1> INFO: Expecting 655360 events.
[17:20:25.879] <TB1> INFO: 655360 events read in total (15317ms).
[17:20:25.975] <TB1> INFO: Expecting 655360 events.
[17:20:41.673] <TB1> INFO: 655360 events read in total (15294ms).
[17:20:41.778] <TB1> INFO: Expecting 655360 events.
[17:20:57.502] <TB1> INFO: 655360 events read in total (15320ms).
[17:20:57.613] <TB1> INFO: Test took 253561ms.
[17:20:57.729] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[17:20:57.987] <TB1> INFO: Expecting 655360 events.
[17:21:13.863] <TB1> INFO: 655360 events read in total (15284ms).
[17:21:13.877] <TB1> INFO: Expecting 655360 events.
[17:21:29.269] <TB1> INFO: 655360 events read in total (14988ms).
[17:21:29.287] <TB1> INFO: Expecting 655360 events.
[17:21:44.476] <TB1> INFO: 655360 events read in total (14784ms).
[17:21:44.500] <TB1> INFO: Expecting 655360 events.
[17:21:59.721] <TB1> INFO: 655360 events read in total (14817ms).
[17:21:59.750] <TB1> INFO: Expecting 655360 events.
[17:22:14.798] <TB1> INFO: 655360 events read in total (14643ms).
[17:22:14.831] <TB1> INFO: Expecting 655360 events.
[17:22:29.925] <TB1> INFO: 655360 events read in total (14689ms).
[17:22:29.963] <TB1> INFO: Expecting 655360 events.
[17:22:45.187] <TB1> INFO: 655360 events read in total (14819ms).
[17:22:45.231] <TB1> INFO: Expecting 655360 events.
[17:23:00.748] <TB1> INFO: 655360 events read in total (15113ms).
[17:23:00.797] <TB1> INFO: Expecting 655360 events.
[17:23:16.167] <TB1> INFO: 655360 events read in total (14965ms).
[17:23:16.223] <TB1> INFO: Expecting 655360 events.
[17:23:31.750] <TB1> INFO: 655360 events read in total (15123ms).
[17:23:31.815] <TB1> INFO: Expecting 655360 events.
[17:23:47.300] <TB1> INFO: 655360 events read in total (15080ms).
[17:23:47.372] <TB1> INFO: Expecting 655360 events.
[17:24:02.771] <TB1> INFO: 655360 events read in total (14995ms).
[17:24:02.847] <TB1> INFO: Expecting 655360 events.
[17:24:18.262] <TB1> INFO: 655360 events read in total (15011ms).
[17:24:18.346] <TB1> INFO: Expecting 655360 events.
[17:24:33.750] <TB1> INFO: 655360 events read in total (15000ms).
[17:24:33.839] <TB1> INFO: Expecting 655360 events.
[17:24:49.348] <TB1> INFO: 655360 events read in total (15104ms).
[17:24:49.444] <TB1> INFO: Expecting 655360 events.
[17:25:04.847] <TB1> INFO: 655360 events read in total (14998ms).
[17:25:04.947] <TB1> INFO: Test took 247218ms.
[17:25:05.244] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[17:25:05.251] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[17:25:05.259] <TB1> INFO: safety margin for low PH: adding 1, margin is now 21
[17:25:05.266] <TB1> INFO: safety margin for low PH: adding 2, margin is now 22
[17:25:05.274] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[17:25:05.281] <TB1> INFO: safety margin for low PH: adding 1, margin is now 21
[17:25:05.289] <TB1> INFO: safety margin for low PH: adding 2, margin is now 22
[17:25:05.296] <TB1> INFO: safety margin for low PH: adding 3, margin is now 23
[17:25:05.303] <TB1> INFO: safety margin for low PH: adding 4, margin is now 24
[17:25:05.311] <TB1> INFO: safety margin for low PH: adding 5, margin is now 25
[17:25:05.319] <TB1> INFO: safety margin for low PH: adding 6, margin is now 26
[17:25:05.326] <TB1> INFO: safety margin for low PH: adding 7, margin is now 27
[17:25:05.333] <TB1> INFO: safety margin for low PH: adding 8, margin is now 28
[17:25:05.341] <TB1> INFO: safety margin for low PH: adding 9, margin is now 29
[17:25:05.348] <TB1> INFO: safety margin for low PH: adding 10, margin is now 30
[17:25:05.356] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[17:25:05.363] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[17:25:05.370] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[17:25:05.379] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[17:25:05.387] <TB1> INFO: safety margin for low PH: adding 1, margin is now 21
[17:25:05.394] <TB1> INFO: safety margin for low PH: adding 2, margin is now 22
[17:25:05.401] <TB1> INFO: safety margin for low PH: adding 3, margin is now 23
[17:25:05.409] <TB1> INFO: safety margin for low PH: adding 4, margin is now 24
[17:25:05.416] <TB1> INFO: safety margin for low PH: adding 5, margin is now 25
[17:25:05.424] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[17:25:05.431] <TB1> INFO: safety margin for low PH: adding 1, margin is now 21
[17:25:05.438] <TB1> INFO: safety margin for low PH: adding 2, margin is now 22
[17:25:05.448] <TB1> INFO: safety margin for low PH: adding 3, margin is now 23
[17:25:05.455] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[17:25:05.462] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[17:25:05.469] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[17:25:05.479] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[17:25:05.486] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[17:25:05.493] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[17:25:05.500] <TB1> INFO: safety margin for low PH: adding 1, margin is now 21
[17:25:05.508] <TB1> INFO: safety margin for low PH: adding 2, margin is now 22
[17:25:05.516] <TB1> INFO: safety margin for low PH: adding 3, margin is now 23
[17:25:05.523] <TB1> INFO: safety margin for low PH: adding 4, margin is now 24
[17:25:05.530] <TB1> INFO: safety margin for low PH: adding 5, margin is now 25
[17:25:05.538] <TB1> INFO: safety margin for low PH: adding 6, margin is now 26
[17:25:05.545] <TB1> INFO: safety margin for low PH: adding 7, margin is now 27
[17:25:05.553] <TB1> INFO: safety margin for low PH: adding 8, margin is now 28
[17:25:05.560] <TB1> INFO: safety margin for low PH: adding 9, margin is now 29
[17:25:05.567] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[17:25:05.576] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[17:25:05.653] <TB1> INFO: write dac parameters into /home/sensor/david/FullQualification/data/M1020_FullQualification_2016-10-19_14h52m_1476881545//001_Fulltest_m20//dacParameters35_C0.dat
[17:25:05.653] <TB1> INFO: write dac parameters into /home/sensor/david/FullQualification/data/M1020_FullQualification_2016-10-19_14h52m_1476881545//001_Fulltest_m20//dacParameters35_C1.dat
[17:25:05.653] <TB1> INFO: write dac parameters into /home/sensor/david/FullQualification/data/M1020_FullQualification_2016-10-19_14h52m_1476881545//001_Fulltest_m20//dacParameters35_C2.dat
[17:25:05.653] <TB1> INFO: write dac parameters into /home/sensor/david/FullQualification/data/M1020_FullQualification_2016-10-19_14h52m_1476881545//001_Fulltest_m20//dacParameters35_C3.dat
[17:25:05.654] <TB1> INFO: write dac parameters into /home/sensor/david/FullQualification/data/M1020_FullQualification_2016-10-19_14h52m_1476881545//001_Fulltest_m20//dacParameters35_C4.dat
[17:25:05.654] <TB1> INFO: write dac parameters into /home/sensor/david/FullQualification/data/M1020_FullQualification_2016-10-19_14h52m_1476881545//001_Fulltest_m20//dacParameters35_C5.dat
[17:25:05.654] <TB1> INFO: write dac parameters into /home/sensor/david/FullQualification/data/M1020_FullQualification_2016-10-19_14h52m_1476881545//001_Fulltest_m20//dacParameters35_C6.dat
[17:25:05.654] <TB1> INFO: write dac parameters into /home/sensor/david/FullQualification/data/M1020_FullQualification_2016-10-19_14h52m_1476881545//001_Fulltest_m20//dacParameters35_C7.dat
[17:25:05.654] <TB1> INFO: write dac parameters into /home/sensor/david/FullQualification/data/M1020_FullQualification_2016-10-19_14h52m_1476881545//001_Fulltest_m20//dacParameters35_C8.dat
[17:25:05.654] <TB1> INFO: write dac parameters into /home/sensor/david/FullQualification/data/M1020_FullQualification_2016-10-19_14h52m_1476881545//001_Fulltest_m20//dacParameters35_C9.dat
[17:25:05.654] <TB1> INFO: write dac parameters into /home/sensor/david/FullQualification/data/M1020_FullQualification_2016-10-19_14h52m_1476881545//001_Fulltest_m20//dacParameters35_C10.dat
[17:25:05.654] <TB1> INFO: write dac parameters into /home/sensor/david/FullQualification/data/M1020_FullQualification_2016-10-19_14h52m_1476881545//001_Fulltest_m20//dacParameters35_C11.dat
[17:25:05.655] <TB1> INFO: write dac parameters into /home/sensor/david/FullQualification/data/M1020_FullQualification_2016-10-19_14h52m_1476881545//001_Fulltest_m20//dacParameters35_C12.dat
[17:25:05.655] <TB1> INFO: write dac parameters into /home/sensor/david/FullQualification/data/M1020_FullQualification_2016-10-19_14h52m_1476881545//001_Fulltest_m20//dacParameters35_C13.dat
[17:25:05.655] <TB1> INFO: write dac parameters into /home/sensor/david/FullQualification/data/M1020_FullQualification_2016-10-19_14h52m_1476881545//001_Fulltest_m20//dacParameters35_C14.dat
[17:25:05.655] <TB1> INFO: write dac parameters into /home/sensor/david/FullQualification/data/M1020_FullQualification_2016-10-19_14h52m_1476881545//001_Fulltest_m20//dacParameters35_C15.dat
[17:25:05.955] <TB1> INFO: Expecting 41600 events.
[17:25:09.246] <TB1> INFO: 41600 events read in total (2697ms).
[17:25:09.247] <TB1> INFO: Test took 3587ms.
[17:25:09.785] <TB1> INFO: Expecting 41600 events.
[17:25:12.927] <TB1> INFO: 41600 events read in total (2549ms).
[17:25:12.928] <TB1> INFO: Test took 3436ms.
[17:25:13.456] <TB1> INFO: Expecting 41600 events.
[17:25:16.750] <TB1> INFO: 41600 events read in total (2700ms).
[17:25:16.751] <TB1> INFO: Test took 3589ms.
[17:25:16.996] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[17:25:17.087] <TB1> INFO: Expecting 2560 events.
[17:25:17.979] <TB1> INFO: 2560 events read in total (299ms).
[17:25:17.979] <TB1> INFO: Test took 983ms.
[17:25:17.984] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[17:25:18.290] <TB1> INFO: Expecting 2560 events.
[17:25:19.183] <TB1> INFO: 2560 events read in total (300ms).
[17:25:19.183] <TB1> INFO: Test took 1199ms.
[17:25:19.187] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[17:25:19.493] <TB1> INFO: Expecting 2560 events.
[17:25:20.386] <TB1> INFO: 2560 events read in total (300ms).
[17:25:20.387] <TB1> INFO: Test took 1200ms.
[17:25:20.394] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[17:25:20.697] <TB1> INFO: Expecting 2560 events.
[17:25:21.589] <TB1> INFO: 2560 events read in total (299ms).
[17:25:21.590] <TB1> INFO: Test took 1196ms.
[17:25:21.597] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[17:25:21.900] <TB1> INFO: Expecting 2560 events.
[17:25:22.793] <TB1> INFO: 2560 events read in total (300ms).
[17:25:22.794] <TB1> INFO: Test took 1197ms.
[17:25:22.799] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[17:25:23.104] <TB1> INFO: Expecting 2560 events.
[17:25:23.997] <TB1> INFO: 2560 events read in total (299ms).
[17:25:23.997] <TB1> INFO: Test took 1199ms.
[17:25:23.004] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[17:25:24.308] <TB1> INFO: Expecting 2560 events.
[17:25:25.201] <TB1> INFO: 2560 events read in total (300ms).
[17:25:25.201] <TB1> INFO: Test took 1197ms.
[17:25:25.206] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[17:25:25.511] <TB1> INFO: Expecting 2560 events.
[17:25:26.404] <TB1> INFO: 2560 events read in total (299ms).
[17:25:26.405] <TB1> INFO: Test took 1199ms.
[17:25:26.410] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[17:25:26.715] <TB1> INFO: Expecting 2560 events.
[17:25:27.604] <TB1> INFO: 2560 events read in total (295ms).
[17:25:27.604] <TB1> INFO: Test took 1194ms.
[17:25:27.608] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[17:25:27.914] <TB1> INFO: Expecting 2560 events.
[17:25:28.804] <TB1> INFO: 2560 events read in total (296ms).
[17:25:28.804] <TB1> INFO: Test took 1196ms.
[17:25:28.808] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[17:25:29.114] <TB1> INFO: Expecting 2560 events.
[17:25:29.002] <TB1> INFO: 2560 events read in total (295ms).
[17:25:29.003] <TB1> INFO: Test took 1195ms.
[17:25:30.010] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[17:25:30.313] <TB1> INFO: Expecting 2560 events.
[17:25:31.202] <TB1> INFO: 2560 events read in total (295ms).
[17:25:31.202] <TB1> INFO: Test took 1192ms.
[17:25:31.207] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[17:25:31.512] <TB1> INFO: Expecting 2560 events.
[17:25:32.404] <TB1> INFO: 2560 events read in total (298ms).
[17:25:32.405] <TB1> INFO: Test took 1198ms.
[17:25:32.408] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[17:25:32.715] <TB1> INFO: Expecting 2560 events.
[17:25:33.603] <TB1> INFO: 2560 events read in total (294ms).
[17:25:33.604] <TB1> INFO: Test took 1196ms.
[17:25:33.609] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[17:25:33.914] <TB1> INFO: Expecting 2560 events.
[17:25:34.804] <TB1> INFO: 2560 events read in total (296ms).
[17:25:34.804] <TB1> INFO: Test took 1195ms.
[17:25:34.809] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[17:25:35.115] <TB1> INFO: Expecting 2560 events.
[17:25:35.003] <TB1> INFO: 2560 events read in total (295ms).
[17:25:35.003] <TB1> INFO: Test took 1194ms.
[17:25:36.011] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[17:25:36.314] <TB1> INFO: Expecting 2560 events.
[17:25:37.203] <TB1> INFO: 2560 events read in total (296ms).
[17:25:37.204] <TB1> INFO: Test took 1194ms.
[17:25:37.210] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[17:25:37.514] <TB1> INFO: Expecting 2560 events.
[17:25:38.402] <TB1> INFO: 2560 events read in total (295ms).
[17:25:38.403] <TB1> INFO: Test took 1193ms.
[17:25:38.410] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[17:25:38.714] <TB1> INFO: Expecting 2560 events.
[17:25:39.602] <TB1> INFO: 2560 events read in total (295ms).
[17:25:39.603] <TB1> INFO: Test took 1193ms.
[17:25:39.607] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[17:25:39.913] <TB1> INFO: Expecting 2560 events.
[17:25:40.801] <TB1> INFO: 2560 events read in total (295ms).
[17:25:40.802] <TB1> INFO: Test took 1195ms.
[17:25:40.809] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[17:25:41.112] <TB1> INFO: Expecting 2560 events.
[17:25:41.001] <TB1> INFO: 2560 events read in total (296ms).
[17:25:41.001] <TB1> INFO: Test took 1192ms.
[17:25:42.008] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[17:25:42.312] <TB1> INFO: Expecting 2560 events.
[17:25:43.200] <TB1> INFO: 2560 events read in total (295ms).
[17:25:43.201] <TB1> INFO: Test took 1193ms.
[17:25:43.208] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[17:25:43.511] <TB1> INFO: Expecting 2560 events.
[17:25:44.399] <TB1> INFO: 2560 events read in total (294ms).
[17:25:44.400] <TB1> INFO: Test took 1192ms.
[17:25:44.407] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[17:25:44.710] <TB1> INFO: Expecting 2560 events.
[17:25:45.598] <TB1> INFO: 2560 events read in total (295ms).
[17:25:45.598] <TB1> INFO: Test took 1191ms.
[17:25:45.604] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[17:25:45.909] <TB1> INFO: Expecting 2560 events.
[17:25:46.802] <TB1> INFO: 2560 events read in total (300ms).
[17:25:46.802] <TB1> INFO: Test took 1198ms.
[17:25:46.807] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[17:25:47.113] <TB1> INFO: Expecting 2560 events.
[17:25:48.006] <TB1> INFO: 2560 events read in total (300ms).
[17:25:48.006] <TB1> INFO: Test took 1199ms.
[17:25:48.013] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[17:25:48.317] <TB1> INFO: Expecting 2560 events.
[17:25:49.209] <TB1> INFO: 2560 events read in total (299ms).
[17:25:49.210] <TB1> INFO: Test took 1198ms.
[17:25:49.215] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[17:25:49.521] <TB1> INFO: Expecting 2560 events.
[17:25:50.413] <TB1> INFO: 2560 events read in total (299ms).
[17:25:50.413] <TB1> INFO: Test took 1198ms.
[17:25:50.421] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[17:25:50.724] <TB1> INFO: Expecting 2560 events.
[17:25:51.617] <TB1> INFO: 2560 events read in total (300ms).
[17:25:51.618] <TB1> INFO: Test took 1197ms.
[17:25:51.624] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[17:25:51.928] <TB1> INFO: Expecting 2560 events.
[17:25:52.821] <TB1> INFO: 2560 events read in total (299ms).
[17:25:52.822] <TB1> INFO: Test took 1198ms.
[17:25:52.830] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[17:25:53.132] <TB1> INFO: Expecting 2560 events.
[17:25:54.025] <TB1> INFO: 2560 events read in total (299ms).
[17:25:54.026] <TB1> INFO: Test took 1196ms.
[17:25:54.031] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[17:25:54.336] <TB1> INFO: Expecting 2560 events.
[17:25:55.229] <TB1> INFO: 2560 events read in total (299ms).
[17:25:55.230] <TB1> INFO: Test took 1200ms.
[17:25:55.789] <TB1> INFO: PixTestPhOptimization::doTest() done, duration: 694 seconds
[17:25:55.789] <TB1> INFO: PH scale (per ROC): 42 45 48 57 61 68 48 48 50 64 68 49 56 48 64 33
[17:25:55.789] <TB1> INFO: PH offset (per ROC): 105 107 112 114 124 115 95 106 108 130 122 95 127 133 124 100
[17:25:55.794] <TB1> INFO: Decoding statistics:
[17:25:55.794] <TB1> INFO: General information:
[17:25:55.794] <TB1> INFO: 16bit words read: 127882
[17:25:55.794] <TB1> INFO: valid events total: 20480
[17:25:55.794] <TB1> INFO: empty events: 17979
[17:25:55.794] <TB1> INFO: valid events with pixels: 2501
[17:25:55.795] <TB1> INFO: valid pixel hits: 2501
[17:25:55.795] <TB1> INFO: Event errors: 0
[17:25:55.795] <TB1> INFO: start marker: 0
[17:25:55.795] <TB1> INFO: stop marker: 0
[17:25:55.795] <TB1> INFO: overflow: 0
[17:25:55.795] <TB1> INFO: invalid 5bit words: 0
[17:25:55.795] <TB1> INFO: invalid XOR eye diagram: 0
[17:25:55.795] <TB1> INFO: frame (failed synchr.): 0
[17:25:55.795] <TB1> INFO: idle data (no TBM trl): 0
[17:25:55.795] <TB1> INFO: no data (only TBM hdr): 0
[17:25:55.795] <TB1> INFO: TBM errors: 0
[17:25:55.795] <TB1> INFO: flawed TBM headers: 0
[17:25:55.795] <TB1> INFO: flawed TBM trailers: 0
[17:25:55.795] <TB1> INFO: event ID mismatches: 0
[17:25:55.795] <TB1> INFO: ROC errors: 0
[17:25:55.795] <TB1> INFO: missing ROC header(s): 0
[17:25:55.795] <TB1> INFO: misplaced readback start: 0
[17:25:55.795] <TB1> INFO: Pixel decoding errors: 0
[17:25:55.795] <TB1> INFO: pixel data incomplete: 0
[17:25:55.795] <TB1> INFO: pixel address: 0
[17:25:55.795] <TB1> INFO: pulse height fill bit: 0
[17:25:55.795] <TB1> INFO: buffer corruption: 0
[17:25:56.040] <TB1> INFO: ######################################################################
[17:25:56.040] <TB1> INFO: PixTestGainPedestal::fullTest() ntrig = 10
[17:25:56.040] <TB1> INFO: ######################################################################
[17:25:56.057] <TB1> INFO: scanning low vcal = 10
[17:25:56.353] <TB1> INFO: Expecting 41600 events.
[17:25:59.000] <TB1> INFO: 41600 events read in total (3054ms).
[17:25:59.000] <TB1> INFO: Test took 3943ms.
[17:26:00.007] <TB1> INFO: scanning low vcal = 20
[17:26:00.303] <TB1> INFO: Expecting 41600 events.
[17:26:03.950] <TB1> INFO: 41600 events read in total (3053ms).
[17:26:03.950] <TB1> INFO: Test took 3943ms.
[17:26:03.957] <TB1> INFO: scanning low vcal = 30
[17:26:04.254] <TB1> INFO: Expecting 41600 events.
[17:26:07.991] <TB1> INFO: 41600 events read in total (3144ms).
[17:26:07.994] <TB1> INFO: Test took 4037ms.
[17:26:07.999] <TB1> INFO: scanning low vcal = 40
[17:26:08.294] <TB1> INFO: Expecting 41600 events.
[17:26:12.367] <TB1> INFO: 41600 events read in total (3479ms).
[17:26:12.368] <TB1> INFO: Test took 4369ms.
[17:26:12.373] <TB1> INFO: scanning low vcal = 50
[17:26:12.668] <TB1> INFO: Expecting 41600 events.
[17:26:16.801] <TB1> INFO: 41600 events read in total (3539ms).
[17:26:16.802] <TB1> INFO: Test took 4429ms.
[17:26:16.808] <TB1> INFO: scanning low vcal = 60
[17:26:17.103] <TB1> INFO: Expecting 41600 events.
[17:26:21.229] <TB1> INFO: 41600 events read in total (3533ms).
[17:26:21.230] <TB1> INFO: Test took 4422ms.
[17:26:21.235] <TB1> INFO: scanning low vcal = 70
[17:26:21.529] <TB1> INFO: Expecting 41600 events.
[17:26:25.658] <TB1> INFO: 41600 events read in total (3536ms).
[17:26:25.658] <TB1> INFO: Test took 4423ms.
[17:26:25.664] <TB1> INFO: scanning low vcal = 80
[17:26:25.965] <TB1> INFO: Expecting 41600 events.
[17:26:30.097] <TB1> INFO: 41600 events read in total (3539ms).
[17:26:30.098] <TB1> INFO: Test took 4427ms.
[17:26:30.104] <TB1> INFO: scanning low vcal = 90
[17:26:30.406] <TB1> INFO: Expecting 41600 events.
[17:26:34.543] <TB1> INFO: 41600 events read in total (3543ms).
[17:26:34.544] <TB1> INFO: Test took 4433ms.
[17:26:34.552] <TB1> INFO: scanning low vcal = 100
[17:26:34.847] <TB1> INFO: Expecting 41600 events.
[17:26:38.976] <TB1> INFO: 41600 events read in total (3536ms).
[17:26:38.976] <TB1> INFO: Test took 4424ms.
[17:26:38.982] <TB1> INFO: scanning low vcal = 110
[17:26:39.278] <TB1> INFO: Expecting 41600 events.
[17:26:43.410] <TB1> INFO: 41600 events read in total (3539ms).
[17:26:43.411] <TB1> INFO: Test took 4428ms.
[17:26:43.417] <TB1> INFO: scanning low vcal = 120
[17:26:43.711] <TB1> INFO: Expecting 41600 events.
[17:26:47.834] <TB1> INFO: 41600 events read in total (3529ms).
[17:26:47.835] <TB1> INFO: Test took 4418ms.
[17:26:47.841] <TB1> INFO: scanning low vcal = 130
[17:26:48.133] <TB1> INFO: Expecting 41600 events.
[17:26:52.270] <TB1> INFO: 41600 events read in total (3543ms).
[17:26:52.271] <TB1> INFO: Test took 4430ms.
[17:26:52.279] <TB1> INFO: scanning low vcal = 140
[17:26:52.579] <TB1> INFO: Expecting 41600 events.
[17:26:56.711] <TB1> INFO: 41600 events read in total (3539ms).
[17:26:56.711] <TB1> INFO: Test took 4427ms.
[17:26:56.716] <TB1> INFO: scanning low vcal = 150
[17:26:57.010] <TB1> INFO: Expecting 41600 events.
[17:27:01.147] <TB1> INFO: 41600 events read in total (3543ms).
[17:27:01.148] <TB1> INFO: Test took 4432ms.
[17:27:01.154] <TB1> INFO: scanning low vcal = 160
[17:27:01.450] <TB1> INFO: Expecting 41600 events.
[17:27:05.582] <TB1> INFO: 41600 events read in total (3539ms).
[17:27:05.583] <TB1> INFO: Test took 4429ms.
[17:27:05.590] <TB1> INFO: scanning low vcal = 170
[17:27:05.884] <TB1> INFO: Expecting 41600 events.
[17:27:10.023] <TB1> INFO: 41600 events read in total (3545ms).
[17:27:10.024] <TB1> INFO: Test took 4434ms.
[17:27:10.033] <TB1> INFO: scanning low vcal = 180
[17:27:10.330] <TB1> INFO: Expecting 41600 events.
[17:27:14.464] <TB1> INFO: 41600 events read in total (3541ms).
[17:27:14.465] <TB1> INFO: Test took 4432ms.
[17:27:14.470] <TB1> INFO: scanning low vcal = 190
[17:27:14.765] <TB1> INFO: Expecting 41600 events.
[17:27:18.901] <TB1> INFO: 41600 events read in total (3543ms).
[17:27:18.901] <TB1> INFO: Test took 4431ms.
[17:27:18.908] <TB1> INFO: scanning low vcal = 200
[17:27:19.207] <TB1> INFO: Expecting 41600 events.
[17:27:23.336] <TB1> INFO: 41600 events read in total (3536ms).
[17:27:23.337] <TB1> INFO: Test took 4423ms.
[17:27:23.344] <TB1> INFO: scanning low vcal = 210
[17:27:23.643] <TB1> INFO: Expecting 41600 events.
[17:27:27.768] <TB1> INFO: 41600 events read in total (3532ms).
[17:27:27.769] <TB1> INFO: Test took 4420ms.
[17:27:27.773] <TB1> INFO: scanning low vcal = 220
[17:27:28.068] <TB1> INFO: Expecting 41600 events.
[17:27:32.195] <TB1> INFO: 41600 events read in total (3533ms).
[17:27:32.196] <TB1> INFO: Test took 4421ms.
[17:27:32.200] <TB1> INFO: scanning low vcal = 230
[17:27:32.492] <TB1> INFO: Expecting 41600 events.
[17:27:36.618] <TB1> INFO: 41600 events read in total (3533ms).
[17:27:36.619] <TB1> INFO: Test took 4419ms.
[17:27:36.627] <TB1> INFO: scanning low vcal = 240
[17:27:36.924] <TB1> INFO: Expecting 41600 events.
[17:27:41.062] <TB1> INFO: 41600 events read in total (3545ms).
[17:27:41.063] <TB1> INFO: Test took 4432ms.
[17:27:41.069] <TB1> INFO: scanning low vcal = 250
[17:27:41.365] <TB1> INFO: Expecting 41600 events.
[17:27:45.500] <TB1> INFO: 41600 events read in total (3541ms).
[17:27:45.501] <TB1> INFO: Test took 4431ms.
[17:27:45.510] <TB1> INFO: scanning high vcal = 30 (= 210 in low range)
[17:27:45.806] <TB1> INFO: Expecting 41600 events.
[17:27:49.943] <TB1> INFO: 41600 events read in total (3544ms).
[17:27:49.944] <TB1> INFO: Test took 4433ms.
[17:27:49.951] <TB1> INFO: scanning high vcal = 50 (= 350 in low range)
[17:27:50.247] <TB1> INFO: Expecting 41600 events.
[17:27:54.374] <TB1> INFO: 41600 events read in total (3534ms).
[17:27:54.375] <TB1> INFO: Test took 4423ms.
[17:27:54.380] <TB1> INFO: scanning high vcal = 70 (= 490 in low range)
[17:27:54.674] <TB1> INFO: Expecting 41600 events.
[17:27:58.809] <TB1> INFO: 41600 events read in total (3542ms).
[17:27:58.810] <TB1> INFO: Test took 4430ms.
[17:27:58.816] <TB1> INFO: scanning high vcal = 90 (= 630 in low range)
[17:27:59.117] <TB1> INFO: Expecting 41600 events.
[17:28:03.254] <TB1> INFO: 41600 events read in total (3543ms).
[17:28:03.255] <TB1> INFO: Test took 4432ms.
[17:28:03.260] <TB1> INFO: scanning high vcal = 200 (= 1400 in low range)
[17:28:03.556] <TB1> INFO: Expecting 41600 events.
[17:28:07.688] <TB1> INFO: 41600 events read in total (3539ms).
[17:28:07.689] <TB1> INFO: Test took 4428ms.
[17:28:08.375] <TB1> INFO: PixTestGainPedestal::measure() done
[17:28:58.328] <TB1> INFO: PixTestGainPedestal::fit() done
[17:28:58.328] <TB1> INFO: non-linearity mean: 0.933 0.931 0.957 0.956 0.983 0.985 0.932 0.954 0.964 0.984 0.985 0.920 0.980 0.983 0.983 1.072
[17:28:58.328] <TB1> INFO: non-linearity RMS: 0.126 0.097 0.036 0.056 0.004 0.004 0.115 0.079 0.029 0.004 0.002 0.104 0.004 0.003 0.004 0.146
[17:28:58.328] <TB1> INFO: write gain/ped parameters into /home/sensor/david/FullQualification/data/M1020_FullQualification_2016-10-19_14h52m_1476881545//001_Fulltest_m20//phCalibrationFitErr35_C0.dat
[17:28:58.353] <TB1> INFO: write gain/ped parameters into /home/sensor/david/FullQualification/data/M1020_FullQualification_2016-10-19_14h52m_1476881545//001_Fulltest_m20//phCalibrationFitErr35_C1.dat
[17:28:58.377] <TB1> INFO: write gain/ped parameters into /home/sensor/david/FullQualification/data/M1020_FullQualification_2016-10-19_14h52m_1476881545//001_Fulltest_m20//phCalibrationFitErr35_C2.dat
[17:28:58.402] <TB1> INFO: write gain/ped parameters into /home/sensor/david/FullQualification/data/M1020_FullQualification_2016-10-19_14h52m_1476881545//001_Fulltest_m20//phCalibrationFitErr35_C3.dat
[17:28:58.426] <TB1> INFO: write gain/ped parameters into /home/sensor/david/FullQualification/data/M1020_FullQualification_2016-10-19_14h52m_1476881545//001_Fulltest_m20//phCalibrationFitErr35_C4.dat
[17:28:58.450] <TB1> INFO: write gain/ped parameters into /home/sensor/david/FullQualification/data/M1020_FullQualification_2016-10-19_14h52m_1476881545//001_Fulltest_m20//phCalibrationFitErr35_C5.dat
[17:28:58.475] <TB1> INFO: write gain/ped parameters into /home/sensor/david/FullQualification/data/M1020_FullQualification_2016-10-19_14h52m_1476881545//001_Fulltest_m20//phCalibrationFitErr35_C6.dat
[17:28:58.499] <TB1> INFO: write gain/ped parameters into /home/sensor/david/FullQualification/data/M1020_FullQualification_2016-10-19_14h52m_1476881545//001_Fulltest_m20//phCalibrationFitErr35_C7.dat
[17:28:58.523] <TB1> INFO: write gain/ped parameters into /home/sensor/david/FullQualification/data/M1020_FullQualification_2016-10-19_14h52m_1476881545//001_Fulltest_m20//phCalibrationFitErr35_C8.dat
[17:28:58.548] <TB1> INFO: write gain/ped parameters into /home/sensor/david/FullQualification/data/M1020_FullQualification_2016-10-19_14h52m_1476881545//001_Fulltest_m20//phCalibrationFitErr35_C9.dat
[17:28:58.572] <TB1> INFO: write gain/ped parameters into /home/sensor/david/FullQualification/data/M1020_FullQualification_2016-10-19_14h52m_1476881545//001_Fulltest_m20//phCalibrationFitErr35_C10.dat
[17:28:58.596] <TB1> INFO: write gain/ped parameters into /home/sensor/david/FullQualification/data/M1020_FullQualification_2016-10-19_14h52m_1476881545//001_Fulltest_m20//phCalibrationFitErr35_C11.dat
[17:28:58.621] <TB1> INFO: write gain/ped parameters into /home/sensor/david/FullQualification/data/M1020_FullQualification_2016-10-19_14h52m_1476881545//001_Fulltest_m20//phCalibrationFitErr35_C12.dat
[17:28:58.645] <TB1> INFO: write gain/ped parameters into /home/sensor/david/FullQualification/data/M1020_FullQualification_2016-10-19_14h52m_1476881545//001_Fulltest_m20//phCalibrationFitErr35_C13.dat
[17:28:58.669] <TB1> INFO: write gain/ped parameters into /home/sensor/david/FullQualification/data/M1020_FullQualification_2016-10-19_14h52m_1476881545//001_Fulltest_m20//phCalibrationFitErr35_C14.dat
[17:28:58.693] <TB1> INFO: write gain/ped parameters into /home/sensor/david/FullQualification/data/M1020_FullQualification_2016-10-19_14h52m_1476881545//001_Fulltest_m20//phCalibrationFitErr35_C15.dat
[17:28:58.718] <TB1> INFO: PixTestGainPedestal::fullTest() done, duration: 182 seconds
[17:28:58.718] <TB1> INFO: Decoding statistics:
[17:28:58.718] <TB1> INFO: General information:
[17:28:58.718] <TB1> INFO: 16bit words read: 3327810
[17:28:58.718] <TB1> INFO: valid events total: 332800
[17:28:58.718] <TB1> INFO: empty events: 0
[17:28:58.718] <TB1> INFO: valid events with pixels: 332800
[17:28:58.718] <TB1> INFO: valid pixel hits: 665505
[17:28:58.718] <TB1> INFO: Event errors: 0
[17:28:58.718] <TB1> INFO: start marker: 0
[17:28:58.718] <TB1> INFO: stop marker: 0
[17:28:58.718] <TB1> INFO: overflow: 0
[17:28:58.718] <TB1> INFO: invalid 5bit words: 0
[17:28:58.718] <TB1> INFO: invalid XOR eye diagram: 0
[17:28:58.718] <TB1> INFO: frame (failed synchr.): 0
[17:28:58.718] <TB1> INFO: idle data (no TBM trl): 0
[17:28:58.718] <TB1> INFO: no data (only TBM hdr): 0
[17:28:58.718] <TB1> INFO: TBM errors: 0
[17:28:58.718] <TB1> INFO: flawed TBM headers: 0
[17:28:58.718] <TB1> INFO: flawed TBM trailers: 0
[17:28:58.718] <TB1> INFO: event ID mismatches: 0
[17:28:58.718] <TB1> INFO: ROC errors: 0
[17:28:58.718] <TB1> INFO: missing ROC header(s): 0
[17:28:58.718] <TB1> INFO: misplaced readback start: 0
[17:28:58.718] <TB1> INFO: Pixel decoding errors: 0
[17:28:58.718] <TB1> INFO: pixel data incomplete: 0
[17:28:58.718] <TB1> INFO: pixel address: 0
[17:28:58.718] <TB1> INFO: pulse height fill bit: 0
[17:28:58.718] <TB1> INFO: buffer corruption: 0
[17:28:58.743] <TB1> INFO: Decoding statistics:
[17:28:58.743] <TB1> INFO: General information:
[17:28:58.743] <TB1> INFO: 16bit words read: 3457228
[17:28:58.743] <TB1> INFO: valid events total: 353536
[17:28:58.743] <TB1> INFO: empty events: 18235
[17:28:58.743] <TB1> INFO: valid events with pixels: 335301
[17:28:58.743] <TB1> INFO: valid pixel hits: 668006
[17:28:58.743] <TB1> INFO: Event errors: 0
[17:28:58.743] <TB1> INFO: start marker: 0
[17:28:58.743] <TB1> INFO: stop marker: 0
[17:28:58.743] <TB1> INFO: overflow: 0
[17:28:58.743] <TB1> INFO: invalid 5bit words: 0
[17:28:58.743] <TB1> INFO: invalid XOR eye diagram: 0
[17:28:58.743] <TB1> INFO: frame (failed synchr.): 0
[17:28:58.743] <TB1> INFO: idle data (no TBM trl): 0
[17:28:58.743] <TB1> INFO: no data (only TBM hdr): 0
[17:28:58.743] <TB1> INFO: TBM errors: 0
[17:28:58.743] <TB1> INFO: flawed TBM headers: 0
[17:28:58.743] <TB1> INFO: flawed TBM trailers: 0
[17:28:58.743] <TB1> INFO: event ID mismatches: 0
[17:28:58.743] <TB1> INFO: ROC errors: 0
[17:28:58.743] <TB1> INFO: missing ROC header(s): 0
[17:28:58.743] <TB1> INFO: misplaced readback start: 0
[17:28:58.743] <TB1> INFO: Pixel decoding errors: 0
[17:28:58.743] <TB1> INFO: pixel data incomplete: 0
[17:28:58.743] <TB1> INFO: pixel address: 0
[17:28:58.743] <TB1> INFO: pulse height fill bit: 0
[17:28:58.743] <TB1> INFO: buffer corruption: 0
[17:28:58.743] <TB1> INFO: enter test to run
[17:28:58.743] <TB1> INFO: test: exit no parameter change
[17:28:59.221] <TB1> QUIET: Connection to board 153 closed.
[17:28:59.226] <TB1> INFO: pXar: this is the end, my friend