[15:20:36.810] <TB3> INFO: *** Welcome to pxar ***
[15:20:36.810] <TB3> INFO: *** Today: 2016/08/15
[15:20:36.850] <TB3> INFO: *** Version: f5d3-dirty
[15:20:36.850] <TB3> INFO: readRocDacs: /home/Reception/DATA/DESYUHH/M4043_Reception_2016-08-15_17h16m_1471274195//000_ReceptionTestBB2_p17//dacParameters_C0.dat .. /home/Reception/DATA/DESYUHH/M4043_Reception_2016-08-15_17h16m_1471274195//000_ReceptionTestBB2_p17//dacParameters_C15.dat
[15:20:36.867] <TB3> INFO: readTbmDacs: /home/Reception/DATA/DESYUHH/M4043_Reception_2016-08-15_17h16m_1471274195//000_ReceptionTestBB2_p17//tbmParameters_C0a.dat .. /home/Reception/DATA/DESYUHH/M4043_Reception_2016-08-15_17h16m_1471274195//000_ReceptionTestBB2_p17//tbmParameters_C0b.dat
[15:20:36.869] <TB3> INFO: readMaskFile: /home/Reception/DATA/DESYUHH/M4043_Reception_2016-08-15_17h16m_1471274195//000_ReceptionTestBB2_p17//defaultMaskFile.dat
[15:20:36.870] <TB3> INFO: readTrimFile: /home/Reception/DATA/DESYUHH/M4043_Reception_2016-08-15_17h16m_1471274195//000_ReceptionTestBB2_p17//trimParameters_C0.dat .. /home/Reception/DATA/DESYUHH/M4043_Reception_2016-08-15_17h16m_1471274195//000_ReceptionTestBB2_p17//trimParameters_C15.dat
[15:20:36.999] <TB3> INFO: clk: 4
[15:20:36.999] <TB3> INFO: ctr: 4
[15:20:36.999] <TB3> INFO: sda: 19
[15:20:36.999] <TB3> INFO: tin: 9
[15:20:36.999] <TB3> INFO: level: 15
[15:20:36.999] <TB3> INFO: triggerdelay: 0
[15:20:36.999] <TB3> QUIET: Instanciating API for pxar v2.7.5+40~g4fce89b
[15:20:36.999] <TB3> INFO: Log level: INFO
[15:20:37.007] <TB3> INFO: Found DTB DTB_WWVASW
[15:20:37.016] <TB3> QUIET: Connection to board DTB_WWVASW opened.
[15:20:37.018] <TB3> INFO: DTB startup information
--- DTB info------------------------------------------
Board id: 126
HW version: DTB1.2
FW version: 4.4
SW version: 4.6
Options:
USB id: DTB_WWVASW
MAC address: 40D85511807E
Hostname: pixelDTB126
Comment:
------------------------------------------------------
[15:20:37.020] <TB3> INFO: RPC call hashes of host and DTB match: 484264910
[15:20:38.552] <TB3> INFO: DUT info:
[15:20:38.553] <TB3> INFO: The DUT currently contains the following objects:
[15:20:38.553] <TB3> INFO: 2 TBM Cores tbm08c (2 ON)
[15:20:38.553] <TB3> INFO: TBM Core alpha (0): 7 registers set
[15:20:38.553] <TB3> INFO: TBM Core beta (1): 7 registers set
[15:20:38.553] <TB3> INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[15:20:38.553] <TB3> INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[15:20:38.553] <TB3> INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[15:20:38.553] <TB3> INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[15:20:38.553] <TB3> INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[15:20:38.553] <TB3> INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[15:20:38.553] <TB3> INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[15:20:38.553] <TB3> INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[15:20:38.553] <TB3> INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[15:20:38.553] <TB3> INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[15:20:38.553] <TB3> INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[15:20:38.553] <TB3> INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[15:20:38.553] <TB3> INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[15:20:38.553] <TB3> INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[15:20:38.553] <TB3> INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[15:20:38.553] <TB3> INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[15:20:38.553] <TB3> INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[15:20:38.956] <TB3> INFO: enter 'restricted' command line mode
[15:20:38.956] <TB3> INFO: enter test to run
[15:20:38.957] <TB3> INFO: test: pretest no parameter change
[15:20:38.957] <TB3> INFO: running: pretest
[15:20:38.965] <TB3> INFO: ----------------------------------------------------------------------
[15:20:38.965] <TB3> INFO: PixTestPretest::programROC()
[15:20:38.965] <TB3> INFO: ----------------------------------------------------------------------
[15:20:56.983] <TB3> INFO: PixTestPretest::programROC() done: ROCs are all programmable
[15:20:56.984] <TB3> INFO: IA differences per ROC: 18.5 19.3 20.9 20.1 20.1 19.3 20.1 19.3 19.3 20.1 20.1 19.3 19.3 21.7 20.9 19.3
[15:20:57.107] <TB3> INFO: enter test to run
[15:20:57.107] <TB3> INFO: test: pretest no parameter change
[15:20:57.107] <TB3> INFO: running: pretest
[15:20:57.109] <TB3> INFO: ----------------------------------------------------------------------
[15:20:57.109] <TB3> INFO: PixTestPretest::setVana() target Ia = 24 mA/ROC
[15:20:57.109] <TB3> INFO: ----------------------------------------------------------------------
[15:21:04.426] <TB3> INFO: PixTestPretest::setVana() done, Module Ia 385.9 mA = 24.1188 mA/ROC
[15:21:04.427] <TB3> INFO: i(loss) [mA/ROC]: 18.5 19.3 19.3 18.5 19.3 19.3 19.3 18.5 19.3 18.5 18.5 18.5 19.3 18.5 18.5 19.3
[15:21:04.471] <TB3> INFO: enter test to run
[15:21:04.471] <TB3> INFO: test: pretest no parameter change
[15:21:04.471] <TB3> INFO: running: pretest
[15:21:04.471] <TB3> INFO: ----------------------------------------------------------------------
[15:21:04.471] <TB3> INFO: PixTestPretest::findTiming()
[15:21:04.471] <TB3> INFO: ----------------------------------------------------------------------
[15:21:04.471] <TB3> INFO: PixTestCmd::init()
[15:21:05.270] <TB3> WARNING: Not unmasking DUT, not setting Calibrate bits!
[15:22:04.618] <TB3> INFO: TBM phases: 160MHz: 7, 400MHz: 6, TBM delays: ROC(0/1):4, header/trailer: 1, token: 1
[15:22:04.618] <TB3> INFO: (success/tries = 100/100), width = 4
[15:22:04.619] <TB3> INFO: enter test to run
[15:22:04.619] <TB3> INFO: test: pretest no parameter change
[15:22:04.619] <TB3> INFO: running: pretest
[15:22:04.621] <TB3> INFO: ----------------------------------------------------------------------
[15:22:04.621] <TB3> INFO: PixTestPretest::findWorkingPixel()
[15:22:04.621] <TB3> INFO: ----------------------------------------------------------------------
[15:22:04.715] <TB3> INFO: Expecting 231680 events.
[15:22:10.390] <TB3> WARNING: Detected DESER400 trailer error bits: "CODE ERROR"
[15:22:13.841] <TB3> INFO: 231680 events read in total (8569ms).
[15:22:13.846] <TB3> INFO: Test took 9220ms.
[15:22:14.150] <TB3> INFO: Found working pixel in all ROCs: col/row = 12/22
[15:22:14.235] <TB3> INFO: enter test to run
[15:22:14.235] <TB3> INFO: test: pretest no parameter change
[15:22:14.235] <TB3> INFO: running: pretest
[15:22:14.236] <TB3> INFO: ----------------------------------------------------------------------
[15:22:14.236] <TB3> INFO: PixTestPretest::setVthrCompCalDel()
[15:22:14.236] <TB3> INFO: ----------------------------------------------------------------------
[15:22:14.331] <TB3> INFO: Expecting 231680 events.
[15:22:23.507] <TB3> INFO: 231680 events read in total (8620ms).
[15:22:23.512] <TB3> INFO: Test took 9271ms.
[15:22:23.838] <TB3> INFO: PixTestPretest::setVthrCompCalDel() done
[15:22:23.838] <TB3> INFO: CalDel: 105 116 110 121 128 93 119 121 123 131 134 121 120 103 109 103
[15:22:23.838] <TB3> INFO: VthrComp: 51 51 51 51 51 51 51 51 51 51 51 51 51 52 52 51
[15:22:23.873] <TB3> INFO: enter test to run
[15:22:23.873] <TB3> INFO: test: pretest no parameter change
[15:22:23.873] <TB3> INFO: running: pretest
[15:22:23.878] <TB3> INFO: write dac parameters into /home/Reception/DATA/DESYUHH/M4043_Reception_2016-08-15_17h16m_1471274195//000_ReceptionTestBB2_p17//dacParameters_C0.dat
[15:22:23.883] <TB3> INFO: write dac parameters into /home/Reception/DATA/DESYUHH/M4043_Reception_2016-08-15_17h16m_1471274195//000_ReceptionTestBB2_p17//dacParameters_C1.dat
[15:22:23.889] <TB3> INFO: write dac parameters into /home/Reception/DATA/DESYUHH/M4043_Reception_2016-08-15_17h16m_1471274195//000_ReceptionTestBB2_p17//dacParameters_C2.dat
[15:22:23.896] <TB3> INFO: write dac parameters into /home/Reception/DATA/DESYUHH/M4043_Reception_2016-08-15_17h16m_1471274195//000_ReceptionTestBB2_p17//dacParameters_C3.dat
[15:22:23.901] <TB3> INFO: write dac parameters into /home/Reception/DATA/DESYUHH/M4043_Reception_2016-08-15_17h16m_1471274195//000_ReceptionTestBB2_p17//dacParameters_C4.dat
[15:22:23.907] <TB3> INFO: write dac parameters into /home/Reception/DATA/DESYUHH/M4043_Reception_2016-08-15_17h16m_1471274195//000_ReceptionTestBB2_p17//dacParameters_C5.dat
[15:22:23.912] <TB3> INFO: write dac parameters into /home/Reception/DATA/DESYUHH/M4043_Reception_2016-08-15_17h16m_1471274195//000_ReceptionTestBB2_p17//dacParameters_C6.dat
[15:22:23.917] <TB3> INFO: write dac parameters into /home/Reception/DATA/DESYUHH/M4043_Reception_2016-08-15_17h16m_1471274195//000_ReceptionTestBB2_p17//dacParameters_C7.dat
[15:22:23.922] <TB3> INFO: write dac parameters into /home/Reception/DATA/DESYUHH/M4043_Reception_2016-08-15_17h16m_1471274195//000_ReceptionTestBB2_p17//dacParameters_C8.dat
[15:22:23.929] <TB3> INFO: write dac parameters into /home/Reception/DATA/DESYUHH/M4043_Reception_2016-08-15_17h16m_1471274195//000_ReceptionTestBB2_p17//dacParameters_C9.dat
[15:22:23.934] <TB3> INFO: write dac parameters into /home/Reception/DATA/DESYUHH/M4043_Reception_2016-08-15_17h16m_1471274195//000_ReceptionTestBB2_p17//dacParameters_C10.dat
[15:22:23.940] <TB3> INFO: write dac parameters into /home/Reception/DATA/DESYUHH/M4043_Reception_2016-08-15_17h16m_1471274195//000_ReceptionTestBB2_p17//dacParameters_C11.dat
[15:22:23.945] <TB3> INFO: write dac parameters into /home/Reception/DATA/DESYUHH/M4043_Reception_2016-08-15_17h16m_1471274195//000_ReceptionTestBB2_p17//dacParameters_C12.dat
[15:22:23.950] <TB3> INFO: write dac parameters into /home/Reception/DATA/DESYUHH/M4043_Reception_2016-08-15_17h16m_1471274195//000_ReceptionTestBB2_p17//dacParameters_C13.dat
[15:22:23.955] <TB3> INFO: write dac parameters into /home/Reception/DATA/DESYUHH/M4043_Reception_2016-08-15_17h16m_1471274195//000_ReceptionTestBB2_p17//dacParameters_C14.dat
[15:22:23.961] <TB3> INFO: write dac parameters into /home/Reception/DATA/DESYUHH/M4043_Reception_2016-08-15_17h16m_1471274195//000_ReceptionTestBB2_p17//dacParameters_C15.dat
[15:22:23.963] <TB3> INFO: enter test to run
[15:22:23.963] <TB3> INFO: test: PixelAlive no parameter change
[15:22:23.963] <TB3> INFO: running: pixelalive
[15:22:23.965] <TB3> INFO: ######################################################################
[15:22:23.965] <TB3> INFO: PixTestAlive::doTest()
[15:22:23.965] <TB3> INFO: ######################################################################
[15:22:23.966] <TB3> INFO: ----------------------------------------------------------------------
[15:22:23.966] <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:22:23.966] <TB3> INFO: ----------------------------------------------------------------------
[15:22:24.256] <TB3> INFO: Expecting 41600 events.
[15:22:27.695] <TB3> INFO: 41600 events read in total (2883ms).
[15:22:27.696] <TB3> INFO: Test took 3728ms.
[15:22:27.998] <TB3> INFO: PixTestAlive::aliveTest() done
[15:22:27.998] <TB3> INFO: number of dead pixels (per ROC): 0 0 1 0 0 0 0 0 1 0 1 1 0 1 0 0
[15:22:27.999] <TB3> INFO: ----------------------------------------------------------------------
[15:22:27.999] <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:22:27.999] <TB3> INFO: ----------------------------------------------------------------------
[15:22:28.265] <TB3> INFO: Expecting 41600 events.
[15:22:30.874] <TB3> INFO: 41600 events read in total (2054ms).
[15:22:30.874] <TB3> INFO: Test took 2872ms.
[15:22:30.874] <TB3> INFO: mask vs. old pixelAlive PixelAlive_C0_V0 .. PixelAlive_C15_V0
[15:22:31.198] <TB3> INFO: PixTestAlive::maskTest() done
[15:22:31.198] <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:22:31.199] <TB3> INFO: ----------------------------------------------------------------------
[15:22:31.199] <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:22:31.199] <TB3> INFO: ----------------------------------------------------------------------
[15:22:31.470] <TB3> INFO: Expecting 41600 events.
[15:22:34.898] <TB3> INFO: 41600 events read in total (2872ms).
[15:22:34.899] <TB3> INFO: Test took 3697ms.
[15:22:35.210] <TB3> INFO: PixTestAlive::addressDecodingTest() done
[15:22:35.210] <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:22:35.210] <TB3> INFO: PixTestAlive::doTest() done, duration: 11 seconds
[15:22:35.217] <TB3> INFO: enter test to run
[15:22:35.217] <TB3> INFO: test: BB no parameter change
[15:22:35.217] <TB3> INFO: running: bb
[15:22:35.219] <TB3> INFO: ######################################################################
[15:22:35.219] <TB3> INFO: PixTestBBMap::doTest() Ntrig = 5, VcalS = 250 (high range)
[15:22:35.219] <TB3> INFO: ######################################################################
[15:22:35.223] <TB3> INFO: ---> dac: VthrComp name: calSMap ntrig: 5 dacrange: 0 .. 149 (-1/-1) hits flags = 514 (plus default)
[15:22:35.239] <TB3> INFO: dacScan split into 1 runs with ntrig = 5
[15:22:35.239] <TB3> INFO: run 1 of 1
[15:22:35.525] <TB3> INFO: Expecting 3120000 events.
[15:23:20.199] <TB3> INFO: 1172325 events read in total (44118ms).
[15:24:04.534] <TB3> INFO: 2324330 events read in total (88453ms).
[15:24:34.637] <TB3> INFO: 3120000 events read in total (118557ms).
[15:24:34.691] <TB3> INFO: Test took 119452ms.
[15:24:57.273] <TB3> INFO: PixTestBBMap::doTest() done, duration: 142 seconds
[15:24:57.273] <TB3> INFO: number of dead bumps (per ROC): 0 0 0 0 0 2 0 0 1 0 0 2 0 0 0 0
[15:24:57.273] <TB3> INFO: separation cut (per ROC): 119 118 117 118 117 126 109 123 106 111 105 106 112 129 127 131
[15:24:57.346] <TB3> INFO: enter test to run
[15:24:57.346] <TB3> INFO: test: Readback no parameter change
[15:24:57.346] <TB3> INFO: running: readback
[15:24:57.346] <TB3> INFO: readReadbackCal: /home/Reception/DATA/DESYUHH/M4043_Reception_2016-08-15_17h16m_1471274195//000_ReceptionTestBB2_p17//readbackCal_C0.dat .. /home/Reception/DATA/DESYUHH/M4043_Reception_2016-08-15_17h16m_1471274195//000_ReceptionTestBB2_p17//readbackCal_C15.dat
[15:24:57.364] <TB3> INFO: readGainPedestalParameters /home/Reception/DATA/DESYUHH/M4043_Reception_2016-08-15_17h16m_1471274195//000_ReceptionTestBB2_p17//phCalibrationFitErr_C0.dat .. /home/Reception/DATA/DESYUHH/M4043_Reception_2016-08-15_17h16m_1471274195//000_ReceptionTestBB2_p17//phCalibrationFitErr_C15.dat
[15:24:57.364] <TB3> ERROR: <ConfigParameters.cc/readGainPedestalParameters:L996> cannot open /home/Reception/DATA/DESYUHH/M4043_Reception_2016-08-15_17h16m_1471274195//000_ReceptionTestBB2_p17//phCalibrationFitErr_C0.dat for reading PH calibration constants
[15:24:57.364] <TB3> INFO: ######################################################################
[15:24:57.364] <TB3> INFO: PixTestReadback::doTest()
[15:24:57.364] <TB3> INFO: ######################################################################
[15:24:57.364] <TB3> INFO: ----------------------------------------------------------------------
[15:24:57.364] <TB3> INFO: PixTestReadback::CalibrateVd()
[15:24:57.364] <TB3> INFO: ----------------------------------------------------------------------
[15:25:06.705] <TB3> INFO: write readback calibration parameters into /home/Reception/DATA/DESYUHH/M4043_Reception_2016-08-15_17h16m_1471274195//000_ReceptionTestBB2_p17//readbackCal_C0.dat
[15:25:06.714] <TB3> INFO: write readback calibration parameters into /home/Reception/DATA/DESYUHH/M4043_Reception_2016-08-15_17h16m_1471274195//000_ReceptionTestBB2_p17//readbackCal_C1.dat
[15:25:06.720] <TB3> INFO: write readback calibration parameters into /home/Reception/DATA/DESYUHH/M4043_Reception_2016-08-15_17h16m_1471274195//000_ReceptionTestBB2_p17//readbackCal_C2.dat
[15:25:06.726] <TB3> INFO: write readback calibration parameters into /home/Reception/DATA/DESYUHH/M4043_Reception_2016-08-15_17h16m_1471274195//000_ReceptionTestBB2_p17//readbackCal_C3.dat
[15:25:06.731] <TB3> INFO: write readback calibration parameters into /home/Reception/DATA/DESYUHH/M4043_Reception_2016-08-15_17h16m_1471274195//000_ReceptionTestBB2_p17//readbackCal_C4.dat
[15:25:06.736] <TB3> INFO: write readback calibration parameters into /home/Reception/DATA/DESYUHH/M4043_Reception_2016-08-15_17h16m_1471274195//000_ReceptionTestBB2_p17//readbackCal_C5.dat
[15:25:06.741] <TB3> INFO: write readback calibration parameters into /home/Reception/DATA/DESYUHH/M4043_Reception_2016-08-15_17h16m_1471274195//000_ReceptionTestBB2_p17//readbackCal_C6.dat
[15:25:06.746] <TB3> INFO: write readback calibration parameters into /home/Reception/DATA/DESYUHH/M4043_Reception_2016-08-15_17h16m_1471274195//000_ReceptionTestBB2_p17//readbackCal_C7.dat
[15:25:06.751] <TB3> INFO: write readback calibration parameters into /home/Reception/DATA/DESYUHH/M4043_Reception_2016-08-15_17h16m_1471274195//000_ReceptionTestBB2_p17//readbackCal_C8.dat
[15:25:06.757] <TB3> INFO: write readback calibration parameters into /home/Reception/DATA/DESYUHH/M4043_Reception_2016-08-15_17h16m_1471274195//000_ReceptionTestBB2_p17//readbackCal_C9.dat
[15:25:06.763] <TB3> INFO: write readback calibration parameters into /home/Reception/DATA/DESYUHH/M4043_Reception_2016-08-15_17h16m_1471274195//000_ReceptionTestBB2_p17//readbackCal_C10.dat
[15:25:06.768] <TB3> INFO: write readback calibration parameters into /home/Reception/DATA/DESYUHH/M4043_Reception_2016-08-15_17h16m_1471274195//000_ReceptionTestBB2_p17//readbackCal_C11.dat
[15:25:06.773] <TB3> INFO: write readback calibration parameters into /home/Reception/DATA/DESYUHH/M4043_Reception_2016-08-15_17h16m_1471274195//000_ReceptionTestBB2_p17//readbackCal_C12.dat
[15:25:06.778] <TB3> INFO: write readback calibration parameters into /home/Reception/DATA/DESYUHH/M4043_Reception_2016-08-15_17h16m_1471274195//000_ReceptionTestBB2_p17//readbackCal_C13.dat
[15:25:06.783] <TB3> INFO: write readback calibration parameters into /home/Reception/DATA/DESYUHH/M4043_Reception_2016-08-15_17h16m_1471274195//000_ReceptionTestBB2_p17//readbackCal_C14.dat
[15:25:06.791] <TB3> INFO: write readback calibration parameters into /home/Reception/DATA/DESYUHH/M4043_Reception_2016-08-15_17h16m_1471274195//000_ReceptionTestBB2_p17//readbackCal_C15.dat
[15:25:06.841] <TB3> INFO: PixTestPattern:: pg_setup set to default.
[15:25:06.841] <TB3> INFO: ----------------------------------------------------------------------
[15:25:06.841] <TB3> INFO: PixTestReadback::CalibrateVa()
[15:25:06.841] <TB3> INFO: ----------------------------------------------------------------------
[15:25:16.192] <TB3> INFO: write readback calibration parameters into /home/Reception/DATA/DESYUHH/M4043_Reception_2016-08-15_17h16m_1471274195//000_ReceptionTestBB2_p17//readbackCal_C0.dat
[15:25:16.199] <TB3> INFO: write readback calibration parameters into /home/Reception/DATA/DESYUHH/M4043_Reception_2016-08-15_17h16m_1471274195//000_ReceptionTestBB2_p17//readbackCal_C1.dat
[15:25:16.205] <TB3> INFO: write readback calibration parameters into /home/Reception/DATA/DESYUHH/M4043_Reception_2016-08-15_17h16m_1471274195//000_ReceptionTestBB2_p17//readbackCal_C2.dat
[15:25:16.210] <TB3> INFO: write readback calibration parameters into /home/Reception/DATA/DESYUHH/M4043_Reception_2016-08-15_17h16m_1471274195//000_ReceptionTestBB2_p17//readbackCal_C3.dat
[15:25:16.218] <TB3> INFO: write readback calibration parameters into /home/Reception/DATA/DESYUHH/M4043_Reception_2016-08-15_17h16m_1471274195//000_ReceptionTestBB2_p17//readbackCal_C4.dat
[15:25:16.223] <TB3> INFO: write readback calibration parameters into /home/Reception/DATA/DESYUHH/M4043_Reception_2016-08-15_17h16m_1471274195//000_ReceptionTestBB2_p17//readbackCal_C5.dat
[15:25:16.230] <TB3> INFO: write readback calibration parameters into /home/Reception/DATA/DESYUHH/M4043_Reception_2016-08-15_17h16m_1471274195//000_ReceptionTestBB2_p17//readbackCal_C6.dat
[15:25:16.236] <TB3> INFO: write readback calibration parameters into /home/Reception/DATA/DESYUHH/M4043_Reception_2016-08-15_17h16m_1471274195//000_ReceptionTestBB2_p17//readbackCal_C7.dat
[15:25:16.241] <TB3> INFO: write readback calibration parameters into /home/Reception/DATA/DESYUHH/M4043_Reception_2016-08-15_17h16m_1471274195//000_ReceptionTestBB2_p17//readbackCal_C8.dat
[15:25:16.247] <TB3> INFO: write readback calibration parameters into /home/Reception/DATA/DESYUHH/M4043_Reception_2016-08-15_17h16m_1471274195//000_ReceptionTestBB2_p17//readbackCal_C9.dat
[15:25:16.254] <TB3> INFO: write readback calibration parameters into /home/Reception/DATA/DESYUHH/M4043_Reception_2016-08-15_17h16m_1471274195//000_ReceptionTestBB2_p17//readbackCal_C10.dat
[15:25:16.261] <TB3> INFO: write readback calibration parameters into /home/Reception/DATA/DESYUHH/M4043_Reception_2016-08-15_17h16m_1471274195//000_ReceptionTestBB2_p17//readbackCal_C11.dat
[15:25:16.266] <TB3> INFO: write readback calibration parameters into /home/Reception/DATA/DESYUHH/M4043_Reception_2016-08-15_17h16m_1471274195//000_ReceptionTestBB2_p17//readbackCal_C12.dat
[15:25:16.271] <TB3> INFO: write readback calibration parameters into /home/Reception/DATA/DESYUHH/M4043_Reception_2016-08-15_17h16m_1471274195//000_ReceptionTestBB2_p17//readbackCal_C13.dat
[15:25:16.278] <TB3> INFO: write readback calibration parameters into /home/Reception/DATA/DESYUHH/M4043_Reception_2016-08-15_17h16m_1471274195//000_ReceptionTestBB2_p17//readbackCal_C14.dat
[15:25:16.284] <TB3> INFO: write readback calibration parameters into /home/Reception/DATA/DESYUHH/M4043_Reception_2016-08-15_17h16m_1471274195//000_ReceptionTestBB2_p17//readbackCal_C15.dat
[15:25:16.334] <TB3> INFO: PixTestPattern:: pg_setup set to default.
[15:25:16.334] <TB3> INFO: ----------------------------------------------------------------------
[15:25:16.334] <TB3> INFO: PixTestReadback::readbackVbg()
[15:25:16.334] <TB3> INFO: ----------------------------------------------------------------------
[15:25:23.506] <TB3> INFO: PixTestPattern:: pg_setup set to default.
[15:25:23.507] <TB3> INFO: ----------------------------------------------------------------------
[15:25:23.507] <TB3> INFO: PixTestReadback::getCalibratedVbg()
[15:25:23.507] <TB3> INFO: ----------------------------------------------------------------------
[15:25:23.507] <TB3> INFO: Vbg will be calibrated using Vd calibration
[15:25:23.507] <TB3> INFO: /*/*/*/*::: ROC 0: uncalibrated Vbg = 155calibrated Vbg = 1.24162 :::*/*/*/*/
[15:25:23.507] <TB3> INFO: /*/*/*/*::: ROC 1: uncalibrated Vbg = 152.5calibrated Vbg = 1.24454 :::*/*/*/*/
[15:25:23.507] <TB3> INFO: /*/*/*/*::: ROC 2: uncalibrated Vbg = 157.8calibrated Vbg = 1.25063 :::*/*/*/*/
[15:25:23.507] <TB3> INFO: /*/*/*/*::: ROC 3: uncalibrated Vbg = 152.5calibrated Vbg = 1.25532 :::*/*/*/*/
[15:25:23.507] <TB3> INFO: /*/*/*/*::: ROC 4: uncalibrated Vbg = 161.5calibrated Vbg = 1.25552 :::*/*/*/*/
[15:25:23.507] <TB3> INFO: /*/*/*/*::: ROC 5: uncalibrated Vbg = 158.7calibrated Vbg = 1.25811 :::*/*/*/*/
[15:25:23.507] <TB3> INFO: /*/*/*/*::: ROC 6: uncalibrated Vbg = 149.1calibrated Vbg = 1.25062 :::*/*/*/*/
[15:25:23.507] <TB3> INFO: /*/*/*/*::: ROC 7: uncalibrated Vbg = 156.9calibrated Vbg = 1.2615 :::*/*/*/*/
[15:25:23.507] <TB3> INFO: /*/*/*/*::: ROC 8: uncalibrated Vbg = 159.7calibrated Vbg = 1.2563 :::*/*/*/*/
[15:25:23.507] <TB3> INFO: /*/*/*/*::: ROC 9: uncalibrated Vbg = 152.2calibrated Vbg = 1.25781 :::*/*/*/*/
[15:25:23.507] <TB3> INFO: /*/*/*/*::: ROC 10: uncalibrated Vbg = 159calibrated Vbg = 1.26043 :::*/*/*/*/
[15:25:23.507] <TB3> INFO: /*/*/*/*::: ROC 11: uncalibrated Vbg = 149.3calibrated Vbg = 1.25014 :::*/*/*/*/
[15:25:23.507] <TB3> INFO: /*/*/*/*::: ROC 12: uncalibrated Vbg = 156.8calibrated Vbg = 1.24753 :::*/*/*/*/
[15:25:23.507] <TB3> INFO: /*/*/*/*::: ROC 13: uncalibrated Vbg = 161.8calibrated Vbg = 1.2438 :::*/*/*/*/
[15:25:23.507] <TB3> INFO: /*/*/*/*::: ROC 14: uncalibrated Vbg = 155.6calibrated Vbg = 1.24623 :::*/*/*/*/
[15:25:23.507] <TB3> INFO: /*/*/*/*::: ROC 15: uncalibrated Vbg = 160.6calibrated Vbg = 1.24716 :::*/*/*/*/
[15:25:23.509] <TB3> INFO: ----------------------------------------------------------------------
[15:25:23.510] <TB3> INFO: PixTestReadback::CalibrateIa()
[15:25:23.510] <TB3> INFO: ----------------------------------------------------------------------
[15:27:56.943] <TB3> INFO: write readback calibration parameters into /home/Reception/DATA/DESYUHH/M4043_Reception_2016-08-15_17h16m_1471274195//000_ReceptionTestBB2_p17//readbackCal_C0.dat
[15:27:56.948] <TB3> INFO: write readback calibration parameters into /home/Reception/DATA/DESYUHH/M4043_Reception_2016-08-15_17h16m_1471274195//000_ReceptionTestBB2_p17//readbackCal_C1.dat
[15:27:56.953] <TB3> INFO: write readback calibration parameters into /home/Reception/DATA/DESYUHH/M4043_Reception_2016-08-15_17h16m_1471274195//000_ReceptionTestBB2_p17//readbackCal_C2.dat
[15:27:56.959] <TB3> INFO: write readback calibration parameters into /home/Reception/DATA/DESYUHH/M4043_Reception_2016-08-15_17h16m_1471274195//000_ReceptionTestBB2_p17//readbackCal_C3.dat
[15:27:56.964] <TB3> INFO: write readback calibration parameters into /home/Reception/DATA/DESYUHH/M4043_Reception_2016-08-15_17h16m_1471274195//000_ReceptionTestBB2_p17//readbackCal_C4.dat
[15:27:56.969] <TB3> INFO: write readback calibration parameters into /home/Reception/DATA/DESYUHH/M4043_Reception_2016-08-15_17h16m_1471274195//000_ReceptionTestBB2_p17//readbackCal_C5.dat
[15:27:56.974] <TB3> INFO: write readback calibration parameters into /home/Reception/DATA/DESYUHH/M4043_Reception_2016-08-15_17h16m_1471274195//000_ReceptionTestBB2_p17//readbackCal_C6.dat
[15:27:56.979] <TB3> INFO: write readback calibration parameters into /home/Reception/DATA/DESYUHH/M4043_Reception_2016-08-15_17h16m_1471274195//000_ReceptionTestBB2_p17//readbackCal_C7.dat
[15:27:56.984] <TB3> INFO: write readback calibration parameters into /home/Reception/DATA/DESYUHH/M4043_Reception_2016-08-15_17h16m_1471274195//000_ReceptionTestBB2_p17//readbackCal_C8.dat
[15:27:56.989] <TB3> INFO: write readback calibration parameters into /home/Reception/DATA/DESYUHH/M4043_Reception_2016-08-15_17h16m_1471274195//000_ReceptionTestBB2_p17//readbackCal_C9.dat
[15:27:56.994] <TB3> INFO: write readback calibration parameters into /home/Reception/DATA/DESYUHH/M4043_Reception_2016-08-15_17h16m_1471274195//000_ReceptionTestBB2_p17//readbackCal_C10.dat
[15:27:57.000] <TB3> INFO: write readback calibration parameters into /home/Reception/DATA/DESYUHH/M4043_Reception_2016-08-15_17h16m_1471274195//000_ReceptionTestBB2_p17//readbackCal_C11.dat
[15:27:57.006] <TB3> INFO: write readback calibration parameters into /home/Reception/DATA/DESYUHH/M4043_Reception_2016-08-15_17h16m_1471274195//000_ReceptionTestBB2_p17//readbackCal_C12.dat
[15:27:57.013] <TB3> INFO: write readback calibration parameters into /home/Reception/DATA/DESYUHH/M4043_Reception_2016-08-15_17h16m_1471274195//000_ReceptionTestBB2_p17//readbackCal_C13.dat
[15:27:57.019] <TB3> INFO: write readback calibration parameters into /home/Reception/DATA/DESYUHH/M4043_Reception_2016-08-15_17h16m_1471274195//000_ReceptionTestBB2_p17//readbackCal_C14.dat
[15:27:57.024] <TB3> INFO: write readback calibration parameters into /home/Reception/DATA/DESYUHH/M4043_Reception_2016-08-15_17h16m_1471274195//000_ReceptionTestBB2_p17//readbackCal_C15.dat
[15:27:57.072] <TB3> INFO: PixTestPattern:: pg_setup set to default.
[15:27:57.073] <TB3> INFO: PixTestReadback::doTest() done
[15:27:57.095] <TB3> INFO: enter test to run
[15:27:57.095] <TB3> INFO: test: BB2 no parameter change
[15:27:57.095] <TB3> INFO: running: bb2
[15:27:57.096] <TB3> INFO: ######################################################################
[15:27:57.096] <TB3> INFO: PixTestBB2Map::doTest() Ntrig = 10, VcalS = 222, PlWidth = 35
[15:27:57.096] <TB3> INFO: ######################################################################
[15:27:57.097] <TB3> INFO: ----------------------------------------------------------------------
[15:27:57.097] <TB3> INFO: PixTestBB2Map::setVana() target Ia = 24 mA/ROC
[15:27:57.097] <TB3> INFO: ----------------------------------------------------------------------
[15:28:02.490] <TB3> INFO: PixTestBB2Map::setVana() done, Module Ia 393.9 mA = 24.6187 mA/ROC
[15:28:02.492] <TB3> INFO: ----------------------------------------------------------------------
[15:28:02.493] <TB3> INFO: PixTestBB2Map::setVthrCompCalDel()
[15:28:02.493] <TB3> INFO: ----------------------------------------------------------------------
[15:28:02.589] <TB3> INFO: Expecting 655360 events.
[15:28:12.533] <TB3> WARNING: Detected DESER400 trailer error bits: "CODE ERROR"
[15:28:20.644] <TB3> INFO: 655360 events read in total (17499ms).
[15:28:20.649] <TB3> INFO: Test took 18148ms.
[15:28:20.957] <TB3> INFO: PixTestBB2Map::setVthrCompCalDel() done
[15:28:20.957] <TB3> INFO: CalDel: 101 114 108 119 124 91 117 119 120 128 126 118 117 104 110 100
[15:28:20.957] <TB3> INFO: VthrComp: 89 91 97 97 92 97 96 97 92 105 89 102 99 120 107 101
[15:28:21.237] <TB3> INFO: Expecting 5324800 events.
[15:28:57.726] <TB3> INFO: 1361560 events read in total (35933ms).
[15:29:33.622] <TB3> INFO: 2702520 events read in total (71829ms).
[15:30:09.384] <TB3> INFO: 4052710 events read in total (107591ms).
[15:30:42.671] <TB3> INFO: 5324800 events read in total (140878ms).
[15:30:42.705] <TB3> INFO: Test took 141732ms.
[15:30:43.262] <TB3> INFO: Missing Bumps: 0 1 0 0 0 7 0 3 2 0 0 3 1 0 0 4
[15:30:43.262] <TB3> INFO: Separation Cut: 25.30 25.16 32.23 30.12 38.68 27.86 32.73 18.65 33.19 43.85 29.95 34.42 33.92 23.24 29.96 23.12
[15:30:43.262] <TB3> INFO: PixTestBB2Map::doTest() done,166 seconds
[15:30:43.589] <TB3> INFO: enter test to run
[15:30:43.589] <TB3> INFO: test: no parameter change
[15:30:43.642] <TB3> QUIET: Connection to board 126 closed.
[15:30:43.724] <TB3> INFO: pXar: this is the end, my friend