[08:33:16.577] <TB1> INFO: *** Welcome to pxar ***
[08:33:16.577] <TB1> INFO: *** Today: 2015/08/28
[08:33:16.577] <TB1> INFO: readRocDacs: /usr/local/data/M2085_XrayQualification_2015-08-28_10h32m_1440750764//000_PixelAlive_p17//dacParameters35_C0.dat .. /usr/local/data/M2085_XrayQualification_2015-08-28_10h32m_1440750764//000_PixelAlive_p17//dacParameters35_C15.dat
[08:33:16.579] <TB1> INFO: readTbmDacs: /usr/local/data/M2085_XrayQualification_2015-08-28_10h32m_1440750764//000_PixelAlive_p17//tbmParameters_C0a.dat .. /usr/local/data/M2085_XrayQualification_2015-08-28_10h32m_1440750764//000_PixelAlive_p17//tbmParameters_C0b.dat
[08:33:16.579] <TB1> INFO: readMaskFile: /usr/local/data/M2085_XrayQualification_2015-08-28_10h32m_1440750764//000_PixelAlive_p17//defaultMaskFile.dat
[08:33:16.579] <TB1> INFO: readTrimFile: /usr/local/data/M2085_XrayQualification_2015-08-28_10h32m_1440750764//000_PixelAlive_p17//trimParameters35_C0.dat .. /usr/local/data/M2085_XrayQualification_2015-08-28_10h32m_1440750764//000_PixelAlive_p17//trimParameters35_C15.dat
[08:33:16.656] <TB1> INFO: clk: 4
[08:33:16.656] <TB1> INFO: ctr: 4
[08:33:16.656] <TB1> INFO: sda: 19
[08:33:16.656] <TB1> INFO: tin: 9
[08:33:16.656] <TB1> INFO: level: 15
[08:33:16.656] <TB1> INFO: triggerdelay: 0
[08:33:16.656] <TB1> QUIET: Instanciating API for pxar v2.5+57~g44d0777
[08:33:16.656] <TB1> INFO: Log level: INFO
[08:33:16.670] <TB1> INFO: Found DTB DTB_WS6UZO
[08:33:16.680] <TB1> QUIET: Connection to board DTB_WS6UZO opened.
[08:33:16.683] <TB1> INFO: DTB startup information
--- DTB info------------------------------------------
Board id: 83
HW version: DTB1.2
FW version: 4.2
SW version: 4.4
USB id: DTB_WS6UZO
MAC address: 40D855118053
Hostname: pixelDTB083
Comment:
------------------------------------------------------
[08:33:16.685] <TB1> INFO: RPC call hashes of host and DTB match: 397073690
[08:33:18.203] <TB1> INFO: DUT info:
[08:33:18.203] <TB1> INFO: The DUT currently contains the following objects:
[08:33:18.203] <TB1> INFO: 2 TBM Cores tbm09c (2 ON)
[08:33:18.203] <TB1> INFO: TBM Core alpha (0): 7 registers set
[08:33:18.203] <TB1> INFO: TBM Core beta (1): 7 registers set
[08:33:18.203] <TB1> INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[08:33:18.203] <TB1> INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[08:33:18.203] <TB1> INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[08:33:18.203] <TB1> INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[08:33:18.203] <TB1> INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[08:33:18.203] <TB1> INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[08:33:18.203] <TB1> INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[08:33:18.203] <TB1> INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[08:33:18.203] <TB1> INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[08:33:18.203] <TB1> INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[08:33:18.203] <TB1> INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[08:33:18.203] <TB1> INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[08:33:18.203] <TB1> INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[08:33:18.204] <TB1> INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[08:33:18.204] <TB1> INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[08:33:18.204] <TB1> INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[08:33:18.204] <TB1> INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[08:33:18.605] <TB1> INFO: enter 'restricted' command line mode
[08:33:18.605] <TB1> INFO: enter test to run
[08:33:18.605] <TB1> INFO: test: PixelAlive no parameter change
[08:33:18.605] <TB1> INFO: running: pixelalive
[08:33:18.610] <TB1> INFO: ######################################################################
[08:33:18.610] <TB1> INFO: PixTestAlive::doTest()
[08:33:18.610] <TB1> INFO: ######################################################################
[08:33:18.611] <TB1> INFO: ----------------------------------------------------------------------
[08:33:18.611] <TB1> INFO: PixTestAlive::aliveTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[08:33:18.611] <TB1> INFO: ----------------------------------------------------------------------
[08:33:18.917] <TB1> INFO: Expecting 41600 events.
[08:33:23.625] <TB1> INFO: 41600 events read in total (3989ms).
[08:33:23.702] <TB1> INFO: Test took 5089ms.
[08:33:23.708] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:33:23.960] <TB1> INFO: PixTestAlive::aliveTest() done
[08:33:23.960] <TB1> INFO: number of dead pixels (per ROC): 1 5 1 1 2 1 1 0 0 2 0 1 0 0 0 0
[08:33:23.961] <TB1> INFO: ----------------------------------------------------------------------
[08:33:23.961] <TB1> INFO: PixTestAlive::maskTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[08:33:23.961] <TB1> INFO: ----------------------------------------------------------------------
[08:33:24.265] <TB1> INFO: Expecting 41600 events.
[08:33:27.463] <TB1> INFO: 41600 events read in total (2482ms).
[08:33:27.463] <TB1> INFO: Test took 3500ms.
[08:33:27.463] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:33:27.464] <TB1> INFO: mask vs. old pixelAlive PixelAlive_C0_V0 .. PixelAlive_C15_V0
[08:33:27.778] <TB1> INFO: PixTestAlive::maskTest() done
[08:33:27.778] <TB1> INFO: number of mask-defect pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[08:33:27.779] <TB1> INFO: ----------------------------------------------------------------------
[08:33:27.779] <TB1> INFO: PixTestAlive::addressDecodingTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[08:33:27.780] <TB1> INFO: ----------------------------------------------------------------------
[08:33:28.082] <TB1> INFO: Expecting 41600 events.
[08:33:32.646] <TB1> INFO: 41600 events read in total (3848ms).
[08:33:32.647] <TB1> INFO: Test took 4866ms.
[08:33:32.654] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:33:32.938] <TB1> INFO: PixTestAlive::addressDecodingTest() done
[08:33:32.938] <TB1> INFO: number of address-decoding pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[08:33:32.938] <TB1> INFO: PixTestAlive::doTest() done, duration: 14 seconds
[08:33:32.996] <TB1> INFO: enter test to run
[08:33:32.996] <TB1> INFO: test: no parameter change
[08:33:34.147] <TB1> QUIET: Connection to board 83 closed.
[08:33:34.227] <TB1> INFO: pXar: this is the end, my friend

[08:38:50.498] <TB1> INFO: *** Welcome to pxar ***
[08:38:50.498] <TB1> INFO: *** Today: 2015/08/28
[08:38:50.498] <TB1> INFO: readRocDacs: /usr/local/data/M2085_XrayQualification_2015-08-28_10h32m_1440750764//002_RetrimHotPixels_150//dacParameters35_C0.dat .. /usr/local/data/M2085_XrayQualification_2015-08-28_10h32m_1440750764//002_RetrimHotPixels_150//dacParameters35_C15.dat
[08:38:50.498] <TB1> INFO: readTbmDacs: /usr/local/data/M2085_XrayQualification_2015-08-28_10h32m_1440750764//002_RetrimHotPixels_150//tbmParameters_C0a.dat .. /usr/local/data/M2085_XrayQualification_2015-08-28_10h32m_1440750764//002_RetrimHotPixels_150//tbmParameters_C0b.dat
[08:38:50.498] <TB1> INFO: readMaskFile: /usr/local/data/M2085_XrayQualification_2015-08-28_10h32m_1440750764//002_RetrimHotPixels_150//defaultMaskFile.dat
[08:38:50.498] <TB1> INFO: readTrimFile: /usr/local/data/M2085_XrayQualification_2015-08-28_10h32m_1440750764//002_RetrimHotPixels_150//trimParameters35_C0.dat .. /usr/local/data/M2085_XrayQualification_2015-08-28_10h32m_1440750764//002_RetrimHotPixels_150//trimParameters35_C15.dat
[08:38:50.570] <TB1> INFO: clk: 4
[08:38:50.570] <TB1> INFO: ctr: 4
[08:38:50.570] <TB1> INFO: sda: 19
[08:38:50.570] <TB1> INFO: tin: 9
[08:38:50.570] <TB1> INFO: level: 15
[08:38:50.570] <TB1> INFO: triggerdelay: 0
[08:38:50.570] <TB1> QUIET: Instanciating API for pxar v2.5+57~g44d0777
[08:38:50.570] <TB1> INFO: Log level: INFO
[08:38:50.584] <TB1> INFO: Found DTB DTB_WS6UZO
[08:38:50.594] <TB1> QUIET: Connection to board DTB_WS6UZO opened.
[08:38:50.597] <TB1> INFO: DTB startup information
--- DTB info------------------------------------------
Board id: 83
HW version: DTB1.2
FW version: 4.2
SW version: 4.4
USB id: DTB_WS6UZO
MAC address: 40D855118053
Hostname: pixelDTB083
Comment:
------------------------------------------------------
[08:38:50.600] <TB1> INFO: RPC call hashes of host and DTB match: 397073690
[08:38:52.135] <TB1> INFO: DUT info:
[08:38:52.135] <TB1> INFO: The DUT currently contains the following objects:
[08:38:52.135] <TB1> INFO: 2 TBM Cores tbm09c (2 ON)
[08:38:52.135] <TB1> INFO: TBM Core alpha (0): 7 registers set
[08:38:52.135] <TB1> INFO: TBM Core beta (1): 7 registers set
[08:38:52.135] <TB1> INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[08:38:52.135] <TB1> INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[08:38:52.135] <TB1> INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[08:38:52.135] <TB1> INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[08:38:52.135] <TB1> INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[08:38:52.135] <TB1> INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[08:38:52.135] <TB1> INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[08:38:52.135] <TB1> INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[08:38:52.135] <TB1> INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[08:38:52.135] <TB1> INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[08:38:52.135] <TB1> INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[08:38:52.135] <TB1> INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[08:38:52.135] <TB1> INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[08:38:52.135] <TB1> INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[08:38:52.135] <TB1> INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[08:38:52.135] <TB1> INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[08:38:52.135] <TB1> INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[08:38:52.538] <TB1> INFO: enter 'restricted' command line mode
[08:38:52.538] <TB1> INFO: enter test to run
[08:38:52.538] <TB1> INFO: test: delay setting parameters: ->5<-
[08:38:52.538] <TB1> INFO: delay test by 5 seconds...
[08:38:57.538] <TB1> INFO: enter test to run
[08:38:57.538] <TB1> INFO: test: highrate no parameter change
[08:38:57.538] <TB1> INFO: running: highrate
[08:38:57.539] <TB1> INFO: readGainPedestalParameters /usr/local/data/M2085_XrayQualification_2015-08-28_10h32m_1440750764//002_RetrimHotPixels_150//phCalibrationFitErr35_C0.dat .. /usr/local/data/M2085_XrayQualification_2015-08-28_10h32m_1440750764//002_RetrimHotPixels_150//phCalibrationFitErr35_C15.dat
[08:38:57.679] <TB1> INFO: ----------------------------------------------------------------------
[08:38:57.679] <TB1> INFO: PixTest::trimHotPixels() running for 1 seconds with 100 kHz trigger rate
[08:38:57.679] <TB1> INFO: ----------------------------------------------------------------------
[08:38:57.679] <TB1> INFO: THR = 150, corresponding to ~ 400 MHz/cm2
[08:38:57.679] <TB1> INFO: edge/corner pixel THR is adjusted
[08:38:57.679] <TB1> INFO: PixTestHighRate::trimHotPixels: step 0...
[08:38:58.640] <TB1> INFO: Collecting data for 1 seconds...
[08:38:59.642] <TB1> INFO: Done with hot pixel readout
[08:39:07.976] <TB1> INFO: PixTest:: pg_setup set to default.
[08:39:07.977] <TB1> INFO: 19 hot pixels found in step 0
[08:39:08.945] <TB1> INFO: Collecting data for 1 seconds...
[08:39:09.947] <TB1> INFO: Done with hot pixel readout
[08:39:17.813] <TB1> INFO: PixTest:: pg_setup set to default.
[08:39:17.814] <TB1> INFO: 11 hot pixels found in step 1
[08:39:18.781] <TB1> INFO: Collecting data for 1 seconds...
[08:39:19.783] <TB1> INFO: Done with hot pixel readout
[08:39:26.062] <TB1> INFO: PixTest:: pg_setup set to default.
[08:39:26.063] <TB1> INFO: 9 hot pixels found in step 2
[08:39:27.029] <TB1> INFO: Collecting data for 1 seconds...
[08:39:28.031] <TB1> INFO: Done with hot pixel readout
[08:39:34.067] <TB1> INFO: PixTest:: pg_setup set to default.
[08:39:34.068] <TB1> INFO: 3 hot pixels found in step 3
[08:39:35.033] <TB1> INFO: Collecting data for 1 seconds...
[08:39:36.035] <TB1> INFO: Done with hot pixel readout
[08:39:42.033] <TB1> INFO: PixTest:: pg_setup set to default.
[08:39:42.034] <TB1> INFO: 0 hot pixels found in step 4
[08:39:42.043] <TB1> INFO: 0 hot pixels could not be trimmed and have been masked.
[08:39:42.076] <TB1> INFO: PixTest::trimHotPixels() done
[08:39:42.077] <TB1> INFO: write trim parameters into /usr/local/data/M2085_XrayQualification_2015-08-28_10h32m_1440750764//002_RetrimHotPixels_150//trimParameters35_C0.dat
[08:39:42.084] <TB1> INFO: write trim parameters into /usr/local/data/M2085_XrayQualification_2015-08-28_10h32m_1440750764//002_RetrimHotPixels_150//trimParameters35_C1.dat
[08:39:42.091] <TB1> INFO: write trim parameters into /usr/local/data/M2085_XrayQualification_2015-08-28_10h32m_1440750764//002_RetrimHotPixels_150//trimParameters35_C2.dat
[08:39:42.098] <TB1> INFO: write trim parameters into /usr/local/data/M2085_XrayQualification_2015-08-28_10h32m_1440750764//002_RetrimHotPixels_150//trimParameters35_C3.dat
[08:39:42.106] <TB1> INFO: write trim parameters into /usr/local/data/M2085_XrayQualification_2015-08-28_10h32m_1440750764//002_RetrimHotPixels_150//trimParameters35_C4.dat
[08:39:42.113] <TB1> INFO: write trim parameters into /usr/local/data/M2085_XrayQualification_2015-08-28_10h32m_1440750764//002_RetrimHotPixels_150//trimParameters35_C5.dat
[08:39:42.120] <TB1> INFO: write trim parameters into /usr/local/data/M2085_XrayQualification_2015-08-28_10h32m_1440750764//002_RetrimHotPixels_150//trimParameters35_C6.dat
[08:39:42.128] <TB1> INFO: write trim parameters into /usr/local/data/M2085_XrayQualification_2015-08-28_10h32m_1440750764//002_RetrimHotPixels_150//trimParameters35_C7.dat
[08:39:42.135] <TB1> INFO: write trim parameters into /usr/local/data/M2085_XrayQualification_2015-08-28_10h32m_1440750764//002_RetrimHotPixels_150//trimParameters35_C8.dat
[08:39:42.142] <TB1> INFO: write trim parameters into /usr/local/data/M2085_XrayQualification_2015-08-28_10h32m_1440750764//002_RetrimHotPixels_150//trimParameters35_C9.dat
[08:39:42.149] <TB1> INFO: write trim parameters into /usr/local/data/M2085_XrayQualification_2015-08-28_10h32m_1440750764//002_RetrimHotPixels_150//trimParameters35_C10.dat
[08:39:42.157] <TB1> INFO: write trim parameters into /usr/local/data/M2085_XrayQualification_2015-08-28_10h32m_1440750764//002_RetrimHotPixels_150//trimParameters35_C11.dat
[08:39:42.164] <TB1> INFO: write trim parameters into /usr/local/data/M2085_XrayQualification_2015-08-28_10h32m_1440750764//002_RetrimHotPixels_150//trimParameters35_C12.dat
[08:39:42.171] <TB1> INFO: write trim parameters into /usr/local/data/M2085_XrayQualification_2015-08-28_10h32m_1440750764//002_RetrimHotPixels_150//trimParameters35_C13.dat
[08:39:42.178] <TB1> INFO: write trim parameters into /usr/local/data/M2085_XrayQualification_2015-08-28_10h32m_1440750764//002_RetrimHotPixels_150//trimParameters35_C14.dat
[08:39:42.185] <TB1> INFO: write trim parameters into /usr/local/data/M2085_XrayQualification_2015-08-28_10h32m_1440750764//002_RetrimHotPixels_150//trimParameters35_C15.dat
[08:39:42.192] <TB1> INFO: write masked pixels into /usr/local/data/M2085_XrayQualification_2015-08-28_10h32m_1440750764//002_RetrimHotPixels_150//defaultMaskFile.dat
[08:39:42.230] <TB1> INFO: enter test to run
[08:39:42.230] <TB1> INFO: test: no parameter change
[08:39:42.404] <TB1> QUIET: Connection to board 83 closed.
[08:39:42.485] <TB1> INFO: pXar: this is the end, my friend

[08:40:22.591] <TB1> INFO: *** Welcome to pxar ***
[08:40:22.591] <TB1> INFO: *** Today: 2015/08/28
[08:40:22.591] <TB1> INFO: readRocDacs: /usr/local/data/M2085_XrayQualification_2015-08-28_10h32m_1440750764//003_HRData_50//dacParameters35_C0.dat .. /usr/local/data/M2085_XrayQualification_2015-08-28_10h32m_1440750764//003_HRData_50//dacParameters35_C15.dat
[08:40:22.592] <TB1> INFO: readTbmDacs: /usr/local/data/M2085_XrayQualification_2015-08-28_10h32m_1440750764//003_HRData_50//tbmParameters_C0a.dat .. /usr/local/data/M2085_XrayQualification_2015-08-28_10h32m_1440750764//003_HRData_50//tbmParameters_C0b.dat
[08:40:22.592] <TB1> INFO: readMaskFile: /usr/local/data/M2085_XrayQualification_2015-08-28_10h32m_1440750764//003_HRData_50//defaultMaskFile.dat
[08:40:22.592] <TB1> INFO: readTrimFile: /usr/local/data/M2085_XrayQualification_2015-08-28_10h32m_1440750764//003_HRData_50//trimParameters35_C0.dat .. /usr/local/data/M2085_XrayQualification_2015-08-28_10h32m_1440750764//003_HRData_50//trimParameters35_C15.dat
[08:40:22.702] <TB1> INFO: clk: 4
[08:40:22.702] <TB1> INFO: ctr: 4
[08:40:22.702] <TB1> INFO: sda: 19
[08:40:22.702] <TB1> INFO: tin: 9
[08:40:22.702] <TB1> INFO: level: 15
[08:40:22.702] <TB1> INFO: triggerdelay: 0
[08:40:22.702] <TB1> QUIET: Instanciating API for pxar v2.5+57~g44d0777
[08:40:22.703] <TB1> INFO: Log level: INFO
[08:40:22.716] <TB1> INFO: Found DTB DTB_WS6UZO
[08:40:22.728] <TB1> QUIET: Connection to board DTB_WS6UZO opened.
[08:40:22.731] <TB1> INFO: DTB startup information
--- DTB info------------------------------------------
Board id: 83
HW version: DTB1.2
FW version: 4.2
SW version: 4.4
USB id: DTB_WS6UZO
MAC address: 40D855118053
Hostname: pixelDTB083
Comment:
------------------------------------------------------
[08:40:22.734] <TB1> INFO: RPC call hashes of host and DTB match: 397073690
[08:40:24.268] <TB1> INFO: DUT info:
[08:40:24.268] <TB1> INFO: The DUT currently contains the following objects:
[08:40:24.269] <TB1> INFO: 2 TBM Cores tbm09c (2 ON)
[08:40:24.269] <TB1> INFO: TBM Core alpha (0): 7 registers set
[08:40:24.269] <TB1> INFO: TBM Core beta (1): 7 registers set
[08:40:24.269] <TB1> INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[08:40:24.269] <TB1> INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[08:40:24.269] <TB1> INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[08:40:24.269] <TB1> INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[08:40:24.269] <TB1> INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[08:40:24.269] <TB1> INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[08:40:24.269] <TB1> INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[08:40:24.269] <TB1> INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[08:40:24.269] <TB1> INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[08:40:24.269] <TB1> INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[08:40:24.269] <TB1> INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[08:40:24.269] <TB1> INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[08:40:24.269] <TB1> INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[08:40:24.269] <TB1> INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[08:40:24.269] <TB1> INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[08:40:24.269] <TB1> INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[08:40:24.269] <TB1> INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[08:40:24.670] <TB1> INFO: enter 'restricted' command line mode
[08:40:24.670] <TB1> INFO: enter test to run
[08:40:24.670] <TB1> INFO: test: delay setting parameters: ->5<-
[08:40:24.670] <TB1> INFO: delay test by 5 seconds...
[08:40:29.670] <TB1> INFO: enter test to run
[08:40:29.670] <TB1> INFO: test: Xray no parameter change
[08:40:29.670] <TB1> INFO: running: xray
[08:40:29.671] <TB1> INFO: readGainPedestalParameters /usr/local/data/M2085_XrayQualification_2015-08-28_10h32m_1440750764//003_HRData_50//phCalibrationFitErr35_C0.dat .. /usr/local/data/M2085_XrayQualification_2015-08-28_10h32m_1440750764//003_HRData_50//phCalibrationFitErr35_C15.dat
[08:40:29.813] <TB1> INFO: ######################################################################
[08:40:29.813] <TB1> INFO: PixTestXray::doTest()
[08:40:29.813] <TB1> INFO: ######################################################################
[08:40:29.813] <TB1> INFO: ----------------------------------------------------------------------
[08:40:29.813] <TB1> INFO: PixTestXray::doPhRun() fParRunSeconds = 100
[08:40:29.813] <TB1> INFO: ----------------------------------------------------------------------
[08:40:30.780] <TB1> INFO: PixTestXray::doPhRun start TriggerLoop with trigger frequency 100 kHz, period 405 and duration 100 seconds, fEventsMax = 10000000
[08:40:36.699] <TB1> INFO: run duration 5 seconds, buffer almost full (81%), pausing triggers.
[08:41:04.575] <TB1> INFO: Resuming triggers.
[08:41:10.507] <TB1> INFO: run duration 11 seconds, buffer almost full (81%), pausing triggers.
[08:41:38.155] <TB1> INFO: Resuming triggers.
[08:41:44.081] <TB1> INFO: run duration 17 seconds, buffer almost full (81%), pausing triggers.
[08:42:11.578] <TB1> INFO: Resuming triggers.
[08:42:17.506] <TB1> INFO: run duration 23 seconds, buffer almost full (81%), pausing triggers.
[08:42:44.920] <TB1> INFO: Resuming triggers.
[08:42:50.847] <TB1> INFO: run duration 29 seconds, buffer almost full (81%), pausing triggers.
[08:43:19.829] <TB1> INFO: Resuming triggers.
[08:43:25.760] <TB1> INFO: run duration 35 seconds, buffer almost full (81%), pausing triggers.
[08:43:53.967] <TB1> INFO: Resuming triggers.
[08:43:59.892] <TB1> INFO: run duration 41 seconds, buffer almost full (81%), pausing triggers.
[08:44:29.106] <TB1> INFO: Resuming triggers.
[08:44:35.034] <TB1> INFO: run duration 47 seconds, buffer almost full (81%), pausing triggers.
[08:45:04.899] <TB1> INFO: Resuming triggers.
[08:45:10.823] <TB1> INFO: run duration 53 seconds, buffer almost full (81%), pausing triggers.
[08:45:41.313] <TB1> INFO: Resuming triggers.
[08:45:47.243] <TB1> INFO: run duration 59 seconds, buffer almost full (81%), pausing triggers.
[08:46:16.959] <TB1> INFO: Resuming triggers.
[08:46:22.883] <TB1> INFO: run duration 65 seconds, buffer almost full (81%), pausing triggers.
[08:46:54.464] <TB1> INFO: Resuming triggers.
[08:47:00.384] <TB1> INFO: run duration 71 seconds, buffer almost full (81%), pausing triggers.
[08:47:30.999] <TB1> INFO: Resuming triggers.
[08:47:36.922] <TB1> INFO: run duration 76 seconds, buffer almost full (81%), pausing triggers.
[08:48:06.458] <TB1> INFO: Resuming triggers.
[08:48:12.374] <TB1> INFO: run duration 82 seconds, buffer almost full (81%), pausing triggers.
[08:48:41.879] <TB1> INFO: Resuming triggers.
[08:48:47.800] <TB1> INFO: run duration 88 seconds, buffer almost full (81%), pausing triggers.
[08:49:14.889] <TB1> INFO: Resuming triggers.
[08:49:20.802] <TB1> INFO: run duration 94 seconds, buffer almost full (81%), pausing triggers.
[08:49:39.276] <TB1> INFO: Resuming triggers.
[08:49:44.611] <TB1> INFO: data taking finished, elapsed time: 100 seconds.
[08:50:01.661] <TB1> INFO: PixTest:: pg_setup set to default.
[08:50:01.663] <TB1> INFO: PixTestXray::doPhRun() done
[08:50:01.663] <TB1> INFO: PixTestXray::doTest() done
[08:50:01.838] <TB1> INFO: enter test to run
[08:50:01.839] <TB1> INFO: test: no parameter change
[08:50:02.144] <TB1> QUIET: Connection to board 83 closed.
[08:50:02.224] <TB1> INFO: pXar: this is the end, my friend

[08:50:18.214] <TB1> INFO: *** Welcome to pxar ***
[08:50:18.214] <TB1> INFO: *** Today: 2015/08/28
[08:50:18.214] <TB1> INFO: readRocDacs: /usr/local/data/M2085_XrayQualification_2015-08-28_10h32m_1440750764//004_HRData_150//dacParameters35_C0.dat .. /usr/local/data/M2085_XrayQualification_2015-08-28_10h32m_1440750764//004_HRData_150//dacParameters35_C15.dat
[08:50:18.215] <TB1> INFO: readTbmDacs: /usr/local/data/M2085_XrayQualification_2015-08-28_10h32m_1440750764//004_HRData_150//tbmParameters_C0a.dat .. /usr/local/data/M2085_XrayQualification_2015-08-28_10h32m_1440750764//004_HRData_150//tbmParameters_C0b.dat
[08:50:18.215] <TB1> INFO: readMaskFile: /usr/local/data/M2085_XrayQualification_2015-08-28_10h32m_1440750764//004_HRData_150//defaultMaskFile.dat
[08:50:18.215] <TB1> INFO: readTrimFile: /usr/local/data/M2085_XrayQualification_2015-08-28_10h32m_1440750764//004_HRData_150//trimParameters35_C0.dat .. /usr/local/data/M2085_XrayQualification_2015-08-28_10h32m_1440750764//004_HRData_150//trimParameters35_C15.dat
[08:50:18.288] <TB1> INFO: clk: 4
[08:50:18.288] <TB1> INFO: ctr: 4
[08:50:18.288] <TB1> INFO: sda: 19
[08:50:18.288] <TB1> INFO: tin: 9
[08:50:18.288] <TB1> INFO: level: 15
[08:50:18.288] <TB1> INFO: triggerdelay: 0
[08:50:18.288] <TB1> QUIET: Instanciating API for pxar v2.5+57~g44d0777
[08:50:18.288] <TB1> INFO: Log level: INFO
[08:50:18.302] <TB1> INFO: Found DTB DTB_WS6UZO
[08:50:18.313] <TB1> QUIET: Connection to board DTB_WS6UZO opened.
[08:50:18.316] <TB1> INFO: DTB startup information
--- DTB info------------------------------------------
Board id: 83
HW version: DTB1.2
FW version: 4.2
SW version: 4.4
USB id: DTB_WS6UZO
MAC address: 40D855118053
Hostname: pixelDTB083
Comment:
------------------------------------------------------
[08:50:18.318] <TB1> INFO: RPC call hashes of host and DTB match: 397073690
[08:50:19.842] <TB1> INFO: DUT info:
[08:50:19.843] <TB1> INFO: The DUT currently contains the following objects:
[08:50:19.843] <TB1> INFO: 2 TBM Cores tbm09c (2 ON)
[08:50:19.843] <TB1> INFO: TBM Core alpha (0): 7 registers set
[08:50:19.843] <TB1> INFO: TBM Core beta (1): 7 registers set
[08:50:19.843] <TB1> INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[08:50:19.843] <TB1> INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[08:50:19.843] <TB1> INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[08:50:19.843] <TB1> INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[08:50:19.843] <TB1> INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[08:50:19.843] <TB1> INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[08:50:19.843] <TB1> INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[08:50:19.843] <TB1> INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[08:50:19.843] <TB1> INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[08:50:19.843] <TB1> INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[08:50:19.843] <TB1> INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[08:50:19.843] <TB1> INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[08:50:19.843] <TB1> INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[08:50:19.843] <TB1> INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[08:50:19.843] <TB1> INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[08:50:19.843] <TB1> INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[08:50:19.843] <TB1> INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[08:50:20.244] <TB1> INFO: enter 'restricted' command line mode
[08:50:20.244] <TB1> INFO: enter test to run
[08:50:20.245] <TB1> INFO: test: delay setting parameters: ->5<-
[08:50:20.245] <TB1> INFO: delay test by 5 seconds...
[08:50:25.245] <TB1> INFO: enter test to run
[08:50:25.245] <TB1> INFO: test: Xray no parameter change
[08:50:25.245] <TB1> INFO: running: xray
[08:50:25.245] <TB1> INFO: readGainPedestalParameters /usr/local/data/M2085_XrayQualification_2015-08-28_10h32m_1440750764//004_HRData_150//phCalibrationFitErr35_C0.dat .. /usr/local/data/M2085_XrayQualification_2015-08-28_10h32m_1440750764//004_HRData_150//phCalibrationFitErr35_C15.dat
[08:50:25.388] <TB1> INFO: ######################################################################
[08:50:25.388] <TB1> INFO: PixTestXray::doTest()
[08:50:25.388] <TB1> INFO: ######################################################################
[08:50:25.388] <TB1> INFO: ----------------------------------------------------------------------
[08:50:25.388] <TB1> INFO: PixTestXray::doPhRun() fParRunSeconds = 100
[08:50:25.388] <TB1> INFO: ----------------------------------------------------------------------
[08:50:26.356] <TB1> INFO: PixTestXray::doPhRun start TriggerLoop with trigger frequency 100 kHz, period 405 and duration 100 seconds, fEventsMax = 10000000
[08:50:29.420] <TB1> INFO: run duration 3 seconds, buffer almost full (81%), pausing triggers.
[08:50:58.176] <TB1> INFO: Resuming triggers.
[08:51:01.249] <TB1> INFO: run duration 6 seconds, buffer almost full (81%), pausing triggers.
[08:51:30.043] <TB1> INFO: Resuming triggers.
[08:51:33.117] <TB1> INFO: run duration 9 seconds, buffer almost full (81%), pausing triggers.
[08:52:01.846] <TB1> INFO: Resuming triggers.
[08:52:04.921] <TB1> INFO: run duration 12 seconds, buffer almost full (81%), pausing triggers.
[08:52:34.041] <TB1> INFO: Resuming triggers.
[08:52:37.116] <TB1> INFO: run duration 15 seconds, buffer almost full (81%), pausing triggers.
[08:53:07.206] <TB1> INFO: Resuming triggers.
[08:53:10.277] <TB1> INFO: run duration 18 seconds, buffer almost full (81%), pausing triggers.
[08:53:40.206] <TB1> INFO: Resuming triggers.
[08:53:43.277] <TB1> INFO: run duration 21 seconds, buffer almost full (81%), pausing triggers.
[08:54:14.980] <TB1> INFO: Resuming triggers.
[08:54:18.055] <TB1> INFO: run duration 24 seconds, buffer almost full (81%), pausing triggers.
[08:54:49.497] <TB1> INFO: Resuming triggers.
[08:54:52.572] <TB1> INFO: run duration 27 seconds, buffer almost full (81%), pausing triggers.
[08:55:23.454] <TB1> INFO: Resuming triggers.
[08:55:26.526] <TB1> INFO: run duration 30 seconds, buffer almost full (81%), pausing triggers.
[08:55:57.123] <TB1> INFO: Resuming triggers.
[08:56:00.193] <TB1> INFO: run duration 33 seconds, buffer almost full (81%), pausing triggers.
[08:56:32.342] <TB1> INFO: Resuming triggers.
[08:56:35.409] <TB1> INFO: run duration 36 seconds, buffer almost full (81%), pausing triggers.
[08:57:06.715] <TB1> INFO: Resuming triggers.
[08:57:09.786] <TB1> INFO: run duration 39 seconds, buffer almost full (81%), pausing triggers.
[08:57:42.152] <TB1> INFO: Resuming triggers.
[08:57:45.220] <TB1> INFO: run duration 42 seconds, buffer almost full (81%), pausing triggers.
[08:58:17.572] <TB1> INFO: Resuming triggers.
[08:58:20.643] <TB1> INFO: run duration 46 seconds, buffer almost full (81%), pausing triggers.
[08:58:51.585] <TB1> INFO: Resuming triggers.
[08:58:54.658] <TB1> INFO: run duration 49 seconds, buffer almost full (81%), pausing triggers.
[08:59:26.738] <TB1> INFO: Resuming triggers.
[08:59:29.810] <TB1> INFO: run duration 52 seconds, buffer almost full (81%), pausing triggers.
[09:00:01.190] <TB1> INFO: Resuming triggers.
[09:00:04.256] <TB1> INFO: run duration 55 seconds, buffer almost full (81%), pausing triggers.
[09:00:34.673] <TB1> INFO: Resuming triggers.
[09:00:37.741] <TB1> INFO: run duration 58 seconds, buffer almost full (81%), pausing triggers.
[09:01:08.845] <TB1> INFO: Resuming triggers.
[09:01:11.916] <TB1> INFO: run duration 61 seconds, buffer almost full (81%), pausing triggers.
[09:01:41.584] <TB1> INFO: Resuming triggers.
[09:01:44.657] <TB1> INFO: run duration 64 seconds, buffer almost full (81%), pausing triggers.
[09:02:16.360] <TB1> INFO: Resuming triggers.
[09:02:19.431] <TB1> INFO: run duration 67 seconds, buffer almost full (81%), pausing triggers.
[09:02:51.161] <TB1> INFO: Resuming triggers.
[09:02:54.227] <TB1> INFO: run duration 70 seconds, buffer almost full (81%), pausing triggers.
[09:03:25.608] <TB1> INFO: Resuming triggers.
[09:03:28.674] <TB1> INFO: run duration 73 seconds, buffer almost full (81%), pausing triggers.
[09:03:59.887] <TB1> INFO: Resuming triggers.
[09:04:02.951] <TB1> INFO: run duration 76 seconds, buffer almost full (81%), pausing triggers.


[09:04:34.841] <TB1> INFO: Resuming triggers.
[09:04:37.905] <TB1> INFO: run duration 79 seconds, buffer almost full (81%), pausing triggers.
[09:05:09.008] <TB1> INFO: Resuming triggers.
[09:05:12.073] <TB1> INFO: run duration 82 seconds, buffer almost full (81%), pausing triggers.
[09:05:38.617] <TB1> INFO: Resuming triggers.
[09:05:41.683] <TB1> INFO: run duration 85 seconds, buffer almost full (81%), pausing triggers.
[09:06:02.040] <TB1> INFO: Resuming triggers.
[09:06:05.101] <TB1> INFO: run duration 88 seconds, buffer almost full (81%), pausing triggers.
[09:06:25.970] <TB1> INFO: Resuming triggers.
[09:06:29.035] <TB1> INFO: run duration 91 seconds, buffer almost full (81%), pausing triggers.
[09:06:49.260] <TB1> INFO: Resuming triggers.
[09:06:52.325] <TB1> INFO: run duration 95 seconds, buffer almost full (81%), pausing triggers.

[09:07:12.984] <TB1> INFO: Resuming triggers.
[09:07:16.045] <TB1> INFO: run duration 98 seconds, buffer almost full (81%), pausing triggers.
[09:07:36.298] <TB1> INFO: Resuming triggers.
[09:07:38.199] <TB1> INFO: data taking finished, elapsed time: 100 seconds.
[09:07:51.214] <TB1> INFO: PixTest:: pg_setup set to default.
[09:07:51.215] <TB1> INFO: PixTestXray::doPhRun() done
[09:07:51.215] <TB1> INFO: PixTestXray::doTest() done
[09:07:51.399] <TB1> INFO: enter test to run
[09:07:51.399] <TB1> INFO: test: no parameter change
[09:07:51.603] <TB1> QUIET: Connection to board 83 closed.
[09:07:51.684] <TB1> INFO: pXar: this is the end, my friend

[09:08:05.880] <TB1> INFO: *** Welcome to pxar ***
[09:08:05.880] <TB1> INFO: *** Today: 2015/08/28
[09:08:05.880] <TB1> INFO: readRocDacs: /usr/local/data/M2085_XrayQualification_2015-08-28_10h32m_1440750764//005_HRSCurves_100//dacParameters35_C0.dat .. /usr/local/data/M2085_XrayQualification_2015-08-28_10h32m_1440750764//005_HRSCurves_100//dacParameters35_C15.dat
[09:08:05.881] <TB1> INFO: readTbmDacs: /usr/local/data/M2085_XrayQualification_2015-08-28_10h32m_1440750764//005_HRSCurves_100//tbmParameters_C0a.dat .. /usr/local/data/M2085_XrayQualification_2015-08-28_10h32m_1440750764//005_HRSCurves_100//tbmParameters_C0b.dat
[09:08:05.881] <TB1> INFO: readMaskFile: /usr/local/data/M2085_XrayQualification_2015-08-28_10h32m_1440750764//005_HRSCurves_100//defaultMaskFile.dat
[09:08:05.881] <TB1> INFO: readTrimFile: /usr/local/data/M2085_XrayQualification_2015-08-28_10h32m_1440750764//005_HRSCurves_100//trimParameters35_C0.dat .. /usr/local/data/M2085_XrayQualification_2015-08-28_10h32m_1440750764//005_HRSCurves_100//trimParameters35_C15.dat
[09:08:05.956] <TB1> INFO: clk: 4
[09:08:05.956] <TB1> INFO: ctr: 4
[09:08:05.956] <TB1> INFO: sda: 19
[09:08:05.956] <TB1> INFO: tin: 9
[09:08:05.956] <TB1> INFO: level: 15
[09:08:05.956] <TB1> INFO: triggerdelay: 0
[09:08:05.956] <TB1> QUIET: Instanciating API for pxar v2.5+57~g44d0777
[09:08:05.956] <TB1> INFO: Log level: INFO
[09:08:05.969] <TB1> INFO: Found DTB DTB_WS6UZO
[09:08:05.979] <TB1> QUIET: Connection to board DTB_WS6UZO opened.
[09:08:05.983] <TB1> INFO: DTB startup information
--- DTB info------------------------------------------
Board id: 83
HW version: DTB1.2
FW version: 4.2
SW version: 4.4
USB id: DTB_WS6UZO
MAC address: 40D855118053
Hostname: pixelDTB083
Comment:
------------------------------------------------------
[09:08:05.985] <TB1> INFO: RPC call hashes of host and DTB match: 397073690
[09:08:07.512] <TB1> INFO: DUT info:
[09:08:07.512] <TB1> INFO: The DUT currently contains the following objects:
[09:08:07.512] <TB1> INFO: 2 TBM Cores tbm09c (2 ON)
[09:08:07.512] <TB1> INFO: TBM Core alpha (0): 7 registers set
[09:08:07.512] <TB1> INFO: TBM Core beta (1): 7 registers set
[09:08:07.512] <TB1> INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[09:08:07.512] <TB1> INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[09:08:07.512] <TB1> INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[09:08:07.512] <TB1> INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[09:08:07.512] <TB1> INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[09:08:07.512] <TB1> INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[09:08:07.512] <TB1> INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[09:08:07.512] <TB1> INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[09:08:07.512] <TB1> INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[09:08:07.512] <TB1> INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[09:08:07.512] <TB1> INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[09:08:07.512] <TB1> INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[09:08:07.512] <TB1> INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[09:08:07.512] <TB1> INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[09:08:07.512] <TB1> INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[09:08:07.513] <TB1> INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[09:08:07.513] <TB1> INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[09:08:07.914] <TB1> INFO: enter 'restricted' command line mode
[09:08:07.914] <TB1> INFO: enter test to run
[09:08:07.914] <TB1> INFO: test: Highrate no parameter change
[09:08:07.914] <TB1> INFO: running: highrate
[09:08:07.914] <TB1> INFO: readGainPedestalParameters /usr/local/data/M2085_XrayQualification_2015-08-28_10h32m_1440750764//005_HRSCurves_100//phCalibrationFitErr35_C0.dat .. /usr/local/data/M2085_XrayQualification_2015-08-28_10h32m_1440750764//005_HRSCurves_100//phCalibrationFitErr35_C15.dat
[09:08:08.060] <TB1> INFO: ----------------------------------------------------------------------
[09:08:08.060] <TB1> INFO: PixTestHighRate::xNoiseMaps() ntrig = 50, vcal = 200
[09:08:08.060] <TB1> INFO: ----------------------------------------------------------------------
[09:08:08.066] <TB1> INFO: ---> dac: vcal name: xNoiseMap ntrig: 50 dacrange: 0 .. 100 (1/-1) hits flags = 384 (plus default)
[09:08:08.078] <TB1> INFO: dacScan step from 0 .. 0
[09:08:08.078] <TB1> INFO: dacScan split into 1 runs with ntrig = 50
[09:08:08.078] <TB1> INFO: run 1 of 1
[09:08:08.676] <TB1> INFO: Expecting 208000 events.
[09:08:27.235] <TB1> INFO: 208000 events read in total (18030ms).
[09:08:27.247] <TB1> INFO: Test took 19169ms.
[09:08:27.505] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[09:08:27.818] <TB1> INFO: dacScan step from 1 .. 1
[09:08:27.818] <TB1> INFO: dacScan split into 1 runs with ntrig = 50
[09:08:27.818] <TB1> INFO: run 1 of 1
[09:08:28.430] <TB1> INFO: Expecting 208000 events.
[09:08:47.389] <TB1> INFO: 208000 events read in total (18429ms).
[09:08:47.400] <TB1> INFO: Test took 19582ms.
[09:08:47.493] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[09:08:47.766] <TB1> INFO: dacScan step from 2 .. 2
[09:08:47.766] <TB1> INFO: dacScan split into 1 runs with ntrig = 50
[09:08:47.766] <TB1> INFO: run 1 of 1
[09:08:48.361] <TB1> INFO: Expecting 208000 events.
[09:09:06.303] <TB1> INFO: 208000 events read in total (17415ms).
[09:09:06.312] <TB1> INFO: Test took 18546ms.
[09:09:06.411] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[09:09:06.717] <TB1> INFO: dacScan step from 3 .. 3
[09:09:06.717] <TB1> INFO: dacScan split into 1 runs with ntrig = 50
[09:09:06.717] <TB1> INFO: run 1 of 1
[09:09:07.328] <TB1> INFO: Expecting 208000 events.
[09:09:24.842] <TB1> INFO: 208000 events read in total (16986ms).
[09:09:24.851] <TB1> INFO: Test took 18133ms.
[09:09:24.948] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[09:09:25.247] <TB1> INFO: dacScan step from 4 .. 4
[09:09:25.247] <TB1> INFO: dacScan split into 1 runs with ntrig = 50
[09:09:25.247] <TB1> INFO: run 1 of 1
[09:09:25.856] <TB1> INFO: Expecting 208000 events.
[09:09:42.902] <TB1> INFO: 208000 events read in total (16519ms).
[09:09:42.913] <TB1> INFO: Test took 17665ms.
[09:09:43.019] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[09:09:43.336] <TB1> INFO: dacScan step from 5 .. 5
[09:09:43.336] <TB1> INFO: dacScan split into 1 runs with ntrig = 50
[09:09:43.336] <TB1> INFO: run 1 of 1
[09:09:43.955] <TB1> INFO: Expecting 208000 events.
[09:10:01.598] <TB1> INFO: 208000 events read in total (17116ms).
[09:10:01.609] <TB1> INFO: Test took 18273ms.
[09:10:01.705] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[09:10:01.993] <TB1> INFO: dacScan step from 6 .. 6
[09:10:01.993] <TB1> INFO: dacScan split into 1 runs with ntrig = 50
[09:10:01.993] <TB1> INFO: run 1 of 1
[09:10:02.590] <TB1> INFO: Expecting 208000 events.
[09:10:21.341] <TB1> INFO: 208000 events read in total (18224ms).
[09:10:21.350] <TB1> INFO: Test took 19356ms.
[09:10:21.450] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[09:10:21.741] <TB1> INFO: dacScan step from 7 .. 7
[09:10:21.741] <TB1> INFO: dacScan split into 1 runs with ntrig = 50
[09:10:21.741] <TB1> INFO: run 1 of 1
[09:10:22.345] <TB1> INFO: Expecting 208000 events.
[09:10:40.901] <TB1> INFO: 208000 events read in total (18029ms).
[09:10:40.913] <TB1> INFO: Test took 19171ms.
[09:10:41.086] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[09:10:41.419] <TB1> INFO: dacScan step from 8 .. 8
[09:10:41.419] <TB1> INFO: dacScan split into 1 runs with ntrig = 50
[09:10:41.419] <TB1> INFO: run 1 of 1
[09:10:42.029] <TB1> INFO: Expecting 208000 events.
[09:11:00.193] <TB1> INFO: 208000 events read in total (17637ms).
[09:11:00.204] <TB1> INFO: Test took 18784ms.
[09:11:00.337] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[09:11:00.638] <TB1> INFO: dacScan step from 9 .. 9
[09:11:00.638] <TB1> INFO: dacScan split into 1 runs with ntrig = 50
[09:11:00.638] <TB1> INFO: run 1 of 1
[09:11:01.242] <TB1> INFO: Expecting 208000 events.
[09:11:18.634] <TB1> INFO: 208000 events read in total (16864ms).
[09:11:18.647] <TB1> INFO: Test took 18008ms.
[09:11:18.749] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[09:11:19.031] <TB1> INFO: dacScan step from 10 .. 10
[09:11:19.031] <TB1> INFO: dacScan split into 1 runs with ntrig = 50
[09:11:19.031] <TB1> INFO: run 1 of 1
[09:11:19.630] <TB1> INFO: Expecting 208000 events.
[09:11:36.961] <TB1> INFO: 208000 events read in total (16804ms).
[09:11:36.971] <TB1> INFO: Test took 17940ms.
[09:11:37.070] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[09:11:37.387] <TB1> INFO: dacScan step from 11 .. 11
[09:11:37.387] <TB1> INFO: dacScan split into 1 runs with ntrig = 50
[09:11:37.387] <TB1> INFO: run 1 of 1
[09:11:38.000] <TB1> INFO: Expecting 208000 events.
[09:11:56.067] <TB1> INFO: 208000 events read in total (17539ms).
[09:11:56.077] <TB1> INFO: Test took 18689ms.
[09:11:56.175] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[09:11:56.463] <TB1> INFO: dacScan step from 12 .. 12
[09:11:56.463] <TB1> INFO: dacScan split into 1 runs with ntrig = 50
[09:11:56.463] <TB1> INFO: run 1 of 1
[09:11:57.061] <TB1> INFO: Expecting 208000 events.
[09:12:16.079] <TB1> INFO: 208000 events read in total (18491ms).
[09:12:16.090] <TB1> INFO: Test took 19627ms.
[09:12:16.195] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[09:12:16.534] <TB1> INFO: dacScan step from 13 .. 13
[09:12:16.534] <TB1> INFO: dacScan split into 1 runs with ntrig = 50
[09:12:16.534] <TB1> INFO: run 1 of 1
[09:12:17.161] <TB1> INFO: Expecting 208000 events.
[09:12:35.292] <TB1> INFO: 208000 events read in total (17604ms).
[09:12:35.301] <TB1> INFO: Test took 18766ms.
[09:12:35.396] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[09:12:35.707] <TB1> INFO: dacScan step from 14 .. 14
[09:12:35.707] <TB1> INFO: dacScan split into 1 runs with ntrig = 50
[09:12:35.708] <TB1> INFO: run 1 of 1
[09:12:36.332] <TB1> INFO: Expecting 208000 events.
[09:12:54.304] <TB1> INFO: 208000 events read in total (17445ms).
[09:12:54.314] <TB1> INFO: Test took 18606ms.
[09:12:54.416] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[09:12:54.713] <TB1> INFO: dacScan step from 15 .. 15
[09:12:54.713] <TB1> INFO: dacScan split into 1 runs with ntrig = 50
[09:12:54.713] <TB1> INFO: run 1 of 1
[09:12:55.320] <TB1> INFO: Expecting 208000 events.
[09:13:12.561] <TB1> INFO: 208000 events read in total (16714ms).
[09:13:12.570] <TB1> INFO: Test took 17857ms.
[09:13:12.667] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[09:13:12.967] <TB1> INFO: dacScan step from 16 .. 16
[09:13:12.967] <TB1> INFO: dacScan split into 1 runs with ntrig = 50
[09:13:12.967] <TB1> INFO: run 1 of 1
[09:13:13.571] <TB1> INFO: Expecting 208000 events.
[09:13:30.834] <TB1> INFO: 208000 events read in total (16735ms).
[09:13:30.844] <TB1> INFO: Test took 17876ms.
[09:13:30.944] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[09:13:31.266] <TB1> INFO: dacScan step from 17 .. 17
[09:13:31.266] <TB1> INFO: dacScan split into 1 runs with ntrig = 50
[09:13:31.266] <TB1> INFO: run 1 of 1
[09:13:31.869] <TB1> INFO: Expecting 208000 events.
[09:13:50.378] <TB1> INFO: 208000 events read in total (17982ms).
[09:13:50.389] <TB1> INFO: Test took 19123ms.
[09:13:50.599] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[09:13:50.906] <TB1> INFO: dacScan step from 18 .. 18
[09:13:50.906] <TB1> INFO: dacScan split into 1 runs with ntrig = 50
[09:13:50.906] <TB1> INFO: run 1 of 1
[09:13:51.504] <TB1> INFO: Expecting 208000 events.
[09:14:10.572] <TB1> INFO: 208000 events read in total (18541ms).
[09:14:10.584] <TB1> INFO: Test took 19678ms.
[09:14:10.682] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[09:14:10.989] <TB1> INFO: dacScan step from 19 .. 19
[09:14:10.989] <TB1> INFO: dacScan split into 1 runs with ntrig = 50
[09:14:10.989] <TB1> INFO: run 1 of 1
[09:14:11.601] <TB1> INFO: Expecting 208000 events.
[09:14:29.660] <TB1> INFO: 208000 events read in total (17532ms).
[09:14:29.670] <TB1> INFO: Test took 18680ms.
[09:14:29.767] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[09:14:30.055] <TB1> INFO: dacScan step from 20 .. 20
[09:14:30.055] <TB1> INFO: dacScan split into 1 runs with ntrig = 50
[09:14:30.055] <TB1> INFO: run 1 of 1
[09:14:30.654] <TB1> INFO: Expecting 208000 events.
[09:14:47.863] <TB1> INFO: 208000 events read in total (16682ms).
[09:14:47.873] <TB1> INFO: Test took 17818ms.
[09:14:47.974] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[09:14:48.280] <TB1> INFO: dacScan step from 21 .. 21
[09:14:48.280] <TB1> INFO: dacScan split into 1 runs with ntrig = 50
[09:14:48.280] <TB1> INFO: run 1 of 1
[09:14:48.892] <TB1> INFO: Expecting 208000 events.
[09:15:06.018] <TB1> INFO: 208000 events read in total (16599ms).
[09:15:06.027] <TB1> INFO: Test took 17746ms.
[09:15:06.126] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[09:15:06.424] <TB1> INFO: dacScan step from 22 .. 22
[09:15:06.424] <TB1> INFO: dacScan split into 1 runs with ntrig = 50
[09:15:06.424] <TB1> INFO: run 1 of 1
[09:15:07.023] <TB1> INFO: Expecting 208000 events.
[09:15:24.428] <TB1> INFO: 208000 events read in total (16877ms).
[09:15:24.439] <TB1> INFO: Test took 18014ms.
[09:15:24.534] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[09:15:24.844] <TB1> INFO: dacScan step from 23 .. 23
[09:15:24.844] <TB1> INFO: dacScan split into 1 runs with ntrig = 50
[09:15:24.844] <TB1> INFO: run 1 of 1
[09:15:25.452] <TB1> INFO: Expecting 208000 events.
[09:15:44.132] <TB1> INFO: 208000 events read in total (18153ms).
[09:15:44.144] <TB1> INFO: Test took 19300ms.
[09:15:44.363] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[09:15:44.779] <TB1> INFO: dacScan step from 24 .. 24
[09:15:44.779] <TB1> INFO: dacScan split into 1 runs with ntrig = 50
[09:15:44.779] <TB1> INFO: run 1 of 1
[09:15:45.382] <TB1> INFO: Expecting 208000 events.
[09:16:03.798] <TB1> INFO: 208000 events read in total (17889ms).
[09:16:03.809] <TB1> INFO: Test took 19029ms.
[09:16:03.908] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[09:16:04.204] <TB1> INFO: dacScan step from 25 .. 25
[09:16:04.204] <TB1> INFO: dacScan split into 1 runs with ntrig = 50
[09:16:04.204] <TB1> INFO: run 1 of 1
[09:16:04.805] <TB1> INFO: Expecting 208000 events.
[09:16:22.656] <TB1> INFO: 208000 events read in total (17324ms).
[09:16:22.667] <TB1> INFO: Test took 18463ms.
[09:16:22.769] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[09:16:23.073] <TB1> INFO: dacScan step from 26 .. 26
[09:16:23.073] <TB1> INFO: dacScan split into 1 runs with ntrig = 50
[09:16:23.073] <TB1> INFO: run 1 of 1
[09:16:23.683] <TB1> INFO: Expecting 208000 events.
[09:16:40.780] <TB1> INFO: 208000 events read in total (16569ms).
[09:16:40.789] <TB1> INFO: Test took 17715ms.
[09:16:40.886] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[09:16:41.196] <TB1> INFO: dacScan step from 27 .. 27
[09:16:41.196] <TB1> INFO: dacScan split into 1 runs with ntrig = 50
[09:16:41.196] <TB1> INFO: run 1 of 1
[09:16:41.805] <TB1> INFO: Expecting 208000 events.
[09:16:59.093] <TB1> INFO: 208000 events read in total (16761ms).
[09:16:59.101] <TB1> INFO: Test took 17904ms.
[09:16:59.200] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[09:16:59.495] <TB1> INFO: dacScan step from 28 .. 28
[09:16:59.495] <TB1> INFO: dacScan split into 1 runs with ntrig = 50
[09:16:59.495] <TB1> INFO: run 1 of 1
[09:17:00.118] <TB1> INFO: Expecting 208000 events.
[09:17:18.291] <TB1> INFO: 208000 events read in total (17646ms).
[09:17:18.300] <TB1> INFO: Test took 18804ms.
[09:17:18.399] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[09:17:18.701] <TB1> INFO: dacScan step from 29 .. 29
[09:17:18.701] <TB1> INFO: dacScan split into 1 runs with ntrig = 50
[09:17:18.701] <TB1> INFO: run 1 of 1
[09:17:19.310] <TB1> INFO: Expecting 208000 events.
[09:17:38.178] <TB1> INFO: 208000 events read in total (18341ms).
[09:17:38.188] <TB1> INFO: Test took 19486ms.
[09:17:38.294] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[09:17:38.578] <TB1> INFO: dacScan step from 30 .. 30
[09:17:38.579] <TB1> INFO: dacScan split into 1 runs with ntrig = 50
[09:17:38.579] <TB1> INFO: run 1 of 1
[09:17:39.185] <TB1> INFO: Expecting 208000 events.
[09:17:58.006] <TB1> INFO: 208000 events read in total (18294ms).
[09:17:58.015] <TB1> INFO: Test took 19436ms.
[09:17:58.122] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[09:17:58.534] <TB1> INFO: dacScan step from 31 .. 31
[09:17:58.534] <TB1> INFO: dacScan split into 1 runs with ntrig = 50
[09:17:58.534] <TB1> INFO: run 1 of 1
[09:17:59.161] <TB1> INFO: Expecting 208000 events.
[09:18:17.133] <TB1> INFO: 208000 events read in total (17444ms).
[09:18:17.144] <TB1> INFO: Test took 18610ms.
[09:18:17.245] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[09:18:17.556] <TB1> INFO: dacScan step from 32 .. 32
[09:18:17.556] <TB1> INFO: dacScan split into 1 runs with ntrig = 50
[09:18:17.556] <TB1> INFO: run 1 of 1
[09:18:18.171] <TB1> INFO: Expecting 208000 events.
[09:18:35.362] <TB1> INFO: 208000 events read in total (16663ms).
[09:18:35.371] <TB1> INFO: Test took 17815ms.
[09:18:35.472] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[09:18:35.774] <TB1> INFO: dacScan step from 33 .. 33
[09:18:35.774] <TB1> INFO: dacScan split into 1 runs with ntrig = 50
[09:18:35.774] <TB1> INFO: run 1 of 1
[09:18:36.391] <TB1> INFO: Expecting 208000 events.
[09:18:53.848] <TB1> INFO: 208000 events read in total (16930ms).
[09:18:53.859] <TB1> INFO: Test took 18084ms.
[09:18:53.960] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[09:18:54.265] <TB1> INFO: dacScan step from 34 .. 34
[09:18:54.265] <TB1> INFO: dacScan split into 1 runs with ntrig = 50
[09:18:54.265] <TB1> INFO: run 1 of 1
[09:18:54.870] <TB1> INFO: Expecting 208000 events.
[09:19:12.153] <TB1> INFO: 208000 events read in total (16756ms).
[09:19:12.164] <TB1> INFO: Test took 17899ms.
[09:19:12.262] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[09:19:12.569] <TB1> INFO: dacScan step from 35 .. 35
[09:19:12.569] <TB1> INFO: dacScan split into 1 runs with ntrig = 50
[09:19:12.569] <TB1> INFO: run 1 of 1
[09:19:13.177] <TB1> INFO: Expecting 208000 events.
[09:19:30.689] <TB1> INFO: 208000 events read in total (16984ms).
[09:19:30.700] <TB1> INFO: Test took 18130ms.
[09:19:30.805] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[09:19:31.118] <TB1> INFO: dacScan step from 36 .. 36
[09:19:31.118] <TB1> INFO: dacScan split into 1 runs with ntrig = 50
[09:19:31.118] <TB1> INFO: run 1 of 1
[09:19:31.731] <TB1> INFO: Expecting 208000 events.
[09:19:50.468] <TB1> INFO: 208000 events read in total (18210ms).
[09:19:50.478] <TB1> INFO: Test took 19359ms.
[09:19:50.580] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[09:19:50.890] <TB1> INFO: dacScan step from 37 .. 37
[09:19:50.890] <TB1> INFO: dacScan split into 1 runs with ntrig = 50
[09:19:50.890] <TB1> INFO: run 1 of 1
[09:19:51.494] <TB1> INFO: Expecting 208000 events.
[09:20:10.603] <TB1> INFO: 208000 events read in total (18582ms).
[09:20:10.613] <TB1> INFO: Test took 19723ms.
[09:20:10.715] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[09:20:11.011] <TB1> INFO: dacScan step from 38 .. 38
[09:20:11.011] <TB1> INFO: dacScan split into 1 runs with ntrig = 50
[09:20:11.011] <TB1> INFO: run 1 of 1
[09:20:11.611] <TB1> INFO: Expecting 208000 events.
[09:20:31.639] <TB1> INFO: 208000 events read in total (19501ms).
[09:20:31.650] <TB1> INFO: Test took 20639ms.
[09:20:31.750] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[09:20:32.055] <TB1> INFO: dacScan step from 39 .. 39
[09:20:32.055] <TB1> INFO: dacScan split into 1 runs with ntrig = 50
[09:20:32.055] <TB1> INFO: run 1 of 1
[09:20:32.655] <TB1> INFO: Expecting 208000 events.
[09:20:52.891] <TB1> INFO: 208000 events read in total (19709ms).
[09:20:52.903] <TB1> INFO: Test took 20847ms.
[09:20:53.005] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[09:20:53.307] <TB1> INFO: dacScan step from 40 .. 40
[09:20:53.307] <TB1> INFO: dacScan split into 1 runs with ntrig = 50
[09:20:53.307] <TB1> INFO: run 1 of 1
[09:20:53.908] <TB1> INFO: Expecting 208000 events.
[09:21:14.014] <TB1> INFO: 208000 events read in total (19578ms).
[09:21:14.024] <TB1> INFO: Test took 20716ms.
[09:21:14.126] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[09:21:14.453] <TB1> INFO: dacScan step from 41 .. 41
[09:21:14.453] <TB1> INFO: dacScan split into 1 runs with ntrig = 50
[09:21:14.453] <TB1> INFO: run 1 of 1
[09:21:15.076] <TB1> INFO: Expecting 208000 events.
[09:21:35.116] <TB1> INFO: 208000 events read in total (19512ms).
[09:21:35.128] <TB1> INFO: Test took 20674ms.
[09:21:35.231] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[09:21:35.573] <TB1> INFO: dacScan step from 42 .. 42
[09:21:35.573] <TB1> INFO: dacScan split into 1 runs with ntrig = 50
[09:21:35.573] <TB1> INFO: run 1 of 1
[09:21:36.180] <TB1> INFO: Expecting 208000 events.
[09:21:56.525] <TB1> INFO: 208000 events read in total (19818ms).
[09:21:56.536] <TB1> INFO: Test took 20962ms.
[09:21:56.639] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[09:21:56.941] <TB1> INFO: dacScan step from 43 .. 43
[09:21:56.941] <TB1> INFO: dacScan split into 1 runs with ntrig = 50
[09:21:56.941] <TB1> INFO: run 1 of 1
[09:21:57.542] <TB1> INFO: Expecting 208000 events.
[09:22:17.688] <TB1> INFO: 208000 events read in total (19619ms).
[09:22:17.699] <TB1> INFO: Test took 20758ms.
[09:22:17.812] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[09:22:18.169] <TB1> INFO: dacScan step from 44 .. 44
[09:22:18.169] <TB1> INFO: dacScan split into 1 runs with ntrig = 50
[09:22:18.169] <TB1> INFO: run 1 of 1
[09:22:18.796] <TB1> INFO: Expecting 208000 events.
[09:22:38.989] <TB1> INFO: 208000 events read in total (19666ms).
[09:22:39.001] <TB1> INFO: Test took 20831ms.
[09:22:39.110] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[09:22:39.445] <TB1> INFO: dacScan step from 45 .. 45
[09:22:39.445] <TB1> INFO: dacScan split into 1 runs with ntrig = 50
[09:22:39.445] <TB1> INFO: run 1 of 1
[09:22:40.070] <TB1> INFO: Expecting 208000 events.
[09:23:00.689] <TB1> INFO: 208000 events read in total (20091ms).
[09:23:00.700] <TB1> INFO: Test took 21254ms.
[09:23:00.804] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[09:23:01.108] <TB1> INFO: dacScan step from 46 .. 46
[09:23:01.108] <TB1> INFO: dacScan split into 1 runs with ntrig = 50
[09:23:01.108] <TB1> INFO: run 1 of 1
[09:23:01.712] <TB1> INFO: Expecting 208000 events.
[09:23:22.045] <TB1> INFO: 208000 events read in total (19805ms).
[09:23:22.055] <TB1> INFO: Test took 20947ms.
[09:23:22.167] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[09:23:22.466] <TB1> INFO: dacScan step from 47 .. 47
[09:23:22.466] <TB1> INFO: dacScan split into 1 runs with ntrig = 50
[09:23:22.466] <TB1> INFO: run 1 of 1
[09:23:23.063] <TB1> INFO: Expecting 208000 events.
[09:23:42.765] <TB1> INFO: 208000 events read in total (19175ms).
[09:23:42.776] <TB1> INFO: Test took 20310ms.
[09:23:42.883] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[09:23:43.204] <TB1> INFO: dacScan step from 48 .. 48
[09:23:43.204] <TB1> INFO: dacScan split into 1 runs with ntrig = 50
[09:23:43.204] <TB1> INFO: run 1 of 1
[09:23:43.804] <TB1> INFO: Expecting 208000 events.
[09:24:05.216] <TB1> INFO: 208000 events read in total (20885ms).
[09:24:05.228] <TB1> INFO: Test took 22023ms.
[09:24:05.328] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[09:24:05.641] <TB1> INFO: dacScan step from 49 .. 49
[09:24:05.641] <TB1> INFO: dacScan split into 1 runs with ntrig = 50
[09:24:05.641] <TB1> INFO: run 1 of 1
[09:24:06.248] <TB1> INFO: Expecting 208000 events.
[09:24:27.724] <TB1> INFO: 208000 events read in total (20949ms).
[09:24:27.735] <TB1> INFO: Test took 22093ms.
[09:24:27.836] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[09:24:28.167] <TB1> INFO: dacScan step from 50 .. 50
[09:24:28.167] <TB1> INFO: dacScan split into 1 runs with ntrig = 50
[09:24:28.167] <TB1> INFO: run 1 of 1
[09:24:28.770] <TB1> INFO: Expecting 208000 events.
[09:24:50.493] <TB1> INFO: 208000 events read in total (21196ms).
[09:24:50.503] <TB1> INFO: Test took 22336ms.
[09:24:50.600] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[09:24:50.928] <TB1> INFO: dacScan step from 51 .. 51
[09:24:50.928] <TB1> INFO: dacScan split into 1 runs with ntrig = 50
[09:24:50.928] <TB1> INFO: run 1 of 1
[09:24:51.523] <TB1> INFO: Expecting 208000 events.
[09:25:13.957] <TB1> INFO: 208000 events read in total (21907ms).
[09:25:13.968] <TB1> INFO: Test took 23040ms.
[09:25:14.073] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[09:25:14.436] <TB1> INFO: dacScan step from 52 .. 52
[09:25:14.436] <TB1> INFO: dacScan split into 1 runs with ntrig = 50
[09:25:14.436] <TB1> INFO: run 1 of 1
[09:25:15.065] <TB1> INFO: Expecting 208000 events.
[09:25:37.658] <TB1> INFO: 208000 events read in total (22066ms).
[09:25:37.670] <TB1> INFO: Test took 23233ms.
[09:25:37.771] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[09:25:38.115] <TB1> INFO: dacScan step from 53 .. 53
[09:25:38.115] <TB1> INFO: dacScan split into 1 runs with ntrig = 50
[09:25:38.115] <TB1> INFO: run 1 of 1
[09:25:38.725] <TB1> INFO: Expecting 208000 events.
[09:26:00.013] <TB1> INFO: 208000 events read in total (20761ms).
[09:26:00.026] <TB1> INFO: Test took 21910ms.
[09:26:00.134] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[09:26:00.447] <TB1> INFO: dacScan step from 54 .. 54
[09:26:00.447] <TB1> INFO: dacScan split into 1 runs with ntrig = 50
[09:26:00.447] <TB1> INFO: run 1 of 1
[09:26:01.054] <TB1> INFO: Expecting 208000 events.
[09:26:21.604] <TB1> INFO: 208000 events read in total (20023ms).
[09:26:21.614] <TB1> INFO: Test took 21166ms.
[09:26:21.720] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[09:26:22.022] <TB1> INFO: dacScan step from 55 .. 55
[09:26:22.022] <TB1> INFO: dacScan split into 1 runs with ntrig = 50
[09:26:22.022] <TB1> INFO: run 1 of 1
[09:26:22.626] <TB1> INFO: Expecting 208000 events.
[09:26:43.725] <TB1> INFO: 208000 events read in total (20572ms).
[09:26:43.736] <TB1> INFO: Test took 21713ms.
[09:26:43.840] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[09:26:44.182] <TB1> INFO: dacScan step from 56 .. 56
[09:26:44.182] <TB1> INFO: dacScan split into 1 runs with ntrig = 50
[09:26:44.182] <TB1> INFO: run 1 of 1
[09:26:44.805] <TB1> INFO: Expecting 208000 events.
[09:27:07.115] <TB1> INFO: 208000 events read in total (21783ms).
[09:27:07.127] <TB1> INFO: Test took 22944ms.
[09:27:07.232] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[09:27:07.574] <TB1> INFO: dacScan step from 57 .. 57
[09:27:07.574] <TB1> INFO: dacScan split into 1 runs with ntrig = 50
[09:27:07.574] <TB1> INFO: run 1 of 1
[09:27:08.169] <TB1> INFO: Expecting 208000 events.
[09:27:31.354] <TB1> INFO: 208000 events read in total (22658ms).
[09:27:31.365] <TB1> INFO: Test took 23790ms.
[09:27:31.469] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[09:27:31.775] <TB1> INFO: dacScan step from 58 .. 58
[09:27:31.775] <TB1> INFO: dacScan split into 1 runs with ntrig = 50
[09:27:31.775] <TB1> INFO: run 1 of 1
[09:27:32.383] <TB1> INFO: Expecting 208000 events.
[09:27:54.573] <TB1> INFO: 208000 events read in total (21663ms).
[09:27:54.585] <TB1> INFO: Test took 22809ms.
[09:27:54.687] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[09:27:54.981] <TB1> INFO: dacScan step from 59 .. 59
[09:27:54.981] <TB1> INFO: dacScan split into 1 runs with ntrig = 50
[09:27:54.981] <TB1> INFO: run 1 of 1
[09:27:55.583] <TB1> INFO: Expecting 208000 events.
[09:28:17.106] <TB1> INFO: 208000 events read in total (20996ms).
[09:28:17.118] <TB1> INFO: Test took 22137ms.
[09:28:17.224] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[09:28:17.521] <TB1> INFO: dacScan step from 60 .. 60
[09:28:17.521] <TB1> INFO: dacScan split into 1 runs with ntrig = 50
[09:28:17.521] <TB1> INFO: run 1 of 1
[09:28:18.117] <TB1> INFO: Expecting 208000 events.
[09:28:39.550] <TB1> INFO: 208000 events read in total (20905ms).
[09:28:39.562] <TB1> INFO: Test took 22040ms.
[09:28:39.665] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[09:28:39.973] <TB1> INFO: dacScan step from 61 .. 61
[09:28:39.973] <TB1> INFO: dacScan split into 1 runs with ntrig = 50
[09:28:39.973] <TB1> INFO: run 1 of 1
[09:28:40.595] <TB1> INFO: Expecting 208000 events.
[09:29:02.492] <TB1> INFO: 208000 events read in total (21369ms).
[09:29:02.506] <TB1> INFO: Test took 22533ms.
[09:29:02.626] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[09:29:02.927] <TB1> INFO: dacScan step from 62 .. 62
[09:29:02.927] <TB1> INFO: dacScan split into 1 runs with ntrig = 50
[09:29:02.927] <TB1> INFO: run 1 of 1
[09:29:03.542] <TB1> INFO: Expecting 208000 events.
[09:29:25.708] <TB1> INFO: 208000 events read in total (21639ms).
[09:29:25.721] <TB1> INFO: Test took 22794ms.
[09:29:25.828] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[09:29:26.178] <TB1> INFO: dacScan step from 63 .. 63
[09:29:26.178] <TB1> INFO: dacScan split into 1 runs with ntrig = 50
[09:29:26.178] <TB1> INFO: run 1 of 1
[09:29:26.791] <TB1> INFO: Expecting 208000 events.
[09:29:49.765] <TB1> INFO: 208000 events read in total (22447ms).
[09:29:49.776] <TB1> INFO: Test took 23597ms.
[09:29:49.889] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[09:29:50.227] <TB1> INFO: dacScan step from 64 .. 64
[09:29:50.227] <TB1> INFO: dacScan split into 1 runs with ntrig = 50
[09:29:50.227] <TB1> INFO: run 1 of 1
[09:29:50.858] <TB1> INFO: Expecting 208000 events.
[09:30:12.899] <TB1> INFO: 208000 events read in total (21514ms).
[09:30:12.910] <TB1> INFO: Test took 22682ms.
[09:30:13.012] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[09:30:13.363] <TB1> INFO: dacScan step from 65 .. 65
[09:30:13.363] <TB1> INFO: dacScan split into 1 runs with ntrig = 50
[09:30:13.363] <TB1> INFO: run 1 of 1
[09:30:13.991] <TB1> INFO: Expecting 208000 events.
[09:30:35.689] <TB1> INFO: 208000 events read in total (21171ms).
[09:30:35.700] <TB1> INFO: Test took 22337ms.
[09:30:35.804] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[09:30:36.138] <TB1> INFO: dacScan step from 66 .. 66
[09:30:36.138] <TB1> INFO: dacScan split into 1 runs with ntrig = 50
[09:30:36.138] <TB1> INFO: run 1 of 1
[09:30:36.755] <TB1> INFO: Expecting 208000 events.
[09:30:57.921] <TB1> INFO: 208000 events read in total (20639ms).
[09:30:57.933] <TB1> INFO: Test took 21794ms.
[09:30:58.042] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[09:30:58.353] <TB1> INFO: dacScan step from 67 .. 67
[09:30:58.353] <TB1> INFO: dacScan split into 1 runs with ntrig = 50
[09:30:58.353] <TB1> INFO: run 1 of 1
[09:30:58.959] <TB1> INFO: Expecting 208000 events.
[09:31:19.831] <TB1> INFO: 208000 events read in total (20345ms).
[09:31:19.843] <TB1> INFO: Test took 21489ms.
[09:31:19.952] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[09:31:20.269] <TB1> INFO: dacScan step from 68 .. 68
[09:31:20.269] <TB1> INFO: dacScan split into 1 runs with ntrig = 50
[09:31:20.269] <TB1> INFO: run 1 of 1
[09:31:20.897] <TB1> INFO: Expecting 208000 events.
[09:31:42.525] <TB1> INFO: 208000 events read in total (21100ms).
[09:31:42.536] <TB1> INFO: Test took 22266ms.
[09:31:42.649] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[09:31:42.960] <TB1> INFO: dacScan step from 69 .. 69
[09:31:42.960] <TB1> INFO: dacScan split into 1 runs with ntrig = 50
[09:31:42.960] <TB1> INFO: run 1 of 1
[09:31:43.566] <TB1> INFO: Expecting 208000 events.
[09:32:06.424] <TB1> INFO: 208000 events read in total (22330ms).
[09:32:06.435] <TB1> INFO: Test took 23474ms.
[09:32:06.541] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[09:32:06.863] <TB1> INFO: dacScan step from 70 .. 70
[09:32:06.863] <TB1> INFO: dacScan split into 1 runs with ntrig = 50
[09:32:06.863] <TB1> INFO: run 1 of 1
[09:32:07.466] <TB1> INFO: Expecting 208000 events.
[09:32:30.183] <TB1> INFO: 208000 events read in total (22189ms).
[09:32:30.194] <TB1> INFO: Test took 23330ms.
[09:32:30.297] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[09:32:30.617] <TB1> INFO: dacScan step from 71 .. 71
[09:32:30.617] <TB1> INFO: dacScan split into 1 runs with ntrig = 50
[09:32:30.617] <TB1> INFO: run 1 of 1
[09:32:31.234] <TB1> INFO: Expecting 208000 events.
[09:32:53.409] <TB1> INFO: 208000 events read in total (21648ms).
[09:32:53.420] <TB1> INFO: Test took 22802ms.
[09:32:53.532] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[09:32:53.892] <TB1> INFO: dacScan step from 72 .. 72
[09:32:53.892] <TB1> INFO: dacScan split into 1 runs with ntrig = 50
[09:32:53.892] <TB1> INFO: run 1 of 1
[09:32:54.506] <TB1> INFO: Expecting 208000 events.
[09:33:16.104] <TB1> INFO: 208000 events read in total (21071ms).
[09:33:16.115] <TB1> INFO: Test took 22222ms.
[09:33:16.228] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[09:33:16.555] <TB1> INFO: dacScan step from 73 .. 73
[09:33:16.555] <TB1> INFO: dacScan split into 1 runs with ntrig = 50
[09:33:16.555] <TB1> INFO: run 1 of 1
[09:33:17.161] <TB1> INFO: Expecting 208000 events.
[09:33:37.890] <TB1> INFO: 208000 events read in total (20201ms).
[09:33:37.903] <TB1> INFO: Test took 21347ms.
[09:33:38.021] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[09:33:38.351] <TB1> INFO: dacScan step from 74 .. 74
[09:33:38.351] <TB1> INFO: dacScan split into 1 runs with ntrig = 50
[09:33:38.351] <TB1> INFO: run 1 of 1
[09:33:38.964] <TB1> INFO: Expecting 208000 events.
[09:33:59.682] <TB1> INFO: 208000 events read in total (20191ms).
[09:33:59.694] <TB1> INFO: Test took 21342ms.
[09:33:59.946] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[09:34:00.247] <TB1> INFO: dacScan step from 75 .. 75
[09:34:00.247] <TB1> INFO: dacScan split into 1 runs with ntrig = 50
[09:34:00.247] <TB1> INFO: run 1 of 1
[09:34:00.848] <TB1> INFO: Expecting 208000 events.
[09:34:22.165] <TB1> INFO: 208000 events read in total (20790ms).
[09:34:22.177] <TB1> INFO: Test took 21930ms.
[09:34:22.395] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[09:34:22.728] <TB1> INFO: dacScan step from 76 .. 76
[09:34:22.728] <TB1> INFO: dacScan split into 1 runs with ntrig = 50
[09:34:22.728] <TB1> INFO: run 1 of 1
[09:34:23.334] <TB1> INFO: Expecting 208000 events.
[09:34:46.216] <TB1> INFO: 208000 events read in total (22355ms).
[09:34:46.227] <TB1> INFO: Test took 23498ms.
[09:34:46.536] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[09:34:46.936] <TB1> INFO: dacScan step from 77 .. 77
[09:34:46.936] <TB1> INFO: dacScan split into 1 runs with ntrig = 50
[09:34:46.936] <TB1> INFO: run 1 of 1
[09:34:47.536] <TB1> INFO: Expecting 208000 events.
[09:35:11.058] <TB1> INFO: 208000 events read in total (22995ms).
[09:35:11.070] <TB1> INFO: Test took 24133ms.
[09:35:11.195] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[09:35:11.515] <TB1> INFO: dacScan step from 78 .. 78
[09:35:11.515] <TB1> INFO: dacScan split into 1 runs with ntrig = 50
[09:35:11.515] <TB1> INFO: run 1 of 1
[09:35:12.120] <TB1> INFO: Expecting 208000 events.
[09:35:33.535] <TB1> INFO: 208000 events read in total (20888ms).
[09:35:33.549] <TB1> INFO: Test took 22033ms.
[09:35:33.758] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[09:35:34.053] <TB1> INFO: dacScan step from 79 .. 79
[09:35:34.053] <TB1> INFO: dacScan split into 1 runs with ntrig = 50
[09:35:34.053] <TB1> INFO: run 1 of 1
[09:35:34.653] <TB1> INFO: Expecting 208000 events.
[09:35:55.962] <TB1> INFO: 208000 events read in total (20782ms).
[09:35:55.976] <TB1> INFO: Test took 21922ms.
[09:35:56.258] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[09:35:56.575] <TB1> INFO: dacScan step from 80 .. 80
[09:35:56.575] <TB1> INFO: dacScan split into 1 runs with ntrig = 50
[09:35:56.575] <TB1> INFO: run 1 of 1
[09:35:57.195] <TB1> INFO: Expecting 208000 events.
[09:36:18.214] <TB1> INFO: 208000 events read in total (20492ms).
[09:36:18.227] <TB1> INFO: Test took 21651ms.
[09:36:18.403] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[09:36:18.710] <TB1> INFO: dacScan step from 81 .. 81
[09:36:18.710] <TB1> INFO: dacScan split into 1 runs with ntrig = 50
[09:36:18.710] <TB1> INFO: run 1 of 1
[09:36:19.317] <TB1> INFO: Expecting 208000 events.
[09:36:39.783] <TB1> INFO: 208000 events read in total (19939ms).
[09:36:39.796] <TB1> INFO: Test took 21085ms.
[09:36:39.909] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[09:36:40.219] <TB1> INFO: dacScan step from 82 .. 82
[09:36:40.219] <TB1> INFO: dacScan split into 1 runs with ntrig = 50
[09:36:40.219] <TB1> INFO: run 1 of 1
[09:36:40.827] <TB1> INFO: Expecting 208000 events.
[09:37:00.764] <TB1> INFO: 208000 events read in total (19410ms).
[09:37:00.779] <TB1> INFO: Test took 20559ms.
[09:37:00.887] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[09:37:01.194] <TB1> INFO: dacScan step from 83 .. 83
[09:37:01.194] <TB1> INFO: dacScan split into 1 runs with ntrig = 50
[09:37:01.194] <TB1> INFO: run 1 of 1
[09:37:01.797] <TB1> INFO: Expecting 208000 events.
[09:37:25.032] <TB1> INFO: 208000 events read in total (22708ms).
[09:37:25.046] <TB1> INFO: Test took 23851ms.
[09:37:25.151] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[09:37:25.451] <TB1> INFO: dacScan step from 84 .. 84
[09:37:25.451] <TB1> INFO: dacScan split into 1 runs with ntrig = 50
[09:37:25.451] <TB1> INFO: run 1 of 1
[09:37:26.052] <TB1> INFO: Expecting 208000 events.
[09:37:49.709] <TB1> INFO: 208000 events read in total (23130ms).
[09:37:49.726] <TB1> INFO: Test took 24275ms.
[09:37:49.842] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[09:37:50.157] <TB1> INFO: dacScan step from 85 .. 85
[09:37:50.157] <TB1> INFO: dacScan split into 1 runs with ntrig = 50
[09:37:50.157] <TB1> INFO: run 1 of 1
[09:37:50.775] <TB1> INFO: Expecting 208000 events.
[09:38:12.793] <TB1> INFO: 208000 events read in total (21491ms).
[09:38:12.807] <TB1> INFO: Test took 22649ms.
[09:38:12.910] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[09:38:13.200] <TB1> INFO: dacScan step from 86 .. 86
[09:38:13.200] <TB1> INFO: dacScan split into 1 runs with ntrig = 50
[09:38:13.200] <TB1> INFO: run 1 of 1
[09:38:13.797] <TB1> INFO: Expecting 208000 events.
[09:38:36.001] <TB1> INFO: 208000 events read in total (21676ms).
[09:38:36.015] <TB1> INFO: Test took 22814ms.
[09:38:36.125] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[09:38:36.449] <TB1> INFO: dacScan step from 87 .. 87
[09:38:36.449] <TB1> INFO: dacScan split into 1 runs with ntrig = 50
[09:38:36.449] <TB1> INFO: run 1 of 1
[09:38:37.067] <TB1> INFO: Expecting 208000 events.
[09:38:56.680] <TB1> INFO: 208000 events read in total (19086ms).
[09:38:56.695] <TB1> INFO: Test took 20245ms.
[09:38:56.800] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[09:38:57.112] <TB1> INFO: dacScan step from 88 .. 88
[09:38:57.112] <TB1> INFO: dacScan split into 1 runs with ntrig = 50
[09:38:57.112] <TB1> INFO: run 1 of 1
[09:38:57.716] <TB1> INFO: Expecting 208000 events.
[09:39:16.748] <TB1> INFO: 208000 events read in total (18505ms).
[09:39:16.762] <TB1> INFO: Test took 19649ms.
[09:39:16.866] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[09:39:17.173] <TB1> INFO: dacScan step from 89 .. 89
[09:39:17.173] <TB1> INFO: dacScan split into 1 runs with ntrig = 50
[09:39:17.173] <TB1> INFO: run 1 of 1
[09:39:17.774] <TB1> INFO: Expecting 208000 events.
[09:39:35.075] <TB1> INFO: 208000 events read in total (16773ms).
[09:39:35.088] <TB1> INFO: Test took 17914ms.
[09:39:35.185] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[09:39:35.490] <TB1> INFO: dacScan step from 90 .. 90
[09:39:35.490] <TB1> INFO: dacScan split into 1 runs with ntrig = 50
[09:39:35.490] <TB1> INFO: run 1 of 1
[09:39:36.089] <TB1> INFO: Expecting 208000 events.
[09:39:53.045] <TB1> INFO: 208000 events read in total (16429ms).
[09:39:53.058] <TB1> INFO: Test took 17567ms.
[09:39:53.154] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[09:39:53.434] <TB1> INFO: dacScan step from 91 .. 91
[09:39:53.434] <TB1> INFO: dacScan split into 1 runs with ntrig = 50
[09:39:53.434] <TB1> INFO: run 1 of 1
[09:39:54.026] <TB1> INFO: Expecting 208000 events.
[09:40:10.587] <TB1> INFO: 208000 events read in total (16034ms).
[09:40:10.602] <TB1> INFO: Test took 17167ms.
[09:40:10.700] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[09:40:10.981] <TB1> INFO: dacScan step from 92 .. 92
[09:40:10.981] <TB1> INFO: dacScan split into 1 runs with ntrig = 50
[09:40:10.981] <TB1> INFO: run 1 of 1
[09:40:11.573] <TB1> INFO: Expecting 208000 events.
[09:40:28.171] <TB1> INFO: 208000 events read in total (16071ms).
[09:40:28.186] <TB1> INFO: Test took 17204ms.
[09:40:28.283] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[09:40:28.561] <TB1> INFO: dacScan step from 93 .. 93
[09:40:28.561] <TB1> INFO: dacScan split into 1 runs with ntrig = 50
[09:40:28.561] <TB1> INFO: run 1 of 1
[09:40:29.148] <TB1> INFO: Expecting 208000 events.
[09:40:45.814] <TB1> INFO: 208000 events read in total (16138ms).
[09:40:45.829] <TB1> INFO: Test took 17267ms.
[09:40:45.924] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[09:40:46.200] <TB1> INFO: dacScan step from 94 .. 94
[09:40:46.200] <TB1> INFO: dacScan split into 1 runs with ntrig = 50
[09:40:46.200] <TB1> INFO: run 1 of 1
[09:40:46.792] <TB1> INFO: Expecting 208000 events.
[09:41:03.102] <TB1> INFO: 208000 events read in total (15782ms).
[09:41:03.117] <TB1> INFO: Test took 16916ms.
[09:41:03.216] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[09:41:03.492] <TB1> INFO: dacScan step from 95 .. 95
[09:41:03.492] <TB1> INFO: dacScan split into 1 runs with ntrig = 50
[09:41:03.492] <TB1> INFO: run 1 of 1
[09:41:04.081] <TB1> INFO: Expecting 208000 events.
[09:41:20.787] <TB1> INFO: 208000 events read in total (16179ms).
[09:41:20.802] <TB1> INFO: Test took 17309ms.
[09:41:20.901] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[09:41:21.178] <TB1> INFO: dacScan step from 96 .. 96
[09:41:21.178] <TB1> INFO: dacScan split into 1 runs with ntrig = 50
[09:41:21.178] <TB1> INFO: run 1 of 1
[09:41:21.769] <TB1> INFO: Expecting 208000 events.
[09:41:38.352] <TB1> INFO: 208000 events read in total (16056ms).
[09:41:38.367] <TB1> INFO: Test took 17188ms.
[09:41:38.465] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[09:41:38.744] <TB1> INFO: dacScan step from 97 .. 97
[09:41:38.744] <TB1> INFO: dacScan split into 1 runs with ntrig = 50
[09:41:38.744] <TB1> INFO: run 1 of 1
[09:41:39.337] <TB1> INFO: Expecting 208000 events.
[09:41:55.918] <TB1> INFO: 208000 events read in total (16054ms).
[09:41:55.933] <TB1> INFO: Test took 17188ms.
[09:41:56.031] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[09:41:56.308] <TB1> INFO: dacScan step from 98 .. 98
[09:41:56.308] <TB1> INFO: dacScan split into 1 runs with ntrig = 50
[09:41:56.308] <TB1> INFO: run 1 of 1
[09:41:56.900] <TB1> INFO: Expecting 208000 events.
[09:42:13.312] <TB1> INFO: 208000 events read in total (15884ms).
[09:42:13.327] <TB1> INFO: Test took 17018ms.
[09:42:13.424] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[09:42:13.705] <TB1> INFO: dacScan step from 99 .. 99
[09:42:13.705] <TB1> INFO: dacScan split into 1 runs with ntrig = 50
[09:42:13.705] <TB1> INFO: run 1 of 1
[09:42:14.296] <TB1> INFO: Expecting 208000 events.
[09:42:30.617] <TB1> INFO: 208000 events read in total (15793ms).
[09:42:30.633] <TB1> INFO: Test took 16928ms.
[09:42:30.728] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[09:42:31.010] <TB1> INFO: dacScan step from 100 .. 100
[09:42:31.010] <TB1> INFO: dacScan split into 1 runs with ntrig = 50
[09:42:31.010] <TB1> INFO: run 1 of 1
[09:42:31.601] <TB1> INFO: Expecting 208000 events.
[09:42:48.286] <TB1> INFO: 208000 events read in total (16158ms).
[09:42:48.301] <TB1> INFO: Test took 17290ms.
[09:42:48.399] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[09:42:48.681] <TB1> INFO: dumping ASCII scurve output file: XSCurveData
[09:42:49.981] <TB1> INFO: dumping ASCII scurve output file: XSCurveData
[09:42:51.292] <TB1> INFO: dumping ASCII scurve output file: XSCurveData
[09:42:52.614] <TB1> INFO: dumping ASCII scurve output file: XSCurveData
[09:42:53.954] <TB1> INFO: dumping ASCII scurve output file: XSCurveData
[09:42:55.344] <TB1> INFO: dumping ASCII scurve output file: XSCurveData
[09:42:56.767] <TB1> INFO: dumping ASCII scurve output file: XSCurveData
[09:42:58.225] <TB1> INFO: dumping ASCII scurve output file: XSCurveData
[09:42:59.653] <TB1> INFO: dumping ASCII scurve output file: XSCurveData
[09:43:01.100] <TB1> INFO: dumping ASCII scurve output file: XSCurveData
[09:43:02.530] <TB1> INFO: dumping ASCII scurve output file: XSCurveData
[09:43:03.985] <TB1> INFO: dumping ASCII scurve output file: XSCurveData
[09:43:05.389] <TB1> INFO: dumping ASCII scurve output file: XSCurveData
[09:43:06.738] <TB1> INFO: dumping ASCII scurve output file: XSCurveData
[09:43:08.011] <TB1> INFO: dumping ASCII scurve output file: XSCurveData
[09:43:09.316] <TB1> INFO: dumping ASCII scurve output file: XSCurveData
[09:43:10.597] <TB1> INFO: PixTest:: pg_setup set to default.
[09:43:10.598] <TB1> INFO: vcal mean: 38.44 39.26 38.53 41.24 43.42 45.07 44.60 45.21 43.99 43.97 43.81 42.20 42.38 39.54 38.78 38.87
[09:43:10.598] <TB1> INFO: vcal RMS: 1.23 1.65 1.13 1.83 1.67 1.69 1.59 1.65 1.62 1.72 1.67 1.71 1.49 1.46 1.21 1.30
[09:43:10.598] <TB1> INFO: number of X-ray hits detected: 194642 220605 199587 349331 434378 520476 494962 503424 475646 451618 461850 354390 368851 215662 195472 194279
[09:43:10.598] <TB1> 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:43:10.598] <TB1> INFO: X-ray hit rate [MHz/cm2]: 57.1 64.7 58.5 102.4 127.3 152.6 145.1 147.6 139.4 132.4 135.4 103.9 108.1 63.2 57.3 56.9
[09:43:10.598] <TB1> INFO: PixTestHighRate::doXNoiseMaps() done
[09:43:10.693] <TB1> INFO: enter test to run
[09:43:10.693] <TB1> INFO: test: no parameter change
[09:43:10.884] <TB1> QUIET: Connection to board 83 closed.
[09:43:10.964] <TB1> INFO: pXar: this is the end, my friend

[10:01:16.001] <TB1> INFO: *** Welcome to pxar ***
[10:01:16.001] <TB1> INFO: *** Today: 2015/08/28
[10:01:16.001] <TB1> INFO: readRocDacs: /usr/local/data/M2085_XrayQualification_2015-08-28_10h32m_1440750764//010_CalDelScanAndSaveDacs_4mA25kV//dacParameters35_C0.dat .. /usr/local/data/M2085_XrayQualification_2015-08-28_10h32m_1440750764//010_CalDelScanAndSaveDacs_4mA25kV//dacParameters35_C15.dat
[10:01:16.002] <TB1> INFO: readTbmDacs: /usr/local/data/M2085_XrayQualification_2015-08-28_10h32m_1440750764//010_CalDelScanAndSaveDacs_4mA25kV//tbmParameters_C0a.dat .. /usr/local/data/M2085_XrayQualification_2015-08-28_10h32m_1440750764//010_CalDelScanAndSaveDacs_4mA25kV//tbmParameters_C0b.dat
[10:01:16.002] <TB1> INFO: readMaskFile: /usr/local/data/M2085_XrayQualification_2015-08-28_10h32m_1440750764//010_CalDelScanAndSaveDacs_4mA25kV//defaultMaskFile.dat
[10:01:16.002] <TB1> INFO: readTrimFile: /usr/local/data/M2085_XrayQualification_2015-08-28_10h32m_1440750764//010_CalDelScanAndSaveDacs_4mA25kV//trimParameters35_C0.dat .. /usr/local/data/M2085_XrayQualification_2015-08-28_10h32m_1440750764//010_CalDelScanAndSaveDacs_4mA25kV//trimParameters35_C15.dat
[10:01:16.072] <TB1> INFO: clk: 4
[10:01:16.072] <TB1> INFO: ctr: 4
[10:01:16.072] <TB1> INFO: sda: 19
[10:01:16.072] <TB1> INFO: tin: 9
[10:01:16.072] <TB1> INFO: level: 15
[10:01:16.072] <TB1> INFO: triggerdelay: 0
[10:01:16.072] <TB1> QUIET: Instanciating API for pxar v2.5+57~g44d0777
[10:01:16.072] <TB1> INFO: Log level: INFO
[10:01:16.085] <TB1> INFO: Found DTB DTB_WS6UZO
[10:01:16.095] <TB1> QUIET: Connection to board DTB_WS6UZO opened.
[10:01:16.098] <TB1> INFO: DTB startup information
--- DTB info------------------------------------------
Board id: 83
HW version: DTB1.2
FW version: 4.2
SW version: 4.4
USB id: DTB_WS6UZO
MAC address: 40D855118053
Hostname: pixelDTB083
Comment:
------------------------------------------------------
[10:01:16.101] <TB1> INFO: RPC call hashes of host and DTB match: 397073690
[10:01:17.627] <TB1> INFO: DUT info:
[10:01:17.627] <TB1> INFO: The DUT currently contains the following objects:
[10:01:17.627] <TB1> INFO: 2 TBM Cores tbm09c (2 ON)
[10:01:17.627] <TB1> INFO: TBM Core alpha (0): 7 registers set
[10:01:17.627] <TB1> INFO: TBM Core beta (1): 7 registers set
[10:01:17.627] <TB1> INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[10:01:17.627] <TB1> INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[10:01:17.627] <TB1> INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[10:01:17.627] <TB1> INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[10:01:17.627] <TB1> INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[10:01:17.627] <TB1> INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[10:01:17.627] <TB1> INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[10:01:17.627] <TB1> INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[10:01:17.627] <TB1> INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[10:01:17.627] <TB1> INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[10:01:17.627] <TB1> INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[10:01:17.627] <TB1> INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[10:01:17.627] <TB1> INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[10:01:17.627] <TB1> INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[10:01:17.627] <TB1> INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[10:01:17.627] <TB1> INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[10:01:17.627] <TB1> INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[10:01:18.028] <TB1> INFO: enter 'restricted' command line mode
[10:01:18.028] <TB1> INFO: enter test to run
[10:01:18.028] <TB1> INFO: test: HighRate no parameter change
[10:01:18.028] <TB1> INFO: running: highrate
[10:01:18.028] <TB1> INFO: readGainPedestalParameters /usr/local/data/M2085_XrayQualification_2015-08-28_10h32m_1440750764//010_CalDelScanAndSaveDacs_4mA25kV//phCalibrationFitErr35_C0.dat .. /usr/local/data/M2085_XrayQualification_2015-08-28_10h32m_1440750764//010_CalDelScanAndSaveDacs_4mA25kV//phCalibrationFitErr35_C15.dat
[10:01:18.172] <TB1> INFO: ----------------------------------------------------------------------
[10:01:18.172] <TB1> INFO: PixTestHighRate::calDelScan() ntrig = 10, vcal = 200
[10:01:18.172] <TB1> INFO: ----------------------------------------------------------------------
[10:01:18.310] <TB1> INFO: Expecting 768 events.
[10:01:19.555] <TB1> INFO: 768 events read in total (526ms).
[10:01:19.650] <TB1> INFO: Test took 1473ms.
[10:01:20.270] <TB1> INFO: Expecting 41600 events.
[10:01:23.992] <TB1> INFO: 41600 events read in total (3195ms).
[10:01:23.994] <TB1> INFO: Test took 4338ms.
[10:01:24.082] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[10:01:24.730] <TB1> INFO: Expecting 41600 events.
[10:01:28.633] <TB1> INFO: 41600 events read in total (3376ms).
[10:01:28.635] <TB1> INFO: Test took 4519ms.
[10:01:28.721] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[10:01:29.365] <TB1> INFO: Expecting 41600 events.
[10:01:33.209] <TB1> INFO: 41600 events read in total (3316ms).
[10:01:33.211] <TB1> INFO: Test took 4459ms.
[10:01:33.299] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[10:01:33.944] <TB1> INFO: Expecting 41600 events.
[10:01:38.083] <TB1> INFO: 41600 events read in total (3611ms).
[10:01:38.085] <TB1> INFO: Test took 4753ms.
[10:01:38.177] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[10:01:38.836] <TB1> INFO: Expecting 41600 events.
[10:01:42.951] <TB1> INFO: 41600 events read in total (3587ms).
[10:01:42.953] <TB1> INFO: Test took 4737ms.
[10:01:43.046] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[10:01:43.699] <TB1> INFO: Expecting 41600 events.
[10:01:47.735] <TB1> INFO: 41600 events read in total (3509ms).
[10:01:47.738] <TB1> INFO: Test took 4652ms.
[10:01:47.835] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[10:01:48.500] <TB1> INFO: Expecting 41600 events.
[10:01:52.772] <TB1> INFO: 41600 events read in total (3745ms).
[10:01:52.774] <TB1> INFO: Test took 4897ms.
[10:01:52.870] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[10:01:53.517] <TB1> INFO: Expecting 41600 events.
[10:01:57.988] <TB1> INFO: 41600 events read in total (3944ms).
[10:01:57.991] <TB1> INFO: Test took 5086ms.
[10:01:58.091] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[10:01:58.741] <TB1> INFO: Expecting 41600 events.
[10:02:03.169] <TB1> INFO: 41600 events read in total (3901ms).
[10:02:03.171] <TB1> INFO: Test took 5043ms.
[10:02:03.272] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[10:02:03.931] <TB1> INFO: Expecting 41600 events.
[10:02:08.158] <TB1> INFO: 41600 events read in total (3700ms).
[10:02:08.161] <TB1> INFO: Test took 4850ms.
[10:02:08.260] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[10:02:08.931] <TB1> INFO: Expecting 41600 events.
[10:02:13.216] <TB1> INFO: 41600 events read in total (3758ms).
[10:02:13.218] <TB1> INFO: Test took 4916ms.
[10:02:13.305] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[10:02:13.951] <TB1> INFO: Expecting 41600 events.
[10:02:18.321] <TB1> INFO: 41600 events read in total (3843ms).
[10:02:18.323] <TB1> INFO: Test took 4985ms.
[10:02:18.409] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[10:02:19.055] <TB1> INFO: Expecting 41600 events.
[10:02:23.087] <TB1> INFO: 41600 events read in total (3505ms).
[10:02:23.089] <TB1> INFO: Test took 4647ms.
[10:02:23.177] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[10:02:23.823] <TB1> INFO: Expecting 41600 events.
[10:02:27.693] <TB1> INFO: 41600 events read in total (3343ms).
[10:02:27.695] <TB1> INFO: Test took 4485ms.
[10:02:27.780] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[10:02:28.428] <TB1> INFO: Expecting 41600 events.
[10:02:32.285] <TB1> INFO: 41600 events read in total (3330ms).
[10:02:32.287] <TB1> INFO: Test took 4472ms.
[10:02:32.374] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[10:02:33.021] <TB1> INFO: Expecting 41600 events.
[10:02:36.764] <TB1> INFO: 41600 events read in total (3215ms).
[10:02:36.765] <TB1> INFO: Test took 4357ms.
[10:02:36.850] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[10:02:37.494] <TB1> INFO: Expecting 41600 events.
[10:02:41.184] <TB1> INFO: 41600 events read in total (3163ms).
[10:02:41.186] <TB1> INFO: Test took 4305ms.
[10:02:41.273] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[10:02:41.920] <TB1> INFO: Expecting 41600 events.
[10:02:45.429] <TB1> INFO: 41600 events read in total (2982ms).
[10:02:45.431] <TB1> INFO: Test took 4124ms.
[10:02:45.523] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[10:02:46.185] <TB1> INFO: Expecting 41600 events.
[10:02:49.717] <TB1> INFO: 41600 events read in total (3004ms).
[10:02:49.719] <TB1> INFO: Test took 4157ms.
[10:02:49.831] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[10:02:50.492] <TB1> INFO: Expecting 41600 events.
[10:02:53.861] <TB1> INFO: 41600 events read in total (2841ms).
[10:02:53.862] <TB1> INFO: Test took 3986ms.
[10:02:53.949] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[10:02:54.263] <TB1> INFO: enter test to run
[10:02:54.263] <TB1> INFO: test: Pretest no parameter change
[10:02:54.263] <TB1> INFO: running: pretest
[10:02:54.263] <TB1> INFO: write dac parameters into /usr/local/data/M2085_XrayQualification_2015-08-28_10h32m_1440750764//010_CalDelScanAndSaveDacs_4mA25kV//dacParameters35_C0.dat
[10:02:54.263] <TB1> INFO: write dac parameters into /usr/local/data/M2085_XrayQualification_2015-08-28_10h32m_1440750764//010_CalDelScanAndSaveDacs_4mA25kV//dacParameters35_C1.dat
[10:02:54.263] <TB1> INFO: write dac parameters into /usr/local/data/M2085_XrayQualification_2015-08-28_10h32m_1440750764//010_CalDelScanAndSaveDacs_4mA25kV//dacParameters35_C2.dat
[10:02:54.263] <TB1> INFO: write dac parameters into /usr/local/data/M2085_XrayQualification_2015-08-28_10h32m_1440750764//010_CalDelScanAndSaveDacs_4mA25kV//dacParameters35_C3.dat
[10:02:54.264] <TB1> INFO: write dac parameters into /usr/local/data/M2085_XrayQualification_2015-08-28_10h32m_1440750764//010_CalDelScanAndSaveDacs_4mA25kV//dacParameters35_C4.dat
[10:02:54.264] <TB1> INFO: write dac parameters into /usr/local/data/M2085_XrayQualification_2015-08-28_10h32m_1440750764//010_CalDelScanAndSaveDacs_4mA25kV//dacParameters35_C5.dat
[10:02:54.264] <TB1> INFO: write dac parameters into /usr/local/data/M2085_XrayQualification_2015-08-28_10h32m_1440750764//010_CalDelScanAndSaveDacs_4mA25kV//dacParameters35_C6.dat
[10:02:54.264] <TB1> INFO: write dac parameters into /usr/local/data/M2085_XrayQualification_2015-08-28_10h32m_1440750764//010_CalDelScanAndSaveDacs_4mA25kV//dacParameters35_C7.dat
[10:02:54.264] <TB1> INFO: write dac parameters into /usr/local/data/M2085_XrayQualification_2015-08-28_10h32m_1440750764//010_CalDelScanAndSaveDacs_4mA25kV//dacParameters35_C8.dat
[10:02:54.264] <TB1> INFO: write dac parameters into /usr/local/data/M2085_XrayQualification_2015-08-28_10h32m_1440750764//010_CalDelScanAndSaveDacs_4mA25kV//dacParameters35_C9.dat
[10:02:54.264] <TB1> INFO: write dac parameters into /usr/local/data/M2085_XrayQualification_2015-08-28_10h32m_1440750764//010_CalDelScanAndSaveDacs_4mA25kV//dacParameters35_C10.dat
[10:02:54.264] <TB1> INFO: write dac parameters into /usr/local/data/M2085_XrayQualification_2015-08-28_10h32m_1440750764//010_CalDelScanAndSaveDacs_4mA25kV//dacParameters35_C11.dat
[10:02:54.264] <TB1> INFO: write dac parameters into /usr/local/data/M2085_XrayQualification_2015-08-28_10h32m_1440750764//010_CalDelScanAndSaveDacs_4mA25kV//dacParameters35_C12.dat
[10:02:54.264] <TB1> INFO: write dac parameters into /usr/local/data/M2085_XrayQualification_2015-08-28_10h32m_1440750764//010_CalDelScanAndSaveDacs_4mA25kV//dacParameters35_C13.dat
[10:02:54.265] <TB1> INFO: write dac parameters into /usr/local/data/M2085_XrayQualification_2015-08-28_10h32m_1440750764//010_CalDelScanAndSaveDacs_4mA25kV//dacParameters35_C14.dat
[10:02:54.265] <TB1> INFO: write dac parameters into /usr/local/data/M2085_XrayQualification_2015-08-28_10h32m_1440750764//010_CalDelScanAndSaveDacs_4mA25kV//dacParameters35_C15.dat
[10:02:54.265] <TB1> INFO: enter test to run
[10:02:54.265] <TB1> INFO: test: no parameter change
[10:02:54.459] <TB1> QUIET: Connection to board 83 closed.
[10:02:54.539] <TB1> INFO: pXar: this is the end, my friend

[10:03:27.078] <TB1> INFO: *** Welcome to pxar ***
[10:03:27.078] <TB1> INFO: *** Today: 2015/08/28
[10:03:27.078] <TB1> INFO: readRocDacs: /usr/local/data/M2085_XrayQualification_2015-08-28_10h32m_1440750764//011_HREfficiency_50//dacParameters35_C0.dat .. /usr/local/data/M2085_XrayQualification_2015-08-28_10h32m_1440750764//011_HREfficiency_50//dacParameters35_C15.dat
[10:03:27.080] <TB1> INFO: readTbmDacs: /usr/local/data/M2085_XrayQualification_2015-08-28_10h32m_1440750764//011_HREfficiency_50//tbmParameters_C0a.dat .. /usr/local/data/M2085_XrayQualification_2015-08-28_10h32m_1440750764//011_HREfficiency_50//tbmParameters_C0b.dat
[10:03:27.080] <TB1> INFO: readMaskFile: /usr/local/data/M2085_XrayQualification_2015-08-28_10h32m_1440750764//011_HREfficiency_50//defaultMaskFile.dat
[10:03:27.080] <TB1> INFO: readTrimFile: /usr/local/data/M2085_XrayQualification_2015-08-28_10h32m_1440750764//011_HREfficiency_50//trimParameters35_C0.dat .. /usr/local/data/M2085_XrayQualification_2015-08-28_10h32m_1440750764//011_HREfficiency_50//trimParameters35_C15.dat
[10:03:27.155] <TB1> INFO: clk: 4
[10:03:27.155] <TB1> INFO: ctr: 4
[10:03:27.155] <TB1> INFO: sda: 19
[10:03:27.155] <TB1> INFO: tin: 9
[10:03:27.155] <TB1> INFO: level: 15
[10:03:27.155] <TB1> INFO: triggerdelay: 0
[10:03:27.155] <TB1> QUIET: Instanciating API for pxar v2.5+57~g44d0777
[10:03:27.155] <TB1> INFO: Log level: INFO
[10:03:27.168] <TB1> INFO: Found DTB DTB_WS6UZO
[10:03:27.178] <TB1> QUIET: Connection to board DTB_WS6UZO opened.
[10:03:27.182] <TB1> INFO: DTB startup information
--- DTB info------------------------------------------
Board id: 83
HW version: DTB1.2
FW version: 4.2
SW version: 4.4
USB id: DTB_WS6UZO
MAC address: 40D855118053
Hostname: pixelDTB083
Comment:
------------------------------------------------------
[10:03:27.184] <TB1> INFO: RPC call hashes of host and DTB match: 397073690
[10:03:28.730] <TB1> INFO: DUT info:
[10:03:28.730] <TB1> INFO: The DUT currently contains the following objects:
[10:03:28.730] <TB1> INFO: 2 TBM Cores tbm09c (2 ON)
[10:03:28.730] <TB1> INFO: TBM Core alpha (0): 7 registers set
[10:03:28.730] <TB1> INFO: TBM Core beta (1): 7 registers set
[10:03:28.730] <TB1> INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[10:03:28.730] <TB1> INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[10:03:28.730] <TB1> INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[10:03:28.730] <TB1> INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[10:03:28.731] <TB1> INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[10:03:28.731] <TB1> INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[10:03:28.731] <TB1> INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[10:03:28.731] <TB1> INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[10:03:28.731] <TB1> INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[10:03:28.731] <TB1> INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[10:03:28.731] <TB1> INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[10:03:28.731] <TB1> INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[10:03:28.731] <TB1> INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[10:03:28.731] <TB1> INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[10:03:28.731] <TB1> INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[10:03:28.731] <TB1> INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[10:03:28.731] <TB1> INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[10:03:29.132] <TB1> INFO: enter 'restricted' command line mode
[10:03:29.132] <TB1> INFO: enter test to run
[10:03:29.132] <TB1> INFO: test: HighRate no parameter change
[10:03:29.132] <TB1> INFO: running: highrate
[10:03:29.133] <TB1> INFO: readGainPedestalParameters /usr/local/data/M2085_XrayQualification_2015-08-28_10h32m_1440750764//011_HREfficiency_50//phCalibrationFitErr35_C0.dat .. /usr/local/data/M2085_XrayQualification_2015-08-28_10h32m_1440750764//011_HREfficiency_50//phCalibrationFitErr35_C15.dat
[10:03:29.277] <TB1> INFO: ----------------------------------------------------------------------
[10:03:29.277] <TB1> INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[10:03:29.277] <TB1> INFO: ----------------------------------------------------------------------
[10:03:29.900] <TB1> INFO: Expecting 208000 events.
[10:03:42.309] <TB1> INFO: 208000 events read in total (11879ms).
[10:03:42.315] <TB1> INFO: Test took 13032ms.
[10:03:42.655] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[10:03:43.011] <TB1> INFO: number of dead pixels (per ROC): 0 2 0 1 2 0 0 0 0 0 0 1 0 0 0 0
[10:03:43.011] <TB1> INFO: number of red-efficiency pixels: 133 142 135 264 427 530 540 489 430 463 430 258 285 171 123 132
[10:03:43.011] <TB1> INFO: number of X-ray hits detected: 96237 109232 97752 178095 219584 265481 250224 257538 242912 230404 237666 177416 185845 105282 94899 96148
[10:03:43.011] <TB1> INFO: number of triggers sent (total per ROC): 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000
[10:03:43.011] <TB1> INFO: number of Vcal hits detected: 207866 207757 207857 207636 207432 207293 207405 207471 207526 207496 207533 207675 207709 207821 207874 207867
[10:03:43.011] <TB1> INFO: Vcal hit fiducial efficiency (%): 99.9 99.9 99.9 99.9 99.8 99.7 99.7 99.8 99.8 99.8 99.8 99.9 99.9 99.9 99.9 99.9
[10:03:43.011] <TB1> INFO: Vcal hit overall efficiency (%): 99.9 99.9 99.9 99.8 99.7 99.7 99.7 99.7 99.8 99.8 99.8 99.8 99.9 99.9 99.9 99.9
[10:03:43.011] <TB1> INFO: X-ray hit rate [MHz/cm2]: 28.2 32.0 28.7 52.2 64.4 77.8 73.3 75.5 71.2 67.5 69.7 52.0 54.5 30.9 27.8 28.2
[10:03:43.011] <TB1> INFO: PixTestHighRate::doXPixelAlive() done
[10:03:43.044] <TB1> INFO: PixTest:: pg_setup set to default.
[10:03:43.080] <TB1> INFO: enter test to run
[10:03:43.080] <TB1> INFO: test: no parameter change
[10:03:43.242] <TB1> QUIET: Connection to board 83 closed.
[10:03:43.321] <TB1> INFO: pXar: this is the end, my friend

[10:04:18.544] <TB1> INFO: *** Welcome to pxar ***
[10:04:18.544] <TB1> INFO: *** Today: 2015/08/28
[10:04:18.544] <TB1> INFO: readRocDacs: /usr/local/data/M2085_XrayQualification_2015-08-28_10h32m_1440750764//012_HREfficiency_100//dacParameters35_C0.dat .. /usr/local/data/M2085_XrayQualification_2015-08-28_10h32m_1440750764//012_HREfficiency_100//dacParameters35_C15.dat
[10:04:18.545] <TB1> INFO: readTbmDacs: /usr/local/data/M2085_XrayQualification_2015-08-28_10h32m_1440750764//012_HREfficiency_100//tbmParameters_C0a.dat .. /usr/local/data/M2085_XrayQualification_2015-08-28_10h32m_1440750764//012_HREfficiency_100//tbmParameters_C0b.dat
[10:04:18.545] <TB1> INFO: readMaskFile: /usr/local/data/M2085_XrayQualification_2015-08-28_10h32m_1440750764//012_HREfficiency_100//defaultMaskFile.dat
[10:04:18.545] <TB1> INFO: readTrimFile: /usr/local/data/M2085_XrayQualification_2015-08-28_10h32m_1440750764//012_HREfficiency_100//trimParameters35_C0.dat .. /usr/local/data/M2085_XrayQualification_2015-08-28_10h32m_1440750764//012_HREfficiency_100//trimParameters35_C15.dat
[10:04:18.615] <TB1> INFO: clk: 4
[10:04:18.615] <TB1> INFO: ctr: 4
[10:04:18.615] <TB1> INFO: sda: 19
[10:04:18.615] <TB1> INFO: tin: 9
[10:04:18.615] <TB1> INFO: level: 15
[10:04:18.615] <TB1> INFO: triggerdelay: 0
[10:04:18.615] <TB1> QUIET: Instanciating API for pxar v2.5+57~g44d0777
[10:04:18.615] <TB1> INFO: Log level: INFO
[10:04:18.629] <TB1> INFO: Found DTB DTB_WS6UZO
[10:04:18.639] <TB1> QUIET: Connection to board DTB_WS6UZO opened.
[10:04:18.642] <TB1> INFO: DTB startup information
--- DTB info------------------------------------------
Board id: 83
HW version: DTB1.2
FW version: 4.2
SW version: 4.4
USB id: DTB_WS6UZO
MAC address: 40D855118053
Hostname: pixelDTB083
Comment:
------------------------------------------------------
[10:04:18.644] <TB1> INFO: RPC call hashes of host and DTB match: 397073690
[10:04:20.175] <TB1> INFO: DUT info:
[10:04:20.175] <TB1> INFO: The DUT currently contains the following objects:
[10:04:20.175] <TB1> INFO: 2 TBM Cores tbm09c (2 ON)
[10:04:20.175] <TB1> INFO: TBM Core alpha (0): 7 registers set
[10:04:20.175] <TB1> INFO: TBM Core beta (1): 7 registers set
[10:04:20.175] <TB1> INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[10:04:20.176] <TB1> INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[10:04:20.176] <TB1> INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[10:04:20.176] <TB1> INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[10:04:20.176] <TB1> INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[10:04:20.176] <TB1> INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[10:04:20.176] <TB1> INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[10:04:20.176] <TB1> INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[10:04:20.176] <TB1> INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[10:04:20.176] <TB1> INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[10:04:20.176] <TB1> INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[10:04:20.176] <TB1> INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[10:04:20.176] <TB1> INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[10:04:20.176] <TB1> INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[10:04:20.176] <TB1> INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[10:04:20.176] <TB1> INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[10:04:20.176] <TB1> INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[10:04:20.578] <TB1> INFO: enter 'restricted' command line mode
[10:04:20.578] <TB1> INFO: enter test to run
[10:04:20.578] <TB1> INFO: test: HighRate no parameter change
[10:04:20.578] <TB1> INFO: running: highrate
[10:04:20.579] <TB1> INFO: readGainPedestalParameters /usr/local/data/M2085_XrayQualification_2015-08-28_10h32m_1440750764//012_HREfficiency_100//phCalibrationFitErr35_C0.dat .. /usr/local/data/M2085_XrayQualification_2015-08-28_10h32m_1440750764//012_HREfficiency_100//phCalibrationFitErr35_C15.dat
[10:04:20.730] <TB1> INFO: ----------------------------------------------------------------------
[10:04:20.730] <TB1> INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[10:04:20.730] <TB1> INFO: ----------------------------------------------------------------------
[10:04:21.345] <TB1> INFO: Expecting 208000 events.
[10:04:38.999] <TB1> INFO: 208000 events read in total (17124ms).
[10:04:39.012] <TB1> INFO: Test took 18276ms.
[10:04:39.647] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[10:04:40.087] <TB1> INFO: number of dead pixels (per ROC): 0 2 0 1 2 0 0 0 0 0 0 1 0 0 0 0
[10:04:40.087] <TB1> INFO: number of red-efficiency pixels: 351 418 506 1011 1595 2031 1904 1940 1809 1576 1653 969 1028 547 365 423
[10:04:40.087] <TB1> INFO: number of X-ray hits detected: 196816 221737 201042 349901 437728 522591 498084 506187 480046 454419 466469 354993 367515 215581 195254 194800
[10:04:40.087] <TB1> 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:40.087] <TB1> INFO: number of Vcal hits detected: 207634 207450 207366 206677 205410 204345 204777 205121 205293 205650 205534 206732 206779 207296 207616 207552
[10:04:40.087] <TB1> INFO: Vcal hit fiducial efficiency (%): 99.8 99.8 99.7 99.5 99.0 98.4 98.7 98.8 98.8 99.0 99.0 99.5 99.5 99.7 99.8 99.8
[10:04:40.087] <TB1> INFO: Vcal hit overall efficiency (%): 99.8 99.7 99.7 99.4 98.8 98.2 98.5 98.6 98.7 98.9 98.8 99.4 99.4 99.7 99.8 99.8
[10:04:40.087] <TB1> INFO: X-ray hit rate [MHz/cm2]: 57.7 65.0 58.9 102.6 128.3 153.2 146.0 148.4 140.7 133.2 136.7 104.1 107.7 63.2 57.2 57.1
[10:04:40.087] <TB1> INFO: PixTestHighRate::doXPixelAlive() done
[10:04:40.117] <TB1> INFO: PixTest:: pg_setup set to default.
[10:04:40.153] <TB1> INFO: enter test to run
[10:04:40.153] <TB1> INFO: test: no parameter change
[10:04:40.346] <TB1> QUIET: Connection to board 83 closed.
[10:04:40.431] <TB1> INFO: pXar: this is the end, my friend

[10:05:14.741] <TB1> INFO: *** Welcome to pxar ***
[10:05:14.741] <TB1> INFO: *** Today: 2015/08/28
[10:05:14.741] <TB1> INFO: readRocDacs: /usr/local/data/M2085_XrayQualification_2015-08-28_10h32m_1440750764//013_HREfficiency_150//dacParameters35_C0.dat .. /usr/local/data/M2085_XrayQualification_2015-08-28_10h32m_1440750764//013_HREfficiency_150//dacParameters35_C15.dat
[10:05:14.742] <TB1> INFO: readTbmDacs: /usr/local/data/M2085_XrayQualification_2015-08-28_10h32m_1440750764//013_HREfficiency_150//tbmParameters_C0a.dat .. /usr/local/data/M2085_XrayQualification_2015-08-28_10h32m_1440750764//013_HREfficiency_150//tbmParameters_C0b.dat
[10:05:14.742] <TB1> INFO: readMaskFile: /usr/local/data/M2085_XrayQualification_2015-08-28_10h32m_1440750764//013_HREfficiency_150//defaultMaskFile.dat
[10:05:14.742] <TB1> INFO: readTrimFile: /usr/local/data/M2085_XrayQualification_2015-08-28_10h32m_1440750764//013_HREfficiency_150//trimParameters35_C0.dat .. /usr/local/data/M2085_XrayQualification_2015-08-28_10h32m_1440750764//013_HREfficiency_150//trimParameters35_C15.dat
[10:05:14.810] <TB1> INFO: clk: 4
[10:05:14.810] <TB1> INFO: ctr: 4
[10:05:14.810] <TB1> INFO: sda: 19
[10:05:14.810] <TB1> INFO: tin: 9
[10:05:14.810] <TB1> INFO: level: 15
[10:05:14.810] <TB1> INFO: triggerdelay: 0
[10:05:14.810] <TB1> QUIET: Instanciating API for pxar v2.5+57~g44d0777
[10:05:14.810] <TB1> INFO: Log level: INFO
[10:05:14.823] <TB1> INFO: Found DTB DTB_WS6UZO
[10:05:14.833] <TB1> QUIET: Connection to board DTB_WS6UZO opened.
[10:05:14.837] <TB1> INFO: DTB startup information
--- DTB info------------------------------------------
Board id: 83
HW version: DTB1.2
FW version: 4.2
SW version: 4.4
USB id: DTB_WS6UZO
MAC address: 40D855118053
Hostname: pixelDTB083
Comment:
------------------------------------------------------
[10:05:14.839] <TB1> INFO: RPC call hashes of host and DTB match: 397073690
[10:05:16.383] <TB1> INFO: DUT info:
[10:05:16.383] <TB1> INFO: The DUT currently contains the following objects:
[10:05:16.383] <TB1> INFO: 2 TBM Cores tbm09c (2 ON)
[10:05:16.383] <TB1> INFO: TBM Core alpha (0): 7 registers set
[10:05:16.383] <TB1> INFO: TBM Core beta (1): 7 registers set
[10:05:16.383] <TB1> INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[10:05:16.383] <TB1> INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[10:05:16.383] <TB1> INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[10:05:16.383] <TB1> INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[10:05:16.383] <TB1> INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[10:05:16.383] <TB1> INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[10:05:16.383] <TB1> INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[10:05:16.383] <TB1> INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[10:05:16.383] <TB1> INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[10:05:16.383] <TB1> INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[10:05:16.383] <TB1> INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[10:05:16.383] <TB1> INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[10:05:16.383] <TB1> INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[10:05:16.383] <TB1> INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[10:05:16.383] <TB1> INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[10:05:16.383] <TB1> INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[10:05:16.383] <TB1> INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[10:05:16.784] <TB1> INFO: enter 'restricted' command line mode
[10:05:16.785] <TB1> INFO: enter test to run
[10:05:16.785] <TB1> INFO: test: HighRate no parameter change
[10:05:16.785] <TB1> INFO: running: highrate
[10:05:16.785] <TB1> INFO: readGainPedestalParameters /usr/local/data/M2085_XrayQualification_2015-08-28_10h32m_1440750764//013_HREfficiency_150//phCalibrationFitErr35_C0.dat .. /usr/local/data/M2085_XrayQualification_2015-08-28_10h32m_1440750764//013_HREfficiency_150//phCalibrationFitErr35_C15.dat
[10:05:16.944] <TB1> INFO: ----------------------------------------------------------------------
[10:05:16.944] <TB1> INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[10:05:16.944] <TB1> INFO: ----------------------------------------------------------------------
[10:05:17.552] <TB1> INFO: Expecting 208000 events.
[10:05:41.439] <TB1> INFO: 208000 events read in total (23357ms).
[10:05:41.456] <TB1> INFO: Test took 24504ms.
[10:05:42.378] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[10:05:42.907] <TB1> INFO: number of dead pixels (per ROC): 0 2 0 1 2 0 0 0 0 0 0 1 0 0 0 0
[10:05:42.907] <TB1> INFO: number of red-efficiency pixels: 701 916 854 2001 2813 3424 3323 3380 3255 2933 3022 1972 1980 997 884 857
[10:05:42.907] <TB1> INFO: number of X-ray hits detected: 283924 321781 292255 500487 629388 745476 715385 725726 685521 647786 660775 509156 528418 315181 286768 282343
[10:05:42.907] <TB1> 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:42.907] <TB1> INFO: number of Vcal hits detected: 207187 206835 206550 204542 201148 197433 198369 199407 200159 201115 200912 204523 204747 206201 206968 206975
[10:05:42.907] <TB1> INFO: Vcal hit fiducial efficiency (%): 99.7 99.5 99.4 98.6 97.2 95.5 96.0 96.3 96.6 97.1 97.0 98.6 98.6 99.3 99.5 99.5
[10:05:42.907] <TB1> INFO: Vcal hit overall efficiency (%): 99.6 99.4 99.3 98.3 96.7 94.9 95.4 95.9 96.2 96.7 96.6 98.3 98.4 99.1 99.5 99.5
[10:05:42.907] <TB1> INFO: X-ray hit rate [MHz/cm2]: 83.2 94.3 85.7 146.7 184.5 218.5 209.7 212.7 200.9 189.9 193.7 149.2 154.9 92.4 84.1 82.8
[10:05:42.907] <TB1> INFO: PixTestHighRate::doXPixelAlive() done
[10:05:42.942] <TB1> INFO: PixTest:: pg_setup set to default.
[10:05:42.988] <TB1> INFO: enter test to run
[10:05:42.988] <TB1> INFO: test: no parameter change
[10:05:43.211] <TB1> QUIET: Connection to board 83 closed.
[10:05:43.291] <TB1> INFO: pXar: this is the end, my friend

[10:06:15.140] <TB1> INFO: *** Welcome to pxar ***
[10:06:15.140] <TB1> INFO: *** Today: 2015/08/28
[10:06:15.140] <TB1> INFO: readRocDacs: /usr/local/data/M2085_XrayQualification_2015-08-28_10h32m_1440750764//014_HREfficiency_200//dacParameters35_C0.dat .. /usr/local/data/M2085_XrayQualification_2015-08-28_10h32m_1440750764//014_HREfficiency_200//dacParameters35_C15.dat
[10:06:15.141] <TB1> INFO: readTbmDacs: /usr/local/data/M2085_XrayQualification_2015-08-28_10h32m_1440750764//014_HREfficiency_200//tbmParameters_C0a.dat .. /usr/local/data/M2085_XrayQualification_2015-08-28_10h32m_1440750764//014_HREfficiency_200//tbmParameters_C0b.dat
[10:06:15.141] <TB1> INFO: readMaskFile: /usr/local/data/M2085_XrayQualification_2015-08-28_10h32m_1440750764//014_HREfficiency_200//defaultMaskFile.dat
[10:06:15.141] <TB1> INFO: readTrimFile: /usr/local/data/M2085_XrayQualification_2015-08-28_10h32m_1440750764//014_HREfficiency_200//trimParameters35_C0.dat .. /usr/local/data/M2085_XrayQualification_2015-08-28_10h32m_1440750764//014_HREfficiency_200//trimParameters35_C15.dat
[10:06:15.219] <TB1> INFO: clk: 4
[10:06:15.219] <TB1> INFO: ctr: 4
[10:06:15.219] <TB1> INFO: sda: 19
[10:06:15.219] <TB1> INFO: tin: 9
[10:06:15.219] <TB1> INFO: level: 15
[10:06:15.219] <TB1> INFO: triggerdelay: 0
[10:06:15.219] <TB1> QUIET: Instanciating API for pxar v2.5+57~g44d0777
[10:06:15.219] <TB1> INFO: Log level: INFO
[10:06:15.232] <TB1> INFO: Found DTB DTB_WS6UZO
[10:06:15.242] <TB1> QUIET: Connection to board DTB_WS6UZO opened.
[10:06:15.245] <TB1> INFO: DTB startup information
--- DTB info------------------------------------------
Board id: 83
HW version: DTB1.2
FW version: 4.2
SW version: 4.4
USB id: DTB_WS6UZO
MAC address: 40D855118053
Hostname: pixelDTB083
Comment:
------------------------------------------------------
[10:06:15.248] <TB1> INFO: RPC call hashes of host and DTB match: 397073690
[10:06:16.783] <TB1> INFO: DUT info:
[10:06:16.783] <TB1> INFO: The DUT currently contains the following objects:
[10:06:16.783] <TB1> INFO: 2 TBM Cores tbm09c (2 ON)
[10:06:16.783] <TB1> INFO: TBM Core alpha (0): 7 registers set
[10:06:16.783] <TB1> INFO: TBM Core beta (1): 7 registers set
[10:06:16.783] <TB1> INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[10:06:16.783] <TB1> INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[10:06:16.783] <TB1> INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[10:06:16.783] <TB1> INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[10:06:16.783] <TB1> INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[10:06:16.783] <TB1> INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[10:06:16.783] <TB1> INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[10:06:16.783] <TB1> INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[10:06:16.783] <TB1> INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[10:06:16.783] <TB1> INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[10:06:16.783] <TB1> INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[10:06:16.783] <TB1> INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[10:06:16.784] <TB1> INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[10:06:16.784] <TB1> INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[10:06:16.784] <TB1> INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[10:06:16.784] <TB1> INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[10:06:16.784] <TB1> INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[10:06:17.185] <TB1> INFO: enter 'restricted' command line mode
[10:06:17.185] <TB1> INFO: enter test to run
[10:06:17.185] <TB1> INFO: test: HighRate no parameter change
[10:06:17.185] <TB1> INFO: running: highrate
[10:06:17.185] <TB1> INFO: readGainPedestalParameters /usr/local/data/M2085_XrayQualification_2015-08-28_10h32m_1440750764//014_HREfficiency_200//phCalibrationFitErr35_C0.dat .. /usr/local/data/M2085_XrayQualification_2015-08-28_10h32m_1440750764//014_HREfficiency_200//phCalibrationFitErr35_C15.dat
[10:06:17.423] <TB1> INFO: ----------------------------------------------------------------------
[10:06:17.423] <TB1> INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[10:06:17.423] <TB1> INFO: ----------------------------------------------------------------------
[10:06:18.065] <TB1> INFO: Expecting 208000 events.
[10:06:48.422] <TB1> INFO: 199950 events read in total (29827ms).
[10:06:49.881] <TB1> INFO: 208000 events read in total (31286ms).
[10:06:49.901] <TB1> INFO: Test took 32471ms.
[10:06:51.062] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[10:06:51.704] <TB1> INFO: number of dead pixels (per ROC): 0 2 0 1 2 0 0 0 0 0 0 1 0 0 0 0
[10:06:51.704] <TB1> INFO: number of red-efficiency pixels: 1302 1638 1403 3079 3790 4089 4027 4099 4038 3868 3921 3052 3208 1539 1597 1581
[10:06:51.704] <TB1> INFO: number of X-ray hits detected: 386264 437143 398150 667587 846619 1002191 959633 972434 916054 865251 881211 686198 709893 427880 389253 384351
[10:06:51.704] <TB1> INFO: number of triggers sent (total per ROC): 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000
[10:06:51.704] <TB1> INFO: number of Vcal hits detected: 206256 205635 204803 200092 192187 183520 185429 187212 189351 191916 191662 199966 200126 203837 205856 205827
[10:06:51.704] <TB1> INFO: Vcal hit fiducial efficiency (%): 99.3 99.0 98.7 96.7 93.3 89.3 90.4 90.8 91.8 93.1 93.0 96.7 96.6 98.4 99.0 99.1
[10:06:51.704] <TB1> INFO: Vcal hit overall efficiency (%): 99.2 98.9 98.5 96.2 92.4 88.2 89.1 90.0 91.0 92.3 92.1 96.1 96.2 98.0 99.0 99.0
[10:06:51.704] <TB1> INFO: X-ray hit rate [MHz/cm2]: 113.2 128.1 116.7 195.7 248.2 293.7 281.3 285.0 268.5 253.6 258.3 201.1 208.1 125.4 114.1 112.7
[10:06:51.704] <TB1> INFO: PixTestHighRate::doXPixelAlive() done
[10:06:51.734] <TB1> INFO: PixTest:: pg_setup set to default.
[10:06:51.770] <TB1> INFO: enter test to run
[10:06:51.770] <TB1> INFO: test: no parameter change
[10:06:51.969] <TB1> QUIET: Connection to board 83 closed.
[10:06:52.048] <TB1> INFO: pXar: this is the end, my friend

[10:07:19.569] <TB1> INFO: *** Welcome to pxar ***
[10:07:19.569] <TB1> INFO: *** Today: 2015/08/28
[10:07:19.569] <TB1> INFO: readRocDacs: /usr/local/data/M2085_XrayQualification_2015-08-28_10h32m_1440750764//015_HREfficiency_250//dacParameters35_C0.dat .. /usr/local/data/M2085_XrayQualification_2015-08-28_10h32m_1440750764//015_HREfficiency_250//dacParameters35_C15.dat
[10:07:19.570] <TB1> INFO: readTbmDacs: /usr/local/data/M2085_XrayQualification_2015-08-28_10h32m_1440750764//015_HREfficiency_250//tbmParameters_C0a.dat .. /usr/local/data/M2085_XrayQualification_2015-08-28_10h32m_1440750764//015_HREfficiency_250//tbmParameters_C0b.dat
[10:07:19.570] <TB1> INFO: readMaskFile: /usr/local/data/M2085_XrayQualification_2015-08-28_10h32m_1440750764//015_HREfficiency_250//defaultMaskFile.dat
[10:07:19.570] <TB1> INFO: readTrimFile: /usr/local/data/M2085_XrayQualification_2015-08-28_10h32m_1440750764//015_HREfficiency_250//trimParameters35_C0.dat .. /usr/local/data/M2085_XrayQualification_2015-08-28_10h32m_1440750764//015_HREfficiency_250//trimParameters35_C15.dat
[10:07:19.642] <TB1> INFO: clk: 4
[10:07:19.642] <TB1> INFO: ctr: 4
[10:07:19.642] <TB1> INFO: sda: 19
[10:07:19.642] <TB1> INFO: tin: 9
[10:07:19.642] <TB1> INFO: level: 15
[10:07:19.642] <TB1> INFO: triggerdelay: 0
[10:07:19.642] <TB1> QUIET: Instanciating API for pxar v2.5+57~g44d0777
[10:07:19.642] <TB1> INFO: Log level: INFO
[10:07:19.655] <TB1> INFO: Found DTB DTB_WS6UZO
[10:07:19.665] <TB1> QUIET: Connection to board DTB_WS6UZO opened.
[10:07:19.668] <TB1> INFO: DTB startup information
--- DTB info------------------------------------------
Board id: 83
HW version: DTB1.2
FW version: 4.2
SW version: 4.4
USB id: DTB_WS6UZO
MAC address: 40D855118053
Hostname: pixelDTB083
Comment:
------------------------------------------------------
[10:07:19.671] <TB1> INFO: RPC call hashes of host and DTB match: 397073690
[10:07:21.223] <TB1> INFO: DUT info:
[10:07:21.223] <TB1> INFO: The DUT currently contains the following objects:
[10:07:21.224] <TB1> INFO: 2 TBM Cores tbm09c (2 ON)
[10:07:21.224] <TB1> INFO: TBM Core alpha (0): 7 registers set
[10:07:21.224] <TB1> INFO: TBM Core beta (1): 7 registers set
[10:07:21.224] <TB1> INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[10:07:21.224] <TB1> INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[10:07:21.224] <TB1> INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[10:07:21.224] <TB1> INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[10:07:21.224] <TB1> INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[10:07:21.224] <TB1> INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[10:07:21.224] <TB1> INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[10:07:21.224] <TB1> INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[10:07:21.224] <TB1> INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[10:07:21.224] <TB1> INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[10:07:21.224] <TB1> INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[10:07:21.224] <TB1> INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[10:07:21.224] <TB1> INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[10:07:21.224] <TB1> INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[10:07:21.224] <TB1> INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[10:07:21.224] <TB1> INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[10:07:21.224] <TB1> INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[10:07:21.626] <TB1> INFO: enter 'restricted' command line mode
[10:07:21.626] <TB1> INFO: enter test to run
[10:07:21.626] <TB1> INFO: test: HighRate no parameter change
[10:07:21.626] <TB1> INFO: running: highrate
[10:07:21.626] <TB1> INFO: readGainPedestalParameters /usr/local/data/M2085_XrayQualification_2015-08-28_10h32m_1440750764//015_HREfficiency_250//phCalibrationFitErr35_C0.dat .. /usr/local/data/M2085_XrayQualification_2015-08-28_10h32m_1440750764//015_HREfficiency_250//phCalibrationFitErr35_C15.dat
[10:07:21.767] <TB1> INFO: ----------------------------------------------------------------------
[10:07:21.767] <TB1> INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[10:07:21.767] <TB1> INFO: ----------------------------------------------------------------------
[10:07:22.369] <TB1> INFO: Expecting 208000 events.
[10:07:52.959] <TB1> INFO: 196350 events read in total (30060ms).
[10:07:54.794] <TB1> INFO: 208000 events read in total (31895ms).
[10:07:54.812] <TB1> INFO: Test took 33039ms.
[10:07:56.035] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[10:07:56.661] <TB1> INFO: number of dead pixels (per ROC): 0 2 0 1 2 0 0 0 0 0 0 1 0 0 0 0
[10:07:56.661] <TB1> INFO: number of red-efficiency pixels: 1377 1768 1476 3230 3877 4103 4078 4098 4077 3956 3975 3182 3311 1601 1762 1692
[10:07:56.661] <TB1> INFO: number of X-ray hits detected: 399162 451289 414406 686326 867438 1023721 989135 999986 947015 892999 902335 703557 726963 445921 405138 396451
[10:07:56.661] <TB1> 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:56.661] <TB1> INFO: number of Vcal hits detected: 206151 205366 204689 199491 190882 182047 183488 185365 187617 190693 190431 199335 199422 203660 205545 205574
[10:07:56.661] <TB1> INFO: Vcal hit fiducial efficiency (%): 99.2 98.9 98.7 96.4 92.8 88.6 89.5 90.0 91.1 92.6 92.5 96.4 96.3 98.3 98.9 98.9
[10:07:56.661] <TB1> INFO: Vcal hit overall efficiency (%): 99.1 98.7 98.4 95.9 91.8 87.5 88.2 89.1 90.2 91.7 91.6 95.8 95.9 97.9 98.8 98.8
[10:07:56.661] <TB1> INFO: X-ray hit rate [MHz/cm2]: 117.0 132.3 121.5 201.2 254.3 300.1 289.9 293.1 277.6 261.7 264.5 206.2 213.1 130.7 118.7 116.2
[10:07:56.661] <TB1> INFO: PixTestHighRate::doXPixelAlive() done
[10:07:56.685] <TB1> INFO: PixTest:: pg_setup set to default.
[10:07:56.726] <TB1> INFO: enter test to run
[10:07:56.726] <TB1> INFO: test: no parameter change
[10:07:56.905] <TB1> QUIET: Connection to board 83 closed.
[10:07:56.984] <TB1> INFO: pXar: this is the end, my friend