[08:08:32.992] <TB3> INFO: *** Welcome to pxar ***
[08:08:32.992] <TB3> INFO: *** Today: 2016/08/16
[08:08:33.032] <TB3> INFO: *** Version: f5d3-dirty
[08:08:33.032] <TB3> INFO: readRocDacs: /home/Reception/DATA/DESYUHH/M4096_Reception_2016-08-16_10h05m_1471334753//000_ReceptionTestBB2_p17//dacParameters_C0.dat .. /home/Reception/DATA/DESYUHH/M4096_Reception_2016-08-16_10h05m_1471334753//000_ReceptionTestBB2_p17//dacParameters_C15.dat
[08:08:33.048] <TB3> INFO: readTbmDacs: /home/Reception/DATA/DESYUHH/M4096_Reception_2016-08-16_10h05m_1471334753//000_ReceptionTestBB2_p17//tbmParameters_C0a.dat .. /home/Reception/DATA/DESYUHH/M4096_Reception_2016-08-16_10h05m_1471334753//000_ReceptionTestBB2_p17//tbmParameters_C0b.dat
[08:08:33.050] <TB3> INFO: readMaskFile: /home/Reception/DATA/DESYUHH/M4096_Reception_2016-08-16_10h05m_1471334753//000_ReceptionTestBB2_p17//defaultMaskFile.dat
[08:08:33.051] <TB3> INFO: readTrimFile: /home/Reception/DATA/DESYUHH/M4096_Reception_2016-08-16_10h05m_1471334753//000_ReceptionTestBB2_p17//trimParameters_C0.dat .. /home/Reception/DATA/DESYUHH/M4096_Reception_2016-08-16_10h05m_1471334753//000_ReceptionTestBB2_p17//trimParameters_C15.dat
[08:08:33.166] <TB3> INFO: clk: 4
[08:08:33.166] <TB3> INFO: ctr: 4
[08:08:33.166] <TB3> INFO: sda: 19
[08:08:33.166] <TB3> INFO: tin: 9
[08:08:33.166] <TB3> INFO: level: 15
[08:08:33.166] <TB3> INFO: triggerdelay: 0
[08:08:33.166] <TB3> QUIET: Instanciating API for pxar v2.7.5+40~g4fce89b
[08:08:33.167] <TB3> INFO: Log level: INFO
[08:08:33.175] <TB3> INFO: Found DTB DTB_WZ4I6J
[08:08:33.184] <TB3> QUIET: Connection to board DTB_WZ4I6J opened.
[08:08:33.186] <TB3> INFO: DTB startup information
--- DTB info------------------------------------------
Board id: 170
HW version: DTB1.2
FW version: 4.4
SW version: 4.6
Options:
USB id: DTB_WZ4I6J
MAC address: 40D8551180AA
Hostname: pixelDTB170
Comment:
------------------------------------------------------
[08:08:33.188] <TB3> INFO: RPC call hashes of host and DTB match: 484264910
[08:08:34.712] <TB3> INFO: DUT info:
[08:08:34.712] <TB3> INFO: The DUT currently contains the following objects:
[08:08:34.712] <TB3> INFO: 2 TBM Cores tbm08c (2 ON)
[08:08:34.712] <TB3> INFO: TBM Core alpha (0): 7 registers set
[08:08:34.712] <TB3> INFO: TBM Core beta (1): 7 registers set
[08:08:34.712] <TB3> INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[08:08:34.712] <TB3> INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[08:08:34.712] <TB3> INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[08:08:34.712] <TB3> INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[08:08:34.712] <TB3> INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[08:08:34.712] <TB3> INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[08:08:34.712] <TB3> INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[08:08:34.712] <TB3> INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[08:08:34.712] <TB3> INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[08:08:34.712] <TB3> INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[08:08:34.712] <TB3> INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[08:08:34.712] <TB3> INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[08:08:34.712] <TB3> INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[08:08:34.713] <TB3> INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[08:08:34.713] <TB3> INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[08:08:34.713] <TB3> INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[08:08:34.713] <TB3> INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[08:08:35.115] <TB3> INFO: enter 'restricted' command line mode
[08:08:35.115] <TB3> INFO: enter test to run
[08:08:35.115] <TB3> INFO: test: pretest no parameter change
[08:08:35.115] <TB3> INFO: running: pretest
[08:08:35.124] <TB3> INFO: ----------------------------------------------------------------------
[08:08:35.124] <TB3> INFO: PixTestPretest::programROC()
[08:08:35.124] <TB3> INFO: ----------------------------------------------------------------------
[08:08:53.143] <TB3> INFO: PixTestPretest::programROC() done: ROCs are all programmable
[08:08:53.143] <TB3> INFO: IA differences per ROC: 18.5 20.9 19.3 18.5 20.9 19.3 20.9 20.9 21.7 19.3 17.7 20.9 19.3 20.9 20.1 20.1
[08:08:53.267] <TB3> INFO: enter test to run
[08:08:53.267] <TB3> INFO: test: pretest no parameter change
[08:08:53.267] <TB3> INFO: running: pretest
[08:08:53.269] <TB3> INFO: ----------------------------------------------------------------------
[08:08:53.269] <TB3> INFO: PixTestPretest::setVana() target Ia = 24 mA/ROC
[08:08:53.269] <TB3> INFO: ----------------------------------------------------------------------
[08:08:58.678] <TB3> INFO: PixTestPretest::setVana() done, Module Ia 392.3 mA = 24.5187 mA/ROC
[08:08:58.679] <TB3> INFO: i(loss) [mA/ROC]: 20.1 20.1 19.3 20.1 20.1 19.3 19.3 20.1 19.3 18.5 19.3 20.1 20.1 19.3 19.3 19.3
[08:08:58.725] <TB3> INFO: enter test to run
[08:08:58.725] <TB3> INFO: test: pretest no parameter change
[08:08:58.725] <TB3> INFO: running: pretest
[08:08:58.725] <TB3> INFO: ----------------------------------------------------------------------
[08:08:58.725] <TB3> INFO: PixTestPretest::findTiming()
[08:08:58.725] <TB3> INFO: ----------------------------------------------------------------------
[08:08:58.725] <TB3> INFO: PixTestCmd::init()
[08:08:59.543] <TB3> WARNING: Not unmasking DUT, not setting Calibrate bits!
[08:09:59.469] <TB3> INFO: TBM phases: 160MHz: 7, 400MHz: 6, TBM delays: ROC(0/1):3, header/trailer: 1, token: 1
[08:09:59.470] <TB3> INFO: (success/tries = 100/100), width = 5
[08:09:59.472] <TB3> INFO: enter test to run
[08:09:59.472] <TB3> INFO: test: pretest no parameter change
[08:09:59.472] <TB3> INFO: running: pretest
[08:09:59.473] <TB3> INFO: ----------------------------------------------------------------------
[08:09:59.473] <TB3> INFO: PixTestPretest::findWorkingPixel()
[08:09:59.473] <TB3> INFO: ----------------------------------------------------------------------
[08:09:59.564] <TB3> INFO: Expecting 231680 events.
[08:10:05.241] <TB3> WARNING: Detected DESER400 trailer error bits: "CODE ERROR"
[08:10:08.628] <TB3> INFO: 231680 events read in total (8508ms).
[08:10:08.632] <TB3> INFO: Test took 9157ms.
[08:10:08.923] <TB3> INFO: Found working pixel in all ROCs: col/row = 12/22
[08:10:09.004] <TB3> INFO: enter test to run
[08:10:09.006] <TB3> INFO: test: pretest no parameter change
[08:10:09.006] <TB3> INFO: running: pretest
[08:10:09.007] <TB3> INFO: ----------------------------------------------------------------------
[08:10:09.007] <TB3> INFO: PixTestPretest::setVthrCompCalDel()
[08:10:09.007] <TB3> INFO: ----------------------------------------------------------------------
[08:10:09.102] <TB3> INFO: Expecting 231680 events.
[08:10:18.265] <TB3> INFO: 231680 events read in total (8607ms).
[08:10:18.269] <TB3> INFO: Test took 9256ms.
[08:10:18.601] <TB3> INFO: PixTestPretest::setVthrCompCalDel() done
[08:10:18.601] <TB3> INFO: CalDel: 145 132 140 118 129 138 127 134 147 135 143 133 109 131 120 126
[08:10:18.601] <TB3> INFO: VthrComp: 51 51 51 51 51 51 51 51 51 51 51 51 51 51 51 51
[08:10:18.635] <TB3> INFO: enter test to run
[08:10:18.635] <TB3> INFO: test: pretest no parameter change
[08:10:18.635] <TB3> INFO: running: pretest
[08:10:18.665] <TB3> INFO: write dac parameters into /home/Reception/DATA/DESYUHH/M4096_Reception_2016-08-16_10h05m_1471334753//000_ReceptionTestBB2_p17//dacParameters_C0.dat
[08:10:18.671] <TB3> INFO: write dac parameters into /home/Reception/DATA/DESYUHH/M4096_Reception_2016-08-16_10h05m_1471334753//000_ReceptionTestBB2_p17//dacParameters_C1.dat
[08:10:18.677] <TB3> INFO: write dac parameters into /home/Reception/DATA/DESYUHH/M4096_Reception_2016-08-16_10h05m_1471334753//000_ReceptionTestBB2_p17//dacParameters_C2.dat
[08:10:18.683] <TB3> INFO: write dac parameters into /home/Reception/DATA/DESYUHH/M4096_Reception_2016-08-16_10h05m_1471334753//000_ReceptionTestBB2_p17//dacParameters_C3.dat
[08:10:18.688] <TB3> INFO: write dac parameters into /home/Reception/DATA/DESYUHH/M4096_Reception_2016-08-16_10h05m_1471334753//000_ReceptionTestBB2_p17//dacParameters_C4.dat
[08:10:18.694] <TB3> INFO: write dac parameters into /home/Reception/DATA/DESYUHH/M4096_Reception_2016-08-16_10h05m_1471334753//000_ReceptionTestBB2_p17//dacParameters_C5.dat
[08:10:18.701] <TB3> INFO: write dac parameters into /home/Reception/DATA/DESYUHH/M4096_Reception_2016-08-16_10h05m_1471334753//000_ReceptionTestBB2_p17//dacParameters_C6.dat
[08:10:18.706] <TB3> INFO: write dac parameters into /home/Reception/DATA/DESYUHH/M4096_Reception_2016-08-16_10h05m_1471334753//000_ReceptionTestBB2_p17//dacParameters_C7.dat
[08:10:18.711] <TB3> INFO: write dac parameters into /home/Reception/DATA/DESYUHH/M4096_Reception_2016-08-16_10h05m_1471334753//000_ReceptionTestBB2_p17//dacParameters_C8.dat
[08:10:18.716] <TB3> INFO: write dac parameters into /home/Reception/DATA/DESYUHH/M4096_Reception_2016-08-16_10h05m_1471334753//000_ReceptionTestBB2_p17//dacParameters_C9.dat
[08:10:18.723] <TB3> INFO: write dac parameters into /home/Reception/DATA/DESYUHH/M4096_Reception_2016-08-16_10h05m_1471334753//000_ReceptionTestBB2_p17//dacParameters_C10.dat
[08:10:18.729] <TB3> INFO: write dac parameters into /home/Reception/DATA/DESYUHH/M4096_Reception_2016-08-16_10h05m_1471334753//000_ReceptionTestBB2_p17//dacParameters_C11.dat
[08:10:18.736] <TB3> INFO: write dac parameters into /home/Reception/DATA/DESYUHH/M4096_Reception_2016-08-16_10h05m_1471334753//000_ReceptionTestBB2_p17//dacParameters_C12.dat
[08:10:18.744] <TB3> INFO: write dac parameters into /home/Reception/DATA/DESYUHH/M4096_Reception_2016-08-16_10h05m_1471334753//000_ReceptionTestBB2_p17//dacParameters_C13.dat
[08:10:18.750] <TB3> INFO: write dac parameters into /home/Reception/DATA/DESYUHH/M4096_Reception_2016-08-16_10h05m_1471334753//000_ReceptionTestBB2_p17//dacParameters_C14.dat
[08:10:18.755] <TB3> INFO: write dac parameters into /home/Reception/DATA/DESYUHH/M4096_Reception_2016-08-16_10h05m_1471334753//000_ReceptionTestBB2_p17//dacParameters_C15.dat
[08:10:18.759] <TB3> INFO: enter test to run
[08:10:18.759] <TB3> INFO: test: PixelAlive no parameter change
[08:10:18.759] <TB3> INFO: running: pixelalive
[08:10:18.760] <TB3> INFO: ######################################################################
[08:10:18.760] <TB3> INFO: PixTestAlive::doTest()
[08:10:18.760] <TB3> INFO: ######################################################################
[08:10:18.761] <TB3> INFO: ----------------------------------------------------------------------
[08:10:18.761] <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)
[08:10:18.761] <TB3> INFO: ----------------------------------------------------------------------
[08:10:19.030] <TB3> INFO: Expecting 41600 events.
[08:10:22.480] <TB3> INFO: 41600 events read in total (2894ms).
[08:10:22.481] <TB3> INFO: Test took 3718ms.
[08:10:22.789] <TB3> INFO: PixTestAlive::aliveTest() done
[08:10:22.789] <TB3> INFO: number of dead pixels (per ROC): 0 0 0 0 0 0 0 0 1 0 0 0 0 0 0 0
[08:10:22.791] <TB3> INFO: ----------------------------------------------------------------------
[08:10:22.791] <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)
[08:10:22.791] <TB3> INFO: ----------------------------------------------------------------------
[08:10:23.074] <TB3> INFO: Expecting 41600 events.
[08:10:25.716] <TB3> INFO: 41600 events read in total (2086ms).
[08:10:25.716] <TB3> INFO: Test took 2923ms.
[08:10:25.717] <TB3> INFO: mask vs. old pixelAlive PixelAlive_C0_V0 .. PixelAlive_C15_V0
[08:10:26.041] <TB3> INFO: PixTestAlive::maskTest() done
[08:10:26.041] <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
[08:10:26.042] <TB3> INFO: ----------------------------------------------------------------------
[08:10:26.042] <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)
[08:10:26.042] <TB3> INFO: ----------------------------------------------------------------------
[08:10:26.320] <TB3> INFO: Expecting 41600 events.
[08:10:29.804] <TB3> INFO: 41600 events read in total (2928ms).
[08:10:29.805] <TB3> INFO: Test took 3760ms.
[08:10:30.116] <TB3> INFO: PixTestAlive::addressDecodingTest() done
[08:10:30.116] <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
[08:10:30.116] <TB3> INFO: PixTestAlive::doTest() done, duration: 11 seconds
[08:10:30.124] <TB3> INFO: enter test to run
[08:10:30.124] <TB3> INFO: test: BB no parameter change
[08:10:30.124] <TB3> INFO: running: bb
[08:10:30.126] <TB3> INFO: ######################################################################
[08:10:30.126] <TB3> INFO: PixTestBBMap::doTest() Ntrig = 5, VcalS = 250 (high range)
[08:10:30.126] <TB3> INFO: ######################################################################
[08:10:30.130] <TB3> INFO: ---> dac: VthrComp name: calSMap ntrig: 5 dacrange: 0 .. 149 (-1/-1) hits flags = 514 (plus default)
[08:10:30.142] <TB3> INFO: dacScan split into 1 runs with ntrig = 5
[08:10:30.142] <TB3> INFO: run 1 of 1
[08:10:30.423] <TB3> INFO: Expecting 3120000 events.
[08:11:16.275] <TB3> INFO: 1217540 events read in total (45296ms).
[08:12:01.439] <TB3> INFO: 2411965 events read in total (90460ms).
[08:12:28.542] <TB3> INFO: 3120000 events read in total (117563ms).
[08:12:28.582] <TB3> INFO: Test took 118439ms.
[08:12:53.761] <TB3> INFO: PixTestBBMap::doTest() done, duration: 143 seconds
[08:12:53.762] <TB3> INFO: number of dead bumps (per ROC): 0 0 0 0 0 0 4 3 1 0 0 0 0 0 0 1
[08:12:53.762] <TB3> INFO: separation cut (per ROC): 126 130 116 125 125 121 131 143 127 117 107 127 123 113 104 117
[08:12:53.840] <TB3> INFO: enter test to run
[08:12:53.840] <TB3> INFO: test: Readback no parameter change
[08:12:53.840] <TB3> INFO: running: readback
[08:12:53.840] <TB3> INFO: readReadbackCal: /home/Reception/DATA/DESYUHH/M4096_Reception_2016-08-16_10h05m_1471334753//000_ReceptionTestBB2_p17//readbackCal_C0.dat .. /home/Reception/DATA/DESYUHH/M4096_Reception_2016-08-16_10h05m_1471334753//000_ReceptionTestBB2_p17//readbackCal_C15.dat
[08:12:53.858] <TB3> INFO: readGainPedestalParameters /home/Reception/DATA/DESYUHH/M4096_Reception_2016-08-16_10h05m_1471334753//000_ReceptionTestBB2_p17//phCalibrationFitErr_C0.dat .. /home/Reception/DATA/DESYUHH/M4096_Reception_2016-08-16_10h05m_1471334753//000_ReceptionTestBB2_p17//phCalibrationFitErr_C15.dat
[08:12:53.859] <TB3> ERROR: <ConfigParameters.cc/readGainPedestalParameters:L996> cannot open /home/Reception/DATA/DESYUHH/M4096_Reception_2016-08-16_10h05m_1471334753//000_ReceptionTestBB2_p17//phCalibrationFitErr_C0.dat for reading PH calibration constants
[08:12:53.859] <TB3> INFO: ######################################################################
[08:12:53.859] <TB3> INFO: PixTestReadback::doTest()
[08:12:53.859] <TB3> INFO: ######################################################################
[08:12:53.859] <TB3> INFO: ----------------------------------------------------------------------
[08:12:53.859] <TB3> INFO: PixTestReadback::CalibrateVd()
[08:12:53.859] <TB3> INFO: ----------------------------------------------------------------------
[08:13:03.194] <TB3> INFO: write readback calibration parameters into /home/Reception/DATA/DESYUHH/M4096_Reception_2016-08-16_10h05m_1471334753//000_ReceptionTestBB2_p17//readbackCal_C0.dat
[08:13:03.201] <TB3> INFO: write readback calibration parameters into /home/Reception/DATA/DESYUHH/M4096_Reception_2016-08-16_10h05m_1471334753//000_ReceptionTestBB2_p17//readbackCal_C1.dat
[08:13:03.206] <TB3> INFO: write readback calibration parameters into /home/Reception/DATA/DESYUHH/M4096_Reception_2016-08-16_10h05m_1471334753//000_ReceptionTestBB2_p17//readbackCal_C2.dat
[08:13:03.212] <TB3> INFO: write readback calibration parameters into /home/Reception/DATA/DESYUHH/M4096_Reception_2016-08-16_10h05m_1471334753//000_ReceptionTestBB2_p17//readbackCal_C3.dat
[08:13:03.219] <TB3> INFO: write readback calibration parameters into /home/Reception/DATA/DESYUHH/M4096_Reception_2016-08-16_10h05m_1471334753//000_ReceptionTestBB2_p17//readbackCal_C4.dat
[08:13:03.225] <TB3> INFO: write readback calibration parameters into /home/Reception/DATA/DESYUHH/M4096_Reception_2016-08-16_10h05m_1471334753//000_ReceptionTestBB2_p17//readbackCal_C5.dat
[08:13:03.230] <TB3> INFO: write readback calibration parameters into /home/Reception/DATA/DESYUHH/M4096_Reception_2016-08-16_10h05m_1471334753//000_ReceptionTestBB2_p17//readbackCal_C6.dat
[08:13:03.235] <TB3> INFO: write readback calibration parameters into /home/Reception/DATA/DESYUHH/M4096_Reception_2016-08-16_10h05m_1471334753//000_ReceptionTestBB2_p17//readbackCal_C7.dat
[08:13:03.240] <TB3> INFO: write readback calibration parameters into /home/Reception/DATA/DESYUHH/M4096_Reception_2016-08-16_10h05m_1471334753//000_ReceptionTestBB2_p17//readbackCal_C8.dat
[08:13:03.248] <TB3> INFO: write readback calibration parameters into /home/Reception/DATA/DESYUHH/M4096_Reception_2016-08-16_10h05m_1471334753//000_ReceptionTestBB2_p17//readbackCal_C9.dat
[08:13:03.254] <TB3> INFO: write readback calibration parameters into /home/Reception/DATA/DESYUHH/M4096_Reception_2016-08-16_10h05m_1471334753//000_ReceptionTestBB2_p17//readbackCal_C10.dat
[08:13:03.259] <TB3> INFO: write readback calibration parameters into /home/Reception/DATA/DESYUHH/M4096_Reception_2016-08-16_10h05m_1471334753//000_ReceptionTestBB2_p17//readbackCal_C11.dat
[08:13:03.266] <TB3> INFO: write readback calibration parameters into /home/Reception/DATA/DESYUHH/M4096_Reception_2016-08-16_10h05m_1471334753//000_ReceptionTestBB2_p17//readbackCal_C12.dat
[08:13:03.274] <TB3> INFO: write readback calibration parameters into /home/Reception/DATA/DESYUHH/M4096_Reception_2016-08-16_10h05m_1471334753//000_ReceptionTestBB2_p17//readbackCal_C13.dat
[08:13:03.280] <TB3> INFO: write readback calibration parameters into /home/Reception/DATA/DESYUHH/M4096_Reception_2016-08-16_10h05m_1471334753//000_ReceptionTestBB2_p17//readbackCal_C14.dat
[08:13:03.286] <TB3> INFO: write readback calibration parameters into /home/Reception/DATA/DESYUHH/M4096_Reception_2016-08-16_10h05m_1471334753//000_ReceptionTestBB2_p17//readbackCal_C15.dat
[08:13:03.335] <TB3> INFO: PixTestPattern:: pg_setup set to default.
[08:13:03.335] <TB3> INFO: ----------------------------------------------------------------------
[08:13:03.335] <TB3> INFO: PixTestReadback::CalibrateVa()
[08:13:03.335] <TB3> INFO: ----------------------------------------------------------------------
[08:13:12.750] <TB3> INFO: write readback calibration parameters into /home/Reception/DATA/DESYUHH/M4096_Reception_2016-08-16_10h05m_1471334753//000_ReceptionTestBB2_p17//readbackCal_C0.dat
[08:13:12.755] <TB3> INFO: write readback calibration parameters into /home/Reception/DATA/DESYUHH/M4096_Reception_2016-08-16_10h05m_1471334753//000_ReceptionTestBB2_p17//readbackCal_C1.dat
[08:13:12.760] <TB3> INFO: write readback calibration parameters into /home/Reception/DATA/DESYUHH/M4096_Reception_2016-08-16_10h05m_1471334753//000_ReceptionTestBB2_p17//readbackCal_C2.dat
[08:13:12.768] <TB3> INFO: write readback calibration parameters into /home/Reception/DATA/DESYUHH/M4096_Reception_2016-08-16_10h05m_1471334753//000_ReceptionTestBB2_p17//readbackCal_C3.dat
[08:13:12.773] <TB3> INFO: write readback calibration parameters into /home/Reception/DATA/DESYUHH/M4096_Reception_2016-08-16_10h05m_1471334753//000_ReceptionTestBB2_p17//readbackCal_C4.dat
[08:13:12.778] <TB3> INFO: write readback calibration parameters into /home/Reception/DATA/DESYUHH/M4096_Reception_2016-08-16_10h05m_1471334753//000_ReceptionTestBB2_p17//readbackCal_C5.dat
[08:13:12.785] <TB3> INFO: write readback calibration parameters into /home/Reception/DATA/DESYUHH/M4096_Reception_2016-08-16_10h05m_1471334753//000_ReceptionTestBB2_p17//readbackCal_C6.dat
[08:13:12.790] <TB3> INFO: write readback calibration parameters into /home/Reception/DATA/DESYUHH/M4096_Reception_2016-08-16_10h05m_1471334753//000_ReceptionTestBB2_p17//readbackCal_C7.dat
[08:13:12.797] <TB3> INFO: write readback calibration parameters into /home/Reception/DATA/DESYUHH/M4096_Reception_2016-08-16_10h05m_1471334753//000_ReceptionTestBB2_p17//readbackCal_C8.dat
[08:13:12.804] <TB3> INFO: write readback calibration parameters into /home/Reception/DATA/DESYUHH/M4096_Reception_2016-08-16_10h05m_1471334753//000_ReceptionTestBB2_p17//readbackCal_C9.dat
[08:13:12.809] <TB3> INFO: write readback calibration parameters into /home/Reception/DATA/DESYUHH/M4096_Reception_2016-08-16_10h05m_1471334753//000_ReceptionTestBB2_p17//readbackCal_C10.dat
[08:13:12.815] <TB3> INFO: write readback calibration parameters into /home/Reception/DATA/DESYUHH/M4096_Reception_2016-08-16_10h05m_1471334753//000_ReceptionTestBB2_p17//readbackCal_C11.dat
[08:13:12.820] <TB3> INFO: write readback calibration parameters into /home/Reception/DATA/DESYUHH/M4096_Reception_2016-08-16_10h05m_1471334753//000_ReceptionTestBB2_p17//readbackCal_C12.dat
[08:13:12.825] <TB3> INFO: write readback calibration parameters into /home/Reception/DATA/DESYUHH/M4096_Reception_2016-08-16_10h05m_1471334753//000_ReceptionTestBB2_p17//readbackCal_C13.dat
[08:13:12.831] <TB3> INFO: write readback calibration parameters into /home/Reception/DATA/DESYUHH/M4096_Reception_2016-08-16_10h05m_1471334753//000_ReceptionTestBB2_p17//readbackCal_C14.dat
[08:13:12.837] <TB3> INFO: write readback calibration parameters into /home/Reception/DATA/DESYUHH/M4096_Reception_2016-08-16_10h05m_1471334753//000_ReceptionTestBB2_p17//readbackCal_C15.dat
[08:13:12.888] <TB3> INFO: PixTestPattern:: pg_setup set to default.
[08:13:12.888] <TB3> INFO: ----------------------------------------------------------------------
[08:13:12.888] <TB3> INFO: PixTestReadback::readbackVbg()
[08:13:12.888] <TB3> INFO: ----------------------------------------------------------------------
[08:13:20.067] <TB3> INFO: PixTestPattern:: pg_setup set to default.
[08:13:20.068] <TB3> INFO: ----------------------------------------------------------------------
[08:13:20.068] <TB3> INFO: PixTestReadback::getCalibratedVbg()
[08:13:20.068] <TB3> INFO: ----------------------------------------------------------------------
[08:13:20.068] <TB3> INFO: Vbg will be calibrated using Vd calibration
[08:13:20.068] <TB3> INFO: /*/*/*/*::: ROC 0: uncalibrated Vbg = 150.8calibrated Vbg = 1.22725 :::*/*/*/*/
[08:13:20.068] <TB3> INFO: /*/*/*/*::: ROC 1: uncalibrated Vbg = 151.7calibrated Vbg = 1.22466 :::*/*/*/*/
[08:13:20.068] <TB3> INFO: /*/*/*/*::: ROC 2: uncalibrated Vbg = 152.8calibrated Vbg = 1.22867 :::*/*/*/*/
[08:13:20.068] <TB3> INFO: /*/*/*/*::: ROC 3: uncalibrated Vbg = 162calibrated Vbg = 1.23912 :::*/*/*/*/
[08:13:20.068] <TB3> INFO: /*/*/*/*::: ROC 4: uncalibrated Vbg = 149.5calibrated Vbg = 1.23175 :::*/*/*/*/
[08:13:20.068] <TB3> INFO: /*/*/*/*::: ROC 5: uncalibrated Vbg = 144.5calibrated Vbg = 1.23809 :::*/*/*/*/
[08:13:20.068] <TB3> INFO: /*/*/*/*::: ROC 6: uncalibrated Vbg = 150.7calibrated Vbg = 1.23857 :::*/*/*/*/
[08:13:20.068] <TB3> INFO: /*/*/*/*::: ROC 7: uncalibrated Vbg = 141.3calibrated Vbg = 1.23024 :::*/*/*/*/
[08:13:20.068] <TB3> INFO: /*/*/*/*::: ROC 8: uncalibrated Vbg = 149.5calibrated Vbg = 1.2412 :::*/*/*/*/
[08:13:20.068] <TB3> INFO: /*/*/*/*::: ROC 9: uncalibrated Vbg = 145.5calibrated Vbg = 1.23529 :::*/*/*/*/
[08:13:20.068] <TB3> INFO: /*/*/*/*::: ROC 10: uncalibrated Vbg = 157.2calibrated Vbg = 1.22743 :::*/*/*/*/
[08:13:20.068] <TB3> INFO: /*/*/*/*::: ROC 11: uncalibrated Vbg = 162.4calibrated Vbg = 1.22587 :::*/*/*/*/
[08:13:20.068] <TB3> INFO: /*/*/*/*::: ROC 12: uncalibrated Vbg = 154.5calibrated Vbg = 1.21892 :::*/*/*/*/
[08:13:20.068] <TB3> INFO: /*/*/*/*::: ROC 13: uncalibrated Vbg = 154.8calibrated Vbg = 1.22454 :::*/*/*/*/
[08:13:20.068] <TB3> INFO: /*/*/*/*::: ROC 14: uncalibrated Vbg = 155.6calibrated Vbg = 1.22266 :::*/*/*/*/
[08:13:20.068] <TB3> INFO: /*/*/*/*::: ROC 15: uncalibrated Vbg = 154calibrated Vbg = 1.21867 :::*/*/*/*/
[08:13:20.071] <TB3> INFO: ----------------------------------------------------------------------
[08:13:20.071] <TB3> INFO: PixTestReadback::CalibrateIa()
[08:13:20.071] <TB3> INFO: ----------------------------------------------------------------------
[08:15:53.526] <TB3> INFO: write readback calibration parameters into /home/Reception/DATA/DESYUHH/M4096_Reception_2016-08-16_10h05m_1471334753//000_ReceptionTestBB2_p17//readbackCal_C0.dat
[08:15:53.532] <TB3> INFO: write readback calibration parameters into /home/Reception/DATA/DESYUHH/M4096_Reception_2016-08-16_10h05m_1471334753//000_ReceptionTestBB2_p17//readbackCal_C1.dat
[08:15:53.537] <TB3> INFO: write readback calibration parameters into /home/Reception/DATA/DESYUHH/M4096_Reception_2016-08-16_10h05m_1471334753//000_ReceptionTestBB2_p17//readbackCal_C2.dat
[08:15:53.542] <TB3> INFO: write readback calibration parameters into /home/Reception/DATA/DESYUHH/M4096_Reception_2016-08-16_10h05m_1471334753//000_ReceptionTestBB2_p17//readbackCal_C3.dat
[08:15:53.547] <TB3> INFO: write readback calibration parameters into /home/Reception/DATA/DESYUHH/M4096_Reception_2016-08-16_10h05m_1471334753//000_ReceptionTestBB2_p17//readbackCal_C4.dat
[08:15:53.552] <TB3> INFO: write readback calibration parameters into /home/Reception/DATA/DESYUHH/M4096_Reception_2016-08-16_10h05m_1471334753//000_ReceptionTestBB2_p17//readbackCal_C5.dat
[08:15:53.557] <TB3> INFO: write readback calibration parameters into /home/Reception/DATA/DESYUHH/M4096_Reception_2016-08-16_10h05m_1471334753//000_ReceptionTestBB2_p17//readbackCal_C6.dat
[08:15:53.562] <TB3> INFO: write readback calibration parameters into /home/Reception/DATA/DESYUHH/M4096_Reception_2016-08-16_10h05m_1471334753//000_ReceptionTestBB2_p17//readbackCal_C7.dat
[08:15:53.567] <TB3> INFO: write readback calibration parameters into /home/Reception/DATA/DESYUHH/M4096_Reception_2016-08-16_10h05m_1471334753//000_ReceptionTestBB2_p17//readbackCal_C8.dat
[08:15:53.571] <TB3> INFO: write readback calibration parameters into /home/Reception/DATA/DESYUHH/M4096_Reception_2016-08-16_10h05m_1471334753//000_ReceptionTestBB2_p17//readbackCal_C9.dat
[08:15:53.577] <TB3> INFO: write readback calibration parameters into /home/Reception/DATA/DESYUHH/M4096_Reception_2016-08-16_10h05m_1471334753//000_ReceptionTestBB2_p17//readbackCal_C10.dat
[08:15:53.582] <TB3> INFO: write readback calibration parameters into /home/Reception/DATA/DESYUHH/M4096_Reception_2016-08-16_10h05m_1471334753//000_ReceptionTestBB2_p17//readbackCal_C11.dat
[08:15:53.587] <TB3> INFO: write readback calibration parameters into /home/Reception/DATA/DESYUHH/M4096_Reception_2016-08-16_10h05m_1471334753//000_ReceptionTestBB2_p17//readbackCal_C12.dat
[08:15:53.592] <TB3> INFO: write readback calibration parameters into /home/Reception/DATA/DESYUHH/M4096_Reception_2016-08-16_10h05m_1471334753//000_ReceptionTestBB2_p17//readbackCal_C13.dat
[08:15:53.597] <TB3> INFO: write readback calibration parameters into /home/Reception/DATA/DESYUHH/M4096_Reception_2016-08-16_10h05m_1471334753//000_ReceptionTestBB2_p17//readbackCal_C14.dat
[08:15:53.602] <TB3> INFO: write readback calibration parameters into /home/Reception/DATA/DESYUHH/M4096_Reception_2016-08-16_10h05m_1471334753//000_ReceptionTestBB2_p17//readbackCal_C15.dat
[08:15:53.650] <TB3> INFO: PixTestPattern:: pg_setup set to default.
[08:15:53.652] <TB3> INFO: PixTestReadback::doTest() done
[08:15:53.680] <TB3> INFO: enter test to run
[08:15:53.680] <TB3> INFO: test: BB2 no parameter change
[08:15:53.680] <TB3> INFO: running: bb2
[08:15:53.681] <TB3> INFO: ######################################################################
[08:15:53.681] <TB3> INFO: PixTestBB2Map::doTest() Ntrig = 10, VcalS = 222, PlWidth = 35
[08:15:53.681] <TB3> INFO: ######################################################################
[08:15:53.682] <TB3> INFO: ----------------------------------------------------------------------
[08:15:53.682] <TB3> INFO: PixTestBB2Map::setVana() target Ia = 24 mA/ROC
[08:15:53.682] <TB3> INFO: ----------------------------------------------------------------------
[08:15:57.170] <TB3> INFO: PixTestBB2Map::setVana() done, Module Ia 386.7 mA = 24.1687 mA/ROC
[08:15:57.172] <TB3> INFO: ----------------------------------------------------------------------
[08:15:57.172] <TB3> INFO: PixTestBB2Map::setVthrCompCalDel()
[08:15:57.172] <TB3> INFO: ----------------------------------------------------------------------
[08:15:57.271] <TB3> INFO: Expecting 655360 events.
[08:16:07.194] <TB3> WARNING: Detected DESER400 trailer error bits: "CODE ERROR"
[08:16:15.284] <TB3> INFO: 655360 events read in total (17457ms).
[08:16:15.288] <TB3> INFO: Test took 18105ms.
[08:16:15.592] <TB3> INFO: PixTestBB2Map::setVthrCompCalDel() done
[08:16:15.592] <TB3> INFO: CalDel: 142 130 135 115 126 131 122 129 143 129 136 129 105 128 115 122
[08:16:15.592] <TB3> INFO: VthrComp: 101 109 93 104 102 100 102 103 95 104 86 96 102 95 94 93
[08:16:15.874] <TB3> INFO: Expecting 5324800 events.
[08:16:52.672] <TB3> INFO: 1401120 events read in total (36242ms).
[08:17:28.903] <TB3> INFO: 2784370 events read in total (72474ms).
[08:18:05.261] <TB3> INFO: 4177640 events read in total (108831ms).
[08:18:34.710] <TB3> INFO: 5324800 events read in total (138280ms).
[08:18:34.738] <TB3> INFO: Test took 139132ms.
[08:18:35.243] <TB3> INFO: Missing Bumps: 0 7 0 0 0 0 36 37 1 0 0 0 2 0 0 2
[08:18:35.243] <TB3> INFO: Separation Cut: 25.83 36.09 25.69 28.35 28.01 28.32 23.28 30.54 27.90 27.42 26.46 22.42 25.37 27.08 26.10 28.59
[08:18:35.243] <TB3> INFO: PixTestBB2Map::doTest() done,161 seconds
[08:18:35.574] <TB3> INFO: enter test to run
[08:18:35.574] <TB3> INFO: test: no parameter change
[08:18:35.639] <TB3> QUIET: Connection to board 170 closed.
[08:18:35.722] <TB3> INFO: pXar: this is the end, my friend