[12:28:56.713] <TB1> INFO: *** Welcome to pxar ***
[12:28:56.713] <TB1> INFO: *** Today: 2015/08/28
[12:28:56.713] <TB1> INFO: readRocDacs: /usr/local/data/M2046_XrayQualification_2015-08-28_14h28m_1440764917//000_PixelAlive_p17//dacParameters35_C0.dat .. /usr/local/data/M2046_XrayQualification_2015-08-28_14h28m_1440764917//000_PixelAlive_p17//dacParameters35_C15.dat
[12:28:56.714] <TB1> INFO: readTbmDacs: /usr/local/data/M2046_XrayQualification_2015-08-28_14h28m_1440764917//000_PixelAlive_p17//tbmParameters_C0a.dat .. /usr/local/data/M2046_XrayQualification_2015-08-28_14h28m_1440764917//000_PixelAlive_p17//tbmParameters_C0b.dat
[12:28:56.714] <TB1> INFO: readMaskFile: /usr/local/data/M2046_XrayQualification_2015-08-28_14h28m_1440764917//000_PixelAlive_p17//defaultMaskFile.dat
[12:28:56.714] <TB1> INFO: readTrimFile: /usr/local/data/M2046_XrayQualification_2015-08-28_14h28m_1440764917//000_PixelAlive_p17//trimParameters35_C0.dat .. /usr/local/data/M2046_XrayQualification_2015-08-28_14h28m_1440764917//000_PixelAlive_p17//trimParameters35_C15.dat
[12:28:56.781] <TB1> INFO: clk: 4
[12:28:56.781] <TB1> INFO: ctr: 4
[12:28:56.781] <TB1> INFO: sda: 19
[12:28:56.781] <TB1> INFO: tin: 9
[12:28:56.781] <TB1> INFO: level: 15
[12:28:56.781] <TB1> INFO: triggerdelay: 0
[12:28:56.781] <TB1> QUIET: Instanciating API for pxar v2.5+57~g44d0777
[12:28:56.781] <TB1> INFO: Log level: INFO
[12:28:56.794] <TB1> INFO: Found DTB DTB_WS6UZO
[12:28:56.803] <TB1> QUIET: Connection to board DTB_WS6UZO opened.
[12:28:56.807] <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:
------------------------------------------------------
[12:28:56.809] <TB1> INFO: RPC call hashes of host and DTB match: 397073690
[12:28:58.323] <TB1> INFO: DUT info:
[12:28:58.323] <TB1> INFO: The DUT currently contains the following objects:
[12:28:58.323] <TB1> INFO: 2 TBM Cores tbm09c (2 ON)
[12:28:58.323] <TB1> INFO: TBM Core alpha (0): 7 registers set
[12:28:58.323] <TB1> INFO: TBM Core beta (1): 7 registers set
[12:28:58.323] <TB1> INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[12:28:58.323] <TB1> INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[12:28:58.323] <TB1> INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[12:28:58.323] <TB1> INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[12:28:58.323] <TB1> INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[12:28:58.323] <TB1> INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[12:28:58.323] <TB1> INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[12:28:58.323] <TB1> INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[12:28:58.323] <TB1> INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[12:28:58.323] <TB1> INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[12:28:58.323] <TB1> INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[12:28:58.323] <TB1> INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[12:28:58.323] <TB1> INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[12:28:58.323] <TB1> INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[12:28:58.323] <TB1> INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[12:28:58.323] <TB1> INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[12:28:58.323] <TB1> INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[12:28:58.724] <TB1> INFO: enter 'restricted' command line mode
[12:28:58.724] <TB1> INFO: enter test to run
[12:28:58.724] <TB1> INFO: test: PixelAlive no parameter change
[12:28:58.724] <TB1> INFO: running: pixelalive
[12:28:58.728] <TB1> INFO: ######################################################################
[12:28:58.728] <TB1> INFO: PixTestAlive::doTest()
[12:28:58.728] <TB1> INFO: ######################################################################
[12:28:58.729] <TB1> INFO: ----------------------------------------------------------------------
[12:28:58.729] <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)
[12:28:58.729] <TB1> INFO: ----------------------------------------------------------------------
[12:28:59.030] <TB1> INFO: Expecting 41600 events.
[12:29:03.373] <TB1> INFO: 41600 events read in total (3626ms).
[12:29:03.454] <TB1> INFO: Test took 4723ms.
[12:29:03.460] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[12:29:03.734] <TB1> INFO: PixTestAlive::aliveTest() done
[12:29:03.735] <TB1> INFO: number of dead pixels (per ROC): 2 0 0 0 2 1 0 1 0 0 0 0 0 0 0 0
[12:29:03.736] <TB1> INFO: ----------------------------------------------------------------------
[12:29:03.736] <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)
[12:29:03.736] <TB1> INFO: ----------------------------------------------------------------------
[12:29:04.036] <TB1> INFO: Expecting 41600 events.
[12:29:07.206] <TB1> INFO: 41600 events read in total (2453ms).
[12:29:07.206] <TB1> INFO: Test took 3470ms.
[12:29:07.207] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[12:29:07.207] <TB1> INFO: mask vs. old pixelAlive PixelAlive_C0_V0 .. PixelAlive_C15_V0
[12:29:07.519] <TB1> INFO: PixTestAlive::maskTest() done
[12:29:07.519] <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
[12:29:07.521] <TB1> INFO: ----------------------------------------------------------------------
[12:29:07.521] <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)
[12:29:07.521] <TB1> INFO: ----------------------------------------------------------------------
[12:29:07.826] <TB1> INFO: Expecting 41600 events.
[12:29:12.390] <TB1> INFO: 41600 events read in total (3847ms).
[12:29:12.391] <TB1> INFO: Test took 4869ms.
[12:29:12.398] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[12:29:12.692] <TB1> INFO: PixTestAlive::addressDecodingTest() done
[12:29:12.692] <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
[12:29:12.692] <TB1> INFO: PixTestAlive::doTest() done, duration: 13 seconds
[12:29:12.748] <TB1> INFO: enter test to run
[12:29:12.748] <TB1> INFO: test: no parameter change
[12:29:12.858] <TB1> QUIET: Connection to board 83 closed.
[12:29:12.938] <TB1> INFO: pXar: this is the end, my friend

[12:33:44.677] <TB1> INFO: *** Welcome to pxar ***
[12:33:44.677] <TB1> INFO: *** Today: 2015/08/28
[12:33:44.677] <TB1> INFO: readRocDacs: /usr/local/data/M2046_XrayQualification_2015-08-28_14h28m_1440764917//002_RetrimHotPixels_150//dacParameters35_C0.dat .. /usr/local/data/M2046_XrayQualification_2015-08-28_14h28m_1440764917//002_RetrimHotPixels_150//dacParameters35_C15.dat
[12:33:44.677] <TB1> INFO: readTbmDacs: /usr/local/data/M2046_XrayQualification_2015-08-28_14h28m_1440764917//002_RetrimHotPixels_150//tbmParameters_C0a.dat .. /usr/local/data/M2046_XrayQualification_2015-08-28_14h28m_1440764917//002_RetrimHotPixels_150//tbmParameters_C0b.dat
[12:33:44.677] <TB1> INFO: readMaskFile: /usr/local/data/M2046_XrayQualification_2015-08-28_14h28m_1440764917//002_RetrimHotPixels_150//defaultMaskFile.dat
[12:33:44.677] <TB1> INFO: readTrimFile: /usr/local/data/M2046_XrayQualification_2015-08-28_14h28m_1440764917//002_RetrimHotPixels_150//trimParameters35_C0.dat .. /usr/local/data/M2046_XrayQualification_2015-08-28_14h28m_1440764917//002_RetrimHotPixels_150//trimParameters35_C15.dat
[12:33:44.745] <TB1> INFO: clk: 4
[12:33:44.745] <TB1> INFO: ctr: 4
[12:33:44.745] <TB1> INFO: sda: 19
[12:33:44.745] <TB1> INFO: tin: 9
[12:33:44.745] <TB1> INFO: level: 15
[12:33:44.745] <TB1> INFO: triggerdelay: 0
[12:33:44.745] <TB1> QUIET: Instanciating API for pxar v2.5+57~g44d0777
[12:33:44.745] <TB1> INFO: Log level: INFO
[12:33:44.759] <TB1> INFO: Found DTB DTB_WS6UZO
[12:33:44.768] <TB1> QUIET: Connection to board DTB_WS6UZO opened.
[12:33:44.772] <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:
------------------------------------------------------
[12:33:44.774] <TB1> INFO: RPC call hashes of host and DTB match: 397073690
[12:33:46.287] <TB1> INFO: DUT info:
[12:33:46.287] <TB1> INFO: The DUT currently contains the following objects:
[12:33:46.287] <TB1> INFO: 2 TBM Cores tbm09c (2 ON)
[12:33:46.287] <TB1> INFO: TBM Core alpha (0): 7 registers set
[12:33:46.287] <TB1> INFO: TBM Core beta (1): 7 registers set
[12:33:46.287] <TB1> INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[12:33:46.287] <TB1> INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[12:33:46.288] <TB1> INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[12:33:46.288] <TB1> INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[12:33:46.288] <TB1> INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[12:33:46.288] <TB1> INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[12:33:46.288] <TB1> INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[12:33:46.288] <TB1> INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[12:33:46.288] <TB1> INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[12:33:46.288] <TB1> INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[12:33:46.288] <TB1> INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[12:33:46.288] <TB1> INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[12:33:46.288] <TB1> INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[12:33:46.288] <TB1> INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[12:33:46.288] <TB1> INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[12:33:46.288] <TB1> INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[12:33:46.288] <TB1> INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[12:33:46.688] <TB1> INFO: enter 'restricted' command line mode
[12:33:46.688] <TB1> INFO: enter test to run
[12:33:46.689] <TB1> INFO: test: delay setting parameters: ->5<-
[12:33:46.689] <TB1> INFO: delay test by 5 seconds...
[12:33:51.689] <TB1> INFO: enter test to run
[12:33:51.689] <TB1> INFO: test: highrate no parameter change
[12:33:51.689] <TB1> INFO: running: highrate
[12:33:51.689] <TB1> INFO: readGainPedestalParameters /usr/local/data/M2046_XrayQualification_2015-08-28_14h28m_1440764917//002_RetrimHotPixels_150//phCalibrationFitErr35_C0.dat .. /usr/local/data/M2046_XrayQualification_2015-08-28_14h28m_1440764917//002_RetrimHotPixels_150//phCalibrationFitErr35_C15.dat
[12:33:51.819] <TB1> INFO: ----------------------------------------------------------------------
[12:33:51.819] <TB1> INFO: PixTest::trimHotPixels() running for 1 seconds with 100 kHz trigger rate
[12:33:51.819] <TB1> INFO: ----------------------------------------------------------------------
[12:33:51.819] <TB1> INFO: THR = 150, corresponding to ~ 400 MHz/cm2
[12:33:51.819] <TB1> INFO: edge/corner pixel THR is adjusted
[12:33:51.819] <TB1> INFO: PixTestHighRate::trimHotPixels: step 0...
[12:33:52.779] <TB1> INFO: Collecting data for 1 seconds...
[12:33:53.780] <TB1> INFO: Done with hot pixel readout
[12:33:59.705] <TB1> INFO: PixTest:: pg_setup set to default.
[12:33:59.706] <TB1> INFO: 6 hot pixels found in step 0
[12:34:00.672] <TB1> INFO: Collecting data for 1 seconds...
[12:34:01.674] <TB1> INFO: Done with hot pixel readout
[12:34:07.912] <TB1> INFO: PixTest:: pg_setup set to default.
[12:34:07.913] <TB1> INFO: 7 hot pixels found in step 1
[12:34:08.881] <TB1> INFO: Collecting data for 1 seconds...
[12:34:09.883] <TB1> INFO: Done with hot pixel readout
[12:34:15.854] <TB1> INFO: PixTest:: pg_setup set to default.
[12:34:15.855] <TB1> INFO: 6 hot pixels found in step 2
[12:34:16.819] <TB1> INFO: Collecting data for 1 seconds...
[12:34:17.820] <TB1> INFO: Done with hot pixel readout
[12:34:23.659] <TB1> INFO: PixTest:: pg_setup set to default.
[12:34:23.660] <TB1> INFO: 3 hot pixels found in step 3
[12:34:24.626] <TB1> INFO: Collecting data for 1 seconds...
[12:34:25.628] <TB1> INFO: Done with hot pixel readout
[12:34:31.818] <TB1> INFO: PixTest:: pg_setup set to default.
[12:34:31.819] <TB1> INFO: 4 hot pixels found in step 4
[12:34:32.785] <TB1> INFO: Collecting data for 1 seconds...
[12:34:33.786] <TB1> INFO: Done with hot pixel readout
[12:34:38.990] <TB1> INFO: PixTest:: pg_setup set to default.
[12:34:38.990] <TB1> INFO: 2 hot pixels found in step 5
[12:34:39.955] <TB1> INFO: Collecting data for 1 seconds...
[12:34:40.956] <TB1> INFO: Done with hot pixel readout
[12:34:46.333] <TB1> INFO: PixTest:: pg_setup set to default.
[12:34:46.334] <TB1> INFO: 4 hot pixels found in step 6
[12:34:47.298] <TB1> INFO: Collecting data for 1 seconds...
[12:34:48.299] <TB1> INFO: Done with hot pixel readout
[12:34:53.782] <TB1> INFO: PixTest:: pg_setup set to default.
[12:34:53.783] <TB1> INFO: 1 hot pixels found in step 7
[12:34:54.747] <TB1> INFO: Collecting data for 1 seconds...
[12:34:55.748] <TB1> INFO: Done with hot pixel readout
[12:35:00.973] <TB1> INFO: PixTest:: pg_setup set to default.
[12:35:00.974] <TB1> INFO: 0 hot pixels found in step 8
[12:35:00.983] <TB1> INFO: 0 hot pixels could not be trimmed and have been masked.
[12:35:01.011] <TB1> INFO: PixTest::trimHotPixels() done
[12:35:01.011] <TB1> INFO: write trim parameters into /usr/local/data/M2046_XrayQualification_2015-08-28_14h28m_1440764917//002_RetrimHotPixels_150//trimParameters35_C0.dat
[12:35:01.017] <TB1> INFO: write trim parameters into /usr/local/data/M2046_XrayQualification_2015-08-28_14h28m_1440764917//002_RetrimHotPixels_150//trimParameters35_C1.dat
[12:35:01.023] <TB1> INFO: write trim parameters into /usr/local/data/M2046_XrayQualification_2015-08-28_14h28m_1440764917//002_RetrimHotPixels_150//trimParameters35_C2.dat
[12:35:01.029] <TB1> INFO: write trim parameters into /usr/local/data/M2046_XrayQualification_2015-08-28_14h28m_1440764917//002_RetrimHotPixels_150//trimParameters35_C3.dat
[12:35:01.035] <TB1> INFO: write trim parameters into /usr/local/data/M2046_XrayQualification_2015-08-28_14h28m_1440764917//002_RetrimHotPixels_150//trimParameters35_C4.dat
[12:35:01.041] <TB1> INFO: write trim parameters into /usr/local/data/M2046_XrayQualification_2015-08-28_14h28m_1440764917//002_RetrimHotPixels_150//trimParameters35_C5.dat
[12:35:01.047] <TB1> INFO: write trim parameters into /usr/local/data/M2046_XrayQualification_2015-08-28_14h28m_1440764917//002_RetrimHotPixels_150//trimParameters35_C6.dat
[12:35:01.053] <TB1> INFO: write trim parameters into /usr/local/data/M2046_XrayQualification_2015-08-28_14h28m_1440764917//002_RetrimHotPixels_150//trimParameters35_C7.dat
[12:35:01.059] <TB1> INFO: write trim parameters into /usr/local/data/M2046_XrayQualification_2015-08-28_14h28m_1440764917//002_RetrimHotPixels_150//trimParameters35_C8.dat
[12:35:01.066] <TB1> INFO: write trim parameters into /usr/local/data/M2046_XrayQualification_2015-08-28_14h28m_1440764917//002_RetrimHotPixels_150//trimParameters35_C9.dat
[12:35:01.072] <TB1> INFO: write trim parameters into /usr/local/data/M2046_XrayQualification_2015-08-28_14h28m_1440764917//002_RetrimHotPixels_150//trimParameters35_C10.dat
[12:35:01.078] <TB1> INFO: write trim parameters into /usr/local/data/M2046_XrayQualification_2015-08-28_14h28m_1440764917//002_RetrimHotPixels_150//trimParameters35_C11.dat
[12:35:01.085] <TB1> INFO: write trim parameters into /usr/local/data/M2046_XrayQualification_2015-08-28_14h28m_1440764917//002_RetrimHotPixels_150//trimParameters35_C12.dat
[12:35:01.091] <TB1> INFO: write trim parameters into /usr/local/data/M2046_XrayQualification_2015-08-28_14h28m_1440764917//002_RetrimHotPixels_150//trimParameters35_C13.dat
[12:35:01.097] <TB1> INFO: write trim parameters into /usr/local/data/M2046_XrayQualification_2015-08-28_14h28m_1440764917//002_RetrimHotPixels_150//trimParameters35_C14.dat
[12:35:01.103] <TB1> INFO: write trim parameters into /usr/local/data/M2046_XrayQualification_2015-08-28_14h28m_1440764917//002_RetrimHotPixels_150//trimParameters35_C15.dat
[12:35:01.109] <TB1> INFO: write masked pixels into /usr/local/data/M2046_XrayQualification_2015-08-28_14h28m_1440764917//002_RetrimHotPixels_150//defaultMaskFile.dat
[12:35:01.141] <TB1> INFO: enter test to run
[12:35:01.141] <TB1> INFO: test: no parameter change
[12:35:01.288] <TB1> QUIET: Connection to board 83 closed.
[12:35:01.369] <TB1> INFO: pXar: this is the end, my friend

[12:35:06.326] <TB1> INFO: *** Welcome to pxar ***
[12:35:06.326] <TB1> INFO: *** Today: 2015/08/28
[12:35:06.326] <TB1> INFO: readRocDacs: /usr/local/data/M2046_XrayQualification_2015-08-28_14h28m_1440764917//003_HRData_50//dacParameters35_C0.dat .. /usr/local/data/M2046_XrayQualification_2015-08-28_14h28m_1440764917//003_HRData_50//dacParameters35_C15.dat
[12:35:06.327] <TB1> INFO: readTbmDacs: /usr/local/data/M2046_XrayQualification_2015-08-28_14h28m_1440764917//003_HRData_50//tbmParameters_C0a.dat .. /usr/local/data/M2046_XrayQualification_2015-08-28_14h28m_1440764917//003_HRData_50//tbmParameters_C0b.dat
[12:35:06.327] <TB1> INFO: readMaskFile: /usr/local/data/M2046_XrayQualification_2015-08-28_14h28m_1440764917//003_HRData_50//defaultMaskFile.dat
[12:35:06.327] <TB1> INFO: readTrimFile: /usr/local/data/M2046_XrayQualification_2015-08-28_14h28m_1440764917//003_HRData_50//trimParameters35_C0.dat .. /usr/local/data/M2046_XrayQualification_2015-08-28_14h28m_1440764917//003_HRData_50//trimParameters35_C15.dat
[12:35:06.398] <TB1> INFO: clk: 4
[12:35:06.398] <TB1> INFO: ctr: 4
[12:35:06.398] <TB1> INFO: sda: 19
[12:35:06.398] <TB1> INFO: tin: 9
[12:35:06.398] <TB1> INFO: level: 15
[12:35:06.398] <TB1> INFO: triggerdelay: 0
[12:35:06.398] <TB1> QUIET: Instanciating API for pxar v2.5+57~g44d0777
[12:35:06.398] <TB1> INFO: Log level: INFO
[12:35:06.411] <TB1> INFO: Found DTB DTB_WS6UZO
[12:35:06.421] <TB1> QUIET: Connection to board DTB_WS6UZO opened.
[12:35:06.424] <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:
------------------------------------------------------
[12:35:06.428] <TB1> INFO: RPC call hashes of host and DTB match: 397073690
[12:35:07.945] <TB1> INFO: DUT info:
[12:35:07.945] <TB1> INFO: The DUT currently contains the following objects:
[12:35:07.945] <TB1> INFO: 2 TBM Cores tbm09c (2 ON)
[12:35:07.945] <TB1> INFO: TBM Core alpha (0): 7 registers set
[12:35:07.945] <TB1> INFO: TBM Core beta (1): 7 registers set
[12:35:07.945] <TB1> INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[12:35:07.945] <TB1> INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[12:35:07.945] <TB1> INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[12:35:07.945] <TB1> INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[12:35:07.945] <TB1> INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[12:35:07.945] <TB1> INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[12:35:07.945] <TB1> INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[12:35:07.945] <TB1> INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[12:35:07.945] <TB1> INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[12:35:07.945] <TB1> INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[12:35:07.945] <TB1> INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[12:35:07.945] <TB1> INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[12:35:07.945] <TB1> INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[12:35:07.945] <TB1> INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[12:35:07.945] <TB1> INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[12:35:07.945] <TB1> INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[12:35:07.945] <TB1> INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[12:35:08.346] <TB1> INFO: enter 'restricted' command line mode
[12:35:08.346] <TB1> INFO: enter test to run
[12:35:08.346] <TB1> INFO: test: delay setting parameters: ->5<-
[12:35:08.346] <TB1> INFO: delay test by 5 seconds...
[12:35:13.346] <TB1> INFO: enter test to run
[12:35:13.346] <TB1> INFO: test: Xray no parameter change
[12:35:13.346] <TB1> INFO: running: xray
[12:35:13.346] <TB1> INFO: readGainPedestalParameters /usr/local/data/M2046_XrayQualification_2015-08-28_14h28m_1440764917//003_HRData_50//phCalibrationFitErr35_C0.dat .. /usr/local/data/M2046_XrayQualification_2015-08-28_14h28m_1440764917//003_HRData_50//phCalibrationFitErr35_C15.dat
[12:35:13.476] <TB1> INFO: ######################################################################
[12:35:13.476] <TB1> INFO: PixTestXray::doTest()
[12:35:13.476] <TB1> INFO: ######################################################################
[12:35:13.476] <TB1> INFO: ----------------------------------------------------------------------
[12:35:13.476] <TB1> INFO: PixTestXray::doPhRun() fParRunSeconds = 100
[12:35:13.476] <TB1> INFO: ----------------------------------------------------------------------
[12:35:14.443] <TB1> INFO: PixTestXray::doPhRun start TriggerLoop with trigger frequency 100 kHz, period 405 and duration 100 seconds, fEventsMax = 10000000
[12:35:20.434] <TB1> INFO: run duration 5 seconds, buffer almost full (81%), pausing triggers.
[12:35:43.279] <TB1> INFO: Resuming triggers.
[12:35:49.278] <TB1> INFO: run duration 11 seconds, buffer almost full (81%), pausing triggers.
[12:36:10.650] <TB1> INFO: Resuming triggers.
[12:36:16.647] <TB1> INFO: run duration 17 seconds, buffer almost full (81%), pausing triggers.
[12:36:39.070] <TB1> INFO: Resuming triggers.
[12:36:45.068] <TB1> INFO: run duration 23 seconds, buffer almost full (81%), pausing triggers.
[12:37:06.764] <TB1> INFO: Resuming triggers.
[12:37:12.763] <TB1> INFO: run duration 29 seconds, buffer almost full (81%), pausing triggers.
[12:37:34.551] <TB1> INFO: Resuming triggers.
[12:37:40.544] <TB1> INFO: run duration 35 seconds, buffer almost full (81%), pausing triggers.
[12:38:02.767] <TB1> INFO: Resuming triggers.
[12:38:08.766] <TB1> INFO: run duration 41 seconds, buffer almost full (81%), pausing triggers.
[12:38:30.941] <TB1> INFO: Resuming triggers.
[12:38:36.940] <TB1> INFO: run duration 47 seconds, buffer almost full (81%), pausing triggers.
[12:38:58.540] <TB1> INFO: Resuming triggers.
[12:39:04.543] <TB1> INFO: run duration 53 seconds, buffer almost full (81%), pausing triggers.
[12:39:26.737] <TB1> INFO: Resuming triggers.
[12:39:32.738] <TB1> INFO: run duration 59 seconds, buffer almost full (81%), pausing triggers.
[12:39:54.118] <TB1> INFO: Resuming triggers.
[12:40:00.117] <TB1> INFO: run duration 65 seconds, buffer almost full (81%), pausing triggers.
[12:40:22.131] <TB1> INFO: Resuming triggers.
[12:40:28.130] <TB1> INFO: run duration 71 seconds, buffer almost full (81%), pausing triggers.
[12:40:49.731] <TB1> INFO: Resuming triggers.
[12:40:55.731] <TB1> INFO: run duration 77 seconds, buffer almost full (81%), pausing triggers.
[12:41:17.166] <TB1> INFO: Resuming triggers.
[12:41:23.168] <TB1> INFO: run duration 83 seconds, buffer almost full (81%), pausing triggers.
[12:41:44.463] <TB1> INFO: Resuming triggers.
[12:41:50.463] <TB1> INFO: run duration 89 seconds, buffer almost full (81%), pausing triggers.
[12:42:11.959] <TB1> INFO: Resuming triggers.
[12:42:17.962] <TB1> INFO: run duration 95 seconds, buffer almost full (81%), pausing triggers.
[12:42:38.676] <TB1> INFO: Resuming triggers.
[12:42:42.851] <TB1> INFO: data taking finished, elapsed time: 100 seconds.
[12:42:58.140] <TB1> INFO: PixTest:: pg_setup set to default.
[12:42:58.141] <TB1> INFO: PixTestXray::doPhRun() done
[12:42:58.141] <TB1> INFO: PixTestXray::doTest() done
[12:42:58.314] <TB1> INFO: enter test to run
[12:42:58.314] <TB1> INFO: test: no parameter change
[12:42:58.544] <TB1> QUIET: Connection to board 83 closed.
[12:42:58.624] <TB1> INFO: pXar: this is the end, my friend

[12:43:05.604] <TB1> INFO: *** Welcome to pxar ***
[12:43:05.604] <TB1> INFO: *** Today: 2015/08/28
[12:43:05.604] <TB1> INFO: readRocDacs: /usr/local/data/M2046_XrayQualification_2015-08-28_14h28m_1440764917//004_HRData_150//dacParameters35_C0.dat .. /usr/local/data/M2046_XrayQualification_2015-08-28_14h28m_1440764917//004_HRData_150//dacParameters35_C15.dat
[12:43:05.605] <TB1> INFO: readTbmDacs: /usr/local/data/M2046_XrayQualification_2015-08-28_14h28m_1440764917//004_HRData_150//tbmParameters_C0a.dat .. /usr/local/data/M2046_XrayQualification_2015-08-28_14h28m_1440764917//004_HRData_150//tbmParameters_C0b.dat
[12:43:05.605] <TB1> INFO: readMaskFile: /usr/local/data/M2046_XrayQualification_2015-08-28_14h28m_1440764917//004_HRData_150//defaultMaskFile.dat
[12:43:05.605] <TB1> INFO: readTrimFile: /usr/local/data/M2046_XrayQualification_2015-08-28_14h28m_1440764917//004_HRData_150//trimParameters35_C0.dat .. /usr/local/data/M2046_XrayQualification_2015-08-28_14h28m_1440764917//004_HRData_150//trimParameters35_C15.dat
[12:43:05.671] <TB1> INFO: clk: 4
[12:43:05.671] <TB1> INFO: ctr: 4
[12:43:05.671] <TB1> INFO: sda: 19
[12:43:05.671] <TB1> INFO: tin: 9
[12:43:05.671] <TB1> INFO: level: 15
[12:43:05.671] <TB1> INFO: triggerdelay: 0
[12:43:05.671] <TB1> QUIET: Instanciating API for pxar v2.5+57~g44d0777
[12:43:05.671] <TB1> INFO: Log level: INFO
[12:43:05.684] <TB1> INFO: Found DTB DTB_WS6UZO
[12:43:05.694] <TB1> QUIET: Connection to board DTB_WS6UZO opened.
[12:43:05.697] <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:
------------------------------------------------------
[12:43:05.700] <TB1> INFO: RPC call hashes of host and DTB match: 397073690
[12:43:07.213] <TB1> INFO: DUT info:
[12:43:07.213] <TB1> INFO: The DUT currently contains the following objects:
[12:43:07.213] <TB1> INFO: 2 TBM Cores tbm09c (2 ON)
[12:43:07.213] <TB1> INFO: TBM Core alpha (0): 7 registers set
[12:43:07.213] <TB1> INFO: TBM Core beta (1): 7 registers set
[12:43:07.213] <TB1> INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[12:43:07.213] <TB1> INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[12:43:07.213] <TB1> INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[12:43:07.213] <TB1> INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[12:43:07.214] <TB1> INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[12:43:07.214] <TB1> INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[12:43:07.214] <TB1> INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[12:43:07.214] <TB1> INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[12:43:07.214] <TB1> INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[12:43:07.214] <TB1> INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[12:43:07.214] <TB1> INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[12:43:07.214] <TB1> INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[12:43:07.214] <TB1> INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[12:43:07.214] <TB1> INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[12:43:07.214] <TB1> INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[12:43:07.214] <TB1> INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[12:43:07.214] <TB1> INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[12:43:07.614] <TB1> INFO: enter 'restricted' command line mode
[12:43:07.614] <TB1> INFO: enter test to run
[12:43:07.615] <TB1> INFO: test: delay setting parameters: ->5<-
[12:43:07.615] <TB1> INFO: delay test by 5 seconds...
[12:43:12.615] <TB1> INFO: enter test to run
[12:43:12.615] <TB1> INFO: test: Xray no parameter change
[12:43:12.615] <TB1> INFO: running: xray
[12:43:12.615] <TB1> INFO: readGainPedestalParameters /usr/local/data/M2046_XrayQualification_2015-08-28_14h28m_1440764917//004_HRData_150//phCalibrationFitErr35_C0.dat .. /usr/local/data/M2046_XrayQualification_2015-08-28_14h28m_1440764917//004_HRData_150//phCalibrationFitErr35_C15.dat
[12:43:12.747] <TB1> INFO: ######################################################################
[12:43:12.747] <TB1> INFO: PixTestXray::doTest()
[12:43:12.747] <TB1> INFO: ######################################################################
[12:43:12.747] <TB1> INFO: ----------------------------------------------------------------------
[12:43:12.747] <TB1> INFO: PixTestXray::doPhRun() fParRunSeconds = 100
[12:43:12.747] <TB1> INFO: ----------------------------------------------------------------------
[12:43:13.714] <TB1> INFO: PixTestXray::doPhRun start TriggerLoop with trigger frequency 100 kHz, period 405 and duration 100 seconds, fEventsMax = 10000000
[12:43:16.837] <TB1> INFO: run duration 3 seconds, buffer almost full (81%), pausing triggers.
[12:43:39.877] <TB1> INFO: Resuming triggers.
[12:43:43.008] <TB1> INFO: run duration 6 seconds, buffer almost full (81%), pausing triggers.
[12:44:05.731] <TB1> INFO: Resuming triggers.
[12:44:08.859] <TB1> INFO: run duration 9 seconds, buffer almost full (81%), pausing triggers.
[12:44:32.045] <TB1> INFO: Resuming triggers.
[12:44:35.169] <TB1> INFO: run duration 12 seconds, buffer almost full (81%), pausing triggers.
[12:44:58.399] <TB1> INFO: Resuming triggers.
[12:45:01.527] <TB1> INFO: run duration 15 seconds, buffer almost full (81%), pausing triggers.
[12:45:25.336] <TB1> INFO: Resuming triggers.
[12:45:28.461] <TB1> INFO: run duration 18 seconds, buffer almost full (81%), pausing triggers.
[12:45:52.238] <TB1> INFO: Resuming triggers.
[12:45:55.363] <TB1> INFO: run duration 21 seconds, buffer almost full (81%), pausing triggers.
[12:46:19.007] <TB1> INFO: Resuming triggers.
[12:46:22.127] <TB1> INFO: run duration 24 seconds, buffer almost full (81%), pausing triggers.
[12:46:45.650] <TB1> INFO: Resuming triggers.
[12:46:48.770] <TB1> INFO: run duration 28 seconds, buffer almost full (81%), pausing triggers.


[12:47:12.419] <TB1> INFO: Resuming triggers.
[12:47:15.539] <TB1> INFO: run duration 31 seconds, buffer almost full (81%), pausing triggers.
[12:47:38.807] <TB1> INFO: Resuming triggers.
[12:47:41.930] <TB1> INFO: run duration 34 seconds, buffer almost full (81%), pausing triggers.
[12:48:05.423] <TB1> INFO: Resuming triggers.
[12:48:08.542] <TB1> INFO: run duration 37 seconds, buffer almost full (81%), pausing triggers.
[12:48:32.266] <TB1> INFO: Resuming triggers.
[12:48:35.385] <TB1> INFO: run duration 40 seconds, buffer almost full (81%), pausing triggers.
[12:48:58.594] <TB1> INFO: Resuming triggers.
[12:49:01.714] <TB1> INFO: run duration 43 seconds, buffer almost full (81%), pausing triggers.
[12:49:24.524] <TB1> INFO: Resuming triggers.
[12:49:27.654] <TB1> INFO: run duration 46 seconds, buffer almost full (81%), pausing triggers.
[12:49:50.755] <TB1> INFO: Resuming triggers.
[12:49:53.873] <TB1> INFO: run duration 49 seconds, buffer almost full (81%), pausing triggers.
[12:50:16.864] <TB1> INFO: Resuming triggers.
[12:50:19.978] <TB1> INFO: run duration 53 seconds, buffer almost full (81%), pausing triggers.
[12:50:42.792] <TB1> INFO: Resuming triggers.
[12:50:45.913] <TB1> INFO: run duration 56 seconds, buffer almost full (81%), pausing triggers.
[12:51:08.655] <TB1> INFO: Resuming triggers.
[12:51:11.773] <TB1> INFO: run duration 59 seconds, buffer almost full (81%), pausing triggers.
[12:51:34.266] <TB1> INFO: Resuming triggers.
[12:51:37.383] <TB1> INFO: run duration 62 seconds, buffer almost full (81%), pausing triggers.
[12:52:00.162] <TB1> INFO: Resuming triggers.
[12:52:03.277] <TB1> INFO: run duration 65 seconds, buffer almost full (81%), pausing triggers.
[12:52:25.630] <TB1> INFO: Resuming triggers.
[12:52:28.746] <TB1> INFO: run duration 68 seconds, buffer almost full (81%), pausing triggers.
[12:52:51.651] <TB1> INFO: Resuming triggers.
[12:52:54.766] <TB1> INFO: run duration 71 seconds, buffer almost full (81%), pausing triggers.
[12:53:17.860] <TB1> INFO: Resuming triggers.
[12:53:20.976] <TB1> INFO: run duration 74 seconds, buffer almost full (81%), pausing triggers.
[12:53:43.871] <TB1> INFO: Resuming triggers.
[12:53:46.990] <TB1> INFO: run duration 77 seconds, buffer almost full (81%), pausing triggers.
[12:54:09.714] <TB1> INFO: Resuming triggers.
[12:54:12.834] <TB1> INFO: run duration 81 seconds, buffer almost full (81%), pausing triggers.
[12:54:35.634] <TB1> INFO: Resuming triggers.
[12:54:38.750] <TB1> INFO: run duration 84 seconds, buffer almost full (81%), pausing triggers.
[12:55:01.248] <TB1> INFO: Resuming triggers.
[12:55:04.367] <TB1> INFO: run duration 87 seconds, buffer almost full (81%), pausing triggers.
[12:55:26.646] <TB1> INFO: Resuming triggers.
[12:55:29.765] <TB1> INFO: run duration 90 seconds, buffer almost full (81%), pausing triggers.
[12:55:51.700] <TB1> INFO: Resuming triggers.
[12:55:54.817] <TB1> INFO: run duration 93 seconds, buffer almost full (81%), pausing triggers.
[12:56:16.820] <TB1> INFO: Resuming triggers.
[12:56:19.935] <TB1> INFO: run duration 96 seconds, buffer almost full (81%), pausing triggers.
[12:56:42.051] <TB1> INFO: Resuming triggers.
[12:56:45.166] <TB1> INFO: run duration 99 seconds, buffer almost full (81%), pausing triggers.
[12:57:07.469] <TB1> INFO: Resuming triggers.
[12:57:07.776] <TB1> INFO: data taking finished, elapsed time: 100 seconds.
[12:57:10.347] <TB1> INFO: PixTest:: pg_setup set to default.
[12:57:10.349] <TB1> INFO: PixTestXray::doPhRun() done
[12:57:10.349] <TB1> INFO: PixTestXray::doTest() done
[12:57:10.547] <TB1> INFO: enter test to run
[12:57:10.547] <TB1> INFO: test: no parameter change
[12:57:10.707] <TB1> QUIET: Connection to board 83 closed.
[12:57:10.790] <TB1> INFO: pXar: this is the end, my friend

[12:57:18.341] <TB1> INFO: *** Welcome to pxar ***
[12:57:18.341] <TB1> INFO: *** Today: 2015/08/28
[12:57:18.341] <TB1> INFO: readRocDacs: /usr/local/data/M2046_XrayQualification_2015-08-28_14h28m_1440764917//005_HRSCurves_100//dacParameters35_C0.dat .. /usr/local/data/M2046_XrayQualification_2015-08-28_14h28m_1440764917//005_HRSCurves_100//dacParameters35_C15.dat
[12:57:18.341] <TB1> INFO: readTbmDacs: /usr/local/data/M2046_XrayQualification_2015-08-28_14h28m_1440764917//005_HRSCurves_100//tbmParameters_C0a.dat .. /usr/local/data/M2046_XrayQualification_2015-08-28_14h28m_1440764917//005_HRSCurves_100//tbmParameters_C0b.dat
[12:57:18.341] <TB1> INFO: readMaskFile: /usr/local/data/M2046_XrayQualification_2015-08-28_14h28m_1440764917//005_HRSCurves_100//defaultMaskFile.dat
[12:57:18.341] <TB1> INFO: readTrimFile: /usr/local/data/M2046_XrayQualification_2015-08-28_14h28m_1440764917//005_HRSCurves_100//trimParameters35_C0.dat .. /usr/local/data/M2046_XrayQualification_2015-08-28_14h28m_1440764917//005_HRSCurves_100//trimParameters35_C15.dat
[12:57:18.409] <TB1> INFO: clk: 4
[12:57:18.409] <TB1> INFO: ctr: 4
[12:57:18.409] <TB1> INFO: sda: 19
[12:57:18.409] <TB1> INFO: tin: 9
[12:57:18.409] <TB1> INFO: level: 15
[12:57:18.409] <TB1> INFO: triggerdelay: 0
[12:57:18.409] <TB1> QUIET: Instanciating API for pxar v2.5+57~g44d0777
[12:57:18.409] <TB1> INFO: Log level: INFO
[12:57:18.422] <TB1> INFO: Found DTB DTB_WS6UZO
[12:57:18.431] <TB1> QUIET: Connection to board DTB_WS6UZO opened.
[12:57:18.435] <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:
------------------------------------------------------
[12:57:18.437] <TB1> INFO: RPC call hashes of host and DTB match: 397073690
[12:57:19.949] <TB1> INFO: DUT info:
[12:57:19.949] <TB1> INFO: The DUT currently contains the following objects:
[12:57:19.949] <TB1> INFO: 2 TBM Cores tbm09c (2 ON)
[12:57:19.949] <TB1> INFO: TBM Core alpha (0): 7 registers set
[12:57:19.949] <TB1> INFO: TBM Core beta (1): 7 registers set
[12:57:19.949] <TB1> INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[12:57:19.949] <TB1> INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[12:57:19.949] <TB1> INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[12:57:19.949] <TB1> INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[12:57:19.949] <TB1> INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[12:57:19.949] <TB1> INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[12:57:19.949] <TB1> INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[12:57:19.949] <TB1> INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[12:57:19.949] <TB1> INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[12:57:19.949] <TB1> INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[12:57:19.949] <TB1> INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[12:57:19.949] <TB1> INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[12:57:19.949] <TB1> INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[12:57:19.949] <TB1> INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[12:57:19.949] <TB1> INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[12:57:19.949] <TB1> INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[12:57:19.949] <TB1> INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[12:57:20.350] <TB1> INFO: enter 'restricted' command line mode
[12:57:20.350] <TB1> INFO: enter test to run
[12:57:20.350] <TB1> INFO: test: Highrate no parameter change
[12:57:20.350] <TB1> INFO: running: highrate
[12:57:20.350] <TB1> INFO: readGainPedestalParameters /usr/local/data/M2046_XrayQualification_2015-08-28_14h28m_1440764917//005_HRSCurves_100//phCalibrationFitErr35_C0.dat .. /usr/local/data/M2046_XrayQualification_2015-08-28_14h28m_1440764917//005_HRSCurves_100//phCalibrationFitErr35_C15.dat
[12:57:20.477] <TB1> INFO: ----------------------------------------------------------------------
[12:57:20.477] <TB1> INFO: PixTestHighRate::xNoiseMaps() ntrig = 50, vcal = 200
[12:57:20.477] <TB1> INFO: ----------------------------------------------------------------------
[12:57:20.483] <TB1> INFO: ---> dac: vcal name: xNoiseMap ntrig: 50 dacrange: 0 .. 100 (1/-1) hits flags = 384 (plus default)
[12:57:20.506] <TB1> INFO: dacScan step from 0 .. 0
[12:57:20.506] <TB1> INFO: dacScan split into 1 runs with ntrig = 50
[12:57:20.506] <TB1> INFO: run 1 of 1
[12:57:21.094] <TB1> INFO: Expecting 208000 events.
[12:57:35.764] <TB1> INFO: 208000 events read in total (14141ms).
[12:57:35.774] <TB1> INFO: Test took 15267ms.
[12:57:36.117] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[12:57:36.395] <TB1> INFO: dacScan step from 1 .. 1
[12:57:36.395] <TB1> INFO: dacScan split into 1 runs with ntrig = 50
[12:57:36.395] <TB1> INFO: run 1 of 1
[12:57:36.993] <TB1> INFO: Expecting 208000 events.
[12:57:52.350] <TB1> INFO: 208000 events read in total (14827ms).
[12:57:52.361] <TB1> INFO: Test took 15965ms.
[12:57:52.598] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[12:57:52.877] <TB1> INFO: dacScan step from 2 .. 2
[12:57:52.877] <TB1> INFO: dacScan split into 1 runs with ntrig = 50
[12:57:52.877] <TB1> INFO: run 1 of 1
[12:57:53.473] <TB1> INFO: Expecting 208000 events.
[12:58:09.224] <TB1> INFO: 208000 events read in total (15223ms).
[12:58:09.234] <TB1> INFO: Test took 16356ms.
[12:58:09.416] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[12:58:09.709] <TB1> INFO: dacScan step from 3 .. 3
[12:58:09.709] <TB1> INFO: dacScan split into 1 runs with ntrig = 50
[12:58:09.709] <TB1> INFO: run 1 of 1
[12:58:10.356] <TB1> INFO: Expecting 208000 events.
[12:58:25.042] <TB1> INFO: 208000 events read in total (14159ms).
[12:58:25.053] <TB1> INFO: Test took 15343ms.
[12:58:25.251] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[12:58:25.544] <TB1> INFO: dacScan step from 4 .. 4
[12:58:25.544] <TB1> INFO: dacScan split into 1 runs with ntrig = 50
[12:58:25.544] <TB1> INFO: run 1 of 1
[12:58:26.139] <TB1> INFO: Expecting 208000 events.
[12:58:41.334] <TB1> INFO: 208000 events read in total (14668ms).
[12:58:41.343] <TB1> INFO: Test took 15799ms.
[12:58:41.518] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[12:58:41.855] <TB1> INFO: dacScan step from 5 .. 5
[12:58:41.855] <TB1> INFO: dacScan split into 1 runs with ntrig = 50
[12:58:41.855] <TB1> INFO: run 1 of 1
[12:58:42.454] <TB1> INFO: Expecting 208000 events.
[12:58:57.620] <TB1> INFO: 208000 events read in total (14639ms).
[12:58:57.629] <TB1> INFO: Test took 15773ms.
[12:58:57.773] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[12:58:58.123] <TB1> INFO: dacScan step from 6 .. 6
[12:58:58.123] <TB1> INFO: dacScan split into 1 runs with ntrig = 50
[12:58:58.123] <TB1> INFO: run 1 of 1
[12:58:58.746] <TB1> INFO: Expecting 208000 events.
[12:59:14.098] <TB1> INFO: 208000 events read in total (14824ms).
[12:59:14.107] <TB1> INFO: Test took 15984ms.
[12:59:14.295] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[12:59:14.568] <TB1> INFO: dacScan step from 7 .. 7
[12:59:14.568] <TB1> INFO: dacScan split into 1 runs with ntrig = 50
[12:59:14.568] <TB1> INFO: run 1 of 1
[12:59:15.159] <TB1> INFO: Expecting 208000 events.
[12:59:30.166] <TB1> INFO: 208000 events read in total (14480ms).
[12:59:30.177] <TB1> INFO: Test took 15609ms.
[12:59:30.360] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[12:59:30.654] <TB1> INFO: dacScan step from 8 .. 8
[12:59:30.654] <TB1> INFO: dacScan split into 1 runs with ntrig = 50
[12:59:30.654] <TB1> INFO: run 1 of 1
[12:59:31.256] <TB1> INFO: Expecting 208000 events.
[12:59:45.585] <TB1> INFO: 208000 events read in total (13801ms).
[12:59:45.595] <TB1> INFO: Test took 14940ms.
[12:59:45.762] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[12:59:46.041] <TB1> INFO: dacScan step from 9 .. 9
[12:59:46.041] <TB1> INFO: dacScan split into 1 runs with ntrig = 50
[12:59:46.041] <TB1> INFO: run 1 of 1
[12:59:46.633] <TB1> INFO: Expecting 208000 events.
[13:00:02.310] <TB1> INFO: 208000 events read in total (15149ms).
[13:00:02.322] <TB1> INFO: Test took 16281ms.
[13:00:02.532] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:00:02.889] <TB1> INFO: dacScan step from 10 .. 10
[13:00:02.889] <TB1> INFO: dacScan split into 1 runs with ntrig = 50
[13:00:02.889] <TB1> INFO: run 1 of 1
[13:00:03.484] <TB1> INFO: Expecting 208000 events.
[13:00:18.884] <TB1> INFO: 208000 events read in total (14872ms).
[13:00:18.894] <TB1> INFO: Test took 16004ms.
[13:00:19.050] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:00:19.388] <TB1> INFO: dacScan step from 11 .. 11
[13:00:19.388] <TB1> INFO: dacScan split into 1 runs with ntrig = 50
[13:00:19.388] <TB1> INFO: run 1 of 1
[13:00:20.003] <TB1> INFO: Expecting 208000 events.
[13:00:34.821] <TB1> INFO: 208000 events read in total (14291ms).
[13:00:34.831] <TB1> INFO: Test took 15443ms.
[13:00:35.030] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:00:35.313] <TB1> INFO: dacScan step from 12 .. 12
[13:00:35.313] <TB1> INFO: dacScan split into 1 runs with ntrig = 50
[13:00:35.313] <TB1> INFO: run 1 of 1
[13:00:35.908] <TB1> INFO: Expecting 208000 events.
[13:00:51.511] <TB1> INFO: 208000 events read in total (15076ms).
[13:00:51.524] <TB1> INFO: Test took 16211ms.
[13:00:51.731] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:00:52.102] <TB1> INFO: dacScan step from 13 .. 13
[13:00:52.102] <TB1> INFO: dacScan split into 1 runs with ntrig = 50
[13:00:52.102] <TB1> INFO: run 1 of 1
[13:00:52.743] <TB1> INFO: Expecting 208000 events.
[13:01:07.350] <TB1> INFO: 208000 events read in total (14080ms).
[13:01:07.360] <TB1> INFO: Test took 15258ms.
[13:01:07.511] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:01:07.789] <TB1> INFO: dacScan step from 14 .. 14
[13:01:07.789] <TB1> INFO: dacScan split into 1 runs with ntrig = 50
[13:01:07.789] <TB1> INFO: run 1 of 1
[13:01:08.384] <TB1> INFO: Expecting 208000 events.
[13:01:23.541] <TB1> INFO: 208000 events read in total (14630ms).
[13:01:23.551] <TB1> INFO: Test took 15762ms.
[13:01:23.758] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:01:24.039] <TB1> INFO: dacScan step from 15 .. 15
[13:01:24.039] <TB1> INFO: dacScan split into 1 runs with ntrig = 50
[13:01:24.039] <TB1> INFO: run 1 of 1
[13:01:24.634] <TB1> INFO: Expecting 208000 events.
[13:01:39.995] <TB1> INFO: 208000 events read in total (14834ms).
[13:01:40.006] <TB1> INFO: Test took 15966ms.
[13:01:40.286] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:01:40.586] <TB1> INFO: dacScan step from 16 .. 16
[13:01:40.586] <TB1> INFO: dacScan split into 1 runs with ntrig = 50
[13:01:40.586] <TB1> INFO: run 1 of 1
[13:01:41.185] <TB1> INFO: Expecting 208000 events.
[13:01:55.835] <TB1> INFO: 208000 events read in total (14122ms).
[13:01:55.846] <TB1> INFO: Test took 15260ms.
[13:01:56.031] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:01:56.309] <TB1> INFO: dacScan step from 17 .. 17
[13:01:56.309] <TB1> INFO: dacScan split into 1 runs with ntrig = 50
[13:01:56.309] <TB1> INFO: run 1 of 1
[13:01:56.904] <TB1> INFO: Expecting 208000 events.
[13:02:11.159] <TB1> INFO: 208000 events read in total (13728ms).
[13:02:11.170] <TB1> INFO: Test took 14861ms.
[13:02:11.267] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:02:11.545] <TB1> INFO: dacScan step from 18 .. 18
[13:02:11.545] <TB1> INFO: dacScan split into 1 runs with ntrig = 50
[13:02:11.545] <TB1> INFO: run 1 of 1
[13:02:12.143] <TB1> INFO: Expecting 208000 events.
[13:02:27.570] <TB1> INFO: 208000 events read in total (14900ms).
[13:02:27.584] <TB1> INFO: Test took 16038ms.
[13:02:27.690] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:02:27.979] <TB1> INFO: dacScan step from 19 .. 19
[13:02:27.979] <TB1> INFO: dacScan split into 1 runs with ntrig = 50
[13:02:27.979] <TB1> INFO: run 1 of 1
[13:02:28.586] <TB1> INFO: Expecting 208000 events.
[13:02:43.793] <TB1> INFO: 208000 events read in total (14680ms).
[13:02:43.804] <TB1> INFO: Test took 15825ms.
[13:02:43.903] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:02:44.249] <TB1> INFO: dacScan step from 20 .. 20
[13:02:44.249] <TB1> INFO: dacScan split into 1 runs with ntrig = 50
[13:02:44.249] <TB1> INFO: run 1 of 1
[13:02:44.852] <TB1> INFO: Expecting 208000 events.
[13:03:00.218] <TB1> INFO: 208000 events read in total (14838ms).
[13:03:00.228] <TB1> INFO: Test took 15979ms.
[13:03:00.324] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:03:00.599] <TB1> INFO: dacScan step from 21 .. 21
[13:03:00.599] <TB1> INFO: dacScan split into 1 runs with ntrig = 50
[13:03:00.599] <TB1> INFO: run 1 of 1
[13:03:01.192] <TB1> INFO: Expecting 208000 events.
[13:03:16.424] <TB1> INFO: 208000 events read in total (14705ms).
[13:03:16.436] <TB1> INFO: Test took 15837ms.
[13:03:16.536] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:03:16.829] <TB1> INFO: dacScan step from 22 .. 22
[13:03:16.829] <TB1> INFO: dacScan split into 1 runs with ntrig = 50
[13:03:16.829] <TB1> INFO: run 1 of 1
[13:03:17.464] <TB1> INFO: Expecting 208000 events.
[13:03:31.975] <TB1> INFO: 208000 events read in total (13983ms).
[13:03:31.987] <TB1> INFO: Test took 15158ms.
[13:03:32.088] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:03:32.369] <TB1> INFO: dacScan step from 23 .. 23
[13:03:32.369] <TB1> INFO: dacScan split into 1 runs with ntrig = 50
[13:03:32.370] <TB1> INFO: run 1 of 1
[13:03:32.967] <TB1> INFO: Expecting 208000 events.
[13:03:47.974] <TB1> INFO: 208000 events read in total (14480ms).
[13:03:47.986] <TB1> INFO: Test took 15616ms.
[13:03:48.084] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:03:48.396] <TB1> INFO: dacScan step from 24 .. 24
[13:03:48.396] <TB1> INFO: dacScan split into 1 runs with ntrig = 50
[13:03:48.396] <TB1> INFO: run 1 of 1
[13:03:48.994] <TB1> INFO: Expecting 208000 events.
[13:04:03.966] <TB1> INFO: 208000 events read in total (14446ms).
[13:04:03.974] <TB1> INFO: Test took 15577ms.
[13:04:04.086] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:04:04.395] <TB1> INFO: dacScan step from 25 .. 25
[13:04:04.395] <TB1> INFO: dacScan split into 1 runs with ntrig = 50
[13:04:04.395] <TB1> INFO: run 1 of 1
[13:04:04.991] <TB1> INFO: Expecting 208000 events.
[13:04:19.662] <TB1> INFO: 208000 events read in total (14144ms).
[13:04:19.672] <TB1> INFO: Test took 15276ms.
[13:04:19.767] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:04:20.044] <TB1> INFO: dacScan step from 26 .. 26
[13:04:20.044] <TB1> INFO: dacScan split into 1 runs with ntrig = 50
[13:04:20.044] <TB1> INFO: run 1 of 1
[13:04:20.640] <TB1> INFO: Expecting 208000 events.
[13:04:36.070] <TB1> INFO: 208000 events read in total (14902ms).
[13:04:36.080] <TB1> INFO: Test took 16035ms.
[13:04:36.176] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:04:36.526] <TB1> INFO: dacScan step from 27 .. 27
[13:04:36.526] <TB1> INFO: dacScan split into 1 runs with ntrig = 50
[13:04:36.526] <TB1> INFO: run 1 of 1
[13:04:37.182] <TB1> INFO: Expecting 208000 events.
[13:04:51.607] <TB1> INFO: 208000 events read in total (13898ms).
[13:04:51.617] <TB1> INFO: Test took 15091ms.
[13:04:51.711] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:04:51.984] <TB1> INFO: dacScan step from 28 .. 28
[13:04:51.984] <TB1> INFO: dacScan split into 1 runs with ntrig = 50
[13:04:51.984] <TB1> INFO: run 1 of 1
[13:04:52.580] <TB1> INFO: Expecting 208000 events.
[13:05:07.974] <TB1> INFO: 208000 events read in total (14867ms).
[13:05:07.984] <TB1> INFO: Test took 15999ms.
[13:05:08.079] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:05:08.393] <TB1> INFO: dacScan step from 29 .. 29
[13:05:08.393] <TB1> INFO: dacScan split into 1 runs with ntrig = 50
[13:05:08.393] <TB1> INFO: run 1 of 1
[13:05:08.987] <TB1> INFO: Expecting 208000 events.
[13:05:24.236] <TB1> INFO: 208000 events read in total (14721ms).
[13:05:24.246] <TB1> INFO: Test took 15852ms.
[13:05:24.347] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:05:24.672] <TB1> INFO: dacScan step from 30 .. 30
[13:05:24.672] <TB1> INFO: dacScan split into 1 runs with ntrig = 50
[13:05:24.672] <TB1> INFO: run 1 of 1
[13:05:25.294] <TB1> INFO: Expecting 208000 events.
[13:05:39.859] <TB1> INFO: 208000 events read in total (14038ms).
[13:05:39.871] <TB1> INFO: Test took 15198ms.
[13:05:40.077] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:05:40.349] <TB1> INFO: dacScan step from 31 .. 31
[13:05:40.349] <TB1> INFO: dacScan split into 1 runs with ntrig = 50
[13:05:40.349] <TB1> INFO: run 1 of 1
[13:05:40.943] <TB1> INFO: Expecting 208000 events.
[13:05:56.325] <TB1> INFO: 208000 events read in total (14855ms).
[13:05:56.337] <TB1> INFO: Test took 15988ms.
[13:05:56.467] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:05:56.795] <TB1> INFO: dacScan step from 32 .. 32
[13:05:56.795] <TB1> INFO: dacScan split into 1 runs with ntrig = 50
[13:05:56.795] <TB1> INFO: run 1 of 1
[13:05:57.397] <TB1> INFO: Expecting 208000 events.
[13:06:11.972] <TB1> INFO: 208000 events read in total (14048ms).
[13:06:11.982] <TB1> INFO: Test took 15187ms.
[13:06:12.087] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:06:12.371] <TB1> INFO: dacScan step from 33 .. 33
[13:06:12.371] <TB1> INFO: dacScan split into 1 runs with ntrig = 50
[13:06:12.371] <TB1> INFO: run 1 of 1
[13:06:12.962] <TB1> INFO: Expecting 208000 events.
[13:06:28.136] <TB1> INFO: 208000 events read in total (14646ms).
[13:06:28.146] <TB1> INFO: Test took 15774ms.
[13:06:28.240] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:06:28.517] <TB1> INFO: dacScan step from 34 .. 34
[13:06:28.517] <TB1> INFO: dacScan split into 1 runs with ntrig = 50
[13:06:28.517] <TB1> INFO: run 1 of 1
[13:06:29.112] <TB1> INFO: Expecting 208000 events.
[13:06:43.890] <TB1> INFO: 208000 events read in total (14250ms).
[13:06:43.901] <TB1> INFO: Test took 15383ms.
[13:06:44.118] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:06:44.392] <TB1> INFO: dacScan step from 35 .. 35
[13:06:44.392] <TB1> INFO: dacScan split into 1 runs with ntrig = 50
[13:06:44.392] <TB1> INFO: run 1 of 1
[13:06:44.982] <TB1> INFO: Expecting 208000 events.
[13:07:00.417] <TB1> INFO: 208000 events read in total (14908ms).
[13:07:00.427] <TB1> INFO: Test took 16035ms.
[13:07:00.522] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:07:00.805] <TB1> INFO: dacScan step from 36 .. 36
[13:07:00.806] <TB1> INFO: dacScan split into 1 runs with ntrig = 50
[13:07:00.806] <TB1> INFO: run 1 of 1
[13:07:01.401] <TB1> INFO: Expecting 208000 events.
[13:07:16.634] <TB1> INFO: 208000 events read in total (14705ms).
[13:07:16.644] <TB1> INFO: Test took 15838ms.
[13:07:16.739] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:07:17.020] <TB1> INFO: dacScan step from 37 .. 37
[13:07:17.020] <TB1> INFO: dacScan split into 1 runs with ntrig = 50
[13:07:17.020] <TB1> INFO: run 1 of 1
[13:07:17.617] <TB1> INFO: Expecting 208000 events.
[13:07:33.736] <TB1> INFO: 208000 events read in total (15591ms).
[13:07:33.748] <TB1> INFO: Test took 16727ms.
[13:07:33.852] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:07:34.161] <TB1> INFO: dacScan step from 38 .. 38
[13:07:34.161] <TB1> INFO: dacScan split into 1 runs with ntrig = 50
[13:07:34.161] <TB1> INFO: run 1 of 1
[13:07:34.763] <TB1> INFO: Expecting 208000 events.
[13:07:50.080] <TB1> INFO: 208000 events read in total (14789ms).
[13:07:50.091] <TB1> INFO: Test took 15930ms.
[13:07:50.187] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:07:50.501] <TB1> INFO: dacScan step from 39 .. 39
[13:07:50.501] <TB1> INFO: dacScan split into 1 runs with ntrig = 50
[13:07:50.501] <TB1> INFO: run 1 of 1
[13:07:51.108] <TB1> INFO: Expecting 208000 events.
[13:08:06.700] <TB1> INFO: 208000 events read in total (15064ms).
[13:08:06.710] <TB1> INFO: Test took 16208ms.
[13:08:06.805] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:08:07.081] <TB1> INFO: dacScan step from 40 .. 40
[13:08:07.081] <TB1> INFO: dacScan split into 1 runs with ntrig = 50
[13:08:07.081] <TB1> INFO: run 1 of 1
[13:08:07.677] <TB1> INFO: Expecting 208000 events.
[13:08:23.536] <TB1> INFO: 208000 events read in total (15332ms).
[13:08:23.547] <TB1> INFO: Test took 16465ms.
[13:08:23.643] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:08:23.920] <TB1> INFO: dacScan step from 41 .. 41
[13:08:23.920] <TB1> INFO: dacScan split into 1 runs with ntrig = 50
[13:08:23.920] <TB1> INFO: run 1 of 1
[13:08:24.515] <TB1> INFO: Expecting 208000 events.
[13:08:40.970] <TB1> INFO: 208000 events read in total (15927ms).
[13:08:40.981] <TB1> INFO: Test took 17060ms.
[13:08:41.076] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:08:41.355] <TB1> INFO: dacScan step from 42 .. 42
[13:08:41.355] <TB1> INFO: dacScan split into 1 runs with ntrig = 50
[13:08:41.355] <TB1> INFO: run 1 of 1
[13:08:41.950] <TB1> INFO: Expecting 208000 events.
[13:08:57.564] <TB1> INFO: 208000 events read in total (15087ms).
[13:08:57.575] <TB1> INFO: Test took 16220ms.
[13:08:57.675] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:08:57.974] <TB1> INFO: dacScan step from 43 .. 43
[13:08:57.974] <TB1> INFO: dacScan split into 1 runs with ntrig = 50
[13:08:57.974] <TB1> INFO: run 1 of 1
[13:08:58.576] <TB1> INFO: Expecting 208000 events.
[13:09:15.327] <TB1> INFO: 208000 events read in total (16224ms).
[13:09:15.338] <TB1> INFO: Test took 17363ms.
[13:09:15.434] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:09:15.715] <TB1> INFO: dacScan step from 44 .. 44
[13:09:15.715] <TB1> INFO: dacScan split into 1 runs with ntrig = 50
[13:09:15.715] <TB1> INFO: run 1 of 1
[13:09:16.310] <TB1> INFO: Expecting 208000 events.
[13:09:33.330] <TB1> INFO: 208000 events read in total (16493ms).
[13:09:33.343] <TB1> INFO: Test took 17627ms.
[13:09:33.460] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:09:33.743] <TB1> INFO: dacScan step from 45 .. 45
[13:09:33.743] <TB1> INFO: dacScan split into 1 runs with ntrig = 50
[13:09:33.743] <TB1> INFO: run 1 of 1
[13:09:34.338] <TB1> INFO: Expecting 208000 events.
[13:09:51.371] <TB1> INFO: 208000 events read in total (16506ms).
[13:09:51.383] <TB1> INFO: Test took 17640ms.
[13:09:51.485] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:09:51.830] <TB1> INFO: dacScan step from 46 .. 46
[13:09:51.830] <TB1> INFO: dacScan split into 1 runs with ntrig = 50
[13:09:51.830] <TB1> INFO: run 1 of 1
[13:09:52.439] <TB1> INFO: Expecting 208000 events.
[13:10:09.230] <TB1> INFO: 208000 events read in total (16264ms).
[13:10:09.240] <TB1> INFO: Test took 17409ms.
[13:10:09.337] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:10:09.634] <TB1> INFO: dacScan step from 47 .. 47
[13:10:09.634] <TB1> INFO: dacScan split into 1 runs with ntrig = 50
[13:10:09.634] <TB1> INFO: run 1 of 1
[13:10:10.229] <TB1> INFO: Expecting 208000 events.
[13:10:27.908] <TB1> INFO: 208000 events read in total (17152ms).
[13:10:27.918] <TB1> INFO: Test took 18283ms.
[13:10:28.024] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:10:28.305] <TB1> INFO: dacScan step from 48 .. 48
[13:10:28.305] <TB1> INFO: dacScan split into 1 runs with ntrig = 50
[13:10:28.305] <TB1> INFO: run 1 of 1
[13:10:28.918] <TB1> INFO: Expecting 208000 events.
[13:10:47.168] <TB1> INFO: 208000 events read in total (17723ms).
[13:10:47.178] <TB1> INFO: Test took 18872ms.
[13:10:47.279] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:10:47.560] <TB1> INFO: dacScan step from 49 .. 49
[13:10:47.560] <TB1> INFO: dacScan split into 1 runs with ntrig = 50
[13:10:47.560] <TB1> INFO: run 1 of 1
[13:10:48.156] <TB1> INFO: Expecting 208000 events.
[13:11:05.787] <TB1> INFO: 208000 events read in total (17104ms).
[13:11:05.798] <TB1> INFO: Test took 18237ms.
[13:11:05.898] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:11:06.210] <TB1> INFO: dacScan step from 50 .. 50
[13:11:06.210] <TB1> INFO: dacScan split into 1 runs with ntrig = 50
[13:11:06.210] <TB1> INFO: run 1 of 1
[13:11:06.806] <TB1> INFO: Expecting 208000 events.
[13:11:24.161] <TB1> INFO: 208000 events read in total (16828ms).
[13:11:24.172] <TB1> INFO: Test took 17961ms.
[13:11:24.269] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:11:24.561] <TB1> INFO: dacScan step from 51 .. 51
[13:11:24.561] <TB1> INFO: dacScan split into 1 runs with ntrig = 50
[13:11:24.561] <TB1> INFO: run 1 of 1
[13:11:25.157] <TB1> INFO: Expecting 208000 events.
[13:11:42.940] <TB1> INFO: 208000 events read in total (17256ms).
[13:11:42.951] <TB1> INFO: Test took 18390ms.
[13:11:43.048] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:11:43.329] <TB1> INFO: dacScan step from 52 .. 52
[13:11:43.329] <TB1> INFO: dacScan split into 1 runs with ntrig = 50
[13:11:43.329] <TB1> INFO: run 1 of 1
[13:11:43.921] <TB1> INFO: Expecting 208000 events.
[13:12:01.929] <TB1> INFO: 208000 events read in total (17481ms).
[13:12:01.939] <TB1> INFO: Test took 18609ms.
[13:12:02.053] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:12:02.396] <TB1> INFO: dacScan step from 53 .. 53
[13:12:02.397] <TB1> INFO: dacScan split into 1 runs with ntrig = 50
[13:12:02.397] <TB1> INFO: run 1 of 1
[13:12:03.006] <TB1> INFO: Expecting 208000 events.
[13:12:20.499] <TB1> INFO: 208000 events read in total (16966ms).
[13:12:20.509] <TB1> INFO: Test took 18112ms.
[13:12:20.606] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:12:20.892] <TB1> INFO: dacScan step from 54 .. 54
[13:12:20.892] <TB1> INFO: dacScan split into 1 runs with ntrig = 50
[13:12:20.892] <TB1> INFO: run 1 of 1
[13:12:21.488] <TB1> INFO: Expecting 208000 events.
[13:12:39.121] <TB1> INFO: 208000 events read in total (17106ms).
[13:12:39.131] <TB1> INFO: Test took 18238ms.
[13:12:39.226] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:12:39.507] <TB1> INFO: dacScan step from 55 .. 55
[13:12:39.508] <TB1> INFO: dacScan split into 1 runs with ntrig = 50
[13:12:39.508] <TB1> INFO: run 1 of 1
[13:12:40.100] <TB1> INFO: Expecting 208000 events.
[13:12:58.475] <TB1> INFO: 208000 events read in total (17848ms).
[13:12:58.485] <TB1> INFO: Test took 18977ms.
[13:12:58.583] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:12:58.873] <TB1> INFO: dacScan step from 56 .. 56
[13:12:58.873] <TB1> INFO: dacScan split into 1 runs with ntrig = 50
[13:12:58.873] <TB1> INFO: run 1 of 1
[13:12:59.469] <TB1> INFO: Expecting 208000 events.
[13:13:17.466] <TB1> INFO: 208000 events read in total (17469ms).
[13:13:17.477] <TB1> INFO: Test took 18603ms.
[13:13:17.576] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:13:17.878] <TB1> INFO: dacScan step from 57 .. 57
[13:13:17.884] <TB1> INFO: dacScan split into 1 runs with ntrig = 50
[13:13:17.884] <TB1> INFO: run 1 of 1
[13:13:18.484] <TB1> INFO: Expecting 208000 events.
[13:13:36.531] <TB1> INFO: 208000 events read in total (17519ms).
[13:13:36.542] <TB1> INFO: Test took 18657ms.
[13:13:36.638] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:13:36.926] <TB1> INFO: dacScan step from 58 .. 58
[13:13:36.926] <TB1> INFO: dacScan split into 1 runs with ntrig = 50
[13:13:36.927] <TB1> INFO: run 1 of 1
[13:13:37.550] <TB1> INFO: Expecting 208000 events.
[13:13:55.354] <TB1> INFO: 208000 events read in total (17277ms).
[13:13:55.365] <TB1> INFO: Test took 18438ms.
[13:13:55.460] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:13:55.803] <TB1> INFO: dacScan step from 59 .. 59
[13:13:55.803] <TB1> INFO: dacScan split into 1 runs with ntrig = 50
[13:13:55.803] <TB1> INFO: run 1 of 1
[13:13:56.422] <TB1> INFO: Expecting 208000 events.
[13:14:14.262] <TB1> INFO: 208000 events read in total (17313ms).
[13:14:14.273] <TB1> INFO: Test took 18469ms.
[13:14:14.377] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:14:14.682] <TB1> INFO: dacScan step from 60 .. 60
[13:14:14.682] <TB1> INFO: dacScan split into 1 runs with ntrig = 50
[13:14:14.682] <TB1> INFO: run 1 of 1
[13:14:15.274] <TB1> INFO: Expecting 208000 events.
[13:14:34.158] <TB1> INFO: 208000 events read in total (18357ms).
[13:14:34.169] <TB1> INFO: Test took 19487ms.
[13:14:34.268] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:14:34.558] <TB1> INFO: dacScan step from 61 .. 61
[13:14:34.558] <TB1> INFO: dacScan split into 1 runs with ntrig = 50
[13:14:34.558] <TB1> INFO: run 1 of 1
[13:14:35.193] <TB1> INFO: Expecting 208000 events.
[13:14:53.390] <TB1> INFO: 208000 events read in total (17669ms).
[13:14:53.401] <TB1> INFO: Test took 18842ms.
[13:14:53.500] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:14:53.806] <TB1> INFO: dacScan step from 62 .. 62
[13:14:53.806] <TB1> INFO: dacScan split into 1 runs with ntrig = 50
[13:14:53.806] <TB1> INFO: run 1 of 1
[13:14:54.432] <TB1> INFO: Expecting 208000 events.
[13:15:12.405] <TB1> INFO: 208000 events read in total (17445ms).
[13:15:12.419] <TB1> INFO: Test took 18613ms.
[13:15:12.522] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:15:12.927] <TB1> INFO: dacScan step from 63 .. 63
[13:15:12.927] <TB1> INFO: dacScan split into 1 runs with ntrig = 50
[13:15:12.928] <TB1> INFO: run 1 of 1
[13:15:13.536] <TB1> INFO: Expecting 208000 events.
[13:15:31.138] <TB1> INFO: 208000 events read in total (17074ms).
[13:15:31.148] <TB1> INFO: Test took 18220ms.
[13:15:31.245] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:15:31.550] <TB1> INFO: dacScan step from 64 .. 64
[13:15:31.550] <TB1> INFO: dacScan split into 1 runs with ntrig = 50
[13:15:31.550] <TB1> INFO: run 1 of 1
[13:15:32.149] <TB1> INFO: Expecting 208000 events.
[13:15:49.631] <TB1> INFO: 208000 events read in total (16955ms).
[13:15:49.642] <TB1> INFO: Test took 18091ms.
[13:15:49.740] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:15:50.039] <TB1> INFO: dacScan step from 65 .. 65
[13:15:50.039] <TB1> INFO: dacScan split into 1 runs with ntrig = 50
[13:15:50.039] <TB1> INFO: run 1 of 1
[13:15:50.637] <TB1> INFO: Expecting 208000 events.


[13:16:08.869] <TB1> INFO: 208000 events read in total (17705ms).
[13:16:08.880] <TB1> INFO: Test took 18840ms.
[13:16:08.975] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:16:09.259] <TB1> INFO: dacScan step from 66 .. 66
[13:16:09.260] <TB1> INFO: dacScan split into 1 runs with ntrig = 50
[13:16:09.260] <TB1> INFO: run 1 of 1
[13:16:09.858] <TB1> INFO: Expecting 208000 events.
[13:16:28.407] <TB1> INFO: 208000 events read in total (18021ms).
[13:16:28.418] <TB1> INFO: Test took 19158ms.
[13:16:28.517] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:16:28.831] <TB1> INFO: dacScan step from 67 .. 67
[13:16:28.831] <TB1> INFO: dacScan split into 1 runs with ntrig = 50
[13:16:28.831] <TB1> INFO: run 1 of 1
[13:16:29.431] <TB1> INFO: Expecting 208000 events.
[13:16:47.025] <TB1> INFO: 208000 events read in total (17067ms).
[13:16:47.036] <TB1> INFO: Test took 18204ms.
[13:16:47.133] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:16:47.423] <TB1> INFO: dacScan step from 68 .. 68
[13:16:47.423] <TB1> INFO: dacScan split into 1 runs with ntrig = 50
[13:16:47.423] <TB1> INFO: run 1 of 1
[13:16:48.020] <TB1> INFO: Expecting 208000 events.
[13:17:05.284] <TB1> INFO: 208000 events read in total (16737ms).
[13:17:05.295] <TB1> INFO: Test took 17871ms.
[13:17:05.389] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:17:05.704] <TB1> INFO: dacScan step from 69 .. 69
[13:17:05.704] <TB1> INFO: dacScan split into 1 runs with ntrig = 50
[13:17:05.704] <TB1> INFO: run 1 of 1
[13:17:06.300] <TB1> INFO: Expecting 208000 events.
[13:17:24.622] <TB1> INFO: 208000 events read in total (17794ms).
[13:17:24.634] <TB1> INFO: Test took 18929ms.
[13:17:24.731] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:17:25.024] <TB1> INFO: dacScan step from 70 .. 70
[13:17:25.024] <TB1> INFO: dacScan split into 1 runs with ntrig = 50
[13:17:25.024] <TB1> INFO: run 1 of 1
[13:17:25.617] <TB1> INFO: Expecting 208000 events.
[13:17:43.412] <TB1> INFO: 208000 events read in total (17267ms).
[13:17:43.423] <TB1> INFO: Test took 18398ms.
[13:17:43.617] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:17:43.906] <TB1> INFO: dacScan step from 71 .. 71
[13:17:43.906] <TB1> INFO: dacScan split into 1 runs with ntrig = 50
[13:17:43.906] <TB1> INFO: run 1 of 1
[13:17:44.503] <TB1> INFO: Expecting 208000 events.
[13:18:02.886] <TB1> INFO: 208000 events read in total (17856ms).
[13:18:02.898] <TB1> INFO: Test took 18991ms.
[13:18:02.996] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:18:03.292] <TB1> INFO: dacScan step from 72 .. 72
[13:18:03.292] <TB1> INFO: dacScan split into 1 runs with ntrig = 50
[13:18:03.292] <TB1> INFO: run 1 of 1
[13:18:03.892] <TB1> INFO: Expecting 208000 events.
[13:18:21.343] <TB1> INFO: 208000 events read in total (16923ms).
[13:18:21.354] <TB1> INFO: Test took 18061ms.
[13:18:21.450] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:18:21.764] <TB1> INFO: dacScan step from 73 .. 73
[13:18:21.764] <TB1> INFO: dacScan split into 1 runs with ntrig = 50
[13:18:21.764] <TB1> INFO: run 1 of 1
[13:18:22.365] <TB1> INFO: Expecting 208000 events.
[13:18:39.713] <TB1> INFO: 208000 events read in total (16821ms).
[13:18:39.725] <TB1> INFO: Test took 17960ms.
[13:18:39.821] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:18:40.107] <TB1> INFO: dacScan step from 74 .. 74
[13:18:40.107] <TB1> INFO: dacScan split into 1 runs with ntrig = 50
[13:18:40.107] <TB1> INFO: run 1 of 1
[13:18:40.703] <TB1> INFO: Expecting 208000 events.
[13:18:58.756] <TB1> INFO: 208000 events read in total (17526ms).
[13:18:58.767] <TB1> INFO: Test took 18659ms.
[13:18:58.864] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:18:59.149] <TB1> INFO: dacScan step from 75 .. 75
[13:18:59.149] <TB1> INFO: dacScan split into 1 runs with ntrig = 50
[13:18:59.149] <TB1> INFO: run 1 of 1
[13:18:59.740] <TB1> INFO: Expecting 208000 events.
[13:19:17.601] <TB1> INFO: 208000 events read in total (17334ms).
[13:19:17.612] <TB1> INFO: Test took 18462ms.
[13:19:17.708] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:19:17.992] <TB1> INFO: dacScan step from 76 .. 76
[13:19:17.992] <TB1> INFO: dacScan split into 1 runs with ntrig = 50
[13:19:17.992] <TB1> INFO: run 1 of 1
[13:19:18.588] <TB1> INFO: Expecting 208000 events.
[13:19:36.569] <TB1> INFO: 208000 events read in total (17453ms).
[13:19:36.579] <TB1> INFO: Test took 18586ms.
[13:19:36.677] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:19:36.978] <TB1> INFO: dacScan step from 77 .. 77
[13:19:36.978] <TB1> INFO: dacScan split into 1 runs with ntrig = 50
[13:19:36.978] <TB1> INFO: run 1 of 1
[13:19:37.581] <TB1> INFO: Expecting 208000 events.
[13:19:55.338] <TB1> INFO: 208000 events read in total (17229ms).
[13:19:55.349] <TB1> INFO: Test took 18370ms.
[13:19:55.449] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:19:55.762] <TB1> INFO: dacScan step from 78 .. 78
[13:19:55.762] <TB1> INFO: dacScan split into 1 runs with ntrig = 50
[13:19:55.762] <TB1> INFO: run 1 of 1
[13:19:56.385] <TB1> INFO: Expecting 208000 events.
[13:20:14.043] <TB1> INFO: 208000 events read in total (17130ms).
[13:20:14.053] <TB1> INFO: Test took 18290ms.
[13:20:14.148] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:20:14.431] <TB1> INFO: dacScan step from 79 .. 79
[13:20:14.431] <TB1> INFO: dacScan split into 1 runs with ntrig = 50
[13:20:14.431] <TB1> INFO: run 1 of 1
[13:20:15.025] <TB1> INFO: Expecting 208000 events.
[13:20:33.732] <TB1> INFO: 208000 events read in total (18180ms).
[13:20:33.742] <TB1> INFO: Test took 19310ms.
[13:20:33.846] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:20:34.179] <TB1> INFO: dacScan step from 80 .. 80
[13:20:34.179] <TB1> INFO: dacScan split into 1 runs with ntrig = 50
[13:20:34.179] <TB1> INFO: run 1 of 1
[13:20:34.774] <TB1> INFO: Expecting 208000 events.
[13:20:52.995] <TB1> INFO: 208000 events read in total (17693ms).
[13:20:53.005] <TB1> INFO: Test took 18825ms.
[13:20:53.102] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:20:53.390] <TB1> INFO: dacScan step from 81 .. 81
[13:20:53.390] <TB1> INFO: dacScan split into 1 runs with ntrig = 50
[13:20:53.390] <TB1> INFO: run 1 of 1
[13:20:53.989] <TB1> INFO: Expecting 208000 events.
[13:21:11.731] <TB1> INFO: 208000 events read in total (17214ms).
[13:21:11.740] <TB1> INFO: Test took 18349ms.
[13:21:11.838] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:21:12.127] <TB1> INFO: dacScan step from 82 .. 82
[13:21:12.127] <TB1> INFO: dacScan split into 1 runs with ntrig = 50
[13:21:12.127] <TB1> INFO: run 1 of 1
[13:21:12.726] <TB1> INFO: Expecting 208000 events.
[13:21:30.246] <TB1> INFO: 208000 events read in total (16992ms).
[13:21:30.255] <TB1> INFO: Test took 18127ms.
[13:21:30.352] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:21:30.641] <TB1> INFO: dacScan step from 83 .. 83
[13:21:30.641] <TB1> INFO: dacScan split into 1 runs with ntrig = 50
[13:21:30.642] <TB1> INFO: run 1 of 1
[13:21:31.247] <TB1> INFO: Expecting 208000 events.
[13:21:49.237] <TB1> INFO: 208000 events read in total (17462ms).
[13:21:49.251] <TB1> INFO: Test took 18608ms.
[13:21:49.360] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:21:49.714] <TB1> INFO: dacScan step from 84 .. 84
[13:21:49.714] <TB1> INFO: dacScan split into 1 runs with ntrig = 50
[13:21:49.714] <TB1> INFO: run 1 of 1
[13:21:50.317] <TB1> INFO: Expecting 208000 events.
[13:22:08.648] <TB1> INFO: 208000 events read in total (17804ms).
[13:22:08.659] <TB1> INFO: Test took 18944ms.
[13:22:08.763] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:22:09.056] <TB1> INFO: dacScan step from 85 .. 85
[13:22:09.057] <TB1> INFO: dacScan split into 1 runs with ntrig = 50
[13:22:09.057] <TB1> INFO: run 1 of 1
[13:22:09.666] <TB1> INFO: Expecting 208000 events.
[13:22:28.407] <TB1> INFO: 208000 events read in total (18214ms).
[13:22:28.417] <TB1> INFO: Test took 19360ms.
[13:22:28.520] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:22:28.959] <TB1> INFO: dacScan step from 86 .. 86
[13:22:28.959] <TB1> INFO: dacScan split into 1 runs with ntrig = 50
[13:22:28.959] <TB1> INFO: run 1 of 1
[13:22:29.578] <TB1> INFO: Expecting 208000 events.
[13:22:47.457] <TB1> INFO: 208000 events read in total (17350ms).
[13:22:47.467] <TB1> INFO: Test took 18507ms.
[13:22:47.567] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:22:47.865] <TB1> INFO: dacScan step from 87 .. 87
[13:22:47.865] <TB1> INFO: dacScan split into 1 runs with ntrig = 50
[13:22:47.874] <TB1> INFO: run 1 of 1
[13:22:48.474] <TB1> INFO: Expecting 208000 events.
[13:23:06.161] <TB1> INFO: 208000 events read in total (17159ms).
[13:23:06.171] <TB1> INFO: Test took 18296ms.
[13:23:06.270] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:23:06.556] <TB1> INFO: dacScan step from 88 .. 88
[13:23:06.556] <TB1> INFO: dacScan split into 1 runs with ntrig = 50
[13:23:06.556] <TB1> INFO: run 1 of 1
[13:23:07.190] <TB1> INFO: Expecting 208000 events.
[13:23:24.004] <TB1> INFO: 208000 events read in total (16287ms).
[13:23:24.016] <TB1> INFO: Test took 17459ms.
[13:23:24.117] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:23:24.425] <TB1> INFO: dacScan step from 89 .. 89
[13:23:24.425] <TB1> INFO: dacScan split into 1 runs with ntrig = 50
[13:23:24.426] <TB1> INFO: run 1 of 1
[13:23:25.030] <TB1> INFO: Expecting 208000 events.
[13:23:43.079] <TB1> INFO: 208000 events read in total (17522ms).
[13:23:43.089] <TB1> INFO: Test took 18663ms.
[13:23:43.189] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:23:43.471] <TB1> INFO: dacScan step from 90 .. 90
[13:23:43.471] <TB1> INFO: dacScan split into 1 runs with ntrig = 50
[13:23:43.471] <TB1> INFO: run 1 of 1
[13:23:44.066] <TB1> INFO: Expecting 208000 events.
[13:24:00.791] <TB1> INFO: 208000 events read in total (16198ms).
[13:24:00.801] <TB1> INFO: Test took 17329ms.
[13:24:00.900] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:24:01.182] <TB1> INFO: dacScan step from 91 .. 91
[13:24:01.182] <TB1> INFO: dacScan split into 1 runs with ntrig = 50
[13:24:01.182] <TB1> INFO: run 1 of 1
[13:24:01.775] <TB1> INFO: Expecting 208000 events.
[13:24:18.302] <TB1> INFO: 208000 events read in total (16000ms).
[13:24:18.313] <TB1> INFO: Test took 17130ms.
[13:24:18.411] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:24:18.685] <TB1> INFO: dacScan step from 92 .. 92
[13:24:18.685] <TB1> INFO: dacScan split into 1 runs with ntrig = 50
[13:24:18.685] <TB1> INFO: run 1 of 1
[13:24:19.272] <TB1> INFO: Expecting 208000 events.
[13:24:35.548] <TB1> INFO: 208000 events read in total (15749ms).
[13:24:35.559] <TB1> INFO: Test took 16873ms.
[13:24:35.657] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:24:35.936] <TB1> INFO: dacScan step from 93 .. 93
[13:24:35.936] <TB1> INFO: dacScan split into 1 runs with ntrig = 50
[13:24:35.936] <TB1> INFO: run 1 of 1
[13:24:36.528] <TB1> INFO: Expecting 208000 events.
[13:24:52.695] <TB1> INFO: 208000 events read in total (15640ms).
[13:24:52.705] <TB1> INFO: Test took 16768ms.
[13:24:52.802] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:24:53.078] <TB1> INFO: dacScan step from 94 .. 94
[13:24:53.078] <TB1> INFO: dacScan split into 1 runs with ntrig = 50
[13:24:53.078] <TB1> INFO: run 1 of 1
[13:24:53.666] <TB1> INFO: Expecting 208000 events.
[13:25:10.084] <TB1> INFO: 208000 events read in total (15891ms).
[13:25:10.094] <TB1> INFO: Test took 17015ms.
[13:25:10.192] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:25:10.473] <TB1> INFO: dacScan step from 95 .. 95
[13:25:10.473] <TB1> INFO: dacScan split into 1 runs with ntrig = 50
[13:25:10.473] <TB1> INFO: run 1 of 1
[13:25:11.065] <TB1> INFO: Expecting 208000 events.
[13:25:27.081] <TB1> INFO: 208000 events read in total (15489ms).
[13:25:27.092] <TB1> INFO: Test took 16618ms.
[13:25:27.189] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:25:27.463] <TB1> INFO: dacScan step from 96 .. 96
[13:25:27.463] <TB1> INFO: dacScan split into 1 runs with ntrig = 50
[13:25:27.463] <TB1> INFO: run 1 of 1
[13:25:28.051] <TB1> INFO: Expecting 208000 events.
[13:25:44.388] <TB1> INFO: 208000 events read in total (15810ms).
[13:25:44.398] <TB1> INFO: Test took 16934ms.
[13:25:44.498] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:25:44.781] <TB1> INFO: dacScan step from 97 .. 97
[13:25:44.781] <TB1> INFO: dacScan split into 1 runs with ntrig = 50
[13:25:44.781] <TB1> INFO: run 1 of 1
[13:25:45.374] <TB1> INFO: Expecting 208000 events.
[13:26:01.849] <TB1> INFO: 208000 events read in total (15948ms).
[13:26:01.859] <TB1> INFO: Test took 17077ms.
[13:26:01.957] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:26:02.234] <TB1> INFO: dacScan step from 98 .. 98
[13:26:02.234] <TB1> INFO: dacScan split into 1 runs with ntrig = 50
[13:26:02.234] <TB1> INFO: run 1 of 1
[13:26:02.821] <TB1> INFO: Expecting 208000 events.
[13:26:18.842] <TB1> INFO: 208000 events read in total (15493ms).
[13:26:18.853] <TB1> INFO: Test took 16618ms.
[13:26:18.950] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:26:19.223] <TB1> INFO: dacScan step from 99 .. 99
[13:26:19.223] <TB1> INFO: dacScan split into 1 runs with ntrig = 50
[13:26:19.223] <TB1> INFO: run 1 of 1
[13:26:19.810] <TB1> INFO: Expecting 208000 events.
[13:26:36.062] <TB1> INFO: 208000 events read in total (15724ms).
[13:26:36.073] <TB1> INFO: Test took 16849ms.
[13:26:36.176] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:26:36.455] <TB1> INFO: dacScan step from 100 .. 100
[13:26:36.455] <TB1> INFO: dacScan split into 1 runs with ntrig = 50
[13:26:36.455] <TB1> INFO: run 1 of 1
[13:26:37.045] <TB1> INFO: Expecting 208000 events.
[13:26:53.600] <TB1> INFO: 208000 events read in total (16028ms).
[13:26:53.611] <TB1> INFO: Test took 17155ms.
[13:26:53.711] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:26:53.989] <TB1> INFO: dumping ASCII scurve output file: XSCurveData
[13:26:55.340] <TB1> INFO: dumping ASCII scurve output file: XSCurveData
[13:26:56.608] <TB1> INFO: dumping ASCII scurve output file: XSCurveData
[13:26:57.910] <TB1> INFO: dumping ASCII scurve output file: XSCurveData
[13:26:59.249] <TB1> INFO: dumping ASCII scurve output file: XSCurveData
[13:27:00.583] <TB1> INFO: dumping ASCII scurve output file: XSCurveData
[13:27:02.004] <TB1> INFO: dumping ASCII scurve output file: XSCurveData
[13:27:03.400] <TB1> INFO: dumping ASCII scurve output file: XSCurveData
[13:27:04.815] <TB1> INFO: dumping ASCII scurve output file: XSCurveData
[13:27:06.176] <TB1> INFO: dumping ASCII scurve output file: XSCurveData
[13:27:07.626] <TB1> INFO: dumping ASCII scurve output file: XSCurveData
[13:27:09.016] <TB1> INFO: dumping ASCII scurve output file: XSCurveData
[13:27:10.374] <TB1> INFO: dumping ASCII scurve output file: XSCurveData
[13:27:11.765] <TB1> INFO: dumping ASCII scurve output file: XSCurveData
[13:27:13.032] <TB1> INFO: dumping ASCII scurve output file: XSCurveData
[13:27:14.305] <TB1> INFO: dumping ASCII scurve output file: XSCurveData
[13:27:15.577] <TB1> INFO: PixTest:: pg_setup set to default.
[13:27:15.578] <TB1> INFO: vcal mean: 39.51 39.74 38.38 41.66 42.27 44.94 44.13 43.63 43.45 44.14 42.76 42.41 42.20 38.66 39.22 38.72
[13:27:15.578] <TB1> INFO: vcal RMS: 1.55 1.36 1.13 1.55 1.59 1.60 1.62 1.61 1.64 1.54 1.61 1.71 1.45 1.30 1.30 1.30
[13:27:15.578] <TB1> INFO: number of X-ray hits detected: 222301 230614 195343 345327 418712 519681 467459 464167 460096 459325 419408 382805 370944 187732 215053 202637
[13:27:15.578] <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
[13:27:15.578] <TB1> INFO: X-ray hit rate [MHz/cm2]: 65.2 67.6 57.3 101.2 122.7 152.3 137.0 136.1 134.9 134.6 122.9 112.2 108.7 55.0 63.0 59.4
[13:27:15.578] <TB1> INFO: PixTestHighRate::doXNoiseMaps() done
[13:27:15.678] <TB1> INFO: enter test to run
[13:27:15.678] <TB1> INFO: test: no parameter change
[13:27:15.850] <TB1> QUIET: Connection to board 83 closed.
[13:27:15.930] <TB1> INFO: pXar: this is the end, my friend

[13:42:02.501] <TB1> INFO: *** Welcome to pxar ***
[13:42:02.501] <TB1> INFO: *** Today: 2015/08/28
[13:42:02.501] <TB1> INFO: readRocDacs: /usr/local/data/M2046_XrayQualification_2015-08-28_14h28m_1440764917//010_CalDelScanAndSaveDacs_4mA25kV//dacParameters35_C0.dat .. /usr/local/data/M2046_XrayQualification_2015-08-28_14h28m_1440764917//010_CalDelScanAndSaveDacs_4mA25kV//dacParameters35_C15.dat
[13:42:02.502] <TB1> INFO: readTbmDacs: /usr/local/data/M2046_XrayQualification_2015-08-28_14h28m_1440764917//010_CalDelScanAndSaveDacs_4mA25kV//tbmParameters_C0a.dat .. /usr/local/data/M2046_XrayQualification_2015-08-28_14h28m_1440764917//010_CalDelScanAndSaveDacs_4mA25kV//tbmParameters_C0b.dat
[13:42:02.502] <TB1> INFO: readMaskFile: /usr/local/data/M2046_XrayQualification_2015-08-28_14h28m_1440764917//010_CalDelScanAndSaveDacs_4mA25kV//defaultMaskFile.dat
[13:42:02.502] <TB1> INFO: readTrimFile: /usr/local/data/M2046_XrayQualification_2015-08-28_14h28m_1440764917//010_CalDelScanAndSaveDacs_4mA25kV//trimParameters35_C0.dat .. /usr/local/data/M2046_XrayQualification_2015-08-28_14h28m_1440764917//010_CalDelScanAndSaveDacs_4mA25kV//trimParameters35_C15.dat
[13:42:02.570] <TB1> INFO: clk: 4
[13:42:02.570] <TB1> INFO: ctr: 4
[13:42:02.570] <TB1> INFO: sda: 19
[13:42:02.570] <TB1> INFO: tin: 9
[13:42:02.570] <TB1> INFO: level: 15
[13:42:02.570] <TB1> INFO: triggerdelay: 0
[13:42:02.570] <TB1> QUIET: Instanciating API for pxar v2.5+57~g44d0777
[13:42:02.570] <TB1> INFO: Log level: INFO
[13:42:02.582] <TB1> INFO: Found DTB DTB_WS6UZO
[13:42:02.592] <TB1> QUIET: Connection to board DTB_WS6UZO opened.
[13:42:02.595] <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:
------------------------------------------------------
[13:42:02.597] <TB1> INFO: RPC call hashes of host and DTB match: 397073690
[13:42:04.109] <TB1> INFO: DUT info:
[13:42:04.109] <TB1> INFO: The DUT currently contains the following objects:
[13:42:04.109] <TB1> INFO: 2 TBM Cores tbm09c (2 ON)
[13:42:04.109] <TB1> INFO: TBM Core alpha (0): 7 registers set
[13:42:04.109] <TB1> INFO: TBM Core beta (1): 7 registers set
[13:42:04.109] <TB1> INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[13:42:04.109] <TB1> INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[13:42:04.109] <TB1> INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[13:42:04.109] <TB1> INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[13:42:04.109] <TB1> INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[13:42:04.109] <TB1> INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[13:42:04.109] <TB1> INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[13:42:04.109] <TB1> INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[13:42:04.109] <TB1> INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[13:42:04.109] <TB1> INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[13:42:04.109] <TB1> INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[13:42:04.109] <TB1> INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[13:42:04.109] <TB1> INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[13:42:04.109] <TB1> INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[13:42:04.109] <TB1> INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[13:42:04.109] <TB1> INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[13:42:04.109] <TB1> INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[13:42:04.510] <TB1> INFO: enter 'restricted' command line mode
[13:42:04.510] <TB1> INFO: enter test to run
[13:42:04.510] <TB1> INFO: test: HighRate no parameter change
[13:42:04.510] <TB1> INFO: running: highrate
[13:42:04.510] <TB1> INFO: readGainPedestalParameters /usr/local/data/M2046_XrayQualification_2015-08-28_14h28m_1440764917//010_CalDelScanAndSaveDacs_4mA25kV//phCalibrationFitErr35_C0.dat .. /usr/local/data/M2046_XrayQualification_2015-08-28_14h28m_1440764917//010_CalDelScanAndSaveDacs_4mA25kV//phCalibrationFitErr35_C15.dat
[13:42:04.636] <TB1> INFO: ----------------------------------------------------------------------
[13:42:04.636] <TB1> INFO: PixTestHighRate::calDelScan() ntrig = 10, vcal = 200
[13:42:04.636] <TB1> INFO: ----------------------------------------------------------------------
[13:42:04.774] <TB1> INFO: Expecting 768 events.
[13:42:06.001] <TB1> INFO: 768 events read in total (508ms).
[13:42:06.096] <TB1> INFO: Test took 1454ms.
[13:42:06.711] <TB1> INFO: Expecting 41600 events.
[13:42:09.940] <TB1> INFO: 41600 events read in total (2701ms).
[13:42:09.942] <TB1> INFO: Test took 3842ms.
[13:42:10.027] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:42:10.669] <TB1> INFO: Expecting 41600 events.
[13:42:14.143] <TB1> INFO: 41600 events read in total (2947ms).
[13:42:14.145] <TB1> INFO: Test took 4088ms.
[13:42:14.231] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:42:14.888] <TB1> INFO: Expecting 41600 events.
[13:42:18.380] <TB1> INFO: 41600 events read in total (2964ms).
[13:42:18.382] <TB1> INFO: Test took 4106ms.
[13:42:18.468] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:42:19.112] <TB1> INFO: Expecting 41600 events.
[13:42:22.644] <TB1> INFO: 41600 events read in total (3005ms).
[13:42:22.645] <TB1> INFO: Test took 4146ms.
[13:42:22.749] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:42:23.435] <TB1> INFO: Expecting 41600 events.
[13:42:26.962] <TB1> INFO: 41600 events read in total (2999ms).
[13:42:26.964] <TB1> INFO: Test took 4167ms.
[13:42:27.054] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:42:27.701] <TB1> INFO: Expecting 41600 events.
[13:42:31.302] <TB1> INFO: 41600 events read in total (3073ms).
[13:42:31.304] <TB1> INFO: Test took 4216ms.
[13:42:31.398] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:42:32.047] <TB1> INFO: Expecting 41600 events.
[13:42:35.591] <TB1> INFO: 41600 events read in total (3017ms).
[13:42:35.593] <TB1> INFO: Test took 4161ms.
[13:42:35.701] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:42:36.399] <TB1> INFO: Expecting 41600 events.
[13:42:40.059] <TB1> INFO: 41600 events read in total (3132ms).
[13:42:40.062] <TB1> INFO: Test took 4310ms.
[13:42:40.172] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:42:40.822] <TB1> INFO: Expecting 41600 events.
[13:42:44.400] <TB1> INFO: 41600 events read in total (3051ms).
[13:42:44.403] <TB1> INFO: Test took 4194ms.
[13:42:44.489] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:42:45.133] <TB1> INFO: Expecting 41600 events.
[13:42:48.659] <TB1> INFO: 41600 events read in total (2998ms).
[13:42:48.661] <TB1> INFO: Test took 4141ms.
[13:42:48.751] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:42:49.396] <TB1> INFO: Expecting 41600 events.
[13:42:52.972] <TB1> INFO: 41600 events read in total (3049ms).
[13:42:52.974] <TB1> INFO: Test took 4191ms.
[13:42:53.064] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:42:53.712] <TB1> INFO: Expecting 41600 events.
[13:42:57.252] <TB1> INFO: 41600 events read in total (3013ms).
[13:42:57.254] <TB1> INFO: Test took 4155ms.
[13:42:57.340] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:42:58.020] <TB1> INFO: Expecting 41600 events.
[13:43:01.747] <TB1> INFO: 41600 events read in total (3200ms).
[13:43:01.749] <TB1> INFO: Test took 4378ms.
[13:43:01.836] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:43:02.484] <TB1> INFO: Expecting 41600 events.
[13:43:06.084] <TB1> INFO: 41600 events read in total (3073ms).
[13:43:06.086] <TB1> INFO: Test took 4218ms.
[13:43:06.173] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:43:06.818] <TB1> INFO: Expecting 41600 events.
[13:43:10.446] <TB1> INFO: 41600 events read in total (3101ms).
[13:43:10.448] <TB1> INFO: Test took 4243ms.
[13:43:10.546] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:43:11.192] <TB1> INFO: Expecting 41600 events.
[13:43:14.783] <TB1> INFO: 41600 events read in total (3064ms).
[13:43:14.785] <TB1> INFO: Test took 4206ms.
[13:43:14.870] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:43:15.514] <TB1> INFO: Expecting 41600 events.
[13:43:19.208] <TB1> INFO: 41600 events read in total (3166ms).
[13:43:19.210] <TB1> INFO: Test took 4308ms.
[13:43:19.297] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:43:19.943] <TB1> INFO: Expecting 41600 events.
[13:43:23.624] <TB1> INFO: 41600 events read in total (3153ms).
[13:43:23.626] <TB1> INFO: Test took 4296ms.
[13:43:23.712] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:43:24.358] <TB1> INFO: Expecting 41600 events.
[13:43:27.980] <TB1> INFO: 41600 events read in total (3095ms).
[13:43:27.982] <TB1> INFO: Test took 4237ms.
[13:43:28.071] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:43:28.716] <TB1> INFO: Expecting 41600 events.
[13:43:32.149] <TB1> INFO: 41600 events read in total (2906ms).
[13:43:32.151] <TB1> INFO: Test took 4049ms.
[13:43:32.240] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:43:32.550] <TB1> INFO: enter test to run
[13:43:32.550] <TB1> INFO: test: Pretest no parameter change
[13:43:32.550] <TB1> INFO: running: pretest
[13:43:32.551] <TB1> INFO: write dac parameters into /usr/local/data/M2046_XrayQualification_2015-08-28_14h28m_1440764917//010_CalDelScanAndSaveDacs_4mA25kV//dacParameters35_C0.dat
[13:43:32.551] <TB1> INFO: write dac parameters into /usr/local/data/M2046_XrayQualification_2015-08-28_14h28m_1440764917//010_CalDelScanAndSaveDacs_4mA25kV//dacParameters35_C1.dat
[13:43:32.551] <TB1> INFO: write dac parameters into /usr/local/data/M2046_XrayQualification_2015-08-28_14h28m_1440764917//010_CalDelScanAndSaveDacs_4mA25kV//dacParameters35_C2.dat
[13:43:32.551] <TB1> INFO: write dac parameters into /usr/local/data/M2046_XrayQualification_2015-08-28_14h28m_1440764917//010_CalDelScanAndSaveDacs_4mA25kV//dacParameters35_C3.dat
[13:43:32.551] <TB1> INFO: write dac parameters into /usr/local/data/M2046_XrayQualification_2015-08-28_14h28m_1440764917//010_CalDelScanAndSaveDacs_4mA25kV//dacParameters35_C4.dat
[13:43:32.551] <TB1> INFO: write dac parameters into /usr/local/data/M2046_XrayQualification_2015-08-28_14h28m_1440764917//010_CalDelScanAndSaveDacs_4mA25kV//dacParameters35_C5.dat
[13:43:32.551] <TB1> INFO: write dac parameters into /usr/local/data/M2046_XrayQualification_2015-08-28_14h28m_1440764917//010_CalDelScanAndSaveDacs_4mA25kV//dacParameters35_C6.dat
[13:43:32.551] <TB1> INFO: write dac parameters into /usr/local/data/M2046_XrayQualification_2015-08-28_14h28m_1440764917//010_CalDelScanAndSaveDacs_4mA25kV//dacParameters35_C7.dat
[13:43:32.551] <TB1> INFO: write dac parameters into /usr/local/data/M2046_XrayQualification_2015-08-28_14h28m_1440764917//010_CalDelScanAndSaveDacs_4mA25kV//dacParameters35_C8.dat
[13:43:32.551] <TB1> INFO: write dac parameters into /usr/local/data/M2046_XrayQualification_2015-08-28_14h28m_1440764917//010_CalDelScanAndSaveDacs_4mA25kV//dacParameters35_C9.dat
[13:43:32.552] <TB1> INFO: write dac parameters into /usr/local/data/M2046_XrayQualification_2015-08-28_14h28m_1440764917//010_CalDelScanAndSaveDacs_4mA25kV//dacParameters35_C10.dat
[13:43:32.552] <TB1> INFO: write dac parameters into /usr/local/data/M2046_XrayQualification_2015-08-28_14h28m_1440764917//010_CalDelScanAndSaveDacs_4mA25kV//dacParameters35_C11.dat
[13:43:32.552] <TB1> INFO: write dac parameters into /usr/local/data/M2046_XrayQualification_2015-08-28_14h28m_1440764917//010_CalDelScanAndSaveDacs_4mA25kV//dacParameters35_C12.dat
[13:43:32.552] <TB1> INFO: write dac parameters into /usr/local/data/M2046_XrayQualification_2015-08-28_14h28m_1440764917//010_CalDelScanAndSaveDacs_4mA25kV//dacParameters35_C13.dat
[13:43:32.552] <TB1> INFO: write dac parameters into /usr/local/data/M2046_XrayQualification_2015-08-28_14h28m_1440764917//010_CalDelScanAndSaveDacs_4mA25kV//dacParameters35_C14.dat
[13:43:32.552] <TB1> INFO: write dac parameters into /usr/local/data/M2046_XrayQualification_2015-08-28_14h28m_1440764917//010_CalDelScanAndSaveDacs_4mA25kV//dacParameters35_C15.dat
[13:43:32.552] <TB1> INFO: enter test to run
[13:43:32.552] <TB1> INFO: test: no parameter change
[13:43:32.740] <TB1> QUIET: Connection to board 83 closed.
[13:43:32.820] <TB1> INFO: pXar: this is the end, my friend

[13:43:46.677] <TB1> INFO: *** Welcome to pxar ***
[13:43:46.677] <TB1> INFO: *** Today: 2015/08/28
[13:43:46.677] <TB1> INFO: readRocDacs: /usr/local/data/M2046_XrayQualification_2015-08-28_14h28m_1440764917//011_HREfficiency_50//dacParameters35_C0.dat .. /usr/local/data/M2046_XrayQualification_2015-08-28_14h28m_1440764917//011_HREfficiency_50//dacParameters35_C15.dat
[13:43:46.677] <TB1> INFO: readTbmDacs: /usr/local/data/M2046_XrayQualification_2015-08-28_14h28m_1440764917//011_HREfficiency_50//tbmParameters_C0a.dat .. /usr/local/data/M2046_XrayQualification_2015-08-28_14h28m_1440764917//011_HREfficiency_50//tbmParameters_C0b.dat
[13:43:46.677] <TB1> INFO: readMaskFile: /usr/local/data/M2046_XrayQualification_2015-08-28_14h28m_1440764917//011_HREfficiency_50//defaultMaskFile.dat
[13:43:46.677] <TB1> INFO: readTrimFile: /usr/local/data/M2046_XrayQualification_2015-08-28_14h28m_1440764917//011_HREfficiency_50//trimParameters35_C0.dat .. /usr/local/data/M2046_XrayQualification_2015-08-28_14h28m_1440764917//011_HREfficiency_50//trimParameters35_C15.dat
[13:43:46.744] <TB1> INFO: clk: 4
[13:43:46.744] <TB1> INFO: ctr: 4
[13:43:46.744] <TB1> INFO: sda: 19
[13:43:46.744] <TB1> INFO: tin: 9
[13:43:46.744] <TB1> INFO: level: 15
[13:43:46.744] <TB1> INFO: triggerdelay: 0
[13:43:46.744] <TB1> QUIET: Instanciating API for pxar v2.5+57~g44d0777
[13:43:46.744] <TB1> INFO: Log level: INFO
[13:43:46.756] <TB1> INFO: Found DTB DTB_WS6UZO
[13:43:46.766] <TB1> QUIET: Connection to board DTB_WS6UZO opened.
[13:43:46.769] <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:
------------------------------------------------------
[13:43:46.771] <TB1> INFO: RPC call hashes of host and DTB match: 397073690
[13:43:48.284] <TB1> INFO: DUT info:
[13:43:48.284] <TB1> INFO: The DUT currently contains the following objects:
[13:43:48.284] <TB1> INFO: 2 TBM Cores tbm09c (2 ON)
[13:43:48.284] <TB1> INFO: TBM Core alpha (0): 7 registers set
[13:43:48.284] <TB1> INFO: TBM Core beta (1): 7 registers set
[13:43:48.284] <TB1> INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[13:43:48.284] <TB1> INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[13:43:48.284] <TB1> INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[13:43:48.284] <TB1> INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[13:43:48.284] <TB1> INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[13:43:48.284] <TB1> INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[13:43:48.284] <TB1> INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[13:43:48.284] <TB1> INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[13:43:48.284] <TB1> INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[13:43:48.284] <TB1> INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[13:43:48.284] <TB1> INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[13:43:48.284] <TB1> INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[13:43:48.284] <TB1> INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[13:43:48.284] <TB1> INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[13:43:48.284] <TB1> INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[13:43:48.284] <TB1> INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[13:43:48.284] <TB1> INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[13:43:48.685] <TB1> INFO: enter 'restricted' command line mode
[13:43:48.685] <TB1> INFO: enter test to run
[13:43:48.685] <TB1> INFO: test: HighRate no parameter change
[13:43:48.685] <TB1> INFO: running: highrate
[13:43:48.685] <TB1> INFO: readGainPedestalParameters /usr/local/data/M2046_XrayQualification_2015-08-28_14h28m_1440764917//011_HREfficiency_50//phCalibrationFitErr35_C0.dat .. /usr/local/data/M2046_XrayQualification_2015-08-28_14h28m_1440764917//011_HREfficiency_50//phCalibrationFitErr35_C15.dat
[13:43:48.811] <TB1> INFO: ----------------------------------------------------------------------
[13:43:48.811] <TB1> INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[13:43:48.811] <TB1> INFO: ----------------------------------------------------------------------
[13:43:49.404] <TB1> INFO: Expecting 208000 events.
[13:44:01.896] <TB1> INFO: 208000 events read in total (11962ms).
[13:44:01.902] <TB1> INFO: Test took 13085ms.
[13:44:02.216] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:44:02.537] <TB1> INFO: number of dead pixels (per ROC): 2 0 0 0 1 0 0 0 0 0 0 0 0 0 0 0
[13:44:02.537] <TB1> INFO: number of red-efficiency pixels: 146 133 141 270 387 538 469 445 390 428 341 346 322 133 150 160
[13:44:02.537] <TB1> INFO: number of X-ray hits detected: 111221 113076 94148 174063 210572 264462 237625 234646 230407 230775 212459 191908 185042 90182 105829 100327
[13:44:02.537] <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
[13:44:02.537] <TB1> INFO: number of Vcal hits detected: 207752 207863 207854 207712 207538 207408 207489 207519 207591 207532 207637 207628 207658 207864 207848 207835
[13:44:02.537] <TB1> INFO: Vcal hit fiducial efficiency (%): 99.9 99.9 99.9 99.9 99.8 99.7 99.8 99.8 99.8 99.8 99.8 99.8 99.9 99.9 99.9 99.9
[13:44:02.537] <TB1> INFO: Vcal hit overall efficiency (%): 99.9 99.9 99.9 99.9 99.8 99.7 99.8 99.8 99.8 99.8 99.8 99.8 99.8 99.9 99.9 99.9
[13:44:02.537] <TB1> INFO: X-ray hit rate [MHz/cm2]: 32.6 33.1 27.6 51.0 61.7 77.5 69.6 68.8 67.5 67.6 62.3 56.2 54.2 26.4 31.0 29.4
[13:44:02.537] <TB1> INFO: PixTestHighRate::doXPixelAlive() done
[13:44:02.573] <TB1> INFO: PixTest:: pg_setup set to default.
[13:44:02.614] <TB1> INFO: enter test to run
[13:44:02.614] <TB1> INFO: test: no parameter change
[13:44:02.739] <TB1> QUIET: Connection to board 83 closed.
[13:44:02.820] <TB1> INFO: pXar: this is the end, my friend

[13:44:14.903] <TB1> INFO: *** Welcome to pxar ***
[13:44:14.903] <TB1> INFO: *** Today: 2015/08/28
[13:44:14.904] <TB1> INFO: readRocDacs: /usr/local/data/M2046_XrayQualification_2015-08-28_14h28m_1440764917//012_HREfficiency_100//dacParameters35_C0.dat .. /usr/local/data/M2046_XrayQualification_2015-08-28_14h28m_1440764917//012_HREfficiency_100//dacParameters35_C15.dat
[13:44:14.904] <TB1> INFO: readTbmDacs: /usr/local/data/M2046_XrayQualification_2015-08-28_14h28m_1440764917//012_HREfficiency_100//tbmParameters_C0a.dat .. /usr/local/data/M2046_XrayQualification_2015-08-28_14h28m_1440764917//012_HREfficiency_100//tbmParameters_C0b.dat
[13:44:14.904] <TB1> INFO: readMaskFile: /usr/local/data/M2046_XrayQualification_2015-08-28_14h28m_1440764917//012_HREfficiency_100//defaultMaskFile.dat
[13:44:14.904] <TB1> INFO: readTrimFile: /usr/local/data/M2046_XrayQualification_2015-08-28_14h28m_1440764917//012_HREfficiency_100//trimParameters35_C0.dat .. /usr/local/data/M2046_XrayQualification_2015-08-28_14h28m_1440764917//012_HREfficiency_100//trimParameters35_C15.dat
[13:44:14.970] <TB1> INFO: clk: 4
[13:44:14.970] <TB1> INFO: ctr: 4
[13:44:14.970] <TB1> INFO: sda: 19
[13:44:14.970] <TB1> INFO: tin: 9
[13:44:14.970] <TB1> INFO: level: 15
[13:44:14.970] <TB1> INFO: triggerdelay: 0
[13:44:14.970] <TB1> QUIET: Instanciating API for pxar v2.5+57~g44d0777
[13:44:14.970] <TB1> INFO: Log level: INFO
[13:44:14.983] <TB1> INFO: Found DTB DTB_WS6UZO
[13:44:14.993] <TB1> QUIET: Connection to board DTB_WS6UZO opened.
[13:44:14.996] <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:
------------------------------------------------------
[13:44:14.998] <TB1> INFO: RPC call hashes of host and DTB match: 397073690
[13:44:16.509] <TB1> INFO: DUT info:
[13:44:16.509] <TB1> INFO: The DUT currently contains the following objects:
[13:44:16.509] <TB1> INFO: 2 TBM Cores tbm09c (2 ON)
[13:44:16.509] <TB1> INFO: TBM Core alpha (0): 7 registers set
[13:44:16.509] <TB1> INFO: TBM Core beta (1): 7 registers set
[13:44:16.509] <TB1> INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[13:44:16.509] <TB1> INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[13:44:16.509] <TB1> INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[13:44:16.509] <TB1> INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[13:44:16.509] <TB1> INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[13:44:16.509] <TB1> INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[13:44:16.509] <TB1> INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[13:44:16.509] <TB1> INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[13:44:16.509] <TB1> INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[13:44:16.509] <TB1> INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[13:44:16.509] <TB1> INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[13:44:16.509] <TB1> INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[13:44:16.509] <TB1> INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[13:44:16.509] <TB1> INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[13:44:16.509] <TB1> INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[13:44:16.509] <TB1> INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[13:44:16.509] <TB1> INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[13:44:16.910] <TB1> INFO: enter 'restricted' command line mode
[13:44:16.910] <TB1> INFO: enter test to run
[13:44:16.910] <TB1> INFO: test: HighRate no parameter change
[13:44:16.910] <TB1> INFO: running: highrate
[13:44:16.910] <TB1> INFO: readGainPedestalParameters /usr/local/data/M2046_XrayQualification_2015-08-28_14h28m_1440764917//012_HREfficiency_100//phCalibrationFitErr35_C0.dat .. /usr/local/data/M2046_XrayQualification_2015-08-28_14h28m_1440764917//012_HREfficiency_100//phCalibrationFitErr35_C15.dat
[13:44:17.037] <TB1> INFO: ----------------------------------------------------------------------
[13:44:17.037] <TB1> INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[13:44:17.037] <TB1> INFO: ----------------------------------------------------------------------
[13:44:17.630] <TB1> INFO: Expecting 208000 events.
[13:44:34.634] <TB1> INFO: 208000 events read in total (16474ms).
[13:44:34.645] <TB1> INFO: Test took 17602ms.
[13:44:35.243] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:44:35.676] <TB1> INFO: number of dead pixels (per ROC): 2 0 0 0 1 0 0 0 0 0 0 0 0 0 0 0
[13:44:35.676] <TB1> INFO: number of red-efficiency pixels: 434 441 504 939 1505 1961 1708 1693 1675 1679 1401 1196 1163 422 465 425
[13:44:35.676] <TB1> INFO: number of X-ray hits detected: 224033 230842 195286 346840 419414 521069 469087 466161 459513 456711 421152 383257 370244 186129 216076 202671
[13:44:35.677] <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
[13:44:35.677] <TB1> INFO: number of Vcal hits detected: 207433 207533 207359 206819 205730 204782 205305 205590 205648 205508 206083 206325 206469 207497 207506 207537
[13:44:35.677] <TB1> INFO: Vcal hit fiducial efficiency (%): 99.8 99.8 99.7 99.5 99.0 98.7 98.9 98.9 99.0 98.9 99.2 99.3 99.3 99.8 99.8 99.8
[13:44:35.677] <TB1> INFO: Vcal hit overall efficiency (%): 99.7 99.8 99.7 99.4 98.9 98.5 98.7 98.8 98.9 98.8 99.1 99.2 99.3 99.8 99.8 99.8
[13:44:35.677] <TB1> INFO: X-ray hit rate [MHz/cm2]: 65.7 67.7 57.2 101.7 122.9 152.7 137.5 136.6 134.7 133.9 123.4 112.3 108.5 54.6 63.3 59.4
[13:44:35.677] <TB1> INFO: PixTestHighRate::doXPixelAlive() done
[13:44:35.712] <TB1> INFO: PixTest:: pg_setup set to default.
[13:44:35.758] <TB1> INFO: enter test to run
[13:44:35.758] <TB1> INFO: test: no parameter change
[13:44:35.912] <TB1> QUIET: Connection to board 83 closed.
[13:44:35.992] <TB1> INFO: pXar: this is the end, my friend

[13:44:49.982] <TB1> INFO: *** Welcome to pxar ***
[13:44:49.982] <TB1> INFO: *** Today: 2015/08/28
[13:44:49.982] <TB1> INFO: readRocDacs: /usr/local/data/M2046_XrayQualification_2015-08-28_14h28m_1440764917//013_HREfficiency_150//dacParameters35_C0.dat .. /usr/local/data/M2046_XrayQualification_2015-08-28_14h28m_1440764917//013_HREfficiency_150//dacParameters35_C15.dat
[13:44:49.982] <TB1> INFO: readTbmDacs: /usr/local/data/M2046_XrayQualification_2015-08-28_14h28m_1440764917//013_HREfficiency_150//tbmParameters_C0a.dat .. /usr/local/data/M2046_XrayQualification_2015-08-28_14h28m_1440764917//013_HREfficiency_150//tbmParameters_C0b.dat
[13:44:49.983] <TB1> INFO: readMaskFile: /usr/local/data/M2046_XrayQualification_2015-08-28_14h28m_1440764917//013_HREfficiency_150//defaultMaskFile.dat
[13:44:49.983] <TB1> INFO: readTrimFile: /usr/local/data/M2046_XrayQualification_2015-08-28_14h28m_1440764917//013_HREfficiency_150//trimParameters35_C0.dat .. /usr/local/data/M2046_XrayQualification_2015-08-28_14h28m_1440764917//013_HREfficiency_150//trimParameters35_C15.dat
[13:44:50.049] <TB1> INFO: clk: 4
[13:44:50.049] <TB1> INFO: ctr: 4
[13:44:50.049] <TB1> INFO: sda: 19
[13:44:50.049] <TB1> INFO: tin: 9
[13:44:50.050] <TB1> INFO: level: 15
[13:44:50.050] <TB1> INFO: triggerdelay: 0
[13:44:50.050] <TB1> QUIET: Instanciating API for pxar v2.5+57~g44d0777
[13:44:50.050] <TB1> INFO: Log level: INFO
[13:44:50.063] <TB1> INFO: Found DTB DTB_WS6UZO
[13:44:50.073] <TB1> QUIET: Connection to board DTB_WS6UZO opened.
[13:44:50.076] <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:
------------------------------------------------------
[13:44:50.079] <TB1> INFO: RPC call hashes of host and DTB match: 397073690
[13:44:51.591] <TB1> INFO: DUT info:
[13:44:51.591] <TB1> INFO: The DUT currently contains the following objects:
[13:44:51.591] <TB1> INFO: 2 TBM Cores tbm09c (2 ON)
[13:44:51.591] <TB1> INFO: TBM Core alpha (0): 7 registers set
[13:44:51.591] <TB1> INFO: TBM Core beta (1): 7 registers set
[13:44:51.591] <TB1> INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[13:44:51.591] <TB1> INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[13:44:51.591] <TB1> INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[13:44:51.591] <TB1> INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[13:44:51.591] <TB1> INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[13:44:51.591] <TB1> INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[13:44:51.591] <TB1> INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[13:44:51.591] <TB1> INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[13:44:51.591] <TB1> INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[13:44:51.591] <TB1> INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[13:44:51.591] <TB1> INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[13:44:51.591] <TB1> INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[13:44:51.591] <TB1> INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[13:44:51.591] <TB1> INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[13:44:51.591] <TB1> INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[13:44:51.591] <TB1> INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[13:44:51.591] <TB1> INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[13:44:51.992] <TB1> INFO: enter 'restricted' command line mode
[13:44:51.992] <TB1> INFO: enter test to run
[13:44:51.992] <TB1> INFO: test: HighRate no parameter change
[13:44:51.992] <TB1> INFO: running: highrate
[13:44:51.992] <TB1> INFO: readGainPedestalParameters /usr/local/data/M2046_XrayQualification_2015-08-28_14h28m_1440764917//013_HREfficiency_150//phCalibrationFitErr35_C0.dat .. /usr/local/data/M2046_XrayQualification_2015-08-28_14h28m_1440764917//013_HREfficiency_150//phCalibrationFitErr35_C15.dat
[13:44:52.123] <TB1> INFO: ----------------------------------------------------------------------
[13:44:52.123] <TB1> INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[13:44:52.123] <TB1> INFO: ----------------------------------------------------------------------
[13:44:52.718] <TB1> INFO: Expecting 208000 events.
[13:45:14.427] <TB1> INFO: 208000 events read in total (21179ms).
[13:45:14.447] <TB1> INFO: Test took 22319ms.
[13:45:15.297] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:45:15.829] <TB1> INFO: number of dead pixels (per ROC): 2 0 0 0 1 0 0 0 0 0 0 0 0 0 0 0
[13:45:15.829] <TB1> INFO: number of red-efficiency pixels: 904 881 897 1934 2701 3399 3087 3096 3060 3032 2620 2352 2217 839 1035 934
[13:45:15.829] <TB1> INFO: number of X-ray hits detected: 323786 334767 285824 496505 603487 745642 672949 664039 663437 658618 600013 549765 534426 273735 315672 292570
[13:45:15.829] <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
[13:45:15.829] <TB1> INFO: number of Vcal hits detected: 206835 206959 206428 204779 201926 198396 200279 201308 201035 200563 202654 203387 204109 206658 206760 206850
[13:45:15.829] <TB1> INFO: Vcal hit fiducial efficiency (%): 99.5 99.5 99.4 98.7 97.5 95.9 96.8 97.1 97.0 96.9 97.8 98.1 98.3 99.5 99.5 99.5
[13:45:15.829] <TB1> INFO: Vcal hit overall efficiency (%): 99.4 99.5 99.2 98.5 97.1 95.4 96.3 96.8 96.7 96.4 97.4 97.8 98.1 99.4 99.4 99.4
[13:45:15.829] <TB1> INFO: X-ray hit rate [MHz/cm2]: 94.9 98.1 83.8 145.5 176.9 218.6 197.2 194.6 194.5 193.0 175.9 161.1 156.6 80.2 92.5 85.8
[13:45:15.829] <TB1> INFO: PixTestHighRate::doXPixelAlive() done
[13:45:15.864] <TB1> INFO: PixTest:: pg_setup set to default.
[13:45:15.900] <TB1> INFO: enter test to run
[13:45:15.900] <TB1> INFO: test: no parameter change
[13:45:16.086] <TB1> QUIET: Connection to board 83 closed.
[13:45:16.174] <TB1> INFO: pXar: this is the end, my friend

[13:45:29.323] <TB1> INFO: *** Welcome to pxar ***
[13:45:29.323] <TB1> INFO: *** Today: 2015/08/28
[13:45:29.323] <TB1> INFO: readRocDacs: /usr/local/data/M2046_XrayQualification_2015-08-28_14h28m_1440764917//014_HREfficiency_200//dacParameters35_C0.dat .. /usr/local/data/M2046_XrayQualification_2015-08-28_14h28m_1440764917//014_HREfficiency_200//dacParameters35_C15.dat
[13:45:29.323] <TB1> INFO: readTbmDacs: /usr/local/data/M2046_XrayQualification_2015-08-28_14h28m_1440764917//014_HREfficiency_200//tbmParameters_C0a.dat .. /usr/local/data/M2046_XrayQualification_2015-08-28_14h28m_1440764917//014_HREfficiency_200//tbmParameters_C0b.dat
[13:45:29.323] <TB1> INFO: readMaskFile: /usr/local/data/M2046_XrayQualification_2015-08-28_14h28m_1440764917//014_HREfficiency_200//defaultMaskFile.dat
[13:45:29.323] <TB1> INFO: readTrimFile: /usr/local/data/M2046_XrayQualification_2015-08-28_14h28m_1440764917//014_HREfficiency_200//trimParameters35_C0.dat .. /usr/local/data/M2046_XrayQualification_2015-08-28_14h28m_1440764917//014_HREfficiency_200//trimParameters35_C15.dat
[13:45:29.393] <TB1> INFO: clk: 4
[13:45:29.393] <TB1> INFO: ctr: 4
[13:45:29.393] <TB1> INFO: sda: 19
[13:45:29.393] <TB1> INFO: tin: 9
[13:45:29.393] <TB1> INFO: level: 15
[13:45:29.393] <TB1> INFO: triggerdelay: 0
[13:45:29.393] <TB1> QUIET: Instanciating API for pxar v2.5+57~g44d0777
[13:45:29.393] <TB1> INFO: Log level: INFO
[13:45:29.406] <TB1> INFO: Found DTB DTB_WS6UZO
[13:45:29.415] <TB1> QUIET: Connection to board DTB_WS6UZO opened.
[13:45:29.419] <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:
------------------------------------------------------
[13:45:29.421] <TB1> INFO: RPC call hashes of host and DTB match: 397073690
[13:45:30.932] <TB1> INFO: DUT info:
[13:45:30.932] <TB1> INFO: The DUT currently contains the following objects:
[13:45:30.932] <TB1> INFO: 2 TBM Cores tbm09c (2 ON)
[13:45:30.932] <TB1> INFO: TBM Core alpha (0): 7 registers set
[13:45:30.932] <TB1> INFO: TBM Core beta (1): 7 registers set
[13:45:30.932] <TB1> INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[13:45:30.932] <TB1> INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[13:45:30.932] <TB1> INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[13:45:30.932] <TB1> INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[13:45:30.932] <TB1> INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[13:45:30.932] <TB1> INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[13:45:30.932] <TB1> INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[13:45:30.932] <TB1> INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[13:45:30.932] <TB1> INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[13:45:30.932] <TB1> INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[13:45:30.932] <TB1> INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[13:45:30.932] <TB1> INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[13:45:30.932] <TB1> INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[13:45:30.932] <TB1> INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[13:45:30.932] <TB1> INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[13:45:30.932] <TB1> INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[13:45:30.932] <TB1> INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[13:45:31.333] <TB1> INFO: enter 'restricted' command line mode
[13:45:31.333] <TB1> INFO: enter test to run
[13:45:31.333] <TB1> INFO: test: HighRate no parameter change
[13:45:31.333] <TB1> INFO: running: highrate
[13:45:31.334] <TB1> INFO: readGainPedestalParameters /usr/local/data/M2046_XrayQualification_2015-08-28_14h28m_1440764917//014_HREfficiency_200//phCalibrationFitErr35_C0.dat .. /usr/local/data/M2046_XrayQualification_2015-08-28_14h28m_1440764917//014_HREfficiency_200//phCalibrationFitErr35_C15.dat
[13:45:31.463] <TB1> INFO: ----------------------------------------------------------------------
[13:45:31.463] <TB1> INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[13:45:31.463] <TB1> INFO: ----------------------------------------------------------------------
[13:45:32.060] <TB1> INFO: Expecting 208000 events.
[13:45:59.389] <TB1> INFO: 205800 events read in total (26799ms).
[13:45:59.995] <TB1> INFO: 208000 events read in total (27405ms).
[13:46:00.012] <TB1> INFO: Test took 28543ms.
[13:46:01.174] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:46:01.767] <TB1> INFO: number of dead pixels (per ROC): 2 0 0 0 1 0 0 0 0 0 0 0 0 0 0 0
[13:46:01.767] <TB1> INFO: number of red-efficiency pixels: 1509 1782 1438 3094 3724 4094 3920 4007 3971 3884 3753 3441 3398 1310 1790 1691
[13:46:01.767] <TB1> INFO: number of X-ray hits detected: 435991 454891 389543 669593 813623 1001206 903232 889445 893241 888509 807564 742667 720928 373035 427741 400127
[13:46:01.767] <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
[13:46:01.767] <TB1> INFO: number of Vcal hits detected: 205668 205481 204385 200292 193497 184964 189974 191398 190667 190610 194895 196972 198359 205062 205455 205544
[13:46:01.767] <TB1> INFO: Vcal hit fiducial efficiency (%): 99.1 98.9 98.6 96.8 93.9 90.0 92.4 92.7 92.4 92.5 94.4 95.3 95.8 98.9 98.9 99.0
[13:46:01.767] <TB1> INFO: Vcal hit overall efficiency (%): 98.9 98.8 98.3 96.3 93.0 88.9 91.3 92.0 91.7 91.6 93.7 94.7 95.4 98.6 98.8 98.8
[13:46:01.767] <TB1> INFO: X-ray hit rate [MHz/cm2]: 127.8 133.3 114.2 196.3 238.5 293.5 264.7 260.7 261.8 260.4 236.7 217.7 211.3 109.3 125.4 117.3
[13:46:01.767] <TB1> INFO: PixTestHighRate::doXPixelAlive() done
[13:46:01.797] <TB1> INFO: PixTest:: pg_setup set to default.
[13:46:01.835] <TB1> INFO: enter test to run
[13:46:01.835] <TB1> INFO: test: no parameter change
[13:46:01.987] <TB1> QUIET: Connection to board 83 closed.
[13:46:02.067] <TB1> INFO: pXar: this is the end, my friend

[13:46:16.264] <TB1> INFO: *** Welcome to pxar ***
[13:46:16.264] <TB1> INFO: *** Today: 2015/08/28
[13:46:16.264] <TB1> INFO: readRocDacs: /usr/local/data/M2046_XrayQualification_2015-08-28_14h28m_1440764917//015_HREfficiency_250//dacParameters35_C0.dat .. /usr/local/data/M2046_XrayQualification_2015-08-28_14h28m_1440764917//015_HREfficiency_250//dacParameters35_C15.dat
[13:46:16.265] <TB1> INFO: readTbmDacs: /usr/local/data/M2046_XrayQualification_2015-08-28_14h28m_1440764917//015_HREfficiency_250//tbmParameters_C0a.dat .. /usr/local/data/M2046_XrayQualification_2015-08-28_14h28m_1440764917//015_HREfficiency_250//tbmParameters_C0b.dat
[13:46:16.265] <TB1> INFO: readMaskFile: /usr/local/data/M2046_XrayQualification_2015-08-28_14h28m_1440764917//015_HREfficiency_250//defaultMaskFile.dat
[13:46:16.265] <TB1> INFO: readTrimFile: /usr/local/data/M2046_XrayQualification_2015-08-28_14h28m_1440764917//015_HREfficiency_250//trimParameters35_C0.dat .. /usr/local/data/M2046_XrayQualification_2015-08-28_14h28m_1440764917//015_HREfficiency_250//trimParameters35_C15.dat
[13:46:16.333] <TB1> INFO: clk: 4
[13:46:16.333] <TB1> INFO: ctr: 4
[13:46:16.333] <TB1> INFO: sda: 19
[13:46:16.333] <TB1> INFO: tin: 9
[13:46:16.333] <TB1> INFO: level: 15
[13:46:16.333] <TB1> INFO: triggerdelay: 0
[13:46:16.333] <TB1> QUIET: Instanciating API for pxar v2.5+57~g44d0777
[13:46:16.333] <TB1> INFO: Log level: INFO
[13:46:16.346] <TB1> INFO: Found DTB DTB_WS6UZO
[13:46:16.356] <TB1> QUIET: Connection to board DTB_WS6UZO opened.
[13:46:16.359] <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:
------------------------------------------------------
[13:46:16.362] <TB1> INFO: RPC call hashes of host and DTB match: 397073690
[13:46:17.871] <TB1> INFO: DUT info:
[13:46:17.871] <TB1> INFO: The DUT currently contains the following objects:
[13:46:17.871] <TB1> INFO: 2 TBM Cores tbm09c (2 ON)
[13:46:17.871] <TB1> INFO: TBM Core alpha (0): 7 registers set
[13:46:17.871] <TB1> INFO: TBM Core beta (1): 7 registers set
[13:46:17.872] <TB1> INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[13:46:17.872] <TB1> INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[13:46:17.872] <TB1> INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[13:46:17.872] <TB1> INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[13:46:17.872] <TB1> INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[13:46:17.872] <TB1> INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[13:46:17.872] <TB1> INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[13:46:17.872] <TB1> INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[13:46:17.872] <TB1> INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[13:46:17.872] <TB1> INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[13:46:17.872] <TB1> INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[13:46:17.872] <TB1> INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[13:46:17.872] <TB1> INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[13:46:17.872] <TB1> INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[13:46:17.872] <TB1> INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[13:46:17.872] <TB1> INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[13:46:17.872] <TB1> INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[13:46:18.272] <TB1> INFO: enter 'restricted' command line mode
[13:46:18.272] <TB1> INFO: enter test to run
[13:46:18.273] <TB1> INFO: test: HighRate no parameter change
[13:46:18.273] <TB1> INFO: running: highrate
[13:46:18.273] <TB1> INFO: readGainPedestalParameters /usr/local/data/M2046_XrayQualification_2015-08-28_14h28m_1440764917//015_HREfficiency_250//phCalibrationFitErr35_C0.dat .. /usr/local/data/M2046_XrayQualification_2015-08-28_14h28m_1440764917//015_HREfficiency_250//phCalibrationFitErr35_C15.dat
[13:46:18.401] <TB1> INFO: ----------------------------------------------------------------------
[13:46:18.401] <TB1> INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[13:46:18.401] <TB1> INFO: ----------------------------------------------------------------------
[13:46:18.998] <TB1> INFO: Expecting 208000 events.
[13:46:47.272] <TB1> INFO: 202100 events read in total (27744ms).
[13:46:48.288] <TB1> INFO: 208000 events read in total (28760ms).
[13:46:48.307] <TB1> INFO: Test took 29900ms.
[13:46:49.497] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:46:50.096] <TB1> INFO: number of dead pixels (per ROC): 2 0 0 0 1 0 0 0 0 0 0 0 0 0 0 0
[13:46:50.096] <TB1> INFO: number of red-efficiency pixels: 1660 1851 1526 3205 3781 4082 3951 4053 4040 3949 3806 3544 3494 1383 1967 1790
[13:46:50.096] <TB1> INFO: number of X-ray hits detected: 450034 471964 408194 687600 836169 1022328 929357 916968 923440 915902 828454 762944 740191 392313 444432 412942
[13:46:50.096] <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
[13:46:50.096] <TB1> INFO: number of Vcal hits detected: 205495 205331 204123 199637 192544 183602 188582 189853 189247 189407 194064 195994 197719 204792 205168 205318
[13:46:50.096] <TB1> INFO: Vcal hit fiducial efficiency (%): 99.0 98.8 98.5 96.5 93.5 89.4 91.8 92.0 91.8 92.0 94.1 94.9 95.6 98.8 98.7 98.8
[13:46:50.096] <TB1> INFO: Vcal hit overall efficiency (%): 98.8 98.7 98.1 96.0 92.6 88.3 90.7 91.3 91.0 91.1 93.3 94.2 95.1 98.5 98.6 98.7
[13:46:50.096] <TB1> INFO: X-ray hit rate [MHz/cm2]: 131.9 138.3 119.6 201.5 245.1 299.7 272.4 268.8 270.7 268.5 242.8 223.6 217.0 115.0 130.3 121.0
[13:46:50.096] <TB1> INFO: PixTestHighRate::doXPixelAlive() done
[13:46:50.125] <TB1> INFO: PixTest:: pg_setup set to default.
[13:46:50.162] <TB1> INFO: enter test to run
[13:46:50.162] <TB1> INFO: test: no parameter change
[13:46:50.351] <TB1> QUIET: Connection to board 83 closed.
[13:46:50.430] <TB1> INFO: pXar: this is the end, my friend