Test Date: 2015-08-24 08:08
Analysis date: 2016-05-26 02:17
Logfile
commander_PixelAlive.log
[06:09:36.340] <TB3> INFO: *** Welcome to pxar ***
[06:09:36.340] <TB3> INFO: *** Today: 2015/08/24
[06:09:36.340] <TB3> INFO: readRocDacs: /usr/local/data/M2079_XrayQualification_2015-08-24_08h08m_1440396521//000_PixelAlive_p17//dacParameters35_C0.dat .. /usr/local/data/M2079_XrayQualification_2015-08-24_08h08m_1440396521//000_PixelAlive_p17//dacParameters35_C15.dat
[06:09:36.341] <TB3> INFO: readTbmDacs: /usr/local/data/M2079_XrayQualification_2015-08-24_08h08m_1440396521//000_PixelAlive_p17//tbmParameters_C0a.dat .. /usr/local/data/M2079_XrayQualification_2015-08-24_08h08m_1440396521//000_PixelAlive_p17//tbmParameters_C0b.dat
[06:09:36.341] <TB3> INFO: readMaskFile: /usr/local/data/M2079_XrayQualification_2015-08-24_08h08m_1440396521//000_PixelAlive_p17//defaultMaskFile.dat
[06:09:36.341] <TB3> INFO: readTrimFile: /usr/local/data/M2079_XrayQualification_2015-08-24_08h08m_1440396521//000_PixelAlive_p17//trimParameters35_C0.dat .. /usr/local/data/M2079_XrayQualification_2015-08-24_08h08m_1440396521//000_PixelAlive_p17//trimParameters35_C15.dat
[06:09:36.409] <TB3> INFO: clk: 4
[06:09:36.409] <TB3> INFO: ctr: 4
[06:09:36.409] <TB3> INFO: sda: 19
[06:09:36.409] <TB3> INFO: tin: 9
[06:09:36.409] <TB3> INFO: level: 15
[06:09:36.409] <TB3> INFO: triggerdelay: 0
[06:09:36.409] <TB3> QUIET: Instanciating API for pxar prod-01+80~g16e673b
[06:09:36.409] <TB3> INFO: Log level: INFO
[06:09:36.422] <TB3> INFO: Found DTB DTB_WWV6Z5
[06:09:36.434] <TB3> QUIET: Connection to board DTB_WWV6Z5 opened.
[06:09:36.437] <TB3> INFO: DTB startup information
--- DTB info------------------------------------------
Board id: 121
HW version: DTB1.2
FW version: 4.0
SW version: 4.0
USB id: DTB_WWV6Z5
MAC address: 40D855118079
Hostname: pixelDTB121
Comment:
------------------------------------------------------
[06:09:36.440] <TB3> INFO: RPC call hashes of host and DTB match: 447413373
[06:09:37.973] <TB3> INFO: DUT info:
[06:09:37.973] <TB3> INFO: The DUT currently contains the following objects:
[06:09:37.973] <TB3> INFO: 2 TBM Cores tbm09c (2 ON)
[06:09:37.973] <TB3> INFO: TBM Core alpha (0): 7 registers set
[06:09:37.973] <TB3> INFO: TBM Core beta (1): 7 registers set
[06:09:37.973] <TB3> INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[06:09:37.973] <TB3> INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[06:09:37.973] <TB3> INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[06:09:37.973] <TB3> INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[06:09:37.973] <TB3> INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[06:09:37.973] <TB3> INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[06:09:37.973] <TB3> INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[06:09:37.973] <TB3> INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[06:09:37.973] <TB3> INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[06:09:37.973] <TB3> INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[06:09:37.973] <TB3> INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[06:09:37.973] <TB3> INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[06:09:37.973] <TB3> INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[06:09:37.973] <TB3> INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[06:09:37.974] <TB3> INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[06:09:37.974] <TB3> INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[06:09:37.974] <TB3> INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[06:09:38.375] <TB3> INFO: enter 'restricted' command line mode
[06:09:38.375] <TB3> INFO: enter test to run
[06:09:38.375] <TB3> INFO: test: PixelAlive no parameter change
[06:09:38.375] <TB3> INFO: running: pixelalive
[06:09:38.381] <TB3> INFO: ######################################################################
[06:09:38.382] <TB3> INFO: PixTestAlive::doTest()
[06:09:38.382] <TB3> INFO: ######################################################################
[06:09:38.383] <TB3> INFO: ----------------------------------------------------------------------
[06:09:38.383] <TB3> INFO: PixTestAlive::aliveTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[06:09:38.383] <TB3> INFO: ----------------------------------------------------------------------
[06:09:41.972] <TB3> INFO: Test took 3587ms.
[06:09:41.991] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[06:09:42.238] <TB3> INFO: PixTestAlive::aliveTest() done
[06:09:42.238] <TB3> INFO: number of dead pixels (per ROC): 0 1 0 1 0 0 0 0 0 0 0 0 1 0 0 3
[06:09:42.240] <TB3> INFO: ----------------------------------------------------------------------
[06:09:42.240] <TB3> INFO: PixTestAlive::maskTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[06:09:42.240] <TB3> INFO: ----------------------------------------------------------------------
[06:09:44.994] <TB3> INFO: Test took 2753ms.
[06:09:44.996] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[06:09:44.996] <TB3> INFO: mask vs. old pixelAlive PixelAlive_C0_V0 .. PixelAlive_C15_V0
[06:09:45.216] <TB3> INFO: PixTestAlive::maskTest() done
[06:09:45.216] <TB3> INFO: number of mask-defect pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[06:09:45.217] <TB3> INFO: ----------------------------------------------------------------------
[06:09:45.217] <TB3> INFO: PixTestAlive::addressDecodingTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[06:09:45.217] <TB3> INFO: ----------------------------------------------------------------------
[06:09:48.814] <TB3> INFO: Test took 3597ms.
[06:09:48.832] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[06:09:49.054] <TB3> INFO: PixTestAlive::addressDecodingTest() done
[06:09:49.054] <TB3> INFO: number of address-decoding pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[06:09:49.054] <TB3> INFO: PixTestAlive::doTest() done, duration: 10 seconds
[06:09:49.082] <TB3> INFO: enter test to run
[06:09:49.082] <TB3> INFO: test: no parameter change
[06:09:49.222] <TB3> QUIET: Connection to board 121 closed.
[06:09:49.302] <TB3> INFO: pXar: this is the end, my friend
commander_RetrimHotPixels.log
[06:14:59.699] <TB3> INFO: *** Welcome to pxar ***
[06:14:59.699] <TB3> INFO: *** Today: 2015/08/24
[06:14:59.699] <TB3> INFO: readRocDacs: /usr/local/data/M2079_XrayQualification_2015-08-24_08h08m_1440396521//002_RetrimHotPixels_150//dacParameters35_C0.dat .. /usr/local/data/M2079_XrayQualification_2015-08-24_08h08m_1440396521//002_RetrimHotPixels_150//dacParameters35_C15.dat
[06:14:59.701] <TB3> INFO: readTbmDacs: /usr/local/data/M2079_XrayQualification_2015-08-24_08h08m_1440396521//002_RetrimHotPixels_150//tbmParameters_C0a.dat .. /usr/local/data/M2079_XrayQualification_2015-08-24_08h08m_1440396521//002_RetrimHotPixels_150//tbmParameters_C0b.dat
[06:14:59.701] <TB3> INFO: readMaskFile: /usr/local/data/M2079_XrayQualification_2015-08-24_08h08m_1440396521//002_RetrimHotPixels_150//defaultMaskFile.dat
[06:14:59.701] <TB3> INFO: readTrimFile: /usr/local/data/M2079_XrayQualification_2015-08-24_08h08m_1440396521//002_RetrimHotPixels_150//trimParameters35_C0.dat .. /usr/local/data/M2079_XrayQualification_2015-08-24_08h08m_1440396521//002_RetrimHotPixels_150//trimParameters35_C15.dat
[06:14:59.778] <TB3> INFO: clk: 4
[06:14:59.778] <TB3> INFO: ctr: 4
[06:14:59.778] <TB3> INFO: sda: 19
[06:14:59.778] <TB3> INFO: tin: 9
[06:14:59.778] <TB3> INFO: level: 15
[06:14:59.778] <TB3> INFO: triggerdelay: 0
[06:14:59.778] <TB3> QUIET: Instanciating API for pxar prod-01+80~g16e673b
[06:14:59.778] <TB3> INFO: Log level: INFO
[06:14:59.791] <TB3> INFO: Found DTB DTB_WWV6Z5
[06:14:59.802] <TB3> QUIET: Connection to board DTB_WWV6Z5 opened.
[06:14:59.805] <TB3> INFO: DTB startup information
--- DTB info------------------------------------------
Board id: 121
HW version: DTB1.2
FW version: 4.0
SW version: 4.0
USB id: DTB_WWV6Z5
MAC address: 40D855118079
Hostname: pixelDTB121
Comment:
------------------------------------------------------
[06:14:59.808] <TB3> INFO: RPC call hashes of host and DTB match: 447413373
[06:15:01.344] <TB3> INFO: DUT info:
[06:15:01.344] <TB3> INFO: The DUT currently contains the following objects:
[06:15:01.344] <TB3> INFO: 2 TBM Cores tbm09c (2 ON)
[06:15:01.344] <TB3> INFO: TBM Core alpha (0): 7 registers set
[06:15:01.344] <TB3> INFO: TBM Core beta (1): 7 registers set
[06:15:01.344] <TB3> INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[06:15:01.344] <TB3> INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[06:15:01.344] <TB3> INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[06:15:01.344] <TB3> INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[06:15:01.344] <TB3> INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[06:15:01.344] <TB3> INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[06:15:01.345] <TB3> INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[06:15:01.345] <TB3> INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[06:15:01.345] <TB3> INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[06:15:01.345] <TB3> INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[06:15:01.345] <TB3> INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[06:15:01.345] <TB3> INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[06:15:01.345] <TB3> INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[06:15:01.345] <TB3> INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[06:15:01.345] <TB3> INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[06:15:01.345] <TB3> INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[06:15:01.345] <TB3> INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[06:15:01.746] <TB3> INFO: enter 'restricted' command line mode
[06:15:01.746] <TB3> INFO: enter test to run
[06:15:01.746] <TB3> INFO: test: delay setting parameters: ->5<-
[06:15:01.746] <TB3> INFO: delay test by 5 seconds...
[06:15:06.747] <TB3> INFO: enter test to run
[06:15:06.747] <TB3> INFO: test: highrate no parameter change
[06:15:06.747] <TB3> INFO: running: highrate
[06:15:06.747] <TB3> INFO: readGainPedestalParameters /usr/local/data/M2079_XrayQualification_2015-08-24_08h08m_1440396521//002_RetrimHotPixels_150//phCalibrationFitErr35_C0.dat .. /usr/local/data/M2079_XrayQualification_2015-08-24_08h08m_1440396521//002_RetrimHotPixels_150//phCalibrationFitErr35_C15.dat
[06:15:06.911] <TB3> INFO: ----------------------------------------------------------------------
[06:15:06.911] <TB3> INFO: PixTest::trimHotPixels() running for 1 seconds with 100 kHz trigger rate
[06:15:06.911] <TB3> INFO: ----------------------------------------------------------------------
[06:15:06.911] <TB3> INFO: THR = 150, corresponding to ~ 400 MHz/cm2
[06:15:06.911] <TB3> INFO: edge/corner pixel THR is adjusted
[06:15:06.911] <TB3> INFO: PixTestHighRate::trimHotPixels: step 0...
[06:15:07.873] <TB3> INFO: Collecting data for 1 seconds...
[06:15:08.875] <TB3> INFO: Done with hot pixel readout
[06:15:14.711] <TB3> INFO: PixTest:: pg_setup set to default.
[06:15:14.712] <TB3> INFO: 9 hot pixels found in step 0
[06:15:15.684] <TB3> INFO: Collecting data for 1 seconds...
[06:15:16.686] <TB3> INFO: Done with hot pixel readout
[06:15:22.704] <TB3> INFO: PixTest:: pg_setup set to default.
[06:15:22.705] <TB3> INFO: 6 hot pixels found in step 1
[06:15:23.668] <TB3> INFO: Collecting data for 1 seconds...
[06:15:24.670] <TB3> INFO: Done with hot pixel readout
[06:15:30.628] <TB3> INFO: PixTest:: pg_setup set to default.
[06:15:30.629] <TB3> INFO: 3 hot pixels found in step 2
[06:15:31.591] <TB3> INFO: Collecting data for 1 seconds...
[06:15:32.593] <TB3> INFO: Done with hot pixel readout
[06:15:38.517] <TB3> INFO: PixTest:: pg_setup set to default.
[06:15:38.518] <TB3> INFO: 0 hot pixels found in step 3
[06:15:38.526] <TB3> INFO: 0 hot pixels could not be trimmed and have been masked.
[06:15:38.558] <TB3> INFO: PixTest::trimHotPixels() done
[06:15:38.559] <TB3> INFO: write trim parameters into /usr/local/data/M2079_XrayQualification_2015-08-24_08h08m_1440396521//002_RetrimHotPixels_150//trimParameters35_C0.dat
[06:15:38.566] <TB3> INFO: write trim parameters into /usr/local/data/M2079_XrayQualification_2015-08-24_08h08m_1440396521//002_RetrimHotPixels_150//trimParameters35_C1.dat
[06:15:38.572] <TB3> INFO: write trim parameters into /usr/local/data/M2079_XrayQualification_2015-08-24_08h08m_1440396521//002_RetrimHotPixels_150//trimParameters35_C2.dat
[06:15:38.580] <TB3> INFO: write trim parameters into /usr/local/data/M2079_XrayQualification_2015-08-24_08h08m_1440396521//002_RetrimHotPixels_150//trimParameters35_C3.dat
[06:15:38.587] <TB3> INFO: write trim parameters into /usr/local/data/M2079_XrayQualification_2015-08-24_08h08m_1440396521//002_RetrimHotPixels_150//trimParameters35_C4.dat
[06:15:38.594] <TB3> INFO: write trim parameters into /usr/local/data/M2079_XrayQualification_2015-08-24_08h08m_1440396521//002_RetrimHotPixels_150//trimParameters35_C5.dat
[06:15:38.601] <TB3> INFO: write trim parameters into /usr/local/data/M2079_XrayQualification_2015-08-24_08h08m_1440396521//002_RetrimHotPixels_150//trimParameters35_C6.dat
[06:15:38.608] <TB3> INFO: write trim parameters into /usr/local/data/M2079_XrayQualification_2015-08-24_08h08m_1440396521//002_RetrimHotPixels_150//trimParameters35_C7.dat
[06:15:38.615] <TB3> INFO: write trim parameters into /usr/local/data/M2079_XrayQualification_2015-08-24_08h08m_1440396521//002_RetrimHotPixels_150//trimParameters35_C8.dat
[06:15:38.622] <TB3> INFO: write trim parameters into /usr/local/data/M2079_XrayQualification_2015-08-24_08h08m_1440396521//002_RetrimHotPixels_150//trimParameters35_C9.dat
[06:15:38.629] <TB3> INFO: write trim parameters into /usr/local/data/M2079_XrayQualification_2015-08-24_08h08m_1440396521//002_RetrimHotPixels_150//trimParameters35_C10.dat
[06:15:38.635] <TB3> INFO: write trim parameters into /usr/local/data/M2079_XrayQualification_2015-08-24_08h08m_1440396521//002_RetrimHotPixels_150//trimParameters35_C11.dat
[06:15:38.641] <TB3> INFO: write trim parameters into /usr/local/data/M2079_XrayQualification_2015-08-24_08h08m_1440396521//002_RetrimHotPixels_150//trimParameters35_C12.dat
[06:15:38.647] <TB3> INFO: write trim parameters into /usr/local/data/M2079_XrayQualification_2015-08-24_08h08m_1440396521//002_RetrimHotPixels_150//trimParameters35_C13.dat
[06:15:38.653] <TB3> INFO: write trim parameters into /usr/local/data/M2079_XrayQualification_2015-08-24_08h08m_1440396521//002_RetrimHotPixels_150//trimParameters35_C14.dat
[06:15:38.660] <TB3> INFO: write trim parameters into /usr/local/data/M2079_XrayQualification_2015-08-24_08h08m_1440396521//002_RetrimHotPixels_150//trimParameters35_C15.dat
[06:15:38.666] <TB3> INFO: write masked pixels into /usr/local/data/M2079_XrayQualification_2015-08-24_08h08m_1440396521//002_RetrimHotPixels_150//defaultMaskFile.dat
[06:15:38.698] <TB3> INFO: enter test to run
[06:15:38.698] <TB3> INFO: test: no parameter change
[06:15:38.883] <TB3> QUIET: Connection to board 121 closed.
[06:15:38.963] <TB3> INFO: pXar: this is the end, my friend
commander_HRData.log
[06:16:14.442] <TB3> INFO: *** Welcome to pxar ***
[06:16:14.442] <TB3> INFO: *** Today: 2015/08/24
[06:16:14.442] <TB3> INFO: readRocDacs: /usr/local/data/M2079_XrayQualification_2015-08-24_08h08m_1440396521//003_HRData_50//dacParameters35_C0.dat .. /usr/local/data/M2079_XrayQualification_2015-08-24_08h08m_1440396521//003_HRData_50//dacParameters35_C15.dat
[06:16:14.443] <TB3> INFO: readTbmDacs: /usr/local/data/M2079_XrayQualification_2015-08-24_08h08m_1440396521//003_HRData_50//tbmParameters_C0a.dat .. /usr/local/data/M2079_XrayQualification_2015-08-24_08h08m_1440396521//003_HRData_50//tbmParameters_C0b.dat
[06:16:14.443] <TB3> INFO: readMaskFile: /usr/local/data/M2079_XrayQualification_2015-08-24_08h08m_1440396521//003_HRData_50//defaultMaskFile.dat
[06:16:14.444] <TB3> INFO: readTrimFile: /usr/local/data/M2079_XrayQualification_2015-08-24_08h08m_1440396521//003_HRData_50//trimParameters35_C0.dat .. /usr/local/data/M2079_XrayQualification_2015-08-24_08h08m_1440396521//003_HRData_50//trimParameters35_C15.dat
[06:16:14.523] <TB3> INFO: clk: 4
[06:16:14.523] <TB3> INFO: ctr: 4
[06:16:14.523] <TB3> INFO: sda: 19
[06:16:14.523] <TB3> INFO: tin: 9
[06:16:14.523] <TB3> INFO: level: 15
[06:16:14.523] <TB3> INFO: triggerdelay: 0
[06:16:14.523] <TB3> QUIET: Instanciating API for pxar prod-01+80~g16e673b
[06:16:14.523] <TB3> INFO: Log level: INFO
[06:16:14.536] <TB3> INFO: Found DTB DTB_WWV6Z5
[06:16:14.548] <TB3> QUIET: Connection to board DTB_WWV6Z5 opened.
[06:16:14.551] <TB3> INFO: DTB startup information
--- DTB info------------------------------------------
Board id: 121
HW version: DTB1.2
FW version: 4.0
SW version: 4.0
USB id: DTB_WWV6Z5
MAC address: 40D855118079
Hostname: pixelDTB121
Comment:
------------------------------------------------------
[06:16:14.554] <TB3> INFO: RPC call hashes of host and DTB match: 447413373
[06:16:16.085] <TB3> INFO: DUT info:
[06:16:16.085] <TB3> INFO: The DUT currently contains the following objects:
[06:16:16.085] <TB3> INFO: 2 TBM Cores tbm09c (2 ON)
[06:16:16.085] <TB3> INFO: TBM Core alpha (0): 7 registers set
[06:16:16.085] <TB3> INFO: TBM Core beta (1): 7 registers set
[06:16:16.085] <TB3> INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[06:16:16.085] <TB3> INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[06:16:16.085] <TB3> INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[06:16:16.085] <TB3> INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[06:16:16.085] <TB3> INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[06:16:16.085] <TB3> INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[06:16:16.085] <TB3> INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[06:16:16.085] <TB3> INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[06:16:16.085] <TB3> INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[06:16:16.085] <TB3> INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[06:16:16.085] <TB3> INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[06:16:16.086] <TB3> INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[06:16:16.086] <TB3> INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[06:16:16.086] <TB3> INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[06:16:16.086] <TB3> INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[06:16:16.086] <TB3> INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[06:16:16.086] <TB3> INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[06:16:16.487] <TB3> INFO: enter 'restricted' command line mode
[06:16:16.487] <TB3> INFO: enter test to run
[06:16:16.487] <TB3> INFO: test: delay setting parameters: ->5<-
[06:16:16.487] <TB3> INFO: delay test by 5 seconds...
[06:16:21.487] <TB3> INFO: enter test to run
[06:16:21.487] <TB3> INFO: test: Xray no parameter change
[06:16:21.487] <TB3> INFO: running: xray
[06:16:21.488] <TB3> INFO: readGainPedestalParameters /usr/local/data/M2079_XrayQualification_2015-08-24_08h08m_1440396521//003_HRData_50//phCalibrationFitErr35_C0.dat .. /usr/local/data/M2079_XrayQualification_2015-08-24_08h08m_1440396521//003_HRData_50//phCalibrationFitErr35_C15.dat
[06:16:21.631] <TB3> INFO: ######################################################################
[06:16:21.631] <TB3> INFO: PixTestXray::doTest()
[06:16:21.631] <TB3> INFO: ######################################################################
[06:16:21.631] <TB3> INFO: ----------------------------------------------------------------------
[06:16:21.631] <TB3> INFO: PixTestXray::doPhRun() fParRunSeconds = 100
[06:16:21.631] <TB3> INFO: ----------------------------------------------------------------------
[06:16:22.600] <TB3> INFO: PixTestXray::doPhRun start TriggerLoop with trigger frequency 100 kHz, period 405 and duration 100 seconds, fEventsMax = 10000000
[06:16:29.313] <TB3> INFO: run duration 6 seconds, buffer almost full (81%), pausing triggers.
[06:16:54.188] <TB3> INFO: Resuming triggers.
[06:17:00.910] <TB3> INFO: run duration 13 seconds, buffer almost full (81%), pausing triggers.
[06:17:04.399] <TB3> ERROR: <datapipe.cc/CheckEventID:L453> Channel 3 Event ID mismatch: local ID (253) != TBM ID (32)

[06:17:04.400] <TB3> ERROR: <datapipe.cc/CheckEventValidity:L470> Channel 3 Number of ROCs (1) != Token Chain Length (4)

[06:17:04.400] <TB3> ERROR: <datapipe.cc/CheckEventID:L453> Channel 3 Event ID mismatch: local ID (33) != TBM ID (254)

[06:17:04.400] <TB3> WARNING: Channel 3 ROC 2: Readback start marker after 15 readouts!

[06:17:04.400] <TB3> WARNING: Channel 3 ROC 3: Readback start marker after 15 readouts!

[06:17:23.670] <TB3> INFO: Resuming triggers.
[06:17:30.391] <TB3> INFO: run duration 20 seconds, buffer almost full (81%), pausing triggers.
[06:17:53.432] <TB3> INFO: Resuming triggers.
[06:18:00.152] <TB3> INFO: run duration 26 seconds, buffer almost full (81%), pausing triggers.
[06:18:22.846] <TB3> INFO: Resuming triggers.
[06:18:29.565] <TB3> INFO: run duration 33 seconds, buffer almost full (81%), pausing triggers.
[06:18:51.237] <TB3> INFO: Resuming triggers.
[06:18:57.958] <TB3> INFO: run duration 40 seconds, buffer almost full (81%), pausing triggers.
[06:19:20.527] <TB3> INFO: Resuming triggers.
[06:19:27.248] <TB3> INFO: run duration 46 seconds, buffer almost full (81%), pausing triggers.
[06:19:49.775] <TB3> INFO: Resuming triggers.
[06:19:56.500] <TB3> INFO: run duration 53 seconds, buffer almost full (81%), pausing triggers.
[06:20:17.442] <TB3> INFO: Resuming triggers.
[06:20:24.168] <TB3> INFO: run duration 60 seconds, buffer almost full (81%), pausing triggers.
[06:20:45.084] <TB3> INFO: Resuming triggers.
[06:20:51.805] <TB3> INFO: run duration 67 seconds, buffer almost full (81%), pausing triggers.
[06:21:15.585] <TB3> INFO: Resuming triggers.
[06:21:22.307] <TB3> INFO: run duration 73 seconds, buffer almost full (81%), pausing triggers.
[06:21:44.197] <TB3> INFO: Resuming triggers.
[06:21:50.919] <TB3> INFO: run duration 80 seconds, buffer almost full (81%), pausing triggers.
[06:22:15.092] <TB3> INFO: Resuming triggers.
[06:22:21.809] <TB3> INFO: run duration 87 seconds, buffer almost full (81%), pausing triggers.
[06:22:42.506] <TB3> INFO: Resuming triggers.
[06:22:49.222] <TB3> INFO: run duration 93 seconds, buffer almost full (81%), pausing triggers.
[06:23:07.741] <TB3> INFO: Resuming triggers.
[06:23:13.773] <TB3> INFO: data taking finished, elapsed time: 100 seconds.
[06:23:33.126] <TB3> INFO: PixTest:: pg_setup set to default.
[06:23:33.127] <TB3> INFO: PixTestXray::doPhRun() done
[06:23:33.127] <TB3> INFO: PixTestXray::doTest() done
[06:23:33.329] <TB3> INFO: enter test to run
[06:23:33.329] <TB3> INFO: test: no parameter change
[06:23:33.563] <TB3> QUIET: Connection to board 121 closed.
[06:23:33.646] <TB3> INFO: pXar: this is the end, my friend
commander_HRData.log
[06:25:05.478] <TB3> INFO: *** Welcome to pxar ***
[06:25:05.478] <TB3> INFO: *** Today: 2015/08/24
[06:25:05.478] <TB3> INFO: readRocDacs: /usr/local/data/M2079_XrayQualification_2015-08-24_08h08m_1440396521//004_HRData_150//dacParameters35_C0.dat .. /usr/local/data/M2079_XrayQualification_2015-08-24_08h08m_1440396521//004_HRData_150//dacParameters35_C15.dat
[06:25:05.479] <TB3> INFO: readTbmDacs: /usr/local/data/M2079_XrayQualification_2015-08-24_08h08m_1440396521//004_HRData_150//tbmParameters_C0a.dat .. /usr/local/data/M2079_XrayQualification_2015-08-24_08h08m_1440396521//004_HRData_150//tbmParameters_C0b.dat
[06:25:05.479] <TB3> INFO: readMaskFile: /usr/local/data/M2079_XrayQualification_2015-08-24_08h08m_1440396521//004_HRData_150//defaultMaskFile.dat
[06:25:05.479] <TB3> INFO: readTrimFile: /usr/local/data/M2079_XrayQualification_2015-08-24_08h08m_1440396521//004_HRData_150//trimParameters35_C0.dat .. /usr/local/data/M2079_XrayQualification_2015-08-24_08h08m_1440396521//004_HRData_150//trimParameters35_C15.dat
[06:25:05.559] <TB3> INFO: clk: 4
[06:25:05.559] <TB3> INFO: ctr: 4
[06:25:05.559] <TB3> INFO: sda: 19
[06:25:05.559] <TB3> INFO: tin: 9
[06:25:05.559] <TB3> INFO: level: 15
[06:25:05.559] <TB3> INFO: triggerdelay: 0
[06:25:05.559] <TB3> QUIET: Instanciating API for pxar prod-01+80~g16e673b
[06:25:05.559] <TB3> INFO: Log level: INFO
[06:25:05.573] <TB3> INFO: Found DTB DTB_WWV6Z5
[06:25:05.585] <TB3> QUIET: Connection to board DTB_WWV6Z5 opened.
[06:25:05.588] <TB3> INFO: DTB startup information
--- DTB info------------------------------------------
Board id: 121
HW version: DTB1.2
FW version: 4.0
SW version: 4.0
USB id: DTB_WWV6Z5
MAC address: 40D855118079
Hostname: pixelDTB121
Comment:
------------------------------------------------------
[06:25:05.591] <TB3> INFO: RPC call hashes of host and DTB match: 447413373
[06:25:07.152] <TB3> INFO: DUT info:
[06:25:07.152] <TB3> INFO: The DUT currently contains the following objects:
[06:25:07.152] <TB3> INFO: 2 TBM Cores tbm09c (2 ON)
[06:25:07.152] <TB3> INFO: TBM Core alpha (0): 7 registers set
[06:25:07.152] <TB3> INFO: TBM Core beta (1): 7 registers set
[06:25:07.152] <TB3> INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[06:25:07.152] <TB3> INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[06:25:07.152] <TB3> INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[06:25:07.152] <TB3> INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[06:25:07.152] <TB3> INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[06:25:07.152] <TB3> INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[06:25:07.152] <TB3> INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[06:25:07.152] <TB3> INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[06:25:07.152] <TB3> INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[06:25:07.152] <TB3> INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[06:25:07.152] <TB3> INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[06:25:07.152] <TB3> INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[06:25:07.152] <TB3> INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[06:25:07.152] <TB3> INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[06:25:07.152] <TB3> INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[06:25:07.152] <TB3> INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[06:25:07.152] <TB3> INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[06:25:07.554] <TB3> INFO: enter 'restricted' command line mode
[06:25:07.554] <TB3> INFO: enter test to run
[06:25:07.554] <TB3> INFO: test: delay setting parameters: ->5<-
[06:25:07.554] <TB3> INFO: delay test by 5 seconds...
[06:25:12.554] <TB3> INFO: enter test to run
[06:25:12.554] <TB3> INFO: test: Xray no parameter change
[06:25:12.554] <TB3> INFO: running: xray
[06:25:12.554] <TB3> INFO: readGainPedestalParameters /usr/local/data/M2079_XrayQualification_2015-08-24_08h08m_1440396521//004_HRData_150//phCalibrationFitErr35_C0.dat .. /usr/local/data/M2079_XrayQualification_2015-08-24_08h08m_1440396521//004_HRData_150//phCalibrationFitErr35_C15.dat
[06:25:12.716] <TB3> INFO: ######################################################################
[06:25:12.716] <TB3> INFO: PixTestXray::doTest()
[06:25:12.716] <TB3> INFO: ######################################################################
[06:25:12.716] <TB3> INFO: ----------------------------------------------------------------------
[06:25:12.716] <TB3> INFO: PixTestXray::doPhRun() fParRunSeconds = 100
[06:25:12.716] <TB3> INFO: ----------------------------------------------------------------------
[06:25:13.684] <TB3> INFO: PixTestXray::doPhRun start TriggerLoop with trigger frequency 100 kHz, period 405 and duration 100 seconds, fEventsMax = 10000000
[06:25:17.397] <TB3> INFO: run duration 3 seconds, buffer almost full (81%), pausing triggers.
[06:25:44.578] <TB3> INFO: Resuming triggers.
[06:25:48.306] <TB3> INFO: run duration 7 seconds, buffer almost full (81%), pausing triggers.
[06:25:51.321] <TB3> ERROR: <datapipe.cc/CheckEventValidity:L470> Channel 3 Number of ROCs (6) != Token Chain Length (4)

[06:25:51.322] <TB3> ERROR: <datapipe.cc/CheckEventID:L453> Channel 3 Event ID mismatch: local ID (178) != TBM ID (255)

[06:25:51.322] <TB3> ERROR: <datapipe.cc/CheckEventValidity:L470> Channel 3 Number of ROCs (0) != Token Chain Length (4)

[06:25:51.322] <TB3> ERROR: <datapipe.cc/CheckEventID:L453> Channel 3 Event ID mismatch: local ID (256) != TBM ID (178)

[06:26:10.577] <TB3> INFO: Resuming triggers.
[06:26:14.306] <TB3> INFO: run duration 11 seconds, buffer almost full (81%), pausing triggers.
[06:26:35.926] <TB3> INFO: Resuming triggers.
[06:26:39.650] <TB3> INFO: run duration 14 seconds, buffer almost full (81%), pausing triggers.
[06:27:04.523] <TB3> INFO: Resuming triggers.
[06:27:08.246] <TB3> INFO: run duration 18 seconds, buffer almost full (81%), pausing triggers.
[06:27:34.185] <TB3> INFO: Resuming triggers.
[06:27:37.911] <TB3> INFO: run duration 22 seconds, buffer almost full (81%), pausing triggers.
[06:28:02.702] <TB3> INFO: Resuming triggers.
[06:28:06.426] <TB3> INFO: run duration 26 seconds, buffer almost full (81%), pausing triggers.
[06:28:30.876] <TB3> INFO: Resuming triggers.
[06:28:34.607] <TB3> INFO: run duration 29 seconds, buffer almost full (81%), pausing triggers.
[06:28:57.532] <TB3> INFO: Resuming triggers.
[06:29:01.260] <TB3> INFO: run duration 33 seconds, buffer almost full (81%), pausing triggers.
[06:29:24.752] <TB3> INFO: Resuming triggers.
[06:29:28.479] <TB3> INFO: run duration 37 seconds, buffer almost full (81%), pausing triggers.
[06:29:48.791] <TB3> INFO: Resuming triggers.
[06:29:52.516] <TB3> INFO: run duration 40 seconds, buffer almost full (81%), pausing triggers.
[06:30:16.225] <TB3> INFO: Resuming triggers.
[06:30:19.948] <TB3> INFO: run duration 44 seconds, buffer almost full (81%), pausing triggers.
[06:30:40.987] <TB3> INFO: Resuming triggers.
[06:30:44.707] <TB3> INFO: run duration 48 seconds, buffer almost full (81%), pausing triggers.
[06:31:05.962] <TB3> INFO: Resuming triggers.
[06:31:09.685] <TB3> INFO: run duration 52 seconds, buffer almost full (81%), pausing triggers.
[06:31:30.142] <TB3> INFO: Resuming triggers.
[06:31:33.868] <TB3> INFO: run duration 55 seconds, buffer almost full (81%), pausing triggers.
[06:31:55.904] <TB3> INFO: Resuming triggers.
[06:31:59.629] <TB3> INFO: run duration 59 seconds, buffer almost full (81%), pausing triggers.
[06:32:23.529] <TB3> INFO: Resuming triggers.
[06:32:27.250] <TB3> INFO: run duration 63 seconds, buffer almost full (81%), pausing triggers.
[06:32:48.592] <TB3> INFO: Resuming triggers.
[06:32:52.322] <TB3> INFO: run duration 66 seconds, buffer almost full (81%), pausing triggers.
[06:33:16.371] <TB3> INFO: Resuming triggers.
[06:33:20.092] <TB3> INFO: run duration 70 seconds, buffer almost full (81%), pausing triggers.
[06:33:42.879] <TB3> INFO: Resuming triggers.
[06:33:46.602] <TB3> INFO: run duration 74 seconds, buffer almost full (81%), pausing triggers.
[06:34:11.314] <TB3> INFO: Resuming triggers.
[06:34:15.034] <TB3> INFO: run duration 78 seconds, buffer almost full (81%), pausing triggers.
[06:34:36.924] <TB3> INFO: Resuming triggers.
[06:34:40.649] <TB3> INFO: run duration 81 seconds, buffer almost full (81%), pausing triggers.
[06:35:04.105] <TB3> INFO: Resuming triggers.
[06:35:07.830] <TB3> INFO: run duration 85 seconds, buffer almost full (81%), pausing triggers.
[06:35:31.637] <TB3> INFO: Resuming triggers.
[06:35:35.356] <TB3> INFO: run duration 89 seconds, buffer almost full (81%), pausing triggers.
[06:35:56.902] <TB3> INFO: Resuming triggers.
[06:36:00.629] <TB3> INFO: run duration 92 seconds, buffer almost full (81%), pausing triggers.
[06:36:22.515] <TB3> INFO: Resuming triggers.
[06:36:26.237] <TB3> INFO: run duration 96 seconds, buffer almost full (81%), pausing triggers.
[06:36:49.977] <TB3> INFO: Resuming triggers.
[06:36:53.267] <TB3> INFO: data taking finished, elapsed time: 100 seconds.
[06:37:15.691] <TB3> INFO: PixTest:: pg_setup set to default.
[06:37:15.692] <TB3> INFO: PixTestXray::doPhRun() done
[06:37:15.692] <TB3> INFO: PixTestXray::doTest() done
[06:37:15.903] <TB3> INFO: enter test to run
[06:37:15.903] <TB3> INFO: test: no parameter change
[06:37:16.090] <TB3> QUIET: Connection to board 121 closed.
[06:37:16.170] <TB3> INFO: pXar: this is the end, my friend
commander_HRSCurves.log
[06:40:41.977] <TB3> INFO: *** Welcome to pxar ***
[06:40:41.977] <TB3> INFO: *** Today: 2015/08/24
[06:40:41.977] <TB3> INFO: readRocDacs: /usr/local/data/M2079_XrayQualification_2015-08-24_08h08m_1440396521//005_HRSCurves_100//dacParameters35_C0.dat .. /usr/local/data/M2079_XrayQualification_2015-08-24_08h08m_1440396521//005_HRSCurves_100//dacParameters35_C15.dat
[06:40:41.978] <TB3> INFO: readTbmDacs: /usr/local/data/M2079_XrayQualification_2015-08-24_08h08m_1440396521//005_HRSCurves_100//tbmParameters_C0a.dat .. /usr/local/data/M2079_XrayQualification_2015-08-24_08h08m_1440396521//005_HRSCurves_100//tbmParameters_C0b.dat
[06:40:41.978] <TB3> INFO: readMaskFile: /usr/local/data/M2079_XrayQualification_2015-08-24_08h08m_1440396521//005_HRSCurves_100//defaultMaskFile.dat
[06:40:41.978] <TB3> INFO: readTrimFile: /usr/local/data/M2079_XrayQualification_2015-08-24_08h08m_1440396521//005_HRSCurves_100//trimParameters35_C0.dat .. /usr/local/data/M2079_XrayQualification_2015-08-24_08h08m_1440396521//005_HRSCurves_100//trimParameters35_C15.dat
[06:40:42.058] <TB3> INFO: clk: 4
[06:40:42.058] <TB3> INFO: ctr: 4
[06:40:42.058] <TB3> INFO: sda: 19
[06:40:42.058] <TB3> INFO: tin: 9
[06:40:42.058] <TB3> INFO: level: 15
[06:40:42.058] <TB3> INFO: triggerdelay: 0
[06:40:42.058] <TB3> QUIET: Instanciating API for pxar prod-01+80~g16e673b
[06:40:42.058] <TB3> INFO: Log level: INFO
[06:40:42.071] <TB3> INFO: Found DTB DTB_WWV6Z5
[06:40:42.082] <TB3> QUIET: Connection to board DTB_WWV6Z5 opened.
[06:40:42.085] <TB3> INFO: DTB startup information
--- DTB info------------------------------------------
Board id: 121
HW version: DTB1.2
FW version: 4.0
SW version: 4.0
USB id: DTB_WWV6Z5
MAC address: 40D855118079
Hostname: pixelDTB121
Comment:
------------------------------------------------------
[06:40:42.088] <TB3> INFO: RPC call hashes of host and DTB match: 447413373
[06:40:43.727] <TB3> INFO: DUT info:
[06:40:43.727] <TB3> INFO: The DUT currently contains the following objects:
[06:40:43.727] <TB3> INFO: 2 TBM Cores tbm09c (2 ON)
[06:40:43.727] <TB3> INFO: TBM Core alpha (0): 7 registers set
[06:40:43.727] <TB3> INFO: TBM Core beta (1): 7 registers set
[06:40:43.727] <TB3> INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[06:40:43.727] <TB3> INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[06:40:43.727] <TB3> INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[06:40:43.727] <TB3> INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[06:40:43.727] <TB3> INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[06:40:43.727] <TB3> INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[06:40:43.727] <TB3> INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[06:40:43.728] <TB3> INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[06:40:43.728] <TB3> INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[06:40:43.728] <TB3> INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[06:40:43.728] <TB3> INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[06:40:43.728] <TB3> INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[06:40:43.728] <TB3> INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[06:40:43.728] <TB3> INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[06:40:43.728] <TB3> INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[06:40:43.728] <TB3> INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[06:40:43.728] <TB3> INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[06:40:44.128] <TB3> INFO: enter 'restricted' command line mode
[06:40:44.128] <TB3> INFO: enter test to run
[06:40:44.129] <TB3> INFO: test: Highrate no parameter change
[06:40:44.129] <TB3> INFO: running: highrate
[06:40:44.129] <TB3> INFO: readGainPedestalParameters /usr/local/data/M2079_XrayQualification_2015-08-24_08h08m_1440396521//005_HRSCurves_100//phCalibrationFitErr35_C0.dat .. /usr/local/data/M2079_XrayQualification_2015-08-24_08h08m_1440396521//005_HRSCurves_100//phCalibrationFitErr35_C15.dat
[06:40:44.274] <TB3> INFO: ----------------------------------------------------------------------
[06:40:44.274] <TB3> INFO: PixTestHighRate::xNoiseMaps() ntrig = 50, vcal = 200
[06:40:44.274] <TB3> INFO: ----------------------------------------------------------------------
[06:40:44.279] <TB3> INFO: ---> dac: vcal name: xNoiseMap ntrig: 50 dacrange: 0 .. 100 (1/-1) hits flags = 384 (plus default)
[06:40:44.292] <TB3> INFO: dacScan step from 0 .. 0
[06:40:44.293] <TB3> INFO: dacScan split into 1 runs with ntrig = 50
[06:40:44.293] <TB3> INFO: run 1 of 1
[06:40:58.099] <TB3> INFO: Test took 13806ms.
[06:40:59.542] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[06:40:59.769] <TB3> INFO: dacScan step from 1 .. 1
[06:40:59.769] <TB3> INFO: dacScan split into 1 runs with ntrig = 50
[06:40:59.769] <TB3> INFO: run 1 of 1
[06:41:13.762] <TB3> INFO: Test took 13992ms.
[06:41:15.175] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[06:41:15.403] <TB3> INFO: dacScan step from 2 .. 2
[06:41:15.403] <TB3> INFO: dacScan split into 1 runs with ntrig = 50
[06:41:15.403] <TB3> INFO: run 1 of 1
[06:41:29.053] <TB3> INFO: Test took 13650ms.
[06:41:30.655] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[06:41:30.877] <TB3> INFO: dacScan step from 3 .. 3
[06:41:30.877] <TB3> INFO: dacScan split into 1 runs with ntrig = 50
[06:41:30.877] <TB3> INFO: run 1 of 1
[06:41:43.957] <TB3> INFO: Test took 13079ms.
[06:41:45.375] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[06:41:45.604] <TB3> INFO: dacScan step from 4 .. 4
[06:41:45.604] <TB3> INFO: dacScan split into 1 runs with ntrig = 50
[06:41:45.604] <TB3> INFO: run 1 of 1
[06:41:59.064] <TB3> INFO: Test took 13460ms.
[06:42:00.513] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[06:42:00.861] <TB3> INFO: dacScan step from 5 .. 5
[06:42:00.861] <TB3> INFO: dacScan split into 1 runs with ntrig = 50
[06:42:00.861] <TB3> INFO: run 1 of 1
[06:42:14.639] <TB3> INFO: Test took 13778ms.
[06:42:16.367] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[06:42:16.599] <TB3> INFO: dacScan step from 6 .. 6
[06:42:16.599] <TB3> INFO: dacScan split into 1 runs with ntrig = 50
[06:42:16.599] <TB3> INFO: run 1 of 1
[06:42:30.236] <TB3> INFO: Test took 13637ms.
[06:42:31.831] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[06:42:32.094] <TB3> INFO: dacScan step from 7 .. 7
[06:42:32.094] <TB3> INFO: dacScan split into 1 runs with ntrig = 50
[06:42:32.094] <TB3> INFO: run 1 of 1
[06:42:45.437] <TB3> INFO: Test took 13342ms.
[06:42:46.966] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[06:42:47.243] <TB3> INFO: dacScan step from 8 .. 8
[06:42:47.243] <TB3> INFO: dacScan split into 1 runs with ntrig = 50
[06:42:47.243] <TB3> INFO: run 1 of 1
[06:43:00.136] <TB3> INFO: Test took 12893ms.
[06:43:01.549] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[06:43:01.785] <TB3> INFO: dacScan step from 9 .. 9
[06:43:01.785] <TB3> INFO: dacScan split into 1 runs with ntrig = 50
[06:43:01.785] <TB3> INFO: run 1 of 1
[06:43:15.293] <TB3> INFO: Test took 13508ms.
[06:43:16.789] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[06:43:17.017] <TB3> INFO: dacScan step from 10 .. 10
[06:43:17.017] <TB3> INFO: dacScan split into 1 runs with ntrig = 50
[06:43:17.017] <TB3> INFO: run 1 of 1
[06:43:30.304] <TB3> INFO: Test took 13287ms.
[06:43:31.850] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[06:43:32.074] <TB3> INFO: dacScan step from 11 .. 11
[06:43:32.074] <TB3> INFO: dacScan split into 1 runs with ntrig = 50
[06:43:32.074] <TB3> INFO: run 1 of 1
[06:43:45.726] <TB3> INFO: Test took 13651ms.
[06:43:47.388] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[06:43:47.668] <TB3> INFO: dacScan step from 12 .. 12
[06:43:47.668] <TB3> INFO: dacScan split into 1 runs with ntrig = 50
[06:43:47.668] <TB3> INFO: run 1 of 1
[06:44:01.287] <TB3> INFO: Test took 13619ms.
[06:44:02.760] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[06:44:02.996] <TB3> INFO: dacScan step from 13 .. 13
[06:44:02.996] <TB3> INFO: dacScan split into 1 runs with ntrig = 50
[06:44:02.996] <TB3> INFO: run 1 of 1
[06:44:16.099] <TB3> INFO: Test took 13102ms.
[06:44:17.690] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[06:44:17.918] <TB3> INFO: dacScan step from 14 .. 14
[06:44:17.918] <TB3> INFO: dacScan split into 1 runs with ntrig = 50
[06:44:17.919] <TB3> INFO: run 1 of 1
[06:44:31.327] <TB3> INFO: Test took 13408ms.
[06:44:32.931] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[06:44:33.159] <TB3> INFO: dacScan step from 15 .. 15
[06:44:33.159] <TB3> INFO: dacScan split into 1 runs with ntrig = 50
[06:44:33.160] <TB3> INFO: run 1 of 1
[06:44:46.339] <TB3> INFO: Test took 13179ms.
[06:44:47.934] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[06:44:48.207] <TB3> INFO: dacScan step from 16 .. 16
[06:44:48.207] <TB3> INFO: dacScan split into 1 runs with ntrig = 50
[06:44:48.207] <TB3> INFO: run 1 of 1
[06:45:01.905] <TB3> INFO: Test took 13698ms.
[06:45:03.524] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[06:45:03.781] <TB3> INFO: dacScan step from 17 .. 17
[06:45:03.781] <TB3> INFO: dacScan split into 1 runs with ntrig = 50
[06:45:03.781] <TB3> INFO: run 1 of 1
[06:45:17.087] <TB3> INFO: Test took 13305ms.
[06:45:18.528] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[06:45:18.779] <TB3> INFO: dacScan step from 18 .. 18
[06:45:18.779] <TB3> INFO: dacScan split into 1 runs with ntrig = 50
[06:45:18.779] <TB3> INFO: run 1 of 1
[06:45:32.581] <TB3> INFO: Test took 13802ms.
[06:45:34.256] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[06:45:34.609] <TB3> INFO: dacScan step from 19 .. 19
[06:45:34.609] <TB3> INFO: dacScan split into 1 runs with ntrig = 50
[06:45:34.609] <TB3> INFO: run 1 of 1
[06:45:48.167] <TB3> INFO: Test took 13558ms.
[06:45:49.515] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[06:45:49.760] <TB3> INFO: dacScan step from 20 .. 20
[06:45:49.760] <TB3> INFO: dacScan split into 1 runs with ntrig = 50
[06:45:49.760] <TB3> INFO: run 1 of 1
[06:46:02.754] <TB3> INFO: Test took 12994ms.
[06:46:04.220] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[06:46:04.484] <TB3> INFO: dacScan step from 21 .. 21
[06:46:04.484] <TB3> INFO: dacScan split into 1 runs with ntrig = 50
[06:46:04.484] <TB3> INFO: run 1 of 1
[06:46:18.255] <TB3> INFO: Test took 13770ms.
[06:46:19.699] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[06:46:19.926] <TB3> INFO: dacScan step from 22 .. 22
[06:46:19.926] <TB3> INFO: dacScan split into 1 runs with ntrig = 50
[06:46:19.926] <TB3> INFO: run 1 of 1
[06:46:33.493] <TB3> INFO: Test took 13566ms.
[06:46:34.856] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[06:46:35.092] <TB3> INFO: dacScan step from 23 .. 23
[06:46:35.092] <TB3> INFO: dacScan split into 1 runs with ntrig = 50
[06:46:35.092] <TB3> INFO: run 1 of 1
[06:46:48.606] <TB3> INFO: Test took 13515ms.
[06:46:50.202] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[06:46:50.446] <TB3> INFO: dacScan step from 24 .. 24
[06:46:50.446] <TB3> INFO: dacScan split into 1 runs with ntrig = 50
[06:46:50.446] <TB3> INFO: run 1 of 1
[06:47:03.623] <TB3> INFO: Test took 13177ms.
[06:47:05.040] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[06:47:05.276] <TB3> INFO: dacScan step from 25 .. 25
[06:47:05.276] <TB3> INFO: dacScan split into 1 runs with ntrig = 50
[06:47:05.276] <TB3> INFO: run 1 of 1
[06:47:18.452] <TB3> INFO: Test took 13175ms.
[06:47:20.182] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[06:47:20.401] <TB3> INFO: dacScan step from 26 .. 26
[06:47:20.401] <TB3> INFO: dacScan split into 1 runs with ntrig = 50
[06:47:20.401] <TB3> INFO: run 1 of 1
[06:47:34.361] <TB3> INFO: Test took 13959ms.
[06:47:35.813] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[06:47:36.066] <TB3> INFO: dacScan step from 27 .. 27
[06:47:36.066] <TB3> INFO: dacScan split into 1 runs with ntrig = 50
[06:47:36.066] <TB3> INFO: run 1 of 1
[06:47:49.517] <TB3> INFO: Test took 13451ms.
[06:47:51.198] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[06:47:51.419] <TB3> INFO: dacScan step from 28 .. 28
[06:47:51.419] <TB3> INFO: dacScan split into 1 runs with ntrig = 50
[06:47:51.420] <TB3> INFO: run 1 of 1
[06:48:04.711] <TB3> INFO: Test took 13292ms.
[06:48:06.213] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[06:48:06.436] <TB3> INFO: dacScan step from 29 .. 29
[06:48:06.436] <TB3> INFO: dacScan split into 1 runs with ntrig = 50
[06:48:06.436] <TB3> INFO: run 1 of 1
[06:48:19.413] <TB3> INFO: Test took 12977ms.
[06:48:21.077] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[06:48:21.352] <TB3> INFO: dacScan step from 30 .. 30
[06:48:21.352] <TB3> INFO: dacScan split into 1 runs with ntrig = 50
[06:48:21.352] <TB3> INFO: run 1 of 1
[06:48:34.891] <TB3> INFO: Test took 13538ms.
[06:48:36.419] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[06:48:36.674] <TB3> INFO: dacScan step from 31 .. 31
[06:48:36.674] <TB3> INFO: dacScan split into 1 runs with ntrig = 50
[06:48:36.674] <TB3> INFO: run 1 of 1
[06:48:50.057] <TB3> INFO: Test took 13382ms.
[06:48:51.464] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[06:48:51.710] <TB3> INFO: dacScan step from 32 .. 32
[06:48:51.710] <TB3> INFO: dacScan split into 1 runs with ntrig = 50
[06:48:51.710] <TB3> INFO: run 1 of 1
[06:49:05.433] <TB3> INFO: Test took 13723ms.
[06:49:07.162] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[06:49:07.438] <TB3> INFO: dacScan step from 33 .. 33
[06:49:07.438] <TB3> INFO: dacScan split into 1 runs with ntrig = 50
[06:49:07.438] <TB3> INFO: run 1 of 1
[06:49:20.893] <TB3> INFO: Test took 13454ms.
[06:49:22.373] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[06:49:22.612] <TB3> INFO: dacScan step from 34 .. 34
[06:49:22.613] <TB3> INFO: dacScan split into 1 runs with ntrig = 50
[06:49:22.613] <TB3> INFO: run 1 of 1
[06:49:35.779] <TB3> INFO: Test took 13166ms.
[06:49:37.471] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[06:49:37.727] <TB3> INFO: dacScan step from 35 .. 35
[06:49:37.727] <TB3> INFO: dacScan split into 1 runs with ntrig = 50
[06:49:37.727] <TB3> INFO: run 1 of 1
[06:49:51.172] <TB3> INFO: Test took 13444ms.
[06:49:52.684] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[06:49:52.935] <TB3> INFO: dacScan step from 36 .. 36
[06:49:52.935] <TB3> INFO: dacScan split into 1 runs with ntrig = 50
[06:49:52.935] <TB3> INFO: run 1 of 1
[06:50:06.610] <TB3> INFO: Test took 13674ms.
[06:50:08.057] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[06:50:08.302] <TB3> INFO: dacScan step from 37 .. 37
[06:50:08.302] <TB3> INFO: dacScan split into 1 runs with ntrig = 50
[06:50:08.302] <TB3> INFO: run 1 of 1
[06:50:22.547] <TB3> INFO: Test took 14244ms.
[06:50:24.071] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[06:50:24.318] <TB3> INFO: dacScan step from 38 .. 38
[06:50:24.318] <TB3> INFO: dacScan split into 1 runs with ntrig = 50
[06:50:24.318] <TB3> INFO: run 1 of 1
[06:50:38.808] <TB3> INFO: Test took 14489ms.
[06:50:40.362] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[06:50:40.606] <TB3> INFO: dacScan step from 39 .. 39
[06:50:40.606] <TB3> INFO: dacScan split into 1 runs with ntrig = 50
[06:50:40.606] <TB3> INFO: run 1 of 1
[06:50:55.367] <TB3> INFO: Test took 14761ms.
[06:50:57.008] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[06:50:57.234] <TB3> INFO: dacScan step from 40 .. 40
[06:50:57.234] <TB3> INFO: dacScan split into 1 runs with ntrig = 50
[06:50:57.234] <TB3> INFO: run 1 of 1
[06:51:12.101] <TB3> INFO: Test took 14866ms.
[06:51:13.781] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[06:51:14.039] <TB3> INFO: dacScan step from 41 .. 41
[06:51:14.039] <TB3> INFO: dacScan split into 1 runs with ntrig = 50
[06:51:14.039] <TB3> INFO: run 1 of 1
[06:51:28.851] <TB3> INFO: Test took 14811ms.
[06:51:30.423] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[06:51:30.685] <TB3> INFO: dacScan step from 42 .. 42
[06:51:30.685] <TB3> INFO: dacScan split into 1 runs with ntrig = 50
[06:51:30.685] <TB3> INFO: run 1 of 1
[06:51:45.650] <TB3> INFO: Test took 14965ms.
[06:51:47.421] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[06:51:47.681] <TB3> INFO: dacScan step from 43 .. 43
[06:51:47.681] <TB3> INFO: dacScan split into 1 runs with ntrig = 50
[06:51:47.681] <TB3> INFO: run 1 of 1
[06:52:03.194] <TB3> INFO: Test took 15513ms.
[06:52:05.212] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[06:52:05.477] <TB3> INFO: dacScan step from 44 .. 44
[06:52:05.477] <TB3> INFO: dacScan split into 1 runs with ntrig = 50
[06:52:05.477] <TB3> INFO: run 1 of 1
[06:52:21.033] <TB3> INFO: Test took 15555ms.
[06:52:22.834] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[06:52:23.085] <TB3> INFO: dacScan step from 45 .. 45
[06:52:23.085] <TB3> INFO: dacScan split into 1 runs with ntrig = 50
[06:52:23.085] <TB3> INFO: run 1 of 1
[06:52:38.987] <TB3> INFO: Test took 15901ms.
[06:52:40.607] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[06:52:40.834] <TB3> INFO: dacScan step from 46 .. 46
[06:52:40.834] <TB3> INFO: dacScan split into 1 runs with ntrig = 50
[06:52:40.834] <TB3> INFO: run 1 of 1
[06:52:57.015] <TB3> INFO: Test took 16180ms.
[06:52:58.653] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[06:52:58.897] <TB3> INFO: dacScan step from 47 .. 47
[06:52:58.897] <TB3> INFO: dacScan split into 1 runs with ntrig = 50
[06:52:58.897] <TB3> INFO: run 1 of 1
[06:53:15.722] <TB3> INFO: Test took 16824ms.
[06:53:17.680] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[06:53:17.947] <TB3> INFO: dacScan step from 48 .. 48
[06:53:17.947] <TB3> INFO: dacScan split into 1 runs with ntrig = 50
[06:53:17.947] <TB3> INFO: run 1 of 1
[06:53:34.584] <TB3> INFO: Test took 16636ms.
[06:53:36.180] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[06:53:36.408] <TB3> INFO: dacScan step from 49 .. 49
[06:53:36.408] <TB3> INFO: dacScan split into 1 runs with ntrig = 50
[06:53:36.408] <TB3> INFO: run 1 of 1
[06:53:53.218] <TB3> INFO: Test took 16809ms.
[06:53:55.156] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[06:53:55.444] <TB3> INFO: dacScan step from 50 .. 50
[06:53:55.444] <TB3> INFO: dacScan split into 1 runs with ntrig = 50
[06:53:55.444] <TB3> INFO: run 1 of 1
[06:54:12.165] <TB3> INFO: Test took 16720ms.
[06:54:14.047] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[06:54:14.321] <TB3> INFO: dacScan step from 51 .. 51
[06:54:14.321] <TB3> INFO: dacScan split into 1 runs with ntrig = 50
[06:54:14.321] <TB3> INFO: run 1 of 1
[06:54:30.645] <TB3> INFO: Test took 16323ms.
[06:54:32.580] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[06:54:32.835] <TB3> INFO: dacScan step from 52 .. 52
[06:54:32.835] <TB3> INFO: dacScan split into 1 runs with ntrig = 50
[06:54:32.835] <TB3> INFO: run 1 of 1
[06:54:49.078] <TB3> INFO: Test took 16242ms.
[06:54:50.742] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[06:54:50.993] <TB3> INFO: dacScan step from 53 .. 53
[06:54:50.993] <TB3> INFO: dacScan split into 1 runs with ntrig = 50
[06:54:50.993] <TB3> INFO: run 1 of 1
[06:55:07.758] <TB3> INFO: Test took 16764ms.
[06:55:09.670] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[06:55:09.920] <TB3> INFO: dacScan step from 54 .. 54
[06:55:09.920] <TB3> INFO: dacScan split into 1 runs with ntrig = 50
[06:55:09.921] <TB3> INFO: run 1 of 1
[06:55:26.537] <TB3> INFO: Test took 16616ms.
[06:55:28.271] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[06:55:28.523] <TB3> INFO: dacScan step from 55 .. 55
[06:55:28.523] <TB3> INFO: dacScan split into 1 runs with ntrig = 50
[06:55:28.523] <TB3> INFO: run 1 of 1
[06:55:45.319] <TB3> INFO: Test took 16795ms.
[06:55:47.205] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[06:55:47.492] <TB3> INFO: dacScan step from 56 .. 56
[06:55:47.492] <TB3> INFO: dacScan split into 1 runs with ntrig = 50
[06:55:47.492] <TB3> INFO: run 1 of 1
[06:56:04.248] <TB3> INFO: Test took 16755ms.
[06:56:06.078] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[06:56:06.320] <TB3> INFO: dacScan step from 57 .. 57
[06:56:06.320] <TB3> INFO: dacScan split into 1 runs with ntrig = 50
[06:56:06.320] <TB3> INFO: run 1 of 1
[06:56:22.858] <TB3> INFO: Test took 16537ms.
[06:56:24.925] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[06:56:25.159] <TB3> INFO: dacScan step from 58 .. 58
[06:56:25.159] <TB3> INFO: dacScan split into 1 runs with ntrig = 50
[06:56:25.159] <TB3> INFO: run 1 of 1
[06:56:42.331] <TB3> INFO: Test took 17171ms.
[06:56:44.148] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[06:56:44.412] <TB3> INFO: dacScan step from 59 .. 59
[06:56:44.412] <TB3> INFO: dacScan split into 1 runs with ntrig = 50
[06:56:44.412] <TB3> INFO: run 1 of 1
[06:57:01.296] <TB3> INFO: Test took 16883ms.
[06:57:03.027] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[06:57:03.269] <TB3> INFO: dacScan step from 60 .. 60
[06:57:03.269] <TB3> INFO: dacScan split into 1 runs with ntrig = 50
[06:57:03.269] <TB3> INFO: run 1 of 1
[06:57:19.618] <TB3> INFO: Test took 16349ms.
[06:57:21.522] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[06:57:21.755] <TB3> INFO: dacScan step from 61 .. 61
[06:57:21.755] <TB3> INFO: dacScan split into 1 runs with ntrig = 50
[06:57:21.755] <TB3> INFO: run 1 of 1
[06:57:38.421] <TB3> INFO: Test took 16666ms.
[06:57:40.283] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[06:57:40.569] <TB3> INFO: dacScan step from 62 .. 62
[06:57:40.569] <TB3> INFO: dacScan split into 1 runs with ntrig = 50
[06:57:40.569] <TB3> INFO: run 1 of 1
[06:57:56.953] <TB3> INFO: Test took 16383ms.
[06:57:58.620] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[06:57:58.860] <TB3> INFO: dacScan step from 63 .. 63
[06:57:58.860] <TB3> INFO: dacScan split into 1 runs with ntrig = 50
[06:57:58.860] <TB3> INFO: run 1 of 1
[06:58:15.883] <TB3> INFO: Test took 17022ms.
[06:58:17.937] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[06:58:18.236] <TB3> INFO: dacScan step from 64 .. 64
[06:58:18.236] <TB3> INFO: dacScan split into 1 runs with ntrig = 50
[06:58:18.236] <TB3> INFO: run 1 of 1
[06:58:35.257] <TB3> INFO: Test took 17020ms.
[06:58:37.104] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[06:58:37.375] <TB3> INFO: dacScan step from 65 .. 65
[06:58:37.376] <TB3> INFO: dacScan split into 1 runs with ntrig = 50
[06:58:37.376] <TB3> INFO: run 1 of 1
[06:58:54.064] <TB3> INFO: Test took 16688ms.
[06:58:56.002] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[06:58:56.304] <TB3> INFO: dacScan step from 66 .. 66
[06:58:56.304] <TB3> INFO: dacScan split into 1 runs with ntrig = 50
[06:58:56.304] <TB3> INFO: run 1 of 1
[06:59:13.200] <TB3> INFO: Test took 16895ms.
[06:59:15.106] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[06:59:15.392] <TB3> INFO: dacScan step from 67 .. 67
[06:59:15.392] <TB3> INFO: dacScan split into 1 runs with ntrig = 50
[06:59:15.392] <TB3> INFO: run 1 of 1
[06:59:32.526] <TB3> INFO: Test took 17133ms.
[06:59:34.270] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[06:59:34.528] <TB3> INFO: dacScan step from 68 .. 68
[06:59:34.528] <TB3> INFO: dacScan split into 1 runs with ntrig = 50
[06:59:34.528] <TB3> INFO: run 1 of 1
[06:59:50.791] <TB3> INFO: Test took 16263ms.
[06:59:52.565] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[06:59:52.798] <TB3> INFO: dacScan step from 69 .. 69
[06:59:52.798] <TB3> INFO: dacScan split into 1 runs with ntrig = 50
[06:59:52.798] <TB3> INFO: run 1 of 1
[07:00:09.982] <TB3> INFO: Test took 17183ms.
[07:00:11.665] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[07:00:11.897] <TB3> INFO: dacScan step from 70 .. 70
[07:00:11.897] <TB3> INFO: dacScan split into 1 runs with ntrig = 50
[07:00:11.897] <TB3> INFO: run 1 of 1
[07:00:28.807] <TB3> INFO: Test took 16909ms.
[07:00:30.535] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[07:00:30.772] <TB3> INFO: dacScan step from 71 .. 71
[07:00:30.772] <TB3> INFO: dacScan split into 1 runs with ntrig = 50
[07:00:30.772] <TB3> INFO: run 1 of 1
[07:00:47.687] <TB3> INFO: Test took 16914ms.
[07:00:49.696] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[07:00:49.968] <TB3> INFO: dacScan step from 72 .. 72
[07:00:49.968] <TB3> INFO: dacScan split into 1 runs with ntrig = 50
[07:00:49.968] <TB3> INFO: run 1 of 1
[07:01:06.354] <TB3> INFO: Test took 16386ms.
[07:01:08.175] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[07:01:08.459] <TB3> INFO: dacScan step from 73 .. 73
[07:01:08.459] <TB3> INFO: dacScan split into 1 runs with ntrig = 50
[07:01:08.459] <TB3> INFO: run 1 of 1
[07:01:25.482] <TB3> INFO: Test took 17022ms.
[07:01:27.312] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[07:01:27.550] <TB3> INFO: dacScan step from 74 .. 74
[07:01:27.550] <TB3> INFO: dacScan split into 1 runs with ntrig = 50
[07:01:27.550] <TB3> INFO: run 1 of 1
[07:01:44.104] <TB3> INFO: Test took 16554ms.
[07:01:45.892] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[07:01:46.133] <TB3> INFO: dacScan step from 75 .. 75
[07:01:46.133] <TB3> INFO: dacScan split into 1 runs with ntrig = 50
[07:01:46.134] <TB3> INFO: run 1 of 1
[07:02:02.986] <TB3> INFO: Test took 16851ms.
[07:02:04.976] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[07:02:05.207] <TB3> INFO: dacScan step from 76 .. 76
[07:02:05.208] <TB3> INFO: dacScan split into 1 runs with ntrig = 50
[07:02:05.208] <TB3> INFO: run 1 of 1
[07:02:22.452] <TB3> INFO: Test took 17244ms.
[07:02:24.220] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[07:02:24.479] <TB3> INFO: dacScan step from 77 .. 77
[07:02:24.479] <TB3> INFO: dacScan split into 1 runs with ntrig = 50
[07:02:24.479] <TB3> INFO: run 1 of 1
[07:02:41.808] <TB3> INFO: Test took 17328ms.
[07:02:43.534] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[07:02:43.771] <TB3> INFO: dacScan step from 78 .. 78
[07:02:43.771] <TB3> INFO: dacScan split into 1 runs with ntrig = 50
[07:02:43.771] <TB3> INFO: run 1 of 1
[07:03:00.615] <TB3> INFO: Test took 16843ms.
[07:03:02.436] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[07:03:02.683] <TB3> INFO: dacScan step from 79 .. 79
[07:03:02.683] <TB3> INFO: dacScan split into 1 runs with ntrig = 50
[07:03:02.683] <TB3> INFO: run 1 of 1
[07:03:19.251] <TB3> INFO: Test took 16567ms.
[07:03:21.221] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[07:03:21.507] <TB3> INFO: dacScan step from 80 .. 80
[07:03:21.507] <TB3> INFO: dacScan split into 1 runs with ntrig = 50
[07:03:21.507] <TB3> INFO: run 1 of 1
[07:03:37.930] <TB3> INFO: Test took 16422ms.
[07:03:40.121] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[07:03:40.359] <TB3> INFO: dacScan step from 81 .. 81
[07:03:40.359] <TB3> INFO: dacScan split into 1 runs with ntrig = 50
[07:03:40.359] <TB3> INFO: run 1 of 1
[07:03:57.314] <TB3> INFO: Test took 16954ms.
[07:03:59.037] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[07:03:59.316] <TB3> INFO: dacScan step from 82 .. 82
[07:03:59.316] <TB3> INFO: dacScan split into 1 runs with ntrig = 50
[07:03:59.316] <TB3> INFO: run 1 of 1
[07:04:16.199] <TB3> INFO: Test took 16882ms.
[07:04:18.161] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[07:04:18.436] <TB3> INFO: dacScan step from 83 .. 83
[07:04:18.436] <TB3> INFO: dacScan split into 1 runs with ntrig = 50
[07:04:18.436] <TB3> INFO: run 1 of 1
[07:04:35.193] <TB3> INFO: Test took 16756ms.
[07:04:36.869] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[07:04:37.144] <TB3> INFO: dacScan step from 84 .. 84
[07:04:37.144] <TB3> INFO: dacScan split into 1 runs with ntrig = 50
[07:04:37.144] <TB3> INFO: run 1 of 1
[07:04:53.828] <TB3> INFO: Test took 16683ms.
[07:04:55.481] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[07:04:55.726] <TB3> INFO: dacScan step from 85 .. 85
[07:04:55.726] <TB3> INFO: dacScan split into 1 runs with ntrig = 50
[07:04:55.726] <TB3> INFO: run 1 of 1
[07:05:12.627] <TB3> INFO: Test took 16901ms.
[07:05:14.372] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[07:05:14.654] <TB3> INFO: dacScan step from 86 .. 86
[07:05:14.654] <TB3> INFO: dacScan split into 1 runs with ntrig = 50
[07:05:14.654] <TB3> INFO: run 1 of 1
[07:05:31.545] <TB3> INFO: Test took 16890ms.
[07:05:33.311] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[07:05:33.548] <TB3> INFO: dacScan step from 87 .. 87
[07:05:33.548] <TB3> INFO: dacScan split into 1 runs with ntrig = 50
[07:05:33.548] <TB3> INFO: run 1 of 1
[07:05:50.445] <TB3> INFO: Test took 16896ms.
[07:05:52.172] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[07:05:52.412] <TB3> INFO: dacScan step from 88 .. 88
[07:05:52.413] <TB3> INFO: dacScan split into 1 runs with ntrig = 50
[07:05:52.413] <TB3> INFO: run 1 of 1
[07:06:09.850] <TB3> INFO: Test took 17437ms.
[07:06:11.575] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[07:06:11.813] <TB3> INFO: dacScan step from 89 .. 89
[07:06:11.813] <TB3> INFO: dacScan split into 1 runs with ntrig = 50
[07:06:11.813] <TB3> INFO: run 1 of 1
[07:06:28.634] <TB3> INFO: Test took 16820ms.
[07:06:30.604] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[07:06:30.888] <TB3> INFO: dacScan step from 90 .. 90
[07:06:30.888] <TB3> INFO: dacScan split into 1 runs with ntrig = 50
[07:06:30.888] <TB3> INFO: run 1 of 1
[07:06:47.374] <TB3> INFO: Test took 16485ms.
[07:06:49.112] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[07:06:49.347] <TB3> INFO: dacScan step from 91 .. 91
[07:06:49.347] <TB3> INFO: dacScan split into 1 runs with ntrig = 50
[07:06:49.347] <TB3> INFO: run 1 of 1
[07:07:06.254] <TB3> INFO: Test took 16906ms.
[07:07:07.867] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[07:07:08.107] <TB3> INFO: dacScan step from 92 .. 92
[07:07:08.107] <TB3> INFO: dacScan split into 1 runs with ntrig = 50
[07:07:08.107] <TB3> INFO: run 1 of 1
[07:07:25.361] <TB3> INFO: Test took 17253ms.
[07:07:27.217] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[07:07:27.481] <TB3> INFO: dacScan step from 93 .. 93
[07:07:27.481] <TB3> INFO: dacScan split into 1 runs with ntrig = 50
[07:07:27.481] <TB3> INFO: run 1 of 1
[07:07:44.342] <TB3> INFO: Test took 16860ms.
[07:07:46.368] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[07:07:46.658] <TB3> INFO: dacScan step from 94 .. 94
[07:07:46.658] <TB3> INFO: dacScan split into 1 runs with ntrig = 50
[07:07:46.658] <TB3> INFO: run 1 of 1
[07:08:02.628] <TB3> INFO: Test took 15968ms.
[07:08:04.245] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[07:08:04.499] <TB3> INFO: dacScan step from 95 .. 95
[07:08:04.499] <TB3> INFO: dacScan split into 1 runs with ntrig = 50
[07:08:04.499] <TB3> INFO: run 1 of 1
[07:08:21.613] <TB3> INFO: Test took 17113ms.
[07:08:23.396] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[07:08:23.720] <TB3> INFO: dacScan step from 96 .. 96
[07:08:23.720] <TB3> INFO: dacScan split into 1 runs with ntrig = 50
[07:08:23.720] <TB3> INFO: run 1 of 1
[07:08:40.593] <TB3> INFO: Test took 16872ms.
[07:08:42.143] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[07:08:42.381] <TB3> INFO: dacScan step from 97 .. 97
[07:08:42.381] <TB3> INFO: dacScan split into 1 runs with ntrig = 50
[07:08:42.381] <TB3> INFO: run 1 of 1
[07:08:59.245] <TB3> INFO: Test took 16863ms.
[07:09:00.868] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[07:09:01.192] <TB3> INFO: dacScan step from 98 .. 98
[07:09:01.192] <TB3> INFO: dacScan split into 1 runs with ntrig = 50
[07:09:01.192] <TB3> INFO: run 1 of 1
[07:09:17.986] <TB3> INFO: Test took 16793ms.
[07:09:19.739] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[07:09:19.995] <TB3> INFO: dacScan step from 99 .. 99
[07:09:19.995] <TB3> INFO: dacScan split into 1 runs with ntrig = 50
[07:09:19.995] <TB3> INFO: run 1 of 1
[07:09:36.755] <TB3> INFO: Test took 16759ms.
[07:09:38.312] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[07:09:38.539] <TB3> INFO: dacScan step from 100 .. 100
[07:09:38.539] <TB3> INFO: dacScan split into 1 runs with ntrig = 50
[07:09:38.539] <TB3> INFO: run 1 of 1
[07:09:54.136] <TB3> INFO: Test took 15596ms.
[07:09:55.732] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[07:09:55.980] <TB3> INFO: dumping ASCII scurve output file: XSCurveData
[07:09:57.356] <TB3> INFO: dumping ASCII scurve output file: XSCurveData
[07:09:58.735] <TB3> INFO: dumping ASCII scurve output file: XSCurveData
[07:10:00.043] <TB3> INFO: dumping ASCII scurve output file: XSCurveData
[07:10:01.382] <TB3> INFO: dumping ASCII scurve output file: XSCurveData
[07:10:02.917] <TB3> INFO: dumping ASCII scurve output file: XSCurveData
[07:10:04.445] <TB3> INFO: dumping ASCII scurve output file: XSCurveData
[07:10:06.117] <TB3> INFO: dumping ASCII scurve output file: XSCurveData
[07:10:07.581] <TB3> INFO: dumping ASCII scurve output file: XSCurveData
[07:10:09.031] <TB3> INFO: dumping ASCII scurve output file: XSCurveData
[07:10:10.513] <TB3> INFO: dumping ASCII scurve output file: XSCurveData
[07:10:12.172] <TB3> INFO: dumping ASCII scurve output file: XSCurveData
[07:10:13.822] <TB3> INFO: dumping ASCII scurve output file: XSCurveData
[07:10:15.267] <TB3> INFO: dumping ASCII scurve output file: XSCurveData
[07:10:16.512] <TB3> INFO: dumping ASCII scurve output file: XSCurveData
[07:10:17.784] <TB3> INFO: dumping ASCII scurve output file: XSCurveData
[07:10:19.082] <TB3> INFO: PixTest:: pg_setup set to default.
[07:10:19.083] <TB3> INFO: vcal mean: 37.50 38.34 37.54 40.65 40.92 41.38 42.72 42.02 42.35 41.81 42.22 40.98 40.58 38.41 38.28 38.01
[07:10:19.083] <TB3> INFO: vcal RMS: 1.17 1.46 1.13 1.61 1.29 1.37 1.61 1.49 1.56 1.44 1.60 1.55 1.50 1.32 1.20 1.43
[07:10:19.083] <TB3> INFO: number of X-ray hits detected: 164272 178385 154878 295321 297247 326762 376682 344475 376104 363554 378072 313527 304400 171664 174961 180151
[07:10:19.083] <TB3> INFO: number of triggers sent (total per ROC): 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000
[07:10:19.083] <TB3> INFO: X-ray hit rate [MHz/cm2]: 48.1 52.3 45.4 86.6 87.1 95.8 110.4 101.0 110.2 106.6 110.8 91.9 89.2 50.3 51.3 52.8
[07:10:19.083] <TB3> INFO: PixTestHighRate::doXNoiseMaps() done
[07:10:19.181] <TB3> INFO: enter test to run
[07:10:19.181] <TB3> INFO: test: no parameter change
[07:10:19.362] <TB3> QUIET: Connection to board 121 closed.
[07:10:19.442] <TB3> INFO: pXar: this is the end, my friend
commander_CalDelScanAndSaveDacs.log
[07:31:44.173] <TB3> INFO: *** Welcome to pxar ***
[07:31:44.173] <TB3> INFO: *** Today: 2015/08/24
[07:31:44.173] <TB3> INFO: readRocDacs: /usr/local/data/M2079_XrayQualification_2015-08-24_08h08m_1440396521//010_CalDelScanAndSaveDacs_4mA25kV//dacParameters35_C0.dat .. /usr/local/data/M2079_XrayQualification_2015-08-24_08h08m_1440396521//010_CalDelScanAndSaveDacs_4mA25kV//dacParameters35_C15.dat
[07:31:44.174] <TB3> INFO: readTbmDacs: /usr/local/data/M2079_XrayQualification_2015-08-24_08h08m_1440396521//010_CalDelScanAndSaveDacs_4mA25kV//tbmParameters_C0a.dat .. /usr/local/data/M2079_XrayQualification_2015-08-24_08h08m_1440396521//010_CalDelScanAndSaveDacs_4mA25kV//tbmParameters_C0b.dat
[07:31:44.174] <TB3> INFO: readMaskFile: /usr/local/data/M2079_XrayQualification_2015-08-24_08h08m_1440396521//010_CalDelScanAndSaveDacs_4mA25kV//defaultMaskFile.dat
[07:31:44.174] <TB3> INFO: readTrimFile: /usr/local/data/M2079_XrayQualification_2015-08-24_08h08m_1440396521//010_CalDelScanAndSaveDacs_4mA25kV//trimParameters35_C0.dat .. /usr/local/data/M2079_XrayQualification_2015-08-24_08h08m_1440396521//010_CalDelScanAndSaveDacs_4mA25kV//trimParameters35_C15.dat
[07:31:44.252] <TB3> INFO: clk: 4
[07:31:44.252] <TB3> INFO: ctr: 4
[07:31:44.252] <TB3> INFO: sda: 19
[07:31:44.252] <TB3> INFO: tin: 9
[07:31:44.252] <TB3> INFO: level: 15
[07:31:44.252] <TB3> INFO: triggerdelay: 0
[07:31:44.252] <TB3> QUIET: Instanciating API for pxar prod-01+86~g1838649
[07:31:44.252] <TB3> INFO: Log level: INFO
[07:31:44.265] <TB3> INFO: Found DTB DTB_WWV6Z5
[07:31:44.277] <TB3> QUIET: Connection to board DTB_WWV6Z5 opened.
[07:31:44.280] <TB3> INFO: DTB startup information
--- DTB info------------------------------------------
Board id: 121
HW version: DTB1.2
FW version: 4.0
SW version: 4.0
USB id: DTB_WWV6Z5
MAC address: 40D855118079
Hostname: pixelDTB121
Comment:
------------------------------------------------------
[07:31:44.283] <TB3> INFO: RPC call hashes of host and DTB match: 447413373
[07:31:45.834] <TB3> INFO: DUT info:
[07:31:45.834] <TB3> INFO: The DUT currently contains the following objects:
[07:31:45.834] <TB3> INFO: 2 TBM Cores tbm09c (2 ON)
[07:31:45.834] <TB3> INFO: TBM Core alpha (0): 7 registers set
[07:31:45.834] <TB3> INFO: TBM Core beta (1): 7 registers set
[07:31:45.834] <TB3> INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[07:31:45.834] <TB3> INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[07:31:45.834] <TB3> INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[07:31:45.834] <TB3> INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[07:31:45.834] <TB3> INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[07:31:45.834] <TB3> INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[07:31:45.834] <TB3> INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[07:31:45.834] <TB3> INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[07:31:45.834] <TB3> INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[07:31:45.834] <TB3> INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[07:31:45.834] <TB3> INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[07:31:45.834] <TB3> INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[07:31:45.834] <TB3> INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[07:31:45.834] <TB3> INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[07:31:45.834] <TB3> INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[07:31:45.834] <TB3> INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[07:31:45.834] <TB3> INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[07:31:46.235] <TB3> INFO: enter 'restricted' command line mode
[07:31:46.235] <TB3> INFO: enter test to run
[07:31:46.235] <TB3> INFO: test: HighRate no parameter change
[07:31:46.235] <TB3> INFO: running: highrate
[07:31:46.236] <TB3> INFO: readGainPedestalParameters /usr/local/data/M2079_XrayQualification_2015-08-24_08h08m_1440396521//010_CalDelScanAndSaveDacs_4mA25kV//phCalibrationFitErr35_C0.dat .. /usr/local/data/M2079_XrayQualification_2015-08-24_08h08m_1440396521//010_CalDelScanAndSaveDacs_4mA25kV//phCalibrationFitErr35_C15.dat
[07:31:46.443] <TB3> INFO: ----------------------------------------------------------------------
[07:31:46.443] <TB3> INFO: PixTestHighRate::calDelScan() ntrig = 10, vcal = 200
[07:31:46.443] <TB3> INFO: ----------------------------------------------------------------------
[07:31:47.525] <TB3> INFO: Test took 1077ms.
[07:31:51.272] <TB3> INFO: Test took 3741ms.
[07:31:51.416] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[07:31:55.239] <TB3> INFO: Test took 3793ms.
[07:31:55.399] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[07:31:59.317] <TB3> INFO: Test took 3890ms.
[07:31:59.486] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[07:32:03.588] <TB3> INFO: Test took 4068ms.
[07:32:03.738] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[07:32:07.504] <TB3> INFO: Test took 3738ms.
[07:32:07.665] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[07:32:11.453] <TB3> INFO: Test took 3757ms.
[07:32:11.606] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[07:32:15.514] <TB3> INFO: Test took 3880ms.
[07:32:15.671] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[07:32:19.729] <TB3> INFO: Test took 4024ms.
[07:32:19.895] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[07:32:23.922] <TB3> INFO: Test took 4002ms.
[07:32:24.089] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[07:32:28.108] <TB3> INFO: Test took 3989ms.
[07:32:28.253] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[07:32:32.177] <TB3> INFO: Test took 3894ms.
[07:32:32.326] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[07:32:36.213] <TB3> INFO: Test took 3859ms.
[07:32:36.372] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[07:32:40.426] <TB3> INFO: Test took 4024ms.
[07:32:40.579] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[07:32:44.456] <TB3> INFO: Test took 3845ms.
[07:32:44.631] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[07:32:48.567] <TB3> INFO: Test took 3901ms.
[07:32:48.705] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[07:32:52.620] <TB3> INFO: Test took 3888ms.
[07:32:52.768] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[07:32:56.583] <TB3> INFO: Test took 3787ms.
[07:32:56.717] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[07:33:00.453] <TB3> INFO: Test took 3712ms.
[07:33:00.584] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[07:33:04.337] <TB3> INFO: Test took 3729ms.
[07:33:04.468] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[07:33:08.027] <TB3> INFO: Test took 3533ms.
[07:33:08.154] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[07:33:08.448] <TB3> INFO: enter test to run
[07:33:08.448] <TB3> INFO: test: Pretest no parameter change
[07:33:08.448] <TB3> INFO: running: pretest
[07:33:08.448] <TB3> INFO: write dac parameters into /usr/local/data/M2079_XrayQualification_2015-08-24_08h08m_1440396521//010_CalDelScanAndSaveDacs_4mA25kV//dacParameters35_C0.dat
[07:33:08.449] <TB3> INFO: write dac parameters into /usr/local/data/M2079_XrayQualification_2015-08-24_08h08m_1440396521//010_CalDelScanAndSaveDacs_4mA25kV//dacParameters35_C1.dat
[07:33:08.449] <TB3> INFO: write dac parameters into /usr/local/data/M2079_XrayQualification_2015-08-24_08h08m_1440396521//010_CalDelScanAndSaveDacs_4mA25kV//dacParameters35_C2.dat
[07:33:08.449] <TB3> INFO: write dac parameters into /usr/local/data/M2079_XrayQualification_2015-08-24_08h08m_1440396521//010_CalDelScanAndSaveDacs_4mA25kV//dacParameters35_C3.dat
[07:33:08.449] <TB3> INFO: write dac parameters into /usr/local/data/M2079_XrayQualification_2015-08-24_08h08m_1440396521//010_CalDelScanAndSaveDacs_4mA25kV//dacParameters35_C4.dat
[07:33:08.449] <TB3> INFO: write dac parameters into /usr/local/data/M2079_XrayQualification_2015-08-24_08h08m_1440396521//010_CalDelScanAndSaveDacs_4mA25kV//dacParameters35_C5.dat
[07:33:08.449] <TB3> INFO: write dac parameters into /usr/local/data/M2079_XrayQualification_2015-08-24_08h08m_1440396521//010_CalDelScanAndSaveDacs_4mA25kV//dacParameters35_C6.dat
[07:33:08.449] <TB3> INFO: write dac parameters into /usr/local/data/M2079_XrayQualification_2015-08-24_08h08m_1440396521//010_CalDelScanAndSaveDacs_4mA25kV//dacParameters35_C7.dat
[07:33:08.449] <TB3> INFO: write dac parameters into /usr/local/data/M2079_XrayQualification_2015-08-24_08h08m_1440396521//010_CalDelScanAndSaveDacs_4mA25kV//dacParameters35_C8.dat
[07:33:08.449] <TB3> INFO: write dac parameters into /usr/local/data/M2079_XrayQualification_2015-08-24_08h08m_1440396521//010_CalDelScanAndSaveDacs_4mA25kV//dacParameters35_C9.dat
[07:33:08.449] <TB3> INFO: write dac parameters into /usr/local/data/M2079_XrayQualification_2015-08-24_08h08m_1440396521//010_CalDelScanAndSaveDacs_4mA25kV//dacParameters35_C10.dat
[07:33:08.450] <TB3> INFO: write dac parameters into /usr/local/data/M2079_XrayQualification_2015-08-24_08h08m_1440396521//010_CalDelScanAndSaveDacs_4mA25kV//dacParameters35_C11.dat
[07:33:08.450] <TB3> INFO: write dac parameters into /usr/local/data/M2079_XrayQualification_2015-08-24_08h08m_1440396521//010_CalDelScanAndSaveDacs_4mA25kV//dacParameters35_C12.dat
[07:33:08.450] <TB3> INFO: write dac parameters into /usr/local/data/M2079_XrayQualification_2015-08-24_08h08m_1440396521//010_CalDelScanAndSaveDacs_4mA25kV//dacParameters35_C13.dat
[07:33:08.450] <TB3> INFO: write dac parameters into /usr/local/data/M2079_XrayQualification_2015-08-24_08h08m_1440396521//010_CalDelScanAndSaveDacs_4mA25kV//dacParameters35_C14.dat
[07:33:08.450] <TB3> INFO: write dac parameters into /usr/local/data/M2079_XrayQualification_2015-08-24_08h08m_1440396521//010_CalDelScanAndSaveDacs_4mA25kV//dacParameters35_C15.dat
[07:33:08.450] <TB3> INFO: enter test to run
[07:33:08.450] <TB3> INFO: test: no parameter change
[07:33:08.611] <TB3> QUIET: Connection to board 121 closed.
[07:33:08.690] <TB3> INFO: pXar: this is the end, my friend
commander_HREfficiency.log
[07:33:44.930] <TB3> INFO: *** Welcome to pxar ***
[07:33:44.930] <TB3> INFO: *** Today: 2015/08/24
[07:33:44.930] <TB3> INFO: readRocDacs: /usr/local/data/M2079_XrayQualification_2015-08-24_08h08m_1440396521//011_HREfficiency_50//dacParameters35_C0.dat .. /usr/local/data/M2079_XrayQualification_2015-08-24_08h08m_1440396521//011_HREfficiency_50//dacParameters35_C15.dat
[07:33:44.931] <TB3> INFO: readTbmDacs: /usr/local/data/M2079_XrayQualification_2015-08-24_08h08m_1440396521//011_HREfficiency_50//tbmParameters_C0a.dat .. /usr/local/data/M2079_XrayQualification_2015-08-24_08h08m_1440396521//011_HREfficiency_50//tbmParameters_C0b.dat
[07:33:44.931] <TB3> INFO: readMaskFile: /usr/local/data/M2079_XrayQualification_2015-08-24_08h08m_1440396521//011_HREfficiency_50//defaultMaskFile.dat
[07:33:44.931] <TB3> INFO: readTrimFile: /usr/local/data/M2079_XrayQualification_2015-08-24_08h08m_1440396521//011_HREfficiency_50//trimParameters35_C0.dat .. /usr/local/data/M2079_XrayQualification_2015-08-24_08h08m_1440396521//011_HREfficiency_50//trimParameters35_C15.dat
[07:33:45.002] <TB3> INFO: clk: 4
[07:33:45.002] <TB3> INFO: ctr: 4
[07:33:45.002] <TB3> INFO: sda: 19
[07:33:45.002] <TB3> INFO: tin: 9
[07:33:45.002] <TB3> INFO: level: 15
[07:33:45.002] <TB3> INFO: triggerdelay: 0
[07:33:45.002] <TB3> QUIET: Instanciating API for pxar prod-01+86~g1838649
[07:33:45.002] <TB3> INFO: Log level: INFO
[07:33:45.014] <TB3> INFO: Found DTB DTB_WWV6Z5
[07:33:45.026] <TB3> QUIET: Connection to board DTB_WWV6Z5 opened.
[07:33:45.029] <TB3> INFO: DTB startup information
--- DTB info------------------------------------------
Board id: 121
HW version: DTB1.2
FW version: 4.0
SW version: 4.0
USB id: DTB_WWV6Z5
MAC address: 40D855118079
Hostname: pixelDTB121
Comment:
------------------------------------------------------
[07:33:45.032] <TB3> INFO: RPC call hashes of host and DTB match: 447413373
[07:33:46.557] <TB3> INFO: DUT info:
[07:33:46.557] <TB3> INFO: The DUT currently contains the following objects:
[07:33:46.557] <TB3> INFO: 2 TBM Cores tbm09c (2 ON)
[07:33:46.557] <TB3> INFO: TBM Core alpha (0): 7 registers set
[07:33:46.557] <TB3> INFO: TBM Core beta (1): 7 registers set
[07:33:46.557] <TB3> INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[07:33:46.557] <TB3> INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[07:33:46.557] <TB3> INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[07:33:46.557] <TB3> INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[07:33:46.557] <TB3> INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[07:33:46.557] <TB3> INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[07:33:46.557] <TB3> INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[07:33:46.557] <TB3> INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[07:33:46.557] <TB3> INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[07:33:46.557] <TB3> INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[07:33:46.558] <TB3> INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[07:33:46.558] <TB3> INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[07:33:46.558] <TB3> INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[07:33:46.558] <TB3> INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[07:33:46.558] <TB3> INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[07:33:46.558] <TB3> INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[07:33:46.558] <TB3> INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[07:33:46.959] <TB3> INFO: enter 'restricted' command line mode
[07:33:46.959] <TB3> INFO: enter test to run
[07:33:46.959] <TB3> INFO: test: HighRate no parameter change
[07:33:46.959] <TB3> INFO: running: highrate
[07:33:46.960] <TB3> INFO: readGainPedestalParameters /usr/local/data/M2079_XrayQualification_2015-08-24_08h08m_1440396521//011_HREfficiency_50//phCalibrationFitErr35_C0.dat .. /usr/local/data/M2079_XrayQualification_2015-08-24_08h08m_1440396521//011_HREfficiency_50//phCalibrationFitErr35_C15.dat
[07:33:47.102] <TB3> INFO: ----------------------------------------------------------------------
[07:33:47.102] <TB3> INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[07:33:47.102] <TB3> INFO: ----------------------------------------------------------------------
[07:34:00.158] <TB3> INFO: Test took 13050ms.
[07:34:00.859] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[07:34:01.158] <TB3> INFO: number of dead pixels (per ROC): 0 1 0 0 0 0 0 0 0 0 0 0 0 0 0 1
[07:34:01.158] <TB3> INFO: number of red-efficiency pixels: 113 110 94 248 215 213 314 294 292 299 293 231 244 107 125 119
[07:34:01.158] <TB3> INFO: number of X-ray hits detected: 80860 88054 75923 148605 149204 165594 189113 174225 190327 183271 191398 157893 152920 83321 86466 88818
[07:34:01.158] <TB3> INFO: number of triggers sent (total per ROC): 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000
[07:34:01.158] <TB3> INFO: number of Vcal hits detected: 207887 207839 207902 207744 207779 207775 207663 207681 207695 207682 207689 207759 207749 207889 207875 207830
[07:34:01.158] <TB3> INFO: Vcal hit fiducial efficiency (%): 99.9 99.9 100.0 99.9 99.9 99.9 99.9 99.9 99.9 99.9 99.9 99.9 99.9 100.0 99.9 99.9
[07:34:01.158] <TB3> INFO: Vcal hit overall efficiency (%): 99.9 99.9 100.0 99.9 99.9 99.9 99.8 99.8 99.9 99.8 99.9 99.9 99.9 99.9 99.9 99.9
[07:34:01.158] <TB3> INFO: X-ray hit rate [MHz/cm2]: 23.7 25.8 22.3 43.6 43.7 48.5 55.4 51.1 55.8 53.7 56.1 46.3 44.8 24.4 25.3 26.0
[07:34:01.158] <TB3> INFO: PixTestHighRate::doXPixelAlive() done
[07:34:01.184] <TB3> INFO: PixTest:: pg_setup set to default.
[07:34:01.215] <TB3> INFO: enter test to run
[07:34:01.215] <TB3> INFO: test: no parameter change
[07:34:01.392] <TB3> QUIET: Connection to board 121 closed.
[07:34:01.471] <TB3> INFO: pXar: this is the end, my friend
commander_HREfficiency.log
[07:34:35.729] <TB3> INFO: *** Welcome to pxar ***
[07:34:35.729] <TB3> INFO: *** Today: 2015/08/24
[07:34:35.729] <TB3> INFO: readRocDacs: /usr/local/data/M2079_XrayQualification_2015-08-24_08h08m_1440396521//012_HREfficiency_100//dacParameters35_C0.dat .. /usr/local/data/M2079_XrayQualification_2015-08-24_08h08m_1440396521//012_HREfficiency_100//dacParameters35_C15.dat
[07:34:35.730] <TB3> INFO: readTbmDacs: /usr/local/data/M2079_XrayQualification_2015-08-24_08h08m_1440396521//012_HREfficiency_100//tbmParameters_C0a.dat .. /usr/local/data/M2079_XrayQualification_2015-08-24_08h08m_1440396521//012_HREfficiency_100//tbmParameters_C0b.dat
[07:34:35.730] <TB3> INFO: readMaskFile: /usr/local/data/M2079_XrayQualification_2015-08-24_08h08m_1440396521//012_HREfficiency_100//defaultMaskFile.dat
[07:34:35.730] <TB3> INFO: readTrimFile: /usr/local/data/M2079_XrayQualification_2015-08-24_08h08m_1440396521//012_HREfficiency_100//trimParameters35_C0.dat .. /usr/local/data/M2079_XrayQualification_2015-08-24_08h08m_1440396521//012_HREfficiency_100//trimParameters35_C15.dat
[07:34:35.810] <TB3> INFO: clk: 4
[07:34:35.810] <TB3> INFO: ctr: 4
[07:34:35.810] <TB3> INFO: sda: 19
[07:34:35.810] <TB3> INFO: tin: 9
[07:34:35.810] <TB3> INFO: level: 15
[07:34:35.810] <TB3> INFO: triggerdelay: 0
[07:34:35.810] <TB3> QUIET: Instanciating API for pxar prod-01+86~g1838649
[07:34:35.810] <TB3> INFO: Log level: INFO
[07:34:35.823] <TB3> INFO: Found DTB DTB_WWV6Z5
[07:34:35.835] <TB3> QUIET: Connection to board DTB_WWV6Z5 opened.
[07:34:35.838] <TB3> INFO: DTB startup information
--- DTB info------------------------------------------
Board id: 121
HW version: DTB1.2
FW version: 4.0
SW version: 4.0
USB id: DTB_WWV6Z5
MAC address: 40D855118079
Hostname: pixelDTB121
Comment:
------------------------------------------------------
[07:34:35.840] <TB3> INFO: RPC call hashes of host and DTB match: 447413373
[07:34:37.358] <TB3> INFO: DUT info:
[07:34:37.358] <TB3> INFO: The DUT currently contains the following objects:
[07:34:37.358] <TB3> INFO: 2 TBM Cores tbm09c (2 ON)
[07:34:37.358] <TB3> INFO: TBM Core alpha (0): 7 registers set
[07:34:37.358] <TB3> INFO: TBM Core beta (1): 7 registers set
[07:34:37.358] <TB3> INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[07:34:37.358] <TB3> INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[07:34:37.358] <TB3> INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[07:34:37.358] <TB3> INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[07:34:37.358] <TB3> INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[07:34:37.358] <TB3> INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[07:34:37.358] <TB3> INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[07:34:37.358] <TB3> INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[07:34:37.358] <TB3> INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[07:34:37.358] <TB3> INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[07:34:37.358] <TB3> INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[07:34:37.358] <TB3> INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[07:34:37.358] <TB3> INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[07:34:37.358] <TB3> INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[07:34:37.358] <TB3> INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[07:34:37.358] <TB3> INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[07:34:37.358] <TB3> INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[07:34:37.759] <TB3> INFO: enter 'restricted' command line mode
[07:34:37.759] <TB3> INFO: enter test to run
[07:34:37.759] <TB3> INFO: test: HighRate no parameter change
[07:34:37.759] <TB3> INFO: running: highrate
[07:34:37.759] <TB3> INFO: readGainPedestalParameters /usr/local/data/M2079_XrayQualification_2015-08-24_08h08m_1440396521//012_HREfficiency_100//phCalibrationFitErr35_C0.dat .. /usr/local/data/M2079_XrayQualification_2015-08-24_08h08m_1440396521//012_HREfficiency_100//phCalibrationFitErr35_C15.dat
[07:34:37.932] <TB3> INFO: ----------------------------------------------------------------------
[07:34:37.932] <TB3> INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[07:34:37.933] <TB3> INFO: ----------------------------------------------------------------------
[07:34:53.087] <TB3> INFO: Test took 15149ms.
[07:34:54.929] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[07:34:55.307] <TB3> INFO: number of dead pixels (per ROC): 0 1 0 0 0 0 0 0 0 0 0 0 0 0 0 1
[07:34:55.307] <TB3> INFO: number of red-efficiency pixels: 243 305 247 868 709 813 1183 1070 1089 1170 1114 750 875 362 357 298
[07:34:55.307] <TB3> INFO: number of X-ray hits detected: 164428 179860 155816 296384 297870 328883 377652 347839 379264 364657 379591 314721 305572 171864 176516 181072
[07:34:55.307] <TB3> INFO: number of triggers sent (total per ROC): 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000
[07:34:55.307] <TB3> INFO: number of Vcal hits detected: 207746 207636 207725 206932 207169 207040 206424 206699 206649 206493 206527 207067 206937 207570 207618 207632
[07:34:55.307] <TB3> INFO: Vcal hit fiducial efficiency (%): 99.9 99.9 99.9 99.6 99.7 99.6 99.3 99.4 99.4 99.4 99.4 99.6 99.6 99.8 99.8 99.9
[07:34:55.307] <TB3> INFO: Vcal hit overall efficiency (%): 99.9 99.8 99.9 99.5 99.6 99.5 99.2 99.4 99.4 99.3 99.3 99.6 99.5 99.8 99.8 99.8
[07:34:55.307] <TB3> INFO: X-ray hit rate [MHz/cm2]: 48.2 52.7 45.7 86.9 87.3 96.4 110.7 102.0 111.2 106.9 111.3 92.2 89.6 50.4 51.7 53.1
[07:34:55.307] <TB3> INFO: PixTestHighRate::doXPixelAlive() done
[07:34:55.332] <TB3> INFO: PixTest:: pg_setup set to default.
[07:34:55.365] <TB3> INFO: enter test to run
[07:34:55.365] <TB3> INFO: test: no parameter change
[07:34:55.530] <TB3> QUIET: Connection to board 121 closed.
[07:34:55.609] <TB3> INFO: pXar: this is the end, my friend
commander_HREfficiency.log
[07:35:29.683] <TB3> INFO: *** Welcome to pxar ***
[07:35:29.683] <TB3> INFO: *** Today: 2015/08/24
[07:35:29.684] <TB3> INFO: readRocDacs: /usr/local/data/M2079_XrayQualification_2015-08-24_08h08m_1440396521//013_HREfficiency_150//dacParameters35_C0.dat .. /usr/local/data/M2079_XrayQualification_2015-08-24_08h08m_1440396521//013_HREfficiency_150//dacParameters35_C15.dat
[07:35:29.684] <TB3> INFO: readTbmDacs: /usr/local/data/M2079_XrayQualification_2015-08-24_08h08m_1440396521//013_HREfficiency_150//tbmParameters_C0a.dat .. /usr/local/data/M2079_XrayQualification_2015-08-24_08h08m_1440396521//013_HREfficiency_150//tbmParameters_C0b.dat
[07:35:29.684] <TB3> INFO: readMaskFile: /usr/local/data/M2079_XrayQualification_2015-08-24_08h08m_1440396521//013_HREfficiency_150//defaultMaskFile.dat
[07:35:29.684] <TB3> INFO: readTrimFile: /usr/local/data/M2079_XrayQualification_2015-08-24_08h08m_1440396521//013_HREfficiency_150//trimParameters35_C0.dat .. /usr/local/data/M2079_XrayQualification_2015-08-24_08h08m_1440396521//013_HREfficiency_150//trimParameters35_C15.dat
[07:35:29.752] <TB3> INFO: clk: 4
[07:35:29.752] <TB3> INFO: ctr: 4
[07:35:29.752] <TB3> INFO: sda: 19
[07:35:29.752] <TB3> INFO: tin: 9
[07:35:29.752] <TB3> INFO: level: 15
[07:35:29.752] <TB3> INFO: triggerdelay: 0
[07:35:29.752] <TB3> QUIET: Instanciating API for pxar prod-01+86~g1838649
[07:35:29.752] <TB3> INFO: Log level: INFO
[07:35:29.766] <TB3> INFO: Found DTB DTB_WWV6Z5
[07:35:29.778] <TB3> QUIET: Connection to board DTB_WWV6Z5 opened.
[07:35:29.781] <TB3> INFO: DTB startup information
--- DTB info------------------------------------------
Board id: 121
HW version: DTB1.2
FW version: 4.0
SW version: 4.0
USB id: DTB_WWV6Z5
MAC address: 40D855118079
Hostname: pixelDTB121
Comment:
------------------------------------------------------
[07:35:29.784] <TB3> INFO: RPC call hashes of host and DTB match: 447413373
[07:35:31.315] <TB3> INFO: DUT info:
[07:35:31.315] <TB3> INFO: The DUT currently contains the following objects:
[07:35:31.315] <TB3> INFO: 2 TBM Cores tbm09c (2 ON)
[07:35:31.315] <TB3> INFO: TBM Core alpha (0): 7 registers set
[07:35:31.315] <TB3> INFO: TBM Core beta (1): 7 registers set
[07:35:31.315] <TB3> INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[07:35:31.315] <TB3> INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[07:35:31.315] <TB3> INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[07:35:31.315] <TB3> INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[07:35:31.315] <TB3> INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[07:35:31.315] <TB3> INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[07:35:31.315] <TB3> INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[07:35:31.315] <TB3> INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[07:35:31.315] <TB3> INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[07:35:31.315] <TB3> INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[07:35:31.315] <TB3> INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[07:35:31.316] <TB3> INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[07:35:31.316] <TB3> INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[07:35:31.316] <TB3> INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[07:35:31.316] <TB3> INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[07:35:31.316] <TB3> INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[07:35:31.316] <TB3> INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[07:35:31.717] <TB3> INFO: enter 'restricted' command line mode
[07:35:31.717] <TB3> INFO: enter test to run
[07:35:31.717] <TB3> INFO: test: HighRate no parameter change
[07:35:31.717] <TB3> INFO: running: highrate
[07:35:31.718] <TB3> INFO: readGainPedestalParameters /usr/local/data/M2079_XrayQualification_2015-08-24_08h08m_1440396521//013_HREfficiency_150//phCalibrationFitErr35_C0.dat .. /usr/local/data/M2079_XrayQualification_2015-08-24_08h08m_1440396521//013_HREfficiency_150//phCalibrationFitErr35_C15.dat
[07:35:31.888] <TB3> INFO: ----------------------------------------------------------------------
[07:35:31.888] <TB3> INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[07:35:31.888] <TB3> INFO: ----------------------------------------------------------------------
[07:35:49.693] <TB3> INFO: Test took 17799ms.
[07:35:53.040] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[07:35:53.477] <TB3> INFO: number of dead pixels (per ROC): 0 1 0 0 0 0 0 0 0 0 0 0 0 0 0 1
[07:35:53.477] <TB3> INFO: number of red-efficiency pixels: 492 572 543 1862 1440 1693 2270 2182 2283 2202 2209 1618 1725 729 733 630
[07:35:53.477] <TB3> INFO: number of X-ray hits detected: 239825 261433 227891 426637 430866 469283 543456 500355 543573 521870 544746 453524 442386 250471 258293 262451
[07:35:53.477] <TB3> INFO: number of triggers sent (total per ROC): 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000
[07:35:53.477] <TB3> INFO: number of Vcal hits detected: 207468 207303 207222 205085 205874 205385 203666 204555 204245 203970 204007 205473 205343 206896 207172 207260
[07:35:53.477] <TB3> INFO: Vcal hit fiducial efficiency (%): 99.8 99.7 99.7 98.8 99.1 98.9 98.2 98.5 98.4 98.3 98.4 99.0 98.9 99.6 99.6 99.7
[07:35:53.477] <TB3> INFO: Vcal hit overall efficiency (%): 99.7 99.7 99.6 98.6 99.0 98.7 97.9 98.3 98.2 98.1 98.1 98.8 98.7 99.5 99.6 99.6
[07:35:53.477] <TB3> INFO: X-ray hit rate [MHz/cm2]: 70.3 76.6 66.8 125.1 126.3 137.6 159.3 146.7 159.3 153.0 159.7 132.9 129.7 73.4 75.7 76.9
[07:35:53.477] <TB3> INFO: PixTestHighRate::doXPixelAlive() done
[07:35:53.503] <TB3> INFO: PixTest:: pg_setup set to default.
[07:35:53.536] <TB3> INFO: enter test to run
[07:35:53.536] <TB3> INFO: test: no parameter change
[07:35:53.781] <TB3> QUIET: Connection to board 121 closed.
[07:35:53.861] <TB3> INFO: pXar: this is the end, my friend
commander_HREfficiency.log
[07:36:29.851] <TB3> INFO: *** Welcome to pxar ***
[07:36:29.851] <TB3> INFO: *** Today: 2015/08/24
[07:36:29.851] <TB3> INFO: readRocDacs: /usr/local/data/M2079_XrayQualification_2015-08-24_08h08m_1440396521//014_HREfficiency_200//dacParameters35_C0.dat .. /usr/local/data/M2079_XrayQualification_2015-08-24_08h08m_1440396521//014_HREfficiency_200//dacParameters35_C15.dat
[07:36:29.853] <TB3> INFO: readTbmDacs: /usr/local/data/M2079_XrayQualification_2015-08-24_08h08m_1440396521//014_HREfficiency_200//tbmParameters_C0a.dat .. /usr/local/data/M2079_XrayQualification_2015-08-24_08h08m_1440396521//014_HREfficiency_200//tbmParameters_C0b.dat
[07:36:29.854] <TB3> INFO: readMaskFile: /usr/local/data/M2079_XrayQualification_2015-08-24_08h08m_1440396521//014_HREfficiency_200//defaultMaskFile.dat
[07:36:29.854] <TB3> INFO: readTrimFile: /usr/local/data/M2079_XrayQualification_2015-08-24_08h08m_1440396521//014_HREfficiency_200//trimParameters35_C0.dat .. /usr/local/data/M2079_XrayQualification_2015-08-24_08h08m_1440396521//014_HREfficiency_200//trimParameters35_C15.dat
[07:36:29.948] <TB3> INFO: clk: 4
[07:36:29.948] <TB3> INFO: ctr: 4
[07:36:29.948] <TB3> INFO: sda: 19
[07:36:29.948] <TB3> INFO: tin: 9
[07:36:29.948] <TB3> INFO: level: 15
[07:36:29.948] <TB3> INFO: triggerdelay: 0
[07:36:29.948] <TB3> QUIET: Instanciating API for pxar prod-01+86~g1838649
[07:36:29.948] <TB3> INFO: Log level: INFO
[07:36:29.961] <TB3> INFO: Found DTB DTB_WWV6Z5
[07:36:29.972] <TB3> QUIET: Connection to board DTB_WWV6Z5 opened.
[07:36:29.976] <TB3> INFO: DTB startup information
--- DTB info------------------------------------------
Board id: 121
HW version: DTB1.2
FW version: 4.0
SW version: 4.0
USB id: DTB_WWV6Z5
MAC address: 40D855118079
Hostname: pixelDTB121
Comment:
------------------------------------------------------
[07:36:29.979] <TB3> INFO: RPC call hashes of host and DTB match: 447413373
[07:36:31.527] <TB3> INFO: DUT info:
[07:36:31.527] <TB3> INFO: The DUT currently contains the following objects:
[07:36:31.527] <TB3> INFO: 2 TBM Cores tbm09c (2 ON)
[07:36:31.527] <TB3> INFO: TBM Core alpha (0): 7 registers set
[07:36:31.527] <TB3> INFO: TBM Core beta (1): 7 registers set
[07:36:31.527] <TB3> INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[07:36:31.528] <TB3> INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[07:36:31.528] <TB3> INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[07:36:31.528] <TB3> INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[07:36:31.528] <TB3> INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[07:36:31.528] <TB3> INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[07:36:31.528] <TB3> INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[07:36:31.528] <TB3> INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[07:36:31.528] <TB3> INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[07:36:31.528] <TB3> INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[07:36:31.528] <TB3> INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[07:36:31.528] <TB3> INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[07:36:31.528] <TB3> INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[07:36:31.528] <TB3> INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[07:36:31.528] <TB3> INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[07:36:31.528] <TB3> INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[07:36:31.528] <TB3> INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[07:36:31.929] <TB3> INFO: enter 'restricted' command line mode
[07:36:31.929] <TB3> INFO: enter test to run
[07:36:31.930] <TB3> INFO: test: HighRate no parameter change
[07:36:31.930] <TB3> INFO: running: highrate
[07:36:31.930] <TB3> INFO: readGainPedestalParameters /usr/local/data/M2079_XrayQualification_2015-08-24_08h08m_1440396521//014_HREfficiency_200//phCalibrationFitErr35_C0.dat .. /usr/local/data/M2079_XrayQualification_2015-08-24_08h08m_1440396521//014_HREfficiency_200//phCalibrationFitErr35_C15.dat
[07:36:32.081] <TB3> INFO: ----------------------------------------------------------------------
[07:36:32.081] <TB3> INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[07:36:32.081] <TB3> INFO: ----------------------------------------------------------------------
[07:36:52.337] <TB3> INFO: Test took 20250ms.
[07:36:57.984] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[07:36:58.496] <TB3> INFO: number of dead pixels (per ROC): 0 1 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[07:36:58.496] <TB3> INFO: number of red-efficiency pixels: 915 1074 954 3034 2386 2773 3409 3338 3507 3363 3386 2655 2852 1166 1398 1114
[07:36:58.496] <TB3> INFO: number of X-ray hits detected: 326115 357349 311076 575487 577688 630323 735317 673963 734291 700626 729008 610660 596032 341382 351780 357439
[07:36:58.496] <TB3> INFO: number of triggers sent (total per ROC): 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000
[07:36:58.496] <TB3> INFO: number of Vcal hits detected: 206909 206664 206175 200926 203194 202018 197580 199998 198607 198530 198333 201996 201557 205617 206231 206508
[07:36:58.496] <TB3> INFO: Vcal hit fiducial efficiency (%): 99.5 99.4 99.3 97.0 98.0 97.5 95.7 96.5 95.9 96.0 96.0 97.5 97.3 99.1 99.2 99.4
[07:36:58.496] <TB3> INFO: Vcal hit overall efficiency (%): 99.5 99.4 99.1 96.6 97.7 97.1 95.0 96.2 95.5 95.4 95.4 97.1 96.9 98.9 99.1 99.3
[07:36:58.496] <TB3> INFO: X-ray hit rate [MHz/cm2]: 95.6 104.7 91.2 168.7 169.3 184.8 215.5 197.5 215.2 205.4 213.7 179.0 174.7 100.1 103.1 104.8
[07:36:58.496] <TB3> INFO: PixTestHighRate::doXPixelAlive() done
[07:36:58.523] <TB3> INFO: PixTest:: pg_setup set to default.
[07:36:58.559] <TB3> INFO: enter test to run
[07:36:58.559] <TB3> INFO: test: no parameter change
[07:36:58.735] <TB3> QUIET: Connection to board 121 closed.
[07:36:58.814] <TB3> INFO: pXar: this is the end, my friend
commander_HREfficiency.log
[07:37:34.280] <TB3> INFO: *** Welcome to pxar ***
[07:37:34.280] <TB3> INFO: *** Today: 2015/08/24
[07:37:34.280] <TB3> INFO: readRocDacs: /usr/local/data/M2079_XrayQualification_2015-08-24_08h08m_1440396521//015_HREfficiency_250//dacParameters35_C0.dat .. /usr/local/data/M2079_XrayQualification_2015-08-24_08h08m_1440396521//015_HREfficiency_250//dacParameters35_C15.dat
[07:37:34.280] <TB3> INFO: readTbmDacs: /usr/local/data/M2079_XrayQualification_2015-08-24_08h08m_1440396521//015_HREfficiency_250//tbmParameters_C0a.dat .. /usr/local/data/M2079_XrayQualification_2015-08-24_08h08m_1440396521//015_HREfficiency_250//tbmParameters_C0b.dat
[07:37:34.280] <TB3> INFO: readMaskFile: /usr/local/data/M2079_XrayQualification_2015-08-24_08h08m_1440396521//015_HREfficiency_250//defaultMaskFile.dat
[07:37:34.280] <TB3> INFO: readTrimFile: /usr/local/data/M2079_XrayQualification_2015-08-24_08h08m_1440396521//015_HREfficiency_250//trimParameters35_C0.dat .. /usr/local/data/M2079_XrayQualification_2015-08-24_08h08m_1440396521//015_HREfficiency_250//trimParameters35_C15.dat
[07:37:34.352] <TB3> INFO: clk: 4
[07:37:34.352] <TB3> INFO: ctr: 4
[07:37:34.352] <TB3> INFO: sda: 19
[07:37:34.352] <TB3> INFO: tin: 9
[07:37:34.352] <TB3> INFO: level: 15
[07:37:34.352] <TB3> INFO: triggerdelay: 0
[07:37:34.352] <TB3> QUIET: Instanciating API for pxar prod-01+86~g1838649
[07:37:34.352] <TB3> INFO: Log level: INFO
[07:37:34.366] <TB3> INFO: Found DTB DTB_WWV6Z5
[07:37:34.377] <TB3> QUIET: Connection to board DTB_WWV6Z5 opened.
[07:37:34.380] <TB3> INFO: DTB startup information
--- DTB info------------------------------------------
Board id: 121
HW version: DTB1.2
FW version: 4.0
SW version: 4.0
USB id: DTB_WWV6Z5
MAC address: 40D855118079
Hostname: pixelDTB121
Comment:
------------------------------------------------------
[07:37:34.383] <TB3> INFO: RPC call hashes of host and DTB match: 447413373
[07:37:35.914] <TB3> INFO: DUT info:
[07:37:35.914] <TB3> INFO: The DUT currently contains the following objects:
[07:37:35.914] <TB3> INFO: 2 TBM Cores tbm09c (2 ON)
[07:37:35.914] <TB3> INFO: TBM Core alpha (0): 7 registers set
[07:37:35.914] <TB3> INFO: TBM Core beta (1): 7 registers set
[07:37:35.914] <TB3> INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[07:37:35.914] <TB3> INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[07:37:35.914] <TB3> INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[07:37:35.914] <TB3> INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[07:37:35.914] <TB3> INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[07:37:35.914] <TB3> INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[07:37:35.914] <TB3> INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[07:37:35.914] <TB3> INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[07:37:35.914] <TB3> INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[07:37:35.914] <TB3> INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[07:37:35.914] <TB3> INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[07:37:35.914] <TB3> INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[07:37:35.914] <TB3> INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[07:37:35.914] <TB3> INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[07:37:35.914] <TB3> INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[07:37:35.915] <TB3> INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[07:37:35.915] <TB3> INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[07:37:36.316] <TB3> INFO: enter 'restricted' command line mode
[07:37:36.316] <TB3> INFO: enter test to run
[07:37:36.316] <TB3> INFO: test: HighRate no parameter change
[07:37:36.316] <TB3> INFO: running: highrate
[07:37:36.317] <TB3> INFO: readGainPedestalParameters /usr/local/data/M2079_XrayQualification_2015-08-24_08h08m_1440396521//015_HREfficiency_250//phCalibrationFitErr35_C0.dat .. /usr/local/data/M2079_XrayQualification_2015-08-24_08h08m_1440396521//015_HREfficiency_250//phCalibrationFitErr35_C15.dat
[07:37:36.487] <TB3> INFO: ----------------------------------------------------------------------
[07:37:36.487] <TB3> INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[07:37:36.487] <TB3> INFO: ----------------------------------------------------------------------
[07:37:57.659] <TB3> INFO: Test took 21165ms.
[07:38:03.479] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[07:38:03.991] <TB3> INFO: number of dead pixels (per ROC): 0 1 0 0 0 0 0 0 0 0 0 0 0 0 0 1
[07:38:03.991] <TB3> INFO: number of red-efficiency pixels: 894 1233 967 3108 2567 2897 3491 3503 3547 3420 3487 2790 2962 1220 1468 1182
[07:38:03.991] <TB3> INFO: number of X-ray hits detected: 336353 370522 324707 591231 594667 648044 755017 695012 755127 720338 747091 629520 613979 356125 365601 368979
[07:38:03.991] <TB3> INFO: number of triggers sent (total per ROC): 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000
[07:38:03.991] <TB3> INFO: number of Vcal hits detected: 206933 206392 206065 200432 202705 201473 196794 198972 198066 197839 197670 201625 201152 205530 206080 206374
[07:38:03.991] <TB3> INFO: Vcal hit fiducial efficiency (%): 99.5 99.3 99.3 96.8 97.8 97.3 95.3 96.1 95.6 95.7 95.7 97.4 97.1 99.0 99.1 99.3
[07:38:03.991] <TB3> INFO: Vcal hit overall efficiency (%): 99.5 99.2 99.1 96.4 97.5 96.9 94.6 95.7 95.2 95.1 95.0 96.9 96.7 98.8 99.1 99.2
[07:38:03.991] <TB3> INFO: X-ray hit rate [MHz/cm2]: 98.6 108.6 95.2 173.3 174.3 189.9 221.3 203.7 221.3 211.1 219.0 184.5 180.0 104.4 107.2 108.2
[07:38:03.991] <TB3> INFO: PixTestHighRate::doXPixelAlive() done
[07:38:04.016] <TB3> INFO: PixTest:: pg_setup set to default.
[07:38:04.052] <TB3> INFO: enter test to run
[07:38:04.052] <TB3> INFO: test: no parameter change
[07:38:04.206] <TB3> QUIET: Connection to board 121 closed.
[07:38:04.285] <TB3> INFO: pXar: this is the end, my friend
MoReWeb-v1.0.0-5-g82d9ff6 on branch psi46master