[08:33:26.568] <TB2> INFO: *** Welcome to pxar ***
[08:33:26.568] <TB2> INFO: *** Today: 2015/08/28
[08:33:26.568] <TB2> INFO: readRocDacs: /usr/local/data/M2086_XrayQualification_2015-08-28_10h32m_1440750764//000_PixelAlive_p17//dacParameters35_C0.dat .. /usr/local/data/M2086_XrayQualification_2015-08-28_10h32m_1440750764//000_PixelAlive_p17//dacParameters35_C15.dat
[08:33:26.569] <TB2> INFO: readTbmDacs: /usr/local/data/M2086_XrayQualification_2015-08-28_10h32m_1440750764//000_PixelAlive_p17//tbmParameters_C0a.dat .. /usr/local/data/M2086_XrayQualification_2015-08-28_10h32m_1440750764//000_PixelAlive_p17//tbmParameters_C0b.dat
[08:33:26.569] <TB2> INFO: readMaskFile: /usr/local/data/M2086_XrayQualification_2015-08-28_10h32m_1440750764//000_PixelAlive_p17//defaultMaskFile.dat
[08:33:26.569] <TB2> INFO: readTrimFile: /usr/local/data/M2086_XrayQualification_2015-08-28_10h32m_1440750764//000_PixelAlive_p17//trimParameters35_C0.dat .. /usr/local/data/M2086_XrayQualification_2015-08-28_10h32m_1440750764//000_PixelAlive_p17//trimParameters35_C15.dat
[08:33:26.641] <TB2> INFO: clk: 4
[08:33:26.641] <TB2> INFO: ctr: 4
[08:33:26.641] <TB2> INFO: sda: 19
[08:33:26.641] <TB2> INFO: tin: 9
[08:33:26.641] <TB2> INFO: level: 15
[08:33:26.641] <TB2> INFO: triggerdelay: 0
[08:33:26.641] <TB2> QUIET: Instanciating API for pxar v2.5+57~g44d0777
[08:33:26.641] <TB2> INFO: Log level: INFO
[08:33:26.655] <TB2> INFO: Found DTB DTB_WXENWR
[08:33:26.667] <TB2> QUIET: Connection to board DTB_WXENWR opened.
[08:33:26.670] <TB2> INFO: DTB startup information
--- DTB info------------------------------------------
Board id: 162
HW version: DTB1.2
FW version: 4.2
SW version: 4.4
USB id: DTB_WXENWR
MAC address: 40D8551180A2
Hostname: pixelDTB162
Comment:
------------------------------------------------------
[08:33:26.673] <TB2> INFO: RPC call hashes of host and DTB match: 397073690
[08:33:28.250] <TB2> INFO: DUT info:
[08:33:28.250] <TB2> INFO: The DUT currently contains the following objects:
[08:33:28.250] <TB2> INFO: 2 TBM Cores tbm09c (2 ON)
[08:33:28.250] <TB2> INFO: TBM Core alpha (0): 7 registers set
[08:33:28.250] <TB2> INFO: TBM Core beta (1): 7 registers set
[08:33:28.250] <TB2> INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[08:33:28.251] <TB2> INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[08:33:28.251] <TB2> INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[08:33:28.251] <TB2> INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[08:33:28.251] <TB2> INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[08:33:28.251] <TB2> INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[08:33:28.251] <TB2> INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[08:33:28.251] <TB2> INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[08:33:28.251] <TB2> INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[08:33:28.251] <TB2> INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[08:33:28.251] <TB2> INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[08:33:28.251] <TB2> INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[08:33:28.251] <TB2> INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[08:33:28.251] <TB2> INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[08:33:28.251] <TB2> INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[08:33:28.251] <TB2> INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[08:33:28.251] <TB2> INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[08:33:28.652] <TB2> INFO: enter 'restricted' command line mode
[08:33:28.652] <TB2> INFO: enter test to run
[08:33:28.652] <TB2> INFO: test: PixelAlive no parameter change
[08:33:28.652] <TB2> INFO: running: pixelalive
[08:33:28.659] <TB2> INFO: ######################################################################
[08:33:28.659] <TB2> INFO: PixTestAlive::doTest()
[08:33:28.659] <TB2> INFO: ######################################################################
[08:33:28.661] <TB2> INFO: ----------------------------------------------------------------------
[08:33:28.661] <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)
[08:33:28.661] <TB2> INFO: ----------------------------------------------------------------------
[08:33:28.966] <TB2> INFO: Expecting 41600 events.
[08:33:33.396] <TB2> INFO: 41600 events read in total (3710ms).
[08:33:33.472] <TB2> INFO: Test took 4808ms.
[08:33:33.480] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:33:33.748] <TB2> INFO: PixTestAlive::aliveTest() done
[08:33:33.748] <TB2> INFO: number of dead pixels (per ROC): 2 1 0 1 0 0 0 0 1 0 0 0 0 0 0 0
[08:33:33.750] <TB2> INFO: ----------------------------------------------------------------------
[08:33:33.750] <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)
[08:33:33.750] <TB2> INFO: ----------------------------------------------------------------------
[08:33:34.068] <TB2> INFO: Expecting 41600 events.
[08:33:37.195] <TB2> INFO: 41600 events read in total (2410ms).
[08:33:37.195] <TB2> INFO: Test took 3442ms.
[08:33:37.196] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:33:37.196] <TB2> INFO: mask vs. old pixelAlive PixelAlive_C0_V0 .. PixelAlive_C15_V0
[08:33:37.526] <TB2> INFO: PixTestAlive::maskTest() done
[08:33:37.526] <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
[08:33:37.528] <TB2> INFO: ----------------------------------------------------------------------
[08:33:37.528] <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)
[08:33:37.528] <TB2> INFO: ----------------------------------------------------------------------
[08:33:37.860] <TB2> INFO: Expecting 41600 events.
[08:33:42.388] <TB2> INFO: 41600 events read in total (3811ms).
[08:33:42.389] <TB2> INFO: Test took 4859ms.
[08:33:42.398] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:33:42.700] <TB2> INFO: PixTestAlive::addressDecodingTest() done
[08:33:42.700] <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
[08:33:42.701] <TB2> INFO: PixTestAlive::doTest() done, duration: 14 seconds
[08:33:42.751] <TB2> INFO: enter test to run
[08:33:42.751] <TB2> INFO: test: no parameter change
[08:33:42.890] <TB2> QUIET: Connection to board 162 closed.
[08:33:42.970] <TB2> INFO: pXar: this is the end, my friend
commander_RetrimHotPixels.log
[08:39:00.414] <TB2> INFO: *** Welcome to pxar ***
[08:39:00.414] <TB2> INFO: *** Today: 2015/08/28
[08:39:00.414] <TB2> INFO: readRocDacs: /usr/local/data/M2086_XrayQualification_2015-08-28_10h32m_1440750764//002_RetrimHotPixels_150//dacParameters35_C0.dat .. /usr/local/data/M2086_XrayQualification_2015-08-28_10h32m_1440750764//002_RetrimHotPixels_150//dacParameters35_C15.dat
[08:39:00.415] <TB2> INFO: readTbmDacs: /usr/local/data/M2086_XrayQualification_2015-08-28_10h32m_1440750764//002_RetrimHotPixels_150//tbmParameters_C0a.dat .. /usr/local/data/M2086_XrayQualification_2015-08-28_10h32m_1440750764//002_RetrimHotPixels_150//tbmParameters_C0b.dat
[08:39:00.415] <TB2> INFO: readMaskFile: /usr/local/data/M2086_XrayQualification_2015-08-28_10h32m_1440750764//002_RetrimHotPixels_150//defaultMaskFile.dat
[08:39:00.415] <TB2> INFO: readTrimFile: /usr/local/data/M2086_XrayQualification_2015-08-28_10h32m_1440750764//002_RetrimHotPixels_150//trimParameters35_C0.dat .. /usr/local/data/M2086_XrayQualification_2015-08-28_10h32m_1440750764//002_RetrimHotPixels_150//trimParameters35_C15.dat
[08:39:00.484] <TB2> INFO: clk: 4
[08:39:00.484] <TB2> INFO: ctr: 4
[08:39:00.484] <TB2> INFO: sda: 19
[08:39:00.484] <TB2> INFO: tin: 9
[08:39:00.484] <TB2> INFO: level: 15
[08:39:00.484] <TB2> INFO: triggerdelay: 0
[08:39:00.484] <TB2> QUIET: Instanciating API for pxar v2.5+57~g44d0777
[08:39:00.484] <TB2> INFO: Log level: INFO
[08:39:00.497] <TB2> INFO: Found DTB DTB_WXENWR
[08:39:00.509] <TB2> QUIET: Connection to board DTB_WXENWR opened.
[08:39:00.513] <TB2> INFO: DTB startup information
--- DTB info------------------------------------------
Board id: 162
HW version: DTB1.2
FW version: 4.2
SW version: 4.4
USB id: DTB_WXENWR
MAC address: 40D8551180A2
Hostname: pixelDTB162
Comment:
------------------------------------------------------
[08:39:00.516] <TB2> INFO: RPC call hashes of host and DTB match: 397073690
[08:39:02.050] <TB2> INFO: DUT info:
[08:39:02.050] <TB2> INFO: The DUT currently contains the following objects:
[08:39:02.050] <TB2> INFO: 2 TBM Cores tbm09c (2 ON)
[08:39:02.050] <TB2> INFO: TBM Core alpha (0): 7 registers set
[08:39:02.050] <TB2> INFO: TBM Core beta (1): 7 registers set
[08:39:02.051] <TB2> INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[08:39:02.051] <TB2> INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[08:39:02.051] <TB2> INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[08:39:02.051] <TB2> INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[08:39:02.051] <TB2> INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[08:39:02.051] <TB2> INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[08:39:02.051] <TB2> INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[08:39:02.051] <TB2> INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[08:39:02.051] <TB2> INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[08:39:02.051] <TB2> INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[08:39:02.051] <TB2> INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[08:39:02.051] <TB2> INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[08:39:02.051] <TB2> INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[08:39:02.051] <TB2> INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[08:39:02.051] <TB2> INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[08:39:02.051] <TB2> INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[08:39:02.051] <TB2> INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[08:39:02.452] <TB2> INFO: enter 'restricted' command line mode
[08:39:02.452] <TB2> INFO: enter test to run
[08:39:02.452] <TB2> INFO: test: delay setting parameters: ->5<-
[08:39:02.452] <TB2> INFO: delay test by 5 seconds...
[08:39:07.452] <TB2> INFO: enter test to run
[08:39:07.453] <TB2> INFO: test: highrate no parameter change
[08:39:07.453] <TB2> INFO: running: highrate
[08:39:07.453] <TB2> INFO: readGainPedestalParameters /usr/local/data/M2086_XrayQualification_2015-08-28_10h32m_1440750764//002_RetrimHotPixels_150//phCalibrationFitErr35_C0.dat .. /usr/local/data/M2086_XrayQualification_2015-08-28_10h32m_1440750764//002_RetrimHotPixels_150//phCalibrationFitErr35_C15.dat
[08:39:07.608] <TB2> INFO: ----------------------------------------------------------------------
[08:39:07.608] <TB2> INFO: PixTest::trimHotPixels() running for 1 seconds with 100 kHz trigger rate
[08:39:07.608] <TB2> INFO: ----------------------------------------------------------------------
[08:39:07.608] <TB2> INFO: THR = 150, corresponding to ~ 400 MHz/cm2
[08:39:07.608] <TB2> INFO: edge/corner pixel THR is adjusted
[08:39:07.608] <TB2> INFO: PixTestHighRate::trimHotPixels: step 0...
[08:39:08.573] <TB2> INFO: Collecting data for 1 seconds...
[08:39:09.574] <TB2> INFO: Done with hot pixel readout
[08:39:15.110] <TB2> INFO: PixTest:: pg_setup set to default.
[08:39:15.110] <TB2> INFO: 8 hot pixels found in step 0
[08:39:16.079] <TB2> INFO: Collecting data for 1 seconds...
[08:39:17.084] <TB2> INFO: Done with hot pixel readout
[08:39:22.556] <TB2> INFO: PixTest:: pg_setup set to default.
[08:39:22.557] <TB2> INFO: 3 hot pixels found in step 1
[08:39:23.527] <TB2> INFO: Collecting data for 1 seconds...
[08:39:24.529] <TB2> INFO: Done with hot pixel readout
[08:39:30.115] <TB2> INFO: PixTest:: pg_setup set to default.
[08:39:30.116] <TB2> INFO: 3 hot pixels found in step 2
[08:39:31.086] <TB2> INFO: Collecting data for 1 seconds...
[08:39:32.088] <TB2> INFO: Done with hot pixel readout
[08:39:37.541] <TB2> INFO: PixTest:: pg_setup set to default.
[08:39:37.542] <TB2> INFO: 1 hot pixels found in step 3
[08:39:38.512] <TB2> INFO: Collecting data for 1 seconds...
[08:39:39.514] <TB2> INFO: Done with hot pixel readout
[08:39:45.140] <TB2> INFO: PixTest:: pg_setup set to default.
[08:39:45.140] <TB2> INFO: 0 hot pixels found in step 4
[08:39:45.150] <TB2> INFO: 0 hot pixels could not be trimmed and have been masked.
[08:39:45.178] <TB2> INFO: PixTest::trimHotPixels() done
[08:39:45.179] <TB2> INFO: write trim parameters into /usr/local/data/M2086_XrayQualification_2015-08-28_10h32m_1440750764//002_RetrimHotPixels_150//trimParameters35_C0.dat
[08:39:45.185] <TB2> INFO: write trim parameters into /usr/local/data/M2086_XrayQualification_2015-08-28_10h32m_1440750764//002_RetrimHotPixels_150//trimParameters35_C1.dat
[08:39:45.191] <TB2> INFO: write trim parameters into /usr/local/data/M2086_XrayQualification_2015-08-28_10h32m_1440750764//002_RetrimHotPixels_150//trimParameters35_C2.dat
[08:39:45.197] <TB2> INFO: write trim parameters into /usr/local/data/M2086_XrayQualification_2015-08-28_10h32m_1440750764//002_RetrimHotPixels_150//trimParameters35_C3.dat
[08:39:45.203] <TB2> INFO: write trim parameters into /usr/local/data/M2086_XrayQualification_2015-08-28_10h32m_1440750764//002_RetrimHotPixels_150//trimParameters35_C4.dat
[08:39:45.209] <TB2> INFO: write trim parameters into /usr/local/data/M2086_XrayQualification_2015-08-28_10h32m_1440750764//002_RetrimHotPixels_150//trimParameters35_C5.dat
[08:39:45.215] <TB2> INFO: write trim parameters into /usr/local/data/M2086_XrayQualification_2015-08-28_10h32m_1440750764//002_RetrimHotPixels_150//trimParameters35_C6.dat
[08:39:45.221] <TB2> INFO: write trim parameters into /usr/local/data/M2086_XrayQualification_2015-08-28_10h32m_1440750764//002_RetrimHotPixels_150//trimParameters35_C7.dat
[08:39:45.227] <TB2> INFO: write trim parameters into /usr/local/data/M2086_XrayQualification_2015-08-28_10h32m_1440750764//002_RetrimHotPixels_150//trimParameters35_C8.dat
[08:39:45.233] <TB2> INFO: write trim parameters into /usr/local/data/M2086_XrayQualification_2015-08-28_10h32m_1440750764//002_RetrimHotPixels_150//trimParameters35_C9.dat
[08:39:45.239] <TB2> INFO: write trim parameters into /usr/local/data/M2086_XrayQualification_2015-08-28_10h32m_1440750764//002_RetrimHotPixels_150//trimParameters35_C10.dat
[08:39:45.245] <TB2> INFO: write trim parameters into /usr/local/data/M2086_XrayQualification_2015-08-28_10h32m_1440750764//002_RetrimHotPixels_150//trimParameters35_C11.dat
[08:39:45.251] <TB2> INFO: write trim parameters into /usr/local/data/M2086_XrayQualification_2015-08-28_10h32m_1440750764//002_RetrimHotPixels_150//trimParameters35_C12.dat
[08:39:45.257] <TB2> INFO: write trim parameters into /usr/local/data/M2086_XrayQualification_2015-08-28_10h32m_1440750764//002_RetrimHotPixels_150//trimParameters35_C13.dat
[08:39:45.263] <TB2> INFO: write trim parameters into /usr/local/data/M2086_XrayQualification_2015-08-28_10h32m_1440750764//002_RetrimHotPixels_150//trimParameters35_C14.dat
[08:39:45.269] <TB2> INFO: write trim parameters into /usr/local/data/M2086_XrayQualification_2015-08-28_10h32m_1440750764//002_RetrimHotPixels_150//trimParameters35_C15.dat
[08:39:45.275] <TB2> INFO: write masked pixels into /usr/local/data/M2086_XrayQualification_2015-08-28_10h32m_1440750764//002_RetrimHotPixels_150//defaultMaskFile.dat
[08:39:45.309] <TB2> INFO: enter test to run
[08:39:45.309] <TB2> INFO: test: no parameter change
[08:39:45.493] <TB2> QUIET: Connection to board 162 closed.
[08:39:45.573] <TB2> INFO: pXar: this is the end, my friend
[08:40:32.773] <TB2> INFO: *** Welcome to pxar ***
[08:40:32.773] <TB2> INFO: *** Today: 2015/08/28
[08:40:32.773] <TB2> INFO: readRocDacs: /usr/local/data/M2086_XrayQualification_2015-08-28_10h32m_1440750764//003_HRData_50//dacParameters35_C0.dat .. /usr/local/data/M2086_XrayQualification_2015-08-28_10h32m_1440750764//003_HRData_50//dacParameters35_C15.dat
[08:40:32.774] <TB2> INFO: readTbmDacs: /usr/local/data/M2086_XrayQualification_2015-08-28_10h32m_1440750764//003_HRData_50//tbmParameters_C0a.dat .. /usr/local/data/M2086_XrayQualification_2015-08-28_10h32m_1440750764//003_HRData_50//tbmParameters_C0b.dat
[08:40:32.774] <TB2> INFO: readMaskFile: /usr/local/data/M2086_XrayQualification_2015-08-28_10h32m_1440750764//003_HRData_50//defaultMaskFile.dat
[08:40:32.774] <TB2> INFO: readTrimFile: /usr/local/data/M2086_XrayQualification_2015-08-28_10h32m_1440750764//003_HRData_50//trimParameters35_C0.dat .. /usr/local/data/M2086_XrayQualification_2015-08-28_10h32m_1440750764//003_HRData_50//trimParameters35_C15.dat
[08:40:32.855] <TB2> INFO: clk: 4
[08:40:32.855] <TB2> INFO: ctr: 4
[08:40:32.855] <TB2> INFO: sda: 19
[08:40:32.855] <TB2> INFO: tin: 9
[08:40:32.855] <TB2> INFO: level: 15
[08:40:32.855] <TB2> INFO: triggerdelay: 0
[08:40:32.855] <TB2> QUIET: Instanciating API for pxar v2.5+57~g44d0777
[08:40:32.855] <TB2> INFO: Log level: INFO
[08:40:32.870] <TB2> INFO: Found DTB DTB_WXENWR
[08:40:32.881] <TB2> QUIET: Connection to board DTB_WXENWR opened.
[08:40:32.885] <TB2> INFO: DTB startup information
--- DTB info------------------------------------------
Board id: 162
HW version: DTB1.2
FW version: 4.2
SW version: 4.4
USB id: DTB_WXENWR
MAC address: 40D8551180A2
Hostname: pixelDTB162
Comment:
------------------------------------------------------
[08:40:32.887] <TB2> INFO: RPC call hashes of host and DTB match: 397073690
[08:40:34.457] <TB2> INFO: DUT info:
[08:40:34.457] <TB2> INFO: The DUT currently contains the following objects:
[08:40:34.457] <TB2> INFO: 2 TBM Cores tbm09c (2 ON)
[08:40:34.457] <TB2> INFO: TBM Core alpha (0): 7 registers set
[08:40:34.457] <TB2> INFO: TBM Core beta (1): 7 registers set
[08:40:34.457] <TB2> INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[08:40:34.457] <TB2> INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[08:40:34.457] <TB2> INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[08:40:34.457] <TB2> INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[08:40:34.457] <TB2> INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[08:40:34.457] <TB2> INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[08:40:34.457] <TB2> INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[08:40:34.457] <TB2> INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[08:40:34.457] <TB2> INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[08:40:34.457] <TB2> INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[08:40:34.457] <TB2> INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[08:40:34.458] <TB2> INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[08:40:34.458] <TB2> INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[08:40:34.458] <TB2> INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[08:40:34.458] <TB2> INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[08:40:34.458] <TB2> INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[08:40:34.458] <TB2> INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[08:40:34.859] <TB2> INFO: enter 'restricted' command line mode
[08:40:34.859] <TB2> INFO: enter test to run
[08:40:34.859] <TB2> INFO: test: delay setting parameters: ->5<-
[08:40:34.859] <TB2> INFO: delay test by 5 seconds...
[08:40:39.859] <TB2> INFO: enter test to run
[08:40:39.859] <TB2> INFO: test: Xray no parameter change
[08:40:39.859] <TB2> INFO: running: xray
[08:40:39.859] <TB2> INFO: readGainPedestalParameters /usr/local/data/M2086_XrayQualification_2015-08-28_10h32m_1440750764//003_HRData_50//phCalibrationFitErr35_C0.dat .. /usr/local/data/M2086_XrayQualification_2015-08-28_10h32m_1440750764//003_HRData_50//phCalibrationFitErr35_C15.dat
[08:40:40.010] <TB2> INFO: ######################################################################
[08:40:40.010] <TB2> INFO: PixTestXray::doTest()
[08:40:40.010] <TB2> INFO: ######################################################################
[08:40:40.010] <TB2> INFO: ----------------------------------------------------------------------
[08:40:40.010] <TB2> INFO: PixTestXray::doPhRun() fParRunSeconds = 100
[08:40:40.010] <TB2> INFO: ----------------------------------------------------------------------
[08:40:40.983] <TB2> INFO: PixTestXray::doPhRun start TriggerLoop with trigger frequency 100 kHz, period 405 and duration 100 seconds, fEventsMax = 10000000
[08:40:47.084] <TB2> INFO: run duration 6 seconds, buffer almost full (81%), pausing triggers.
[08:41:07.170] <TB2> INFO: Resuming triggers.
[08:41:13.275] <TB2> INFO: run duration 12 seconds, buffer almost full (81%), pausing triggers.
[08:41:33.383] <TB2> INFO: Resuming triggers.
[08:41:39.491] <TB2> INFO: run duration 18 seconds, buffer almost full (81%), pausing triggers.
[08:41:59.921] <TB2> INFO: Resuming triggers.
[08:42:06.034] <TB2> INFO: run duration 24 seconds, buffer almost full (81%), pausing triggers.
[08:42:25.762] <TB2> INFO: Resuming triggers.
[08:42:31.868] <TB2> INFO: run duration 30 seconds, buffer almost full (81%), pausing triggers.
[08:42:51.922] <TB2> INFO: Resuming triggers.
[08:42:58.036] <TB2> INFO: run duration 36 seconds, buffer almost full (81%), pausing triggers.
[08:43:18.916] <TB2> INFO: Resuming triggers.
[08:43:25.021] <TB2> INFO: run duration 42 seconds, buffer almost full (81%), pausing triggers.
[08:43:45.211] <TB2> INFO: Resuming triggers.
[08:43:51.317] <TB2> INFO: run duration 48 seconds, buffer almost full (81%), pausing triggers.
[08:44:11.011] <TB2> INFO: Resuming triggers.
[08:44:17.116] <TB2> INFO: run duration 54 seconds, buffer almost full (81%), pausing triggers.
[08:44:37.033] <TB2> INFO: Resuming triggers.
[08:44:43.140] <TB2> INFO: run duration 61 seconds, buffer almost full (81%), pausing triggers.
[08:45:05.057] <TB2> INFO: Resuming triggers.
[08:45:11.166] <TB2> INFO: run duration 67 seconds, buffer almost full (81%), pausing triggers.
[08:45:31.785] <TB2> INFO: Resuming triggers.
[08:45:37.887] <TB2> INFO: run duration 73 seconds, buffer almost full (81%), pausing triggers.
[08:45:57.440] <TB2> INFO: Resuming triggers.
[08:46:03.545] <TB2> INFO: run duration 79 seconds, buffer almost full (81%), pausing triggers.
[08:46:23.274] <TB2> INFO: Resuming triggers.
[08:46:29.378] <TB2> INFO: run duration 85 seconds, buffer almost full (81%), pausing triggers.
[08:46:51.326] <TB2> INFO: Resuming triggers.
[08:46:57.425] <TB2> INFO: run duration 91 seconds, buffer almost full (81%), pausing triggers.
[08:47:18.403] <TB2> INFO: Resuming triggers.
[08:47:24.504] <TB2> INFO: run duration 97 seconds, buffer almost full (81%), pausing triggers.
[08:47:44.288] <TB2> INFO: Resuming triggers.
[08:47:46.687] <TB2> INFO: data taking finished, elapsed time: 100 seconds.
[08:47:55.445] <TB2> INFO: PixTest:: pg_setup set to default.
[08:47:55.447] <TB2> INFO: PixTestXray::doPhRun() done
[08:47:55.447] <TB2> INFO: PixTestXray::doTest() done
[08:47:55.640] <TB2> INFO: enter test to run
[08:47:55.640] <TB2> INFO: test: no parameter change
[08:47:55.857] <TB2> QUIET: Connection to board 162 closed.
[08:47:55.942] <TB2> INFO: pXar: this is the end, my friend
[08:50:28.338] <TB2> INFO: *** Welcome to pxar ***
[08:50:28.338] <TB2> INFO: *** Today: 2015/08/28
[08:50:28.338] <TB2> INFO: readRocDacs: /usr/local/data/M2086_XrayQualification_2015-08-28_10h32m_1440750764//004_HRData_150//dacParameters35_C0.dat .. /usr/local/data/M2086_XrayQualification_2015-08-28_10h32m_1440750764//004_HRData_150//dacParameters35_C15.dat
[08:50:28.340] <TB2> INFO: readTbmDacs: /usr/local/data/M2086_XrayQualification_2015-08-28_10h32m_1440750764//004_HRData_150//tbmParameters_C0a.dat .. /usr/local/data/M2086_XrayQualification_2015-08-28_10h32m_1440750764//004_HRData_150//tbmParameters_C0b.dat
[08:50:28.340] <TB2> INFO: readMaskFile: /usr/local/data/M2086_XrayQualification_2015-08-28_10h32m_1440750764//004_HRData_150//defaultMaskFile.dat
[08:50:28.340] <TB2> INFO: readTrimFile: /usr/local/data/M2086_XrayQualification_2015-08-28_10h32m_1440750764//004_HRData_150//trimParameters35_C0.dat .. /usr/local/data/M2086_XrayQualification_2015-08-28_10h32m_1440750764//004_HRData_150//trimParameters35_C15.dat
[08:50:28.409] <TB2> INFO: clk: 4
[08:50:28.409] <TB2> INFO: ctr: 4
[08:50:28.409] <TB2> INFO: sda: 19
[08:50:28.409] <TB2> INFO: tin: 9
[08:50:28.409] <TB2> INFO: level: 15
[08:50:28.409] <TB2> INFO: triggerdelay: 0
[08:50:28.409] <TB2> QUIET: Instanciating API for pxar v2.5+57~g44d0777
[08:50:28.409] <TB2> INFO: Log level: INFO
[08:50:28.423] <TB2> INFO: Found DTB DTB_WXENWR
[08:50:28.435] <TB2> QUIET: Connection to board DTB_WXENWR opened.
[08:50:28.438] <TB2> INFO: DTB startup information
--- DTB info------------------------------------------
Board id: 162
HW version: DTB1.2
FW version: 4.2
SW version: 4.4
USB id: DTB_WXENWR
MAC address: 40D8551180A2
Hostname: pixelDTB162
Comment:
------------------------------------------------------
[08:50:28.441] <TB2> INFO: RPC call hashes of host and DTB match: 397073690
[08:50:29.983] <TB2> INFO: DUT info:
[08:50:29.984] <TB2> INFO: The DUT currently contains the following objects:
[08:50:29.984] <TB2> INFO: 2 TBM Cores tbm09c (2 ON)
[08:50:29.984] <TB2> INFO: TBM Core alpha (0): 7 registers set
[08:50:29.984] <TB2> INFO: TBM Core beta (1): 7 registers set
[08:50:29.984] <TB2> INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[08:50:29.984] <TB2> INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[08:50:29.984] <TB2> INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[08:50:29.984] <TB2> INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[08:50:29.984] <TB2> INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[08:50:29.984] <TB2> INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[08:50:29.984] <TB2> INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[08:50:29.984] <TB2> INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[08:50:29.984] <TB2> INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[08:50:29.984] <TB2> INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[08:50:29.984] <TB2> INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[08:50:29.984] <TB2> INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[08:50:29.984] <TB2> INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[08:50:29.984] <TB2> INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[08:50:29.984] <TB2> INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[08:50:29.984] <TB2> INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[08:50:29.984] <TB2> INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[08:50:30.385] <TB2> INFO: enter 'restricted' command line mode
[08:50:30.386] <TB2> INFO: enter test to run
[08:50:30.386] <TB2> INFO: test: delay setting parameters: ->5<-
[08:50:30.386] <TB2> INFO: delay test by 5 seconds...
[08:50:35.386] <TB2> INFO: enter test to run
[08:50:35.386] <TB2> INFO: test: Xray no parameter change
[08:50:35.386] <TB2> INFO: running: xray
[08:50:35.386] <TB2> INFO: readGainPedestalParameters /usr/local/data/M2086_XrayQualification_2015-08-28_10h32m_1440750764//004_HRData_150//phCalibrationFitErr35_C0.dat .. /usr/local/data/M2086_XrayQualification_2015-08-28_10h32m_1440750764//004_HRData_150//phCalibrationFitErr35_C15.dat
[08:50:35.537] <TB2> INFO: ######################################################################
[08:50:35.537] <TB2> INFO: PixTestXray::doTest()
[08:50:35.537] <TB2> INFO: ######################################################################
[08:50:35.537] <TB2> INFO: ----------------------------------------------------------------------
[08:50:35.537] <TB2> INFO: PixTestXray::doPhRun() fParRunSeconds = 100
[08:50:35.537] <TB2> INFO: ----------------------------------------------------------------------
[08:50:36.512] <TB2> INFO: PixTestXray::doPhRun start TriggerLoop with trigger frequency 100 kHz, period 405 and duration 100 seconds, fEventsMax = 10000000
[08:50:39.738] <TB2> INFO: run duration 3 seconds, buffer almost full (81%), pausing triggers.
[08:51:01.084] <TB2> INFO: Resuming triggers.
[08:51:04.321] <TB2> INFO: run duration 6 seconds, buffer almost full (81%), pausing triggers.
[08:51:27.085] <TB2> INFO: Resuming triggers.
[08:51:30.324] <TB2> INFO: run duration 9 seconds, buffer almost full (81%), pausing triggers.
[08:51:53.333] <TB2> INFO: Resuming triggers.
[08:51:56.569] <TB2> INFO: run duration 12 seconds, buffer almost full (81%), pausing triggers.
[08:52:18.436] <TB2> INFO: Resuming triggers.
[08:52:21.670] <TB2> INFO: run duration 16 seconds, buffer almost full (81%), pausing triggers.
[08:52:42.454] <TB2> INFO: Resuming triggers.
[08:52:45.692] <TB2> INFO: run duration 19 seconds, buffer almost full (81%), pausing triggers.
[08:53:07.664] <TB2> INFO: Resuming triggers.
[08:53:10.900] <TB2> INFO: run duration 22 seconds, buffer almost full (81%), pausing triggers.
[08:53:32.449] <TB2> INFO: Resuming triggers.
[08:53:35.683] <TB2> INFO: run duration 25 seconds, buffer almost full (81%), pausing triggers.
[08:53:58.056] <TB2> INFO: Resuming triggers.
[08:54:01.295] <TB2> INFO: run duration 29 seconds, buffer almost full (81%), pausing triggers.
[08:54:22.258] <TB2> INFO: Resuming triggers.
[08:54:25.492] <TB2> INFO: run duration 32 seconds, buffer almost full (81%), pausing triggers.
[08:54:48.735] <TB2> INFO: Resuming triggers.
[08:54:51.972] <TB2> INFO: run duration 35 seconds, buffer almost full (81%), pausing triggers.
[08:55:13.424] <TB2> INFO: Resuming triggers.
[08:55:16.659] <TB2> INFO: run duration 38 seconds, buffer almost full (81%), pausing triggers.
[08:55:37.105] <TB2> INFO: Resuming triggers.
[08:55:40.340] <TB2> INFO: run duration 42 seconds, buffer almost full (81%), pausing triggers.
[08:56:03.334] <TB2> INFO: Resuming triggers.
[08:56:06.565] <TB2> INFO: run duration 45 seconds, buffer almost full (81%), pausing triggers.
[08:56:30.053] <TB2> INFO: Resuming triggers.
[08:56:33.284] <TB2> INFO: run duration 48 seconds, buffer almost full (81%), pausing triggers.
[08:56:55.387] <TB2> INFO: Resuming triggers.
[08:56:58.617] <TB2> INFO: run duration 51 seconds, buffer almost full (81%), pausing triggers.
[08:57:19.785] <TB2> INFO: Resuming triggers.
[08:57:23.015] <TB2> INFO: run duration 54 seconds, buffer almost full (81%), pausing triggers.
[08:57:46.204] <TB2> INFO: Resuming triggers.
[08:57:49.433] <TB2> INFO: run duration 58 seconds, buffer almost full (81%), pausing triggers.
[08:58:13.287] <TB2> INFO: Resuming triggers.
[08:58:16.515] <TB2> INFO: run duration 61 seconds, buffer almost full (81%), pausing triggers.
[08:58:39.201] <TB2> INFO: Resuming triggers.
[08:58:42.429] <TB2> INFO: run duration 64 seconds, buffer almost full (81%), pausing triggers.
[08:59:03.627] <TB2> INFO: Resuming triggers.
[08:59:06.857] <TB2> INFO: run duration 67 seconds, buffer almost full (81%), pausing triggers.
[08:59:29.534] <TB2> INFO: Resuming triggers.
[08:59:32.762] <TB2> INFO: run duration 71 seconds, buffer almost full (81%), pausing triggers.
[08:59:55.742] <TB2> INFO: Resuming triggers.
[08:59:58.971] <TB2> INFO: run duration 74 seconds, buffer almost full (81%), pausing triggers.
[09:00:21.406] <TB2> INFO: Resuming triggers.
[09:00:24.635] <TB2> INFO: run duration 77 seconds, buffer almost full (81%), pausing triggers.
[09:00:45.723] <TB2> INFO: Resuming triggers.
[09:00:48.953] <TB2> INFO: run duration 80 seconds, buffer almost full (81%), pausing triggers.
[09:01:11.187] <TB2> INFO: Resuming triggers.
[09:01:14.419] <TB2> INFO: run duration 83 seconds, buffer almost full (81%), pausing triggers.
[09:01:35.440] <TB2> INFO: Resuming triggers.
[09:01:38.669] <TB2> INFO: run duration 87 seconds, buffer almost full (81%), pausing triggers.
[09:02:01.308] <TB2> INFO: Resuming triggers.
[09:02:04.540] <TB2> INFO: run duration 90 seconds, buffer almost full (81%), pausing triggers.
[09:02:25.946] <TB2> INFO: Resuming triggers.
[09:02:29.191] <TB2> INFO: run duration 93 seconds, buffer almost full (81%), pausing triggers.
[09:02:51.894] <TB2> INFO: Resuming triggers.
[09:02:55.126] <TB2> INFO: run duration 96 seconds, buffer almost full (81%), pausing triggers.
[09:03:16.906] <TB2> INFO: Resuming triggers.
[09:03:20.003] <TB2> INFO: data taking finished, elapsed time: 100 seconds.
[09:03:40.256] <TB2> INFO: PixTest:: pg_setup set to default.
[09:03:40.258] <TB2> INFO: PixTestXray::doPhRun() done
[09:03:40.258] <TB2> INFO: PixTestXray::doTest() done
[09:03:40.460] <TB2> INFO: enter test to run
[09:03:40.460] <TB2> INFO: test: no parameter change
[09:03:40.686] <TB2> QUIET: Connection to board 162 closed.
[09:03:40.765] <TB2> INFO: pXar: this is the end, my friend
[09:08:16.137] <TB2> INFO: *** Welcome to pxar ***
[09:08:16.137] <TB2> INFO: *** Today: 2015/08/28
[09:08:16.137] <TB2> INFO: readRocDacs: /usr/local/data/M2086_XrayQualification_2015-08-28_10h32m_1440750764//005_HRSCurves_100//dacParameters35_C0.dat .. /usr/local/data/M2086_XrayQualification_2015-08-28_10h32m_1440750764//005_HRSCurves_100//dacParameters35_C15.dat
[09:08:16.139] <TB2> INFO: readTbmDacs: /usr/local/data/M2086_XrayQualification_2015-08-28_10h32m_1440750764//005_HRSCurves_100//tbmParameters_C0a.dat .. /usr/local/data/M2086_XrayQualification_2015-08-28_10h32m_1440750764//005_HRSCurves_100//tbmParameters_C0b.dat
[09:08:16.139] <TB2> INFO: readMaskFile: /usr/local/data/M2086_XrayQualification_2015-08-28_10h32m_1440750764//005_HRSCurves_100//defaultMaskFile.dat
[09:08:16.139] <TB2> INFO: readTrimFile: /usr/local/data/M2086_XrayQualification_2015-08-28_10h32m_1440750764//005_HRSCurves_100//trimParameters35_C0.dat .. /usr/local/data/M2086_XrayQualification_2015-08-28_10h32m_1440750764//005_HRSCurves_100//trimParameters35_C15.dat
[09:08:16.216] <TB2> INFO: clk: 4
[09:08:16.216] <TB2> INFO: ctr: 4
[09:08:16.216] <TB2> INFO: sda: 19
[09:08:16.216] <TB2> INFO: tin: 9
[09:08:16.216] <TB2> INFO: level: 15
[09:08:16.216] <TB2> INFO: triggerdelay: 0
[09:08:16.216] <TB2> QUIET: Instanciating API for pxar v2.5+57~g44d0777
[09:08:16.216] <TB2> INFO: Log level: INFO
[09:08:16.229] <TB2> INFO: Found DTB DTB_WXENWR
[09:08:16.241] <TB2> QUIET: Connection to board DTB_WXENWR opened.
[09:08:16.245] <TB2> INFO: DTB startup information
--- DTB info------------------------------------------
Board id: 162
HW version: DTB1.2
FW version: 4.2
SW version: 4.4
USB id: DTB_WXENWR
MAC address: 40D8551180A2
Hostname: pixelDTB162
Comment:
------------------------------------------------------
[09:08:16.248] <TB2> INFO: RPC call hashes of host and DTB match: 397073690
[09:08:17.778] <TB2> INFO: DUT info:
[09:08:17.778] <TB2> INFO: The DUT currently contains the following objects:
[09:08:17.778] <TB2> INFO: 2 TBM Cores tbm09c (2 ON)
[09:08:17.778] <TB2> INFO: TBM Core alpha (0): 7 registers set
[09:08:17.778] <TB2> INFO: TBM Core beta (1): 7 registers set
[09:08:17.778] <TB2> INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[09:08:17.778] <TB2> INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[09:08:17.778] <TB2> INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[09:08:17.778] <TB2> INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[09:08:17.778] <TB2> INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[09:08:17.778] <TB2> INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[09:08:17.778] <TB2> INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[09:08:17.779] <TB2> INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[09:08:17.779] <TB2> INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[09:08:17.779] <TB2> INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[09:08:17.779] <TB2> INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[09:08:17.779] <TB2> INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[09:08:17.779] <TB2> INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[09:08:17.779] <TB2> INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[09:08:17.779] <TB2> INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[09:08:17.779] <TB2> INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[09:08:17.779] <TB2> INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[09:08:18.180] <TB2> INFO: enter 'restricted' command line mode
[09:08:18.180] <TB2> INFO: enter test to run
[09:08:18.180] <TB2> INFO: test: Highrate no parameter change
[09:08:18.180] <TB2> INFO: running: highrate
[09:08:18.181] <TB2> INFO: readGainPedestalParameters /usr/local/data/M2086_XrayQualification_2015-08-28_10h32m_1440750764//005_HRSCurves_100//phCalibrationFitErr35_C0.dat .. /usr/local/data/M2086_XrayQualification_2015-08-28_10h32m_1440750764//005_HRSCurves_100//phCalibrationFitErr35_C15.dat
[09:08:18.327] <TB2> INFO: ----------------------------------------------------------------------
[09:08:18.327] <TB2> INFO: PixTestHighRate::xNoiseMaps() ntrig = 50, vcal = 200
[09:08:18.327] <TB2> INFO: ----------------------------------------------------------------------
[09:08:18.334] <TB2> INFO: ---> dac: vcal name: xNoiseMap ntrig: 50 dacrange: 0 .. 100 (1/-1) hits flags = 384 (plus default)
[09:08:18.350] <TB2> INFO: dacScan step from 0 .. 0
[09:08:18.350] <TB2> INFO: dacScan split into 1 runs with ntrig = 50
[09:08:18.350] <TB2> INFO: run 1 of 1
[09:08:18.953] <TB2> INFO: Expecting 208000 events.
[09:08:33.012] <TB2> INFO: 208000 events read in total (13528ms).
[09:08:33.025] <TB2> INFO: Test took 14675ms.
[09:08:33.127] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[09:08:33.472] <TB2> INFO: dacScan step from 1 .. 1
[09:08:33.472] <TB2> INFO: dacScan split into 1 runs with ntrig = 50
[09:08:33.472] <TB2> INFO: run 1 of 1
[09:08:34.097] <TB2> INFO: Expecting 208000 events.
[09:08:48.272] <TB2> INFO: 208000 events read in total (13647ms).
[09:08:48.282] <TB2> INFO: Test took 14810ms.
[09:08:48.380] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[09:08:48.666] <TB2> INFO: dacScan step from 2 .. 2
[09:08:48.666] <TB2> INFO: dacScan split into 1 runs with ntrig = 50
[09:08:48.666] <TB2> INFO: run 1 of 1
[09:08:49.269] <TB2> INFO: Expecting 208000 events.
[09:09:03.483] <TB2> INFO: 208000 events read in total (13686ms).
[09:09:03.493] <TB2> INFO: Test took 14827ms.
[09:09:03.594] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[09:09:03.899] <TB2> INFO: dacScan step from 3 .. 3
[09:09:03.899] <TB2> INFO: dacScan split into 1 runs with ntrig = 50
[09:09:03.899] <TB2> INFO: run 1 of 1
[09:09:04.507] <TB2> INFO: Expecting 208000 events.
[09:09:18.831] <TB2> INFO: 208000 events read in total (13797ms).
[09:09:18.846] <TB2> INFO: Test took 14946ms.
[09:09:18.951] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[09:09:19.237] <TB2> INFO: dacScan step from 4 .. 4
[09:09:19.237] <TB2> INFO: dacScan split into 1 runs with ntrig = 50
[09:09:19.237] <TB2> INFO: run 1 of 1
[09:09:19.847] <TB2> INFO: Expecting 208000 events.
[09:09:34.007] <TB2> INFO: 208000 events read in total (13632ms).
[09:09:34.017] <TB2> INFO: Test took 14780ms.
[09:09:34.115] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[09:09:34.402] <TB2> INFO: dacScan step from 5 .. 5
[09:09:34.402] <TB2> INFO: dacScan split into 1 runs with ntrig = 50
[09:09:34.402] <TB2> INFO: run 1 of 1
[09:09:35.009] <TB2> INFO: Expecting 208000 events.
[09:09:49.272] <TB2> INFO: 208000 events read in total (13735ms).
[09:09:49.284] <TB2> INFO: Test took 14882ms.
[09:09:49.449] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[09:09:49.764] <TB2> INFO: dacScan step from 6 .. 6
[09:09:49.764] <TB2> INFO: dacScan split into 1 runs with ntrig = 50
[09:09:49.764] <TB2> INFO: run 1 of 1
[09:09:50.376] <TB2> INFO: Expecting 208000 events.
[09:10:04.601] <TB2> INFO: 208000 events read in total (13696ms).
[09:10:04.611] <TB2> INFO: Test took 14847ms.
[09:10:04.710] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[09:10:05.027] <TB2> INFO: dacScan step from 7 .. 7
[09:10:05.027] <TB2> INFO: dacScan split into 1 runs with ntrig = 50
[09:10:05.027] <TB2> INFO: run 1 of 1
[09:10:05.636] <TB2> INFO: Expecting 208000 events.
[09:10:20.287] <TB2> INFO: 208000 events read in total (14123ms).
[09:10:20.298] <TB2> INFO: Test took 15271ms.
[09:10:20.428] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[09:10:20.866] <TB2> INFO: dacScan step from 8 .. 8
[09:10:20.866] <TB2> INFO: dacScan split into 1 runs with ntrig = 50
[09:10:20.866] <TB2> INFO: run 1 of 1
[09:10:21.546] <TB2> INFO: Expecting 208000 events.
[09:10:36.246] <TB2> INFO: 208000 events read in total (14172ms).
[09:10:36.261] <TB2> INFO: Test took 15395ms.
[09:10:36.367] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[09:10:36.708] <TB2> INFO: dacScan step from 9 .. 9
[09:10:36.708] <TB2> INFO: dacScan split into 1 runs with ntrig = 50
[09:10:36.708] <TB2> INFO: run 1 of 1
[09:10:37.331] <TB2> INFO: Expecting 208000 events.
[09:10:51.779] <TB2> INFO: 208000 events read in total (13920ms).
[09:10:51.789] <TB2> INFO: Test took 15080ms.
[09:10:51.915] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[09:10:52.206] <TB2> INFO: dacScan step from 10 .. 10
[09:10:52.206] <TB2> INFO: dacScan split into 1 runs with ntrig = 50
[09:10:52.207] <TB2> INFO: run 1 of 1
[09:10:52.812] <TB2> INFO: Expecting 208000 events.
[09:11:07.041] <TB2> INFO: 208000 events read in total (13701ms).
[09:11:07.051] <TB2> INFO: Test took 14844ms.
[09:11:07.157] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[09:11:07.454] <TB2> INFO: dacScan step from 11 .. 11
[09:11:07.454] <TB2> INFO: dacScan split into 1 runs with ntrig = 50
[09:11:07.454] <TB2> INFO: run 1 of 1
[09:11:08.062] <TB2> INFO: Expecting 208000 events.
[09:11:21.972] <TB2> INFO: 208000 events read in total (13382ms).
[09:11:21.983] <TB2> INFO: Test took 14529ms.
[09:11:22.086] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[09:11:22.391] <TB2> INFO: dacScan step from 12 .. 12
[09:11:22.391] <TB2> INFO: dacScan split into 1 runs with ntrig = 50
[09:11:22.391] <TB2> INFO: run 1 of 1
[09:11:23.012] <TB2> INFO: Expecting 208000 events.
[09:11:37.615] <TB2> INFO: 208000 events read in total (14075ms).
[09:11:37.625] <TB2> INFO: Test took 15234ms.
[09:11:37.734] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[09:11:38.023] <TB2> INFO: dacScan step from 13 .. 13
[09:11:38.023] <TB2> INFO: dacScan split into 1 runs with ntrig = 50
[09:11:38.023] <TB2> INFO: run 1 of 1
[09:11:38.627] <TB2> INFO: Expecting 208000 events.
[09:11:53.062] <TB2> INFO: 208000 events read in total (13906ms).
[09:11:53.072] <TB2> INFO: Test took 15049ms.
[09:11:53.175] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[09:11:53.519] <TB2> INFO: dacScan step from 14 .. 14
[09:11:53.519] <TB2> INFO: dacScan split into 1 runs with ntrig = 50
[09:11:53.519] <TB2> INFO: run 1 of 1
[09:11:54.136] <TB2> INFO: Expecting 208000 events.
[09:12:08.460] <TB2> INFO: 208000 events read in total (13796ms).
[09:12:08.472] <TB2> INFO: Test took 14953ms.
[09:12:08.574] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[09:12:08.858] <TB2> INFO: dacScan step from 15 .. 15
[09:12:08.858] <TB2> INFO: dacScan split into 1 runs with ntrig = 50
[09:12:08.858] <TB2> INFO: run 1 of 1
[09:12:09.462] <TB2> INFO: Expecting 208000 events.
[09:12:23.599] <TB2> INFO: 208000 events read in total (13609ms).
[09:12:23.610] <TB2> INFO: Test took 14751ms.
[09:12:23.714] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[09:12:24.012] <TB2> INFO: dacScan step from 16 .. 16
[09:12:24.012] <TB2> INFO: dacScan split into 1 runs with ntrig = 50
[09:12:24.012] <TB2> INFO: run 1 of 1
[09:12:24.615] <TB2> INFO: Expecting 208000 events.
[09:12:38.356] <TB2> INFO: 208000 events read in total (13213ms).
[09:12:38.367] <TB2> INFO: Test took 14355ms.
[09:12:38.479] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[09:12:38.828] <TB2> INFO: dacScan step from 17 .. 17
[09:12:38.828] <TB2> INFO: dacScan split into 1 runs with ntrig = 50
[09:12:38.828] <TB2> INFO: run 1 of 1
[09:12:39.448] <TB2> INFO: Expecting 208000 events.
[09:12:53.668] <TB2> INFO: 208000 events read in total (13692ms).
[09:12:53.678] <TB2> INFO: Test took 14849ms.
[09:12:53.781] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[09:12:54.071] <TB2> INFO: dacScan step from 18 .. 18
[09:12:54.071] <TB2> INFO: dacScan split into 1 runs with ntrig = 50
[09:12:54.071] <TB2> INFO: run 1 of 1
[09:12:54.670] <TB2> INFO: Expecting 208000 events.
[09:13:08.349] <TB2> INFO: 208000 events read in total (13151ms).
[09:13:08.360] <TB2> INFO: Test took 14289ms.
[09:13:08.459] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[09:13:08.776] <TB2> INFO: dacScan step from 19 .. 19
[09:13:08.776] <TB2> INFO: dacScan split into 1 runs with ntrig = 50
[09:13:08.776] <TB2> INFO: run 1 of 1
[09:13:09.397] <TB2> INFO: Expecting 208000 events.
[09:13:23.341] <TB2> INFO: 208000 events read in total (13415ms).
[09:13:23.351] <TB2> INFO: Test took 14574ms.
[09:13:23.454] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[09:13:23.739] <TB2> INFO: dacScan step from 20 .. 20
[09:13:23.739] <TB2> INFO: dacScan split into 1 runs with ntrig = 50
[09:13:23.739] <TB2> INFO: run 1 of 1
[09:13:24.340] <TB2> INFO: Expecting 208000 events.
[09:13:37.884] <TB2> INFO: 208000 events read in total (13016ms).
[09:13:37.895] <TB2> INFO: Test took 14156ms.
[09:13:38.000] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[09:13:38.298] <TB2> INFO: dacScan step from 21 .. 21
[09:13:38.298] <TB2> INFO: dacScan split into 1 runs with ntrig = 50
[09:13:38.298] <TB2> INFO: run 1 of 1
[09:13:38.903] <TB2> INFO: Expecting 208000 events.
[09:13:52.890] <TB2> INFO: 208000 events read in total (13459ms).
[09:13:52.901] <TB2> INFO: Test took 14602ms.
[09:13:53.006] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[09:13:53.303] <TB2> INFO: dacScan step from 22 .. 22
[09:13:53.303] <TB2> INFO: dacScan split into 1 runs with ntrig = 50
[09:13:53.303] <TB2> INFO: run 1 of 1
[09:13:53.936] <TB2> INFO: Expecting 208000 events.
[09:14:08.642] <TB2> INFO: 208000 events read in total (14178ms).
[09:14:08.656] <TB2> INFO: Test took 15353ms.
[09:14:08.757] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[09:14:09.053] <TB2> INFO: dacScan step from 23 .. 23
[09:14:09.053] <TB2> INFO: dacScan split into 1 runs with ntrig = 50
[09:14:09.053] <TB2> INFO: run 1 of 1
[09:14:09.656] <TB2> INFO: Expecting 208000 events.
[09:14:24.217] <TB2> INFO: 208000 events read in total (14033ms).
[09:14:24.228] <TB2> INFO: Test took 15175ms.
[09:14:24.331] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[09:14:24.614] <TB2> INFO: dacScan step from 24 .. 24
[09:14:24.614] <TB2> INFO: dacScan split into 1 runs with ntrig = 50
[09:14:24.614] <TB2> INFO: run 1 of 1
[09:14:25.221] <TB2> INFO: Expecting 208000 events.
[09:14:39.718] <TB2> INFO: 208000 events read in total (13970ms).
[09:14:39.728] <TB2> INFO: Test took 15113ms.
[09:14:39.826] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[09:14:40.118] <TB2> INFO: dacScan step from 25 .. 25
[09:14:40.118] <TB2> INFO: dacScan split into 1 runs with ntrig = 50
[09:14:40.118] <TB2> INFO: run 1 of 1
[09:14:40.725] <TB2> INFO: Expecting 208000 events.
[09:14:55.060] <TB2> INFO: 208000 events read in total (13807ms).
[09:14:55.070] <TB2> INFO: Test took 14952ms.
[09:14:55.182] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[09:14:55.505] <TB2> INFO: dacScan step from 26 .. 26
[09:14:55.505] <TB2> INFO: dacScan split into 1 runs with ntrig = 50
[09:14:55.505] <TB2> INFO: run 1 of 1
[09:14:56.112] <TB2> INFO: Expecting 208000 events.
[09:15:10.196] <TB2> INFO: 208000 events read in total (13556ms).
[09:15:10.207] <TB2> INFO: Test took 14701ms.
[09:15:10.308] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[09:15:10.596] <TB2> INFO: dacScan step from 27 .. 27
[09:15:10.596] <TB2> INFO: dacScan split into 1 runs with ntrig = 50
[09:15:10.596] <TB2> INFO: run 1 of 1
[09:15:11.199] <TB2> INFO: Expecting 208000 events.
[09:15:25.535] <TB2> INFO: 208000 events read in total (13808ms).
[09:15:25.545] <TB2> INFO: Test took 14948ms.
[09:15:25.643] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[09:15:25.921] <TB2> INFO: dacScan step from 28 .. 28
[09:15:25.922] <TB2> INFO: dacScan split into 1 runs with ntrig = 50
[09:15:25.922] <TB2> INFO: run 1 of 1
[09:15:26.539] <TB2> INFO: Expecting 208000 events.
[09:15:40.978] <TB2> INFO: 208000 events read in total (13911ms).
[09:15:40.990] <TB2> INFO: Test took 15068ms.
[09:15:41.116] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[09:15:41.446] <TB2> INFO: dacScan step from 29 .. 29
[09:15:41.446] <TB2> INFO: dacScan split into 1 runs with ntrig = 50
[09:15:41.446] <TB2> INFO: run 1 of 1
[09:15:42.065] <TB2> INFO: Expecting 208000 events.
[09:15:56.548] <TB2> INFO: 208000 events read in total (13955ms).
[09:15:56.558] <TB2> INFO: Test took 15111ms.
[09:15:56.657] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[09:15:56.954] <TB2> INFO: dacScan step from 30 .. 30
[09:15:56.954] <TB2> INFO: dacScan split into 1 runs with ntrig = 50
[09:15:56.954] <TB2> INFO: run 1 of 1
[09:15:57.560] <TB2> INFO: Expecting 208000 events.
[09:16:11.668] <TB2> INFO: 208000 events read in total (13580ms).
[09:16:11.679] <TB2> INFO: Test took 14725ms.
[09:16:11.776] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[09:16:12.092] <TB2> INFO: dacScan step from 31 .. 31
[09:16:12.092] <TB2> INFO: dacScan split into 1 runs with ntrig = 50
[09:16:12.092] <TB2> INFO: run 1 of 1
[09:16:12.708] <TB2> INFO: Expecting 208000 events.
[09:16:26.615] <TB2> INFO: 208000 events read in total (13379ms).
[09:16:26.626] <TB2> INFO: Test took 14533ms.
[09:16:26.729] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[09:16:27.069] <TB2> INFO: dacScan step from 32 .. 32
[09:16:27.069] <TB2> INFO: dacScan split into 1 runs with ntrig = 50
[09:16:27.069] <TB2> INFO: run 1 of 1
[09:16:27.685] <TB2> INFO: Expecting 208000 events.
[09:16:41.684] <TB2> INFO: 208000 events read in total (13471ms).
[09:16:41.695] <TB2> INFO: Test took 14625ms.
[09:16:41.794] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[09:16:42.074] <TB2> INFO: dacScan step from 33 .. 33
[09:16:42.074] <TB2> INFO: dacScan split into 1 runs with ntrig = 50
[09:16:42.074] <TB2> INFO: run 1 of 1
[09:16:42.701] <TB2> INFO: Expecting 208000 events.
[09:16:56.787] <TB2> INFO: 208000 events read in total (13558ms).
[09:16:56.797] <TB2> INFO: Test took 14723ms.
[09:16:56.898] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[09:16:57.212] <TB2> INFO: dacScan step from 34 .. 34
[09:16:57.212] <TB2> INFO: dacScan split into 1 runs with ntrig = 50
[09:16:57.212] <TB2> INFO: run 1 of 1
[09:16:57.831] <TB2> INFO: Expecting 208000 events.
[09:17:11.835] <TB2> INFO: 208000 events read in total (13476ms).
[09:17:11.845] <TB2> INFO: Test took 14633ms.
[09:17:11.943] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[09:17:12.267] <TB2> INFO: dacScan step from 35 .. 35
[09:17:12.267] <TB2> INFO: dacScan split into 1 runs with ntrig = 50
[09:17:12.267] <TB2> INFO: run 1 of 1
[09:17:12.882] <TB2> INFO: Expecting 208000 events.
[09:17:26.825] <TB2> INFO: 208000 events read in total (13415ms).
[09:17:26.836] <TB2> INFO: Test took 14568ms.
[09:17:26.941] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[09:17:27.286] <TB2> INFO: dacScan step from 36 .. 36
[09:17:27.286] <TB2> INFO: dacScan split into 1 runs with ntrig = 50
[09:17:27.286] <TB2> INFO: run 1 of 1
[09:17:27.910] <TB2> INFO: Expecting 208000 events.
[09:17:42.373] <TB2> INFO: 208000 events read in total (13935ms).
[09:17:42.384] <TB2> INFO: Test took 15097ms.
[09:17:42.494] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[09:17:42.799] <TB2> INFO: dacScan step from 37 .. 37
[09:17:42.799] <TB2> INFO: dacScan split into 1 runs with ntrig = 50
[09:17:42.799] <TB2> INFO: run 1 of 1
[09:17:43.409] <TB2> INFO: Expecting 208000 events.
[09:17:58.091] <TB2> INFO: 208000 events read in total (14154ms).
[09:17:58.104] <TB2> INFO: Test took 15304ms.
[09:17:58.205] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[09:17:58.583] <TB2> INFO: dacScan step from 38 .. 38
[09:17:58.583] <TB2> INFO: dacScan split into 1 runs with ntrig = 50
[09:17:58.583] <TB2> INFO: run 1 of 1
[09:17:59.184] <TB2> INFO: Expecting 208000 events.
[09:18:14.125] <TB2> INFO: 208000 events read in total (14413ms).
[09:18:14.135] <TB2> INFO: Test took 15551ms.
[09:18:14.231] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[09:18:14.532] <TB2> INFO: dacScan step from 39 .. 39
[09:18:14.532] <TB2> INFO: dacScan split into 1 runs with ntrig = 50
[09:18:14.532] <TB2> INFO: run 1 of 1
[09:18:15.140] <TB2> INFO: Expecting 208000 events.
[09:18:30.038] <TB2> INFO: 208000 events read in total (14370ms).
[09:18:30.048] <TB2> INFO: Test took 15515ms.
[09:18:30.146] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[09:18:30.438] <TB2> INFO: dacScan step from 40 .. 40
[09:18:30.438] <TB2> INFO: dacScan split into 1 runs with ntrig = 50
[09:18:30.438] <TB2> INFO: run 1 of 1
[09:18:31.043] <TB2> INFO: Expecting 208000 events.
[09:18:46.093] <TB2> INFO: 208000 events read in total (14522ms).
[09:18:46.103] <TB2> INFO: Test took 15664ms.
[09:18:46.204] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[09:18:46.494] <TB2> INFO: dacScan step from 41 .. 41
[09:18:46.494] <TB2> INFO: dacScan split into 1 runs with ntrig = 50
[09:18:46.494] <TB2> INFO: run 1 of 1
[09:18:47.099] <TB2> INFO: Expecting 208000 events.
[09:19:02.026] <TB2> INFO: 208000 events read in total (14399ms).
[09:19:02.035] <TB2> INFO: Test took 15541ms.
[09:19:02.131] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[09:19:02.430] <TB2> INFO: dacScan step from 42 .. 42
[09:19:02.430] <TB2> INFO: dacScan split into 1 runs with ntrig = 50
[09:19:02.430] <TB2> INFO: run 1 of 1
[09:19:03.034] <TB2> INFO: Expecting 208000 events.
[09:19:18.125] <TB2> INFO: 208000 events read in total (14563ms).
[09:19:18.136] <TB2> INFO: Test took 15706ms.
[09:19:18.241] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[09:19:18.547] <TB2> INFO: dacScan step from 43 .. 43
[09:19:18.547] <TB2> INFO: dacScan split into 1 runs with ntrig = 50
[09:19:18.547] <TB2> INFO: run 1 of 1
[09:19:19.149] <TB2> INFO: Expecting 208000 events.
[09:19:34.513] <TB2> INFO: 208000 events read in total (14836ms).
[09:19:34.523] <TB2> INFO: Test took 15975ms.
[09:19:34.626] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[09:19:34.928] <TB2> INFO: dacScan step from 44 .. 44
[09:19:34.928] <TB2> INFO: dacScan split into 1 runs with ntrig = 50
[09:19:34.928] <TB2> INFO: run 1 of 1
[09:19:35.536] <TB2> INFO: Expecting 208000 events.
[09:19:51.458] <TB2> INFO: 208000 events read in total (15393ms).
[09:19:51.471] <TB2> INFO: Test took 16542ms.
[09:19:51.567] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[09:19:51.852] <TB2> INFO: dacScan step from 45 .. 45
[09:19:51.852] <TB2> INFO: dacScan split into 1 runs with ntrig = 50
[09:19:51.852] <TB2> INFO: run 1 of 1
[09:19:52.453] <TB2> INFO: Expecting 208000 events.
[09:20:09.049] <TB2> INFO: 208000 events read in total (16068ms).
[09:20:09.060] <TB2> INFO: Test took 17207ms.
[09:20:09.164] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[09:20:09.467] <TB2> INFO: dacScan step from 46 .. 46
[09:20:09.467] <TB2> INFO: dacScan split into 1 runs with ntrig = 50
[09:20:09.467] <TB2> INFO: run 1 of 1
[09:20:10.101] <TB2> INFO: Expecting 208000 events.
[09:20:26.572] <TB2> INFO: 208000 events read in total (15943ms).
[09:20:26.581] <TB2> INFO: Test took 17113ms.
[09:20:26.680] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[09:20:26.974] <TB2> INFO: dacScan step from 47 .. 47
[09:20:26.974] <TB2> INFO: dacScan split into 1 runs with ntrig = 50
[09:20:26.974] <TB2> INFO: run 1 of 1
[09:20:27.581] <TB2> INFO: Expecting 208000 events.
[09:20:44.261] <TB2> INFO: 208000 events read in total (16152ms).
[09:20:44.271] <TB2> INFO: Test took 17296ms.
[09:20:44.369] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[09:20:44.661] <TB2> INFO: dacScan step from 48 .. 48
[09:20:44.661] <TB2> INFO: dacScan split into 1 runs with ntrig = 50
[09:20:44.661] <TB2> INFO: run 1 of 1
[09:20:45.265] <TB2> INFO: Expecting 208000 events.
[09:21:02.173] <TB2> INFO: 208000 events read in total (16379ms).
[09:21:02.185] <TB2> INFO: Test took 17523ms.
[09:21:02.292] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[09:21:02.597] <TB2> INFO: dacScan step from 49 .. 49
[09:21:02.597] <TB2> INFO: dacScan split into 1 runs with ntrig = 50
[09:21:02.597] <TB2> INFO: run 1 of 1
[09:21:03.223] <TB2> INFO: Expecting 208000 events.
[09:21:19.682] <TB2> INFO: 208000 events read in total (15931ms).
[09:21:19.693] <TB2> INFO: Test took 17096ms.
[09:21:19.805] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[09:21:20.128] <TB2> INFO: dacScan step from 50 .. 50
[09:21:20.128] <TB2> INFO: dacScan split into 1 runs with ntrig = 50
[09:21:20.128] <TB2> INFO: run 1 of 1
[09:21:20.747] <TB2> INFO: Expecting 208000 events.
[09:21:37.210] <TB2> INFO: 208000 events read in total (15935ms).
[09:21:37.221] <TB2> INFO: Test took 17093ms.
[09:21:37.331] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[09:21:37.658] <TB2> INFO: dacScan step from 51 .. 51
[09:21:37.658] <TB2> INFO: dacScan split into 1 runs with ntrig = 50
[09:21:37.658] <TB2> INFO: run 1 of 1
[09:21:38.280] <TB2> INFO: Expecting 208000 events.
[09:21:55.157] <TB2> INFO: 208000 events read in total (16349ms).
[09:21:55.168] <TB2> INFO: Test took 17509ms.
[09:21:55.272] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[09:21:55.575] <TB2> INFO: dacScan step from 52 .. 52
[09:21:55.575] <TB2> INFO: dacScan split into 1 runs with ntrig = 50
[09:21:55.575] <TB2> INFO: run 1 of 1
[09:21:56.193] <TB2> INFO: Expecting 208000 events.
[09:22:13.222] <TB2> INFO: 208000 events read in total (16502ms).
[09:22:13.233] <TB2> INFO: Test took 17657ms.
[09:22:13.346] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[09:22:13.648] <TB2> INFO: dacScan step from 53 .. 53
[09:22:13.648] <TB2> INFO: dacScan split into 1 runs with ntrig = 50
[09:22:13.648] <TB2> INFO: run 1 of 1
[09:22:14.252] <TB2> INFO: Expecting 208000 events.
[09:22:31.388] <TB2> INFO: 208000 events read in total (16608ms).
[09:22:31.399] <TB2> INFO: Test took 17750ms.
[09:22:31.508] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[09:22:31.806] <TB2> INFO: dacScan step from 54 .. 54
[09:22:31.806] <TB2> INFO: dacScan split into 1 runs with ntrig = 50
[09:22:31.806] <TB2> INFO: run 1 of 1
[09:22:32.411] <TB2> INFO: Expecting 208000 events.
[09:22:49.248] <TB2> INFO: 208000 events read in total (16309ms).
[09:22:49.259] <TB2> INFO: Test took 17452ms.
[09:22:49.367] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[09:22:49.706] <TB2> INFO: dacScan step from 55 .. 55
[09:22:49.707] <TB2> INFO: dacScan split into 1 runs with ntrig = 50
[09:22:49.707] <TB2> INFO: run 1 of 1
[09:22:50.314] <TB2> INFO: Expecting 208000 events.
[09:23:06.795] <TB2> INFO: 208000 events read in total (15953ms).
[09:23:06.807] <TB2> INFO: Test took 17100ms.
[09:23:06.912] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[09:23:07.204] <TB2> INFO: dacScan step from 56 .. 56
[09:23:07.204] <TB2> INFO: dacScan split into 1 runs with ntrig = 50
[09:23:07.204] <TB2> INFO: run 1 of 1
[09:23:07.807] <TB2> INFO: Expecting 208000 events.
[09:23:24.308] <TB2> INFO: 208000 events read in total (15973ms).
[09:23:24.320] <TB2> INFO: Test took 17115ms.
[09:23:24.428] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[09:23:24.762] <TB2> INFO: dacScan step from 57 .. 57
[09:23:24.762] <TB2> INFO: dacScan split into 1 runs with ntrig = 50
[09:23:24.762] <TB2> INFO: run 1 of 1
[09:23:25.387] <TB2> INFO: Expecting 208000 events.
[09:23:42.294] <TB2> INFO: 208000 events read in total (16379ms).
[09:23:42.305] <TB2> INFO: Test took 17542ms.
[09:23:42.412] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[09:23:42.710] <TB2> INFO: dacScan step from 58 .. 58
[09:23:42.710] <TB2> INFO: dacScan split into 1 runs with ntrig = 50
[09:23:42.710] <TB2> INFO: run 1 of 1
[09:23:43.316] <TB2> INFO: Expecting 208000 events.
[09:24:00.150] <TB2> INFO: 208000 events read in total (16306ms).
[09:24:00.160] <TB2> INFO: Test took 17449ms.
[09:24:00.266] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[09:24:00.568] <TB2> INFO: dacScan step from 59 .. 59
[09:24:00.568] <TB2> INFO: dacScan split into 1 runs with ntrig = 50
[09:24:00.568] <TB2> INFO: run 1 of 1
[09:24:01.172] <TB2> INFO: Expecting 208000 events.
[09:24:17.820] <TB2> INFO: 208000 events read in total (16119ms).
[09:24:17.832] <TB2> INFO: Test took 17264ms.
[09:24:17.936] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[09:24:18.250] <TB2> INFO: dacScan step from 60 .. 60
[09:24:18.250] <TB2> INFO: dacScan split into 1 runs with ntrig = 50
[09:24:18.250] <TB2> INFO: run 1 of 1
[09:24:18.865] <TB2> INFO: Expecting 208000 events.
[09:24:35.460] <TB2> INFO: 208000 events read in total (16067ms).
[09:24:35.472] <TB2> INFO: Test took 17221ms.
[09:24:35.587] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[09:24:35.892] <TB2> INFO: dacScan step from 61 .. 61
[09:24:35.893] <TB2> INFO: dacScan split into 1 runs with ntrig = 50
[09:24:35.893] <TB2> INFO: run 1 of 1
[09:24:36.503] <TB2> INFO: Expecting 208000 events.
[09:24:53.224] <TB2> INFO: 208000 events read in total (16193ms).
[09:24:53.235] <TB2> INFO: Test took 17341ms.
[09:24:53.340] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[09:24:53.633] <TB2> INFO: dacScan step from 62 .. 62
[09:24:53.633] <TB2> INFO: dacScan split into 1 runs with ntrig = 50
[09:24:53.633] <TB2> INFO: run 1 of 1
[09:24:54.234] <TB2> INFO: Expecting 208000 events.
[09:25:11.169] <TB2> INFO: 208000 events read in total (16406ms).
[09:25:11.180] <TB2> INFO: Test took 17547ms.
[09:25:11.284] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[09:25:11.583] <TB2> INFO: dacScan step from 63 .. 63
[09:25:11.583] <TB2> INFO: dacScan split into 1 runs with ntrig = 50
[09:25:11.583] <TB2> INFO: run 1 of 1
[09:25:12.207] <TB2> INFO: Expecting 208000 events.
[09:25:29.525] <TB2> INFO: 208000 events read in total (16790ms).
[09:25:29.536] <TB2> INFO: Test took 17953ms.
[09:25:29.640] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[09:25:29.935] <TB2> INFO: dacScan step from 64 .. 64
[09:25:29.935] <TB2> INFO: dacScan split into 1 runs with ntrig = 50
[09:25:29.935] <TB2> INFO: run 1 of 1
[09:25:30.541] <TB2> INFO: Expecting 208000 events.
[09:25:47.191] <TB2> INFO: 208000 events read in total (16122ms).
[09:25:47.205] <TB2> INFO: Test took 17270ms.
[09:25:47.311] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[09:25:47.610] <TB2> INFO: dacScan step from 65 .. 65
[09:25:47.610] <TB2> INFO: dacScan split into 1 runs with ntrig = 50
[09:25:47.610] <TB2> INFO: run 1 of 1
[09:25:48.223] <TB2> INFO: Expecting 208000 events.
[09:26:04.511] <TB2> INFO: 208000 events read in total (15760ms).
[09:26:04.524] <TB2> INFO: Test took 16913ms.
[09:26:04.627] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[09:26:04.931] <TB2> INFO: dacScan step from 66 .. 66
[09:26:04.931] <TB2> INFO: dacScan split into 1 runs with ntrig = 50
[09:26:04.931] <TB2> INFO: run 1 of 1
[09:26:05.539] <TB2> INFO: Expecting 208000 events.
[09:26:22.471] <TB2> INFO: 208000 events read in total (16403ms).
[09:26:22.485] <TB2> INFO: Test took 17553ms.
[09:26:22.597] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[09:26:22.906] <TB2> INFO: dacScan step from 67 .. 67
[09:26:22.906] <TB2> INFO: dacScan split into 1 runs with ntrig = 50
[09:26:22.906] <TB2> INFO: run 1 of 1
[09:26:23.509] <TB2> INFO: Expecting 208000 events.
[09:26:40.420] <TB2> INFO: 208000 events read in total (16382ms).
[09:26:40.434] <TB2> INFO: Test took 17527ms.
[09:26:40.538] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[09:26:40.846] <TB2> INFO: dacScan step from 68 .. 68
[09:26:40.846] <TB2> INFO: dacScan split into 1 runs with ntrig = 50
[09:26:40.846] <TB2> INFO: run 1 of 1
[09:26:41.454] <TB2> INFO: Expecting 208000 events.
[09:26:58.544] <TB2> INFO: 208000 events read in total (16561ms).
[09:26:58.559] <TB2> INFO: Test took 17712ms.
[09:26:58.672] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[09:26:58.981] <TB2> INFO: dacScan step from 69 .. 69
[09:26:58.981] <TB2> INFO: dacScan split into 1 runs with ntrig = 50
[09:26:58.981] <TB2> INFO: run 1 of 1
[09:26:59.591] <TB2> INFO: Expecting 208000 events.
[09:27:16.411] <TB2> INFO: 208000 events read in total (16291ms).
[09:27:16.426] <TB2> INFO: Test took 17444ms.
[09:27:16.542] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[09:27:16.838] <TB2> INFO: dacScan step from 70 .. 70
[09:27:16.838] <TB2> INFO: dacScan split into 1 runs with ntrig = 50
[09:27:16.838] <TB2> INFO: run 1 of 1
[09:27:17.443] <TB2> INFO: Expecting 208000 events.
[09:27:34.034] <TB2> INFO: 208000 events read in total (16063ms).
[09:27:34.048] <TB2> INFO: Test took 17209ms.
[09:27:34.161] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[09:27:34.531] <TB2> INFO: dacScan step from 71 .. 71
[09:27:34.531] <TB2> INFO: dacScan split into 1 runs with ntrig = 50
[09:27:34.531] <TB2> INFO: run 1 of 1
[09:27:35.152] <TB2> INFO: Expecting 208000 events.
[09:27:51.959] <TB2> INFO: 208000 events read in total (16276ms).
[09:27:51.974] <TB2> INFO: Test took 17442ms.
[09:27:52.086] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[09:27:52.435] <TB2> INFO: dacScan step from 72 .. 72
[09:27:52.435] <TB2> INFO: dacScan split into 1 runs with ntrig = 50
[09:27:52.435] <TB2> INFO: run 1 of 1
[09:27:53.062] <TB2> INFO: Expecting 208000 events.
[09:28:10.335] <TB2> INFO: 208000 events read in total (16745ms).
[09:28:10.349] <TB2> INFO: Test took 17913ms.
[09:28:10.450] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[09:28:10.752] <TB2> INFO: dacScan step from 73 .. 73
[09:28:10.752] <TB2> INFO: dacScan split into 1 runs with ntrig = 50
[09:28:10.752] <TB2> INFO: run 1 of 1
[09:28:11.360] <TB2> INFO: Expecting 208000 events.
[09:28:28.457] <TB2> INFO: 208000 events read in total (16569ms).
[09:28:28.470] <TB2> INFO: Test took 17717ms.
[09:28:28.571] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[09:28:28.893] <TB2> INFO: dacScan step from 74 .. 74
[09:28:28.893] <TB2> INFO: dacScan split into 1 runs with ntrig = 50
[09:28:28.893] <TB2> INFO: run 1 of 1
[09:28:29.493] <TB2> INFO: Expecting 208000 events.
[09:28:46.422] <TB2> INFO: 208000 events read in total (16401ms).
[09:28:46.436] <TB2> INFO: Test took 17543ms.
[09:28:46.542] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[09:28:46.859] <TB2> INFO: dacScan step from 75 .. 75
[09:28:46.859] <TB2> INFO: dacScan split into 1 runs with ntrig = 50
[09:28:46.859] <TB2> INFO: run 1 of 1
[09:28:47.459] <TB2> INFO: Expecting 208000 events.
[09:29:04.808] <TB2> INFO: 208000 events read in total (16821ms).
[09:29:04.822] <TB2> INFO: Test took 17962ms.
[09:29:04.929] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[09:29:05.246] <TB2> INFO: dacScan step from 76 .. 76
[09:29:05.246] <TB2> INFO: dacScan split into 1 runs with ntrig = 50
[09:29:05.246] <TB2> INFO: run 1 of 1
[09:29:05.868] <TB2> INFO: Expecting 208000 events.
[09:29:23.159] <TB2> INFO: 208000 events read in total (16763ms).
[09:29:23.173] <TB2> INFO: Test took 17926ms.
[09:29:23.275] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[09:29:23.594] <TB2> INFO: dacScan step from 77 .. 77
[09:29:23.594] <TB2> INFO: dacScan split into 1 runs with ntrig = 50
[09:29:23.594] <TB2> INFO: run 1 of 1
[09:29:24.205] <TB2> INFO: Expecting 208000 events.
[09:29:41.469] <TB2> INFO: 208000 events read in total (16736ms).
[09:29:41.483] <TB2> INFO: Test took 17888ms.
[09:29:41.586] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[09:29:41.897] <TB2> INFO: dacScan step from 78 .. 78
[09:29:41.897] <TB2> INFO: dacScan split into 1 runs with ntrig = 50
[09:29:41.897] <TB2> INFO: run 1 of 1
[09:29:42.518] <TB2> INFO: Expecting 208000 events.
[09:29:59.429] <TB2> INFO: 208000 events read in total (16382ms).
[09:29:59.442] <TB2> INFO: Test took 17544ms.
[09:29:59.542] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[09:29:59.867] <TB2> INFO: dacScan step from 79 .. 79
[09:29:59.867] <TB2> INFO: dacScan split into 1 runs with ntrig = 50
[09:29:59.867] <TB2> INFO: run 1 of 1
[09:30:00.471] <TB2> INFO: Expecting 208000 events.
[09:30:16.657] <TB2> INFO: 208000 events read in total (15658ms).
[09:30:16.670] <TB2> INFO: Test took 16802ms.
[09:30:16.774] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[09:30:17.101] <TB2> INFO: dacScan step from 80 .. 80
[09:30:17.101] <TB2> INFO: dacScan split into 1 runs with ntrig = 50
[09:30:17.101] <TB2> INFO: run 1 of 1
[09:30:17.725] <TB2> INFO: Expecting 208000 events.
[09:30:34.719] <TB2> INFO: 208000 events read in total (16466ms).
[09:30:34.733] <TB2> INFO: Test took 17632ms.
[09:30:34.839] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[09:30:35.141] <TB2> INFO: dacScan step from 81 .. 81
[09:30:35.141] <TB2> INFO: dacScan split into 1 runs with ntrig = 50
[09:30:35.141] <TB2> INFO: run 1 of 1
[09:30:35.743] <TB2> INFO: Expecting 208000 events.
[09:30:52.966] <TB2> INFO: 208000 events read in total (16696ms).
[09:30:52.981] <TB2> INFO: Test took 17839ms.
[09:30:53.085] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[09:30:53.386] <TB2> INFO: dacScan step from 82 .. 82
[09:30:53.386] <TB2> INFO: dacScan split into 1 runs with ntrig = 50
[09:30:53.386] <TB2> INFO: run 1 of 1
[09:30:53.990] <TB2> INFO: Expecting 208000 events.
[09:31:10.753] <TB2> INFO: 208000 events read in total (16235ms).
[09:31:10.770] <TB2> INFO: Test took 17383ms.
[09:31:10.868] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[09:31:11.163] <TB2> INFO: dacScan step from 83 .. 83
[09:31:11.163] <TB2> INFO: dacScan split into 1 runs with ntrig = 50
[09:31:11.163] <TB2> INFO: run 1 of 1
[09:31:11.766] <TB2> INFO: Expecting 208000 events.
[09:31:28.515] <TB2> INFO: 208000 events read in total (16221ms).
[09:31:28.530] <TB2> INFO: Test took 17367ms.
[09:31:28.636] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[09:31:28.943] <TB2> INFO: dacScan step from 84 .. 84
[09:31:28.943] <TB2> INFO: dacScan split into 1 runs with ntrig = 50
[09:31:28.943] <TB2> INFO: run 1 of 1
[09:31:29.555] <TB2> INFO: Expecting 208000 events.
[09:31:46.275] <TB2> INFO: 208000 events read in total (16192ms).
[09:31:46.289] <TB2> INFO: Test took 17345ms.
[09:31:46.389] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[09:31:46.692] <TB2> INFO: dacScan step from 85 .. 85
[09:31:46.692] <TB2> INFO: dacScan split into 1 runs with ntrig = 50
[09:31:46.692] <TB2> INFO: run 1 of 1
[09:31:47.324] <TB2> INFO: Expecting 208000 events.
[09:32:04.595] <TB2> INFO: 208000 events read in total (16743ms).
[09:32:04.609] <TB2> INFO: Test took 17916ms.
[09:32:04.713] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[09:32:05.035] <TB2> INFO: dacScan step from 86 .. 86
[09:32:05.035] <TB2> INFO: dacScan split into 1 runs with ntrig = 50
[09:32:05.035] <TB2> INFO: run 1 of 1
[09:32:05.650] <TB2> INFO: Expecting 208000 events.
[09:32:22.972] <TB2> INFO: 208000 events read in total (16794ms).
[09:32:22.987] <TB2> INFO: Test took 17951ms.
[09:32:23.177] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[09:32:23.493] <TB2> INFO: dacScan step from 87 .. 87
[09:32:23.493] <TB2> INFO: dacScan split into 1 runs with ntrig = 50
[09:32:23.493] <TB2> INFO: run 1 of 1
[09:32:24.114] <TB2> INFO: Expecting 208000 events.
[09:32:41.119] <TB2> INFO: 208000 events read in total (16476ms).
[09:32:41.133] <TB2> INFO: Test took 17639ms.
[09:32:41.234] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[09:32:41.560] <TB2> INFO: dacScan step from 88 .. 88
[09:32:41.560] <TB2> INFO: dacScan split into 1 runs with ntrig = 50
[09:32:41.560] <TB2> INFO: run 1 of 1
[09:32:42.183] <TB2> INFO: Expecting 208000 events.
[09:32:58.333] <TB2> INFO: 208000 events read in total (15621ms).
[09:32:58.347] <TB2> INFO: Test took 16785ms.
[09:32:58.455] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[09:32:58.738] <TB2> INFO: dacScan step from 89 .. 89
[09:32:58.738] <TB2> INFO: dacScan split into 1 runs with ntrig = 50
[09:32:58.738] <TB2> INFO: run 1 of 1
[09:32:59.336] <TB2> INFO: Expecting 208000 events.
[09:33:16.475] <TB2> INFO: 208000 events read in total (16611ms).
[09:33:16.490] <TB2> INFO: Test took 17751ms.
[09:33:16.598] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[09:33:16.882] <TB2> INFO: dacScan step from 90 .. 90
[09:33:16.882] <TB2> INFO: dacScan split into 1 runs with ntrig = 50
[09:33:16.882] <TB2> INFO: run 1 of 1
[09:33:17.480] <TB2> INFO: Expecting 208000 events.
[09:33:34.595] <TB2> INFO: 208000 events read in total (16586ms).
[09:33:34.610] <TB2> INFO: Test took 17727ms.
[09:33:34.710] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[09:33:35.042] <TB2> INFO: dacScan step from 91 .. 91
[09:33:35.042] <TB2> INFO: dacScan split into 1 runs with ntrig = 50
[09:33:35.043] <TB2> INFO: run 1 of 1
[09:33:35.657] <TB2> INFO: Expecting 208000 events.
[09:33:52.777] <TB2> INFO: 208000 events read in total (16592ms).
[09:33:52.794] <TB2> INFO: Test took 17752ms.
[09:33:52.961] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[09:33:53.295] <TB2> INFO: dacScan step from 92 .. 92
[09:33:53.295] <TB2> INFO: dacScan split into 1 runs with ntrig = 50
[09:33:53.295] <TB2> INFO: run 1 of 1
[09:33:53.907] <TB2> INFO: Expecting 208000 events.
[09:34:10.321] <TB2> INFO: 208000 events read in total (15884ms).
[09:34:10.332] <TB2> INFO: Test took 17036ms.
[09:34:10.444] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[09:34:10.743] <TB2> INFO: dacScan step from 93 .. 93
[09:34:10.743] <TB2> INFO: dacScan split into 1 runs with ntrig = 50
[09:34:10.743] <TB2> INFO: run 1 of 1
[09:34:11.347] <TB2> INFO: Expecting 208000 events.
[09:34:27.901] <TB2> INFO: 208000 events read in total (16026ms).
[09:34:27.910] <TB2> INFO: Test took 17166ms.
[09:34:28.009] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[09:34:28.306] <TB2> INFO: dacScan step from 94 .. 94
[09:34:28.307] <TB2> INFO: dacScan split into 1 runs with ntrig = 50
[09:34:28.307] <TB2> INFO: run 1 of 1
[09:34:28.923] <TB2> INFO: Expecting 208000 events.
[09:34:46.650] <TB2> INFO: 208000 events read in total (17199ms).
[09:34:46.667] <TB2> INFO: Test took 18360ms.
[09:34:46.950] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[09:34:47.399] <TB2> INFO: dacScan step from 95 .. 95
[09:34:47.399] <TB2> INFO: dacScan split into 1 runs with ntrig = 50
[09:34:47.399] <TB2> INFO: run 1 of 1
[09:34:48.009] <TB2> INFO: Expecting 208000 events.
[09:35:05.311] <TB2> INFO: 208000 events read in total (16774ms).
[09:35:05.325] <TB2> INFO: Test took 17924ms.
[09:35:05.446] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[09:35:05.774] <TB2> INFO: dacScan step from 96 .. 96
[09:35:05.774] <TB2> INFO: dacScan split into 1 runs with ntrig = 50
[09:35:05.774] <TB2> INFO: run 1 of 1
[09:35:06.383] <TB2> INFO: Expecting 208000 events.
[09:35:23.519] <TB2> INFO: 208000 events read in total (16608ms).
[09:35:23.535] <TB2> INFO: Test took 17760ms.
[09:35:23.862] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[09:35:24.212] <TB2> INFO: dacScan step from 97 .. 97
[09:35:24.212] <TB2> INFO: dacScan split into 1 runs with ntrig = 50
[09:35:24.212] <TB2> INFO: run 1 of 1
[09:35:24.833] <TB2> INFO: Expecting 208000 events.
[09:35:41.624] <TB2> INFO: 208000 events read in total (16263ms).
[09:35:41.638] <TB2> INFO: Test took 17425ms.
[09:35:41.826] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[09:35:42.135] <TB2> INFO: dacScan step from 98 .. 98
[09:35:42.135] <TB2> INFO: dacScan split into 1 runs with ntrig = 50
[09:35:42.135] <TB2> INFO: run 1 of 1
[09:35:42.747] <TB2> INFO: Expecting 208000 events.
[09:35:59.364] <TB2> INFO: 208000 events read in total (16089ms).
[09:35:59.379] <TB2> INFO: Test took 17243ms.
[09:35:59.577] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[09:35:59.930] <TB2> INFO: dacScan step from 99 .. 99
[09:35:59.931] <TB2> INFO: dacScan split into 1 runs with ntrig = 50
[09:35:59.931] <TB2> INFO: run 1 of 1
[09:36:00.541] <TB2> INFO: Expecting 208000 events.
[09:36:17.292] <TB2> INFO: 208000 events read in total (16221ms).
[09:36:17.306] <TB2> INFO: Test took 17374ms.
[09:36:17.539] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[09:36:17.860] <TB2> INFO: dacScan step from 100 .. 100
[09:36:17.860] <TB2> INFO: dacScan split into 1 runs with ntrig = 50
[09:36:17.860] <TB2> INFO: run 1 of 1
[09:36:18.475] <TB2> INFO: Expecting 208000 events.
[09:36:35.412] <TB2> INFO: 208000 events read in total (16411ms).
[09:36:35.430] <TB2> INFO: Test took 17569ms.
[09:36:35.596] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[09:36:35.891] <TB2> INFO: dumping ASCII scurve output file: XSCurveData
[09:36:37.425] <TB2> INFO: dumping ASCII scurve output file: XSCurveData
[09:36:38.875] <TB2> INFO: dumping ASCII scurve output file: XSCurveData
[09:36:40.203] <TB2> INFO: dumping ASCII scurve output file: XSCurveData
[09:36:41.725] <TB2> INFO: dumping ASCII scurve output file: XSCurveData
[09:36:43.326] <TB2> INFO: dumping ASCII scurve output file: XSCurveData
[09:36:44.880] <TB2> INFO: dumping ASCII scurve output file: XSCurveData
[09:36:46.480] <TB2> INFO: dumping ASCII scurve output file: XSCurveData
[09:36:48.100] <TB2> INFO: dumping ASCII scurve output file: XSCurveData
[09:36:49.642] <TB2> INFO: dumping ASCII scurve output file: XSCurveData
[09:36:51.210] <TB2> INFO: dumping ASCII scurve output file: XSCurveData
[09:36:52.751] <TB2> INFO: dumping ASCII scurve output file: XSCurveData
[09:36:54.260] <TB2> INFO: dumping ASCII scurve output file: XSCurveData
[09:36:55.817] <TB2> INFO: dumping ASCII scurve output file: XSCurveData
[09:36:57.332] <TB2> INFO: dumping ASCII scurve output file: XSCurveData
[09:36:58.792] <TB2> INFO: dumping ASCII scurve output file: XSCurveData
[09:37:00.204] <TB2> INFO: PixTest:: pg_setup set to default.
[09:37:00.205] <TB2> INFO: vcal mean: 39.21 39.49 39.49 43.21 41.96 44.32 43.66 43.20 43.12 43.45 44.03 42.70 41.66 39.61 39.60 39.72
[09:37:00.205] <TB2> INFO: vcal RMS: 1.73 1.49 1.23 1.60 1.46 1.49 1.57 1.53 1.81 1.54 1.58 1.65 1.44 1.48 1.28 1.34
[09:37:00.205] <TB2> INFO: number of X-ray hits detected: 223363 244162 221985 365986 357686 448296 453449 428824 424245 441121 467955 390610 345302 213004 229790 241017
[09:37:00.205] <TB2> INFO: number of triggers sent (total per ROC): 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000
[09:37:00.205] <TB2> INFO: X-ray hit rate [MHz/cm2]: 65.5 71.6 65.1 107.3 104.8 131.4 132.9 125.7 124.3 129.3 137.2 114.5 101.2 62.4 67.4 70.6
[09:37:00.205] <TB2> INFO: PixTestHighRate::doXNoiseMaps() done
[09:37:00.307] <TB2> INFO: enter test to run
[09:37:00.307] <TB2> INFO: test: no parameter change
[09:37:00.503] <TB2> QUIET: Connection to board 162 closed.
[09:37:00.583] <TB2> INFO: pXar: this is the end, my friend
commander_CalDelScanAndSaveDacs.log
[10:01:26.008] <TB2> INFO: *** Welcome to pxar ***
[10:01:26.008] <TB2> INFO: *** Today: 2015/08/28
[10:01:26.008] <TB2> INFO: readRocDacs: /usr/local/data/M2086_XrayQualification_2015-08-28_10h32m_1440750764//010_CalDelScanAndSaveDacs_4mA25kV//dacParameters35_C0.dat .. /usr/local/data/M2086_XrayQualification_2015-08-28_10h32m_1440750764//010_CalDelScanAndSaveDacs_4mA25kV//dacParameters35_C15.dat
[10:01:26.010] <TB2> INFO: readTbmDacs: /usr/local/data/M2086_XrayQualification_2015-08-28_10h32m_1440750764//010_CalDelScanAndSaveDacs_4mA25kV//tbmParameters_C0a.dat .. /usr/local/data/M2086_XrayQualification_2015-08-28_10h32m_1440750764//010_CalDelScanAndSaveDacs_4mA25kV//tbmParameters_C0b.dat
[10:01:26.010] <TB2> INFO: readMaskFile: /usr/local/data/M2086_XrayQualification_2015-08-28_10h32m_1440750764//010_CalDelScanAndSaveDacs_4mA25kV//defaultMaskFile.dat
[10:01:26.010] <TB2> INFO: readTrimFile: /usr/local/data/M2086_XrayQualification_2015-08-28_10h32m_1440750764//010_CalDelScanAndSaveDacs_4mA25kV//trimParameters35_C0.dat .. /usr/local/data/M2086_XrayQualification_2015-08-28_10h32m_1440750764//010_CalDelScanAndSaveDacs_4mA25kV//trimParameters35_C15.dat
[10:01:26.084] <TB2> INFO: clk: 4
[10:01:26.084] <TB2> INFO: ctr: 4
[10:01:26.084] <TB2> INFO: sda: 19
[10:01:26.084] <TB2> INFO: tin: 9
[10:01:26.084] <TB2> INFO: level: 15
[10:01:26.084] <TB2> INFO: triggerdelay: 0
[10:01:26.084] <TB2> QUIET: Instanciating API for pxar v2.5+57~g44d0777
[10:01:26.084] <TB2> INFO: Log level: INFO
[10:01:26.096] <TB2> INFO: Found DTB DTB_WXENWR
[10:01:26.108] <TB2> QUIET: Connection to board DTB_WXENWR opened.
[10:01:26.112] <TB2> INFO: DTB startup information
--- DTB info------------------------------------------
Board id: 162
HW version: DTB1.2
FW version: 4.2
SW version: 4.4
USB id: DTB_WXENWR
MAC address: 40D8551180A2
Hostname: pixelDTB162
Comment:
------------------------------------------------------
[10:01:26.114] <TB2> INFO: RPC call hashes of host and DTB match: 397073690
[10:01:27.675] <TB2> INFO: DUT info:
[10:01:27.675] <TB2> INFO: The DUT currently contains the following objects:
[10:01:27.675] <TB2> INFO: 2 TBM Cores tbm09c (2 ON)
[10:01:27.675] <TB2> INFO: TBM Core alpha (0): 7 registers set
[10:01:27.675] <TB2> INFO: TBM Core beta (1): 7 registers set
[10:01:27.675] <TB2> INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[10:01:27.675] <TB2> INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[10:01:27.675] <TB2> INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[10:01:27.676] <TB2> INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[10:01:27.676] <TB2> INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[10:01:27.676] <TB2> INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[10:01:27.676] <TB2> INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[10:01:27.676] <TB2> INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[10:01:27.676] <TB2> INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[10:01:27.676] <TB2> INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[10:01:27.676] <TB2> INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[10:01:27.676] <TB2> INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[10:01:27.676] <TB2> INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[10:01:27.676] <TB2> INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[10:01:27.676] <TB2> INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[10:01:27.676] <TB2> INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[10:01:27.676] <TB2> INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[10:01:28.077] <TB2> INFO: enter 'restricted' command line mode
[10:01:28.077] <TB2> INFO: enter test to run
[10:01:28.077] <TB2> INFO: test: HighRate no parameter change
[10:01:28.077] <TB2> INFO: running: highrate
[10:01:28.078] <TB2> INFO: readGainPedestalParameters /usr/local/data/M2086_XrayQualification_2015-08-28_10h32m_1440750764//010_CalDelScanAndSaveDacs_4mA25kV//phCalibrationFitErr35_C0.dat .. /usr/local/data/M2086_XrayQualification_2015-08-28_10h32m_1440750764//010_CalDelScanAndSaveDacs_4mA25kV//phCalibrationFitErr35_C15.dat
[10:01:28.229] <TB2> INFO: ----------------------------------------------------------------------
[10:01:28.229] <TB2> INFO: PixTestHighRate::calDelScan() ntrig = 10, vcal = 200
[10:01:28.229] <TB2> INFO: ----------------------------------------------------------------------
[10:01:28.369] <TB2> INFO: Expecting 768 events.
[10:01:29.601] <TB2> INFO: 768 events read in total (512ms).
[10:01:29.696] <TB2> INFO: Test took 1461ms.
[10:01:30.318] <TB2> INFO: Expecting 41600 events.
[10:01:33.610] <TB2> INFO: 41600 events read in total (2764ms).
[10:01:33.612] <TB2> INFO: Test took 3909ms.
[10:01:33.709] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[10:01:34.386] <TB2> INFO: Expecting 41600 events.
[10:01:37.772] <TB2> INFO: 41600 events read in total (2858ms).
[10:01:37.774] <TB2> INFO: Test took 4021ms.
[10:01:37.863] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[10:01:38.510] <TB2> INFO: Expecting 41600 events.
[10:01:42.020] <TB2> INFO: 41600 events read in total (2982ms).
[10:01:42.023] <TB2> INFO: Test took 4128ms.
[10:01:42.115] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[10:01:42.783] <TB2> INFO: Expecting 41600 events.
[10:01:46.194] <TB2> INFO: 41600 events read in total (2883ms).
[10:01:46.196] <TB2> INFO: Test took 4045ms.
[10:01:46.288] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[10:01:46.939] <TB2> INFO: Expecting 41600 events.
[10:01:50.308] <TB2> INFO: 41600 events read in total (2840ms).
[10:01:50.310] <TB2> INFO: Test took 3986ms.
[10:01:50.401] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[10:01:51.050] <TB2> INFO: Expecting 41600 events.
[10:01:54.360] <TB2> INFO: 41600 events read in total (2782ms).
[10:01:54.363] <TB2> INFO: Test took 3928ms.
[10:01:54.450] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[10:01:55.099] <TB2> INFO: Expecting 41600 events.
[10:01:58.567] <TB2> INFO: 41600 events read in total (2940ms).
[10:01:58.569] <TB2> INFO: Test took 4085ms.
[10:01:58.657] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[10:01:59.305] <TB2> INFO: Expecting 41600 events.
[10:02:02.774] <TB2> INFO: 41600 events read in total (2940ms).
[10:02:02.776] <TB2> INFO: Test took 4086ms.
[10:02:02.866] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[10:02:03.514] <TB2> INFO: Expecting 41600 events.
[10:02:07.028] <TB2> INFO: 41600 events read in total (2986ms).
[10:02:07.030] <TB2> INFO: Test took 4131ms.
[10:02:07.124] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[10:02:07.773] <TB2> INFO: Expecting 41600 events.
[10:02:11.327] <TB2> INFO: 41600 events read in total (3026ms).
[10:02:11.329] <TB2> INFO: Test took 4170ms.
[10:02:11.422] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[10:02:12.073] <TB2> INFO: Expecting 41600 events.
[10:02:15.542] <TB2> INFO: 41600 events read in total (2941ms).
[10:02:15.544] <TB2> INFO: Test took 4086ms.
[10:02:15.635] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[10:02:16.284] <TB2> INFO: Expecting 41600 events.
[10:02:19.763] <TB2> INFO: 41600 events read in total (2951ms).
[10:02:19.765] <TB2> INFO: Test took 4096ms.
[10:02:19.855] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[10:02:20.509] <TB2> INFO: Expecting 41600 events.
[10:02:23.901] <TB2> INFO: 41600 events read in total (2863ms).
[10:02:23.904] <TB2> INFO: Test took 4012ms.
[10:02:24.002] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[10:02:24.658] <TB2> INFO: Expecting 41600 events.
[10:02:28.110] <TB2> INFO: 41600 events read in total (2924ms).
[10:02:28.112] <TB2> INFO: Test took 4069ms.
[10:02:28.209] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[10:02:28.857] <TB2> INFO: Expecting 41600 events.
[10:02:32.276] <TB2> INFO: 41600 events read in total (2889ms).
[10:02:32.278] <TB2> INFO: Test took 4036ms.
[10:02:32.366] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[10:02:33.015] <TB2> INFO: Expecting 41600 events.
[10:02:36.447] <TB2> INFO: 41600 events read in total (2903ms).
[10:02:36.449] <TB2> INFO: Test took 4049ms.
[10:02:36.541] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[10:02:37.190] <TB2> INFO: Expecting 41600 events.
[10:02:40.609] <TB2> INFO: 41600 events read in total (2891ms).
[10:02:40.611] <TB2> INFO: Test took 4036ms.
[10:02:40.698] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[10:02:41.346] <TB2> INFO: Expecting 41600 events.
[10:02:44.805] <TB2> INFO: 41600 events read in total (2931ms).
[10:02:44.807] <TB2> INFO: Test took 4076ms.
[10:02:44.900] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[10:02:45.548] <TB2> INFO: Expecting 41600 events.
[10:02:49.024] <TB2> INFO: 41600 events read in total (2948ms).
[10:02:49.026] <TB2> INFO: Test took 4092ms.
[10:02:49.110] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[10:02:49.767] <TB2> INFO: Expecting 41600 events.
[10:02:53.235] <TB2> INFO: 41600 events read in total (2940ms).
[10:02:53.237] <TB2> INFO: Test took 4094ms.
[10:02:53.335] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[10:02:53.676] <TB2> INFO: enter test to run
[10:02:53.676] <TB2> INFO: test: Pretest no parameter change
[10:02:53.676] <TB2> INFO: running: pretest
[10:02:53.676] <TB2> INFO: write dac parameters into /usr/local/data/M2086_XrayQualification_2015-08-28_10h32m_1440750764//010_CalDelScanAndSaveDacs_4mA25kV//dacParameters35_C0.dat
[10:02:53.676] <TB2> INFO: write dac parameters into /usr/local/data/M2086_XrayQualification_2015-08-28_10h32m_1440750764//010_CalDelScanAndSaveDacs_4mA25kV//dacParameters35_C1.dat
[10:02:53.676] <TB2> INFO: write dac parameters into /usr/local/data/M2086_XrayQualification_2015-08-28_10h32m_1440750764//010_CalDelScanAndSaveDacs_4mA25kV//dacParameters35_C2.dat
[10:02:53.676] <TB2> INFO: write dac parameters into /usr/local/data/M2086_XrayQualification_2015-08-28_10h32m_1440750764//010_CalDelScanAndSaveDacs_4mA25kV//dacParameters35_C3.dat
[10:02:53.676] <TB2> INFO: write dac parameters into /usr/local/data/M2086_XrayQualification_2015-08-28_10h32m_1440750764//010_CalDelScanAndSaveDacs_4mA25kV//dacParameters35_C4.dat
[10:02:53.677] <TB2> INFO: write dac parameters into /usr/local/data/M2086_XrayQualification_2015-08-28_10h32m_1440750764//010_CalDelScanAndSaveDacs_4mA25kV//dacParameters35_C5.dat
[10:02:53.677] <TB2> INFO: write dac parameters into /usr/local/data/M2086_XrayQualification_2015-08-28_10h32m_1440750764//010_CalDelScanAndSaveDacs_4mA25kV//dacParameters35_C6.dat
[10:02:53.677] <TB2> INFO: write dac parameters into /usr/local/data/M2086_XrayQualification_2015-08-28_10h32m_1440750764//010_CalDelScanAndSaveDacs_4mA25kV//dacParameters35_C7.dat
[10:02:53.677] <TB2> INFO: write dac parameters into /usr/local/data/M2086_XrayQualification_2015-08-28_10h32m_1440750764//010_CalDelScanAndSaveDacs_4mA25kV//dacParameters35_C8.dat
[10:02:53.677] <TB2> INFO: write dac parameters into /usr/local/data/M2086_XrayQualification_2015-08-28_10h32m_1440750764//010_CalDelScanAndSaveDacs_4mA25kV//dacParameters35_C9.dat
[10:02:53.677] <TB2> INFO: write dac parameters into /usr/local/data/M2086_XrayQualification_2015-08-28_10h32m_1440750764//010_CalDelScanAndSaveDacs_4mA25kV//dacParameters35_C10.dat
[10:02:53.677] <TB2> INFO: write dac parameters into /usr/local/data/M2086_XrayQualification_2015-08-28_10h32m_1440750764//010_CalDelScanAndSaveDacs_4mA25kV//dacParameters35_C11.dat
[10:02:53.677] <TB2> INFO: write dac parameters into /usr/local/data/M2086_XrayQualification_2015-08-28_10h32m_1440750764//010_CalDelScanAndSaveDacs_4mA25kV//dacParameters35_C12.dat
[10:02:53.677] <TB2> INFO: write dac parameters into /usr/local/data/M2086_XrayQualification_2015-08-28_10h32m_1440750764//010_CalDelScanAndSaveDacs_4mA25kV//dacParameters35_C13.dat
[10:02:53.677] <TB2> INFO: write dac parameters into /usr/local/data/M2086_XrayQualification_2015-08-28_10h32m_1440750764//010_CalDelScanAndSaveDacs_4mA25kV//dacParameters35_C14.dat
[10:02:53.677] <TB2> INFO: write dac parameters into /usr/local/data/M2086_XrayQualification_2015-08-28_10h32m_1440750764//010_CalDelScanAndSaveDacs_4mA25kV//dacParameters35_C15.dat
[10:02:53.678] <TB2> INFO: enter test to run
[10:02:53.678] <TB2> INFO: test: no parameter change
[10:02:53.884] <TB2> QUIET: Connection to board 162 closed.
[10:02:53.964] <TB2> INFO: pXar: this is the end, my friend
commander_HREfficiency.log
[10:03:36.927] <TB2> INFO: *** Welcome to pxar ***
[10:03:36.927] <TB2> INFO: *** Today: 2015/08/28
[10:03:36.928] <TB2> INFO: readRocDacs: /usr/local/data/M2086_XrayQualification_2015-08-28_10h32m_1440750764//011_HREfficiency_50//dacParameters35_C0.dat .. /usr/local/data/M2086_XrayQualification_2015-08-28_10h32m_1440750764//011_HREfficiency_50//dacParameters35_C15.dat
[10:03:36.928] <TB2> INFO: readTbmDacs: /usr/local/data/M2086_XrayQualification_2015-08-28_10h32m_1440750764//011_HREfficiency_50//tbmParameters_C0a.dat .. /usr/local/data/M2086_XrayQualification_2015-08-28_10h32m_1440750764//011_HREfficiency_50//tbmParameters_C0b.dat
[10:03:36.928] <TB2> INFO: readMaskFile: /usr/local/data/M2086_XrayQualification_2015-08-28_10h32m_1440750764//011_HREfficiency_50//defaultMaskFile.dat
[10:03:36.928] <TB2> INFO: readTrimFile: /usr/local/data/M2086_XrayQualification_2015-08-28_10h32m_1440750764//011_HREfficiency_50//trimParameters35_C0.dat .. /usr/local/data/M2086_XrayQualification_2015-08-28_10h32m_1440750764//011_HREfficiency_50//trimParameters35_C15.dat
[10:03:36.996] <TB2> INFO: clk: 4
[10:03:36.996] <TB2> INFO: ctr: 4
[10:03:36.996] <TB2> INFO: sda: 19
[10:03:36.996] <TB2> INFO: tin: 9
[10:03:36.996] <TB2> INFO: level: 15
[10:03:36.996] <TB2> INFO: triggerdelay: 0
[10:03:36.996] <TB2> QUIET: Instanciating API for pxar v2.5+57~g44d0777
[10:03:36.996] <TB2> INFO: Log level: INFO
[10:03:37.009] <TB2> INFO: Found DTB DTB_WXENWR
[10:03:37.022] <TB2> QUIET: Connection to board DTB_WXENWR opened.
[10:03:37.025] <TB2> INFO: DTB startup information
--- DTB info------------------------------------------
Board id: 162
HW version: DTB1.2
FW version: 4.2
SW version: 4.4
USB id: DTB_WXENWR
MAC address: 40D8551180A2
Hostname: pixelDTB162
Comment:
------------------------------------------------------
[10:03:37.028] <TB2> INFO: RPC call hashes of host and DTB match: 397073690
[10:03:38.558] <TB2> INFO: DUT info:
[10:03:38.558] <TB2> INFO: The DUT currently contains the following objects:
[10:03:38.558] <TB2> INFO: 2 TBM Cores tbm09c (2 ON)
[10:03:38.558] <TB2> INFO: TBM Core alpha (0): 7 registers set
[10:03:38.558] <TB2> INFO: TBM Core beta (1): 7 registers set
[10:03:38.558] <TB2> INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[10:03:38.558] <TB2> INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[10:03:38.558] <TB2> INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[10:03:38.558] <TB2> INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[10:03:38.558] <TB2> INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[10:03:38.558] <TB2> INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[10:03:38.558] <TB2> INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[10:03:38.558] <TB2> INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[10:03:38.558] <TB2> INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[10:03:38.558] <TB2> INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[10:03:38.558] <TB2> INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[10:03:38.558] <TB2> INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[10:03:38.558] <TB2> INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[10:03:38.558] <TB2> INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[10:03:38.558] <TB2> INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[10:03:38.558] <TB2> INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[10:03:38.558] <TB2> INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[10:03:38.960] <TB2> INFO: enter 'restricted' command line mode
[10:03:38.960] <TB2> INFO: enter test to run
[10:03:38.960] <TB2> INFO: test: HighRate no parameter change
[10:03:38.960] <TB2> INFO: running: highrate
[10:03:38.960] <TB2> INFO: readGainPedestalParameters /usr/local/data/M2086_XrayQualification_2015-08-28_10h32m_1440750764//011_HREfficiency_50//phCalibrationFitErr35_C0.dat .. /usr/local/data/M2086_XrayQualification_2015-08-28_10h32m_1440750764//011_HREfficiency_50//phCalibrationFitErr35_C15.dat
[10:03:39.127] <TB2> INFO: ----------------------------------------------------------------------
[10:03:39.127] <TB2> INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[10:03:39.127] <TB2> INFO: ----------------------------------------------------------------------
[10:03:39.741] <TB2> INFO: Expecting 208000 events.
[10:03:51.542] <TB2> INFO: 208000 events read in total (11270ms).
[10:03:51.550] <TB2> INFO: Test took 12415ms.
[10:03:51.891] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[10:03:52.237] <TB2> INFO: number of dead pixels (per ROC): 2 1 0 0 0 0 0 0 1 0 0 0 0 0 0 0
[10:03:52.237] <TB2> INFO: number of red-efficiency pixels: 151 165 158 284 272 413 451 366 392 396 448 363 249 150 168 160
[10:03:52.237] <TB2> INFO: number of X-ray hits detected: 111649 122252 108638 186060 181174 231525 229868 216912 215867 222095 240171 198895 174249 105418 113737 120016
[10:03:52.237] <TB2> INFO: number of triggers sent (total per ROC): 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000
[10:03:52.237] <TB2> INFO: number of Vcal hits detected: 207653 207784 207828 207702 207721 207547 207498 207617 207540 207570 207514 207604 207741 207844 207830 207833
[10:03:52.237] <TB2> INFO: Vcal hit fiducial efficiency (%): 99.9 99.9 99.9 99.9 99.9 99.8 99.8 99.8 99.8 99.8 99.8 99.8 99.9 99.9 99.9 99.9
[10:03:52.237] <TB2> INFO: Vcal hit overall efficiency (%): 99.8 99.9 99.9 99.9 99.9 99.8 99.8 99.8 99.8 99.8 99.8 99.8 99.9 99.9 99.9 99.9
[10:03:52.237] <TB2> INFO: X-ray hit rate [MHz/cm2]: 32.7 35.8 31.8 54.5 53.1 67.9 67.4 63.6 63.3 65.1 70.4 58.3 51.1 30.9 33.3 35.2
[10:03:52.237] <TB2> INFO: PixTestHighRate::doXPixelAlive() done
[10:03:52.285] <TB2> INFO: PixTest:: pg_setup set to default.
[10:03:52.344] <TB2> INFO: enter test to run
[10:03:52.344] <TB2> INFO: test: no parameter change
[10:03:52.485] <TB2> QUIET: Connection to board 162 closed.
[10:03:52.567] <TB2> INFO: pXar: this is the end, my friend
commander_HREfficiency.log
[10:04:27.887] <TB2> INFO: *** Welcome to pxar ***
[10:04:27.887] <TB2> INFO: *** Today: 2015/08/28
[10:04:27.887] <TB2> INFO: readRocDacs: /usr/local/data/M2086_XrayQualification_2015-08-28_10h32m_1440750764//012_HREfficiency_100//dacParameters35_C0.dat .. /usr/local/data/M2086_XrayQualification_2015-08-28_10h32m_1440750764//012_HREfficiency_100//dacParameters35_C15.dat
[10:04:27.888] <TB2> INFO: readTbmDacs: /usr/local/data/M2086_XrayQualification_2015-08-28_10h32m_1440750764//012_HREfficiency_100//tbmParameters_C0a.dat .. /usr/local/data/M2086_XrayQualification_2015-08-28_10h32m_1440750764//012_HREfficiency_100//tbmParameters_C0b.dat
[10:04:27.888] <TB2> INFO: readMaskFile: /usr/local/data/M2086_XrayQualification_2015-08-28_10h32m_1440750764//012_HREfficiency_100//defaultMaskFile.dat
[10:04:27.888] <TB2> INFO: readTrimFile: /usr/local/data/M2086_XrayQualification_2015-08-28_10h32m_1440750764//012_HREfficiency_100//trimParameters35_C0.dat .. /usr/local/data/M2086_XrayQualification_2015-08-28_10h32m_1440750764//012_HREfficiency_100//trimParameters35_C15.dat
[10:04:27.999] <TB2> INFO: clk: 4
[10:04:27.999] <TB2> INFO: ctr: 4
[10:04:27.999] <TB2> INFO: sda: 19
[10:04:27.999] <TB2> INFO: tin: 9
[10:04:27.999] <TB2> INFO: level: 15
[10:04:27.999] <TB2> INFO: triggerdelay: 0
[10:04:27.999] <TB2> QUIET: Instanciating API for pxar v2.5+57~g44d0777
[10:04:27.999] <TB2> INFO: Log level: INFO
[10:04:28.014] <TB2> INFO: Found DTB DTB_WXENWR
[10:04:28.026] <TB2> QUIET: Connection to board DTB_WXENWR opened.
[10:04:28.029] <TB2> INFO: DTB startup information
--- DTB info------------------------------------------
Board id: 162
HW version: DTB1.2
FW version: 4.2
SW version: 4.4
USB id: DTB_WXENWR
MAC address: 40D8551180A2
Hostname: pixelDTB162
Comment:
------------------------------------------------------
[10:04:28.032] <TB2> INFO: RPC call hashes of host and DTB match: 397073690
[10:04:29.559] <TB2> INFO: DUT info:
[10:04:29.559] <TB2> INFO: The DUT currently contains the following objects:
[10:04:29.559] <TB2> INFO: 2 TBM Cores tbm09c (2 ON)
[10:04:29.559] <TB2> INFO: TBM Core alpha (0): 7 registers set
[10:04:29.559] <TB2> INFO: TBM Core beta (1): 7 registers set
[10:04:29.559] <TB2> INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[10:04:29.559] <TB2> INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[10:04:29.559] <TB2> INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[10:04:29.559] <TB2> INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[10:04:29.559] <TB2> INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[10:04:29.559] <TB2> INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[10:04:29.560] <TB2> INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[10:04:29.560] <TB2> INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[10:04:29.560] <TB2> INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[10:04:29.560] <TB2> INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[10:04:29.560] <TB2> INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[10:04:29.560] <TB2> INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[10:04:29.560] <TB2> INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[10:04:29.560] <TB2> INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[10:04:29.560] <TB2> INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[10:04:29.560] <TB2> INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[10:04:29.560] <TB2> INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[10:04:29.961] <TB2> INFO: enter 'restricted' command line mode
[10:04:29.961] <TB2> INFO: enter test to run
[10:04:29.961] <TB2> INFO: test: HighRate no parameter change
[10:04:29.961] <TB2> INFO: running: highrate
[10:04:29.961] <TB2> INFO: readGainPedestalParameters /usr/local/data/M2086_XrayQualification_2015-08-28_10h32m_1440750764//012_HREfficiency_100//phCalibrationFitErr35_C0.dat .. /usr/local/data/M2086_XrayQualification_2015-08-28_10h32m_1440750764//012_HREfficiency_100//phCalibrationFitErr35_C15.dat
[10:04:30.115] <TB2> INFO: ----------------------------------------------------------------------
[10:04:30.115] <TB2> INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[10:04:30.115] <TB2> INFO: ----------------------------------------------------------------------
[10:04:30.726] <TB2> INFO: Expecting 208000 events.
[10:04:47.420] <TB2> INFO: 208000 events read in total (16163ms).
[10:04:47.429] <TB2> INFO: Test took 17307ms.
[10:04:48.111] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[10:04:48.529] <TB2> INFO: number of dead pixels (per ROC): 3 1 0 0 0 0 0 0 1 0 0 0 0 0 0 0
[10:04:48.529] <TB2> INFO: number of red-efficiency pixels: 432 488 541 981 1024 1599 1736 1401 1475 1523 1693 1222 927 530 522 499
[10:04:48.529] <TB2> INFO: number of X-ray hits detected: 223546 246070 220706 366569 360187 453523 455275 429544 430542 441888 470008 395970 348453 215292 230237 242066
[10:04:48.529] <TB2> INFO: number of triggers sent (total per ROC): 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000
[10:04:48.529] <TB2> INFO: number of Vcal hits detected: 207348 207422 207192 206771 206669 205623 205132 206195 205986 205818 205407 206269 206910 207316 207438 207456
[10:04:48.529] <TB2> INFO: Vcal hit fiducial efficiency (%): 99.8 99.8 99.7 99.5 99.4 99.0 98.8 99.2 99.2 99.1 98.9 99.3 99.5 99.7 99.8 99.8
[10:04:48.529] <TB2> INFO: Vcal hit overall efficiency (%): 99.7 99.7 99.6 99.4 99.4 98.9 98.6 99.1 99.0 99.0 98.8 99.2 99.5 99.7 99.7 99.7
[10:04:48.529] <TB2> INFO: X-ray hit rate [MHz/cm2]: 65.5 72.1 64.7 107.4 105.6 132.9 133.4 125.9 126.2 129.5 137.8 116.1 102.1 63.1 67.5 71.0
[10:04:48.529] <TB2> INFO: PixTestHighRate::doXPixelAlive() done
[10:04:48.577] <TB2> INFO: PixTest:: pg_setup set to default.
[10:04:48.622] <TB2> INFO: enter test to run
[10:04:48.623] <TB2> INFO: test: no parameter change
[10:04:48.789] <TB2> QUIET: Connection to board 162 closed.
[10:04:48.870] <TB2> INFO: pXar: this is the end, my friend
commander_HREfficiency.log
[10:05:25.181] <TB2> INFO: *** Welcome to pxar ***
[10:05:25.181] <TB2> INFO: *** Today: 2015/08/28
[10:05:25.181] <TB2> INFO: readRocDacs: /usr/local/data/M2086_XrayQualification_2015-08-28_10h32m_1440750764//013_HREfficiency_150//dacParameters35_C0.dat .. /usr/local/data/M2086_XrayQualification_2015-08-28_10h32m_1440750764//013_HREfficiency_150//dacParameters35_C15.dat
[10:05:25.182] <TB2> INFO: readTbmDacs: /usr/local/data/M2086_XrayQualification_2015-08-28_10h32m_1440750764//013_HREfficiency_150//tbmParameters_C0a.dat .. /usr/local/data/M2086_XrayQualification_2015-08-28_10h32m_1440750764//013_HREfficiency_150//tbmParameters_C0b.dat
[10:05:25.182] <TB2> INFO: readMaskFile: /usr/local/data/M2086_XrayQualification_2015-08-28_10h32m_1440750764//013_HREfficiency_150//defaultMaskFile.dat
[10:05:25.182] <TB2> INFO: readTrimFile: /usr/local/data/M2086_XrayQualification_2015-08-28_10h32m_1440750764//013_HREfficiency_150//trimParameters35_C0.dat .. /usr/local/data/M2086_XrayQualification_2015-08-28_10h32m_1440750764//013_HREfficiency_150//trimParameters35_C15.dat
[10:05:25.258] <TB2> INFO: clk: 4
[10:05:25.258] <TB2> INFO: ctr: 4
[10:05:25.258] <TB2> INFO: sda: 19
[10:05:25.258] <TB2> INFO: tin: 9
[10:05:25.258] <TB2> INFO: level: 15
[10:05:25.258] <TB2> INFO: triggerdelay: 0
[10:05:25.258] <TB2> QUIET: Instanciating API for pxar v2.5+57~g44d0777
[10:05:25.258] <TB2> INFO: Log level: INFO
[10:05:25.271] <TB2> INFO: Found DTB DTB_WXENWR
[10:05:25.282] <TB2> QUIET: Connection to board DTB_WXENWR opened.
[10:05:25.286] <TB2> INFO: DTB startup information
--- DTB info------------------------------------------
Board id: 162
HW version: DTB1.2
FW version: 4.2
SW version: 4.4
USB id: DTB_WXENWR
MAC address: 40D8551180A2
Hostname: pixelDTB162
Comment:
------------------------------------------------------
[10:05:25.288] <TB2> INFO: RPC call hashes of host and DTB match: 397073690
[10:05:26.823] <TB2> INFO: DUT info:
[10:05:26.823] <TB2> INFO: The DUT currently contains the following objects:
[10:05:26.823] <TB2> INFO: 2 TBM Cores tbm09c (2 ON)
[10:05:26.823] <TB2> INFO: TBM Core alpha (0): 7 registers set
[10:05:26.823] <TB2> INFO: TBM Core beta (1): 7 registers set
[10:05:26.823] <TB2> INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[10:05:26.823] <TB2> INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[10:05:26.823] <TB2> INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[10:05:26.823] <TB2> INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[10:05:26.823] <TB2> INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[10:05:26.823] <TB2> INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[10:05:26.823] <TB2> INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[10:05:26.823] <TB2> INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[10:05:26.823] <TB2> INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[10:05:26.823] <TB2> INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[10:05:26.823] <TB2> INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[10:05:26.823] <TB2> INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[10:05:26.823] <TB2> INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[10:05:26.823] <TB2> INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[10:05:26.823] <TB2> INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[10:05:26.823] <TB2> INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[10:05:26.823] <TB2> INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[10:05:27.224] <TB2> INFO: enter 'restricted' command line mode
[10:05:27.224] <TB2> INFO: enter test to run
[10:05:27.224] <TB2> INFO: test: HighRate no parameter change
[10:05:27.224] <TB2> INFO: running: highrate
[10:05:27.224] <TB2> INFO: readGainPedestalParameters /usr/local/data/M2086_XrayQualification_2015-08-28_10h32m_1440750764//013_HREfficiency_150//phCalibrationFitErr35_C0.dat .. /usr/local/data/M2086_XrayQualification_2015-08-28_10h32m_1440750764//013_HREfficiency_150//phCalibrationFitErr35_C15.dat
[10:05:27.373] <TB2> INFO: ----------------------------------------------------------------------
[10:05:27.373] <TB2> INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[10:05:27.373] <TB2> INFO: ----------------------------------------------------------------------
[10:05:28.033] <TB2> INFO: Expecting 208000 events.
[10:05:48.187] <TB2> INFO: 208000 events read in total (19623ms).
[10:05:48.207] <TB2> INFO: Test took 20827ms.
[10:05:49.058] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[10:05:49.558] <TB2> INFO: number of dead pixels (per ROC): 2 0 0 0 0 0 0 0 1 0 0 0 0 0 0 0
[10:05:49.558] <TB2> INFO: number of red-efficiency pixels: 852 1002 969 2037 2069 2913 3096 2758 2831 2756 3119 2414 1904 1004 1119 1006
[10:05:49.558] <TB2> INFO: number of X-ray hits detected: 320779 355889 320538 524115 518562 649025 655015 614490 618332 631817 672529 566570 498838 313110 334360 348342
[10:05:49.558] <TB2> INFO: number of triggers sent (total per ROC): 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000
[10:05:49.558] <TB2> INFO: number of Vcal hits detected: 206810 206730 205845 204603 204409 201281 199785 202857 202381 202076 200448 203130 205006 206124 206623 206789
[10:05:49.558] <TB2> INFO: Vcal hit fiducial efficiency (%): 99.5 99.4 99.2 98.6 98.5 97.2 96.7 97.8 97.6 97.5 96.8 98.0 98.7 99.3 99.4 99.5
[10:05:49.558] <TB2> INFO: Vcal hit overall efficiency (%): 99.4 99.4 99.0 98.4 98.3 96.8 96.1 97.5 97.3 97.2 96.4 97.7 98.6 99.1 99.3 99.4
[10:05:49.558] <TB2> INFO: X-ray hit rate [MHz/cm2]: 94.0 104.3 94.0 153.6 152.0 190.2 192.0 180.1 181.2 185.2 197.1 166.1 146.2 91.8 98.0 102.1
[10:05:49.558] <TB2> INFO: PixTestHighRate::doXPixelAlive() done
[10:05:49.603] <TB2> INFO: PixTest:: pg_setup set to default.
[10:05:49.642] <TB2> INFO: enter test to run
[10:05:49.642] <TB2> INFO: test: no parameter change
[10:05:49.798] <TB2> QUIET: Connection to board 162 closed.
[10:05:49.881] <TB2> INFO: pXar: this is the end, my friend
commander_HREfficiency.log
[10:06:26.081] <TB2> INFO: *** Welcome to pxar ***
[10:06:26.081] <TB2> INFO: *** Today: 2015/08/28
[10:06:26.081] <TB2> INFO: readRocDacs: /usr/local/data/M2086_XrayQualification_2015-08-28_10h32m_1440750764//014_HREfficiency_200//dacParameters35_C0.dat .. /usr/local/data/M2086_XrayQualification_2015-08-28_10h32m_1440750764//014_HREfficiency_200//dacParameters35_C15.dat
[10:06:26.082] <TB2> INFO: readTbmDacs: /usr/local/data/M2086_XrayQualification_2015-08-28_10h32m_1440750764//014_HREfficiency_200//tbmParameters_C0a.dat .. /usr/local/data/M2086_XrayQualification_2015-08-28_10h32m_1440750764//014_HREfficiency_200//tbmParameters_C0b.dat
[10:06:26.082] <TB2> INFO: readMaskFile: /usr/local/data/M2086_XrayQualification_2015-08-28_10h32m_1440750764//014_HREfficiency_200//defaultMaskFile.dat
[10:06:26.082] <TB2> INFO: readTrimFile: /usr/local/data/M2086_XrayQualification_2015-08-28_10h32m_1440750764//014_HREfficiency_200//trimParameters35_C0.dat .. /usr/local/data/M2086_XrayQualification_2015-08-28_10h32m_1440750764//014_HREfficiency_200//trimParameters35_C15.dat
[10:06:26.154] <TB2> INFO: clk: 4
[10:06:26.154] <TB2> INFO: ctr: 4
[10:06:26.154] <TB2> INFO: sda: 19
[10:06:26.154] <TB2> INFO: tin: 9
[10:06:26.154] <TB2> INFO: level: 15
[10:06:26.154] <TB2> INFO: triggerdelay: 0
[10:06:26.154] <TB2> QUIET: Instanciating API for pxar v2.5+57~g44d0777
[10:06:26.154] <TB2> INFO: Log level: INFO
[10:06:26.167] <TB2> INFO: Found DTB DTB_WXENWR
[10:06:26.180] <TB2> QUIET: Connection to board DTB_WXENWR opened.
[10:06:26.183] <TB2> INFO: DTB startup information
--- DTB info------------------------------------------
Board id: 162
HW version: DTB1.2
FW version: 4.2
SW version: 4.4
USB id: DTB_WXENWR
MAC address: 40D8551180A2
Hostname: pixelDTB162
Comment:
------------------------------------------------------
[10:06:26.186] <TB2> INFO: RPC call hashes of host and DTB match: 397073690
[10:06:27.713] <TB2> INFO: DUT info:
[10:06:27.713] <TB2> INFO: The DUT currently contains the following objects:
[10:06:27.713] <TB2> INFO: 2 TBM Cores tbm09c (2 ON)
[10:06:27.713] <TB2> INFO: TBM Core alpha (0): 7 registers set
[10:06:27.713] <TB2> INFO: TBM Core beta (1): 7 registers set
[10:06:27.713] <TB2> INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[10:06:27.713] <TB2> INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[10:06:27.714] <TB2> INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[10:06:27.714] <TB2> INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[10:06:27.714] <TB2> INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[10:06:27.714] <TB2> INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[10:06:27.714] <TB2> INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[10:06:27.714] <TB2> INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[10:06:27.714] <TB2> INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[10:06:27.714] <TB2> INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[10:06:27.714] <TB2> INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[10:06:27.714] <TB2> INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[10:06:27.714] <TB2> INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[10:06:27.714] <TB2> INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[10:06:27.714] <TB2> INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[10:06:27.714] <TB2> INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[10:06:27.714] <TB2> INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[10:06:28.116] <TB2> INFO: enter 'restricted' command line mode
[10:06:28.116] <TB2> INFO: enter test to run
[10:06:28.116] <TB2> INFO: test: HighRate no parameter change
[10:06:28.116] <TB2> INFO: running: highrate
[10:06:28.117] <TB2> INFO: readGainPedestalParameters /usr/local/data/M2086_XrayQualification_2015-08-28_10h32m_1440750764//014_HREfficiency_200//phCalibrationFitErr35_C0.dat .. /usr/local/data/M2086_XrayQualification_2015-08-28_10h32m_1440750764//014_HREfficiency_200//phCalibrationFitErr35_C15.dat
[10:06:28.266] <TB2> INFO: ----------------------------------------------------------------------
[10:06:28.266] <TB2> INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[10:06:28.266] <TB2> INFO: ----------------------------------------------------------------------
[10:06:28.898] <TB2> INFO: Expecting 208000 events.
[10:06:54.298] <TB2> INFO: 208000 events read in total (24869ms).
[10:06:54.317] <TB2> INFO: Test took 26044ms.
[10:06:55.479] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[10:06:56.084] <TB2> INFO: number of dead pixels (per ROC): 2 1 0 0 0 0 0 0 1 0 0 0 0 0 0 0
[10:06:56.084] <TB2> INFO: number of red-efficiency pixels: 1541 1810 1483 3172 3161 3898 3931 3844 3848 3757 3977 3554 3094 1535 2101 1852
[10:06:56.084] <TB2> INFO: number of X-ray hits detected: 432920 484341 436131 698365 697407 866655 877089 823227 831519 849572 903214 759772 673267 425902 452156 471048
[10:06:56.084] <TB2> INFO: number of triggers sent (total per ROC): 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000
[10:06:56.084] <TB2> INFO: number of Vcal hits detected: 205670 205274 203367 199792 199550 192145 189180 195072 194455 193396 190083 196412 200508 203897 204736 205194
[10:06:56.085] <TB2> INFO: Vcal hit fiducial efficiency (%): 99.0 98.8 98.3 96.5 96.5 93.3 92.1 94.3 94.1 93.8 92.4 95.1 96.8 98.4 98.6 98.8
[10:06:56.085] <TB2> INFO: Vcal hit overall efficiency (%): 98.9 98.7 97.8 96.1 95.9 92.4 91.0 93.8 93.5 93.0 91.4 94.4 96.4 98.0 98.4 98.7
[10:06:56.085] <TB2> INFO: X-ray hit rate [MHz/cm2]: 126.9 142.0 127.8 204.7 204.4 254.0 257.1 241.3 243.7 249.0 264.7 222.7 197.3 124.8 132.5 138.1
[10:06:56.085] <TB2> INFO: PixTestHighRate::doXPixelAlive() done
[10:06:56.129] <TB2> INFO: PixTest:: pg_setup set to default.
[10:06:56.180] <TB2> INFO: enter test to run
[10:06:56.180] <TB2> INFO: test: no parameter change
[10:06:56.365] <TB2> QUIET: Connection to board 162 closed.
[10:06:56.445] <TB2> INFO: pXar: this is the end, my friend
commander_HREfficiency.log
[10:07:29.951] <TB2> INFO: *** Welcome to pxar ***
[10:07:29.951] <TB2> INFO: *** Today: 2015/08/28
[10:07:29.951] <TB2> INFO: readRocDacs: /usr/local/data/M2086_XrayQualification_2015-08-28_10h32m_1440750764//015_HREfficiency_250//dacParameters35_C0.dat .. /usr/local/data/M2086_XrayQualification_2015-08-28_10h32m_1440750764//015_HREfficiency_250//dacParameters35_C15.dat
[10:07:29.953] <TB2> INFO: readTbmDacs: /usr/local/data/M2086_XrayQualification_2015-08-28_10h32m_1440750764//015_HREfficiency_250//tbmParameters_C0a.dat .. /usr/local/data/M2086_XrayQualification_2015-08-28_10h32m_1440750764//015_HREfficiency_250//tbmParameters_C0b.dat
[10:07:29.953] <TB2> INFO: readMaskFile: /usr/local/data/M2086_XrayQualification_2015-08-28_10h32m_1440750764//015_HREfficiency_250//defaultMaskFile.dat
[10:07:29.953] <TB2> INFO: readTrimFile: /usr/local/data/M2086_XrayQualification_2015-08-28_10h32m_1440750764//015_HREfficiency_250//trimParameters35_C0.dat .. /usr/local/data/M2086_XrayQualification_2015-08-28_10h32m_1440750764//015_HREfficiency_250//trimParameters35_C15.dat
[10:07:30.031] <TB2> INFO: clk: 4
[10:07:30.031] <TB2> INFO: ctr: 4
[10:07:30.031] <TB2> INFO: sda: 19
[10:07:30.031] <TB2> INFO: tin: 9
[10:07:30.031] <TB2> INFO: level: 15
[10:07:30.031] <TB2> INFO: triggerdelay: 0
[10:07:30.031] <TB2> QUIET: Instanciating API for pxar v2.5+57~g44d0777
[10:07:30.031] <TB2> INFO: Log level: INFO
[10:07:30.044] <TB2> INFO: Found DTB DTB_WXENWR
[10:07:30.057] <TB2> QUIET: Connection to board DTB_WXENWR opened.
[10:07:30.060] <TB2> INFO: DTB startup information
--- DTB info------------------------------------------
Board id: 162
HW version: DTB1.2
FW version: 4.2
SW version: 4.4
USB id: DTB_WXENWR
MAC address: 40D8551180A2
Hostname: pixelDTB162
Comment:
------------------------------------------------------
[10:07:30.063] <TB2> INFO: RPC call hashes of host and DTB match: 397073690
[10:07:31.595] <TB2> INFO: DUT info:
[10:07:31.595] <TB2> INFO: The DUT currently contains the following objects:
[10:07:31.595] <TB2> INFO: 2 TBM Cores tbm09c (2 ON)
[10:07:31.595] <TB2> INFO: TBM Core alpha (0): 7 registers set
[10:07:31.595] <TB2> INFO: TBM Core beta (1): 7 registers set
[10:07:31.595] <TB2> INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[10:07:31.595] <TB2> INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[10:07:31.595] <TB2> INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[10:07:31.595] <TB2> INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[10:07:31.595] <TB2> INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[10:07:31.595] <TB2> INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[10:07:31.595] <TB2> INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[10:07:31.595] <TB2> INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[10:07:31.595] <TB2> INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[10:07:31.595] <TB2> INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[10:07:31.595] <TB2> INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[10:07:31.595] <TB2> INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[10:07:31.595] <TB2> INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[10:07:31.595] <TB2> INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[10:07:31.595] <TB2> INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[10:07:31.595] <TB2> INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[10:07:31.596] <TB2> INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[10:07:31.997] <TB2> INFO: enter 'restricted' command line mode
[10:07:31.997] <TB2> INFO: enter test to run
[10:07:31.997] <TB2> INFO: test: HighRate no parameter change
[10:07:31.997] <TB2> INFO: running: highrate
[10:07:31.997] <TB2> INFO: readGainPedestalParameters /usr/local/data/M2086_XrayQualification_2015-08-28_10h32m_1440750764//015_HREfficiency_250//phCalibrationFitErr35_C0.dat .. /usr/local/data/M2086_XrayQualification_2015-08-28_10h32m_1440750764//015_HREfficiency_250//phCalibrationFitErr35_C15.dat
[10:07:32.140] <TB2> INFO: ----------------------------------------------------------------------
[10:07:32.140] <TB2> INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[10:07:32.140] <TB2> INFO: ----------------------------------------------------------------------
[10:07:32.758] <TB2> INFO: Expecting 208000 events.
[10:07:58.940] <TB2> INFO: 208000 events read in total (25651ms).
[10:07:58.958] <TB2> INFO: Test took 26811ms.
[10:08:00.160] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[10:08:00.763] <TB2> INFO: number of dead pixels (per ROC): 2 1 0 0 0 0 0 0 1 0 0 0 0 0 0 0
[10:08:00.764] <TB2> INFO: number of red-efficiency pixels: 1563 2000 1576 3223 3282 3978 3968 3919 3935 3892 4023 3574 3158 1666 2151 1986
[10:08:00.764] <TB2> INFO: number of X-ray hits detected: 444905 498030 452330 717699 720018 890158 905633 849879 859458 873650 920207 780781 691763 440733 467422 483034
[10:08:00.764] <TB2> INFO: number of triggers sent (total per ROC): 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000
[10:08:00.764] <TB2> INFO: number of Vcal hits detected: 205607 204949 203124 199338 198706 191113 187800 193935 193070 191886 189135 195809 200231 203463 204555 204915
[10:08:00.764] <TB2> INFO: Vcal hit fiducial efficiency (%): 99.0 98.7 98.2 96.3 96.1 92.8 91.4 93.8 93.5 93.1 91.9 94.9 96.7 98.2 98.5 98.7
[10:08:00.764] <TB2> INFO: Vcal hit overall efficiency (%): 98.8 98.5 97.7 95.8 95.5 91.9 90.3 93.2 92.8 92.3 90.9 94.1 96.3 97.8 98.3 98.5
[10:08:00.764] <TB2> INFO: X-ray hit rate [MHz/cm2]: 130.4 146.0 132.6 210.4 211.0 260.9 265.4 249.1 251.9 256.1 269.7 228.9 202.8 129.2 137.0 141.6
[10:08:00.764] <TB2> INFO: PixTestHighRate::doXPixelAlive() done
[10:08:00.809] <TB2> INFO: PixTest:: pg_setup set to default.
[10:08:00.849] <TB2> INFO: enter test to run
[10:08:00.849] <TB2> INFO: test: no parameter change
[10:08:01.018] <TB2> QUIET: Connection to board 162 closed.
[10:08:01.101] <TB2> INFO: pXar: this is the end, my friend