[07:21:01.020] <TB3> INFO: *** Welcome to pxar ***
[07:21:01.020] <TB3> INFO: *** Today: 2016/08/17
[07:21:01.060] <TB3> INFO: *** Version: f5d3-dirty
[07:21:01.060] <TB3> INFO: readRocDacs: /home/Reception/DATA/DESYUHH/M4149_Reception_2016-08-17_09h18m_1471418331//000_ReceptionTestBB2_p17//dacParameters_C0.dat .. /home/Reception/DATA/DESYUHH/M4149_Reception_2016-08-17_09h18m_1471418331//000_ReceptionTestBB2_p17//dacParameters_C15.dat
[07:21:01.078] <TB3> INFO: readTbmDacs: /home/Reception/DATA/DESYUHH/M4149_Reception_2016-08-17_09h18m_1471418331//000_ReceptionTestBB2_p17//tbmParameters_C0a.dat .. /home/Reception/DATA/DESYUHH/M4149_Reception_2016-08-17_09h18m_1471418331//000_ReceptionTestBB2_p17//tbmParameters_C0b.dat
[07:21:01.081] <TB3> INFO: readMaskFile: /home/Reception/DATA/DESYUHH/M4149_Reception_2016-08-17_09h18m_1471418331//000_ReceptionTestBB2_p17//defaultMaskFile.dat
[07:21:01.082] <TB3> INFO: readTrimFile: /home/Reception/DATA/DESYUHH/M4149_Reception_2016-08-17_09h18m_1471418331//000_ReceptionTestBB2_p17//trimParameters_C0.dat .. /home/Reception/DATA/DESYUHH/M4149_Reception_2016-08-17_09h18m_1471418331//000_ReceptionTestBB2_p17//trimParameters_C15.dat
[07:21:01.203] <TB3> INFO: clk: 4
[07:21:01.203] <TB3> INFO: ctr: 4
[07:21:01.203] <TB3> INFO: sda: 19
[07:21:01.203] <TB3> INFO: tin: 9
[07:21:01.203] <TB3> INFO: level: 15
[07:21:01.203] <TB3> INFO: triggerdelay: 0
[07:21:01.203] <TB3> QUIET: Instanciating API for pxar v2.7.5+40~g4fce89b
[07:21:01.203] <TB3> INFO: Log level: INFO
[07:21:01.210] <TB3> INFO: Found DTB DTB_WWVASW
[07:21:01.223] <TB3> QUIET: Connection to board DTB_WWVASW opened.
[07:21:01.225] <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:
------------------------------------------------------
[07:21:01.227] <TB3> INFO: RPC call hashes of host and DTB match: 484264910
[07:21:02.763] <TB3> INFO: DUT info:
[07:21:02.763] <TB3> INFO: The DUT currently contains the following objects:
[07:21:02.763] <TB3> INFO: 2 TBM Cores tbm08c (2 ON)
[07:21:02.763] <TB3> INFO: TBM Core alpha (0): 7 registers set
[07:21:02.763] <TB3> INFO: TBM Core beta (1): 7 registers set
[07:21:02.763] <TB3> INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[07:21:02.763] <TB3> INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[07:21:02.763] <TB3> INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[07:21:02.763] <TB3> INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[07:21:02.763] <TB3> INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[07:21:02.763] <TB3> INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[07:21:02.763] <TB3> INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[07:21:02.763] <TB3> INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[07:21:02.763] <TB3> INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[07:21:02.763] <TB3> INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[07:21:02.763] <TB3> INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[07:21:02.763] <TB3> INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[07:21:02.763] <TB3> INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[07:21:02.763] <TB3> INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[07:21:02.763] <TB3> INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[07:21:02.763] <TB3> INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[07:21:02.764] <TB3> INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[07:21:03.167] <TB3> INFO: enter 'restricted' command line mode
[07:21:03.167] <TB3> INFO: enter test to run
[07:21:03.167] <TB3> INFO: test: pretest no parameter change
[07:21:03.167] <TB3> INFO: running: pretest
[07:21:03.171] <TB3> INFO: ----------------------------------------------------------------------
[07:21:03.171] <TB3> INFO: PixTestPretest::programROC()
[07:21:03.171] <TB3> INFO: ----------------------------------------------------------------------
[07:21:21.187] <TB3> INFO: PixTestPretest::programROC() done: ROCs are all programmable
[07:21:21.189] <TB3> INFO: IA differences per ROC: 20.9 20.9 19.3 17.7 18.5 20.1 17.7 20.1 20.1 20.1 17.7 20.1 20.1 20.9 19.3 20.9
[07:21:21.300] <TB3> INFO: enter test to run
[07:21:21.300] <TB3> INFO: test: pretest no parameter change
[07:21:21.300] <TB3> INFO: running: pretest
[07:21:21.301] <TB3> INFO: ----------------------------------------------------------------------
[07:21:21.301] <TB3> INFO: PixTestPretest::setVana() target Ia = 24 mA/ROC
[07:21:21.301] <TB3> INFO: ----------------------------------------------------------------------
[07:21:28.920] <TB3> INFO: PixTestPretest::setVana() done, Module Ia 387.5 mA = 24.2188 mA/ROC
[07:21:28.920] <TB3> INFO: i(loss) [mA/ROC]: 20.1 20.1 20.1 20.1 20.1 20.1 20.1 20.1 19.3 19.3 19.3 20.1 19.3 19.3 19.3 19.3
[07:21:28.965] <TB3> INFO: enter test to run
[07:21:28.965] <TB3> INFO: test: pretest no parameter change
[07:21:28.965] <TB3> INFO: running: pretest
[07:21:28.966] <TB3> INFO: ----------------------------------------------------------------------
[07:21:28.966] <TB3> INFO: PixTestPretest::findWorkingPixel()
[07:21:28.966] <TB3> INFO: ----------------------------------------------------------------------
[07:21:29.059] <TB3> INFO: Expecting 231680 events.
[07:21:38.007] <TB3> INFO: 231680 events read in total (8390ms).
[07:21:38.105] <TB3> INFO: Test took 9136ms.
[07:21:38.310] <TB3> INFO: Found working pixel in all ROCs: col/row = 12/22
[07:21:38.389] <TB3> INFO: enter test to run
[07:21:38.389] <TB3> INFO: test: pretest no parameter change
[07:21:38.389] <TB3> INFO: running: pretest
[07:21:38.390] <TB3> INFO: ----------------------------------------------------------------------
[07:21:38.390] <TB3> INFO: PixTestPretest::setVthrCompCalDel()
[07:21:38.390] <TB3> INFO: ----------------------------------------------------------------------
[07:21:38.486] <TB3> INFO: Expecting 231680 events.
[07:21:47.495] <TB3> INFO: 231680 events read in total (8453ms).
[07:21:47.499] <TB3> INFO: Test took 9103ms.
[07:21:47.823] <TB3> INFO: PixTestPretest::setVthrCompCalDel() done
[07:21:47.823] <TB3> INFO: CalDel: 128 126 113 126 139 134 121 128 138 119 117 137 154 127 132 166
[07:21:47.823] <TB3> INFO: VthrComp: 51 51 51 51 51 51 51 51 51 51 51 51 51 51 51 51
[07:21:47.856] <TB3> INFO: enter test to run
[07:21:47.856] <TB3> INFO: test: pretest no parameter change
[07:21:47.856] <TB3> INFO: running: pretest
[07:21:47.990] <TB3> INFO: write dac parameters into /home/Reception/DATA/DESYUHH/M4149_Reception_2016-08-17_09h18m_1471418331//000_ReceptionTestBB2_p17//dacParameters_C0.dat
[07:21:47.995] <TB3> INFO: write dac parameters into /home/Reception/DATA/DESYUHH/M4149_Reception_2016-08-17_09h18m_1471418331//000_ReceptionTestBB2_p17//dacParameters_C1.dat
[07:21:48.000] <TB3> INFO: write dac parameters into /home/Reception/DATA/DESYUHH/M4149_Reception_2016-08-17_09h18m_1471418331//000_ReceptionTestBB2_p17//dacParameters_C2.dat
[07:21:48.007] <TB3> INFO: write dac parameters into /home/Reception/DATA/DESYUHH/M4149_Reception_2016-08-17_09h18m_1471418331//000_ReceptionTestBB2_p17//dacParameters_C3.dat
[07:21:48.012] <TB3> INFO: write dac parameters into /home/Reception/DATA/DESYUHH/M4149_Reception_2016-08-17_09h18m_1471418331//000_ReceptionTestBB2_p17//dacParameters_C4.dat
[07:21:48.017] <TB3> INFO: write dac parameters into /home/Reception/DATA/DESYUHH/M4149_Reception_2016-08-17_09h18m_1471418331//000_ReceptionTestBB2_p17//dacParameters_C5.dat
[07:21:48.022] <TB3> INFO: write dac parameters into /home/Reception/DATA/DESYUHH/M4149_Reception_2016-08-17_09h18m_1471418331//000_ReceptionTestBB2_p17//dacParameters_C6.dat
[07:21:48.027] <TB3> INFO: write dac parameters into /home/Reception/DATA/DESYUHH/M4149_Reception_2016-08-17_09h18m_1471418331//000_ReceptionTestBB2_p17//dacParameters_C7.dat
[07:21:48.032] <TB3> INFO: write dac parameters into /home/Reception/DATA/DESYUHH/M4149_Reception_2016-08-17_09h18m_1471418331//000_ReceptionTestBB2_p17//dacParameters_C8.dat
[07:21:48.039] <TB3> INFO: write dac parameters into /home/Reception/DATA/DESYUHH/M4149_Reception_2016-08-17_09h18m_1471418331//000_ReceptionTestBB2_p17//dacParameters_C9.dat
[07:21:48.044] <TB3> INFO: write dac parameters into /home/Reception/DATA/DESYUHH/M4149_Reception_2016-08-17_09h18m_1471418331//000_ReceptionTestBB2_p17//dacParameters_C10.dat
[07:21:48.051] <TB3> INFO: write dac parameters into /home/Reception/DATA/DESYUHH/M4149_Reception_2016-08-17_09h18m_1471418331//000_ReceptionTestBB2_p17//dacParameters_C11.dat
[07:21:48.058] <TB3> INFO: write dac parameters into /home/Reception/DATA/DESYUHH/M4149_Reception_2016-08-17_09h18m_1471418331//000_ReceptionTestBB2_p17//dacParameters_C12.dat
[07:21:48.064] <TB3> INFO: write dac parameters into /home/Reception/DATA/DESYUHH/M4149_Reception_2016-08-17_09h18m_1471418331//000_ReceptionTestBB2_p17//dacParameters_C13.dat
[07:21:48.070] <TB3> INFO: write dac parameters into /home/Reception/DATA/DESYUHH/M4149_Reception_2016-08-17_09h18m_1471418331//000_ReceptionTestBB2_p17//dacParameters_C14.dat
[07:21:48.075] <TB3> INFO: write dac parameters into /home/Reception/DATA/DESYUHH/M4149_Reception_2016-08-17_09h18m_1471418331//000_ReceptionTestBB2_p17//dacParameters_C15.dat
[07:21:48.080] <TB3> INFO: enter test to run
[07:21:48.080] <TB3> INFO: test: PixelAlive no parameter change
[07:21:48.080] <TB3> INFO: running: pixelalive
[07:21:48.081] <TB3> INFO: ######################################################################
[07:21:48.081] <TB3> INFO: PixTestAlive::doTest()
[07:21:48.081] <TB3> INFO: ######################################################################
[07:21:48.082] <TB3> INFO: ----------------------------------------------------------------------
[07:21:48.082] <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)
[07:21:48.082] <TB3> INFO: ----------------------------------------------------------------------
[07:21:48.350] <TB3> INFO: Expecting 41600 events.
[07:21:51.747] <TB3> INFO: 41600 events read in total (2841ms).
[07:21:51.748] <TB3> INFO: Test took 3664ms.
[07:21:52.058] <TB3> INFO: PixTestAlive::aliveTest() done
[07:21:52.058] <TB3> INFO: number of dead pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[07:21:52.060] <TB3> INFO: ----------------------------------------------------------------------
[07:21:52.060] <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)
[07:21:52.060] <TB3> INFO: ----------------------------------------------------------------------
[07:21:52.345] <TB3> INFO: Expecting 41600 events.
[07:21:54.977] <TB3> INFO: 41600 events read in total (2076ms).
[07:21:54.977] <TB3> INFO: Test took 2915ms.
[07:21:54.977] <TB3> INFO: mask vs. old pixelAlive PixelAlive_C0_V0 .. PixelAlive_C15_V0
[07:21:55.304] <TB3> INFO: PixTestAlive::maskTest() done
[07:21:55.304] <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
[07:21:55.305] <TB3> INFO: ----------------------------------------------------------------------
[07:21:55.305] <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)
[07:21:55.305] <TB3> INFO: ----------------------------------------------------------------------
[07:21:55.605] <TB3> INFO: Expecting 41600 events.
[07:21:59.044] <TB3> INFO: 41600 events read in total (2883ms).
[07:21:59.045] <TB3> INFO: Test took 3738ms.
[07:21:59.353] <TB3> INFO: PixTestAlive::addressDecodingTest() done
[07:21:59.353] <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
[07:21:59.353] <TB3> INFO: PixTestAlive::doTest() done, duration: 11 seconds
[07:21:59.359] <TB3> INFO: enter test to run
[07:21:59.359] <TB3> INFO: test: BB no parameter change
[07:21:59.359] <TB3> INFO: running: bb
[07:21:59.360] <TB3> INFO: ######################################################################
[07:21:59.360] <TB3> INFO: PixTestBBMap::doTest() Ntrig = 5, VcalS = 250 (high range)
[07:21:59.360] <TB3> INFO: ######################################################################
[07:21:59.364] <TB3> INFO: ---> dac: VthrComp name: calSMap ntrig: 5 dacrange: 0 .. 149 (-1/-1) hits flags = 514 (plus default)
[07:21:59.377] <TB3> INFO: dacScan split into 1 runs with ntrig = 5
[07:21:59.377] <TB3> INFO: run 1 of 1
[07:21:59.638] <TB3> INFO: Expecting 3120000 events.
[07:22:45.019] <TB3> INFO: 1221230 events read in total (44825ms).
[07:23:29.954] <TB3> INFO: 2434990 events read in total (89760ms).
[07:23:55.019] <TB3> INFO: 3120000 events read in total (114825ms).
[07:23:55.056] <TB3> INFO: Test took 115680ms.
[07:24:18.136] <TB3> INFO: PixTestBBMap::doTest() done, duration: 138 seconds
[07:24:18.137] <TB3> INFO: number of dead bumps (per ROC): 2 1 8 1 0 0 1 5 2 2 2 0 0 0 2 2
[07:24:18.137] <TB3> INFO: separation cut (per ROC): 134 142 130 140 130 132 122 136 122 119 108 108 102 111 103 114
[07:24:18.214] <TB3> INFO: enter test to run
[07:24:18.214] <TB3> INFO: test: Readback no parameter change
[07:24:18.214] <TB3> INFO: running: readback
[07:24:18.214] <TB3> INFO: readReadbackCal: /home/Reception/DATA/DESYUHH/M4149_Reception_2016-08-17_09h18m_1471418331//000_ReceptionTestBB2_p17//readbackCal_C0.dat .. /home/Reception/DATA/DESYUHH/M4149_Reception_2016-08-17_09h18m_1471418331//000_ReceptionTestBB2_p17//readbackCal_C15.dat
[07:24:18.229] <TB3> INFO: readGainPedestalParameters /home/Reception/DATA/DESYUHH/M4149_Reception_2016-08-17_09h18m_1471418331//000_ReceptionTestBB2_p17//phCalibrationFitErr_C0.dat .. /home/Reception/DATA/DESYUHH/M4149_Reception_2016-08-17_09h18m_1471418331//000_ReceptionTestBB2_p17//phCalibrationFitErr_C15.dat

[07:24:18.230] <TB3> INFO: ######################################################################
[07:24:18.230] <TB3> INFO: PixTestReadback::doTest()
[07:24:18.230] <TB3> INFO: ######################################################################
[07:24:18.230] <TB3> INFO: ----------------------------------------------------------------------
[07:24:18.230] <TB3> INFO: PixTestReadback::CalibrateVd()
[07:24:18.230] <TB3> INFO: ----------------------------------------------------------------------
[07:24:34.507] <TB3> INFO: write readback calibration parameters into /home/Reception/DATA/DESYUHH/M4149_Reception_2016-08-17_09h18m_1471418331//000_ReceptionTestBB2_p17//readbackCal_C0.dat
[07:24:34.513] <TB3> INFO: write readback calibration parameters into /home/Reception/DATA/DESYUHH/M4149_Reception_2016-08-17_09h18m_1471418331//000_ReceptionTestBB2_p17//readbackCal_C1.dat
[07:24:34.518] <TB3> INFO: write readback calibration parameters into /home/Reception/DATA/DESYUHH/M4149_Reception_2016-08-17_09h18m_1471418331//000_ReceptionTestBB2_p17//readbackCal_C2.dat
[07:24:34.523] <TB3> INFO: write readback calibration parameters into /home/Reception/DATA/DESYUHH/M4149_Reception_2016-08-17_09h18m_1471418331//000_ReceptionTestBB2_p17//readbackCal_C3.dat
[07:24:34.529] <TB3> INFO: write readback calibration parameters into /home/Reception/DATA/DESYUHH/M4149_Reception_2016-08-17_09h18m_1471418331//000_ReceptionTestBB2_p17//readbackCal_C4.dat
[07:24:34.534] <TB3> INFO: write readback calibration parameters into /home/Reception/DATA/DESYUHH/M4149_Reception_2016-08-17_09h18m_1471418331//000_ReceptionTestBB2_p17//readbackCal_C5.dat
[07:24:34.539] <TB3> INFO: write readback calibration parameters into /home/Reception/DATA/DESYUHH/M4149_Reception_2016-08-17_09h18m_1471418331//000_ReceptionTestBB2_p17//readbackCal_C6.dat
[07:24:34.544] <TB3> INFO: write readback calibration parameters into /home/Reception/DATA/DESYUHH/M4149_Reception_2016-08-17_09h18m_1471418331//000_ReceptionTestBB2_p17//readbackCal_C7.dat
[07:24:34.550] <TB3> INFO: write readback calibration parameters into /home/Reception/DATA/DESYUHH/M4149_Reception_2016-08-17_09h18m_1471418331//000_ReceptionTestBB2_p17//readbackCal_C8.dat
[07:24:34.556] <TB3> INFO: write readback calibration parameters into /home/Reception/DATA/DESYUHH/M4149_Reception_2016-08-17_09h18m_1471418331//000_ReceptionTestBB2_p17//readbackCal_C9.dat
[07:24:34.561] <TB3> INFO: write readback calibration parameters into /home/Reception/DATA/DESYUHH/M4149_Reception_2016-08-17_09h18m_1471418331//000_ReceptionTestBB2_p17//readbackCal_C10.dat
[07:24:34.592] <TB3> INFO: write readback calibration parameters into /home/Reception/DATA/DESYUHH/M4149_Reception_2016-08-17_09h18m_1471418331//000_ReceptionTestBB2_p17//readbackCal_C11.dat
[07:24:34.598] <TB3> INFO: write readback calibration parameters into /home/Reception/DATA/DESYUHH/M4149_Reception_2016-08-17_09h18m_1471418331//000_ReceptionTestBB2_p17//readbackCal_C12.dat
[07:24:34.603] <TB3> INFO: write readback calibration parameters into /home/Reception/DATA/DESYUHH/M4149_Reception_2016-08-17_09h18m_1471418331//000_ReceptionTestBB2_p17//readbackCal_C13.dat
[07:24:34.609] <TB3> INFO: write readback calibration parameters into /home/Reception/DATA/DESYUHH/M4149_Reception_2016-08-17_09h18m_1471418331//000_ReceptionTestBB2_p17//readbackCal_C14.dat
[07:24:34.614] <TB3> INFO: write readback calibration parameters into /home/Reception/DATA/DESYUHH/M4149_Reception_2016-08-17_09h18m_1471418331//000_ReceptionTestBB2_p17//readbackCal_C15.dat
[07:24:34.662] <TB3> INFO: PixTestPattern:: pg_setup set to default.
[07:24:34.662] <TB3> INFO: ----------------------------------------------------------------------
[07:24:34.662] <TB3> INFO: PixTestReadback::CalibrateVa()
[07:24:34.662] <TB3> INFO: ----------------------------------------------------------------------
[07:24:50.940] <TB3> INFO: write readback calibration parameters into /home/Reception/DATA/DESYUHH/M4149_Reception_2016-08-17_09h18m_1471418331//000_ReceptionTestBB2_p17//readbackCal_C0.dat
[07:24:50.947] <TB3> INFO: write readback calibration parameters into /home/Reception/DATA/DESYUHH/M4149_Reception_2016-08-17_09h18m_1471418331//000_ReceptionTestBB2_p17//readbackCal_C1.dat
[07:24:50.952] <TB3> INFO: write readback calibration parameters into /home/Reception/DATA/DESYUHH/M4149_Reception_2016-08-17_09h18m_1471418331//000_ReceptionTestBB2_p17//readbackCal_C2.dat
[07:24:50.957] <TB3> INFO: write readback calibration parameters into /home/Reception/DATA/DESYUHH/M4149_Reception_2016-08-17_09h18m_1471418331//000_ReceptionTestBB2_p17//readbackCal_C3.dat
[07:24:50.962] <TB3> INFO: write readback calibration parameters into /home/Reception/DATA/DESYUHH/M4149_Reception_2016-08-17_09h18m_1471418331//000_ReceptionTestBB2_p17//readbackCal_C4.dat
[07:24:50.967] <TB3> INFO: write readback calibration parameters into /home/Reception/DATA/DESYUHH/M4149_Reception_2016-08-17_09h18m_1471418331//000_ReceptionTestBB2_p17//readbackCal_C5.dat
[07:24:50.972] <TB3> INFO: write readback calibration parameters into /home/Reception/DATA/DESYUHH/M4149_Reception_2016-08-17_09h18m_1471418331//000_ReceptionTestBB2_p17//readbackCal_C6.dat
[07:24:50.978] <TB3> INFO: write readback calibration parameters into /home/Reception/DATA/DESYUHH/M4149_Reception_2016-08-17_09h18m_1471418331//000_ReceptionTestBB2_p17//readbackCal_C7.dat
[07:24:50.983] <TB3> INFO: write readback calibration parameters into /home/Reception/DATA/DESYUHH/M4149_Reception_2016-08-17_09h18m_1471418331//000_ReceptionTestBB2_p17//readbackCal_C8.dat
[07:24:50.988] <TB3> INFO: write readback calibration parameters into /home/Reception/DATA/DESYUHH/M4149_Reception_2016-08-17_09h18m_1471418331//000_ReceptionTestBB2_p17//readbackCal_C9.dat
[07:24:50.994] <TB3> INFO: write readback calibration parameters into /home/Reception/DATA/DESYUHH/M4149_Reception_2016-08-17_09h18m_1471418331//000_ReceptionTestBB2_p17//readbackCal_C10.dat
[07:24:51.000] <TB3> INFO: write readback calibration parameters into /home/Reception/DATA/DESYUHH/M4149_Reception_2016-08-17_09h18m_1471418331//000_ReceptionTestBB2_p17//readbackCal_C11.dat
[07:24:51.005] <TB3> INFO: write readback calibration parameters into /home/Reception/DATA/DESYUHH/M4149_Reception_2016-08-17_09h18m_1471418331//000_ReceptionTestBB2_p17//readbackCal_C12.dat
[07:24:51.010] <TB3> INFO: write readback calibration parameters into /home/Reception/DATA/DESYUHH/M4149_Reception_2016-08-17_09h18m_1471418331//000_ReceptionTestBB2_p17//readbackCal_C13.dat
[07:24:51.015] <TB3> INFO: write readback calibration parameters into /home/Reception/DATA/DESYUHH/M4149_Reception_2016-08-17_09h18m_1471418331//000_ReceptionTestBB2_p17//readbackCal_C14.dat
[07:24:51.020] <TB3> INFO: write readback calibration parameters into /home/Reception/DATA/DESYUHH/M4149_Reception_2016-08-17_09h18m_1471418331//000_ReceptionTestBB2_p17//readbackCal_C15.dat
[07:24:51.071] <TB3> INFO: PixTestPattern:: pg_setup set to default.
[07:24:51.071] <TB3> INFO: ----------------------------------------------------------------------
[07:24:51.071] <TB3> INFO: PixTestReadback::readbackVbg()
[07:24:51.071] <TB3> INFO: ----------------------------------------------------------------------
[07:25:03.690] <TB3> INFO: PixTestPattern:: pg_setup set to default.
[07:25:03.694] <TB3> INFO: ----------------------------------------------------------------------
[07:25:03.694] <TB3> INFO: PixTestReadback::getCalibratedVbg()
[07:25:03.694] <TB3> INFO: ----------------------------------------------------------------------
[07:25:03.694] <TB3> INFO: Vbg will be calibrated using Vd calibration
[07:25:03.694] <TB3> INFO: /*/*/*/*::: ROC 0: uncalibrated Vbg = 150.3calibrated Vbg = 1.24409 :::*/*/*/*/
[07:25:03.694] <TB3> INFO: /*/*/*/*::: ROC 1: uncalibrated Vbg = 153.9calibrated Vbg = 1.23965 :::*/*/*/*/
[07:25:03.694] <TB3> INFO: /*/*/*/*::: ROC 2: uncalibrated Vbg = 149.8calibrated Vbg = 1.24447 :::*/*/*/*/
[07:25:03.694] <TB3> INFO: /*/*/*/*::: ROC 3: uncalibrated Vbg = 157calibrated Vbg = 1.24606 :::*/*/*/*/
[07:25:03.694] <TB3> INFO: /*/*/*/*::: ROC 4: uncalibrated Vbg = 153.4calibrated Vbg = 1.24901 :::*/*/*/*/
[07:25:03.694] <TB3> INFO: /*/*/*/*::: ROC 5: uncalibrated Vbg = 150.8calibrated Vbg = 1.25447 :::*/*/*/*/
[07:25:03.694] <TB3> INFO: /*/*/*/*::: ROC 6: uncalibrated Vbg = 151.2calibrated Vbg = 1.25164 :::*/*/*/*/
[07:25:03.694] <TB3> INFO: /*/*/*/*::: ROC 7: uncalibrated Vbg = 143.4calibrated Vbg = 1.25092 :::*/*/*/*/
[07:25:03.694] <TB3> INFO: /*/*/*/*::: ROC 8: uncalibrated Vbg = 156.7calibrated Vbg = 1.25511 :::*/*/*/*/
[07:25:03.694] <TB3> INFO: /*/*/*/*::: ROC 9: uncalibrated Vbg = 160.1calibrated Vbg = 1.25703 :::*/*/*/*/
[07:25:03.694] <TB3> INFO: /*/*/*/*::: ROC 10: uncalibrated Vbg = 159.9calibrated Vbg = 1.24938 :::*/*/*/*/
[07:25:03.694] <TB3> INFO: /*/*/*/*::: ROC 11: uncalibrated Vbg = 153.2calibrated Vbg = 1.2493 :::*/*/*/*/
[07:25:03.694] <TB3> INFO: /*/*/*/*::: ROC 12: uncalibrated Vbg = 147.2calibrated Vbg = 1.2393 :::*/*/*/*/
[07:25:03.694] <TB3> INFO: /*/*/*/*::: ROC 13: uncalibrated Vbg = 148calibrated Vbg = 1.24306 :::*/*/*/*/
[07:25:03.694] <TB3> INFO: /*/*/*/*::: ROC 14: uncalibrated Vbg = 149.4calibrated Vbg = 1.23965 :::*/*/*/*/
[07:25:03.694] <TB3> INFO: /*/*/*/*::: ROC 15: uncalibrated Vbg = 155.5calibrated Vbg = 1.24505 :::*/*/*/*/
[07:25:03.697] <TB3> INFO: ----------------------------------------------------------------------
[07:25:03.697] <TB3> INFO: PixTestReadback::CalibrateIa()
[07:25:03.697] <TB3> INFO: ----------------------------------------------------------------------
[07:28:36.867] <TB3> INFO: write readback calibration parameters into /home/Reception/DATA/DESYUHH/M4149_Reception_2016-08-17_09h18m_1471418331//000_ReceptionTestBB2_p17//readbackCal_C0.dat
[07:28:36.872] <TB3> INFO: write readback calibration parameters into /home/Reception/DATA/DESYUHH/M4149_Reception_2016-08-17_09h18m_1471418331//000_ReceptionTestBB2_p17//readbackCal_C1.dat
[07:28:36.877] <TB3> INFO: write readback calibration parameters into /home/Reception/DATA/DESYUHH/M4149_Reception_2016-08-17_09h18m_1471418331//000_ReceptionTestBB2_p17//readbackCal_C2.dat
[07:28:36.882] <TB3> INFO: write readback calibration parameters into /home/Reception/DATA/DESYUHH/M4149_Reception_2016-08-17_09h18m_1471418331//000_ReceptionTestBB2_p17//readbackCal_C3.dat
[07:28:36.887] <TB3> INFO: write readback calibration parameters into /home/Reception/DATA/DESYUHH/M4149_Reception_2016-08-17_09h18m_1471418331//000_ReceptionTestBB2_p17//readbackCal_C4.dat
[07:28:36.894] <TB3> INFO: write readback calibration parameters into /home/Reception/DATA/DESYUHH/M4149_Reception_2016-08-17_09h18m_1471418331//000_ReceptionTestBB2_p17//readbackCal_C5.dat
[07:28:36.899] <TB3> INFO: write readback calibration parameters into /home/Reception/DATA/DESYUHH/M4149_Reception_2016-08-17_09h18m_1471418331//000_ReceptionTestBB2_p17//readbackCal_C6.dat
[07:28:36.904] <TB3> INFO: write readback calibration parameters into /home/Reception/DATA/DESYUHH/M4149_Reception_2016-08-17_09h18m_1471418331//000_ReceptionTestBB2_p17//readbackCal_C7.dat
[07:28:36.909] <TB3> INFO: write readback calibration parameters into /home/Reception/DATA/DESYUHH/M4149_Reception_2016-08-17_09h18m_1471418331//000_ReceptionTestBB2_p17//readbackCal_C8.dat
[07:28:36.914] <TB3> INFO: write readback calibration parameters into /home/Reception/DATA/DESYUHH/M4149_Reception_2016-08-17_09h18m_1471418331//000_ReceptionTestBB2_p17//readbackCal_C9.dat
[07:28:36.919] <TB3> INFO: write readback calibration parameters into /home/Reception/DATA/DESYUHH/M4149_Reception_2016-08-17_09h18m_1471418331//000_ReceptionTestBB2_p17//readbackCal_C10.dat
[07:28:36.926] <TB3> INFO: write readback calibration parameters into /home/Reception/DATA/DESYUHH/M4149_Reception_2016-08-17_09h18m_1471418331//000_ReceptionTestBB2_p17//readbackCal_C11.dat
[07:28:36.932] <TB3> INFO: write readback calibration parameters into /home/Reception/DATA/DESYUHH/M4149_Reception_2016-08-17_09h18m_1471418331//000_ReceptionTestBB2_p17//readbackCal_C12.dat
[07:28:36.942] <TB3> INFO: write readback calibration parameters into /home/Reception/DATA/DESYUHH/M4149_Reception_2016-08-17_09h18m_1471418331//000_ReceptionTestBB2_p17//readbackCal_C13.dat
[07:28:36.947] <TB3> INFO: write readback calibration parameters into /home/Reception/DATA/DESYUHH/M4149_Reception_2016-08-17_09h18m_1471418331//000_ReceptionTestBB2_p17//readbackCal_C14.dat
[07:28:36.952] <TB3> INFO: write readback calibration parameters into /home/Reception/DATA/DESYUHH/M4149_Reception_2016-08-17_09h18m_1471418331//000_ReceptionTestBB2_p17//readbackCal_C15.dat
[07:28:37.005] <TB3> INFO: PixTestPattern:: pg_setup set to default.
[07:28:37.008] <TB3> INFO: PixTestReadback::doTest() done
[07:28:37.034] <TB3> INFO: enter test to run
[07:28:37.034] <TB3> INFO: test: BB2 no parameter change
[07:28:37.034] <TB3> INFO: running: bb2
[07:28:37.035] <TB3> INFO: ######################################################################
[07:28:37.035] <TB3> INFO: PixTestBB2Map::doTest() Ntrig = 10, VcalS = 222, PlWidth = 35
[07:28:37.035] <TB3> INFO: ######################################################################
[07:28:37.036] <TB3> INFO: ----------------------------------------------------------------------
[07:28:37.036] <TB3> INFO: PixTestBB2Map::setVana() target Ia = 24 mA/ROC
[07:28:37.036] <TB3> INFO: ----------------------------------------------------------------------
[07:28:41.525] <TB3> INFO: PixTestBB2Map::setVana() done, Module Ia 389.9 mA = 24.3688 mA/ROC
[07:28:41.526] <TB3> INFO: ----------------------------------------------------------------------
[07:28:41.526] <TB3> INFO: PixTestBB2Map::setVthrCompCalDel()
[07:28:41.526] <TB3> INFO: ----------------------------------------------------------------------
[07:28:41.621] <TB3> INFO: Expecting 655360 events.
[07:28:59.706] <TB3> INFO: 655360 events read in total (17530ms).
[07:28:59.710] <TB3> INFO: Test took 18177ms.
[07:29:00.022] <TB3> INFO: PixTestBB2Map::setVthrCompCalDel() done
[07:29:00.022] <TB3> INFO: CalDel: 124 121 112 123 133 126 117 124 132 117 112 132 148 123 129 161
[07:29:00.022] <TB3> INFO: VthrComp: 100 107 116 108 93 95 103 108 102 95 90 101 90 101 94 99
[07:29:00.305] <TB3> INFO: Expecting 5324800 events.
[07:29:37.253] <TB3> INFO: 1399030 events read in total (36392ms).
[07:30:13.652] <TB3> INFO: 2787670 events read in total (72791ms).
[07:30:49.588] <TB3> INFO: 4184300 events read in total (108728ms).
[07:31:18.704] <TB3> INFO: 5324800 events read in total (137843ms).
[07:31:18.730] <TB3> INFO: Test took 138688ms.
[07:31:19.317] <TB3> INFO: Missing Bumps: 44 16 17 28 17 17 12 17 2 5 3 0 0 0 2 2
[07:31:19.317] <TB3> INFO: Separation Cut: 23.70 25.50 30.94 34.57 26.84 26.33 24.67 33.25 25.57 23.54 25.37 27.71 21.06 32.28 23.92 27.66
[07:31:19.317] <TB3> INFO: PixTestBB2Map::doTest() done,162 seconds
[07:31:19.714] <TB3> INFO: enter test to run
[07:31:19.714] <TB3> INFO: test: no parameter change
[07:31:19.771] <TB3> QUIET: Connection to board 126 closed.
[07:31:19.851] <TB3> INFO: pXar: this is the end, my friend