[15:17:57.417] <TB1> INFO: *** Welcome to pxar ***
[15:17:57.417] <TB1> INFO: *** Today: 2016/08/16
[15:17:57.482] <TB1> INFO: *** Version: f5d3-dirty
[15:17:57.482] <TB1> INFO: readRocDacs: /home/Reception/DATA/DESYUHH/M4204_Reception_2016-08-16_17h15m_1471360531//000_ReceptionTestBB2_p17//dacParameters_C0.dat .. /home/Reception/DATA/DESYUHH/M4204_Reception_2016-08-16_17h15m_1471360531//000_ReceptionTestBB2_p17//dacParameters_C15.dat
[15:17:57.688] <TB1> INFO: readTbmDacs: /home/Reception/DATA/DESYUHH/M4204_Reception_2016-08-16_17h15m_1471360531//000_ReceptionTestBB2_p17//tbmParameters_C0a.dat .. /home/Reception/DATA/DESYUHH/M4204_Reception_2016-08-16_17h15m_1471360531//000_ReceptionTestBB2_p17//tbmParameters_C0b.dat
[15:17:57.690] <TB1> INFO: readMaskFile: /home/Reception/DATA/DESYUHH/M4204_Reception_2016-08-16_17h15m_1471360531//000_ReceptionTestBB2_p17//defaultMaskFile.dat
[15:17:57.692] <TB1> INFO: readTrimFile: /home/Reception/DATA/DESYUHH/M4204_Reception_2016-08-16_17h15m_1471360531//000_ReceptionTestBB2_p17//trimParameters_C0.dat .. /home/Reception/DATA/DESYUHH/M4204_Reception_2016-08-16_17h15m_1471360531//000_ReceptionTestBB2_p17//trimParameters_C15.dat
[15:17:58.071] <TB1> INFO: clk: 4
[15:17:58.071] <TB1> INFO: ctr: 4
[15:17:58.071] <TB1> INFO: sda: 19
[15:17:58.071] <TB1> INFO: tin: 9
[15:17:58.071] <TB1> INFO: level: 15
[15:17:58.071] <TB1> INFO: triggerdelay: 0
[15:17:58.071] <TB1> QUIET: Instanciating API for pxar v2.7.5+40~g4fce89b
[15:17:58.071] <TB1> INFO: Log level: INFO
[15:17:58.078] <TB1> INFO: Found DTB DTB_WXBYFL
[15:17:58.089] <TB1> QUIET: Connection to board DTB_WXBYFL opened.
[15:17:58.091] <TB1> INFO: DTB startup information
--- DTB info------------------------------------------
Board id: 153
HW version: DTB1.2
FW version: 4.4
SW version: 4.6
Options:
USB id: DTB_WXBYFL
MAC address: 40D855118099
Hostname: pixelDTB153
Comment:
------------------------------------------------------
[15:17:58.093] <TB1> INFO: RPC call hashes of host and DTB match: 484264910
[15:17:59.610] <TB1> INFO: DUT info:
[15:17:59.610] <TB1> INFO: The DUT currently contains the following objects:
[15:17:59.610] <TB1> INFO: 2 TBM Cores tbm08c (2 ON)
[15:17:59.610] <TB1> INFO: TBM Core alpha (0): 7 registers set
[15:17:59.610] <TB1> INFO: TBM Core beta (1): 7 registers set
[15:17:59.610] <TB1> INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[15:17:59.610] <TB1> INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[15:17:59.610] <TB1> INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[15:17:59.611] <TB1> INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[15:17:59.611] <TB1> INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[15:17:59.611] <TB1> INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[15:17:59.611] <TB1> INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[15:17:59.611] <TB1> INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[15:17:59.611] <TB1> INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[15:17:59.611] <TB1> INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[15:17:59.611] <TB1> INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[15:17:59.611] <TB1> INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[15:17:59.611] <TB1> INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[15:17:59.611] <TB1> INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[15:17:59.611] <TB1> INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[15:17:59.611] <TB1> INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[15:17:59.611] <TB1> INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[15:18:00.030] <TB1> INFO: enter 'restricted' command line mode
[15:18:00.030] <TB1> INFO: enter test to run
[15:18:00.030] <TB1> INFO: test: pretest no parameter change
[15:18:00.031] <TB1> INFO: running: pretest
[15:18:00.037] <TB1> INFO: ----------------------------------------------------------------------
[15:18:00.037] <TB1> INFO: PixTestPretest::programROC()
[15:18:00.037] <TB1> INFO: ----------------------------------------------------------------------
[15:18:18.053] <TB1> INFO: PixTestPretest::programROC() done: ROCs are all programmable
[15:18:18.054] <TB1> INFO: IA differences per ROC: 18.5 19.3 21.7 19.3 18.5 20.1 20.1 20.9 20.9 20.9 21.7 20.1 20.9 20.9 18.5 20.9
[15:18:18.152] <TB1> INFO: enter test to run
[15:18:18.152] <TB1> INFO: test: pretest no parameter change
[15:18:18.152] <TB1> INFO: running: pretest
[15:18:18.154] <TB1> INFO: ----------------------------------------------------------------------
[15:18:18.154] <TB1> INFO: PixTestPretest::setVana() target Ia = 24 mA/ROC
[15:18:18.154] <TB1> INFO: ----------------------------------------------------------------------
[15:18:39.446] <TB1> INFO: PixTestPretest::setVana() done, Module Ia 382.7 mA = 23.9187 mA/ROC
[15:18:39.447] <TB1> INFO: i(loss) [mA/ROC]: 19.3 19.3 18.5 19.3 19.3 19.3 18.5 18.5 18.5 18.5 18.5 19.3 18.5 19.3 17.7 19.3
[15:18:39.484] <TB1> INFO: enter test to run
[15:18:39.484] <TB1> INFO: test: pretest no parameter change
[15:18:39.484] <TB1> INFO: running: pretest
[15:18:39.486] <TB1> INFO: ----------------------------------------------------------------------
[15:18:39.486] <TB1> INFO: PixTestPretest::findWorkingPixel()
[15:18:39.486] <TB1> INFO: ----------------------------------------------------------------------
[15:18:39.584] <TB1> INFO: Expecting 231680 events.
[15:18:48.655] <TB1> INFO: 231680 events read in total (8514ms).
[15:18:48.756] <TB1> INFO: Test took 9263ms.
[15:18:48.959] <TB1> INFO: Found working pixel in all ROCs: col/row = 12/22
[15:18:49.014] <TB1> INFO: enter test to run
[15:18:49.014] <TB1> INFO: test: pretest no parameter change
[15:18:49.014] <TB1> INFO: running: pretest
[15:18:49.015] <TB1> INFO: ----------------------------------------------------------------------
[15:18:49.015] <TB1> INFO: PixTestPretest::setVthrCompCalDel()
[15:18:49.015] <TB1> INFO: ----------------------------------------------------------------------
[15:18:49.106] <TB1> INFO: Expecting 231680 events.
[15:18:58.166] <TB1> INFO: 231680 events read in total (8504ms).
[15:18:58.169] <TB1> INFO: Test took 9151ms.
[15:18:58.479] <TB1> INFO: PixTestPretest::setVthrCompCalDel() done
[15:18:58.479] <TB1> INFO: CalDel: 126 143 115 125 122 132 140 123 115 123 144 146 137 144 142 133
[15:18:58.479] <TB1> INFO: VthrComp: 51 51 51 51 51 51 51 51 51 51 51 51 51 51 51 53
[15:18:58.513] <TB1> INFO: enter test to run
[15:18:58.513] <TB1> INFO: test: pretest no parameter change
[15:18:58.513] <TB1> INFO: running: pretest
[15:18:58.533] <TB1> INFO: write dac parameters into /home/Reception/DATA/DESYUHH/M4204_Reception_2016-08-16_17h15m_1471360531//000_ReceptionTestBB2_p17//dacParameters_C0.dat
[15:18:58.545] <TB1> INFO: write dac parameters into /home/Reception/DATA/DESYUHH/M4204_Reception_2016-08-16_17h15m_1471360531//000_ReceptionTestBB2_p17//dacParameters_C1.dat
[15:18:58.583] <TB1> INFO: write dac parameters into /home/Reception/DATA/DESYUHH/M4204_Reception_2016-08-16_17h15m_1471360531//000_ReceptionTestBB2_p17//dacParameters_C2.dat
[15:18:58.590] <TB1> INFO: write dac parameters into /home/Reception/DATA/DESYUHH/M4204_Reception_2016-08-16_17h15m_1471360531//000_ReceptionTestBB2_p17//dacParameters_C3.dat
[15:18:58.596] <TB1> INFO: write dac parameters into /home/Reception/DATA/DESYUHH/M4204_Reception_2016-08-16_17h15m_1471360531//000_ReceptionTestBB2_p17//dacParameters_C4.dat
[15:18:58.601] <TB1> INFO: write dac parameters into /home/Reception/DATA/DESYUHH/M4204_Reception_2016-08-16_17h15m_1471360531//000_ReceptionTestBB2_p17//dacParameters_C5.dat
[15:18:58.608] <TB1> INFO: write dac parameters into /home/Reception/DATA/DESYUHH/M4204_Reception_2016-08-16_17h15m_1471360531//000_ReceptionTestBB2_p17//dacParameters_C6.dat
[15:18:58.613] <TB1> INFO: write dac parameters into /home/Reception/DATA/DESYUHH/M4204_Reception_2016-08-16_17h15m_1471360531//000_ReceptionTestBB2_p17//dacParameters_C7.dat
[15:18:58.618] <TB1> INFO: write dac parameters into /home/Reception/DATA/DESYUHH/M4204_Reception_2016-08-16_17h15m_1471360531//000_ReceptionTestBB2_p17//dacParameters_C8.dat
[15:18:58.627] <TB1> INFO: write dac parameters into /home/Reception/DATA/DESYUHH/M4204_Reception_2016-08-16_17h15m_1471360531//000_ReceptionTestBB2_p17//dacParameters_C9.dat
[15:18:58.636] <TB1> INFO: write dac parameters into /home/Reception/DATA/DESYUHH/M4204_Reception_2016-08-16_17h15m_1471360531//000_ReceptionTestBB2_p17//dacParameters_C10.dat
[15:18:58.642] <TB1> INFO: write dac parameters into /home/Reception/DATA/DESYUHH/M4204_Reception_2016-08-16_17h15m_1471360531//000_ReceptionTestBB2_p17//dacParameters_C11.dat
[15:18:58.649] <TB1> INFO: write dac parameters into /home/Reception/DATA/DESYUHH/M4204_Reception_2016-08-16_17h15m_1471360531//000_ReceptionTestBB2_p17//dacParameters_C12.dat
[15:18:58.660] <TB1> INFO: write dac parameters into /home/Reception/DATA/DESYUHH/M4204_Reception_2016-08-16_17h15m_1471360531//000_ReceptionTestBB2_p17//dacParameters_C13.dat
[15:18:58.671] <TB1> INFO: write dac parameters into /home/Reception/DATA/DESYUHH/M4204_Reception_2016-08-16_17h15m_1471360531//000_ReceptionTestBB2_p17//dacParameters_C14.dat
[15:18:58.677] <TB1> INFO: write dac parameters into /home/Reception/DATA/DESYUHH/M4204_Reception_2016-08-16_17h15m_1471360531//000_ReceptionTestBB2_p17//dacParameters_C15.dat
[15:18:58.688] <TB1> INFO: enter test to run
[15:18:58.688] <TB1> INFO: test: PixelAlive no parameter change
[15:18:58.688] <TB1> INFO: running: pixelalive
[15:18:58.690] <TB1> INFO: ######################################################################
[15:18:58.691] <TB1> INFO: PixTestAlive::doTest()
[15:18:58.691] <TB1> INFO: ######################################################################
[15:18:58.692] <TB1> INFO: ----------------------------------------------------------------------
[15:18:58.692] <TB1> INFO: PixTestAlive::aliveTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[15:18:58.692] <TB1> INFO: ----------------------------------------------------------------------
[15:18:58.962] <TB1> INFO: Expecting 41600 events.
[15:19:02.388] <TB1> INFO: 41600 events read in total (2870ms).
[15:19:02.389] <TB1> INFO: Test took 3696ms.
[15:19:02.678] <TB1> INFO: PixTestAlive::aliveTest() done
[15:19:02.678] <TB1> INFO: number of dead pixels (per ROC): 0 0 0 0 0 1 2 0 0 0 0 0 0 0 0 1
[15:19:02.679] <TB1> INFO: ----------------------------------------------------------------------
[15:19:02.679] <TB1> INFO: PixTestAlive::maskTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[15:19:02.679] <TB1> INFO: ----------------------------------------------------------------------
[15:19:02.946] <TB1> INFO: Expecting 41600 events.
[15:19:05.565] <TB1> INFO: 41600 events read in total (2063ms).
[15:19:05.566] <TB1> INFO: Test took 2886ms.
[15:19:05.566] <TB1> INFO: mask vs. old pixelAlive PixelAlive_C0_V0 .. PixelAlive_C15_V0
[15:19:05.879] <TB1> INFO: PixTestAlive::maskTest() done
[15:19:05.879] <TB1> INFO: number of mask-defect pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[15:19:05.880] <TB1> INFO: ----------------------------------------------------------------------
[15:19:05.880] <TB1> INFO: PixTestAlive::addressDecodingTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[15:19:05.880] <TB1> INFO: ----------------------------------------------------------------------
[15:19:06.154] <TB1> INFO: Expecting 41600 events.
[15:19:09.570] <TB1> INFO: 41600 events read in total (2861ms).
[15:19:09.571] <TB1> INFO: Test took 3689ms.
[15:19:09.855] <TB1> INFO: PixTestAlive::addressDecodingTest() done
[15:19:09.855] <TB1> INFO: number of address-decoding pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[15:19:09.855] <TB1> INFO: PixTestAlive::doTest() done, duration: 11 seconds
[15:19:09.864] <TB1> INFO: enter test to run
[15:19:09.864] <TB1> INFO: test: BB no parameter change
[15:19:09.864] <TB1> INFO: running: bb
[15:19:09.865] <TB1> INFO: ######################################################################
[15:19:09.865] <TB1> INFO: PixTestBBMap::doTest() Ntrig = 5, VcalS = 250 (high range)
[15:19:09.865] <TB1> INFO: ######################################################################
[15:19:09.868] <TB1> INFO: ---> dac: VthrComp name: calSMap ntrig: 5 dacrange: 0 .. 149 (-1/-1) hits flags = 514 (plus default)
[15:19:09.880] <TB1> INFO: dacScan split into 1 runs with ntrig = 5
[15:19:09.880] <TB1> INFO: run 1 of 1
[15:19:10.161] <TB1> INFO: Expecting 3120000 events.
[15:19:55.541] <TB1> INFO: 1216385 events read in total (44824ms).
[15:20:41.185] <TB1> INFO: 2438400 events read in total (90469ms).
[15:21:06.094] <TB1> INFO: 3120000 events read in total (115377ms).
[15:21:06.135] <TB1> INFO: Test took 116256ms.
[15:21:28.762] <TB1> INFO: PixTestBBMap::doTest() done, duration: 138 seconds
[15:21:28.763] <TB1> INFO: number of dead bumps (per ROC): 1 0 0 0 0 1 2 2 0 0 0 0 1 0 0 0
[15:21:28.763] <TB1> INFO: separation cut (per ROC): 128 129 136 139 125 129 111 129 119 131 112 115 109 121 104 138
[15:21:28.837] <TB1> INFO: enter test to run
[15:21:28.837] <TB1> INFO: test: Readback no parameter change
[15:21:28.837] <TB1> INFO: running: readback
[15:21:28.837] <TB1> INFO: readReadbackCal: /home/Reception/DATA/DESYUHH/M4204_Reception_2016-08-16_17h15m_1471360531//000_ReceptionTestBB2_p17//readbackCal_C0.dat .. /home/Reception/DATA/DESYUHH/M4204_Reception_2016-08-16_17h15m_1471360531//000_ReceptionTestBB2_p17//readbackCal_C15.dat
[15:21:29.030] <TB1> INFO: readGainPedestalParameters /home/Reception/DATA/DESYUHH/M4204_Reception_2016-08-16_17h15m_1471360531//000_ReceptionTestBB2_p17//phCalibrationFitErr_C0.dat .. /home/Reception/DATA/DESYUHH/M4204_Reception_2016-08-16_17h15m_1471360531//000_ReceptionTestBB2_p17//phCalibrationFitErr_C15.dat
[15:21:29.030] <TB1> ERROR: <ConfigParameters.cc/readGainPedestalParameters:L996> cannot open /home/Reception/DATA/DESYUHH/M4204_Reception_2016-08-16_17h15m_1471360531//000_ReceptionTestBB2_p17//phCalibrationFitErr_C0.dat for reading PH calibration constants
[15:21:29.030] <TB1> INFO: ######################################################################
[15:21:29.030] <TB1> INFO: PixTestReadback::doTest()
[15:21:29.030] <TB1> INFO: ######################################################################
[15:21:29.030] <TB1> INFO: ----------------------------------------------------------------------
[15:21:29.030] <TB1> INFO: PixTestReadback::CalibrateVd()
[15:21:29.030] <TB1> INFO: ----------------------------------------------------------------------
[15:21:45.239] <TB1> INFO: write readback calibration parameters into /home/Reception/DATA/DESYUHH/M4204_Reception_2016-08-16_17h15m_1471360531//000_ReceptionTestBB2_p17//readbackCal_C0.dat
[15:21:45.248] <TB1> INFO: write readback calibration parameters into /home/Reception/DATA/DESYUHH/M4204_Reception_2016-08-16_17h15m_1471360531//000_ReceptionTestBB2_p17//readbackCal_C1.dat
[15:21:45.254] <TB1> INFO: write readback calibration parameters into /home/Reception/DATA/DESYUHH/M4204_Reception_2016-08-16_17h15m_1471360531//000_ReceptionTestBB2_p17//readbackCal_C2.dat
[15:21:45.266] <TB1> INFO: write readback calibration parameters into /home/Reception/DATA/DESYUHH/M4204_Reception_2016-08-16_17h15m_1471360531//000_ReceptionTestBB2_p17//readbackCal_C3.dat
[15:21:45.271] <TB1> INFO: write readback calibration parameters into /home/Reception/DATA/DESYUHH/M4204_Reception_2016-08-16_17h15m_1471360531//000_ReceptionTestBB2_p17//readbackCal_C4.dat
[15:21:45.276] <TB1> INFO: write readback calibration parameters into /home/Reception/DATA/DESYUHH/M4204_Reception_2016-08-16_17h15m_1471360531//000_ReceptionTestBB2_p17//readbackCal_C5.dat
[15:21:45.283] <TB1> INFO: write readback calibration parameters into /home/Reception/DATA/DESYUHH/M4204_Reception_2016-08-16_17h15m_1471360531//000_ReceptionTestBB2_p17//readbackCal_C6.dat
[15:21:45.288] <TB1> INFO: write readback calibration parameters into /home/Reception/DATA/DESYUHH/M4204_Reception_2016-08-16_17h15m_1471360531//000_ReceptionTestBB2_p17//readbackCal_C7.dat
[15:21:45.299] <TB1> INFO: write readback calibration parameters into /home/Reception/DATA/DESYUHH/M4204_Reception_2016-08-16_17h15m_1471360531//000_ReceptionTestBB2_p17//readbackCal_C8.dat
[15:21:45.317] <TB1> INFO: write readback calibration parameters into /home/Reception/DATA/DESYUHH/M4204_Reception_2016-08-16_17h15m_1471360531//000_ReceptionTestBB2_p17//readbackCal_C9.dat
[15:21:45.336] <TB1> INFO: write readback calibration parameters into /home/Reception/DATA/DESYUHH/M4204_Reception_2016-08-16_17h15m_1471360531//000_ReceptionTestBB2_p17//readbackCal_C10.dat
[15:21:45.342] <TB1> INFO: write readback calibration parameters into /home/Reception/DATA/DESYUHH/M4204_Reception_2016-08-16_17h15m_1471360531//000_ReceptionTestBB2_p17//readbackCal_C11.dat
[15:21:45.349] <TB1> INFO: write readback calibration parameters into /home/Reception/DATA/DESYUHH/M4204_Reception_2016-08-16_17h15m_1471360531//000_ReceptionTestBB2_p17//readbackCal_C12.dat
[15:21:45.371] <TB1> INFO: write readback calibration parameters into /home/Reception/DATA/DESYUHH/M4204_Reception_2016-08-16_17h15m_1471360531//000_ReceptionTestBB2_p17//readbackCal_C13.dat
[15:21:45.378] <TB1> INFO: write readback calibration parameters into /home/Reception/DATA/DESYUHH/M4204_Reception_2016-08-16_17h15m_1471360531//000_ReceptionTestBB2_p17//readbackCal_C14.dat
[15:21:45.382] <TB1> INFO: write readback calibration parameters into /home/Reception/DATA/DESYUHH/M4204_Reception_2016-08-16_17h15m_1471360531//000_ReceptionTestBB2_p17//readbackCal_C15.dat
[15:21:45.416] <TB1> INFO: PixTestPattern:: pg_setup set to default.
[15:21:45.416] <TB1> INFO: ----------------------------------------------------------------------
[15:21:45.416] <TB1> INFO: PixTestReadback::CalibrateVa()
[15:21:45.416] <TB1> INFO: ----------------------------------------------------------------------
[15:22:01.660] <TB1> INFO: write readback calibration parameters into /home/Reception/DATA/DESYUHH/M4204_Reception_2016-08-16_17h15m_1471360531//000_ReceptionTestBB2_p17//readbackCal_C0.dat
[15:22:01.665] <TB1> INFO: write readback calibration parameters into /home/Reception/DATA/DESYUHH/M4204_Reception_2016-08-16_17h15m_1471360531//000_ReceptionTestBB2_p17//readbackCal_C1.dat
[15:22:01.670] <TB1> INFO: write readback calibration parameters into /home/Reception/DATA/DESYUHH/M4204_Reception_2016-08-16_17h15m_1471360531//000_ReceptionTestBB2_p17//readbackCal_C2.dat
[15:22:01.675] <TB1> INFO: write readback calibration parameters into /home/Reception/DATA/DESYUHH/M4204_Reception_2016-08-16_17h15m_1471360531//000_ReceptionTestBB2_p17//readbackCal_C3.dat
[15:22:01.680] <TB1> INFO: write readback calibration parameters into /home/Reception/DATA/DESYUHH/M4204_Reception_2016-08-16_17h15m_1471360531//000_ReceptionTestBB2_p17//readbackCal_C4.dat
[15:22:01.685] <TB1> INFO: write readback calibration parameters into /home/Reception/DATA/DESYUHH/M4204_Reception_2016-08-16_17h15m_1471360531//000_ReceptionTestBB2_p17//readbackCal_C5.dat
[15:22:01.690] <TB1> INFO: write readback calibration parameters into /home/Reception/DATA/DESYUHH/M4204_Reception_2016-08-16_17h15m_1471360531//000_ReceptionTestBB2_p17//readbackCal_C6.dat
[15:22:01.695] <TB1> INFO: write readback calibration parameters into /home/Reception/DATA/DESYUHH/M4204_Reception_2016-08-16_17h15m_1471360531//000_ReceptionTestBB2_p17//readbackCal_C7.dat
[15:22:01.703] <TB1> INFO: write readback calibration parameters into /home/Reception/DATA/DESYUHH/M4204_Reception_2016-08-16_17h15m_1471360531//000_ReceptionTestBB2_p17//readbackCal_C8.dat
[15:22:01.722] <TB1> INFO: write readback calibration parameters into /home/Reception/DATA/DESYUHH/M4204_Reception_2016-08-16_17h15m_1471360531//000_ReceptionTestBB2_p17//readbackCal_C9.dat
[15:22:01.728] <TB1> INFO: write readback calibration parameters into /home/Reception/DATA/DESYUHH/M4204_Reception_2016-08-16_17h15m_1471360531//000_ReceptionTestBB2_p17//readbackCal_C10.dat
[15:22:01.733] <TB1> INFO: write readback calibration parameters into /home/Reception/DATA/DESYUHH/M4204_Reception_2016-08-16_17h15m_1471360531//000_ReceptionTestBB2_p17//readbackCal_C11.dat
[15:22:01.738] <TB1> INFO: write readback calibration parameters into /home/Reception/DATA/DESYUHH/M4204_Reception_2016-08-16_17h15m_1471360531//000_ReceptionTestBB2_p17//readbackCal_C12.dat
[15:22:01.744] <TB1> INFO: write readback calibration parameters into /home/Reception/DATA/DESYUHH/M4204_Reception_2016-08-16_17h15m_1471360531//000_ReceptionTestBB2_p17//readbackCal_C13.dat
[15:22:01.749] <TB1> INFO: write readback calibration parameters into /home/Reception/DATA/DESYUHH/M4204_Reception_2016-08-16_17h15m_1471360531//000_ReceptionTestBB2_p17//readbackCal_C14.dat
[15:22:01.755] <TB1> INFO: write readback calibration parameters into /home/Reception/DATA/DESYUHH/M4204_Reception_2016-08-16_17h15m_1471360531//000_ReceptionTestBB2_p17//readbackCal_C15.dat
[15:22:01.797] <TB1> INFO: PixTestPattern:: pg_setup set to default.
[15:22:01.797] <TB1> INFO: ----------------------------------------------------------------------
[15:22:01.797] <TB1> INFO: PixTestReadback::readbackVbg()
[15:22:01.797] <TB1> INFO: ----------------------------------------------------------------------
[15:22:14.366] <TB1> INFO: PixTestPattern:: pg_setup set to default.
[15:22:14.367] <TB1> INFO: ----------------------------------------------------------------------
[15:22:14.367] <TB1> INFO: PixTestReadback::getCalibratedVbg()
[15:22:14.367] <TB1> INFO: ----------------------------------------------------------------------
[15:22:14.367] <TB1> INFO: Vbg will be calibrated using Vd calibration
[15:22:14.367] <TB1> INFO: /*/*/*/*::: ROC 0: uncalibrated Vbg = 149.7calibrated Vbg = 1.19989 :::*/*/*/*/
[15:22:14.367] <TB1> INFO: /*/*/*/*::: ROC 1: uncalibrated Vbg = 149.3calibrated Vbg = 1.198 :::*/*/*/*/
[15:22:14.367] <TB1> INFO: /*/*/*/*::: ROC 2: uncalibrated Vbg = 152.1calibrated Vbg = 1.19998 :::*/*/*/*/
[15:22:14.367] <TB1> INFO: /*/*/*/*::: ROC 3: uncalibrated Vbg = 149.5calibrated Vbg = 1.20202 :::*/*/*/*/
[15:22:14.367] <TB1> INFO: /*/*/*/*::: ROC 4: uncalibrated Vbg = 156.8calibrated Vbg = 1.20909 :::*/*/*/*/
[15:22:14.367] <TB1> INFO: /*/*/*/*::: ROC 5: uncalibrated Vbg = 157.3calibrated Vbg = 1.21039 :::*/*/*/*/
[15:22:14.367] <TB1> INFO: /*/*/*/*::: ROC 6: uncalibrated Vbg = 167calibrated Vbg = 1.21086 :::*/*/*/*/
[15:22:14.367] <TB1> INFO: /*/*/*/*::: ROC 7: uncalibrated Vbg = 156calibrated Vbg = 1.20859 :::*/*/*/*/
[15:22:14.367] <TB1> INFO: /*/*/*/*::: ROC 8: uncalibrated Vbg = 154.1calibrated Vbg = 1.21204 :::*/*/*/*/
[15:22:14.367] <TB1> INFO: /*/*/*/*::: ROC 9: uncalibrated Vbg = 148.6calibrated Vbg = 1.21303 :::*/*/*/*/
[15:22:14.367] <TB1> INFO: /*/*/*/*::: ROC 10: uncalibrated Vbg = 153.2calibrated Vbg = 1.20831 :::*/*/*/*/
[15:22:14.367] <TB1> INFO: /*/*/*/*::: ROC 11: uncalibrated Vbg = 166.5calibrated Vbg = 1.19962 :::*/*/*/*/
[15:22:14.367] <TB1> INFO: /*/*/*/*::: ROC 12: uncalibrated Vbg = 154.6calibrated Vbg = 1.19068 :::*/*/*/*/
[15:22:14.367] <TB1> INFO: /*/*/*/*::: ROC 13: uncalibrated Vbg = 156.8calibrated Vbg = 1.19678 :::*/*/*/*/
[15:22:14.367] <TB1> INFO: /*/*/*/*::: ROC 14: uncalibrated Vbg = 161.2calibrated Vbg = 1.2021 :::*/*/*/*/
[15:22:14.367] <TB1> INFO: /*/*/*/*::: ROC 15: uncalibrated Vbg = 163calibrated Vbg = 1.19087 :::*/*/*/*/
[15:22:14.371] <TB1> INFO: ----------------------------------------------------------------------
[15:22:14.371] <TB1> INFO: PixTestReadback::CalibrateIa()
[15:22:14.371] <TB1> INFO: ----------------------------------------------------------------------
[15:25:46.827] <TB1> INFO: write readback calibration parameters into /home/Reception/DATA/DESYUHH/M4204_Reception_2016-08-16_17h15m_1471360531//000_ReceptionTestBB2_p17//readbackCal_C0.dat
[15:25:46.848] <TB1> INFO: write readback calibration parameters into /home/Reception/DATA/DESYUHH/M4204_Reception_2016-08-16_17h15m_1471360531//000_ReceptionTestBB2_p17//readbackCal_C1.dat
[15:25:46.853] <TB1> INFO: write readback calibration parameters into /home/Reception/DATA/DESYUHH/M4204_Reception_2016-08-16_17h15m_1471360531//000_ReceptionTestBB2_p17//readbackCal_C2.dat
[15:25:46.859] <TB1> INFO: write readback calibration parameters into /home/Reception/DATA/DESYUHH/M4204_Reception_2016-08-16_17h15m_1471360531//000_ReceptionTestBB2_p17//readbackCal_C3.dat
[15:25:46.866] <TB1> INFO: write readback calibration parameters into /home/Reception/DATA/DESYUHH/M4204_Reception_2016-08-16_17h15m_1471360531//000_ReceptionTestBB2_p17//readbackCal_C4.dat
[15:25:46.870] <TB1> INFO: write readback calibration parameters into /home/Reception/DATA/DESYUHH/M4204_Reception_2016-08-16_17h15m_1471360531//000_ReceptionTestBB2_p17//readbackCal_C5.dat
[15:25:46.876] <TB1> INFO: write readback calibration parameters into /home/Reception/DATA/DESYUHH/M4204_Reception_2016-08-16_17h15m_1471360531//000_ReceptionTestBB2_p17//readbackCal_C6.dat
[15:25:46.881] <TB1> INFO: write readback calibration parameters into /home/Reception/DATA/DESYUHH/M4204_Reception_2016-08-16_17h15m_1471360531//000_ReceptionTestBB2_p17//readbackCal_C7.dat
[15:25:46.888] <TB1> INFO: write readback calibration parameters into /home/Reception/DATA/DESYUHH/M4204_Reception_2016-08-16_17h15m_1471360531//000_ReceptionTestBB2_p17//readbackCal_C8.dat
[15:25:46.893] <TB1> INFO: write readback calibration parameters into /home/Reception/DATA/DESYUHH/M4204_Reception_2016-08-16_17h15m_1471360531//000_ReceptionTestBB2_p17//readbackCal_C9.dat
[15:25:46.899] <TB1> INFO: write readback calibration parameters into /home/Reception/DATA/DESYUHH/M4204_Reception_2016-08-16_17h15m_1471360531//000_ReceptionTestBB2_p17//readbackCal_C10.dat
[15:25:46.906] <TB1> INFO: write readback calibration parameters into /home/Reception/DATA/DESYUHH/M4204_Reception_2016-08-16_17h15m_1471360531//000_ReceptionTestBB2_p17//readbackCal_C11.dat
[15:25:46.911] <TB1> INFO: write readback calibration parameters into /home/Reception/DATA/DESYUHH/M4204_Reception_2016-08-16_17h15m_1471360531//000_ReceptionTestBB2_p17//readbackCal_C12.dat
[15:25:46.916] <TB1> INFO: write readback calibration parameters into /home/Reception/DATA/DESYUHH/M4204_Reception_2016-08-16_17h15m_1471360531//000_ReceptionTestBB2_p17//readbackCal_C13.dat
[15:25:46.921] <TB1> INFO: write readback calibration parameters into /home/Reception/DATA/DESYUHH/M4204_Reception_2016-08-16_17h15m_1471360531//000_ReceptionTestBB2_p17//readbackCal_C14.dat
[15:25:46.926] <TB1> INFO: write readback calibration parameters into /home/Reception/DATA/DESYUHH/M4204_Reception_2016-08-16_17h15m_1471360531//000_ReceptionTestBB2_p17//readbackCal_C15.dat
[15:25:46.965] <TB1> INFO: PixTestPattern:: pg_setup set to default.
[15:25:46.969] <TB1> INFO: PixTestReadback::doTest() done
[15:25:46.989] <TB1> INFO: enter test to run
[15:25:46.989] <TB1> INFO: test: BB2 no parameter change
[15:25:46.989] <TB1> INFO: running: bb2
[15:25:46.990] <TB1> INFO: ######################################################################
[15:25:46.990] <TB1> INFO: PixTestBB2Map::doTest() Ntrig = 10, VcalS = 222, PlWidth = 35
[15:25:46.990] <TB1> INFO: ######################################################################
[15:25:46.991] <TB1> INFO: ----------------------------------------------------------------------
[15:25:46.991] <TB1> INFO: PixTestBB2Map::setVana() target Ia = 24 mA/ROC
[15:25:46.991] <TB1> INFO: ----------------------------------------------------------------------
[15:25:51.149] <TB1> INFO: PixTestBB2Map::setVana() done, Module Ia 385.9 mA = 24.1188 mA/ROC
[15:25:51.150] <TB1> INFO: ----------------------------------------------------------------------
[15:25:51.150] <TB1> INFO: PixTestBB2Map::setVthrCompCalDel()
[15:25:51.150] <TB1> INFO: ----------------------------------------------------------------------
[15:25:51.239] <TB1> INFO: Expecting 655360 events.
[15:26:09.441] <TB1> INFO: 655360 events read in total (17647ms).
[15:26:09.445] <TB1> INFO: Test took 18292ms.
[15:26:09.728] <TB1> INFO: PixTestBB2Map::setVthrCompCalDel() done
[15:26:09.728] <TB1> INFO: CalDel: 119 138 114 120 116 128 137 122 114 117 141 144 133 143 133 132
[15:26:09.728] <TB1> INFO: VthrComp: 99 101 106 109 95 101 94 110 98 91 93 102 94 96 92 107
[15:26:10.014] <TB1> INFO: Expecting 5324800 events.
[15:26:46.964] <TB1> INFO: 1395000 events read in total (36394ms).
[15:27:23.300] <TB1> INFO: 2776890 events read in total (72730ms).
[15:27:59.895] <TB1> INFO: 4181060 events read in total (109326ms).
[15:28:29.241] <TB1> INFO: 5324800 events read in total (138671ms).
[15:28:29.273] <TB1> INFO: Test took 139530ms.
[15:28:29.920] <TB1> INFO: Missing Bumps: 1 0 0 2 1 3 0 3 0 0 0 0 1 0 0 2
[15:28:29.920] <TB1> INFO: Separation Cut: 17.76 15.03 25.70 16.11 18.64 18.82 24.85 29.21 31.10 32.33 29.79 18.82 19.16 19.75 22.43 30.61
[15:28:29.920] <TB1> INFO: PixTestBB2Map::doTest() done,162 seconds
[15:28:30.328] <TB1> INFO: enter test to run
[15:28:30.328] <TB1> INFO: test: no parameter change
[15:28:30.396] <TB1> QUIET: Connection to board 153 closed.
[15:28:30.478] <TB1> INFO: pXar: this is the end, my friend