[14:01:25.691] <TB2> INFO: *** Welcome to pxar ***
[14:01:25.691] <TB2> INFO: *** Today: 2016/07/20
[14:01:25.736] <TB2> INFO: *** Version: f5d3-dirty
[14:01:25.736] <TB2> INFO: readRocDacs: /home/Reception/DATA/KITAACHEN/M4589_Reception_2016-07-20_15h56m_1469022964//000_ReceptionTestBB4_p17//dacParameters_C0.dat .. /home/Reception/DATA/KITAACHEN/M4589_Reception_2016-07-20_15h56m_1469022964//000_ReceptionTestBB4_p17//dacParameters_C15.dat
[14:01:25.755] <TB2> INFO: readTbmDacs: /home/Reception/DATA/KITAACHEN/M4589_Reception_2016-07-20_15h56m_1469022964//000_ReceptionTestBB4_p17//tbmParameters_C0a.dat .. /home/Reception/DATA/KITAACHEN/M4589_Reception_2016-07-20_15h56m_1469022964//000_ReceptionTestBB4_p17//tbmParameters_C0b.dat
[14:01:25.758] <TB2> INFO: readMaskFile: /home/Reception/DATA/KITAACHEN/M4589_Reception_2016-07-20_15h56m_1469022964//000_ReceptionTestBB4_p17//defaultMaskFile.dat
[14:01:25.759] <TB2> INFO: readTrimFile: /home/Reception/DATA/KITAACHEN/M4589_Reception_2016-07-20_15h56m_1469022964//000_ReceptionTestBB4_p17//trimParameters_C0.dat .. /home/Reception/DATA/KITAACHEN/M4589_Reception_2016-07-20_15h56m_1469022964//000_ReceptionTestBB4_p17//trimParameters_C15.dat
[14:01:25.869] <TB2> INFO: clk: 4
[14:01:25.869] <TB2> INFO: ctr: 4
[14:01:25.869] <TB2> INFO: sda: 19
[14:01:25.869] <TB2> INFO: tin: 9
[14:01:25.869] <TB2> INFO: level: 15
[14:01:25.869] <TB2> INFO: triggerdelay: 0
[14:01:25.869] <TB2> QUIET: Instanciating API for pxar v2.7.5+40~g4fce89b
[14:01:25.869] <TB2> INFO: Log level: INFO
[14:01:25.876] <TB2> INFO: Found DTB DTB_WWXUD2
[14:01:25.886] <TB2> QUIET: Connection to board DTB_WWXUD2 opened.
[14:01:25.888] <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:
------------------------------------------------------
[14:01:25.890] <TB2> INFO: RPC call hashes of host and DTB match: 484264910
[14:01:27.416] <TB2> INFO: DUT info:
[14:01:27.416] <TB2> INFO: The DUT currently contains the following objects:
[14:01:27.416] <TB2> INFO: 2 TBM Cores tbm08c (2 ON)
[14:01:27.416] <TB2> INFO: TBM Core alpha (0): 7 registers set
[14:01:27.416] <TB2> INFO: TBM Core beta (1): 7 registers set
[14:01:27.416] <TB2> INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[14:01:27.416] <TB2> INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[14:01:27.416] <TB2> INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[14:01:27.416] <TB2> INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[14:01:27.416] <TB2> INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[14:01:27.416] <TB2> INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[14:01:27.416] <TB2> INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[14:01:27.416] <TB2> INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[14:01:27.416] <TB2> INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[14:01:27.416] <TB2> INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[14:01:27.416] <TB2> INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[14:01:27.416] <TB2> INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[14:01:27.416] <TB2> INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[14:01:27.416] <TB2> INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[14:01:27.416] <TB2> INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[14:01:27.416] <TB2> INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[14:01:27.417] <TB2> INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[14:01:27.819] <TB2> INFO: enter 'restricted' command line mode
[14:01:27.819] <TB2> INFO: enter test to run
[14:01:27.820] <TB2> INFO: test: pretest no parameter change
[14:01:27.820] <TB2> INFO: running: pretest
[14:01:27.827] <TB2> INFO: ----------------------------------------------------------------------
[14:01:27.827] <TB2> INFO: PixTestPretest::programROC()
[14:01:27.827] <TB2> INFO: ----------------------------------------------------------------------
[14:01:45.847] <TB2> INFO: PixTestPretest::programROC() done: ROCs are all programmable
[14:01:45.847] <TB2> INFO: IA differences per ROC: 20.9 20.1 20.1 19.3 17.7 19.3 18.5 18.5 20.9 18.5 17.7 20.9 18.5 22.5 18.5 22.5
[14:01:45.966] <TB2> INFO: enter test to run
[14:01:45.966] <TB2> INFO: test: pretest no parameter change
[14:01:45.966] <TB2> INFO: running: pretest
[14:01:45.968] <TB2> INFO: ----------------------------------------------------------------------
[14:01:45.968] <TB2> INFO: PixTestPretest::setVana() target Ia = 24 mA/ROC
[14:01:45.968] <TB2> INFO: ----------------------------------------------------------------------
[14:01:52.998] <TB2> INFO: PixTestPretest::setVana() done, Module Ia 380.2 mA = 23.7625 mA/ROC
[14:01:52.999] <TB2> INFO: i(loss) [mA/ROC]: 19.2 19.2 19.2 19.2 19.2 19.2 19.2 19.2 19.2 19.2 19.2 19.2 19.2 19.2 19.2 19.2
[14:01:53.045] <TB2> INFO: enter test to run
[14:01:53.045] <TB2> INFO: test: pretest no parameter change
[14:01:53.045] <TB2> INFO: running: pretest
[14:01:53.045] <TB2> INFO: ----------------------------------------------------------------------
[14:01:53.045] <TB2> INFO: PixTestPretest::findTiming()
[14:01:53.045] <TB2> INFO: ----------------------------------------------------------------------
[14:01:53.045] <TB2> INFO: PixTestCmd::init()
[14:01:53.849] <TB2> WARNING: Not unmasking DUT, not setting Calibrate bits!
[14:02:54.027] <TB2> INFO: TBM phases: 160MHz: 7, 400MHz: 2, TBM delays: ROC(0/1):4, header/trailer: 1, token: 1
[14:02:54.028] <TB2> INFO: (success/tries = 100/100), width = 5
[14:02:54.029] <TB2> INFO: enter test to run
[14:02:54.029] <TB2> INFO: test: pretest no parameter change
[14:02:54.029] <TB2> INFO: running: pretest
[14:02:54.031] <TB2> INFO: ----------------------------------------------------------------------
[14:02:54.031] <TB2> INFO: PixTestPretest::findWorkingPixel()
[14:02:54.031] <TB2> INFO: ----------------------------------------------------------------------
[14:02:54.126] <TB2> INFO: Expecting 231680 events.
[14:02:59.803] <TB2> WARNING: Detected DESER400 trailer error bits: "CODE ERROR"
[14:03:03.225] <TB2> INFO: 231680 events read in total (8543ms).
[14:03:03.230] <TB2> INFO: Test took 9194ms.
[14:03:03.544] <TB2> INFO: Found working pixel in all ROCs: col/row = 12/22
[14:03:03.626] <TB2> INFO: enter test to run
[14:03:03.626] <TB2> INFO: test: pretest no parameter change
[14:03:03.626] <TB2> INFO: running: pretest
[14:03:03.627] <TB2> INFO: ----------------------------------------------------------------------
[14:03:03.627] <TB2> INFO: PixTestPretest::setVthrCompCalDel()
[14:03:03.627] <TB2> INFO: ----------------------------------------------------------------------
[14:03:03.724] <TB2> INFO: Expecting 231680 events.
[14:03:12.853] <TB2> INFO: 231680 events read in total (8573ms).
[14:03:12.857] <TB2> INFO: Test took 9223ms.
[14:03:13.204] <TB2> INFO: PixTestPretest::setVthrCompCalDel() done
[14:03:13.204] <TB2> INFO: CalDel: 127 112 121 131 115 120 113 119 139 120 108 120 111 120 143 119
[14:03:13.204] <TB2> INFO: VthrComp: 51 51 51 51 53 51 51 51 51 51 51 51 51 55 51 51
[14:03:13.238] <TB2> INFO: enter test to run
[14:03:13.238] <TB2> INFO: test: pretest no parameter change
[14:03:13.238] <TB2> INFO: running: pretest
[14:03:13.260] <TB2> INFO: write dac parameters into /home/Reception/DATA/KITAACHEN/M4589_Reception_2016-07-20_15h56m_1469022964//000_ReceptionTestBB4_p17//dacParameters_C0.dat
[14:03:13.267] <TB2> INFO: write dac parameters into /home/Reception/DATA/KITAACHEN/M4589_Reception_2016-07-20_15h56m_1469022964//000_ReceptionTestBB4_p17//dacParameters_C1.dat
[14:03:13.273] <TB2> INFO: write dac parameters into /home/Reception/DATA/KITAACHEN/M4589_Reception_2016-07-20_15h56m_1469022964//000_ReceptionTestBB4_p17//dacParameters_C2.dat
[14:03:13.278] <TB2> INFO: write dac parameters into /home/Reception/DATA/KITAACHEN/M4589_Reception_2016-07-20_15h56m_1469022964//000_ReceptionTestBB4_p17//dacParameters_C3.dat
[14:03:13.284] <TB2> INFO: write dac parameters into /home/Reception/DATA/KITAACHEN/M4589_Reception_2016-07-20_15h56m_1469022964//000_ReceptionTestBB4_p17//dacParameters_C4.dat
[14:03:13.289] <TB2> INFO: write dac parameters into /home/Reception/DATA/KITAACHEN/M4589_Reception_2016-07-20_15h56m_1469022964//000_ReceptionTestBB4_p17//dacParameters_C5.dat
[14:03:13.302] <TB2> INFO: write dac parameters into /home/Reception/DATA/KITAACHEN/M4589_Reception_2016-07-20_15h56m_1469022964//000_ReceptionTestBB4_p17//dacParameters_C6.dat
[14:03:13.312] <TB2> INFO: write dac parameters into /home/Reception/DATA/KITAACHEN/M4589_Reception_2016-07-20_15h56m_1469022964//000_ReceptionTestBB4_p17//dacParameters_C7.dat
[14:03:13.318] <TB2> INFO: write dac parameters into /home/Reception/DATA/KITAACHEN/M4589_Reception_2016-07-20_15h56m_1469022964//000_ReceptionTestBB4_p17//dacParameters_C8.dat
[14:03:13.323] <TB2> INFO: write dac parameters into /home/Reception/DATA/KITAACHEN/M4589_Reception_2016-07-20_15h56m_1469022964//000_ReceptionTestBB4_p17//dacParameters_C9.dat
[14:03:13.328] <TB2> INFO: write dac parameters into /home/Reception/DATA/KITAACHEN/M4589_Reception_2016-07-20_15h56m_1469022964//000_ReceptionTestBB4_p17//dacParameters_C10.dat
[14:03:13.334] <TB2> INFO: write dac parameters into /home/Reception/DATA/KITAACHEN/M4589_Reception_2016-07-20_15h56m_1469022964//000_ReceptionTestBB4_p17//dacParameters_C11.dat
[14:03:13.340] <TB2> INFO: write dac parameters into /home/Reception/DATA/KITAACHEN/M4589_Reception_2016-07-20_15h56m_1469022964//000_ReceptionTestBB4_p17//dacParameters_C12.dat
[14:03:13.346] <TB2> INFO: write dac parameters into /home/Reception/DATA/KITAACHEN/M4589_Reception_2016-07-20_15h56m_1469022964//000_ReceptionTestBB4_p17//dacParameters_C13.dat
[14:03:13.351] <TB2> INFO: write dac parameters into /home/Reception/DATA/KITAACHEN/M4589_Reception_2016-07-20_15h56m_1469022964//000_ReceptionTestBB4_p17//dacParameters_C14.dat
[14:03:13.357] <TB2> INFO: write dac parameters into /home/Reception/DATA/KITAACHEN/M4589_Reception_2016-07-20_15h56m_1469022964//000_ReceptionTestBB4_p17//dacParameters_C15.dat
[14:03:13.362] <TB2> INFO: enter test to run
[14:03:13.362] <TB2> INFO: test: PixelAlive no parameter change
[14:03:13.362] <TB2> INFO: running: pixelalive
[14:03:13.364] <TB2> INFO: ######################################################################
[14:03:13.364] <TB2> INFO: PixTestAlive::doTest()
[14:03:13.364] <TB2> INFO: ######################################################################
[14:03:13.365] <TB2> INFO: ----------------------------------------------------------------------
[14:03:13.365] <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)
[14:03:13.365] <TB2> INFO: ----------------------------------------------------------------------
[14:03:13.641] <TB2> INFO: Expecting 41600 events.
[14:03:17.178] <TB2> INFO: 41600 events read in total (2981ms).
[14:03:17.179] <TB2> INFO: Test took 3812ms.
[14:03:17.484] <TB2> INFO: PixTestAlive::aliveTest() done
[14:03:17.484] <TB2> INFO: number of dead pixels (per ROC): 0 0 0 0 0 0 0 1 2 0 0 0 0 0 0 0
[14:03:17.486] <TB2> INFO: ----------------------------------------------------------------------
[14:03:17.486] <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)
[14:03:17.486] <TB2> INFO: ----------------------------------------------------------------------
[14:03:17.780] <TB2> INFO: Expecting 41600 events.
[14:03:20.401] <TB2> INFO: 41600 events read in total (2065ms).
[14:03:20.401] <TB2> INFO: Test took 2913ms.
[14:03:20.402] <TB2> INFO: mask vs. old pixelAlive PixelAlive_C0_V0 .. PixelAlive_C15_V0
[14:03:20.725] <TB2> INFO: PixTestAlive::maskTest() done
[14:03:20.725] <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
[14:03:20.727] <TB2> INFO: ----------------------------------------------------------------------
[14:03:20.727] <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)
[14:03:20.727] <TB2> INFO: ----------------------------------------------------------------------
[14:03:21.005] <TB2> INFO: Expecting 41600 events.
[14:03:24.469] <TB2> INFO: 41600 events read in total (2909ms).
[14:03:24.470] <TB2> INFO: Test took 3740ms.
[14:03:24.780] <TB2> INFO: PixTestAlive::addressDecodingTest() done
[14:03:24.780] <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
[14:03:24.780] <TB2> INFO: PixTestAlive::doTest() done, duration: 11 seconds
[14:03:24.789] <TB2> INFO: enter test to run
[14:03:24.789] <TB2> INFO: test: BB no parameter change
[14:03:24.789] <TB2> INFO: running: bb
[14:03:24.791] <TB2> INFO: ######################################################################
[14:03:24.791] <TB2> INFO: PixTestBBMap::doTest() Ntrig = 5, VcalS = 250 (high range)
[14:03:24.791] <TB2> INFO: ######################################################################
[14:03:24.795] <TB2> INFO: ---> dac: VthrComp name: calSMap ntrig: 5 dacrange: 0 .. 149 (-1/-1) hits flags = 514 (plus default)
[14:03:24.806] <TB2> INFO: dacScan split into 1 runs with ntrig = 5
[14:03:24.806] <TB2> INFO: run 1 of 1
[14:03:25.078] <TB2> INFO: Expecting 3120000 events.
[14:04:08.852] <TB2> INFO: 1103000 events read in total (43217ms).
[14:04:52.261] <TB2> INFO: 2204930 events read in total (86627ms).
[14:05:27.109] <TB2> INFO: 3120000 events read in total (121475ms).
[14:05:27.162] <TB2> INFO: Test took 122357ms.
[14:05:50.651] <TB2> INFO: PixTestBBMap::doTest() done, duration: 145 seconds
[14:05:50.651] <TB2> INFO: number of dead bumps (per ROC): 0 0 0 0 0 0 0 0 2 0 0 0 0 0 0 0
[14:05:50.651] <TB2> INFO: separation cut (per ROC): 110 109 110 112 114 110 93 130 107 103 108 107 111 109 105 124
[14:05:50.719] <TB2> INFO: enter test to run
[14:05:50.719] <TB2> INFO: test: Readback no parameter change
[14:05:50.719] <TB2> INFO: running: readback
[14:05:50.719] <TB2> INFO: readReadbackCal: /home/Reception/DATA/KITAACHEN/M4589_Reception_2016-07-20_15h56m_1469022964//000_ReceptionTestBB4_p17//readbackCal_C0.dat .. /home/Reception/DATA/KITAACHEN/M4589_Reception_2016-07-20_15h56m_1469022964//000_ReceptionTestBB4_p17//readbackCal_C15.dat
[14:05:50.900] <TB2> INFO: readGainPedestalParameters /home/Reception/DATA/KITAACHEN/M4589_Reception_2016-07-20_15h56m_1469022964//000_ReceptionTestBB4_p17//phCalibrationFitErr_C0.dat .. /home/Reception/DATA/KITAACHEN/M4589_Reception_2016-07-20_15h56m_1469022964//000_ReceptionTestBB4_p17//phCalibrationFitErr_C15.dat
[14:05:50.901] <TB2> ERROR: <ConfigParameters.cc/readGainPedestalParameters:L996> cannot open /home/Reception/DATA/KITAACHEN/M4589_Reception_2016-07-20_15h56m_1469022964//000_ReceptionTestBB4_p17//phCalibrationFitErr_C0.dat for reading PH calibration constants
[14:05:50.901] <TB2> INFO: ######################################################################
[14:05:50.901] <TB2> INFO: PixTestReadback::doTest()
[14:05:50.901] <TB2> INFO: ######################################################################
[14:05:50.901] <TB2> INFO: ----------------------------------------------------------------------
[14:05:50.901] <TB2> INFO: PixTestReadback::CalibrateVd()
[14:05:50.901] <TB2> INFO: ----------------------------------------------------------------------
[14:06:00.350] <TB2> INFO: write readback calibration parameters into /home/Reception/DATA/KITAACHEN/M4589_Reception_2016-07-20_15h56m_1469022964//000_ReceptionTestBB4_p17//readbackCal_C0.dat
[14:06:00.589] <TB2> INFO: write readback calibration parameters into /home/Reception/DATA/KITAACHEN/M4589_Reception_2016-07-20_15h56m_1469022964//000_ReceptionTestBB4_p17//readbackCal_C1.dat
[14:06:00.891] <TB2> INFO: write readback calibration parameters into /home/Reception/DATA/KITAACHEN/M4589_Reception_2016-07-20_15h56m_1469022964//000_ReceptionTestBB4_p17//readbackCal_C2.dat
[14:06:01.189] <TB2> INFO: write readback calibration parameters into /home/Reception/DATA/KITAACHEN/M4589_Reception_2016-07-20_15h56m_1469022964//000_ReceptionTestBB4_p17//readbackCal_C3.dat
[14:06:01.420] <TB2> INFO: write readback calibration parameters into /home/Reception/DATA/KITAACHEN/M4589_Reception_2016-07-20_15h56m_1469022964//000_ReceptionTestBB4_p17//readbackCal_C4.dat
[14:06:01.626] <TB2> INFO: write readback calibration parameters into /home/Reception/DATA/KITAACHEN/M4589_Reception_2016-07-20_15h56m_1469022964//000_ReceptionTestBB4_p17//readbackCal_C5.dat
[14:06:01.866] <TB2> INFO: write readback calibration parameters into /home/Reception/DATA/KITAACHEN/M4589_Reception_2016-07-20_15h56m_1469022964//000_ReceptionTestBB4_p17//readbackCal_C6.dat
[14:06:02.455] <TB2> INFO: write readback calibration parameters into /home/Reception/DATA/KITAACHEN/M4589_Reception_2016-07-20_15h56m_1469022964//000_ReceptionTestBB4_p17//readbackCal_C7.dat
[14:06:02.488] <TB2> INFO: write readback calibration parameters into /home/Reception/DATA/KITAACHEN/M4589_Reception_2016-07-20_15h56m_1469022964//000_ReceptionTestBB4_p17//readbackCal_C8.dat
[14:06:02.511] <TB2> INFO: write readback calibration parameters into /home/Reception/DATA/KITAACHEN/M4589_Reception_2016-07-20_15h56m_1469022964//000_ReceptionTestBB4_p17//readbackCal_C9.dat
[14:06:02.518] <TB2> INFO: write readback calibration parameters into /home/Reception/DATA/KITAACHEN/M4589_Reception_2016-07-20_15h56m_1469022964//000_ReceptionTestBB4_p17//readbackCal_C10.dat
[14:06:02.529] <TB2> INFO: write readback calibration parameters into /home/Reception/DATA/KITAACHEN/M4589_Reception_2016-07-20_15h56m_1469022964//000_ReceptionTestBB4_p17//readbackCal_C11.dat
[14:06:02.540] <TB2> INFO: write readback calibration parameters into /home/Reception/DATA/KITAACHEN/M4589_Reception_2016-07-20_15h56m_1469022964//000_ReceptionTestBB4_p17//readbackCal_C12.dat
[14:06:02.546] <TB2> INFO: write readback calibration parameters into /home/Reception/DATA/KITAACHEN/M4589_Reception_2016-07-20_15h56m_1469022964//000_ReceptionTestBB4_p17//readbackCal_C13.dat
[14:06:02.554] <TB2> INFO: write readback calibration parameters into /home/Reception/DATA/KITAACHEN/M4589_Reception_2016-07-20_15h56m_1469022964//000_ReceptionTestBB4_p17//readbackCal_C14.dat
[14:06:02.560] <TB2> INFO: write readback calibration parameters into /home/Reception/DATA/KITAACHEN/M4589_Reception_2016-07-20_15h56m_1469022964//000_ReceptionTestBB4_p17//readbackCal_C15.dat
[14:06:02.611] <TB2> INFO: PixTestPattern:: pg_setup set to default.
[14:06:02.611] <TB2> INFO: ----------------------------------------------------------------------
[14:06:02.612] <TB2> INFO: PixTestReadback::CalibrateVa()
[14:06:02.612] <TB2> INFO: ----------------------------------------------------------------------
[14:06:12.014] <TB2> INFO: write readback calibration parameters into /home/Reception/DATA/KITAACHEN/M4589_Reception_2016-07-20_15h56m_1469022964//000_ReceptionTestBB4_p17//readbackCal_C0.dat
[14:06:12.036] <TB2> INFO: write readback calibration parameters into /home/Reception/DATA/KITAACHEN/M4589_Reception_2016-07-20_15h56m_1469022964//000_ReceptionTestBB4_p17//readbackCal_C1.dat
[14:06:12.047] <TB2> INFO: write readback calibration parameters into /home/Reception/DATA/KITAACHEN/M4589_Reception_2016-07-20_15h56m_1469022964//000_ReceptionTestBB4_p17//readbackCal_C2.dat
[14:06:12.056] <TB2> INFO: write readback calibration parameters into /home/Reception/DATA/KITAACHEN/M4589_Reception_2016-07-20_15h56m_1469022964//000_ReceptionTestBB4_p17//readbackCal_C3.dat
[14:06:12.066] <TB2> INFO: write readback calibration parameters into /home/Reception/DATA/KITAACHEN/M4589_Reception_2016-07-20_15h56m_1469022964//000_ReceptionTestBB4_p17//readbackCal_C4.dat
[14:06:12.075] <TB2> INFO: write readback calibration parameters into /home/Reception/DATA/KITAACHEN/M4589_Reception_2016-07-20_15h56m_1469022964//000_ReceptionTestBB4_p17//readbackCal_C5.dat
[14:06:12.083] <TB2> INFO: write readback calibration parameters into /home/Reception/DATA/KITAACHEN/M4589_Reception_2016-07-20_15h56m_1469022964//000_ReceptionTestBB4_p17//readbackCal_C6.dat
[14:06:12.092] <TB2> INFO: write readback calibration parameters into /home/Reception/DATA/KITAACHEN/M4589_Reception_2016-07-20_15h56m_1469022964//000_ReceptionTestBB4_p17//readbackCal_C7.dat
[14:06:12.102] <TB2> INFO: write readback calibration parameters into /home/Reception/DATA/KITAACHEN/M4589_Reception_2016-07-20_15h56m_1469022964//000_ReceptionTestBB4_p17//readbackCal_C8.dat
[14:06:12.109] <TB2> INFO: write readback calibration parameters into /home/Reception/DATA/KITAACHEN/M4589_Reception_2016-07-20_15h56m_1469022964//000_ReceptionTestBB4_p17//readbackCal_C9.dat
[14:06:12.118] <TB2> INFO: write readback calibration parameters into /home/Reception/DATA/KITAACHEN/M4589_Reception_2016-07-20_15h56m_1469022964//000_ReceptionTestBB4_p17//readbackCal_C10.dat
[14:06:12.126] <TB2> INFO: write readback calibration parameters into /home/Reception/DATA/KITAACHEN/M4589_Reception_2016-07-20_15h56m_1469022964//000_ReceptionTestBB4_p17//readbackCal_C11.dat
[14:06:12.137] <TB2> INFO: write readback calibration parameters into /home/Reception/DATA/KITAACHEN/M4589_Reception_2016-07-20_15h56m_1469022964//000_ReceptionTestBB4_p17//readbackCal_C12.dat
[14:06:12.153] <TB2> INFO: write readback calibration parameters into /home/Reception/DATA/KITAACHEN/M4589_Reception_2016-07-20_15h56m_1469022964//000_ReceptionTestBB4_p17//readbackCal_C13.dat
[14:06:12.162] <TB2> INFO: write readback calibration parameters into /home/Reception/DATA/KITAACHEN/M4589_Reception_2016-07-20_15h56m_1469022964//000_ReceptionTestBB4_p17//readbackCal_C14.dat
[14:06:12.168] <TB2> INFO: write readback calibration parameters into /home/Reception/DATA/KITAACHEN/M4589_Reception_2016-07-20_15h56m_1469022964//000_ReceptionTestBB4_p17//readbackCal_C15.dat
[14:06:12.221] <TB2> INFO: PixTestPattern:: pg_setup set to default.
[14:06:12.221] <TB2> INFO: ----------------------------------------------------------------------
[14:06:12.221] <TB2> INFO: PixTestReadback::readbackVbg()
[14:06:12.221] <TB2> INFO: ----------------------------------------------------------------------
[14:06:19.404] <TB2> INFO: PixTestPattern:: pg_setup set to default.
[14:06:19.404] <TB2> INFO: ----------------------------------------------------------------------
[14:06:19.405] <TB2> INFO: PixTestReadback::getCalibratedVbg()
[14:06:19.405] <TB2> INFO: ----------------------------------------------------------------------
[14:06:19.405] <TB2> INFO: Vbg will be calibrated using Vd calibration
[14:06:19.405] <TB2> INFO: /*/*/*/*::: ROC 0: uncalibrated Vbg = 155.8calibrated Vbg = 1.2233 :::*/*/*/*/
[14:06:19.405] <TB2> INFO: /*/*/*/*::: ROC 1: uncalibrated Vbg = 159calibrated Vbg = 1.22627 :::*/*/*/*/
[14:06:19.405] <TB2> INFO: /*/*/*/*::: ROC 2: uncalibrated Vbg = 159.3calibrated Vbg = 1.22792 :::*/*/*/*/
[14:06:19.405] <TB2> INFO: /*/*/*/*::: ROC 3: uncalibrated Vbg = 157.3calibrated Vbg = 1.23073 :::*/*/*/*/
[14:06:19.405] <TB2> INFO: /*/*/*/*::: ROC 4: uncalibrated Vbg = 157.8calibrated Vbg = 1.23702 :::*/*/*/*/
[14:06:19.405] <TB2> INFO: /*/*/*/*::: ROC 5: uncalibrated Vbg = 158.8calibrated Vbg = 1.24363 :::*/*/*/*/
[14:06:19.405] <TB2> INFO: /*/*/*/*::: ROC 6: uncalibrated Vbg = 154.3calibrated Vbg = 1.24379 :::*/*/*/*/
[14:06:19.405] <TB2> INFO: /*/*/*/*::: ROC 7: uncalibrated Vbg = 160.5calibrated Vbg = 1.23724 :::*/*/*/*/
[14:06:19.405] <TB2> INFO: /*/*/*/*::: ROC 8: uncalibrated Vbg = 152calibrated Vbg = 1.24245 :::*/*/*/*/
[14:06:19.405] <TB2> INFO: /*/*/*/*::: ROC 9: uncalibrated Vbg = 147.7calibrated Vbg = 1.24089 :::*/*/*/*/
[14:06:19.405] <TB2> INFO: /*/*/*/*::: ROC 10: uncalibrated Vbg = 163calibrated Vbg = 1.23549 :::*/*/*/*/
[14:06:19.405] <TB2> INFO: /*/*/*/*::: ROC 11: uncalibrated Vbg = 151.2calibrated Vbg = 1.23714 :::*/*/*/*/
[14:06:19.405] <TB2> INFO: /*/*/*/*::: ROC 12: uncalibrated Vbg = 152.9calibrated Vbg = 1.22681 :::*/*/*/*/
[14:06:19.405] <TB2> INFO: /*/*/*/*::: ROC 13: uncalibrated Vbg = 151.9calibrated Vbg = 1.22851 :::*/*/*/*/
[14:06:19.405] <TB2> INFO: /*/*/*/*::: ROC 14: uncalibrated Vbg = 160.9calibrated Vbg = 1.23163 :::*/*/*/*/
[14:06:19.405] <TB2> INFO: /*/*/*/*::: ROC 15: uncalibrated Vbg = 158.4calibrated Vbg = 1.23201 :::*/*/*/*/
[14:06:19.408] <TB2> INFO: ----------------------------------------------------------------------
[14:06:19.408] <TB2> INFO: PixTestReadback::CalibrateIa()
[14:06:19.408] <TB2> INFO: ----------------------------------------------------------------------
[14:08:52.906] <TB2> INFO: write readback calibration parameters into /home/Reception/DATA/KITAACHEN/M4589_Reception_2016-07-20_15h56m_1469022964//000_ReceptionTestBB4_p17//readbackCal_C0.dat
[14:08:52.913] <TB2> INFO: write readback calibration parameters into /home/Reception/DATA/KITAACHEN/M4589_Reception_2016-07-20_15h56m_1469022964//000_ReceptionTestBB4_p17//readbackCal_C1.dat
[14:08:52.944] <TB2> INFO: write readback calibration parameters into /home/Reception/DATA/KITAACHEN/M4589_Reception_2016-07-20_15h56m_1469022964//000_ReceptionTestBB4_p17//readbackCal_C2.dat
[14:08:52.950] <TB2> INFO: write readback calibration parameters into /home/Reception/DATA/KITAACHEN/M4589_Reception_2016-07-20_15h56m_1469022964//000_ReceptionTestBB4_p17//readbackCal_C3.dat
[14:08:52.955] <TB2> INFO: write readback calibration parameters into /home/Reception/DATA/KITAACHEN/M4589_Reception_2016-07-20_15h56m_1469022964//000_ReceptionTestBB4_p17//readbackCal_C4.dat
[14:08:52.961] <TB2> INFO: write readback calibration parameters into /home/Reception/DATA/KITAACHEN/M4589_Reception_2016-07-20_15h56m_1469022964//000_ReceptionTestBB4_p17//readbackCal_C5.dat
[14:08:52.968] <TB2> INFO: write readback calibration parameters into /home/Reception/DATA/KITAACHEN/M4589_Reception_2016-07-20_15h56m_1469022964//000_ReceptionTestBB4_p17//readbackCal_C6.dat
[14:08:52.973] <TB2> INFO: write readback calibration parameters into /home/Reception/DATA/KITAACHEN/M4589_Reception_2016-07-20_15h56m_1469022964//000_ReceptionTestBB4_p17//readbackCal_C7.dat
[14:08:52.979] <TB2> INFO: write readback calibration parameters into /home/Reception/DATA/KITAACHEN/M4589_Reception_2016-07-20_15h56m_1469022964//000_ReceptionTestBB4_p17//readbackCal_C8.dat
[14:08:52.985] <TB2> INFO: write readback calibration parameters into /home/Reception/DATA/KITAACHEN/M4589_Reception_2016-07-20_15h56m_1469022964//000_ReceptionTestBB4_p17//readbackCal_C9.dat
[14:08:52.990] <TB2> INFO: write readback calibration parameters into /home/Reception/DATA/KITAACHEN/M4589_Reception_2016-07-20_15h56m_1469022964//000_ReceptionTestBB4_p17//readbackCal_C10.dat
[14:08:52.996] <TB2> INFO: write readback calibration parameters into /home/Reception/DATA/KITAACHEN/M4589_Reception_2016-07-20_15h56m_1469022964//000_ReceptionTestBB4_p17//readbackCal_C11.dat
[14:08:53.003] <TB2> INFO: write readback calibration parameters into /home/Reception/DATA/KITAACHEN/M4589_Reception_2016-07-20_15h56m_1469022964//000_ReceptionTestBB4_p17//readbackCal_C12.dat
[14:08:53.010] <TB2> INFO: write readback calibration parameters into /home/Reception/DATA/KITAACHEN/M4589_Reception_2016-07-20_15h56m_1469022964//000_ReceptionTestBB4_p17//readbackCal_C13.dat
[14:08:53.016] <TB2> INFO: write readback calibration parameters into /home/Reception/DATA/KITAACHEN/M4589_Reception_2016-07-20_15h56m_1469022964//000_ReceptionTestBB4_p17//readbackCal_C14.dat
[14:08:53.021] <TB2> INFO: write readback calibration parameters into /home/Reception/DATA/KITAACHEN/M4589_Reception_2016-07-20_15h56m_1469022964//000_ReceptionTestBB4_p17//readbackCal_C15.dat
[14:08:53.072] <TB2> INFO: PixTestPattern:: pg_setup set to default.
[14:08:53.074] <TB2> INFO: PixTestReadback::doTest() done
[14:08:53.096] <TB2> INFO: enter test to run
[14:08:53.096] <TB2> INFO: test: BB4 no parameter change
[14:08:53.096] <TB2> INFO: running: bb4
[14:08:53.096] <TB2> INFO: PixTestBB4Map::doTest() ntrig = 100
[14:08:53.096] <TB2> INFO: VthrComp (min,max,step) = 70 130 5
[14:08:53.096] <TB2> INFO: CalDel (min,max,step) = 80 200 10
[14:08:53.096] <TB2> INFO: Vcal = 255
[14:08:53.096] <TB2> INFO: Cut = 0.5
[14:08:53.096] <TB2> INFO: flag 2
[14:08:53.098] <TB2> INFO: CtrlReg 4 (large Vcal)
[14:08:53.098] <TB2> INFO: saving VthrComp and CalDel before running tests)
[14:08:53.098] <TB2> INFO: 0 130 0
[14:08:53.098] <TB2> INFO: 1 130 0
[14:08:53.098] <TB2> INFO: 2 130 0
[14:08:53.098] <TB2> INFO: 3 130 0
[14:08:53.098] <TB2> INFO: 4 130 0
[14:08:53.098] <TB2> INFO: 5 130 0
[14:08:53.098] <TB2> INFO: 6 130 0
[14:08:53.098] <TB2> INFO: 7 130 0
[14:08:53.098] <TB2> INFO: 8 130 0
[14:08:53.098] <TB2> INFO: 9 130 0
[14:08:53.098] <TB2> INFO: 10 130 0
[14:08:53.098] <TB2> INFO: 11 130 0
[14:08:53.098] <TB2> INFO: 12 130 0
[14:08:53.098] <TB2> INFO: 13 130 0
[14:08:53.098] <TB2> INFO: 14 130 0
[14:08:53.098] <TB2> INFO: 15 130 0
[14:08:53.381] <TB2> INFO: Expecting 416000 events.
[14:08:58.031] <TB2> ERROR: <datapipe.cc/CheckEventValidity:L507> Channel 0 Number of ROCs (0) != Token Chain Length (8)
[14:08:58.059] <TB2> ERROR: <datapipe.cc/CheckEventID:L469> Channel 0 Event ID mismatch: local ID (16) != TBM ID (2)
[14:09:02.862] <TB2> INFO: 416000 events read in total (8925ms).
[14:09:02.862] <TB2> INFO: Test took 9758ms.
[14:09:02.863] <TB2> INFO: starting loop over VthrComp
[14:09:03.411] <TB2> INFO: Expecting 416000 events.
[14:09:12.866] <TB2> INFO: 416000 events read in total (8899ms).
[14:09:12.866] <TB2> INFO: Test took 9728ms.
[14:09:12.867] <TB2> INFO: got noisemap
[14:09:13.142] <TB2> INFO: ROC 0, integral 0
[14:09:13.142] <TB2> INFO: ROC 1, integral 0
[14:09:13.143] <TB2> INFO: ROC 2, integral 0
[14:09:13.143] <TB2> INFO: ROC 3, integral 0
[14:09:13.143] <TB2> INFO: ROC 4, integral 0
[14:09:13.143] <TB2> INFO: ROC 5, integral 0
[14:09:13.143] <TB2> INFO: ROC 6, integral 0
[14:09:13.143] <TB2> INFO: ROC 7, integral 0
[14:09:13.143] <TB2> INFO: ROC 8, integral 0
[14:09:13.144] <TB2> INFO: ROC 9, integral 0
[14:09:13.144] <TB2> INFO: ROC 10, integral 0
[14:09:13.144] <TB2> INFO: ROC 11, integral 0
[14:09:13.144] <TB2> INFO: ROC 12, integral 0
[14:09:13.144] <TB2> INFO: ROC 13, integral 0
[14:09:13.144] <TB2> INFO: ROC 14, integral 0
[14:09:13.144] <TB2> INFO: ROC 15, integral 0
[14:09:13.145] <TB2> INFO: VthrComp = 70 done
[14:09:13.415] <TB2> INFO: Expecting 416000 events.
[14:09:22.918] <TB2> INFO: 416000 events read in total (8947ms).
[14:09:22.918] <TB2> INFO: Test took 9768ms.
[14:09:22.919] <TB2> INFO: got noisemap
[14:09:23.194] <TB2> INFO: ROC 0, integral 0
[14:09:23.194] <TB2> INFO: ROC 1, integral 0
[14:09:23.194] <TB2> INFO: ROC 2, integral 0
[14:09:23.194] <TB2> INFO: ROC 3, integral 0
[14:09:23.194] <TB2> INFO: ROC 4, integral 0
[14:09:23.194] <TB2> INFO: ROC 5, integral 0
[14:09:23.195] <TB2> INFO: ROC 6, integral 0
[14:09:23.195] <TB2> INFO: ROC 7, integral 0
[14:09:23.195] <TB2> INFO: ROC 8, integral 0
[14:09:23.195] <TB2> INFO: ROC 9, integral 0
[14:09:23.195] <TB2> INFO: ROC 10, integral 0
[14:09:23.195] <TB2> INFO: ROC 11, integral 0
[14:09:23.195] <TB2> INFO: ROC 12, integral 0
[14:09:23.195] <TB2> INFO: ROC 13, integral 0
[14:09:23.196] <TB2> INFO: ROC 14, integral 0
[14:09:23.196] <TB2> INFO: ROC 15, integral 0
[14:09:23.196] <TB2> INFO: VthrComp = 75 done
[14:09:23.474] <TB2> INFO: Expecting 416000 events.
[14:09:32.927] <TB2> INFO: 416000 events read in total (8896ms).
[14:09:32.928] <TB2> INFO: Test took 9727ms.
[14:09:32.928] <TB2> INFO: got noisemap
[14:09:33.198] <TB2> INFO: ROC 0, integral 0
[14:09:33.198] <TB2> INFO: ROC 1, integral 0
[14:09:33.199] <TB2> INFO: ROC 2, integral 0
[14:09:33.199] <TB2> INFO: ROC 3, integral 0
[14:09:33.199] <TB2> INFO: ROC 4, integral 0
[14:09:33.199] <TB2> INFO: ROC 5, integral 0
[14:09:33.199] <TB2> INFO: ROC 6, integral 0
[14:09:33.199] <TB2> INFO: ROC 7, integral 0
[14:09:33.199] <TB2> INFO: ROC 8, integral 0
[14:09:33.200] <TB2> INFO: ROC 9, integral 0
[14:09:33.200] <TB2> INFO: ROC 10, integral 0
[14:09:33.200] <TB2> INFO: ROC 11, integral 0
[14:09:33.200] <TB2> INFO: ROC 12, integral 0
[14:09:33.200] <TB2> INFO: ROC 13, integral 0
[14:09:33.200] <TB2> INFO: ROC 14, integral 0
[14:09:33.200] <TB2> INFO: ROC 15, integral 0
[14:09:33.201] <TB2> INFO: VthrComp = 80 done
[14:09:33.473] <TB2> INFO: Expecting 416000 events.
[14:09:42.889] <TB2> INFO: 416000 events read in total (8860ms).
[14:09:42.890] <TB2> INFO: Test took 9684ms.
[14:09:42.890] <TB2> INFO: got noisemap
[14:09:43.161] <TB2> INFO: ROC 0, integral 0
[14:09:43.162] <TB2> INFO: ROC 1, integral 0
[14:09:43.162] <TB2> INFO: ROC 2, integral 0
[14:09:43.162] <TB2> INFO: ROC 3, integral 0
[14:09:43.162] <TB2> INFO: ROC 4, integral 0
[14:09:43.162] <TB2> INFO: ROC 5, integral 0
[14:09:43.162] <TB2> INFO: ROC 6, integral 0
[14:09:43.162] <TB2> INFO: ROC 7, integral 0
[14:09:43.162] <TB2> INFO: ROC 8, integral 0
[14:09:43.162] <TB2> INFO: ROC 9, integral 0
[14:09:43.162] <TB2> INFO: ROC 10, integral 0
[14:09:43.162] <TB2> INFO: ROC 11, integral 0
[14:09:43.162] <TB2> INFO: ROC 12, integral 0
[14:09:43.162] <TB2> INFO: ROC 13, integral 0
[14:09:43.162] <TB2> INFO: ROC 14, integral 0
[14:09:43.162] <TB2> INFO: ROC 15, integral 0
[14:09:43.162] <TB2> INFO: VthrComp = 85 done
[14:09:43.428] <TB2> INFO: Expecting 416000 events.
[14:09:52.887] <TB2> INFO: 416000 events read in total (8903ms).
[14:09:52.888] <TB2> INFO: Test took 9721ms.
[14:09:52.888] <TB2> INFO: got noisemap
[14:09:53.159] <TB2> INFO: ROC 0, integral 0
[14:09:53.159] <TB2> INFO: ROC 1, integral 0
[14:09:53.159] <TB2> INFO: ROC 2, integral 0
[14:09:53.159] <TB2> INFO: ROC 3, integral 0
[14:09:53.159] <TB2> INFO: ROC 4, integral 0
[14:09:53.159] <TB2> INFO: ROC 5, integral 0
[14:09:53.159] <TB2> INFO: ROC 6, integral 0
[14:09:53.159] <TB2> INFO: ROC 7, integral 0
[14:09:53.159] <TB2> INFO: ROC 8, integral 0
[14:09:53.159] <TB2> INFO: ROC 9, integral 0
[14:09:53.159] <TB2> INFO: ROC 10, integral 0
[14:09:53.159] <TB2> INFO: ROC 11, integral 0
[14:09:53.159] <TB2> INFO: ROC 12, integral 0
[14:09:53.160] <TB2> INFO: ROC 13, integral 0
[14:09:53.160] <TB2> INFO: ROC 14, integral 0
[14:09:53.160] <TB2> INFO: ROC 15, integral 0
[14:09:53.160] <TB2> INFO: VthrComp = 90 done
[14:09:53.430] <TB2> INFO: Expecting 416000 events.
[14:10:02.858] <TB2> INFO: 416000 events read in total (8873ms).
[14:10:02.858] <TB2> INFO: Test took 9694ms.
[14:10:02.858] <TB2> INFO: got noisemap
[14:10:03.129] <TB2> INFO: ROC 0, integral 0
[14:10:03.129] <TB2> INFO: ROC 1, integral 0
[14:10:03.129] <TB2> INFO: ROC 2, integral 0
[14:10:03.129] <TB2> INFO: ROC 3, integral 0
[14:10:03.129] <TB2> INFO: ROC 4, integral 0
[14:10:03.130] <TB2> INFO: ROC 5, integral 0
[14:10:03.130] <TB2> INFO: ROC 6, integral 0
[14:10:03.130] <TB2> INFO: ROC 7, integral 0
[14:10:03.130] <TB2> INFO: ROC 8, integral 0
[14:10:03.130] <TB2> INFO: ROC 9, integral 0
[14:10:03.130] <TB2> INFO: ROC 10, integral 0
[14:10:03.130] <TB2> INFO: ROC 11, integral 0
[14:10:03.130] <TB2> INFO: ROC 12, integral 0
[14:10:03.130] <TB2> INFO: ROC 13, integral 0
[14:10:03.130] <TB2> INFO: ROC 14, integral 0
[14:10:03.131] <TB2> INFO: ROC 15, integral 0
[14:10:03.131] <TB2> INFO: VthrComp = 95 done
[14:10:03.422] <TB2> INFO: Expecting 416000 events.
[14:10:12.910] <TB2> INFO: 416000 events read in total (8932ms).
[14:10:12.910] <TB2> INFO: Test took 9775ms.
[14:10:12.910] <TB2> INFO: got noisemap
[14:10:13.185] <TB2> INFO: ROC 0, integral 0
[14:10:13.185] <TB2> INFO: ROC 1, integral 0
[14:10:13.185] <TB2> INFO: ROC 2, integral 0
[14:10:13.185] <TB2> INFO: ROC 3, integral 0
[14:10:13.185] <TB2> INFO: ROC 4, integral 0
[14:10:13.185] <TB2> INFO: ROC 5, integral 0
[14:10:13.185] <TB2> INFO: ROC 6, integral 1
[14:10:13.185] <TB2> INFO: ROC 7, integral 0
[14:10:13.185] <TB2> INFO: ROC 8, integral 0
[14:10:13.185] <TB2> INFO: ROC 9, integral 0
[14:10:13.185] <TB2> INFO: ROC 10, integral 0
[14:10:13.185] <TB2> INFO: ROC 11, integral 0
[14:10:13.185] <TB2> INFO: ROC 12, integral 0
[14:10:13.185] <TB2> INFO: ROC 13, integral 0
[14:10:13.186] <TB2> INFO: ROC 14, integral 0
[14:10:13.186] <TB2> INFO: ROC 15, integral 0
[14:10:13.186] <TB2> INFO: VthrComp = 100 done
[14:10:13.456] <TB2> INFO: Expecting 416000 events.
[14:10:22.960] <TB2> INFO: 416000 events read in total (8948ms).
[14:10:22.961] <TB2> INFO: Test took 9771ms.
[14:10:22.961] <TB2> INFO: got noisemap
[14:10:23.232] <TB2> INFO: ROC 0, integral 0
[14:10:23.232] <TB2> INFO: ROC 1, integral 0
[14:10:23.232] <TB2> INFO: ROC 2, integral 0
[14:10:23.232] <TB2> INFO: ROC 3, integral 0
[14:10:23.232] <TB2> INFO: ROC 4, integral 0
[14:10:23.232] <TB2> INFO: ROC 5, integral 0
[14:10:23.232] <TB2> INFO: ROC 6, integral 9
[14:10:23.232] <TB2> INFO: ROC 7, integral 0
[14:10:23.232] <TB2> INFO: ROC 8, integral 0
[14:10:23.232] <TB2> INFO: ROC 9, integral 0
[14:10:23.233] <TB2> INFO: ROC 10, integral 0
[14:10:23.233] <TB2> INFO: ROC 11, integral 0
[14:10:23.233] <TB2> INFO: ROC 12, integral 0
[14:10:23.233] <TB2> INFO: ROC 13, integral 0
[14:10:23.233] <TB2> INFO: ROC 14, integral 0
[14:10:23.233] <TB2> INFO: ROC 15, integral 0
[14:10:23.233] <TB2> INFO: VthrComp = 105 done
[14:10:23.520] <TB2> INFO: Expecting 416000 events.
[14:10:32.938] <TB2> INFO: 416000 events read in total (8862ms).
[14:10:32.938] <TB2> INFO: Test took 9701ms.
[14:10:32.939] <TB2> INFO: got noisemap
[14:10:33.212] <TB2> INFO: ROC 0, integral 0
[14:10:33.212] <TB2> INFO: ROC 1, integral 1
[14:10:33.212] <TB2> INFO: ROC 2, integral 0
[14:10:33.212] <TB2> INFO: ROC 3, integral 1
[14:10:33.213] <TB2> INFO: ROC 4, integral 0
[14:10:33.213] <TB2> INFO: ROC 5, integral 1
[14:10:33.213] <TB2> INFO: ROC 6, integral 158
[14:10:33.213] <TB2> INFO: ROC #6: threshold found
[14:10:33.213] <TB2> INFO: ROC 7, integral 1
[14:10:33.213] <TB2> INFO: ROC 8, integral 0
[14:10:33.214] <TB2> INFO: ROC 9, integral 1
[14:10:33.214] <TB2> INFO: ROC 10, integral 0
[14:10:33.214] <TB2> INFO: ROC 11, integral 1
[14:10:33.214] <TB2> INFO: ROC 12, integral 0
[14:10:33.214] <TB2> INFO: ROC 13, integral 0
[14:10:33.215] <TB2> INFO: ROC 14, integral 2
[14:10:33.215] <TB2> INFO: ROC 15, integral 0
[14:10:33.215] <TB2> INFO: VthrComp = 110 done
[14:10:33.494] <TB2> INFO: Expecting 416000 events.
[14:10:43.027] <TB2> INFO: 416000 events read in total (8977ms).
[14:10:43.028] <TB2> INFO: Test took 9809ms.
[14:10:43.028] <TB2> INFO: got noisemap
[14:10:43.302] <TB2> INFO: ROC 0, integral 1
[14:10:43.302] <TB2> INFO: ROC 1, integral 55
[14:10:43.302] <TB2> INFO: ROC #1: threshold found
[14:10:43.302] <TB2> INFO: ROC 2, integral 22
[14:10:43.302] <TB2> INFO: ROC #2: threshold found
[14:10:43.302] <TB2> INFO: ROC 3, integral 36
[14:10:43.302] <TB2> INFO: ROC #3: threshold found
[14:10:43.303] <TB2> INFO: ROC 4, integral 0
[14:10:43.303] <TB2> INFO: ROC 5, integral 54
[14:10:43.303] <TB2> INFO: ROC #5: threshold found
[14:10:43.303] <TB2> INFO: ROC 7, integral 57
[14:10:43.304] <TB2> INFO: ROC #7: threshold found
[14:10:43.305] <TB2> INFO: ROC 8, integral 11
[14:10:43.305] <TB2> INFO: ROC #8: threshold found
[14:10:43.305] <TB2> INFO: ROC 9, integral 113
[14:10:43.305] <TB2> INFO: ROC #9: threshold found
[14:10:43.307] <TB2> INFO: ROC 10, integral 6
[14:10:43.307] <TB2> INFO: ROC 11, integral 31
[14:10:43.307] <TB2> INFO: ROC #11: threshold found
[14:10:43.307] <TB2> INFO: ROC 12, integral 0
[14:10:43.307] <TB2> INFO: ROC 13, integral 0
[14:10:43.308] <TB2> INFO: ROC 14, integral 105
[14:10:43.308] <TB2> INFO: ROC #14: threshold found
[14:10:43.308] <TB2> INFO: ROC 15, integral 5
[14:10:43.308] <TB2> INFO: VthrComp = 115 done
[14:10:43.602] <TB2> INFO: Expecting 416000 events.
[14:10:53.127] <TB2> INFO: 416000 events read in total (8969ms).
[14:10:53.128] <TB2> INFO: Test took 9815ms.
[14:10:53.129] <TB2> INFO: got noisemap
[14:10:53.396] <TB2> INFO: ROC 0, integral 5
[14:10:53.396] <TB2> INFO: ROC 4, integral 0
[14:10:53.396] <TB2> INFO: ROC 10, integral 35
[14:10:53.396] <TB2> INFO: ROC #10: threshold found
[14:10:53.397] <TB2> INFO: ROC 12, integral 1
[14:10:53.397] <TB2> INFO: ROC 13, integral 2
[14:10:53.397] <TB2> INFO: ROC 15, integral 13
[14:10:53.397] <TB2> INFO: ROC #15: threshold found
[14:10:53.397] <TB2> INFO: VthrComp = 120 done
[14:10:53.666] <TB2> INFO: Expecting 416000 events.
[14:11:03.179] <TB2> INFO: 416000 events read in total (8957ms).
[14:11:03.180] <TB2> INFO: Test took 9779ms.
[14:11:03.182] <TB2> INFO: got noisemap
[14:11:03.444] <TB2> INFO: ROC 0, integral 134
[14:11:03.444] <TB2> INFO: ROC #0: threshold found
[14:11:03.444] <TB2> INFO: ROC 4, integral 30
[14:11:03.444] <TB2> INFO: ROC #4: threshold found
[14:11:03.444] <TB2> INFO: ROC 12, integral 9
[14:11:03.444] <TB2> INFO: ROC 13, integral 16
[14:11:03.445] <TB2> INFO: ROC #13: threshold found
[14:11:03.445] <TB2> INFO: VthrComp = 125 done
[14:11:03.740] <TB2> INFO: Expecting 416000 events.
[14:11:13.204] <TB2> INFO: 416000 events read in total (8908ms).
[14:11:13.204] <TB2> INFO: Test took 9754ms.
[14:11:13.206] <TB2> INFO: got noisemap
[14:11:13.472] <TB2> INFO: ROC 12, integral 73
[14:11:13.472] <TB2> INFO: ROC #12: threshold found
[14:11:13.472] <TB2> INFO: VthrComp = 130 done
[14:11:13.472] <TB2> INFO: Minimal threshold for all ROCs found
[14:11:13.474] <TB2> INFO: resulting noise thresholds per ROC = 120 110 110 110 120 110 105 110 110 110 115 110 125 120 110 115
[14:11:13.474] <TB2> INFO: threshold scan done
[14:11:13.750] <TB2> INFO: Expecting 416000 events.
[14:11:23.531] <TB2> INFO: 416000 events read in total (9225ms).
[14:11:23.532] <TB2> INFO: Test took 10049ms.
[14:11:23.781] <TB2> INFO: ROC 0, missing bumps = 4160, total number of good bumps = 0
[14:11:23.783] <TB2> INFO: ROC 1, missing bumps = 3955, total number of good bumps = 205
[14:11:23.785] <TB2> INFO: ROC 2, missing bumps = 4152, total number of good bumps = 8
[14:11:23.786] <TB2> INFO: ROC 3, missing bumps = 4160, total number of good bumps = 0
[14:11:23.788] <TB2> INFO: ROC 4, missing bumps = 4159, total number of good bumps = 1
[14:11:23.790] <TB2> INFO: ROC 5, missing bumps = 4158, total number of good bumps = 2
[14:11:23.791] <TB2> INFO: ROC 6, missing bumps = 3910, total number of good bumps = 250
[14:11:23.793] <TB2> INFO: ROC 7, missing bumps = 4119, total number of good bumps = 41
[14:11:23.795] <TB2> INFO: ROC 8, missing bumps = 4159, total number of good bumps = 1
[14:11:23.796] <TB2> INFO: ROC 9, missing bumps = 4160, total number of good bumps = 0
[14:11:23.798] <TB2> INFO: ROC 10, missing bumps = 1859, total number of good bumps = 2301
[14:11:23.800] <TB2> INFO: ROC 11, missing bumps = 4160, total number of good bumps = 0
[14:11:23.801] <TB2> INFO: ROC 12, missing bumps = 3970, total number of good bumps = 190
[14:11:23.803] <TB2> INFO: ROC 13, missing bumps = 4159, total number of good bumps = 1
[14:11:23.805] <TB2> INFO: ROC 14, missing bumps = 4160, total number of good bumps = 0
[14:11:23.807] <TB2> INFO: ROC 15, missing bumps = 4129, total number of good bumps = 31
[14:11:23.807] <TB2> INFO: CalDel = 80 done
[14:11:24.082] <TB2> INFO: Expecting 416000 events.
[14:11:35.702] <TB2> INFO: 416000 events read in total (11064ms).
[14:11:35.703] <TB2> INFO: Test took 11891ms.
[14:11:35.899] <TB2> INFO: ROC 0, missing bumps = 4160, total number of good bumps = 0
[14:11:35.901] <TB2> INFO: ROC 1, missing bumps = 741, total number of good bumps = 3419
[14:11:35.903] <TB2> INFO: ROC 2, missing bumps = 2715, total number of good bumps = 1445
[14:11:35.904] <TB2> INFO: ROC 3, missing bumps = 4144, total number of good bumps = 16
[14:11:35.906] <TB2> INFO: ROC 4, missing bumps = 3414, total number of good bumps = 746
[14:11:35.908] <TB2> INFO: ROC 5, missing bumps = 3331, total number of good bumps = 829
[14:11:35.910] <TB2> INFO: ROC 6, missing bumps = 932, total number of good bumps = 3228
[14:11:35.912] <TB2> INFO: ROC 7, missing bumps = 2765, total number of good bumps = 1395
[14:11:35.913] <TB2> INFO: ROC 8, missing bumps = 4159, total number of good bumps = 1
[14:11:35.915] <TB2> INFO: ROC 9, missing bumps = 3719, total number of good bumps = 441
[14:11:35.917] <TB2> INFO: ROC 10, missing bumps = 27, total number of good bumps = 4133
[14:11:35.919] <TB2> INFO: ROC 11, missing bumps = 3866, total number of good bumps = 294
[14:11:35.921] <TB2> INFO: ROC 12, missing bumps = 697, total number of good bumps = 3463
[14:11:35.923] <TB2> INFO: ROC 13, missing bumps = 3579, total number of good bumps = 581
[14:11:35.924] <TB2> INFO: ROC 14, missing bumps = 4160, total number of good bumps = 0
[14:11:35.926] <TB2> INFO: ROC 15, missing bumps = 2307, total number of good bumps = 1853
[14:11:35.926] <TB2> INFO: CalDel = 90 done
[14:11:36.227] <TB2> INFO: Expecting 416000 events.
[14:11:50.724] <TB2> INFO: 416000 events read in total (13942ms).
[14:11:50.725] <TB2> INFO: Test took 14793ms.
[14:11:50.894] <TB2> INFO: ROC 0, missing bumps = 2532, total number of good bumps = 1628
[14:11:50.896] <TB2> INFO: ROC 1, missing bumps = 2, total number of good bumps = 4158
[14:11:50.898] <TB2> INFO: ROC 2, missing bumps = 2, total number of good bumps = 4158
[14:11:50.899] <TB2> INFO: ROC 3, missing bumps = 1750, total number of good bumps = 2410
[14:11:50.901] <TB2> INFO: ROC 4, missing bumps = 54, total number of good bumps = 4106
[14:11:50.903] <TB2> INFO: ROC 5, missing bumps = 21, total number of good bumps = 4139
[14:11:50.905] <TB2> INFO: ROC 6, missing bumps = 11, total number of good bumps = 4149
[14:11:50.907] <TB2> INFO: ROC 7, missing bumps = 64, total number of good bumps = 4096
[14:11:50.909] <TB2> INFO: ROC 8, missing bumps = 4059, total number of good bumps = 101
[14:11:50.911] <TB2> INFO: ROC 9, missing bumps = 71, total number of good bumps = 4089
[14:11:50.912] <TB2> INFO: ROC 10, missing bumps = 4, total number of good bumps = 4156
[14:11:50.914] <TB2> INFO: ROC 11, missing bumps = 227, total number of good bumps = 3933
[14:11:50.916] <TB2> INFO: ROC 12, missing bumps = 9, total number of good bumps = 4151
[14:11:50.918] <TB2> INFO: ROC 13, missing bumps = 21, total number of good bumps = 4139
[14:11:50.919] <TB2> INFO: ROC 14, missing bumps = 4159, total number of good bumps = 1
[14:11:50.921] <TB2> INFO: ROC 15, missing bumps = 19, total number of good bumps = 4141
[14:11:50.921] <TB2> INFO: CalDel = 100 done
[14:11:51.191] <TB2> INFO: Expecting 416000 events.
[14:12:06.377] <TB2> INFO: 416000 events read in total (14630ms).
[14:12:06.378] <TB2> INFO: Test took 15453ms.
[14:12:06.549] <TB2> INFO: ROC 0, missing bumps = 48, total number of good bumps = 4112
[14:12:06.551] <TB2> INFO: ROC 1, missing bumps = 1, total number of good bumps = 4159
[14:12:06.552] <TB2> INFO: ROC 2, missing bumps = 0, total number of good bumps = 4160
[14:12:06.554] <TB2> INFO: ROC 3, missing bumps = 10, total number of good bumps = 4150
[14:12:06.555] <TB2> INFO: ROC 4, missing bumps = 2, total number of good bumps = 4158
[14:12:06.557] <TB2> INFO: ROC 5, missing bumps = 2, total number of good bumps = 4158
[14:12:06.558] <TB2> INFO: ROC 6, missing bumps = 6, total number of good bumps = 4154
[14:12:06.560] <TB2> INFO: ROC 7, missing bumps = 2, total number of good bumps = 4158
[14:12:06.561] <TB2> INFO: ROC 8, missing bumps = 1087, total number of good bumps = 3074
[14:12:06.563] <TB2> INFO: ROC 9, missing bumps = 2, total number of good bumps = 4158
[14:12:06.564] <TB2> INFO: ROC 10, missing bumps = 2, total number of good bumps = 4158
[14:12:06.566] <TB2> INFO: ROC 11, missing bumps = 1, total number of good bumps = 4159
[14:12:06.567] <TB2> INFO: ROC 12, missing bumps = 4, total number of good bumps = 4156
[14:12:06.569] <TB2> INFO: ROC 13, missing bumps = 2, total number of good bumps = 4158
[14:12:06.570] <TB2> INFO: ROC 14, missing bumps = 3944, total number of good bumps = 216
[14:12:06.572] <TB2> INFO: ROC 15, missing bumps = 3, total number of good bumps = 4157
[14:12:06.572] <TB2> INFO: CalDel = 110 done
[14:12:06.848] <TB2> INFO: Expecting 416000 events.
[14:12:22.612] <TB2> INFO: 416000 events read in total (15207ms).
[14:12:22.614] <TB2> INFO: Test took 16038ms.
[14:12:22.787] <TB2> INFO: ROC 0, missing bumps = 4, total number of good bumps = 4156
[14:12:22.789] <TB2> INFO: ROC 1, missing bumps = 1, total number of good bumps = 4159
[14:12:22.791] <TB2> INFO: ROC 3, missing bumps = 1, total number of good bumps = 4159
[14:12:22.792] <TB2> INFO: ROC 4, missing bumps = 0, total number of good bumps = 4160
[14:12:22.794] <TB2> INFO: ROC 5, missing bumps = 0, total number of good bumps = 4160
[14:12:22.796] <TB2> INFO: ROC 6, missing bumps = 5, total number of good bumps = 4155
[14:12:22.798] <TB2> INFO: ROC 7, missing bumps = 2, total number of good bumps = 4158
[14:12:22.800] <TB2> INFO: ROC 8, missing bumps = 2, total number of good bumps = 4159
[14:12:22.802] <TB2> INFO: ROC 9, missing bumps = 0, total number of good bumps = 4160
[14:12:22.804] <TB2> INFO: ROC 10, missing bumps = 2, total number of good bumps = 4158
[14:12:22.806] <TB2> INFO: ROC 11, missing bumps = 1, total number of good bumps = 4159
[14:12:22.808] <TB2> INFO: ROC 12, missing bumps = 2, total number of good bumps = 4158
[14:12:22.810] <TB2> INFO: ROC 13, missing bumps = 1, total number of good bumps = 4159
[14:12:22.811] <TB2> INFO: ROC 14, missing bumps = 101, total number of good bumps = 4059
[14:12:22.814] <TB2> INFO: ROC 15, missing bumps = 1, total number of good bumps = 4159
[14:12:22.814] <TB2> INFO: CalDel = 120 done
[14:12:23.100] <TB2> INFO: Expecting 416000 events.
[14:12:38.785] <TB2> INFO: 416000 events read in total (15129ms).
[14:12:38.786] <TB2> INFO: Test took 15967ms.
[14:12:38.959] <TB2> INFO: ROC 0, missing bumps = 1, total number of good bumps = 4159
[14:12:38.960] <TB2> INFO: ROC 1, missing bumps = 2, total number of good bumps = 4159
[14:12:38.962] <TB2> INFO: ROC 3, missing bumps = 1, total number of good bumps = 4159
[14:12:38.963] <TB2> INFO: ROC 6, missing bumps = 0, total number of good bumps = 4160
[14:12:38.965] <TB2> INFO: ROC 7, missing bumps = 1, total number of good bumps = 4159
[14:12:38.966] <TB2> INFO: ROC 8, missing bumps = 2, total number of good bumps = 4159
[14:12:38.968] <TB2> INFO: ROC 10, missing bumps = 4, total number of good bumps = 4159
[14:12:38.969] <TB2> INFO: ROC 11, missing bumps = 0, total number of good bumps = 4160
[14:12:38.971] <TB2> INFO: ROC 12, missing bumps = 2, total number of good bumps = 4158
[14:12:38.973] <TB2> INFO: ROC 13, missing bumps = 0, total number of good bumps = 4160
[14:12:38.974] <TB2> INFO: ROC 14, missing bumps = 3, total number of good bumps = 4157
[14:12:38.976] <TB2> INFO: ROC 15, missing bumps = 1, total number of good bumps = 4159
[14:12:38.976] <TB2> INFO: CalDel = 130 done
[14:12:39.256] <TB2> INFO: Expecting 416000 events.
[14:12:54.817] <TB2> INFO: 416000 events read in total (15005ms).
[14:12:54.819] <TB2> INFO: Test took 15838ms.
[14:12:54.993] <TB2> INFO: ROC 0, missing bumps = 1, total number of good bumps = 4159
[14:12:54.995] <TB2> INFO: ROC 1, missing bumps = 188, total number of good bumps = 4159
[14:12:54.996] <TB2> INFO: ROC 3, missing bumps = 1, total number of good bumps = 4159
[14:12:54.998] <TB2> INFO: ROC 7, missing bumps = 3, total number of good bumps = 4159
[14:12:55.000] <TB2> INFO: ROC 8, missing bumps = 2, total number of good bumps = 4159
[14:12:55.002] <TB2> INFO: ROC 10, missing bumps = 1438, total number of good bumps = 4159
[14:12:55.004] <TB2> INFO: ROC 12, missing bumps = 82, total number of good bumps = 4158
[14:12:55.006] <TB2> INFO: ROC 14, missing bumps = 2, total number of good bumps = 4158
[14:12:55.008] <TB2> INFO: ROC 15, missing bumps = 11, total number of good bumps = 4159
[14:12:55.008] <TB2> INFO: CalDel = 140 done
[14:12:55.297] <TB2> INFO: Expecting 416000 events.
[14:13:08.514] <TB2> INFO: 416000 events read in total (12661ms).
[14:13:08.515] <TB2> INFO: Test took 13501ms.
[14:13:08.684] <TB2> INFO: ROC 0, missing bumps = 12, total number of good bumps = 4159
[14:13:08.686] <TB2> INFO: ROC 1, missing bumps = 4077, total number of good bumps = 4159
[14:13:08.687] <TB2> INFO: ROC 3, missing bumps = 21, total number of good bumps = 4159
[14:13:08.689] <TB2> INFO: ROC 7, missing bumps = 1436, total number of good bumps = 4159
[14:13:08.690] <TB2> INFO: ROC 8, missing bumps = 2, total number of good bumps = 4159
[14:13:08.692] <TB2> INFO: ROC 10, missing bumps = 4148, total number of good bumps = 4159
[14:13:08.693] <TB2> INFO: ROC 12, missing bumps = 3887, total number of good bumps = 4158
[14:13:08.695] <TB2> INFO: ROC 14, missing bumps = 1, total number of good bumps = 4159
[14:13:08.696] <TB2> INFO: ROC 15, missing bumps = 2751, total number of good bumps = 4159
[14:13:08.696] <TB2> INFO: CalDel = 150 done
[14:13:08.966] <TB2> INFO: Expecting 416000 events.
[14:13:19.834] <TB2> INFO: 416000 events read in total (10311ms).
[14:13:19.835] <TB2> INFO: Test took 11134ms.
[14:13:20.053] <TB2> INFO: ROC 0, missing bumps = 2084, total number of good bumps = 4159
[14:13:20.055] <TB2> INFO: ROC 1, missing bumps = 4160, total number of good bumps = 4159
[14:13:20.057] <TB2> INFO: ROC 3, missing bumps = 1927, total number of good bumps = 4159
[14:13:20.059] <TB2> INFO: ROC 7, missing bumps = 4045, total number of good bumps = 4159
[14:13:20.060] <TB2> INFO: ROC 8, missing bumps = 33, total number of good bumps = 4159
[14:13:20.062] <TB2> INFO: ROC 10, missing bumps = 4157, total number of good bumps = 4159
[14:13:20.064] <TB2> INFO: ROC 12, missing bumps = 4153, total number of good bumps = 4158
[14:13:20.066] <TB2> INFO: ROC 14, missing bumps = 1, total number of good bumps = 4159
[14:13:20.067] <TB2> INFO: ROC 15, missing bumps = 4149, total number of good bumps = 4159
[14:13:20.067] <TB2> INFO: CalDel = 160 done
[14:13:20.364] <TB2> INFO: Expecting 416000 events.
[14:13:30.548] <TB2> INFO: 416000 events read in total (9628ms).
[14:13:30.549] <TB2> INFO: Test took 10476ms.
[14:13:30.779] <TB2> INFO: ROC 0, missing bumps = 4129, total number of good bumps = 4159
[14:13:30.780] <TB2> INFO: ROC 1, missing bumps = 4160, total number of good bumps = 4159
[14:13:30.782] <TB2> INFO: ROC 3, missing bumps = 4142, total number of good bumps = 4159
[14:13:30.784] <TB2> INFO: ROC 7, missing bumps = 4159, total number of good bumps = 4159
[14:13:30.786] <TB2> INFO: ROC 8, missing bumps = 2020, total number of good bumps = 4159
[14:13:30.788] <TB2> INFO: ROC 10, missing bumps = 4159, total number of good bumps = 4159
[14:13:30.790] <TB2> INFO: ROC 12, missing bumps = 4159, total number of good bumps = 4158
[14:13:30.791] <TB2> INFO: ROC 14, missing bumps = 3, total number of good bumps = 4160
[14:13:30.793] <TB2> INFO: ROC 15, missing bumps = 4158, total number of good bumps = 4159
[14:13:30.793] <TB2> INFO: CalDel = 170 done
[14:13:31.069] <TB2> INFO: Expecting 416000 events.
[14:13:40.723] <TB2> INFO: 416000 events read in total (9098ms).
[14:13:40.727] <TB2> INFO: Test took 9928ms.
[14:13:40.982] <TB2> INFO: ROC 0, missing bumps = 4159, total number of good bumps = 4159
[14:13:40.984] <TB2> INFO: ROC 1, missing bumps = 4160, total number of good bumps = 4159
[14:13:40.986] <TB2> INFO: ROC 3, missing bumps = 4160, total number of good bumps = 4159
[14:13:40.988] <TB2> INFO: ROC 7, missing bumps = 4160, total number of good bumps = 4159
[14:13:40.990] <TB2> INFO: ROC 8, missing bumps = 4159, total number of good bumps = 4159
[14:13:40.992] <TB2> INFO: ROC 10, missing bumps = 4160, total number of good bumps = 4159
[14:13:40.993] <TB2> INFO: ROC 12, missing bumps = 4160, total number of good bumps = 4158
[14:13:40.995] <TB2> INFO: ROC 14, missing bumps = 3304, total number of good bumps = 4160
[14:13:40.997] <TB2> INFO: ROC 15, missing bumps = 4160, total number of good bumps = 4159
[14:13:40.997] <TB2> INFO: CalDel = 180 done
[14:13:41.275] <TB2> INFO: Expecting 416000 events.
[14:13:50.658] <TB2> INFO: 416000 events read in total (8827ms).
[14:13:50.660] <TB2> INFO: Test took 9658ms.
[14:13:50.931] <TB2> INFO: ROC 0, missing bumps = 4160, total number of good bumps = 4159
[14:13:50.932] <TB2> INFO: ROC 1, missing bumps = 4160, total number of good bumps = 4159
[14:13:50.934] <TB2> INFO: ROC 3, missing bumps = 4160, total number of good bumps = 4159
[14:13:50.936] <TB2> INFO: ROC 7, missing bumps = 4160, total number of good bumps = 4159
[14:13:50.938] <TB2> INFO: ROC 8, missing bumps = 4160, total number of good bumps = 4159
[14:13:50.939] <TB2> INFO: ROC 10, missing bumps = 4160, total number of good bumps = 4159
[14:13:50.941] <TB2> INFO: ROC 12, missing bumps = 4160, total number of good bumps = 4158
[14:13:50.943] <TB2> INFO: ROC 14, missing bumps = 4154, total number of good bumps = 4160
[14:13:50.945] <TB2> INFO: ROC 15, missing bumps = 4160, total number of good bumps = 4159
[14:13:50.945] <TB2> INFO: CalDel = 190 done
[14:13:51.215] <TB2> INFO: Expecting 416000 events.
[14:14:00.566] <TB2> INFO: 416000 events read in total (8795ms).
[14:14:00.569] <TB2> INFO: Test took 9620ms.
[14:14:00.840] <TB2> INFO: ROC 0, missing bumps = 4160, total number of good bumps = 4159
[14:14:00.848] <TB2> INFO: ROC 1, missing bumps = 4160, total number of good bumps = 4159
[14:14:00.849] <TB2> INFO: ROC 3, missing bumps = 4160, total number of good bumps = 4159
[14:14:00.851] <TB2> INFO: ROC 7, missing bumps = 4160, total number of good bumps = 4159
[14:14:00.852] <TB2> INFO: ROC 8, missing bumps = 4160, total number of good bumps = 4159
[14:14:00.854] <TB2> INFO: ROC 10, missing bumps = 4160, total number of good bumps = 4159
[14:14:00.855] <TB2> INFO: ROC 12, missing bumps = 4160, total number of good bumps = 4158
[14:14:00.857] <TB2> INFO: ROC 14, missing bumps = 4158, total number of good bumps = 4160
[14:14:00.858] <TB2> INFO: ROC 15, missing bumps = 4160, total number of good bumps = 4159
[14:14:00.858] <TB2> INFO: CalDel = 200 done
[14:14:00.858] <TB2> INFO: CalDel scan done
[14:14:00.860] <TB2> INFO: resulting values: CalDel = 130 110 110 120 120 120 130 130 120 120 110 130 120 130 150 120
[14:14:01.125] <TB2> INFO: Expecting 416000 events.
[14:14:16.702] <TB2> INFO: 416000 events read in total (15021ms).
[14:14:16.703] <TB2> INFO: Test took 15840ms.
[14:14:16.717] <TB2> INFO: maps.size() = 16
[14:14:16.919] <TB2> INFO: 9 broken Bump Bonds found
[14:14:16.922] <TB2> INFO: back to CtrlReg 0
[14:14:16.926] <TB2> INFO: PixTestBB4Map::doTest() done
[14:14:16.926] <TB2> INFO: Write out MinimumThreshold
[14:14:16.926] <TB2> INFO: Write out CalDel Scan_C0_V0
[14:14:16.926] <TB2> INFO: Write out PixelHit_C0_V0
[14:14:16.926] <TB2> INFO: Write out BB4MapBest_C0_V0
[14:14:16.926] <TB2> INFO: Write out NoiseScan_C0_V0
[14:14:16.926] <TB2> INFO: Write out BB4Map_C0_V14
[14:14:16.926] <TB2> INFO: Write out Hit Distr_C0_V0
[14:14:16.926] <TB2> INFO: Write out Hit Distr_C0_V0
[14:14:16.926] <TB2> INFO: Write out CalDel Scan_C1_V0
[14:14:16.926] <TB2> INFO: Write out PixelHit_C1_V0
[14:14:16.927] <TB2> INFO: Write out BB4MapBest_C1_V0
[14:14:16.927] <TB2> INFO: Write out NoiseScan_C1_V0
[14:14:16.927] <TB2> INFO: Write out BB4Map_C1_V14
[14:14:16.927] <TB2> INFO: Write out Hit Distr_C1_V0
[14:14:16.927] <TB2> INFO: Write out Hit Distr_C1_V0
[14:14:16.927] <TB2> INFO: Write out CalDel Scan_C2_V0
[14:14:16.927] <TB2> INFO: Write out PixelHit_C2_V0
[14:14:16.927] <TB2> INFO: Write out BB4MapBest_C2_V0
[14:14:16.927] <TB2> INFO: Write out NoiseScan_C2_V0
[14:14:16.927] <TB2> INFO: Write out BB4Map_C2_V14
[14:14:16.927] <TB2> INFO: Write out Hit Distr_C2_V0
[14:14:16.928] <TB2> INFO: Write out Hit Distr_C2_V0
[14:14:16.928] <TB2> INFO: Write out CalDel Scan_C3_V0
[14:14:16.928] <TB2> INFO: Write out PixelHit_C3_V0
[14:14:16.928] <TB2> INFO: Write out BB4MapBest_C3_V0
[14:14:16.928] <TB2> INFO: Write out NoiseScan_C3_V0
[14:14:16.928] <TB2> INFO: Write out BB4Map_C3_V14
[14:14:16.928] <TB2> INFO: Write out Hit Distr_C3_V0
[14:14:16.928] <TB2> INFO: Write out Hit Distr_C3_V0
[14:14:16.928] <TB2> INFO: Write out CalDel Scan_C4_V0
[14:14:16.928] <TB2> INFO: Write out PixelHit_C4_V0
[14:14:16.928] <TB2> INFO: Write out BB4MapBest_C4_V0
[14:14:16.928] <TB2> INFO: Write out NoiseScan_C4_V0
[14:14:16.928] <TB2> INFO: Write out BB4Map_C4_V14
[14:14:16.929] <TB2> INFO: Write out Hit Distr_C4_V0
[14:14:16.929] <TB2> INFO: Write out Hit Distr_C4_V0
[14:14:16.929] <TB2> INFO: Write out CalDel Scan_C5_V0
[14:14:16.929] <TB2> INFO: Write out PixelHit_C5_V0
[14:14:16.929] <TB2> INFO: Write out BB4MapBest_C5_V0
[14:14:16.929] <TB2> INFO: Write out NoiseScan_C5_V0
[14:14:16.929] <TB2> INFO: Write out BB4Map_C5_V14
[14:14:16.929] <TB2> INFO: Write out Hit Distr_C5_V0
[14:14:16.929] <TB2> INFO: Write out Hit Distr_C5_V0
[14:14:16.929] <TB2> INFO: Write out CalDel Scan_C6_V0
[14:14:16.929] <TB2> INFO: Write out PixelHit_C6_V0
[14:14:16.929] <TB2> INFO: Write out BB4MapBest_C6_V0
[14:14:16.930] <TB2> INFO: Write out NoiseScan_C6_V0
[14:14:16.930] <TB2> INFO: Write out BB4Map_C6_V14
[14:14:16.930] <TB2> INFO: Write out Hit Distr_C6_V0
[14:14:16.930] <TB2> INFO: Write out Hit Distr_C6_V0
[14:14:16.930] <TB2> INFO: Write out CalDel Scan_C7_V0
[14:14:16.930] <TB2> INFO: Write out PixelHit_C7_V0
[14:14:16.930] <TB2> INFO: Write out BB4MapBest_C7_V0
[14:14:16.930] <TB2> INFO: Write out NoiseScan_C7_V0
[14:14:16.930] <TB2> INFO: Write out BB4Map_C7_V14
[14:14:16.930] <TB2> INFO: Write out Hit Distr_C7_V0
[14:14:16.930] <TB2> INFO: Write out Hit Distr_C7_V0
[14:14:16.930] <TB2> INFO: Write out CalDel Scan_C8_V0
[14:14:16.930] <TB2> INFO: Write out PixelHit_C8_V0
[14:14:16.931] <TB2> INFO: Write out BB4MapBest_C8_V0
[14:14:16.931] <TB2> INFO: Write out NoiseScan_C8_V0
[14:14:16.931] <TB2> INFO: Write out BB4Map_C8_V14
[14:14:16.931] <TB2> INFO: Write out Hit Distr_C8_V0
[14:14:16.931] <TB2> INFO: Write out Hit Distr_C8_V0
[14:14:16.931] <TB2> INFO: Write out CalDel Scan_C9_V0
[14:14:16.931] <TB2> INFO: Write out PixelHit_C9_V0
[14:14:16.931] <TB2> INFO: Write out BB4MapBest_C9_V0
[14:14:16.931] <TB2> INFO: Write out NoiseScan_C9_V0
[14:14:16.931] <TB2> INFO: Write out BB4Map_C9_V14
[14:14:16.931] <TB2> INFO: Write out Hit Distr_C9_V0
[14:14:16.932] <TB2> INFO: Write out Hit Distr_C9_V0
[14:14:16.932] <TB2> INFO: Write out CalDel Scan_C10_V0
[14:14:16.932] <TB2> INFO: Write out PixelHit_C10_V0
[14:14:16.932] <TB2> INFO: Write out BB4MapBest_C10_V0
[14:14:16.932] <TB2> INFO: Write out NoiseScan_C10_V0
[14:14:16.932] <TB2> INFO: Write out BB4Map_C10_V14
[14:14:16.932] <TB2> INFO: Write out Hit Distr_C10_V0
[14:14:16.932] <TB2> INFO: Write out Hit Distr_C10_V0
[14:14:16.932] <TB2> INFO: Write out CalDel Scan_C11_V0
[14:14:16.932] <TB2> INFO: Write out PixelHit_C11_V0
[14:14:16.932] <TB2> INFO: Write out BB4MapBest_C11_V0
[14:14:16.932] <TB2> INFO: Write out NoiseScan_C11_V0
[14:14:16.933] <TB2> INFO: Write out BB4Map_C11_V14
[14:14:16.933] <TB2> INFO: Write out Hit Distr_C11_V0
[14:14:16.933] <TB2> INFO: Write out Hit Distr_C11_V0
[14:14:16.933] <TB2> INFO: Write out CalDel Scan_C12_V0
[14:14:16.933] <TB2> INFO: Write out PixelHit_C12_V0
[14:14:16.933] <TB2> INFO: Write out BB4MapBest_C12_V0
[14:14:16.933] <TB2> INFO: Write out NoiseScan_C12_V0
[14:14:16.933] <TB2> INFO: Write out BB4Map_C12_V14
[14:14:16.933] <TB2> INFO: Write out Hit Distr_C12_V0
[14:14:16.933] <TB2> INFO: Write out Hit Distr_C12_V0
[14:14:16.933] <TB2> INFO: Write out CalDel Scan_C13_V0
[14:14:16.933] <TB2> INFO: Write out PixelHit_C13_V0
[14:14:16.934] <TB2> INFO: Write out BB4MapBest_C13_V0
[14:14:16.934] <TB2> INFO: Write out NoiseScan_C13_V0
[14:14:16.934] <TB2> INFO: Write out BB4Map_C13_V14
[14:14:16.934] <TB2> INFO: Write out Hit Distr_C13_V0
[14:14:16.934] <TB2> INFO: Write out Hit Distr_C13_V0
[14:14:16.934] <TB2> INFO: Write out CalDel Scan_C14_V0
[14:14:16.934] <TB2> INFO: Write out PixelHit_C14_V0
[14:14:16.934] <TB2> INFO: Write out BB4MapBest_C14_V0
[14:14:16.934] <TB2> INFO: Write out NoiseScan_C14_V0
[14:14:16.934] <TB2> INFO: Write out BB4Map_C14_V14
[14:14:16.934] <TB2> INFO: Write out Hit Distr_C14_V0
[14:14:16.934] <TB2> INFO: Write out Hit Distr_C14_V0
[14:14:16.934] <TB2> INFO: Write out CalDel Scan_C15_V0
[14:14:16.935] <TB2> INFO: Write out PixelHit_C15_V0
[14:14:16.935] <TB2> INFO: Write out BB4MapBest_C15_V0
[14:14:16.935] <TB2> INFO: Write out NoiseScan_C15_V0
[14:14:16.935] <TB2> INFO: Write out BB4Map_C15_V14
[14:14:16.935] <TB2> INFO: Write out Hit Distr_C15_V0
[14:14:16.935] <TB2> INFO: Write out Hit Distr_C15_V0
[14:14:16.935] <TB2> INFO: enter test to run
[14:14:16.935] <TB2> INFO: test: no parameter change
[14:14:16.982] <TB2> QUIET: Connection to board 149 closed.
[14:14:17.062] <TB2> INFO: pXar: this is the end, my friend