[07:32:22.143] <TB2> INFO: *** Welcome to pxar ***
[07:32:22.143] <TB2> INFO: *** Today: 2016/07/29
[07:32:22.199] <TB2> INFO: *** Version: f5d3-dirty
[07:32:22.199] <TB2> INFO: readRocDacs: /home/Reception/DATA/KITAACHEN/M4787_Reception_2016-07-29_09h26m_1469777213//000_ReceptionTestBB4_p17//dacParameters_C0.dat .. /home/Reception/DATA/KITAACHEN/M4787_Reception_2016-07-29_09h26m_1469777213//000_ReceptionTestBB4_p17//dacParameters_C15.dat
[07:32:22.215] <TB2> INFO: readTbmDacs: /home/Reception/DATA/KITAACHEN/M4787_Reception_2016-07-29_09h26m_1469777213//000_ReceptionTestBB4_p17//tbmParameters_C0a.dat .. /home/Reception/DATA/KITAACHEN/M4787_Reception_2016-07-29_09h26m_1469777213//000_ReceptionTestBB4_p17//tbmParameters_C0b.dat
[07:32:22.216] <TB2> INFO: readMaskFile: /home/Reception/DATA/KITAACHEN/M4787_Reception_2016-07-29_09h26m_1469777213//000_ReceptionTestBB4_p17//defaultMaskFile.dat
[07:32:22.217] <TB2> INFO: readTrimFile: /home/Reception/DATA/KITAACHEN/M4787_Reception_2016-07-29_09h26m_1469777213//000_ReceptionTestBB4_p17//trimParameters_C0.dat .. /home/Reception/DATA/KITAACHEN/M4787_Reception_2016-07-29_09h26m_1469777213//000_ReceptionTestBB4_p17//trimParameters_C15.dat
[07:32:22.323] <TB2> INFO: clk: 4
[07:32:22.323] <TB2> INFO: ctr: 4
[07:32:22.323] <TB2> INFO: sda: 19
[07:32:22.323] <TB2> INFO: tin: 9
[07:32:22.323] <TB2> INFO: level: 15
[07:32:22.323] <TB2> INFO: triggerdelay: 0
[07:32:22.323] <TB2> QUIET: Instanciating API for pxar v2.7.5+40~g4fce89b
[07:32:22.323] <TB2> INFO: Log level: INFO
[07:32:22.330] <TB2> INFO: Found DTB DTB_WWXUD2
[07:32:22.340] <TB2> QUIET: Connection to board DTB_WWXUD2 opened.
[07:32:22.342] <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:
------------------------------------------------------
[07:32:22.344] <TB2> INFO: RPC call hashes of host and DTB match: 484264910
[07:32:23.865] <TB2> INFO: DUT info:
[07:32:23.865] <TB2> INFO: The DUT currently contains the following objects:
[07:32:23.865] <TB2> INFO: 2 TBM Cores tbm08c (2 ON)
[07:32:23.865] <TB2> INFO: TBM Core alpha (0): 7 registers set
[07:32:23.865] <TB2> INFO: TBM Core beta (1): 7 registers set
[07:32:23.865] <TB2> INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[07:32:23.865] <TB2> INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[07:32:23.865] <TB2> INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[07:32:23.865] <TB2> INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[07:32:23.865] <TB2> INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[07:32:23.866] <TB2> INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[07:32:23.866] <TB2> INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[07:32:23.866] <TB2> INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[07:32:23.866] <TB2> INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[07:32:23.866] <TB2> INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[07:32:23.866] <TB2> INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[07:32:23.866] <TB2> INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[07:32:23.866] <TB2> INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[07:32:23.866] <TB2> INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[07:32:23.866] <TB2> INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[07:32:23.866] <TB2> INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[07:32:23.866] <TB2> INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[07:32:24.268] <TB2> INFO: enter 'restricted' command line mode
[07:32:24.268] <TB2> INFO: enter test to run
[07:32:24.268] <TB2> INFO: test: pretest no parameter change
[07:32:24.268] <TB2> INFO: running: pretest
[07:32:24.274] <TB2> INFO: ----------------------------------------------------------------------
[07:32:24.275] <TB2> INFO: PixTestPretest::programROC()
[07:32:24.275] <TB2> INFO: ----------------------------------------------------------------------
[07:32:42.294] <TB2> INFO: PixTestPretest::programROC() done: ROCs are all programmable
[07:32:42.295] <TB2> INFO: IA differences per ROC: 18.5 21.7 19.3 20.1 20.1 20.1 20.9 19.3 20.1 21.7 20.1 16.9 18.5 20.1 20.1 18.5
[07:32:42.411] <TB2> INFO: enter test to run
[07:32:42.411] <TB2> INFO: test: pretest no parameter change
[07:32:42.411] <TB2> INFO: running: pretest
[07:32:42.412] <TB2> INFO: ----------------------------------------------------------------------
[07:32:42.412] <TB2> INFO: PixTestPretest::setVana() target Ia = 24 mA/ROC
[07:32:42.412] <TB2> INFO: ----------------------------------------------------------------------
[07:33:03.738] <TB2> INFO: PixTestPretest::setVana() done, Module Ia 396.3 mA = 24.7688 mA/ROC
[07:33:03.739] <TB2> INFO: i(loss) [mA/ROC]: 20.1 20.1 19.3 19.3 19.3 19.3 18.5 19.3 20.1 19.3 19.3 19.3 18.5 19.3 19.3 19.3
[07:33:03.783] <TB2> INFO: enter test to run
[07:33:03.783] <TB2> INFO: test: pretest no parameter change
[07:33:03.784] <TB2> INFO: running: pretest
[07:33:03.784] <TB2> INFO: ----------------------------------------------------------------------
[07:33:03.784] <TB2> INFO: PixTestPretest::findTiming()
[07:33:03.784] <TB2> INFO: ----------------------------------------------------------------------
[07:33:03.784] <TB2> INFO: PixTestCmd::init()
[07:33:04.459] <TB2> WARNING: Not unmasking DUT, not setting Calibrate bits!
[07:34:04.555] <TB2> INFO: TBM phases: 160MHz: 7, 400MHz: 2, TBM delays: ROC(0/1):4, header/trailer: 1, token: 1
[07:34:04.555] <TB2> INFO: (success/tries = 100/100), width = 3
[07:34:04.557] <TB2> INFO: enter test to run
[07:34:04.557] <TB2> INFO: test: pretest no parameter change
[07:34:04.557] <TB2> INFO: running: pretest
[07:34:04.559] <TB2> INFO: ----------------------------------------------------------------------
[07:34:04.559] <TB2> INFO: PixTestPretest::findWorkingPixel()
[07:34:04.559] <TB2> INFO: ----------------------------------------------------------------------
[07:34:04.654] <TB2> INFO: Expecting 231680 events.
[07:34:10.337] <TB2> WARNING: Detected DESER400 trailer error bits: "CODE ERROR"
[07:34:13.812] <TB2> INFO: 231680 events read in total (8601ms).
[07:34:13.818] <TB2> INFO: Test took 9254ms.
[07:34:14.116] <TB2> INFO: Found working pixel in all ROCs: col/row = 12/22
[07:34:14.195] <TB2> INFO: enter test to run
[07:34:14.195] <TB2> INFO: test: pretest no parameter change
[07:34:14.195] <TB2> INFO: running: pretest
[07:34:14.197] <TB2> INFO: ----------------------------------------------------------------------
[07:34:14.197] <TB2> INFO: PixTestPretest::setVthrCompCalDel()
[07:34:14.197] <TB2> INFO: ----------------------------------------------------------------------
[07:34:14.292] <TB2> INFO: Expecting 231680 events.
[07:34:23.471] <TB2> INFO: 231680 events read in total (8623ms).
[07:34:23.476] <TB2> INFO: Test took 9274ms.
[07:34:23.754] <TB2> INFO: PixTestPretest::setVthrCompCalDel() done
[07:34:23.754] <TB2> INFO: CalDel: 99 128 124 127 126 119 138 130 143 129 97 149 160 93 114 124
[07:34:23.754] <TB2> INFO: VthrComp: 56 52 51 51 51 51 51 51 51 51 52 51 51 51 54 51
[07:34:23.788] <TB2> INFO: enter test to run
[07:34:23.788] <TB2> INFO: test: pretest no parameter change
[07:34:23.788] <TB2> INFO: running: pretest
[07:34:23.793] <TB2> INFO: write dac parameters into /home/Reception/DATA/KITAACHEN/M4787_Reception_2016-07-29_09h26m_1469777213//000_ReceptionTestBB4_p17//dacParameters_C0.dat
[07:34:23.798] <TB2> INFO: write dac parameters into /home/Reception/DATA/KITAACHEN/M4787_Reception_2016-07-29_09h26m_1469777213//000_ReceptionTestBB4_p17//dacParameters_C1.dat
[07:34:23.804] <TB2> INFO: write dac parameters into /home/Reception/DATA/KITAACHEN/M4787_Reception_2016-07-29_09h26m_1469777213//000_ReceptionTestBB4_p17//dacParameters_C2.dat
[07:34:23.810] <TB2> INFO: write dac parameters into /home/Reception/DATA/KITAACHEN/M4787_Reception_2016-07-29_09h26m_1469777213//000_ReceptionTestBB4_p17//dacParameters_C3.dat
[07:34:23.815] <TB2> INFO: write dac parameters into /home/Reception/DATA/KITAACHEN/M4787_Reception_2016-07-29_09h26m_1469777213//000_ReceptionTestBB4_p17//dacParameters_C4.dat
[07:34:23.820] <TB2> INFO: write dac parameters into /home/Reception/DATA/KITAACHEN/M4787_Reception_2016-07-29_09h26m_1469777213//000_ReceptionTestBB4_p17//dacParameters_C5.dat
[07:34:23.825] <TB2> INFO: write dac parameters into /home/Reception/DATA/KITAACHEN/M4787_Reception_2016-07-29_09h26m_1469777213//000_ReceptionTestBB4_p17//dacParameters_C6.dat
[07:34:23.830] <TB2> INFO: write dac parameters into /home/Reception/DATA/KITAACHEN/M4787_Reception_2016-07-29_09h26m_1469777213//000_ReceptionTestBB4_p17//dacParameters_C7.dat
[07:34:23.835] <TB2> INFO: write dac parameters into /home/Reception/DATA/KITAACHEN/M4787_Reception_2016-07-29_09h26m_1469777213//000_ReceptionTestBB4_p17//dacParameters_C8.dat
[07:34:23.840] <TB2> INFO: write dac parameters into /home/Reception/DATA/KITAACHEN/M4787_Reception_2016-07-29_09h26m_1469777213//000_ReceptionTestBB4_p17//dacParameters_C9.dat
[07:34:23.846] <TB2> INFO: write dac parameters into /home/Reception/DATA/KITAACHEN/M4787_Reception_2016-07-29_09h26m_1469777213//000_ReceptionTestBB4_p17//dacParameters_C10.dat
[07:34:23.851] <TB2> INFO: write dac parameters into /home/Reception/DATA/KITAACHEN/M4787_Reception_2016-07-29_09h26m_1469777213//000_ReceptionTestBB4_p17//dacParameters_C11.dat
[07:34:23.856] <TB2> INFO: write dac parameters into /home/Reception/DATA/KITAACHEN/M4787_Reception_2016-07-29_09h26m_1469777213//000_ReceptionTestBB4_p17//dacParameters_C12.dat
[07:34:23.862] <TB2> INFO: write dac parameters into /home/Reception/DATA/KITAACHEN/M4787_Reception_2016-07-29_09h26m_1469777213//000_ReceptionTestBB4_p17//dacParameters_C13.dat
[07:34:23.868] <TB2> INFO: write dac parameters into /home/Reception/DATA/KITAACHEN/M4787_Reception_2016-07-29_09h26m_1469777213//000_ReceptionTestBB4_p17//dacParameters_C14.dat
[07:34:23.873] <TB2> INFO: write dac parameters into /home/Reception/DATA/KITAACHEN/M4787_Reception_2016-07-29_09h26m_1469777213//000_ReceptionTestBB4_p17//dacParameters_C15.dat
[07:34:23.876] <TB2> INFO: enter test to run
[07:34:23.876] <TB2> INFO: test: PixelAlive no parameter change
[07:34:23.876] <TB2> INFO: running: pixelalive
[07:34:23.877] <TB2> INFO: ######################################################################
[07:34:23.877] <TB2> INFO: PixTestAlive::doTest()
[07:34:23.877] <TB2> INFO: ######################################################################
[07:34:23.878] <TB2> INFO: ----------------------------------------------------------------------
[07:34:23.878] <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)
[07:34:23.878] <TB2> INFO: ----------------------------------------------------------------------
[07:34:24.157] <TB2> INFO: Expecting 41600 events.
[07:34:27.541] <TB2> INFO: 41600 events read in total (2828ms).
[07:34:27.542] <TB2> INFO: Test took 3662ms.
[07:34:27.852] <TB2> INFO: PixTestAlive::aliveTest() done
[07:34:27.852] <TB2> INFO: number of dead pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[07:34:27.853] <TB2> INFO: ----------------------------------------------------------------------
[07:34:27.853] <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)
[07:34:27.853] <TB2> INFO: ----------------------------------------------------------------------
[07:34:28.122] <TB2> INFO: Expecting 41600 events.
[07:34:30.726] <TB2> INFO: 41600 events read in total (2048ms).
[07:34:30.726] <TB2> INFO: Test took 2871ms.
[07:34:30.727] <TB2> INFO: mask vs. old pixelAlive PixelAlive_C0_V0 .. PixelAlive_C15_V0
[07:34:31.052] <TB2> INFO: PixTestAlive::maskTest() done
[07:34:31.052] <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
[07:34:31.053] <TB2> INFO: ----------------------------------------------------------------------
[07:34:31.053] <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)
[07:34:31.053] <TB2> INFO: ----------------------------------------------------------------------
[07:34:31.316] <TB2> INFO: Expecting 41600 events.
[07:34:34.729] <TB2> INFO: 41600 events read in total (2857ms).
[07:34:34.730] <TB2> INFO: Test took 3675ms.
[07:34:35.040] <TB2> INFO: PixTestAlive::addressDecodingTest() done
[07:34:35.040] <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
[07:34:35.040] <TB2> INFO: PixTestAlive::doTest() done, duration: 11 seconds
[07:34:35.045] <TB2> INFO: enter test to run
[07:34:35.045] <TB2> INFO: test: BB no parameter change
[07:34:35.045] <TB2> INFO: running: bb
[07:34:35.047] <TB2> INFO: ######################################################################
[07:34:35.047] <TB2> INFO: PixTestBBMap::doTest() Ntrig = 5, VcalS = 250 (high range)
[07:34:35.047] <TB2> INFO: ######################################################################
[07:34:35.051] <TB2> INFO: ---> dac: VthrComp name: calSMap ntrig: 5 dacrange: 0 .. 149 (-1/-1) hits flags = 514 (plus default)
[07:34:35.102] <TB2> INFO: dacScan split into 1 runs with ntrig = 5
[07:34:35.102] <TB2> INFO: run 1 of 1
[07:34:35.369] <TB2> INFO: Expecting 3120000 events.
[07:35:18.867] <TB2> INFO: 1102855 events read in total (42943ms).
[07:36:01.565] <TB2> INFO: 2186315 events read in total (85641ms).
[07:36:38.364] <TB2> INFO: 3120000 events read in total (122440ms).
[07:36:38.424] <TB2> INFO: Test took 123323ms.
[07:37:02.668] <TB2> INFO: PixTestBBMap::doTest() done, duration: 147 seconds
[07:37:02.668] <TB2> INFO: number of dead bumps (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[07:37:02.668] <TB2> INFO: separation cut (per ROC): 119 112 104 105 92 105 102 112 123 108 118 111 112 113 111 102
[07:37:02.736] <TB2> INFO: enter test to run
[07:37:02.736] <TB2> INFO: test: Readback no parameter change
[07:37:02.736] <TB2> INFO: running: readback
[07:37:02.736] <TB2> INFO: readReadbackCal: /home/Reception/DATA/KITAACHEN/M4787_Reception_2016-07-29_09h26m_1469777213//000_ReceptionTestBB4_p17//readbackCal_C0.dat .. /home/Reception/DATA/KITAACHEN/M4787_Reception_2016-07-29_09h26m_1469777213//000_ReceptionTestBB4_p17//readbackCal_C15.dat
[07:37:02.752] <TB2> INFO: readGainPedestalParameters /home/Reception/DATA/KITAACHEN/M4787_Reception_2016-07-29_09h26m_1469777213//000_ReceptionTestBB4_p17//phCalibrationFitErr_C0.dat .. /home/Reception/DATA/KITAACHEN/M4787_Reception_2016-07-29_09h26m_1469777213//000_ReceptionTestBB4_p17//phCalibrationFitErr_C15.dat
[07:37:02.752] <TB2> ERROR: <ConfigParameters.cc/readGainPedestalParameters:L996> cannot open /home/Reception/DATA/KITAACHEN/M4787_Reception_2016-07-29_09h26m_1469777213//000_ReceptionTestBB4_p17//phCalibrationFitErr_C0.dat for reading PH calibration constants
[07:37:02.752] <TB2> INFO: ######################################################################
[07:37:02.752] <TB2> INFO: PixTestReadback::doTest()
[07:37:02.752] <TB2> INFO: ######################################################################
[07:37:02.752] <TB2> INFO: ----------------------------------------------------------------------
[07:37:02.752] <TB2> INFO: PixTestReadback::CalibrateVd()
[07:37:02.752] <TB2> INFO: ----------------------------------------------------------------------
[07:37:12.078] <TB2> INFO: write readback calibration parameters into /home/Reception/DATA/KITAACHEN/M4787_Reception_2016-07-29_09h26m_1469777213//000_ReceptionTestBB4_p17//readbackCal_C0.dat
[07:37:12.083] <TB2> INFO: write readback calibration parameters into /home/Reception/DATA/KITAACHEN/M4787_Reception_2016-07-29_09h26m_1469777213//000_ReceptionTestBB4_p17//readbackCal_C1.dat
[07:37:12.091] <TB2> INFO: write readback calibration parameters into /home/Reception/DATA/KITAACHEN/M4787_Reception_2016-07-29_09h26m_1469777213//000_ReceptionTestBB4_p17//readbackCal_C2.dat
[07:37:12.096] <TB2> INFO: write readback calibration parameters into /home/Reception/DATA/KITAACHEN/M4787_Reception_2016-07-29_09h26m_1469777213//000_ReceptionTestBB4_p17//readbackCal_C3.dat
[07:37:12.104] <TB2> INFO: write readback calibration parameters into /home/Reception/DATA/KITAACHEN/M4787_Reception_2016-07-29_09h26m_1469777213//000_ReceptionTestBB4_p17//readbackCal_C4.dat
[07:37:12.109] <TB2> INFO: write readback calibration parameters into /home/Reception/DATA/KITAACHEN/M4787_Reception_2016-07-29_09h26m_1469777213//000_ReceptionTestBB4_p17//readbackCal_C5.dat
[07:37:12.114] <TB2> INFO: write readback calibration parameters into /home/Reception/DATA/KITAACHEN/M4787_Reception_2016-07-29_09h26m_1469777213//000_ReceptionTestBB4_p17//readbackCal_C6.dat
[07:37:12.121] <TB2> INFO: write readback calibration parameters into /home/Reception/DATA/KITAACHEN/M4787_Reception_2016-07-29_09h26m_1469777213//000_ReceptionTestBB4_p17//readbackCal_C7.dat
[07:37:12.126] <TB2> INFO: write readback calibration parameters into /home/Reception/DATA/KITAACHEN/M4787_Reception_2016-07-29_09h26m_1469777213//000_ReceptionTestBB4_p17//readbackCal_C8.dat
[07:37:12.132] <TB2> INFO: write readback calibration parameters into /home/Reception/DATA/KITAACHEN/M4787_Reception_2016-07-29_09h26m_1469777213//000_ReceptionTestBB4_p17//readbackCal_C9.dat
[07:37:12.137] <TB2> INFO: write readback calibration parameters into /home/Reception/DATA/KITAACHEN/M4787_Reception_2016-07-29_09h26m_1469777213//000_ReceptionTestBB4_p17//readbackCal_C10.dat
[07:37:12.142] <TB2> INFO: write readback calibration parameters into /home/Reception/DATA/KITAACHEN/M4787_Reception_2016-07-29_09h26m_1469777213//000_ReceptionTestBB4_p17//readbackCal_C11.dat
[07:37:12.150] <TB2> INFO: write readback calibration parameters into /home/Reception/DATA/KITAACHEN/M4787_Reception_2016-07-29_09h26m_1469777213//000_ReceptionTestBB4_p17//readbackCal_C12.dat
[07:37:12.156] <TB2> INFO: write readback calibration parameters into /home/Reception/DATA/KITAACHEN/M4787_Reception_2016-07-29_09h26m_1469777213//000_ReceptionTestBB4_p17//readbackCal_C13.dat
[07:37:12.162] <TB2> INFO: write readback calibration parameters into /home/Reception/DATA/KITAACHEN/M4787_Reception_2016-07-29_09h26m_1469777213//000_ReceptionTestBB4_p17//readbackCal_C14.dat
[07:37:12.169] <TB2> INFO: write readback calibration parameters into /home/Reception/DATA/KITAACHEN/M4787_Reception_2016-07-29_09h26m_1469777213//000_ReceptionTestBB4_p17//readbackCal_C15.dat
[07:37:12.218] <TB2> INFO: PixTestPattern:: pg_setup set to default.
[07:37:12.218] <TB2> INFO: ----------------------------------------------------------------------
[07:37:12.218] <TB2> INFO: PixTestReadback::CalibrateVa()
[07:37:12.218] <TB2> INFO: ----------------------------------------------------------------------
[07:37:21.586] <TB2> INFO: write readback calibration parameters into /home/Reception/DATA/KITAACHEN/M4787_Reception_2016-07-29_09h26m_1469777213//000_ReceptionTestBB4_p17//readbackCal_C0.dat
[07:37:21.591] <TB2> INFO: write readback calibration parameters into /home/Reception/DATA/KITAACHEN/M4787_Reception_2016-07-29_09h26m_1469777213//000_ReceptionTestBB4_p17//readbackCal_C1.dat
[07:37:21.597] <TB2> INFO: write readback calibration parameters into /home/Reception/DATA/KITAACHEN/M4787_Reception_2016-07-29_09h26m_1469777213//000_ReceptionTestBB4_p17//readbackCal_C2.dat
[07:37:21.604] <TB2> INFO: write readback calibration parameters into /home/Reception/DATA/KITAACHEN/M4787_Reception_2016-07-29_09h26m_1469777213//000_ReceptionTestBB4_p17//readbackCal_C3.dat
[07:37:21.609] <TB2> INFO: write readback calibration parameters into /home/Reception/DATA/KITAACHEN/M4787_Reception_2016-07-29_09h26m_1469777213//000_ReceptionTestBB4_p17//readbackCal_C4.dat
[07:37:21.615] <TB2> INFO: write readback calibration parameters into /home/Reception/DATA/KITAACHEN/M4787_Reception_2016-07-29_09h26m_1469777213//000_ReceptionTestBB4_p17//readbackCal_C5.dat
[07:37:21.623] <TB2> INFO: write readback calibration parameters into /home/Reception/DATA/KITAACHEN/M4787_Reception_2016-07-29_09h26m_1469777213//000_ReceptionTestBB4_p17//readbackCal_C6.dat
[07:37:21.630] <TB2> INFO: write readback calibration parameters into /home/Reception/DATA/KITAACHEN/M4787_Reception_2016-07-29_09h26m_1469777213//000_ReceptionTestBB4_p17//readbackCal_C7.dat
[07:37:21.635] <TB2> INFO: write readback calibration parameters into /home/Reception/DATA/KITAACHEN/M4787_Reception_2016-07-29_09h26m_1469777213//000_ReceptionTestBB4_p17//readbackCal_C8.dat
[07:37:21.640] <TB2> INFO: write readback calibration parameters into /home/Reception/DATA/KITAACHEN/M4787_Reception_2016-07-29_09h26m_1469777213//000_ReceptionTestBB4_p17//readbackCal_C9.dat
[07:37:21.645] <TB2> INFO: write readback calibration parameters into /home/Reception/DATA/KITAACHEN/M4787_Reception_2016-07-29_09h26m_1469777213//000_ReceptionTestBB4_p17//readbackCal_C10.dat
[07:37:21.650] <TB2> INFO: write readback calibration parameters into /home/Reception/DATA/KITAACHEN/M4787_Reception_2016-07-29_09h26m_1469777213//000_ReceptionTestBB4_p17//readbackCal_C11.dat
[07:37:21.656] <TB2> INFO: write readback calibration parameters into /home/Reception/DATA/KITAACHEN/M4787_Reception_2016-07-29_09h26m_1469777213//000_ReceptionTestBB4_p17//readbackCal_C12.dat
[07:37:21.661] <TB2> INFO: write readback calibration parameters into /home/Reception/DATA/KITAACHEN/M4787_Reception_2016-07-29_09h26m_1469777213//000_ReceptionTestBB4_p17//readbackCal_C13.dat
[07:37:21.666] <TB2> INFO: write readback calibration parameters into /home/Reception/DATA/KITAACHEN/M4787_Reception_2016-07-29_09h26m_1469777213//000_ReceptionTestBB4_p17//readbackCal_C14.dat
[07:37:21.671] <TB2> INFO: write readback calibration parameters into /home/Reception/DATA/KITAACHEN/M4787_Reception_2016-07-29_09h26m_1469777213//000_ReceptionTestBB4_p17//readbackCal_C15.dat
[07:37:21.733] <TB2> INFO: PixTestPattern:: pg_setup set to default.
[07:37:21.733] <TB2> INFO: ----------------------------------------------------------------------
[07:37:21.733] <TB2> INFO: PixTestReadback::readbackVbg()
[07:37:21.733] <TB2> INFO: ----------------------------------------------------------------------
[07:37:28.913] <TB2> INFO: PixTestPattern:: pg_setup set to default.
[07:37:28.914] <TB2> INFO: ----------------------------------------------------------------------
[07:37:28.914] <TB2> INFO: PixTestReadback::getCalibratedVbg()
[07:37:28.914] <TB2> INFO: ----------------------------------------------------------------------
[07:37:28.914] <TB2> INFO: Vbg will be calibrated using Vd calibration
[07:37:28.914] <TB2> INFO: /*/*/*/*::: ROC 0: uncalibrated Vbg = 150.7calibrated Vbg = 1.23121 :::*/*/*/*/
[07:37:28.914] <TB2> INFO: /*/*/*/*::: ROC 1: uncalibrated Vbg = 149.9calibrated Vbg = 1.22874 :::*/*/*/*/
[07:37:28.914] <TB2> INFO: /*/*/*/*::: ROC 2: uncalibrated Vbg = 149.5calibrated Vbg = 1.23453 :::*/*/*/*/
[07:37:28.914] <TB2> INFO: /*/*/*/*::: ROC 3: uncalibrated Vbg = 156calibrated Vbg = 1.24023 :::*/*/*/*/
[07:37:28.914] <TB2> INFO: /*/*/*/*::: ROC 4: uncalibrated Vbg = 149calibrated Vbg = 1.24244 :::*/*/*/*/
[07:37:28.914] <TB2> INFO: /*/*/*/*::: ROC 5: uncalibrated Vbg = 154.6calibrated Vbg = 1.24525 :::*/*/*/*/
[07:37:28.914] <TB2> INFO: /*/*/*/*::: ROC 6: uncalibrated Vbg = 152.3calibrated Vbg = 1.243 :::*/*/*/*/
[07:37:28.914] <TB2> INFO: /*/*/*/*::: ROC 7: uncalibrated Vbg = 148.3calibrated Vbg = 1.2472 :::*/*/*/*/
[07:37:28.914] <TB2> INFO: /*/*/*/*::: ROC 8: uncalibrated Vbg = 149.8calibrated Vbg = 1.24621 :::*/*/*/*/
[07:37:28.914] <TB2> INFO: /*/*/*/*::: ROC 9: uncalibrated Vbg = 152calibrated Vbg = 1.24858 :::*/*/*/*/
[07:37:28.914] <TB2> INFO: /*/*/*/*::: ROC 10: uncalibrated Vbg = 148.2calibrated Vbg = 1.23525 :::*/*/*/*/
[07:37:28.914] <TB2> INFO: /*/*/*/*::: ROC 11: uncalibrated Vbg = 149.1calibrated Vbg = 1.23679 :::*/*/*/*/
[07:37:28.914] <TB2> INFO: /*/*/*/*::: ROC 12: uncalibrated Vbg = 152.7calibrated Vbg = 1.23301 :::*/*/*/*/
[07:37:28.914] <TB2> INFO: /*/*/*/*::: ROC 13: uncalibrated Vbg = 154.5calibrated Vbg = 1.22671 :::*/*/*/*/
[07:37:28.914] <TB2> INFO: /*/*/*/*::: ROC 14: uncalibrated Vbg = 159.3calibrated Vbg = 1.23029 :::*/*/*/*/
[07:37:28.914] <TB2> INFO: /*/*/*/*::: ROC 15: uncalibrated Vbg = 154.3calibrated Vbg = 1.23067 :::*/*/*/*/
[07:37:28.918] <TB2> INFO: ----------------------------------------------------------------------
[07:37:28.918] <TB2> INFO: PixTestReadback::CalibrateIa()
[07:37:28.918] <TB2> INFO: ----------------------------------------------------------------------
[07:40:02.312] <TB2> INFO: write readback calibration parameters into /home/Reception/DATA/KITAACHEN/M4787_Reception_2016-07-29_09h26m_1469777213//000_ReceptionTestBB4_p17//readbackCal_C0.dat
[07:40:02.318] <TB2> INFO: write readback calibration parameters into /home/Reception/DATA/KITAACHEN/M4787_Reception_2016-07-29_09h26m_1469777213//000_ReceptionTestBB4_p17//readbackCal_C1.dat
[07:40:02.323] <TB2> INFO: write readback calibration parameters into /home/Reception/DATA/KITAACHEN/M4787_Reception_2016-07-29_09h26m_1469777213//000_ReceptionTestBB4_p17//readbackCal_C2.dat
[07:40:02.328] <TB2> INFO: write readback calibration parameters into /home/Reception/DATA/KITAACHEN/M4787_Reception_2016-07-29_09h26m_1469777213//000_ReceptionTestBB4_p17//readbackCal_C3.dat
[07:40:02.335] <TB2> INFO: write readback calibration parameters into /home/Reception/DATA/KITAACHEN/M4787_Reception_2016-07-29_09h26m_1469777213//000_ReceptionTestBB4_p17//readbackCal_C4.dat
[07:40:02.340] <TB2> INFO: write readback calibration parameters into /home/Reception/DATA/KITAACHEN/M4787_Reception_2016-07-29_09h26m_1469777213//000_ReceptionTestBB4_p17//readbackCal_C5.dat
[07:40:02.345] <TB2> INFO: write readback calibration parameters into /home/Reception/DATA/KITAACHEN/M4787_Reception_2016-07-29_09h26m_1469777213//000_ReceptionTestBB4_p17//readbackCal_C6.dat
[07:40:02.350] <TB2> INFO: write readback calibration parameters into /home/Reception/DATA/KITAACHEN/M4787_Reception_2016-07-29_09h26m_1469777213//000_ReceptionTestBB4_p17//readbackCal_C7.dat
[07:40:02.356] <TB2> INFO: write readback calibration parameters into /home/Reception/DATA/KITAACHEN/M4787_Reception_2016-07-29_09h26m_1469777213//000_ReceptionTestBB4_p17//readbackCal_C8.dat
[07:40:02.360] <TB2> INFO: write readback calibration parameters into /home/Reception/DATA/KITAACHEN/M4787_Reception_2016-07-29_09h26m_1469777213//000_ReceptionTestBB4_p17//readbackCal_C9.dat
[07:40:02.367] <TB2> INFO: write readback calibration parameters into /home/Reception/DATA/KITAACHEN/M4787_Reception_2016-07-29_09h26m_1469777213//000_ReceptionTestBB4_p17//readbackCal_C10.dat
[07:40:02.372] <TB2> INFO: write readback calibration parameters into /home/Reception/DATA/KITAACHEN/M4787_Reception_2016-07-29_09h26m_1469777213//000_ReceptionTestBB4_p17//readbackCal_C11.dat
[07:40:02.377] <TB2> INFO: write readback calibration parameters into /home/Reception/DATA/KITAACHEN/M4787_Reception_2016-07-29_09h26m_1469777213//000_ReceptionTestBB4_p17//readbackCal_C12.dat
[07:40:02.383] <TB2> INFO: write readback calibration parameters into /home/Reception/DATA/KITAACHEN/M4787_Reception_2016-07-29_09h26m_1469777213//000_ReceptionTestBB4_p17//readbackCal_C13.dat
[07:40:02.392] <TB2> INFO: write readback calibration parameters into /home/Reception/DATA/KITAACHEN/M4787_Reception_2016-07-29_09h26m_1469777213//000_ReceptionTestBB4_p17//readbackCal_C14.dat
[07:40:02.397] <TB2> INFO: write readback calibration parameters into /home/Reception/DATA/KITAACHEN/M4787_Reception_2016-07-29_09h26m_1469777213//000_ReceptionTestBB4_p17//readbackCal_C15.dat
[07:40:02.447] <TB2> INFO: PixTestPattern:: pg_setup set to default.
[07:40:02.450] <TB2> INFO: PixTestReadback::doTest() done
[07:40:02.478] <TB2> INFO: enter test to run
[07:40:02.478] <TB2> INFO: test: BB4 no parameter change
[07:40:02.478] <TB2> INFO: running: bb4
[07:40:02.478] <TB2> INFO: PixTestBB4Map::doTest() ntrig = 100
[07:40:02.478] <TB2> INFO: VthrComp (min,max,step) = 70 130 5
[07:40:02.478] <TB2> INFO: CalDel (min,max,step) = 80 200 10
[07:40:02.478] <TB2> INFO: Vcal = 255
[07:40:02.478] <TB2> INFO: Cut = 0.5
[07:40:02.478] <TB2> INFO: flag 2
[07:40:02.480] <TB2> INFO: CtrlReg 4 (large Vcal)
[07:40:02.480] <TB2> INFO: saving VthrComp and CalDel before running tests)
[07:40:02.480] <TB2> INFO: 0 130 0
[07:40:02.480] <TB2> INFO: 1 130 0
[07:40:02.480] <TB2> INFO: 2 130 0
[07:40:02.480] <TB2> INFO: 3 130 0
[07:40:02.480] <TB2> INFO: 4 130 0
[07:40:02.480] <TB2> INFO: 5 130 0
[07:40:02.480] <TB2> INFO: 6 130 0
[07:40:02.480] <TB2> INFO: 7 130 0
[07:40:02.480] <TB2> INFO: 8 130 0
[07:40:02.480] <TB2> INFO: 9 130 0
[07:40:02.480] <TB2> INFO: 10 130 0
[07:40:02.480] <TB2> INFO: 11 130 0
[07:40:02.480] <TB2> INFO: 12 130 0
[07:40:02.480] <TB2> INFO: 13 130 0
[07:40:02.480] <TB2> INFO: 14 130 0
[07:40:02.480] <TB2> INFO: 15 130 0
[07:40:02.749] <TB2> INFO: Expecting 416000 events.
[07:40:07.401] <TB2> ERROR: <datapipe.cc/CheckEventValidity:L507> Channel 0 Number of ROCs (0) != Token Chain Length (8)
[07:40:07.429] <TB2> ERROR: <datapipe.cc/CheckEventID:L469> Channel 0 Event ID mismatch: local ID (16) != TBM ID (2)
[07:40:12.157] <TB2> INFO: 416000 events read in total (8852ms).
[07:40:12.158] <TB2> INFO: Test took 9673ms.
[07:40:12.158] <TB2> INFO: starting loop over VthrComp
[07:40:12.708] <TB2> INFO: Expecting 416000 events.
[07:40:22.143] <TB2> INFO: 416000 events read in total (8878ms).
[07:40:22.143] <TB2> INFO: Test took 9709ms.
[07:40:22.143] <TB2> INFO: got noisemap
[07:40:22.420] <TB2> INFO: ROC 0, integral 0
[07:40:22.420] <TB2> INFO: ROC 1, integral 0
[07:40:22.420] <TB2> INFO: ROC 2, integral 0
[07:40:22.420] <TB2> INFO: ROC 3, integral 0
[07:40:22.420] <TB2> INFO: ROC 4, integral 0
[07:40:22.420] <TB2> INFO: ROC 5, integral 0
[07:40:22.420] <TB2> INFO: ROC 6, integral 0
[07:40:22.420] <TB2> INFO: ROC 7, integral 0
[07:40:22.420] <TB2> INFO: ROC 8, integral 0
[07:40:22.420] <TB2> INFO: ROC 9, integral 0
[07:40:22.421] <TB2> INFO: ROC 10, integral 0
[07:40:22.421] <TB2> INFO: ROC 11, integral 0
[07:40:22.421] <TB2> INFO: ROC 12, integral 0
[07:40:22.421] <TB2> INFO: ROC 13, integral 0
[07:40:22.421] <TB2> INFO: ROC 14, integral 0
[07:40:22.421] <TB2> INFO: ROC 15, integral 0
[07:40:22.421] <TB2> INFO: VthrComp = 70 done
[07:40:22.695] <TB2> INFO: Expecting 416000 events.
[07:40:32.061] <TB2> INFO: 416000 events read in total (8810ms).
[07:40:32.062] <TB2> INFO: Test took 9635ms.
[07:40:32.062] <TB2> INFO: got noisemap
[07:40:32.336] <TB2> INFO: ROC 0, integral 0
[07:40:32.337] <TB2> INFO: ROC 1, integral 0
[07:40:32.337] <TB2> INFO: ROC 2, integral 0
[07:40:32.337] <TB2> INFO: ROC 3, integral 0
[07:40:32.337] <TB2> INFO: ROC 4, integral 0
[07:40:32.337] <TB2> INFO: ROC 5, integral 0
[07:40:32.337] <TB2> INFO: ROC 6, integral 0
[07:40:32.337] <TB2> INFO: ROC 7, integral 0
[07:40:32.337] <TB2> INFO: ROC 8, integral 0
[07:40:32.337] <TB2> INFO: ROC 9, integral 0
[07:40:32.337] <TB2> INFO: ROC 10, integral 0
[07:40:32.337] <TB2> INFO: ROC 11, integral 0
[07:40:32.337] <TB2> INFO: ROC 12, integral 0
[07:40:32.337] <TB2> INFO: ROC 13, integral 0
[07:40:32.337] <TB2> INFO: ROC 14, integral 0
[07:40:32.338] <TB2> INFO: ROC 15, integral 0
[07:40:32.338] <TB2> INFO: VthrComp = 75 done
[07:40:32.605] <TB2> INFO: Expecting 416000 events.
[07:40:41.981] <TB2> INFO: 416000 events read in total (8820ms).
[07:40:41.982] <TB2> INFO: Test took 9640ms.
[07:40:41.982] <TB2> INFO: got noisemap
[07:40:42.258] <TB2> INFO: ROC 0, integral 0
[07:40:42.258] <TB2> INFO: ROC 1, integral 0
[07:40:42.258] <TB2> INFO: ROC 2, integral 0
[07:40:42.258] <TB2> INFO: ROC 3, integral 0
[07:40:42.258] <TB2> INFO: ROC 4, integral 0
[07:40:42.258] <TB2> INFO: ROC 5, integral 0
[07:40:42.258] <TB2> INFO: ROC 6, integral 0
[07:40:42.258] <TB2> INFO: ROC 7, integral 0
[07:40:42.258] <TB2> INFO: ROC 8, integral 0
[07:40:42.258] <TB2> INFO: ROC 9, integral 0
[07:40:42.259] <TB2> INFO: ROC 10, integral 0
[07:40:42.259] <TB2> INFO: ROC 11, integral 0
[07:40:42.259] <TB2> INFO: ROC 12, integral 0
[07:40:42.259] <TB2> INFO: ROC 13, integral 0
[07:40:42.259] <TB2> INFO: ROC 14, integral 0
[07:40:42.259] <TB2> INFO: ROC 15, integral 0
[07:40:42.259] <TB2> INFO: VthrComp = 80 done
[07:40:42.537] <TB2> INFO: Expecting 416000 events.
[07:40:51.910] <TB2> INFO: 416000 events read in total (8817ms).
[07:40:51.911] <TB2> INFO: Test took 9648ms.
[07:40:51.911] <TB2> INFO: got noisemap
[07:40:52.186] <TB2> INFO: ROC 0, integral 0
[07:40:52.186] <TB2> INFO: ROC 1, integral 0
[07:40:52.186] <TB2> INFO: ROC 2, integral 0
[07:40:52.186] <TB2> INFO: ROC 3, integral 0
[07:40:52.186] <TB2> INFO: ROC 4, integral 0
[07:40:52.186] <TB2> INFO: ROC 5, integral 0
[07:40:52.187] <TB2> INFO: ROC 6, integral 0
[07:40:52.187] <TB2> INFO: ROC 7, integral 0
[07:40:52.187] <TB2> INFO: ROC 8, integral 0
[07:40:52.187] <TB2> INFO: ROC 9, integral 0
[07:40:52.187] <TB2> INFO: ROC 10, integral 0
[07:40:52.187] <TB2> INFO: ROC 11, integral 0
[07:40:52.187] <TB2> INFO: ROC 12, integral 0
[07:40:52.187] <TB2> INFO: ROC 13, integral 0
[07:40:52.187] <TB2> INFO: ROC 14, integral 0
[07:40:52.187] <TB2> INFO: ROC 15, integral 0
[07:40:52.187] <TB2> INFO: VthrComp = 85 done
[07:40:52.458] <TB2> INFO: Expecting 416000 events.
[07:41:01.810] <TB2> INFO: 416000 events read in total (8796ms).
[07:41:01.810] <TB2> INFO: Test took 9618ms.
[07:41:01.811] <TB2> INFO: got noisemap
[07:41:02.087] <TB2> INFO: ROC 0, integral 0
[07:41:02.087] <TB2> INFO: ROC 1, integral 0
[07:41:02.087] <TB2> INFO: ROC 2, integral 0
[07:41:02.087] <TB2> INFO: ROC 3, integral 0
[07:41:02.087] <TB2> INFO: ROC 4, integral 0
[07:41:02.087] <TB2> INFO: ROC 5, integral 0
[07:41:02.087] <TB2> INFO: ROC 6, integral 0
[07:41:02.087] <TB2> INFO: ROC 7, integral 0
[07:41:02.087] <TB2> INFO: ROC 8, integral 0
[07:41:02.087] <TB2> INFO: ROC 9, integral 0
[07:41:02.087] <TB2> INFO: ROC 10, integral 0
[07:41:02.087] <TB2> INFO: ROC 11, integral 0
[07:41:02.088] <TB2> INFO: ROC 12, integral 0
[07:41:02.088] <TB2> INFO: ROC 13, integral 0
[07:41:02.088] <TB2> INFO: ROC 14, integral 0
[07:41:02.088] <TB2> INFO: ROC 15, integral 0
[07:41:02.088] <TB2> INFO: VthrComp = 90 done
[07:41:02.360] <TB2> INFO: Expecting 416000 events.
[07:41:11.732] <TB2> INFO: 416000 events read in total (8817ms).
[07:41:11.732] <TB2> INFO: Test took 9640ms.
[07:41:11.732] <TB2> INFO: got noisemap
[07:41:12.008] <TB2> INFO: ROC 0, integral 0
[07:41:12.008] <TB2> INFO: ROC 1, integral 0
[07:41:12.008] <TB2> INFO: ROC 2, integral 0
[07:41:12.008] <TB2> INFO: ROC 3, integral 0
[07:41:12.009] <TB2> INFO: ROC 4, integral 0
[07:41:12.009] <TB2> INFO: ROC 5, integral 0
[07:41:12.009] <TB2> INFO: ROC 6, integral 0
[07:41:12.009] <TB2> INFO: ROC 7, integral 0
[07:41:12.009] <TB2> INFO: ROC 8, integral 0
[07:41:12.009] <TB2> INFO: ROC 9, integral 0
[07:41:12.009] <TB2> INFO: ROC 10, integral 0
[07:41:12.009] <TB2> INFO: ROC 11, integral 0
[07:41:12.009] <TB2> INFO: ROC 12, integral 0
[07:41:12.009] <TB2> INFO: ROC 13, integral 0
[07:41:12.010] <TB2> INFO: ROC 14, integral 0
[07:41:12.010] <TB2> INFO: ROC 15, integral 0
[07:41:12.010] <TB2> INFO: VthrComp = 95 done
[07:41:12.288] <TB2> INFO: Expecting 416000 events.
[07:41:21.686] <TB2> INFO: 416000 events read in total (8843ms).
[07:41:21.687] <TB2> INFO: Test took 9673ms.
[07:41:21.687] <TB2> INFO: got noisemap
[07:41:21.962] <TB2> INFO: ROC 0, integral 0
[07:41:21.962] <TB2> INFO: ROC 1, integral 0
[07:41:21.962] <TB2> INFO: ROC 2, integral 0
[07:41:21.962] <TB2> INFO: ROC 3, integral 0
[07:41:21.962] <TB2> INFO: ROC 4, integral 0
[07:41:21.962] <TB2> INFO: ROC 5, integral 0
[07:41:21.963] <TB2> INFO: ROC 6, integral 0
[07:41:21.963] <TB2> INFO: ROC 7, integral 0
[07:41:21.963] <TB2> INFO: ROC 8, integral 0
[07:41:21.963] <TB2> INFO: ROC 9, integral 0
[07:41:21.963] <TB2> INFO: ROC 10, integral 0
[07:41:21.963] <TB2> INFO: ROC 11, integral 0
[07:41:21.963] <TB2> INFO: ROC 12, integral 0
[07:41:21.963] <TB2> INFO: ROC 13, integral 0
[07:41:21.963] <TB2> INFO: ROC 14, integral 0
[07:41:21.963] <TB2> INFO: ROC 15, integral 0
[07:41:21.963] <TB2> INFO: VthrComp = 100 done
[07:41:22.243] <TB2> INFO: Expecting 416000 events.
[07:41:31.599] <TB2> INFO: 416000 events read in total (8800ms).
[07:41:31.599] <TB2> INFO: Test took 9632ms.
[07:41:31.599] <TB2> INFO: got noisemap
[07:41:31.875] <TB2> INFO: ROC 0, integral 0
[07:41:31.875] <TB2> INFO: ROC 1, integral 0
[07:41:31.875] <TB2> INFO: ROC 2, integral 0
[07:41:31.875] <TB2> INFO: ROC 3, integral 0
[07:41:31.875] <TB2> INFO: ROC 4, integral 0
[07:41:31.875] <TB2> INFO: ROC 5, integral 0
[07:41:31.875] <TB2> INFO: ROC 6, integral 0
[07:41:31.875] <TB2> INFO: ROC 7, integral 0
[07:41:31.875] <TB2> INFO: ROC 8, integral 0
[07:41:31.875] <TB2> INFO: ROC 9, integral 0
[07:41:31.875] <TB2> INFO: ROC 10, integral 0
[07:41:31.876] <TB2> INFO: ROC 11, integral 0
[07:41:31.876] <TB2> INFO: ROC 12, integral 0
[07:41:31.876] <TB2> INFO: ROC 13, integral 0
[07:41:31.876] <TB2> INFO: ROC 14, integral 0
[07:41:31.876] <TB2> INFO: ROC 15, integral 0
[07:41:31.876] <TB2> INFO: VthrComp = 105 done
[07:41:32.157] <TB2> INFO: Expecting 416000 events.
[07:41:41.482] <TB2> INFO: 416000 events read in total (8769ms).
[07:41:41.483] <TB2> INFO: Test took 9602ms.
[07:41:41.483] <TB2> INFO: got noisemap
[07:41:41.758] <TB2> INFO: ROC 0, integral 0
[07:41:41.759] <TB2> INFO: ROC 1, integral 0
[07:41:41.759] <TB2> INFO: ROC 2, integral 0
[07:41:41.759] <TB2> INFO: ROC 3, integral 0
[07:41:41.759] <TB2> INFO: ROC 4, integral 0
[07:41:41.759] <TB2> INFO: ROC 5, integral 0
[07:41:41.759] <TB2> INFO: ROC 6, integral 0
[07:41:41.759] <TB2> INFO: ROC 7, integral 0
[07:41:41.759] <TB2> INFO: ROC 8, integral 0
[07:41:41.760] <TB2> INFO: ROC 9, integral 0
[07:41:41.760] <TB2> INFO: ROC 10, integral 0
[07:41:41.760] <TB2> INFO: ROC 11, integral 1
[07:41:41.760] <TB2> INFO: ROC 12, integral 8
[07:41:41.760] <TB2> INFO: ROC 13, integral 0
[07:41:41.760] <TB2> INFO: ROC 14, integral 0
[07:41:41.760] <TB2> INFO: ROC 15, integral 0
[07:41:41.760] <TB2> INFO: VthrComp = 110 done
[07:41:42.038] <TB2> INFO: Expecting 416000 events.
[07:41:51.345] <TB2> INFO: 416000 events read in total (8751ms).
[07:41:51.346] <TB2> INFO: Test took 9581ms.
[07:41:51.346] <TB2> INFO: got noisemap
[07:41:51.622] <TB2> INFO: ROC 0, integral 0
[07:41:51.622] <TB2> INFO: ROC 1, integral 0
[07:41:51.622] <TB2> INFO: ROC 2, integral 0
[07:41:51.622] <TB2> INFO: ROC 3, integral 0
[07:41:51.622] <TB2> INFO: ROC 4, integral 62
[07:41:51.622] <TB2> INFO: ROC #4: threshold found
[07:41:51.622] <TB2> INFO: ROC 5, integral 0
[07:41:51.622] <TB2> INFO: ROC 6, integral 1
[07:41:51.622] <TB2> INFO: ROC 7, integral 3
[07:41:51.622] <TB2> INFO: ROC 8, integral 1
[07:41:51.622] <TB2> INFO: ROC 9, integral 0
[07:41:51.622] <TB2> INFO: ROC 10, integral 0
[07:41:51.622] <TB2> INFO: ROC 11, integral 70
[07:41:51.622] <TB2> INFO: ROC #11: threshold found
[07:41:51.622] <TB2> INFO: ROC 12, integral 161
[07:41:51.622] <TB2> INFO: ROC #12: threshold found
[07:41:51.623] <TB2> INFO: ROC 13, integral 0
[07:41:51.623] <TB2> INFO: ROC 14, integral 0
[07:41:51.623] <TB2> INFO: ROC 15, integral 0
[07:41:51.623] <TB2> INFO: VthrComp = 115 done
[07:41:51.900] <TB2> INFO: Expecting 416000 events.
[07:42:01.195] <TB2> INFO: 416000 events read in total (8739ms).
[07:42:01.196] <TB2> INFO: Test took 9569ms.
[07:42:01.196] <TB2> INFO: got noisemap
[07:42:01.472] <TB2> INFO: ROC 0, integral 0
[07:42:01.472] <TB2> INFO: ROC 1, integral 0
[07:42:01.472] <TB2> INFO: ROC 2, integral 2
[07:42:01.472] <TB2> INFO: ROC 3, integral 1
[07:42:01.472] <TB2> INFO: ROC 5, integral 2
[07:42:01.472] <TB2> INFO: ROC 6, integral 1
[07:42:01.472] <TB2> INFO: ROC 7, integral 22
[07:42:01.472] <TB2> INFO: ROC #7: threshold found
[07:42:01.472] <TB2> INFO: ROC 8, integral 2
[07:42:01.472] <TB2> INFO: ROC 9, integral 1
[07:42:01.472] <TB2> INFO: ROC 10, integral 0
[07:42:01.472] <TB2> INFO: ROC 13, integral 0
[07:42:01.472] <TB2> INFO: ROC 14, integral 0
[07:42:01.473] <TB2> INFO: ROC 15, integral 0
[07:42:01.473] <TB2> INFO: VthrComp = 120 done
[07:42:01.739] <TB2> INFO: Expecting 416000 events.
[07:42:11.075] <TB2> INFO: 416000 events read in total (8780ms).
[07:42:11.076] <TB2> INFO: Test took 9599ms.
[07:42:11.077] <TB2> INFO: got noisemap
[07:42:11.350] <TB2> INFO: ROC 0, integral 1
[07:42:11.350] <TB2> INFO: ROC 1, integral 0
[07:42:11.350] <TB2> INFO: ROC 2, integral 23
[07:42:11.350] <TB2> INFO: ROC #2: threshold found
[07:42:11.351] <TB2> INFO: ROC 3, integral 6
[07:42:11.351] <TB2> INFO: ROC 5, integral 21
[07:42:11.351] <TB2> INFO: ROC #5: threshold found
[07:42:11.351] <TB2> INFO: ROC 6, integral 30
[07:42:11.351] <TB2> INFO: ROC #6: threshold found
[07:42:11.351] <TB2> INFO: ROC 8, integral 27
[07:42:11.351] <TB2> INFO: ROC #8: threshold found
[07:42:11.351] <TB2> INFO: ROC 9, integral 10
[07:42:11.351] <TB2> INFO: ROC #9: threshold found
[07:42:11.351] <TB2> INFO: ROC 10, integral 0
[07:42:11.351] <TB2> INFO: ROC 13, integral 8
[07:42:11.351] <TB2> INFO: ROC 14, integral 5
[07:42:11.351] <TB2> INFO: ROC 15, integral 19
[07:42:11.351] <TB2> INFO: ROC #15: threshold found
[07:42:11.351] <TB2> INFO: VthrComp = 125 done
[07:42:11.622] <TB2> INFO: Expecting 416000 events.
[07:42:21.005] <TB2> INFO: 416000 events read in total (8827ms).
[07:42:21.006] <TB2> INFO: Test took 9650ms.
[07:42:21.007] <TB2> INFO: got noisemap
[07:42:21.277] <TB2> INFO: ROC 0, integral 11
[07:42:21.277] <TB2> INFO: ROC #0: threshold found
[07:42:21.277] <TB2> INFO: ROC 1, integral 0
[07:42:21.277] <TB2> INFO: ROC 3, integral 56
[07:42:21.277] <TB2> INFO: ROC #3: threshold found
[07:42:21.277] <TB2> INFO: ROC 10, integral 11
[07:42:21.277] <TB2> INFO: ROC #10: threshold found
[07:42:21.277] <TB2> INFO: ROC 13, integral 49
[07:42:21.277] <TB2> INFO: ROC #13: threshold found
[07:42:21.277] <TB2> INFO: ROC 14, integral 42
[07:42:21.277] <TB2> INFO: ROC #14: threshold found
[07:42:21.277] <TB2> INFO: VthrComp = 130 done
[07:42:21.280] <TB2> INFO: resulting noise thresholds per ROC = 125 130 120 125 110 120 120 115 120 120 125 110 110 125 125 120
[07:42:21.280] <TB2> INFO: threshold scan done
[07:42:21.549] <TB2> INFO: Expecting 416000 events.
[07:42:32.214] <TB2> INFO: 416000 events read in total (10109ms).
[07:42:32.229] <TB2> INFO: Test took 10942ms.
[07:42:32.398] <TB2> INFO: ROC 0, missing bumps = 54, total number of good bumps = 4106
[07:42:32.400] <TB2> INFO: ROC 1, missing bumps = 4160, total number of good bumps = 0
[07:42:32.401] <TB2> INFO: ROC 2, missing bumps = 4160, total number of good bumps = 0
[07:42:32.402] <TB2> INFO: ROC 3, missing bumps = 4160, total number of good bumps = 0
[07:42:32.403] <TB2> INFO: ROC 4, missing bumps = 4160, total number of good bumps = 0
[07:42:32.405] <TB2> INFO: ROC 5, missing bumps = 4160, total number of good bumps = 0
[07:42:32.406] <TB2> INFO: ROC 6, missing bumps = 4160, total number of good bumps = 0
[07:42:32.408] <TB2> INFO: ROC 7, missing bumps = 4160, total number of good bumps = 0
[07:42:32.409] <TB2> INFO: ROC 8, missing bumps = 4160, total number of good bumps = 0
[07:42:32.410] <TB2> INFO: ROC 9, missing bumps = 4160, total number of good bumps = 0
[07:42:32.412] <TB2> INFO: ROC 10, missing bumps = 88, total number of good bumps = 4072
[07:42:32.413] <TB2> INFO: ROC 11, missing bumps = 4160, total number of good bumps = 0
[07:42:32.415] <TB2> INFO: ROC 12, missing bumps = 4160, total number of good bumps = 0
[07:42:32.416] <TB2> INFO: ROC 13, missing bumps = 18, total number of good bumps = 4142
[07:42:32.418] <TB2> INFO: ROC 14, missing bumps = 4090, total number of good bumps = 70
[07:42:32.419] <TB2> INFO: ROC 15, missing bumps = 4160, total number of good bumps = 0
[07:42:32.419] <TB2> INFO: CalDel = 80 done
[07:42:32.692] <TB2> INFO: Expecting 416000 events.
[07:42:43.545] <TB2> INFO: 416000 events read in total (10297ms).
[07:42:43.546] <TB2> INFO: Test took 11122ms.
[07:42:43.769] <TB2> INFO: ROC 0, missing bumps = 0, total number of good bumps = 4160
[07:42:43.770] <TB2> INFO: ROC 1, missing bumps = 4160, total number of good bumps = 0
[07:42:43.771] <TB2> INFO: ROC 2, missing bumps = 4157, total number of good bumps = 3
[07:42:43.773] <TB2> INFO: ROC 3, missing bumps = 4160, total number of good bumps = 0
[07:42:43.774] <TB2> INFO: ROC 4, missing bumps = 4160, total number of good bumps = 0
[07:42:43.775] <TB2> INFO: ROC 5, missing bumps = 3639, total number of good bumps = 521
[07:42:43.776] <TB2> INFO: ROC 6, missing bumps = 4160, total number of good bumps = 0
[07:42:43.778] <TB2> INFO: ROC 7, missing bumps = 4151, total number of good bumps = 9
[07:42:43.779] <TB2> INFO: ROC 8, missing bumps = 4160, total number of good bumps = 0
[07:42:43.780] <TB2> INFO: ROC 9, missing bumps = 4160, total number of good bumps = 0
[07:42:43.782] <TB2> INFO: ROC 10, missing bumps = 2, total number of good bumps = 4158
[07:42:43.783] <TB2> INFO: ROC 11, missing bumps = 4160, total number of good bumps = 0
[07:42:43.784] <TB2> INFO: ROC 12, missing bumps = 4160, total number of good bumps = 0
[07:42:43.785] <TB2> INFO: ROC 13, missing bumps = 7, total number of good bumps = 4153
[07:42:43.787] <TB2> INFO: ROC 14, missing bumps = 1372, total number of good bumps = 2788
[07:42:43.788] <TB2> INFO: ROC 15, missing bumps = 4142, total number of good bumps = 18
[07:42:43.788] <TB2> INFO: CalDel = 90 done
[07:42:44.068] <TB2> INFO: Expecting 416000 events.
[07:42:56.682] <TB2> INFO: 416000 events read in total (12058ms).
[07:42:56.683] <TB2> INFO: Test took 12890ms.
[07:42:56.848] <TB2> INFO: ROC 1, missing bumps = 3245, total number of good bumps = 915
[07:42:56.850] <TB2> INFO: ROC 2, missing bumps = 1111, total number of good bumps = 3049
[07:42:56.851] <TB2> INFO: ROC 3, missing bumps = 2884, total number of good bumps = 1276
[07:42:56.852] <TB2> INFO: ROC 4, missing bumps = 2072, total number of good bumps = 2088
[07:42:56.854] <TB2> INFO: ROC 5, missing bumps = 46, total number of good bumps = 4114
[07:42:56.855] <TB2> INFO: ROC 6, missing bumps = 4155, total number of good bumps = 5
[07:42:56.856] <TB2> INFO: ROC 7, missing bumps = 2345, total number of good bumps = 1815
[07:42:56.858] <TB2> INFO: ROC 8, missing bumps = 4160, total number of good bumps = 0
[07:42:56.859] <TB2> INFO: ROC 9, missing bumps = 3928, total number of good bumps = 232
[07:42:56.860] <TB2> INFO: ROC 10, missing bumps = 0, total number of good bumps = 4160
[07:42:56.862] <TB2> INFO: ROC 11, missing bumps = 4160, total number of good bumps = 0
[07:42:56.863] <TB2> INFO: ROC 12, missing bumps = 4160, total number of good bumps = 0
[07:42:56.864] <TB2> INFO: ROC 13, missing bumps = 6, total number of good bumps = 4154
[07:42:56.866] <TB2> INFO: ROC 14, missing bumps = 7, total number of good bumps = 4153
[07:42:56.867] <TB2> INFO: ROC 15, missing bumps = 1081, total number of good bumps = 3079
[07:42:56.867] <TB2> INFO: CalDel = 100 done
[07:42:57.197] <TB2> INFO: Expecting 416000 events.
[07:43:11.592] <TB2> INFO: 416000 events read in total (13839ms).
[07:43:11.593] <TB2> INFO: Test took 14721ms.
[07:43:11.763] <TB2> INFO: ROC 1, missing bumps = 113, total number of good bumps = 4047
[07:43:11.764] <TB2> INFO: ROC 2, missing bumps = 4, total number of good bumps = 4156
[07:43:11.766] <TB2> INFO: ROC 3, missing bumps = 39, total number of good bumps = 4121
[07:43:11.767] <TB2> INFO: ROC 4, missing bumps = 7, total number of good bumps = 4153
[07:43:11.768] <TB2> INFO: ROC 5, missing bumps = 0, total number of good bumps = 4160
[07:43:11.770] <TB2> INFO: ROC 6, missing bumps = 2326, total number of good bumps = 1834
[07:43:11.771] <TB2> INFO: ROC 7, missing bumps = 59, total number of good bumps = 4101
[07:43:11.772] <TB2> INFO: ROC 8, missing bumps = 3849, total number of good bumps = 311
[07:43:11.774] <TB2> INFO: ROC 9, missing bumps = 458, total number of good bumps = 3702
[07:43:11.775] <TB2> INFO: ROC 11, missing bumps = 4080, total number of good bumps = 80
[07:43:11.776] <TB2> INFO: ROC 12, missing bumps = 4160, total number of good bumps = 0
[07:43:11.777] <TB2> INFO: ROC 13, missing bumps = 4, total number of good bumps = 4156
[07:43:11.779] <TB2> INFO: ROC 14, missing bumps = 2, total number of good bumps = 4158
[07:43:11.780] <TB2> INFO: ROC 15, missing bumps = 2, total number of good bumps = 4158
[07:43:11.780] <TB2> INFO: CalDel = 110 done
[07:43:12.058] <TB2> INFO: Expecting 416000 events.
[07:43:27.342] <TB2> INFO: 416000 events read in total (14728ms).
[07:43:27.343] <TB2> INFO: Test took 15559ms.
[07:43:27.513] <TB2> INFO: ROC 1, missing bumps = 0, total number of good bumps = 4160
[07:43:27.514] <TB2> INFO: ROC 2, missing bumps = 1, total number of good bumps = 4159
[07:43:27.515] <TB2> INFO: ROC 3, missing bumps = 5, total number of good bumps = 4155
[07:43:27.517] <TB2> INFO: ROC 4, missing bumps = 0, total number of good bumps = 4160
[07:43:27.518] <TB2> INFO: ROC 6, missing bumps = 8, total number of good bumps = 4152
[07:43:27.519] <TB2> INFO: ROC 7, missing bumps = 4, total number of good bumps = 4156
[07:43:27.520] <TB2> INFO: ROC 8, missing bumps = 406, total number of good bumps = 3754
[07:43:27.522] <TB2> INFO: ROC 9, missing bumps = 0, total number of good bumps = 4160
[07:43:27.523] <TB2> INFO: ROC 11, missing bumps = 688, total number of good bumps = 3472
[07:43:27.525] <TB2> INFO: ROC 12, missing bumps = 4121, total number of good bumps = 39
[07:43:27.526] <TB2> INFO: ROC 13, missing bumps = 56, total number of good bumps = 4158
[07:43:27.527] <TB2> INFO: ROC 14, missing bumps = 0, total number of good bumps = 4160
[07:43:27.528] <TB2> INFO: ROC 15, missing bumps = 0, total number of good bumps = 4160
[07:43:27.528] <TB2> INFO: CalDel = 120 done
[07:43:27.809] <TB2> INFO: Expecting 416000 events.
[07:43:42.867] <TB2> INFO: 416000 events read in total (14502ms).
[07:43:42.868] <TB2> INFO: Test took 15335ms.
[07:43:43.038] <TB2> INFO: ROC 2, missing bumps = 1, total number of good bumps = 4159
[07:43:43.039] <TB2> INFO: ROC 3, missing bumps = 2, total number of good bumps = 4158
[07:43:43.040] <TB2> INFO: ROC 6, missing bumps = 1, total number of good bumps = 4159
[07:43:43.041] <TB2> INFO: ROC 7, missing bumps = 2, total number of good bumps = 4158
[07:43:43.043] <TB2> INFO: ROC 8, missing bumps = 2, total number of good bumps = 4158
[07:43:43.044] <TB2> INFO: ROC 11, missing bumps = 2, total number of good bumps = 4158
[07:43:43.045] <TB2> INFO: ROC 12, missing bumps = 1474, total number of good bumps = 2686
[07:43:43.047] <TB2> INFO: ROC 13, missing bumps = 3080, total number of good bumps = 4158
[07:43:43.047] <TB2> INFO: CalDel = 130 done
[07:43:43.319] <TB2> INFO: Expecting 416000 events.
[07:43:57.873] <TB2> INFO: 416000 events read in total (13997ms).
[07:43:57.874] <TB2> INFO: Test took 14823ms.
[07:43:58.042] <TB2> INFO: ROC 2, missing bumps = 1, total number of good bumps = 4159
[07:43:58.043] <TB2> INFO: ROC 3, missing bumps = 1, total number of good bumps = 4159
[07:43:58.044] <TB2> INFO: ROC 6, missing bumps = 1, total number of good bumps = 4159
[07:43:58.046] <TB2> INFO: ROC 7, missing bumps = 2, total number of good bumps = 4158
[07:43:58.047] <TB2> INFO: ROC 8, missing bumps = 2, total number of good bumps = 4158
[07:43:58.048] <TB2> INFO: ROC 11, missing bumps = 1, total number of good bumps = 4159
[07:43:58.049] <TB2> INFO: ROC 12, missing bumps = 16, total number of good bumps = 4144
[07:43:58.051] <TB2> INFO: ROC 13, missing bumps = 4140, total number of good bumps = 4158
[07:43:58.051] <TB2> INFO: CalDel = 140 done
[07:43:58.326] <TB2> INFO: Expecting 416000 events.
[07:44:12.403] <TB2> INFO: 416000 events read in total (13521ms).
[07:44:12.404] <TB2> INFO: Test took 14349ms.
[07:44:12.572] <TB2> INFO: ROC 2, missing bumps = 7, total number of good bumps = 4160
[07:44:12.573] <TB2> INFO: ROC 3, missing bumps = 1, total number of good bumps = 4159
[07:44:12.575] <TB2> INFO: ROC 6, missing bumps = 1, total number of good bumps = 4159
[07:44:12.576] <TB2> INFO: ROC 7, missing bumps = 5, total number of good bumps = 4158
[07:44:12.577] <TB2> INFO: ROC 8, missing bumps = 1, total number of good bumps = 4159
[07:44:12.578] <TB2> INFO: ROC 11, missing bumps = 1, total number of good bumps = 4159
[07:44:12.580] <TB2> INFO: ROC 12, missing bumps = 5, total number of good bumps = 4155
[07:44:12.581] <TB2> INFO: ROC 13, missing bumps = 4156, total number of good bumps = 4158
[07:44:12.581] <TB2> INFO: CalDel = 150 done
[07:44:12.847] <TB2> INFO: Expecting 416000 events.
[07:44:25.881] <TB2> INFO: 416000 events read in total (12478ms).
[07:44:25.882] <TB2> INFO: Test took 13297ms.
[07:44:26.050] <TB2> INFO: ROC 2, missing bumps = 2671, total number of good bumps = 4160
[07:44:26.052] <TB2> INFO: ROC 3, missing bumps = 664, total number of good bumps = 4159
[07:44:26.053] <TB2> INFO: ROC 6, missing bumps = 1, total number of good bumps = 4159
[07:44:26.055] <TB2> INFO: ROC 7, missing bumps = 833, total number of good bumps = 4158
[07:44:26.057] <TB2> INFO: ROC 8, missing bumps = 1, total number of good bumps = 4159
[07:44:26.059] <TB2> INFO: ROC 11, missing bumps = 1, total number of good bumps = 4159
[07:44:26.060] <TB2> INFO: ROC 12, missing bumps = 2, total number of good bumps = 4158
[07:44:26.062] <TB2> INFO: ROC 13, missing bumps = 4157, total number of good bumps = 4158
[07:44:26.062] <TB2> INFO: CalDel = 160 done
[07:44:26.339] <TB2> INFO: Expecting 416000 events.
[07:44:37.549] <TB2> INFO: 416000 events read in total (10654ms).
[07:44:37.550] <TB2> INFO: Test took 11483ms.
[07:44:37.766] <TB2> INFO: ROC 2, missing bumps = 4152, total number of good bumps = 4160
[07:44:37.767] <TB2> INFO: ROC 3, missing bumps = 4055, total number of good bumps = 4159
[07:44:37.769] <TB2> INFO: ROC 6, missing bumps = 245, total number of good bumps = 4159
[07:44:37.770] <TB2> INFO: ROC 7, missing bumps = 3928, total number of good bumps = 4159
[07:44:37.772] <TB2> INFO: ROC 8, missing bumps = 64, total number of good bumps = 4160
[07:44:37.774] <TB2> INFO: ROC 11, missing bumps = 1, total number of good bumps = 4159
[07:44:37.775] <TB2> INFO: ROC 12, missing bumps = 2, total number of good bumps = 4158
[07:44:37.777] <TB2> INFO: ROC 13, missing bumps = 4160, total number of good bumps = 4158
[07:44:37.777] <TB2> INFO: CalDel = 170 done
[07:44:38.057] <TB2> INFO: Expecting 416000 events.
[07:44:48.332] <TB2> INFO: 416000 events read in total (9719ms).
[07:44:48.333] <TB2> INFO: Test took 10550ms.
[07:44:48.565] <TB2> INFO: ROC 2, missing bumps = 4159, total number of good bumps = 4160
[07:44:48.566] <TB2> INFO: ROC 3, missing bumps = 4156, total number of good bumps = 4159
[07:44:48.568] <TB2> INFO: ROC 6, missing bumps = 4095, total number of good bumps = 4159
[07:44:48.569] <TB2> INFO: ROC 7, missing bumps = 4157, total number of good bumps = 4159
[07:44:48.570] <TB2> INFO: ROC 8, missing bumps = 3376, total number of good bumps = 4160
[07:44:48.572] <TB2> INFO: ROC 11, missing bumps = 884, total number of good bumps = 4159
[07:44:48.573] <TB2> INFO: ROC 12, missing bumps = 1, total number of good bumps = 4159
[07:44:48.574] <TB2> INFO: ROC 13, missing bumps = 4160, total number of good bumps = 4158
[07:44:48.574] <TB2> INFO: CalDel = 180 done
[07:44:48.842] <TB2> INFO: Expecting 416000 events.
[07:44:58.702] <TB2> INFO: 416000 events read in total (9304ms).
[07:44:58.704] <TB2> INFO: Test took 10126ms.
[07:44:58.947] <TB2> INFO: ROC 2, missing bumps = 4160, total number of good bumps = 4160
[07:44:58.948] <TB2> INFO: ROC 3, missing bumps = 4159, total number of good bumps = 4159
[07:44:58.949] <TB2> INFO: ROC 6, missing bumps = 4160, total number of good bumps = 4159
[07:44:58.951] <TB2> INFO: ROC 7, missing bumps = 4159, total number of good bumps = 4159
[07:44:58.952] <TB2> INFO: ROC 8, missing bumps = 4157, total number of good bumps = 4160
[07:44:58.953] <TB2> INFO: ROC 11, missing bumps = 4054, total number of good bumps = 4160
[07:44:58.954] <TB2> INFO: ROC 12, missing bumps = 157, total number of good bumps = 4160
[07:44:58.956] <TB2> INFO: ROC 13, missing bumps = 4160, total number of good bumps = 4158
[07:44:58.956] <TB2> INFO: CalDel = 190 done
[07:44:59.234] <TB2> INFO: Expecting 416000 events.
[07:45:08.717] <TB2> INFO: 416000 events read in total (8927ms).
[07:45:08.718] <TB2> INFO: Test took 9758ms.
[07:45:08.981] <TB2> INFO: ROC 2, missing bumps = 4160, total number of good bumps = 4160
[07:45:08.983] <TB2> INFO: ROC 3, missing bumps = 4160, total number of good bumps = 4159
[07:45:08.984] <TB2> INFO: ROC 6, missing bumps = 4160, total number of good bumps = 4159
[07:45:08.986] <TB2> INFO: ROC 7, missing bumps = 4160, total number of good bumps = 4159
[07:45:08.987] <TB2> INFO: ROC 8, missing bumps = 4158, total number of good bumps = 4160
[07:45:08.988] <TB2> INFO: ROC 11, missing bumps = 4158, total number of good bumps = 4160
[07:45:08.990] <TB2> INFO: ROC 12, missing bumps = 3020, total number of good bumps = 4160
[07:45:08.991] <TB2> INFO: ROC 13, missing bumps = 4160, total number of good bumps = 4158
[07:45:08.991] <TB2> INFO: CalDel = 200 done
[07:45:08.991] <TB2> INFO: CalDel scan done
[07:45:08.994] <TB2> INFO: resulting values: CalDel = 90 120 120 140 120 110 130 130 150 120 100 140 180 110 120 120
[07:45:09.256] <TB2> INFO: Expecting 416000 events.
[07:45:24.632] <TB2> INFO: 416000 events read in total (14819ms).
[07:45:24.638] <TB2> INFO: Test took 15642ms.
[07:45:24.646] <TB2> INFO: maps.size() = 16
[07:45:24.831] <TB2> INFO: 5 broken Bump Bonds found
[07:45:24.834] <TB2> INFO: back to CtrlReg 0
[07:45:24.838] <TB2> INFO: PixTestBB4Map::doTest() done
[07:45:24.838] <TB2> INFO: Write out MinimumThreshold
[07:45:24.838] <TB2> INFO: Write out CalDel Scan_C0_V0
[07:45:24.838] <TB2> INFO: Write out PixelHit_C0_V0
[07:45:24.838] <TB2> INFO: Write out BB4MapBest_C0_V0
[07:45:24.838] <TB2> INFO: Write out NoiseScan_C0_V0
[07:45:24.838] <TB2> INFO: Write out BB4Map_C0_V14
[07:45:24.838] <TB2> INFO: Write out Hit Distr_C0_V0
[07:45:24.838] <TB2> INFO: Write out Hit Distr_C0_V0
[07:45:24.838] <TB2> INFO: Write out CalDel Scan_C1_V0
[07:45:24.838] <TB2> INFO: Write out PixelHit_C1_V0
[07:45:24.839] <TB2> INFO: Write out BB4MapBest_C1_V0
[07:45:24.839] <TB2> INFO: Write out NoiseScan_C1_V0
[07:45:24.839] <TB2> INFO: Write out BB4Map_C1_V14
[07:45:24.839] <TB2> INFO: Write out Hit Distr_C1_V0
[07:45:24.839] <TB2> INFO: Write out Hit Distr_C1_V0
[07:45:24.839] <TB2> INFO: Write out CalDel Scan_C2_V0
[07:45:24.839] <TB2> INFO: Write out PixelHit_C2_V0
[07:45:24.839] <TB2> INFO: Write out BB4MapBest_C2_V0
[07:45:24.839] <TB2> INFO: Write out NoiseScan_C2_V0
[07:45:24.839] <TB2> INFO: Write out BB4Map_C2_V14
[07:45:24.840] <TB2> INFO: Write out Hit Distr_C2_V0
[07:45:24.840] <TB2> INFO: Write out Hit Distr_C2_V0
[07:45:24.840] <TB2> INFO: Write out CalDel Scan_C3_V0
[07:45:24.840] <TB2> INFO: Write out PixelHit_C3_V0
[07:45:24.840] <TB2> INFO: Write out BB4MapBest_C3_V0
[07:45:24.840] <TB2> INFO: Write out NoiseScan_C3_V0
[07:45:24.840] <TB2> INFO: Write out BB4Map_C3_V14
[07:45:24.840] <TB2> INFO: Write out Hit Distr_C3_V0
[07:45:24.840] <TB2> INFO: Write out Hit Distr_C3_V0
[07:45:24.840] <TB2> INFO: Write out CalDel Scan_C4_V0
[07:45:24.840] <TB2> INFO: Write out PixelHit_C4_V0
[07:45:24.841] <TB2> INFO: Write out BB4MapBest_C4_V0
[07:45:24.841] <TB2> INFO: Write out NoiseScan_C4_V0
[07:45:24.841] <TB2> INFO: Write out BB4Map_C4_V14
[07:45:24.841] <TB2> INFO: Write out Hit Distr_C4_V0
[07:45:24.841] <TB2> INFO: Write out Hit Distr_C4_V0
[07:45:24.841] <TB2> INFO: Write out CalDel Scan_C5_V0
[07:45:24.841] <TB2> INFO: Write out PixelHit_C5_V0
[07:45:24.841] <TB2> INFO: Write out BB4MapBest_C5_V0
[07:45:24.841] <TB2> INFO: Write out NoiseScan_C5_V0
[07:45:24.841] <TB2> INFO: Write out BB4Map_C5_V14
[07:45:24.841] <TB2> INFO: Write out Hit Distr_C5_V0
[07:45:24.842] <TB2> INFO: Write out Hit Distr_C5_V0
[07:45:24.842] <TB2> INFO: Write out CalDel Scan_C6_V0
[07:45:24.842] <TB2> INFO: Write out PixelHit_C6_V0
[07:45:24.842] <TB2> INFO: Write out BB4MapBest_C6_V0
[07:45:24.842] <TB2> INFO: Write out NoiseScan_C6_V0
[07:45:24.842] <TB2> INFO: Write out BB4Map_C6_V14
[07:45:24.842] <TB2> INFO: Write out Hit Distr_C6_V0
[07:45:24.842] <TB2> INFO: Write out Hit Distr_C6_V0
[07:45:24.842] <TB2> INFO: Write out CalDel Scan_C7_V0
[07:45:24.842] <TB2> INFO: Write out PixelHit_C7_V0
[07:45:24.842] <TB2> INFO: Write out BB4MapBest_C7_V0
[07:45:24.843] <TB2> INFO: Write out NoiseScan_C7_V0
[07:45:24.843] <TB2> INFO: Write out BB4Map_C7_V14
[07:45:24.843] <TB2> INFO: Write out Hit Distr_C7_V0
[07:45:24.843] <TB2> INFO: Write out Hit Distr_C7_V0
[07:45:24.843] <TB2> INFO: Write out CalDel Scan_C8_V0
[07:45:24.843] <TB2> INFO: Write out PixelHit_C8_V0
[07:45:24.843] <TB2> INFO: Write out BB4MapBest_C8_V0
[07:45:24.843] <TB2> INFO: Write out NoiseScan_C8_V0
[07:45:24.843] <TB2> INFO: Write out BB4Map_C8_V14
[07:45:24.843] <TB2> INFO: Write out Hit Distr_C8_V0
[07:45:24.843] <TB2> INFO: Write out Hit Distr_C8_V0
[07:45:24.844] <TB2> INFO: Write out CalDel Scan_C9_V0
[07:45:24.844] <TB2> INFO: Write out PixelHit_C9_V0
[07:45:24.844] <TB2> INFO: Write out BB4MapBest_C9_V0
[07:45:24.844] <TB2> INFO: Write out NoiseScan_C9_V0
[07:45:24.844] <TB2> INFO: Write out BB4Map_C9_V14
[07:45:24.844] <TB2> INFO: Write out Hit Distr_C9_V0
[07:45:24.844] <TB2> INFO: Write out Hit Distr_C9_V0
[07:45:24.844] <TB2> INFO: Write out CalDel Scan_C10_V0
[07:45:24.844] <TB2> INFO: Write out PixelHit_C10_V0
[07:45:24.844] <TB2> INFO: Write out BB4MapBest_C10_V0
[07:45:24.845] <TB2> INFO: Write out NoiseScan_C10_V0
[07:45:24.845] <TB2> INFO: Write out BB4Map_C10_V14
[07:45:24.845] <TB2> INFO: Write out Hit Distr_C10_V0
[07:45:24.845] <TB2> INFO: Write out Hit Distr_C10_V0
[07:45:24.845] <TB2> INFO: Write out CalDel Scan_C11_V0
[07:45:24.845] <TB2> INFO: Write out PixelHit_C11_V0
[07:45:24.845] <TB2> INFO: Write out BB4MapBest_C11_V0
[07:45:24.845] <TB2> INFO: Write out NoiseScan_C11_V0
[07:45:24.845] <TB2> INFO: Write out BB4Map_C11_V14
[07:45:24.845] <TB2> INFO: Write out Hit Distr_C11_V0
[07:45:24.845] <TB2> INFO: Write out Hit Distr_C11_V0
[07:45:24.845] <TB2> INFO: Write out CalDel Scan_C12_V0
[07:45:24.846] <TB2> INFO: Write out PixelHit_C12_V0
[07:45:24.846] <TB2> INFO: Write out BB4MapBest_C12_V0
[07:45:24.846] <TB2> INFO: Write out NoiseScan_C12_V0
[07:45:24.846] <TB2> INFO: Write out BB4Map_C12_V14
[07:45:24.846] <TB2> INFO: Write out Hit Distr_C12_V0
[07:45:24.846] <TB2> INFO: Write out Hit Distr_C12_V0
[07:45:24.846] <TB2> INFO: Write out CalDel Scan_C13_V0
[07:45:24.846] <TB2> INFO: Write out PixelHit_C13_V0
[07:45:24.846] <TB2> INFO: Write out BB4MapBest_C13_V0
[07:45:24.846] <TB2> INFO: Write out NoiseScan_C13_V0
[07:45:24.847] <TB2> INFO: Write out BB4Map_C13_V14
[07:45:24.847] <TB2> INFO: Write out Hit Distr_C13_V0
[07:45:24.847] <TB2> INFO: Write out Hit Distr_C13_V0
[07:45:24.847] <TB2> INFO: Write out CalDel Scan_C14_V0
[07:45:24.847] <TB2> INFO: Write out PixelHit_C14_V0
[07:45:24.847] <TB2> INFO: Write out BB4MapBest_C14_V0
[07:45:24.847] <TB2> INFO: Write out NoiseScan_C14_V0
[07:45:24.847] <TB2> INFO: Write out BB4Map_C14_V14
[07:45:24.847] <TB2> INFO: Write out Hit Distr_C14_V0
[07:45:24.847] <TB2> INFO: Write out Hit Distr_C14_V0
[07:45:24.847] <TB2> INFO: Write out CalDel Scan_C15_V0
[07:45:24.847] <TB2> INFO: Write out PixelHit_C15_V0
[07:45:24.848] <TB2> INFO: Write out BB4MapBest_C15_V0
[07:45:24.848] <TB2> INFO: Write out NoiseScan_C15_V0
[07:45:24.848] <TB2> INFO: Write out BB4Map_C15_V14
[07:45:24.848] <TB2> INFO: Write out Hit Distr_C15_V0
[07:45:24.848] <TB2> INFO: Write out Hit Distr_C15_V0
[07:45:24.848] <TB2> INFO: enter test to run
[07:45:24.848] <TB2> INFO: test: no parameter change
[07:45:24.876] <TB2> QUIET: Connection to board 149 closed.
[07:45:24.955] <TB2> INFO: pXar: this is the end, my friend