[11:18:24.062] <TB1> INFO: *** Welcome to pxar ***
[11:18:24.062] <TB1> INFO: *** Today: 2016/07/27
[11:18:24.102] <TB1> INFO: *** Version: f5d3-dirty
[11:18:24.102] <TB1> INFO: readRocDacs: /home/Reception/DATA/KITAACHEN/M4630_Reception_2016-07-27_13h13m_1469618008//000_ReceptionTestBB4_p17//dacParameters_C0.dat .. /home/Reception/DATA/KITAACHEN/M4630_Reception_2016-07-27_13h13m_1469618008//000_ReceptionTestBB4_p17//dacParameters_C15.dat
[11:18:24.119] <TB1> INFO: readTbmDacs: /home/Reception/DATA/KITAACHEN/M4630_Reception_2016-07-27_13h13m_1469618008//000_ReceptionTestBB4_p17//tbmParameters_C0a.dat .. /home/Reception/DATA/KITAACHEN/M4630_Reception_2016-07-27_13h13m_1469618008//000_ReceptionTestBB4_p17//tbmParameters_C0b.dat
[11:18:24.122] <TB1> INFO: readMaskFile: /home/Reception/DATA/KITAACHEN/M4630_Reception_2016-07-27_13h13m_1469618008//000_ReceptionTestBB4_p17//defaultMaskFile.dat
[11:18:24.123] <TB1> INFO: readTrimFile: /home/Reception/DATA/KITAACHEN/M4630_Reception_2016-07-27_13h13m_1469618008//000_ReceptionTestBB4_p17//trimParameters_C0.dat .. /home/Reception/DATA/KITAACHEN/M4630_Reception_2016-07-27_13h13m_1469618008//000_ReceptionTestBB4_p17//trimParameters_C15.dat
[11:18:24.261] <TB1> INFO: clk: 4
[11:18:24.261] <TB1> INFO: ctr: 4
[11:18:24.261] <TB1> INFO: sda: 19
[11:18:24.261] <TB1> INFO: tin: 9
[11:18:24.261] <TB1> INFO: level: 15
[11:18:24.261] <TB1> INFO: triggerdelay: 0
[11:18:24.261] <TB1> QUIET: Instanciating API for pxar v2.7.5+40~g4fce89b
[11:18:24.261] <TB1> INFO: Log level: INFO
[11:18:24.268] <TB1> INFO: Found DTB DTB_WXC03A
[11:18:24.276] <TB1> QUIET: Connection to board DTB_WXC03A opened.
[11:18:24.278] <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:
------------------------------------------------------
[11:18:24.280] <TB1> INFO: RPC call hashes of host and DTB match: 484264910
[11:18:25.788] <TB1> INFO: DUT info:
[11:18:25.788] <TB1> INFO: The DUT currently contains the following objects:
[11:18:25.788] <TB1> INFO: 2 TBM Cores tbm08c (2 ON)
[11:18:25.788] <TB1> INFO: TBM Core alpha (0): 7 registers set
[11:18:25.788] <TB1> INFO: TBM Core beta (1): 7 registers set
[11:18:25.788] <TB1> INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[11:18:25.788] <TB1> INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[11:18:25.788] <TB1> INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[11:18:25.788] <TB1> INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[11:18:25.788] <TB1> INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[11:18:25.788] <TB1> INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[11:18:25.788] <TB1> INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[11:18:25.788] <TB1> INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[11:18:25.788] <TB1> INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[11:18:25.788] <TB1> INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[11:18:25.788] <TB1> INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[11:18:25.788] <TB1> INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[11:18:25.788] <TB1> INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[11:18:25.788] <TB1> INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[11:18:25.788] <TB1> INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[11:18:25.788] <TB1> INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[11:18:25.788] <TB1> INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[11:18:26.190] <TB1> INFO: enter 'restricted' command line mode
[11:18:26.190] <TB1> INFO: enter test to run
[11:18:26.191] <TB1> INFO: test: pretest no parameter change
[11:18:26.191] <TB1> INFO: running: pretest
[11:18:26.197] <TB1> INFO: ----------------------------------------------------------------------
[11:18:26.197] <TB1> INFO: PixTestPretest::programROC()
[11:18:26.197] <TB1> INFO: ----------------------------------------------------------------------
[11:18:44.211] <TB1> INFO: PixTestPretest::programROC() done: ROCs are all programmable
[11:18:44.212] <TB1> INFO: IA differences per ROC: 20.9 20.9 19.3 20.9 20.9 20.1 20.9 22.5 19.3 19.3 20.9 20.1 19.3 22.5 19.3 21.7
[11:18:44.297] <TB1> INFO: enter test to run
[11:18:44.297] <TB1> INFO: test: pretest no parameter change
[11:18:44.297] <TB1> INFO: running: pretest
[11:18:44.298] <TB1> INFO: ----------------------------------------------------------------------
[11:18:44.298] <TB1> INFO: PixTestPretest::setVana() target Ia = 24 mA/ROC
[11:18:44.298] <TB1> INFO: ----------------------------------------------------------------------
[11:19:05.561] <TB1> INFO: PixTestPretest::setVana() done, Module Ia 376.2 mA = 23.5125 mA/ROC
[11:19:05.561] <TB1> INFO: i(loss) [mA/ROC]: 19.3 19.3 18.5 19.3 18.5 19.3 20.1 18.5 19.3 18.5 19.3 18.5 18.5 18.5 19.3 18.5
[11:19:05.585] <TB1> INFO: enter test to run
[11:19:05.585] <TB1> INFO: test: pretest no parameter change
[11:19:05.585] <TB1> INFO: running: pretest
[11:19:05.585] <TB1> INFO: ----------------------------------------------------------------------
[11:19:05.585] <TB1> INFO: PixTestPretest::findTiming()
[11:19:05.585] <TB1> INFO: ----------------------------------------------------------------------
[11:19:05.585] <TB1> INFO: PixTestCmd::init()
[11:19:06.357] <TB1> WARNING: Not unmasking DUT, not setting Calibrate bits!
[11:20:04.147] <TB1> INFO: TBM phases: 160MHz: 7, 400MHz: 2, TBM delays: ROC(0/1):4, header/trailer: 1, token: 1
[11:20:04.147] <TB1> INFO: (success/tries = 100/100), width = 4
[11:20:04.148] <TB1> INFO: enter test to run
[11:20:04.148] <TB1> INFO: test: pretest no parameter change
[11:20:04.148] <TB1> INFO: running: pretest
[11:20:04.149] <TB1> INFO: ----------------------------------------------------------------------
[11:20:04.149] <TB1> INFO: PixTestPretest::findWorkingPixel()
[11:20:04.149] <TB1> INFO: ----------------------------------------------------------------------
[11:20:04.239] <TB1> INFO: Expecting 231680 events.
[11:20:09.929] <TB1> WARNING: Detected DESER400 trailer error bits: "CODE ERROR"
[11:20:13.244] <TB1> INFO: 231680 events read in total (8449ms).
[11:20:13.248] <TB1> INFO: Test took 9096ms.
[11:20:13.555] <TB1> INFO: Found working pixel in all ROCs: col/row = 12/22
[11:20:13.627] <TB1> INFO: enter test to run
[11:20:13.627] <TB1> INFO: test: pretest no parameter change
[11:20:13.627] <TB1> INFO: running: pretest
[11:20:13.629] <TB1> INFO: ----------------------------------------------------------------------
[11:20:13.629] <TB1> INFO: PixTestPretest::setVthrCompCalDel()
[11:20:13.629] <TB1> INFO: ----------------------------------------------------------------------
[11:20:13.720] <TB1> INFO: Expecting 231680 events.
[11:20:22.720] <TB1> INFO: 231680 events read in total (8444ms).
[11:20:22.724] <TB1> INFO: Test took 9092ms.
[11:20:23.036] <TB1> INFO: PixTestPretest::setVthrCompCalDel() done
[11:20:23.036] <TB1> INFO: CalDel: 121 137 142 128 115 140 123 129 142 143 130 114 116 118 139 143
[11:20:23.036] <TB1> INFO: VthrComp: 51 51 51 51 51 51 51 51 51 51 51 51 51 51 51 51
[11:20:23.077] <TB1> INFO: enter test to run
[11:20:23.077] <TB1> INFO: test: pretest no parameter change
[11:20:23.077] <TB1> INFO: running: pretest
[11:20:23.082] <TB1> INFO: write dac parameters into /home/Reception/DATA/KITAACHEN/M4630_Reception_2016-07-27_13h13m_1469618008//000_ReceptionTestBB4_p17//dacParameters_C0.dat
[11:20:23.087] <TB1> INFO: write dac parameters into /home/Reception/DATA/KITAACHEN/M4630_Reception_2016-07-27_13h13m_1469618008//000_ReceptionTestBB4_p17//dacParameters_C1.dat
[11:20:23.094] <TB1> INFO: write dac parameters into /home/Reception/DATA/KITAACHEN/M4630_Reception_2016-07-27_13h13m_1469618008//000_ReceptionTestBB4_p17//dacParameters_C2.dat
[11:20:23.099] <TB1> INFO: write dac parameters into /home/Reception/DATA/KITAACHEN/M4630_Reception_2016-07-27_13h13m_1469618008//000_ReceptionTestBB4_p17//dacParameters_C3.dat
[11:20:23.104] <TB1> INFO: write dac parameters into /home/Reception/DATA/KITAACHEN/M4630_Reception_2016-07-27_13h13m_1469618008//000_ReceptionTestBB4_p17//dacParameters_C4.dat
[11:20:23.109] <TB1> INFO: write dac parameters into /home/Reception/DATA/KITAACHEN/M4630_Reception_2016-07-27_13h13m_1469618008//000_ReceptionTestBB4_p17//dacParameters_C5.dat
[11:20:23.114] <TB1> INFO: write dac parameters into /home/Reception/DATA/KITAACHEN/M4630_Reception_2016-07-27_13h13m_1469618008//000_ReceptionTestBB4_p17//dacParameters_C6.dat
[11:20:23.120] <TB1> INFO: write dac parameters into /home/Reception/DATA/KITAACHEN/M4630_Reception_2016-07-27_13h13m_1469618008//000_ReceptionTestBB4_p17//dacParameters_C7.dat
[11:20:23.126] <TB1> INFO: write dac parameters into /home/Reception/DATA/KITAACHEN/M4630_Reception_2016-07-27_13h13m_1469618008//000_ReceptionTestBB4_p17//dacParameters_C8.dat
[11:20:23.131] <TB1> INFO: write dac parameters into /home/Reception/DATA/KITAACHEN/M4630_Reception_2016-07-27_13h13m_1469618008//000_ReceptionTestBB4_p17//dacParameters_C9.dat
[11:20:23.137] <TB1> INFO: write dac parameters into /home/Reception/DATA/KITAACHEN/M4630_Reception_2016-07-27_13h13m_1469618008//000_ReceptionTestBB4_p17//dacParameters_C10.dat
[11:20:23.143] <TB1> INFO: write dac parameters into /home/Reception/DATA/KITAACHEN/M4630_Reception_2016-07-27_13h13m_1469618008//000_ReceptionTestBB4_p17//dacParameters_C11.dat
[11:20:23.149] <TB1> INFO: write dac parameters into /home/Reception/DATA/KITAACHEN/M4630_Reception_2016-07-27_13h13m_1469618008//000_ReceptionTestBB4_p17//dacParameters_C12.dat
[11:20:23.154] <TB1> INFO: write dac parameters into /home/Reception/DATA/KITAACHEN/M4630_Reception_2016-07-27_13h13m_1469618008//000_ReceptionTestBB4_p17//dacParameters_C13.dat
[11:20:23.161] <TB1> INFO: write dac parameters into /home/Reception/DATA/KITAACHEN/M4630_Reception_2016-07-27_13h13m_1469618008//000_ReceptionTestBB4_p17//dacParameters_C14.dat
[11:20:23.167] <TB1> INFO: write dac parameters into /home/Reception/DATA/KITAACHEN/M4630_Reception_2016-07-27_13h13m_1469618008//000_ReceptionTestBB4_p17//dacParameters_C15.dat
[11:20:23.170] <TB1> INFO: enter test to run
[11:20:23.170] <TB1> INFO: test: PixelAlive no parameter change
[11:20:23.170] <TB1> INFO: running: pixelalive
[11:20:23.171] <TB1> INFO: ######################################################################
[11:20:23.171] <TB1> INFO: PixTestAlive::doTest()
[11:20:23.171] <TB1> INFO: ######################################################################
[11:20:23.173] <TB1> INFO: ----------------------------------------------------------------------
[11:20:23.173] <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)
[11:20:23.173] <TB1> INFO: ----------------------------------------------------------------------
[11:20:23.446] <TB1> INFO: Expecting 41600 events.
[11:20:26.860] <TB1> INFO: 41600 events read in total (2858ms).
[11:20:26.861] <TB1> INFO: Test took 3687ms.
[11:20:27.157] <TB1> INFO: PixTestAlive::aliveTest() done
[11:20:27.157] <TB1> INFO: number of dead pixels (per ROC): 0 0 0 0 0 0 0 2 1 0 0 0 0 0 0 0
[11:20:27.160] <TB1> INFO: ----------------------------------------------------------------------
[11:20:27.160] <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)
[11:20:27.161] <TB1> INFO: ----------------------------------------------------------------------
[11:20:27.438] <TB1> INFO: Expecting 41600 events.
[11:20:30.057] <TB1> INFO: 41600 events read in total (2063ms).
[11:20:30.057] <TB1> INFO: Test took 2894ms.
[11:20:30.057] <TB1> INFO: mask vs. old pixelAlive PixelAlive_C0_V0 .. PixelAlive_C15_V0
[11:20:30.356] <TB1> INFO: PixTestAlive::maskTest() done
[11:20:30.356] <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
[11:20:30.357] <TB1> INFO: ----------------------------------------------------------------------
[11:20:30.357] <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)
[11:20:30.357] <TB1> INFO: ----------------------------------------------------------------------
[11:20:30.619] <TB1> INFO: Expecting 41600 events.
[11:20:34.049] <TB1> INFO: 41600 events read in total (2874ms).
[11:20:34.049] <TB1> INFO: Test took 3690ms.
[11:20:34.334] <TB1> INFO: PixTestAlive::addressDecodingTest() done
[11:20:34.334] <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
[11:20:34.334] <TB1> INFO: PixTestAlive::doTest() done, duration: 11 seconds
[11:20:34.340] <TB1> INFO: enter test to run
[11:20:34.340] <TB1> INFO: test: BB no parameter change
[11:20:34.340] <TB1> INFO: running: bb
[11:20:34.341] <TB1> INFO: ######################################################################
[11:20:34.341] <TB1> INFO: PixTestBBMap::doTest() Ntrig = 5, VcalS = 250 (high range)
[11:20:34.341] <TB1> INFO: ######################################################################
[11:20:34.343] <TB1> INFO: ---> dac: VthrComp name: calSMap ntrig: 5 dacrange: 0 .. 149 (-1/-1) hits flags = 514 (plus default)
[11:20:34.354] <TB1> INFO: dacScan split into 1 runs with ntrig = 5
[11:20:34.354] <TB1> INFO: run 1 of 1
[11:20:34.618] <TB1> INFO: Expecting 3120000 events.
[11:21:19.457] <TB1> INFO: 1192455 events read in total (44283ms).
[11:22:04.175] <TB1> INFO: 2387355 events read in total (89001ms).
[11:22:31.156] <TB1> INFO: 3120000 events read in total (115983ms).
[11:22:31.202] <TB1> INFO: Test took 116849ms.
[11:22:53.205] <TB1> INFO: PixTestBBMap::doTest() done, duration: 138 seconds
[11:22:53.206] <TB1> INFO: number of dead bumps (per ROC): 1 0 0 0 0 0 0 2 0 0 0 0 0 0 0 0
[11:22:53.206] <TB1> INFO: separation cut (per ROC): 123 102 101 107 102 103 119 107 109 106 106 102 102 103 101 92
[11:22:53.276] <TB1> INFO: enter test to run
[11:22:53.276] <TB1> INFO: test: Readback no parameter change
[11:22:53.276] <TB1> INFO: running: readback
[11:22:53.276] <TB1> INFO: readReadbackCal: /home/Reception/DATA/KITAACHEN/M4630_Reception_2016-07-27_13h13m_1469618008//000_ReceptionTestBB4_p17//readbackCal_C0.dat .. /home/Reception/DATA/KITAACHEN/M4630_Reception_2016-07-27_13h13m_1469618008//000_ReceptionTestBB4_p17//readbackCal_C15.dat
[11:22:53.292] <TB1> INFO: readGainPedestalParameters /home/Reception/DATA/KITAACHEN/M4630_Reception_2016-07-27_13h13m_1469618008//000_ReceptionTestBB4_p17//phCalibrationFitErr_C0.dat .. /home/Reception/DATA/KITAACHEN/M4630_Reception_2016-07-27_13h13m_1469618008//000_ReceptionTestBB4_p17//phCalibrationFitErr_C15.dat
[11:22:53.292] <TB1> ERROR: <ConfigParameters.cc/readGainPedestalParameters:L996> cannot open /home/Reception/DATA/KITAACHEN/M4630_Reception_2016-07-27_13h13m_1469618008//000_ReceptionTestBB4_p17//phCalibrationFitErr_C0.dat for reading PH calibration constants
[11:22:53.292] <TB1> INFO: ######################################################################
[11:22:53.292] <TB1> INFO: PixTestReadback::doTest()
[11:22:53.292] <TB1> INFO: ######################################################################
[11:22:53.292] <TB1> INFO: ----------------------------------------------------------------------
[11:22:53.292] <TB1> INFO: PixTestReadback::CalibrateVd()
[11:22:53.292] <TB1> INFO: ----------------------------------------------------------------------
[11:23:02.512] <TB1> INFO: write readback calibration parameters into /home/Reception/DATA/KITAACHEN/M4630_Reception_2016-07-27_13h13m_1469618008//000_ReceptionTestBB4_p17//readbackCal_C0.dat
[11:23:02.518] <TB1> INFO: write readback calibration parameters into /home/Reception/DATA/KITAACHEN/M4630_Reception_2016-07-27_13h13m_1469618008//000_ReceptionTestBB4_p17//readbackCal_C1.dat
[11:23:02.523] <TB1> INFO: write readback calibration parameters into /home/Reception/DATA/KITAACHEN/M4630_Reception_2016-07-27_13h13m_1469618008//000_ReceptionTestBB4_p17//readbackCal_C2.dat
[11:23:02.529] <TB1> INFO: write readback calibration parameters into /home/Reception/DATA/KITAACHEN/M4630_Reception_2016-07-27_13h13m_1469618008//000_ReceptionTestBB4_p17//readbackCal_C3.dat
[11:23:02.534] <TB1> INFO: write readback calibration parameters into /home/Reception/DATA/KITAACHEN/M4630_Reception_2016-07-27_13h13m_1469618008//000_ReceptionTestBB4_p17//readbackCal_C4.dat
[11:23:02.539] <TB1> INFO: write readback calibration parameters into /home/Reception/DATA/KITAACHEN/M4630_Reception_2016-07-27_13h13m_1469618008//000_ReceptionTestBB4_p17//readbackCal_C5.dat
[11:23:02.544] <TB1> INFO: write readback calibration parameters into /home/Reception/DATA/KITAACHEN/M4630_Reception_2016-07-27_13h13m_1469618008//000_ReceptionTestBB4_p17//readbackCal_C6.dat
[11:23:02.549] <TB1> INFO: write readback calibration parameters into /home/Reception/DATA/KITAACHEN/M4630_Reception_2016-07-27_13h13m_1469618008//000_ReceptionTestBB4_p17//readbackCal_C7.dat
[11:23:02.554] <TB1> INFO: write readback calibration parameters into /home/Reception/DATA/KITAACHEN/M4630_Reception_2016-07-27_13h13m_1469618008//000_ReceptionTestBB4_p17//readbackCal_C8.dat
[11:23:02.559] <TB1> INFO: write readback calibration parameters into /home/Reception/DATA/KITAACHEN/M4630_Reception_2016-07-27_13h13m_1469618008//000_ReceptionTestBB4_p17//readbackCal_C9.dat
[11:23:02.564] <TB1> INFO: write readback calibration parameters into /home/Reception/DATA/KITAACHEN/M4630_Reception_2016-07-27_13h13m_1469618008//000_ReceptionTestBB4_p17//readbackCal_C10.dat
[11:23:02.570] <TB1> INFO: write readback calibration parameters into /home/Reception/DATA/KITAACHEN/M4630_Reception_2016-07-27_13h13m_1469618008//000_ReceptionTestBB4_p17//readbackCal_C11.dat
[11:23:02.576] <TB1> INFO: write readback calibration parameters into /home/Reception/DATA/KITAACHEN/M4630_Reception_2016-07-27_13h13m_1469618008//000_ReceptionTestBB4_p17//readbackCal_C12.dat
[11:23:02.581] <TB1> INFO: write readback calibration parameters into /home/Reception/DATA/KITAACHEN/M4630_Reception_2016-07-27_13h13m_1469618008//000_ReceptionTestBB4_p17//readbackCal_C13.dat
[11:23:02.586] <TB1> INFO: write readback calibration parameters into /home/Reception/DATA/KITAACHEN/M4630_Reception_2016-07-27_13h13m_1469618008//000_ReceptionTestBB4_p17//readbackCal_C14.dat
[11:23:02.591] <TB1> INFO: write readback calibration parameters into /home/Reception/DATA/KITAACHEN/M4630_Reception_2016-07-27_13h13m_1469618008//000_ReceptionTestBB4_p17//readbackCal_C15.dat
[11:23:02.619] <TB1> INFO: PixTestPattern:: pg_setup set to default.
[11:23:02.619] <TB1> INFO: ----------------------------------------------------------------------
[11:23:02.619] <TB1> INFO: PixTestReadback::CalibrateVa()
[11:23:02.619] <TB1> INFO: ----------------------------------------------------------------------
[11:23:11.905] <TB1> INFO: write readback calibration parameters into /home/Reception/DATA/KITAACHEN/M4630_Reception_2016-07-27_13h13m_1469618008//000_ReceptionTestBB4_p17//readbackCal_C0.dat
[11:23:11.911] <TB1> INFO: write readback calibration parameters into /home/Reception/DATA/KITAACHEN/M4630_Reception_2016-07-27_13h13m_1469618008//000_ReceptionTestBB4_p17//readbackCal_C1.dat
[11:23:11.917] <TB1> INFO: write readback calibration parameters into /home/Reception/DATA/KITAACHEN/M4630_Reception_2016-07-27_13h13m_1469618008//000_ReceptionTestBB4_p17//readbackCal_C2.dat
[11:23:11.924] <TB1> INFO: write readback calibration parameters into /home/Reception/DATA/KITAACHEN/M4630_Reception_2016-07-27_13h13m_1469618008//000_ReceptionTestBB4_p17//readbackCal_C3.dat
[11:23:11.930] <TB1> INFO: write readback calibration parameters into /home/Reception/DATA/KITAACHEN/M4630_Reception_2016-07-27_13h13m_1469618008//000_ReceptionTestBB4_p17//readbackCal_C4.dat
[11:23:11.935] <TB1> INFO: write readback calibration parameters into /home/Reception/DATA/KITAACHEN/M4630_Reception_2016-07-27_13h13m_1469618008//000_ReceptionTestBB4_p17//readbackCal_C5.dat
[11:23:11.940] <TB1> INFO: write readback calibration parameters into /home/Reception/DATA/KITAACHEN/M4630_Reception_2016-07-27_13h13m_1469618008//000_ReceptionTestBB4_p17//readbackCal_C6.dat
[11:23:11.946] <TB1> INFO: write readback calibration parameters into /home/Reception/DATA/KITAACHEN/M4630_Reception_2016-07-27_13h13m_1469618008//000_ReceptionTestBB4_p17//readbackCal_C7.dat
[11:23:11.952] <TB1> INFO: write readback calibration parameters into /home/Reception/DATA/KITAACHEN/M4630_Reception_2016-07-27_13h13m_1469618008//000_ReceptionTestBB4_p17//readbackCal_C8.dat
[11:23:11.959] <TB1> INFO: write readback calibration parameters into /home/Reception/DATA/KITAACHEN/M4630_Reception_2016-07-27_13h13m_1469618008//000_ReceptionTestBB4_p17//readbackCal_C9.dat
[11:23:11.966] <TB1> INFO: write readback calibration parameters into /home/Reception/DATA/KITAACHEN/M4630_Reception_2016-07-27_13h13m_1469618008//000_ReceptionTestBB4_p17//readbackCal_C10.dat
[11:23:11.973] <TB1> INFO: write readback calibration parameters into /home/Reception/DATA/KITAACHEN/M4630_Reception_2016-07-27_13h13m_1469618008//000_ReceptionTestBB4_p17//readbackCal_C11.dat
[11:23:11.978] <TB1> INFO: write readback calibration parameters into /home/Reception/DATA/KITAACHEN/M4630_Reception_2016-07-27_13h13m_1469618008//000_ReceptionTestBB4_p17//readbackCal_C12.dat
[11:23:11.985] <TB1> INFO: write readback calibration parameters into /home/Reception/DATA/KITAACHEN/M4630_Reception_2016-07-27_13h13m_1469618008//000_ReceptionTestBB4_p17//readbackCal_C13.dat
[11:23:11.990] <TB1> INFO: write readback calibration parameters into /home/Reception/DATA/KITAACHEN/M4630_Reception_2016-07-27_13h13m_1469618008//000_ReceptionTestBB4_p17//readbackCal_C14.dat
[11:23:11.996] <TB1> INFO: write readback calibration parameters into /home/Reception/DATA/KITAACHEN/M4630_Reception_2016-07-27_13h13m_1469618008//000_ReceptionTestBB4_p17//readbackCal_C15.dat
[11:23:12.035] <TB1> INFO: PixTestPattern:: pg_setup set to default.
[11:23:12.035] <TB1> INFO: ----------------------------------------------------------------------
[11:23:12.035] <TB1> INFO: PixTestReadback::readbackVbg()
[11:23:12.035] <TB1> INFO: ----------------------------------------------------------------------
[11:23:19.159] <TB1> INFO: PixTestPattern:: pg_setup set to default.
[11:23:19.160] <TB1> INFO: ----------------------------------------------------------------------
[11:23:19.160] <TB1> INFO: PixTestReadback::getCalibratedVbg()
[11:23:19.160] <TB1> INFO: ----------------------------------------------------------------------
[11:23:19.160] <TB1> INFO: Vbg will be calibrated using Vd calibration
[11:23:19.160] <TB1> INFO: /*/*/*/*::: ROC 0: uncalibrated Vbg = 156.7calibrated Vbg = 1.2186 :::*/*/*/*/
[11:23:19.160] <TB1> INFO: /*/*/*/*::: ROC 1: uncalibrated Vbg = 154.5calibrated Vbg = 1.22497 :::*/*/*/*/
[11:23:19.160] <TB1> INFO: /*/*/*/*::: ROC 2: uncalibrated Vbg = 155.1calibrated Vbg = 1.22922 :::*/*/*/*/
[11:23:19.160] <TB1> INFO: /*/*/*/*::: ROC 3: uncalibrated Vbg = 154.1calibrated Vbg = 1.23631 :::*/*/*/*/
[11:23:19.160] <TB1> INFO: /*/*/*/*::: ROC 4: uncalibrated Vbg = 161.1calibrated Vbg = 1.24154 :::*/*/*/*/
[11:23:19.160] <TB1> INFO: /*/*/*/*::: ROC 5: uncalibrated Vbg = 160.5calibrated Vbg = 1.24232 :::*/*/*/*/
[11:23:19.160] <TB1> INFO: /*/*/*/*::: ROC 6: uncalibrated Vbg = 154.9calibrated Vbg = 1.24293 :::*/*/*/*/
[11:23:19.160] <TB1> INFO: /*/*/*/*::: ROC 7: uncalibrated Vbg = 143.8calibrated Vbg = 1.24581 :::*/*/*/*/
[11:23:19.160] <TB1> INFO: /*/*/*/*::: ROC 8: uncalibrated Vbg = 152.2calibrated Vbg = 1.24339 :::*/*/*/*/
[11:23:19.160] <TB1> INFO: /*/*/*/*::: ROC 9: uncalibrated Vbg = 154.6calibrated Vbg = 1.24139 :::*/*/*/*/
[11:23:19.160] <TB1> INFO: /*/*/*/*::: ROC 10: uncalibrated Vbg = 154.7calibrated Vbg = 1.24039 :::*/*/*/*/
[11:23:19.160] <TB1> INFO: /*/*/*/*::: ROC 11: uncalibrated Vbg = 154.5calibrated Vbg = 1.23234 :::*/*/*/*/
[11:23:19.160] <TB1> INFO: /*/*/*/*::: ROC 12: uncalibrated Vbg = 152calibrated Vbg = 1.22742 :::*/*/*/*/
[11:23:19.160] <TB1> INFO: /*/*/*/*::: ROC 13: uncalibrated Vbg = 147.7calibrated Vbg = 1.22661 :::*/*/*/*/
[11:23:19.160] <TB1> INFO: /*/*/*/*::: ROC 14: uncalibrated Vbg = 152.1calibrated Vbg = 1.2333 :::*/*/*/*/
[11:23:19.160] <TB1> INFO: /*/*/*/*::: ROC 15: uncalibrated Vbg = 151.9calibrated Vbg = 1.22385 :::*/*/*/*/
[11:23:19.164] <TB1> INFO: ----------------------------------------------------------------------
[11:23:19.164] <TB1> INFO: PixTestReadback::CalibrateIa()
[11:23:19.164] <TB1> INFO: ----------------------------------------------------------------------
[11:25:51.739] <TB1> INFO: write readback calibration parameters into /home/Reception/DATA/KITAACHEN/M4630_Reception_2016-07-27_13h13m_1469618008//000_ReceptionTestBB4_p17//readbackCal_C0.dat
[11:25:51.744] <TB1> INFO: write readback calibration parameters into /home/Reception/DATA/KITAACHEN/M4630_Reception_2016-07-27_13h13m_1469618008//000_ReceptionTestBB4_p17//readbackCal_C1.dat
[11:25:51.749] <TB1> INFO: write readback calibration parameters into /home/Reception/DATA/KITAACHEN/M4630_Reception_2016-07-27_13h13m_1469618008//000_ReceptionTestBB4_p17//readbackCal_C2.dat
[11:25:51.754] <TB1> INFO: write readback calibration parameters into /home/Reception/DATA/KITAACHEN/M4630_Reception_2016-07-27_13h13m_1469618008//000_ReceptionTestBB4_p17//readbackCal_C3.dat
[11:25:51.759] <TB1> INFO: write readback calibration parameters into /home/Reception/DATA/KITAACHEN/M4630_Reception_2016-07-27_13h13m_1469618008//000_ReceptionTestBB4_p17//readbackCal_C4.dat
[11:25:51.764] <TB1> INFO: write readback calibration parameters into /home/Reception/DATA/KITAACHEN/M4630_Reception_2016-07-27_13h13m_1469618008//000_ReceptionTestBB4_p17//readbackCal_C5.dat
[11:25:51.769] <TB1> INFO: write readback calibration parameters into /home/Reception/DATA/KITAACHEN/M4630_Reception_2016-07-27_13h13m_1469618008//000_ReceptionTestBB4_p17//readbackCal_C6.dat
[11:25:51.774] <TB1> INFO: write readback calibration parameters into /home/Reception/DATA/KITAACHEN/M4630_Reception_2016-07-27_13h13m_1469618008//000_ReceptionTestBB4_p17//readbackCal_C7.dat
[11:25:51.779] <TB1> INFO: write readback calibration parameters into /home/Reception/DATA/KITAACHEN/M4630_Reception_2016-07-27_13h13m_1469618008//000_ReceptionTestBB4_p17//readbackCal_C8.dat
[11:25:51.785] <TB1> INFO: write readback calibration parameters into /home/Reception/DATA/KITAACHEN/M4630_Reception_2016-07-27_13h13m_1469618008//000_ReceptionTestBB4_p17//readbackCal_C9.dat
[11:25:51.790] <TB1> INFO: write readback calibration parameters into /home/Reception/DATA/KITAACHEN/M4630_Reception_2016-07-27_13h13m_1469618008//000_ReceptionTestBB4_p17//readbackCal_C10.dat
[11:25:51.795] <TB1> INFO: write readback calibration parameters into /home/Reception/DATA/KITAACHEN/M4630_Reception_2016-07-27_13h13m_1469618008//000_ReceptionTestBB4_p17//readbackCal_C11.dat
[11:25:51.803] <TB1> INFO: write readback calibration parameters into /home/Reception/DATA/KITAACHEN/M4630_Reception_2016-07-27_13h13m_1469618008//000_ReceptionTestBB4_p17//readbackCal_C12.dat
[11:25:51.808] <TB1> INFO: write readback calibration parameters into /home/Reception/DATA/KITAACHEN/M4630_Reception_2016-07-27_13h13m_1469618008//000_ReceptionTestBB4_p17//readbackCal_C13.dat
[11:25:51.815] <TB1> INFO: write readback calibration parameters into /home/Reception/DATA/KITAACHEN/M4630_Reception_2016-07-27_13h13m_1469618008//000_ReceptionTestBB4_p17//readbackCal_C14.dat
[11:25:51.821] <TB1> INFO: write readback calibration parameters into /home/Reception/DATA/KITAACHEN/M4630_Reception_2016-07-27_13h13m_1469618008//000_ReceptionTestBB4_p17//readbackCal_C15.dat
[11:25:51.859] <TB1> INFO: PixTestPattern:: pg_setup set to default.
[11:25:51.862] <TB1> INFO: PixTestReadback::doTest() done
[11:25:51.886] <TB1> INFO: enter test to run
[11:25:51.886] <TB1> INFO: test: BB4 no parameter change
[11:25:51.886] <TB1> INFO: running: bb4
[11:25:51.886] <TB1> INFO: PixTestBB4Map::doTest() ntrig = 100
[11:25:51.886] <TB1> INFO: VthrComp (min,max,step) = 70 130 5
[11:25:51.886] <TB1> INFO: CalDel (min,max,step) = 80 200 10
[11:25:51.886] <TB1> INFO: Vcal = 255
[11:25:51.886] <TB1> INFO: Cut = 0.5
[11:25:51.886] <TB1> INFO: flag 2
[11:25:51.887] <TB1> INFO: CtrlReg 4 (large Vcal)
[11:25:51.887] <TB1> INFO: saving VthrComp and CalDel before running tests)
[11:25:51.887] <TB1> INFO: 0 130 0
[11:25:51.887] <TB1> INFO: 1 130 0
[11:25:51.887] <TB1> INFO: 2 130 0
[11:25:51.887] <TB1> INFO: 3 130 0
[11:25:51.887] <TB1> INFO: 4 130 0
[11:25:51.887] <TB1> INFO: 5 130 0
[11:25:51.887] <TB1> INFO: 6 130 0
[11:25:51.887] <TB1> INFO: 7 130 0
[11:25:51.887] <TB1> INFO: 8 130 0
[11:25:51.887] <TB1> INFO: 9 130 0
[11:25:51.887] <TB1> INFO: 10 130 0
[11:25:51.887] <TB1> INFO: 11 130 0
[11:25:51.887] <TB1> INFO: 12 130 0
[11:25:51.887] <TB1> INFO: 13 130 0
[11:25:51.887] <TB1> INFO: 14 130 0
[11:25:51.887] <TB1> INFO: 15 130 0
[11:25:52.155] <TB1> INFO: Expecting 416000 events.
[11:25:56.806] <TB1> ERROR: <datapipe.cc/CheckEventValidity:L507> Channel 0 Number of ROCs (0) != Token Chain Length (8)
[11:25:56.835] <TB1> ERROR: <datapipe.cc/CheckEventID:L469> Channel 0 Event ID mismatch: local ID (16) != TBM ID (2)
[11:26:01.549] <TB1> INFO: 416000 events read in total (8838ms).
[11:26:01.549] <TB1> INFO: Test took 9659ms.
[11:26:01.550] <TB1> INFO: starting loop over VthrComp
[11:26:02.120] <TB1> INFO: Expecting 416000 events.
[11:26:11.540] <TB1> INFO: 416000 events read in total (8865ms).
[11:26:11.541] <TB1> INFO: Test took 9719ms.
[11:26:11.541] <TB1> INFO: got noisemap
[11:26:11.814] <TB1> INFO: ROC 0, integral 0
[11:26:11.815] <TB1> INFO: ROC 1, integral 0
[11:26:11.815] <TB1> INFO: ROC 2, integral 0
[11:26:11.815] <TB1> INFO: ROC 3, integral 0
[11:26:11.815] <TB1> INFO: ROC 4, integral 0
[11:26:11.815] <TB1> INFO: ROC 5, integral 0
[11:26:11.815] <TB1> INFO: ROC 6, integral 0
[11:26:11.815] <TB1> INFO: ROC 7, integral 0
[11:26:11.816] <TB1> INFO: ROC 8, integral 0
[11:26:11.816] <TB1> INFO: ROC 9, integral 0
[11:26:11.816] <TB1> INFO: ROC 10, integral 0
[11:26:11.816] <TB1> INFO: ROC 11, integral 0
[11:26:11.816] <TB1> INFO: ROC 12, integral 0
[11:26:11.816] <TB1> INFO: ROC 13, integral 0
[11:26:11.816] <TB1> INFO: ROC 14, integral 0
[11:26:11.817] <TB1> INFO: ROC 15, integral 0
[11:26:11.817] <TB1> INFO: VthrComp = 70 done
[11:26:12.085] <TB1> INFO: Expecting 416000 events.
[11:26:21.498] <TB1> INFO: 416000 events read in total (8857ms).
[11:26:21.498] <TB1> INFO: Test took 9679ms.
[11:26:21.499] <TB1> INFO: got noisemap
[11:26:21.769] <TB1> INFO: ROC 0, integral 0
[11:26:21.770] <TB1> INFO: ROC 1, integral 0
[11:26:21.770] <TB1> INFO: ROC 2, integral 0
[11:26:21.770] <TB1> INFO: ROC 3, integral 0
[11:26:21.770] <TB1> INFO: ROC 4, integral 0
[11:26:21.770] <TB1> INFO: ROC 5, integral 0
[11:26:21.771] <TB1> INFO: ROC 6, integral 0
[11:26:21.771] <TB1> INFO: ROC 7, integral 0
[11:26:21.771] <TB1> INFO: ROC 8, integral 0
[11:26:21.771] <TB1> INFO: ROC 9, integral 0
[11:26:21.771] <TB1> INFO: ROC 10, integral 0
[11:26:21.772] <TB1> INFO: ROC 11, integral 0
[11:26:21.772] <TB1> INFO: ROC 12, integral 0
[11:26:21.772] <TB1> INFO: ROC 13, integral 0
[11:26:21.772] <TB1> INFO: ROC 14, integral 0
[11:26:21.773] <TB1> INFO: ROC 15, integral 0
[11:26:21.773] <TB1> INFO: VthrComp = 75 done
[11:26:22.049] <TB1> INFO: Expecting 416000 events.
[11:26:31.458] <TB1> INFO: 416000 events read in total (8854ms).
[11:26:31.459] <TB1> INFO: Test took 9681ms.
[11:26:31.459] <TB1> INFO: got noisemap
[11:26:31.731] <TB1> INFO: ROC 0, integral 0
[11:26:31.731] <TB1> INFO: ROC 1, integral 0
[11:26:31.731] <TB1> INFO: ROC 2, integral 0
[11:26:31.731] <TB1> INFO: ROC 3, integral 0
[11:26:31.731] <TB1> INFO: ROC 4, integral 0
[11:26:31.731] <TB1> INFO: ROC 5, integral 0
[11:26:31.731] <TB1> INFO: ROC 6, integral 0
[11:26:31.731] <TB1> INFO: ROC 7, integral 0
[11:26:31.731] <TB1> INFO: ROC 8, integral 0
[11:26:31.731] <TB1> INFO: ROC 9, integral 0
[11:26:31.731] <TB1> INFO: ROC 10, integral 0
[11:26:31.731] <TB1> INFO: ROC 11, integral 0
[11:26:31.732] <TB1> INFO: ROC 12, integral 0
[11:26:31.732] <TB1> INFO: ROC 13, integral 0
[11:26:31.732] <TB1> INFO: ROC 14, integral 0
[11:26:31.732] <TB1> INFO: ROC 15, integral 0
[11:26:31.732] <TB1> INFO: VthrComp = 80 done
[11:26:31.999] <TB1> INFO: Expecting 416000 events.
[11:26:41.410] <TB1> INFO: 416000 events read in total (8855ms).
[11:26:41.411] <TB1> INFO: Test took 9677ms.
[11:26:41.411] <TB1> INFO: got noisemap
[11:26:41.684] <TB1> INFO: ROC 0, integral 0
[11:26:41.684] <TB1> INFO: ROC 1, integral 0
[11:26:41.684] <TB1> INFO: ROC 2, integral 0
[11:26:41.684] <TB1> INFO: ROC 3, integral 0
[11:26:41.685] <TB1> INFO: ROC 4, integral 0
[11:26:41.685] <TB1> INFO: ROC 5, integral 0
[11:26:41.685] <TB1> INFO: ROC 6, integral 0
[11:26:41.685] <TB1> INFO: ROC 7, integral 0
[11:26:41.685] <TB1> INFO: ROC 8, integral 0
[11:26:41.685] <TB1> INFO: ROC 9, integral 0
[11:26:41.685] <TB1> INFO: ROC 10, integral 0
[11:26:41.685] <TB1> INFO: ROC 11, integral 0
[11:26:41.685] <TB1> INFO: ROC 12, integral 0
[11:26:41.685] <TB1> INFO: ROC 13, integral 0
[11:26:41.685] <TB1> INFO: ROC 14, integral 0
[11:26:41.685] <TB1> INFO: ROC 15, integral 0
[11:26:41.685] <TB1> INFO: VthrComp = 85 done
[11:26:41.980] <TB1> INFO: Expecting 416000 events.
[11:26:51.329] <TB1> INFO: 416000 events read in total (8793ms).
[11:26:51.329] <TB1> INFO: Test took 9641ms.
[11:26:51.329] <TB1> INFO: got noisemap
[11:26:51.603] <TB1> INFO: ROC 0, integral 0
[11:26:51.603] <TB1> INFO: ROC 1, integral 0
[11:26:51.603] <TB1> INFO: ROC 2, integral 0
[11:26:51.603] <TB1> INFO: ROC 3, integral 0
[11:26:51.603] <TB1> INFO: ROC 4, integral 0
[11:26:51.603] <TB1> INFO: ROC 5, integral 0
[11:26:51.603] <TB1> INFO: ROC 6, integral 0
[11:26:51.603] <TB1> INFO: ROC 7, integral 0
[11:26:51.604] <TB1> INFO: ROC 8, integral 0
[11:26:51.604] <TB1> INFO: ROC 9, integral 0
[11:26:51.604] <TB1> INFO: ROC 10, integral 0
[11:26:51.604] <TB1> INFO: ROC 11, integral 0
[11:26:51.604] <TB1> INFO: ROC 12, integral 0
[11:26:51.604] <TB1> INFO: ROC 13, integral 0
[11:26:51.604] <TB1> INFO: ROC 14, integral 0
[11:26:51.604] <TB1> INFO: ROC 15, integral 0
[11:26:51.604] <TB1> INFO: VthrComp = 90 done
[11:26:51.870] <TB1> INFO: Expecting 416000 events.
[11:27:01.291] <TB1> INFO: 416000 events read in total (8865ms).
[11:27:01.292] <TB1> INFO: Test took 9685ms.
[11:27:01.292] <TB1> INFO: got noisemap
[11:27:01.568] <TB1> INFO: ROC 0, integral 0
[11:27:01.568] <TB1> INFO: ROC 1, integral 0
[11:27:01.568] <TB1> INFO: ROC 2, integral 0
[11:27:01.568] <TB1> INFO: ROC 3, integral 0
[11:27:01.569] <TB1> INFO: ROC 4, integral 0
[11:27:01.569] <TB1> INFO: ROC 5, integral 0
[11:27:01.569] <TB1> INFO: ROC 6, integral 0
[11:27:01.569] <TB1> INFO: ROC 7, integral 0
[11:27:01.569] <TB1> INFO: ROC 8, integral 0
[11:27:01.569] <TB1> INFO: ROC 9, integral 0
[11:27:01.570] <TB1> INFO: ROC 10, integral 0
[11:27:01.570] <TB1> INFO: ROC 11, integral 0
[11:27:01.570] <TB1> INFO: ROC 12, integral 0
[11:27:01.570] <TB1> INFO: ROC 13, integral 0
[11:27:01.570] <TB1> INFO: ROC 14, integral 0
[11:27:01.571] <TB1> INFO: ROC 15, integral 0
[11:27:01.571] <TB1> INFO: VthrComp = 95 done
[11:27:01.846] <TB1> INFO: Expecting 416000 events.
[11:27:11.150] <TB1> INFO: 416000 events read in total (8748ms).
[11:27:11.151] <TB1> INFO: Test took 9576ms.
[11:27:11.151] <TB1> INFO: got noisemap
[11:27:11.427] <TB1> INFO: ROC 0, integral 0
[11:27:11.427] <TB1> INFO: ROC 1, integral 0
[11:27:11.427] <TB1> INFO: ROC 2, integral 0
[11:27:11.427] <TB1> INFO: ROC 3, integral 0
[11:27:11.427] <TB1> INFO: ROC 4, integral 0
[11:27:11.427] <TB1> INFO: ROC 5, integral 0
[11:27:11.427] <TB1> INFO: ROC 6, integral 0
[11:27:11.427] <TB1> INFO: ROC 7, integral 0
[11:27:11.427] <TB1> INFO: ROC 8, integral 1
[11:27:11.428] <TB1> INFO: ROC 9, integral 2
[11:27:11.428] <TB1> INFO: ROC 10, integral 0
[11:27:11.428] <TB1> INFO: ROC 11, integral 0
[11:27:11.428] <TB1> INFO: ROC 12, integral 6
[11:27:11.428] <TB1> INFO: ROC 13, integral 0
[11:27:11.428] <TB1> INFO: ROC 14, integral 3
[11:27:11.428] <TB1> INFO: ROC 15, integral 0
[11:27:11.428] <TB1> INFO: VthrComp = 100 done
[11:27:11.696] <TB1> INFO: Expecting 416000 events.
[11:27:21.053] <TB1> INFO: 416000 events read in total (8801ms).
[11:27:21.055] <TB1> INFO: Test took 9624ms.
[11:27:21.056] <TB1> INFO: got noisemap
[11:27:21.329] <TB1> INFO: ROC 0, integral 0
[11:27:21.329] <TB1> INFO: ROC 1, integral 0
[11:27:21.329] <TB1> INFO: ROC 2, integral 2
[11:27:21.329] <TB1> INFO: ROC 3, integral 0
[11:27:21.329] <TB1> INFO: ROC 4, integral 0
[11:27:21.329] <TB1> INFO: ROC 5, integral 0
[11:27:21.329] <TB1> INFO: ROC 6, integral 0
[11:27:21.329] <TB1> INFO: ROC 7, integral 0
[11:27:21.329] <TB1> INFO: ROC 8, integral 3
[11:27:21.329] <TB1> INFO: ROC 9, integral 15
[11:27:21.329] <TB1> INFO: ROC #9: threshold found
[11:27:21.329] <TB1> INFO: ROC 10, integral 0
[11:27:21.329] <TB1> INFO: ROC 11, integral 0
[11:27:21.329] <TB1> INFO: ROC 12, integral 40
[11:27:21.329] <TB1> INFO: ROC #12: threshold found
[11:27:21.329] <TB1> INFO: ROC 13, integral 0
[11:27:21.330] <TB1> INFO: ROC 14, integral 27
[11:27:21.330] <TB1> INFO: ROC #14: threshold found
[11:27:21.330] <TB1> INFO: ROC 15, integral 25
[11:27:21.330] <TB1> INFO: ROC #15: threshold found
[11:27:21.330] <TB1> INFO: VthrComp = 105 done
[11:27:21.596] <TB1> INFO: Expecting 416000 events.
[11:27:30.949] <TB1> INFO: 416000 events read in total (8798ms).
[11:27:30.949] <TB1> INFO: Test took 9618ms.
[11:27:30.950] <TB1> INFO: got noisemap
[11:27:31.225] <TB1> INFO: ROC 0, integral 0
[11:27:31.225] <TB1> INFO: ROC 1, integral 1
[11:27:31.225] <TB1> INFO: ROC 2, integral 47
[11:27:31.225] <TB1> INFO: ROC #2: threshold found
[11:27:31.225] <TB1> INFO: ROC 3, integral 0
[11:27:31.225] <TB1> INFO: ROC 4, integral 0
[11:27:31.225] <TB1> INFO: ROC 5, integral 2
[11:27:31.226] <TB1> INFO: ROC 6, integral 0
[11:27:31.226] <TB1> INFO: ROC 7, integral 4
[11:27:31.226] <TB1> INFO: ROC 8, integral 49
[11:27:31.226] <TB1> INFO: ROC #8: threshold found
[11:27:31.226] <TB1> INFO: ROC 10, integral 3
[11:27:31.226] <TB1> INFO: ROC 11, integral 7
[11:27:31.226] <TB1> INFO: ROC 13, integral 3
[11:27:31.227] <TB1> INFO: VthrComp = 110 done
[11:27:31.506] <TB1> INFO: Expecting 416000 events.
[11:27:40.938] <TB1> INFO: 416000 events read in total (8876ms).
[11:27:40.939] <TB1> INFO: Test took 9709ms.
[11:27:40.940] <TB1> INFO: got noisemap
[11:27:41.212] <TB1> INFO: ROC 0, integral 0
[11:27:41.212] <TB1> INFO: ROC 1, integral 40
[11:27:41.212] <TB1> INFO: ROC #1: threshold found
[11:27:41.212] <TB1> INFO: ROC 3, integral 4
[11:27:41.212] <TB1> INFO: ROC 4, integral 62
[11:27:41.212] <TB1> INFO: ROC #4: threshold found
[11:27:41.212] <TB1> INFO: ROC 5, integral 125
[11:27:41.213] <TB1> INFO: ROC #5: threshold found
[11:27:41.213] <TB1> INFO: ROC 6, integral 1
[11:27:41.213] <TB1> INFO: ROC 7, integral 203
[11:27:41.213] <TB1> INFO: ROC #7: threshold found
[11:27:41.213] <TB1> INFO: ROC 10, integral 180
[11:27:41.213] <TB1> INFO: ROC #10: threshold found
[11:27:41.213] <TB1> INFO: ROC 11, integral 249
[11:27:41.213] <TB1> INFO: ROC #11: threshold found
[11:27:41.213] <TB1> INFO: ROC 13, integral 161
[11:27:41.213] <TB1> INFO: ROC #13: threshold found
[11:27:41.213] <TB1> INFO: VthrComp = 115 done
[11:27:41.486] <TB1> INFO: Expecting 416000 events.
[11:27:50.894] <TB1> INFO: 416000 events read in total (8852ms).
[11:27:50.895] <TB1> INFO: Test took 9678ms.
[11:27:50.897] <TB1> INFO: got noisemap
[11:27:51.164] <TB1> INFO: ROC 0, integral 2
[11:27:51.164] <TB1> INFO: ROC 3, integral 33
[11:27:51.164] <TB1> INFO: ROC #3: threshold found
[11:27:51.164] <TB1> INFO: ROC 6, integral 3
[11:27:51.164] <TB1> INFO: VthrComp = 120 done
[11:27:51.437] <TB1> INFO: Expecting 416000 events.
[11:28:00.813] <TB1> INFO: 416000 events read in total (8820ms).
[11:28:00.814] <TB1> INFO: Test took 9647ms.
[11:28:00.816] <TB1> INFO: got noisemap
[11:28:01.083] <TB1> INFO: ROC 0, integral 42
[11:28:01.083] <TB1> INFO: ROC #0: threshold found
[11:28:01.083] <TB1> INFO: ROC 6, integral 47
[11:28:01.083] <TB1> INFO: ROC #6: threshold found
[11:28:01.083] <TB1> INFO: VthrComp = 125 done
[11:28:01.083] <TB1> INFO: Minimal threshold for all ROCs found
[11:28:01.085] <TB1> INFO: resulting noise thresholds per ROC = 120 110 105 115 110 110 120 110 105 100 110 110 100 110 100 100
[11:28:01.085] <TB1> INFO: threshold scan done
[11:28:01.357] <TB1> INFO: Expecting 416000 events.
[11:28:11.070] <TB1> INFO: 416000 events read in total (9157ms).
[11:28:11.072] <TB1> INFO: Test took 9982ms.
[11:28:11.329] <TB1> INFO: ROC 0, missing bumps = 4137, total number of good bumps = 23
[11:28:11.332] <TB1> INFO: ROC 1, missing bumps = 4160, total number of good bumps = 0
[11:28:11.335] <TB1> INFO: ROC 2, missing bumps = 4160, total number of good bumps = 0
[11:28:11.337] <TB1> INFO: ROC 3, missing bumps = 4160, total number of good bumps = 0
[11:28:11.339] <TB1> INFO: ROC 4, missing bumps = 3843, total number of good bumps = 317
[11:28:11.341] <TB1> INFO: ROC 5, missing bumps = 4160, total number of good bumps = 0
[11:28:11.343] <TB1> INFO: ROC 6, missing bumps = 4159, total number of good bumps = 1
[11:28:11.345] <TB1> INFO: ROC 7, missing bumps = 4160, total number of good bumps = 0
[11:28:11.347] <TB1> INFO: ROC 8, missing bumps = 4160, total number of good bumps = 0
[11:28:11.349] <TB1> INFO: ROC 9, missing bumps = 4160, total number of good bumps = 0
[11:28:11.351] <TB1> INFO: ROC 10, missing bumps = 4160, total number of good bumps = 0
[11:28:11.353] <TB1> INFO: ROC 11, missing bumps = 3451, total number of good bumps = 709
[11:28:11.355] <TB1> INFO: ROC 12, missing bumps = 3569, total number of good bumps = 591
[11:28:11.357] <TB1> INFO: ROC 13, missing bumps = 4149, total number of good bumps = 11
[11:28:11.359] <TB1> INFO: ROC 14, missing bumps = 4160, total number of good bumps = 0
[11:28:11.361] <TB1> INFO: ROC 15, missing bumps = 4160, total number of good bumps = 0
[11:28:11.361] <TB1> INFO: CalDel = 80 done
[11:28:11.631] <TB1> INFO: Expecting 416000 events.
[11:28:22.690] <TB1> INFO: 416000 events read in total (10503ms).
[11:28:22.691] <TB1> INFO: Test took 11326ms.
[11:28:22.911] <TB1> INFO: ROC 0, missing bumps = 2309, total number of good bumps = 1851
[11:28:22.913] <TB1> INFO: ROC 1, missing bumps = 4160, total number of good bumps = 0
[11:28:22.914] <TB1> INFO: ROC 2, missing bumps = 4160, total number of good bumps = 0
[11:28:22.916] <TB1> INFO: ROC 3, missing bumps = 4067, total number of good bumps = 93
[11:28:22.917] <TB1> INFO: ROC 4, missing bumps = 541, total number of good bumps = 3619
[11:28:22.918] <TB1> INFO: ROC 5, missing bumps = 4160, total number of good bumps = 0
[11:28:22.919] <TB1> INFO: ROC 6, missing bumps = 3331, total number of good bumps = 829
[11:28:22.920] <TB1> INFO: ROC 7, missing bumps = 4144, total number of good bumps = 16
[11:28:22.921] <TB1> INFO: ROC 8, missing bumps = 4151, total number of good bumps = 9
[11:28:22.923] <TB1> INFO: ROC 9, missing bumps = 4160, total number of good bumps = 0
[11:28:22.924] <TB1> INFO: ROC 10, missing bumps = 4147, total number of good bumps = 13
[11:28:22.925] <TB1> INFO: ROC 11, missing bumps = 180, total number of good bumps = 3980
[11:28:22.926] <TB1> INFO: ROC 12, missing bumps = 400, total number of good bumps = 3760
[11:28:22.927] <TB1> INFO: ROC 13, missing bumps = 1980, total number of good bumps = 2180
[11:28:22.929] <TB1> INFO: ROC 14, missing bumps = 4160, total number of good bumps = 0
[11:28:22.930] <TB1> INFO: ROC 15, missing bumps = 4160, total number of good bumps = 0
[11:28:22.930] <TB1> INFO: CalDel = 90 done
[11:28:23.213] <TB1> INFO: Expecting 416000 events.
[11:28:36.098] <TB1> INFO: 416000 events read in total (12330ms).
[11:28:36.099] <TB1> INFO: Test took 13167ms.
[11:28:36.270] <TB1> INFO: ROC 0, missing bumps = 28, total number of good bumps = 4132
[11:28:36.271] <TB1> INFO: ROC 1, missing bumps = 3595, total number of good bumps = 565
[11:28:36.272] <TB1> INFO: ROC 2, missing bumps = 3942, total number of good bumps = 218
[11:28:36.274] <TB1> INFO: ROC 3, missing bumps = 454, total number of good bumps = 3706
[11:28:36.275] <TB1> INFO: ROC 4, missing bumps = 1, total number of good bumps = 4159
[11:28:36.276] <TB1> INFO: ROC 5, missing bumps = 3667, total number of good bumps = 493
[11:28:36.278] <TB1> INFO: ROC 6, missing bumps = 53, total number of good bumps = 4107
[11:28:36.279] <TB1> INFO: ROC 7, missing bumps = 1620, total number of good bumps = 2540
[11:28:36.280] <TB1> INFO: ROC 8, missing bumps = 3425, total number of good bumps = 735
[11:28:36.281] <TB1> INFO: ROC 9, missing bumps = 4115, total number of good bumps = 45
[11:28:36.282] <TB1> INFO: ROC 10, missing bumps = 1865, total number of good bumps = 2295
[11:28:36.283] <TB1> INFO: ROC 11, missing bumps = 6, total number of good bumps = 4154
[11:28:36.285] <TB1> INFO: ROC 12, missing bumps = 13, total number of good bumps = 4147
[11:28:36.286] <TB1> INFO: ROC 13, missing bumps = 13, total number of good bumps = 4147
[11:28:36.287] <TB1> INFO: ROC 14, missing bumps = 2749, total number of good bumps = 1411
[11:28:36.288] <TB1> INFO: ROC 15, missing bumps = 4160, total number of good bumps = 0
[11:28:36.288] <TB1> INFO: CalDel = 100 done
[11:28:36.569] <TB1> INFO: Expecting 416000 events.
[11:28:51.382] <TB1> INFO: 416000 events read in total (14258ms).
[11:28:51.383] <TB1> INFO: Test took 15092ms.
[11:28:51.552] <TB1> INFO: ROC 0, missing bumps = 3, total number of good bumps = 4157
[11:28:51.553] <TB1> INFO: ROC 1, missing bumps = 351, total number of good bumps = 3809
[11:28:51.554] <TB1> INFO: ROC 2, missing bumps = 835, total number of good bumps = 3325
[11:28:51.555] <TB1> INFO: ROC 3, missing bumps = 12, total number of good bumps = 4148
[11:28:51.556] <TB1> INFO: ROC 4, missing bumps = 0, total number of good bumps = 4160
[11:28:51.557] <TB1> INFO: ROC 5, missing bumps = 395, total number of good bumps = 3765
[11:28:51.559] <TB1> INFO: ROC 6, missing bumps = 4, total number of good bumps = 4156
[11:28:51.560] <TB1> INFO: ROC 7, missing bumps = 18, total number of good bumps = 4142
[11:28:51.561] <TB1> INFO: ROC 8, missing bumps = 447, total number of good bumps = 3713
[11:28:51.562] <TB1> INFO: ROC 9, missing bumps = 2262, total number of good bumps = 1898
[11:28:51.563] <TB1> INFO: ROC 10, missing bumps = 29, total number of good bumps = 4131
[11:28:51.564] <TB1> INFO: ROC 11, missing bumps = 3, total number of good bumps = 4157
[11:28:51.566] <TB1> INFO: ROC 12, missing bumps = 8, total number of good bumps = 4152
[11:28:51.567] <TB1> INFO: ROC 13, missing bumps = 3, total number of good bumps = 4157
[11:28:51.568] <TB1> INFO: ROC 14, missing bumps = 107, total number of good bumps = 4053
[11:28:51.569] <TB1> INFO: ROC 15, missing bumps = 3918, total number of good bumps = 242
[11:28:51.569] <TB1> INFO: CalDel = 110 done
[11:28:51.834] <TB1> INFO: Expecting 416000 events.
[11:29:07.379] <TB1> INFO: 416000 events read in total (14989ms).
[11:29:07.380] <TB1> INFO: Test took 15808ms.
[11:29:07.550] <TB1> INFO: ROC 0, missing bumps = 2, total number of good bumps = 4158
[11:29:07.551] <TB1> INFO: ROC 1, missing bumps = 2, total number of good bumps = 4158
[11:29:07.552] <TB1> INFO: ROC 2, missing bumps = 10, total number of good bumps = 4150
[11:29:07.554] <TB1> INFO: ROC 3, missing bumps = 2, total number of good bumps = 4158
[11:29:07.555] <TB1> INFO: ROC 5, missing bumps = 6, total number of good bumps = 4154
[11:29:07.556] <TB1> INFO: ROC 6, missing bumps = 4, total number of good bumps = 4156
[11:29:07.558] <TB1> INFO: ROC 7, missing bumps = 7, total number of good bumps = 4153
[11:29:07.559] <TB1> INFO: ROC 8, missing bumps = 5, total number of good bumps = 4155
[11:29:07.560] <TB1> INFO: ROC 9, missing bumps = 19, total number of good bumps = 4141
[11:29:07.561] <TB1> INFO: ROC 10, missing bumps = 5, total number of good bumps = 4155
[11:29:07.562] <TB1> INFO: ROC 11, missing bumps = 3, total number of good bumps = 4157
[11:29:07.564] <TB1> INFO: ROC 12, missing bumps = 3, total number of good bumps = 4157
[11:29:07.565] <TB1> INFO: ROC 13, missing bumps = 1, total number of good bumps = 4159
[11:29:07.566] <TB1> INFO: ROC 14, missing bumps = 4, total number of good bumps = 4156
[11:29:07.567] <TB1> INFO: ROC 15, missing bumps = 150, total number of good bumps = 4010
[11:29:07.567] <TB1> INFO: CalDel = 120 done
[11:29:07.852] <TB1> INFO: Expecting 416000 events.
[11:29:23.353] <TB1> INFO: 416000 events read in total (14946ms).
[11:29:23.354] <TB1> INFO: Test took 15784ms.
[11:29:23.525] <TB1> INFO: ROC 0, missing bumps = 2, total number of good bumps = 4158
[11:29:23.526] <TB1> INFO: ROC 1, missing bumps = 1, total number of good bumps = 4159
[11:29:23.527] <TB1> INFO: ROC 2, missing bumps = 1, total number of good bumps = 4159
[11:29:23.528] <TB1> INFO: ROC 3, missing bumps = 1, total number of good bumps = 4159
[11:29:23.530] <TB1> INFO: ROC 5, missing bumps = 0, total number of good bumps = 4160
[11:29:23.531] <TB1> INFO: ROC 6, missing bumps = 3, total number of good bumps = 4157
[11:29:23.548] <TB1> INFO: ROC 7, missing bumps = 5, total number of good bumps = 4155
[11:29:23.550] <TB1> INFO: ROC 8, missing bumps = 3, total number of good bumps = 4157
[11:29:23.551] <TB1> INFO: ROC 9, missing bumps = 2, total number of good bumps = 4158
[11:29:23.552] <TB1> INFO: ROC 10, missing bumps = 1, total number of good bumps = 4159
[11:29:23.553] <TB1> INFO: ROC 11, missing bumps = 2, total number of good bumps = 4158
[11:29:23.555] <TB1> INFO: ROC 12, missing bumps = 1, total number of good bumps = 4159
[11:29:23.556] <TB1> INFO: ROC 13, missing bumps = 0, total number of good bumps = 4160
[11:29:23.557] <TB1> INFO: ROC 14, missing bumps = 3, total number of good bumps = 4157
[11:29:23.558] <TB1> INFO: ROC 15, missing bumps = 0, total number of good bumps = 4160
[11:29:23.558] <TB1> INFO: CalDel = 130 done
[11:29:23.821] <TB1> INFO: Expecting 416000 events.
[11:29:39.367] <TB1> INFO: 416000 events read in total (14990ms).
[11:29:39.368] <TB1> INFO: Test took 15807ms.
[11:29:39.539] <TB1> INFO: ROC 0, missing bumps = 3, total number of good bumps = 4158
[11:29:39.541] <TB1> INFO: ROC 1, missing bumps = 0, total number of good bumps = 4160
[11:29:39.543] <TB1> INFO: ROC 2, missing bumps = 1, total number of good bumps = 4159
[11:29:39.544] <TB1> INFO: ROC 3, missing bumps = 1, total number of good bumps = 4159
[11:29:39.546] <TB1> INFO: ROC 6, missing bumps = 2, total number of good bumps = 4158
[11:29:39.547] <TB1> INFO: ROC 7, missing bumps = 4, total number of good bumps = 4156
[11:29:39.549] <TB1> INFO: ROC 8, missing bumps = 3, total number of good bumps = 4157
[11:29:39.550] <TB1> INFO: ROC 9, missing bumps = 1, total number of good bumps = 4159
[11:29:39.551] <TB1> INFO: ROC 10, missing bumps = 1, total number of good bumps = 4159
[11:29:39.552] <TB1> INFO: ROC 11, missing bumps = 313, total number of good bumps = 4159
[11:29:39.553] <TB1> INFO: ROC 12, missing bumps = 114, total number of good bumps = 4159
[11:29:39.554] <TB1> INFO: ROC 14, missing bumps = 2, total number of good bumps = 4158
[11:29:39.554] <TB1> INFO: CalDel = 140 done
[11:29:39.827] <TB1> INFO: Expecting 416000 events.
[11:29:53.899] <TB1> INFO: 416000 events read in total (13516ms).
[11:29:53.900] <TB1> INFO: Test took 14343ms.
[11:29:54.068] <TB1> INFO: ROC 0, missing bumps = 1662, total number of good bumps = 4158
[11:29:54.069] <TB1> INFO: ROC 2, missing bumps = 0, total number of good bumps = 4160
[11:29:54.070] <TB1> INFO: ROC 3, missing bumps = 26, total number of good bumps = 4159
[11:29:54.072] <TB1> INFO: ROC 6, missing bumps = 622, total number of good bumps = 4158
[11:29:54.073] <TB1> INFO: ROC 7, missing bumps = 7, total number of good bumps = 4156
[11:29:54.074] <TB1> INFO: ROC 8, missing bumps = 2, total number of good bumps = 4158
[11:29:54.075] <TB1> INFO: ROC 9, missing bumps = 1, total number of good bumps = 4159
[11:29:54.076] <TB1> INFO: ROC 10, missing bumps = 8, total number of good bumps = 4159
[11:29:54.077] <TB1> INFO: ROC 11, missing bumps = 4096, total number of good bumps = 4159
[11:29:54.078] <TB1> INFO: ROC 12, missing bumps = 3898, total number of good bumps = 4159
[11:29:54.079] <TB1> INFO: ROC 14, missing bumps = 1, total number of good bumps = 4159
[11:29:54.079] <TB1> INFO: CalDel = 150 done
[11:29:54.341] <TB1> INFO: Expecting 416000 events.
[11:30:07.155] <TB1> INFO: 416000 events read in total (12258ms).
[11:30:07.156] <TB1> INFO: Test took 13075ms.
[11:30:07.326] <TB1> INFO: ROC 0, missing bumps = 4075, total number of good bumps = 4158
[11:30:07.327] <TB1> INFO: ROC 3, missing bumps = 2575, total number of good bumps = 4159
[11:30:07.329] <TB1> INFO: ROC 6, missing bumps = 3963, total number of good bumps = 4158
[11:30:07.330] <TB1> INFO: ROC 7, missing bumps = 722, total number of good bumps = 4156
[11:30:07.331] <TB1> INFO: ROC 8, missing bumps = 52, total number of good bumps = 4158
[11:30:07.332] <TB1> INFO: ROC 9, missing bumps = 1, total number of good bumps = 4160
[11:30:07.334] <TB1> INFO: ROC 10, missing bumps = 1266, total number of good bumps = 4159
[11:30:07.335] <TB1> INFO: ROC 11, missing bumps = 4154, total number of good bumps = 4160
[11:30:07.336] <TB1> INFO: ROC 12, missing bumps = 4147, total number of good bumps = 4159
[11:30:07.337] <TB1> INFO: ROC 14, missing bumps = 343, total number of good bumps = 4160
[11:30:07.337] <TB1> INFO: CalDel = 160 done
[11:30:07.611] <TB1> INFO: Expecting 416000 events.
[11:30:18.778] <TB1> INFO: 416000 events read in total (10610ms).
[11:30:18.779] <TB1> INFO: Test took 11439ms.
[11:30:18.991] <TB1> INFO: ROC 0, missing bumps = 4156, total number of good bumps = 4159
[11:30:18.992] <TB1> INFO: ROC 3, missing bumps = 4132, total number of good bumps = 4159
[11:30:18.994] <TB1> INFO: ROC 6, missing bumps = 4157, total number of good bumps = 4158
[11:30:18.995] <TB1> INFO: ROC 7, missing bumps = 3970, total number of good bumps = 4156
[11:30:18.996] <TB1> INFO: ROC 8, missing bumps = 1901, total number of good bumps = 4158
[11:30:18.998] <TB1> INFO: ROC 9, missing bumps = 412, total number of good bumps = 4160
[11:30:18.999] <TB1> INFO: ROC 10, missing bumps = 4026, total number of good bumps = 4159
[11:30:19.001] <TB1> INFO: ROC 11, missing bumps = 4158, total number of good bumps = 4160
[11:30:19.003] <TB1> INFO: ROC 12, missing bumps = 4155, total number of good bumps = 4159
[11:30:19.005] <TB1> INFO: ROC 14, missing bumps = 3587, total number of good bumps = 4160
[11:30:19.005] <TB1> INFO: CalDel = 170 done
[11:30:19.271] <TB1> INFO: Expecting 416000 events.
[11:30:28.794] <TB1> INFO: 416000 events read in total (8968ms).
[11:30:28.796] <TB1> INFO: Test took 9787ms.
[11:30:29.048] <TB1> INFO: ROC 0, missing bumps = 4159, total number of good bumps = 4159
[11:30:29.051] <TB1> INFO: ROC 3, missing bumps = 4159, total number of good bumps = 4159
[11:30:29.053] <TB1> INFO: ROC 6, missing bumps = 4158, total number of good bumps = 4158
[11:30:29.055] <TB1> INFO: ROC 7, missing bumps = 4157, total number of good bumps = 4156
[11:30:29.056] <TB1> INFO: ROC 8, missing bumps = 4144, total number of good bumps = 4158
[11:30:29.058] <TB1> INFO: ROC 9, missing bumps = 3989, total number of good bumps = 4160
[11:30:29.060] <TB1> INFO: ROC 10, missing bumps = 4157, total number of good bumps = 4159
[11:30:29.062] <TB1> INFO: ROC 11, missing bumps = 4160, total number of good bumps = 4160
[11:30:29.063] <TB1> INFO: ROC 12, missing bumps = 4160, total number of good bumps = 4159
[11:30:29.064] <TB1> INFO: ROC 14, missing bumps = 4155, total number of good bumps = 4160
[11:30:29.064] <TB1> INFO: CalDel = 180 done
[11:30:29.336] <TB1> INFO: Expecting 416000 events.
[11:30:38.743] <TB1> INFO: 416000 events read in total (8851ms).
[11:30:38.744] <TB1> INFO: Test took 9677ms.
[11:30:39.017] <TB1> INFO: ROC 0, missing bumps = 4160, total number of good bumps = 4159
[11:30:39.018] <TB1> INFO: ROC 3, missing bumps = 4160, total number of good bumps = 4159
[11:30:39.019] <TB1> INFO: ROC 6, missing bumps = 4160, total number of good bumps = 4158
[11:30:39.020] <TB1> INFO: ROC 7, missing bumps = 4159, total number of good bumps = 4157
[11:30:39.021] <TB1> INFO: ROC 8, missing bumps = 4159, total number of good bumps = 4158
[11:30:39.023] <TB1> INFO: ROC 9, missing bumps = 4156, total number of good bumps = 4160
[11:30:39.024] <TB1> INFO: ROC 10, missing bumps = 4160, total number of good bumps = 4159
[11:30:39.025] <TB1> INFO: ROC 11, missing bumps = 4160, total number of good bumps = 4160
[11:30:39.026] <TB1> INFO: ROC 12, missing bumps = 4160, total number of good bumps = 4159
[11:30:39.027] <TB1> INFO: ROC 14, missing bumps = 4157, total number of good bumps = 4160
[11:30:39.027] <TB1> INFO: CalDel = 190 done
[11:30:39.295] <TB1> INFO: Expecting 416000 events.
[11:30:48.677] <TB1> INFO: 416000 events read in total (8826ms).
[11:30:48.678] <TB1> INFO: Test took 9648ms.
[11:30:48.953] <TB1> INFO: ROC 0, missing bumps = 4160, total number of good bumps = 4159
[11:30:48.954] <TB1> INFO: ROC 3, missing bumps = 4160, total number of good bumps = 4159
[11:30:48.956] <TB1> INFO: ROC 6, missing bumps = 4160, total number of good bumps = 4158
[11:30:48.957] <TB1> INFO: ROC 7, missing bumps = 4160, total number of good bumps = 4157
[11:30:48.959] <TB1> INFO: ROC 8, missing bumps = 4159, total number of good bumps = 4158
[11:30:48.960] <TB1> INFO: ROC 9, missing bumps = 4158, total number of good bumps = 4160
[11:30:48.961] <TB1> INFO: ROC 10, missing bumps = 4160, total number of good bumps = 4159
[11:30:48.963] <TB1> INFO: ROC 11, missing bumps = 4160, total number of good bumps = 4160
[11:30:48.964] <TB1> INFO: ROC 12, missing bumps = 4160, total number of good bumps = 4159
[11:30:48.965] <TB1> INFO: ROC 14, missing bumps = 4159, total number of good bumps = 4160
[11:30:48.965] <TB1> INFO: CalDel = 200 done
[11:30:48.966] <TB1> INFO: CalDel scan done
[11:30:48.967] <TB1> INFO: resulting values: CalDel = 120 140 150 130 110 130 140 140 150 140 130 130 130 130 150 130
[11:30:49.250] <TB1> INFO: Expecting 416000 events.
[11:31:04.713] <TB1> INFO: 416000 events read in total (14907ms).
[11:31:04.720] <TB1> INFO: Test took 15751ms.
[11:31:04.728] <TB1> INFO: maps.size() = 16
[11:31:04.907] <TB1> INFO: 11 broken Bump Bonds found
[11:31:04.908] <TB1> INFO: back to CtrlReg 0
[11:31:04.910] <TB1> INFO: PixTestBB4Map::doTest() done
[11:31:04.910] <TB1> INFO: Write out MinimumThreshold
[11:31:04.910] <TB1> INFO: Write out CalDel Scan_C0_V0
[11:31:04.910] <TB1> INFO: Write out PixelHit_C0_V0
[11:31:04.911] <TB1> INFO: Write out BB4MapBest_C0_V0
[11:31:04.911] <TB1> INFO: Write out NoiseScan_C0_V0
[11:31:04.911] <TB1> INFO: Write out BB4Map_C0_V13
[11:31:04.911] <TB1> INFO: Write out Hit Distr_C0_V0
[11:31:04.911] <TB1> INFO: Write out Hit Distr_C0_V0
[11:31:04.911] <TB1> INFO: Write out CalDel Scan_C1_V0
[11:31:04.911] <TB1> INFO: Write out PixelHit_C1_V0
[11:31:04.912] <TB1> INFO: Write out BB4MapBest_C1_V0
[11:31:04.912] <TB1> INFO: Write out NoiseScan_C1_V0
[11:31:04.912] <TB1> INFO: Write out BB4Map_C1_V13
[11:31:04.912] <TB1> INFO: Write out Hit Distr_C1_V0
[11:31:04.912] <TB1> INFO: Write out Hit Distr_C1_V0
[11:31:04.912] <TB1> INFO: Write out CalDel Scan_C2_V0
[11:31:04.912] <TB1> INFO: Write out PixelHit_C2_V0
[11:31:04.913] <TB1> INFO: Write out BB4MapBest_C2_V0
[11:31:04.913] <TB1> INFO: Write out NoiseScan_C2_V0
[11:31:04.913] <TB1> INFO: Write out BB4Map_C2_V13
[11:31:04.913] <TB1> INFO: Write out Hit Distr_C2_V0
[11:31:04.913] <TB1> INFO: Write out Hit Distr_C2_V0
[11:31:04.913] <TB1> INFO: Write out CalDel Scan_C3_V0
[11:31:04.913] <TB1> INFO: Write out PixelHit_C3_V0
[11:31:04.913] <TB1> INFO: Write out BB4MapBest_C3_V0
[11:31:04.914] <TB1> INFO: Write out NoiseScan_C3_V0
[11:31:04.914] <TB1> INFO: Write out BB4Map_C3_V13
[11:31:04.914] <TB1> INFO: Write out Hit Distr_C3_V0
[11:31:04.914] <TB1> INFO: Write out Hit Distr_C3_V0
[11:31:04.914] <TB1> INFO: Write out CalDel Scan_C4_V0
[11:31:04.914] <TB1> INFO: Write out PixelHit_C4_V0
[11:31:04.914] <TB1> INFO: Write out BB4MapBest_C4_V0
[11:31:04.914] <TB1> INFO: Write out NoiseScan_C4_V0
[11:31:04.915] <TB1> INFO: Write out BB4Map_C4_V13
[11:31:04.915] <TB1> INFO: Write out Hit Distr_C4_V0
[11:31:04.915] <TB1> INFO: Write out Hit Distr_C4_V0
[11:31:04.915] <TB1> INFO: Write out CalDel Scan_C5_V0
[11:31:04.915] <TB1> INFO: Write out PixelHit_C5_V0
[11:31:04.915] <TB1> INFO: Write out BB4MapBest_C5_V0
[11:31:04.915] <TB1> INFO: Write out NoiseScan_C5_V0
[11:31:04.915] <TB1> INFO: Write out BB4Map_C5_V13
[11:31:04.916] <TB1> INFO: Write out Hit Distr_C5_V0
[11:31:04.916] <TB1> INFO: Write out Hit Distr_C5_V0
[11:31:04.916] <TB1> INFO: Write out CalDel Scan_C6_V0
[11:31:04.916] <TB1> INFO: Write out PixelHit_C6_V0
[11:31:04.916] <TB1> INFO: Write out BB4MapBest_C6_V0
[11:31:04.916] <TB1> INFO: Write out NoiseScan_C6_V0
[11:31:04.916] <TB1> INFO: Write out BB4Map_C6_V13
[11:31:04.916] <TB1> INFO: Write out Hit Distr_C6_V0
[11:31:04.917] <TB1> INFO: Write out Hit Distr_C6_V0
[11:31:04.917] <TB1> INFO: Write out CalDel Scan_C7_V0
[11:31:04.917] <TB1> INFO: Write out PixelHit_C7_V0
[11:31:04.917] <TB1> INFO: Write out BB4MapBest_C7_V0
[11:31:04.917] <TB1> INFO: Write out NoiseScan_C7_V0
[11:31:04.917] <TB1> INFO: Write out BB4Map_C7_V13
[11:31:04.917] <TB1> INFO: Write out Hit Distr_C7_V0
[11:31:04.917] <TB1> INFO: Write out Hit Distr_C7_V0
[11:31:04.918] <TB1> INFO: Write out CalDel Scan_C8_V0
[11:31:04.918] <TB1> INFO: Write out PixelHit_C8_V0
[11:31:04.918] <TB1> INFO: Write out BB4MapBest_C8_V0
[11:31:04.918] <TB1> INFO: Write out NoiseScan_C8_V0
[11:31:04.918] <TB1> INFO: Write out BB4Map_C8_V13
[11:31:04.918] <TB1> INFO: Write out Hit Distr_C8_V0
[11:31:04.918] <TB1> INFO: Write out Hit Distr_C8_V0
[11:31:04.918] <TB1> INFO: Write out CalDel Scan_C9_V0
[11:31:04.919] <TB1> INFO: Write out PixelHit_C9_V0
[11:31:04.919] <TB1> INFO: Write out BB4MapBest_C9_V0
[11:31:04.919] <TB1> INFO: Write out NoiseScan_C9_V0
[11:31:04.919] <TB1> INFO: Write out BB4Map_C9_V13
[11:31:04.919] <TB1> INFO: Write out Hit Distr_C9_V0
[11:31:04.919] <TB1> INFO: Write out Hit Distr_C9_V0
[11:31:04.919] <TB1> INFO: Write out CalDel Scan_C10_V0
[11:31:04.919] <TB1> INFO: Write out PixelHit_C10_V0
[11:31:04.920] <TB1> INFO: Write out BB4MapBest_C10_V0
[11:31:04.920] <TB1> INFO: Write out NoiseScan_C10_V0
[11:31:04.920] <TB1> INFO: Write out BB4Map_C10_V13
[11:31:04.920] <TB1> INFO: Write out Hit Distr_C10_V0
[11:31:04.920] <TB1> INFO: Write out Hit Distr_C10_V0
[11:31:04.920] <TB1> INFO: Write out CalDel Scan_C11_V0
[11:31:04.920] <TB1> INFO: Write out PixelHit_C11_V0
[11:31:04.920] <TB1> INFO: Write out BB4MapBest_C11_V0
[11:31:04.921] <TB1> INFO: Write out NoiseScan_C11_V0
[11:31:04.921] <TB1> INFO: Write out BB4Map_C11_V13
[11:31:04.921] <TB1> INFO: Write out Hit Distr_C11_V0
[11:31:04.921] <TB1> INFO: Write out Hit Distr_C11_V0
[11:31:04.921] <TB1> INFO: Write out CalDel Scan_C12_V0
[11:31:04.921] <TB1> INFO: Write out PixelHit_C12_V0
[11:31:04.921] <TB1> INFO: Write out BB4MapBest_C12_V0
[11:31:04.921] <TB1> INFO: Write out NoiseScan_C12_V0
[11:31:04.922] <TB1> INFO: Write out BB4Map_C12_V13
[11:31:04.922] <TB1> INFO: Write out Hit Distr_C12_V0
[11:31:04.922] <TB1> INFO: Write out Hit Distr_C12_V0
[11:31:04.922] <TB1> INFO: Write out CalDel Scan_C13_V0
[11:31:04.922] <TB1> INFO: Write out PixelHit_C13_V0
[11:31:04.922] <TB1> INFO: Write out BB4MapBest_C13_V0
[11:31:04.922] <TB1> INFO: Write out NoiseScan_C13_V0
[11:31:04.922] <TB1> INFO: Write out BB4Map_C13_V13
[11:31:04.923] <TB1> INFO: Write out Hit Distr_C13_V0
[11:31:04.923] <TB1> INFO: Write out Hit Distr_C13_V0
[11:31:04.923] <TB1> INFO: Write out CalDel Scan_C14_V0
[11:31:04.923] <TB1> INFO: Write out PixelHit_C14_V0
[11:31:04.923] <TB1> INFO: Write out BB4MapBest_C14_V0
[11:31:04.923] <TB1> INFO: Write out NoiseScan_C14_V0
[11:31:04.923] <TB1> INFO: Write out BB4Map_C14_V13
[11:31:04.923] <TB1> INFO: Write out Hit Distr_C14_V0
[11:31:04.924] <TB1> INFO: Write out Hit Distr_C14_V0
[11:31:04.924] <TB1> INFO: Write out CalDel Scan_C15_V0
[11:31:04.924] <TB1> INFO: Write out PixelHit_C15_V0
[11:31:04.924] <TB1> INFO: Write out BB4MapBest_C15_V0
[11:31:04.924] <TB1> INFO: Write out NoiseScan_C15_V0
[11:31:04.924] <TB1> INFO: Write out BB4Map_C15_V13
[11:31:04.924] <TB1> INFO: Write out Hit Distr_C15_V0
[11:31:04.924] <TB1> INFO: Write out Hit Distr_C15_V0
[11:31:04.925] <TB1> INFO: enter test to run
[11:31:04.925] <TB1> INFO: test: no parameter change
[11:31:04.953] <TB1> QUIET: Connection to board 154 closed.
[11:31:05.033] <TB1> INFO: pXar: this is the end, my friend