[16:25:47.486] <TB1> INFO: *** Welcome to pxar ***
[16:25:47.486] <TB1> INFO: *** Today: 2016/07/22
[16:25:47.528] <TB1> INFO: *** Version: f5d3-dirty
[16:25:47.528] <TB1> INFO: readRocDacs: /home/Reception/DATA/KITAACHEN/M4682_Reception_2016-07-22_18h19m_1469204380//000_ReceptionTestBB4_p17//dacParameters_C0.dat .. /home/Reception/DATA/KITAACHEN/M4682_Reception_2016-07-22_18h19m_1469204380//000_ReceptionTestBB4_p17//dacParameters_C15.dat
[16:25:47.546] <TB1> INFO: readTbmDacs: /home/Reception/DATA/KITAACHEN/M4682_Reception_2016-07-22_18h19m_1469204380//000_ReceptionTestBB4_p17//tbmParameters_C0a.dat .. /home/Reception/DATA/KITAACHEN/M4682_Reception_2016-07-22_18h19m_1469204380//000_ReceptionTestBB4_p17//tbmParameters_C0b.dat
[16:25:47.550] <TB1> INFO: readMaskFile: /home/Reception/DATA/KITAACHEN/M4682_Reception_2016-07-22_18h19m_1469204380//000_ReceptionTestBB4_p17//defaultMaskFile.dat
[16:25:47.553] <TB1> INFO: readTrimFile: /home/Reception/DATA/KITAACHEN/M4682_Reception_2016-07-22_18h19m_1469204380//000_ReceptionTestBB4_p17//trimParameters_C0.dat .. /home/Reception/DATA/KITAACHEN/M4682_Reception_2016-07-22_18h19m_1469204380//000_ReceptionTestBB4_p17//trimParameters_C15.dat
[16:25:47.680] <TB1> INFO: clk: 4
[16:25:47.680] <TB1> INFO: ctr: 4
[16:25:47.680] <TB1> INFO: sda: 19
[16:25:47.680] <TB1> INFO: tin: 9
[16:25:47.680] <TB1> INFO: level: 15
[16:25:47.680] <TB1> INFO: triggerdelay: 0
[16:25:47.681] <TB1> QUIET: Instanciating API for pxar v2.7.5+40~g4fce89b
[16:25:47.681] <TB1> INFO: Log level: INFO
[16:25:47.689] <TB1> INFO: Found DTB DTB_WXC03A
[16:25:47.699] <TB1> QUIET: Connection to board DTB_WXC03A opened.
[16:25:47.701] <TB1> INFO: DTB startup information
--- DTB info------------------------------------------
Board id: 154
HW version: DTB1.2
FW version: 4.4
SW version: 4.6
Options:
USB id: DTB_WXC03A
MAC address: 40D85511809A
Hostname: pixelDTB154
Comment:
------------------------------------------------------
[16:25:47.703] <TB1> INFO: RPC call hashes of host and DTB match: 484264910
[16:25:49.211] <TB1> INFO: DUT info:
[16:25:49.211] <TB1> INFO: The DUT currently contains the following objects:
[16:25:49.211] <TB1> INFO: 2 TBM Cores tbm08c (2 ON)
[16:25:49.211] <TB1> INFO: TBM Core alpha (0): 7 registers set
[16:25:49.211] <TB1> INFO: TBM Core beta (1): 7 registers set
[16:25:49.211] <TB1> INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[16:25:49.211] <TB1> INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[16:25:49.211] <TB1> INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[16:25:49.211] <TB1> INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[16:25:49.211] <TB1> INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[16:25:49.211] <TB1> INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[16:25:49.211] <TB1> INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[16:25:49.211] <TB1> INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[16:25:49.211] <TB1> INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[16:25:49.211] <TB1> INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[16:25:49.211] <TB1> INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[16:25:49.211] <TB1> INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[16:25:49.211] <TB1> INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[16:25:49.211] <TB1> INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[16:25:49.211] <TB1> INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[16:25:49.211] <TB1> INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[16:25:49.211] <TB1> INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[16:25:49.702] <TB1> INFO: enter 'restricted' command line mode
[16:25:49.702] <TB1> INFO: enter test to run
[16:25:49.703] <TB1> INFO: test: pretest no parameter change
[16:25:49.703] <TB1> INFO: running: pretest
[16:25:49.709] <TB1> INFO: ----------------------------------------------------------------------
[16:25:49.709] <TB1> INFO: PixTestPretest::programROC()
[16:25:49.709] <TB1> INFO: ----------------------------------------------------------------------
[16:26:07.724] <TB1> INFO: PixTestPretest::programROC() done: ROCs are all programmable
[16:26:07.724] <TB1> INFO: IA differences per ROC: 20.9 17.7 19.3 19.3 20.1 17.7 20.1 20.1 19.3 18.5 20.1 18.5 20.1 19.3 20.1 18.5
[16:26:07.827] <TB1> INFO: enter test to run
[16:26:07.827] <TB1> INFO: test: pretest no parameter change
[16:26:07.827] <TB1> INFO: running: pretest
[16:26:07.828] <TB1> INFO: ----------------------------------------------------------------------
[16:26:07.828] <TB1> INFO: PixTestPretest::setVana() target Ia = 24 mA/ROC
[16:26:07.828] <TB1> INFO: ----------------------------------------------------------------------
[16:26:29.108] <TB1> INFO: PixTestPretest::setVana() done, Module Ia 395.5 mA = 24.7188 mA/ROC
[16:26:29.109] <TB1> INFO: i(loss) [mA/ROC]: 19.3 20.1 20.9 20.1 20.1 20.1 20.1 20.1 20.1 20.1 20.1 20.1 19.3 20.1 20.1 20.1
[16:26:29.145] <TB1> INFO: enter test to run
[16:26:29.145] <TB1> INFO: test: pretest no parameter change
[16:26:29.145] <TB1> INFO: running: pretest
[16:26:29.145] <TB1> INFO: ----------------------------------------------------------------------
[16:26:29.145] <TB1> INFO: PixTestPretest::findTiming()
[16:26:29.145] <TB1> INFO: ----------------------------------------------------------------------
[16:26:29.145] <TB1> INFO: PixTestCmd::init()
[16:26:29.806] <TB1> WARNING: Not unmasking DUT, not setting Calibrate bits!
[16:27:27.701] <TB1> INFO: TBM phases: 160MHz: 7, 400MHz: 2, TBM delays: ROC(0/1):5, header/trailer: 1, token: 1
[16:27:27.701] <TB1> INFO: (success/tries = 100/100), width = 4
[16:27:27.703] <TB1> INFO: enter test to run
[16:27:27.703] <TB1> INFO: test: pretest no parameter change
[16:27:27.703] <TB1> INFO: running: pretest
[16:27:27.705] <TB1> INFO: ----------------------------------------------------------------------
[16:27:27.705] <TB1> INFO: PixTestPretest::findWorkingPixel()
[16:27:27.705] <TB1> INFO: ----------------------------------------------------------------------
[16:27:27.795] <TB1> INFO: Expecting 231680 events.
[16:27:33.473] <TB1> WARNING: Detected DESER400 trailer error bits: "CODE ERROR"
[16:27:36.841] <TB1> INFO: 231680 events read in total (8490ms).
[16:27:36.845] <TB1> INFO: Test took 9137ms.
[16:27:37.133] <TB1> INFO: Found working pixel in all ROCs: col/row = 12/22
[16:27:37.203] <TB1> INFO: enter test to run
[16:27:37.203] <TB1> INFO: test: pretest no parameter change
[16:27:37.203] <TB1> INFO: running: pretest
[16:27:37.204] <TB1> INFO: ----------------------------------------------------------------------
[16:27:37.204] <TB1> INFO: PixTestPretest::setVthrCompCalDel()
[16:27:37.204] <TB1> INFO: ----------------------------------------------------------------------
[16:27:37.296] <TB1> INFO: Expecting 231680 events.
[16:27:46.313] <TB1> INFO: 231680 events read in total (8462ms).
[16:27:46.318] <TB1> INFO: Test took 9110ms.
[16:27:46.641] <TB1> INFO: PixTestPretest::setVthrCompCalDel() done
[16:27:46.641] <TB1> INFO: CalDel: 135 116 159 145 143 131 139 146 125 144 146 131 144 128 133 127
[16:27:46.641] <TB1> INFO: VthrComp: 51 51 51 51 51 51 52 51 51 51 51 51 51 51 51 51
[16:27:46.676] <TB1> INFO: enter test to run
[16:27:46.676] <TB1> INFO: test: pretest no parameter change
[16:27:46.676] <TB1> INFO: running: pretest
[16:27:46.683] <TB1> INFO: write dac parameters into /home/Reception/DATA/KITAACHEN/M4682_Reception_2016-07-22_18h19m_1469204380//000_ReceptionTestBB4_p17//dacParameters_C0.dat
[16:27:46.688] <TB1> INFO: write dac parameters into /home/Reception/DATA/KITAACHEN/M4682_Reception_2016-07-22_18h19m_1469204380//000_ReceptionTestBB4_p17//dacParameters_C1.dat
[16:27:46.693] <TB1> INFO: write dac parameters into /home/Reception/DATA/KITAACHEN/M4682_Reception_2016-07-22_18h19m_1469204380//000_ReceptionTestBB4_p17//dacParameters_C2.dat
[16:27:46.697] <TB1> INFO: write dac parameters into /home/Reception/DATA/KITAACHEN/M4682_Reception_2016-07-22_18h19m_1469204380//000_ReceptionTestBB4_p17//dacParameters_C3.dat
[16:27:46.702] <TB1> INFO: write dac parameters into /home/Reception/DATA/KITAACHEN/M4682_Reception_2016-07-22_18h19m_1469204380//000_ReceptionTestBB4_p17//dacParameters_C4.dat
[16:27:46.707] <TB1> INFO: write dac parameters into /home/Reception/DATA/KITAACHEN/M4682_Reception_2016-07-22_18h19m_1469204380//000_ReceptionTestBB4_p17//dacParameters_C5.dat
[16:27:46.713] <TB1> INFO: write dac parameters into /home/Reception/DATA/KITAACHEN/M4682_Reception_2016-07-22_18h19m_1469204380//000_ReceptionTestBB4_p17//dacParameters_C6.dat
[16:27:46.718] <TB1> INFO: write dac parameters into /home/Reception/DATA/KITAACHEN/M4682_Reception_2016-07-22_18h19m_1469204380//000_ReceptionTestBB4_p17//dacParameters_C7.dat
[16:27:46.723] <TB1> INFO: write dac parameters into /home/Reception/DATA/KITAACHEN/M4682_Reception_2016-07-22_18h19m_1469204380//000_ReceptionTestBB4_p17//dacParameters_C8.dat
[16:27:46.728] <TB1> INFO: write dac parameters into /home/Reception/DATA/KITAACHEN/M4682_Reception_2016-07-22_18h19m_1469204380//000_ReceptionTestBB4_p17//dacParameters_C9.dat
[16:27:46.732] <TB1> INFO: write dac parameters into /home/Reception/DATA/KITAACHEN/M4682_Reception_2016-07-22_18h19m_1469204380//000_ReceptionTestBB4_p17//dacParameters_C10.dat
[16:27:46.737] <TB1> INFO: write dac parameters into /home/Reception/DATA/KITAACHEN/M4682_Reception_2016-07-22_18h19m_1469204380//000_ReceptionTestBB4_p17//dacParameters_C11.dat
[16:27:46.743] <TB1> INFO: write dac parameters into /home/Reception/DATA/KITAACHEN/M4682_Reception_2016-07-22_18h19m_1469204380//000_ReceptionTestBB4_p17//dacParameters_C12.dat
[16:27:46.749] <TB1> INFO: write dac parameters into /home/Reception/DATA/KITAACHEN/M4682_Reception_2016-07-22_18h19m_1469204380//000_ReceptionTestBB4_p17//dacParameters_C13.dat
[16:27:46.757] <TB1> INFO: write dac parameters into /home/Reception/DATA/KITAACHEN/M4682_Reception_2016-07-22_18h19m_1469204380//000_ReceptionTestBB4_p17//dacParameters_C14.dat
[16:27:46.762] <TB1> INFO: write dac parameters into /home/Reception/DATA/KITAACHEN/M4682_Reception_2016-07-22_18h19m_1469204380//000_ReceptionTestBB4_p17//dacParameters_C15.dat
[16:27:46.766] <TB1> INFO: enter test to run
[16:27:46.766] <TB1> INFO: test: PixelAlive no parameter change
[16:27:46.767] <TB1> INFO: running: pixelalive
[16:27:46.769] <TB1> INFO: ######################################################################
[16:27:46.769] <TB1> INFO: PixTestAlive::doTest()
[16:27:46.769] <TB1> INFO: ######################################################################
[16:27:46.770] <TB1> INFO: ----------------------------------------------------------------------
[16:27:46.770] <TB1> INFO: PixTestAlive::aliveTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[16:27:46.770] <TB1> INFO: ----------------------------------------------------------------------
[16:27:47.054] <TB1> INFO: Expecting 41600 events.
[16:27:50.559] <TB1> INFO: 41600 events read in total (2949ms).
[16:27:50.560] <TB1> INFO: Test took 3789ms.
[16:27:50.850] <TB1> INFO: PixTestAlive::aliveTest() done
[16:27:50.850] <TB1> INFO: number of dead pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 3 0
[16:27:50.852] <TB1> INFO: ----------------------------------------------------------------------
[16:27:50.852] <TB1> INFO: PixTestAlive::maskTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[16:27:50.852] <TB1> INFO: ----------------------------------------------------------------------
[16:27:51.140] <TB1> INFO: Expecting 41600 events.
[16:27:53.776] <TB1> INFO: 41600 events read in total (2081ms).
[16:27:53.777] <TB1> INFO: Test took 2923ms.
[16:27:53.777] <TB1> INFO: mask vs. old pixelAlive PixelAlive_C0_V0 .. PixelAlive_C15_V0
[16:27:54.083] <TB1> INFO: PixTestAlive::maskTest() done
[16:27:54.084] <TB1> INFO: number of mask-defect pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[16:27:54.085] <TB1> INFO: ----------------------------------------------------------------------
[16:27:54.085] <TB1> INFO: PixTestAlive::addressDecodingTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[16:27:54.085] <TB1> INFO: ----------------------------------------------------------------------
[16:27:54.350] <TB1> INFO: Expecting 41600 events.
[16:27:57.778] <TB1> INFO: 41600 events read in total (2872ms).
[16:27:57.779] <TB1> INFO: Test took 3693ms.
[16:27:58.071] <TB1> INFO: PixTestAlive::addressDecodingTest() done
[16:27:58.071] <TB1> INFO: number of address-decoding pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[16:27:58.071] <TB1> INFO: PixTestAlive::doTest() done, duration: 11 seconds
[16:27:58.076] <TB1> INFO: enter test to run
[16:27:58.076] <TB1> INFO: test: BB no parameter change
[16:27:58.076] <TB1> INFO: running: bb
[16:27:58.077] <TB1> INFO: ######################################################################
[16:27:58.077] <TB1> INFO: PixTestBBMap::doTest() Ntrig = 5, VcalS = 250 (high range)
[16:27:58.077] <TB1> INFO: ######################################################################
[16:27:58.080] <TB1> INFO: ---> dac: VthrComp name: calSMap ntrig: 5 dacrange: 0 .. 149 (-1/-1) hits flags = 514 (plus default)
[16:27:58.091] <TB1> INFO: dacScan split into 1 runs with ntrig = 5
[16:27:58.091] <TB1> INFO: run 1 of 1
[16:27:58.364] <TB1> INFO: Expecting 3120000 events.
[16:28:42.783] <TB1> INFO: 1161935 events read in total (43863ms).
[16:29:26.302] <TB1> INFO: 2307575 events read in total (87382ms).
[16:29:56.750] <TB1> INFO: 3120000 events read in total (117830ms).
[16:29:56.794] <TB1> INFO: Test took 118702ms.
[16:30:22.161] <TB1> INFO: PixTestBBMap::doTest() done, duration: 144 seconds
[16:30:22.161] <TB1> INFO: number of dead bumps (per ROC): 0 0 2 2 0 2 0 0 0 1 0 1 0 0 0 0
[16:30:22.161] <TB1> INFO: separation cut (per ROC): 144 121 123 126 104 98 103 104 126 102 103 106 102 102 103 115
[16:30:22.232] <TB1> INFO: enter test to run
[16:30:22.232] <TB1> INFO: test: Readback no parameter change
[16:30:22.232] <TB1> INFO: running: readback
[16:30:22.232] <TB1> INFO: readReadbackCal: /home/Reception/DATA/KITAACHEN/M4682_Reception_2016-07-22_18h19m_1469204380//000_ReceptionTestBB4_p17//readbackCal_C0.dat .. /home/Reception/DATA/KITAACHEN/M4682_Reception_2016-07-22_18h19m_1469204380//000_ReceptionTestBB4_p17//readbackCal_C15.dat
[16:30:22.248] <TB1> INFO: readGainPedestalParameters /home/Reception/DATA/KITAACHEN/M4682_Reception_2016-07-22_18h19m_1469204380//000_ReceptionTestBB4_p17//phCalibrationFitErr_C0.dat .. /home/Reception/DATA/KITAACHEN/M4682_Reception_2016-07-22_18h19m_1469204380//000_ReceptionTestBB4_p17//phCalibrationFitErr_C15.dat
[16:30:22.249] <TB1> ERROR: <ConfigParameters.cc/readGainPedestalParameters:L996> cannot open /home/Reception/DATA/KITAACHEN/M4682_Reception_2016-07-22_18h19m_1469204380//000_ReceptionTestBB4_p17//phCalibrationFitErr_C0.dat for reading PH calibration constants
[16:30:22.249] <TB1> INFO: ######################################################################
[16:30:22.249] <TB1> INFO: PixTestReadback::doTest()
[16:30:22.249] <TB1> INFO: ######################################################################
[16:30:22.249] <TB1> INFO: ----------------------------------------------------------------------
[16:30:22.249] <TB1> INFO: PixTestReadback::CalibrateVd()
[16:30:22.249] <TB1> INFO: ----------------------------------------------------------------------
[16:30:31.477] <TB1> INFO: write readback calibration parameters into /home/Reception/DATA/KITAACHEN/M4682_Reception_2016-07-22_18h19m_1469204380//000_ReceptionTestBB4_p17//readbackCal_C0.dat
[16:30:31.487] <TB1> INFO: write readback calibration parameters into /home/Reception/DATA/KITAACHEN/M4682_Reception_2016-07-22_18h19m_1469204380//000_ReceptionTestBB4_p17//readbackCal_C1.dat
[16:30:31.492] <TB1> INFO: write readback calibration parameters into /home/Reception/DATA/KITAACHEN/M4682_Reception_2016-07-22_18h19m_1469204380//000_ReceptionTestBB4_p17//readbackCal_C2.dat
[16:30:31.497] <TB1> INFO: write readback calibration parameters into /home/Reception/DATA/KITAACHEN/M4682_Reception_2016-07-22_18h19m_1469204380//000_ReceptionTestBB4_p17//readbackCal_C3.dat
[16:30:31.503] <TB1> INFO: write readback calibration parameters into /home/Reception/DATA/KITAACHEN/M4682_Reception_2016-07-22_18h19m_1469204380//000_ReceptionTestBB4_p17//readbackCal_C4.dat
[16:30:31.510] <TB1> INFO: write readback calibration parameters into /home/Reception/DATA/KITAACHEN/M4682_Reception_2016-07-22_18h19m_1469204380//000_ReceptionTestBB4_p17//readbackCal_C5.dat
[16:30:31.516] <TB1> INFO: write readback calibration parameters into /home/Reception/DATA/KITAACHEN/M4682_Reception_2016-07-22_18h19m_1469204380//000_ReceptionTestBB4_p17//readbackCal_C6.dat
[16:30:31.523] <TB1> INFO: write readback calibration parameters into /home/Reception/DATA/KITAACHEN/M4682_Reception_2016-07-22_18h19m_1469204380//000_ReceptionTestBB4_p17//readbackCal_C7.dat
[16:30:31.530] <TB1> INFO: write readback calibration parameters into /home/Reception/DATA/KITAACHEN/M4682_Reception_2016-07-22_18h19m_1469204380//000_ReceptionTestBB4_p17//readbackCal_C8.dat
[16:30:31.538] <TB1> INFO: write readback calibration parameters into /home/Reception/DATA/KITAACHEN/M4682_Reception_2016-07-22_18h19m_1469204380//000_ReceptionTestBB4_p17//readbackCal_C9.dat
[16:30:31.545] <TB1> INFO: write readback calibration parameters into /home/Reception/DATA/KITAACHEN/M4682_Reception_2016-07-22_18h19m_1469204380//000_ReceptionTestBB4_p17//readbackCal_C10.dat
[16:30:31.554] <TB1> INFO: write readback calibration parameters into /home/Reception/DATA/KITAACHEN/M4682_Reception_2016-07-22_18h19m_1469204380//000_ReceptionTestBB4_p17//readbackCal_C11.dat
[16:30:31.561] <TB1> INFO: write readback calibration parameters into /home/Reception/DATA/KITAACHEN/M4682_Reception_2016-07-22_18h19m_1469204380//000_ReceptionTestBB4_p17//readbackCal_C12.dat
[16:30:31.569] <TB1> INFO: write readback calibration parameters into /home/Reception/DATA/KITAACHEN/M4682_Reception_2016-07-22_18h19m_1469204380//000_ReceptionTestBB4_p17//readbackCal_C13.dat
[16:30:31.576] <TB1> INFO: write readback calibration parameters into /home/Reception/DATA/KITAACHEN/M4682_Reception_2016-07-22_18h19m_1469204380//000_ReceptionTestBB4_p17//readbackCal_C14.dat
[16:30:31.584] <TB1> INFO: write readback calibration parameters into /home/Reception/DATA/KITAACHEN/M4682_Reception_2016-07-22_18h19m_1469204380//000_ReceptionTestBB4_p17//readbackCal_C15.dat
[16:30:31.615] <TB1> INFO: PixTestPattern:: pg_setup set to default.
[16:30:31.615] <TB1> INFO: ----------------------------------------------------------------------
[16:30:31.615] <TB1> INFO: PixTestReadback::CalibrateVa()
[16:30:31.615] <TB1> INFO: ----------------------------------------------------------------------
[16:30:40.897] <TB1> INFO: write readback calibration parameters into /home/Reception/DATA/KITAACHEN/M4682_Reception_2016-07-22_18h19m_1469204380//000_ReceptionTestBB4_p17//readbackCal_C0.dat
[16:30:40.903] <TB1> INFO: write readback calibration parameters into /home/Reception/DATA/KITAACHEN/M4682_Reception_2016-07-22_18h19m_1469204380//000_ReceptionTestBB4_p17//readbackCal_C1.dat
[16:30:40.909] <TB1> INFO: write readback calibration parameters into /home/Reception/DATA/KITAACHEN/M4682_Reception_2016-07-22_18h19m_1469204380//000_ReceptionTestBB4_p17//readbackCal_C2.dat
[16:30:40.915] <TB1> INFO: write readback calibration parameters into /home/Reception/DATA/KITAACHEN/M4682_Reception_2016-07-22_18h19m_1469204380//000_ReceptionTestBB4_p17//readbackCal_C3.dat
[16:30:40.920] <TB1> INFO: write readback calibration parameters into /home/Reception/DATA/KITAACHEN/M4682_Reception_2016-07-22_18h19m_1469204380//000_ReceptionTestBB4_p17//readbackCal_C4.dat
[16:30:40.928] <TB1> INFO: write readback calibration parameters into /home/Reception/DATA/KITAACHEN/M4682_Reception_2016-07-22_18h19m_1469204380//000_ReceptionTestBB4_p17//readbackCal_C5.dat
[16:30:40.935] <TB1> INFO: write readback calibration parameters into /home/Reception/DATA/KITAACHEN/M4682_Reception_2016-07-22_18h19m_1469204380//000_ReceptionTestBB4_p17//readbackCal_C6.dat
[16:30:40.941] <TB1> INFO: write readback calibration parameters into /home/Reception/DATA/KITAACHEN/M4682_Reception_2016-07-22_18h19m_1469204380//000_ReceptionTestBB4_p17//readbackCal_C7.dat
[16:30:40.946] <TB1> INFO: write readback calibration parameters into /home/Reception/DATA/KITAACHEN/M4682_Reception_2016-07-22_18h19m_1469204380//000_ReceptionTestBB4_p17//readbackCal_C8.dat
[16:30:40.952] <TB1> INFO: write readback calibration parameters into /home/Reception/DATA/KITAACHEN/M4682_Reception_2016-07-22_18h19m_1469204380//000_ReceptionTestBB4_p17//readbackCal_C9.dat
[16:30:40.957] <TB1> INFO: write readback calibration parameters into /home/Reception/DATA/KITAACHEN/M4682_Reception_2016-07-22_18h19m_1469204380//000_ReceptionTestBB4_p17//readbackCal_C10.dat
[16:30:40.964] <TB1> INFO: write readback calibration parameters into /home/Reception/DATA/KITAACHEN/M4682_Reception_2016-07-22_18h19m_1469204380//000_ReceptionTestBB4_p17//readbackCal_C11.dat
[16:30:40.971] <TB1> INFO: write readback calibration parameters into /home/Reception/DATA/KITAACHEN/M4682_Reception_2016-07-22_18h19m_1469204380//000_ReceptionTestBB4_p17//readbackCal_C12.dat
[16:30:40.978] <TB1> INFO: write readback calibration parameters into /home/Reception/DATA/KITAACHEN/M4682_Reception_2016-07-22_18h19m_1469204380//000_ReceptionTestBB4_p17//readbackCal_C13.dat
[16:30:40.986] <TB1> INFO: write readback calibration parameters into /home/Reception/DATA/KITAACHEN/M4682_Reception_2016-07-22_18h19m_1469204380//000_ReceptionTestBB4_p17//readbackCal_C14.dat
[16:30:40.991] <TB1> INFO: write readback calibration parameters into /home/Reception/DATA/KITAACHEN/M4682_Reception_2016-07-22_18h19m_1469204380//000_ReceptionTestBB4_p17//readbackCal_C15.dat
[16:30:41.030] <TB1> INFO: PixTestPattern:: pg_setup set to default.
[16:30:41.030] <TB1> INFO: ----------------------------------------------------------------------
[16:30:41.030] <TB1> INFO: PixTestReadback::readbackVbg()
[16:30:41.030] <TB1> INFO: ----------------------------------------------------------------------
[16:30:48.150] <TB1> INFO: PixTestPattern:: pg_setup set to default.
[16:30:48.151] <TB1> INFO: ----------------------------------------------------------------------
[16:30:48.151] <TB1> INFO: PixTestReadback::getCalibratedVbg()
[16:30:48.151] <TB1> INFO: ----------------------------------------------------------------------
[16:30:48.151] <TB1> INFO: Vbg will be calibrated using Vd calibration
[16:30:48.151] <TB1> INFO: /*/*/*/*::: ROC 0: uncalibrated Vbg = 156.6calibrated Vbg = 1.22902 :::*/*/*/*/
[16:30:48.151] <TB1> INFO: /*/*/*/*::: ROC 1: uncalibrated Vbg = 160calibrated Vbg = 1.23107 :::*/*/*/*/
[16:30:48.151] <TB1> INFO: /*/*/*/*::: ROC 2: uncalibrated Vbg = 151.1calibrated Vbg = 1.24108 :::*/*/*/*/
[16:30:48.151] <TB1> INFO: /*/*/*/*::: ROC 3: uncalibrated Vbg = 160.2calibrated Vbg = 1.24016 :::*/*/*/*/
[16:30:48.151] <TB1> INFO: /*/*/*/*::: ROC 4: uncalibrated Vbg = 157.2calibrated Vbg = 1.2447 :::*/*/*/*/
[16:30:48.151] <TB1> INFO: /*/*/*/*::: ROC 5: uncalibrated Vbg = 155.8calibrated Vbg = 1.24367 :::*/*/*/*/
[16:30:48.151] <TB1> INFO: /*/*/*/*::: ROC 6: uncalibrated Vbg = 158.2calibrated Vbg = 1.24836 :::*/*/*/*/
[16:30:48.151] <TB1> INFO: /*/*/*/*::: ROC 7: uncalibrated Vbg = 147.5calibrated Vbg = 1.24281 :::*/*/*/*/
[16:30:48.151] <TB1> INFO: /*/*/*/*::: ROC 8: uncalibrated Vbg = 149.4calibrated Vbg = 1.24552 :::*/*/*/*/
[16:30:48.151] <TB1> INFO: /*/*/*/*::: ROC 9: uncalibrated Vbg = 153.2calibrated Vbg = 1.24563 :::*/*/*/*/
[16:30:48.151] <TB1> INFO: /*/*/*/*::: ROC 10: uncalibrated Vbg = 145.9calibrated Vbg = 1.23689 :::*/*/*/*/
[16:30:48.151] <TB1> INFO: /*/*/*/*::: ROC 11: uncalibrated Vbg = 154.2calibrated Vbg = 1.23416 :::*/*/*/*/
[16:30:48.151] <TB1> INFO: /*/*/*/*::: ROC 12: uncalibrated Vbg = 154.7calibrated Vbg = 1.23541 :::*/*/*/*/
[16:30:48.151] <TB1> INFO: /*/*/*/*::: ROC 13: uncalibrated Vbg = 160.9calibrated Vbg = 1.22809 :::*/*/*/*/
[16:30:48.151] <TB1> INFO: /*/*/*/*::: ROC 14: uncalibrated Vbg = 151calibrated Vbg = 1.22663 :::*/*/*/*/
[16:30:48.151] <TB1> INFO: /*/*/*/*::: ROC 15: uncalibrated Vbg = 147.7calibrated Vbg = 1.2329 :::*/*/*/*/
[16:30:48.155] <TB1> INFO: ----------------------------------------------------------------------
[16:30:48.155] <TB1> INFO: PixTestReadback::CalibrateIa()
[16:30:48.155] <TB1> INFO: ----------------------------------------------------------------------
[16:33:20.900] <TB1> INFO: write readback calibration parameters into /home/Reception/DATA/KITAACHEN/M4682_Reception_2016-07-22_18h19m_1469204380//000_ReceptionTestBB4_p17//readbackCal_C0.dat
[16:33:20.906] <TB1> INFO: write readback calibration parameters into /home/Reception/DATA/KITAACHEN/M4682_Reception_2016-07-22_18h19m_1469204380//000_ReceptionTestBB4_p17//readbackCal_C1.dat
[16:33:20.911] <TB1> INFO: write readback calibration parameters into /home/Reception/DATA/KITAACHEN/M4682_Reception_2016-07-22_18h19m_1469204380//000_ReceptionTestBB4_p17//readbackCal_C2.dat
[16:33:20.917] <TB1> INFO: write readback calibration parameters into /home/Reception/DATA/KITAACHEN/M4682_Reception_2016-07-22_18h19m_1469204380//000_ReceptionTestBB4_p17//readbackCal_C3.dat
[16:33:20.923] <TB1> INFO: write readback calibration parameters into /home/Reception/DATA/KITAACHEN/M4682_Reception_2016-07-22_18h19m_1469204380//000_ReceptionTestBB4_p17//readbackCal_C4.dat
[16:33:20.930] <TB1> INFO: write readback calibration parameters into /home/Reception/DATA/KITAACHEN/M4682_Reception_2016-07-22_18h19m_1469204380//000_ReceptionTestBB4_p17//readbackCal_C5.dat
[16:33:20.935] <TB1> INFO: write readback calibration parameters into /home/Reception/DATA/KITAACHEN/M4682_Reception_2016-07-22_18h19m_1469204380//000_ReceptionTestBB4_p17//readbackCal_C6.dat
[16:33:20.940] <TB1> INFO: write readback calibration parameters into /home/Reception/DATA/KITAACHEN/M4682_Reception_2016-07-22_18h19m_1469204380//000_ReceptionTestBB4_p17//readbackCal_C7.dat
[16:33:20.945] <TB1> INFO: write readback calibration parameters into /home/Reception/DATA/KITAACHEN/M4682_Reception_2016-07-22_18h19m_1469204380//000_ReceptionTestBB4_p17//readbackCal_C8.dat
[16:33:20.950] <TB1> INFO: write readback calibration parameters into /home/Reception/DATA/KITAACHEN/M4682_Reception_2016-07-22_18h19m_1469204380//000_ReceptionTestBB4_p17//readbackCal_C9.dat
[16:33:20.956] <TB1> INFO: write readback calibration parameters into /home/Reception/DATA/KITAACHEN/M4682_Reception_2016-07-22_18h19m_1469204380//000_ReceptionTestBB4_p17//readbackCal_C10.dat
[16:33:20.962] <TB1> INFO: write readback calibration parameters into /home/Reception/DATA/KITAACHEN/M4682_Reception_2016-07-22_18h19m_1469204380//000_ReceptionTestBB4_p17//readbackCal_C11.dat
[16:33:20.968] <TB1> INFO: write readback calibration parameters into /home/Reception/DATA/KITAACHEN/M4682_Reception_2016-07-22_18h19m_1469204380//000_ReceptionTestBB4_p17//readbackCal_C12.dat
[16:33:20.974] <TB1> INFO: write readback calibration parameters into /home/Reception/DATA/KITAACHEN/M4682_Reception_2016-07-22_18h19m_1469204380//000_ReceptionTestBB4_p17//readbackCal_C13.dat
[16:33:20.980] <TB1> INFO: write readback calibration parameters into /home/Reception/DATA/KITAACHEN/M4682_Reception_2016-07-22_18h19m_1469204380//000_ReceptionTestBB4_p17//readbackCal_C14.dat
[16:33:20.985] <TB1> INFO: write readback calibration parameters into /home/Reception/DATA/KITAACHEN/M4682_Reception_2016-07-22_18h19m_1469204380//000_ReceptionTestBB4_p17//readbackCal_C15.dat
[16:33:21.013] <TB1> INFO: PixTestPattern:: pg_setup set to default.
[16:33:21.014] <TB1> INFO: PixTestReadback::doTest() done
[16:33:21.033] <TB1> INFO: enter test to run
[16:33:21.033] <TB1> INFO: test: BB4 no parameter change
[16:33:21.033] <TB1> INFO: running: bb4
[16:33:21.033] <TB1> INFO: PixTestBB4Map::doTest() ntrig = 100
[16:33:21.033] <TB1> INFO: VthrComp (min,max,step) = 70 130 5
[16:33:21.033] <TB1> INFO: CalDel (min,max,step) = 80 200 10
[16:33:21.033] <TB1> INFO: Vcal = 255
[16:33:21.033] <TB1> INFO: Cut = 0.5
[16:33:21.033] <TB1> INFO: flag 2
[16:33:21.035] <TB1> INFO: CtrlReg 4 (large Vcal)
[16:33:21.035] <TB1> INFO: saving VthrComp and CalDel before running tests)
[16:33:21.035] <TB1> INFO: 0 130 0
[16:33:21.035] <TB1> INFO: 1 130 0
[16:33:21.035] <TB1> INFO: 2 130 0
[16:33:21.035] <TB1> INFO: 3 130 0
[16:33:21.035] <TB1> INFO: 4 130 0
[16:33:21.035] <TB1> INFO: 5 130 0
[16:33:21.035] <TB1> INFO: 6 130 0
[16:33:21.035] <TB1> INFO: 7 130 0
[16:33:21.035] <TB1> INFO: 8 130 0
[16:33:21.035] <TB1> INFO: 9 130 0
[16:33:21.035] <TB1> INFO: 10 130 0
[16:33:21.035] <TB1> INFO: 11 130 0
[16:33:21.035] <TB1> INFO: 12 130 0
[16:33:21.035] <TB1> INFO: 13 130 0
[16:33:21.035] <TB1> INFO: 14 130 0
[16:33:21.035] <TB1> INFO: 15 130 0
[16:33:21.311] <TB1> INFO: Expecting 416000 events.
[16:33:25.961] <TB1> ERROR: <datapipe.cc/CheckEventValidity:L507> Channel 0 Number of ROCs (0) != Token Chain Length (8)
[16:33:25.988] <TB1> ERROR: <datapipe.cc/CheckEventID:L469> Channel 0 Event ID mismatch: local ID (16) != TBM ID (2)
[16:33:30.710] <TB1> INFO: 416000 events read in total (8843ms).
[16:33:30.711] <TB1> INFO: Test took 9673ms.
[16:33:30.711] <TB1> INFO: starting loop over VthrComp
[16:33:31.256] <TB1> INFO: Expecting 416000 events.
[16:33:40.560] <TB1> INFO: 416000 events read in total (8748ms).
[16:33:40.560] <TB1> INFO: Test took 9577ms.
[16:33:40.560] <TB1> INFO: got noisemap
[16:33:40.833] <TB1> INFO: ROC 0, integral 0
[16:33:40.834] <TB1> INFO: ROC 1, integral 0
[16:33:40.834] <TB1> INFO: ROC 2, integral 0
[16:33:40.834] <TB1> INFO: ROC 3, integral 0
[16:33:40.834] <TB1> INFO: ROC 4, integral 0
[16:33:40.834] <TB1> INFO: ROC 5, integral 0
[16:33:40.834] <TB1> INFO: ROC 6, integral 0
[16:33:40.834] <TB1> INFO: ROC 7, integral 0
[16:33:40.834] <TB1> INFO: ROC 8, integral 0
[16:33:40.834] <TB1> INFO: ROC 9, integral 0
[16:33:40.834] <TB1> INFO: ROC 10, integral 0
[16:33:40.834] <TB1> INFO: ROC 11, integral 0
[16:33:40.834] <TB1> INFO: ROC 12, integral 0
[16:33:40.834] <TB1> INFO: ROC 13, integral 0
[16:33:40.834] <TB1> INFO: ROC 14, integral 0
[16:33:40.834] <TB1> INFO: ROC 15, integral 0
[16:33:40.834] <TB1> INFO: VthrComp = 70 done
[16:33:41.102] <TB1> INFO: Expecting 416000 events.
[16:33:50.445] <TB1> INFO: 416000 events read in total (8787ms).
[16:33:50.446] <TB1> INFO: Test took 9609ms.
[16:33:50.446] <TB1> INFO: got noisemap
[16:33:50.722] <TB1> INFO: ROC 0, integral 0
[16:33:50.722] <TB1> INFO: ROC 1, integral 0
[16:33:50.722] <TB1> INFO: ROC 2, integral 0
[16:33:50.722] <TB1> INFO: ROC 3, integral 0
[16:33:50.722] <TB1> INFO: ROC 4, integral 0
[16:33:50.722] <TB1> INFO: ROC 5, integral 0
[16:33:50.722] <TB1> INFO: ROC 6, integral 0
[16:33:50.722] <TB1> INFO: ROC 7, integral 0
[16:33:50.722] <TB1> INFO: ROC 8, integral 0
[16:33:50.722] <TB1> INFO: ROC 9, integral 0
[16:33:50.722] <TB1> INFO: ROC 10, integral 0
[16:33:50.722] <TB1> INFO: ROC 11, integral 0
[16:33:50.722] <TB1> INFO: ROC 12, integral 0
[16:33:50.723] <TB1> INFO: ROC 13, integral 0
[16:33:50.723] <TB1> INFO: ROC 14, integral 0
[16:33:50.723] <TB1> INFO: ROC 15, integral 0
[16:33:50.723] <TB1> INFO: VthrComp = 75 done
[16:33:51.018] <TB1> INFO: Expecting 416000 events.
[16:34:00.416] <TB1> INFO: 416000 events read in total (8843ms).
[16:34:00.416] <TB1> INFO: Test took 9690ms.
[16:34:00.417] <TB1> INFO: got noisemap
[16:34:00.689] <TB1> INFO: ROC 0, integral 0
[16:34:00.689] <TB1> INFO: ROC 1, integral 0
[16:34:00.689] <TB1> INFO: ROC 2, integral 0
[16:34:00.689] <TB1> INFO: ROC 3, integral 0
[16:34:00.689] <TB1> INFO: ROC 4, integral 0
[16:34:00.689] <TB1> INFO: ROC 5, integral 0
[16:34:00.689] <TB1> INFO: ROC 6, integral 0
[16:34:00.689] <TB1> INFO: ROC 7, integral 0
[16:34:00.689] <TB1> INFO: ROC 8, integral 0
[16:34:00.689] <TB1> INFO: ROC 9, integral 0
[16:34:00.689] <TB1> INFO: ROC 10, integral 0
[16:34:00.689] <TB1> INFO: ROC 11, integral 0
[16:34:00.690] <TB1> INFO: ROC 12, integral 0
[16:34:00.690] <TB1> INFO: ROC 13, integral 0
[16:34:00.690] <TB1> INFO: ROC 14, integral 0
[16:34:00.690] <TB1> INFO: ROC 15, integral 0
[16:34:00.690] <TB1> INFO: VthrComp = 80 done
[16:34:00.957] <TB1> INFO: Expecting 416000 events.
[16:34:10.388] <TB1> INFO: 416000 events read in total (8875ms).
[16:34:10.389] <TB1> INFO: Test took 9695ms.
[16:34:10.389] <TB1> INFO: got noisemap
[16:34:10.660] <TB1> INFO: ROC 0, integral 0
[16:34:10.660] <TB1> INFO: ROC 1, integral 0
[16:34:10.660] <TB1> INFO: ROC 2, integral 0
[16:34:10.660] <TB1> INFO: ROC 3, integral 0
[16:34:10.660] <TB1> INFO: ROC 4, integral 0
[16:34:10.660] <TB1> INFO: ROC 5, integral 0
[16:34:10.660] <TB1> INFO: ROC 6, integral 0
[16:34:10.660] <TB1> INFO: ROC 7, integral 0
[16:34:10.661] <TB1> INFO: ROC 8, integral 0
[16:34:10.661] <TB1> INFO: ROC 9, integral 0
[16:34:10.661] <TB1> INFO: ROC 10, integral 0
[16:34:10.661] <TB1> INFO: ROC 11, integral 0
[16:34:10.661] <TB1> INFO: ROC 12, integral 0
[16:34:10.661] <TB1> INFO: ROC 13, integral 0
[16:34:10.661] <TB1> INFO: ROC 14, integral 0
[16:34:10.661] <TB1> INFO: ROC 15, integral 0
[16:34:10.661] <TB1> INFO: VthrComp = 85 done
[16:34:10.966] <TB1> INFO: Expecting 416000 events.
[16:34:20.330] <TB1> INFO: 416000 events read in total (8808ms).
[16:34:20.330] <TB1> INFO: Test took 9665ms.
[16:34:20.330] <TB1> INFO: got noisemap
[16:34:20.606] <TB1> INFO: ROC 0, integral 0
[16:34:20.606] <TB1> INFO: ROC 1, integral 0
[16:34:20.606] <TB1> INFO: ROC 2, integral 0
[16:34:20.606] <TB1> INFO: ROC 3, integral 0
[16:34:20.606] <TB1> INFO: ROC 4, integral 0
[16:34:20.606] <TB1> INFO: ROC 5, integral 0
[16:34:20.606] <TB1> INFO: ROC 6, integral 0
[16:34:20.606] <TB1> INFO: ROC 7, integral 0
[16:34:20.606] <TB1> INFO: ROC 8, integral 0
[16:34:20.607] <TB1> INFO: ROC 9, integral 0
[16:34:20.607] <TB1> INFO: ROC 10, integral 0
[16:34:20.607] <TB1> INFO: ROC 11, integral 0
[16:34:20.607] <TB1> INFO: ROC 12, integral 0
[16:34:20.607] <TB1> INFO: ROC 13, integral 0
[16:34:20.607] <TB1> INFO: ROC 14, integral 0
[16:34:20.607] <TB1> INFO: ROC 15, integral 0
[16:34:20.607] <TB1> INFO: VthrComp = 90 done
[16:34:20.901] <TB1> INFO: Expecting 416000 events.
[16:34:30.273] <TB1> INFO: 416000 events read in total (8816ms).
[16:34:30.274] <TB1> INFO: Test took 9664ms.
[16:34:30.274] <TB1> INFO: got noisemap
[16:34:30.548] <TB1> INFO: ROC 0, integral 0
[16:34:30.548] <TB1> INFO: ROC 1, integral 0
[16:34:30.548] <TB1> INFO: ROC 2, integral 0
[16:34:30.548] <TB1> INFO: ROC 3, integral 0
[16:34:30.548] <TB1> INFO: ROC 4, integral 0
[16:34:30.548] <TB1> INFO: ROC 5, integral 0
[16:34:30.548] <TB1> INFO: ROC 6, integral 0
[16:34:30.548] <TB1> INFO: ROC 7, integral 0
[16:34:30.548] <TB1> INFO: ROC 8, integral 0
[16:34:30.548] <TB1> INFO: ROC 9, integral 0
[16:34:30.548] <TB1> INFO: ROC 10, integral 0
[16:34:30.548] <TB1> INFO: ROC 11, integral 0
[16:34:30.548] <TB1> INFO: ROC 12, integral 0
[16:34:30.548] <TB1> INFO: ROC 13, integral 0
[16:34:30.548] <TB1> INFO: ROC 14, integral 0
[16:34:30.548] <TB1> INFO: ROC 15, integral 0
[16:34:30.548] <TB1> INFO: VthrComp = 95 done
[16:34:30.833] <TB1> INFO: Expecting 416000 events.
[16:34:40.227] <TB1> INFO: 416000 events read in total (8839ms).
[16:34:40.227] <TB1> INFO: Test took 9676ms.
[16:34:40.227] <TB1> INFO: got noisemap
[16:34:40.502] <TB1> INFO: ROC 0, integral 0
[16:34:40.502] <TB1> INFO: ROC 1, integral 0
[16:34:40.502] <TB1> INFO: ROC 2, integral 0
[16:34:40.502] <TB1> INFO: ROC 3, integral 0
[16:34:40.502] <TB1> INFO: ROC 4, integral 0
[16:34:40.502] <TB1> INFO: ROC 5, integral 0
[16:34:40.502] <TB1> INFO: ROC 6, integral 0
[16:34:40.502] <TB1> INFO: ROC 7, integral 0
[16:34:40.502] <TB1> INFO: ROC 8, integral 0
[16:34:40.502] <TB1> INFO: ROC 9, integral 0
[16:34:40.502] <TB1> INFO: ROC 10, integral 1
[16:34:40.502] <TB1> INFO: ROC 11, integral 0
[16:34:40.503] <TB1> INFO: ROC 12, integral 0
[16:34:40.503] <TB1> INFO: ROC 13, integral 0
[16:34:40.503] <TB1> INFO: ROC 14, integral 0
[16:34:40.503] <TB1> INFO: ROC 15, integral 0
[16:34:40.503] <TB1> INFO: VthrComp = 100 done
[16:34:40.766] <TB1> INFO: Expecting 416000 events.
[16:34:50.088] <TB1> INFO: 416000 events read in total (8767ms).
[16:34:50.089] <TB1> INFO: Test took 9583ms.
[16:34:50.089] <TB1> INFO: got noisemap
[16:34:50.363] <TB1> INFO: ROC 0, integral 0
[16:34:50.363] <TB1> INFO: ROC 1, integral 0
[16:34:50.363] <TB1> INFO: ROC 2, integral 0
[16:34:50.363] <TB1> INFO: ROC 3, integral 0
[16:34:50.364] <TB1> INFO: ROC 4, integral 0
[16:34:50.364] <TB1> INFO: ROC 5, integral 0
[16:34:50.364] <TB1> INFO: ROC 6, integral 0
[16:34:50.364] <TB1> INFO: ROC 7, integral 0
[16:34:50.364] <TB1> INFO: ROC 8, integral 0
[16:34:50.364] <TB1> INFO: ROC 9, integral 0
[16:34:50.364] <TB1> INFO: ROC 10, integral 1
[16:34:50.364] <TB1> INFO: ROC 11, integral 0
[16:34:50.364] <TB1> INFO: ROC 12, integral 0
[16:34:50.364] <TB1> INFO: ROC 13, integral 0
[16:34:50.364] <TB1> INFO: ROC 14, integral 0
[16:34:50.364] <TB1> INFO: ROC 15, integral 0
[16:34:50.364] <TB1> INFO: VthrComp = 105 done
[16:34:50.676] <TB1> INFO: Expecting 416000 events.
[16:35:00.078] <TB1> INFO: 416000 events read in total (8846ms).
[16:35:00.078] <TB1> INFO: Test took 9710ms.
[16:35:00.078] <TB1> INFO: got noisemap
[16:35:00.353] <TB1> INFO: ROC 0, integral 0
[16:35:00.353] <TB1> INFO: ROC 1, integral 0
[16:35:00.353] <TB1> INFO: ROC 2, integral 0
[16:35:00.353] <TB1> INFO: ROC 3, integral 0
[16:35:00.353] <TB1> INFO: ROC 4, integral 0
[16:35:00.353] <TB1> INFO: ROC 5, integral 4
[16:35:00.353] <TB1> INFO: ROC 6, integral 0
[16:35:00.353] <TB1> INFO: ROC 7, integral 0
[16:35:00.353] <TB1> INFO: ROC 8, integral 0
[16:35:00.353] <TB1> INFO: ROC 9, integral 26
[16:35:00.353] <TB1> INFO: ROC #9: threshold found
[16:35:00.354] <TB1> INFO: ROC 10, integral 75
[16:35:00.354] <TB1> INFO: ROC #10: threshold found
[16:35:00.354] <TB1> INFO: ROC 11, integral 0
[16:35:00.354] <TB1> INFO: ROC 12, integral 0
[16:35:00.354] <TB1> INFO: ROC 13, integral 0
[16:35:00.354] <TB1> INFO: ROC 14, integral 0
[16:35:00.354] <TB1> INFO: ROC 15, integral 0
[16:35:00.354] <TB1> INFO: VthrComp = 110 done
[16:35:00.619] <TB1> INFO: Expecting 416000 events.
[16:35:10.025] <TB1> INFO: 416000 events read in total (8850ms).
[16:35:10.027] <TB1> INFO: Test took 9670ms.
[16:35:10.027] <TB1> INFO: got noisemap
[16:35:10.292] <TB1> INFO: ROC 0, integral 12
[16:35:10.292] <TB1> INFO: ROC #0: threshold found
[16:35:10.292] <TB1> INFO: ROC 1, integral 0
[16:35:10.292] <TB1> INFO: ROC 2, integral 2
[16:35:10.293] <TB1> INFO: ROC 3, integral 0
[16:35:10.293] <TB1> INFO: ROC 4, integral 7
[16:35:10.293] <TB1> INFO: ROC 5, integral 56
[16:35:10.293] <TB1> INFO: ROC #5: threshold found
[16:35:10.293] <TB1> INFO: ROC 6, integral 10
[16:35:10.293] <TB1> INFO: ROC #6: threshold found
[16:35:10.293] <TB1> INFO: ROC 7, integral 3
[16:35:10.293] <TB1> INFO: ROC 8, integral 0
[16:35:10.293] <TB1> INFO: ROC 11, integral 7
[16:35:10.293] <TB1> INFO: ROC 12, integral 5
[16:35:10.293] <TB1> INFO: ROC 13, integral 2
[16:35:10.293] <TB1> INFO: ROC 14, integral 1
[16:35:10.293] <TB1> INFO: ROC 15, integral 0
[16:35:10.293] <TB1> INFO: VthrComp = 115 done
[16:35:10.568] <TB1> INFO: Expecting 416000 events.
[16:35:19.938] <TB1> INFO: 416000 events read in total (8815ms).
[16:35:19.939] <TB1> INFO: Test took 9643ms.
[16:35:19.940] <TB1> INFO: got noisemap
[16:35:20.212] <TB1> INFO: ROC 1, integral 0
[16:35:20.212] <TB1> INFO: ROC 2, integral 17
[16:35:20.212] <TB1> INFO: ROC #2: threshold found
[16:35:20.212] <TB1> INFO: ROC 3, integral 1
[16:35:20.212] <TB1> INFO: ROC 4, integral 34
[16:35:20.212] <TB1> INFO: ROC #4: threshold found
[16:35:20.212] <TB1> INFO: ROC 7, integral 9
[16:35:20.212] <TB1> INFO: ROC 8, integral 5
[16:35:20.213] <TB1> INFO: ROC 11, integral 18
[16:35:20.213] <TB1> INFO: ROC #11: threshold found
[16:35:20.213] <TB1> INFO: ROC 12, integral 23
[16:35:20.213] <TB1> INFO: ROC #12: threshold found
[16:35:20.213] <TB1> INFO: ROC 13, integral 15
[16:35:20.213] <TB1> INFO: ROC #13: threshold found
[16:35:20.213] <TB1> INFO: ROC 14, integral 22
[16:35:20.213] <TB1> INFO: ROC #14: threshold found
[16:35:20.213] <TB1> INFO: ROC 15, integral 9
[16:35:20.213] <TB1> INFO: VthrComp = 120 done
[16:35:20.491] <TB1> INFO: Expecting 416000 events.
[16:35:29.893] <TB1> INFO: 416000 events read in total (8847ms).
[16:35:29.894] <TB1> INFO: Test took 9678ms.
[16:35:29.895] <TB1> INFO: got noisemap
[16:35:30.166] <TB1> INFO: ROC 1, integral 1
[16:35:30.166] <TB1> INFO: ROC 3, integral 24
[16:35:30.166] <TB1> INFO: ROC #3: threshold found
[16:35:30.166] <TB1> INFO: ROC 7, integral 93
[16:35:30.166] <TB1> INFO: ROC #7: threshold found
[16:35:30.166] <TB1> INFO: ROC 8, integral 86
[16:35:30.166] <TB1> INFO: ROC #8: threshold found
[16:35:30.166] <TB1> INFO: ROC 15, integral 90
[16:35:30.166] <TB1> INFO: ROC #15: threshold found
[16:35:30.166] <TB1> INFO: VthrComp = 125 done
[16:35:30.436] <TB1> INFO: Expecting 416000 events.
[16:35:39.892] <TB1> INFO: 416000 events read in total (8900ms).
[16:35:39.893] <TB1> INFO: Test took 9724ms.
[16:35:39.895] <TB1> INFO: got noisemap
[16:35:40.164] <TB1> INFO: ROC 1, integral 6
[16:35:40.164] <TB1> INFO: VthrComp = 130 done
[16:35:40.165] <TB1> INFO: resulting noise thresholds per ROC = 110 130 115 120 115 110 110 120 120 105 105 115 115 115 115 120
[16:35:40.165] <TB1> INFO: threshold scan done
[16:35:40.434] <TB1> INFO: Expecting 416000 events.
[16:35:49.790] <TB1> INFO: 416000 events read in total (8801ms).
[16:35:49.791] <TB1> INFO: Test took 9620ms.
[16:35:50.064] <TB1> INFO: ROC 0, missing bumps = 4160, total number of good bumps = 0
[16:35:50.065] <TB1> INFO: ROC 1, missing bumps = 4150, total number of good bumps = 10
[16:35:50.067] <TB1> INFO: ROC 2, missing bumps = 4160, total number of good bumps = 0
[16:35:50.069] <TB1> INFO: ROC 3, missing bumps = 4160, total number of good bumps = 0
[16:35:50.070] <TB1> INFO: ROC 4, missing bumps = 4160, total number of good bumps = 0
[16:35:50.071] <TB1> INFO: ROC 5, missing bumps = 4160, total number of good bumps = 0
[16:35:50.072] <TB1> INFO: ROC 6, missing bumps = 4160, total number of good bumps = 0
[16:35:50.074] <TB1> INFO: ROC 7, missing bumps = 4160, total number of good bumps = 0
[16:35:50.075] <TB1> INFO: ROC 8, missing bumps = 4159, total number of good bumps = 1
[16:35:50.076] <TB1> INFO: ROC 9, missing bumps = 4160, total number of good bumps = 0
[16:35:50.077] <TB1> INFO: ROC 10, missing bumps = 4160, total number of good bumps = 0
[16:35:50.078] <TB1> INFO: ROC 11, missing bumps = 4160, total number of good bumps = 0
[16:35:50.080] <TB1> INFO: ROC 12, missing bumps = 4160, total number of good bumps = 0
[16:35:50.081] <TB1> INFO: ROC 13, missing bumps = 4160, total number of good bumps = 0
[16:35:50.082] <TB1> INFO: ROC 14, missing bumps = 4160, total number of good bumps = 0
[16:35:50.083] <TB1> INFO: ROC 15, missing bumps = 4160, total number of good bumps = 0
[16:35:50.083] <TB1> INFO: CalDel = 80 done
[16:35:50.359] <TB1> INFO: Expecting 416000 events.
[16:36:00.165] <TB1> INFO: 416000 events read in total (9250ms).
[16:36:00.167] <TB1> INFO: Test took 10081ms.
[16:36:00.422] <TB1> INFO: ROC 0, missing bumps = 4160, total number of good bumps = 0
[16:36:00.423] <TB1> INFO: ROC 1, missing bumps = 2182, total number of good bumps = 1978
[16:36:00.425] <TB1> INFO: ROC 2, missing bumps = 4160, total number of good bumps = 0
[16:36:00.426] <TB1> INFO: ROC 3, missing bumps = 4160, total number of good bumps = 0
[16:36:00.427] <TB1> INFO: ROC 4, missing bumps = 4160, total number of good bumps = 0
[16:36:00.428] <TB1> INFO: ROC 5, missing bumps = 4154, total number of good bumps = 6
[16:36:00.429] <TB1> INFO: ROC 6, missing bumps = 4160, total number of good bumps = 0
[16:36:00.430] <TB1> INFO: ROC 7, missing bumps = 4160, total number of good bumps = 0
[16:36:00.432] <TB1> INFO: ROC 8, missing bumps = 3992, total number of good bumps = 168
[16:36:00.433] <TB1> INFO: ROC 9, missing bumps = 4160, total number of good bumps = 0
[16:36:00.434] <TB1> INFO: ROC 10, missing bumps = 4160, total number of good bumps = 0
[16:36:00.435] <TB1> INFO: ROC 11, missing bumps = 4160, total number of good bumps = 0
[16:36:00.436] <TB1> INFO: ROC 12, missing bumps = 4160, total number of good bumps = 0
[16:36:00.437] <TB1> INFO: ROC 13, missing bumps = 4160, total number of good bumps = 0
[16:36:00.438] <TB1> INFO: ROC 14, missing bumps = 4160, total number of good bumps = 0
[16:36:00.440] <TB1> INFO: ROC 15, missing bumps = 4060, total number of good bumps = 100
[16:36:00.440] <TB1> INFO: CalDel = 90 done
[16:36:00.710] <TB1> INFO: Expecting 416000 events.
[16:36:11.683] <TB1> INFO: 416000 events read in total (10418ms).
[16:36:11.684] <TB1> INFO: Test took 11241ms.
[16:36:11.901] <TB1> INFO: ROC 0, missing bumps = 3789, total number of good bumps = 371
[16:36:11.903] <TB1> INFO: ROC 1, missing bumps = 15, total number of good bumps = 4145
[16:36:11.904] <TB1> INFO: ROC 2, missing bumps = 4160, total number of good bumps = 0
[16:36:11.905] <TB1> INFO: ROC 3, missing bumps = 4160, total number of good bumps = 0
[16:36:11.906] <TB1> INFO: ROC 4, missing bumps = 4160, total number of good bumps = 0
[16:36:11.908] <TB1> INFO: ROC 5, missing bumps = 2005, total number of good bumps = 2155
[16:36:11.909] <TB1> INFO: ROC 6, missing bumps = 3536, total number of good bumps = 624
[16:36:11.910] <TB1> INFO: ROC 7, missing bumps = 4160, total number of good bumps = 0
[16:36:11.911] <TB1> INFO: ROC 8, missing bumps = 1282, total number of good bumps = 2878
[16:36:11.913] <TB1> INFO: ROC 9, missing bumps = 4157, total number of good bumps = 3
[16:36:11.914] <TB1> INFO: ROC 10, missing bumps = 4159, total number of good bumps = 1
[16:36:11.915] <TB1> INFO: ROC 11, missing bumps = 3838, total number of good bumps = 322
[16:36:11.916] <TB1> INFO: ROC 12, missing bumps = 4160, total number of good bumps = 0
[16:36:11.917] <TB1> INFO: ROC 13, missing bumps = 2994, total number of good bumps = 1166
[16:36:11.918] <TB1> INFO: ROC 14, missing bumps = 4022, total number of good bumps = 138
[16:36:11.919] <TB1> INFO: ROC 15, missing bumps = 908, total number of good bumps = 3252
[16:36:11.919] <TB1> INFO: CalDel = 100 done
[16:36:12.187] <TB1> INFO: Expecting 416000 events.
[16:36:24.885] <TB1> INFO: 416000 events read in total (12142ms).
[16:36:24.886] <TB1> INFO: Test took 12964ms.
[16:36:25.066] <TB1> INFO: ROC 0, missing bumps = 1068, total number of good bumps = 3092
[16:36:25.067] <TB1> INFO: ROC 1, missing bumps = 5, total number of good bumps = 4155
[16:36:25.068] <TB1> INFO: ROC 2, missing bumps = 4159, total number of good bumps = 1
[16:36:25.070] <TB1> INFO: ROC 3, missing bumps = 4154, total number of good bumps = 6
[16:36:25.071] <TB1> INFO: ROC 4, missing bumps = 4027, total number of good bumps = 133
[16:36:25.072] <TB1> INFO: ROC 5, missing bumps = 12, total number of good bumps = 4148
[16:36:25.073] <TB1> INFO: ROC 6, missing bumps = 171, total number of good bumps = 3989
[16:36:25.074] <TB1> INFO: ROC 7, missing bumps = 4145, total number of good bumps = 15
[16:36:25.075] <TB1> INFO: ROC 8, missing bumps = 27, total number of good bumps = 4133
[16:36:25.077] <TB1> INFO: ROC 9, missing bumps = 3521, total number of good bumps = 639
[16:36:25.078] <TB1> INFO: ROC 10, missing bumps = 4049, total number of good bumps = 111
[16:36:25.079] <TB1> INFO: ROC 11, missing bumps = 323, total number of good bumps = 3837
[16:36:25.080] <TB1> INFO: ROC 12, missing bumps = 4109, total number of good bumps = 51
[16:36:25.081] <TB1> INFO: ROC 13, missing bumps = 70, total number of good bumps = 4090
[16:36:25.082] <TB1> INFO: ROC 14, missing bumps = 545, total number of good bumps = 3615
[16:36:25.084] <TB1> INFO: ROC 15, missing bumps = 16, total number of good bumps = 4144
[16:36:25.084] <TB1> INFO: CalDel = 110 done
[16:36:25.359] <TB1> INFO: Expecting 416000 events.
[16:36:40.041] <TB1> INFO: 416000 events read in total (14126ms).
[16:36:40.042] <TB1> INFO: Test took 14955ms.
[16:36:40.212] <TB1> INFO: ROC 0, missing bumps = 56, total number of good bumps = 4104
[16:36:40.213] <TB1> INFO: ROC 1, missing bumps = 4, total number of good bumps = 4156
[16:36:40.214] <TB1> INFO: ROC 2, missing bumps = 4145, total number of good bumps = 15
[16:36:40.216] <TB1> INFO: ROC 3, missing bumps = 1670, total number of good bumps = 2490
[16:36:40.217] <TB1> INFO: ROC 4, missing bumps = 464, total number of good bumps = 3696
[16:36:40.219] <TB1> INFO: ROC 5, missing bumps = 5, total number of good bumps = 4155
[16:36:40.220] <TB1> INFO: ROC 6, missing bumps = 0, total number of good bumps = 4160
[16:36:40.221] <TB1> INFO: ROC 7, missing bumps = 1066, total number of good bumps = 3094
[16:36:40.223] <TB1> INFO: ROC 8, missing bumps = 5, total number of good bumps = 4155
[16:36:40.224] <TB1> INFO: ROC 9, missing bumps = 95, total number of good bumps = 4065
[16:36:40.225] <TB1> INFO: ROC 10, missing bumps = 489, total number of good bumps = 3671
[16:36:40.226] <TB1> INFO: ROC 11, missing bumps = 7, total number of good bumps = 4153
[16:36:40.227] <TB1> INFO: ROC 12, missing bumps = 514, total number of good bumps = 3646
[16:36:40.229] <TB1> INFO: ROC 13, missing bumps = 0, total number of good bumps = 4160
[16:36:40.230] <TB1> INFO: ROC 14, missing bumps = 5, total number of good bumps = 4155
[16:36:40.231] <TB1> INFO: ROC 15, missing bumps = 6, total number of good bumps = 4154
[16:36:40.231] <TB1> INFO: CalDel = 120 done
[16:36:40.504] <TB1> INFO: Expecting 416000 events.
[16:36:55.867] <TB1> INFO: 416000 events read in total (14806ms).
[16:36:55.868] <TB1> INFO: Test took 15635ms.
[16:36:56.038] <TB1> INFO: ROC 0, missing bumps = 56, total number of good bumps = 4104
[16:36:56.039] <TB1> INFO: ROC 1, missing bumps = 2, total number of good bumps = 4158
[16:36:56.040] <TB1> INFO: ROC 2, missing bumps = 2056, total number of good bumps = 2104
[16:36:56.041] <TB1> INFO: ROC 3, missing bumps = 9, total number of good bumps = 4152
[16:36:56.043] <TB1> INFO: ROC 4, missing bumps = 8, total number of good bumps = 4152
[16:36:56.044] <TB1> INFO: ROC 5, missing bumps = 3, total number of good bumps = 4157
[16:36:56.045] <TB1> INFO: ROC 7, missing bumps = 16, total number of good bumps = 4144
[16:36:56.046] <TB1> INFO: ROC 8, missing bumps = 3, total number of good bumps = 4157
[16:36:56.047] <TB1> INFO: ROC 9, missing bumps = 1, total number of good bumps = 4159
[16:36:56.048] <TB1> INFO: ROC 10, missing bumps = 3, total number of good bumps = 4157
[16:36:56.050] <TB1> INFO: ROC 11, missing bumps = 5, total number of good bumps = 4155
[16:36:56.051] <TB1> INFO: ROC 12, missing bumps = 6, total number of good bumps = 4154
[16:36:56.052] <TB1> INFO: ROC 14, missing bumps = 3, total number of good bumps = 4157
[16:36:56.053] <TB1> INFO: ROC 15, missing bumps = 3, total number of good bumps = 4157
[16:36:56.053] <TB1> INFO: CalDel = 130 done
[16:36:56.324] <TB1> INFO: Expecting 416000 events.
[16:37:11.855] <TB1> INFO: 416000 events read in total (14975ms).
[16:37:11.861] <TB1> INFO: Test took 15805ms.
[16:37:12.026] <TB1> INFO: ROC 0, missing bumps = 56, total number of good bumps = 4104
[16:37:12.028] <TB1> INFO: ROC 1, missing bumps = 6, total number of good bumps = 4159
[16:37:12.029] <TB1> INFO: ROC 2, missing bumps = 26, total number of good bumps = 4134
[16:37:12.030] <TB1> INFO: ROC 3, missing bumps = 7, total number of good bumps = 4153
[16:37:12.031] <TB1> INFO: ROC 4, missing bumps = 5, total number of good bumps = 4155
[16:37:12.032] <TB1> INFO: ROC 5, missing bumps = 3, total number of good bumps = 4157
[16:37:12.034] <TB1> INFO: ROC 7, missing bumps = 11, total number of good bumps = 4149
[16:37:12.035] <TB1> INFO: ROC 8, missing bumps = 1, total number of good bumps = 4159
[16:37:12.036] <TB1> INFO: ROC 9, missing bumps = 1, total number of good bumps = 4159
[16:37:12.037] <TB1> INFO: ROC 10, missing bumps = 1, total number of good bumps = 4159
[16:37:12.038] <TB1> INFO: ROC 11, missing bumps = 4, total number of good bumps = 4156
[16:37:12.039] <TB1> INFO: ROC 12, missing bumps = 3, total number of good bumps = 4157
[16:37:12.040] <TB1> INFO: ROC 14, missing bumps = 3, total number of good bumps = 4157
[16:37:12.041] <TB1> INFO: ROC 15, missing bumps = 0, total number of good bumps = 4160
[16:37:12.041] <TB1> INFO: CalDel = 140 done
[16:37:12.307] <TB1> INFO: Expecting 416000 events.
[16:37:27.800] <TB1> INFO: 416000 events read in total (14937ms).
[16:37:27.802] <TB1> INFO: Test took 15758ms.
[16:37:27.974] <TB1> INFO: ROC 0, missing bumps = 56, total number of good bumps = 4104
[16:37:27.976] <TB1> INFO: ROC 1, missing bumps = 2877, total number of good bumps = 4159
[16:37:27.978] <TB1> INFO: ROC 2, missing bumps = 8, total number of good bumps = 4153
[16:37:27.979] <TB1> INFO: ROC 3, missing bumps = 7, total number of good bumps = 4154
[16:37:27.980] <TB1> INFO: ROC 4, missing bumps = 2, total number of good bumps = 4158
[16:37:27.982] <TB1> INFO: ROC 5, missing bumps = 11, total number of good bumps = 4158
[16:37:27.983] <TB1> INFO: ROC 7, missing bumps = 3, total number of good bumps = 4157
[16:37:27.984] <TB1> INFO: ROC 8, missing bumps = 67, total number of good bumps = 4159
[16:37:27.985] <TB1> INFO: ROC 9, missing bumps = 1, total number of good bumps = 4159
[16:37:27.986] <TB1> INFO: ROC 10, missing bumps = 1, total number of good bumps = 4159
[16:37:27.987] <TB1> INFO: ROC 11, missing bumps = 3, total number of good bumps = 4157
[16:37:27.989] <TB1> INFO: ROC 12, missing bumps = 1, total number of good bumps = 4159
[16:37:27.990] <TB1> INFO: ROC 14, missing bumps = 3, total number of good bumps = 4157
[16:37:27.990] <TB1> INFO: CalDel = 150 done
[16:37:28.256] <TB1> INFO: Expecting 416000 events.
[16:37:42.885] <TB1> INFO: 416000 events read in total (14073ms).
[16:37:42.886] <TB1> INFO: Test took 14893ms.
[16:37:43.055] <TB1> INFO: ROC 0, missing bumps = 160, total number of good bumps = 4104
[16:37:43.056] <TB1> INFO: ROC 1, missing bumps = 4147, total number of good bumps = 4159
[16:37:43.058] <TB1> INFO: ROC 2, missing bumps = 9, total number of good bumps = 4153
[16:37:43.059] <TB1> INFO: ROC 3, missing bumps = 8, total number of good bumps = 4154
[16:37:43.061] <TB1> INFO: ROC 4, missing bumps = 0, total number of good bumps = 4160
[16:37:43.063] <TB1> INFO: ROC 5, missing bumps = 1524, total number of good bumps = 4158
[16:37:43.064] <TB1> INFO: ROC 7, missing bumps = 3, total number of good bumps = 4157
[16:37:43.066] <TB1> INFO: ROC 8, missing bumps = 1818, total number of good bumps = 4159
[16:37:43.067] <TB1> INFO: ROC 9, missing bumps = 2, total number of good bumps = 4159
[16:37:43.068] <TB1> INFO: ROC 10, missing bumps = 1, total number of good bumps = 4159
[16:37:43.069] <TB1> INFO: ROC 11, missing bumps = 166, total number of good bumps = 4159
[16:37:43.071] <TB1> INFO: ROC 12, missing bumps = 1, total number of good bumps = 4159
[16:37:43.072] <TB1> INFO: ROC 14, missing bumps = 67, total number of good bumps = 4157
[16:37:43.072] <TB1> INFO: CalDel = 160 done
[16:37:43.334] <TB1> INFO: Expecting 416000 events.
[16:37:56.031] <TB1> INFO: 416000 events read in total (12141ms).
[16:37:56.033] <TB1> INFO: Test took 12958ms.
[16:37:56.208] <TB1> INFO: ROC 0, missing bumps = 2021, total number of good bumps = 4104
[16:37:56.210] <TB1> INFO: ROC 1, missing bumps = 4156, total number of good bumps = 4159
[16:37:56.211] <TB1> INFO: ROC 2, missing bumps = 11, total number of good bumps = 4153
[16:37:56.213] <TB1> INFO: ROC 3, missing bumps = 10, total number of good bumps = 4154
[16:37:56.214] <TB1> INFO: ROC 5, missing bumps = 4121, total number of good bumps = 4158
[16:37:56.216] <TB1> INFO: ROC 7, missing bumps = 3, total number of good bumps = 4157
[16:37:56.217] <TB1> INFO: ROC 8, missing bumps = 4035, total number of good bumps = 4160
[16:37:56.219] <TB1> INFO: ROC 9, missing bumps = 147, total number of good bumps = 4159
[16:37:56.220] <TB1> INFO: ROC 10, missing bumps = 16, total number of good bumps = 4159
[16:37:56.222] <TB1> INFO: ROC 11, missing bumps = 3567, total number of good bumps = 4159
[16:37:56.223] <TB1> INFO: ROC 12, missing bumps = 7, total number of good bumps = 4159
[16:37:56.228] <TB1> INFO: ROC 14, missing bumps = 3217, total number of good bumps = 4157
[16:37:56.228] <TB1> INFO: CalDel = 170 done
[16:37:56.506] <TB1> INFO: Expecting 416000 events.
[16:38:07.261] <TB1> INFO: 416000 events read in total (10199ms).
[16:38:07.262] <TB1> INFO: Test took 11031ms.
[16:38:07.482] <TB1> INFO: ROC 0, missing bumps = 4156, total number of good bumps = 4104
[16:38:07.484] <TB1> INFO: ROC 1, missing bumps = 4159, total number of good bumps = 4159
[16:38:07.485] <TB1> INFO: ROC 2, missing bumps = 17, total number of good bumps = 4153
[16:38:07.487] <TB1> INFO: ROC 3, missing bumps = 631, total number of good bumps = 4154
[16:38:07.488] <TB1> INFO: ROC 5, missing bumps = 4158, total number of good bumps = 4158
[16:38:07.490] <TB1> INFO: ROC 7, missing bumps = 1617, total number of good bumps = 4159
[16:38:07.491] <TB1> INFO: ROC 8, missing bumps = 4155, total number of good bumps = 4160
[16:38:07.493] <TB1> INFO: ROC 9, missing bumps = 3937, total number of good bumps = 4159
[16:38:07.495] <TB1> INFO: ROC 10, missing bumps = 3323, total number of good bumps = 4159
[16:38:07.496] <TB1> INFO: ROC 11, missing bumps = 4153, total number of good bumps = 4159
[16:38:07.497] <TB1> INFO: ROC 12, missing bumps = 3378, total number of good bumps = 4159
[16:38:07.498] <TB1> INFO: ROC 14, missing bumps = 4158, total number of good bumps = 4157
[16:38:07.498] <TB1> INFO: CalDel = 180 done
[16:38:07.766] <TB1> INFO: Expecting 416000 events.
[16:38:17.602] <TB1> INFO: 416000 events read in total (9280ms).
[16:38:17.602] <TB1> INFO: Test took 10100ms.
[16:38:17.841] <TB1> INFO: ROC 0, missing bumps = 4160, total number of good bumps = 4104
[16:38:17.843] <TB1> INFO: ROC 1, missing bumps = 4160, total number of good bumps = 4159
[16:38:17.844] <TB1> INFO: ROC 2, missing bumps = 318, total number of good bumps = 4153
[16:38:17.846] <TB1> INFO: ROC 3, missing bumps = 4045, total number of good bumps = 4154
[16:38:17.848] <TB1> INFO: ROC 5, missing bumps = 4159, total number of good bumps = 4158
[16:38:17.849] <TB1> INFO: ROC 7, missing bumps = 4073, total number of good bumps = 4159
[16:38:17.850] <TB1> INFO: ROC 8, missing bumps = 4159, total number of good bumps = 4160
[16:38:17.851] <TB1> INFO: ROC 9, missing bumps = 4158, total number of good bumps = 4159
[16:38:17.853] <TB1> INFO: ROC 10, missing bumps = 4150, total number of good bumps = 4159
[16:38:17.855] <TB1> INFO: ROC 11, missing bumps = 4157, total number of good bumps = 4159
[16:38:17.857] <TB1> INFO: ROC 12, missing bumps = 4152, total number of good bumps = 4159
[16:38:17.859] <TB1> INFO: ROC 14, missing bumps = 4160, total number of good bumps = 4157
[16:38:17.859] <TB1> INFO: CalDel = 190 done
[16:38:18.152] <TB1> INFO: Expecting 416000 events.
[16:38:27.595] <TB1> INFO: 416000 events read in total (8888ms).
[16:38:27.596] <TB1> INFO: Test took 9734ms.
[16:38:27.861] <TB1> INFO: ROC 0, missing bumps = 4160, total number of good bumps = 4104
[16:38:27.864] <TB1> INFO: ROC 1, missing bumps = 4160, total number of good bumps = 4159
[16:38:27.866] <TB1> INFO: ROC 2, missing bumps = 3683, total number of good bumps = 4153
[16:38:27.869] <TB1> INFO: ROC 3, missing bumps = 4158, total number of good bumps = 4154
[16:38:27.872] <TB1> INFO: ROC 5, missing bumps = 4160, total number of good bumps = 4158
[16:38:27.873] <TB1> INFO: ROC 7, missing bumps = 4147, total number of good bumps = 4159
[16:38:27.875] <TB1> INFO: ROC 8, missing bumps = 4160, total number of good bumps = 4160
[16:38:27.877] <TB1> INFO: ROC 9, missing bumps = 4160, total number of good bumps = 4159
[16:38:27.879] <TB1> INFO: ROC 10, missing bumps = 4160, total number of good bumps = 4159
[16:38:27.881] <TB1> INFO: ROC 11, missing bumps = 4159, total number of good bumps = 4159
[16:38:27.883] <TB1> INFO: ROC 12, missing bumps = 4156, total number of good bumps = 4159
[16:38:27.885] <TB1> INFO: ROC 14, missing bumps = 4160, total number of good bumps = 4157
[16:38:27.885] <TB1> INFO: CalDel = 200 done
[16:38:27.885] <TB1> INFO: CalDel scan done
[16:38:27.887] <TB1> INFO: resulting values: CalDel = 120 130 150 140 160 130 120 150 140 130 140 150 150 120 130 140
[16:38:28.153] <TB1> INFO: Expecting 416000 events.
[16:38:43.652] <TB1> INFO: 416000 events read in total (14944ms).
[16:38:43.660] <TB1> INFO: Test took 15772ms.
[16:38:43.668] <TB1> INFO: maps.size() = 16
[16:38:43.849] <TB1> INFO: 80 broken Bump Bonds found
[16:38:43.851] <TB1> INFO: back to CtrlReg 0
[16:38:43.854] <TB1> INFO: PixTestBB4Map::doTest() done
[16:38:43.854] <TB1> INFO: Write out MinimumThreshold
[16:38:43.855] <TB1> INFO: Write out CalDel Scan_C0_V0
[16:38:43.855] <TB1> INFO: Write out PixelHit_C0_V0
[16:38:43.855] <TB1> INFO: Write out BB4MapBest_C0_V0
[16:38:43.855] <TB1> INFO: Write out NoiseScan_C0_V0
[16:38:43.855] <TB1> INFO: Write out BB4Map_C0_V14
[16:38:43.856] <TB1> INFO: Write out Hit Distr_C0_V0
[16:38:43.856] <TB1> INFO: Write out Hit Distr_C0_V0
[16:38:43.856] <TB1> INFO: Write out CalDel Scan_C1_V0
[16:38:43.856] <TB1> INFO: Write out PixelHit_C1_V0
[16:38:43.856] <TB1> INFO: Write out BB4MapBest_C1_V0
[16:38:43.857] <TB1> INFO: Write out NoiseScan_C1_V0
[16:38:43.857] <TB1> INFO: Write out BB4Map_C1_V14
[16:38:43.857] <TB1> INFO: Write out Hit Distr_C1_V0
[16:38:43.857] <TB1> INFO: Write out Hit Distr_C1_V0
[16:38:43.857] <TB1> INFO: Write out CalDel Scan_C2_V0
[16:38:43.857] <TB1> INFO: Write out PixelHit_C2_V0
[16:38:43.858] <TB1> INFO: Write out BB4MapBest_C2_V0
[16:38:43.858] <TB1> INFO: Write out NoiseScan_C2_V0
[16:38:43.858] <TB1> INFO: Write out BB4Map_C2_V14
[16:38:43.858] <TB1> INFO: Write out Hit Distr_C2_V0
[16:38:43.859] <TB1> INFO: Write out Hit Distr_C2_V0
[16:38:43.859] <TB1> INFO: Write out CalDel Scan_C3_V0
[16:38:43.859] <TB1> INFO: Write out PixelHit_C3_V0
[16:38:43.859] <TB1> INFO: Write out BB4MapBest_C3_V0
[16:38:43.859] <TB1> INFO: Write out NoiseScan_C3_V0
[16:38:43.860] <TB1> INFO: Write out BB4Map_C3_V14
[16:38:43.860] <TB1> INFO: Write out Hit Distr_C3_V0
[16:38:43.860] <TB1> INFO: Write out Hit Distr_C3_V0
[16:38:43.860] <TB1> INFO: Write out CalDel Scan_C4_V0
[16:38:43.860] <TB1> INFO: Write out PixelHit_C4_V0
[16:38:43.861] <TB1> INFO: Write out BB4MapBest_C4_V0
[16:38:43.861] <TB1> INFO: Write out NoiseScan_C4_V0
[16:38:43.861] <TB1> INFO: Write out BB4Map_C4_V14
[16:38:43.861] <TB1> INFO: Write out Hit Distr_C4_V0
[16:38:43.861] <TB1> INFO: Write out Hit Distr_C4_V0
[16:38:43.862] <TB1> INFO: Write out CalDel Scan_C5_V0
[16:38:43.862] <TB1> INFO: Write out PixelHit_C5_V0
[16:38:43.862] <TB1> INFO: Write out BB4MapBest_C5_V0
[16:38:43.862] <TB1> INFO: Write out NoiseScan_C5_V0
[16:38:43.862] <TB1> INFO: Write out BB4Map_C5_V14
[16:38:43.863] <TB1> INFO: Write out Hit Distr_C5_V0
[16:38:43.863] <TB1> INFO: Write out Hit Distr_C5_V0
[16:38:43.863] <TB1> INFO: Write out CalDel Scan_C6_V0
[16:38:43.863] <TB1> INFO: Write out PixelHit_C6_V0
[16:38:43.863] <TB1> INFO: Write out BB4MapBest_C6_V0
[16:38:43.864] <TB1> INFO: Write out NoiseScan_C6_V0
[16:38:43.864] <TB1> INFO: Write out BB4Map_C6_V14
[16:38:43.864] <TB1> INFO: Write out Hit Distr_C6_V0
[16:38:43.864] <TB1> INFO: Write out Hit Distr_C6_V0
[16:38:43.864] <TB1> INFO: Write out CalDel Scan_C7_V0
[16:38:43.864] <TB1> INFO: Write out PixelHit_C7_V0
[16:38:43.865] <TB1> INFO: Write out BB4MapBest_C7_V0
[16:38:43.865] <TB1> INFO: Write out NoiseScan_C7_V0
[16:38:43.865] <TB1> INFO: Write out BB4Map_C7_V14
[16:38:43.865] <TB1> INFO: Write out Hit Distr_C7_V0
[16:38:43.865] <TB1> INFO: Write out Hit Distr_C7_V0
[16:38:43.866] <TB1> INFO: Write out CalDel Scan_C8_V0
[16:38:43.866] <TB1> INFO: Write out PixelHit_C8_V0
[16:38:43.866] <TB1> INFO: Write out BB4MapBest_C8_V0
[16:38:43.866] <TB1> INFO: Write out NoiseScan_C8_V0
[16:38:43.866] <TB1> INFO: Write out BB4Map_C8_V14
[16:38:43.867] <TB1> INFO: Write out Hit Distr_C8_V0
[16:38:43.867] <TB1> INFO: Write out Hit Distr_C8_V0
[16:38:43.867] <TB1> INFO: Write out CalDel Scan_C9_V0
[16:38:43.867] <TB1> INFO: Write out PixelHit_C9_V0
[16:38:43.867] <TB1> INFO: Write out BB4MapBest_C9_V0
[16:38:43.868] <TB1> INFO: Write out NoiseScan_C9_V0
[16:38:43.868] <TB1> INFO: Write out BB4Map_C9_V14
[16:38:43.868] <TB1> INFO: Write out Hit Distr_C9_V0
[16:38:43.868] <TB1> INFO: Write out Hit Distr_C9_V0
[16:38:43.868] <TB1> INFO: Write out CalDel Scan_C10_V0
[16:38:43.869] <TB1> INFO: Write out PixelHit_C10_V0
[16:38:43.869] <TB1> INFO: Write out BB4MapBest_C10_V0
[16:38:43.869] <TB1> INFO: Write out NoiseScan_C10_V0
[16:38:43.869] <TB1> INFO: Write out BB4Map_C10_V14
[16:38:43.869] <TB1> INFO: Write out Hit Distr_C10_V0
[16:38:43.870] <TB1> INFO: Write out Hit Distr_C10_V0
[16:38:43.870] <TB1> INFO: Write out CalDel Scan_C11_V0
[16:38:43.870] <TB1> INFO: Write out PixelHit_C11_V0
[16:38:43.870] <TB1> INFO: Write out BB4MapBest_C11_V0
[16:38:43.870] <TB1> INFO: Write out NoiseScan_C11_V0
[16:38:43.871] <TB1> INFO: Write out BB4Map_C11_V14
[16:38:43.871] <TB1> INFO: Write out Hit Distr_C11_V0
[16:38:43.871] <TB1> INFO: Write out Hit Distr_C11_V0
[16:38:43.871] <TB1> INFO: Write out CalDel Scan_C12_V0
[16:38:43.871] <TB1> INFO: Write out PixelHit_C12_V0
[16:38:43.872] <TB1> INFO: Write out BB4MapBest_C12_V0
[16:38:43.872] <TB1> INFO: Write out NoiseScan_C12_V0
[16:38:43.872] <TB1> INFO: Write out BB4Map_C12_V14
[16:38:43.872] <TB1> INFO: Write out Hit Distr_C12_V0
[16:38:43.872] <TB1> INFO: Write out Hit Distr_C12_V0
[16:38:43.873] <TB1> INFO: Write out CalDel Scan_C13_V0
[16:38:43.873] <TB1> INFO: Write out PixelHit_C13_V0
[16:38:43.873] <TB1> INFO: Write out BB4MapBest_C13_V0
[16:38:43.873] <TB1> INFO: Write out NoiseScan_C13_V0
[16:38:43.873] <TB1> INFO: Write out BB4Map_C13_V14
[16:38:43.874] <TB1> INFO: Write out Hit Distr_C13_V0
[16:38:43.874] <TB1> INFO: Write out Hit Distr_C13_V0
[16:38:43.874] <TB1> INFO: Write out CalDel Scan_C14_V0
[16:38:43.874] <TB1> INFO: Write out PixelHit_C14_V0
[16:38:43.874] <TB1> INFO: Write out BB4MapBest_C14_V0
[16:38:43.875] <TB1> INFO: Write out NoiseScan_C14_V0
[16:38:43.875] <TB1> INFO: Write out BB4Map_C14_V14
[16:38:43.875] <TB1> INFO: Write out Hit Distr_C14_V0
[16:38:43.875] <TB1> INFO: Write out Hit Distr_C14_V0
[16:38:43.875] <TB1> INFO: Write out CalDel Scan_C15_V0
[16:38:43.875] <TB1> INFO: Write out PixelHit_C15_V0
[16:38:43.876] <TB1> INFO: Write out BB4MapBest_C15_V0
[16:38:43.876] <TB1> INFO: Write out NoiseScan_C15_V0
[16:38:43.876] <TB1> INFO: Write out BB4Map_C15_V14
[16:38:43.876] <TB1> INFO: Write out Hit Distr_C15_V0
[16:38:43.877] <TB1> INFO: Write out Hit Distr_C15_V0
[16:38:43.877] <TB1> INFO: enter test to run
[16:38:43.877] <TB1> INFO: test: no parameter change
[16:38:43.907] <TB1> QUIET: Connection to board 154 closed.
[16:38:43.986] <TB1> INFO: pXar: this is the end, my friend