[10:03:45.430] <TB2> INFO: *** Welcome to pxar ***
[10:03:45.430] <TB2> INFO: *** Today: 2016/07/19
[10:03:45.489] <TB2> INFO: *** Version: f5d3-dirty
[10:03:45.489] <TB2> INFO: readRocDacs: /home/Reception/DATA/KITAACHEN/M4783_Reception_2016-07-19_11h58m_1468922337//000_ReceptionTestBB4_p17//dacParameters_C0.dat .. /home/Reception/DATA/KITAACHEN/M4783_Reception_2016-07-19_11h58m_1468922337//000_ReceptionTestBB4_p17//dacParameters_C15.dat
[10:03:45.506] <TB2> INFO: readTbmDacs: /home/Reception/DATA/KITAACHEN/M4783_Reception_2016-07-19_11h58m_1468922337//000_ReceptionTestBB4_p17//tbmParameters_C0a.dat .. /home/Reception/DATA/KITAACHEN/M4783_Reception_2016-07-19_11h58m_1468922337//000_ReceptionTestBB4_p17//tbmParameters_C0b.dat
[10:03:45.509] <TB2> INFO: readMaskFile: /home/Reception/DATA/KITAACHEN/M4783_Reception_2016-07-19_11h58m_1468922337//000_ReceptionTestBB4_p17//defaultMaskFile.dat
[10:03:45.509] <TB2> INFO: readTrimFile: /home/Reception/DATA/KITAACHEN/M4783_Reception_2016-07-19_11h58m_1468922337//000_ReceptionTestBB4_p17//trimParameters_C0.dat .. /home/Reception/DATA/KITAACHEN/M4783_Reception_2016-07-19_11h58m_1468922337//000_ReceptionTestBB4_p17//trimParameters_C15.dat
[10:03:45.625] <TB2> INFO: clk: 4
[10:03:45.625] <TB2> INFO: ctr: 4
[10:03:45.625] <TB2> INFO: sda: 19
[10:03:45.625] <TB2> INFO: tin: 9
[10:03:45.625] <TB2> INFO: level: 15
[10:03:45.625] <TB2> INFO: triggerdelay: 0
[10:03:45.626] <TB2> QUIET: Instanciating API for pxar v2.7.5+40~g4fce89b
[10:03:45.626] <TB2> INFO: Log level: INFO
[10:03:45.632] <TB2> INFO: Found DTB DTB_WWXUD2
[10:03:45.642] <TB2> QUIET: Connection to board DTB_WWXUD2 opened.
[10:03:45.644] <TB2> INFO: DTB startup information
--- DTB info------------------------------------------
Board id: 149
HW version: DTB1.2
FW version: 4.4
SW version: 4.6
Options:
USB id: DTB_WWXUD2
MAC address: 40D855118095
Hostname: pixelDTB149
Comment:
------------------------------------------------------
[10:03:45.646] <TB2> INFO: RPC call hashes of host and DTB match: 484264910
[10:03:47.167] <TB2> INFO: DUT info:
[10:03:47.167] <TB2> INFO: The DUT currently contains the following objects:
[10:03:47.167] <TB2> INFO: 2 TBM Cores tbm08c (2 ON)
[10:03:47.167] <TB2> INFO: TBM Core alpha (0): 7 registers set
[10:03:47.167] <TB2> INFO: TBM Core beta (1): 7 registers set
[10:03:47.167] <TB2> INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[10:03:47.168] <TB2> INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[10:03:47.168] <TB2> INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[10:03:47.168] <TB2> INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[10:03:47.168] <TB2> INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[10:03:47.168] <TB2> INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[10:03:47.168] <TB2> INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[10:03:47.168] <TB2> INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[10:03:47.168] <TB2> INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[10:03:47.168] <TB2> INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[10:03:47.168] <TB2> INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[10:03:47.168] <TB2> INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[10:03:47.168] <TB2> INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[10:03:47.168] <TB2> INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[10:03:47.168] <TB2> INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[10:03:47.168] <TB2> INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[10:03:47.168] <TB2> INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[10:03:47.570] <TB2> INFO: enter 'restricted' command line mode
[10:03:47.570] <TB2> INFO: enter test to run
[10:03:47.570] <TB2> INFO: test: pretest no parameter change
[10:03:47.570] <TB2> INFO: running: pretest
[10:03:47.578] <TB2> INFO: ----------------------------------------------------------------------
[10:03:47.578] <TB2> INFO: PixTestPretest::programROC()
[10:03:47.578] <TB2> INFO: ----------------------------------------------------------------------
[10:04:05.597] <TB2> INFO: PixTestPretest::programROC() done: ROCs are all programmable
[10:04:05.597] <TB2> INFO: IA differences per ROC: 20.1 21.7 20.9 18.5 19.3 19.3 19.3 19.3 17.7 19.3 17.7 19.3 19.3 16.9 19.3 19.3
[10:04:05.714] <TB2> INFO: enter test to run
[10:04:05.714] <TB2> INFO: test: pretest no parameter change
[10:04:05.714] <TB2> INFO: running: pretest
[10:04:05.715] <TB2> INFO: ----------------------------------------------------------------------
[10:04:05.715] <TB2> INFO: PixTestPretest::setVana() target Ia = 24 mA/ROC
[10:04:05.715] <TB2> INFO: ----------------------------------------------------------------------
[10:04:27.050] <TB2> INFO: PixTestPretest::setVana() done, Module Ia 389.1 mA = 24.3188 mA/ROC
[10:04:27.051] <TB2> INFO: i(loss) [mA/ROC]: 19.3 20.1 20.1 20.1 19.3 20.1 20.1 20.9 19.3 19.3 19.3 19.3 19.3 19.3 19.3 19.3
[10:04:27.096] <TB2> INFO: enter test to run
[10:04:27.096] <TB2> INFO: test: pretest no parameter change
[10:04:27.096] <TB2> INFO: running: pretest
[10:04:27.097] <TB2> INFO: ----------------------------------------------------------------------
[10:04:27.097] <TB2> INFO: PixTestPretest::findTiming()
[10:04:27.097] <TB2> INFO: ----------------------------------------------------------------------
[10:04:27.097] <TB2> INFO: PixTestCmd::init()
[10:04:27.773] <TB2> WARNING: Not unmasking DUT, not setting Calibrate bits!
[10:05:27.910] <TB2> INFO: TBM phases: 160MHz: 7, 400MHz: 2, TBM delays: ROC(0/1):4, header/trailer: 1, token: 1
[10:05:27.910] <TB2> INFO: (success/tries = 100/100), width = 2
[10:05:27.911] <TB2> INFO: enter test to run
[10:05:27.911] <TB2> INFO: test: pretest no parameter change
[10:05:27.912] <TB2> INFO: running: pretest
[10:05:27.913] <TB2> INFO: ----------------------------------------------------------------------
[10:05:27.913] <TB2> INFO: PixTestPretest::findWorkingPixel()
[10:05:27.913] <TB2> INFO: ----------------------------------------------------------------------
[10:05:28.010] <TB2> INFO: Expecting 231680 events.
[10:05:33.695] <TB2> WARNING: Detected DESER400 trailer error bits: "CODE ERROR"
[10:05:37.074] <TB2> INFO: 231680 events read in total (8509ms).
[10:05:37.079] <TB2> INFO: Test took 9160ms.
[10:05:37.375] <TB2> INFO: Found working pixel in all ROCs: col/row = 12/22
[10:05:37.453] <TB2> INFO: enter test to run
[10:05:37.453] <TB2> INFO: test: pretest no parameter change
[10:05:37.453] <TB2> INFO: running: pretest
[10:05:37.455] <TB2> INFO: ----------------------------------------------------------------------
[10:05:37.455] <TB2> INFO: PixTestPretest::setVthrCompCalDel()
[10:05:37.455] <TB2> INFO: ----------------------------------------------------------------------
[10:05:37.551] <TB2> INFO: Expecting 231680 events.
[10:05:46.682] <TB2> INFO: 231680 events read in total (8575ms).
[10:05:46.687] <TB2> INFO: Test took 9227ms.
[10:05:47.022] <TB2> INFO: PixTestPretest::setVthrCompCalDel() done
[10:05:47.022] <TB2> INFO: CalDel: 108 124 109 110 138 99 126 111 111 144 117 118 136 130 120 116
[10:05:47.022] <TB2> INFO: VthrComp: 53 51 51 51 51 51 51 51 51 51 51 51 51 51 51 51
[10:05:47.064] <TB2> INFO: enter test to run
[10:05:47.064] <TB2> INFO: test: pretest no parameter change
[10:05:47.064] <TB2> INFO: running: pretest
[10:05:47.070] <TB2> INFO: write dac parameters into /home/Reception/DATA/KITAACHEN/M4783_Reception_2016-07-19_11h58m_1468922337//000_ReceptionTestBB4_p17//dacParameters_C0.dat
[10:05:47.075] <TB2> INFO: write dac parameters into /home/Reception/DATA/KITAACHEN/M4783_Reception_2016-07-19_11h58m_1468922337//000_ReceptionTestBB4_p17//dacParameters_C1.dat
[10:05:47.080] <TB2> INFO: write dac parameters into /home/Reception/DATA/KITAACHEN/M4783_Reception_2016-07-19_11h58m_1468922337//000_ReceptionTestBB4_p17//dacParameters_C2.dat
[10:05:47.085] <TB2> INFO: write dac parameters into /home/Reception/DATA/KITAACHEN/M4783_Reception_2016-07-19_11h58m_1468922337//000_ReceptionTestBB4_p17//dacParameters_C3.dat
[10:05:47.091] <TB2> INFO: write dac parameters into /home/Reception/DATA/KITAACHEN/M4783_Reception_2016-07-19_11h58m_1468922337//000_ReceptionTestBB4_p17//dacParameters_C4.dat
[10:05:47.096] <TB2> INFO: write dac parameters into /home/Reception/DATA/KITAACHEN/M4783_Reception_2016-07-19_11h58m_1468922337//000_ReceptionTestBB4_p17//dacParameters_C5.dat
[10:05:47.101] <TB2> INFO: write dac parameters into /home/Reception/DATA/KITAACHEN/M4783_Reception_2016-07-19_11h58m_1468922337//000_ReceptionTestBB4_p17//dacParameters_C6.dat
[10:05:47.106] <TB2> INFO: write dac parameters into /home/Reception/DATA/KITAACHEN/M4783_Reception_2016-07-19_11h58m_1468922337//000_ReceptionTestBB4_p17//dacParameters_C7.dat
[10:05:47.111] <TB2> INFO: write dac parameters into /home/Reception/DATA/KITAACHEN/M4783_Reception_2016-07-19_11h58m_1468922337//000_ReceptionTestBB4_p17//dacParameters_C8.dat
[10:05:47.117] <TB2> INFO: write dac parameters into /home/Reception/DATA/KITAACHEN/M4783_Reception_2016-07-19_11h58m_1468922337//000_ReceptionTestBB4_p17//dacParameters_C9.dat
[10:05:47.122] <TB2> INFO: write dac parameters into /home/Reception/DATA/KITAACHEN/M4783_Reception_2016-07-19_11h58m_1468922337//000_ReceptionTestBB4_p17//dacParameters_C10.dat
[10:05:47.128] <TB2> INFO: write dac parameters into /home/Reception/DATA/KITAACHEN/M4783_Reception_2016-07-19_11h58m_1468922337//000_ReceptionTestBB4_p17//dacParameters_C11.dat
[10:05:47.135] <TB2> INFO: write dac parameters into /home/Reception/DATA/KITAACHEN/M4783_Reception_2016-07-19_11h58m_1468922337//000_ReceptionTestBB4_p17//dacParameters_C12.dat
[10:05:47.140] <TB2> INFO: write dac parameters into /home/Reception/DATA/KITAACHEN/M4783_Reception_2016-07-19_11h58m_1468922337//000_ReceptionTestBB4_p17//dacParameters_C13.dat
[10:05:47.145] <TB2> INFO: write dac parameters into /home/Reception/DATA/KITAACHEN/M4783_Reception_2016-07-19_11h58m_1468922337//000_ReceptionTestBB4_p17//dacParameters_C14.dat
[10:05:47.151] <TB2> INFO: write dac parameters into /home/Reception/DATA/KITAACHEN/M4783_Reception_2016-07-19_11h58m_1468922337//000_ReceptionTestBB4_p17//dacParameters_C15.dat
[10:05:47.154] <TB2> INFO: enter test to run
[10:05:47.154] <TB2> INFO: test: PixelAlive no parameter change
[10:05:47.154] <TB2> INFO: running: pixelalive
[10:05:47.155] <TB2> INFO: ######################################################################
[10:05:47.155] <TB2> INFO: PixTestAlive::doTest()
[10:05:47.155] <TB2> INFO: ######################################################################
[10:05:47.157] <TB2> INFO: ----------------------------------------------------------------------
[10:05:47.157] <TB2> INFO: PixTestAlive::aliveTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[10:05:47.157] <TB2> INFO: ----------------------------------------------------------------------
[10:05:47.425] <TB2> INFO: Expecting 41600 events.
[10:05:50.865] <TB2> INFO: 41600 events read in total (2884ms).
[10:05:50.867] <TB2> INFO: Test took 3708ms.
[10:05:51.173] <TB2> INFO: PixTestAlive::aliveTest() done
[10:05:51.173] <TB2> INFO: number of dead pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[10:05:51.175] <TB2> INFO: ----------------------------------------------------------------------
[10:05:51.175] <TB2> INFO: PixTestAlive::maskTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[10:05:51.175] <TB2> INFO: ----------------------------------------------------------------------
[10:05:51.449] <TB2> INFO: Expecting 41600 events.
[10:05:54.092] <TB2> INFO: 41600 events read in total (2088ms).
[10:05:54.092] <TB2> INFO: Test took 2915ms.
[10:05:54.093] <TB2> INFO: mask vs. old pixelAlive PixelAlive_C0_V0 .. PixelAlive_C15_V0
[10:05:54.417] <TB2> INFO: PixTestAlive::maskTest() done
[10:05:54.417] <TB2> INFO: number of mask-defect pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[10:05:54.419] <TB2> INFO: ----------------------------------------------------------------------
[10:05:54.419] <TB2> INFO: PixTestAlive::addressDecodingTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[10:05:54.419] <TB2> INFO: ----------------------------------------------------------------------
[10:05:54.695] <TB2> INFO: Expecting 41600 events.
[10:05:58.082] <TB2> INFO: 41600 events read in total (2831ms).
[10:05:58.083] <TB2> INFO: Test took 3662ms.
[10:05:58.394] <TB2> INFO: PixTestAlive::addressDecodingTest() done
[10:05:58.394] <TB2> INFO: number of address-decoding pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[10:05:58.394] <TB2> INFO: PixTestAlive::doTest() done, duration: 11 seconds
[10:05:58.400] <TB2> INFO: enter test to run
[10:05:58.400] <TB2> INFO: test: BB no parameter change
[10:05:58.400] <TB2> INFO: running: bb
[10:05:58.401] <TB2> INFO: ######################################################################
[10:05:58.401] <TB2> INFO: PixTestBBMap::doTest() Ntrig = 5, VcalS = 250 (high range)
[10:05:58.401] <TB2> INFO: ######################################################################
[10:05:58.406] <TB2> INFO: ---> dac: VthrComp name: calSMap ntrig: 5 dacrange: 0 .. 149 (-1/-1) hits flags = 514 (plus default)
[10:05:58.416] <TB2> INFO: dacScan split into 1 runs with ntrig = 5
[10:05:58.416] <TB2> INFO: run 1 of 1
[10:05:58.679] <TB2> INFO: Expecting 3120000 events.
[10:06:42.966] <TB2> INFO: 1097800 events read in total (43731ms).
[10:07:25.988] <TB2> INFO: 2169515 events read in total (86753ms).
[10:08:03.991] <TB2> INFO: 3120000 events read in total (124756ms).
[10:08:04.035] <TB2> INFO: Test took 125619ms.
[10:08:27.369] <TB2> INFO: PixTestBBMap::doTest() done, duration: 148 seconds
[10:08:27.370] <TB2> INFO: number of dead bumps (per ROC): 2 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[10:08:27.370] <TB2> INFO: separation cut (per ROC): 109 125 122 114 107 116 113 107 117 112 119 117 103 98 98 111
[10:08:27.439] <TB2> INFO: enter test to run
[10:08:27.439] <TB2> INFO: test: Readback no parameter change
[10:08:27.439] <TB2> INFO: running: readback
[10:08:27.439] <TB2> INFO: readReadbackCal: /home/Reception/DATA/KITAACHEN/M4783_Reception_2016-07-19_11h58m_1468922337//000_ReceptionTestBB4_p17//readbackCal_C0.dat .. /home/Reception/DATA/KITAACHEN/M4783_Reception_2016-07-19_11h58m_1468922337//000_ReceptionTestBB4_p17//readbackCal_C15.dat
[10:08:27.952] <TB2> INFO: readGainPedestalParameters /home/Reception/DATA/KITAACHEN/M4783_Reception_2016-07-19_11h58m_1468922337//000_ReceptionTestBB4_p17//phCalibrationFitErr_C0.dat .. /home/Reception/DATA/KITAACHEN/M4783_Reception_2016-07-19_11h58m_1468922337//000_ReceptionTestBB4_p17//phCalibrationFitErr_C15.dat
[10:08:27.953] <TB2> ERROR: <ConfigParameters.cc/readGainPedestalParameters:L996> cannot open /home/Reception/DATA/KITAACHEN/M4783_Reception_2016-07-19_11h58m_1468922337//000_ReceptionTestBB4_p17//phCalibrationFitErr_C0.dat for reading PH calibration constants
[10:08:27.953] <TB2> INFO: ######################################################################
[10:08:27.953] <TB2> INFO: PixTestReadback::doTest()
[10:08:27.953] <TB2> INFO: ######################################################################
[10:08:27.953] <TB2> INFO: ----------------------------------------------------------------------
[10:08:27.953] <TB2> INFO: PixTestReadback::CalibrateVd()
[10:08:27.953] <TB2> INFO: ----------------------------------------------------------------------
[10:08:37.362] <TB2> INFO: write readback calibration parameters into /home/Reception/DATA/KITAACHEN/M4783_Reception_2016-07-19_11h58m_1468922337//000_ReceptionTestBB4_p17//readbackCal_C0.dat
[10:08:37.389] <TB2> INFO: write readback calibration parameters into /home/Reception/DATA/KITAACHEN/M4783_Reception_2016-07-19_11h58m_1468922337//000_ReceptionTestBB4_p17//readbackCal_C1.dat
[10:08:37.403] <TB2> INFO: write readback calibration parameters into /home/Reception/DATA/KITAACHEN/M4783_Reception_2016-07-19_11h58m_1468922337//000_ReceptionTestBB4_p17//readbackCal_C2.dat
[10:08:37.412] <TB2> INFO: write readback calibration parameters into /home/Reception/DATA/KITAACHEN/M4783_Reception_2016-07-19_11h58m_1468922337//000_ReceptionTestBB4_p17//readbackCal_C3.dat
[10:08:37.422] <TB2> INFO: write readback calibration parameters into /home/Reception/DATA/KITAACHEN/M4783_Reception_2016-07-19_11h58m_1468922337//000_ReceptionTestBB4_p17//readbackCal_C4.dat
[10:08:37.435] <TB2> INFO: write readback calibration parameters into /home/Reception/DATA/KITAACHEN/M4783_Reception_2016-07-19_11h58m_1468922337//000_ReceptionTestBB4_p17//readbackCal_C5.dat
[10:08:37.440] <TB2> INFO: write readback calibration parameters into /home/Reception/DATA/KITAACHEN/M4783_Reception_2016-07-19_11h58m_1468922337//000_ReceptionTestBB4_p17//readbackCal_C6.dat
[10:08:37.469] <TB2> INFO: write readback calibration parameters into /home/Reception/DATA/KITAACHEN/M4783_Reception_2016-07-19_11h58m_1468922337//000_ReceptionTestBB4_p17//readbackCal_C7.dat
[10:08:37.480] <TB2> INFO: write readback calibration parameters into /home/Reception/DATA/KITAACHEN/M4783_Reception_2016-07-19_11h58m_1468922337//000_ReceptionTestBB4_p17//readbackCal_C8.dat
[10:08:37.516] <TB2> INFO: write readback calibration parameters into /home/Reception/DATA/KITAACHEN/M4783_Reception_2016-07-19_11h58m_1468922337//000_ReceptionTestBB4_p17//readbackCal_C9.dat
[10:08:37.525] <TB2> INFO: write readback calibration parameters into /home/Reception/DATA/KITAACHEN/M4783_Reception_2016-07-19_11h58m_1468922337//000_ReceptionTestBB4_p17//readbackCal_C10.dat
[10:08:37.532] <TB2> INFO: write readback calibration parameters into /home/Reception/DATA/KITAACHEN/M4783_Reception_2016-07-19_11h58m_1468922337//000_ReceptionTestBB4_p17//readbackCal_C11.dat
[10:08:37.551] <TB2> INFO: write readback calibration parameters into /home/Reception/DATA/KITAACHEN/M4783_Reception_2016-07-19_11h58m_1468922337//000_ReceptionTestBB4_p17//readbackCal_C12.dat
[10:08:37.562] <TB2> INFO: write readback calibration parameters into /home/Reception/DATA/KITAACHEN/M4783_Reception_2016-07-19_11h58m_1468922337//000_ReceptionTestBB4_p17//readbackCal_C13.dat
[10:08:37.583] <TB2> INFO: write readback calibration parameters into /home/Reception/DATA/KITAACHEN/M4783_Reception_2016-07-19_11h58m_1468922337//000_ReceptionTestBB4_p17//readbackCal_C14.dat
[10:08:37.609] <TB2> INFO: write readback calibration parameters into /home/Reception/DATA/KITAACHEN/M4783_Reception_2016-07-19_11h58m_1468922337//000_ReceptionTestBB4_p17//readbackCal_C15.dat
[10:08:37.676] <TB2> INFO: PixTestPattern:: pg_setup set to default.
[10:08:37.676] <TB2> INFO: ----------------------------------------------------------------------
[10:08:37.676] <TB2> INFO: PixTestReadback::CalibrateVa()
[10:08:37.676] <TB2> INFO: ----------------------------------------------------------------------
[10:08:47.101] <TB2> INFO: write readback calibration parameters into /home/Reception/DATA/KITAACHEN/M4783_Reception_2016-07-19_11h58m_1468922337//000_ReceptionTestBB4_p17//readbackCal_C0.dat
[10:08:47.148] <TB2> INFO: write readback calibration parameters into /home/Reception/DATA/KITAACHEN/M4783_Reception_2016-07-19_11h58m_1468922337//000_ReceptionTestBB4_p17//readbackCal_C1.dat
[10:08:47.169] <TB2> INFO: write readback calibration parameters into /home/Reception/DATA/KITAACHEN/M4783_Reception_2016-07-19_11h58m_1468922337//000_ReceptionTestBB4_p17//readbackCal_C2.dat
[10:08:47.179] <TB2> INFO: write readback calibration parameters into /home/Reception/DATA/KITAACHEN/M4783_Reception_2016-07-19_11h58m_1468922337//000_ReceptionTestBB4_p17//readbackCal_C3.dat
[10:08:47.196] <TB2> INFO: write readback calibration parameters into /home/Reception/DATA/KITAACHEN/M4783_Reception_2016-07-19_11h58m_1468922337//000_ReceptionTestBB4_p17//readbackCal_C4.dat
[10:08:47.210] <TB2> INFO: write readback calibration parameters into /home/Reception/DATA/KITAACHEN/M4783_Reception_2016-07-19_11h58m_1468922337//000_ReceptionTestBB4_p17//readbackCal_C5.dat
[10:08:47.219] <TB2> INFO: write readback calibration parameters into /home/Reception/DATA/KITAACHEN/M4783_Reception_2016-07-19_11h58m_1468922337//000_ReceptionTestBB4_p17//readbackCal_C6.dat
[10:08:47.227] <TB2> INFO: write readback calibration parameters into /home/Reception/DATA/KITAACHEN/M4783_Reception_2016-07-19_11h58m_1468922337//000_ReceptionTestBB4_p17//readbackCal_C7.dat
[10:08:47.233] <TB2> INFO: write readback calibration parameters into /home/Reception/DATA/KITAACHEN/M4783_Reception_2016-07-19_11h58m_1468922337//000_ReceptionTestBB4_p17//readbackCal_C8.dat
[10:08:47.263] <TB2> INFO: write readback calibration parameters into /home/Reception/DATA/KITAACHEN/M4783_Reception_2016-07-19_11h58m_1468922337//000_ReceptionTestBB4_p17//readbackCal_C9.dat
[10:08:47.270] <TB2> INFO: write readback calibration parameters into /home/Reception/DATA/KITAACHEN/M4783_Reception_2016-07-19_11h58m_1468922337//000_ReceptionTestBB4_p17//readbackCal_C10.dat
[10:08:47.279] <TB2> INFO: write readback calibration parameters into /home/Reception/DATA/KITAACHEN/M4783_Reception_2016-07-19_11h58m_1468922337//000_ReceptionTestBB4_p17//readbackCal_C11.dat
[10:08:47.290] <TB2> INFO: write readback calibration parameters into /home/Reception/DATA/KITAACHEN/M4783_Reception_2016-07-19_11h58m_1468922337//000_ReceptionTestBB4_p17//readbackCal_C12.dat
[10:08:47.305] <TB2> INFO: write readback calibration parameters into /home/Reception/DATA/KITAACHEN/M4783_Reception_2016-07-19_11h58m_1468922337//000_ReceptionTestBB4_p17//readbackCal_C13.dat
[10:08:47.324] <TB2> INFO: write readback calibration parameters into /home/Reception/DATA/KITAACHEN/M4783_Reception_2016-07-19_11h58m_1468922337//000_ReceptionTestBB4_p17//readbackCal_C14.dat
[10:08:47.356] <TB2> INFO: write readback calibration parameters into /home/Reception/DATA/KITAACHEN/M4783_Reception_2016-07-19_11h58m_1468922337//000_ReceptionTestBB4_p17//readbackCal_C15.dat
[10:08:47.422] <TB2> INFO: PixTestPattern:: pg_setup set to default.
[10:08:47.422] <TB2> INFO: ----------------------------------------------------------------------
[10:08:47.422] <TB2> INFO: PixTestReadback::readbackVbg()
[10:08:47.422] <TB2> INFO: ----------------------------------------------------------------------
[10:08:54.608] <TB2> INFO: PixTestPattern:: pg_setup set to default.
[10:08:54.608] <TB2> INFO: ----------------------------------------------------------------------
[10:08:54.608] <TB2> INFO: PixTestReadback::getCalibratedVbg()
[10:08:54.609] <TB2> INFO: ----------------------------------------------------------------------
[10:08:54.609] <TB2> INFO: Vbg will be calibrated using Vd calibration
[10:08:54.609] <TB2> INFO: /*/*/*/*::: ROC 0: uncalibrated Vbg = 148.7calibrated Vbg = 1.2403 :::*/*/*/*/
[10:08:54.609] <TB2> INFO: /*/*/*/*::: ROC 1: uncalibrated Vbg = 150.3calibrated Vbg = 1.23778 :::*/*/*/*/
[10:08:54.609] <TB2> INFO: /*/*/*/*::: ROC 2: uncalibrated Vbg = 155.5calibrated Vbg = 1.24493 :::*/*/*/*/
[10:08:54.609] <TB2> INFO: /*/*/*/*::: ROC 3: uncalibrated Vbg = 157.4calibrated Vbg = 1.24645 :::*/*/*/*/
[10:08:54.609] <TB2> INFO: /*/*/*/*::: ROC 4: uncalibrated Vbg = 154.1calibrated Vbg = 1.24954 :::*/*/*/*/
[10:08:54.609] <TB2> INFO: /*/*/*/*::: ROC 5: uncalibrated Vbg = 152.8calibrated Vbg = 1.25339 :::*/*/*/*/
[10:08:54.609] <TB2> INFO: /*/*/*/*::: ROC 6: uncalibrated Vbg = 149.7calibrated Vbg = 1.25035 :::*/*/*/*/
[10:08:54.609] <TB2> INFO: /*/*/*/*::: ROC 7: uncalibrated Vbg = 156.7calibrated Vbg = 1.25281 :::*/*/*/*/
[10:08:54.609] <TB2> INFO: /*/*/*/*::: ROC 8: uncalibrated Vbg = 150.2calibrated Vbg = 1.24911 :::*/*/*/*/
[10:08:54.609] <TB2> INFO: /*/*/*/*::: ROC 9: uncalibrated Vbg = 157calibrated Vbg = 1.25379 :::*/*/*/*/
[10:08:54.609] <TB2> INFO: /*/*/*/*::: ROC 10: uncalibrated Vbg = 148calibrated Vbg = 1.24952 :::*/*/*/*/
[10:08:54.609] <TB2> INFO: /*/*/*/*::: ROC 11: uncalibrated Vbg = 149.1calibrated Vbg = 1.24157 :::*/*/*/*/
[10:08:54.609] <TB2> INFO: /*/*/*/*::: ROC 12: uncalibrated Vbg = 144.3calibrated Vbg = 1.2321 :::*/*/*/*/
[10:08:54.609] <TB2> INFO: /*/*/*/*::: ROC 13: uncalibrated Vbg = 153.9calibrated Vbg = 1.22712 :::*/*/*/*/
[10:08:54.609] <TB2> INFO: /*/*/*/*::: ROC 14: uncalibrated Vbg = 150calibrated Vbg = 1.24167 :::*/*/*/*/
[10:08:54.609] <TB2> INFO: /*/*/*/*::: ROC 15: uncalibrated Vbg = 141.9calibrated Vbg = 1.2412 :::*/*/*/*/
[10:08:54.613] <TB2> INFO: ----------------------------------------------------------------------
[10:08:54.613] <TB2> INFO: PixTestReadback::CalibrateIa()
[10:08:54.613] <TB2> INFO: ----------------------------------------------------------------------
[10:11:28.125] <TB2> INFO: write readback calibration parameters into /home/Reception/DATA/KITAACHEN/M4783_Reception_2016-07-19_11h58m_1468922337//000_ReceptionTestBB4_p17//readbackCal_C0.dat
[10:11:28.155] <TB2> INFO: write readback calibration parameters into /home/Reception/DATA/KITAACHEN/M4783_Reception_2016-07-19_11h58m_1468922337//000_ReceptionTestBB4_p17//readbackCal_C1.dat
[10:11:28.182] <TB2> INFO: write readback calibration parameters into /home/Reception/DATA/KITAACHEN/M4783_Reception_2016-07-19_11h58m_1468922337//000_ReceptionTestBB4_p17//readbackCal_C2.dat
[10:11:28.214] <TB2> INFO: write readback calibration parameters into /home/Reception/DATA/KITAACHEN/M4783_Reception_2016-07-19_11h58m_1468922337//000_ReceptionTestBB4_p17//readbackCal_C3.dat
[10:11:28.246] <TB2> INFO: write readback calibration parameters into /home/Reception/DATA/KITAACHEN/M4783_Reception_2016-07-19_11h58m_1468922337//000_ReceptionTestBB4_p17//readbackCal_C4.dat
[10:11:28.409] <TB2> INFO: write readback calibration parameters into /home/Reception/DATA/KITAACHEN/M4783_Reception_2016-07-19_11h58m_1468922337//000_ReceptionTestBB4_p17//readbackCal_C5.dat
[10:11:28.743] <TB2> INFO: write readback calibration parameters into /home/Reception/DATA/KITAACHEN/M4783_Reception_2016-07-19_11h58m_1468922337//000_ReceptionTestBB4_p17//readbackCal_C6.dat
[10:11:29.044] <TB2> INFO: write readback calibration parameters into /home/Reception/DATA/KITAACHEN/M4783_Reception_2016-07-19_11h58m_1468922337//000_ReceptionTestBB4_p17//readbackCal_C7.dat
[10:11:29.453] <TB2> INFO: write readback calibration parameters into /home/Reception/DATA/KITAACHEN/M4783_Reception_2016-07-19_11h58m_1468922337//000_ReceptionTestBB4_p17//readbackCal_C8.dat
[10:11:29.702] <TB2> INFO: write readback calibration parameters into /home/Reception/DATA/KITAACHEN/M4783_Reception_2016-07-19_11h58m_1468922337//000_ReceptionTestBB4_p17//readbackCal_C9.dat
[10:11:29.986] <TB2> INFO: write readback calibration parameters into /home/Reception/DATA/KITAACHEN/M4783_Reception_2016-07-19_11h58m_1468922337//000_ReceptionTestBB4_p17//readbackCal_C10.dat
[10:11:30.228] <TB2> INFO: write readback calibration parameters into /home/Reception/DATA/KITAACHEN/M4783_Reception_2016-07-19_11h58m_1468922337//000_ReceptionTestBB4_p17//readbackCal_C11.dat
[10:11:30.536] <TB2> INFO: write readback calibration parameters into /home/Reception/DATA/KITAACHEN/M4783_Reception_2016-07-19_11h58m_1468922337//000_ReceptionTestBB4_p17//readbackCal_C12.dat
[10:11:30.895] <TB2> INFO: write readback calibration parameters into /home/Reception/DATA/KITAACHEN/M4783_Reception_2016-07-19_11h58m_1468922337//000_ReceptionTestBB4_p17//readbackCal_C13.dat
[10:11:31.196] <TB2> INFO: write readback calibration parameters into /home/Reception/DATA/KITAACHEN/M4783_Reception_2016-07-19_11h58m_1468922337//000_ReceptionTestBB4_p17//readbackCal_C14.dat
[10:11:31.704] <TB2> INFO: write readback calibration parameters into /home/Reception/DATA/KITAACHEN/M4783_Reception_2016-07-19_11h58m_1468922337//000_ReceptionTestBB4_p17//readbackCal_C15.dat
[10:11:31.950] <TB2> INFO: PixTestPattern:: pg_setup set to default.
[10:11:31.952] <TB2> INFO: PixTestReadback::doTest() done
[10:11:31.973] <TB2> INFO: enter test to run
[10:11:31.973] <TB2> INFO: test: BB4 no parameter change
[10:11:31.973] <TB2> INFO: running: bb4
[10:11:31.973] <TB2> INFO: PixTestBB4Map::doTest() ntrig = 100
[10:11:31.973] <TB2> INFO: VthrComp (min,max,step) = 70 130 5
[10:11:31.973] <TB2> INFO: CalDel (min,max,step) = 80 200 10
[10:11:31.973] <TB2> INFO: Vcal = 255
[10:11:31.974] <TB2> INFO: Cut = 0.5
[10:11:31.974] <TB2> INFO: flag 2
[10:11:31.976] <TB2> INFO: CtrlReg 4 (large Vcal)
[10:11:31.976] <TB2> INFO: saving VthrComp and CalDel before running tests)
[10:11:31.976] <TB2> INFO: 0 130 0
[10:11:31.976] <TB2> INFO: 1 130 0
[10:11:31.976] <TB2> INFO: 2 130 0
[10:11:31.976] <TB2> INFO: 3 130 0
[10:11:31.976] <TB2> INFO: 4 130 0
[10:11:31.976] <TB2> INFO: 5 130 0
[10:11:31.976] <TB2> INFO: 6 130 0
[10:11:31.976] <TB2> INFO: 7 130 0
[10:11:31.976] <TB2> INFO: 8 130 0
[10:11:31.976] <TB2> INFO: 9 130 0
[10:11:31.976] <TB2> INFO: 10 130 0
[10:11:31.976] <TB2> INFO: 11 130 0
[10:11:31.976] <TB2> INFO: 12 130 0
[10:11:31.976] <TB2> INFO: 13 130 0
[10:11:31.976] <TB2> INFO: 14 130 0
[10:11:31.976] <TB2> INFO: 15 130 0
[10:11:32.246] <TB2> INFO: Expecting 416000 events.
[10:11:36.897] <TB2> ERROR: <datapipe.cc/CheckEventValidity:L507> Channel 0 Number of ROCs (0) != Token Chain Length (8)
[10:11:36.926] <TB2> ERROR: <datapipe.cc/CheckEventID:L469> Channel 0 Event ID mismatch: local ID (16) != TBM ID (2)
[10:11:41.633] <TB2> INFO: 416000 events read in total (8831ms).
[10:11:41.633] <TB2> INFO: Test took 9652ms.
[10:11:41.633] <TB2> INFO: starting loop over VthrComp
[10:11:42.190] <TB2> INFO: Expecting 416000 events.
[10:11:51.665] <TB2> INFO: 416000 events read in total (8918ms).
[10:11:51.666] <TB2> INFO: Test took 9756ms.
[10:11:51.666] <TB2> INFO: got noisemap
[10:11:51.942] <TB2> INFO: ROC 0, integral 0
[10:11:51.942] <TB2> INFO: ROC 1, integral 0
[10:11:51.942] <TB2> INFO: ROC 2, integral 0
[10:11:51.942] <TB2> INFO: ROC 3, integral 0
[10:11:51.943] <TB2> INFO: ROC 4, integral 0
[10:11:51.943] <TB2> INFO: ROC 5, integral 0
[10:11:51.943] <TB2> INFO: ROC 6, integral 0
[10:11:51.943] <TB2> INFO: ROC 7, integral 0
[10:11:51.943] <TB2> INFO: ROC 8, integral 0
[10:11:51.943] <TB2> INFO: ROC 9, integral 0
[10:11:51.943] <TB2> INFO: ROC 10, integral 0
[10:11:51.943] <TB2> INFO: ROC 11, integral 0
[10:11:51.943] <TB2> INFO: ROC 12, integral 0
[10:11:51.943] <TB2> INFO: ROC 13, integral 0
[10:11:51.943] <TB2> INFO: ROC 14, integral 0
[10:11:51.943] <TB2> INFO: ROC 15, integral 0
[10:11:51.943] <TB2> INFO: VthrComp = 70 done
[10:11:52.221] <TB2> INFO: Expecting 416000 events.
[10:12:01.610] <TB2> INFO: 416000 events read in total (8833ms).
[10:12:01.611] <TB2> INFO: Test took 9663ms.
[10:12:01.611] <TB2> INFO: got noisemap
[10:12:01.886] <TB2> INFO: ROC 0, integral 0
[10:12:01.886] <TB2> INFO: ROC 1, integral 0
[10:12:01.886] <TB2> INFO: ROC 2, integral 0
[10:12:01.886] <TB2> INFO: ROC 3, integral 0
[10:12:01.886] <TB2> INFO: ROC 4, integral 0
[10:12:01.886] <TB2> INFO: ROC 5, integral 0
[10:12:01.886] <TB2> INFO: ROC 6, integral 0
[10:12:01.886] <TB2> INFO: ROC 7, integral 0
[10:12:01.886] <TB2> INFO: ROC 8, integral 0
[10:12:01.886] <TB2> INFO: ROC 9, integral 0
[10:12:01.886] <TB2> INFO: ROC 10, integral 0
[10:12:01.886] <TB2> INFO: ROC 11, integral 0
[10:12:01.887] <TB2> INFO: ROC 12, integral 0
[10:12:01.887] <TB2> INFO: ROC 13, integral 0
[10:12:01.887] <TB2> INFO: ROC 14, integral 0
[10:12:01.887] <TB2> INFO: ROC 15, integral 0
[10:12:01.887] <TB2> INFO: VthrComp = 75 done
[10:12:02.185] <TB2> INFO: Expecting 416000 events.
[10:12:11.594] <TB2> INFO: 416000 events read in total (8853ms).
[10:12:11.595] <TB2> INFO: Test took 9703ms.
[10:12:11.595] <TB2> INFO: got noisemap
[10:12:11.869] <TB2> INFO: ROC 0, integral 0
[10:12:11.870] <TB2> INFO: ROC 1, integral 0
[10:12:11.870] <TB2> INFO: ROC 2, integral 0
[10:12:11.870] <TB2> INFO: ROC 3, integral 0
[10:12:11.870] <TB2> INFO: ROC 4, integral 0
[10:12:11.870] <TB2> INFO: ROC 5, integral 0
[10:12:11.870] <TB2> INFO: ROC 6, integral 0
[10:12:11.871] <TB2> INFO: ROC 7, integral 0
[10:12:11.871] <TB2> INFO: ROC 8, integral 0
[10:12:11.871] <TB2> INFO: ROC 9, integral 0
[10:12:11.871] <TB2> INFO: ROC 10, integral 0
[10:12:11.871] <TB2> INFO: ROC 11, integral 0
[10:12:11.871] <TB2> INFO: ROC 12, integral 0
[10:12:11.872] <TB2> INFO: ROC 13, integral 0
[10:12:11.872] <TB2> INFO: ROC 14, integral 0
[10:12:11.872] <TB2> INFO: ROC 15, integral 0
[10:12:11.872] <TB2> INFO: VthrComp = 80 done
[10:12:12.168] <TB2> INFO: Expecting 416000 events.
[10:12:21.579] <TB2> INFO: 416000 events read in total (8855ms).
[10:12:21.580] <TB2> INFO: Test took 9702ms.
[10:12:21.580] <TB2> INFO: got noisemap
[10:12:21.855] <TB2> INFO: ROC 0, integral 0
[10:12:21.855] <TB2> INFO: ROC 1, integral 0
[10:12:21.855] <TB2> INFO: ROC 2, integral 0
[10:12:21.856] <TB2> INFO: ROC 3, integral 0
[10:12:21.856] <TB2> INFO: ROC 4, integral 0
[10:12:21.856] <TB2> INFO: ROC 5, integral 0
[10:12:21.856] <TB2> INFO: ROC 6, integral 0
[10:12:21.856] <TB2> INFO: ROC 7, integral 0
[10:12:21.856] <TB2> INFO: ROC 8, integral 0
[10:12:21.856] <TB2> INFO: ROC 9, integral 0
[10:12:21.856] <TB2> INFO: ROC 10, integral 0
[10:12:21.856] <TB2> INFO: ROC 11, integral 0
[10:12:21.856] <TB2> INFO: ROC 12, integral 0
[10:12:21.856] <TB2> INFO: ROC 13, integral 0
[10:12:21.856] <TB2> INFO: ROC 14, integral 0
[10:12:21.856] <TB2> INFO: ROC 15, integral 0
[10:12:21.856] <TB2> INFO: VthrComp = 85 done
[10:12:22.124] <TB2> INFO: Expecting 416000 events.
[10:12:31.570] <TB2> INFO: 416000 events read in total (8890ms).
[10:12:31.570] <TB2> INFO: Test took 9709ms.
[10:12:31.570] <TB2> INFO: got noisemap
[10:12:31.846] <TB2> INFO: ROC 0, integral 0
[10:12:31.846] <TB2> INFO: ROC 1, integral 0
[10:12:31.846] <TB2> INFO: ROC 2, integral 0
[10:12:31.846] <TB2> INFO: ROC 3, integral 0
[10:12:31.846] <TB2> INFO: ROC 4, integral 0
[10:12:31.846] <TB2> INFO: ROC 5, integral 0
[10:12:31.846] <TB2> INFO: ROC 6, integral 0
[10:12:31.847] <TB2> INFO: ROC 7, integral 0
[10:12:31.847] <TB2> INFO: ROC 8, integral 0
[10:12:31.847] <TB2> INFO: ROC 9, integral 0
[10:12:31.847] <TB2> INFO: ROC 10, integral 0
[10:12:31.847] <TB2> INFO: ROC 11, integral 0
[10:12:31.847] <TB2> INFO: ROC 12, integral 0
[10:12:31.847] <TB2> INFO: ROC 13, integral 0
[10:12:31.847] <TB2> INFO: ROC 14, integral 0
[10:12:31.847] <TB2> INFO: ROC 15, integral 0
[10:12:31.847] <TB2> INFO: VthrComp = 90 done
[10:12:32.126] <TB2> INFO: Expecting 416000 events.
[10:12:41.564] <TB2> INFO: 416000 events read in total (8882ms).
[10:12:41.565] <TB2> INFO: Test took 9714ms.
[10:12:41.565] <TB2> INFO: got noisemap
[10:12:41.840] <TB2> INFO: ROC 0, integral 0
[10:12:41.841] <TB2> INFO: ROC 1, integral 0
[10:12:41.841] <TB2> INFO: ROC 2, integral 0
[10:12:41.841] <TB2> INFO: ROC 3, integral 0
[10:12:41.841] <TB2> INFO: ROC 4, integral 0
[10:12:41.841] <TB2> INFO: ROC 5, integral 0
[10:12:41.841] <TB2> INFO: ROC 6, integral 0
[10:12:41.841] <TB2> INFO: ROC 7, integral 0
[10:12:41.841] <TB2> INFO: ROC 8, integral 0
[10:12:41.841] <TB2> INFO: ROC 9, integral 0
[10:12:41.841] <TB2> INFO: ROC 10, integral 0
[10:12:41.841] <TB2> INFO: ROC 11, integral 0
[10:12:41.841] <TB2> INFO: ROC 12, integral 0
[10:12:41.841] <TB2> INFO: ROC 13, integral 0
[10:12:41.842] <TB2> INFO: ROC 14, integral 0
[10:12:41.842] <TB2> INFO: ROC 15, integral 0
[10:12:41.842] <TB2> INFO: VthrComp = 95 done
[10:12:42.140] <TB2> INFO: Expecting 416000 events.
[10:12:51.550] <TB2> INFO: 416000 events read in total (8854ms).
[10:12:51.550] <TB2> INFO: Test took 9704ms.
[10:12:51.551] <TB2> INFO: got noisemap
[10:12:51.823] <TB2> INFO: ROC 0, integral 0
[10:12:51.823] <TB2> INFO: ROC 1, integral 0
[10:12:51.824] <TB2> INFO: ROC 2, integral 0
[10:12:51.824] <TB2> INFO: ROC 3, integral 0
[10:12:51.824] <TB2> INFO: ROC 4, integral 0
[10:12:51.824] <TB2> INFO: ROC 5, integral 0
[10:12:51.824] <TB2> INFO: ROC 6, integral 0
[10:12:51.824] <TB2> INFO: ROC 7, integral 0
[10:12:51.824] <TB2> INFO: ROC 8, integral 0
[10:12:51.824] <TB2> INFO: ROC 9, integral 0
[10:12:51.824] <TB2> INFO: ROC 10, integral 0
[10:12:51.824] <TB2> INFO: ROC 11, integral 0
[10:12:51.824] <TB2> INFO: ROC 12, integral 0
[10:12:51.824] <TB2> INFO: ROC 13, integral 0
[10:12:51.824] <TB2> INFO: ROC 14, integral 0
[10:12:51.824] <TB2> INFO: ROC 15, integral 0
[10:12:51.824] <TB2> INFO: VthrComp = 100 done
[10:12:52.102] <TB2> INFO: Expecting 416000 events.
[10:13:01.495] <TB2> INFO: 416000 events read in total (8837ms).
[10:13:01.496] <TB2> INFO: Test took 9667ms.
[10:13:01.496] <TB2> INFO: got noisemap
[10:13:01.772] <TB2> INFO: ROC 0, integral 0
[10:13:01.772] <TB2> INFO: ROC 1, integral 0
[10:13:01.772] <TB2> INFO: ROC 2, integral 0
[10:13:01.772] <TB2> INFO: ROC 3, integral 0
[10:13:01.773] <TB2> INFO: ROC 4, integral 0
[10:13:01.773] <TB2> INFO: ROC 5, integral 0
[10:13:01.773] <TB2> INFO: ROC 6, integral 0
[10:13:01.773] <TB2> INFO: ROC 7, integral 0
[10:13:01.773] <TB2> INFO: ROC 8, integral 0
[10:13:01.773] <TB2> INFO: ROC 9, integral 0
[10:13:01.773] <TB2> INFO: ROC 10, integral 0
[10:13:01.773] <TB2> INFO: ROC 11, integral 0
[10:13:01.773] <TB2> INFO: ROC 12, integral 0
[10:13:01.774] <TB2> INFO: ROC 13, integral 0
[10:13:01.774] <TB2> INFO: ROC 14, integral 0
[10:13:01.774] <TB2> INFO: ROC 15, integral 0
[10:13:01.774] <TB2> INFO: VthrComp = 105 done
[10:13:02.042] <TB2> INFO: Expecting 416000 events.
[10:13:11.453] <TB2> INFO: 416000 events read in total (8855ms).
[10:13:11.454] <TB2> INFO: Test took 9675ms.
[10:13:11.454] <TB2> INFO: got noisemap
[10:13:11.731] <TB2> INFO: ROC 0, integral 0
[10:13:11.731] <TB2> INFO: ROC 1, integral 0
[10:13:11.731] <TB2> INFO: ROC 2, integral 0
[10:13:11.731] <TB2> INFO: ROC 3, integral 0
[10:13:11.731] <TB2> INFO: ROC 4, integral 0
[10:13:11.732] <TB2> INFO: ROC 5, integral 0
[10:13:11.732] <TB2> INFO: ROC 6, integral 0
[10:13:11.732] <TB2> INFO: ROC 7, integral 0
[10:13:11.732] <TB2> INFO: ROC 8, integral 0
[10:13:11.732] <TB2> INFO: ROC 9, integral 4
[10:13:11.733] <TB2> INFO: ROC 10, integral 0
[10:13:11.733] <TB2> INFO: ROC 11, integral 0
[10:13:11.733] <TB2> INFO: ROC 12, integral 0
[10:13:11.733] <TB2> INFO: ROC 13, integral 1
[10:13:11.733] <TB2> INFO: ROC 14, integral 1
[10:13:11.734] <TB2> INFO: ROC 15, integral 0
[10:13:11.734] <TB2> INFO: VthrComp = 110 done
[10:13:12.017] <TB2> INFO: Expecting 416000 events.
[10:13:21.422] <TB2> INFO: 416000 events read in total (8850ms).
[10:13:21.422] <TB2> INFO: Test took 9683ms.
[10:13:21.422] <TB2> INFO: got noisemap
[10:13:21.697] <TB2> INFO: ROC 0, integral 0
[10:13:21.697] <TB2> INFO: ROC 1, integral 0
[10:13:21.697] <TB2> INFO: ROC 2, integral 0
[10:13:21.697] <TB2> INFO: ROC 3, integral 9
[10:13:21.697] <TB2> INFO: ROC 4, integral 1
[10:13:21.697] <TB2> INFO: ROC 5, integral 0
[10:13:21.697] <TB2> INFO: ROC 6, integral 0
[10:13:21.698] <TB2> INFO: ROC 7, integral 0
[10:13:21.698] <TB2> INFO: ROC 8, integral 0
[10:13:21.698] <TB2> INFO: ROC 9, integral 96
[10:13:21.698] <TB2> INFO: ROC #9: threshold found
[10:13:21.698] <TB2> INFO: ROC 10, integral 1
[10:13:21.698] <TB2> INFO: ROC 11, integral 0
[10:13:21.698] <TB2> INFO: ROC 12, integral 3
[10:13:21.698] <TB2> INFO: ROC 13, integral 143
[10:13:21.698] <TB2> INFO: ROC #13: threshold found
[10:13:21.698] <TB2> INFO: ROC 14, integral 63
[10:13:21.698] <TB2> INFO: ROC #14: threshold found
[10:13:21.698] <TB2> INFO: ROC 15, integral 5
[10:13:21.698] <TB2> INFO: VthrComp = 115 done
[10:13:21.977] <TB2> INFO: Expecting 416000 events.
[10:13:31.387] <TB2> INFO: 416000 events read in total (8854ms).
[10:13:31.388] <TB2> INFO: Test took 9685ms.
[10:13:31.388] <TB2> INFO: got noisemap
[10:13:31.663] <TB2> INFO: ROC 0, integral 0
[10:13:31.663] <TB2> INFO: ROC 1, integral 1
[10:13:31.664] <TB2> INFO: ROC 2, integral 0
[10:13:31.664] <TB2> INFO: ROC 3, integral 31
[10:13:31.664] <TB2> INFO: ROC #3: threshold found
[10:13:31.664] <TB2> INFO: ROC 4, integral 7
[10:13:31.664] <TB2> INFO: ROC 5, integral 0
[10:13:31.664] <TB2> INFO: ROC 6, integral 4
[10:13:31.664] <TB2> INFO: ROC 7, integral 0
[10:13:31.664] <TB2> INFO: ROC 8, integral 1
[10:13:31.665] <TB2> INFO: ROC 10, integral 5
[10:13:31.665] <TB2> INFO: ROC 11, integral 0
[10:13:31.665] <TB2> INFO: ROC 12, integral 20
[10:13:31.665] <TB2> INFO: ROC #12: threshold found
[10:13:31.665] <TB2> INFO: ROC 15, integral 37
[10:13:31.665] <TB2> INFO: ROC #15: threshold found
[10:13:31.665] <TB2> INFO: VthrComp = 120 done
[10:13:32.029] <TB2> INFO: Expecting 416000 events.
[10:13:41.400] <TB2> INFO: 416000 events read in total (8815ms).
[10:13:41.401] <TB2> INFO: Test took 9731ms.
[10:13:41.401] <TB2> INFO: got noisemap
[10:13:41.671] <TB2> INFO: ROC 0, integral 18
[10:13:41.671] <TB2> INFO: ROC #0: threshold found
[10:13:41.671] <TB2> INFO: ROC 1, integral 6
[10:13:41.671] <TB2> INFO: ROC 2, integral 1
[10:13:41.671] <TB2> INFO: ROC 4, integral 83
[10:13:41.671] <TB2> INFO: ROC #4: threshold found
[10:13:41.671] <TB2> INFO: ROC 5, integral 17
[10:13:41.671] <TB2> INFO: ROC #5: threshold found
[10:13:41.671] <TB2> INFO: ROC 6, integral 60
[10:13:41.671] <TB2> INFO: ROC #6: threshold found
[10:13:41.671] <TB2> INFO: ROC 7, integral 5
[10:13:41.671] <TB2> INFO: ROC 8, integral 3
[10:13:41.671] <TB2> INFO: ROC 10, integral 60
[10:13:41.671] <TB2> INFO: ROC #10: threshold found
[10:13:41.671] <TB2> INFO: ROC 11, integral 4
[10:13:41.671] <TB2> INFO: VthrComp = 125 done
[10:13:41.939] <TB2> INFO: Expecting 416000 events.
[10:13:51.428] <TB2> INFO: 416000 events read in total (8933ms).
[10:13:51.429] <TB2> INFO: Test took 9753ms.
[10:13:51.430] <TB2> INFO: got noisemap
[10:13:51.701] <TB2> INFO: ROC 1, integral 102
[10:13:51.701] <TB2> INFO: ROC #1: threshold found
[10:13:51.701] <TB2> INFO: ROC 2, integral 16
[10:13:51.701] <TB2> INFO: ROC #2: threshold found
[10:13:51.702] <TB2> INFO: ROC 7, integral 63
[10:13:51.702] <TB2> INFO: ROC #7: threshold found
[10:13:51.702] <TB2> INFO: ROC 8, integral 33
[10:13:51.702] <TB2> INFO: ROC #8: threshold found
[10:13:51.702] <TB2> INFO: ROC 11, integral 54
[10:13:51.702] <TB2> INFO: ROC #11: threshold found
[10:13:51.702] <TB2> INFO: VthrComp = 130 done
[10:13:51.702] <TB2> INFO: Minimal threshold for all ROCs found
[10:13:51.704] <TB2> INFO: resulting noise thresholds per ROC = 120 125 125 115 120 120 120 125 125 110 120 125 115 110 110 115
[10:13:51.704] <TB2> INFO: threshold scan done
[10:13:51.977] <TB2> INFO: Expecting 416000 events.
[10:14:02.520] <TB2> INFO: 416000 events read in total (9987ms).
[10:14:02.521] <TB2> INFO: Test took 10809ms.
[10:14:02.741] <TB2> INFO: ROC 0, missing bumps = 2377, total number of good bumps = 1783
[10:14:02.743] <TB2> INFO: ROC 1, missing bumps = 4160, total number of good bumps = 0
[10:14:02.745] <TB2> INFO: ROC 2, missing bumps = 2935, total number of good bumps = 1225
[10:14:02.747] <TB2> INFO: ROC 3, missing bumps = 2340, total number of good bumps = 1820
[10:14:02.748] <TB2> INFO: ROC 4, missing bumps = 4160, total number of good bumps = 0
[10:14:02.750] <TB2> INFO: ROC 5, missing bumps = 37, total number of good bumps = 4123
[10:14:02.752] <TB2> INFO: ROC 6, missing bumps = 4160, total number of good bumps = 0
[10:14:02.753] <TB2> INFO: ROC 7, missing bumps = 3857, total number of good bumps = 303
[10:14:02.755] <TB2> INFO: ROC 8, missing bumps = 3434, total number of good bumps = 726
[10:14:02.757] <TB2> INFO: ROC 9, missing bumps = 4160, total number of good bumps = 0
[10:14:02.758] <TB2> INFO: ROC 10, missing bumps = 4110, total number of good bumps = 50
[10:14:02.760] <TB2> INFO: ROC 11, missing bumps = 4160, total number of good bumps = 0
[10:14:02.762] <TB2> INFO: ROC 12, missing bumps = 4160, total number of good bumps = 0
[10:14:02.763] <TB2> INFO: ROC 13, missing bumps = 4160, total number of good bumps = 0
[10:14:02.765] <TB2> INFO: ROC 14, missing bumps = 4159, total number of good bumps = 1
[10:14:02.767] <TB2> INFO: ROC 15, missing bumps = 4160, total number of good bumps = 0
[10:14:02.767] <TB2> INFO: CalDel = 80 done
[10:14:03.054] <TB2> INFO: Expecting 416000 events.
[10:14:15.273] <TB2> INFO: 416000 events read in total (11663ms).
[10:14:15.274] <TB2> INFO: Test took 12501ms.
[10:14:15.468] <TB2> INFO: ROC 0, missing bumps = 75, total number of good bumps = 4085
[10:14:15.470] <TB2> INFO: ROC 1, missing bumps = 4091, total number of good bumps = 69
[10:14:15.472] <TB2> INFO: ROC 2, missing bumps = 110, total number of good bumps = 4050
[10:14:15.473] <TB2> INFO: ROC 3, missing bumps = 34, total number of good bumps = 4126
[10:14:15.475] <TB2> INFO: ROC 4, missing bumps = 4160, total number of good bumps = 0
[10:14:15.476] <TB2> INFO: ROC 5, missing bumps = 0, total number of good bumps = 4160
[10:14:15.483] <TB2> INFO: ROC 6, missing bumps = 4142, total number of good bumps = 18
[10:14:15.484] <TB2> INFO: ROC 7, missing bumps = 387, total number of good bumps = 3773
[10:14:15.486] <TB2> INFO: ROC 8, missing bumps = 445, total number of good bumps = 3715
[10:14:15.488] <TB2> INFO: ROC 9, missing bumps = 4160, total number of good bumps = 0
[10:14:15.489] <TB2> INFO: ROC 10, missing bumps = 2342, total number of good bumps = 1818
[10:14:15.491] <TB2> INFO: ROC 11, missing bumps = 3887, total number of good bumps = 273
[10:14:15.492] <TB2> INFO: ROC 12, missing bumps = 4160, total number of good bumps = 0
[10:14:15.494] <TB2> INFO: ROC 13, missing bumps = 4160, total number of good bumps = 0
[10:14:15.496] <TB2> INFO: ROC 14, missing bumps = 3253, total number of good bumps = 907
[10:14:15.497] <TB2> INFO: ROC 15, missing bumps = 3355, total number of good bumps = 805
[10:14:15.497] <TB2> INFO: CalDel = 90 done
[10:14:15.767] <TB2> INFO: Expecting 416000 events.
[10:14:29.666] <TB2> INFO: 416000 events read in total (13343ms).
[10:14:29.667] <TB2> INFO: Test took 14165ms.
[10:14:29.834] <TB2> INFO: ROC 0, missing bumps = 0, total number of good bumps = 4160
[10:14:29.836] <TB2> INFO: ROC 1, missing bumps = 1655, total number of good bumps = 2505
[10:14:29.837] <TB2> INFO: ROC 2, missing bumps = 1, total number of good bumps = 4159
[10:14:29.839] <TB2> INFO: ROC 3, missing bumps = 6, total number of good bumps = 4154
[10:14:29.840] <TB2> INFO: ROC 4, missing bumps = 4157, total number of good bumps = 3
[10:14:29.841] <TB2> INFO: ROC 6, missing bumps = 1527, total number of good bumps = 2633
[10:14:29.842] <TB2> INFO: ROC 7, missing bumps = 5, total number of good bumps = 4155
[10:14:29.844] <TB2> INFO: ROC 8, missing bumps = 5, total number of good bumps = 4155
[10:14:29.845] <TB2> INFO: ROC 9, missing bumps = 4160, total number of good bumps = 0
[10:14:29.846] <TB2> INFO: ROC 10, missing bumps = 37, total number of good bumps = 4123
[10:14:29.848] <TB2> INFO: ROC 11, missing bumps = 353, total number of good bumps = 3807
[10:14:29.849] <TB2> INFO: ROC 12, missing bumps = 4120, total number of good bumps = 40
[10:14:29.851] <TB2> INFO: ROC 13, missing bumps = 3446, total number of good bumps = 714
[10:14:29.852] <TB2> INFO: ROC 14, missing bumps = 42, total number of good bumps = 4118
[10:14:29.854] <TB2> INFO: ROC 15, missing bumps = 72, total number of good bumps = 4088
[10:14:29.854] <TB2> INFO: CalDel = 100 done
[10:14:30.129] <TB2> INFO: Expecting 416000 events.
[10:14:45.027] <TB2> INFO: 416000 events read in total (14342ms).
[10:14:45.028] <TB2> INFO: Test took 15170ms.
[10:14:45.198] <TB2> INFO: ROC 1, missing bumps = 40, total number of good bumps = 4120
[10:14:45.200] <TB2> INFO: ROC 2, missing bumps = 0, total number of good bumps = 4160
[10:14:45.201] <TB2> INFO: ROC 3, missing bumps = 3, total number of good bumps = 4157
[10:14:45.202] <TB2> INFO: ROC 4, missing bumps = 2627, total number of good bumps = 1533
[10:14:45.204] <TB2> INFO: ROC 6, missing bumps = 8, total number of good bumps = 4152
[10:14:45.205] <TB2> INFO: ROC 7, missing bumps = 1, total number of good bumps = 4159
[10:14:45.206] <TB2> INFO: ROC 8, missing bumps = 3, total number of good bumps = 4157
[10:14:45.207] <TB2> INFO: ROC 9, missing bumps = 4014, total number of good bumps = 146
[10:14:45.209] <TB2> INFO: ROC 10, missing bumps = 6, total number of good bumps = 4154
[10:14:45.210] <TB2> INFO: ROC 11, missing bumps = 4, total number of good bumps = 4156
[10:14:45.212] <TB2> INFO: ROC 12, missing bumps = 2337, total number of good bumps = 1823
[10:14:45.213] <TB2> INFO: ROC 13, missing bumps = 190, total number of good bumps = 3970
[10:14:45.215] <TB2> INFO: ROC 14, missing bumps = 0, total number of good bumps = 4160
[10:14:45.216] <TB2> INFO: ROC 15, missing bumps = 4, total number of good bumps = 4156
[10:14:45.216] <TB2> INFO: CalDel = 110 done
[10:14:45.487] <TB2> INFO: Expecting 416000 events.
[10:15:01.184] <TB2> INFO: 416000 events read in total (15141ms).
[10:15:01.186] <TB2> INFO: Test took 15965ms.
[10:15:01.354] <TB2> INFO: ROC 1, missing bumps = 6, total number of good bumps = 4154
[10:15:01.356] <TB2> INFO: ROC 3, missing bumps = 2, total number of good bumps = 4158
[10:15:01.357] <TB2> INFO: ROC 4, missing bumps = 24, total number of good bumps = 4136
[10:15:01.358] <TB2> INFO: ROC 6, missing bumps = 1, total number of good bumps = 4159
[10:15:01.360] <TB2> INFO: ROC 7, missing bumps = 0, total number of good bumps = 4160
[10:15:01.361] <TB2> INFO: ROC 8, missing bumps = 3, total number of good bumps = 4157
[10:15:01.363] <TB2> INFO: ROC 9, missing bumps = 855, total number of good bumps = 3305
[10:15:01.364] <TB2> INFO: ROC 10, missing bumps = 3, total number of good bumps = 4157
[10:15:01.366] <TB2> INFO: ROC 11, missing bumps = 0, total number of good bumps = 4160
[10:15:01.367] <TB2> INFO: ROC 12, missing bumps = 12, total number of good bumps = 4148
[10:15:01.369] <TB2> INFO: ROC 13, missing bumps = 1, total number of good bumps = 4159
[10:15:01.371] <TB2> INFO: ROC 15, missing bumps = 1, total number of good bumps = 4159
[10:15:01.371] <TB2> INFO: CalDel = 120 done
[10:15:01.646] <TB2> INFO: Expecting 416000 events.
[10:15:17.309] <TB2> INFO: 416000 events read in total (15107ms).
[10:15:17.310] <TB2> INFO: Test took 15935ms.
[10:15:17.479] <TB2> INFO: ROC 1, missing bumps = 5, total number of good bumps = 4155
[10:15:17.480] <TB2> INFO: ROC 3, missing bumps = 10, total number of good bumps = 4159
[10:15:17.482] <TB2> INFO: ROC 4, missing bumps = 3, total number of good bumps = 4157
[10:15:17.484] <TB2> INFO: ROC 6, missing bumps = 0, total number of good bumps = 4160
[10:15:17.485] <TB2> INFO: ROC 8, missing bumps = 2, total number of good bumps = 4158
[10:15:17.487] <TB2> INFO: ROC 9, missing bumps = 6, total number of good bumps = 4154
[10:15:17.488] <TB2> INFO: ROC 10, missing bumps = 2, total number of good bumps = 4158
[10:15:17.489] <TB2> INFO: ROC 12, missing bumps = 2, total number of good bumps = 4158
[10:15:17.491] <TB2> INFO: ROC 13, missing bumps = 0, total number of good bumps = 4160
[10:15:17.492] <TB2> INFO: ROC 15, missing bumps = 1, total number of good bumps = 4159
[10:15:17.492] <TB2> INFO: CalDel = 130 done
[10:15:17.759] <TB2> INFO: Expecting 416000 events.
[10:15:32.830] <TB2> INFO: 416000 events read in total (14515ms).
[10:15:32.831] <TB2> INFO: Test took 15334ms.
[10:15:33.002] <TB2> INFO: ROC 1, missing bumps = 3, total number of good bumps = 4157
[10:15:33.004] <TB2> INFO: ROC 3, missing bumps = 1445, total number of good bumps = 4159
[10:15:33.006] <TB2> INFO: ROC 4, missing bumps = 2, total number of good bumps = 4158
[10:15:33.008] <TB2> INFO: ROC 8, missing bumps = 30, total number of good bumps = 4159
[10:15:33.010] <TB2> INFO: ROC 9, missing bumps = 3, total number of good bumps = 4157
[10:15:33.011] <TB2> INFO: ROC 10, missing bumps = 7, total number of good bumps = 4159
[10:15:33.013] <TB2> INFO: ROC 12, missing bumps = 0, total number of good bumps = 4160
[10:15:33.015] <TB2> INFO: ROC 15, missing bumps = 1, total number of good bumps = 4159
[10:15:33.015] <TB2> INFO: CalDel = 140 done
[10:15:33.299] <TB2> INFO: Expecting 416000 events.
[10:15:46.523] <TB2> INFO: 416000 events read in total (12669ms).
[10:15:46.524] <TB2> INFO: Test took 13503ms.
[10:15:46.700] <TB2> INFO: ROC 1, missing bumps = 51, total number of good bumps = 4157
[10:15:46.702] <TB2> INFO: ROC 3, missing bumps = 4148, total number of good bumps = 4159
[10:15:46.703] <TB2> INFO: ROC 4, missing bumps = 1, total number of good bumps = 4159
[10:15:46.705] <TB2> INFO: ROC 8, missing bumps = 3237, total number of good bumps = 4159
[10:15:46.706] <TB2> INFO: ROC 9, missing bumps = 1, total number of good bumps = 4159
[10:15:46.708] <TB2> INFO: ROC 10, missing bumps = 2264, total number of good bumps = 4159
[10:15:46.709] <TB2> INFO: ROC 15, missing bumps = 549, total number of good bumps = 4159
[10:15:46.709] <TB2> INFO: CalDel = 150 done
[10:15:46.987] <TB2> INFO: Expecting 416000 events.
[10:15:58.592] <TB2> INFO: 416000 events read in total (11049ms).
[10:15:58.593] <TB2> INFO: Test took 11880ms.
[10:15:58.806] <TB2> INFO: ROC 1, missing bumps = 1765, total number of good bumps = 4157
[10:15:58.807] <TB2> INFO: ROC 3, missing bumps = 4156, total number of good bumps = 4159
[10:15:58.809] <TB2> INFO: ROC 4, missing bumps = 1, total number of good bumps = 4159
[10:15:58.810] <TB2> INFO: ROC 8, missing bumps = 4148, total number of good bumps = 4159
[10:15:58.811] <TB2> INFO: ROC 9, missing bumps = 0, total number of good bumps = 4160
[10:15:58.812] <TB2> INFO: ROC 10, missing bumps = 4118, total number of good bumps = 4159
[10:15:58.814] <TB2> INFO: ROC 15, missing bumps = 3903, total number of good bumps = 4159
[10:15:58.814] <TB2> INFO: CalDel = 160 done
[10:15:59.081] <TB2> INFO: Expecting 416000 events.
[10:16:09.854] <TB2> INFO: 416000 events read in total (10217ms).
[10:16:09.855] <TB2> INFO: Test took 11037ms.
[10:16:10.086] <TB2> INFO: ROC 1, missing bumps = 4069, total number of good bumps = 4158
[10:16:10.088] <TB2> INFO: ROC 3, missing bumps = 4159, total number of good bumps = 4159
[10:16:10.089] <TB2> INFO: ROC 4, missing bumps = 263, total number of good bumps = 4160
[10:16:10.090] <TB2> INFO: ROC 8, missing bumps = 4158, total number of good bumps = 4159
[10:16:10.092] <TB2> INFO: ROC 10, missing bumps = 4155, total number of good bumps = 4159
[10:16:10.093] <TB2> INFO: ROC 15, missing bumps = 4155, total number of good bumps = 4159
[10:16:10.093] <TB2> INFO: CalDel = 170 done
[10:16:10.359] <TB2> INFO: Expecting 416000 events.
[10:16:20.020] <TB2> INFO: 416000 events read in total (9105ms).
[10:16:20.020] <TB2> INFO: Test took 9922ms.
[10:16:20.273] <TB2> INFO: ROC 1, missing bumps = 4156, total number of good bumps = 4158
[10:16:20.274] <TB2> INFO: ROC 3, missing bumps = 4160, total number of good bumps = 4159
[10:16:20.276] <TB2> INFO: ROC 4, missing bumps = 4041, total number of good bumps = 4160
[10:16:20.277] <TB2> INFO: ROC 8, missing bumps = 4159, total number of good bumps = 4159
[10:16:20.278] <TB2> INFO: ROC 10, missing bumps = 4158, total number of good bumps = 4159
[10:16:20.280] <TB2> INFO: ROC 15, missing bumps = 4160, total number of good bumps = 4159
[10:16:20.280] <TB2> INFO: CalDel = 180 done
[10:16:20.547] <TB2> INFO: Expecting 416000 events.
[10:16:29.913] <TB2> INFO: 416000 events read in total (8810ms).
[10:16:29.918] <TB2> INFO: Test took 9633ms.
[10:16:30.185] <TB2> INFO: ROC 1, missing bumps = 4159, total number of good bumps = 4158
[10:16:30.187] <TB2> INFO: ROC 3, missing bumps = 4160, total number of good bumps = 4159
[10:16:30.188] <TB2> INFO: ROC 4, missing bumps = 4155, total number of good bumps = 4160
[10:16:30.190] <TB2> INFO: ROC 8, missing bumps = 4159, total number of good bumps = 4159
[10:16:30.191] <TB2> INFO: ROC 10, missing bumps = 4160, total number of good bumps = 4159
[10:16:30.193] <TB2> INFO: ROC 15, missing bumps = 4160, total number of good bumps = 4159
[10:16:30.193] <TB2> INFO: CalDel = 190 done
[10:16:30.460] <TB2> INFO: Expecting 416000 events.
[10:16:39.828] <TB2> INFO: 416000 events read in total (8812ms).
[10:16:39.829] <TB2> INFO: Test took 9632ms.
[10:16:40.101] <TB2> INFO: ROC 1, missing bumps = 4160, total number of good bumps = 4158
[10:16:40.102] <TB2> INFO: ROC 3, missing bumps = 4160, total number of good bumps = 4159
[10:16:40.104] <TB2> INFO: ROC 4, missing bumps = 4158, total number of good bumps = 4160
[10:16:40.106] <TB2> INFO: ROC 8, missing bumps = 4160, total number of good bumps = 4159
[10:16:40.107] <TB2> INFO: ROC 10, missing bumps = 4160, total number of good bumps = 4159
[10:16:40.109] <TB2> INFO: ROC 15, missing bumps = 4160, total number of good bumps = 4159
[10:16:40.109] <TB2> INFO: CalDel = 200 done
[10:16:40.109] <TB2> INFO: CalDel scan done
[10:16:40.111] <TB2> INFO: resulting values: CalDel = 100 140 110 120 150 90 130 120 130 160 130 120 140 130 110 120
[10:16:40.384] <TB2> INFO: Expecting 416000 events.
[10:16:55.791] <TB2> INFO: 416000 events read in total (14851ms).
[10:16:55.792] <TB2> INFO: Test took 15679ms.
[10:16:55.799] <TB2> INFO: maps.size() = 16
[10:16:56.006] <TB2> INFO: 6 broken Bump Bonds found
[10:16:56.008] <TB2> INFO: back to CtrlReg 0
[10:16:56.012] <TB2> INFO: PixTestBB4Map::doTest() done
[10:16:56.012] <TB2> INFO: Write out MinimumThreshold
[10:16:56.013] <TB2> INFO: Write out CalDel Scan_C0_V0
[10:16:56.013] <TB2> INFO: Write out PixelHit_C0_V0
[10:16:56.013] <TB2> INFO: Write out BB4MapBest_C0_V0
[10:16:56.013] <TB2> INFO: Write out NoiseScan_C0_V0
[10:16:56.013] <TB2> INFO: Write out BB4Map_C0_V14
[10:16:56.013] <TB2> INFO: Write out Hit Distr_C0_V0
[10:16:56.013] <TB2> INFO: Write out Hit Distr_C0_V0
[10:16:56.013] <TB2> INFO: Write out CalDel Scan_C1_V0
[10:16:56.013] <TB2> INFO: Write out PixelHit_C1_V0
[10:16:56.013] <TB2> INFO: Write out BB4MapBest_C1_V0
[10:16:56.013] <TB2> INFO: Write out NoiseScan_C1_V0
[10:16:56.014] <TB2> INFO: Write out BB4Map_C1_V14
[10:16:56.014] <TB2> INFO: Write out Hit Distr_C1_V0
[10:16:56.014] <TB2> INFO: Write out Hit Distr_C1_V0
[10:16:56.014] <TB2> INFO: Write out CalDel Scan_C2_V0
[10:16:56.014] <TB2> INFO: Write out PixelHit_C2_V0
[10:16:56.014] <TB2> INFO: Write out BB4MapBest_C2_V0
[10:16:56.014] <TB2> INFO: Write out NoiseScan_C2_V0
[10:16:56.014] <TB2> INFO: Write out BB4Map_C2_V14
[10:16:56.014] <TB2> INFO: Write out Hit Distr_C2_V0
[10:16:56.014] <TB2> INFO: Write out Hit Distr_C2_V0
[10:16:56.014] <TB2> INFO: Write out CalDel Scan_C3_V0
[10:16:56.014] <TB2> INFO: Write out PixelHit_C3_V0
[10:16:56.014] <TB2> INFO: Write out BB4MapBest_C3_V0
[10:16:56.015] <TB2> INFO: Write out NoiseScan_C3_V0
[10:16:56.015] <TB2> INFO: Write out BB4Map_C3_V14
[10:16:56.015] <TB2> INFO: Write out Hit Distr_C3_V0
[10:16:56.015] <TB2> INFO: Write out Hit Distr_C3_V0
[10:16:56.015] <TB2> INFO: Write out CalDel Scan_C4_V0
[10:16:56.015] <TB2> INFO: Write out PixelHit_C4_V0
[10:16:56.015] <TB2> INFO: Write out BB4MapBest_C4_V0
[10:16:56.015] <TB2> INFO: Write out NoiseScan_C4_V0
[10:16:56.015] <TB2> INFO: Write out BB4Map_C4_V14
[10:16:56.015] <TB2> INFO: Write out Hit Distr_C4_V0
[10:16:56.015] <TB2> INFO: Write out Hit Distr_C4_V0
[10:16:56.015] <TB2> INFO: Write out CalDel Scan_C5_V0
[10:16:56.015] <TB2> INFO: Write out PixelHit_C5_V0
[10:16:56.016] <TB2> INFO: Write out BB4MapBest_C5_V0
[10:16:56.016] <TB2> INFO: Write out NoiseScan_C5_V0
[10:16:56.016] <TB2> INFO: Write out BB4Map_C5_V14
[10:16:56.016] <TB2> INFO: Write out Hit Distr_C5_V0
[10:16:56.016] <TB2> INFO: Write out Hit Distr_C5_V0
[10:16:56.016] <TB2> INFO: Write out CalDel Scan_C6_V0
[10:16:56.016] <TB2> INFO: Write out PixelHit_C6_V0
[10:16:56.016] <TB2> INFO: Write out BB4MapBest_C6_V0
[10:16:56.016] <TB2> INFO: Write out NoiseScan_C6_V0
[10:16:56.016] <TB2> INFO: Write out BB4Map_C6_V14
[10:16:56.016] <TB2> INFO: Write out Hit Distr_C6_V0
[10:16:56.017] <TB2> INFO: Write out Hit Distr_C6_V0
[10:16:56.017] <TB2> INFO: Write out CalDel Scan_C7_V0
[10:16:56.017] <TB2> INFO: Write out PixelHit_C7_V0
[10:16:56.017] <TB2> INFO: Write out BB4MapBest_C7_V0
[10:16:56.017] <TB2> INFO: Write out NoiseScan_C7_V0
[10:16:56.017] <TB2> INFO: Write out BB4Map_C7_V14
[10:16:56.017] <TB2> INFO: Write out Hit Distr_C7_V0
[10:16:56.017] <TB2> INFO: Write out Hit Distr_C7_V0
[10:16:56.017] <TB2> INFO: Write out CalDel Scan_C8_V0
[10:16:56.017] <TB2> INFO: Write out PixelHit_C8_V0
[10:16:56.017] <TB2> INFO: Write out BB4MapBest_C8_V0
[10:16:56.017] <TB2> INFO: Write out NoiseScan_C8_V0
[10:16:56.017] <TB2> INFO: Write out BB4Map_C8_V14
[10:16:56.018] <TB2> INFO: Write out Hit Distr_C8_V0
[10:16:56.018] <TB2> INFO: Write out Hit Distr_C8_V0
[10:16:56.018] <TB2> INFO: Write out CalDel Scan_C9_V0
[10:16:56.018] <TB2> INFO: Write out PixelHit_C9_V0
[10:16:56.018] <TB2> INFO: Write out BB4MapBest_C9_V0
[10:16:56.018] <TB2> INFO: Write out NoiseScan_C9_V0
[10:16:56.018] <TB2> INFO: Write out BB4Map_C9_V14
[10:16:56.018] <TB2> INFO: Write out Hit Distr_C9_V0
[10:16:56.018] <TB2> INFO: Write out Hit Distr_C9_V0
[10:16:56.018] <TB2> INFO: Write out CalDel Scan_C10_V0
[10:16:56.018] <TB2> INFO: Write out PixelHit_C10_V0
[10:16:56.018] <TB2> INFO: Write out BB4MapBest_C10_V0
[10:16:56.019] <TB2> INFO: Write out NoiseScan_C10_V0
[10:16:56.019] <TB2> INFO: Write out BB4Map_C10_V14
[10:16:56.019] <TB2> INFO: Write out Hit Distr_C10_V0
[10:16:56.019] <TB2> INFO: Write out Hit Distr_C10_V0
[10:16:56.019] <TB2> INFO: Write out CalDel Scan_C11_V0
[10:16:56.019] <TB2> INFO: Write out PixelHit_C11_V0
[10:16:56.019] <TB2> INFO: Write out BB4MapBest_C11_V0
[10:16:56.019] <TB2> INFO: Write out NoiseScan_C11_V0
[10:16:56.019] <TB2> INFO: Write out BB4Map_C11_V14
[10:16:56.019] <TB2> INFO: Write out Hit Distr_C11_V0
[10:16:56.019] <TB2> INFO: Write out Hit Distr_C11_V0
[10:16:56.019] <TB2> INFO: Write out CalDel Scan_C12_V0
[10:16:56.020] <TB2> INFO: Write out PixelHit_C12_V0
[10:16:56.020] <TB2> INFO: Write out BB4MapBest_C12_V0
[10:16:56.020] <TB2> INFO: Write out NoiseScan_C12_V0
[10:16:56.020] <TB2> INFO: Write out BB4Map_C12_V14
[10:16:56.020] <TB2> INFO: Write out Hit Distr_C12_V0
[10:16:56.020] <TB2> INFO: Write out Hit Distr_C12_V0
[10:16:56.020] <TB2> INFO: Write out CalDel Scan_C13_V0
[10:16:56.020] <TB2> INFO: Write out PixelHit_C13_V0
[10:16:56.020] <TB2> INFO: Write out BB4MapBest_C13_V0
[10:16:56.020] <TB2> INFO: Write out NoiseScan_C13_V0
[10:16:56.020] <TB2> INFO: Write out BB4Map_C13_V14
[10:16:56.020] <TB2> INFO: Write out Hit Distr_C13_V0
[10:16:56.021] <TB2> INFO: Write out Hit Distr_C13_V0
[10:16:56.021] <TB2> INFO: Write out CalDel Scan_C14_V0
[10:16:56.021] <TB2> INFO: Write out PixelHit_C14_V0
[10:16:56.021] <TB2> INFO: Write out BB4MapBest_C14_V0
[10:16:56.021] <TB2> INFO: Write out NoiseScan_C14_V0
[10:16:56.021] <TB2> INFO: Write out BB4Map_C14_V14
[10:16:56.021] <TB2> INFO: Write out Hit Distr_C14_V0
[10:16:56.021] <TB2> INFO: Write out Hit Distr_C14_V0
[10:16:56.021] <TB2> INFO: Write out CalDel Scan_C15_V0
[10:16:56.021] <TB2> INFO: Write out PixelHit_C15_V0
[10:16:56.021] <TB2> INFO: Write out BB4MapBest_C15_V0
[10:16:56.021] <TB2> INFO: Write out NoiseScan_C15_V0
[10:16:56.021] <TB2> INFO: Write out BB4Map_C15_V14
[10:16:56.022] <TB2> INFO: Write out Hit Distr_C15_V0
[10:16:56.022] <TB2> INFO: Write out Hit Distr_C15_V0
[10:16:56.022] <TB2> INFO: enter test to run
[10:16:56.022] <TB2> INFO: test: no parameter change
[10:16:56.046] <TB2> QUIET: Connection to board 149 closed.
[10:16:56.126] <TB2> INFO: pXar: this is the end, my friend