[08:33:36.718] <TB3> INFO: *** Welcome to pxar ***
[08:33:36.718] <TB3> INFO: *** Today: 2015/08/28
[08:33:36.718] <TB3> INFO: readRocDacs: /usr/local/data/M2087_XrayQualification_2015-08-28_10h32m_1440750764//000_PixelAlive_p17//dacParameters35_C0.dat .. /usr/local/data/M2087_XrayQualification_2015-08-28_10h32m_1440750764//000_PixelAlive_p17//dacParameters35_C15.dat
[08:33:36.719] <TB3> INFO: readTbmDacs: /usr/local/data/M2087_XrayQualification_2015-08-28_10h32m_1440750764//000_PixelAlive_p17//tbmParameters_C0a.dat .. /usr/local/data/M2087_XrayQualification_2015-08-28_10h32m_1440750764//000_PixelAlive_p17//tbmParameters_C0b.dat
[08:33:36.719] <TB3> INFO: readMaskFile: /usr/local/data/M2087_XrayQualification_2015-08-28_10h32m_1440750764//000_PixelAlive_p17//defaultMaskFile.dat
[08:33:36.720] <TB3> INFO: readTrimFile: /usr/local/data/M2087_XrayQualification_2015-08-28_10h32m_1440750764//000_PixelAlive_p17//trimParameters35_C0.dat .. /usr/local/data/M2087_XrayQualification_2015-08-28_10h32m_1440750764//000_PixelAlive_p17//trimParameters35_C15.dat
[08:33:36.796] <TB3> INFO: clk: 4
[08:33:36.796] <TB3> INFO: ctr: 4
[08:33:36.796] <TB3> INFO: sda: 19
[08:33:36.796] <TB3> INFO: tin: 9
[08:33:36.796] <TB3> INFO: level: 15
[08:33:36.796] <TB3> INFO: triggerdelay: 0
[08:33:36.796] <TB3> QUIET: Instanciating API for pxar v2.5+57~g44d0777
[08:33:36.796] <TB3> INFO: Log level: INFO
[08:33:36.808] <TB3> INFO: Found DTB DTB_WWV6Z5
[08:33:36.822] <TB3> QUIET: Connection to board DTB_WWV6Z5 opened.
[08:33:36.825] <TB3> INFO: DTB startup information
--- DTB info------------------------------------------
Board id: 121
HW version: DTB1.2
FW version: 4.2
SW version: 4.4
USB id: DTB_WWV6Z5
MAC address: 40D855118079
Hostname: pixelDTB121
Comment:
------------------------------------------------------
[08:33:36.828] <TB3> INFO: RPC call hashes of host and DTB match: 397073690
[08:33:38.349] <TB3> INFO: DUT info:
[08:33:38.349] <TB3> INFO: The DUT currently contains the following objects:
[08:33:38.349] <TB3> INFO: 2 TBM Cores tbm09c (2 ON)
[08:33:38.349] <TB3> INFO: TBM Core alpha (0): 7 registers set
[08:33:38.349] <TB3> INFO: TBM Core beta (1): 7 registers set
[08:33:38.349] <TB3> INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[08:33:38.349] <TB3> INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[08:33:38.349] <TB3> INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[08:33:38.349] <TB3> INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[08:33:38.349] <TB3> INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[08:33:38.349] <TB3> INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[08:33:38.349] <TB3> INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[08:33:38.349] <TB3> INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[08:33:38.349] <TB3> INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[08:33:38.349] <TB3> INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[08:33:38.350] <TB3> INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[08:33:38.350] <TB3> INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[08:33:38.350] <TB3> INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[08:33:38.350] <TB3> INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[08:33:38.350] <TB3> INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[08:33:38.350] <TB3> INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[08:33:38.350] <TB3> INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[08:33:38.751] <TB3> INFO: enter 'restricted' command line mode
[08:33:38.751] <TB3> INFO: enter test to run
[08:33:38.751] <TB3> INFO: test: PixelAlive no parameter change
[08:33:38.751] <TB3> INFO: running: pixelalive
[08:33:38.759] <TB3> INFO: ######################################################################
[08:33:38.760] <TB3> INFO: PixTestAlive::doTest()
[08:33:38.760] <TB3> INFO: ######################################################################
[08:33:38.761] <TB3> INFO: ----------------------------------------------------------------------
[08:33:38.761] <TB3> 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:38.761] <TB3> INFO: ----------------------------------------------------------------------
[08:33:39.094] <TB3> INFO: Expecting 41600 events.
[08:33:43.847] <TB3> INFO: 41600 events read in total (4034ms).
[08:33:43.926] <TB3> INFO: Test took 5163ms.
[08:33:43.933] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:33:44.181] <TB3> INFO: PixTestAlive::aliveTest() done
[08:33:44.181] <TB3> INFO: number of dead pixels (per ROC): 1 0 0 2 0 1 0 0 0 0 0 0 0 0 0 0
[08:33:44.183] <TB3> INFO: ----------------------------------------------------------------------
[08:33:44.183] <TB3> 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:44.183] <TB3> INFO: ----------------------------------------------------------------------
[08:33:44.484] <TB3> INFO: Expecting 41600 events.
[08:33:47.863] <TB3> INFO: 41600 events read in total (2663ms).
[08:33:47.863] <TB3> INFO: Test took 3679ms.
[08:33:47.863] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:33:47.864] <TB3> INFO: mask vs. old pixelAlive PixelAlive_C0_V0 .. PixelAlive_C15_V0
[08:33:48.174] <TB3> INFO: PixTestAlive::maskTest() done
[08:33:48.174] <TB3> 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:48.175] <TB3> INFO: ----------------------------------------------------------------------
[08:33:48.182] <TB3> 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:48.182] <TB3> INFO: ----------------------------------------------------------------------
[08:33:48.487] <TB3> INFO: Expecting 41600 events.
[08:33:53.249] <TB3> INFO: 41600 events read in total (4045ms).
[08:33:53.250] <TB3> INFO: Test took 5067ms.
[08:33:53.266] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:33:53.545] <TB3> INFO: PixTestAlive::addressDecodingTest() done
[08:33:53.545] <TB3> 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:53.545] <TB3> INFO: PixTestAlive::doTest() done, duration: 14 seconds
[08:33:53.577] <TB3> INFO: enter test to run
[08:33:53.577] <TB3> INFO: test: no parameter change
[08:33:53.704] <TB3> QUIET: Connection to board 121 closed.
[08:33:53.783] <TB3> INFO: pXar: this is the end, my friend

[08:39:10.455] <TB3> INFO: *** Welcome to pxar ***
[08:39:10.455] <TB3> INFO: *** Today: 2015/08/28
[08:39:10.455] <TB3> INFO: readRocDacs: /usr/local/data/M2087_XrayQualification_2015-08-28_10h32m_1440750764//002_RetrimHotPixels_150//dacParameters35_C0.dat .. /usr/local/data/M2087_XrayQualification_2015-08-28_10h32m_1440750764//002_RetrimHotPixels_150//dacParameters35_C15.dat
[08:39:10.456] <TB3> INFO: readTbmDacs: /usr/local/data/M2087_XrayQualification_2015-08-28_10h32m_1440750764//002_RetrimHotPixels_150//tbmParameters_C0a.dat .. /usr/local/data/M2087_XrayQualification_2015-08-28_10h32m_1440750764//002_RetrimHotPixels_150//tbmParameters_C0b.dat
[08:39:10.456] <TB3> INFO: readMaskFile: /usr/local/data/M2087_XrayQualification_2015-08-28_10h32m_1440750764//002_RetrimHotPixels_150//defaultMaskFile.dat
[08:39:10.457] <TB3> INFO: readTrimFile: /usr/local/data/M2087_XrayQualification_2015-08-28_10h32m_1440750764//002_RetrimHotPixels_150//trimParameters35_C0.dat .. /usr/local/data/M2087_XrayQualification_2015-08-28_10h32m_1440750764//002_RetrimHotPixels_150//trimParameters35_C15.dat
[08:39:10.534] <TB3> INFO: clk: 4
[08:39:10.534] <TB3> INFO: ctr: 4
[08:39:10.534] <TB3> INFO: sda: 19
[08:39:10.534] <TB3> INFO: tin: 9
[08:39:10.534] <TB3> INFO: level: 15
[08:39:10.534] <TB3> INFO: triggerdelay: 0
[08:39:10.534] <TB3> QUIET: Instanciating API for pxar v2.5+57~g44d0777
[08:39:10.534] <TB3> INFO: Log level: INFO
[08:39:10.548] <TB3> INFO: Found DTB DTB_WWV6Z5
[08:39:10.559] <TB3> QUIET: Connection to board DTB_WWV6Z5 opened.
[08:39:10.563] <TB3> INFO: DTB startup information
--- DTB info------------------------------------------
Board id: 121
HW version: DTB1.2
FW version: 4.2
SW version: 4.4
USB id: DTB_WWV6Z5
MAC address: 40D855118079
Hostname: pixelDTB121
Comment:
------------------------------------------------------
[08:39:10.565] <TB3> INFO: RPC call hashes of host and DTB match: 397073690
[08:39:12.097] <TB3> INFO: DUT info:
[08:39:12.097] <TB3> INFO: The DUT currently contains the following objects:
[08:39:12.097] <TB3> INFO: 2 TBM Cores tbm09c (2 ON)
[08:39:12.097] <TB3> INFO: TBM Core alpha (0): 7 registers set
[08:39:12.097] <TB3> INFO: TBM Core beta (1): 7 registers set
[08:39:12.097] <TB3> INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[08:39:12.097] <TB3> INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[08:39:12.098] <TB3> INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[08:39:12.098] <TB3> INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[08:39:12.098] <TB3> INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[08:39:12.098] <TB3> INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[08:39:12.098] <TB3> INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[08:39:12.098] <TB3> INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[08:39:12.098] <TB3> INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[08:39:12.098] <TB3> INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[08:39:12.098] <TB3> INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[08:39:12.098] <TB3> INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[08:39:12.098] <TB3> INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[08:39:12.098] <TB3> INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[08:39:12.098] <TB3> INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[08:39:12.098] <TB3> INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[08:39:12.098] <TB3> INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[08:39:12.499] <TB3> INFO: enter 'restricted' command line mode
[08:39:12.499] <TB3> INFO: enter test to run
[08:39:12.499] <TB3> INFO: test: delay setting parameters: ->5<-
[08:39:12.499] <TB3> INFO: delay test by 5 seconds...
[08:39:17.499] <TB3> INFO: enter test to run
[08:39:17.499] <TB3> INFO: test: highrate no parameter change
[08:39:17.499] <TB3> INFO: running: highrate
[08:39:17.500] <TB3> INFO: readGainPedestalParameters /usr/local/data/M2087_XrayQualification_2015-08-28_10h32m_1440750764//002_RetrimHotPixels_150//phCalibrationFitErr35_C0.dat .. /usr/local/data/M2087_XrayQualification_2015-08-28_10h32m_1440750764//002_RetrimHotPixels_150//phCalibrationFitErr35_C15.dat
[08:39:17.658] <TB3> INFO: ----------------------------------------------------------------------
[08:39:17.658] <TB3> INFO: PixTest::trimHotPixels() running for 1 seconds with 100 kHz trigger rate
[08:39:17.658] <TB3> INFO: ----------------------------------------------------------------------
[08:39:17.658] <TB3> INFO: THR = 150, corresponding to ~ 400 MHz/cm2
[08:39:17.658] <TB3> INFO: edge/corner pixel THR is adjusted
[08:39:17.658] <TB3> INFO: PixTestHighRate::trimHotPixels: step 0...
[08:39:18.618] <TB3> INFO: Collecting data for 1 seconds...
[08:39:19.620] <TB3> INFO: Done with hot pixel readout
[08:39:26.132] <TB3> INFO: PixTest:: pg_setup set to default.
[08:39:26.133] <TB3> INFO: 4 hot pixels found in step 0
[08:39:27.097] <TB3> INFO: Collecting data for 1 seconds...
[08:39:28.099] <TB3> INFO: Done with hot pixel readout
[08:39:34.604] <TB3> INFO: PixTest:: pg_setup set to default.
[08:39:34.606] <TB3> INFO: 4 hot pixels found in step 1
[08:39:35.571] <TB3> INFO: Collecting data for 1 seconds...
[08:39:36.573] <TB3> INFO: Done with hot pixel readout
[08:39:43.050] <TB3> INFO: PixTest:: pg_setup set to default.
[08:39:43.051] <TB3> INFO: 2 hot pixels found in step 2
[08:39:44.019] <TB3> INFO: Collecting data for 1 seconds...
[08:39:45.021] <TB3> INFO: Done with hot pixel readout
[08:39:50.795] <TB3> INFO: PixTest:: pg_setup set to default.
[08:39:50.796] <TB3> INFO: 2 hot pixels found in step 3
[08:39:51.768] <TB3> INFO: Collecting data for 1 seconds...
[08:39:52.769] <TB3> INFO: Done with hot pixel readout
[08:39:58.635] <TB3> INFO: PixTest:: pg_setup set to default.
[08:39:58.636] <TB3> INFO: 1 hot pixels found in step 4
[08:39:59.599] <TB3> INFO: Collecting data for 1 seconds...
[08:40:00.600] <TB3> INFO: Done with hot pixel readout
[08:40:06.410] <TB3> INFO: PixTest:: pg_setup set to default.
[08:40:06.411] <TB3> INFO: 0 hot pixels found in step 5
[08:40:06.422] <TB3> INFO: 0 hot pixels could not be trimmed and have been masked.
[08:40:06.450] <TB3> INFO: PixTest::trimHotPixels() done
[08:40:06.450] <TB3> INFO: write trim parameters into /usr/local/data/M2087_XrayQualification_2015-08-28_10h32m_1440750764//002_RetrimHotPixels_150//trimParameters35_C0.dat
[08:40:06.456] <TB3> INFO: write trim parameters into /usr/local/data/M2087_XrayQualification_2015-08-28_10h32m_1440750764//002_RetrimHotPixels_150//trimParameters35_C1.dat
[08:40:06.462] <TB3> INFO: write trim parameters into /usr/local/data/M2087_XrayQualification_2015-08-28_10h32m_1440750764//002_RetrimHotPixels_150//trimParameters35_C2.dat
[08:40:06.468] <TB3> INFO: write trim parameters into /usr/local/data/M2087_XrayQualification_2015-08-28_10h32m_1440750764//002_RetrimHotPixels_150//trimParameters35_C3.dat
[08:40:06.474] <TB3> INFO: write trim parameters into /usr/local/data/M2087_XrayQualification_2015-08-28_10h32m_1440750764//002_RetrimHotPixels_150//trimParameters35_C4.dat
[08:40:06.480] <TB3> INFO: write trim parameters into /usr/local/data/M2087_XrayQualification_2015-08-28_10h32m_1440750764//002_RetrimHotPixels_150//trimParameters35_C5.dat
[08:40:06.486] <TB3> INFO: write trim parameters into /usr/local/data/M2087_XrayQualification_2015-08-28_10h32m_1440750764//002_RetrimHotPixels_150//trimParameters35_C6.dat
[08:40:06.492] <TB3> INFO: write trim parameters into /usr/local/data/M2087_XrayQualification_2015-08-28_10h32m_1440750764//002_RetrimHotPixels_150//trimParameters35_C7.dat
[08:40:06.498] <TB3> INFO: write trim parameters into /usr/local/data/M2087_XrayQualification_2015-08-28_10h32m_1440750764//002_RetrimHotPixels_150//trimParameters35_C8.dat
[08:40:06.504] <TB3> INFO: write trim parameters into /usr/local/data/M2087_XrayQualification_2015-08-28_10h32m_1440750764//002_RetrimHotPixels_150//trimParameters35_C9.dat
[08:40:06.509] <TB3> INFO: write trim parameters into /usr/local/data/M2087_XrayQualification_2015-08-28_10h32m_1440750764//002_RetrimHotPixels_150//trimParameters35_C10.dat
[08:40:06.516] <TB3> INFO: write trim parameters into /usr/local/data/M2087_XrayQualification_2015-08-28_10h32m_1440750764//002_RetrimHotPixels_150//trimParameters35_C11.dat
[08:40:06.521] <TB3> INFO: write trim parameters into /usr/local/data/M2087_XrayQualification_2015-08-28_10h32m_1440750764//002_RetrimHotPixels_150//trimParameters35_C12.dat
[08:40:06.527] <TB3> INFO: write trim parameters into /usr/local/data/M2087_XrayQualification_2015-08-28_10h32m_1440750764//002_RetrimHotPixels_150//trimParameters35_C13.dat
[08:40:06.533] <TB3> INFO: write trim parameters into /usr/local/data/M2087_XrayQualification_2015-08-28_10h32m_1440750764//002_RetrimHotPixels_150//trimParameters35_C14.dat
[08:40:06.539] <TB3> INFO: write trim parameters into /usr/local/data/M2087_XrayQualification_2015-08-28_10h32m_1440750764//002_RetrimHotPixels_150//trimParameters35_C15.dat
[08:40:06.545] <TB3> INFO: write masked pixels into /usr/local/data/M2087_XrayQualification_2015-08-28_10h32m_1440750764//002_RetrimHotPixels_150//defaultMaskFile.dat
[08:40:06.577] <TB3> INFO: enter test to run
[08:40:06.577] <TB3> INFO: test: no parameter change
[08:40:06.756] <TB3> QUIET: Connection to board 121 closed.
[08:40:06.835] <TB3> INFO: pXar: this is the end, my friend

[08:40:42.740] <TB3> INFO: *** Welcome to pxar ***
[08:40:42.740] <TB3> INFO: *** Today: 2015/08/28
[08:40:42.740] <TB3> INFO: readRocDacs: /usr/local/data/M2087_XrayQualification_2015-08-28_10h32m_1440750764//003_HRData_50//dacParameters35_C0.dat .. /usr/local/data/M2087_XrayQualification_2015-08-28_10h32m_1440750764//003_HRData_50//dacParameters35_C15.dat
[08:40:42.742] <TB3> INFO: readTbmDacs: /usr/local/data/M2087_XrayQualification_2015-08-28_10h32m_1440750764//003_HRData_50//tbmParameters_C0a.dat .. /usr/local/data/M2087_XrayQualification_2015-08-28_10h32m_1440750764//003_HRData_50//tbmParameters_C0b.dat
[08:40:42.742] <TB3> INFO: readMaskFile: /usr/local/data/M2087_XrayQualification_2015-08-28_10h32m_1440750764//003_HRData_50//defaultMaskFile.dat
[08:40:42.742] <TB3> INFO: readTrimFile: /usr/local/data/M2087_XrayQualification_2015-08-28_10h32m_1440750764//003_HRData_50//trimParameters35_C0.dat .. /usr/local/data/M2087_XrayQualification_2015-08-28_10h32m_1440750764//003_HRData_50//trimParameters35_C15.dat
[08:40:42.817] <TB3> INFO: clk: 4
[08:40:42.817] <TB3> INFO: ctr: 4
[08:40:42.817] <TB3> INFO: sda: 19
[08:40:42.817] <TB3> INFO: tin: 9
[08:40:42.817] <TB3> INFO: level: 15
[08:40:42.817] <TB3> INFO: triggerdelay: 0
[08:40:42.817] <TB3> QUIET: Instanciating API for pxar v2.5+57~g44d0777
[08:40:42.817] <TB3> INFO: Log level: INFO
[08:40:42.831] <TB3> INFO: Found DTB DTB_WWV6Z5
[08:40:42.842] <TB3> QUIET: Connection to board DTB_WWV6Z5 opened.
[08:40:42.845] <TB3> INFO: DTB startup information
--- DTB info------------------------------------------
Board id: 121
HW version: DTB1.2
FW version: 4.2
SW version: 4.4
USB id: DTB_WWV6Z5
MAC address: 40D855118079
Hostname: pixelDTB121
Comment:
------------------------------------------------------
[08:40:42.848] <TB3> INFO: RPC call hashes of host and DTB match: 397073690
[08:40:44.373] <TB3> INFO: DUT info:
[08:40:44.373] <TB3> INFO: The DUT currently contains the following objects:
[08:40:44.373] <TB3> INFO: 2 TBM Cores tbm09c (2 ON)
[08:40:44.373] <TB3> INFO: TBM Core alpha (0): 7 registers set
[08:40:44.373] <TB3> INFO: TBM Core beta (1): 7 registers set
[08:40:44.373] <TB3> INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[08:40:44.373] <TB3> INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[08:40:44.373] <TB3> INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[08:40:44.373] <TB3> INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[08:40:44.373] <TB3> INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[08:40:44.373] <TB3> INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[08:40:44.373] <TB3> INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[08:40:44.373] <TB3> INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[08:40:44.373] <TB3> INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[08:40:44.373] <TB3> INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[08:40:44.373] <TB3> INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[08:40:44.373] <TB3> INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[08:40:44.373] <TB3> INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[08:40:44.373] <TB3> INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[08:40:44.373] <TB3> INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[08:40:44.373] <TB3> INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[08:40:44.373] <TB3> INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[08:40:44.774] <TB3> INFO: enter 'restricted' command line mode
[08:40:44.774] <TB3> INFO: enter test to run
[08:40:44.774] <TB3> INFO: test: delay setting parameters: ->5<-
[08:40:44.774] <TB3> INFO: delay test by 5 seconds...
[08:40:49.774] <TB3> INFO: enter test to run
[08:40:49.774] <TB3> INFO: test: Xray no parameter change
[08:40:49.775] <TB3> INFO: running: xray
[08:40:49.775] <TB3> INFO: readGainPedestalParameters /usr/local/data/M2087_XrayQualification_2015-08-28_10h32m_1440750764//003_HRData_50//phCalibrationFitErr35_C0.dat .. /usr/local/data/M2087_XrayQualification_2015-08-28_10h32m_1440750764//003_HRData_50//phCalibrationFitErr35_C15.dat
[08:40:49.915] <TB3> INFO: ######################################################################
[08:40:49.915] <TB3> INFO: PixTestXray::doTest()
[08:40:49.915] <TB3> INFO: ######################################################################
[08:40:49.915] <TB3> INFO: ----------------------------------------------------------------------
[08:40:49.915] <TB3> INFO: PixTestXray::doPhRun() fParRunSeconds = 100
[08:40:49.915] <TB3> INFO: ----------------------------------------------------------------------
[08:40:50.883] <TB3> INFO: PixTestXray::doPhRun start TriggerLoop with trigger frequency 100 kHz, period 405 and duration 100 seconds, fEventsMax = 10000000
[08:40:57.689] <TB3> INFO: run duration 6 seconds, buffer almost full (81%), pausing triggers.
[08:41:23.913] <TB3> INFO: Resuming triggers.
[08:41:30.728] <TB3> INFO: run duration 13 seconds, buffer almost full (81%), pausing triggers.
[08:41:57.167] <TB3> INFO: Resuming triggers.
[08:42:03.977] <TB3> INFO: run duration 20 seconds, buffer almost full (81%), pausing triggers.
[08:42:29.489] <TB3> INFO: Resuming triggers.
[08:42:36.300] <TB3> INFO: run duration 27 seconds, buffer almost full (81%), pausing triggers.
[08:43:01.716] <TB3> INFO: Resuming triggers.
[08:43:08.523] <TB3> INFO: run duration 34 seconds, buffer almost full (81%), pausing triggers.
[08:43:34.265] <TB3> INFO: Resuming triggers.
[08:43:41.073] <TB3> INFO: run duration 40 seconds, buffer almost full (81%), pausing triggers.
[08:44:06.080] <TB3> INFO: Resuming triggers.
[08:44:12.888] <TB3> INFO: run duration 47 seconds, buffer almost full (81%), pausing triggers.
[08:44:39.008] <TB3> INFO: Resuming triggers.
[08:44:45.813] <TB3> INFO: run duration 54 seconds, buffer almost full (81%), pausing triggers.
[08:45:12.057] <TB3> INFO: Resuming triggers.
[08:45:18.863] <TB3> INFO: run duration 61 seconds, buffer almost full (81%), pausing triggers.
[08:45:44.393] <TB3> INFO: Resuming triggers.
[08:45:51.197] <TB3> INFO: run duration 67 seconds, buffer almost full (81%), pausing triggers.
[08:46:18.234] <TB3> INFO: Resuming triggers.
[08:46:25.034] <TB3> INFO: run duration 74 seconds, buffer almost full (81%), pausing triggers.
[08:46:52.889] <TB3> INFO: Resuming triggers.
[08:46:59.691] <TB3> INFO: run duration 81 seconds, buffer almost full (81%), pausing triggers.
[08:47:25.896] <TB3> INFO: Resuming triggers.
[08:47:32.695] <TB3> INFO: run duration 88 seconds, buffer almost full (81%), pausing triggers.
[08:47:58.912] <TB3> INFO: Resuming triggers.
[08:48:05.714] <TB3> INFO: run duration 95 seconds, buffer almost full (81%), pausing triggers.
[08:48:32.290] <TB3> INFO: Resuming triggers.
[08:48:37.135] <TB3> INFO: data taking finished, elapsed time: 100 seconds.
[08:48:55.874] <TB3> INFO: PixTest:: pg_setup set to default.
[08:48:55.876] <TB3> INFO: PixTestXray::doPhRun() done
[08:48:55.876] <TB3> INFO: PixTestXray::doTest() done
[08:48:56.106] <TB3> INFO: enter test to run
[08:48:56.106] <TB3> INFO: test: no parameter change
[08:48:56.319] <TB3> QUIET: Connection to board 121 closed.
[08:48:56.399] <TB3> INFO: pXar: this is the end, my friend

[08:50:38.254] <TB3> INFO: *** Welcome to pxar ***
[08:50:38.254] <TB3> INFO: *** Today: 2015/08/28
[08:50:38.254] <TB3> INFO: readRocDacs: /usr/local/data/M2087_XrayQualification_2015-08-28_10h32m_1440750764//004_HRData_150//dacParameters35_C0.dat .. /usr/local/data/M2087_XrayQualification_2015-08-28_10h32m_1440750764//004_HRData_150//dacParameters35_C15.dat
[08:50:38.255] <TB3> INFO: readTbmDacs: /usr/local/data/M2087_XrayQualification_2015-08-28_10h32m_1440750764//004_HRData_150//tbmParameters_C0a.dat .. /usr/local/data/M2087_XrayQualification_2015-08-28_10h32m_1440750764//004_HRData_150//tbmParameters_C0b.dat
[08:50:38.255] <TB3> INFO: readMaskFile: /usr/local/data/M2087_XrayQualification_2015-08-28_10h32m_1440750764//004_HRData_150//defaultMaskFile.dat
[08:50:38.255] <TB3> INFO: readTrimFile: /usr/local/data/M2087_XrayQualification_2015-08-28_10h32m_1440750764//004_HRData_150//trimParameters35_C0.dat .. /usr/local/data/M2087_XrayQualification_2015-08-28_10h32m_1440750764//004_HRData_150//trimParameters35_C15.dat
[08:50:38.330] <TB3> INFO: clk: 4
[08:50:38.330] <TB3> INFO: ctr: 4
[08:50:38.330] <TB3> INFO: sda: 19
[08:50:38.330] <TB3> INFO: tin: 9
[08:50:38.330] <TB3> INFO: level: 15
[08:50:38.330] <TB3> INFO: triggerdelay: 0
[08:50:38.330] <TB3> QUIET: Instanciating API for pxar v2.5+57~g44d0777
[08:50:38.330] <TB3> INFO: Log level: INFO
[08:50:38.342] <TB3> INFO: Found DTB DTB_WWV6Z5
[08:50:38.353] <TB3> QUIET: Connection to board DTB_WWV6Z5 opened.
[08:50:38.356] <TB3> INFO: DTB startup information
--- DTB info------------------------------------------
Board id: 121
HW version: DTB1.2
FW version: 4.2
SW version: 4.4
USB id: DTB_WWV6Z5
MAC address: 40D855118079
Hostname: pixelDTB121
Comment:
------------------------------------------------------
[08:50:38.359] <TB3> INFO: RPC call hashes of host and DTB match: 397073690
[08:50:39.899] <TB3> INFO: DUT info:
[08:50:39.899] <TB3> INFO: The DUT currently contains the following objects:
[08:50:39.899] <TB3> INFO: 2 TBM Cores tbm09c (2 ON)
[08:50:39.899] <TB3> INFO: TBM Core alpha (0): 7 registers set
[08:50:39.899] <TB3> INFO: TBM Core beta (1): 7 registers set
[08:50:39.899] <TB3> INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[08:50:39.899] <TB3> INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[08:50:39.899] <TB3> INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[08:50:39.899] <TB3> INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[08:50:39.899] <TB3> INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[08:50:39.899] <TB3> INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[08:50:39.899] <TB3> INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[08:50:39.899] <TB3> INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[08:50:39.900] <TB3> INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[08:50:39.900] <TB3> INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[08:50:39.900] <TB3> INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[08:50:39.900] <TB3> INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[08:50:39.900] <TB3> INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[08:50:39.900] <TB3> INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[08:50:39.900] <TB3> INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[08:50:39.900] <TB3> INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[08:50:39.901] <TB3> INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[08:50:40.302] <TB3> INFO: enter 'restricted' command line mode
[08:50:40.302] <TB3> INFO: enter test to run
[08:50:40.302] <TB3> INFO: test: delay setting parameters: ->5<-
[08:50:40.302] <TB3> INFO: delay test by 5 seconds...
[08:50:45.302] <TB3> INFO: enter test to run
[08:50:45.302] <TB3> INFO: test: Xray no parameter change
[08:50:45.302] <TB3> INFO: running: xray
[08:50:45.302] <TB3> INFO: readGainPedestalParameters /usr/local/data/M2087_XrayQualification_2015-08-28_10h32m_1440750764//004_HRData_150//phCalibrationFitErr35_C0.dat .. /usr/local/data/M2087_XrayQualification_2015-08-28_10h32m_1440750764//004_HRData_150//phCalibrationFitErr35_C15.dat
[08:50:45.451] <TB3> INFO: ######################################################################
[08:50:45.451] <TB3> INFO: PixTestXray::doTest()
[08:50:45.451] <TB3> INFO: ######################################################################
[08:50:45.451] <TB3> INFO: ----------------------------------------------------------------------
[08:50:45.451] <TB3> INFO: PixTestXray::doPhRun() fParRunSeconds = 100
[08:50:45.451] <TB3> INFO: ----------------------------------------------------------------------
[08:50:46.418] <TB3> INFO: PixTestXray::doPhRun start TriggerLoop with trigger frequency 100 kHz, period 405 and duration 100 seconds, fEventsMax = 10000000
[08:50:50.207] <TB3> INFO: run duration 3 seconds, buffer almost full (81%), pausing triggers.
[08:51:18.992] <TB3> INFO: Resuming triggers.
[08:51:22.784] <TB3> INFO: run duration 7 seconds, buffer almost full (81%), pausing triggers.
[08:51:51.723] <TB3> INFO: Resuming triggers.
[08:51:55.521] <TB3> INFO: run duration 11 seconds, buffer almost full (81%), pausing triggers.
[08:52:22.862] <TB3> INFO: Resuming triggers.
[08:52:26.658] <TB3> INFO: run duration 15 seconds, buffer almost full (81%), pausing triggers.
[08:52:54.139] <TB3> INFO: Resuming triggers.
[08:52:57.931] <TB3> INFO: run duration 18 seconds, buffer almost full (81%), pausing triggers.
[08:53:25.256] <TB3> INFO: Resuming triggers.
[08:53:29.048] <TB3> INFO: run duration 22 seconds, buffer almost full (81%), pausing triggers.
[08:53:57.389] <TB3> INFO: Resuming triggers.
[08:54:01.178] <TB3> INFO: run duration 26 seconds, buffer almost full (81%), pausing triggers.
[08:54:28.334] <TB3> INFO: Resuming triggers.
[08:54:32.121] <TB3> INFO: run duration 30 seconds, buffer almost full (81%), pausing triggers.
[08:55:00.067] <TB3> INFO: Resuming triggers.
[08:55:03.857] <TB3> INFO: run duration 34 seconds, buffer almost full (81%), pausing triggers.
[08:55:30.827] <TB3> INFO: Resuming triggers.
[08:55:34.616] <TB3> INFO: run duration 37 seconds, buffer almost full (81%), pausing triggers.
[08:56:04.297] <TB3> INFO: Resuming triggers.
[08:56:08.091] <TB3> INFO: run duration 41 seconds, buffer almost full (81%), pausing triggers.
[08:56:36.582] <TB3> INFO: Resuming triggers.
[08:56:40.376] <TB3> INFO: run duration 45 seconds, buffer almost full (81%), pausing triggers.
[08:57:08.898] <TB3> INFO: Resuming triggers.
[08:57:12.686] <TB3> INFO: run duration 49 seconds, buffer almost full (81%), pausing triggers.
[08:57:41.763] <TB3> INFO: Resuming triggers.
[08:57:45.551] <TB3> INFO: run duration 53 seconds, buffer almost full (81%), pausing triggers.
[08:58:15.907] <TB3> INFO: Resuming triggers.
[08:58:19.694] <TB3> INFO: run duration 56 seconds, buffer almost full (81%), pausing triggers.
[08:58:48.660] <TB3> INFO: Resuming triggers.
[08:58:52.449] <TB3> INFO: run duration 60 seconds, buffer almost full (81%), pausing triggers.
[08:59:20.638] <TB3> INFO: Resuming triggers.
[08:59:24.424] <TB3> INFO: run duration 64 seconds, buffer almost full (81%), pausing triggers.
[08:59:53.955] <TB3> INFO: Resuming triggers.
[08:59:57.746] <TB3> INFO: run duration 68 seconds, buffer almost full (81%), pausing triggers.
[09:00:25.895] <TB3> INFO: Resuming triggers.
[09:00:29.685] <TB3> INFO: run duration 71 seconds, buffer almost full (81%), pausing triggers.
[09:00:56.883] <TB3> INFO: Resuming triggers.
[09:01:00.673] <TB3> INFO: run duration 75 seconds, buffer almost full (81%), pausing triggers.
[09:01:29.065] <TB3> INFO: Resuming triggers.
[09:01:32.853] <TB3> INFO: run duration 79 seconds, buffer almost full (81%), pausing triggers.
[09:02:01.550] <TB3> INFO: Resuming triggers.
[09:02:05.335] <TB3> INFO: run duration 83 seconds, buffer almost full (81%), pausing triggers.
[09:02:32.642] <TB3> INFO: Resuming triggers.
[09:02:36.430] <TB3> INFO: run duration 87 seconds, buffer almost full (81%), pausing triggers.
[09:03:04.193] <TB3> INFO: Resuming triggers.
[09:03:07.980] <TB3> INFO: run duration 90 seconds, buffer almost full (81%), pausing triggers.
[09:03:35.745] <TB3> INFO: Resuming triggers.
[09:03:39.528] <TB3> INFO: run duration 94 seconds, buffer almost full (81%), pausing triggers.
[09:04:08.227] <TB3> INFO: Resuming triggers.
[09:04:12.009] <TB3> INFO: run duration 98 seconds, buffer almost full (81%), pausing triggers.
[09:04:39.604] <TB3> INFO: Resuming triggers.
[09:04:41.198] <TB3> INFO: data taking finished, elapsed time: 100 seconds.
[09:04:53.744] <TB3> INFO: PixTest:: pg_setup set to default.
[09:04:53.746] <TB3> INFO: PixTestXray::doPhRun() done
[09:04:53.746] <TB3> INFO: PixTestXray::doTest() done
[09:04:53.944] <TB3> INFO: enter test to run
[09:04:53.944] <TB3> INFO: test: no parameter change
[09:04:54.135] <TB3> QUIET: Connection to board 121 closed.
[09:04:54.220] <TB3> INFO: pXar: this is the end, my friend

[09:08:25.820] <TB3> INFO: *** Welcome to pxar ***
[09:08:25.820] <TB3> INFO: *** Today: 2015/08/28
[09:08:25.820] <TB3> INFO: readRocDacs: /usr/local/data/M2087_XrayQualification_2015-08-28_10h32m_1440750764//005_HRSCurves_100//dacParameters35_C0.dat .. /usr/local/data/M2087_XrayQualification_2015-08-28_10h32m_1440750764//005_HRSCurves_100//dacParameters35_C15.dat
[09:08:25.821] <TB3> INFO: readTbmDacs: /usr/local/data/M2087_XrayQualification_2015-08-28_10h32m_1440750764//005_HRSCurves_100//tbmParameters_C0a.dat .. /usr/local/data/M2087_XrayQualification_2015-08-28_10h32m_1440750764//005_HRSCurves_100//tbmParameters_C0b.dat
[09:08:25.821] <TB3> INFO: readMaskFile: /usr/local/data/M2087_XrayQualification_2015-08-28_10h32m_1440750764//005_HRSCurves_100//defaultMaskFile.dat
[09:08:25.821] <TB3> INFO: readTrimFile: /usr/local/data/M2087_XrayQualification_2015-08-28_10h32m_1440750764//005_HRSCurves_100//trimParameters35_C0.dat .. /usr/local/data/M2087_XrayQualification_2015-08-28_10h32m_1440750764//005_HRSCurves_100//trimParameters35_C15.dat
[09:08:25.897] <TB3> INFO: clk: 4
[09:08:25.897] <TB3> INFO: ctr: 4
[09:08:25.897] <TB3> INFO: sda: 19
[09:08:25.897] <TB3> INFO: tin: 9
[09:08:25.897] <TB3> INFO: level: 15
[09:08:25.897] <TB3> INFO: triggerdelay: 0
[09:08:25.897] <TB3> QUIET: Instanciating API for pxar v2.5+57~g44d0777
[09:08:25.897] <TB3> INFO: Log level: INFO
[09:08:25.910] <TB3> INFO: Found DTB DTB_WWV6Z5
[09:08:25.925] <TB3> QUIET: Connection to board DTB_WWV6Z5 opened.
[09:08:25.928] <TB3> INFO: DTB startup information
--- DTB info------------------------------------------
Board id: 121
HW version: DTB1.2
FW version: 4.2
SW version: 4.4
USB id: DTB_WWV6Z5
MAC address: 40D855118079
Hostname: pixelDTB121
Comment:
------------------------------------------------------
[09:08:25.930] <TB3> INFO: RPC call hashes of host and DTB match: 397073690
[09:08:27.463] <TB3> INFO: DUT info:
[09:08:27.463] <TB3> INFO: The DUT currently contains the following objects:
[09:08:27.463] <TB3> INFO: 2 TBM Cores tbm09c (2 ON)
[09:08:27.463] <TB3> INFO: TBM Core alpha (0): 7 registers set
[09:08:27.463] <TB3> INFO: TBM Core beta (1): 7 registers set
[09:08:27.463] <TB3> INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[09:08:27.463] <TB3> INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[09:08:27.463] <TB3> INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[09:08:27.463] <TB3> INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[09:08:27.463] <TB3> INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[09:08:27.463] <TB3> INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[09:08:27.463] <TB3> INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[09:08:27.463] <TB3> INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[09:08:27.463] <TB3> INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[09:08:27.463] <TB3> INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[09:08:27.463] <TB3> INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[09:08:27.463] <TB3> INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[09:08:27.463] <TB3> INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[09:08:27.463] <TB3> INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[09:08:27.463] <TB3> INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[09:08:27.463] <TB3> INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[09:08:27.463] <TB3> INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[09:08:27.864] <TB3> INFO: enter 'restricted' command line mode
[09:08:27.864] <TB3> INFO: enter test to run
[09:08:27.864] <TB3> INFO: test: Highrate no parameter change
[09:08:27.864] <TB3> INFO: running: highrate
[09:08:27.864] <TB3> INFO: readGainPedestalParameters /usr/local/data/M2087_XrayQualification_2015-08-28_10h32m_1440750764//005_HRSCurves_100//phCalibrationFitErr35_C0.dat .. /usr/local/data/M2087_XrayQualification_2015-08-28_10h32m_1440750764//005_HRSCurves_100//phCalibrationFitErr35_C15.dat
[09:08:28.046] <TB3> INFO: ----------------------------------------------------------------------
[09:08:28.046] <TB3> INFO: PixTestHighRate::xNoiseMaps() ntrig = 50, vcal = 200
[09:08:28.046] <TB3> INFO: ----------------------------------------------------------------------
[09:08:28.052] <TB3> INFO: ---> dac: vcal name: xNoiseMap ntrig: 50 dacrange: 0 .. 100 (1/-1) hits flags = 384 (plus default)
[09:08:28.064] <TB3> INFO: dacScan step from 0 .. 0
[09:08:28.064] <TB3> INFO: dacScan split into 1 runs with ntrig = 50
[09:08:28.064] <TB3> INFO: run 1 of 1
[09:08:28.675] <TB3> INFO: Expecting 208000 events.
[09:08:44.311] <TB3> INFO: 208000 events read in total (15106ms).
[09:08:44.321] <TB3> INFO: Test took 16257ms.
[09:08:44.400] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[09:08:44.629] <TB3> INFO: dacScan step from 1 .. 1
[09:08:44.629] <TB3> INFO: dacScan split into 1 runs with ntrig = 50
[09:08:44.629] <TB3> INFO: run 1 of 1
[09:08:45.224] <TB3> INFO: Expecting 208000 events.
[09:09:00.597] <TB3> INFO: 208000 events read in total (14846ms).
[09:09:00.604] <TB3> INFO: Test took 15975ms.
[09:09:00.695] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[09:09:00.938] <TB3> INFO: dacScan step from 2 .. 2
[09:09:00.938] <TB3> INFO: dacScan split into 1 runs with ntrig = 50
[09:09:00.938] <TB3> INFO: run 1 of 1
[09:09:01.535] <TB3> INFO: Expecting 208000 events.
[09:09:17.097] <TB3> INFO: 208000 events read in total (15034ms).
[09:09:17.104] <TB3> INFO: Test took 16166ms.
[09:09:17.219] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[09:09:17.465] <TB3> INFO: dacScan step from 3 .. 3
[09:09:17.465] <TB3> INFO: dacScan split into 1 runs with ntrig = 50
[09:09:17.465] <TB3> INFO: run 1 of 1
[09:09:18.069] <TB3> INFO: Expecting 208000 events.
[09:09:33.191] <TB3> INFO: 208000 events read in total (14595ms).
[09:09:33.198] <TB3> INFO: Test took 15733ms.
[09:09:33.279] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[09:09:33.537] <TB3> INFO: dacScan step from 4 .. 4
[09:09:33.537] <TB3> INFO: dacScan split into 1 runs with ntrig = 50
[09:09:33.537] <TB3> INFO: run 1 of 1
[09:09:34.132] <TB3> INFO: Expecting 208000 events.
[09:09:49.298] <TB3> INFO: 208000 events read in total (14639ms).
[09:09:49.308] <TB3> INFO: Test took 15771ms.
[09:09:49.426] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[09:09:49.677] <TB3> INFO: dacScan step from 5 .. 5
[09:09:49.677] <TB3> INFO: dacScan split into 1 runs with ntrig = 50
[09:09:49.677] <TB3> INFO: run 1 of 1
[09:09:50.286] <TB3> INFO: Expecting 208000 events.
[09:10:05.883] <TB3> INFO: 208000 events read in total (15070ms).
[09:10:05.890] <TB3> INFO: Test took 16213ms.
[09:10:05.981] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[09:10:06.262] <TB3> INFO: dacScan step from 6 .. 6
[09:10:06.262] <TB3> INFO: dacScan split into 1 runs with ntrig = 50
[09:10:06.262] <TB3> INFO: run 1 of 1
[09:10:06.890] <TB3> INFO: Expecting 208000 events.
[09:10:22.049] <TB3> INFO: 208000 events read in total (14631ms).
[09:10:22.057] <TB3> INFO: Test took 15795ms.
[09:10:22.139] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[09:10:22.367] <TB3> INFO: dacScan step from 7 .. 7
[09:10:22.367] <TB3> INFO: dacScan split into 1 runs with ntrig = 50
[09:10:22.367] <TB3> INFO: run 1 of 1
[09:10:22.964] <TB3> INFO: Expecting 208000 events.
[09:10:38.469] <TB3> INFO: 208000 events read in total (14977ms).
[09:10:38.477] <TB3> INFO: Test took 16109ms.
[09:10:38.557] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[09:10:38.783] <TB3> INFO: dacScan step from 8 .. 8
[09:10:38.783] <TB3> INFO: dacScan split into 1 runs with ntrig = 50
[09:10:38.783] <TB3> INFO: run 1 of 1
[09:10:39.423] <TB3> INFO: Expecting 208000 events.
[09:10:54.821] <TB3> INFO: 208000 events read in total (14871ms).
[09:10:54.829] <TB3> INFO: Test took 16046ms.
[09:10:54.908] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[09:10:55.131] <TB3> INFO: dacScan step from 9 .. 9
[09:10:55.131] <TB3> INFO: dacScan split into 1 runs with ntrig = 50
[09:10:55.131] <TB3> INFO: run 1 of 1
[09:10:55.725] <TB3> INFO: Expecting 208000 events.
[09:11:10.782] <TB3> INFO: 208000 events read in total (14530ms).
[09:11:10.789] <TB3> INFO: Test took 15658ms.
[09:11:10.870] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[09:11:11.102] <TB3> INFO: dacScan step from 10 .. 10
[09:11:11.102] <TB3> INFO: dacScan split into 1 runs with ntrig = 50
[09:11:11.102] <TB3> INFO: run 1 of 1
[09:11:11.705] <TB3> INFO: Expecting 208000 events.
[09:11:26.648] <TB3> INFO: 208000 events read in total (14416ms).
[09:11:26.656] <TB3> INFO: Test took 15554ms.
[09:11:26.746] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[09:11:27.010] <TB3> INFO: dacScan step from 11 .. 11
[09:11:27.010] <TB3> INFO: dacScan split into 1 runs with ntrig = 50
[09:11:27.010] <TB3> INFO: run 1 of 1
[09:11:27.621] <TB3> INFO: Expecting 208000 events.
[09:11:42.619] <TB3> INFO: 208000 events read in total (14470ms).
[09:11:42.626] <TB3> INFO: Test took 15616ms.
[09:11:42.707] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[09:11:42.939] <TB3> INFO: dacScan step from 12 .. 12
[09:11:42.939] <TB3> INFO: dacScan split into 1 runs with ntrig = 50
[09:11:42.939] <TB3> INFO: run 1 of 1
[09:11:43.547] <TB3> INFO: Expecting 208000 events.
[09:11:58.464] <TB3> INFO: 208000 events read in total (14390ms).
[09:11:58.471] <TB3> INFO: Test took 15532ms.
[09:11:58.556] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[09:11:58.788] <TB3> INFO: dacScan step from 13 .. 13
[09:11:58.788] <TB3> INFO: dacScan split into 1 runs with ntrig = 50
[09:11:58.788] <TB3> INFO: run 1 of 1
[09:11:59.388] <TB3> INFO: Expecting 208000 events.
[09:12:14.587] <TB3> INFO: 208000 events read in total (14671ms).
[09:12:14.596] <TB3> INFO: Test took 15807ms.
[09:12:14.680] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[09:12:14.948] <TB3> INFO: dacScan step from 14 .. 14
[09:12:14.948] <TB3> INFO: dacScan split into 1 runs with ntrig = 50
[09:12:14.948] <TB3> INFO: run 1 of 1
[09:12:15.543] <TB3> INFO: Expecting 208000 events.
[09:12:31.017] <TB3> INFO: 208000 events read in total (14947ms).
[09:12:31.026] <TB3> INFO: Test took 16078ms.
[09:12:31.108] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[09:12:31.360] <TB3> INFO: dacScan step from 15 .. 15
[09:12:31.360] <TB3> INFO: dacScan split into 1 runs with ntrig = 50
[09:12:31.360] <TB3> INFO: run 1 of 1
[09:12:31.957] <TB3> INFO: Expecting 208000 events.
[09:12:47.119] <TB3> INFO: 208000 events read in total (14634ms).
[09:12:47.128] <TB3> INFO: Test took 15768ms.
[09:12:47.208] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[09:12:47.477] <TB3> INFO: dacScan step from 16 .. 16
[09:12:47.477] <TB3> INFO: dacScan split into 1 runs with ntrig = 50
[09:12:47.477] <TB3> INFO: run 1 of 1
[09:12:48.089] <TB3> INFO: Expecting 208000 events.
[09:13:03.342] <TB3> INFO: 208000 events read in total (14725ms).
[09:13:03.351] <TB3> INFO: Test took 15874ms.
[09:13:03.430] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[09:13:03.653] <TB3> INFO: dacScan step from 17 .. 17
[09:13:03.654] <TB3> INFO: dacScan split into 1 runs with ntrig = 50
[09:13:03.654] <TB3> INFO: run 1 of 1
[09:13:04.250] <TB3> INFO: Expecting 208000 events.
[09:13:19.264] <TB3> INFO: 208000 events read in total (14486ms).
[09:13:19.273] <TB3> INFO: Test took 15619ms.
[09:13:19.353] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[09:13:19.578] <TB3> INFO: dacScan step from 18 .. 18
[09:13:19.579] <TB3> INFO: dacScan split into 1 runs with ntrig = 50
[09:13:19.579] <TB3> INFO: run 1 of 1
[09:13:20.177] <TB3> INFO: Expecting 208000 events.
[09:13:35.859] <TB3> INFO: 208000 events read in total (15154ms).
[09:13:35.867] <TB3> INFO: Test took 16288ms.
[09:13:35.954] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[09:13:36.214] <TB3> INFO: dacScan step from 19 .. 19
[09:13:36.214] <TB3> INFO: dacScan split into 1 runs with ntrig = 50
[09:13:36.214] <TB3> INFO: run 1 of 1
[09:13:36.838] <TB3> INFO: Expecting 208000 events.
[09:13:52.279] <TB3> INFO: 208000 events read in total (14913ms).
[09:13:52.287] <TB3> INFO: Test took 16073ms.
[09:13:52.370] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[09:13:52.624] <TB3> INFO: dacScan step from 20 .. 20
[09:13:52.624] <TB3> INFO: dacScan split into 1 runs with ntrig = 50
[09:13:52.624] <TB3> INFO: run 1 of 1
[09:13:53.235] <TB3> INFO: Expecting 208000 events.
[09:14:08.562] <TB3> INFO: 208000 events read in total (14800ms).
[09:14:08.571] <TB3> INFO: Test took 15946ms.
[09:14:08.659] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[09:14:08.938] <TB3> INFO: dacScan step from 21 .. 21
[09:14:08.938] <TB3> INFO: dacScan split into 1 runs with ntrig = 50
[09:14:08.938] <TB3> INFO: run 1 of 1
[09:14:09.541] <TB3> INFO: Expecting 208000 events.

[09:14:24.816] <TB3> INFO: 208000 events read in total (14748ms).
[09:14:24.824] <TB3> INFO: Test took 15885ms.
[09:14:24.906] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[09:14:25.131] <TB3> INFO: dacScan step from 22 .. 22
[09:14:25.131] <TB3> INFO: dacScan split into 1 runs with ntrig = 50
[09:14:25.131] <TB3> INFO: run 1 of 1
[09:14:25.730] <TB3> INFO: Expecting 208000 events.
[09:14:40.729] <TB3> INFO: 208000 events read in total (14471ms).
[09:14:40.737] <TB3> INFO: Test took 15605ms.
[09:14:40.827] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[09:14:41.099] <TB3> INFO: dacScan step from 23 .. 23
[09:14:41.099] <TB3> INFO: dacScan split into 1 runs with ntrig = 50
[09:14:41.099] <TB3> INFO: run 1 of 1
[09:14:41.717] <TB3> INFO: Expecting 208000 events.
[09:14:56.881] <TB3> INFO: 208000 events read in total (14637ms).
[09:14:56.889] <TB3> INFO: Test took 15789ms.
[09:14:56.971] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[09:14:57.196] <TB3> INFO: dacScan step from 24 .. 24
[09:14:57.196] <TB3> INFO: dacScan split into 1 runs with ntrig = 50
[09:14:57.196] <TB3> INFO: run 1 of 1
[09:14:57.821] <TB3> INFO: Expecting 208000 events.
[09:15:12.768] <TB3> INFO: 208000 events read in total (14420ms).
[09:15:12.776] <TB3> INFO: Test took 15580ms.
[09:15:12.858] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[09:15:13.085] <TB3> INFO: dacScan step from 25 .. 25
[09:15:13.085] <TB3> INFO: dacScan split into 1 runs with ntrig = 50
[09:15:13.085] <TB3> INFO: run 1 of 1
[09:15:13.692] <TB3> INFO: Expecting 208000 events.
[09:15:28.782] <TB3> INFO: 208000 events read in total (14563ms).
[09:15:28.790] <TB3> INFO: Test took 15705ms.
[09:15:28.880] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[09:15:29.148] <TB3> INFO: dacScan step from 26 .. 26
[09:15:29.148] <TB3> INFO: dacScan split into 1 runs with ntrig = 50
[09:15:29.148] <TB3> INFO: run 1 of 1
[09:15:29.771] <TB3> INFO: Expecting 208000 events.
[09:15:44.960] <TB3> INFO: 208000 events read in total (14662ms).
[09:15:44.968] <TB3> INFO: Test took 15819ms.
[09:15:45.051] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[09:15:45.280] <TB3> INFO: dacScan step from 27 .. 27
[09:15:45.280] <TB3> INFO: dacScan split into 1 runs with ntrig = 50
[09:15:45.280] <TB3> INFO: run 1 of 1
[09:15:45.880] <TB3> INFO: Expecting 208000 events.
[09:16:01.148] <TB3> INFO: 208000 events read in total (14741ms).
[09:16:01.156] <TB3> INFO: Test took 15876ms.
[09:16:01.250] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[09:16:01.540] <TB3> INFO: dacScan step from 28 .. 28
[09:16:01.540] <TB3> INFO: dacScan split into 1 runs with ntrig = 50
[09:16:01.540] <TB3> INFO: run 1 of 1
[09:16:02.142] <TB3> INFO: Expecting 208000 events.
[09:16:17.395] <TB3> INFO: 208000 events read in total (14726ms).
[09:16:17.403] <TB3> INFO: Test took 15863ms.
[09:16:17.485] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[09:16:17.722] <TB3> INFO: dacScan step from 29 .. 29
[09:16:17.722] <TB3> INFO: dacScan split into 1 runs with ntrig = 50
[09:16:17.723] <TB3> INFO: run 1 of 1
[09:16:18.329] <TB3> INFO: Expecting 208000 events.
[09:16:33.470] <TB3> INFO: 208000 events read in total (14613ms).
[09:16:33.478] <TB3> INFO: Test took 15755ms.
[09:16:33.558] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[09:16:33.788] <TB3> INFO: dacScan step from 30 .. 30
[09:16:33.788] <TB3> INFO: dacScan split into 1 runs with ntrig = 50
[09:16:33.788] <TB3> INFO: run 1 of 1
[09:16:34.390] <TB3> INFO: Expecting 208000 events.
[09:16:49.222] <TB3> INFO: 208000 events read in total (14304ms).
[09:16:49.230] <TB3> INFO: Test took 15441ms.
[09:16:49.313] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[09:16:49.557] <TB3> INFO: dacScan step from 31 .. 31
[09:16:49.557] <TB3> INFO: dacScan split into 1 runs with ntrig = 50
[09:16:49.557] <TB3> INFO: run 1 of 1
[09:16:50.165] <TB3> INFO: Expecting 208000 events.
[09:17:04.925] <TB3> INFO: 208000 events read in total (14233ms).
[09:17:04.933] <TB3> INFO: Test took 15375ms.
[09:17:05.020] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[09:17:05.279] <TB3> INFO: dacScan step from 32 .. 32
[09:17:05.279] <TB3> INFO: dacScan split into 1 runs with ntrig = 50
[09:17:05.279] <TB3> INFO: run 1 of 1
[09:17:05.905] <TB3> INFO: Expecting 208000 events.
[09:17:20.873] <TB3> INFO: 208000 events read in total (14440ms).
[09:17:20.881] <TB3> INFO: Test took 15602ms.
[09:17:20.967] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[09:17:21.214] <TB3> INFO: dacScan step from 33 .. 33
[09:17:21.214] <TB3> INFO: dacScan split into 1 runs with ntrig = 50
[09:17:21.214] <TB3> INFO: run 1 of 1
[09:17:21.823] <TB3> INFO: Expecting 208000 events.
[09:17:37.598] <TB3> INFO: 208000 events read in total (15247ms).
[09:17:37.606] <TB3> INFO: Test took 16391ms.
[09:17:37.690] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[09:17:37.942] <TB3> INFO: dacScan step from 34 .. 34
[09:17:37.942] <TB3> INFO: dacScan split into 1 runs with ntrig = 50
[09:17:37.942] <TB3> INFO: run 1 of 1
[09:17:38.547] <TB3> INFO: Expecting 208000 events.
[09:17:54.488] <TB3> INFO: 208000 events read in total (15413ms).
[09:17:54.496] <TB3> INFO: Test took 16554ms.
[09:17:54.582] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[09:17:54.847] <TB3> INFO: dacScan step from 35 .. 35
[09:17:54.847] <TB3> INFO: dacScan split into 1 runs with ntrig = 50
[09:17:54.847] <TB3> INFO: run 1 of 1
[09:17:55.448] <TB3> INFO: Expecting 208000 events.
[09:18:11.487] <TB3> INFO: 208000 events read in total (15512ms).
[09:18:11.495] <TB3> INFO: Test took 16647ms.
[09:18:11.580] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[09:18:11.828] <TB3> INFO: dacScan step from 36 .. 36
[09:18:11.828] <TB3> INFO: dacScan split into 1 runs with ntrig = 50
[09:18:11.828] <TB3> INFO: run 1 of 1
[09:18:12.431] <TB3> INFO: Expecting 208000 events.
[09:18:28.273] <TB3> INFO: 208000 events read in total (15314ms).
[09:18:28.281] <TB3> INFO: Test took 16452ms.
[09:18:28.368] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[09:18:28.614] <TB3> INFO: dacScan step from 37 .. 37
[09:18:28.614] <TB3> INFO: dacScan split into 1 runs with ntrig = 50
[09:18:28.614] <TB3> INFO: run 1 of 1
[09:18:29.220] <TB3> INFO: Expecting 208000 events.
[09:18:44.990] <TB3> INFO: 208000 events read in total (15242ms).
[09:18:44.998] <TB3> INFO: Test took 16383ms.
[09:18:45.086] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[09:18:45.334] <TB3> INFO: dacScan step from 38 .. 38
[09:18:45.334] <TB3> INFO: dacScan split into 1 runs with ntrig = 50
[09:18:45.334] <TB3> INFO: run 1 of 1
[09:18:45.936] <TB3> INFO: Expecting 208000 events.
[09:19:02.196] <TB3> INFO: 208000 events read in total (15733ms).
[09:19:02.205] <TB3> INFO: Test took 16870ms.
[09:19:02.289] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[09:19:02.520] <TB3> INFO: dacScan step from 39 .. 39
[09:19:02.520] <TB3> INFO: dacScan split into 1 runs with ntrig = 50
[09:19:02.520] <TB3> INFO: run 1 of 1
[09:19:03.118] <TB3> INFO: Expecting 208000 events.
[09:19:19.854] <TB3> INFO: 208000 events read in total (16209ms).
[09:19:19.864] <TB3> INFO: Test took 17344ms.
[09:19:19.960] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[09:19:20.239] <TB3> INFO: dacScan step from 40 .. 40
[09:19:20.239] <TB3> INFO: dacScan split into 1 runs with ntrig = 50
[09:19:20.239] <TB3> INFO: run 1 of 1
[09:19:20.866] <TB3> INFO: Expecting 208000 events.
[09:19:37.369] <TB3> INFO: 208000 events read in total (15976ms).
[09:19:37.381] <TB3> INFO: Test took 17141ms.
[09:19:37.476] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[09:19:37.746] <TB3> INFO: dacScan step from 41 .. 41
[09:19:37.746] <TB3> INFO: dacScan split into 1 runs with ntrig = 50
[09:19:37.746] <TB3> INFO: run 1 of 1
[09:19:38.371] <TB3> INFO: Expecting 208000 events.
[09:19:55.431] <TB3> INFO: 208000 events read in total (16533ms).
[09:19:55.441] <TB3> INFO: Test took 17694ms.
[09:19:55.533] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[09:19:55.805] <TB3> INFO: dacScan step from 42 .. 42
[09:19:55.805] <TB3> INFO: dacScan split into 1 runs with ntrig = 50
[09:19:55.805] <TB3> INFO: run 1 of 1
[09:19:56.431] <TB3> INFO: Expecting 208000 events.
[09:20:13.744] <TB3> INFO: 208000 events read in total (16784ms).
[09:20:13.755] <TB3> INFO: Test took 17950ms.
[09:20:13.843] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[09:20:14.121] <TB3> INFO: dacScan step from 43 .. 43
[09:20:14.121] <TB3> INFO: dacScan split into 1 runs with ntrig = 50
[09:20:14.121] <TB3> INFO: run 1 of 1
[09:20:14.746] <TB3> INFO: Expecting 208000 events.
[09:20:32.559] <TB3> INFO: 208000 events read in total (17285ms).
[09:20:32.569] <TB3> INFO: Test took 18447ms.
[09:20:32.656] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[09:20:32.953] <TB3> INFO: dacScan step from 44 .. 44
[09:20:32.953] <TB3> INFO: dacScan split into 1 runs with ntrig = 50
[09:20:32.953] <TB3> INFO: run 1 of 1
[09:20:33.555] <TB3> INFO: Expecting 208000 events.
[09:20:51.891] <TB3> INFO: 208000 events read in total (17808ms).
[09:20:51.900] <TB3> INFO: Test took 18947ms.
[09:20:51.987] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[09:20:52.237] <TB3> INFO: dacScan step from 45 .. 45
[09:20:52.237] <TB3> INFO: dacScan split into 1 runs with ntrig = 50
[09:20:52.237] <TB3> INFO: run 1 of 1
[09:20:52.848] <TB3> INFO: Expecting 208000 events.
[09:21:11.178] <TB3> INFO: 208000 events read in total (17802ms).
[09:21:11.188] <TB3> INFO: Test took 18950ms.
[09:21:11.276] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[09:21:11.566] <TB3> INFO: dacScan step from 46 .. 46
[09:21:11.566] <TB3> INFO: dacScan split into 1 runs with ntrig = 50
[09:21:11.566] <TB3> INFO: run 1 of 1
[09:21:12.193] <TB3> INFO: Expecting 208000 events.
[09:21:30.270] <TB3> INFO: 208000 events read in total (17550ms).
[09:21:30.278] <TB3> INFO: Test took 18712ms.
[09:21:30.368] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[09:21:30.624] <TB3> INFO: dacScan step from 47 .. 47
[09:21:30.624] <TB3> INFO: dacScan split into 1 runs with ntrig = 50
[09:21:30.624] <TB3> INFO: run 1 of 1
[09:21:31.232] <TB3> INFO: Expecting 208000 events.
[09:21:50.122] <TB3> INFO: 208000 events read in total (18363ms).
[09:21:50.130] <TB3> INFO: Test took 19505ms.
[09:21:50.218] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[09:21:50.460] <TB3> INFO: dacScan step from 48 .. 48
[09:21:50.460] <TB3> INFO: dacScan split into 1 runs with ntrig = 50
[09:21:50.460] <TB3> INFO: run 1 of 1
[09:21:51.060] <TB3> INFO: Expecting 208000 events.
[09:22:09.901] <TB3> INFO: 208000 events read in total (18313ms).
[09:22:09.910] <TB3> INFO: Test took 19449ms.
[09:22:09.993] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[09:22:10.226] <TB3> INFO: dacScan step from 49 .. 49
[09:22:10.226] <TB3> INFO: dacScan split into 1 runs with ntrig = 50
[09:22:10.226] <TB3> INFO: run 1 of 1
[09:22:10.823] <TB3> INFO: Expecting 208000 events.
[09:22:29.786] <TB3> INFO: 208000 events read in total (18434ms).
[09:22:29.794] <TB3> INFO: Test took 19567ms.
[09:22:29.879] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[09:22:30.141] <TB3> INFO: dacScan step from 50 .. 50
[09:22:30.142] <TB3> INFO: dacScan split into 1 runs with ntrig = 50
[09:22:30.142] <TB3> INFO: run 1 of 1
[09:22:30.762] <TB3> INFO: Expecting 208000 events.
[09:22:49.413] <TB3> INFO: 208000 events read in total (18124ms).
[09:22:49.423] <TB3> INFO: Test took 19281ms.
[09:22:49.516] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[09:22:49.769] <TB3> INFO: dacScan step from 51 .. 51
[09:22:49.769] <TB3> INFO: dacScan split into 1 runs with ntrig = 50
[09:22:49.769] <TB3> INFO: run 1 of 1
[09:22:50.374] <TB3> INFO: Expecting 208000 events.
[09:23:09.176] <TB3> INFO: 208000 events read in total (18274ms).
[09:23:09.185] <TB3> INFO: Test took 19416ms.
[09:23:09.272] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[09:23:09.524] <TB3> INFO: dacScan step from 52 .. 52
[09:23:09.524] <TB3> INFO: dacScan split into 1 runs with ntrig = 50
[09:23:09.524] <TB3> INFO: run 1 of 1
[09:23:10.134] <TB3> INFO: Expecting 208000 events.
[09:23:28.687] <TB3> INFO: 208000 events read in total (18026ms).
[09:23:28.695] <TB3> INFO: Test took 19171ms.
[09:23:28.790] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[09:23:29.052] <TB3> INFO: dacScan step from 53 .. 53
[09:23:29.052] <TB3> INFO: dacScan split into 1 runs with ntrig = 50
[09:23:29.052] <TB3> INFO: run 1 of 1
[09:23:29.655] <TB3> INFO: Expecting 208000 events.
[09:23:48.745] <TB3> INFO: 208000 events read in total (18563ms).
[09:23:48.754] <TB3> INFO: Test took 19701ms.
[09:23:48.839] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[09:23:49.095] <TB3> INFO: dacScan step from 54 .. 54
[09:23:49.095] <TB3> INFO: dacScan split into 1 runs with ntrig = 50
[09:23:49.095] <TB3> INFO: run 1 of 1
[09:23:49.706] <TB3> INFO: Expecting 208000 events.
[09:24:08.438] <TB3> INFO: 208000 events read in total (18205ms).
[09:24:08.446] <TB3> INFO: Test took 19351ms.
[09:24:08.533] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[09:24:08.777] <TB3> INFO: dacScan step from 55 .. 55
[09:24:08.777] <TB3> INFO: dacScan split into 1 runs with ntrig = 50
[09:24:08.777] <TB3> INFO: run 1 of 1
[09:24:09.377] <TB3> INFO: Expecting 208000 events.
[09:24:28.036] <TB3> INFO: 208000 events read in total (18132ms).
[09:24:28.045] <TB3> INFO: Test took 19267ms.
[09:24:28.132] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[09:24:28.381] <TB3> INFO: dacScan step from 56 .. 56
[09:24:28.381] <TB3> INFO: dacScan split into 1 runs with ntrig = 50
[09:24:28.381] <TB3> INFO: run 1 of 1
[09:24:28.992] <TB3> INFO: Expecting 208000 events.
[09:24:48.002] <TB3> INFO: 208000 events read in total (18483ms).
[09:24:48.011] <TB3> INFO: Test took 19629ms.
[09:24:48.096] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[09:24:48.333] <TB3> INFO: dacScan step from 57 .. 57
[09:24:48.333] <TB3> INFO: dacScan split into 1 runs with ntrig = 50
[09:24:48.333] <TB3> INFO: run 1 of 1
[09:24:48.930] <TB3> INFO: Expecting 208000 events.
[09:25:08.159] <TB3> INFO: 208000 events read in total (18702ms).
[09:25:08.168] <TB3> INFO: Test took 19835ms.
[09:25:08.260] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[09:25:08.533] <TB3> INFO: dacScan step from 58 .. 58
[09:25:08.533] <TB3> INFO: dacScan split into 1 runs with ntrig = 50
[09:25:08.533] <TB3> INFO: run 1 of 1
[09:25:09.154] <TB3> INFO: Expecting 208000 events.
[09:25:28.334] <TB3> INFO: 208000 events read in total (18653ms).
[09:25:28.343] <TB3> INFO: Test took 19809ms.
[09:25:28.433] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[09:25:28.681] <TB3> INFO: dacScan step from 59 .. 59
[09:25:28.681] <TB3> INFO: dacScan split into 1 runs with ntrig = 50
[09:25:28.681] <TB3> INFO: run 1 of 1
[09:25:29.282] <TB3> INFO: Expecting 208000 events.
[09:25:48.109] <TB3> INFO: 208000 events read in total (18299ms).
[09:25:48.118] <TB3> INFO: Test took 19436ms.
[09:25:48.211] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[09:25:48.479] <TB3> INFO: dacScan step from 60 .. 60
[09:25:48.479] <TB3> INFO: dacScan split into 1 runs with ntrig = 50
[09:25:48.479] <TB3> INFO: run 1 of 1
[09:25:49.101] <TB3> INFO: Expecting 208000 events.
[09:26:07.882] <TB3> INFO: 208000 events read in total (18254ms).
[09:26:07.891] <TB3> INFO: Test took 19411ms.
[09:26:07.976] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[09:26:08.209] <TB3> INFO: dacScan step from 61 .. 61
[09:26:08.209] <TB3> INFO: dacScan split into 1 runs with ntrig = 50
[09:26:08.209] <TB3> INFO: run 1 of 1
[09:26:08.832] <TB3> INFO: Expecting 208000 events.
[09:26:28.265] <TB3> INFO: 208000 events read in total (18905ms).
[09:26:28.274] <TB3> INFO: Test took 20064ms.
[09:26:28.359] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[09:26:28.613] <TB3> INFO: dacScan step from 62 .. 62
[09:26:28.613] <TB3> INFO: dacScan split into 1 runs with ntrig = 50
[09:26:28.613] <TB3> INFO: run 1 of 1
[09:26:29.213] <TB3> INFO: Expecting 208000 events.
[09:26:47.739] <TB3> INFO: 208000 events read in total (17999ms).
[09:26:47.750] <TB3> INFO: Test took 19137ms.
[09:26:47.839] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[09:26:48.084] <TB3> INFO: dacScan step from 63 .. 63
[09:26:48.084] <TB3> INFO: dacScan split into 1 runs with ntrig = 50
[09:26:48.084] <TB3> INFO: run 1 of 1
[09:26:48.680] <TB3> INFO: Expecting 208000 events.
[09:27:07.768] <TB3> INFO: 208000 events read in total (18561ms).
[09:27:07.777] <TB3> INFO: Test took 19692ms.
[09:27:07.869] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[09:27:08.129] <TB3> INFO: dacScan step from 64 .. 64
[09:27:08.129] <TB3> INFO: dacScan split into 1 runs with ntrig = 50
[09:27:08.129] <TB3> INFO: run 1 of 1
[09:27:08.730] <TB3> INFO: Expecting 208000 events.
[09:27:27.650] <TB3> INFO: 208000 events read in total (18393ms).
[09:27:27.659] <TB3> INFO: Test took 19530ms.
[09:27:27.748] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[09:27:27.995] <TB3> INFO: dacScan step from 65 .. 65
[09:27:27.995] <TB3> INFO: dacScan split into 1 runs with ntrig = 50
[09:27:27.995] <TB3> INFO: run 1 of 1
[09:27:28.598] <TB3> INFO: Expecting 208000 events.
[09:27:48.126] <TB3> INFO: 208000 events read in total (19001ms).
[09:27:48.134] <TB3> INFO: Test took 20139ms.
[09:27:48.223] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[09:27:48.461] <TB3> INFO: dacScan step from 66 .. 66
[09:27:48.461] <TB3> INFO: dacScan split into 1 runs with ntrig = 50
[09:27:48.461] <TB3> INFO: run 1 of 1
[09:27:49.059] <TB3> INFO: Expecting 208000 events.
[09:28:08.480] <TB3> INFO: 208000 events read in total (18894ms).
[09:28:08.488] <TB3> INFO: Test took 20026ms.
[09:28:08.573] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[09:28:08.816] <TB3> INFO: dacScan step from 67 .. 67
[09:28:08.816] <TB3> INFO: dacScan split into 1 runs with ntrig = 50
[09:28:08.816] <TB3> INFO: run 1 of 1
[09:28:09.420] <TB3> INFO: Expecting 208000 events.
[09:28:28.304] <TB3> INFO: 208000 events read in total (18357ms).
[09:28:28.313] <TB3> INFO: Test took 19496ms.
[09:28:28.410] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[09:28:28.663] <TB3> INFO: dacScan step from 68 .. 68
[09:28:28.663] <TB3> INFO: dacScan split into 1 runs with ntrig = 50
[09:28:28.663] <TB3> INFO: run 1 of 1
[09:28:29.281] <TB3> INFO: Expecting 208000 events.
[09:28:47.804] <TB3> INFO: 208000 events read in total (17996ms).
[09:28:47.813] <TB3> INFO: Test took 19149ms.
[09:28:47.904] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[09:28:48.145] <TB3> INFO: dacScan step from 69 .. 69
[09:28:48.145] <TB3> INFO: dacScan split into 1 runs with ntrig = 50
[09:28:48.145] <TB3> INFO: run 1 of 1
[09:28:48.752] <TB3> INFO: Expecting 208000 events.
[09:29:07.612] <TB3> INFO: 208000 events read in total (18332ms).
[09:29:07.621] <TB3> INFO: Test took 19475ms.
[09:29:07.708] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[09:29:07.952] <TB3> INFO: dacScan step from 70 .. 70
[09:29:07.953] <TB3> INFO: dacScan split into 1 runs with ntrig = 50
[09:29:07.953] <TB3> INFO: run 1 of 1
[09:29:08.556] <TB3> INFO: Expecting 208000 events.
[09:29:27.564] <TB3> INFO: 208000 events read in total (18480ms).
[09:29:27.573] <TB3> INFO: Test took 19620ms.
[09:29:27.663] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[09:29:27.905] <TB3> INFO: dacScan step from 71 .. 71
[09:29:27.905] <TB3> INFO: dacScan split into 1 runs with ntrig = 50
[09:29:27.905] <TB3> INFO: run 1 of 1
[09:29:28.506] <TB3> INFO: Expecting 208000 events.
[09:29:47.614] <TB3> INFO: 208000 events read in total (18579ms).
[09:29:47.623] <TB3> INFO: Test took 19717ms.
[09:29:47.709] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[09:29:47.964] <TB3> INFO: dacScan step from 72 .. 72
[09:29:47.965] <TB3> INFO: dacScan split into 1 runs with ntrig = 50
[09:29:47.965] <TB3> INFO: run 1 of 1
[09:29:48.569] <TB3> INFO: Expecting 208000 events.
[09:30:07.536] <TB3> INFO: 208000 events read in total (18440ms).
[09:30:07.545] <TB3> INFO: Test took 19580ms.
[09:30:07.632] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[09:30:07.880] <TB3> INFO: dacScan step from 73 .. 73
[09:30:07.880] <TB3> INFO: dacScan split into 1 runs with ntrig = 50
[09:30:07.880] <TB3> INFO: run 1 of 1
[09:30:08.497] <TB3> INFO: Expecting 208000 events.
[09:30:27.646] <TB3> INFO: 208000 events read in total (18622ms).
[09:30:27.656] <TB3> INFO: Test took 19775ms.
[09:30:27.746] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[09:30:28.014] <TB3> INFO: dacScan step from 74 .. 74
[09:30:28.014] <TB3> INFO: dacScan split into 1 runs with ntrig = 50
[09:30:28.014] <TB3> INFO: run 1 of 1
[09:30:28.628] <TB3> INFO: Expecting 208000 events.
[09:30:47.544] <TB3> INFO: 208000 events read in total (18389ms).
[09:30:47.553] <TB3> INFO: Test took 19538ms.
[09:30:47.638] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[09:30:47.880] <TB3> INFO: dacScan step from 75 .. 75
[09:30:47.880] <TB3> INFO: dacScan split into 1 runs with ntrig = 50
[09:30:47.880] <TB3> INFO: run 1 of 1
[09:30:48.483] <TB3> INFO: Expecting 208000 events.
[09:31:07.449] <TB3> INFO: 208000 events read in total (18439ms).
[09:31:07.458] <TB3> INFO: Test took 19577ms.
[09:31:07.547] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[09:31:07.793] <TB3> INFO: dacScan step from 76 .. 76
[09:31:07.793] <TB3> INFO: dacScan split into 1 runs with ntrig = 50
[09:31:07.793] <TB3> INFO: run 1 of 1
[09:31:08.395] <TB3> INFO: Expecting 208000 events.
[09:31:27.789] <TB3> INFO: 208000 events read in total (18866ms).
[09:31:27.797] <TB3> INFO: Test took 20003ms.
[09:31:27.883] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[09:31:28.134] <TB3> INFO: dacScan step from 77 .. 77
[09:31:28.134] <TB3> INFO: dacScan split into 1 runs with ntrig = 50
[09:31:28.134] <TB3> INFO: run 1 of 1
[09:31:28.736] <TB3> INFO: Expecting 208000 events.
[09:31:47.360] <TB3> INFO: 208000 events read in total (18097ms).
[09:31:47.369] <TB3> INFO: Test took 19234ms.
[09:31:47.454] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[09:31:47.692] <TB3> INFO: dacScan step from 78 .. 78
[09:31:47.692] <TB3> INFO: dacScan split into 1 runs with ntrig = 50
[09:31:47.692] <TB3> INFO: run 1 of 1
[09:31:48.292] <TB3> INFO: Expecting 208000 events.
[09:32:07.295] <TB3> INFO: 208000 events read in total (18476ms).
[09:32:07.304] <TB3> INFO: Test took 19611ms.
[09:32:07.402] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[09:32:07.677] <TB3> INFO: dacScan step from 79 .. 79
[09:32:07.677] <TB3> INFO: dacScan split into 1 runs with ntrig = 50
[09:32:07.677] <TB3> INFO: run 1 of 1
[09:32:08.308] <TB3> INFO: Expecting 208000 events.
[09:32:27.366] <TB3> INFO: 208000 events read in total (18531ms).
[09:32:27.376] <TB3> INFO: Test took 19698ms.
[09:32:27.463] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[09:32:27.716] <TB3> INFO: dacScan step from 80 .. 80
[09:32:27.716] <TB3> INFO: dacScan split into 1 runs with ntrig = 50
[09:32:27.716] <TB3> INFO: run 1 of 1
[09:32:28.334] <TB3> INFO: Expecting 208000 events.
[09:32:47.365] <TB3> INFO: 208000 events read in total (18504ms).
[09:32:47.373] <TB3> INFO: Test took 19656ms.
[09:32:47.459] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[09:32:47.773] <TB3> INFO: dacScan step from 81 .. 81
[09:32:47.773] <TB3> INFO: dacScan split into 1 runs with ntrig = 50
[09:32:47.773] <TB3> INFO: run 1 of 1
[09:32:48.402] <TB3> INFO: Expecting 208000 events.
[09:33:07.336] <TB3> INFO: 208000 events read in total (18407ms).
[09:33:07.346] <TB3> INFO: Test took 19572ms.
[09:33:07.434] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[09:33:07.685] <TB3> INFO: dacScan step from 82 .. 82
[09:33:07.685] <TB3> INFO: dacScan split into 1 runs with ntrig = 50
[09:33:07.685] <TB3> INFO: run 1 of 1
[09:33:08.291] <TB3> INFO: Expecting 208000 events.
[09:33:27.723] <TB3> INFO: 208000 events read in total (18904ms).
[09:33:27.733] <TB3> INFO: Test took 20047ms.
[09:33:27.859] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[09:33:28.139] <TB3> INFO: dacScan step from 83 .. 83
[09:33:28.139] <TB3> INFO: dacScan split into 1 runs with ntrig = 50
[09:33:28.139] <TB3> INFO: run 1 of 1
[09:33:28.769] <TB3> INFO: Expecting 208000 events.
[09:33:47.496] <TB3> INFO: 208000 events read in total (18200ms).
[09:33:47.505] <TB3> INFO: Test took 19365ms.
[09:33:47.592] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[09:33:47.838] <TB3> INFO: dacScan step from 84 .. 84
[09:33:47.838] <TB3> INFO: dacScan split into 1 runs with ntrig = 50
[09:33:47.838] <TB3> INFO: run 1 of 1
[09:33:48.438] <TB3> INFO: Expecting 208000 events.
[09:34:07.172] <TB3> INFO: 208000 events read in total (18207ms).
[09:34:07.182] <TB3> INFO: Test took 19343ms.
[09:34:07.269] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[09:34:07.512] <TB3> INFO: dacScan step from 85 .. 85
[09:34:07.512] <TB3> INFO: dacScan split into 1 runs with ntrig = 50
[09:34:07.512] <TB3> INFO: run 1 of 1
[09:34:08.113] <TB3> INFO: Expecting 208000 events.
[09:34:27.100] <TB3> INFO: 208000 events read in total (18460ms).
[09:34:27.109] <TB3> INFO: Test took 19596ms.
[09:34:27.191] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[09:34:27.468] <TB3> INFO: dacScan step from 86 .. 86
[09:34:27.468] <TB3> INFO: dacScan split into 1 runs with ntrig = 50
[09:34:27.468] <TB3> INFO: run 1 of 1
[09:34:28.096] <TB3> INFO: Expecting 208000 events.
[09:34:47.784] <TB3> INFO: 208000 events read in total (19161ms).
[09:34:47.793] <TB3> INFO: Test took 20324ms.
[09:34:47.878] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[09:34:48.136] <TB3> INFO: dacScan step from 87 .. 87
[09:34:48.136] <TB3> INFO: dacScan split into 1 runs with ntrig = 50
[09:34:48.136] <TB3> INFO: run 1 of 1
[09:34:48.731] <TB3> INFO: Expecting 208000 events.
[09:35:08.095] <TB3> INFO: 208000 events read in total (18836ms).
[09:35:08.104] <TB3> INFO: Test took 19967ms.
[09:35:08.388] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[09:35:08.646] <TB3> INFO: dacScan step from 88 .. 88
[09:35:08.646] <TB3> INFO: dacScan split into 1 runs with ntrig = 50
[09:35:08.646] <TB3> INFO: run 1 of 1
[09:35:09.247] <TB3> INFO: Expecting 208000 events.
[09:35:28.607] <TB3> INFO: 208000 events read in total (18833ms).
[09:35:28.617] <TB3> INFO: Test took 19970ms.
[09:35:29.187] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[09:35:29.442] <TB3> INFO: dacScan step from 89 .. 89
[09:35:29.442] <TB3> INFO: dacScan split into 1 runs with ntrig = 50
[09:35:29.442] <TB3> INFO: run 1 of 1
[09:35:30.050] <TB3> INFO: Expecting 208000 events.
[09:35:49.305] <TB3> INFO: 208000 events read in total (18727ms).
[09:35:49.314] <TB3> INFO: Test took 19871ms.
[09:35:49.556] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[09:35:49.814] <TB3> INFO: dacScan step from 90 .. 90
[09:35:49.814] <TB3> INFO: dacScan split into 1 runs with ntrig = 50
[09:35:49.814] <TB3> INFO: run 1 of 1
[09:35:50.426] <TB3> INFO: Expecting 208000 events.
[09:36:09.621] <TB3> INFO: 208000 events read in total (18668ms).
[09:36:09.629] <TB3> INFO: Test took 19814ms.
[09:36:09.713] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[09:36:09.982] <TB3> INFO: dacScan step from 91 .. 91
[09:36:09.983] <TB3> INFO: dacScan split into 1 runs with ntrig = 50
[09:36:09.983] <TB3> INFO: run 1 of 1
[09:36:10.598] <TB3> INFO: Expecting 208000 events.
[09:36:29.528] <TB3> INFO: 208000 events read in total (18402ms).
[09:36:29.537] <TB3> INFO: Test took 19553ms.
[09:36:29.627] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[09:36:29.896] <TB3> INFO: dacScan step from 92 .. 92
[09:36:29.896] <TB3> INFO: dacScan split into 1 runs with ntrig = 50
[09:36:29.896] <TB3> INFO: run 1 of 1
[09:36:30.523] <TB3> INFO: Expecting 208000 events.
[09:36:48.528] <TB3> INFO: 208000 events read in total (17478ms).
[09:36:48.537] <TB3> INFO: Test took 18640ms.
[09:36:48.619] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[09:36:48.889] <TB3> INFO: dacScan step from 93 .. 93
[09:36:48.889] <TB3> INFO: dacScan split into 1 runs with ntrig = 50
[09:36:48.889] <TB3> INFO: run 1 of 1
[09:36:49.499] <TB3> INFO: Expecting 208000 events.
[09:37:07.703] <TB3> INFO: 208000 events read in total (17677ms).
[09:37:07.712] <TB3> INFO: Test took 18822ms.
[09:37:07.791] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[09:37:08.026] <TB3> INFO: dacScan step from 94 .. 94
[09:37:08.026] <TB3> INFO: dacScan split into 1 runs with ntrig = 50
[09:37:08.026] <TB3> INFO: run 1 of 1
[09:37:08.624] <TB3> INFO: Expecting 208000 events.
[09:37:28.038] <TB3> INFO: 208000 events read in total (18887ms).
[09:37:28.046] <TB3> INFO: Test took 20019ms.
[09:37:28.126] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[09:37:28.359] <TB3> INFO: dacScan step from 95 .. 95
[09:37:28.359] <TB3> INFO: dacScan split into 1 runs with ntrig = 50
[09:37:28.359] <TB3> INFO: run 1 of 1
[09:37:28.976] <TB3> INFO: Expecting 208000 events.
[09:37:48.261] <TB3> INFO: 208000 events read in total (18757ms).
[09:37:48.269] <TB3> INFO: Test took 19909ms.
[09:37:48.354] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[09:37:48.644] <TB3> INFO: dacScan step from 96 .. 96
[09:37:48.644] <TB3> INFO: dacScan split into 1 runs with ntrig = 50
[09:37:48.644] <TB3> INFO: run 1 of 1
[09:37:49.250] <TB3> INFO: Expecting 208000 events.
[09:38:08.541] <TB3> INFO: 208000 events read in total (18764ms).
[09:38:08.550] <TB3> INFO: Test took 19905ms.
[09:38:08.633] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[09:38:08.875] <TB3> INFO: dacScan step from 97 .. 97
[09:38:08.875] <TB3> INFO: dacScan split into 1 runs with ntrig = 50
[09:38:08.875] <TB3> INFO: run 1 of 1
[09:38:09.478] <TB3> INFO: Expecting 208000 events.
[09:38:28.633] <TB3> INFO: 208000 events read in total (18628ms).
[09:38:28.642] <TB3> INFO: Test took 19766ms.
[09:38:28.723] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[09:38:28.953] <TB3> INFO: dacScan step from 98 .. 98
[09:38:28.953] <TB3> INFO: dacScan split into 1 runs with ntrig = 50
[09:38:28.953] <TB3> INFO: run 1 of 1
[09:38:29.549] <TB3> INFO: Expecting 208000 events.
[09:38:48.142] <TB3> INFO: 208000 events read in total (18066ms).
[09:38:48.150] <TB3> INFO: Test took 19196ms.
[09:38:48.233] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[09:38:48.481] <TB3> INFO: dacScan step from 99 .. 99
[09:38:48.481] <TB3> INFO: dacScan split into 1 runs with ntrig = 50
[09:38:48.481] <TB3> INFO: run 1 of 1
[09:38:49.076] <TB3> INFO: Expecting 208000 events.
[09:39:06.681] <TB3> INFO: 208000 events read in total (17078ms).
[09:39:06.689] <TB3> INFO: Test took 18207ms.
[09:39:06.773] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[09:39:07.003] <TB3> INFO: dacScan step from 100 .. 100
[09:39:07.003] <TB3> INFO: dacScan split into 1 runs with ntrig = 50
[09:39:07.003] <TB3> INFO: run 1 of 1
[09:39:07.600] <TB3> INFO: Expecting 208000 events.
[09:39:26.573] <TB3> INFO: 208000 events read in total (18446ms).
[09:39:26.583] <TB3> INFO: Test took 19579ms.
[09:39:26.667] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[09:39:26.991] <TB3> INFO: dumping ASCII scurve output file: XSCurveData
[09:39:28.348] <TB3> INFO: dumping ASCII scurve output file: XSCurveData
[09:39:29.673] <TB3> INFO: dumping ASCII scurve output file: XSCurveData
[09:39:31.213] <TB3> INFO: dumping ASCII scurve output file: XSCurveData
[09:39:32.669] <TB3> INFO: dumping ASCII scurve output file: XSCurveData
[09:39:33.986] <TB3> INFO: dumping ASCII scurve output file: XSCurveData
[09:39:35.364] <TB3> INFO: dumping ASCII scurve output file: XSCurveData
[09:39:36.745] <TB3> INFO: dumping ASCII scurve output file: XSCurveData
[09:39:38.118] <TB3> INFO: dumping ASCII scurve output file: XSCurveData
[09:39:39.470] <TB3> INFO: dumping ASCII scurve output file: XSCurveData
[09:39:40.832] <TB3> INFO: dumping ASCII scurve output file: XSCurveData
[09:39:42.211] <TB3> INFO: dumping ASCII scurve output file: XSCurveData
[09:39:43.559] <TB3> INFO: dumping ASCII scurve output file: XSCurveData
[09:39:44.897] <TB3> INFO: dumping ASCII scurve output file: XSCurveData
[09:39:46.179] <TB3> INFO: dumping ASCII scurve output file: XSCurveData
[09:39:47.474] <TB3> INFO: dumping ASCII scurve output file: XSCurveData
[09:39:48.773] <TB3> INFO: PixTest:: pg_setup set to default.
[09:39:48.775] <TB3> INFO: vcal mean: 37.72 38.19 38.21 40.99 41.07 42.45 41.40 41.39 41.97 41.33 41.25 41.38 40.93 37.90 38.68 38.69
[09:39:48.775] <TB3> INFO: vcal RMS: 1.41 1.23 1.09 1.64 1.31 1.45 1.45 1.51 1.56 1.47 1.49 1.64 1.46 1.31 1.20 1.25
[09:39:48.775] <TB3> INFO: number of X-ray hits detected: 159606 173411 155032 296582 302535 360875 329487 338182 357323 345583 334231 315257 325038 164684 196071 182876
[09:39:48.775] <TB3> 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:39:48.775] <TB3> INFO: X-ray hit rate [MHz/cm2]: 46.8 50.8 45.4 86.9 88.7 105.8 96.6 99.1 104.7 101.3 98.0 92.4 95.3 48.3 57.5 53.6
[09:39:48.775] <TB3> INFO: PixTestHighRate::doXNoiseMaps() done
[09:39:48.885] <TB3> INFO: enter test to run
[09:39:48.885] <TB3> INFO: test: no parameter change
[09:39:49.099] <TB3> QUIET: Connection to board 121 closed.
[09:39:49.178] <TB3> INFO: pXar: this is the end, my friend

[10:01:36.008] <TB3> INFO: *** Welcome to pxar ***
[10:01:36.008] <TB3> INFO: *** Today: 2015/08/28
[10:01:36.008] <TB3> INFO: readRocDacs: /usr/local/data/M2087_XrayQualification_2015-08-28_10h32m_1440750764//010_CalDelScanAndSaveDacs_4mA25kV//dacParameters35_C0.dat .. /usr/local/data/M2087_XrayQualification_2015-08-28_10h32m_1440750764//010_CalDelScanAndSaveDacs_4mA25kV//dacParameters35_C15.dat
[10:01:36.009] <TB3> INFO: readTbmDacs: /usr/local/data/M2087_XrayQualification_2015-08-28_10h32m_1440750764//010_CalDelScanAndSaveDacs_4mA25kV//tbmParameters_C0a.dat .. /usr/local/data/M2087_XrayQualification_2015-08-28_10h32m_1440750764//010_CalDelScanAndSaveDacs_4mA25kV//tbmParameters_C0b.dat
[10:01:36.009] <TB3> INFO: readMaskFile: /usr/local/data/M2087_XrayQualification_2015-08-28_10h32m_1440750764//010_CalDelScanAndSaveDacs_4mA25kV//defaultMaskFile.dat
[10:01:36.009] <TB3> INFO: readTrimFile: /usr/local/data/M2087_XrayQualification_2015-08-28_10h32m_1440750764//010_CalDelScanAndSaveDacs_4mA25kV//trimParameters35_C0.dat .. /usr/local/data/M2087_XrayQualification_2015-08-28_10h32m_1440750764//010_CalDelScanAndSaveDacs_4mA25kV//trimParameters35_C15.dat
[10:01:36.082] <TB3> INFO: clk: 4
[10:01:36.082] <TB3> INFO: ctr: 4
[10:01:36.082] <TB3> INFO: sda: 19
[10:01:36.082] <TB3> INFO: tin: 9
[10:01:36.082] <TB3> INFO: level: 15
[10:01:36.082] <TB3> INFO: triggerdelay: 0
[10:01:36.082] <TB3> QUIET: Instanciating API for pxar v2.5+57~g44d0777
[10:01:36.082] <TB3> INFO: Log level: INFO
[10:01:36.095] <TB3> INFO: Found DTB DTB_WWV6Z5
[10:01:36.107] <TB3> QUIET: Connection to board DTB_WWV6Z5 opened.
[10:01:36.110] <TB3> INFO: DTB startup information
--- DTB info------------------------------------------
Board id: 121
HW version: DTB1.2
FW version: 4.2
SW version: 4.4
USB id: DTB_WWV6Z5
MAC address: 40D855118079
Hostname: pixelDTB121
Comment:
------------------------------------------------------
[10:01:36.112] <TB3> INFO: RPC call hashes of host and DTB match: 397073690
[10:01:37.644] <TB3> INFO: DUT info:
[10:01:37.644] <TB3> INFO: The DUT currently contains the following objects:
[10:01:37.644] <TB3> INFO: 2 TBM Cores tbm09c (2 ON)
[10:01:37.644] <TB3> INFO: TBM Core alpha (0): 7 registers set
[10:01:37.644] <TB3> INFO: TBM Core beta (1): 7 registers set
[10:01:37.644] <TB3> INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[10:01:37.644] <TB3> INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[10:01:37.644] <TB3> INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[10:01:37.644] <TB3> INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[10:01:37.644] <TB3> INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[10:01:37.644] <TB3> INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[10:01:37.644] <TB3> INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[10:01:37.644] <TB3> INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[10:01:37.644] <TB3> INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[10:01:37.645] <TB3> INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[10:01:37.645] <TB3> INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[10:01:37.645] <TB3> INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[10:01:37.645] <TB3> INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[10:01:37.645] <TB3> INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[10:01:37.645] <TB3> INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[10:01:37.645] <TB3> INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[10:01:37.645] <TB3> INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[10:01:38.046] <TB3> INFO: enter 'restricted' command line mode
[10:01:38.046] <TB3> INFO: enter test to run
[10:01:38.046] <TB3> INFO: test: HighRate no parameter change
[10:01:38.046] <TB3> INFO: running: highrate
[10:01:38.047] <TB3> INFO: readGainPedestalParameters /usr/local/data/M2087_XrayQualification_2015-08-28_10h32m_1440750764//010_CalDelScanAndSaveDacs_4mA25kV//phCalibrationFitErr35_C0.dat .. /usr/local/data/M2087_XrayQualification_2015-08-28_10h32m_1440750764//010_CalDelScanAndSaveDacs_4mA25kV//phCalibrationFitErr35_C15.dat
[10:01:38.190] <TB3> INFO: ----------------------------------------------------------------------
[10:01:38.190] <TB3> INFO: PixTestHighRate::calDelScan() ntrig = 10, vcal = 200
[10:01:38.190] <TB3> INFO: ----------------------------------------------------------------------
[10:01:38.327] <TB3> INFO: Expecting 768 events.
[10:01:39.556] <TB3> INFO: 768 events read in total (510ms).
[10:01:39.651] <TB3> INFO: Test took 1456ms.
[10:01:40.268] <TB3> INFO: Expecting 41600 events.
[10:01:43.992] <TB3> INFO: 41600 events read in total (3197ms).
[10:01:43.993] <TB3> INFO: Test took 4339ms.
[10:01:44.067] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[10:01:44.710] <TB3> INFO: Expecting 41600 events.
[10:01:48.498] <TB3> INFO: 41600 events read in total (3261ms).
[10:01:48.500] <TB3> INFO: Test took 4405ms.
[10:01:48.574] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[10:01:49.221] <TB3> INFO: Expecting 41600 events.
[10:01:53.165] <TB3> INFO: 41600 events read in total (3417ms).
[10:01:53.167] <TB3> INFO: Test took 4559ms.
[10:01:53.241] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[10:01:53.888] <TB3> INFO: Expecting 41600 events.
[10:01:57.897] <TB3> INFO: 41600 events read in total (3482ms).
[10:01:57.898] <TB3> INFO: Test took 4625ms.
[10:01:57.971] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[10:01:58.623] <TB3> INFO: Expecting 41600 events.
[10:02:02.675] <TB3> INFO: 41600 events read in total (3524ms).
[10:02:02.677] <TB3> INFO: Test took 4675ms.
[10:02:02.748] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[10:02:03.389] <TB3> INFO: Expecting 41600 events.
[10:02:07.399] <TB3> INFO: 41600 events read in total (3483ms).
[10:02:07.401] <TB3> INFO: Test took 4625ms.
[10:02:07.474] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[10:02:08.122] <TB3> INFO: Expecting 41600 events.
[10:02:12.199] <TB3> INFO: 41600 events read in total (3550ms).
[10:02:12.201] <TB3> INFO: Test took 4701ms.
[10:02:12.273] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[10:02:12.912] <TB3> INFO: Expecting 41600 events.
[10:02:16.826] <TB3> INFO: 41600 events read in total (3387ms).
[10:02:16.828] <TB3> INFO: Test took 4529ms.
[10:02:16.899] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[10:02:17.538] <TB3> INFO: Expecting 41600 events.
[10:02:21.369] <TB3> INFO: 41600 events read in total (3303ms).
[10:02:21.371] <TB3> INFO: Test took 4445ms.
[10:02:21.443] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[10:02:22.083] <TB3> INFO: Expecting 41600 events.
[10:02:25.909] <TB3> INFO: 41600 events read in total (3299ms).
[10:02:25.910] <TB3> INFO: Test took 4440ms.
[10:02:25.987] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[10:02:26.631] <TB3> INFO: Expecting 41600 events.
[10:02:30.417] <TB3> INFO: 41600 events read in total (3259ms).
[10:02:30.418] <TB3> INFO: Test took 4400ms.
[10:02:30.489] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[10:02:31.129] <TB3> INFO: Expecting 41600 events.
[10:02:35.104] <TB3> INFO: 41600 events read in total (3448ms).
[10:02:35.105] <TB3> INFO: Test took 4589ms.
[10:02:35.174] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[10:02:35.826] <TB3> INFO: Expecting 41600 events.
[10:02:39.910] <TB3> INFO: 41600 events read in total (3557ms).
[10:02:39.911] <TB3> INFO: Test took 4710ms.
[10:02:39.984] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[10:02:40.627] <TB3> INFO: Expecting 41600 events.
[10:02:44.697] <TB3> INFO: 41600 events read in total (3543ms).
[10:02:44.699] <TB3> INFO: Test took 4685ms.
[10:02:44.774] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[10:02:45.417] <TB3> INFO: Expecting 41600 events.
[10:02:49.456] <TB3> INFO: 41600 events read in total (3512ms).
[10:02:49.458] <TB3> INFO: Test took 4654ms.
[10:02:49.534] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[10:02:50.183] <TB3> INFO: Expecting 41600 events.
[10:02:53.951] <TB3> INFO: 41600 events read in total (3241ms).
[10:02:53.952] <TB3> INFO: Test took 4382ms.
[10:02:54.029] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[10:02:54.669] <TB3> INFO: Expecting 41600 events.
[10:02:58.304] <TB3> INFO: 41600 events read in total (3108ms).
[10:02:58.306] <TB3> INFO: Test took 4250ms.
[10:02:58.371] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[10:02:59.008] <TB3> INFO: Expecting 41600 events.
[10:03:02.697] <TB3> INFO: 41600 events read in total (3162ms).
[10:03:02.699] <TB3> INFO: Test took 4303ms.
[10:03:02.764] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[10:03:03.403] <TB3> INFO: Expecting 41600 events.
[10:03:06.980] <TB3> INFO: 41600 events read in total (3050ms).
[10:03:06.982] <TB3> INFO: Test took 4192ms.
[10:03:07.051] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[10:03:07.690] <TB3> INFO: Expecting 41600 events.
[10:03:11.168] <TB3> INFO: 41600 events read in total (2951ms).
[10:03:11.170] <TB3> INFO: Test took 4092ms.
[10:03:11.235] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[10:03:11.532] <TB3> INFO: enter test to run
[10:03:11.532] <TB3> INFO: test: Pretest no parameter change
[10:03:11.532] <TB3> INFO: running: pretest
[10:03:11.532] <TB3> INFO: write dac parameters into /usr/local/data/M2087_XrayQualification_2015-08-28_10h32m_1440750764//010_CalDelScanAndSaveDacs_4mA25kV//dacParameters35_C0.dat
[10:03:11.533] <TB3> INFO: write dac parameters into /usr/local/data/M2087_XrayQualification_2015-08-28_10h32m_1440750764//010_CalDelScanAndSaveDacs_4mA25kV//dacParameters35_C1.dat
[10:03:11.533] <TB3> INFO: write dac parameters into /usr/local/data/M2087_XrayQualification_2015-08-28_10h32m_1440750764//010_CalDelScanAndSaveDacs_4mA25kV//dacParameters35_C2.dat
[10:03:11.533] <TB3> INFO: write dac parameters into /usr/local/data/M2087_XrayQualification_2015-08-28_10h32m_1440750764//010_CalDelScanAndSaveDacs_4mA25kV//dacParameters35_C3.dat
[10:03:11.533] <TB3> INFO: write dac parameters into /usr/local/data/M2087_XrayQualification_2015-08-28_10h32m_1440750764//010_CalDelScanAndSaveDacs_4mA25kV//dacParameters35_C4.dat
[10:03:11.533] <TB3> INFO: write dac parameters into /usr/local/data/M2087_XrayQualification_2015-08-28_10h32m_1440750764//010_CalDelScanAndSaveDacs_4mA25kV//dacParameters35_C5.dat
[10:03:11.533] <TB3> INFO: write dac parameters into /usr/local/data/M2087_XrayQualification_2015-08-28_10h32m_1440750764//010_CalDelScanAndSaveDacs_4mA25kV//dacParameters35_C6.dat
[10:03:11.533] <TB3> INFO: write dac parameters into /usr/local/data/M2087_XrayQualification_2015-08-28_10h32m_1440750764//010_CalDelScanAndSaveDacs_4mA25kV//dacParameters35_C7.dat
[10:03:11.533] <TB3> INFO: write dac parameters into /usr/local/data/M2087_XrayQualification_2015-08-28_10h32m_1440750764//010_CalDelScanAndSaveDacs_4mA25kV//dacParameters35_C8.dat
[10:03:11.533] <TB3> INFO: write dac parameters into /usr/local/data/M2087_XrayQualification_2015-08-28_10h32m_1440750764//010_CalDelScanAndSaveDacs_4mA25kV//dacParameters35_C9.dat
[10:03:11.534] <TB3> INFO: write dac parameters into /usr/local/data/M2087_XrayQualification_2015-08-28_10h32m_1440750764//010_CalDelScanAndSaveDacs_4mA25kV//dacParameters35_C10.dat
[10:03:11.534] <TB3> INFO: write dac parameters into /usr/local/data/M2087_XrayQualification_2015-08-28_10h32m_1440750764//010_CalDelScanAndSaveDacs_4mA25kV//dacParameters35_C11.dat
[10:03:11.534] <TB3> INFO: write dac parameters into /usr/local/data/M2087_XrayQualification_2015-08-28_10h32m_1440750764//010_CalDelScanAndSaveDacs_4mA25kV//dacParameters35_C12.dat
[10:03:11.534] <TB3> INFO: write dac parameters into /usr/local/data/M2087_XrayQualification_2015-08-28_10h32m_1440750764//010_CalDelScanAndSaveDacs_4mA25kV//dacParameters35_C13.dat
[10:03:11.534] <TB3> INFO: write dac parameters into /usr/local/data/M2087_XrayQualification_2015-08-28_10h32m_1440750764//010_CalDelScanAndSaveDacs_4mA25kV//dacParameters35_C14.dat
[10:03:11.534] <TB3> INFO: write dac parameters into /usr/local/data/M2087_XrayQualification_2015-08-28_10h32m_1440750764//010_CalDelScanAndSaveDacs_4mA25kV//dacParameters35_C15.dat
[10:03:11.534] <TB3> INFO: enter test to run
[10:03:11.534] <TB3> INFO: test: no parameter change
[10:03:11.701] <TB3> QUIET: Connection to board 121 closed.
[10:03:11.781] <TB3> INFO: pXar: this is the end, my friend

[10:03:46.905] <TB3> INFO: *** Welcome to pxar ***
[10:03:46.905] <TB3> INFO: *** Today: 2015/08/28
[10:03:46.905] <TB3> INFO: readRocDacs: /usr/local/data/M2087_XrayQualification_2015-08-28_10h32m_1440750764//011_HREfficiency_50//dacParameters35_C0.dat .. /usr/local/data/M2087_XrayQualification_2015-08-28_10h32m_1440750764//011_HREfficiency_50//dacParameters35_C15.dat
[10:03:46.905] <TB3> INFO: readTbmDacs: /usr/local/data/M2087_XrayQualification_2015-08-28_10h32m_1440750764//011_HREfficiency_50//tbmParameters_C0a.dat .. /usr/local/data/M2087_XrayQualification_2015-08-28_10h32m_1440750764//011_HREfficiency_50//tbmParameters_C0b.dat
[10:03:46.905] <TB3> INFO: readMaskFile: /usr/local/data/M2087_XrayQualification_2015-08-28_10h32m_1440750764//011_HREfficiency_50//defaultMaskFile.dat
[10:03:46.905] <TB3> INFO: readTrimFile: /usr/local/data/M2087_XrayQualification_2015-08-28_10h32m_1440750764//011_HREfficiency_50//trimParameters35_C0.dat .. /usr/local/data/M2087_XrayQualification_2015-08-28_10h32m_1440750764//011_HREfficiency_50//trimParameters35_C15.dat
[10:03:46.976] <TB3> INFO: clk: 4
[10:03:46.976] <TB3> INFO: ctr: 4
[10:03:46.976] <TB3> INFO: sda: 19
[10:03:46.976] <TB3> INFO: tin: 9
[10:03:46.976] <TB3> INFO: level: 15
[10:03:46.976] <TB3> INFO: triggerdelay: 0
[10:03:46.976] <TB3> QUIET: Instanciating API for pxar v2.5+57~g44d0777
[10:03:46.976] <TB3> INFO: Log level: INFO
[10:03:46.988] <TB3> INFO: Found DTB DTB_WWV6Z5
[10:03:47.000] <TB3> QUIET: Connection to board DTB_WWV6Z5 opened.
[10:03:47.003] <TB3> INFO: DTB startup information
--- DTB info------------------------------------------
Board id: 121
HW version: DTB1.2
FW version: 4.2
SW version: 4.4
USB id: DTB_WWV6Z5
MAC address: 40D855118079
Hostname: pixelDTB121
Comment:
------------------------------------------------------
[10:03:47.006] <TB3> INFO: RPC call hashes of host and DTB match: 397073690
[10:03:48.528] <TB3> INFO: DUT info:
[10:03:48.528] <TB3> INFO: The DUT currently contains the following objects:
[10:03:48.528] <TB3> INFO: 2 TBM Cores tbm09c (2 ON)
[10:03:48.528] <TB3> INFO: TBM Core alpha (0): 7 registers set
[10:03:48.528] <TB3> INFO: TBM Core beta (1): 7 registers set
[10:03:48.528] <TB3> INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[10:03:48.528] <TB3> INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[10:03:48.528] <TB3> INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[10:03:48.528] <TB3> INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[10:03:48.528] <TB3> INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[10:03:48.528] <TB3> INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[10:03:48.528] <TB3> INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[10:03:48.528] <TB3> INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[10:03:48.528] <TB3> INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[10:03:48.528] <TB3> INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[10:03:48.528] <TB3> INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[10:03:48.528] <TB3> INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[10:03:48.528] <TB3> INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[10:03:48.528] <TB3> INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[10:03:48.529] <TB3> INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[10:03:48.529] <TB3> INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[10:03:48.529] <TB3> INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[10:03:48.930] <TB3> INFO: enter 'restricted' command line mode
[10:03:48.930] <TB3> INFO: enter test to run
[10:03:48.930] <TB3> INFO: test: HighRate no parameter change
[10:03:48.930] <TB3> INFO: running: highrate
[10:03:48.930] <TB3> INFO: readGainPedestalParameters /usr/local/data/M2087_XrayQualification_2015-08-28_10h32m_1440750764//011_HREfficiency_50//phCalibrationFitErr35_C0.dat .. /usr/local/data/M2087_XrayQualification_2015-08-28_10h32m_1440750764//011_HREfficiency_50//phCalibrationFitErr35_C15.dat
[10:03:49.066] <TB3> INFO: ----------------------------------------------------------------------
[10:03:49.066] <TB3> INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[10:03:49.066] <TB3> INFO: ----------------------------------------------------------------------
[10:03:49.668] <TB3> INFO: Expecting 208000 events.
[10:04:03.268] <TB3> INFO: 208000 events read in total (13070ms).
[10:04:03.274] <TB3> INFO: Test took 14203ms.
[10:04:03.508] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[10:04:03.807] <TB3> INFO: number of dead pixels (per ROC): 1 0 0 2 0 0 0 0 0 0 0 0 0 0 0 0
[10:04:03.807] <TB3> INFO: number of red-efficiency pixels: 100 89 92 214 213 334 254 253 288 293 243 225 271 118 132 105
[10:04:03.807] <TB3> INFO: number of X-ray hits detected: 79350 84671 76326 148735 152150 184638 166582 169519 179686 174338 168493 157902 162656 79818 96531 90553
[10:04:03.807] <TB3> 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:03.807] <TB3> INFO: number of Vcal hits detected: 207850 207908 207908 207678 207777 207645 207735 207739 207705 207687 207748 207761 207722 207878 207868 207889
[10:04:03.807] <TB3> INFO: Vcal hit fiducial efficiency (%): 100.0 100.0 100.0 99.9 99.9 99.8 99.9 99.9 99.9 99.9 99.9 99.9 99.9 100.0 99.9 99.9
[10:04:03.807] <TB3> INFO: Vcal hit overall efficiency (%): 99.9 100.0 100.0 99.8 99.9 99.8 99.9 99.9 99.9 99.8 99.9 99.9 99.9 99.9 99.9 99.9
[10:04:03.807] <TB3> INFO: X-ray hit rate [MHz/cm2]: 23.3 24.8 22.4 43.6 44.6 54.1 48.8 49.7 52.7 51.1 49.4 46.3 47.7 23.4 28.3 26.5
[10:04:03.807] <TB3> INFO: PixTestHighRate::doXPixelAlive() done
[10:04:03.833] <TB3> INFO: PixTest:: pg_setup set to default.
[10:04:03.865] <TB3> INFO: enter test to run
[10:04:03.865] <TB3> INFO: test: no parameter change
[10:04:03.999] <TB3> QUIET: Connection to board 121 closed.
[10:04:04.078] <TB3> INFO: pXar: this is the end, my friend

[10:04:37.771] <TB3> INFO: *** Welcome to pxar ***
[10:04:37.771] <TB3> INFO: *** Today: 2015/08/28
[10:04:37.771] <TB3> INFO: readRocDacs: /usr/local/data/M2087_XrayQualification_2015-08-28_10h32m_1440750764//012_HREfficiency_100//dacParameters35_C0.dat .. /usr/local/data/M2087_XrayQualification_2015-08-28_10h32m_1440750764//012_HREfficiency_100//dacParameters35_C15.dat
[10:04:37.771] <TB3> INFO: readTbmDacs: /usr/local/data/M2087_XrayQualification_2015-08-28_10h32m_1440750764//012_HREfficiency_100//tbmParameters_C0a.dat .. /usr/local/data/M2087_XrayQualification_2015-08-28_10h32m_1440750764//012_HREfficiency_100//tbmParameters_C0b.dat
[10:04:37.771] <TB3> INFO: readMaskFile: /usr/local/data/M2087_XrayQualification_2015-08-28_10h32m_1440750764//012_HREfficiency_100//defaultMaskFile.dat
[10:04:37.771] <TB3> INFO: readTrimFile: /usr/local/data/M2087_XrayQualification_2015-08-28_10h32m_1440750764//012_HREfficiency_100//trimParameters35_C0.dat .. /usr/local/data/M2087_XrayQualification_2015-08-28_10h32m_1440750764//012_HREfficiency_100//trimParameters35_C15.dat
[10:04:37.843] <TB3> INFO: clk: 4
[10:04:37.843] <TB3> INFO: ctr: 4
[10:04:37.843] <TB3> INFO: sda: 19
[10:04:37.843] <TB3> INFO: tin: 9
[10:04:37.843] <TB3> INFO: level: 15
[10:04:37.843] <TB3> INFO: triggerdelay: 0
[10:04:37.843] <TB3> QUIET: Instanciating API for pxar v2.5+57~g44d0777
[10:04:37.843] <TB3> INFO: Log level: INFO
[10:04:37.855] <TB3> INFO: Found DTB DTB_WWV6Z5
[10:04:37.866] <TB3> QUIET: Connection to board DTB_WWV6Z5 opened.
[10:04:37.869] <TB3> INFO: DTB startup information
--- DTB info------------------------------------------
Board id: 121
HW version: DTB1.2
FW version: 4.2
SW version: 4.4
USB id: DTB_WWV6Z5
MAC address: 40D855118079
Hostname: pixelDTB121
Comment:
------------------------------------------------------
[10:04:37.872] <TB3> INFO: RPC call hashes of host and DTB match: 397073690
[10:04:39.413] <TB3> INFO: DUT info:
[10:04:39.413] <TB3> INFO: The DUT currently contains the following objects:
[10:04:39.413] <TB3> INFO: 2 TBM Cores tbm09c (2 ON)
[10:04:39.413] <TB3> INFO: TBM Core alpha (0): 7 registers set
[10:04:39.413] <TB3> INFO: TBM Core beta (1): 7 registers set
[10:04:39.413] <TB3> INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[10:04:39.413] <TB3> INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[10:04:39.413] <TB3> INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[10:04:39.413] <TB3> INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[10:04:39.413] <TB3> INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[10:04:39.413] <TB3> INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[10:04:39.413] <TB3> INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[10:04:39.413] <TB3> INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[10:04:39.413] <TB3> INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[10:04:39.413] <TB3> INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[10:04:39.413] <TB3> INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[10:04:39.414] <TB3> INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[10:04:39.414] <TB3> INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[10:04:39.414] <TB3> INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[10:04:39.414] <TB3> INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[10:04:39.414] <TB3> INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[10:04:39.414] <TB3> INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[10:04:39.814] <TB3> INFO: enter 'restricted' command line mode
[10:04:39.814] <TB3> INFO: enter test to run
[10:04:39.815] <TB3> INFO: test: HighRate no parameter change
[10:04:39.815] <TB3> INFO: running: highrate
[10:04:39.815] <TB3> INFO: readGainPedestalParameters /usr/local/data/M2087_XrayQualification_2015-08-28_10h32m_1440750764//012_HREfficiency_100//phCalibrationFitErr35_C0.dat .. /usr/local/data/M2087_XrayQualification_2015-08-28_10h32m_1440750764//012_HREfficiency_100//phCalibrationFitErr35_C15.dat
[10:04:39.967] <TB3> INFO: ----------------------------------------------------------------------
[10:04:39.967] <TB3> INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[10:04:39.967] <TB3> INFO: ----------------------------------------------------------------------
[10:04:40.598] <TB3> INFO: Expecting 208000 events.
[10:04:58.126] <TB3> INFO: 208000 events read in total (16998ms).
[10:04:58.139] <TB3> INFO: Test took 18166ms.
[10:04:58.617] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[10:04:58.992] <TB3> INFO: number of dead pixels (per ROC): 1 0 0 2 0 0 0 0 0 0 0 0 0 0 0 0
[10:04:58.992] <TB3> INFO: number of red-efficiency pixels: 302 253 266 722 752 1404 799 849 986 976 872 801 796 339 342 280
[10:04:58.992] <TB3> INFO: number of X-ray hits detected: 159508 173786 155604 297432 305297 362606 331306 339006 358994 346161 337906 315890 325088 164743 195722 182742
[10:04:58.992] <TB3> 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:58.992] <TB3> INFO: number of Vcal hits detected: 207637 207730 207696 207081 207102 206163 207015 207029 206807 206784 206926 207039 207083 207614 207643 207706
[10:04:58.992] <TB3> INFO: Vcal hit fiducial efficiency (%): 99.9 99.9 99.9 99.6 99.6 99.2 99.6 99.6 99.5 99.5 99.6 99.6 99.6 99.8 99.8 99.9
[10:04:58.992] <TB3> INFO: Vcal hit overall efficiency (%): 99.8 99.9 99.9 99.6 99.6 99.1 99.5 99.5 99.4 99.4 99.5 99.5 99.6 99.8 99.8 99.9
[10:04:58.992] <TB3> INFO: X-ray hit rate [MHz/cm2]: 46.8 50.9 45.6 87.2 89.5 106.3 97.1 99.4 105.2 101.5 99.0 92.6 95.3 48.3 57.4 53.6
[10:04:58.992] <TB3> INFO: PixTestHighRate::doXPixelAlive() done
[10:04:59.018] <TB3> INFO: PixTest:: pg_setup set to default.
[10:04:59.051] <TB3> INFO: enter test to run
[10:04:59.052] <TB3> INFO: test: no parameter change
[10:04:59.213] <TB3> QUIET: Connection to board 121 closed.
[10:04:59.295] <TB3> INFO: pXar: this is the end, my friend

[10:05:34.806] <TB3> INFO: *** Welcome to pxar ***
[10:05:34.806] <TB3> INFO: *** Today: 2015/08/28
[10:05:34.806] <TB3> INFO: readRocDacs: /usr/local/data/M2087_XrayQualification_2015-08-28_10h32m_1440750764//013_HREfficiency_150//dacParameters35_C0.dat .. /usr/local/data/M2087_XrayQualification_2015-08-28_10h32m_1440750764//013_HREfficiency_150//dacParameters35_C15.dat
[10:05:34.808] <TB3> INFO: readTbmDacs: /usr/local/data/M2087_XrayQualification_2015-08-28_10h32m_1440750764//013_HREfficiency_150//tbmParameters_C0a.dat .. /usr/local/data/M2087_XrayQualification_2015-08-28_10h32m_1440750764//013_HREfficiency_150//tbmParameters_C0b.dat
[10:05:34.808] <TB3> INFO: readMaskFile: /usr/local/data/M2087_XrayQualification_2015-08-28_10h32m_1440750764//013_HREfficiency_150//defaultMaskFile.dat
[10:05:34.808] <TB3> INFO: readTrimFile: /usr/local/data/M2087_XrayQualification_2015-08-28_10h32m_1440750764//013_HREfficiency_150//trimParameters35_C0.dat .. /usr/local/data/M2087_XrayQualification_2015-08-28_10h32m_1440750764//013_HREfficiency_150//trimParameters35_C15.dat
[10:05:34.889] <TB3> INFO: clk: 4
[10:05:34.889] <TB3> INFO: ctr: 4
[10:05:34.889] <TB3> INFO: sda: 19
[10:05:34.889] <TB3> INFO: tin: 9
[10:05:34.889] <TB3> INFO: level: 15
[10:05:34.889] <TB3> INFO: triggerdelay: 0
[10:05:34.889] <TB3> QUIET: Instanciating API for pxar v2.5+57~g44d0777
[10:05:34.889] <TB3> INFO: Log level: INFO
[10:05:34.902] <TB3> INFO: Found DTB DTB_WWV6Z5
[10:05:34.913] <TB3> QUIET: Connection to board DTB_WWV6Z5 opened.
[10:05:34.916] <TB3> INFO: DTB startup information
--- DTB info------------------------------------------
Board id: 121
HW version: DTB1.2
FW version: 4.2
SW version: 4.4
USB id: DTB_WWV6Z5
MAC address: 40D855118079
Hostname: pixelDTB121
Comment:
------------------------------------------------------
[10:05:34.919] <TB3> INFO: RPC call hashes of host and DTB match: 397073690
[10:05:36.447] <TB3> INFO: DUT info:
[10:05:36.447] <TB3> INFO: The DUT currently contains the following objects:
[10:05:36.447] <TB3> INFO: 2 TBM Cores tbm09c (2 ON)
[10:05:36.447] <TB3> INFO: TBM Core alpha (0): 7 registers set
[10:05:36.447] <TB3> INFO: TBM Core beta (1): 7 registers set
[10:05:36.447] <TB3> INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[10:05:36.447] <TB3> INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[10:05:36.447] <TB3> INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[10:05:36.447] <TB3> INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[10:05:36.447] <TB3> INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[10:05:36.447] <TB3> INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[10:05:36.447] <TB3> INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[10:05:36.447] <TB3> INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[10:05:36.447] <TB3> INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[10:05:36.447] <TB3> INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[10:05:36.447] <TB3> INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[10:05:36.447] <TB3> INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[10:05:36.447] <TB3> INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[10:05:36.447] <TB3> INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[10:05:36.447] <TB3> INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[10:05:36.447] <TB3> INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[10:05:36.447] <TB3> INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[10:05:36.848] <TB3> INFO: enter 'restricted' command line mode
[10:05:36.848] <TB3> INFO: enter test to run
[10:05:36.848] <TB3> INFO: test: HighRate no parameter change
[10:05:36.848] <TB3> INFO: running: highrate
[10:05:36.849] <TB3> INFO: readGainPedestalParameters /usr/local/data/M2087_XrayQualification_2015-08-28_10h32m_1440750764//013_HREfficiency_150//phCalibrationFitErr35_C0.dat .. /usr/local/data/M2087_XrayQualification_2015-08-28_10h32m_1440750764//013_HREfficiency_150//phCalibrationFitErr35_C15.dat
[10:05:36.996] <TB3> INFO: ----------------------------------------------------------------------
[10:05:36.996] <TB3> INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[10:05:36.996] <TB3> INFO: ----------------------------------------------------------------------
[10:05:37.597] <TB3> INFO: Expecting 208000 events.
[10:05:58.623] <TB3> INFO: 208000 events read in total (20496ms).
[10:05:58.633] <TB3> INFO: Test took 21631ms.
[10:05:59.296] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[10:05:59.730] <TB3> INFO: number of dead pixels (per ROC): 1 0 0 2 0 0 0 0 0 0 0 0 0 0 0 0
[10:05:59.730] <TB3> INFO: number of red-efficiency pixels: 569 510 561 1504 1490 2493 1756 1865 2074 2011 1799 1582 1775 657 640 561
[10:05:59.730] <TB3> INFO: number of X-ray hits detected: 232522 252485 226101 427544 439439 520398 476419 489924 516189 499159 487625 454945 467764 238937 283924 264560
[10:05:59.730] <TB3> 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:59.730] <TB3> INFO: number of Vcal hits detected: 207334 207457 207246 205756 205799 203156 205180 205318 204698 204569 205231 205592 205403 207124 207279 207367
[10:05:59.730] <TB3> INFO: Vcal hit fiducial efficiency (%): 99.7 99.8 99.7 99.1 99.1 98.0 98.9 98.8 98.6 98.6 98.8 99.0 98.9 99.6 99.7 99.7
[10:05:59.730] <TB3> INFO: Vcal hit overall efficiency (%): 99.7 99.7 99.6 98.9 98.9 97.7 98.6 98.7 98.4 98.4 98.7 98.8 98.8 99.6 99.7 99.7
[10:05:59.730] <TB3> INFO: X-ray hit rate [MHz/cm2]: 68.2 74.0 66.3 125.3 128.8 152.5 139.6 143.6 151.3 146.3 142.9 133.3 137.1 70.0 83.2 77.5
[10:05:59.730] <TB3> INFO: PixTestHighRate::doXPixelAlive() done
[10:05:59.755] <TB3> INFO: PixTest:: pg_setup set to default.
[10:05:59.789] <TB3> INFO: enter test to run
[10:05:59.789] <TB3> INFO: test: no parameter change
[10:05:59.954] <TB3> QUIET: Connection to board 121 closed.
[10:06:00.035] <TB3> INFO: pXar: this is the end, my friend

[10:06:35.223] <TB3> INFO: *** Welcome to pxar ***
[10:06:35.223] <TB3> INFO: *** Today: 2015/08/28
[10:06:35.223] <TB3> INFO: readRocDacs: /usr/local/data/M2087_XrayQualification_2015-08-28_10h32m_1440750764//014_HREfficiency_200//dacParameters35_C0.dat .. /usr/local/data/M2087_XrayQualification_2015-08-28_10h32m_1440750764//014_HREfficiency_200//dacParameters35_C15.dat
[10:06:35.224] <TB3> INFO: readTbmDacs: /usr/local/data/M2087_XrayQualification_2015-08-28_10h32m_1440750764//014_HREfficiency_200//tbmParameters_C0a.dat .. /usr/local/data/M2087_XrayQualification_2015-08-28_10h32m_1440750764//014_HREfficiency_200//tbmParameters_C0b.dat
[10:06:35.224] <TB3> INFO: readMaskFile: /usr/local/data/M2087_XrayQualification_2015-08-28_10h32m_1440750764//014_HREfficiency_200//defaultMaskFile.dat
[10:06:35.224] <TB3> INFO: readTrimFile: /usr/local/data/M2087_XrayQualification_2015-08-28_10h32m_1440750764//014_HREfficiency_200//trimParameters35_C0.dat .. /usr/local/data/M2087_XrayQualification_2015-08-28_10h32m_1440750764//014_HREfficiency_200//trimParameters35_C15.dat
[10:06:35.301] <TB3> INFO: clk: 4
[10:06:35.301] <TB3> INFO: ctr: 4
[10:06:35.301] <TB3> INFO: sda: 19
[10:06:35.301] <TB3> INFO: tin: 9
[10:06:35.301] <TB3> INFO: level: 15
[10:06:35.301] <TB3> INFO: triggerdelay: 0
[10:06:35.301] <TB3> QUIET: Instanciating API for pxar v2.5+57~g44d0777
[10:06:35.301] <TB3> INFO: Log level: INFO
[10:06:35.314] <TB3> INFO: Found DTB DTB_WWV6Z5
[10:06:35.326] <TB3> QUIET: Connection to board DTB_WWV6Z5 opened.
[10:06:35.329] <TB3> INFO: DTB startup information
--- DTB info------------------------------------------
Board id: 121
HW version: DTB1.2
FW version: 4.2
SW version: 4.4
USB id: DTB_WWV6Z5
MAC address: 40D855118079
Hostname: pixelDTB121
Comment:
------------------------------------------------------
[10:06:35.331] <TB3> INFO: RPC call hashes of host and DTB match: 397073690
[10:06:36.879] <TB3> INFO: DUT info:
[10:06:36.879] <TB3> INFO: The DUT currently contains the following objects:
[10:06:36.879] <TB3> INFO: 2 TBM Cores tbm09c (2 ON)
[10:06:36.879] <TB3> INFO: TBM Core alpha (0): 7 registers set
[10:06:36.879] <TB3> INFO: TBM Core beta (1): 7 registers set
[10:06:36.879] <TB3> INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[10:06:36.879] <TB3> INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[10:06:36.879] <TB3> INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[10:06:36.879] <TB3> INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[10:06:36.879] <TB3> INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[10:06:36.879] <TB3> INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[10:06:36.879] <TB3> INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[10:06:36.879] <TB3> INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[10:06:36.879] <TB3> INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[10:06:36.879] <TB3> INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[10:06:36.879] <TB3> INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[10:06:36.879] <TB3> INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[10:06:36.879] <TB3> INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[10:06:36.879] <TB3> INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[10:06:36.879] <TB3> INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[10:06:36.879] <TB3> INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[10:06:36.879] <TB3> INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[10:06:37.280] <TB3> INFO: enter 'restricted' command line mode
[10:06:37.280] <TB3> INFO: enter test to run
[10:06:37.280] <TB3> INFO: test: HighRate no parameter change
[10:06:37.280] <TB3> INFO: running: highrate
[10:06:37.281] <TB3> INFO: readGainPedestalParameters /usr/local/data/M2087_XrayQualification_2015-08-28_10h32m_1440750764//014_HREfficiency_200//phCalibrationFitErr35_C0.dat .. /usr/local/data/M2087_XrayQualification_2015-08-28_10h32m_1440750764//014_HREfficiency_200//phCalibrationFitErr35_C15.dat
[10:06:37.445] <TB3> INFO: ----------------------------------------------------------------------
[10:06:37.445] <TB3> INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[10:06:37.445] <TB3> INFO: ----------------------------------------------------------------------
[10:06:38.062] <TB3> INFO: Expecting 208000 events.
[10:07:03.225] <TB3> INFO: 208000 events read in total (24633ms).
[10:07:03.238] <TB3> INFO: Test took 25787ms.
[10:07:04.136] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[10:07:04.646] <TB3> INFO: number of dead pixels (per ROC): 1 0 0 2 0 0 0 0 0 0 0 0 0 0 0 0
[10:07:04.646] <TB3> INFO: number of red-efficiency pixels: 1062 958 926 2543 2577 3654 2837 3113 3232 3119 2949 2655 2874 1001 1173 1026
[10:07:04.646] <TB3> INFO: number of X-ray hits detected: 316428 345091 308076 573978 591985 695942 641905 660710 694434 671198 653280 611159 634040 326948 386421 358475
[10:07:04.646] <TB3> 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:07:04.646] <TB3> INFO: number of Vcal hits detected: 206653 206877 206301 202924 202633 196232 201209 201328 200177 199792 201266 202340 202001 206167 206512 206753
[10:07:04.646] <TB3> INFO: Vcal hit fiducial efficiency (%): 99.4 99.5 99.4 97.9 97.8 95.0 97.2 97.1 96.7 96.6 97.1 97.6 97.5 99.3 99.3 99.5
[10:07:04.646] <TB3> INFO: Vcal hit overall efficiency (%): 99.4 99.5 99.2 97.6 97.4 94.3 96.7 96.8 96.2 96.1 96.8 97.3 97.1 99.1 99.3 99.4
[10:07:04.646] <TB3> INFO: X-ray hit rate [MHz/cm2]: 92.7 101.1 90.3 168.2 173.5 204.0 188.1 193.7 203.5 196.7 191.5 179.1 185.8 95.8 113.3 105.1
[10:07:04.646] <TB3> INFO: PixTestHighRate::doXPixelAlive() done
[10:07:04.672] <TB3> INFO: PixTest:: pg_setup set to default.
[10:07:04.709] <TB3> INFO: enter test to run
[10:07:04.709] <TB3> INFO: test: no parameter change
[10:07:04.865] <TB3> QUIET: Connection to board 121 closed.
[10:07:04.946] <TB3> INFO: pXar: this is the end, my friend

[10:07:39.564] <TB3> INFO: *** Welcome to pxar ***
[10:07:39.564] <TB3> INFO: *** Today: 2015/08/28
[10:07:39.564] <TB3> INFO: readRocDacs: /usr/local/data/M2087_XrayQualification_2015-08-28_10h32m_1440750764//015_HREfficiency_250//dacParameters35_C0.dat .. /usr/local/data/M2087_XrayQualification_2015-08-28_10h32m_1440750764//015_HREfficiency_250//dacParameters35_C15.dat
[10:07:39.566] <TB3> INFO: readTbmDacs: /usr/local/data/M2087_XrayQualification_2015-08-28_10h32m_1440750764//015_HREfficiency_250//tbmParameters_C0a.dat .. /usr/local/data/M2087_XrayQualification_2015-08-28_10h32m_1440750764//015_HREfficiency_250//tbmParameters_C0b.dat
[10:07:39.566] <TB3> INFO: readMaskFile: /usr/local/data/M2087_XrayQualification_2015-08-28_10h32m_1440750764//015_HREfficiency_250//defaultMaskFile.dat
[10:07:39.566] <TB3> INFO: readTrimFile: /usr/local/data/M2087_XrayQualification_2015-08-28_10h32m_1440750764//015_HREfficiency_250//trimParameters35_C0.dat .. /usr/local/data/M2087_XrayQualification_2015-08-28_10h32m_1440750764//015_HREfficiency_250//trimParameters35_C15.dat
[10:07:39.693] <TB3> INFO: clk: 4
[10:07:39.693] <TB3> INFO: ctr: 4
[10:07:39.693] <TB3> INFO: sda: 19
[10:07:39.693] <TB3> INFO: tin: 9
[10:07:39.693] <TB3> INFO: level: 15
[10:07:39.693] <TB3> INFO: triggerdelay: 0
[10:07:39.693] <TB3> QUIET: Instanciating API for pxar v2.5+57~g44d0777
[10:07:39.693] <TB3> INFO: Log level: INFO
[10:07:39.705] <TB3> INFO: Found DTB DTB_WWV6Z5
[10:07:39.717] <TB3> QUIET: Connection to board DTB_WWV6Z5 opened.
[10:07:39.721] <TB3> INFO: DTB startup information
--- DTB info------------------------------------------
Board id: 121
HW version: DTB1.2
FW version: 4.2
SW version: 4.4
USB id: DTB_WWV6Z5
MAC address: 40D855118079
Hostname: pixelDTB121
Comment:
------------------------------------------------------
[10:07:39.723] <TB3> INFO: RPC call hashes of host and DTB match: 397073690
[10:07:41.273] <TB3> INFO: DUT info:
[10:07:41.273] <TB3> INFO: The DUT currently contains the following objects:
[10:07:41.273] <TB3> INFO: 2 TBM Cores tbm09c (2 ON)
[10:07:41.273] <TB3> INFO: TBM Core alpha (0): 7 registers set
[10:07:41.273] <TB3> INFO: TBM Core beta (1): 7 registers set
[10:07:41.273] <TB3> INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[10:07:41.273] <TB3> INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[10:07:41.273] <TB3> INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[10:07:41.273] <TB3> INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[10:07:41.273] <TB3> INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[10:07:41.273] <TB3> INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[10:07:41.273] <TB3> INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[10:07:41.273] <TB3> INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[10:07:41.273] <TB3> INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[10:07:41.273] <TB3> INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[10:07:41.273] <TB3> INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[10:07:41.273] <TB3> INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[10:07:41.273] <TB3> INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[10:07:41.273] <TB3> INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[10:07:41.274] <TB3> INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[10:07:41.274] <TB3> INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[10:07:41.274] <TB3> INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[10:07:41.675] <TB3> INFO: enter 'restricted' command line mode
[10:07:41.675] <TB3> INFO: enter test to run
[10:07:41.675] <TB3> INFO: test: HighRate no parameter change
[10:07:41.675] <TB3> INFO: running: highrate
[10:07:41.675] <TB3> INFO: readGainPedestalParameters /usr/local/data/M2087_XrayQualification_2015-08-28_10h32m_1440750764//015_HREfficiency_250//phCalibrationFitErr35_C0.dat .. /usr/local/data/M2087_XrayQualification_2015-08-28_10h32m_1440750764//015_HREfficiency_250//phCalibrationFitErr35_C15.dat
[10:07:41.856] <TB3> INFO: ----------------------------------------------------------------------
[10:07:41.856] <TB3> INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[10:07:41.856] <TB3> INFO: ----------------------------------------------------------------------
[10:07:42.494] <TB3> INFO: Expecting 208000 events.
[10:08:09.269] <TB3> INFO: 208000 events read in total (26246ms).
[10:08:09.283] <TB3> INFO: Test took 27421ms.
[10:08:10.189] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[10:08:10.700] <TB3> INFO: number of dead pixels (per ROC): 1 0 0 2 0 0 0 0 0 0 0 0 0 0 0 0
[10:08:10.700] <TB3> INFO: number of red-efficiency pixels: 1098 1090 965 2624 2694 3749 2966 3268 3400 3276 3074 2784 2933 1106 1346 1080
[10:08:10.700] <TB3> INFO: number of X-ray hits detected: 327054 358412 321584 589921 609158 715487 662313 682570 719296 692994 673116 631483 651176 343029 401428 367421
[10:08:10.700] <TB3> 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:10.700] <TB3> INFO: number of Vcal hits detected: 206595 206673 206171 202676 202117 195611 200602 200549 199302 198993 200738 201769 201447 205951 206282 206676
[10:08:10.700] <TB3> INFO: Vcal hit fiducial efficiency (%): 99.4 99.4 99.3 97.8 97.6 94.6 96.9 96.8 96.2 96.2 96.9 97.4 97.2 99.2 99.2 99.4
[10:08:10.701] <TB3> INFO: Vcal hit overall efficiency (%): 99.3 99.4 99.1 97.4 97.2 94.0 96.4 96.4 95.8 95.7 96.5 97.0 96.8 99.0 99.2 99.4
[10:08:10.701] <TB3> INFO: X-ray hit rate [MHz/cm2]: 95.9 105.1 94.3 172.9 178.5 209.7 194.1 200.1 210.8 203.1 197.3 185.1 190.9 100.5 117.7 107.7
[10:08:10.701] <TB3> INFO: PixTestHighRate::doXPixelAlive() done
[10:08:10.726] <TB3> INFO: PixTest:: pg_setup set to default.
[10:08:10.761] <TB3> INFO: enter test to run
[10:08:10.761] <TB3> INFO: test: no parameter change
[10:08:10.935] <TB3> QUIET: Connection to board 121 closed.
[10:08:11.015] <TB3> INFO: pXar: this is the end, my friend