[08:29:33.917] <TB2> INFO: *** Welcome to pxar ***
[08:29:33.917] <TB2> INFO: *** Today: 2016/08/16
[08:29:33.976] <TB2> INFO: *** Version: f5d3-dirty
[08:29:33.976] <TB2> INFO: readRocDacs: /home/Reception/DATA/DESYUHH/M4107_Reception_2016-08-16_10h27m_1471336044//000_ReceptionTestBB2_p17//dacParameters_C0.dat .. /home/Reception/DATA/DESYUHH/M4107_Reception_2016-08-16_10h27m_1471336044//000_ReceptionTestBB2_p17//dacParameters_C15.dat
[08:29:33.994] <TB2> INFO: readTbmDacs: /home/Reception/DATA/DESYUHH/M4107_Reception_2016-08-16_10h27m_1471336044//000_ReceptionTestBB2_p17//tbmParameters_C0a.dat .. /home/Reception/DATA/DESYUHH/M4107_Reception_2016-08-16_10h27m_1471336044//000_ReceptionTestBB2_p17//tbmParameters_C0b.dat
[08:29:33.997] <TB2> INFO: readMaskFile: /home/Reception/DATA/DESYUHH/M4107_Reception_2016-08-16_10h27m_1471336044//000_ReceptionTestBB2_p17//defaultMaskFile.dat
[08:29:33.998] <TB2> INFO: readTrimFile: /home/Reception/DATA/DESYUHH/M4107_Reception_2016-08-16_10h27m_1471336044//000_ReceptionTestBB2_p17//trimParameters_C0.dat .. /home/Reception/DATA/DESYUHH/M4107_Reception_2016-08-16_10h27m_1471336044//000_ReceptionTestBB2_p17//trimParameters_C15.dat
[08:29:34.138] <TB2> INFO: clk: 4
[08:29:34.138] <TB2> INFO: ctr: 4
[08:29:34.138] <TB2> INFO: sda: 19
[08:29:34.138] <TB2> INFO: tin: 9
[08:29:34.138] <TB2> INFO: level: 15
[08:29:34.138] <TB2> INFO: triggerdelay: 0
[08:29:34.138] <TB2> QUIET: Instanciating API for pxar v2.7.5+40~g4fce89b
[08:29:34.138] <TB2> INFO: Log level: INFO
[08:29:34.147] <TB2> INFO: Found DTB DTB_WWXUD2
[08:29:34.154] <TB2> QUIET: Connection to board DTB_WWXUD2 opened.
[08:29:34.156] <TB2> INFO: DTB startup information
--- DTB info------------------------------------------
Board id: 149
HW version: DTB1.2
FW version: 4.4
SW version: 4.6
Options:
USB id: DTB_WWXUD2
MAC address: 40D855118095
Hostname: pixelDTB149
Comment:
------------------------------------------------------
[08:29:34.157] <TB2> INFO: RPC call hashes of host and DTB match: 484264910
[08:29:35.670] <TB2> INFO: DUT info:
[08:29:35.670] <TB2> INFO: The DUT currently contains the following objects:
[08:29:35.670] <TB2> INFO: 2 TBM Cores tbm08c (2 ON)
[08:29:35.670] <TB2> INFO: TBM Core alpha (0): 7 registers set
[08:29:35.670] <TB2> INFO: TBM Core beta (1): 7 registers set
[08:29:35.670] <TB2> INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[08:29:35.670] <TB2> INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[08:29:35.670] <TB2> INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[08:29:35.671] <TB2> INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[08:29:35.671] <TB2> INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[08:29:35.671] <TB2> INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[08:29:35.671] <TB2> INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[08:29:35.671] <TB2> INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[08:29:35.671] <TB2> INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[08:29:35.671] <TB2> INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[08:29:35.671] <TB2> INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[08:29:35.671] <TB2> INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[08:29:35.671] <TB2> INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[08:29:35.671] <TB2> INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[08:29:35.671] <TB2> INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[08:29:35.671] <TB2> INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[08:29:35.671] <TB2> INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[08:29:36.073] <TB2> INFO: enter 'restricted' command line mode
[08:29:36.073] <TB2> INFO: enter test to run
[08:29:36.073] <TB2> INFO: test: pretest no parameter change
[08:29:36.073] <TB2> INFO: running: pretest
[08:29:36.081] <TB2> INFO: ----------------------------------------------------------------------
[08:29:36.081] <TB2> INFO: PixTestPretest::programROC()
[08:29:36.081] <TB2> INFO: ----------------------------------------------------------------------
[08:29:54.095] <TB2> INFO: PixTestPretest::programROC() done: ROCs are all programmable
[08:29:54.095] <TB2> INFO: IA differences per ROC: 20.1 19.3 20.9 18.5 19.3 19.3 20.1 20.1 20.1 20.1 19.3 20.1 19.3 19.3 20.9 20.1
[08:29:54.204] <TB2> INFO: enter test to run
[08:29:54.204] <TB2> INFO: test: pretest no parameter change
[08:29:54.204] <TB2> INFO: running: pretest
[08:29:54.205] <TB2> INFO: ----------------------------------------------------------------------
[08:29:54.205] <TB2> INFO: PixTestPretest::setVana() target Ia = 24 mA/ROC
[08:29:54.205] <TB2> INFO: ----------------------------------------------------------------------
[08:30:15.464] <TB2> INFO: PixTestPretest::setVana() done, Module Ia 381 mA = 23.8125 mA/ROC
[08:30:15.465] <TB2> INFO: i(loss) [mA/ROC]: 19.2 19.2 20.1 19.2 20.1 19.2 20.1 18.4 19.2 19.2 18.4 19.2 19.2 19.2 20.1 19.2
[08:30:15.497] <TB2> INFO: enter test to run
[08:30:15.497] <TB2> INFO: test: pretest no parameter change
[08:30:15.498] <TB2> INFO: running: pretest
[08:30:15.498] <TB2> INFO: ----------------------------------------------------------------------
[08:30:15.498] <TB2> INFO: PixTestPretest::findTiming()
[08:30:15.498] <TB2> INFO: ----------------------------------------------------------------------
[08:30:15.498] <TB2> INFO: PixTestCmd::init()
[08:30:16.271] <TB2> WARNING: Not unmasking DUT, not setting Calibrate bits!
[08:31:14.830] <TB2> INFO: TBM phases: 160MHz: 7, 400MHz: 2, TBM delays: ROC(0/1):4, header/trailer: 1, token: 1
[08:31:14.831] <TB2> INFO: (success/tries = 100/100), width = 4
[08:31:14.831] <TB2> INFO: enter test to run
[08:31:14.832] <TB2> INFO: test: pretest no parameter change
[08:31:14.832] <TB2> INFO: running: pretest
[08:31:14.833] <TB2> INFO: ----------------------------------------------------------------------
[08:31:14.833] <TB2> INFO: PixTestPretest::findWorkingPixel()
[08:31:14.833] <TB2> INFO: ----------------------------------------------------------------------
[08:31:14.923] <TB2> INFO: Expecting 231680 events.
[08:31:20.606] <TB2> WARNING: Detected DESER400 trailer error bits: "CODE ERROR"
[08:31:23.987] <TB2> INFO: 231680 events read in total (8508ms).
[08:31:23.993] <TB2> INFO: Test took 9156ms.
[08:31:24.281] <TB2> INFO: Found working pixel in all ROCs: col/row = 12/22
[08:31:24.336] <TB2> INFO: enter test to run
[08:31:24.336] <TB2> INFO: test: pretest no parameter change
[08:31:24.336] <TB2> INFO: running: pretest
[08:31:24.337] <TB2> INFO: ----------------------------------------------------------------------
[08:31:24.337] <TB2> INFO: PixTestPretest::setVthrCompCalDel()
[08:31:24.337] <TB2> INFO: ----------------------------------------------------------------------
[08:31:24.429] <TB2> INFO: Expecting 231680 events.
[08:31:33.591] <TB2> INFO: 231680 events read in total (8607ms).
[08:31:33.595] <TB2> INFO: Test took 9255ms.
[08:31:33.885] <TB2> INFO: PixTestPretest::setVthrCompCalDel() done
[08:31:33.885] <TB2> INFO: CalDel: 111 142 143 121 135 141 125 120 149 154 119 112 129 139 130 127
[08:31:33.885] <TB2> INFO: VthrComp: 51 51 51 51 51 51 51 51 51 51 52 51 51 51 51 51
[08:31:33.917] <TB2> INFO: enter test to run
[08:31:33.917] <TB2> INFO: test: pretest no parameter change
[08:31:33.917] <TB2> INFO: running: pretest
[08:31:33.931] <TB2> INFO: write dac parameters into /home/Reception/DATA/DESYUHH/M4107_Reception_2016-08-16_10h27m_1471336044//000_ReceptionTestBB2_p17//dacParameters_C0.dat
[08:31:33.936] <TB2> INFO: write dac parameters into /home/Reception/DATA/DESYUHH/M4107_Reception_2016-08-16_10h27m_1471336044//000_ReceptionTestBB2_p17//dacParameters_C1.dat
[08:31:33.942] <TB2> INFO: write dac parameters into /home/Reception/DATA/DESYUHH/M4107_Reception_2016-08-16_10h27m_1471336044//000_ReceptionTestBB2_p17//dacParameters_C2.dat
[08:31:33.947] <TB2> INFO: write dac parameters into /home/Reception/DATA/DESYUHH/M4107_Reception_2016-08-16_10h27m_1471336044//000_ReceptionTestBB2_p17//dacParameters_C3.dat
[08:31:33.952] <TB2> INFO: write dac parameters into /home/Reception/DATA/DESYUHH/M4107_Reception_2016-08-16_10h27m_1471336044//000_ReceptionTestBB2_p17//dacParameters_C4.dat
[08:31:33.957] <TB2> INFO: write dac parameters into /home/Reception/DATA/DESYUHH/M4107_Reception_2016-08-16_10h27m_1471336044//000_ReceptionTestBB2_p17//dacParameters_C5.dat
[08:31:33.962] <TB2> INFO: write dac parameters into /home/Reception/DATA/DESYUHH/M4107_Reception_2016-08-16_10h27m_1471336044//000_ReceptionTestBB2_p17//dacParameters_C6.dat
[08:31:33.968] <TB2> INFO: write dac parameters into /home/Reception/DATA/DESYUHH/M4107_Reception_2016-08-16_10h27m_1471336044//000_ReceptionTestBB2_p17//dacParameters_C7.dat
[08:31:33.973] <TB2> INFO: write dac parameters into /home/Reception/DATA/DESYUHH/M4107_Reception_2016-08-16_10h27m_1471336044//000_ReceptionTestBB2_p17//dacParameters_C8.dat
[08:31:33.978] <TB2> INFO: write dac parameters into /home/Reception/DATA/DESYUHH/M4107_Reception_2016-08-16_10h27m_1471336044//000_ReceptionTestBB2_p17//dacParameters_C9.dat
[08:31:33.983] <TB2> INFO: write dac parameters into /home/Reception/DATA/DESYUHH/M4107_Reception_2016-08-16_10h27m_1471336044//000_ReceptionTestBB2_p17//dacParameters_C10.dat
[08:31:33.989] <TB2> INFO: write dac parameters into /home/Reception/DATA/DESYUHH/M4107_Reception_2016-08-16_10h27m_1471336044//000_ReceptionTestBB2_p17//dacParameters_C11.dat
[08:31:33.997] <TB2> INFO: write dac parameters into /home/Reception/DATA/DESYUHH/M4107_Reception_2016-08-16_10h27m_1471336044//000_ReceptionTestBB2_p17//dacParameters_C12.dat
[08:31:34.003] <TB2> INFO: write dac parameters into /home/Reception/DATA/DESYUHH/M4107_Reception_2016-08-16_10h27m_1471336044//000_ReceptionTestBB2_p17//dacParameters_C13.dat
[08:31:34.008] <TB2> INFO: write dac parameters into /home/Reception/DATA/DESYUHH/M4107_Reception_2016-08-16_10h27m_1471336044//000_ReceptionTestBB2_p17//dacParameters_C14.dat
[08:31:34.013] <TB2> INFO: write dac parameters into /home/Reception/DATA/DESYUHH/M4107_Reception_2016-08-16_10h27m_1471336044//000_ReceptionTestBB2_p17//dacParameters_C15.dat
[08:31:34.016] <TB2> INFO: enter test to run
[08:31:34.016] <TB2> INFO: test: PixelAlive no parameter change
[08:31:34.016] <TB2> INFO: running: pixelalive
[08:31:34.017] <TB2> INFO: ######################################################################
[08:31:34.017] <TB2> INFO: PixTestAlive::doTest()
[08:31:34.017] <TB2> INFO: ######################################################################
[08:31:34.019] <TB2> INFO: ----------------------------------------------------------------------
[08:31:34.019] <TB2> INFO: PixTestAlive::aliveTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[08:31:34.019] <TB2> INFO: ----------------------------------------------------------------------
[08:31:34.300] <TB2> INFO: Expecting 41600 events.
[08:31:37.802] <TB2> INFO: 41600 events read in total (2946ms).
[08:31:37.803] <TB2> INFO: Test took 3783ms.
[08:31:38.097] <TB2> INFO: PixTestAlive::aliveTest() done
[08:31:38.097] <TB2> INFO: number of dead pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[08:31:38.098] <TB2> INFO: ----------------------------------------------------------------------
[08:31:38.098] <TB2> INFO: PixTestAlive::maskTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[08:31:38.098] <TB2> INFO: ----------------------------------------------------------------------
[08:31:38.373] <TB2> INFO: Expecting 41600 events.
[08:31:41.029] <TB2> INFO: 41600 events read in total (2100ms).
[08:31:41.029] <TB2> INFO: Test took 2929ms.
[08:31:41.030] <TB2> INFO: mask vs. old pixelAlive PixelAlive_C0_V0 .. PixelAlive_C15_V0
[08:31:41.337] <TB2> INFO: PixTestAlive::maskTest() done
[08:31:41.337] <TB2> INFO: number of mask-defect pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[08:31:41.339] <TB2> INFO: ----------------------------------------------------------------------
[08:31:41.339] <TB2> INFO: PixTestAlive::addressDecodingTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[08:31:41.339] <TB2> INFO: ----------------------------------------------------------------------
[08:31:41.624] <TB2> INFO: Expecting 41600 events.
[08:31:45.059] <TB2> INFO: 41600 events read in total (2879ms).
[08:31:45.088] <TB2> INFO: Test took 3747ms.
[08:31:45.348] <TB2> INFO: PixTestAlive::addressDecodingTest() done
[08:31:45.348] <TB2> INFO: number of address-decoding pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[08:31:45.348] <TB2> INFO: PixTestAlive::doTest() done, duration: 11 seconds
[08:31:45.353] <TB2> INFO: enter test to run
[08:31:45.353] <TB2> INFO: test: BB no parameter change
[08:31:45.353] <TB2> INFO: running: bb
[08:31:45.355] <TB2> INFO: ######################################################################
[08:31:45.355] <TB2> INFO: PixTestBBMap::doTest() Ntrig = 5, VcalS = 250 (high range)
[08:31:45.355] <TB2> INFO: ######################################################################
[08:31:45.357] <TB2> INFO: ---> dac: VthrComp name: calSMap ntrig: 5 dacrange: 0 .. 149 (-1/-1) hits flags = 514 (plus default)
[08:31:45.368] <TB2> INFO: dacScan split into 1 runs with ntrig = 5
[08:31:45.368] <TB2> INFO: run 1 of 1
[08:31:45.635] <TB2> INFO: Expecting 3120000 events.
[08:32:31.009] <TB2> INFO: 1224720 events read in total (44818ms).
[08:33:16.083] <TB2> INFO: 2449335 events read in total (89893ms).
[08:33:41.638] <TB2> INFO: 3120000 events read in total (115448ms).
[08:33:41.678] <TB2> INFO: Test took 116311ms.
[08:34:04.766] <TB2> INFO: PixTestBBMap::doTest() done, duration: 139 seconds
[08:34:04.766] <TB2> INFO: number of dead bumps (per ROC): 0 0 0 4 0 0 0 3 0 0 0 0 0 0 0 2
[08:34:04.766] <TB2> INFO: separation cut (per ROC): 133 127 121 146 121 111 119 126 106 107 131 118 103 127 106 105
[08:34:04.837] <TB2> INFO: enter test to run
[08:34:04.837] <TB2> INFO: test: Readback no parameter change
[08:34:04.838] <TB2> INFO: running: readback
[08:34:04.838] <TB2> INFO: readReadbackCal: /home/Reception/DATA/DESYUHH/M4107_Reception_2016-08-16_10h27m_1471336044//000_ReceptionTestBB2_p17//readbackCal_C0.dat .. /home/Reception/DATA/DESYUHH/M4107_Reception_2016-08-16_10h27m_1471336044//000_ReceptionTestBB2_p17//readbackCal_C15.dat
[08:34:04.853] <TB2> INFO: readGainPedestalParameters /home/Reception/DATA/DESYUHH/M4107_Reception_2016-08-16_10h27m_1471336044//000_ReceptionTestBB2_p17//phCalibrationFitErr_C0.dat .. /home/Reception/DATA/DESYUHH/M4107_Reception_2016-08-16_10h27m_1471336044//000_ReceptionTestBB2_p17//phCalibrationFitErr_C15.dat
[08:34:04.867] <TB2> ERROR: <ConfigParameters.cc/readGainPedestalParameters:L996> cannot open /home/Reception/DATA/DESYUHH/M4107_Reception_2016-08-16_10h27m_1471336044//000_ReceptionTestBB2_p17//phCalibrationFitErr_C0.dat for reading PH calibration constants
[08:34:04.867] <TB2> INFO: ######################################################################
[08:34:04.867] <TB2> INFO: PixTestReadback::doTest()
[08:34:04.867] <TB2> INFO: ######################################################################
[08:34:04.867] <TB2> INFO: ----------------------------------------------------------------------
[08:34:04.867] <TB2> INFO: PixTestReadback::CalibrateVd()
[08:34:04.867] <TB2> INFO: ----------------------------------------------------------------------
[08:34:14.097] <TB2> INFO: write readback calibration parameters into /home/Reception/DATA/DESYUHH/M4107_Reception_2016-08-16_10h27m_1471336044//000_ReceptionTestBB2_p17//readbackCal_C0.dat
[08:34:14.105] <TB2> INFO: write readback calibration parameters into /home/Reception/DATA/DESYUHH/M4107_Reception_2016-08-16_10h27m_1471336044//000_ReceptionTestBB2_p17//readbackCal_C1.dat
[08:34:14.116] <TB2> INFO: write readback calibration parameters into /home/Reception/DATA/DESYUHH/M4107_Reception_2016-08-16_10h27m_1471336044//000_ReceptionTestBB2_p17//readbackCal_C2.dat
[08:34:14.121] <TB2> INFO: write readback calibration parameters into /home/Reception/DATA/DESYUHH/M4107_Reception_2016-08-16_10h27m_1471336044//000_ReceptionTestBB2_p17//readbackCal_C3.dat
[08:34:14.126] <TB2> INFO: write readback calibration parameters into /home/Reception/DATA/DESYUHH/M4107_Reception_2016-08-16_10h27m_1471336044//000_ReceptionTestBB2_p17//readbackCal_C4.dat
[08:34:14.131] <TB2> INFO: write readback calibration parameters into /home/Reception/DATA/DESYUHH/M4107_Reception_2016-08-16_10h27m_1471336044//000_ReceptionTestBB2_p17//readbackCal_C5.dat
[08:34:14.136] <TB2> INFO: write readback calibration parameters into /home/Reception/DATA/DESYUHH/M4107_Reception_2016-08-16_10h27m_1471336044//000_ReceptionTestBB2_p17//readbackCal_C6.dat
[08:34:14.143] <TB2> INFO: write readback calibration parameters into /home/Reception/DATA/DESYUHH/M4107_Reception_2016-08-16_10h27m_1471336044//000_ReceptionTestBB2_p17//readbackCal_C7.dat
[08:34:14.152] <TB2> INFO: write readback calibration parameters into /home/Reception/DATA/DESYUHH/M4107_Reception_2016-08-16_10h27m_1471336044//000_ReceptionTestBB2_p17//readbackCal_C8.dat
[08:34:14.163] <TB2> INFO: write readback calibration parameters into /home/Reception/DATA/DESYUHH/M4107_Reception_2016-08-16_10h27m_1471336044//000_ReceptionTestBB2_p17//readbackCal_C9.dat
[08:34:14.168] <TB2> INFO: write readback calibration parameters into /home/Reception/DATA/DESYUHH/M4107_Reception_2016-08-16_10h27m_1471336044//000_ReceptionTestBB2_p17//readbackCal_C10.dat
[08:34:14.177] <TB2> INFO: write readback calibration parameters into /home/Reception/DATA/DESYUHH/M4107_Reception_2016-08-16_10h27m_1471336044//000_ReceptionTestBB2_p17//readbackCal_C11.dat
[08:34:14.182] <TB2> INFO: write readback calibration parameters into /home/Reception/DATA/DESYUHH/M4107_Reception_2016-08-16_10h27m_1471336044//000_ReceptionTestBB2_p17//readbackCal_C12.dat
[08:34:14.196] <TB2> INFO: write readback calibration parameters into /home/Reception/DATA/DESYUHH/M4107_Reception_2016-08-16_10h27m_1471336044//000_ReceptionTestBB2_p17//readbackCal_C13.dat
[08:34:14.204] <TB2> INFO: write readback calibration parameters into /home/Reception/DATA/DESYUHH/M4107_Reception_2016-08-16_10h27m_1471336044//000_ReceptionTestBB2_p17//readbackCal_C14.dat
[08:34:14.212] <TB2> INFO: write readback calibration parameters into /home/Reception/DATA/DESYUHH/M4107_Reception_2016-08-16_10h27m_1471336044//000_ReceptionTestBB2_p17//readbackCal_C15.dat
[08:34:14.242] <TB2> INFO: PixTestPattern:: pg_setup set to default.
[08:34:14.242] <TB2> INFO: ----------------------------------------------------------------------
[08:34:14.242] <TB2> INFO: PixTestReadback::CalibrateVa()
[08:34:14.242] <TB2> INFO: ----------------------------------------------------------------------
[08:34:23.501] <TB2> INFO: write readback calibration parameters into /home/Reception/DATA/DESYUHH/M4107_Reception_2016-08-16_10h27m_1471336044//000_ReceptionTestBB2_p17//readbackCal_C0.dat
[08:34:23.508] <TB2> INFO: write readback calibration parameters into /home/Reception/DATA/DESYUHH/M4107_Reception_2016-08-16_10h27m_1471336044//000_ReceptionTestBB2_p17//readbackCal_C1.dat
[08:34:23.518] <TB2> INFO: write readback calibration parameters into /home/Reception/DATA/DESYUHH/M4107_Reception_2016-08-16_10h27m_1471336044//000_ReceptionTestBB2_p17//readbackCal_C2.dat
[08:34:23.526] <TB2> INFO: write readback calibration parameters into /home/Reception/DATA/DESYUHH/M4107_Reception_2016-08-16_10h27m_1471336044//000_ReceptionTestBB2_p17//readbackCal_C3.dat
[08:34:23.534] <TB2> INFO: write readback calibration parameters into /home/Reception/DATA/DESYUHH/M4107_Reception_2016-08-16_10h27m_1471336044//000_ReceptionTestBB2_p17//readbackCal_C4.dat
[08:34:23.539] <TB2> INFO: write readback calibration parameters into /home/Reception/DATA/DESYUHH/M4107_Reception_2016-08-16_10h27m_1471336044//000_ReceptionTestBB2_p17//readbackCal_C5.dat
[08:34:23.544] <TB2> INFO: write readback calibration parameters into /home/Reception/DATA/DESYUHH/M4107_Reception_2016-08-16_10h27m_1471336044//000_ReceptionTestBB2_p17//readbackCal_C6.dat
[08:34:23.549] <TB2> INFO: write readback calibration parameters into /home/Reception/DATA/DESYUHH/M4107_Reception_2016-08-16_10h27m_1471336044//000_ReceptionTestBB2_p17//readbackCal_C7.dat
[08:34:23.554] <TB2> INFO: write readback calibration parameters into /home/Reception/DATA/DESYUHH/M4107_Reception_2016-08-16_10h27m_1471336044//000_ReceptionTestBB2_p17//readbackCal_C8.dat
[08:34:23.559] <TB2> INFO: write readback calibration parameters into /home/Reception/DATA/DESYUHH/M4107_Reception_2016-08-16_10h27m_1471336044//000_ReceptionTestBB2_p17//readbackCal_C9.dat
[08:34:23.565] <TB2> INFO: write readback calibration parameters into /home/Reception/DATA/DESYUHH/M4107_Reception_2016-08-16_10h27m_1471336044//000_ReceptionTestBB2_p17//readbackCal_C10.dat
[08:34:23.570] <TB2> INFO: write readback calibration parameters into /home/Reception/DATA/DESYUHH/M4107_Reception_2016-08-16_10h27m_1471336044//000_ReceptionTestBB2_p17//readbackCal_C11.dat
[08:34:23.575] <TB2> INFO: write readback calibration parameters into /home/Reception/DATA/DESYUHH/M4107_Reception_2016-08-16_10h27m_1471336044//000_ReceptionTestBB2_p17//readbackCal_C12.dat
[08:34:23.579] <TB2> INFO: write readback calibration parameters into /home/Reception/DATA/DESYUHH/M4107_Reception_2016-08-16_10h27m_1471336044//000_ReceptionTestBB2_p17//readbackCal_C13.dat
[08:34:23.584] <TB2> INFO: write readback calibration parameters into /home/Reception/DATA/DESYUHH/M4107_Reception_2016-08-16_10h27m_1471336044//000_ReceptionTestBB2_p17//readbackCal_C14.dat
[08:34:23.590] <TB2> INFO: write readback calibration parameters into /home/Reception/DATA/DESYUHH/M4107_Reception_2016-08-16_10h27m_1471336044//000_ReceptionTestBB2_p17//readbackCal_C15.dat
[08:34:23.621] <TB2> INFO: PixTestPattern:: pg_setup set to default.
[08:34:23.621] <TB2> INFO: ----------------------------------------------------------------------
[08:34:23.621] <TB2> INFO: PixTestReadback::readbackVbg()
[08:34:23.621] <TB2> INFO: ----------------------------------------------------------------------
[08:34:30.721] <TB2> INFO: PixTestPattern:: pg_setup set to default.
[08:34:30.722] <TB2> INFO: ----------------------------------------------------------------------
[08:34:30.722] <TB2> INFO: PixTestReadback::getCalibratedVbg()
[08:34:30.722] <TB2> INFO: ----------------------------------------------------------------------
[08:34:30.722] <TB2> INFO: Vbg will be calibrated using Vd calibration
[08:34:30.722] <TB2> INFO: /*/*/*/*::: ROC 0: uncalibrated Vbg = 154calibrated Vbg = 1.22674 :::*/*/*/*/
[08:34:30.722] <TB2> INFO: /*/*/*/*::: ROC 1: uncalibrated Vbg = 150.2calibrated Vbg = 1.22706 :::*/*/*/*/
[08:34:30.722] <TB2> INFO: /*/*/*/*::: ROC 2: uncalibrated Vbg = 151.2calibrated Vbg = 1.23674 :::*/*/*/*/
[08:34:30.722] <TB2> INFO: /*/*/*/*::: ROC 3: uncalibrated Vbg = 152.7calibrated Vbg = 1.24607 :::*/*/*/*/
[08:34:30.722] <TB2> INFO: /*/*/*/*::: ROC 4: uncalibrated Vbg = 150.4calibrated Vbg = 1.24324 :::*/*/*/*/
[08:34:30.722] <TB2> INFO: /*/*/*/*::: ROC 5: uncalibrated Vbg = 161.6calibrated Vbg = 1.24778 :::*/*/*/*/
[08:34:30.722] <TB2> INFO: /*/*/*/*::: ROC 6: uncalibrated Vbg = 148.8calibrated Vbg = 1.24499 :::*/*/*/*/
[08:34:30.722] <TB2> INFO: /*/*/*/*::: ROC 7: uncalibrated Vbg = 153.2calibrated Vbg = 1.24583 :::*/*/*/*/
[08:34:30.722] <TB2> INFO: /*/*/*/*::: ROC 8: uncalibrated Vbg = 157.2calibrated Vbg = 1.25001 :::*/*/*/*/
[08:34:30.722] <TB2> INFO: /*/*/*/*::: ROC 9: uncalibrated Vbg = 149.7calibrated Vbg = 1.25155 :::*/*/*/*/
[08:34:30.722] <TB2> INFO: /*/*/*/*::: ROC 10: uncalibrated Vbg = 147.6calibrated Vbg = 1.24179 :::*/*/*/*/
[08:34:30.722] <TB2> INFO: /*/*/*/*::: ROC 11: uncalibrated Vbg = 152.7calibrated Vbg = 1.23986 :::*/*/*/*/
[08:34:30.722] <TB2> INFO: /*/*/*/*::: ROC 12: uncalibrated Vbg = 153.5calibrated Vbg = 1.23842 :::*/*/*/*/
[08:34:30.722] <TB2> INFO: /*/*/*/*::: ROC 13: uncalibrated Vbg = 162.6calibrated Vbg = 1.23369 :::*/*/*/*/
[08:34:30.722] <TB2> INFO: /*/*/*/*::: ROC 14: uncalibrated Vbg = 152.4calibrated Vbg = 1.23306 :::*/*/*/*/
[08:34:30.722] <TB2> INFO: /*/*/*/*::: ROC 15: uncalibrated Vbg = 149.2calibrated Vbg = 1.23174 :::*/*/*/*/
[08:34:30.724] <TB2> INFO: ----------------------------------------------------------------------
[08:34:30.724] <TB2> INFO: PixTestReadback::CalibrateIa()
[08:34:30.724] <TB2> INFO: ----------------------------------------------------------------------
[08:37:03.310] <TB2> INFO: write readback calibration parameters into /home/Reception/DATA/DESYUHH/M4107_Reception_2016-08-16_10h27m_1471336044//000_ReceptionTestBB2_p17//readbackCal_C0.dat
[08:37:03.469] <TB2> INFO: write readback calibration parameters into /home/Reception/DATA/DESYUHH/M4107_Reception_2016-08-16_10h27m_1471336044//000_ReceptionTestBB2_p17//readbackCal_C1.dat
[08:37:03.544] <TB2> INFO: write readback calibration parameters into /home/Reception/DATA/DESYUHH/M4107_Reception_2016-08-16_10h27m_1471336044//000_ReceptionTestBB2_p17//readbackCal_C2.dat
[08:37:03.603] <TB2> INFO: write readback calibration parameters into /home/Reception/DATA/DESYUHH/M4107_Reception_2016-08-16_10h27m_1471336044//000_ReceptionTestBB2_p17//readbackCal_C3.dat
[08:37:03.661] <TB2> INFO: write readback calibration parameters into /home/Reception/DATA/DESYUHH/M4107_Reception_2016-08-16_10h27m_1471336044//000_ReceptionTestBB2_p17//readbackCal_C4.dat
[08:37:03.730] <TB2> INFO: write readback calibration parameters into /home/Reception/DATA/DESYUHH/M4107_Reception_2016-08-16_10h27m_1471336044//000_ReceptionTestBB2_p17//readbackCal_C5.dat
[08:37:03.788] <TB2> INFO: write readback calibration parameters into /home/Reception/DATA/DESYUHH/M4107_Reception_2016-08-16_10h27m_1471336044//000_ReceptionTestBB2_p17//readbackCal_C6.dat
[08:37:03.847] <TB2> INFO: write readback calibration parameters into /home/Reception/DATA/DESYUHH/M4107_Reception_2016-08-16_10h27m_1471336044//000_ReceptionTestBB2_p17//readbackCal_C7.dat
[08:37:03.906] <TB2> INFO: write readback calibration parameters into /home/Reception/DATA/DESYUHH/M4107_Reception_2016-08-16_10h27m_1471336044//000_ReceptionTestBB2_p17//readbackCal_C8.dat
[08:37:03.964] <TB2> INFO: write readback calibration parameters into /home/Reception/DATA/DESYUHH/M4107_Reception_2016-08-16_10h27m_1471336044//000_ReceptionTestBB2_p17//readbackCal_C9.dat
[08:37:04.023] <TB2> INFO: write readback calibration parameters into /home/Reception/DATA/DESYUHH/M4107_Reception_2016-08-16_10h27m_1471336044//000_ReceptionTestBB2_p17//readbackCal_C10.dat
[08:37:04.082] <TB2> INFO: write readback calibration parameters into /home/Reception/DATA/DESYUHH/M4107_Reception_2016-08-16_10h27m_1471336044//000_ReceptionTestBB2_p17//readbackCal_C11.dat
[08:37:04.140] <TB2> INFO: write readback calibration parameters into /home/Reception/DATA/DESYUHH/M4107_Reception_2016-08-16_10h27m_1471336044//000_ReceptionTestBB2_p17//readbackCal_C12.dat
[08:37:04.199] <TB2> INFO: write readback calibration parameters into /home/Reception/DATA/DESYUHH/M4107_Reception_2016-08-16_10h27m_1471336044//000_ReceptionTestBB2_p17//readbackCal_C13.dat
[08:37:04.258] <TB2> INFO: write readback calibration parameters into /home/Reception/DATA/DESYUHH/M4107_Reception_2016-08-16_10h27m_1471336044//000_ReceptionTestBB2_p17//readbackCal_C14.dat
[08:37:04.316] <TB2> INFO: write readback calibration parameters into /home/Reception/DATA/DESYUHH/M4107_Reception_2016-08-16_10h27m_1471336044//000_ReceptionTestBB2_p17//readbackCal_C15.dat
[08:37:04.388] <TB2> INFO: PixTestPattern:: pg_setup set to default.
[08:37:04.389] <TB2> INFO: PixTestReadback::doTest() done
[08:37:04.410] <TB2> INFO: enter test to run
[08:37:04.410] <TB2> INFO: test: BB2 no parameter change
[08:37:04.410] <TB2> INFO: running: bb2
[08:37:04.411] <TB2> INFO: ######################################################################
[08:37:04.411] <TB2> INFO: PixTestBB2Map::doTest() Ntrig = 10, VcalS = 222, PlWidth = 35
[08:37:04.411] <TB2> INFO: ######################################################################
[08:37:04.412] <TB2> INFO: ----------------------------------------------------------------------
[08:37:04.412] <TB2> INFO: PixTestBB2Map::setVana() target Ia = 24 mA/ROC
[08:37:04.412] <TB2> INFO: ----------------------------------------------------------------------
[08:37:23.943] <TB2> INFO: PixTestBB2Map::setVana() done, Module Ia 385.9 mA = 24.1188 mA/ROC
[08:37:23.944] <TB2> INFO: ----------------------------------------------------------------------
[08:37:23.944] <TB2> INFO: PixTestBB2Map::setVthrCompCalDel()
[08:37:23.944] <TB2> INFO: ----------------------------------------------------------------------
[08:37:24.035] <TB2> INFO: Expecting 655360 events.
[08:37:33.955] <TB2> WARNING: Detected DESER400 trailer error bits: "CODE ERROR"
[08:37:41.861] <TB2> INFO: 655360 events read in total (17271ms).
[08:37:41.865] <TB2> INFO: Test took 17917ms.
[08:37:42.161] <TB2> INFO: PixTestBB2Map::setVthrCompCalDel() done
[08:37:42.161] <TB2> INFO: CalDel: 107 137 138 120 132 135 120 114 146 145 119 108 122 136 122 123
[08:37:42.161] <TB2> INFO: VthrComp: 104 105 104 116 108 89 105 96 95 88 110 103 87 103 83 93
[08:37:42.435] <TB2> INFO: Expecting 5324800 events.
[08:38:19.724] <TB2> INFO: 1402960 events read in total (36734ms).
[08:38:55.891] <TB2> INFO: 2791000 events read in total (72901ms).
[08:39:32.441] <TB2> INFO: 4190800 events read in total (109451ms).
[08:40:01.442] <TB2> INFO: 5324800 events read in total (138452ms).
[08:40:01.472] <TB2> INFO: Test took 139297ms.
[08:40:01.964] <TB2> INFO: Missing Bumps: 0 0 0 36 0 0 1 6 0 0 1 0 0 0 0 2
[08:40:01.964] <TB2> INFO: Separation Cut: 32.12 25.86 24.20 23.18 26.80 23.39 25.56 20.16 29.25 28.26 33.49 31.96 23.21 28.69 19.87 26.06
[08:40:01.964] <TB2> INFO: PixTestBB2Map::doTest() done,177 seconds
[08:40:02.297] <TB2> INFO: enter test to run
[08:40:02.297] <TB2> INFO: test: no parameter change
[08:40:02.350] <TB2> QUIET: Connection to board 149 closed.
[08:40:02.432] <TB2> INFO: pXar: this is the end, my friend