[15:34:40.406] <TB3> INFO: *** Welcome to pxar ***
[15:34:40.406] <TB3> INFO: *** Today: 2016/10/19
[15:34:40.413] <TB3> INFO: *** Version: c8ba-dirty
[15:34:40.414] <TB3> INFO: readRocDacs: /home/sensor/david/FullQualification/data/M1023_FullQualification_2016-10-19_14h52m_1476881545//001_Fulltest_m20//dacParameters_C0.dat .. /home/sensor/david/FullQualification/data/M1023_FullQualification_2016-10-19_14h52m_1476881545//001_Fulltest_m20//dacParameters_C15.dat
[15:34:40.414] <TB3> INFO: readTbmDacs: /home/sensor/david/FullQualification/data/M1023_FullQualification_2016-10-19_14h52m_1476881545//001_Fulltest_m20//tbmParameters_C0a.dat .. /home/sensor/david/FullQualification/data/M1023_FullQualification_2016-10-19_14h52m_1476881545//001_Fulltest_m20//tbmParameters_C1b.dat
[15:34:40.414] <TB3> INFO: readMaskFile: /home/sensor/david/FullQualification/data/M1023_FullQualification_2016-10-19_14h52m_1476881545//001_Fulltest_m20//defaultMaskFile.dat
[15:34:40.414] <TB3> INFO: readTrimFile: /home/sensor/david/FullQualification/data/M1023_FullQualification_2016-10-19_14h52m_1476881545//001_Fulltest_m20//trimParameters_C0.dat .. /home/sensor/david/FullQualification/data/M1023_FullQualification_2016-10-19_14h52m_1476881545//001_Fulltest_m20//trimParameters_C15.dat
[15:34:40.499] <TB3> INFO: clk: 4
[15:34:40.499] <TB3> INFO: ctr: 4
[15:34:40.499] <TB3> INFO: sda: 19
[15:34:40.499] <TB3> INFO: tin: 9
[15:34:40.499] <TB3> INFO: level: 15
[15:34:40.499] <TB3> INFO: triggerdelay: 0
[15:34:40.499] <TB3> QUIET: Instanciating API for pxar v2.1.0+868~g7f4a123
[15:34:40.499] <TB3> INFO: Log level: INFO
[15:34:40.506] <TB3> INFO: Found DTB DTB_WZ4I6J
[15:34:40.517] <TB3> QUIET: Connection to board DTB_WZ4I6J opened.
[15:34:40.519] <TB3> INFO: DTB startup information
--- DTB info------------------------------------------
Board id: 170
HW version: DTB1.2
FW version: 4.6
SW version: 4.7
Options:
USB id: DTB_WZ4I6J
MAC address: 40D8551180AA
Hostname: pixelDTB170
Comment:
------------------------------------------------------
[15:34:40.522] <TB3> INFO: RPC call hashes of host and DTB match: 486171790
[15:34:42.147] <TB3> INFO: DUT info:
[15:34:42.147] <TB3> INFO: The DUT currently contains the following objects:
[15:34:42.147] <TB3> INFO: 4 TBM Cores tbm10c (4 ON)
[15:34:42.147] <TB3> INFO: TBM Core alpha (0): 7 registers set
[15:34:42.147] <TB3> INFO: TBM Core beta (1): 7 registers set
[15:34:42.147] <TB3> INFO: TBM Core alpha (2): 7 registers set
[15:34:42.147] <TB3> INFO: TBM Core beta (3): 7 registers set
[15:34:42.147] <TB3> INFO: 16 ROCs proc600 (16 ON) with 4160 pixelConfigs
[15:34:42.147] <TB3> INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[15:34:42.147] <TB3> INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[15:34:42.147] <TB3> INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[15:34:42.147] <TB3> INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[15:34:42.148] <TB3> INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[15:34:42.148] <TB3> INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[15:34:42.148] <TB3> INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[15:34:42.148] <TB3> INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[15:34:42.148] <TB3> INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[15:34:42.148] <TB3> INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[15:34:42.148] <TB3> INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[15:34:42.148] <TB3> INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[15:34:42.148] <TB3> INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[15:34:42.148] <TB3> INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[15:34:42.148] <TB3> INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[15:34:42.148] <TB3> INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[15:34:42.549] <TB3> INFO: enter 'restricted' command line mode
[15:34:42.549] <TB3> INFO: enter test to run
[15:34:42.549] <TB3> INFO: test: pretest no parameter change
[15:34:42.549] <TB3> INFO: running: pretest
[15:34:42.556] <TB3> INFO: ######################################################################
[15:34:42.556] <TB3> INFO: PixTestPretest::doTest()
[15:34:42.556] <TB3> INFO: ######################################################################
[15:34:42.557] <TB3> INFO: ----------------------------------------------------------------------
[15:34:42.557] <TB3> INFO: PixTestPretest::programROC()
[15:34:42.557] <TB3> INFO: ----------------------------------------------------------------------
[15:35:00.576] <TB3> INFO: PixTestPretest::programROC() done: ROCs are all programmable
[15:35:00.576] <TB3> INFO: IA differences per ROC: 18.5 17.7 17.7 20.1 18.5 20.1 21.7 17.7 19.3 17.7 19.3 18.5 18.5 20.1 20.9 18.5
[15:35:00.675] <TB3> INFO: ----------------------------------------------------------------------
[15:35:00.675] <TB3> INFO: PixTestPretest::setVana() target Ia = 24 mA/ROC
[15:35:00.675] <TB3> INFO: ----------------------------------------------------------------------
[15:35:22.015] <TB3> INFO: PixTestPretest::setVana() done, Module Ia 389.9 mA = 24.3688 mA/ROC
[15:35:22.015] <TB3> INFO: i(loss) [mA/ROC]: 20.1 20.1 20.1 19.3 18.5 18.5 19.3 20.1 18.5 20.1 19.3 20.1 20.1 19.3 19.3 19.3
[15:35:22.063] <TB3> INFO: ----------------------------------------------------------------------
[15:35:22.063] <TB3> INFO: PixTestPretest::findTiming()
[15:35:22.063] <TB3> INFO: ----------------------------------------------------------------------
[15:35:22.063] <TB3> INFO: PixTestCmd::init()

[15:35:56.884] <TB3> INFO: TBM phases: 160MHz: 0, 400MHz: 2, TBM delays: ROC(0/1):5, header/trailer: 1, token: 1
[15:35:56.884] <TB3> INFO: (success/tries = 100/100), width = 3
[15:35:58.393] <TB3> INFO: ----------------------------------------------------------------------
[15:35:58.393] <TB3> INFO: PixTestPretest::findWorkingPixel()
[15:35:58.394] <TB3> INFO: ----------------------------------------------------------------------
[15:35:58.494] <TB3> INFO: Expecting 231680 events.
[15:36:08.803] <TB3> INFO: 231680 events read in total (9716ms).
[15:36:08.813] <TB3> INFO: Test took 10415ms.
[15:36:09.065] <TB3> INFO: Found working pixel in all ROCs: col/row = 12/22
[15:36:09.116] <TB3> INFO: ----------------------------------------------------------------------
[15:36:09.116] <TB3> INFO: PixTestPretest::setVthrCompCalDel()
[15:36:09.116] <TB3> INFO: ----------------------------------------------------------------------
[15:36:09.221] <TB3> INFO: Expecting 231680 events.
[15:36:19.581] <TB3> INFO: 231680 events read in total (9767ms).
[15:36:19.591] <TB3> INFO: Test took 10466ms.
[15:36:19.882] <TB3> INFO: PixTestPretest::setVthrCompCalDel() done
[15:36:19.882] <TB3> INFO: CalDel: 116 96 119 88 109 101 114 122 87 112 92 99 107 135 90 95
[15:36:19.882] <TB3> INFO: VthrComp: 51 51 51 51 51 51 51 51 51 51 51 51 52 51 51 51
[15:36:19.886] <TB3> INFO: write dac parameters into /home/sensor/david/FullQualification/data/M1023_FullQualification_2016-10-19_14h52m_1476881545//001_Fulltest_m20//dacParameters_C0.dat
[15:36:19.886] <TB3> INFO: write dac parameters into /home/sensor/david/FullQualification/data/M1023_FullQualification_2016-10-19_14h52m_1476881545//001_Fulltest_m20//dacParameters_C1.dat
[15:36:19.886] <TB3> INFO: write dac parameters into /home/sensor/david/FullQualification/data/M1023_FullQualification_2016-10-19_14h52m_1476881545//001_Fulltest_m20//dacParameters_C2.dat
[15:36:19.887] <TB3> INFO: write dac parameters into /home/sensor/david/FullQualification/data/M1023_FullQualification_2016-10-19_14h52m_1476881545//001_Fulltest_m20//dacParameters_C3.dat
[15:36:19.887] <TB3> INFO: write dac parameters into /home/sensor/david/FullQualification/data/M1023_FullQualification_2016-10-19_14h52m_1476881545//001_Fulltest_m20//dacParameters_C4.dat
[15:36:19.887] <TB3> INFO: write dac parameters into /home/sensor/david/FullQualification/data/M1023_FullQualification_2016-10-19_14h52m_1476881545//001_Fulltest_m20//dacParameters_C5.dat
[15:36:19.887] <TB3> INFO: write dac parameters into /home/sensor/david/FullQualification/data/M1023_FullQualification_2016-10-19_14h52m_1476881545//001_Fulltest_m20//dacParameters_C6.dat
[15:36:19.887] <TB3> INFO: write dac parameters into /home/sensor/david/FullQualification/data/M1023_FullQualification_2016-10-19_14h52m_1476881545//001_Fulltest_m20//dacParameters_C7.dat
[15:36:19.888] <TB3> INFO: write dac parameters into /home/sensor/david/FullQualification/data/M1023_FullQualification_2016-10-19_14h52m_1476881545//001_Fulltest_m20//dacParameters_C8.dat
[15:36:19.888] <TB3> INFO: write dac parameters into /home/sensor/david/FullQualification/data/M1023_FullQualification_2016-10-19_14h52m_1476881545//001_Fulltest_m20//dacParameters_C9.dat
[15:36:19.888] <TB3> INFO: write dac parameters into /home/sensor/david/FullQualification/data/M1023_FullQualification_2016-10-19_14h52m_1476881545//001_Fulltest_m20//dacParameters_C10.dat
[15:36:19.888] <TB3> INFO: write dac parameters into /home/sensor/david/FullQualification/data/M1023_FullQualification_2016-10-19_14h52m_1476881545//001_Fulltest_m20//dacParameters_C11.dat
[15:36:19.889] <TB3> INFO: write dac parameters into /home/sensor/david/FullQualification/data/M1023_FullQualification_2016-10-19_14h52m_1476881545//001_Fulltest_m20//dacParameters_C12.dat
[15:36:19.889] <TB3> INFO: write dac parameters into /home/sensor/david/FullQualification/data/M1023_FullQualification_2016-10-19_14h52m_1476881545//001_Fulltest_m20//dacParameters_C13.dat
[15:36:19.889] <TB3> INFO: write dac parameters into /home/sensor/david/FullQualification/data/M1023_FullQualification_2016-10-19_14h52m_1476881545//001_Fulltest_m20//dacParameters_C14.dat
[15:36:19.889] <TB3> INFO: write dac parameters into /home/sensor/david/FullQualification/data/M1023_FullQualification_2016-10-19_14h52m_1476881545//001_Fulltest_m20//dacParameters_C15.dat
[15:36:19.889] <TB3> INFO: write tbm parameters into /home/sensor/david/FullQualification/data/M1023_FullQualification_2016-10-19_14h52m_1476881545//001_Fulltest_m20//tbmParameters_C0a.dat
[15:36:19.890] <TB3> INFO: write tbm parameters into /home/sensor/david/FullQualification/data/M1023_FullQualification_2016-10-19_14h52m_1476881545//001_Fulltest_m20//tbmParameters_C0b.dat
[15:36:19.890] <TB3> INFO: write tbm parameters into /home/sensor/david/FullQualification/data/M1023_FullQualification_2016-10-19_14h52m_1476881545//001_Fulltest_m20//tbmParameters_C1a.dat
[15:36:19.890] <TB3> INFO: write tbm parameters into /home/sensor/david/FullQualification/data/M1023_FullQualification_2016-10-19_14h52m_1476881545//001_Fulltest_m20//tbmParameters_C1b.dat
[15:36:19.890] <TB3> INFO: PixTestPretest::doTest() done, duration: 97 seconds
[15:36:19.974] <TB3> INFO: enter test to run
[15:36:19.974] <TB3> INFO: test: FullTest no parameter change
[15:36:19.974] <TB3> INFO: running: fulltest
[15:36:19.974] <TB3> INFO: ######################################################################
[15:36:19.974] <TB3> INFO: PixTestFullTest::doTest()
[15:36:19.974] <TB3> INFO: ######################################################################
[15:36:19.976] <TB3> INFO: ######################################################################
[15:36:19.976] <TB3> INFO: PixTestAlive::doTest()
[15:36:19.976] <TB3> INFO: ######################################################################
[15:36:19.977] <TB3> INFO: ----------------------------------------------------------------------
[15:36:19.977] <TB3> 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:19.977] <TB3> INFO: ----------------------------------------------------------------------
[15:36:20.301] <TB3> INFO: Expecting 41600 events.
[15:36:24.159] <TB3> INFO: 41600 events read in total (3265ms).
[15:36:24.160] <TB3> INFO: Test took 4180ms.
[15:36:24.411] <TB3> INFO: PixTestAlive::aliveTest() done
[15:36:24.411] <TB3> INFO: number of dead pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[15:36:24.413] <TB3> INFO: ----------------------------------------------------------------------
[15:36:24.413] <TB3> 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:24.413] <TB3> INFO: ----------------------------------------------------------------------
[15:36:24.740] <TB3> INFO: Expecting 41600 events.
[15:36:27.957] <TB3> INFO: 41600 events read in total (2624ms).
[15:36:27.958] <TB3> INFO: Test took 3543ms.
[15:36:27.958] <TB3> INFO: mask vs. old pixelAlive PixelAlive_C0_V0 .. PixelAlive_C15_V0
[15:36:28.213] <TB3> INFO: PixTestAlive::maskTest() done
[15:36:28.213] <TB3> 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:28.217] <TB3> INFO: ----------------------------------------------------------------------
[15:36:28.217] <TB3> 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:28.217] <TB3> INFO: ----------------------------------------------------------------------
[15:36:28.536] <TB3> INFO: Expecting 41600 events.
[15:36:32.405] <TB3> INFO: 41600 events read in total (3276ms).
[15:36:32.406] <TB3> INFO: Test took 4186ms.
[15:36:32.661] <TB3> INFO: PixTestAlive::addressDecodingTest() done
[15:36:32.661] <TB3> 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:32.662] <TB3> INFO: PixTestAlive::doTest() done, duration: 12 seconds
[15:36:32.662] <TB3> INFO: Decoding statistics:
[15:36:32.662] <TB3> INFO: General information:
[15:36:32.662] <TB3> INFO: 16bit words read: 0
[15:36:32.662] <TB3> INFO: valid events total: 0
[15:36:32.662] <TB3> INFO: empty events: 0
[15:36:32.662] <TB3> INFO: valid events with pixels: 0
[15:36:32.662] <TB3> INFO: valid pixel hits: 0
[15:36:32.662] <TB3> INFO: Event errors: 0
[15:36:32.662] <TB3> INFO: start marker: 0
[15:36:32.662] <TB3> INFO: stop marker: 0
[15:36:32.662] <TB3> INFO: overflow: 0
[15:36:32.662] <TB3> INFO: invalid 5bit words: 0
[15:36:32.662] <TB3> INFO: invalid XOR eye diagram: 0
[15:36:32.662] <TB3> INFO: frame (failed synchr.): 0
[15:36:32.662] <TB3> INFO: idle data (no TBM trl): 0
[15:36:32.662] <TB3> INFO: no data (only TBM hdr): 0
[15:36:32.662] <TB3> INFO: TBM errors: 0
[15:36:32.662] <TB3> INFO: flawed TBM headers: 0
[15:36:32.662] <TB3> INFO: flawed TBM trailers: 0
[15:36:32.662] <TB3> INFO: event ID mismatches: 0
[15:36:32.662] <TB3> INFO: ROC errors: 0
[15:36:32.662] <TB3> INFO: missing ROC header(s): 0
[15:36:32.662] <TB3> INFO: misplaced readback start: 0
[15:36:32.663] <TB3> INFO: Pixel decoding errors: 0
[15:36:32.663] <TB3> INFO: pixel data incomplete: 0
[15:36:32.663] <TB3> INFO: pixel address: 0
[15:36:32.663] <TB3> INFO: pulse height fill bit: 0
[15:36:32.663] <TB3> INFO: buffer corruption: 0
[15:36:32.671] <TB3> INFO: readReadbackCal: /home/sensor/david/FullQualification/data/M1023_FullQualification_2016-10-19_14h52m_1476881545//001_Fulltest_m20//readbackCal_C0.dat .. /home/sensor/david/FullQualification/data/M1023_FullQualification_2016-10-19_14h52m_1476881545//001_Fulltest_m20//readbackCal_C15.dat
[15:36:32.672] <TB3> INFO: readGainPedestalParameters /home/sensor/david/FullQualification/data/M1023_FullQualification_2016-10-19_14h52m_1476881545//001_Fulltest_m20//phCalibrationFitErr_C0.dat .. /home/sensor/david/FullQualification/data/M1023_FullQualification_2016-10-19_14h52m_1476881545//001_Fulltest_m20//phCalibrationFitErr_C15.dat

[15:36:32.672] <TB3> INFO: ######################################################################
[15:36:32.672] <TB3> INFO: PixTestReadback::doTest()
[15:36:32.672] <TB3> INFO: ######################################################################
[15:36:32.672] <TB3> INFO: ----------------------------------------------------------------------
[15:36:32.672] <TB3> INFO: PixTestReadback::CalibrateVd()
[15:36:32.672] <TB3> INFO: ----------------------------------------------------------------------
[15:36:42.838] <TB3> INFO: write readback calibration parameters into /home/sensor/david/FullQualification/data/M1023_FullQualification_2016-10-19_14h52m_1476881545//001_Fulltest_m20//readbackCal_C0.dat
[15:36:42.839] <TB3> INFO: write readback calibration parameters into /home/sensor/david/FullQualification/data/M1023_FullQualification_2016-10-19_14h52m_1476881545//001_Fulltest_m20//readbackCal_C1.dat
[15:36:42.839] <TB3> INFO: write readback calibration parameters into /home/sensor/david/FullQualification/data/M1023_FullQualification_2016-10-19_14h52m_1476881545//001_Fulltest_m20//readbackCal_C2.dat
[15:36:42.839] <TB3> INFO: write readback calibration parameters into /home/sensor/david/FullQualification/data/M1023_FullQualification_2016-10-19_14h52m_1476881545//001_Fulltest_m20//readbackCal_C3.dat
[15:36:42.839] <TB3> INFO: write readback calibration parameters into /home/sensor/david/FullQualification/data/M1023_FullQualification_2016-10-19_14h52m_1476881545//001_Fulltest_m20//readbackCal_C4.dat
[15:36:42.839] <TB3> INFO: write readback calibration parameters into /home/sensor/david/FullQualification/data/M1023_FullQualification_2016-10-19_14h52m_1476881545//001_Fulltest_m20//readbackCal_C5.dat
[15:36:42.839] <TB3> INFO: write readback calibration parameters into /home/sensor/david/FullQualification/data/M1023_FullQualification_2016-10-19_14h52m_1476881545//001_Fulltest_m20//readbackCal_C6.dat
[15:36:42.839] <TB3> INFO: write readback calibration parameters into /home/sensor/david/FullQualification/data/M1023_FullQualification_2016-10-19_14h52m_1476881545//001_Fulltest_m20//readbackCal_C7.dat
[15:36:42.840] <TB3> INFO: write readback calibration parameters into /home/sensor/david/FullQualification/data/M1023_FullQualification_2016-10-19_14h52m_1476881545//001_Fulltest_m20//readbackCal_C8.dat
[15:36:42.840] <TB3> INFO: write readback calibration parameters into /home/sensor/david/FullQualification/data/M1023_FullQualification_2016-10-19_14h52m_1476881545//001_Fulltest_m20//readbackCal_C9.dat
[15:36:42.840] <TB3> INFO: write readback calibration parameters into /home/sensor/david/FullQualification/data/M1023_FullQualification_2016-10-19_14h52m_1476881545//001_Fulltest_m20//readbackCal_C10.dat
[15:36:42.840] <TB3> INFO: write readback calibration parameters into /home/sensor/david/FullQualification/data/M1023_FullQualification_2016-10-19_14h52m_1476881545//001_Fulltest_m20//readbackCal_C11.dat
[15:36:42.840] <TB3> INFO: write readback calibration parameters into /home/sensor/david/FullQualification/data/M1023_FullQualification_2016-10-19_14h52m_1476881545//001_Fulltest_m20//readbackCal_C12.dat
[15:36:42.840] <TB3> INFO: write readback calibration parameters into /home/sensor/david/FullQualification/data/M1023_FullQualification_2016-10-19_14h52m_1476881545//001_Fulltest_m20//readbackCal_C13.dat
[15:36:42.840] <TB3> INFO: write readback calibration parameters into /home/sensor/david/FullQualification/data/M1023_FullQualification_2016-10-19_14h52m_1476881545//001_Fulltest_m20//readbackCal_C14.dat
[15:36:42.840] <TB3> INFO: write readback calibration parameters into /home/sensor/david/FullQualification/data/M1023_FullQualification_2016-10-19_14h52m_1476881545//001_Fulltest_m20//readbackCal_C15.dat
[15:36:42.892] <TB3> INFO: PixTestPattern:: pg_setup set to default.
[15:36:42.892] <TB3> INFO: ----------------------------------------------------------------------
[15:36:42.892] <TB3> INFO: PixTestReadback::CalibrateVa()
[15:36:42.892] <TB3> INFO: ----------------------------------------------------------------------
[15:36:52.987] <TB3> INFO: write readback calibration parameters into /home/sensor/david/FullQualification/data/M1023_FullQualification_2016-10-19_14h52m_1476881545//001_Fulltest_m20//readbackCal_C0.dat
[15:36:52.987] <TB3> INFO: write readback calibration parameters into /home/sensor/david/FullQualification/data/M1023_FullQualification_2016-10-19_14h52m_1476881545//001_Fulltest_m20//readbackCal_C1.dat
[15:36:52.987] <TB3> INFO: write readback calibration parameters into /home/sensor/david/FullQualification/data/M1023_FullQualification_2016-10-19_14h52m_1476881545//001_Fulltest_m20//readbackCal_C2.dat
[15:36:52.987] <TB3> INFO: write readback calibration parameters into /home/sensor/david/FullQualification/data/M1023_FullQualification_2016-10-19_14h52m_1476881545//001_Fulltest_m20//readbackCal_C3.dat
[15:36:52.987] <TB3> INFO: write readback calibration parameters into /home/sensor/david/FullQualification/data/M1023_FullQualification_2016-10-19_14h52m_1476881545//001_Fulltest_m20//readbackCal_C4.dat
[15:36:52.988] <TB3> INFO: write readback calibration parameters into /home/sensor/david/FullQualification/data/M1023_FullQualification_2016-10-19_14h52m_1476881545//001_Fulltest_m20//readbackCal_C5.dat
[15:36:52.988] <TB3> INFO: write readback calibration parameters into /home/sensor/david/FullQualification/data/M1023_FullQualification_2016-10-19_14h52m_1476881545//001_Fulltest_m20//readbackCal_C6.dat
[15:36:52.988] <TB3> INFO: write readback calibration parameters into /home/sensor/david/FullQualification/data/M1023_FullQualification_2016-10-19_14h52m_1476881545//001_Fulltest_m20//readbackCal_C7.dat
[15:36:52.988] <TB3> INFO: write readback calibration parameters into /home/sensor/david/FullQualification/data/M1023_FullQualification_2016-10-19_14h52m_1476881545//001_Fulltest_m20//readbackCal_C8.dat
[15:36:52.988] <TB3> INFO: write readback calibration parameters into /home/sensor/david/FullQualification/data/M1023_FullQualification_2016-10-19_14h52m_1476881545//001_Fulltest_m20//readbackCal_C9.dat
[15:36:52.988] <TB3> INFO: write readback calibration parameters into /home/sensor/david/FullQualification/data/M1023_FullQualification_2016-10-19_14h52m_1476881545//001_Fulltest_m20//readbackCal_C10.dat
[15:36:52.988] <TB3> INFO: write readback calibration parameters into /home/sensor/david/FullQualification/data/M1023_FullQualification_2016-10-19_14h52m_1476881545//001_Fulltest_m20//readbackCal_C11.dat
[15:36:52.988] <TB3> INFO: write readback calibration parameters into /home/sensor/david/FullQualification/data/M1023_FullQualification_2016-10-19_14h52m_1476881545//001_Fulltest_m20//readbackCal_C12.dat
[15:36:52.988] <TB3> INFO: write readback calibration parameters into /home/sensor/david/FullQualification/data/M1023_FullQualification_2016-10-19_14h52m_1476881545//001_Fulltest_m20//readbackCal_C13.dat
[15:36:52.988] <TB3> INFO: write readback calibration parameters into /home/sensor/david/FullQualification/data/M1023_FullQualification_2016-10-19_14h52m_1476881545//001_Fulltest_m20//readbackCal_C14.dat
[15:36:52.989] <TB3> INFO: write readback calibration parameters into /home/sensor/david/FullQualification/data/M1023_FullQualification_2016-10-19_14h52m_1476881545//001_Fulltest_m20//readbackCal_C15.dat
[15:36:53.045] <TB3> INFO: PixTestPattern:: pg_setup set to default.
[15:36:53.045] <TB3> INFO: ----------------------------------------------------------------------
[15:36:53.045] <TB3> INFO: PixTestReadback::readbackVbg()
[15:36:53.045] <TB3> INFO: ----------------------------------------------------------------------
[15:37:00.814] <TB3> INFO: PixTestPattern:: pg_setup set to default.
[15:37:00.814] <TB3> INFO: ----------------------------------------------------------------------
[15:37:00.814] <TB3> INFO: PixTestReadback::getCalibratedVbg()
[15:37:00.814] <TB3> INFO: ----------------------------------------------------------------------
[15:37:00.814] <TB3> INFO: Vbg will be calibrated using Vd calibration
[15:37:00.814] <TB3> INFO: /*/*/*/*::: ROC 0: uncalibrated Vbg = 149.3calibrated Vbg = 1.15931 :::*/*/*/*/
[15:37:00.814] <TB3> INFO: /*/*/*/*::: ROC 1: uncalibrated Vbg = 158.3calibrated Vbg = 1.16292 :::*/*/*/*/
[15:37:00.814] <TB3> INFO: /*/*/*/*::: ROC 2: uncalibrated Vbg = 161.4calibrated Vbg = 1.15786 :::*/*/*/*/
[15:37:00.814] <TB3> INFO: /*/*/*/*::: ROC 3: uncalibrated Vbg = 155.3calibrated Vbg = 1.1493 :::*/*/*/*/
[15:37:00.814] <TB3> INFO: /*/*/*/*::: ROC 4: uncalibrated Vbg = 156.7calibrated Vbg = 1.15788 :::*/*/*/*/
[15:37:00.814] <TB3> INFO: /*/*/*/*::: ROC 5: uncalibrated Vbg = 157.4calibrated Vbg = 1.16729 :::*/*/*/*/
[15:37:00.814] <TB3> INFO: /*/*/*/*::: ROC 6: uncalibrated Vbg = 160.8calibrated Vbg = 1.16839 :::*/*/*/*/
[15:37:00.814] <TB3> INFO: /*/*/*/*::: ROC 7: uncalibrated Vbg = 158.9calibrated Vbg = 1.16933 :::*/*/*/*/
[15:37:00.814] <TB3> INFO: /*/*/*/*::: ROC 8: uncalibrated Vbg = 152.9calibrated Vbg = 1.15902 :::*/*/*/*/
[15:37:00.814] <TB3> INFO: /*/*/*/*::: ROC 9: uncalibrated Vbg = 150.1calibrated Vbg = 1.15579 :::*/*/*/*/
[15:37:00.814] <TB3> INFO: /*/*/*/*::: ROC 10: uncalibrated Vbg = 161.9calibrated Vbg = 1.15015 :::*/*/*/*/
[15:37:00.814] <TB3> INFO: /*/*/*/*::: ROC 11: uncalibrated Vbg = 162.9calibrated Vbg = 1.15211 :::*/*/*/*/
[15:37:00.814] <TB3> INFO: /*/*/*/*::: ROC 12: uncalibrated Vbg = 165.6calibrated Vbg = 1.15342 :::*/*/*/*/
[15:37:00.814] <TB3> INFO: /*/*/*/*::: ROC 13: uncalibrated Vbg = 149.7calibrated Vbg = 1.16554 :::*/*/*/*/
[15:37:00.814] <TB3> INFO: /*/*/*/*::: ROC 14: uncalibrated Vbg = 155.5calibrated Vbg = 1.16124 :::*/*/*/*/
[15:37:00.814] <TB3> INFO: /*/*/*/*::: ROC 15: uncalibrated Vbg = 171calibrated Vbg = 1.15265 :::*/*/*/*/
[15:37:00.826] <TB3> INFO: ----------------------------------------------------------------------
[15:37:00.826] <TB3> INFO: PixTestReadback::CalibrateIa()
[15:37:00.826] <TB3> INFO: ----------------------------------------------------------------------
[15:39:42.926] <TB3> INFO: write readback calibration parameters into /home/sensor/david/FullQualification/data/M1023_FullQualification_2016-10-19_14h52m_1476881545//001_Fulltest_m20//readbackCal_C0.dat
[15:39:42.927] <TB3> INFO: write readback calibration parameters into /home/sensor/david/FullQualification/data/M1023_FullQualification_2016-10-19_14h52m_1476881545//001_Fulltest_m20//readbackCal_C1.dat
[15:39:42.927] <TB3> INFO: write readback calibration parameters into /home/sensor/david/FullQualification/data/M1023_FullQualification_2016-10-19_14h52m_1476881545//001_Fulltest_m20//readbackCal_C2.dat
[15:39:42.927] <TB3> INFO: write readback calibration parameters into /home/sensor/david/FullQualification/data/M1023_FullQualification_2016-10-19_14h52m_1476881545//001_Fulltest_m20//readbackCal_C3.dat
[15:39:42.927] <TB3> INFO: write readback calibration parameters into /home/sensor/david/FullQualification/data/M1023_FullQualification_2016-10-19_14h52m_1476881545//001_Fulltest_m20//readbackCal_C4.dat
[15:39:42.927] <TB3> INFO: write readback calibration parameters into /home/sensor/david/FullQualification/data/M1023_FullQualification_2016-10-19_14h52m_1476881545//001_Fulltest_m20//readbackCal_C5.dat
[15:39:42.927] <TB3> INFO: write readback calibration parameters into /home/sensor/david/FullQualification/data/M1023_FullQualification_2016-10-19_14h52m_1476881545//001_Fulltest_m20//readbackCal_C6.dat
[15:39:42.927] <TB3> INFO: write readback calibration parameters into /home/sensor/david/FullQualification/data/M1023_FullQualification_2016-10-19_14h52m_1476881545//001_Fulltest_m20//readbackCal_C7.dat
[15:39:42.927] <TB3> INFO: write readback calibration parameters into /home/sensor/david/FullQualification/data/M1023_FullQualification_2016-10-19_14h52m_1476881545//001_Fulltest_m20//readbackCal_C8.dat
[15:39:42.927] <TB3> INFO: write readback calibration parameters into /home/sensor/david/FullQualification/data/M1023_FullQualification_2016-10-19_14h52m_1476881545//001_Fulltest_m20//readbackCal_C9.dat
[15:39:42.928] <TB3> INFO: write readback calibration parameters into /home/sensor/david/FullQualification/data/M1023_FullQualification_2016-10-19_14h52m_1476881545//001_Fulltest_m20//readbackCal_C10.dat
[15:39:42.928] <TB3> INFO: write readback calibration parameters into /home/sensor/david/FullQualification/data/M1023_FullQualification_2016-10-19_14h52m_1476881545//001_Fulltest_m20//readbackCal_C11.dat
[15:39:42.928] <TB3> INFO: write readback calibration parameters into /home/sensor/david/FullQualification/data/M1023_FullQualification_2016-10-19_14h52m_1476881545//001_Fulltest_m20//readbackCal_C12.dat
[15:39:42.928] <TB3> INFO: write readback calibration parameters into /home/sensor/david/FullQualification/data/M1023_FullQualification_2016-10-19_14h52m_1476881545//001_Fulltest_m20//readbackCal_C13.dat
[15:39:42.928] <TB3> INFO: write readback calibration parameters into /home/sensor/david/FullQualification/data/M1023_FullQualification_2016-10-19_14h52m_1476881545//001_Fulltest_m20//readbackCal_C14.dat
[15:39:42.928] <TB3> INFO: write readback calibration parameters into /home/sensor/david/FullQualification/data/M1023_FullQualification_2016-10-19_14h52m_1476881545//001_Fulltest_m20//readbackCal_C15.dat
[15:39:42.998] <TB3> INFO: PixTestPattern:: pg_setup set to default.
[15:39:42.000] <TB3> INFO: PixTestReadback::doTest() done
[15:39:42.000] <TB3> INFO: Decoding statistics:
[15:39:42.000] <TB3> INFO: General information:
[15:39:42.000] <TB3> INFO: 16bit words read: 1536
[15:39:42.000] <TB3> INFO: valid events total: 256
[15:39:42.000] <TB3> INFO: empty events: 256
[15:39:42.000] <TB3> INFO: valid events with pixels: 0
[15:39:42.000] <TB3> INFO: valid pixel hits: 0
[15:39:42.000] <TB3> INFO: Event errors: 0
[15:39:42.000] <TB3> INFO: start marker: 0
[15:39:42.000] <TB3> INFO: stop marker: 0
[15:39:42.000] <TB3> INFO: overflow: 0
[15:39:42.000] <TB3> INFO: invalid 5bit words: 0
[15:39:42.000] <TB3> INFO: invalid XOR eye diagram: 0
[15:39:42.000] <TB3> INFO: frame (failed synchr.): 0
[15:39:42.000] <TB3> INFO: idle data (no TBM trl): 0
[15:39:42.000] <TB3> INFO: no data (only TBM hdr): 0
[15:39:42.000] <TB3> INFO: TBM errors: 0
[15:39:42.001] <TB3> INFO: flawed TBM headers: 0
[15:39:42.001] <TB3> INFO: flawed TBM trailers: 0
[15:39:42.001] <TB3> INFO: event ID mismatches: 0
[15:39:42.001] <TB3> INFO: ROC errors: 0
[15:39:42.001] <TB3> INFO: missing ROC header(s): 0
[15:39:42.001] <TB3> INFO: misplaced readback start: 0
[15:39:42.001] <TB3> INFO: Pixel decoding errors: 0
[15:39:42.001] <TB3> INFO: pixel data incomplete: 0
[15:39:42.001] <TB3> INFO: pixel address: 0
[15:39:42.001] <TB3> INFO: pulse height fill bit: 0
[15:39:42.001] <TB3> INFO: buffer corruption: 0
[15:39:43.072] <TB3> INFO: ######################################################################
[15:39:43.072] <TB3> INFO: PixTestBBMap::doTest() Ntrig = 5, VcalS = 250 (high range)
[15:39:43.072] <TB3> INFO: ######################################################################
[15:39:43.076] <TB3> INFO: ---> dac: VthrComp name: calSMap ntrig: 5 dacrange: 0 .. 149 (-1/-1) hits flags = 514 (plus default)
[15:39:43.101] <TB3> INFO: dacScan split into 1 runs with ntrig = 5
[15:39:43.101] <TB3> INFO: run 1 of 1
[15:39:43.419] <TB3> INFO: Expecting 3120000 events.
[15:40:18.238] <TB3> INFO: 651645 events read in total (34226ms).











[15:40:52.294] <TB3> INFO: 1297660 events read in total (68282ms).











[15:41:26.193] <TB3> INFO: 1940030 events read in total (102181ms).











[15:42:00.144] <TB3> INFO: 2583330 events read in total (136132ms).











[15:42:34.712] <TB3> INFO: 3120000 events read in total (170700ms).
[15:42:34.800] <TB3> INFO: Test took 171700ms.
[15:43:14.772] <TB3> INFO: PixTestBBMap::doTest() done with 3 decoding errors: , duration: 211 seconds
[15:43:14.772] <TB3> INFO: number of dead bumps (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 1
[15:43:14.772] <TB3> INFO: separation cut (per ROC): 97 109 94 109 107 106 94 100 102 97 99 112 91 77 94 105
[15:43:14.772] <TB3> INFO: Decoding statistics:
[15:43:14.772] <TB3> INFO: General information:
[15:43:14.772] <TB3> INFO: 16bit words read: 0
[15:43:14.773] <TB3> INFO: valid events total: 0
[15:43:14.773] <TB3> INFO: empty events: 0
[15:43:14.773] <TB3> INFO: valid events with pixels: 0
[15:43:14.773] <TB3> INFO: valid pixel hits: 0
[15:43:14.773] <TB3> INFO: Event errors: 0
[15:43:14.773] <TB3> INFO: start marker: 0
[15:43:14.773] <TB3> INFO: stop marker: 0
[15:43:14.773] <TB3> INFO: overflow: 0
[15:43:14.773] <TB3> INFO: invalid 5bit words: 0
[15:43:14.773] <TB3> INFO: invalid XOR eye diagram: 0
[15:43:14.773] <TB3> INFO: frame (failed synchr.): 0
[15:43:14.773] <TB3> INFO: idle data (no TBM trl): 0
[15:43:14.773] <TB3> INFO: no data (only TBM hdr): 0
[15:43:14.773] <TB3> INFO: TBM errors: 0
[15:43:14.773] <TB3> INFO: flawed TBM headers: 0
[15:43:14.773] <TB3> INFO: flawed TBM trailers: 0
[15:43:14.773] <TB3> INFO: event ID mismatches: 0
[15:43:14.773] <TB3> INFO: ROC errors: 0
[15:43:14.773] <TB3> INFO: missing ROC header(s): 0
[15:43:14.773] <TB3> INFO: misplaced readback start: 0
[15:43:14.773] <TB3> INFO: Pixel decoding errors: 0
[15:43:14.773] <TB3> INFO: pixel data incomplete: 0
[15:43:14.773] <TB3> INFO: pixel address: 0
[15:43:14.773] <TB3> INFO: pulse height fill bit: 0
[15:43:14.773] <TB3> INFO: buffer corruption: 0
[15:43:14.830] <TB3> INFO: ######################################################################
[15:43:14.830] <TB3> INFO: PixTestScurves::fullTest() ntrig = 50, dacs/step = -1, ntrig/step = -1
[15:43:14.830] <TB3> INFO: ######################################################################
[15:43:14.831] <TB3> INFO: ----------------------------------------------------------------------
[15:43:14.831] <TB3> INFO: PixTestScurves::scurves(Vcal), ntrig = 50, dacs/step = -1, ntrig/step = -1
[15:43:14.831] <TB3> INFO: ----------------------------------------------------------------------
[15:43:14.831] <TB3> INFO: ---> dac: Vcal name: scurveVcal ntrig: 50 dacrange: 25 .. 200 (-1/-1) hits flags = 528 (plus default)
[15:43:14.845] <TB3> INFO: dacScan split into 1 runs with ntrig = 50
[15:43:14.845] <TB3> INFO: run 1 of 1
[15:43:15.161] <TB3> INFO: Expecting 36608000 events.
[15:43:43.687] <TB3> INFO: 668400 events read in total (27933ms).
[15:44:11.093] <TB3> INFO: 1324700 events read in total (55339ms).
[15:44:38.649] <TB3> INFO: 1979350 events read in total (82895ms).
[15:45:06.303] <TB3> INFO: 2635150 events read in total (110549ms).
[15:45:33.989] <TB3> INFO: 3289400 events read in total (138235ms).
[15:46:01.893] <TB3> INFO: 3944050 events read in total (166139ms).
[15:46:29.446] <TB3> INFO: 4599700 events read in total (193692ms).
[15:46:57.107] <TB3> INFO: 5252400 events read in total (221353ms).
[15:47:24.524] <TB3> INFO: 5906600 events read in total (248770ms).
[15:47:51.747] <TB3> INFO: 6562450 events read in total (275993ms).
[15:48:19.090] <TB3> INFO: 7218600 events read in total (303336ms).
[15:48:47.020] <TB3> INFO: 7872800 events read in total (331266ms).
[15:49:14.765] <TB3> INFO: 8527850 events read in total (359011ms).
[15:49:42.315] <TB3> INFO: 9183150 events read in total (386561ms).
[15:50:09.965] <TB3> INFO: 9838200 events read in total (414211ms).
[15:50:37.564] <TB3> INFO: 10491500 events read in total (441810ms).
[15:51:05.046] <TB3> INFO: 11145900 events read in total (469292ms).
[15:51:32.191] <TB3> INFO: 11798550 events read in total (496437ms).
[15:51:59.014] <TB3> INFO: 12452500 events read in total (523260ms).
[15:52:25.874] <TB3> INFO: 13108750 events read in total (550120ms).
[15:52:52.696] <TB3> INFO: 13762750 events read in total (576942ms).
[15:53:19.419] <TB3> INFO: 14416550 events read in total (603665ms).
[15:53:46.013] <TB3> INFO: 15066800 events read in total (630259ms).
[15:54:13.593] <TB3> INFO: 15718650 events read in total (657839ms).
[15:54:40.856] <TB3> INFO: 16370550 events read in total (685103ms).
[15:55:08.138] <TB3> INFO: 17023150 events read in total (712384ms).
[15:55:35.414] <TB3> INFO: 17674050 events read in total (739660ms).
[15:56:02.677] <TB3> INFO: 18325400 events read in total (766923ms).
[15:56:30.023] <TB3> INFO: 18974750 events read in total (794269ms).
[15:56:57.481] <TB3> INFO: 19623600 events read in total (821727ms).
[15:57:24.923] <TB3> INFO: 20271950 events read in total (849169ms).
[15:57:52.267] <TB3> INFO: 20920750 events read in total (876513ms).
[15:58:19.755] <TB3> INFO: 21569900 events read in total (904002ms).
[15:58:47.118] <TB3> INFO: 22218050 events read in total (931364ms).
[15:59:14.539] <TB3> INFO: 22867100 events read in total (958785ms).
[15:59:41.866] <TB3> INFO: 23514700 events read in total (986112ms).
[16:00:09.266] <TB3> INFO: 24162800 events read in total (1013512ms).
[16:00:36.435] <TB3> INFO: 24807950 events read in total (1040681ms).
[16:01:03.899] <TB3> INFO: 25455900 events read in total (1068145ms).
[16:01:31.379] <TB3> INFO: 26103850 events read in total (1095625ms).
[16:01:58.817] <TB3> INFO: 26750950 events read in total (1123063ms).
[16:02:26.170] <TB3> INFO: 27398450 events read in total (1150416ms).
[16:02:53.462] <TB3> INFO: 28044550 events read in total (1177708ms).
[16:03:20.853] <TB3> INFO: 28690250 events read in total (1205099ms).
[16:03:48.393] <TB3> INFO: 29336100 events read in total (1232639ms).
[16:04:16.026] <TB3> INFO: 29982000 events read in total (1260272ms).
[16:04:43.817] <TB3> INFO: 30627150 events read in total (1288063ms).
[16:05:11.249] <TB3> INFO: 31272000 events read in total (1315495ms).
[16:05:38.466] <TB3> INFO: 31915550 events read in total (1342712ms).
[16:06:05.941] <TB3> INFO: 32560800 events read in total (1370187ms).
[16:06:33.588] <TB3> INFO: 33208000 events read in total (1397834ms).
[16:07:01.287] <TB3> INFO: 33855100 events read in total (1425533ms).
[16:07:28.832] <TB3> INFO: 34502100 events read in total (1453078ms).
[16:07:56.211] <TB3> INFO: 35148200 events read in total (1480457ms).
[16:08:23.765] <TB3> INFO: 35795450 events read in total (1508011ms).
[16:08:54.437] <TB3> INFO: 36452150 events read in total (1538683ms).
[16:09:01.680] <TB3> INFO: 36608000 events read in total (1545926ms).
[16:09:01.763] <TB3> INFO: Test took 1546918ms.
[16:09:02.426] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[16:09:05.760] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[16:09:08.631] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[16:09:11.929] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[16:09:14.672] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[16:09:17.342] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[16:09:20.119] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[16:09:22.969] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[16:09:25.846] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[16:09:28.833] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[16:09:31.678] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[16:09:34.581] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[16:09:37.349] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[16:09:40.193] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[16:09:42.952] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[16:09:45.941] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[16:09:49.028] <TB3> INFO: PixTestScurves::scurves() done
[16:09:49.028] <TB3> INFO: Vcal mean: 106.65 120.07 115.01 118.92 118.40 117.40 108.80 110.57 100.71 109.75 112.94 115.11 116.35 98.95 107.87 111.45
[16:09:49.028] <TB3> INFO: Vcal RMS: 5.77 5.75 7.39 6.29 6.06 6.19 6.19 5.14 5.39 5.52 5.31 5.55 5.43 5.41 4.76 5.00
[16:09:49.029] <TB3> INFO: PixTestScurves::fullTest() done, duration: 1594 seconds
[16:09:49.029] <TB3> INFO: Decoding statistics:
[16:09:49.029] <TB3> INFO: General information:
[16:09:49.029] <TB3> INFO: 16bit words read: 0
[16:09:49.029] <TB3> INFO: valid events total: 0
[16:09:49.029] <TB3> INFO: empty events: 0
[16:09:49.029] <TB3> INFO: valid events with pixels: 0
[16:09:49.029] <TB3> INFO: valid pixel hits: 0
[16:09:49.029] <TB3> INFO: Event errors: 0
[16:09:49.029] <TB3> INFO: start marker: 0
[16:09:49.029] <TB3> INFO: stop marker: 0
[16:09:49.029] <TB3> INFO: overflow: 0
[16:09:49.029] <TB3> INFO: invalid 5bit words: 0
[16:09:49.029] <TB3> INFO: invalid XOR eye diagram: 0
[16:09:49.029] <TB3> INFO: frame (failed synchr.): 0
[16:09:49.029] <TB3> INFO: idle data (no TBM trl): 0
[16:09:49.029] <TB3> INFO: no data (only TBM hdr): 0
[16:09:49.029] <TB3> INFO: TBM errors: 0
[16:09:49.029] <TB3> INFO: flawed TBM headers: 0
[16:09:49.029] <TB3> INFO: flawed TBM trailers: 0
[16:09:49.029] <TB3> INFO: event ID mismatches: 0
[16:09:49.029] <TB3> INFO: ROC errors: 0
[16:09:49.029] <TB3> INFO: missing ROC header(s): 0
[16:09:49.029] <TB3> INFO: misplaced readback start: 0
[16:09:49.029] <TB3> INFO: Pixel decoding errors: 0
[16:09:49.029] <TB3> INFO: pixel data incomplete: 0
[16:09:49.029] <TB3> INFO: pixel address: 0
[16:09:49.029] <TB3> INFO: pulse height fill bit: 0
[16:09:49.029] <TB3> INFO: buffer corruption: 0
[16:09:49.145] <TB3> INFO: ######################################################################
[16:09:49.145] <TB3> INFO: PixTestTrim::doTest()
[16:09:49.145] <TB3> INFO: ######################################################################
[16:09:49.147] <TB3> INFO: ----------------------------------------------------------------------
[16:09:49.147] <TB3> INFO: PixTestTrim::trimTest() ntrig = 8, vcal = 35
[16:09:49.147] <TB3> INFO: ----------------------------------------------------------------------
[16:09:49.239] <TB3> INFO: ---> VthrComp thr map (minimal VthrComp)
[16:09:49.240] <TB3> INFO: ---> dac: vthrcomp name: TrimThr0 ntrig: 8 dacrange: 10 .. 160 (-1/-1) hits flags = 528 (plus default)
[16:09:49.254] <TB3> INFO: dacScan split into 1 runs with ntrig = 8
[16:09:49.254] <TB3> INFO: run 1 of 1
[16:09:49.571] <TB3> INFO: Expecting 5025280 events.
[16:10:24.886] <TB3> INFO: 814528 events read in total (34722ms).
[16:10:59.686] <TB3> INFO: 1626256 events read in total (69522ms).
[16:11:34.656] <TB3> INFO: 2434048 events read in total (104492ms).
[16:12:09.284] <TB3> INFO: 3238896 events read in total (139120ms).
[16:12:43.642] <TB3> INFO: 4040048 events read in total (173478ms).
[16:13:20.860] <TB3> INFO: 4837976 events read in total (210696ms).
[16:13:29.256] <TB3> INFO: 5025280 events read in total (219092ms).
[16:13:29.313] <TB3> INFO: Test took 220060ms.
[16:13:59.308] <TB3> INFO: ROC 0 VthrComp = 110
[16:13:59.309] <TB3> INFO: ROC 1 VthrComp = 127
[16:13:59.313] <TB3> INFO: ROC 2 VthrComp = 108
[16:13:59.313] <TB3> INFO: ROC 3 VthrComp = 126
[16:13:59.313] <TB3> INFO: ROC 4 VthrComp = 123
[16:13:59.314] <TB3> INFO: ROC 5 VthrComp = 119
[16:13:59.314] <TB3> INFO: ROC 6 VthrComp = 110
[16:13:59.314] <TB3> INFO: ROC 7 VthrComp = 115
[16:13:59.314] <TB3> INFO: ROC 8 VthrComp = 112
[16:13:59.314] <TB3> INFO: ROC 9 VthrComp = 113
[16:13:59.314] <TB3> INFO: ROC 10 VthrComp = 121
[16:13:59.314] <TB3> INFO: ROC 11 VthrComp = 127
[16:13:59.315] <TB3> INFO: ROC 12 VthrComp = 116
[16:13:59.315] <TB3> INFO: ROC 13 VthrComp = 100
[16:13:59.315] <TB3> INFO: ROC 14 VthrComp = 118
[16:13:59.315] <TB3> INFO: ROC 15 VthrComp = 120
[16:13:59.639] <TB3> INFO: Expecting 41600 events.
[16:14:03.529] <TB3> INFO: 41600 events read in total (3291ms).
[16:14:03.531] <TB3> INFO: Test took 4213ms.
[16:14:03.546] <TB3> INFO: ---> Vcal thr map (pixel with maximum Vcal thr)
[16:14:03.546] <TB3> INFO: ---> dac: vcal name: TrimThr1 ntrig: 8 dacrange: 10 .. 160 (-1/-1) hits flags = 528 (plus default)
[16:14:03.559] <TB3> INFO: dacScan split into 1 runs with ntrig = 8
[16:14:03.559] <TB3> INFO: run 1 of 1
[16:14:03.873] <TB3> INFO: Expecting 5025280 events.
[16:14:34.886] <TB3> INFO: 588752 events read in total (30420ms).
[16:15:04.900] <TB3> INFO: 1176256 events read in total (60434ms).
[16:15:34.879] <TB3> INFO: 1765208 events read in total (90413ms).
[16:16:05.030] <TB3> INFO: 2353104 events read in total (120564ms).
[16:16:35.086] <TB3> INFO: 2939848 events read in total (150620ms).
[16:17:05.398] <TB3> INFO: 3525832 events read in total (180932ms).
[16:17:35.268] <TB3> INFO: 4110744 events read in total (210802ms).
[16:18:06.481] <TB3> INFO: 4695704 events read in total (242015ms).
[16:18:25.532] <TB3> INFO: 5025280 events read in total (261066ms).
[16:18:25.641] <TB3> INFO: Test took 262082ms.
[16:19:06.081] <TB3> INFO: roc 0 with ID = 0 has maximal Vcal 64.633 for pixel 5/4 mean/min/max = 48.6693/32.1785/65.1601
[16:19:06.081] <TB3> INFO: roc 1 with ID = 1 has maximal Vcal 62.7605 for pixel 46/8 mean/min/max = 47.139/31.2702/63.0078
[16:19:06.082] <TB3> INFO: roc 2 with ID = 2 has maximal Vcal 73.0635 for pixel 4/5 mean/min/max = 52.6792/32.1732/73.1851
[16:19:06.082] <TB3> INFO: roc 3 with ID = 3 has maximal Vcal 61.4935 for pixel 17/0 mean/min/max = 45.8029/29.8182/61.7877
[16:19:06.082] <TB3> INFO: roc 4 with ID = 4 has maximal Vcal 59.8014 for pixel 9/69 mean/min/max = 45.4221/31.0071/59.8372
[16:19:06.083] <TB3> INFO: roc 5 with ID = 5 has maximal Vcal 62.6035 for pixel 29/1 mean/min/max = 46.9207/30.7211/63.1203
[16:19:06.083] <TB3> INFO: roc 6 with ID = 6 has maximal Vcal 64.6541 for pixel 20/12 mean/min/max = 48.7847/32.8985/64.671
[16:19:06.084] <TB3> INFO: roc 7 with ID = 7 has maximal Vcal 62.3511 for pixel 3/3 mean/min/max = 46.8582/31.2636/62.4529
[16:19:06.084] <TB3> INFO: roc 8 with ID = 8 has maximal Vcal 57.3271 for pixel 2/73 mean/min/max = 44.9429/32.4685/57.4172
[16:19:06.085] <TB3> INFO: roc 9 with ID = 9 has maximal Vcal 60.33 for pixel 12/5 mean/min/max = 46.277/32.1609/60.393
[16:19:06.085] <TB3> INFO: roc 10 with ID = 10 has maximal Vcal 60.3636 for pixel 12/76 mean/min/max = 46.1932/31.9246/60.4617
[16:19:06.086] <TB3> INFO: roc 11 with ID = 11 has maximal Vcal 60.1828 for pixel 17/4 mean/min/max = 45.8761/31.5203/60.2319
[16:19:06.086] <TB3> INFO: roc 12 with ID = 12 has maximal Vcal 64.2226 for pixel 26/15 mean/min/max = 47.773/31.2504/64.2955
[16:19:06.087] <TB3> INFO: roc 13 with ID = 13 has maximal Vcal 61.7782 for pixel 13/76 mean/min/max = 47.3324/32.6933/61.9716
[16:19:06.087] <TB3> INFO: roc 14 with ID = 14 has maximal Vcal 57.4908 for pixel 18/10 mean/min/max = 44.3645/31.2222/57.5068
[16:19:06.088] <TB3> INFO: roc 15 with ID = 15 has maximal Vcal 58.8055 for pixel 18/79 mean/min/max = 45.7143/32.5493/58.8792
[16:19:06.088] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[16:19:06.187] <TB3> INFO: Expecting 411648 events.
[16:19:16.513] <TB3> INFO: 411648 events read in total (9734ms).
[16:19:16.533] <TB3> INFO: Expecting 411648 events.
[16:19:26.760] <TB3> INFO: 411648 events read in total (9822ms).
[16:19:26.779] <TB3> INFO: Expecting 411648 events.
[16:19:36.899] <TB3> INFO: 411648 events read in total (9716ms).
[16:19:36.917] <TB3> INFO: Expecting 411648 events.
[16:19:47.104] <TB3> INFO: 411648 events read in total (9783ms).
[16:19:47.126] <TB3> INFO: Expecting 411648 events.
[16:19:57.333] <TB3> INFO: 411648 events read in total (9803ms).
[16:19:57.362] <TB3> INFO: Expecting 411648 events.
[16:20:07.431] <TB3> INFO: 411648 events read in total (9665ms).
[16:20:07.460] <TB3> INFO: Expecting 411648 events.
[16:20:17.613] <TB3> INFO: 411648 events read in total (9749ms).
[16:20:17.649] <TB3> INFO: Expecting 411648 events.
[16:20:27.795] <TB3> INFO: 411648 events read in total (9742ms).
[16:20:27.830] <TB3> INFO: Expecting 411648 events.
[16:20:37.928] <TB3> INFO: 411648 events read in total (9693ms).
[16:20:37.973] <TB3> INFO: Expecting 411648 events.
[16:20:48.121] <TB3> INFO: 411648 events read in total (9743ms).
[16:20:48.164] <TB3> INFO: Expecting 411648 events.
[16:20:58.247] <TB3> INFO: 411648 events read in total (9678ms).
[16:20:58.295] <TB3> INFO: Expecting 411648 events.
[16:21:08.410] <TB3> INFO: 411648 events read in total (9712ms).
[16:21:08.471] <TB3> INFO: Expecting 411648 events.
[16:21:18.610] <TB3> INFO: 411648 events read in total (9735ms).
[16:21:18.669] <TB3> INFO: Expecting 411648 events.
[16:21:28.792] <TB3> INFO: 411648 events read in total (9718ms).
[16:21:28.856] <TB3> INFO: Expecting 411648 events.
[16:21:38.888] <TB3> INFO: 411648 events read in total (9628ms).
[16:21:38.950] <TB3> INFO: Expecting 411648 events.
[16:21:49.172] <TB3> INFO: 411648 events read in total (9818ms).
[16:21:49.245] <TB3> INFO: Test took 163157ms.
[16:21:50.393] <TB3> INFO: ---> dac: vcal name: TrimThr2 ntrig: 8 dacrange: 0 .. 150 (-1/-1) hits flags = 528 (plus default)
[16:21:50.407] <TB3> INFO: dacScan split into 1 runs with ntrig = 8
[16:21:50.407] <TB3> INFO: run 1 of 1
[16:21:50.730] <TB3> INFO: Expecting 5025280 events.
[16:22:21.934] <TB3> INFO: 587024 events read in total (30611ms).
[16:22:51.953] <TB3> INFO: 1171928 events read in total (60630ms).
[16:23:21.877] <TB3> INFO: 1756824 events read in total (90554ms).
[16:23:52.388] <TB3> INFO: 2341440 events read in total (121065ms).
[16:24:22.413] <TB3> INFO: 2925608 events read in total (151091ms).
[16:24:52.281] <TB3> INFO: 3508904 events read in total (180958ms).
[16:25:22.287] <TB3> INFO: 4092032 events read in total (210964ms).
[16:25:53.242] <TB3> INFO: 4675040 events read in total (241919ms).

[16:28:34.876] <TB3> INFO: Expecting 5025280 events.
[16:29:06.582] <TB3> INFO: 586976 events read in total (31109ms).
[16:29:36.584] <TB3> INFO: 1171792 events read in total (61111ms).
[16:30:06.689] <TB3> INFO: 1756728 events read in total (91216ms).
[16:30:36.541] <TB3> INFO: 2341264 events read in total (121068ms).
[16:31:07.493] <TB3> INFO: 2925424 events read in total (152020ms).
[16:31:39.723] <TB3> INFO: 3508696 events read in total (184250ms).
[16:32:09.573] <TB3> INFO: 4091696 events read in total (214100ms).
[16:32:39.747] <TB3> INFO: 4674648 events read in total (244274ms).
[16:32:58.564] <TB3> INFO: 5025280 events read in total (263091ms).
[16:32:58.669] <TB3> INFO: Test took 264111ms.
[16:33:37.644] <TB3> INFO: ---> TrimStepCorr4 extremal thresholds: 2.500000 .. 143.892933
[16:33:37.960] <TB3> INFO: Expecting 208000 events.
[16:33:49.298] <TB3> INFO: 208000 events read in total (10742ms).
[16:33:49.300] <TB3> INFO: Test took 11653ms.
[16:33:49.391] <TB3> INFO: ---> dac: vcal name: trimStepCorr4 ntrig: 8 dacrange: 2 .. 153 (-1/-1) hits flags = 528 (plus default)
[16:33:49.405] <TB3> INFO: dacScan split into 1 runs with ntrig = 8
[16:33:49.405] <TB3> INFO: run 1 of 1
[16:33:49.725] <TB3> INFO: Expecting 5058560 events.
[16:34:20.700] <TB3> INFO: 583536 events read in total (30382ms).
[16:34:51.568] <TB3> INFO: 1167208 events read in total (61250ms).
[16:35:22.745] <TB3> INFO: 1750936 events read in total (92427ms).
[16:35:52.777] <TB3> INFO: 2334784 events read in total (122459ms).
[16:36:23.449] <TB3> INFO: 2918136 events read in total (153131ms).
[16:36:55.878] <TB3> INFO: 3501384 events read in total (185560ms).
[16:37:26.249] <TB3> INFO: 4084280 events read in total (215931ms).
[16:37:56.404] <TB3> INFO: 4666912 events read in total (246086ms).
[16:38:17.325] <TB3> INFO: 5058560 events read in total (267007ms).
[16:38:17.417] <TB3> INFO: Test took 268012ms.
[16:38:57.529] <TB3> INFO: ---> TrimStepCorr2 extremal thresholds: 26.065532 .. 47.348785
[16:38:57.855] <TB3> INFO: Expecting 208000 events.
[16:39:09.223] <TB3> INFO: 208000 events read in total (10775ms).
[16:39:09.224] <TB3> INFO: Test took 11691ms.
[16:39:09.317] <TB3> INFO: ---> dac: vcal name: trimStepCorr2 ntrig: 8 dacrange: 16 .. 57 (-1/-1) hits flags = 528 (plus default)
[16:39:09.331] <TB3> INFO: dacScan split into 1 runs with ntrig = 8
[16:39:09.331] <TB3> INFO: run 1 of 1
[16:39:09.647] <TB3> INFO: Expecting 1397760 events.
[16:39:41.952] <TB3> INFO: 654552 events read in total (31709ms).
[16:40:13.600] <TB3> INFO: 1307136 events read in total (63357ms).
[16:40:18.347] <TB3> INFO: 1397760 events read in total (68104ms).
[16:40:18.376] <TB3> INFO: Test took 69046ms.
[16:40:39.845] <TB3> INFO: ---> TrimStepCorr1a extremal thresholds: 24.302613 .. 49.223475
[16:40:40.176] <TB3> INFO: Expecting 208000 events.
[16:40:51.538] <TB3> INFO: 208000 events read in total (10768ms).
[16:40:51.539] <TB3> INFO: Test took 11677ms.
[16:40:51.630] <TB3> INFO: ---> dac: vcal name: trimStepCorr1a ntrig: 8 dacrange: 14 .. 59 (-1/-1) hits flags = 528 (plus default)
[16:40:51.644] <TB3> INFO: dacScan split into 1 runs with ntrig = 8
[16:40:51.644] <TB3> INFO: run 1 of 1
[16:40:51.956] <TB3> INFO: Expecting 1530880 events.
[16:41:24.592] <TB3> INFO: 656296 events read in total (32043ms).
[16:41:57.942] <TB3> INFO: 1311424 events read in total (65393ms).
[16:42:10.061] <TB3> INFO: 1530880 events read in total (77512ms).
[16:42:10.094] <TB3> INFO: Test took 78451ms.
[16:42:36.434] <TB3> INFO: ---> TrimStepCorr1b extremal thresholds: 24.091284 .. 51.990720
[16:42:36.759] <TB3> INFO: Expecting 208000 events.
[16:42:48.113] <TB3> INFO: 208000 events read in total (10762ms).
[16:42:48.117] <TB3> INFO: Test took 11679ms.
[16:42:48.211] <TB3> INFO: ---> dac: vcal name: trimStepCorr1b ntrig: 8 dacrange: 14 .. 61 (-1/-1) hits flags = 528 (plus default)
[16:42:48.224] <TB3> INFO: dacScan split into 1 runs with ntrig = 8
[16:42:48.224] <TB3> INFO: run 1 of 1
[16:42:48.543] <TB3> INFO: Expecting 1597440 events.
[16:43:20.858] <TB3> INFO: 649552 events read in total (31722ms).
[16:43:52.443] <TB3> INFO: 1299104 events read in total (63307ms).
[16:44:07.136] <TB3> INFO: 1597440 events read in total (78000ms).
[16:44:07.180] <TB3> INFO: Test took 78956ms.
[16:44:29.090] <TB3> INFO: ---> TrimThrFinal extremal thresholds: 15 .. 55
[16:44:29.090] <TB3> INFO: ---> dac: vcal name: TrimThrFinal ntrig: 8 dacrange: 15 .. 55 (-1/-1) hits flags = 528 (plus default)
[16:44:29.105] <TB3> INFO: dacScan split into 1 runs with ntrig = 8
[16:44:29.105] <TB3> INFO: run 1 of 1
[16:44:29.419] <TB3> INFO: Expecting 1364480 events.
[16:45:02.341] <TB3> INFO: 667352 events read in total (32327ms).
[16:45:34.743] <TB3> INFO: 1333696 events read in total (64730ms).
[16:45:36.826] <TB3> INFO: 1364480 events read in total (66812ms).
[16:45:36.867] <TB3> INFO: Test took 67761ms.
[16:45:58.201] <TB3> INFO: write dac parameters into /home/sensor/david/FullQualification/data/M1023_FullQualification_2016-10-19_14h52m_1476881545//001_Fulltest_m20//dacParameters35_C0.dat
[16:45:58.201] <TB3> INFO: write dac parameters into /home/sensor/david/FullQualification/data/M1023_FullQualification_2016-10-19_14h52m_1476881545//001_Fulltest_m20//dacParameters35_C1.dat
[16:45:58.201] <TB3> INFO: write dac parameters into /home/sensor/david/FullQualification/data/M1023_FullQualification_2016-10-19_14h52m_1476881545//001_Fulltest_m20//dacParameters35_C2.dat
[16:45:58.201] <TB3> INFO: write dac parameters into /home/sensor/david/FullQualification/data/M1023_FullQualification_2016-10-19_14h52m_1476881545//001_Fulltest_m20//dacParameters35_C3.dat
[16:45:58.201] <TB3> INFO: write dac parameters into /home/sensor/david/FullQualification/data/M1023_FullQualification_2016-10-19_14h52m_1476881545//001_Fulltest_m20//dacParameters35_C4.dat
[16:45:58.202] <TB3> INFO: write dac parameters into /home/sensor/david/FullQualification/data/M1023_FullQualification_2016-10-19_14h52m_1476881545//001_Fulltest_m20//dacParameters35_C5.dat
[16:45:58.202] <TB3> INFO: write dac parameters into /home/sensor/david/FullQualification/data/M1023_FullQualification_2016-10-19_14h52m_1476881545//001_Fulltest_m20//dacParameters35_C6.dat
[16:45:58.202] <TB3> INFO: write dac parameters into /home/sensor/david/FullQualification/data/M1023_FullQualification_2016-10-19_14h52m_1476881545//001_Fulltest_m20//dacParameters35_C7.dat
[16:45:58.202] <TB3> INFO: write dac parameters into /home/sensor/david/FullQualification/data/M1023_FullQualification_2016-10-19_14h52m_1476881545//001_Fulltest_m20//dacParameters35_C8.dat
[16:45:58.202] <TB3> INFO: write dac parameters into /home/sensor/david/FullQualification/data/M1023_FullQualification_2016-10-19_14h52m_1476881545//001_Fulltest_m20//dacParameters35_C9.dat
[16:45:58.203] <TB3> INFO: write dac parameters into /home/sensor/david/FullQualification/data/M1023_FullQualification_2016-10-19_14h52m_1476881545//001_Fulltest_m20//dacParameters35_C10.dat
[16:45:58.203] <TB3> INFO: write dac parameters into /home/sensor/david/FullQualification/data/M1023_FullQualification_2016-10-19_14h52m_1476881545//001_Fulltest_m20//dacParameters35_C11.dat
[16:45:58.203] <TB3> INFO: write dac parameters into /home/sensor/david/FullQualification/data/M1023_FullQualification_2016-10-19_14h52m_1476881545//001_Fulltest_m20//dacParameters35_C12.dat
[16:45:58.203] <TB3> INFO: write dac parameters into /home/sensor/david/FullQualification/data/M1023_FullQualification_2016-10-19_14h52m_1476881545//001_Fulltest_m20//dacParameters35_C13.dat
[16:45:58.203] <TB3> INFO: write dac parameters into /home/sensor/david/FullQualification/data/M1023_FullQualification_2016-10-19_14h52m_1476881545//001_Fulltest_m20//dacParameters35_C14.dat
[16:45:58.203] <TB3> INFO: write dac parameters into /home/sensor/david/FullQualification/data/M1023_FullQualification_2016-10-19_14h52m_1476881545//001_Fulltest_m20//dacParameters35_C15.dat
[16:45:58.204] <TB3> INFO: write trim parameters into /home/sensor/david/FullQualification/data/M1023_FullQualification_2016-10-19_14h52m_1476881545//001_Fulltest_m20//trimParameters35_C0.dat
[16:45:58.217] <TB3> INFO: write trim parameters into /home/sensor/david/FullQualification/data/M1023_FullQualification_2016-10-19_14h52m_1476881545//001_Fulltest_m20//trimParameters35_C1.dat
[16:45:58.228] <TB3> INFO: write trim parameters into /home/sensor/david/FullQualification/data/M1023_FullQualification_2016-10-19_14h52m_1476881545//001_Fulltest_m20//trimParameters35_C2.dat
[16:45:58.240] <TB3> INFO: write trim parameters into /home/sensor/david/FullQualification/data/M1023_FullQualification_2016-10-19_14h52m_1476881545//001_Fulltest_m20//trimParameters35_C3.dat
[16:45:58.249] <TB3> INFO: write trim parameters into /home/sensor/david/FullQualification/data/M1023_FullQualification_2016-10-19_14h52m_1476881545//001_Fulltest_m20//trimParameters35_C4.dat
[16:45:58.259] <TB3> INFO: write trim parameters into /home/sensor/david/FullQualification/data/M1023_FullQualification_2016-10-19_14h52m_1476881545//001_Fulltest_m20//trimParameters35_C5.dat
[16:45:58.269] <TB3> INFO: write trim parameters into /home/sensor/david/FullQualification/data/M1023_FullQualification_2016-10-19_14h52m_1476881545//001_Fulltest_m20//trimParameters35_C6.dat
[16:45:58.279] <TB3> INFO: write trim parameters into /home/sensor/david/FullQualification/data/M1023_FullQualification_2016-10-19_14h52m_1476881545//001_Fulltest_m20//trimParameters35_C7.dat
[16:45:58.289] <TB3> INFO: write trim parameters into /home/sensor/david/FullQualification/data/M1023_FullQualification_2016-10-19_14h52m_1476881545//001_Fulltest_m20//trimParameters35_C8.dat
[16:45:58.299] <TB3> INFO: write trim parameters into /home/sensor/david/FullQualification/data/M1023_FullQualification_2016-10-19_14h52m_1476881545//001_Fulltest_m20//trimParameters35_C9.dat
[16:45:58.309] <TB3> INFO: write trim parameters into /home/sensor/david/FullQualification/data/M1023_FullQualification_2016-10-19_14h52m_1476881545//001_Fulltest_m20//trimParameters35_C10.dat
[16:45:58.320] <TB3> INFO: write trim parameters into /home/sensor/david/FullQualification/data/M1023_FullQualification_2016-10-19_14h52m_1476881545//001_Fulltest_m20//trimParameters35_C11.dat
[16:45:58.334] <TB3> INFO: write trim parameters into /home/sensor/david/FullQualification/data/M1023_FullQualification_2016-10-19_14h52m_1476881545//001_Fulltest_m20//trimParameters35_C12.dat
[16:45:58.349] <TB3> INFO: write trim parameters into /home/sensor/david/FullQualification/data/M1023_FullQualification_2016-10-19_14h52m_1476881545//001_Fulltest_m20//trimParameters35_C13.dat
[16:45:58.359] <TB3> INFO: write trim parameters into /home/sensor/david/FullQualification/data/M1023_FullQualification_2016-10-19_14h52m_1476881545//001_Fulltest_m20//trimParameters35_C14.dat
[16:45:58.370] <TB3> INFO: write trim parameters into /home/sensor/david/FullQualification/data/M1023_FullQualification_2016-10-19_14h52m_1476881545//001_Fulltest_m20//trimParameters35_C15.dat
[16:45:58.381] <TB3> INFO: PixTestTrim::trimTest() done
[16:45:58.381] <TB3> INFO: vtrim: 161 159 151 122 126 130 139 130 120 135 136 145 156 120 120 131
[16:45:58.381] <TB3> INFO: vthrcomp: 110 127 108 126 123 119 110 115 112 113 121 127 116 100 118 120
[16:45:58.381] <TB3> INFO: vcal mean: 34.98 34.99 35.40 35.04 34.96 34.97 35.00 34.97 34.98 34.94 34.98 34.95 34.95 34.99 34.93 35.00
[16:45:58.381] <TB3> INFO: vcal RMS: 1.04 1.13 1.69 1.20 1.12 1.16 1.07 1.09 0.88 0.96 1.06 1.03 1.23 1.08 0.99 1.00
[16:45:58.381] <TB3> INFO: bits mean: 9.15 9.59 8.90 9.59 9.74 9.30 8.91 9.30 9.46 9.51 9.28 9.72 9.46 9.23 9.98 9.28
[16:45:58.381] <TB3> INFO: bits RMS: 2.65 2.71 2.68 2.85 2.72 2.88 2.58 2.84 2.68 2.58 2.73 2.64 2.70 2.59 2.66 2.70
[16:45:58.403] <TB3> INFO: ----------------------------------------------------------------------
[16:45:58.403] <TB3> INFO: PixTestTrim::trimBitTest() ntrig = 5, vtrims = 254 126 63 32
[16:45:58.403] <TB3> INFO: ----------------------------------------------------------------------
[16:45:58.409] <TB3> INFO: ---> dac: Vcal name: TrimBitsThr0 ntrig: 5 dacrange: 0 .. 199 (-1/-1) hits flags = 528 (plus default)
[16:45:58.423] <TB3> INFO: dacScan split into 1 runs with ntrig = 5
[16:45:58.423] <TB3> INFO: run 1 of 1
[16:45:58.736] <TB3> INFO: Expecting 4160000 events.
[16:46:37.214] <TB3> INFO: 739215 events read in total (37884ms).
[16:47:13.939] <TB3> INFO: 1472775 events read in total (74609ms).
[16:47:51.863] <TB3> INFO: 2200940 events read in total (112533ms).
[16:48:29.370] <TB3> INFO: 2923980 events read in total (150040ms).
[16:49:05.334] <TB3> INFO: 3643900 events read in total (186004ms).
[16:49:30.671] <TB3> INFO: 4160000 events read in total (211341ms).
[16:49:30.744] <TB3> INFO: Test took 212321ms.
[16:50:14.451] <TB3> INFO: ---> dac: Vcal name: TrimThr_trim14 ntrig: 5 dacrange: 0 .. 198 (-1/-1) hits flags = 528 (plus default)
[16:50:14.471] <TB3> INFO: dacScan split into 1 runs with ntrig = 5
[16:50:14.473] <TB3> INFO: run 1 of 1
[16:50:14.801] <TB3> INFO: Expecting 4139200 events.
[16:50:50.821] <TB3> INFO: 716750 events read in total (35427ms).
[16:51:25.925] <TB3> INFO: 1427660 events read in total (70532ms).
[16:52:00.897] <TB3> INFO: 2134410 events read in total (105503ms).
[16:52:36.046] <TB3> INFO: 2836560 events read in total (140652ms).
[16:53:14.419] <TB3> INFO: 3536315 events read in total (179025ms).
[16:53:44.431] <TB3> INFO: 4139200 events read in total (209037ms).
[16:53:44.511] <TB3> INFO: Test took 210035ms.
[16:54:29.722] <TB3> INFO: ---> dac: Vcal name: TrimThr_trim13 ntrig: 5 dacrange: 0 .. 183 (-1/-1) hits flags = 528 (plus default)
[16:54:29.736] <TB3> INFO: dacScan split into 1 runs with ntrig = 5
[16:54:29.736] <TB3> INFO: run 1 of 1
[16:54:30.054] <TB3> INFO: Expecting 3827200 events.
[16:55:06.673] <TB3> INFO: 737350 events read in total (36026ms).
[16:55:42.754] <TB3> INFO: 1468580 events read in total (72107ms).
[16:56:21.251] <TB3> INFO: 2193100 events read in total (110604ms).
[16:56:58.747] <TB3> INFO: 2913650 events read in total (148100ms).
[16:57:36.195] <TB3> INFO: 3631490 events read in total (185548ms).
[16:57:46.942] <TB3> INFO: 3827200 events read in total (196295ms).
[16:57:47.019] <TB3> INFO: Test took 197283ms.
[16:58:33.457] <TB3> INFO: ---> dac: Vcal name: TrimThr_trim11 ntrig: 5 dacrange: 0 .. 182 (-1/-1) hits flags = 528 (plus default)
[16:58:33.471] <TB3> INFO: dacScan split into 1 runs with ntrig = 5
[16:58:33.471] <TB3> INFO: run 1 of 1
[16:58:33.786] <TB3> INFO: Expecting 3806400 events.
[16:59:11.543] <TB3> INFO: 739150 events read in total (37179ms).
[16:59:47.597] <TB3> INFO: 1472105 events read in total (73233ms).
[17:00:23.379] <TB3> INFO: 2198300 events read in total (109015ms).
[17:00:59.038] <TB3> INFO: 2920265 events read in total (144674ms).
[17:01:34.917] <TB3> INFO: 3639425 events read in total (180553ms).
[17:01:43.320] <TB3> INFO: 3806400 events read in total (188956ms).
[17:01:43.380] <TB3> INFO: Test took 189910ms.
[17:02:24.767] <TB3> INFO: ---> dac: Vcal name: TrimThr_trim7 ntrig: 5 dacrange: 0 .. 183 (-1/-1) hits flags = 528 (plus default)
[17:02:24.788] <TB3> INFO: dacScan split into 1 runs with ntrig = 5
[17:02:24.788] <TB3> INFO: run 1 of 1
[17:02:25.132] <TB3> INFO: Expecting 3827200 events.
[17:03:01.579] <TB3> INFO: 737655 events read in total (35854ms).
[17:03:37.582] <TB3> INFO: 1469230 events read in total (71857ms).
[17:04:13.675] <TB3> INFO: 2194095 events read in total (107950ms).
[17:04:49.349] <TB3> INFO: 2915015 events read in total (143624ms).
[17:05:24.743] <TB3> INFO: 3633020 events read in total (179018ms).
[17:05:34.572] <TB3> INFO: 3827200 events read in total (188847ms).
[17:05:34.632] <TB3> INFO: Test took 189843ms.
[17:06:15.175] <TB3> INFO: PixTestTrim::trimBitTest() done
[17:06:15.176] <TB3> INFO: PixTestTrim::doTest() done, duration: 3386 seconds
[17:06:15.176] <TB3> INFO: Decoding statistics:
[17:06:15.176] <TB3> INFO: General information:
[17:06:15.176] <TB3> INFO: 16bit words read: 0
[17:06:15.176] <TB3> INFO: valid events total: 0
[17:06:15.176] <TB3> INFO: empty events: 0
[17:06:15.176] <TB3> INFO: valid events with pixels: 0
[17:06:15.176] <TB3> INFO: valid pixel hits: 0
[17:06:15.176] <TB3> INFO: Event errors: 0
[17:06:15.176] <TB3> INFO: start marker: 0
[17:06:15.176] <TB3> INFO: stop marker: 0
[17:06:15.176] <TB3> INFO: overflow: 0
[17:06:15.176] <TB3> INFO: invalid 5bit words: 0
[17:06:15.176] <TB3> INFO: invalid XOR eye diagram: 0
[17:06:15.176] <TB3> INFO: frame (failed synchr.): 0
[17:06:15.176] <TB3> INFO: idle data (no TBM trl): 0
[17:06:15.177] <TB3> INFO: no data (only TBM hdr): 0
[17:06:15.177] <TB3> INFO: TBM errors: 0
[17:06:15.177] <TB3> INFO: flawed TBM headers: 0
[17:06:15.177] <TB3> INFO: flawed TBM trailers: 0
[17:06:15.177] <TB3> INFO: event ID mismatches: 0
[17:06:15.177] <TB3> INFO: ROC errors: 0
[17:06:15.177] <TB3> INFO: missing ROC header(s): 0
[17:06:15.177] <TB3> INFO: misplaced readback start: 0
[17:06:15.177] <TB3> INFO: Pixel decoding errors: 0
[17:06:15.177] <TB3> INFO: pixel data incomplete: 0
[17:06:15.177] <TB3> INFO: pixel address: 0
[17:06:15.177] <TB3> INFO: pulse height fill bit: 0
[17:06:15.177] <TB3> INFO: buffer corruption: 0
[17:06:16.178] <TB3> INFO: ######################################################################
[17:06:16.178] <TB3> INFO: PixTestPhOptimization::doTest() Ntrig = 10
[17:06:16.179] <TB3> INFO: ######################################################################
[17:06:16.492] <TB3> INFO: Expecting 41600 events.
[17:06:20.372] <TB3> INFO: 41600 events read in total (3287ms).
[17:06:20.373] <TB3> INFO: Test took 4193ms.
[17:06:20.916] <TB3> INFO: Expecting 41600 events.
[17:06:24.815] <TB3> INFO: 41600 events read in total (3306ms).
[17:06:24.816] <TB3> INFO: Test took 4233ms.
[17:06:25.158] <TB3> INFO: Expecting 41600 events.
[17:06:29.075] <TB3> INFO: 41600 events read in total (3324ms).
[17:06:29.076] <TB3> INFO: Test took 4231ms.
[17:06:29.417] <TB3> INFO: Expecting 41600 events.
[17:06:33.329] <TB3> INFO: 41600 events read in total (3318ms).
[17:06:33.334] <TB3> INFO: Test took 4229ms.
[17:06:33.694] <TB3> INFO: Expecting 41600 events.
[17:06:37.616] <TB3> INFO: 41600 events read in total (3329ms).
[17:06:37.617] <TB3> INFO: Test took 4245ms.
[17:06:37.967] <TB3> INFO: Expecting 41600 events.
[17:06:41.871] <TB3> INFO: 41600 events read in total (3311ms).
[17:06:41.871] <TB3> INFO: Test took 4225ms.
[17:06:42.232] <TB3> INFO: Expecting 41600 events.
[17:06:46.150] <TB3> INFO: 41600 events read in total (3325ms).
[17:06:46.152] <TB3> INFO: Test took 4235ms.
[17:06:46.502] <TB3> INFO: Expecting 41600 events.
[17:06:50.427] <TB3> INFO: 41600 events read in total (3332ms).
[17:06:50.428] <TB3> INFO: Test took 4248ms.
[17:06:50.772] <TB3> INFO: Expecting 41600 events.
[17:06:54.683] <TB3> INFO: 41600 events read in total (3318ms).
[17:06:54.685] <TB3> INFO: Test took 4227ms.
[17:06:55.039] <TB3> INFO: Expecting 41600 events.
[17:06:59.017] <TB3> INFO: 41600 events read in total (3386ms).
[17:06:59.017] <TB3> INFO: Test took 4296ms.
[17:06:59.370] <TB3> INFO: Expecting 41600 events.
[17:07:03.312] <TB3> INFO: 41600 events read in total (3349ms).
[17:07:03.313] <TB3> INFO: Test took 4265ms.
[17:07:03.661] <TB3> INFO: Expecting 41600 events.
[17:07:07.585] <TB3> INFO: 41600 events read in total (3331ms).
[17:07:07.586] <TB3> INFO: Test took 4243ms.
[17:07:07.927] <TB3> INFO: Expecting 41600 events.
[17:07:11.866] <TB3> INFO: 41600 events read in total (3346ms).
[17:07:11.869] <TB3> INFO: Test took 4254ms.
[17:07:12.228] <TB3> INFO: Expecting 41600 events.
[17:07:16.159] <TB3> INFO: 41600 events read in total (3338ms).
[17:07:16.160] <TB3> INFO: Test took 4256ms.
[17:07:16.507] <TB3> INFO: Expecting 41600 events.
[17:07:20.444] <TB3> INFO: 41600 events read in total (3345ms).
[17:07:20.446] <TB3> INFO: Test took 4257ms.
[17:07:20.789] <TB3> INFO: Expecting 41600 events.
[17:07:24.729] <TB3> INFO: 41600 events read in total (3347ms).
[17:07:24.734] <TB3> INFO: Test took 4257ms.
[17:07:25.087] <TB3> INFO: Expecting 41600 events.
[17:07:29.019] <TB3> INFO: 41600 events read in total (3340ms).
[17:07:29.020] <TB3> INFO: Test took 4249ms.
[17:07:29.380] <TB3> INFO: Expecting 41600 events.
[17:07:33.305] <TB3> INFO: 41600 events read in total (3332ms).
[17:07:33.306] <TB3> INFO: Test took 4254ms.
[17:07:33.652] <TB3> INFO: Expecting 41600 events.
[17:07:37.564] <TB3> INFO: 41600 events read in total (3319ms).
[17:07:37.564] <TB3> INFO: Test took 4229ms.
[17:07:37.906] <TB3> INFO: Expecting 41600 events.
[17:07:41.850] <TB3> INFO: 41600 events read in total (3351ms).
[17:07:41.851] <TB3> INFO: Test took 4258ms.
[17:07:42.194] <TB3> INFO: Expecting 41600 events.
[17:07:46.139] <TB3> INFO: 41600 events read in total (3350ms).
[17:07:46.147] <TB3> INFO: Test took 4267ms.
[17:07:46.489] <TB3> INFO: Expecting 41600 events.
[17:07:50.418] <TB3> INFO: 41600 events read in total (3337ms).
[17:07:50.418] <TB3> INFO: Test took 4241ms.
[17:07:50.769] <TB3> INFO: Expecting 41600 events.
[17:07:54.693] <TB3> INFO: 41600 events read in total (3331ms).
[17:07:54.697] <TB3> INFO: Test took 4250ms.
[17:07:55.043] <TB3> INFO: Expecting 41600 events.
[17:07:58.952] <TB3> INFO: 41600 events read in total (3315ms).
[17:07:58.953] <TB3> INFO: Test took 4227ms.
[17:07:59.307] <TB3> INFO: Expecting 41600 events.
[17:08:03.230] <TB3> INFO: 41600 events read in total (3331ms).
[17:08:03.231] <TB3> INFO: Test took 4249ms.
[17:08:03.580] <TB3> INFO: Expecting 41600 events.
[17:08:07.503] <TB3> INFO: 41600 events read in total (3330ms).
[17:08:07.504] <TB3> INFO: Test took 4245ms.
[17:08:07.859] <TB3> INFO: Expecting 41600 events.
[17:08:11.843] <TB3> INFO: 41600 events read in total (3391ms).
[17:08:11.844] <TB3> INFO: Test took 4308ms.
[17:08:12.190] <TB3> INFO: Expecting 41600 events.
[17:08:16.117] <TB3> INFO: 41600 events read in total (3331ms).
[17:08:16.120] <TB3> INFO: Test took 4248ms.
[17:08:16.473] <TB3> INFO: Expecting 41600 events.
[17:08:20.378] <TB3> INFO: 41600 events read in total (3312ms).
[17:08:20.379] <TB3> INFO: Test took 4229ms.
[17:08:20.726] <TB3> INFO: Expecting 41600 events.
[17:08:24.640] <TB3> INFO: 41600 events read in total (3321ms).
[17:08:24.641] <TB3> INFO: Test took 4230ms.
[17:08:24.938] <TB3> INFO: Expecting 2560 events.
[17:08:25.831] <TB3> INFO: 2560 events read in total (300ms).
[17:08:25.831] <TB3> INFO: Test took 1170ms.
[17:08:26.141] <TB3> INFO: Expecting 2560 events.
[17:08:27.034] <TB3> INFO: 2560 events read in total (300ms).
[17:08:27.035] <TB3> INFO: Test took 1200ms.
[17:08:27.344] <TB3> INFO: Expecting 2560 events.
[17:08:28.240] <TB3> INFO: 2560 events read in total (301ms).
[17:08:28.241] <TB3> INFO: Test took 1205ms.
[17:08:28.551] <TB3> INFO: Expecting 2560 events.
[17:08:29.445] <TB3> INFO: 2560 events read in total (302ms).
[17:08:29.445] <TB3> INFO: Test took 1199ms.
[17:08:29.760] <TB3> INFO: Expecting 2560 events.
[17:08:30.656] <TB3> INFO: 2560 events read in total (303ms).
[17:08:30.659] <TB3> INFO: Test took 1213ms.
[17:08:30.967] <TB3> INFO: Expecting 2560 events.
[17:08:31.861] <TB3> INFO: 2560 events read in total (301ms).
[17:08:31.862] <TB3> INFO: Test took 1202ms.
[17:08:32.175] <TB3> INFO: Expecting 2560 events.
[17:08:33.063] <TB3> INFO: 2560 events read in total (296ms).
[17:08:33.064] <TB3> INFO: Test took 1201ms.
[17:08:33.375] <TB3> INFO: Expecting 2560 events.
[17:08:34.263] <TB3> INFO: 2560 events read in total (295ms).
[17:08:34.264] <TB3> INFO: Test took 1199ms.
[17:08:34.575] <TB3> INFO: Expecting 2560 events.
[17:08:35.463] <TB3> INFO: 2560 events read in total (295ms).
[17:08:35.467] <TB3> INFO: Test took 1202ms.
[17:08:35.774] <TB3> INFO: Expecting 2560 events.
[17:08:36.662] <TB3> INFO: 2560 events read in total (295ms).
[17:08:36.662] <TB3> INFO: Test took 1188ms.
[17:08:36.972] <TB3> INFO: Expecting 2560 events.
[17:08:37.861] <TB3> INFO: 2560 events read in total (295ms).
[17:08:37.861] <TB3> INFO: Test took 1193ms.
[17:08:38.173] <TB3> INFO: Expecting 2560 events.
[17:08:39.066] <TB3> INFO: 2560 events read in total (298ms).
[17:08:39.067] <TB3> INFO: Test took 1205ms.
[17:08:39.378] <TB3> INFO: Expecting 2560 events.
[17:08:40.271] <TB3> INFO: 2560 events read in total (300ms).
[17:08:40.271] <TB3> INFO: Test took 1202ms.
[17:08:40.581] <TB3> INFO: Expecting 2560 events.
[17:08:41.481] <TB3> INFO: 2560 events read in total (300ms).
[17:08:41.481] <TB3> INFO: Test took 1209ms.
[17:08:41.794] <TB3> INFO: Expecting 2560 events.
[17:08:42.691] <TB3> INFO: 2560 events read in total (304ms).
[17:08:42.692] <TB3> INFO: Test took 1209ms.
[17:08:42.003] <TB3> INFO: Expecting 2560 events.
[17:08:43.901] <TB3> INFO: 2560 events read in total (305ms).
[17:08:43.902] <TB3> INFO: Test took 1209ms.
[17:08:43.911] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[17:08:44.213] <TB3> INFO: Expecting 655360 events.
[17:09:00.305] <TB3> INFO: 655360 events read in total (15498ms).
[17:09:00.320] <TB3> INFO: Expecting 655360 events.
[17:09:16.227] <TB3> INFO: 655360 events read in total (15502ms).
[17:09:16.248] <TB3> INFO: Expecting 655360 events.
[17:09:32.053] <TB3> INFO: 655360 events read in total (15400ms).
[17:09:32.079] <TB3> INFO: Expecting 655360 events.
[17:09:47.874] <TB3> INFO: 655360 events read in total (15391ms).
[17:09:47.915] <TB3> INFO: Expecting 655360 events.
[17:10:03.647] <TB3> INFO: 655360 events read in total (15329ms).
[17:10:03.687] <TB3> INFO: Expecting 655360 events.
[17:10:19.450] <TB3> INFO: 655360 events read in total (15359ms).
[17:10:19.493] <TB3> INFO: Expecting 655360 events.
[17:10:35.224] <TB3> INFO: 655360 events read in total (15327ms).
[17:10:35.283] <TB3> INFO: Expecting 655360 events.
[17:10:51.084] <TB3> INFO: 655360 events read in total (15396ms).
[17:10:51.140] <TB3> INFO: Expecting 655360 events.
[17:11:07.278] <TB3> INFO: 655360 events read in total (15732ms).
[17:11:07.345] <TB3> INFO: Expecting 655360 events.
[17:11:23.133] <TB3> INFO: 655360 events read in total (15384ms).
[17:11:23.200] <TB3> INFO: Expecting 655360 events.
[17:11:38.928] <TB3> INFO: 655360 events read in total (15324ms).
[17:11:39.007] <TB3> INFO: Expecting 655360 events.
[17:11:54.874] <TB3> INFO: 655360 events read in total (15462ms).
[17:11:54.955] <TB3> INFO: Expecting 655360 events.
[17:12:10.691] <TB3> INFO: 655360 events read in total (15332ms).
[17:12:10.789] <TB3> INFO: Expecting 655360 events.
[17:12:26.572] <TB3> INFO: 655360 events read in total (15379ms).
[17:12:26.670] <TB3> INFO: Expecting 655360 events.
[17:12:42.419] <TB3> INFO: 655360 events read in total (15340ms).
[17:12:42.531] <TB3> INFO: Expecting 655360 events.
[17:12:58.321] <TB3> INFO: 655360 events read in total (15386ms).
[17:12:58.434] <TB3> INFO: Test took 254523ms.
[17:12:58.551] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[17:12:58.806] <TB3> INFO: Expecting 655360 events.
[17:13:14.568] <TB3> INFO: 655360 events read in total (15169ms).
[17:13:14.582] <TB3> INFO: Expecting 655360 events.
[17:13:30.149] <TB3> INFO: 655360 events read in total (15162ms).
[17:13:30.171] <TB3> INFO: Expecting 655360 events.
[17:13:45.579] <TB3> INFO: 655360 events read in total (15003ms).
[17:13:45.609] <TB3> INFO: Expecting 655360 events.
[17:14:01.242] <TB3> INFO: 655360 events read in total (15225ms).
[17:14:01.274] <TB3> INFO: Expecting 655360 events.
[17:14:17.519] <TB3> INFO: 655360 events read in total (15841ms).
[17:14:17.583] <TB3> INFO: Expecting 655360 events.
[17:14:33.415] <TB3> INFO: 655360 events read in total (15428ms).
[17:14:33.460] <TB3> INFO: Expecting 655360 events.
[17:14:49.214] <TB3> INFO: 655360 events read in total (15350ms).
[17:14:49.267] <TB3> INFO: Expecting 655360 events.
[17:15:05.143] <TB3> INFO: 655360 events read in total (15472ms).
[17:15:05.205] <TB3> INFO: Expecting 655360 events.
[17:15:20.812] <TB3> INFO: 655360 events read in total (15203ms).
[17:15:20.872] <TB3> INFO: Expecting 655360 events.
[17:15:36.639] <TB3> INFO: 655360 events read in total (15363ms).
[17:15:36.715] <TB3> INFO: Expecting 655360 events.
[17:15:52.502] <TB3> INFO: 655360 events read in total (15383ms).
[17:15:52.575] <TB3> INFO: Expecting 655360 events.
[17:16:08.297] <TB3> INFO: 655360 events read in total (15318ms).
[17:16:08.385] <TB3> INFO: Expecting 655360 events.
[17:16:23.899] <TB3> INFO: 655360 events read in total (15110ms).
[17:16:23.983] <TB3> INFO: Expecting 655360 events.
[17:16:39.617] <TB3> INFO: 655360 events read in total (15229ms).
[17:16:39.716] <TB3> INFO: Expecting 655360 events.
[17:16:55.440] <TB3> INFO: 655360 events read in total (15320ms).
[17:16:55.547] <TB3> INFO: Expecting 655360 events.
[17:17:10.853] <TB3> INFO: 655360 events read in total (14901ms).
[17:17:10.966] <TB3> INFO: Test took 252415ms.
[17:17:11.289] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[17:17:11.297] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[17:17:11.305] <TB3> INFO: safety margin for low PH: adding 1, margin is now 21
[17:17:11.312] <TB3> INFO: safety margin for low PH: adding 2, margin is now 22
[17:17:11.320] <TB3> INFO: safety margin for low PH: adding 3, margin is now 23
[17:17:11.328] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[17:17:11.336] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[17:17:11.344] <TB3> INFO: safety margin for low PH: adding 1, margin is now 21
[17:17:11.352] <TB3> INFO: safety margin for low PH: adding 2, margin is now 22
[17:17:11.360] <TB3> INFO: safety margin for low PH: adding 3, margin is now 23
[17:17:11.368] <TB3> INFO: safety margin for low PH: adding 4, margin is now 24
[17:17:11.376] <TB3> INFO: safety margin for low PH: adding 5, margin is now 25
[17:17:11.383] <TB3> INFO: safety margin for low PH: adding 6, margin is now 26
[17:17:11.391] <TB3> INFO: safety margin for low PH: adding 7, margin is now 27
[17:17:11.399] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[17:17:11.407] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[17:17:11.415] <TB3> INFO: safety margin for low PH: adding 1, margin is now 21
[17:17:11.423] <TB3> INFO: safety margin for low PH: adding 2, margin is now 22
[17:17:11.431] <TB3> INFO: safety margin for low PH: adding 3, margin is now 23
[17:17:11.438] <TB3> INFO: safety margin for low PH: adding 4, margin is now 24
[17:17:11.446] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[17:17:11.454] <TB3> INFO: safety margin for low PH: adding 1, margin is now 21
[17:17:11.462] <TB3> INFO: safety margin for low PH: adding 2, margin is now 22
[17:17:11.470] <TB3> INFO: safety margin for low PH: adding 3, margin is now 23
[17:17:11.478] <TB3> INFO: safety margin for low PH: adding 4, margin is now 24
[17:17:11.486] <TB3> INFO: safety margin for low PH: adding 5, margin is now 25
[17:17:11.494] <TB3> INFO: safety margin for low PH: adding 6, margin is now 26
[17:17:11.502] <TB3> INFO: safety margin for low PH: adding 7, margin is now 27
[17:17:11.509] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[17:17:11.518] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[17:17:11.525] <TB3> INFO: safety margin for low PH: adding 1, margin is now 21
[17:17:11.533] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[17:17:11.541] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[17:17:11.549] <TB3> INFO: safety margin for low PH: adding 1, margin is now 21
[17:17:11.557] <TB3> INFO: safety margin for low PH: adding 2, margin is now 22
[17:17:11.565] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[17:17:11.573] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[17:17:11.581] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[17:17:11.589] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[17:17:11.596] <TB3> INFO: safety margin for low PH: adding 1, margin is now 21
[17:17:11.604] <TB3> INFO: safety margin for low PH: adding 2, margin is now 22
[17:17:11.612] <TB3> INFO: safety margin for low PH: adding 3, margin is now 23
[17:17:11.620] <TB3> INFO: safety margin for low PH: adding 4, margin is now 24
[17:17:11.628] <TB3> INFO: safety margin for low PH: adding 5, margin is now 25
[17:17:11.636] <TB3> INFO: safety margin for low PH: adding 6, margin is now 26
[17:17:11.644] <TB3> INFO: safety margin for low PH: adding 7, margin is now 27
[17:17:11.651] <TB3> INFO: safety margin for low PH: adding 8, margin is now 28
[17:17:11.659] <TB3> INFO: safety margin for low PH: adding 9, margin is now 29
[17:17:11.667] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[17:17:11.728] <TB3> INFO: write dac parameters into /home/sensor/david/FullQualification/data/M1023_FullQualification_2016-10-19_14h52m_1476881545//001_Fulltest_m20//dacParameters35_C0.dat
[17:17:11.728] <TB3> INFO: write dac parameters into /home/sensor/david/FullQualification/data/M1023_FullQualification_2016-10-19_14h52m_1476881545//001_Fulltest_m20//dacParameters35_C1.dat
[17:17:11.728] <TB3> INFO: write dac parameters into /home/sensor/david/FullQualification/data/M1023_FullQualification_2016-10-19_14h52m_1476881545//001_Fulltest_m20//dacParameters35_C2.dat
[17:17:11.728] <TB3> INFO: write dac parameters into /home/sensor/david/FullQualification/data/M1023_FullQualification_2016-10-19_14h52m_1476881545//001_Fulltest_m20//dacParameters35_C3.dat
[17:17:11.729] <TB3> INFO: write dac parameters into /home/sensor/david/FullQualification/data/M1023_FullQualification_2016-10-19_14h52m_1476881545//001_Fulltest_m20//dacParameters35_C4.dat
[17:17:11.729] <TB3> INFO: write dac parameters into /home/sensor/david/FullQualification/data/M1023_FullQualification_2016-10-19_14h52m_1476881545//001_Fulltest_m20//dacParameters35_C5.dat
[17:17:11.729] <TB3> INFO: write dac parameters into /home/sensor/david/FullQualification/data/M1023_FullQualification_2016-10-19_14h52m_1476881545//001_Fulltest_m20//dacParameters35_C6.dat
[17:17:11.729] <TB3> INFO: write dac parameters into /home/sensor/david/FullQualification/data/M1023_FullQualification_2016-10-19_14h52m_1476881545//001_Fulltest_m20//dacParameters35_C7.dat
[17:17:11.729] <TB3> INFO: write dac parameters into /home/sensor/david/FullQualification/data/M1023_FullQualification_2016-10-19_14h52m_1476881545//001_Fulltest_m20//dacParameters35_C8.dat
[17:17:11.730] <TB3> INFO: write dac parameters into /home/sensor/david/FullQualification/data/M1023_FullQualification_2016-10-19_14h52m_1476881545//001_Fulltest_m20//dacParameters35_C9.dat
[17:17:11.730] <TB3> INFO: write dac parameters into /home/sensor/david/FullQualification/data/M1023_FullQualification_2016-10-19_14h52m_1476881545//001_Fulltest_m20//dacParameters35_C10.dat
[17:17:11.730] <TB3> INFO: write dac parameters into /home/sensor/david/FullQualification/data/M1023_FullQualification_2016-10-19_14h52m_1476881545//001_Fulltest_m20//dacParameters35_C11.dat
[17:17:11.730] <TB3> INFO: write dac parameters into /home/sensor/david/FullQualification/data/M1023_FullQualification_2016-10-19_14h52m_1476881545//001_Fulltest_m20//dacParameters35_C12.dat
[17:17:11.730] <TB3> INFO: write dac parameters into /home/sensor/david/FullQualification/data/M1023_FullQualification_2016-10-19_14h52m_1476881545//001_Fulltest_m20//dacParameters35_C13.dat
[17:17:11.730] <TB3> INFO: write dac parameters into /home/sensor/david/FullQualification/data/M1023_FullQualification_2016-10-19_14h52m_1476881545//001_Fulltest_m20//dacParameters35_C14.dat
[17:17:11.731] <TB3> INFO: write dac parameters into /home/sensor/david/FullQualification/data/M1023_FullQualification_2016-10-19_14h52m_1476881545//001_Fulltest_m20//dacParameters35_C15.dat
[17:17:12.073] <TB3> INFO: Expecting 41600 events.
[17:17:15.377] <TB3> INFO: 41600 events read in total (2711ms).
[17:17:15.379] <TB3> INFO: Test took 3644ms.
[17:17:15.928] <TB3> INFO: Expecting 41600 events.
[17:17:19.114] <TB3> INFO: 41600 events read in total (2594ms).
[17:17:19.115] <TB3> INFO: Test took 3503ms.
[17:17:19.659] <TB3> INFO: Expecting 41600 events.
[17:17:22.969] <TB3> INFO: 41600 events read in total (2718ms).
[17:17:22.972] <TB3> INFO: Test took 3632ms.
[17:17:23.207] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[17:17:23.298] <TB3> INFO: Expecting 2560 events.
[17:17:24.194] <TB3> INFO: 2560 events read in total (299ms).
[17:17:24.194] <TB3> INFO: Test took 987ms.
[17:17:24.200] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[17:17:24.504] <TB3> INFO: Expecting 2560 events.
[17:17:25.396] <TB3> INFO: 2560 events read in total (299ms).
[17:17:25.397] <TB3> INFO: Test took 1197ms.
[17:17:25.401] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[17:17:25.707] <TB3> INFO: Expecting 2560 events.
[17:17:26.598] <TB3> INFO: 2560 events read in total (299ms).
[17:17:26.598] <TB3> INFO: Test took 1197ms.
[17:17:26.603] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[17:17:26.908] <TB3> INFO: Expecting 2560 events.
[17:17:27.803] <TB3> INFO: 2560 events read in total (303ms).
[17:17:27.803] <TB3> INFO: Test took 1200ms.
[17:17:27.808] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[17:17:28.113] <TB3> INFO: Expecting 2560 events.
[17:17:29.012] <TB3> INFO: 2560 events read in total (306ms).
[17:17:29.016] <TB3> INFO: Test took 1208ms.
[17:17:29.025] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[17:17:29.323] <TB3> INFO: Expecting 2560 events.
[17:17:30.215] <TB3> INFO: 2560 events read in total (300ms).
[17:17:30.216] <TB3> INFO: Test took 1191ms.
[17:17:30.223] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[17:17:30.525] <TB3> INFO: Expecting 2560 events.
[17:17:31.417] <TB3> INFO: 2560 events read in total (299ms).
[17:17:31.417] <TB3> INFO: Test took 1195ms.
[17:17:31.425] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[17:17:31.728] <TB3> INFO: Expecting 2560 events.
[17:17:32.620] <TB3> INFO: 2560 events read in total (299ms).
[17:17:32.621] <TB3> INFO: Test took 1196ms.
[17:17:32.625] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[17:17:32.932] <TB3> INFO: Expecting 2560 events.
[17:17:33.820] <TB3> INFO: 2560 events read in total (296ms).
[17:17:33.823] <TB3> INFO: Test took 1199ms.
[17:17:33.826] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[17:17:34.131] <TB3> INFO: Expecting 2560 events.
[17:17:35.019] <TB3> INFO: 2560 events read in total (296ms).
[17:17:35.020] <TB3> INFO: Test took 1194ms.
[17:17:35.023] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[17:17:35.330] <TB3> INFO: Expecting 2560 events.
[17:17:36.218] <TB3> INFO: 2560 events read in total (296ms).
[17:17:36.219] <TB3> INFO: Test took 1196ms.
[17:17:36.224] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[17:17:36.529] <TB3> INFO: Expecting 2560 events.
[17:17:37.417] <TB3> INFO: 2560 events read in total (295ms).
[17:17:37.417] <TB3> INFO: Test took 1193ms.
[17:17:37.424] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[17:17:37.728] <TB3> INFO: Expecting 2560 events.
[17:17:38.620] <TB3> INFO: 2560 events read in total (300ms).
[17:17:38.620] <TB3> INFO: Test took 1196ms.
[17:17:38.628] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[17:17:38.931] <TB3> INFO: Expecting 2560 events.
[17:17:39.822] <TB3> INFO: 2560 events read in total (298ms).
[17:17:39.823] <TB3> INFO: Test took 1196ms.
[17:17:39.830] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[17:17:40.133] <TB3> INFO: Expecting 2560 events.
[17:17:41.020] <TB3> INFO: 2560 events read in total (295ms).
[17:17:41.021] <TB3> INFO: Test took 1191ms.
[17:17:41.026] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[17:17:41.332] <TB3> INFO: Expecting 2560 events.
[17:17:42.219] <TB3> INFO: 2560 events read in total (296ms).
[17:17:42.220] <TB3> INFO: Test took 1194ms.
[17:17:42.226] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[17:17:42.530] <TB3> INFO: Expecting 2560 events.
[17:17:43.419] <TB3> INFO: 2560 events read in total (296ms).
[17:17:43.420] <TB3> INFO: Test took 1194ms.
[17:17:43.423] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[17:17:43.730] <TB3> INFO: Expecting 2560 events.
[17:17:44.616] <TB3> INFO: 2560 events read in total (294ms).
[17:17:44.617] <TB3> INFO: Test took 1194ms.
[17:17:44.623] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[17:17:44.927] <TB3> INFO: Expecting 2560 events.
[17:17:45.817] <TB3> INFO: 2560 events read in total (298ms).
[17:17:45.818] <TB3> INFO: Test took 1195ms.
[17:17:45.824] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[17:17:46.127] <TB3> INFO: Expecting 2560 events.
[17:17:47.014] <TB3> INFO: 2560 events read in total (295ms).
[17:17:47.014] <TB3> INFO: Test took 1190ms.
[17:17:47.021] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[17:17:47.327] <TB3> INFO: Expecting 2560 events.
[17:17:48.218] <TB3> INFO: 2560 events read in total (296ms).
[17:17:48.219] <TB3> INFO: Test took 1199ms.
[17:17:48.226] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[17:17:48.529] <TB3> INFO: Expecting 2560 events.
[17:17:49.421] <TB3> INFO: 2560 events read in total (299ms).
[17:17:49.421] <TB3> INFO: Test took 1196ms.
[17:17:49.425] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[17:17:49.735] <TB3> INFO: Expecting 2560 events.
[17:17:50.629] <TB3> INFO: 2560 events read in total (300ms).
[17:17:50.629] <TB3> INFO: Test took 1204ms.
[17:17:50.635] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[17:17:50.941] <TB3> INFO: Expecting 2560 events.
[17:17:51.830] <TB3> INFO: 2560 events read in total (297ms).
[17:17:51.831] <TB3> INFO: Test took 1196ms.
[17:17:51.836] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[17:17:52.142] <TB3> INFO: Expecting 2560 events.
[17:17:53.034] <TB3> INFO: 2560 events read in total (300ms).
[17:17:53.035] <TB3> INFO: Test took 1200ms.
[17:17:53.040] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[17:17:53.345] <TB3> INFO: Expecting 2560 events.
[17:17:54.237] <TB3> INFO: 2560 events read in total (299ms).
[17:17:54.237] <TB3> INFO: Test took 1198ms.
[17:17:54.241] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[17:17:54.548] <TB3> INFO: Expecting 2560 events.
[17:17:55.441] <TB3> INFO: 2560 events read in total (301ms).
[17:17:55.442] <TB3> INFO: Test took 1201ms.
[17:17:55.448] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[17:17:55.751] <TB3> INFO: Expecting 2560 events.
[17:17:56.644] <TB3> INFO: 2560 events read in total (301ms).
[17:17:56.645] <TB3> INFO: Test took 1198ms.
[17:17:56.652] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[17:17:56.955] <TB3> INFO: Expecting 2560 events.
[17:17:57.852] <TB3> INFO: 2560 events read in total (304ms).
[17:17:57.852] <TB3> INFO: Test took 1200ms.
[17:17:57.859] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[17:17:58.162] <TB3> INFO: Expecting 2560 events.
[17:17:59.058] <TB3> INFO: 2560 events read in total (303ms).
[17:17:59.059] <TB3> INFO: Test took 1200ms.
[17:17:59.066] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[17:17:59.371] <TB3> INFO: Expecting 2560 events.
[17:18:00.265] <TB3> INFO: 2560 events read in total (301ms).
[17:18:00.266] <TB3> INFO: Test took 1201ms.
[17:18:00.269] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[17:18:00.575] <TB3> INFO: Expecting 2560 events.
[17:18:01.466] <TB3> INFO: 2560 events read in total (297ms).
[17:18:01.467] <TB3> INFO: Test took 1198ms.
[17:18:02.008] <TB3> INFO: PixTestPhOptimization::doTest() done, duration: 705 seconds
[17:18:02.009] <TB3> INFO: PH scale (per ROC): 62 48 44 48 54 54 38 61 41 44 61 64 39 50 48 66
[17:18:02.009] <TB3> INFO: PH offset (per ROC): 115 107 100 95 122 132 103 113 84 95 139 131 104 104 113 119
[17:18:02.014] <TB3> INFO: Decoding statistics:
[17:18:02.014] <TB3> INFO: General information:
[17:18:02.014] <TB3> INFO: 16bit words read: 127892
[17:18:02.014] <TB3> INFO: valid events total: 20480
[17:18:02.014] <TB3> INFO: empty events: 17974
[17:18:02.014] <TB3> INFO: valid events with pixels: 2506
[17:18:02.014] <TB3> INFO: valid pixel hits: 2506
[17:18:02.014] <TB3> INFO: Event errors: 0
[17:18:02.014] <TB3> INFO: start marker: 0
[17:18:02.014] <TB3> INFO: stop marker: 0
[17:18:02.014] <TB3> INFO: overflow: 0
[17:18:02.014] <TB3> INFO: invalid 5bit words: 0
[17:18:02.014] <TB3> INFO: invalid XOR eye diagram: 0
[17:18:02.015] <TB3> INFO: frame (failed synchr.): 0
[17:18:02.015] <TB3> INFO: idle data (no TBM trl): 0
[17:18:02.015] <TB3> INFO: no data (only TBM hdr): 0
[17:18:02.015] <TB3> INFO: TBM errors: 0
[17:18:02.015] <TB3> INFO: flawed TBM headers: 0
[17:18:02.015] <TB3> INFO: flawed TBM trailers: 0
[17:18:02.015] <TB3> INFO: event ID mismatches: 0
[17:18:02.015] <TB3> INFO: ROC errors: 0
[17:18:02.015] <TB3> INFO: missing ROC header(s): 0
[17:18:02.015] <TB3> INFO: misplaced readback start: 0
[17:18:02.015] <TB3> INFO: Pixel decoding errors: 0
[17:18:02.015] <TB3> INFO: pixel data incomplete: 0
[17:18:02.015] <TB3> INFO: pixel address: 0
[17:18:02.015] <TB3> INFO: pulse height fill bit: 0
[17:18:02.015] <TB3> INFO: buffer corruption: 0
[17:18:02.272] <TB3> INFO: ######################################################################
[17:18:02.272] <TB3> INFO: PixTestGainPedestal::fullTest() ntrig = 10
[17:18:02.272] <TB3> INFO: ######################################################################
[17:18:02.288] <TB3> INFO: scanning low vcal = 10
[17:18:02.605] <TB3> INFO: Expecting 41600 events.
[17:18:06.257] <TB3> INFO: 41600 events read in total (3059ms).
[17:18:06.258] <TB3> INFO: Test took 3969ms.
[17:18:06.264] <TB3> INFO: scanning low vcal = 20
[17:18:06.580] <TB3> INFO: Expecting 41600 events.
[17:18:10.226] <TB3> INFO: 41600 events read in total (3053ms).
[17:18:10.226] <TB3> INFO: Test took 3962ms.
[17:18:10.232] <TB3> INFO: scanning low vcal = 30
[17:18:10.549] <TB3> INFO: Expecting 41600 events.
[17:18:14.271] <TB3> INFO: 41600 events read in total (3129ms).
[17:18:14.272] <TB3> INFO: Test took 4039ms.
[17:18:14.278] <TB3> INFO: scanning low vcal = 40
[17:18:14.601] <TB3> INFO: Expecting 41600 events.
[17:18:18.683] <TB3> INFO: 41600 events read in total (3486ms).
[17:18:18.684] <TB3> INFO: Test took 4399ms.
[17:18:18.691] <TB3> INFO: scanning low vcal = 50
[17:18:19.008] <TB3> INFO: Expecting 41600 events.
[17:18:23.174] <TB3> INFO: 41600 events read in total (3573ms).
[17:18:23.175] <TB3> INFO: Test took 4483ms.
[17:18:23.179] <TB3> INFO: scanning low vcal = 60
[17:18:23.494] <TB3> INFO: Expecting 41600 events.
[17:18:27.624] <TB3> INFO: 41600 events read in total (3537ms).
[17:18:27.625] <TB3> INFO: Test took 4446ms.
[17:18:27.631] <TB3> INFO: scanning low vcal = 70
[17:18:27.945] <TB3> INFO: Expecting 41600 events.
[17:18:32.080] <TB3> INFO: 41600 events read in total (3543ms).
[17:18:32.081] <TB3> INFO: Test took 4450ms.
[17:18:32.086] <TB3> INFO: scanning low vcal = 80
[17:18:32.398] <TB3> INFO: Expecting 41600 events.
[17:18:36.553] <TB3> INFO: 41600 events read in total (3562ms).
[17:18:36.554] <TB3> INFO: Test took 4468ms.
[17:18:36.561] <TB3> INFO: scanning low vcal = 90
[17:18:36.877] <TB3> INFO: Expecting 41600 events.
[17:18:41.007] <TB3> INFO: 41600 events read in total (3538ms).
[17:18:41.008] <TB3> INFO: Test took 4447ms.
[17:18:41.014] <TB3> INFO: scanning low vcal = 100
[17:18:41.329] <TB3> INFO: Expecting 41600 events.
[17:18:45.460] <TB3> INFO: 41600 events read in total (3539ms).
[17:18:45.461] <TB3> INFO: Test took 4447ms.
[17:18:45.466] <TB3> INFO: scanning low vcal = 110
[17:18:45.780] <TB3> INFO: Expecting 41600 events.
[17:18:49.920] <TB3> INFO: 41600 events read in total (3547ms).
[17:18:49.921] <TB3> INFO: Test took 4455ms.
[17:18:49.927] <TB3> INFO: scanning low vcal = 120
[17:18:50.249] <TB3> INFO: Expecting 41600 events.
[17:18:54.402] <TB3> INFO: 41600 events read in total (3561ms).
[17:18:54.402] <TB3> INFO: Test took 4468ms.
[17:18:54.407] <TB3> INFO: scanning low vcal = 130
[17:18:54.722] <TB3> INFO: Expecting 41600 events.
[17:18:58.831] <TB3> INFO: 41600 events read in total (3516ms).
[17:18:58.832] <TB3> INFO: Test took 4425ms.
[17:18:58.838] <TB3> INFO: scanning low vcal = 140
[17:18:59.153] <TB3> INFO: Expecting 41600 events.
[17:19:03.286] <TB3> INFO: 41600 events read in total (3540ms).
[17:19:03.287] <TB3> INFO: Test took 4449ms.
[17:19:03.292] <TB3> INFO: scanning low vcal = 150
[17:19:03.605] <TB3> INFO: Expecting 41600 events.
[17:19:07.773] <TB3> INFO: 41600 events read in total (3576ms).
[17:19:07.774] <TB3> INFO: Test took 4481ms.
[17:19:07.778] <TB3> INFO: scanning low vcal = 160
[17:19:08.092] <TB3> INFO: Expecting 41600 events.
[17:19:12.255] <TB3> INFO: 41600 events read in total (3570ms).
[17:19:12.256] <TB3> INFO: Test took 4478ms.
[17:19:12.260] <TB3> INFO: scanning low vcal = 170
[17:19:12.577] <TB3> INFO: Expecting 41600 events.
[17:19:16.703] <TB3> INFO: 41600 events read in total (3533ms).
[17:19:16.704] <TB3> INFO: Test took 4442ms.
[17:19:16.712] <TB3> INFO: scanning low vcal = 180
[17:19:17.030] <TB3> INFO: Expecting 41600 events.
[17:19:21.156] <TB3> INFO: 41600 events read in total (3533ms).
[17:19:21.157] <TB3> INFO: Test took 4441ms.
[17:19:21.161] <TB3> INFO: scanning low vcal = 190
[17:19:21.478] <TB3> INFO: Expecting 41600 events.
[17:19:25.644] <TB3> INFO: 41600 events read in total (3574ms).
[17:19:25.645] <TB3> INFO: Test took 4484ms.
[17:19:25.649] <TB3> INFO: scanning low vcal = 200
[17:19:25.966] <TB3> INFO: Expecting 41600 events.
[17:19:30.085] <TB3> INFO: 41600 events read in total (3526ms).
[17:19:30.087] <TB3> INFO: Test took 4437ms.
[17:19:30.093] <TB3> INFO: scanning low vcal = 210
[17:19:30.412] <TB3> INFO: Expecting 41600 events.
[17:19:34.541] <TB3> INFO: 41600 events read in total (3536ms).
[17:19:34.542] <TB3> INFO: Test took 4444ms.
[17:19:34.548] <TB3> INFO: scanning low vcal = 220
[17:19:34.861] <TB3> INFO: Expecting 41600 events.
[17:19:39.027] <TB3> INFO: 41600 events read in total (3573ms).
[17:19:39.029] <TB3> INFO: Test took 4481ms.
[17:19:39.034] <TB3> INFO: scanning low vcal = 230
[17:19:39.357] <TB3> INFO: Expecting 41600 events.
[17:19:43.512] <TB3> INFO: 41600 events read in total (3562ms).
[17:19:43.513] <TB3> INFO: Test took 4473ms.
[17:19:43.519] <TB3> INFO: scanning low vcal = 240
[17:19:43.834] <TB3> INFO: Expecting 41600 events.
[17:19:47.951] <TB3> INFO: 41600 events read in total (3525ms).
[17:19:47.952] <TB3> INFO: Test took 4432ms.
[17:19:47.956] <TB3> INFO: scanning low vcal = 250
[17:19:48.273] <TB3> INFO: Expecting 41600 events.
[17:19:52.406] <TB3> INFO: 41600 events read in total (3540ms).
[17:19:52.407] <TB3> INFO: Test took 4451ms.
[17:19:52.414] <TB3> INFO: scanning high vcal = 30 (= 210 in low range)
[17:19:52.728] <TB3> INFO: Expecting 41600 events.
[17:19:56.894] <TB3> INFO: 41600 events read in total (3573ms).
[17:19:56.895] <TB3> INFO: Test took 4481ms.
[17:19:56.899] <TB3> INFO: scanning high vcal = 50 (= 350 in low range)
[17:19:57.213] <TB3> INFO: Expecting 41600 events.
[17:20:01.346] <TB3> INFO: 41600 events read in total (3540ms).
[17:20:01.347] <TB3> INFO: Test took 4448ms.
[17:20:01.353] <TB3> INFO: scanning high vcal = 70 (= 490 in low range)
[17:20:01.667] <TB3> INFO: Expecting 41600 events.
[17:20:05.786] <TB3> INFO: 41600 events read in total (3527ms).
[17:20:05.787] <TB3> INFO: Test took 4434ms.
[17:20:05.794] <TB3> INFO: scanning high vcal = 90 (= 630 in low range)
[17:20:06.116] <TB3> INFO: Expecting 41600 events.
[17:20:10.255] <TB3> INFO: 41600 events read in total (3546ms).
[17:20:10.255] <TB3> INFO: Test took 4455ms.
[17:20:10.259] <TB3> INFO: scanning high vcal = 200 (= 1400 in low range)
[17:20:10.577] <TB3> INFO: Expecting 41600 events.
[17:20:14.734] <TB3> INFO: 41600 events read in total (3564ms).
[17:20:14.735] <TB3> INFO: Test took 4476ms.
[17:20:15.419] <TB3> INFO: PixTestGainPedestal::measure() done
[17:21:11.119] <TB3> INFO: PixTestGainPedestal::fit() done
[17:21:11.119] <TB3> INFO: non-linearity mean: 0.965 0.944 0.945 0.914 0.963 0.980 0.934 0.985 1.018 0.921 0.982 0.982 0.925 0.945 0.924 0.986
[17:21:11.119] <TB3> INFO: non-linearity RMS: 0.018 0.045 0.111 0.149 0.019 0.004 0.089 0.003 0.160 0.130 0.004 0.005 0.120 0.071 0.096 0.003
[17:21:11.119] <TB3> INFO: write gain/ped parameters into /home/sensor/david/FullQualification/data/M1023_FullQualification_2016-10-19_14h52m_1476881545//001_Fulltest_m20//phCalibrationFitErr35_C0.dat
[17:21:11.146] <TB3> INFO: write gain/ped parameters into /home/sensor/david/FullQualification/data/M1023_FullQualification_2016-10-19_14h52m_1476881545//001_Fulltest_m20//phCalibrationFitErr35_C1.dat
[17:21:11.171] <TB3> INFO: write gain/ped parameters into /home/sensor/david/FullQualification/data/M1023_FullQualification_2016-10-19_14h52m_1476881545//001_Fulltest_m20//phCalibrationFitErr35_C2.dat
[17:21:11.196] <TB3> INFO: write gain/ped parameters into /home/sensor/david/FullQualification/data/M1023_FullQualification_2016-10-19_14h52m_1476881545//001_Fulltest_m20//phCalibrationFitErr35_C3.dat
[17:21:11.221] <TB3> INFO: write gain/ped parameters into /home/sensor/david/FullQualification/data/M1023_FullQualification_2016-10-19_14h52m_1476881545//001_Fulltest_m20//phCalibrationFitErr35_C4.dat
[17:21:11.247] <TB3> INFO: write gain/ped parameters into /home/sensor/david/FullQualification/data/M1023_FullQualification_2016-10-19_14h52m_1476881545//001_Fulltest_m20//phCalibrationFitErr35_C5.dat
[17:21:11.271] <TB3> INFO: write gain/ped parameters into /home/sensor/david/FullQualification/data/M1023_FullQualification_2016-10-19_14h52m_1476881545//001_Fulltest_m20//phCalibrationFitErr35_C6.dat
[17:21:11.296] <TB3> INFO: write gain/ped parameters into /home/sensor/david/FullQualification/data/M1023_FullQualification_2016-10-19_14h52m_1476881545//001_Fulltest_m20//phCalibrationFitErr35_C7.dat
[17:21:11.321] <TB3> INFO: write gain/ped parameters into /home/sensor/david/FullQualification/data/M1023_FullQualification_2016-10-19_14h52m_1476881545//001_Fulltest_m20//phCalibrationFitErr35_C8.dat
[17:21:11.346] <TB3> INFO: write gain/ped parameters into /home/sensor/david/FullQualification/data/M1023_FullQualification_2016-10-19_14h52m_1476881545//001_Fulltest_m20//phCalibrationFitErr35_C9.dat
[17:21:11.371] <TB3> INFO: write gain/ped parameters into /home/sensor/david/FullQualification/data/M1023_FullQualification_2016-10-19_14h52m_1476881545//001_Fulltest_m20//phCalibrationFitErr35_C10.dat
[17:21:11.396] <TB3> INFO: write gain/ped parameters into /home/sensor/david/FullQualification/data/M1023_FullQualification_2016-10-19_14h52m_1476881545//001_Fulltest_m20//phCalibrationFitErr35_C11.dat
[17:21:11.420] <TB3> INFO: write gain/ped parameters into /home/sensor/david/FullQualification/data/M1023_FullQualification_2016-10-19_14h52m_1476881545//001_Fulltest_m20//phCalibrationFitErr35_C12.dat
[17:21:11.446] <TB3> INFO: write gain/ped parameters into /home/sensor/david/FullQualification/data/M1023_FullQualification_2016-10-19_14h52m_1476881545//001_Fulltest_m20//phCalibrationFitErr35_C13.dat
[17:21:11.471] <TB3> INFO: write gain/ped parameters into /home/sensor/david/FullQualification/data/M1023_FullQualification_2016-10-19_14h52m_1476881545//001_Fulltest_m20//phCalibrationFitErr35_C14.dat
[17:21:11.496] <TB3> INFO: write gain/ped parameters into /home/sensor/david/FullQualification/data/M1023_FullQualification_2016-10-19_14h52m_1476881545//001_Fulltest_m20//phCalibrationFitErr35_C15.dat
[17:21:11.520] <TB3> INFO: PixTestGainPedestal::fullTest() done, duration: 189 seconds
[17:21:11.520] <TB3> INFO: Decoding statistics:
[17:21:11.520] <TB3> INFO: General information:
[17:21:11.520] <TB3> INFO: 16bit words read: 3327876
[17:21:11.520] <TB3> INFO: valid events total: 332800
[17:21:11.520] <TB3> INFO: empty events: 0
[17:21:11.520] <TB3> INFO: valid events with pixels: 332800
[17:21:11.520] <TB3> INFO: valid pixel hits: 665538
[17:21:11.520] <TB3> INFO: Event errors: 0
[17:21:11.520] <TB3> INFO: start marker: 0
[17:21:11.520] <TB3> INFO: stop marker: 0
[17:21:11.520] <TB3> INFO: overflow: 0
[17:21:11.520] <TB3> INFO: invalid 5bit words: 0
[17:21:11.520] <TB3> INFO: invalid XOR eye diagram: 0
[17:21:11.520] <TB3> INFO: frame (failed synchr.): 0
[17:21:11.520] <TB3> INFO: idle data (no TBM trl): 0
[17:21:11.520] <TB3> INFO: no data (only TBM hdr): 0
[17:21:11.520] <TB3> INFO: TBM errors: 0
[17:21:11.520] <TB3> INFO: flawed TBM headers: 0
[17:21:11.521] <TB3> INFO: flawed TBM trailers: 0
[17:21:11.521] <TB3> INFO: event ID mismatches: 0
[17:21:11.521] <TB3> INFO: ROC errors: 0
[17:21:11.521] <TB3> INFO: missing ROC header(s): 0
[17:21:11.521] <TB3> INFO: misplaced readback start: 0
[17:21:11.521] <TB3> INFO: Pixel decoding errors: 0
[17:21:11.521] <TB3> INFO: pixel data incomplete: 0
[17:21:11.521] <TB3> INFO: pixel address: 0
[17:21:11.521] <TB3> INFO: pulse height fill bit: 0
[17:21:11.521] <TB3> INFO: buffer corruption: 0
[17:21:11.546] <TB3> INFO: Decoding statistics:
[17:21:11.547] <TB3> INFO: General information:
[17:21:11.547] <TB3> INFO: 16bit words read: 3457304
[17:21:11.547] <TB3> INFO: valid events total: 353536
[17:21:11.547] <TB3> INFO: empty events: 18230
[17:21:11.547] <TB3> INFO: valid events with pixels: 335306
[17:21:11.547] <TB3> INFO: valid pixel hits: 668044
[17:21:11.547] <TB3> INFO: Event errors: 0
[17:21:11.547] <TB3> INFO: start marker: 0
[17:21:11.547] <TB3> INFO: stop marker: 0
[17:21:11.547] <TB3> INFO: overflow: 0
[17:21:11.547] <TB3> INFO: invalid 5bit words: 0
[17:21:11.547] <TB3> INFO: invalid XOR eye diagram: 0
[17:21:11.547] <TB3> INFO: frame (failed synchr.): 0
[17:21:11.547] <TB3> INFO: idle data (no TBM trl): 0
[17:21:11.547] <TB3> INFO: no data (only TBM hdr): 0
[17:21:11.547] <TB3> INFO: TBM errors: 0
[17:21:11.547] <TB3> INFO: flawed TBM headers: 0
[17:21:11.547] <TB3> INFO: flawed TBM trailers: 0
[17:21:11.547] <TB3> INFO: event ID mismatches: 0
[17:21:11.547] <TB3> INFO: ROC errors: 0
[17:21:11.547] <TB3> INFO: missing ROC header(s): 0
[17:21:11.547] <TB3> INFO: misplaced readback start: 0
[17:21:11.547] <TB3> INFO: Pixel decoding errors: 0
[17:21:11.547] <TB3> INFO: pixel data incomplete: 0
[17:21:11.547] <TB3> INFO: pixel address: 0
[17:21:11.547] <TB3> INFO: pulse height fill bit: 0
[17:21:11.548] <TB3> INFO: buffer corruption: 0
[17:21:11.548] <TB3> INFO: enter test to run
[17:21:11.548] <TB3> INFO: test: exit no parameter change
[17:21:12.014] <TB3> QUIET: Connection to board 170 closed.
[17:21:12.018] <TB3> INFO: pXar: this is the end, my friend