[08:25:59.296] <TB1> INFO: *** Welcome to pxar ***
[08:25:59.296] <TB1> INFO: *** Today: 2016/07/27
[08:25:59.340] <TB1> INFO: *** Version: f5d3-dirty
[08:25:59.340] <TB1> INFO: readRocDacs: /home/Reception/DATA/KITAACHEN/M4633_Reception_2016-07-27_10h21m_1469607660//000_ReceptionTestBB4_p17//dacParameters_C0.dat .. /home/Reception/DATA/KITAACHEN/M4633_Reception_2016-07-27_10h21m_1469607660//000_ReceptionTestBB4_p17//dacParameters_C15.dat
[08:25:59.358] <TB1> INFO: readTbmDacs: /home/Reception/DATA/KITAACHEN/M4633_Reception_2016-07-27_10h21m_1469607660//000_ReceptionTestBB4_p17//tbmParameters_C0a.dat .. /home/Reception/DATA/KITAACHEN/M4633_Reception_2016-07-27_10h21m_1469607660//000_ReceptionTestBB4_p17//tbmParameters_C0b.dat
[08:25:59.360] <TB1> INFO: readMaskFile: /home/Reception/DATA/KITAACHEN/M4633_Reception_2016-07-27_10h21m_1469607660//000_ReceptionTestBB4_p17//defaultMaskFile.dat
[08:25:59.361] <TB1> INFO: readTrimFile: /home/Reception/DATA/KITAACHEN/M4633_Reception_2016-07-27_10h21m_1469607660//000_ReceptionTestBB4_p17//trimParameters_C0.dat .. /home/Reception/DATA/KITAACHEN/M4633_Reception_2016-07-27_10h21m_1469607660//000_ReceptionTestBB4_p17//trimParameters_C15.dat
[08:25:59.603] <TB1> INFO: clk: 4
[08:25:59.603] <TB1> INFO: ctr: 4
[08:25:59.603] <TB1> INFO: sda: 19
[08:25:59.603] <TB1> INFO: tin: 9
[08:25:59.603] <TB1> INFO: level: 15
[08:25:59.603] <TB1> INFO: triggerdelay: 0
[08:25:59.603] <TB1> QUIET: Instanciating API for pxar v2.7.5+40~g4fce89b
[08:25:59.603] <TB1> INFO: Log level: INFO
[08:25:59.611] <TB1> INFO: Found DTB DTB_WXC03A
[08:25:59.619] <TB1> QUIET: Connection to board DTB_WXC03A opened.
[08:25:59.620] <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:
------------------------------------------------------
[08:25:59.622] <TB1> INFO: RPC call hashes of host and DTB match: 484264910
[08:26:01.141] <TB1> INFO: DUT info:
[08:26:01.141] <TB1> INFO: The DUT currently contains the following objects:
[08:26:01.141] <TB1> INFO: 2 TBM Cores tbm08c (2 ON)
[08:26:01.141] <TB1> INFO: TBM Core alpha (0): 7 registers set
[08:26:01.141] <TB1> INFO: TBM Core beta (1): 7 registers set
[08:26:01.141] <TB1> INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[08:26:01.141] <TB1> INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[08:26:01.141] <TB1> INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[08:26:01.141] <TB1> INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[08:26:01.141] <TB1> INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[08:26:01.141] <TB1> INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[08:26:01.141] <TB1> INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[08:26:01.141] <TB1> INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[08:26:01.141] <TB1> INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[08:26:01.141] <TB1> INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[08:26:01.141] <TB1> INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[08:26:01.141] <TB1> INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[08:26:01.141] <TB1> INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[08:26:01.141] <TB1> INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[08:26:01.141] <TB1> INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[08:26:01.141] <TB1> INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[08:26:01.141] <TB1> INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[08:26:01.544] <TB1> INFO: enter 'restricted' command line mode
[08:26:01.544] <TB1> INFO: enter test to run
[08:26:01.544] <TB1> INFO: test: pretest no parameter change
[08:26:01.544] <TB1> INFO: running: pretest
[08:26:01.548] <TB1> INFO: ----------------------------------------------------------------------
[08:26:01.548] <TB1> INFO: PixTestPretest::programROC()
[08:26:01.548] <TB1> INFO: ----------------------------------------------------------------------
[08:26:19.563] <TB1> INFO: PixTestPretest::programROC() done: ROCs are all programmable
[08:26:19.564] <TB1> INFO: IA differences per ROC: 17.7 19.3 19.3 20.1 20.9 18.5 20.1 20.1 20.1 20.1 20.9 19.3 19.3 20.9 20.1 18.5
[08:26:19.657] <TB1> INFO: enter test to run
[08:26:19.657] <TB1> INFO: test: pretest no parameter change
[08:26:19.657] <TB1> INFO: running: pretest
[08:26:19.658] <TB1> INFO: ----------------------------------------------------------------------
[08:26:19.658] <TB1> INFO: PixTestPretest::setVana() target Ia = 24 mA/ROC
[08:26:19.658] <TB1> INFO: ----------------------------------------------------------------------
[08:26:40.929] <TB1> INFO: PixTestPretest::setVana() done, Module Ia 385.1 mA = 24.0688 mA/ROC
[08:26:40.930] <TB1> INFO: i(loss) [mA/ROC]: 19.3 19.3 19.3 20.1 19.3 19.3 20.1 18.5 17.7 20.1 19.3 19.3 19.3 18.5 20.1 19.3
[08:26:40.965] <TB1> INFO: enter test to run
[08:26:40.965] <TB1> INFO: test: pretest no parameter change
[08:26:40.965] <TB1> INFO: running: pretest
[08:26:40.966] <TB1> INFO: ----------------------------------------------------------------------
[08:26:40.966] <TB1> INFO: PixTestPretest::findTiming()
[08:26:40.966] <TB1> INFO: ----------------------------------------------------------------------
[08:26:40.966] <TB1> INFO: PixTestCmd::init()
[08:26:41.739] <TB1> WARNING: Not unmasking DUT, not setting Calibrate bits!
[08:27:39.890] <TB1> INFO: TBM phases: 160MHz: 7, 400MHz: 2, TBM delays: ROC(0/1):4, header/trailer: 1, token: 1
[08:27:39.891] <TB1> INFO: (success/tries = 100/100), width = 4
[08:27:39.891] <TB1> INFO: enter test to run
[08:27:39.891] <TB1> INFO: test: pretest no parameter change
[08:27:39.891] <TB1> INFO: running: pretest
[08:27:39.893] <TB1> INFO: ----------------------------------------------------------------------
[08:27:39.893] <TB1> INFO: PixTestPretest::findWorkingPixel()
[08:27:39.893] <TB1> INFO: ----------------------------------------------------------------------
[08:27:39.984] <TB1> INFO: Expecting 231680 events.
[08:27:45.679] <TB1> WARNING: Detected DESER400 trailer error bits: "CODE ERROR"
[08:27:49.107] <TB1> INFO: 231680 events read in total (8568ms).
[08:27:49.111] <TB1> INFO: Test took 9215ms.
[08:27:49.397] <TB1> INFO: Found working pixel in all ROCs: col/row = 12/22
[08:27:49.459] <TB1> INFO: enter test to run
[08:27:49.459] <TB1> INFO: test: pretest no parameter change
[08:27:49.459] <TB1> INFO: running: pretest
[08:27:49.460] <TB1> INFO: ----------------------------------------------------------------------
[08:27:49.460] <TB1> INFO: PixTestPretest::setVthrCompCalDel()
[08:27:49.460] <TB1> INFO: ----------------------------------------------------------------------
[08:27:49.552] <TB1> INFO: Expecting 231680 events.
[08:27:58.579] <TB1> INFO: 231680 events read in total (8471ms).
[08:27:58.582] <TB1> INFO: Test took 9118ms.
[08:27:58.876] <TB1> INFO: PixTestPretest::setVthrCompCalDel() done
[08:27:58.876] <TB1> INFO: CalDel: 121 127 138 122 128 123 132 126 133 135 127 120 132 124 143 128
[08:27:58.876] <TB1> INFO: VthrComp: 51 52 51 51 51 51 56 51 51 51 51 51 51 51 51 51
[08:27:58.916] <TB1> INFO: enter test to run
[08:27:58.916] <TB1> INFO: test: pretest no parameter change
[08:27:58.916] <TB1> INFO: running: pretest
[08:27:58.919] <TB1> INFO: write dac parameters into /home/Reception/DATA/KITAACHEN/M4633_Reception_2016-07-27_10h21m_1469607660//000_ReceptionTestBB4_p17//dacParameters_C0.dat
[08:27:58.925] <TB1> INFO: write dac parameters into /home/Reception/DATA/KITAACHEN/M4633_Reception_2016-07-27_10h21m_1469607660//000_ReceptionTestBB4_p17//dacParameters_C1.dat
[08:27:58.930] <TB1> INFO: write dac parameters into /home/Reception/DATA/KITAACHEN/M4633_Reception_2016-07-27_10h21m_1469607660//000_ReceptionTestBB4_p17//dacParameters_C2.dat
[08:27:58.935] <TB1> INFO: write dac parameters into /home/Reception/DATA/KITAACHEN/M4633_Reception_2016-07-27_10h21m_1469607660//000_ReceptionTestBB4_p17//dacParameters_C3.dat
[08:27:58.940] <TB1> INFO: write dac parameters into /home/Reception/DATA/KITAACHEN/M4633_Reception_2016-07-27_10h21m_1469607660//000_ReceptionTestBB4_p17//dacParameters_C4.dat
[08:27:58.945] <TB1> INFO: write dac parameters into /home/Reception/DATA/KITAACHEN/M4633_Reception_2016-07-27_10h21m_1469607660//000_ReceptionTestBB4_p17//dacParameters_C5.dat
[08:27:58.950] <TB1> INFO: write dac parameters into /home/Reception/DATA/KITAACHEN/M4633_Reception_2016-07-27_10h21m_1469607660//000_ReceptionTestBB4_p17//dacParameters_C6.dat
[08:27:58.955] <TB1> INFO: write dac parameters into /home/Reception/DATA/KITAACHEN/M4633_Reception_2016-07-27_10h21m_1469607660//000_ReceptionTestBB4_p17//dacParameters_C7.dat
[08:27:58.960] <TB1> INFO: write dac parameters into /home/Reception/DATA/KITAACHEN/M4633_Reception_2016-07-27_10h21m_1469607660//000_ReceptionTestBB4_p17//dacParameters_C8.dat
[08:27:58.965] <TB1> INFO: write dac parameters into /home/Reception/DATA/KITAACHEN/M4633_Reception_2016-07-27_10h21m_1469607660//000_ReceptionTestBB4_p17//dacParameters_C9.dat
[08:27:58.970] <TB1> INFO: write dac parameters into /home/Reception/DATA/KITAACHEN/M4633_Reception_2016-07-27_10h21m_1469607660//000_ReceptionTestBB4_p17//dacParameters_C10.dat
[08:27:58.975] <TB1> INFO: write dac parameters into /home/Reception/DATA/KITAACHEN/M4633_Reception_2016-07-27_10h21m_1469607660//000_ReceptionTestBB4_p17//dacParameters_C11.dat
[08:27:58.980] <TB1> INFO: write dac parameters into /home/Reception/DATA/KITAACHEN/M4633_Reception_2016-07-27_10h21m_1469607660//000_ReceptionTestBB4_p17//dacParameters_C12.dat
[08:27:58.985] <TB1> INFO: write dac parameters into /home/Reception/DATA/KITAACHEN/M4633_Reception_2016-07-27_10h21m_1469607660//000_ReceptionTestBB4_p17//dacParameters_C13.dat
[08:27:58.990] <TB1> INFO: write dac parameters into /home/Reception/DATA/KITAACHEN/M4633_Reception_2016-07-27_10h21m_1469607660//000_ReceptionTestBB4_p17//dacParameters_C14.dat
[08:27:58.995] <TB1> INFO: write dac parameters into /home/Reception/DATA/KITAACHEN/M4633_Reception_2016-07-27_10h21m_1469607660//000_ReceptionTestBB4_p17//dacParameters_C15.dat
[08:27:58.998] <TB1> INFO: enter test to run
[08:27:58.998] <TB1> INFO: test: PixelAlive no parameter change
[08:27:58.998] <TB1> INFO: running: pixelalive
[08:27:58.999] <TB1> INFO: ######################################################################
[08:27:58.999] <TB1> INFO: PixTestAlive::doTest()
[08:27:58.999] <TB1> INFO: ######################################################################
[08:27:59.000] <TB1> INFO: ----------------------------------------------------------------------
[08:27:59.000] <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)
[08:27:59.000] <TB1> INFO: ----------------------------------------------------------------------
[08:27:59.266] <TB1> INFO: Expecting 41600 events.
[08:28:02.698] <TB1> INFO: 41600 events read in total (2877ms).
[08:28:02.699] <TB1> INFO: Test took 3698ms.
[08:28:02.987] <TB1> INFO: PixTestAlive::aliveTest() done
[08:28:02.987] <TB1> INFO: number of dead pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[08:28:02.988] <TB1> INFO: ----------------------------------------------------------------------
[08:28:02.988] <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)
[08:28:02.988] <TB1> INFO: ----------------------------------------------------------------------
[08:28:03.252] <TB1> INFO: Expecting 41600 events.
[08:28:05.847] <TB1> INFO: 41600 events read in total (2039ms).
[08:28:05.848] <TB1> INFO: Test took 2859ms.
[08:28:05.848] <TB1> INFO: mask vs. old pixelAlive PixelAlive_C0_V0 .. PixelAlive_C15_V0
[08:28:06.154] <TB1> INFO: PixTestAlive::maskTest() done
[08:28:06.154] <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
[08:28:06.155] <TB1> INFO: ----------------------------------------------------------------------
[08:28:06.155] <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)
[08:28:06.155] <TB1> INFO: ----------------------------------------------------------------------
[08:28:06.430] <TB1> INFO: Expecting 41600 events.
[08:28:09.842] <TB1> INFO: 41600 events read in total (2856ms).
[08:28:09.842] <TB1> INFO: Test took 3685ms.
[08:28:10.136] <TB1> INFO: PixTestAlive::addressDecodingTest() done
[08:28:10.136] <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
[08:28:10.136] <TB1> INFO: PixTestAlive::doTest() done, duration: 11 seconds
[08:28:10.142] <TB1> INFO: enter test to run
[08:28:10.142] <TB1> INFO: test: BB no parameter change
[08:28:10.142] <TB1> INFO: running: bb
[08:28:10.144] <TB1> INFO: ######################################################################
[08:28:10.144] <TB1> INFO: PixTestBBMap::doTest() Ntrig = 5, VcalS = 250 (high range)
[08:28:10.144] <TB1> INFO: ######################################################################
[08:28:10.146] <TB1> INFO: ---> dac: VthrComp name: calSMap ntrig: 5 dacrange: 0 .. 149 (-1/-1) hits flags = 514 (plus default)
[08:28:10.158] <TB1> INFO: dacScan split into 1 runs with ntrig = 5
[08:28:10.158] <TB1> INFO: run 1 of 1
[08:28:10.434] <TB1> INFO: Expecting 3120000 events.
[08:28:54.577] <TB1> INFO: 1142970 events read in total (43588ms).
[08:29:37.999] <TB1> INFO: 2264690 events read in total (87010ms).
[08:30:11.095] <TB1> INFO: 3120000 events read in total (120107ms).
[08:30:11.146] <TB1> INFO: Test took 120988ms.
[08:30:34.624] <TB1> INFO: PixTestBBMap::doTest() done with 2 decoding errors: , duration: 144 seconds
[08:30:34.624] <TB1> INFO: number of dead bumps (per ROC): 0 0 0 0 0 0 0 0 0 12 0 0 0 0 0 0
[08:30:34.624] <TB1> INFO: separation cut (per ROC): 128 121 121 120 123 119 125 109 131 123 126 121 124 115 114 116
[08:30:34.694] <TB1> INFO: enter test to run
[08:30:34.694] <TB1> INFO: test: Readback no parameter change
[08:30:34.694] <TB1> INFO: running: readback
[08:30:34.694] <TB1> INFO: readReadbackCal: /home/Reception/DATA/KITAACHEN/M4633_Reception_2016-07-27_10h21m_1469607660//000_ReceptionTestBB4_p17//readbackCal_C0.dat .. /home/Reception/DATA/KITAACHEN/M4633_Reception_2016-07-27_10h21m_1469607660//000_ReceptionTestBB4_p17//readbackCal_C15.dat
[08:30:34.710] <TB1> INFO: readGainPedestalParameters /home/Reception/DATA/KITAACHEN/M4633_Reception_2016-07-27_10h21m_1469607660//000_ReceptionTestBB4_p17//phCalibrationFitErr_C0.dat .. /home/Reception/DATA/KITAACHEN/M4633_Reception_2016-07-27_10h21m_1469607660//000_ReceptionTestBB4_p17//phCalibrationFitErr_C15.dat
[08:30:34.710] <TB1> ERROR: <ConfigParameters.cc/readGainPedestalParameters:L996> cannot open /home/Reception/DATA/KITAACHEN/M4633_Reception_2016-07-27_10h21m_1469607660//000_ReceptionTestBB4_p17//phCalibrationFitErr_C0.dat for reading PH calibration constants
[08:30:34.710] <TB1> INFO: ######################################################################
[08:30:34.710] <TB1> INFO: PixTestReadback::doTest()
[08:30:34.710] <TB1> INFO: ######################################################################
[08:30:34.710] <TB1> INFO: ----------------------------------------------------------------------
[08:30:34.710] <TB1> INFO: PixTestReadback::CalibrateVd()
[08:30:34.710] <TB1> INFO: ----------------------------------------------------------------------
[08:30:43.975] <TB1> INFO: write readback calibration parameters into /home/Reception/DATA/KITAACHEN/M4633_Reception_2016-07-27_10h21m_1469607660//000_ReceptionTestBB4_p17//readbackCal_C0.dat
[08:30:43.982] <TB1> INFO: write readback calibration parameters into /home/Reception/DATA/KITAACHEN/M4633_Reception_2016-07-27_10h21m_1469607660//000_ReceptionTestBB4_p17//readbackCal_C1.dat
[08:30:43.987] <TB1> INFO: write readback calibration parameters into /home/Reception/DATA/KITAACHEN/M4633_Reception_2016-07-27_10h21m_1469607660//000_ReceptionTestBB4_p17//readbackCal_C2.dat
[08:30:43.994] <TB1> INFO: write readback calibration parameters into /home/Reception/DATA/KITAACHEN/M4633_Reception_2016-07-27_10h21m_1469607660//000_ReceptionTestBB4_p17//readbackCal_C3.dat
[08:30:44.001] <TB1> INFO: write readback calibration parameters into /home/Reception/DATA/KITAACHEN/M4633_Reception_2016-07-27_10h21m_1469607660//000_ReceptionTestBB4_p17//readbackCal_C4.dat
[08:30:44.008] <TB1> INFO: write readback calibration parameters into /home/Reception/DATA/KITAACHEN/M4633_Reception_2016-07-27_10h21m_1469607660//000_ReceptionTestBB4_p17//readbackCal_C5.dat
[08:30:44.013] <TB1> INFO: write readback calibration parameters into /home/Reception/DATA/KITAACHEN/M4633_Reception_2016-07-27_10h21m_1469607660//000_ReceptionTestBB4_p17//readbackCal_C6.dat
[08:30:44.020] <TB1> INFO: write readback calibration parameters into /home/Reception/DATA/KITAACHEN/M4633_Reception_2016-07-27_10h21m_1469607660//000_ReceptionTestBB4_p17//readbackCal_C7.dat
[08:30:44.027] <TB1> INFO: write readback calibration parameters into /home/Reception/DATA/KITAACHEN/M4633_Reception_2016-07-27_10h21m_1469607660//000_ReceptionTestBB4_p17//readbackCal_C8.dat
[08:30:44.034] <TB1> INFO: write readback calibration parameters into /home/Reception/DATA/KITAACHEN/M4633_Reception_2016-07-27_10h21m_1469607660//000_ReceptionTestBB4_p17//readbackCal_C9.dat
[08:30:44.039] <TB1> INFO: write readback calibration parameters into /home/Reception/DATA/KITAACHEN/M4633_Reception_2016-07-27_10h21m_1469607660//000_ReceptionTestBB4_p17//readbackCal_C10.dat
[08:30:44.046] <TB1> INFO: write readback calibration parameters into /home/Reception/DATA/KITAACHEN/M4633_Reception_2016-07-27_10h21m_1469607660//000_ReceptionTestBB4_p17//readbackCal_C11.dat
[08:30:44.053] <TB1> INFO: write readback calibration parameters into /home/Reception/DATA/KITAACHEN/M4633_Reception_2016-07-27_10h21m_1469607660//000_ReceptionTestBB4_p17//readbackCal_C12.dat
[08:30:44.059] <TB1> INFO: write readback calibration parameters into /home/Reception/DATA/KITAACHEN/M4633_Reception_2016-07-27_10h21m_1469607660//000_ReceptionTestBB4_p17//readbackCal_C13.dat
[08:30:44.066] <TB1> INFO: write readback calibration parameters into /home/Reception/DATA/KITAACHEN/M4633_Reception_2016-07-27_10h21m_1469607660//000_ReceptionTestBB4_p17//readbackCal_C14.dat
[08:30:44.073] <TB1> INFO: write readback calibration parameters into /home/Reception/DATA/KITAACHEN/M4633_Reception_2016-07-27_10h21m_1469607660//000_ReceptionTestBB4_p17//readbackCal_C15.dat
[08:30:44.105] <TB1> INFO: PixTestPattern:: pg_setup set to default.
[08:30:44.105] <TB1> INFO: ----------------------------------------------------------------------
[08:30:44.105] <TB1> INFO: PixTestReadback::CalibrateVa()
[08:30:44.105] <TB1> INFO: ----------------------------------------------------------------------
[08:30:53.399] <TB1> INFO: write readback calibration parameters into /home/Reception/DATA/KITAACHEN/M4633_Reception_2016-07-27_10h21m_1469607660//000_ReceptionTestBB4_p17//readbackCal_C0.dat
[08:30:53.405] <TB1> INFO: write readback calibration parameters into /home/Reception/DATA/KITAACHEN/M4633_Reception_2016-07-27_10h21m_1469607660//000_ReceptionTestBB4_p17//readbackCal_C1.dat
[08:30:53.412] <TB1> INFO: write readback calibration parameters into /home/Reception/DATA/KITAACHEN/M4633_Reception_2016-07-27_10h21m_1469607660//000_ReceptionTestBB4_p17//readbackCal_C2.dat
[08:30:53.418] <TB1> INFO: write readback calibration parameters into /home/Reception/DATA/KITAACHEN/M4633_Reception_2016-07-27_10h21m_1469607660//000_ReceptionTestBB4_p17//readbackCal_C3.dat
[08:30:53.424] <TB1> INFO: write readback calibration parameters into /home/Reception/DATA/KITAACHEN/M4633_Reception_2016-07-27_10h21m_1469607660//000_ReceptionTestBB4_p17//readbackCal_C4.dat
[08:30:53.431] <TB1> INFO: write readback calibration parameters into /home/Reception/DATA/KITAACHEN/M4633_Reception_2016-07-27_10h21m_1469607660//000_ReceptionTestBB4_p17//readbackCal_C5.dat
[08:30:53.437] <TB1> INFO: write readback calibration parameters into /home/Reception/DATA/KITAACHEN/M4633_Reception_2016-07-27_10h21m_1469607660//000_ReceptionTestBB4_p17//readbackCal_C6.dat
[08:30:53.444] <TB1> INFO: write readback calibration parameters into /home/Reception/DATA/KITAACHEN/M4633_Reception_2016-07-27_10h21m_1469607660//000_ReceptionTestBB4_p17//readbackCal_C7.dat
[08:30:53.449] <TB1> INFO: write readback calibration parameters into /home/Reception/DATA/KITAACHEN/M4633_Reception_2016-07-27_10h21m_1469607660//000_ReceptionTestBB4_p17//readbackCal_C8.dat
[08:30:53.456] <TB1> INFO: write readback calibration parameters into /home/Reception/DATA/KITAACHEN/M4633_Reception_2016-07-27_10h21m_1469607660//000_ReceptionTestBB4_p17//readbackCal_C9.dat
[08:30:53.462] <TB1> INFO: write readback calibration parameters into /home/Reception/DATA/KITAACHEN/M4633_Reception_2016-07-27_10h21m_1469607660//000_ReceptionTestBB4_p17//readbackCal_C10.dat
[08:30:53.470] <TB1> INFO: write readback calibration parameters into /home/Reception/DATA/KITAACHEN/M4633_Reception_2016-07-27_10h21m_1469607660//000_ReceptionTestBB4_p17//readbackCal_C11.dat
[08:30:53.476] <TB1> INFO: write readback calibration parameters into /home/Reception/DATA/KITAACHEN/M4633_Reception_2016-07-27_10h21m_1469607660//000_ReceptionTestBB4_p17//readbackCal_C12.dat
[08:30:53.481] <TB1> INFO: write readback calibration parameters into /home/Reception/DATA/KITAACHEN/M4633_Reception_2016-07-27_10h21m_1469607660//000_ReceptionTestBB4_p17//readbackCal_C13.dat
[08:30:53.487] <TB1> INFO: write readback calibration parameters into /home/Reception/DATA/KITAACHEN/M4633_Reception_2016-07-27_10h21m_1469607660//000_ReceptionTestBB4_p17//readbackCal_C14.dat
[08:30:53.493] <TB1> INFO: write readback calibration parameters into /home/Reception/DATA/KITAACHEN/M4633_Reception_2016-07-27_10h21m_1469607660//000_ReceptionTestBB4_p17//readbackCal_C15.dat
[08:30:53.530] <TB1> INFO: PixTestPattern:: pg_setup set to default.
[08:30:53.530] <TB1> INFO: ----------------------------------------------------------------------
[08:30:53.530] <TB1> INFO: PixTestReadback::readbackVbg()
[08:30:53.530] <TB1> INFO: ----------------------------------------------------------------------
[08:31:00.638] <TB1> INFO: PixTestPattern:: pg_setup set to default.
[08:31:00.638] <TB1> INFO: ----------------------------------------------------------------------
[08:31:00.638] <TB1> INFO: PixTestReadback::getCalibratedVbg()
[08:31:00.638] <TB1> INFO: ----------------------------------------------------------------------
[08:31:00.638] <TB1> INFO: Vbg will be calibrated using Vd calibration
[08:31:00.638] <TB1> INFO: /*/*/*/*::: ROC 0: uncalibrated Vbg = 152.8calibrated Vbg = 1.22778 :::*/*/*/*/
[08:31:00.638] <TB1> INFO: /*/*/*/*::: ROC 1: uncalibrated Vbg = 164.5calibrated Vbg = 1.23331 :::*/*/*/*/
[08:31:00.638] <TB1> INFO: /*/*/*/*::: ROC 2: uncalibrated Vbg = 157.6calibrated Vbg = 1.2328 :::*/*/*/*/
[08:31:00.638] <TB1> INFO: /*/*/*/*::: ROC 3: uncalibrated Vbg = 151.9calibrated Vbg = 1.24074 :::*/*/*/*/
[08:31:00.638] <TB1> INFO: /*/*/*/*::: ROC 4: uncalibrated Vbg = 156.6calibrated Vbg = 1.23994 :::*/*/*/*/
[08:31:00.638] <TB1> INFO: /*/*/*/*::: ROC 5: uncalibrated Vbg = 166.4calibrated Vbg = 1.24149 :::*/*/*/*/
[08:31:00.638] <TB1> INFO: /*/*/*/*::: ROC 6: uncalibrated Vbg = 162.3calibrated Vbg = 1.25175 :::*/*/*/*/
[08:31:00.638] <TB1> INFO: /*/*/*/*::: ROC 7: uncalibrated Vbg = 152.3calibrated Vbg = 1.24973 :::*/*/*/*/
[08:31:00.638] <TB1> INFO: /*/*/*/*::: ROC 8: uncalibrated Vbg = 151calibrated Vbg = 1.24355 :::*/*/*/*/
[08:31:00.638] <TB1> INFO: /*/*/*/*::: ROC 9: uncalibrated Vbg = 154.6calibrated Vbg = 1.2491 :::*/*/*/*/
[08:31:00.638] <TB1> INFO: /*/*/*/*::: ROC 10: uncalibrated Vbg = 156.5calibrated Vbg = 1.23696 :::*/*/*/*/
[08:31:00.638] <TB1> INFO: /*/*/*/*::: ROC 11: uncalibrated Vbg = 158.6calibrated Vbg = 1.23758 :::*/*/*/*/
[08:31:00.638] <TB1> INFO: /*/*/*/*::: ROC 12: uncalibrated Vbg = 166.4calibrated Vbg = 1.2321 :::*/*/*/*/
[08:31:00.639] <TB1> INFO: /*/*/*/*::: ROC 13: uncalibrated Vbg = 156.8calibrated Vbg = 1.23411 :::*/*/*/*/
[08:31:00.639] <TB1> INFO: /*/*/*/*::: ROC 14: uncalibrated Vbg = 148.9calibrated Vbg = 1.23641 :::*/*/*/*/
[08:31:00.639] <TB1> INFO: /*/*/*/*::: ROC 15: uncalibrated Vbg = 153.5calibrated Vbg = 1.23279 :::*/*/*/*/
[08:31:00.640] <TB1> INFO: ----------------------------------------------------------------------
[08:31:00.641] <TB1> INFO: PixTestReadback::CalibrateIa()
[08:31:00.641] <TB1> INFO: ----------------------------------------------------------------------
[08:33:33.275] <TB1> INFO: write readback calibration parameters into /home/Reception/DATA/KITAACHEN/M4633_Reception_2016-07-27_10h21m_1469607660//000_ReceptionTestBB4_p17//readbackCal_C0.dat
[08:33:33.289] <TB1> INFO: write readback calibration parameters into /home/Reception/DATA/KITAACHEN/M4633_Reception_2016-07-27_10h21m_1469607660//000_ReceptionTestBB4_p17//readbackCal_C1.dat
[08:33:33.303] <TB1> INFO: write readback calibration parameters into /home/Reception/DATA/KITAACHEN/M4633_Reception_2016-07-27_10h21m_1469607660//000_ReceptionTestBB4_p17//readbackCal_C2.dat
[08:33:33.317] <TB1> INFO: write readback calibration parameters into /home/Reception/DATA/KITAACHEN/M4633_Reception_2016-07-27_10h21m_1469607660//000_ReceptionTestBB4_p17//readbackCal_C3.dat
[08:33:33.329] <TB1> INFO: write readback calibration parameters into /home/Reception/DATA/KITAACHEN/M4633_Reception_2016-07-27_10h21m_1469607660//000_ReceptionTestBB4_p17//readbackCal_C4.dat
[08:33:33.342] <TB1> INFO: write readback calibration parameters into /home/Reception/DATA/KITAACHEN/M4633_Reception_2016-07-27_10h21m_1469607660//000_ReceptionTestBB4_p17//readbackCal_C5.dat
[08:33:33.355] <TB1> INFO: write readback calibration parameters into /home/Reception/DATA/KITAACHEN/M4633_Reception_2016-07-27_10h21m_1469607660//000_ReceptionTestBB4_p17//readbackCal_C6.dat
[08:33:33.369] <TB1> INFO: write readback calibration parameters into /home/Reception/DATA/KITAACHEN/M4633_Reception_2016-07-27_10h21m_1469607660//000_ReceptionTestBB4_p17//readbackCal_C7.dat
[08:33:33.379] <TB1> INFO: write readback calibration parameters into /home/Reception/DATA/KITAACHEN/M4633_Reception_2016-07-27_10h21m_1469607660//000_ReceptionTestBB4_p17//readbackCal_C8.dat
[08:33:33.385] <TB1> INFO: write readback calibration parameters into /home/Reception/DATA/KITAACHEN/M4633_Reception_2016-07-27_10h21m_1469607660//000_ReceptionTestBB4_p17//readbackCal_C9.dat
[08:33:33.391] <TB1> INFO: write readback calibration parameters into /home/Reception/DATA/KITAACHEN/M4633_Reception_2016-07-27_10h21m_1469607660//000_ReceptionTestBB4_p17//readbackCal_C10.dat
[08:33:33.398] <TB1> INFO: write readback calibration parameters into /home/Reception/DATA/KITAACHEN/M4633_Reception_2016-07-27_10h21m_1469607660//000_ReceptionTestBB4_p17//readbackCal_C11.dat
[08:33:33.404] <TB1> INFO: write readback calibration parameters into /home/Reception/DATA/KITAACHEN/M4633_Reception_2016-07-27_10h21m_1469607660//000_ReceptionTestBB4_p17//readbackCal_C12.dat
[08:33:33.412] <TB1> INFO: write readback calibration parameters into /home/Reception/DATA/KITAACHEN/M4633_Reception_2016-07-27_10h21m_1469607660//000_ReceptionTestBB4_p17//readbackCal_C13.dat
[08:33:33.418] <TB1> INFO: write readback calibration parameters into /home/Reception/DATA/KITAACHEN/M4633_Reception_2016-07-27_10h21m_1469607660//000_ReceptionTestBB4_p17//readbackCal_C14.dat
[08:33:33.423] <TB1> INFO: write readback calibration parameters into /home/Reception/DATA/KITAACHEN/M4633_Reception_2016-07-27_10h21m_1469607660//000_ReceptionTestBB4_p17//readbackCal_C15.dat
[08:33:33.458] <TB1> INFO: PixTestPattern:: pg_setup set to default.
[08:33:33.461] <TB1> INFO: PixTestReadback::doTest() done
[08:33:33.487] <TB1> INFO: enter test to run
[08:33:33.487] <TB1> INFO: test: BB4 no parameter change
[08:33:33.487] <TB1> INFO: running: bb4
[08:33:33.487] <TB1> INFO: PixTestBB4Map::doTest() ntrig = 100
[08:33:33.487] <TB1> INFO: VthrComp (min,max,step) = 70 130 5
[08:33:33.487] <TB1> INFO: CalDel (min,max,step) = 80 200 10
[08:33:33.487] <TB1> INFO: Vcal = 255
[08:33:33.487] <TB1> INFO: Cut = 0.5
[08:33:33.487] <TB1> INFO: flag 2
[08:33:33.488] <TB1> INFO: CtrlReg 4 (large Vcal)
[08:33:33.488] <TB1> INFO: saving VthrComp and CalDel before running tests)
[08:33:33.488] <TB1> INFO: 0 130 0
[08:33:33.488] <TB1> INFO: 1 130 0
[08:33:33.488] <TB1> INFO: 2 130 0
[08:33:33.488] <TB1> INFO: 3 130 0
[08:33:33.488] <TB1> INFO: 4 130 0
[08:33:33.488] <TB1> INFO: 5 130 0
[08:33:33.488] <TB1> INFO: 6 130 0
[08:33:33.488] <TB1> INFO: 7 130 0
[08:33:33.488] <TB1> INFO: 8 130 0
[08:33:33.488] <TB1> INFO: 9 130 0
[08:33:33.488] <TB1> INFO: 10 130 0
[08:33:33.488] <TB1> INFO: 11 130 0
[08:33:33.488] <TB1> INFO: 12 130 0
[08:33:33.488] <TB1> INFO: 13 130 0
[08:33:33.488] <TB1> INFO: 14 130 0
[08:33:33.488] <TB1> INFO: 15 130 0
[08:33:33.775] <TB1> INFO: Expecting 416000 events.
[08:33:38.425] <TB1> ERROR: <datapipe.cc/CheckEventValidity:L507> Channel 0 Number of ROCs (0) != Token Chain Length (8)
[08:33:38.451] <TB1> ERROR: <datapipe.cc/CheckEventID:L469> Channel 0 Event ID mismatch: local ID (16) != TBM ID (2)
[08:33:43.201] <TB1> INFO: 416000 events read in total (8870ms).
[08:33:43.202] <TB1> INFO: Test took 9710ms.
[08:33:43.202] <TB1> INFO: starting loop over VthrComp
[08:33:43.740] <TB1> INFO: Expecting 416000 events.
[08:33:53.053] <TB1> INFO: 416000 events read in total (8757ms).
[08:33:53.053] <TB1> INFO: Test took 9576ms.
[08:33:53.054] <TB1> INFO: got noisemap
[08:33:53.328] <TB1> INFO: ROC 0, integral 0
[08:33:53.328] <TB1> INFO: ROC 1, integral 0
[08:33:53.328] <TB1> INFO: ROC 2, integral 0
[08:33:53.328] <TB1> INFO: ROC 3, integral 0
[08:33:53.328] <TB1> INFO: ROC 4, integral 0
[08:33:53.328] <TB1> INFO: ROC 5, integral 0
[08:33:53.328] <TB1> INFO: ROC 6, integral 0
[08:33:53.328] <TB1> INFO: ROC 7, integral 0
[08:33:53.328] <TB1> INFO: ROC 8, integral 0
[08:33:53.328] <TB1> INFO: ROC 9, integral 0
[08:33:53.329] <TB1> INFO: ROC 10, integral 0
[08:33:53.329] <TB1> INFO: ROC 11, integral 0
[08:33:53.329] <TB1> INFO: ROC 12, integral 0
[08:33:53.329] <TB1> INFO: ROC 13, integral 0
[08:33:53.329] <TB1> INFO: ROC 14, integral 0
[08:33:53.329] <TB1> INFO: ROC 15, integral 0
[08:33:53.329] <TB1> INFO: VthrComp = 70 done
[08:33:53.604] <TB1> INFO: Expecting 416000 events.
[08:34:02.921] <TB1> INFO: 416000 events read in total (8762ms).
[08:34:02.922] <TB1> INFO: Test took 9590ms.
[08:34:02.922] <TB1> INFO: got noisemap
[08:34:03.194] <TB1> INFO: ROC 0, integral 0
[08:34:03.194] <TB1> INFO: ROC 1, integral 0
[08:34:03.194] <TB1> INFO: ROC 2, integral 0
[08:34:03.195] <TB1> INFO: ROC 3, integral 0
[08:34:03.195] <TB1> INFO: ROC 4, integral 0
[08:34:03.195] <TB1> INFO: ROC 5, integral 0
[08:34:03.195] <TB1> INFO: ROC 6, integral 0
[08:34:03.195] <TB1> INFO: ROC 7, integral 0
[08:34:03.195] <TB1> INFO: ROC 8, integral 0
[08:34:03.195] <TB1> INFO: ROC 9, integral 0
[08:34:03.196] <TB1> INFO: ROC 10, integral 0
[08:34:03.196] <TB1> INFO: ROC 11, integral 0
[08:34:03.196] <TB1> INFO: ROC 12, integral 0
[08:34:03.196] <TB1> INFO: ROC 13, integral 0
[08:34:03.196] <TB1> INFO: ROC 14, integral 0
[08:34:03.196] <TB1> INFO: ROC 15, integral 0
[08:34:03.196] <TB1> INFO: VthrComp = 75 done
[08:34:03.471] <TB1> INFO: Expecting 416000 events.
[08:34:12.917] <TB1> INFO: 416000 events read in total (8891ms).
[08:34:12.919] <TB1> INFO: Test took 9718ms.
[08:34:12.919] <TB1> INFO: got noisemap
[08:34:13.187] <TB1> INFO: ROC 0, integral 0
[08:34:13.187] <TB1> INFO: ROC 1, integral 0
[08:34:13.187] <TB1> INFO: ROC 2, integral 0
[08:34:13.187] <TB1> INFO: ROC 3, integral 0
[08:34:13.187] <TB1> INFO: ROC 4, integral 0
[08:34:13.187] <TB1> INFO: ROC 5, integral 0
[08:34:13.187] <TB1> INFO: ROC 6, integral 0
[08:34:13.187] <TB1> INFO: ROC 7, integral 0
[08:34:13.188] <TB1> INFO: ROC 8, integral 0
[08:34:13.188] <TB1> INFO: ROC 9, integral 0
[08:34:13.188] <TB1> INFO: ROC 10, integral 0
[08:34:13.188] <TB1> INFO: ROC 11, integral 0
[08:34:13.188] <TB1> INFO: ROC 12, integral 0
[08:34:13.188] <TB1> INFO: ROC 13, integral 0
[08:34:13.188] <TB1> INFO: ROC 14, integral 0
[08:34:13.188] <TB1> INFO: ROC 15, integral 0
[08:34:13.188] <TB1> INFO: VthrComp = 80 done
[08:34:13.479] <TB1> INFO: Expecting 416000 events.
[08:34:22.761] <TB1> INFO: 416000 events read in total (8727ms).
[08:34:22.762] <TB1> INFO: Test took 9571ms.
[08:34:22.762] <TB1> INFO: got noisemap
[08:34:23.035] <TB1> INFO: ROC 0, integral 0
[08:34:23.035] <TB1> INFO: ROC 1, integral 0
[08:34:23.036] <TB1> INFO: ROC 2, integral 0
[08:34:23.036] <TB1> INFO: ROC 3, integral 0
[08:34:23.036] <TB1> INFO: ROC 4, integral 0
[08:34:23.036] <TB1> INFO: ROC 5, integral 0
[08:34:23.036] <TB1> INFO: ROC 6, integral 0
[08:34:23.037] <TB1> INFO: ROC 7, integral 0
[08:34:23.037] <TB1> INFO: ROC 8, integral 0
[08:34:23.037] <TB1> INFO: ROC 9, integral 0
[08:34:23.037] <TB1> INFO: ROC 10, integral 0
[08:34:23.037] <TB1> INFO: ROC 11, integral 0
[08:34:23.037] <TB1> INFO: ROC 12, integral 0
[08:34:23.037] <TB1> INFO: ROC 13, integral 0
[08:34:23.038] <TB1> INFO: ROC 14, integral 0
[08:34:23.038] <TB1> INFO: ROC 15, integral 0
[08:34:23.038] <TB1> INFO: VthrComp = 85 done
[08:34:23.310] <TB1> INFO: Expecting 416000 events.
[08:34:32.695] <TB1> INFO: 416000 events read in total (8830ms).
[08:34:32.696] <TB1> INFO: Test took 9653ms.
[08:34:32.697] <TB1> INFO: got noisemap
[08:34:32.966] <TB1> INFO: ROC 0, integral 0
[08:34:32.966] <TB1> INFO: ROC 1, integral 0
[08:34:32.966] <TB1> INFO: ROC 2, integral 0
[08:34:32.966] <TB1> INFO: ROC 3, integral 0
[08:34:32.966] <TB1> INFO: ROC 4, integral 0
[08:34:32.966] <TB1> INFO: ROC 5, integral 0
[08:34:32.966] <TB1> INFO: ROC 6, integral 0
[08:34:32.966] <TB1> INFO: ROC 7, integral 0
[08:34:32.967] <TB1> INFO: ROC 8, integral 0
[08:34:32.967] <TB1> INFO: ROC 9, integral 0
[08:34:32.967] <TB1> INFO: ROC 10, integral 0
[08:34:32.967] <TB1> INFO: ROC 11, integral 0
[08:34:32.967] <TB1> INFO: ROC 12, integral 0
[08:34:32.967] <TB1> INFO: ROC 13, integral 0
[08:34:32.967] <TB1> INFO: ROC 14, integral 0
[08:34:32.967] <TB1> INFO: ROC 15, integral 0
[08:34:32.967] <TB1> INFO: VthrComp = 90 done
[08:34:33.235] <TB1> INFO: Expecting 416000 events.
[08:34:42.581] <TB1> INFO: 416000 events read in total (8790ms).
[08:34:42.582] <TB1> INFO: Test took 9612ms.
[08:34:42.582] <TB1> INFO: got noisemap
[08:34:42.857] <TB1> INFO: ROC 0, integral 0
[08:34:42.857] <TB1> INFO: ROC 1, integral 0
[08:34:42.857] <TB1> INFO: ROC 2, integral 0
[08:34:42.857] <TB1> INFO: ROC 3, integral 0
[08:34:42.857] <TB1> INFO: ROC 4, integral 0
[08:34:42.857] <TB1> INFO: ROC 5, integral 0
[08:34:42.857] <TB1> INFO: ROC 6, integral 0
[08:34:42.857] <TB1> INFO: ROC 7, integral 0
[08:34:42.857] <TB1> INFO: ROC 8, integral 0
[08:34:42.857] <TB1> INFO: ROC 9, integral 0
[08:34:42.857] <TB1> INFO: ROC 10, integral 0
[08:34:42.857] <TB1> INFO: ROC 11, integral 0
[08:34:42.858] <TB1> INFO: ROC 12, integral 0
[08:34:42.858] <TB1> INFO: ROC 13, integral 0
[08:34:42.858] <TB1> INFO: ROC 14, integral 0
[08:34:42.858] <TB1> INFO: ROC 15, integral 0
[08:34:42.858] <TB1> INFO: VthrComp = 95 done
[08:34:43.143] <TB1> INFO: Expecting 416000 events.
[08:34:52.475] <TB1> INFO: 416000 events read in total (8777ms).
[08:34:52.476] <TB1> INFO: Test took 9615ms.
[08:34:52.476] <TB1> INFO: got noisemap
[08:34:52.751] <TB1> INFO: ROC 0, integral 0
[08:34:52.751] <TB1> INFO: ROC 1, integral 0
[08:34:52.752] <TB1> INFO: ROC 2, integral 0
[08:34:52.752] <TB1> INFO: ROC 3, integral 0
[08:34:52.752] <TB1> INFO: ROC 4, integral 0
[08:34:52.752] <TB1> INFO: ROC 5, integral 0
[08:34:52.752] <TB1> INFO: ROC 6, integral 0
[08:34:52.752] <TB1> INFO: ROC 7, integral 0
[08:34:52.752] <TB1> INFO: ROC 8, integral 0
[08:34:52.752] <TB1> INFO: ROC 9, integral 0
[08:34:52.753] <TB1> INFO: ROC 10, integral 0
[08:34:52.753] <TB1> INFO: ROC 11, integral 0
[08:34:52.753] <TB1> INFO: ROC 12, integral 0
[08:34:52.753] <TB1> INFO: ROC 13, integral 0
[08:34:52.753] <TB1> INFO: ROC 14, integral 0
[08:34:52.753] <TB1> INFO: ROC 15, integral 0
[08:34:52.753] <TB1> INFO: VthrComp = 100 done
[08:34:53.031] <TB1> INFO: Expecting 416000 events.
[08:35:02.386] <TB1> INFO: 416000 events read in total (8800ms).
[08:35:02.388] <TB1> INFO: Test took 9631ms.
[08:35:02.388] <TB1> INFO: got noisemap
[08:35:02.656] <TB1> INFO: ROC 0, integral 0
[08:35:02.656] <TB1> INFO: ROC 1, integral 0
[08:35:02.656] <TB1> INFO: ROC 2, integral 0
[08:35:02.657] <TB1> INFO: ROC 3, integral 0
[08:35:02.657] <TB1> INFO: ROC 4, integral 0
[08:35:02.657] <TB1> INFO: ROC 5, integral 0
[08:35:02.657] <TB1> INFO: ROC 6, integral 0
[08:35:02.657] <TB1> INFO: ROC 7, integral 0
[08:35:02.657] <TB1> INFO: ROC 8, integral 0
[08:35:02.657] <TB1> INFO: ROC 9, integral 0
[08:35:02.657] <TB1> INFO: ROC 10, integral 0
[08:35:02.657] <TB1> INFO: ROC 11, integral 0
[08:35:02.657] <TB1> INFO: ROC 12, integral 0
[08:35:02.657] <TB1> INFO: ROC 13, integral 0
[08:35:02.657] <TB1> INFO: ROC 14, integral 0
[08:35:02.657] <TB1> INFO: ROC 15, integral 0
[08:35:02.657] <TB1> INFO: VthrComp = 105 done
[08:35:02.944] <TB1> INFO: Expecting 416000 events.
[08:35:12.358] <TB1> INFO: 416000 events read in total (8858ms).
[08:35:12.359] <TB1> INFO: Test took 9698ms.
[08:35:12.359] <TB1> INFO: got noisemap
[08:35:12.629] <TB1> INFO: ROC 0, integral 0
[08:35:12.629] <TB1> INFO: ROC 1, integral 0
[08:35:12.629] <TB1> INFO: ROC 2, integral 1
[08:35:12.629] <TB1> INFO: ROC 3, integral 0
[08:35:12.630] <TB1> INFO: ROC 4, integral 0
[08:35:12.630] <TB1> INFO: ROC 5, integral 0
[08:35:12.630] <TB1> INFO: ROC 6, integral 0
[08:35:12.630] <TB1> INFO: ROC 7, integral 0
[08:35:12.630] <TB1> INFO: ROC 8, integral 0
[08:35:12.630] <TB1> INFO: ROC 9, integral 1
[08:35:12.630] <TB1> INFO: ROC 10, integral 0
[08:35:12.630] <TB1> INFO: ROC 11, integral 6
[08:35:12.630] <TB1> INFO: ROC 12, integral 0
[08:35:12.630] <TB1> INFO: ROC 13, integral 0
[08:35:12.630] <TB1> INFO: ROC 14, integral 1
[08:35:12.630] <TB1> INFO: ROC 15, integral 0
[08:35:12.630] <TB1> INFO: VthrComp = 110 done
[08:35:12.905] <TB1> INFO: Expecting 416000 events.
[08:35:22.258] <TB1> INFO: 416000 events read in total (8798ms).
[08:35:22.259] <TB1> INFO: Test took 9626ms.
[08:35:22.259] <TB1> INFO: got noisemap
[08:35:22.530] <TB1> INFO: ROC 0, integral 0
[08:35:22.530] <TB1> INFO: ROC 1, integral 16
[08:35:22.530] <TB1> INFO: ROC #1: threshold found
[08:35:22.530] <TB1> INFO: ROC 2, integral 26
[08:35:22.530] <TB1> INFO: ROC #2: threshold found
[08:35:22.530] <TB1> INFO: ROC 3, integral 0
[08:35:22.530] <TB1> INFO: ROC 4, integral 0
[08:35:22.530] <TB1> INFO: ROC 5, integral 10
[08:35:22.530] <TB1> INFO: ROC #5: threshold found
[08:35:22.530] <TB1> INFO: ROC 6, integral 8
[08:35:22.530] <TB1> INFO: ROC 7, integral 19
[08:35:22.530] <TB1> INFO: ROC #7: threshold found
[08:35:22.530] <TB1> INFO: ROC 8, integral 0
[08:35:22.530] <TB1> INFO: ROC 9, integral 99
[08:35:22.530] <TB1> INFO: ROC #9: threshold found
[08:35:22.530] <TB1> INFO: ROC 10, integral 1
[08:35:22.530] <TB1> INFO: ROC 11, integral 169
[08:35:22.530] <TB1> INFO: ROC #11: threshold found
[08:35:22.530] <TB1> INFO: ROC 12, integral 39
[08:35:22.530] <TB1> INFO: ROC #12: threshold found
[08:35:22.530] <TB1> INFO: ROC 13, integral 2
[08:35:22.530] <TB1> INFO: ROC 14, integral 14
[08:35:22.530] <TB1> INFO: ROC #14: threshold found
[08:35:22.531] <TB1> INFO: ROC 15, integral 0
[08:35:22.531] <TB1> INFO: VthrComp = 115 done
[08:35:22.813] <TB1> INFO: Expecting 416000 events.
[08:35:32.228] <TB1> INFO: 416000 events read in total (8859ms).
[08:35:32.229] <TB1> INFO: Test took 9695ms.
[08:35:32.230] <TB1> INFO: got noisemap
[08:35:32.503] <TB1> INFO: ROC 0, integral 6
[08:35:32.503] <TB1> INFO: ROC 3, integral 6
[08:35:32.503] <TB1> INFO: ROC 4, integral 1
[08:35:32.503] <TB1> INFO: ROC 6, integral 30
[08:35:32.503] <TB1> INFO: ROC #6: threshold found
[08:35:32.503] <TB1> INFO: ROC 8, integral 3
[08:35:32.503] <TB1> INFO: ROC 10, integral 7
[08:35:32.503] <TB1> INFO: ROC 13, integral 21
[08:35:32.503] <TB1> INFO: ROC #13: threshold found
[08:35:32.503] <TB1> INFO: ROC 15, integral 1
[08:35:32.503] <TB1> INFO: VthrComp = 120 done
[08:35:32.792] <TB1> INFO: Expecting 416000 events.
[08:35:42.145] <TB1> INFO: 416000 events read in total (8797ms).
[08:35:42.146] <TB1> INFO: Test took 9639ms.
[08:35:42.147] <TB1> INFO: got noisemap
[08:35:42.416] <TB1> INFO: ROC 0, integral 42
[08:35:42.416] <TB1> INFO: ROC #0: threshold found
[08:35:42.416] <TB1> INFO: ROC 3, integral 95
[08:35:42.416] <TB1> INFO: ROC #3: threshold found
[08:35:42.416] <TB1> INFO: ROC 4, integral 6
[08:35:42.416] <TB1> INFO: ROC 8, integral 57
[08:35:42.416] <TB1> INFO: ROC #8: threshold found
[08:35:42.416] <TB1> INFO: ROC 10, integral 113
[08:35:42.417] <TB1> INFO: ROC #10: threshold found
[08:35:42.417] <TB1> INFO: ROC 15, integral 49
[08:35:42.417] <TB1> INFO: ROC #15: threshold found
[08:35:42.417] <TB1> INFO: VthrComp = 125 done
[08:35:42.686] <TB1> INFO: Expecting 416000 events.
[08:35:52.024] <TB1> INFO: 416000 events read in total (8783ms).
[08:35:52.025] <TB1> INFO: Test took 9605ms.
[08:35:52.027] <TB1> INFO: got noisemap
[08:35:52.292] <TB1> INFO: ROC 4, integral 47
[08:35:52.292] <TB1> INFO: ROC #4: threshold found
[08:35:52.292] <TB1> INFO: VthrComp = 130 done
[08:35:52.292] <TB1> INFO: Minimal threshold for all ROCs found
[08:35:52.293] <TB1> INFO: resulting noise thresholds per ROC = 120 110 110 120 125 110 115 110 120 110 120 110 110 115 110 120
[08:35:52.293] <TB1> INFO: threshold scan done
[08:35:52.570] <TB1> INFO: Expecting 416000 events.
[08:36:01.938] <TB1> INFO: 416000 events read in total (8813ms).
[08:36:01.939] <TB1> INFO: Test took 9640ms.
[08:36:02.205] <TB1> INFO: ROC 0, missing bumps = 4160, total number of good bumps = 0
[08:36:02.207] <TB1> INFO: ROC 1, missing bumps = 4160, total number of good bumps = 0
[08:36:02.217] <TB1> INFO: ROC 2, missing bumps = 4160, total number of good bumps = 0
[08:36:02.219] <TB1> INFO: ROC 3, missing bumps = 4159, total number of good bumps = 1
[08:36:02.220] <TB1> INFO: ROC 4, missing bumps = 4160, total number of good bumps = 0
[08:36:02.221] <TB1> INFO: ROC 5, missing bumps = 4078, total number of good bumps = 82
[08:36:02.222] <TB1> INFO: ROC 6, missing bumps = 4156, total number of good bumps = 4
[08:36:02.224] <TB1> INFO: ROC 7, missing bumps = 4157, total number of good bumps = 3
[08:36:02.225] <TB1> INFO: ROC 8, missing bumps = 4159, total number of good bumps = 1
[08:36:02.226] <TB1> INFO: ROC 9, missing bumps = 4160, total number of good bumps = 0
[08:36:02.227] <TB1> INFO: ROC 10, missing bumps = 4159, total number of good bumps = 1
[08:36:02.229] <TB1> INFO: ROC 11, missing bumps = 4087, total number of good bumps = 73
[08:36:02.230] <TB1> INFO: ROC 12, missing bumps = 4160, total number of good bumps = 0
[08:36:02.231] <TB1> INFO: ROC 13, missing bumps = 4160, total number of good bumps = 0
[08:36:02.232] <TB1> INFO: ROC 14, missing bumps = 4160, total number of good bumps = 0
[08:36:02.233] <TB1> INFO: ROC 15, missing bumps = 4160, total number of good bumps = 0
[08:36:02.233] <TB1> INFO: CalDel = 80 done
[08:36:02.505] <TB1> INFO: Expecting 416000 events.
[08:36:12.463] <TB1> INFO: 416000 events read in total (9402ms).
[08:36:12.464] <TB1> INFO: Test took 10228ms.
[08:36:12.712] <TB1> INFO: ROC 0, missing bumps = 3997, total number of good bumps = 163
[08:36:12.713] <TB1> INFO: ROC 1, missing bumps = 4041, total number of good bumps = 119
[08:36:12.715] <TB1> INFO: ROC 2, missing bumps = 4140, total number of good bumps = 20
[08:36:12.716] <TB1> INFO: ROC 3, missing bumps = 3991, total number of good bumps = 169
[08:36:12.717] <TB1> INFO: ROC 4, missing bumps = 4155, total number of good bumps = 5
[08:36:12.718] <TB1> INFO: ROC 5, missing bumps = 2732, total number of good bumps = 1428
[08:36:12.719] <TB1> INFO: ROC 6, missing bumps = 4121, total number of good bumps = 39
[08:36:12.721] <TB1> INFO: ROC 7, missing bumps = 3594, total number of good bumps = 566
[08:36:12.722] <TB1> INFO: ROC 8, missing bumps = 4111, total number of good bumps = 49
[08:36:12.723] <TB1> INFO: ROC 9, missing bumps = 4157, total number of good bumps = 3
[08:36:12.724] <TB1> INFO: ROC 10, missing bumps = 3906, total number of good bumps = 254
[08:36:12.725] <TB1> INFO: ROC 11, missing bumps = 2423, total number of good bumps = 1737
[08:36:12.727] <TB1> INFO: ROC 12, missing bumps = 4142, total number of good bumps = 18
[08:36:12.728] <TB1> INFO: ROC 13, missing bumps = 3888, total number of good bumps = 272
[08:36:12.729] <TB1> INFO: ROC 14, missing bumps = 4160, total number of good bumps = 0
[08:36:12.730] <TB1> INFO: ROC 15, missing bumps = 4131, total number of good bumps = 29
[08:36:12.730] <TB1> INFO: CalDel = 90 done
[08:36:12.992] <TB1> INFO: Expecting 416000 events.
[08:36:25.962] <TB1> INFO: 416000 events read in total (12414ms).
[08:36:25.963] <TB1> INFO: Test took 13230ms.
[08:36:26.129] <TB1> INFO: ROC 0, missing bumps = 1149, total number of good bumps = 3011
[08:36:26.131] <TB1> INFO: ROC 1, missing bumps = 1532, total number of good bumps = 2628
[08:36:26.132] <TB1> INFO: ROC 2, missing bumps = 2965, total number of good bumps = 1195
[08:36:26.134] <TB1> INFO: ROC 3, missing bumps = 561, total number of good bumps = 3599
[08:36:26.136] <TB1> INFO: ROC 4, missing bumps = 3138, total number of good bumps = 1022
[08:36:26.137] <TB1> INFO: ROC 5, missing bumps = 65, total number of good bumps = 4095
[08:36:26.138] <TB1> INFO: ROC 6, missing bumps = 3046, total number of good bumps = 1114
[08:36:26.140] <TB1> INFO: ROC 7, missing bumps = 232, total number of good bumps = 3928
[08:36:26.141] <TB1> INFO: ROC 8, missing bumps = 2641, total number of good bumps = 1519
[08:36:26.142] <TB1> INFO: ROC 9, missing bumps = 3601, total number of good bumps = 559
[08:36:26.143] <TB1> INFO: ROC 10, missing bumps = 831, total number of good bumps = 3329
[08:36:26.144] <TB1> INFO: ROC 11, missing bumps = 32, total number of good bumps = 4128
[08:36:26.146] <TB1> INFO: ROC 12, missing bumps = 3036, total number of good bumps = 1124
[08:36:26.147] <TB1> INFO: ROC 13, missing bumps = 418, total number of good bumps = 3742
[08:36:26.148] <TB1> INFO: ROC 14, missing bumps = 4096, total number of good bumps = 64
[08:36:26.149] <TB1> INFO: ROC 15, missing bumps = 1505, total number of good bumps = 2655
[08:36:26.149] <TB1> INFO: CalDel = 100 done
[08:36:26.418] <TB1> INFO: Expecting 416000 events.
[08:36:41.700] <TB1> INFO: 416000 events read in total (14726ms).
[08:36:41.702] <TB1> INFO: Test took 15550ms.
[08:36:41.872] <TB1> INFO: ROC 0, missing bumps = 23, total number of good bumps = 4137
[08:36:41.873] <TB1> INFO: ROC 1, missing bumps = 23, total number of good bumps = 4137
[08:36:41.874] <TB1> INFO: ROC 2, missing bumps = 126, total number of good bumps = 4034
[08:36:41.875] <TB1> INFO: ROC 3, missing bumps = 4, total number of good bumps = 4156
[08:36:41.877] <TB1> INFO: ROC 4, missing bumps = 165, total number of good bumps = 3995
[08:36:41.878] <TB1> INFO: ROC 5, missing bumps = 0, total number of good bumps = 4160
[08:36:41.879] <TB1> INFO: ROC 6, missing bumps = 430, total number of good bumps = 3730
[08:36:41.880] <TB1> INFO: ROC 7, missing bumps = 0, total number of good bumps = 4160
[08:36:41.881] <TB1> INFO: ROC 8, missing bumps = 172, total number of good bumps = 3988
[08:36:41.883] <TB1> INFO: ROC 9, missing bumps = 686, total number of good bumps = 3474
[08:36:41.884] <TB1> INFO: ROC 10, missing bumps = 18, total number of good bumps = 4142
[08:36:41.885] <TB1> INFO: ROC 11, missing bumps = 2, total number of good bumps = 4158
[08:36:41.886] <TB1> INFO: ROC 12, missing bumps = 237, total number of good bumps = 3923
[08:36:41.887] <TB1> INFO: ROC 13, missing bumps = 5, total number of good bumps = 4155
[08:36:41.889] <TB1> INFO: ROC 14, missing bumps = 2204, total number of good bumps = 1956
[08:36:41.890] <TB1> INFO: ROC 15, missing bumps = 8, total number of good bumps = 4152
[08:36:41.890] <TB1> INFO: CalDel = 110 done
[08:36:42.160] <TB1> INFO: Expecting 416000 events.
[08:36:57.718] <TB1> INFO: 416000 events read in total (15003ms).
[08:36:57.720] <TB1> INFO: Test took 15827ms.
[08:36:57.885] <TB1> INFO: ROC 0, missing bumps = 5, total number of good bumps = 4155
[08:36:57.886] <TB1> INFO: ROC 1, missing bumps = 0, total number of good bumps = 4160
[08:36:57.887] <TB1> INFO: ROC 2, missing bumps = 2, total number of good bumps = 4158
[08:36:57.888] <TB1> INFO: ROC 3, missing bumps = 2, total number of good bumps = 4158
[08:36:57.889] <TB1> INFO: ROC 4, missing bumps = 5, total number of good bumps = 4155
[08:36:57.891] <TB1> INFO: ROC 6, missing bumps = 9, total number of good bumps = 4151
[08:36:57.892] <TB1> INFO: ROC 8, missing bumps = 5, total number of good bumps = 4155
[08:36:57.893] <TB1> INFO: ROC 9, missing bumps = 3, total number of good bumps = 4157
[08:36:57.894] <TB1> INFO: ROC 10, missing bumps = 5, total number of good bumps = 4155
[08:36:57.895] <TB1> INFO: ROC 11, missing bumps = 2, total number of good bumps = 4158
[08:36:57.896] <TB1> INFO: ROC 12, missing bumps = 1, total number of good bumps = 4159
[08:36:57.897] <TB1> INFO: ROC 13, missing bumps = 1, total number of good bumps = 4159
[08:36:57.899] <TB1> INFO: ROC 14, missing bumps = 5, total number of good bumps = 4155
[08:36:57.900] <TB1> INFO: ROC 15, missing bumps = 1, total number of good bumps = 4159
[08:36:57.900] <TB1> INFO: CalDel = 120 done
[08:36:58.164] <TB1> INFO: Expecting 416000 events.
[08:37:13.863] <TB1> INFO: 416000 events read in total (15143ms).
[08:37:13.865] <TB1> INFO: Test took 15962ms.
[08:37:14.030] <TB1> INFO: ROC 0, missing bumps = 3, total number of good bumps = 4157
[08:37:14.032] <TB1> INFO: ROC 2, missing bumps = 1, total number of good bumps = 4159
[08:37:14.033] <TB1> INFO: ROC 3, missing bumps = 2, total number of good bumps = 4158
[08:37:14.035] <TB1> INFO: ROC 4, missing bumps = 5, total number of good bumps = 4155
[08:37:14.036] <TB1> INFO: ROC 6, missing bumps = 5, total number of good bumps = 4155
[08:37:14.038] <TB1> INFO: ROC 8, missing bumps = 2, total number of good bumps = 4158
[08:37:14.039] <TB1> INFO: ROC 9, missing bumps = 0, total number of good bumps = 4160
[08:37:14.041] <TB1> INFO: ROC 10, missing bumps = 4, total number of good bumps = 4156
[08:37:14.042] <TB1> INFO: ROC 11, missing bumps = 2, total number of good bumps = 4159
[08:37:14.044] <TB1> INFO: ROC 12, missing bumps = 1, total number of good bumps = 4159
[08:37:14.045] <TB1> INFO: ROC 13, missing bumps = 1, total number of good bumps = 4159
[08:37:14.047] <TB1> INFO: ROC 14, missing bumps = 1, total number of good bumps = 4159
[08:37:14.048] <TB1> INFO: ROC 15, missing bumps = 1, total number of good bumps = 4159
[08:37:14.048] <TB1> INFO: CalDel = 130 done
[08:37:14.325] <TB1> INFO: Expecting 416000 events.
[08:37:29.873] <TB1> INFO: 416000 events read in total (14993ms).
[08:37:29.880] <TB1> INFO: Test took 15827ms.
[08:37:30.045] <TB1> INFO: ROC 0, missing bumps = 2, total number of good bumps = 4158
[08:37:30.046] <TB1> INFO: ROC 2, missing bumps = 1, total number of good bumps = 4159
[08:37:30.047] <TB1> INFO: ROC 3, missing bumps = 3, total number of good bumps = 4158
[08:37:30.048] <TB1> INFO: ROC 4, missing bumps = 3, total number of good bumps = 4157
[08:37:30.049] <TB1> INFO: ROC 6, missing bumps = 3, total number of good bumps = 4157
[08:37:30.050] <TB1> INFO: ROC 8, missing bumps = 2, total number of good bumps = 4158
[08:37:30.052] <TB1> INFO: ROC 10, missing bumps = 4, total number of good bumps = 4156
[08:37:30.053] <TB1> INFO: ROC 11, missing bumps = 46, total number of good bumps = 4159
[08:37:30.054] <TB1> INFO: ROC 12, missing bumps = 0, total number of good bumps = 4160
[08:37:30.056] <TB1> INFO: ROC 13, missing bumps = 1, total number of good bumps = 4159
[08:37:30.057] <TB1> INFO: ROC 14, missing bumps = 1, total number of good bumps = 4159
[08:37:30.058] <TB1> INFO: ROC 15, missing bumps = 0, total number of good bumps = 4160
[08:37:30.058] <TB1> INFO: CalDel = 140 done
[08:37:30.332] <TB1> INFO: Expecting 416000 events.
[08:37:45.420] <TB1> INFO: 416000 events read in total (14533ms).
[08:37:45.422] <TB1> INFO: Test took 15361ms.
[08:37:45.591] <TB1> INFO: ROC 0, missing bumps = 99, total number of good bumps = 4160
[08:37:45.593] <TB1> INFO: ROC 2, missing bumps = 9, total number of good bumps = 4160
[08:37:45.594] <TB1> INFO: ROC 3, missing bumps = 270, total number of good bumps = 4159
[08:37:45.595] <TB1> INFO: ROC 4, missing bumps = 5, total number of good bumps = 4157
[08:37:45.596] <TB1> INFO: ROC 6, missing bumps = 19, total number of good bumps = 4158
[08:37:45.597] <TB1> INFO: ROC 8, missing bumps = 42, total number of good bumps = 4158
[08:37:45.598] <TB1> INFO: ROC 10, missing bumps = 202, total number of good bumps = 4157
[08:37:45.599] <TB1> INFO: ROC 11, missing bumps = 2984, total number of good bumps = 4159
[08:37:45.600] <TB1> INFO: ROC 13, missing bumps = 307, total number of good bumps = 4160
[08:37:45.601] <TB1> INFO: ROC 14, missing bumps = 1, total number of good bumps = 4159
[08:37:45.602] <TB1> INFO: CalDel = 150 done
[08:37:45.869] <TB1> INFO: Expecting 416000 events.
[08:37:58.669] <TB1> INFO: 416000 events read in total (12245ms).
[08:37:58.671] <TB1> INFO: Test took 13067ms.
[08:37:58.837] <TB1> INFO: ROC 0, missing bumps = 2212, total number of good bumps = 4160
[08:37:58.838] <TB1> INFO: ROC 2, missing bumps = 458, total number of good bumps = 4160
[08:37:58.839] <TB1> INFO: ROC 3, missing bumps = 3487, total number of good bumps = 4160
[08:37:58.840] <TB1> INFO: ROC 4, missing bumps = 467, total number of good bumps = 4157
[08:37:58.841] <TB1> INFO: ROC 6, missing bumps = 406, total number of good bumps = 4158
[08:37:58.842] <TB1> INFO: ROC 8, missing bumps = 991, total number of good bumps = 4158
[08:37:58.844] <TB1> INFO: ROC 10, missing bumps = 2718, total number of good bumps = 4157
[08:37:58.845] <TB1> INFO: ROC 11, missing bumps = 4149, total number of good bumps = 4159
[08:37:58.846] <TB1> INFO: ROC 13, missing bumps = 3479, total number of good bumps = 4160
[08:37:58.847] <TB1> INFO: ROC 14, missing bumps = 9, total number of good bumps = 4159
[08:37:58.847] <TB1> INFO: CalDel = 160 done
[08:37:59.110] <TB1> INFO: Expecting 416000 events.
[08:38:09.552] <TB1> INFO: 416000 events read in total (9887ms).
[08:38:09.553] <TB1> INFO: Test took 10703ms.
[08:38:09.782] <TB1> INFO: ROC 0, missing bumps = 4105, total number of good bumps = 4160
[08:38:09.783] <TB1> INFO: ROC 2, missing bumps = 3465, total number of good bumps = 4160
[08:38:09.784] <TB1> INFO: ROC 3, missing bumps = 4152, total number of good bumps = 4160
[08:38:09.785] <TB1> INFO: ROC 4, missing bumps = 3635, total number of good bumps = 4158
[08:38:09.787] <TB1> INFO: ROC 6, missing bumps = 2703, total number of good bumps = 4158
[08:38:09.788] <TB1> INFO: ROC 8, missing bumps = 3734, total number of good bumps = 4159
[08:38:09.789] <TB1> INFO: ROC 10, missing bumps = 4121, total number of good bumps = 4159
[08:38:09.790] <TB1> INFO: ROC 11, missing bumps = 4159, total number of good bumps = 4159
[08:38:09.791] <TB1> INFO: ROC 13, missing bumps = 4150, total number of good bumps = 4160
[08:38:09.792] <TB1> INFO: ROC 14, missing bumps = 484, total number of good bumps = 4159
[08:38:09.792] <TB1> INFO: CalDel = 170 done
[08:38:10.054] <TB1> INFO: Expecting 416000 events.
[08:38:19.469] <TB1> INFO: 416000 events read in total (8859ms).
[08:38:19.471] <TB1> INFO: Test took 9676ms.
[08:38:19.738] <TB1> INFO: ROC 0, missing bumps = 4155, total number of good bumps = 4160
[08:38:19.739] <TB1> INFO: ROC 2, missing bumps = 4159, total number of good bumps = 4160
[08:38:19.741] <TB1> INFO: ROC 3, missing bumps = 4158, total number of good bumps = 4160
[08:38:19.742] <TB1> INFO: ROC 4, missing bumps = 4156, total number of good bumps = 4158
[08:38:19.744] <TB1> INFO: ROC 6, missing bumps = 4149, total number of good bumps = 4158
[08:38:19.745] <TB1> INFO: ROC 8, missing bumps = 4155, total number of good bumps = 4159
[08:38:19.746] <TB1> INFO: ROC 10, missing bumps = 4157, total number of good bumps = 4159
[08:38:19.748] <TB1> INFO: ROC 11, missing bumps = 4160, total number of good bumps = 4159
[08:38:19.749] <TB1> INFO: ROC 13, missing bumps = 4159, total number of good bumps = 4160
[08:38:19.750] <TB1> INFO: ROC 14, missing bumps = 4074, total number of good bumps = 4160
[08:38:19.750] <TB1> INFO: CalDel = 180 done
[08:38:20.043] <TB1> INFO: Expecting 416000 events.
[08:38:29.353] <TB1> INFO: 416000 events read in total (8754ms).
[08:38:29.354] <TB1> INFO: Test took 9599ms.
[08:38:29.623] <TB1> INFO: ROC 0, missing bumps = 4158, total number of good bumps = 4160
[08:38:29.624] <TB1> INFO: ROC 2, missing bumps = 4159, total number of good bumps = 4160
[08:38:29.625] <TB1> INFO: ROC 3, missing bumps = 4160, total number of good bumps = 4160
[08:38:29.626] <TB1> INFO: ROC 4, missing bumps = 4158, total number of good bumps = 4158
[08:38:29.627] <TB1> INFO: ROC 6, missing bumps = 4156, total number of good bumps = 4158
[08:38:29.628] <TB1> INFO: ROC 8, missing bumps = 4159, total number of good bumps = 4159
[08:38:29.629] <TB1> INFO: ROC 10, missing bumps = 4159, total number of good bumps = 4159
[08:38:29.631] <TB1> INFO: ROC 11, missing bumps = 4160, total number of good bumps = 4159
[08:38:29.632] <TB1> INFO: ROC 13, missing bumps = 4160, total number of good bumps = 4160
[08:38:29.633] <TB1> INFO: ROC 14, missing bumps = 4159, total number of good bumps = 4160
[08:38:29.633] <TB1> INFO: CalDel = 190 done
[08:38:29.900] <TB1> INFO: Expecting 416000 events.
[08:38:39.250] <TB1> INFO: 416000 events read in total (8795ms).
[08:38:39.252] <TB1> INFO: Test took 9616ms.
[08:38:39.515] <TB1> INFO: ROC 0, missing bumps = 4159, total number of good bumps = 4160
[08:38:39.516] <TB1> INFO: ROC 2, missing bumps = 4159, total number of good bumps = 4160
[08:38:39.517] <TB1> INFO: ROC 3, missing bumps = 4160, total number of good bumps = 4160
[08:38:39.518] <TB1> INFO: ROC 4, missing bumps = 4159, total number of good bumps = 4158
[08:38:39.519] <TB1> INFO: ROC 6, missing bumps = 4160, total number of good bumps = 4158
[08:38:39.520] <TB1> INFO: ROC 8, missing bumps = 4160, total number of good bumps = 4159
[08:38:39.521] <TB1> INFO: ROC 10, missing bumps = 4160, total number of good bumps = 4159
[08:38:39.522] <TB1> INFO: ROC 11, missing bumps = 4160, total number of good bumps = 4159
[08:38:39.524] <TB1> INFO: ROC 13, missing bumps = 4160, total number of good bumps = 4160
[08:38:39.525] <TB1> INFO: ROC 14, missing bumps = 4159, total number of good bumps = 4160
[08:38:39.525] <TB1> INFO: CalDel = 200 done
[08:38:39.525] <TB1> INFO: CalDel scan done
[08:38:39.526] <TB1> INFO: resulting values: CalDel = 140 120 130 120 140 110 140 110 130 130 130 110 140 120 130 140
[08:38:39.787] <TB1> INFO: Expecting 416000 events.
[08:38:55.161] <TB1> INFO: 416000 events read in total (14818ms).
[08:38:55.162] <TB1> INFO: Test took 15634ms.
[08:38:55.170] <TB1> INFO: maps.size() = 16
[08:38:55.388] <TB1> INFO: 7 broken Bump Bonds found
[08:38:55.389] <TB1> INFO: back to CtrlReg 0
[08:38:55.392] <TB1> INFO: PixTestBB4Map::doTest() done
[08:38:55.392] <TB1> INFO: Write out MinimumThreshold
[08:38:55.392] <TB1> INFO: Write out CalDel Scan_C0_V0
[08:38:55.392] <TB1> INFO: Write out PixelHit_C0_V0
[08:38:55.392] <TB1> INFO: Write out BB4MapBest_C0_V0
[08:38:55.392] <TB1> INFO: Write out NoiseScan_C0_V0
[08:38:55.392] <TB1> INFO: Write out BB4Map_C0_V14
[08:38:55.392] <TB1> INFO: Write out Hit Distr_C0_V0
[08:38:55.392] <TB1> INFO: Write out Hit Distr_C0_V0
[08:38:55.392] <TB1> INFO: Write out CalDel Scan_C1_V0
[08:38:55.392] <TB1> INFO: Write out PixelHit_C1_V0
[08:38:55.392] <TB1> INFO: Write out BB4MapBest_C1_V0
[08:38:55.393] <TB1> INFO: Write out NoiseScan_C1_V0
[08:38:55.393] <TB1> INFO: Write out BB4Map_C1_V14
[08:38:55.393] <TB1> INFO: Write out Hit Distr_C1_V0
[08:38:55.393] <TB1> INFO: Write out Hit Distr_C1_V0
[08:38:55.393] <TB1> INFO: Write out CalDel Scan_C2_V0
[08:38:55.393] <TB1> INFO: Write out PixelHit_C2_V0
[08:38:55.393] <TB1> INFO: Write out BB4MapBest_C2_V0
[08:38:55.393] <TB1> INFO: Write out NoiseScan_C2_V0
[08:38:55.393] <TB1> INFO: Write out BB4Map_C2_V14
[08:38:55.393] <TB1> INFO: Write out Hit Distr_C2_V0
[08:38:55.393] <TB1> INFO: Write out Hit Distr_C2_V0
[08:38:55.393] <TB1> INFO: Write out CalDel Scan_C3_V0
[08:38:55.393] <TB1> INFO: Write out PixelHit_C3_V0
[08:38:55.394] <TB1> INFO: Write out BB4MapBest_C3_V0
[08:38:55.394] <TB1> INFO: Write out NoiseScan_C3_V0
[08:38:55.394] <TB1> INFO: Write out BB4Map_C3_V14
[08:38:55.394] <TB1> INFO: Write out Hit Distr_C3_V0
[08:38:55.394] <TB1> INFO: Write out Hit Distr_C3_V0
[08:38:55.394] <TB1> INFO: Write out CalDel Scan_C4_V0
[08:38:55.394] <TB1> INFO: Write out PixelHit_C4_V0
[08:38:55.394] <TB1> INFO: Write out BB4MapBest_C4_V0
[08:38:55.394] <TB1> INFO: Write out NoiseScan_C4_V0
[08:38:55.394] <TB1> INFO: Write out BB4Map_C4_V14
[08:38:55.394] <TB1> INFO: Write out Hit Distr_C4_V0
[08:38:55.394] <TB1> INFO: Write out Hit Distr_C4_V0
[08:38:55.395] <TB1> INFO: Write out CalDel Scan_C5_V0
[08:38:55.395] <TB1> INFO: Write out PixelHit_C5_V0
[08:38:55.395] <TB1> INFO: Write out BB4MapBest_C5_V0
[08:38:55.395] <TB1> INFO: Write out NoiseScan_C5_V0
[08:38:55.395] <TB1> INFO: Write out BB4Map_C5_V14
[08:38:55.395] <TB1> INFO: Write out Hit Distr_C5_V0
[08:38:55.395] <TB1> INFO: Write out Hit Distr_C5_V0
[08:38:55.395] <TB1> INFO: Write out CalDel Scan_C6_V0
[08:38:55.395] <TB1> INFO: Write out PixelHit_C6_V0
[08:38:55.395] <TB1> INFO: Write out BB4MapBest_C6_V0
[08:38:55.395] <TB1> INFO: Write out NoiseScan_C6_V0
[08:38:55.395] <TB1> INFO: Write out BB4Map_C6_V14
[08:38:55.396] <TB1> INFO: Write out Hit Distr_C6_V0
[08:38:55.396] <TB1> INFO: Write out Hit Distr_C6_V0
[08:38:55.396] <TB1> INFO: Write out CalDel Scan_C7_V0
[08:38:55.396] <TB1> INFO: Write out PixelHit_C7_V0
[08:38:55.396] <TB1> INFO: Write out BB4MapBest_C7_V0
[08:38:55.396] <TB1> INFO: Write out NoiseScan_C7_V0
[08:38:55.396] <TB1> INFO: Write out BB4Map_C7_V14
[08:38:55.396] <TB1> INFO: Write out Hit Distr_C7_V0
[08:38:55.396] <TB1> INFO: Write out Hit Distr_C7_V0
[08:38:55.396] <TB1> INFO: Write out CalDel Scan_C8_V0
[08:38:55.396] <TB1> INFO: Write out PixelHit_C8_V0
[08:38:55.396] <TB1> INFO: Write out BB4MapBest_C8_V0
[08:38:55.397] <TB1> INFO: Write out NoiseScan_C8_V0
[08:38:55.397] <TB1> INFO: Write out BB4Map_C8_V14
[08:38:55.397] <TB1> INFO: Write out Hit Distr_C8_V0
[08:38:55.397] <TB1> INFO: Write out Hit Distr_C8_V0
[08:38:55.397] <TB1> INFO: Write out CalDel Scan_C9_V0
[08:38:55.397] <TB1> INFO: Write out PixelHit_C9_V0
[08:38:55.397] <TB1> INFO: Write out BB4MapBest_C9_V0
[08:38:55.397] <TB1> INFO: Write out NoiseScan_C9_V0
[08:38:55.397] <TB1> INFO: Write out BB4Map_C9_V14
[08:38:55.397] <TB1> INFO: Write out Hit Distr_C9_V0
[08:38:55.397] <TB1> INFO: Write out Hit Distr_C9_V0
[08:38:55.397] <TB1> INFO: Write out CalDel Scan_C10_V0
[08:38:55.397] <TB1> INFO: Write out PixelHit_C10_V0
[08:38:55.398] <TB1> INFO: Write out BB4MapBest_C10_V0
[08:38:55.398] <TB1> INFO: Write out NoiseScan_C10_V0
[08:38:55.398] <TB1> INFO: Write out BB4Map_C10_V14
[08:38:55.398] <TB1> INFO: Write out Hit Distr_C10_V0
[08:38:55.398] <TB1> INFO: Write out Hit Distr_C10_V0
[08:38:55.398] <TB1> INFO: Write out CalDel Scan_C11_V0
[08:38:55.398] <TB1> INFO: Write out PixelHit_C11_V0
[08:38:55.398] <TB1> INFO: Write out BB4MapBest_C11_V0
[08:38:55.398] <TB1> INFO: Write out NoiseScan_C11_V0
[08:38:55.398] <TB1> INFO: Write out BB4Map_C11_V14
[08:38:55.398] <TB1> INFO: Write out Hit Distr_C11_V0
[08:38:55.398] <TB1> INFO: Write out Hit Distr_C11_V0
[08:38:55.399] <TB1> INFO: Write out CalDel Scan_C12_V0
[08:38:55.399] <TB1> INFO: Write out PixelHit_C12_V0
[08:38:55.399] <TB1> INFO: Write out BB4MapBest_C12_V0
[08:38:55.399] <TB1> INFO: Write out NoiseScan_C12_V0
[08:38:55.399] <TB1> INFO: Write out BB4Map_C12_V14
[08:38:55.399] <TB1> INFO: Write out Hit Distr_C12_V0
[08:38:55.399] <TB1> INFO: Write out Hit Distr_C12_V0
[08:38:55.399] <TB1> INFO: Write out CalDel Scan_C13_V0
[08:38:55.399] <TB1> INFO: Write out PixelHit_C13_V0
[08:38:55.399] <TB1> INFO: Write out BB4MapBest_C13_V0
[08:38:55.399] <TB1> INFO: Write out NoiseScan_C13_V0
[08:38:55.399] <TB1> INFO: Write out BB4Map_C13_V14
[08:38:55.400] <TB1> INFO: Write out Hit Distr_C13_V0
[08:38:55.400] <TB1> INFO: Write out Hit Distr_C13_V0
[08:38:55.400] <TB1> INFO: Write out CalDel Scan_C14_V0
[08:38:55.400] <TB1> INFO: Write out PixelHit_C14_V0
[08:38:55.400] <TB1> INFO: Write out BB4MapBest_C14_V0
[08:38:55.400] <TB1> INFO: Write out NoiseScan_C14_V0
[08:38:55.400] <TB1> INFO: Write out BB4Map_C14_V14
[08:38:55.400] <TB1> INFO: Write out Hit Distr_C14_V0
[08:38:55.400] <TB1> INFO: Write out Hit Distr_C14_V0
[08:38:55.400] <TB1> INFO: Write out CalDel Scan_C15_V0
[08:38:55.400] <TB1> INFO: Write out PixelHit_C15_V0
[08:38:55.400] <TB1> INFO: Write out BB4MapBest_C15_V0
[08:38:55.400] <TB1> INFO: Write out NoiseScan_C15_V0
[08:38:55.400] <TB1> INFO: Write out BB4Map_C15_V14
[08:38:55.401] <TB1> INFO: Write out Hit Distr_C15_V0
[08:38:55.401] <TB1> INFO: Write out Hit Distr_C15_V0
[08:38:55.401] <TB1> INFO: enter test to run
[08:38:55.401] <TB1> INFO: test: no parameter change
[08:38:55.548] <TB1> QUIET: Connection to board 154 closed.
[08:38:55.628] <TB1> INFO: pXar: this is the end, my friend