[15:50:45.534] <TB1> INFO: *** Welcome to pxar ***
[15:50:45.534] <TB1> INFO: *** Today: 2015/08/24
[15:50:45.534] <TB1> INFO: readRocDacs: /usr/local/data/M2081_XrayQualification_2015-08-24_17h49m_1440431385//000_PixelAlive_p17//dacParameters35_C0.dat .. /usr/local/data/M2081_XrayQualification_2015-08-24_17h49m_1440431385//000_PixelAlive_p17//dacParameters35_C15.dat
[15:50:45.534] <TB1> INFO: readTbmDacs: /usr/local/data/M2081_XrayQualification_2015-08-24_17h49m_1440431385//000_PixelAlive_p17//tbmParameters_C0a.dat .. /usr/local/data/M2081_XrayQualification_2015-08-24_17h49m_1440431385//000_PixelAlive_p17//tbmParameters_C0b.dat
[15:50:45.534] <TB1> INFO: readMaskFile: /usr/local/data/M2081_XrayQualification_2015-08-24_17h49m_1440431385//000_PixelAlive_p17//defaultMaskFile.dat
[15:50:45.534] <TB1> INFO: readTrimFile: /usr/local/data/M2081_XrayQualification_2015-08-24_17h49m_1440431385//000_PixelAlive_p17//trimParameters35_C0.dat .. /usr/local/data/M2081_XrayQualification_2015-08-24_17h49m_1440431385//000_PixelAlive_p17//trimParameters35_C15.dat
[15:50:45.601] <TB1> INFO: clk: 4
[15:50:45.601] <TB1> INFO: ctr: 4
[15:50:45.601] <TB1> INFO: sda: 19
[15:50:45.601] <TB1> INFO: tin: 9
[15:50:45.601] <TB1> INFO: level: 15
[15:50:45.601] <TB1> INFO: triggerdelay: 0
[15:50:45.601] <TB1> QUIET: Instanciating API for pxar prod-01+86~g1838649
[15:50:45.601] <TB1> INFO: Log level: INFO
[15:50:45.614] <TB1> INFO: Found DTB DTB_WS6UZO
[15:50:45.623] <TB1> QUIET: Connection to board DTB_WS6UZO opened.
[15:50:45.626] <TB1> INFO: DTB startup information
--- DTB info------------------------------------------
Board id: 83
HW version: DTB1.2
FW version: 4.0
SW version: 4.0
USB id: DTB_WS6UZO
MAC address: 40D855118053
Hostname: pixelDTB083
Comment:
------------------------------------------------------
[15:50:45.629] <TB1> INFO: RPC call hashes of host and DTB match: 447413373
[15:50:47.190] <TB1> INFO: DUT info:
[15:50:47.190] <TB1> INFO: The DUT currently contains the following objects:
[15:50:47.190] <TB1> INFO: 2 TBM Cores tbm09c (2 ON)
[15:50:47.190] <TB1> INFO: TBM Core alpha (0): 7 registers set
[15:50:47.190] <TB1> INFO: TBM Core beta (1): 7 registers set
[15:50:47.190] <TB1> INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[15:50:47.190] <TB1> INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[15:50:47.190] <TB1> INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[15:50:47.190] <TB1> INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[15:50:47.190] <TB1> INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[15:50:47.190] <TB1> INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[15:50:47.190] <TB1> INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[15:50:47.190] <TB1> INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[15:50:47.190] <TB1> INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[15:50:47.190] <TB1> INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[15:50:47.190] <TB1> INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[15:50:47.190] <TB1> INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[15:50:47.190] <TB1> INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[15:50:47.191] <TB1> INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[15:50:47.191] <TB1> INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[15:50:47.191] <TB1> INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[15:50:47.191] <TB1> INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[15:50:47.592] <TB1> INFO: enter 'restricted' command line mode
[15:50:47.592] <TB1> INFO: enter test to run
[15:50:47.592] <TB1> INFO: test: PixelAlive no parameter change
[15:50:47.592] <TB1> INFO: running: pixelalive
[15:50:47.598] <TB1> INFO: ######################################################################
[15:50:47.599] <TB1> INFO: PixTestAlive::doTest()
[15:50:47.599] <TB1> INFO: ######################################################################
[15:50:47.600] <TB1> INFO: ----------------------------------------------------------------------
[15:50:47.600] <TB1> INFO: PixTestAlive::aliveTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[15:50:47.600] <TB1> INFO: ----------------------------------------------------------------------
[15:50:50.964] <TB1> INFO: Test took 3362ms.
[15:50:50.982] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[15:50:51.231] <TB1> INFO: PixTestAlive::aliveTest() done
[15:50:51.231] <TB1> INFO: number of dead pixels (per ROC): 1 1 0 2 2 2 0 2 0 1 0 2 1 0 0 0
[15:50:51.232] <TB1> INFO: ----------------------------------------------------------------------
[15:50:51.232] <TB1> INFO: PixTestAlive::maskTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[15:50:51.232] <TB1> INFO: ----------------------------------------------------------------------
[15:50:53.905] <TB1> INFO: Test took 2671ms.
[15:50:53.906] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[15:50:53.906] <TB1> INFO: mask vs. old pixelAlive PixelAlive_C0_V0 .. PixelAlive_C15_V0
[15:50:54.130] <TB1> INFO: PixTestAlive::maskTest() done
[15:50:54.130] <TB1> INFO: number of mask-defect pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[15:50:54.131] <TB1> INFO: ----------------------------------------------------------------------
[15:50:54.131] <TB1> INFO: PixTestAlive::addressDecodingTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[15:50:54.131] <TB1> INFO: ----------------------------------------------------------------------
[15:50:57.632] <TB1> INFO: Test took 3499ms.
[15:50:57.657] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[15:50:57.892] <TB1> INFO: PixTestAlive::addressDecodingTest() done
[15:50:57.892] <TB1> INFO: number of address-decoding pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[15:50:57.892] <TB1> INFO: PixTestAlive::doTest() done, duration: 10 seconds
[15:50:57.938] <TB1> INFO: enter test to run
[15:50:57.938] <TB1> INFO: test: no parameter change
[15:50:58.110] <TB1> QUIET: Connection to board 83 closed.
[15:50:58.190] <TB1> INFO: pXar: this is the end, my friend

[15:56:15.625] <TB1> INFO: *** Welcome to pxar ***
[15:56:15.625] <TB1> INFO: *** Today: 2015/08/24
[15:56:15.625] <TB1> INFO: readRocDacs: /usr/local/data/M2081_XrayQualification_2015-08-24_17h49m_1440431385//002_RetrimHotPixels_150//dacParameters35_C0.dat .. /usr/local/data/M2081_XrayQualification_2015-08-24_17h49m_1440431385//002_RetrimHotPixels_150//dacParameters35_C15.dat
[15:56:15.626] <TB1> INFO: readTbmDacs: /usr/local/data/M2081_XrayQualification_2015-08-24_17h49m_1440431385//002_RetrimHotPixels_150//tbmParameters_C0a.dat .. /usr/local/data/M2081_XrayQualification_2015-08-24_17h49m_1440431385//002_RetrimHotPixels_150//tbmParameters_C0b.dat
[15:56:15.626] <TB1> INFO: readMaskFile: /usr/local/data/M2081_XrayQualification_2015-08-24_17h49m_1440431385//002_RetrimHotPixels_150//defaultMaskFile.dat
[15:56:15.626] <TB1> INFO: readTrimFile: /usr/local/data/M2081_XrayQualification_2015-08-24_17h49m_1440431385//002_RetrimHotPixels_150//trimParameters35_C0.dat .. /usr/local/data/M2081_XrayQualification_2015-08-24_17h49m_1440431385//002_RetrimHotPixels_150//trimParameters35_C15.dat
[15:56:15.697] <TB1> INFO: clk: 4
[15:56:15.697] <TB1> INFO: ctr: 4
[15:56:15.697] <TB1> INFO: sda: 19
[15:56:15.697] <TB1> INFO: tin: 9
[15:56:15.697] <TB1> INFO: level: 15
[15:56:15.697] <TB1> INFO: triggerdelay: 0
[15:56:15.697] <TB1> QUIET: Instanciating API for pxar prod-01+86~g1838649
[15:56:15.697] <TB1> INFO: Log level: INFO
[15:56:15.712] <TB1> INFO: Found DTB DTB_WS6UZO
[15:56:15.721] <TB1> QUIET: Connection to board DTB_WS6UZO opened.
[15:56:15.724] <TB1> INFO: DTB startup information
--- DTB info------------------------------------------
Board id: 83
HW version: DTB1.2
FW version: 4.0
SW version: 4.0
USB id: DTB_WS6UZO
MAC address: 40D855118053
Hostname: pixelDTB083
Comment:
------------------------------------------------------
[15:56:15.727] <TB1> INFO: RPC call hashes of host and DTB match: 447413373
[15:56:17.283] <TB1> INFO: DUT info:
[15:56:17.283] <TB1> INFO: The DUT currently contains the following objects:
[15:56:17.283] <TB1> INFO: 2 TBM Cores tbm09c (2 ON)
[15:56:17.283] <TB1> INFO: TBM Core alpha (0): 7 registers set
[15:56:17.283] <TB1> INFO: TBM Core beta (1): 7 registers set
[15:56:17.283] <TB1> INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[15:56:17.284] <TB1> INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[15:56:17.284] <TB1> INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[15:56:17.284] <TB1> INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[15:56:17.284] <TB1> INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[15:56:17.284] <TB1> INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[15:56:17.284] <TB1> INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[15:56:17.284] <TB1> INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[15:56:17.284] <TB1> INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[15:56:17.284] <TB1> INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[15:56:17.284] <TB1> INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[15:56:17.284] <TB1> INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[15:56:17.284] <TB1> INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[15:56:17.284] <TB1> INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[15:56:17.284] <TB1> INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[15:56:17.284] <TB1> INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[15:56:17.284] <TB1> INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[15:56:17.685] <TB1> INFO: enter 'restricted' command line mode
[15:56:17.685] <TB1> INFO: enter test to run
[15:56:17.685] <TB1> INFO: test: delay setting parameters: ->5<-
[15:56:17.685] <TB1> INFO: delay test by 5 seconds...
[15:56:22.686] <TB1> INFO: enter test to run
[15:56:22.686] <TB1> INFO: test: highrate no parameter change
[15:56:22.686] <TB1> INFO: running: highrate
[15:56:22.686] <TB1> INFO: readGainPedestalParameters /usr/local/data/M2081_XrayQualification_2015-08-24_17h49m_1440431385//002_RetrimHotPixels_150//phCalibrationFitErr35_C0.dat .. /usr/local/data/M2081_XrayQualification_2015-08-24_17h49m_1440431385//002_RetrimHotPixels_150//phCalibrationFitErr35_C15.dat
[15:56:22.840] <TB1> INFO: ----------------------------------------------------------------------
[15:56:22.840] <TB1> INFO: PixTest::trimHotPixels() running for 1 seconds with 100 kHz trigger rate
[15:56:22.840] <TB1> INFO: ----------------------------------------------------------------------
[15:56:22.840] <TB1> INFO: THR = 150, corresponding to ~ 400 MHz/cm2
[15:56:22.840] <TB1> INFO: edge/corner pixel THR is adjusted
[15:56:22.840] <TB1> INFO: PixTestHighRate::trimHotPixels: step 0...
[15:56:23.803] <TB1> INFO: Collecting data for 1 seconds...
[15:56:24.805] <TB1> INFO: Done with hot pixel readout
[15:56:32.527] <TB1> INFO: PixTest:: pg_setup set to default.
[15:56:32.529] <TB1> INFO: 10 hot pixels found in step 0
[15:56:33.494] <TB1> INFO: Collecting data for 1 seconds...
[15:56:34.496] <TB1> INFO: Done with hot pixel readout
[15:56:41.872] <TB1> INFO: PixTest:: pg_setup set to default.
[15:56:41.873] <TB1> INFO: 10 hot pixels found in step 1
[15:56:42.837] <TB1> INFO: Collecting data for 1 seconds...
[15:56:43.839] <TB1> INFO: Done with hot pixel readout
[15:56:51.284] <TB1> INFO: PixTest:: pg_setup set to default.
[15:56:51.285] <TB1> INFO: 5 hot pixels found in step 2
[15:56:52.250] <TB1> INFO: Collecting data for 1 seconds...
[15:56:53.252] <TB1> INFO: Done with hot pixel readout
[15:56:58.510] <TB1> INFO: PixTest:: pg_setup set to default.
[15:56:58.511] <TB1> INFO: 2 hot pixels found in step 3
[15:56:59.473] <TB1> INFO: Collecting data for 1 seconds...
[15:57:00.475] <TB1> INFO: Done with hot pixel readout
[15:57:05.995] <TB1> INFO: PixTest:: pg_setup set to default.
[15:57:05.996] <TB1> INFO: 2 hot pixels found in step 4
[15:57:06.959] <TB1> INFO: Collecting data for 1 seconds...
[15:57:07.961] <TB1> INFO: Done with hot pixel readout
[15:57:13.363] <TB1> INFO: PixTest:: pg_setup set to default.
[15:57:13.364] <TB1> INFO: 1 hot pixels found in step 5
[15:57:14.327] <TB1> INFO: Collecting data for 1 seconds...
[15:57:15.329] <TB1> INFO: Done with hot pixel readout
[15:57:20.758] <TB1> INFO: PixTest:: pg_setup set to default.
[15:57:20.758] <TB1> INFO: 1 hot pixels found in step 6
[15:57:21.722] <TB1> INFO: Collecting data for 1 seconds...
[15:57:22.724] <TB1> INFO: Done with hot pixel readout
[15:57:27.439] <TB1> INFO: PixTest:: pg_setup set to default.
[15:57:27.440] <TB1> INFO: 2 hot pixels found in step 7
[15:57:28.401] <TB1> INFO: Collecting data for 1 seconds...
[15:57:29.402] <TB1> INFO: Done with hot pixel readout
[15:57:34.027] <TB1> INFO: PixTest:: pg_setup set to default.
[15:57:34.028] <TB1> INFO: 1 hot pixels found in step 8
[15:57:34.990] <TB1> INFO: Collecting data for 1 seconds...
[15:57:35.991] <TB1> INFO: Done with hot pixel readout
[15:57:40.750] <TB1> INFO: PixTest:: pg_setup set to default.
[15:57:40.751] <TB1> INFO: 1 hot pixels found in step 9
[15:57:41.713] <TB1> INFO: Collecting data for 1 seconds...
[15:57:42.714] <TB1> INFO: Done with hot pixel readout
[15:57:47.449] <TB1> INFO: PixTest:: pg_setup set to default.
[15:57:47.450] <TB1> INFO: 2 hot pixels found in step 10
[15:57:48.413] <TB1> INFO: Collecting data for 1 seconds...
[15:57:49.414] <TB1> INFO: Done with hot pixel readout
[15:57:54.124] <TB1> INFO: PixTest:: pg_setup set to default.
[15:57:54.125] <TB1> INFO: 0 hot pixels found in step 11
[15:57:54.132] <TB1> INFO: 0 hot pixels could not be trimmed and have been masked.
[15:57:54.161] <TB1> INFO: PixTest::trimHotPixels() done
[15:57:54.161] <TB1> INFO: write trim parameters into /usr/local/data/M2081_XrayQualification_2015-08-24_17h49m_1440431385//002_RetrimHotPixels_150//trimParameters35_C0.dat
[15:57:54.167] <TB1> INFO: write trim parameters into /usr/local/data/M2081_XrayQualification_2015-08-24_17h49m_1440431385//002_RetrimHotPixels_150//trimParameters35_C1.dat
[15:57:54.173] <TB1> INFO: write trim parameters into /usr/local/data/M2081_XrayQualification_2015-08-24_17h49m_1440431385//002_RetrimHotPixels_150//trimParameters35_C2.dat
[15:57:54.179] <TB1> INFO: write trim parameters into /usr/local/data/M2081_XrayQualification_2015-08-24_17h49m_1440431385//002_RetrimHotPixels_150//trimParameters35_C3.dat
[15:57:54.185] <TB1> INFO: write trim parameters into /usr/local/data/M2081_XrayQualification_2015-08-24_17h49m_1440431385//002_RetrimHotPixels_150//trimParameters35_C4.dat
[15:57:54.191] <TB1> INFO: write trim parameters into /usr/local/data/M2081_XrayQualification_2015-08-24_17h49m_1440431385//002_RetrimHotPixels_150//trimParameters35_C5.dat
[15:57:54.197] <TB1> INFO: write trim parameters into /usr/local/data/M2081_XrayQualification_2015-08-24_17h49m_1440431385//002_RetrimHotPixels_150//trimParameters35_C6.dat
[15:57:54.203] <TB1> INFO: write trim parameters into /usr/local/data/M2081_XrayQualification_2015-08-24_17h49m_1440431385//002_RetrimHotPixels_150//trimParameters35_C7.dat
[15:57:54.209] <TB1> INFO: write trim parameters into /usr/local/data/M2081_XrayQualification_2015-08-24_17h49m_1440431385//002_RetrimHotPixels_150//trimParameters35_C8.dat
[15:57:54.215] <TB1> INFO: write trim parameters into /usr/local/data/M2081_XrayQualification_2015-08-24_17h49m_1440431385//002_RetrimHotPixels_150//trimParameters35_C9.dat
[15:57:54.221] <TB1> INFO: write trim parameters into /usr/local/data/M2081_XrayQualification_2015-08-24_17h49m_1440431385//002_RetrimHotPixels_150//trimParameters35_C10.dat
[15:57:54.226] <TB1> INFO: write trim parameters into /usr/local/data/M2081_XrayQualification_2015-08-24_17h49m_1440431385//002_RetrimHotPixels_150//trimParameters35_C11.dat
[15:57:54.232] <TB1> INFO: write trim parameters into /usr/local/data/M2081_XrayQualification_2015-08-24_17h49m_1440431385//002_RetrimHotPixels_150//trimParameters35_C12.dat
[15:57:54.238] <TB1> INFO: write trim parameters into /usr/local/data/M2081_XrayQualification_2015-08-24_17h49m_1440431385//002_RetrimHotPixels_150//trimParameters35_C13.dat
[15:57:54.245] <TB1> INFO: write trim parameters into /usr/local/data/M2081_XrayQualification_2015-08-24_17h49m_1440431385//002_RetrimHotPixels_150//trimParameters35_C14.dat
[15:57:54.250] <TB1> INFO: write trim parameters into /usr/local/data/M2081_XrayQualification_2015-08-24_17h49m_1440431385//002_RetrimHotPixels_150//trimParameters35_C15.dat
[15:57:54.256] <TB1> INFO: write masked pixels into /usr/local/data/M2081_XrayQualification_2015-08-24_17h49m_1440431385//002_RetrimHotPixels_150//defaultMaskFile.dat
[15:57:54.287] <TB1> INFO: enter test to run
[15:57:54.287] <TB1> INFO: test: no parameter change
[15:57:54.471] <TB1> QUIET: Connection to board 83 closed.
[15:57:54.550] <TB1> INFO: pXar: this is the end, my friend

[15:58:10.141] <TB1> INFO: *** Welcome to pxar ***
[15:58:10.141] <TB1> INFO: *** Today: 2015/08/24
[15:58:10.141] <TB1> INFO: readRocDacs: /usr/local/data/M2081_XrayQualification_2015-08-24_17h49m_1440431385//003_HRData_50//dacParameters35_C0.dat .. /usr/local/data/M2081_XrayQualification_2015-08-24_17h49m_1440431385//003_HRData_50//dacParameters35_C15.dat
[15:58:10.141] <TB1> INFO: readTbmDacs: /usr/local/data/M2081_XrayQualification_2015-08-24_17h49m_1440431385//003_HRData_50//tbmParameters_C0a.dat .. /usr/local/data/M2081_XrayQualification_2015-08-24_17h49m_1440431385//003_HRData_50//tbmParameters_C0b.dat
[15:58:10.142] <TB1> INFO: readMaskFile: /usr/local/data/M2081_XrayQualification_2015-08-24_17h49m_1440431385//003_HRData_50//defaultMaskFile.dat
[15:58:10.142] <TB1> INFO: readTrimFile: /usr/local/data/M2081_XrayQualification_2015-08-24_17h49m_1440431385//003_HRData_50//trimParameters35_C0.dat .. /usr/local/data/M2081_XrayQualification_2015-08-24_17h49m_1440431385//003_HRData_50//trimParameters35_C15.dat
[15:58:10.214] <TB1> INFO: clk: 4
[15:58:10.214] <TB1> INFO: ctr: 4
[15:58:10.214] <TB1> INFO: sda: 19
[15:58:10.214] <TB1> INFO: tin: 9
[15:58:10.214] <TB1> INFO: level: 15
[15:58:10.214] <TB1> INFO: triggerdelay: 0
[15:58:10.214] <TB1> QUIET: Instanciating API for pxar prod-01+86~g1838649
[15:58:10.214] <TB1> INFO: Log level: INFO
[15:58:10.227] <TB1> INFO: Found DTB DTB_WS6UZO
[15:58:10.237] <TB1> QUIET: Connection to board DTB_WS6UZO opened.
[15:58:10.240] <TB1> INFO: DTB startup information
--- DTB info------------------------------------------
Board id: 83
HW version: DTB1.2
FW version: 4.0
SW version: 4.0
USB id: DTB_WS6UZO
MAC address: 40D855118053
Hostname: pixelDTB083
Comment:
------------------------------------------------------
[15:58:10.243] <TB1> INFO: RPC call hashes of host and DTB match: 447413373
[15:58:11.798] <TB1> INFO: DUT info:
[15:58:11.798] <TB1> INFO: The DUT currently contains the following objects:
[15:58:11.798] <TB1> INFO: 2 TBM Cores tbm09c (2 ON)
[15:58:11.798] <TB1> INFO: TBM Core alpha (0): 7 registers set
[15:58:11.798] <TB1> INFO: TBM Core beta (1): 7 registers set
[15:58:11.798] <TB1> INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[15:58:11.798] <TB1> INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[15:58:11.798] <TB1> INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[15:58:11.798] <TB1> INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[15:58:11.799] <TB1> INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[15:58:11.799] <TB1> INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[15:58:11.799] <TB1> INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[15:58:11.799] <TB1> INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[15:58:11.799] <TB1> INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[15:58:11.799] <TB1> INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[15:58:11.799] <TB1> INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[15:58:11.799] <TB1> INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[15:58:11.799] <TB1> INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[15:58:11.799] <TB1> INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[15:58:11.799] <TB1> INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[15:58:11.799] <TB1> INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[15:58:11.799] <TB1> INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[15:58:12.200] <TB1> INFO: enter 'restricted' command line mode
[15:58:12.200] <TB1> INFO: enter test to run
[15:58:12.200] <TB1> INFO: test: delay setting parameters: ->5<-
[15:58:12.200] <TB1> INFO: delay test by 5 seconds...
[15:58:17.200] <TB1> INFO: enter test to run
[15:58:17.200] <TB1> INFO: test: Xray no parameter change
[15:58:17.200] <TB1> INFO: running: xray
[15:58:17.201] <TB1> INFO: readGainPedestalParameters /usr/local/data/M2081_XrayQualification_2015-08-24_17h49m_1440431385//003_HRData_50//phCalibrationFitErr35_C0.dat .. /usr/local/data/M2081_XrayQualification_2015-08-24_17h49m_1440431385//003_HRData_50//phCalibrationFitErr35_C15.dat
[15:58:17.344] <TB1> INFO: ######################################################################
[15:58:17.344] <TB1> INFO: PixTestXray::doTest()
[15:58:17.344] <TB1> INFO: ######################################################################
[15:58:17.344] <TB1> INFO: ----------------------------------------------------------------------
[15:58:17.344] <TB1> INFO: PixTestXray::doPhRun() fParRunSeconds = 100
[15:58:17.344] <TB1> INFO: ----------------------------------------------------------------------
[15:58:18.314] <TB1> INFO: PixTestXray::doPhRun start TriggerLoop with trigger frequency 100 kHz, period 405 and duration 100 seconds, fEventsMax = 10000000
[15:58:24.326] <TB1> INFO: run duration 6 seconds, buffer almost full (81%), pausing triggers.
[15:58:51.775] <TB1> INFO: Resuming triggers.
[15:58:57.801] <TB1> INFO: run duration 12 seconds, buffer almost full (81%), pausing triggers.






[15:59:26.051] <TB1> INFO: Resuming triggers.
[15:59:32.074] <TB1> INFO: run duration 18 seconds, buffer almost full (81%), pausing triggers.
[15:59:52.035] <TB1> INFO: Resuming triggers.
[15:59:58.064] <TB1> INFO: run duration 24 seconds, buffer almost full (81%), pausing triggers.
[16:00:21.404] <TB1> INFO: Resuming triggers.
[16:00:27.434] <TB1> INFO: run duration 30 seconds, buffer almost full (81%), pausing triggers.
[16:00:54.024] <TB1> INFO: Resuming triggers.
[16:01:00.047] <TB1> INFO: run duration 36 seconds, buffer almost full (81%), pausing triggers.
[16:01:28.935] <TB1> INFO: Resuming triggers.
[16:01:34.959] <TB1> INFO: run duration 42 seconds, buffer almost full (81%), pausing triggers.
[16:02:04.287] <TB1> INFO: Resuming triggers.
[16:02:10.315] <TB1> INFO: run duration 48 seconds, buffer almost full (81%), pausing triggers.
[16:02:37.903] <TB1> INFO: Resuming triggers.
[16:02:43.931] <TB1> INFO: run duration 54 seconds, buffer almost full (81%), pausing triggers.
[16:03:10.976] <TB1> INFO: Resuming triggers.
[16:03:17.004] <TB1> INFO: run duration 60 seconds, buffer almost full (81%), pausing triggers.
[16:03:41.088] <TB1> INFO: Resuming triggers.
[16:03:47.117] <TB1> INFO: run duration 66 seconds, buffer almost full (81%), pausing triggers.
[16:04:17.654] <TB1> INFO: Resuming triggers.
[16:04:23.681] <TB1> INFO: run duration 72 seconds, buffer almost full (81%), pausing triggers.
[16:04:46.938] <TB1> INFO: Resuming triggers.
[16:04:52.966] <TB1> INFO: run duration 78 seconds, buffer almost full (81%), pausing triggers.
[16:05:15.372] <TB1> INFO: Resuming triggers.
[16:05:21.401] <TB1> INFO: run duration 84 seconds, buffer almost full (81%), pausing triggers.
[16:05:46.405] <TB1> INFO: Resuming triggers.
[16:05:52.435] <TB1> INFO: run duration 90 seconds, buffer almost full (81%), pausing triggers.
[16:06:10.218] <TB1> INFO: Resuming triggers.
[16:06:16.241] <TB1> INFO: run duration 96 seconds, buffer almost full (81%), pausing triggers.
[16:06:34.020] <TB1> INFO: Resuming triggers.
[16:06:37.740] <TB1> INFO: data taking finished, elapsed time: 100 seconds.
[16:06:48.951] <TB1> INFO: PixTest:: pg_setup set to default.
[16:06:48.952] <TB1> INFO: PixTestXray::doPhRun() done
[16:06:48.952] <TB1> INFO: PixTestXray::doTest() done
[16:06:49.126] <TB1> INFO: enter test to run
[16:06:49.126] <TB1> INFO: test: no parameter change
[16:06:49.312] <TB1> QUIET: Connection to board 83 closed.
[16:06:49.391] <TB1> INFO: pXar: this is the end, my friend

[16:07:04.498] <TB1> INFO: *** Welcome to pxar ***
[16:07:04.498] <TB1> INFO: *** Today: 2015/08/24
[16:07:04.498] <TB1> INFO: readRocDacs: /usr/local/data/M2081_XrayQualification_2015-08-24_17h49m_1440431385//004_HRData_150//dacParameters35_C0.dat .. /usr/local/data/M2081_XrayQualification_2015-08-24_17h49m_1440431385//004_HRData_150//dacParameters35_C15.dat
[16:07:04.499] <TB1> INFO: readTbmDacs: /usr/local/data/M2081_XrayQualification_2015-08-24_17h49m_1440431385//004_HRData_150//tbmParameters_C0a.dat .. /usr/local/data/M2081_XrayQualification_2015-08-24_17h49m_1440431385//004_HRData_150//tbmParameters_C0b.dat
[16:07:04.499] <TB1> INFO: readMaskFile: /usr/local/data/M2081_XrayQualification_2015-08-24_17h49m_1440431385//004_HRData_150//defaultMaskFile.dat
[16:07:04.499] <TB1> INFO: readTrimFile: /usr/local/data/M2081_XrayQualification_2015-08-24_17h49m_1440431385//004_HRData_150//trimParameters35_C0.dat .. /usr/local/data/M2081_XrayQualification_2015-08-24_17h49m_1440431385//004_HRData_150//trimParameters35_C15.dat
[16:07:04.563] <TB1> INFO: clk: 4
[16:07:04.563] <TB1> INFO: ctr: 4
[16:07:04.563] <TB1> INFO: sda: 19
[16:07:04.563] <TB1> INFO: tin: 9
[16:07:04.563] <TB1> INFO: level: 15
[16:07:04.563] <TB1> INFO: triggerdelay: 0
[16:07:04.563] <TB1> QUIET: Instanciating API for pxar prod-01+86~g1838649
[16:07:04.563] <TB1> INFO: Log level: INFO
[16:07:05.577] <TB1> INFO: Found DTB DTB_WS6UZO
[16:07:05.585] <TB1> QUIET: Connection to board DTB_WS6UZO opened.
[16:07:05.588] <TB1> INFO: DTB startup information
--- DTB info------------------------------------------
Board id: 83
HW version: DTB1.2
FW version: 4.0
SW version: 4.0
USB id: DTB_WS6UZO
MAC address: 40D855118053
Hostname: pixelDTB083
Comment:
------------------------------------------------------
[16:07:05.591] <TB1> INFO: RPC call hashes of host and DTB match: 447413373
[16:07:07.102] <TB1> INFO: DUT info:
[16:07:07.102] <TB1> INFO: The DUT currently contains the following objects:
[16:07:07.102] <TB1> INFO: 2 TBM Cores tbm09c (2 ON)
[16:07:07.102] <TB1> INFO: TBM Core alpha (0): 7 registers set
[16:07:07.102] <TB1> INFO: TBM Core beta (1): 7 registers set
[16:07:07.103] <TB1> INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[16:07:07.103] <TB1> INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[16:07:07.103] <TB1> INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[16:07:07.103] <TB1> INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[16:07:07.103] <TB1> INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[16:07:07.103] <TB1> INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[16:07:07.103] <TB1> INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[16:07:07.103] <TB1> INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[16:07:07.103] <TB1> INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[16:07:07.103] <TB1> INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[16:07:07.103] <TB1> INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[16:07:07.103] <TB1> INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[16:07:07.103] <TB1> INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[16:07:07.103] <TB1> INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[16:07:07.103] <TB1> INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[16:07:07.103] <TB1> INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[16:07:07.103] <TB1> INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[16:07:07.504] <TB1> INFO: enter 'restricted' command line mode
[16:07:07.504] <TB1> INFO: enter test to run
[16:07:07.504] <TB1> INFO: test: delay setting parameters: ->5<-
[16:07:07.504] <TB1> INFO: delay test by 5 seconds...
[16:07:12.504] <TB1> INFO: enter test to run
[16:07:12.504] <TB1> INFO: test: Xray no parameter change
[16:07:12.504] <TB1> INFO: running: xray
[16:07:12.504] <TB1> INFO: readGainPedestalParameters /usr/local/data/M2081_XrayQualification_2015-08-24_17h49m_1440431385//004_HRData_150//phCalibrationFitErr35_C0.dat .. /usr/local/data/M2081_XrayQualification_2015-08-24_17h49m_1440431385//004_HRData_150//phCalibrationFitErr35_C15.dat
[16:07:12.630] <TB1> INFO: ######################################################################
[16:07:12.630] <TB1> INFO: PixTestXray::doTest()
[16:07:12.630] <TB1> INFO: ######################################################################
[16:07:12.630] <TB1> INFO: ----------------------------------------------------------------------
[16:07:12.630] <TB1> INFO: PixTestXray::doPhRun() fParRunSeconds = 100
[16:07:12.630] <TB1> INFO: ----------------------------------------------------------------------
[16:07:13.599] <TB1> INFO: PixTestXray::doPhRun start TriggerLoop with trigger frequency 100 kHz, period 405 and duration 100 seconds, fEventsMax = 10000000
[16:07:16.772] <TB1> INFO: run duration 3 seconds, buffer almost full (81%), pausing triggers.
[16:07:39.195] <TB1> INFO: Resuming triggers.
[16:07:42.377] <TB1> INFO: run duration 6 seconds, buffer almost full (81%), pausing triggers.
[16:08:04.345] <TB1> INFO: Resuming triggers.
[16:08:07.534] <TB1> INFO: run duration 9 seconds, buffer almost full (81%), pausing triggers.
[16:08:29.413] <TB1> INFO: Resuming triggers.
[16:08:32.596] <TB1> INFO: run duration 12 seconds, buffer almost full (81%), pausing triggers.
[16:08:54.561] <TB1> INFO: Resuming triggers.
[16:08:57.746] <TB1> INFO: run duration 15 seconds, buffer almost full (81%), pausing triggers.
[16:09:19.275] <TB1> INFO: Resuming triggers.
[16:09:22.456] <TB1> INFO: run duration 19 seconds, buffer almost full (81%), pausing triggers.

[16:09:44.898] <TB1> INFO: Resuming triggers.
[16:09:48.081] <TB1> INFO: run duration 22 seconds, buffer almost full (81%), pausing triggers.
[16:10:10.191] <TB1> INFO: Resuming triggers.
[16:10:13.372] <TB1> INFO: run duration 25 seconds, buffer almost full (81%), pausing triggers.
[16:10:35.160] <TB1> INFO: Resuming triggers.
[16:10:38.342] <TB1> INFO: run duration 28 seconds, buffer almost full (81%), pausing triggers.
[16:10:59.644] <TB1> INFO: Resuming triggers.
[16:11:02.825] <TB1> INFO: run duration 31 seconds, buffer almost full (81%), pausing triggers.
[16:11:24.335] <TB1> INFO: Resuming triggers.
[16:11:27.512] <TB1> INFO: run duration 34 seconds, buffer almost full (81%), pausing triggers.
[16:11:48.497] <TB1> INFO: Resuming triggers.
[16:11:51.676] <TB1> INFO: run duration 38 seconds, buffer almost full (81%), pausing triggers.
[16:12:14.127] <TB1> INFO: Resuming triggers.
[16:12:17.301] <TB1> INFO: run duration 41 seconds, buffer almost full (81%), pausing triggers.
[16:12:40.318] <TB1> INFO: Resuming triggers.
[16:12:43.497] <TB1> INFO: run duration 44 seconds, buffer almost full (81%), pausing triggers.
[16:13:06.530] <TB1> INFO: Resuming triggers.
[16:13:09.709] <TB1> INFO: run duration 47 seconds, buffer almost full (81%), pausing triggers.
[16:13:32.650] <TB1> INFO: Resuming triggers.
[16:13:35.833] <TB1> INFO: run duration 50 seconds, buffer almost full (81%), pausing triggers.
[16:13:58.371] <TB1> INFO: Resuming triggers.
[16:14:01.547] <TB1> INFO: run duration 54 seconds, buffer almost full (81%), pausing triggers.
[16:14:23.862] <TB1> INFO: Resuming triggers.
[16:14:27.037] <TB1> INFO: run duration 57 seconds, buffer almost full (81%), pausing triggers.
[16:14:49.272] <TB1> INFO: Resuming triggers.
[16:14:52.446] <TB1> INFO: run duration 60 seconds, buffer almost full (81%), pausing triggers.
[16:15:14.412] <TB1> INFO: Resuming triggers.
[16:15:17.585] <TB1> INFO: run duration 63 seconds, buffer almost full (81%), pausing triggers.
[16:15:38.708] <TB1> INFO: Resuming triggers.
[16:15:41.880] <TB1> INFO: run duration 66 seconds, buffer almost full (81%), pausing triggers.
[16:16:03.037] <TB1> INFO: Resuming triggers.
[16:16:06.206] <TB1> INFO: run duration 69 seconds, buffer almost full (81%), pausing triggers.
[16:16:26.554] <TB1> INFO: Resuming triggers.
[16:16:29.722] <TB1> INFO: run duration 73 seconds, buffer almost full (81%), pausing triggers.
[16:16:50.929] <TB1> INFO: Resuming triggers.
[16:16:54.098] <TB1> INFO: run duration 76 seconds, buffer almost full (81%), pausing triggers.
[16:17:15.196] <TB1> INFO: Resuming triggers.
[16:17:18.365] <TB1> INFO: run duration 79 seconds, buffer almost full (81%), pausing triggers.
[16:17:40.346] <TB1> INFO: Resuming triggers.
[16:17:43.520] <TB1> INFO: run duration 82 seconds, buffer almost full (81%), pausing triggers.
[16:18:05.985] <TB1> INFO: Resuming triggers.
[16:18:09.157] <TB1> INFO: run duration 85 seconds, buffer almost full (81%), pausing triggers.
[16:18:31.943] <TB1> INFO: Resuming triggers.
[16:18:35.112] <TB1> INFO: run duration 88 seconds, buffer almost full (81%), pausing triggers.
[16:18:58.230] <TB1> INFO: Resuming triggers.
[16:19:01.401] <TB1> INFO: run duration 92 seconds, buffer almost full (81%), pausing triggers.
[16:19:23.686] <TB1> INFO: Resuming triggers.
[16:19:26.857] <TB1> INFO: run duration 95 seconds, buffer almost full (81%), pausing triggers.
[16:19:48.433] <TB1> INFO: Resuming triggers.
[16:19:51.602] <TB1> INFO: run duration 98 seconds, buffer almost full (81%), pausing triggers.
[16:20:13.087] <TB1> INFO: Resuming triggers.
[16:20:14.723] <TB1> INFO: data taking finished, elapsed time: 100 seconds.
[16:20:24.691] <TB1> INFO: PixTest:: pg_setup set to default.
[16:20:24.693] <TB1> INFO: PixTestXray::doPhRun() done
[16:20:24.693] <TB1> INFO: PixTestXray::doTest() done
[16:20:24.871] <TB1> INFO: enter test to run
[16:20:24.871] <TB1> INFO: test: no parameter change
[16:20:25.059] <TB1> QUIET: Connection to board 83 closed.
[16:20:25.138] <TB1> INFO: pXar: this is the end, my friend

[16:20:39.753] <TB1> INFO: *** Welcome to pxar ***
[16:20:39.753] <TB1> INFO: *** Today: 2015/08/24
[16:20:39.753] <TB1> INFO: readRocDacs: /usr/local/data/M2081_XrayQualification_2015-08-24_17h49m_1440431385//005_HRSCurves_100//dacParameters35_C0.dat .. /usr/local/data/M2081_XrayQualification_2015-08-24_17h49m_1440431385//005_HRSCurves_100//dacParameters35_C15.dat
[16:20:39.754] <TB1> INFO: readTbmDacs: /usr/local/data/M2081_XrayQualification_2015-08-24_17h49m_1440431385//005_HRSCurves_100//tbmParameters_C0a.dat .. /usr/local/data/M2081_XrayQualification_2015-08-24_17h49m_1440431385//005_HRSCurves_100//tbmParameters_C0b.dat
[16:20:39.754] <TB1> INFO: readMaskFile: /usr/local/data/M2081_XrayQualification_2015-08-24_17h49m_1440431385//005_HRSCurves_100//defaultMaskFile.dat
[16:20:39.754] <TB1> INFO: readTrimFile: /usr/local/data/M2081_XrayQualification_2015-08-24_17h49m_1440431385//005_HRSCurves_100//trimParameters35_C0.dat .. /usr/local/data/M2081_XrayQualification_2015-08-24_17h49m_1440431385//005_HRSCurves_100//trimParameters35_C15.dat
[16:20:39.822] <TB1> INFO: clk: 4
[16:20:39.823] <TB1> INFO: ctr: 4
[16:20:39.823] <TB1> INFO: sda: 19
[16:20:39.823] <TB1> INFO: tin: 9
[16:20:39.823] <TB1> INFO: level: 15
[16:20:39.823] <TB1> INFO: triggerdelay: 0
[16:20:39.823] <TB1> QUIET: Instanciating API for pxar prod-01+86~g1838649
[16:20:39.823] <TB1> INFO: Log level: INFO
[16:20:40.834] <TB1> INFO: Found DTB DTB_WS6UZO
[16:20:40.842] <TB1> QUIET: Connection to board DTB_WS6UZO opened.
[16:20:40.846] <TB1> INFO: DTB startup information
--- DTB info------------------------------------------
Board id: 83
HW version: DTB1.2
FW version: 4.0
SW version: 4.0
USB id: DTB_WS6UZO
MAC address: 40D855118053
Hostname: pixelDTB083
Comment:
------------------------------------------------------
[16:20:40.848] <TB1> INFO: RPC call hashes of host and DTB match: 447413373
[16:20:42.363] <TB1> INFO: DUT info:
[16:20:42.363] <TB1> INFO: The DUT currently contains the following objects:
[16:20:42.363] <TB1> INFO: 2 TBM Cores tbm09c (2 ON)
[16:20:42.363] <TB1> INFO: TBM Core alpha (0): 7 registers set
[16:20:42.363] <TB1> INFO: TBM Core beta (1): 7 registers set
[16:20:42.363] <TB1> INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[16:20:42.363] <TB1> INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[16:20:42.363] <TB1> INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[16:20:42.363] <TB1> INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[16:20:42.363] <TB1> INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[16:20:42.363] <TB1> INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[16:20:42.363] <TB1> INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[16:20:42.363] <TB1> INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[16:20:42.363] <TB1> INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[16:20:42.363] <TB1> INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[16:20:42.363] <TB1> INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[16:20:42.363] <TB1> INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[16:20:42.363] <TB1> INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[16:20:42.363] <TB1> INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[16:20:42.363] <TB1> INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[16:20:42.363] <TB1> INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[16:20:42.363] <TB1> INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[16:20:42.764] <TB1> INFO: enter 'restricted' command line mode
[16:20:42.764] <TB1> INFO: enter test to run
[16:20:42.764] <TB1> INFO: test: Highrate no parameter change
[16:20:42.764] <TB1> INFO: running: highrate
[16:20:42.765] <TB1> INFO: readGainPedestalParameters /usr/local/data/M2081_XrayQualification_2015-08-24_17h49m_1440431385//005_HRSCurves_100//phCalibrationFitErr35_C0.dat .. /usr/local/data/M2081_XrayQualification_2015-08-24_17h49m_1440431385//005_HRSCurves_100//phCalibrationFitErr35_C15.dat
[16:20:42.889] <TB1> INFO: ----------------------------------------------------------------------
[16:20:42.889] <TB1> INFO: PixTestHighRate::xNoiseMaps() ntrig = 50, vcal = 200
[16:20:42.889] <TB1> INFO: ----------------------------------------------------------------------
[16:20:42.895] <TB1> INFO: ---> dac: vcal name: xNoiseMap ntrig: 50 dacrange: 0 .. 100 (1/-1) hits flags = 384 (plus default)
[16:20:42.906] <TB1> INFO: dacScan step from 0 .. 0
[16:20:42.906] <TB1> INFO: dacScan split into 1 runs with ntrig = 50
[16:20:42.906] <TB1> INFO: run 1 of 1
[16:20:55.285] <TB1> INFO: Test took 12379ms.
[16:20:57.329] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[16:20:57.621] <TB1> INFO: dacScan step from 1 .. 1
[16:20:57.621] <TB1> INFO: dacScan split into 1 runs with ntrig = 50
[16:20:57.621] <TB1> INFO: run 1 of 1
[16:21:09.737] <TB1> INFO: Test took 12115ms.
[16:21:11.929] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[16:21:12.215] <TB1> INFO: dacScan step from 2 .. 2
[16:21:12.215] <TB1> INFO: dacScan split into 1 runs with ntrig = 50
[16:21:12.215] <TB1> INFO: run 1 of 1
[16:21:24.349] <TB1> INFO: Test took 12134ms.
[16:21:26.619] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[16:21:26.974] <TB1> INFO: dacScan step from 3 .. 3
[16:21:26.974] <TB1> INFO: dacScan split into 1 runs with ntrig = 50
[16:21:26.974] <TB1> INFO: run 1 of 1
[16:21:38.915] <TB1> INFO: Test took 11941ms.
[16:21:41.118] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[16:21:41.398] <TB1> INFO: dacScan step from 4 .. 4
[16:21:41.398] <TB1> INFO: dacScan split into 1 runs with ntrig = 50
[16:21:41.398] <TB1> INFO: run 1 of 1
[16:21:53.513] <TB1> INFO: Test took 12114ms.
[16:21:55.598] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[16:21:55.998] <TB1> INFO: dacScan step from 5 .. 5
[16:21:55.998] <TB1> INFO: dacScan split into 1 runs with ntrig = 50
[16:21:55.998] <TB1> INFO: run 1 of 1
[16:22:08.163] <TB1> INFO: Test took 12164ms.
[16:22:10.538] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[16:22:10.849] <TB1> INFO: dacScan step from 6 .. 6
[16:22:10.849] <TB1> INFO: dacScan split into 1 runs with ntrig = 50
[16:22:10.849] <TB1> INFO: run 1 of 1
[16:22:23.049] <TB1> INFO: Test took 12200ms.
[16:22:25.154] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[16:22:25.425] <TB1> INFO: dacScan step from 7 .. 7
[16:22:25.425] <TB1> INFO: dacScan split into 1 runs with ntrig = 50
[16:22:25.425] <TB1> INFO: run 1 of 1
[16:22:37.500] <TB1> INFO: Test took 12075ms.
[16:22:39.488] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[16:22:39.835] <TB1> INFO: dacScan step from 8 .. 8
[16:22:39.836] <TB1> INFO: dacScan split into 1 runs with ntrig = 50
[16:22:39.836] <TB1> INFO: run 1 of 1
[16:22:51.986] <TB1> INFO: Test took 12150ms.
[16:22:54.004] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[16:22:54.283] <TB1> INFO: dacScan step from 9 .. 9
[16:22:54.283] <TB1> INFO: dacScan split into 1 runs with ntrig = 50
[16:22:54.283] <TB1> INFO: run 1 of 1
[16:23:06.552] <TB1> INFO: Test took 12269ms.
[16:23:08.784] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[16:23:09.080] <TB1> INFO: dacScan step from 10 .. 10
[16:23:09.080] <TB1> INFO: dacScan split into 1 runs with ntrig = 50
[16:23:09.080] <TB1> INFO: run 1 of 1
[16:23:21.234] <TB1> INFO: Test took 12154ms.
[16:23:23.363] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[16:23:23.742] <TB1> INFO: dacScan step from 11 .. 11
[16:23:23.742] <TB1> INFO: dacScan split into 1 runs with ntrig = 50
[16:23:23.742] <TB1> INFO: run 1 of 1
[16:23:36.047] <TB1> INFO: Test took 12305ms.
[16:23:38.237] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[16:23:38.562] <TB1> INFO: dacScan step from 12 .. 12
[16:23:38.562] <TB1> INFO: dacScan split into 1 runs with ntrig = 50
[16:23:38.562] <TB1> INFO: run 1 of 1
[16:23:50.674] <TB1> INFO: Test took 12111ms.
[16:23:53.125] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[16:23:53.479] <TB1> INFO: dacScan step from 13 .. 13
[16:23:53.479] <TB1> INFO: dacScan split into 1 runs with ntrig = 50
[16:23:53.480] <TB1> INFO: run 1 of 1
[16:24:05.713] <TB1> INFO: Test took 12233ms.
[16:24:07.765] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[16:24:08.052] <TB1> INFO: dacScan step from 14 .. 14
[16:24:08.052] <TB1> INFO: dacScan split into 1 runs with ntrig = 50
[16:24:08.052] <TB1> INFO: run 1 of 1
[16:24:20.308] <TB1> INFO: Test took 12256ms.
[16:24:22.682] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[16:24:23.029] <TB1> INFO: dacScan step from 15 .. 15
[16:24:23.029] <TB1> INFO: dacScan split into 1 runs with ntrig = 50
[16:24:23.029] <TB1> INFO: run 1 of 1
[16:24:35.397] <TB1> INFO: Test took 12367ms.
[16:24:37.632] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[16:24:37.904] <TB1> INFO: dacScan step from 16 .. 16
[16:24:37.904] <TB1> INFO: dacScan split into 1 runs with ntrig = 50
[16:24:37.904] <TB1> INFO: run 1 of 1
[16:24:49.869] <TB1> INFO: Test took 11965ms.
[16:24:51.874] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[16:24:52.168] <TB1> INFO: dacScan step from 17 .. 17
[16:24:52.168] <TB1> INFO: dacScan split into 1 runs with ntrig = 50
[16:24:52.168] <TB1> INFO: run 1 of 1
[16:25:04.531] <TB1> INFO: Test took 12363ms.
[16:25:06.536] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[16:25:06.866] <TB1> INFO: dacScan step from 18 .. 18
[16:25:06.866] <TB1> INFO: dacScan split into 1 runs with ntrig = 50
[16:25:06.866] <TB1> INFO: run 1 of 1
[16:25:19.161] <TB1> INFO: Test took 12295ms.
[16:25:21.374] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[16:25:21.661] <TB1> INFO: dacScan step from 19 .. 19
[16:25:21.661] <TB1> INFO: dacScan split into 1 runs with ntrig = 50
[16:25:21.661] <TB1> INFO: run 1 of 1
[16:25:34.252] <TB1> INFO: Test took 12590ms.
[16:25:36.624] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[16:25:36.980] <TB1> INFO: dacScan step from 20 .. 20
[16:25:36.980] <TB1> INFO: dacScan split into 1 runs with ntrig = 50
[16:25:36.980] <TB1> INFO: run 1 of 1
[16:25:49.321] <TB1> INFO: Test took 12341ms.
[16:25:51.439] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[16:25:51.714] <TB1> INFO: dacScan step from 21 .. 21
[16:25:51.714] <TB1> INFO: dacScan split into 1 runs with ntrig = 50
[16:25:51.714] <TB1> INFO: run 1 of 1
[16:26:04.074] <TB1> INFO: Test took 12360ms.
[16:26:06.101] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[16:26:06.389] <TB1> INFO: dacScan step from 22 .. 22
[16:26:06.389] <TB1> INFO: dacScan split into 1 runs with ntrig = 50
[16:26:06.393] <TB1> INFO: run 1 of 1
[16:26:18.762] <TB1> INFO: Test took 12368ms.
[16:26:20.901] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[16:26:21.175] <TB1> INFO: dacScan step from 23 .. 23
[16:26:21.175] <TB1> INFO: dacScan split into 1 runs with ntrig = 50
[16:26:21.175] <TB1> INFO: run 1 of 1
[16:26:33.578] <TB1> INFO: Test took 12402ms.
[16:26:35.602] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[16:26:35.887] <TB1> INFO: dacScan step from 24 .. 24
[16:26:35.887] <TB1> INFO: dacScan split into 1 runs with ntrig = 50
[16:26:35.887] <TB1> INFO: run 1 of 1
[16:26:48.350] <TB1> INFO: Test took 12463ms.
[16:26:50.514] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[16:26:50.817] <TB1> INFO: dacScan step from 25 .. 25
[16:26:50.818] <TB1> INFO: dacScan split into 1 runs with ntrig = 50
[16:26:50.818] <TB1> INFO: run 1 of 1
[16:27:03.225] <TB1> INFO: Test took 12407ms.
[16:27:05.232] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[16:27:05.502] <TB1> INFO: dacScan step from 26 .. 26
[16:27:05.502] <TB1> INFO: dacScan split into 1 runs with ntrig = 50
[16:27:05.502] <TB1> INFO: run 1 of 1
[16:27:18.081] <TB1> INFO: Test took 12579ms.
[16:27:20.390] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[16:27:20.709] <TB1> INFO: dacScan step from 27 .. 27
[16:27:20.709] <TB1> INFO: dacScan split into 1 runs with ntrig = 50
[16:27:20.709] <TB1> INFO: run 1 of 1
[16:27:33.331] <TB1> INFO: Test took 12621ms.
[16:27:35.595] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[16:27:35.875] <TB1> INFO: dacScan step from 28 .. 28
[16:27:35.875] <TB1> INFO: dacScan split into 1 runs with ntrig = 50
[16:27:35.875] <TB1> INFO: run 1 of 1
[16:27:48.291] <TB1> INFO: Test took 12415ms.
[16:27:50.369] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[16:27:50.648] <TB1> INFO: dacScan step from 29 .. 29
[16:27:50.648] <TB1> INFO: dacScan split into 1 runs with ntrig = 50
[16:27:50.648] <TB1> INFO: run 1 of 1
[16:28:03.077] <TB1> INFO: Test took 12428ms.
[16:28:05.085] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[16:28:05.375] <TB1> INFO: dacScan step from 30 .. 30
[16:28:05.375] <TB1> INFO: dacScan split into 1 runs with ntrig = 50
[16:28:05.375] <TB1> INFO: run 1 of 1
[16:28:17.803] <TB1> INFO: Test took 12427ms.
[16:28:20.018] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[16:28:20.335] <TB1> INFO: dacScan step from 31 .. 31
[16:28:20.335] <TB1> INFO: dacScan split into 1 runs with ntrig = 50
[16:28:20.335] <TB1> INFO: run 1 of 1
[16:28:32.967] <TB1> INFO: Test took 12631ms.
[16:28:35.426] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[16:28:35.791] <TB1> INFO: dacScan step from 32 .. 32
[16:28:35.791] <TB1> INFO: dacScan split into 1 runs with ntrig = 50
[16:28:35.791] <TB1> INFO: run 1 of 1
[16:28:48.210] <TB1> INFO: Test took 12419ms.
[16:28:50.324] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[16:28:50.602] <TB1> INFO: dacScan step from 33 .. 33
[16:28:50.602] <TB1> INFO: dacScan split into 1 runs with ntrig = 50
[16:28:50.602] <TB1> INFO: run 1 of 1
[16:29:03.103] <TB1> INFO: Test took 12501ms.
[16:29:05.130] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[16:29:05.471] <TB1> INFO: dacScan step from 34 .. 34
[16:29:05.471] <TB1> INFO: dacScan split into 1 runs with ntrig = 50
[16:29:05.471] <TB1> INFO: run 1 of 1
[16:29:17.904] <TB1> INFO: Test took 12432ms.
[16:29:19.986] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[16:29:20.259] <TB1> INFO: dacScan step from 35 .. 35
[16:29:20.259] <TB1> INFO: dacScan split into 1 runs with ntrig = 50
[16:29:20.259] <TB1> INFO: run 1 of 1
[16:29:32.838] <TB1> INFO: Test took 12579ms.
[16:29:34.919] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[16:29:35.191] <TB1> INFO: dacScan step from 36 .. 36
[16:29:35.191] <TB1> INFO: dacScan split into 1 runs with ntrig = 50
[16:29:35.191] <TB1> INFO: run 1 of 1
[16:29:47.685] <TB1> INFO: Test took 12493ms.
[16:29:49.895] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[16:29:50.180] <TB1> INFO: dacScan step from 37 .. 37
[16:29:50.180] <TB1> INFO: dacScan split into 1 runs with ntrig = 50
[16:29:50.180] <TB1> INFO: run 1 of 1
[16:30:02.851] <TB1> INFO: Test took 12671ms.
[16:30:04.966] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[16:30:05.246] <TB1> INFO: dacScan step from 38 .. 38
[16:30:05.246] <TB1> INFO: dacScan split into 1 runs with ntrig = 50
[16:30:05.246] <TB1> INFO: run 1 of 1
[16:30:18.037] <TB1> INFO: Test took 12790ms.
[16:30:20.147] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[16:30:20.452] <TB1> INFO: dacScan step from 39 .. 39
[16:30:20.452] <TB1> INFO: dacScan split into 1 runs with ntrig = 50
[16:30:20.452] <TB1> INFO: run 1 of 1
[16:30:33.375] <TB1> INFO: Test took 12923ms.
[16:30:35.556] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[16:30:35.836] <TB1> INFO: dacScan step from 40 .. 40
[16:30:35.836] <TB1> INFO: dacScan split into 1 runs with ntrig = 50
[16:30:35.836] <TB1> INFO: run 1 of 1
[16:30:48.866] <TB1> INFO: Test took 13030ms.
[16:30:51.099] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[16:30:51.387] <TB1> INFO: dacScan step from 41 .. 41
[16:30:51.387] <TB1> INFO: dacScan split into 1 runs with ntrig = 50
[16:30:51.387] <TB1> INFO: run 1 of 1
[16:31:04.429] <TB1> INFO: Test took 13041ms.
[16:31:07.070] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[16:31:07.366] <TB1> INFO: dacScan step from 42 .. 42
[16:31:07.366] <TB1> INFO: dacScan split into 1 runs with ntrig = 50
[16:31:07.366] <TB1> INFO: run 1 of 1
[16:31:20.647] <TB1> INFO: Test took 13280ms.
[16:31:22.832] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[16:31:23.126] <TB1> INFO: dacScan step from 43 .. 43
[16:31:23.126] <TB1> INFO: dacScan split into 1 runs with ntrig = 50
[16:31:23.126] <TB1> INFO: run 1 of 1
[16:31:36.516] <TB1> INFO: Test took 13389ms.
[16:31:38.783] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[16:31:39.067] <TB1> INFO: dacScan step from 44 .. 44
[16:31:39.067] <TB1> INFO: dacScan split into 1 runs with ntrig = 50
[16:31:39.067] <TB1> INFO: run 1 of 1
[16:31:52.731] <TB1> INFO: Test took 13664ms.
[16:31:55.353] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[16:31:55.677] <TB1> INFO: dacScan step from 45 .. 45
[16:31:55.677] <TB1> INFO: dacScan split into 1 runs with ntrig = 50
[16:31:55.677] <TB1> INFO: run 1 of 1
[16:32:09.717] <TB1> INFO: Test took 14039ms.
[16:32:12.048] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[16:32:12.401] <TB1> INFO: dacScan step from 46 .. 46
[16:32:12.401] <TB1> INFO: dacScan split into 1 runs with ntrig = 50
[16:32:12.401] <TB1> INFO: run 1 of 1
[16:32:26.754] <TB1> INFO: Test took 14353ms.
[16:32:29.154] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[16:32:29.447] <TB1> INFO: dacScan step from 47 .. 47
[16:32:29.447] <TB1> INFO: dacScan split into 1 runs with ntrig = 50
[16:32:29.447] <TB1> INFO: run 1 of 1
[16:32:43.641] <TB1> INFO: Test took 14194ms.
[16:32:45.904] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[16:32:46.232] <TB1> INFO: dacScan step from 48 .. 48
[16:32:46.232] <TB1> INFO: dacScan split into 1 runs with ntrig = 50
[16:32:46.232] <TB1> INFO: run 1 of 1
[16:33:00.955] <TB1> INFO: Test took 14722ms.
[16:33:03.187] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[16:33:03.570] <TB1> INFO: dacScan step from 49 .. 49
[16:33:03.570] <TB1> INFO: dacScan split into 1 runs with ntrig = 50
[16:33:03.570] <TB1> INFO: run 1 of 1
[16:33:18.454] <TB1> INFO: Test took 14883ms.
[16:33:21.102] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[16:33:21.482] <TB1> INFO: dacScan step from 50 .. 50
[16:33:21.482] <TB1> INFO: dacScan split into 1 runs with ntrig = 50
[16:33:21.482] <TB1> INFO: run 1 of 1
[16:33:36.330] <TB1> INFO: Test took 14847ms.
[16:33:38.748] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[16:33:39.047] <TB1> INFO: dacScan step from 51 .. 51
[16:33:39.047] <TB1> INFO: dacScan split into 1 runs with ntrig = 50
[16:33:39.047] <TB1> INFO: run 1 of 1
[16:33:53.791] <TB1> INFO: Test took 14743ms.
[16:33:56.414] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[16:33:56.794] <TB1> INFO: dacScan step from 52 .. 52
[16:33:56.794] <TB1> INFO: dacScan split into 1 runs with ntrig = 50
[16:33:56.794] <TB1> INFO: run 1 of 1
[16:34:11.607] <TB1> INFO: Test took 14812ms.
[16:34:14.257] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[16:34:14.614] <TB1> INFO: dacScan step from 53 .. 53
[16:34:14.614] <TB1> INFO: dacScan split into 1 runs with ntrig = 50
[16:34:14.614] <TB1> INFO: run 1 of 1
[16:34:29.443] <TB1> INFO: Test took 14828ms.
[16:34:32.199] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[16:34:32.540] <TB1> INFO: dacScan step from 54 .. 54
[16:34:32.540] <TB1> INFO: dacScan split into 1 runs with ntrig = 50
[16:34:32.540] <TB1> INFO: run 1 of 1
[16:34:47.340] <TB1> INFO: Test took 14799ms.
[16:34:49.789] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[16:34:50.073] <TB1> INFO: dacScan step from 55 .. 55
[16:34:50.073] <TB1> INFO: dacScan split into 1 runs with ntrig = 50
[16:34:50.073] <TB1> INFO: run 1 of 1
[16:35:04.849] <TB1> INFO: Test took 14775ms.
[16:35:07.302] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[16:35:07.674] <TB1> INFO: dacScan step from 56 .. 56
[16:35:07.674] <TB1> INFO: dacScan split into 1 runs with ntrig = 50
[16:35:07.674] <TB1> INFO: run 1 of 1
[16:35:22.247] <TB1> INFO: Test took 14572ms.
[16:35:25.002] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[16:35:25.364] <TB1> INFO: dacScan step from 57 .. 57
[16:35:25.364] <TB1> INFO: dacScan split into 1 runs with ntrig = 50
[16:35:25.364] <TB1> INFO: run 1 of 1
[16:35:40.064] <TB1> INFO: Test took 14699ms.
[16:35:42.449] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[16:35:42.751] <TB1> INFO: dacScan step from 58 .. 58
[16:35:42.751] <TB1> INFO: dacScan split into 1 runs with ntrig = 50
[16:35:42.751] <TB1> INFO: run 1 of 1
[16:35:57.341] <TB1> INFO: Test took 14589ms.
[16:35:59.657] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[16:35:59.951] <TB1> INFO: dacScan step from 59 .. 59
[16:35:59.951] <TB1> INFO: dacScan split into 1 runs with ntrig = 50
[16:35:59.951] <TB1> INFO: run 1 of 1
[16:36:14.586] <TB1> INFO: Test took 14635ms.
[16:36:16.903] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[16:36:17.229] <TB1> INFO: dacScan step from 60 .. 60
[16:36:17.229] <TB1> INFO: dacScan split into 1 runs with ntrig = 50
[16:36:17.229] <TB1> INFO: run 1 of 1
[16:36:31.902] <TB1> INFO: Test took 14672ms.
[16:36:34.491] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[16:36:34.849] <TB1> INFO: dacScan step from 61 .. 61
[16:36:34.849] <TB1> INFO: dacScan split into 1 runs with ntrig = 50
[16:36:34.849] <TB1> INFO: run 1 of 1
[16:36:49.670] <TB1> INFO: Test took 14820ms.
[16:36:52.085] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[16:36:52.401] <TB1> INFO: dacScan step from 62 .. 62
[16:36:52.401] <TB1> INFO: dacScan split into 1 runs with ntrig = 50
[16:36:52.401] <TB1> INFO: run 1 of 1
[16:37:07.345] <TB1> INFO: Test took 14944ms.
[16:37:09.865] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[16:37:10.183] <TB1> INFO: dacScan step from 63 .. 63
[16:37:10.183] <TB1> INFO: dacScan split into 1 runs with ntrig = 50
[16:37:10.183] <TB1> INFO: run 1 of 1
[16:37:24.813] <TB1> INFO: Test took 14629ms.
[16:37:27.440] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[16:37:27.761] <TB1> INFO: dacScan step from 64 .. 64
[16:37:27.761] <TB1> INFO: dacScan split into 1 runs with ntrig = 50
[16:37:27.761] <TB1> INFO: run 1 of 1
[16:37:42.172] <TB1> INFO: Test took 14410ms.
[16:37:44.707] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[16:37:45.018] <TB1> INFO: dacScan step from 65 .. 65
[16:37:45.018] <TB1> INFO: dacScan split into 1 runs with ntrig = 50
[16:37:45.019] <TB1> INFO: run 1 of 1
[16:37:59.339] <TB1> INFO: Test took 14319ms.
[16:38:02.029] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[16:38:02.402] <TB1> INFO: dacScan step from 66 .. 66
[16:38:02.402] <TB1> INFO: dacScan split into 1 runs with ntrig = 50
[16:38:02.402] <TB1> INFO: run 1 of 1
[16:38:16.932] <TB1> INFO: Test took 14529ms.
[16:38:19.220] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[16:38:19.507] <TB1> INFO: dacScan step from 67 .. 67
[16:38:19.507] <TB1> INFO: dacScan split into 1 runs with ntrig = 50
[16:38:19.507] <TB1> INFO: run 1 of 1
[16:38:34.350] <TB1> INFO: Test took 14842ms.
[16:38:36.657] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[16:38:37.012] <TB1> INFO: dacScan step from 68 .. 68
[16:38:37.012] <TB1> INFO: dacScan split into 1 runs with ntrig = 50
[16:38:37.012] <TB1> INFO: run 1 of 1
[16:38:51.832] <TB1> INFO: Test took 14819ms.
[16:38:54.085] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[16:38:54.379] <TB1> INFO: dacScan step from 69 .. 69
[16:38:54.379] <TB1> INFO: dacScan split into 1 runs with ntrig = 50
[16:38:54.379] <TB1> INFO: run 1 of 1
[16:39:09.173] <TB1> INFO: Test took 14793ms.
[16:39:11.667] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[16:39:11.967] <TB1> INFO: dacScan step from 70 .. 70
[16:39:11.967] <TB1> INFO: dacScan split into 1 runs with ntrig = 50
[16:39:11.967] <TB1> INFO: run 1 of 1
[16:39:26.739] <TB1> INFO: Test took 14771ms.
[16:39:28.995] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[16:39:29.315] <TB1> INFO: dacScan step from 71 .. 71
[16:39:29.315] <TB1> INFO: dacScan split into 1 runs with ntrig = 50
[16:39:29.315] <TB1> INFO: run 1 of 1
[16:39:44.070] <TB1> INFO: Test took 14754ms.
[16:39:46.649] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[16:39:46.967] <TB1> INFO: dacScan step from 72 .. 72
[16:39:46.967] <TB1> INFO: dacScan split into 1 runs with ntrig = 50
[16:39:46.967] <TB1> INFO: run 1 of 1
[16:40:01.886] <TB1> INFO: Test took 14918ms.
[16:40:04.335] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[16:40:04.646] <TB1> INFO: dacScan step from 73 .. 73
[16:40:04.646] <TB1> INFO: dacScan split into 1 runs with ntrig = 50
[16:40:04.646] <TB1> INFO: run 1 of 1
[16:40:19.732] <TB1> INFO: Test took 15085ms.
[16:40:22.131] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[16:40:22.427] <TB1> INFO: dacScan step from 74 .. 74
[16:40:22.427] <TB1> INFO: dacScan split into 1 runs with ntrig = 50
[16:40:22.427] <TB1> INFO: run 1 of 1
[16:40:37.256] <TB1> INFO: Test took 14829ms.
[16:40:39.681] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[16:40:39.970] <TB1> INFO: dacScan step from 75 .. 75
[16:40:39.970] <TB1> INFO: dacScan split into 1 runs with ntrig = 50
[16:40:39.970] <TB1> INFO: run 1 of 1
[16:40:54.929] <TB1> INFO: Test took 14958ms.
[16:40:57.524] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[16:40:57.888] <TB1> INFO: dacScan step from 76 .. 76
[16:40:57.888] <TB1> INFO: dacScan split into 1 runs with ntrig = 50
[16:40:57.888] <TB1> INFO: run 1 of 1
[16:41:13.025] <TB1> INFO: Test took 15136ms.
[16:41:15.274] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[16:41:15.576] <TB1> INFO: dacScan step from 77 .. 77
[16:41:15.576] <TB1> INFO: dacScan split into 1 runs with ntrig = 50
[16:41:15.576] <TB1> INFO: run 1 of 1
[16:41:30.590] <TB1> INFO: Test took 15013ms.
[16:41:33.238] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[16:41:33.579] <TB1> INFO: dacScan step from 78 .. 78
[16:41:33.579] <TB1> INFO: dacScan split into 1 runs with ntrig = 50
[16:41:33.579] <TB1> INFO: run 1 of 1
[16:41:48.595] <TB1> INFO: Test took 15015ms.
[16:41:51.013] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[16:41:51.303] <TB1> INFO: dacScan step from 79 .. 79
[16:41:51.303] <TB1> INFO: dacScan split into 1 runs with ntrig = 50
[16:41:51.303] <TB1> INFO: run 1 of 1
[16:42:06.237] <TB1> INFO: Test took 14933ms.
[16:42:08.906] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[16:42:09.268] <TB1> INFO: dacScan step from 80 .. 80
[16:42:09.268] <TB1> INFO: dacScan split into 1 runs with ntrig = 50
[16:42:09.268] <TB1> INFO: run 1 of 1
[16:42:24.123] <TB1> INFO: Test took 14854ms.
[16:42:26.808] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[16:42:27.126] <TB1> INFO: dacScan step from 81 .. 81
[16:42:27.126] <TB1> INFO: dacScan split into 1 runs with ntrig = 50
[16:42:27.126] <TB1> INFO: run 1 of 1
[16:42:42.013] <TB1> INFO: Test took 14886ms.
[16:42:44.424] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[16:42:44.705] <TB1> INFO: dacScan step from 82 .. 82
[16:42:44.705] <TB1> INFO: dacScan split into 1 runs with ntrig = 50
[16:42:44.705] <TB1> INFO: run 1 of 1
[16:42:59.677] <TB1> INFO: Test took 14971ms.
[16:43:02.100] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[16:43:02.405] <TB1> INFO: dacScan step from 83 .. 83
[16:43:02.405] <TB1> INFO: dacScan split into 1 runs with ntrig = 50
[16:43:02.405] <TB1> INFO: run 1 of 1
[16:43:17.456] <TB1> INFO: Test took 15050ms.
[16:43:19.985] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[16:43:20.344] <TB1> INFO: dacScan step from 84 .. 84
[16:43:20.344] <TB1> INFO: dacScan split into 1 runs with ntrig = 50
[16:43:20.344] <TB1> INFO: run 1 of 1
[16:43:35.358] <TB1> INFO: Test took 15013ms.
[16:43:37.925] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[16:43:38.283] <TB1> INFO: dacScan step from 85 .. 85
[16:43:38.283] <TB1> INFO: dacScan split into 1 runs with ntrig = 50
[16:43:38.283] <TB1> INFO: run 1 of 1
[16:43:53.012] <TB1> INFO: Test took 14728ms.
[16:43:55.375] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[16:43:55.660] <TB1> INFO: dacScan step from 86 .. 86
[16:43:55.660] <TB1> INFO: dacScan split into 1 runs with ntrig = 50
[16:43:55.660] <TB1> INFO: run 1 of 1
[16:44:10.693] <TB1> INFO: Test took 15032ms.
[16:44:13.146] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[16:44:13.451] <TB1> INFO: dacScan step from 87 .. 87
[16:44:13.451] <TB1> INFO: dacScan split into 1 runs with ntrig = 50
[16:44:13.451] <TB1> INFO: run 1 of 1
[16:44:28.446] <TB1> INFO: Test took 14994ms.
[16:44:30.736] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[16:44:31.062] <TB1> INFO: dacScan step from 88 .. 88
[16:44:31.062] <TB1> INFO: dacScan split into 1 runs with ntrig = 50
[16:44:31.062] <TB1> INFO: run 1 of 1
[16:44:45.837] <TB1> INFO: Test took 14774ms.
[16:44:48.179] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[16:44:48.515] <TB1> INFO: dacScan step from 89 .. 89
[16:44:48.515] <TB1> INFO: dacScan split into 1 runs with ntrig = 50
[16:44:48.515] <TB1> INFO: run 1 of 1
[16:45:03.275] <TB1> INFO: Test took 14759ms.
[16:45:05.536] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[16:45:05.823] <TB1> INFO: dacScan step from 90 .. 90
[16:45:05.823] <TB1> INFO: dacScan split into 1 runs with ntrig = 50
[16:45:05.823] <TB1> INFO: run 1 of 1
[16:45:20.782] <TB1> INFO: Test took 14958ms.
[16:45:23.074] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[16:45:23.371] <TB1> INFO: dacScan step from 91 .. 91
[16:45:23.371] <TB1> INFO: dacScan split into 1 runs with ntrig = 50
[16:45:23.371] <TB1> INFO: run 1 of 1
[16:45:38.423] <TB1> INFO: Test took 15051ms.
[16:45:40.748] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[16:45:41.088] <TB1> INFO: dacScan step from 92 .. 92
[16:45:41.088] <TB1> INFO: dacScan split into 1 runs with ntrig = 50
[16:45:41.088] <TB1> INFO: run 1 of 1
[16:45:56.216] <TB1> INFO: Test took 15127ms.
[16:45:58.488] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[16:45:58.774] <TB1> INFO: dacScan step from 93 .. 93
[16:45:58.774] <TB1> INFO: dacScan split into 1 runs with ntrig = 50
[16:45:58.774] <TB1> INFO: run 1 of 1
[16:46:13.959] <TB1> INFO: Test took 15184ms.
[16:46:16.222] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[16:46:16.508] <TB1> INFO: dacScan step from 94 .. 94
[16:46:16.508] <TB1> INFO: dacScan split into 1 runs with ntrig = 50
[16:46:16.508] <TB1> INFO: run 1 of 1
[16:46:31.649] <TB1> INFO: Test took 15141ms.
[16:46:33.936] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[16:46:34.216] <TB1> INFO: dacScan step from 95 .. 95
[16:46:34.216] <TB1> INFO: dacScan split into 1 runs with ntrig = 50
[16:46:34.216] <TB1> INFO: run 1 of 1
[16:46:49.248] <TB1> INFO: Test took 15031ms.
[16:46:51.522] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[16:46:51.806] <TB1> INFO: dacScan step from 96 .. 96
[16:46:51.806] <TB1> INFO: dacScan split into 1 runs with ntrig = 50
[16:46:51.806] <TB1> INFO: run 1 of 1
[16:47:06.944] <TB1> INFO: Test took 15138ms.
[16:47:09.158] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[16:47:09.437] <TB1> INFO: dacScan step from 97 .. 97
[16:47:09.437] <TB1> INFO: dacScan split into 1 runs with ntrig = 50
[16:47:09.437] <TB1> INFO: run 1 of 1
[16:47:24.635] <TB1> INFO: Test took 15197ms.
[16:47:26.945] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[16:47:27.260] <TB1> INFO: dacScan step from 98 .. 98
[16:47:27.260] <TB1> INFO: dacScan split into 1 runs with ntrig = 50
[16:47:27.260] <TB1> INFO: run 1 of 1
[16:47:42.325] <TB1> INFO: Test took 15064ms.
[16:47:44.928] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[16:47:45.227] <TB1> INFO: dacScan step from 99 .. 99
[16:47:45.227] <TB1> INFO: dacScan split into 1 runs with ntrig = 50
[16:47:45.227] <TB1> INFO: run 1 of 1
[16:48:00.300] <TB1> INFO: Test took 15072ms.
[16:48:02.663] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[16:48:02.965] <TB1> INFO: dacScan step from 100 .. 100
[16:48:02.965] <TB1> INFO: dacScan split into 1 runs with ntrig = 50
[16:48:02.965] <TB1> INFO: run 1 of 1
[16:48:18.040] <TB1> INFO: Test took 15074ms.
[16:48:20.595] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[16:48:20.897] <TB1> INFO: dumping ASCII scurve output file: XSCurveData
[16:48:22.493] <TB1> INFO: dumping ASCII scurve output file: XSCurveData
[16:48:23.908] <TB1> INFO: dumping ASCII scurve output file: XSCurveData
[16:48:25.254] <TB1> INFO: dumping ASCII scurve output file: XSCurveData
[16:48:26.621] <TB1> INFO: dumping ASCII scurve output file: XSCurveData
[16:48:28.000] <TB1> INFO: dumping ASCII scurve output file: XSCurveData
[16:48:29.437] <TB1> INFO: dumping ASCII scurve output file: XSCurveData
[16:48:30.968] <TB1> INFO: dumping ASCII scurve output file: XSCurveData
[16:48:32.544] <TB1> INFO: dumping ASCII scurve output file: XSCurveData
[16:48:34.037] <TB1> INFO: dumping ASCII scurve output file: XSCurveData
[16:48:35.518] <TB1> INFO: dumping ASCII scurve output file: XSCurveData
[16:48:37.007] <TB1> INFO: dumping ASCII scurve output file: XSCurveData
[16:48:38.421] <TB1> INFO: dumping ASCII scurve output file: XSCurveData
[16:48:39.835] <TB1> INFO: dumping ASCII scurve output file: XSCurveData
[16:48:41.142] <TB1> INFO: dumping ASCII scurve output file: XSCurveData
[16:48:42.473] <TB1> INFO: dumping ASCII scurve output file: XSCurveData
[16:48:43.760] <TB1> INFO: PixTest:: pg_setup set to default.
[16:48:43.762] <TB1> INFO: vcal mean: 39.28 39.44 39.48 42.47 42.46 44.50 43.78 44.99 44.93 44.28 44.03 42.51 42.41 39.78 40.17 39.59
[16:48:43.762] <TB1> INFO: vcal RMS: 1.39 1.35 1.12 1.78 1.43 1.56 1.46 1.73 1.67 1.52 1.57 1.86 1.51 1.39 1.27 1.27
[16:48:43.762] <TB1> INFO: number of X-ray hits detected: 202574 211258 210374 355183 389049 477035 433164 492145 490781 452400 445326 359401 354924 200157 212983 205056
[16:48:43.762] <TB1> INFO: number of triggers sent (total per ROC): 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000
[16:48:43.762] <TB1> INFO: X-ray hit rate [MHz/cm2]: 59.4 61.9 61.7 104.1 114.0 139.8 127.0 144.3 143.9 132.6 130.5 105.3 104.0 58.7 62.4 60.1
[16:48:43.762] <TB1> INFO: PixTestHighRate::doXNoiseMaps() done
[16:48:43.860] <TB1> INFO: enter test to run
[16:48:43.860] <TB1> INFO: test: no parameter change
[16:48:44.053] <TB1> QUIET: Connection to board 83 closed.
[16:48:44.134] <TB1> INFO: pXar: this is the end, my friend

[17:47:16.648] <TB1> INFO: *** Welcome to pxar ***
[17:47:16.648] <TB1> INFO: *** Today: 2015/08/24
[17:47:16.648] <TB1> INFO: readRocDacs: /usr/local/data/M2081_XrayQualification2_2015-08-24_19h24m_1440437058//006_CalDelScanAndSaveDacs_4mA25kV//dacParameters35_C0.dat .. /usr/local/data/M2081_XrayQualification2_2015-08-24_19h24m_1440437058//006_CalDelScanAndSaveDacs_4mA25kV//dacParameters35_C15.dat
[17:47:16.649] <TB1> INFO: readTbmDacs: /usr/local/data/M2081_XrayQualification2_2015-08-24_19h24m_1440437058//006_CalDelScanAndSaveDacs_4mA25kV//tbmParameters_C0a.dat .. /usr/local/data/M2081_XrayQualification2_2015-08-24_19h24m_1440437058//006_CalDelScanAndSaveDacs_4mA25kV//tbmParameters_C0b.dat
[17:47:16.649] <TB1> INFO: readMaskFile: /usr/local/data/M2081_XrayQualification2_2015-08-24_19h24m_1440437058//006_CalDelScanAndSaveDacs_4mA25kV//defaultMaskFile.dat
[17:47:16.649] <TB1> INFO: readTrimFile: /usr/local/data/M2081_XrayQualification2_2015-08-24_19h24m_1440437058//006_CalDelScanAndSaveDacs_4mA25kV//trimParameters35_C0.dat .. /usr/local/data/M2081_XrayQualification2_2015-08-24_19h24m_1440437058//006_CalDelScanAndSaveDacs_4mA25kV//trimParameters35_C15.dat
[17:47:16.721] <TB1> INFO: clk: 4
[17:47:16.721] <TB1> INFO: ctr: 4
[17:47:16.721] <TB1> INFO: sda: 19
[17:47:16.721] <TB1> INFO: tin: 9
[17:47:16.721] <TB1> INFO: level: 15
[17:47:16.721] <TB1> INFO: triggerdelay: 0
[17:47:16.721] <TB1> QUIET: Instanciating API for pxar prod-01+86~g1838649
[17:47:16.721] <TB1> INFO: Log level: INFO
[17:47:16.735] <TB1> INFO: Found DTB DTB_WS6UZO
[17:47:16.743] <TB1> QUIET: Connection to board DTB_WS6UZO opened.
[17:47:16.746] <TB1> INFO: DTB startup information
--- DTB info------------------------------------------
Board id: 83
HW version: DTB1.2
FW version: 4.0
SW version: 4.0
USB id: DTB_WS6UZO
MAC address: 40D855118053
Hostname: pixelDTB083
Comment:
------------------------------------------------------
[17:47:16.749] <TB1> INFO: RPC call hashes of host and DTB match: 447413373
[17:47:18.280] <TB1> INFO: DUT info:
[17:47:18.280] <TB1> INFO: The DUT currently contains the following objects:
[17:47:18.280] <TB1> INFO: 2 TBM Cores tbm09c (2 ON)
[17:47:18.281] <TB1> INFO: TBM Core alpha (0): 7 registers set
[17:47:18.281] <TB1> INFO: TBM Core beta (1): 7 registers set
[17:47:18.281] <TB1> INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[17:47:18.281] <TB1> INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[17:47:18.281] <TB1> INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[17:47:18.281] <TB1> INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[17:47:18.281] <TB1> INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[17:47:18.281] <TB1> INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[17:47:18.281] <TB1> INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[17:47:18.281] <TB1> INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[17:47:18.281] <TB1> INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[17:47:18.281] <TB1> INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[17:47:18.281] <TB1> INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[17:47:18.281] <TB1> INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[17:47:18.281] <TB1> INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[17:47:18.281] <TB1> INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[17:47:18.281] <TB1> INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[17:47:18.281] <TB1> INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[17:47:18.281] <TB1> INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[17:47:18.683] <TB1> INFO: enter 'restricted' command line mode
[17:47:18.683] <TB1> INFO: enter test to run
[17:47:18.683] <TB1> INFO: test: HighRate no parameter change
[17:47:18.683] <TB1> INFO: running: highrate
[17:47:18.684] <TB1> INFO: readGainPedestalParameters /usr/local/data/M2081_XrayQualification2_2015-08-24_19h24m_1440437058//006_CalDelScanAndSaveDacs_4mA25kV//phCalibrationFitErr35_C0.dat .. /usr/local/data/M2081_XrayQualification2_2015-08-24_19h24m_1440437058//006_CalDelScanAndSaveDacs_4mA25kV//phCalibrationFitErr35_C15.dat
[17:47:18.904] <TB1> INFO: ----------------------------------------------------------------------
[17:47:18.904] <TB1> INFO: PixTestHighRate::calDelScan() ntrig = 10, vcal = 200
[17:47:18.904] <TB1> INFO: ----------------------------------------------------------------------
[17:47:19.987] <TB1> INFO: Test took 1077ms.
[17:47:24.096] <TB1> INFO: Test took 4103ms.
[17:47:24.261] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[17:47:28.440] <TB1> INFO: Test took 4149ms.
[17:47:28.617] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[17:47:32.760] <TB1> INFO: Test took 4102ms.
[17:47:32.935] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[17:47:37.156] <TB1> INFO: Test took 4189ms.
[17:47:37.323] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[17:47:41.620] <TB1> INFO: Test took 4263ms.
[17:47:41.810] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[17:47:46.154] <TB1> INFO: Test took 4308ms.
[17:47:46.341] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[17:47:50.704] <TB1> INFO: Test took 4327ms.
[17:47:50.888] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[17:47:55.516] <TB1> INFO: Test took 4594ms.
[17:47:55.762] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[17:48:00.171] <TB1> INFO: Test took 4378ms.
[17:48:00.344] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[17:48:04.650] <TB1> INFO: Test took 4274ms.
[17:48:04.817] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[17:48:09.139] <TB1> INFO: Test took 4288ms.
[17:48:09.337] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[17:48:13.640] <TB1> INFO: Test took 4264ms.
[17:48:13.821] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[17:48:18.023] <TB1> INFO: Test took 4170ms.
[17:48:18.203] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[17:48:22.161] <TB1> INFO: Test took 3925ms.
[17:48:22.346] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[17:48:26.466] <TB1> INFO: Test took 4083ms.
[17:48:26.637] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[17:48:30.583] <TB1> INFO: Test took 3915ms.
[17:48:30.757] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[17:48:34.432] <TB1> INFO: Test took 3644ms.
[17:48:34.605] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[17:48:38.122] <TB1> INFO: Test took 3486ms.
[17:48:38.301] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[17:48:41.861] <TB1> INFO: Test took 3526ms.
[17:48:42.068] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[17:48:45.647] <TB1> INFO: Test took 3534ms.
[17:48:45.810] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[17:48:46.124] <TB1> INFO: enter test to run
[17:48:46.124] <TB1> INFO: test: Pretest no parameter change
[17:48:46.124] <TB1> INFO: running: pretest
[17:48:46.124] <TB1> INFO: write dac parameters into /usr/local/data/M2081_XrayQualification2_2015-08-24_19h24m_1440437058//006_CalDelScanAndSaveDacs_4mA25kV//dacParameters35_C0.dat
[17:48:46.124] <TB1> INFO: write dac parameters into /usr/local/data/M2081_XrayQualification2_2015-08-24_19h24m_1440437058//006_CalDelScanAndSaveDacs_4mA25kV//dacParameters35_C1.dat
[17:48:46.124] <TB1> INFO: write dac parameters into /usr/local/data/M2081_XrayQualification2_2015-08-24_19h24m_1440437058//006_CalDelScanAndSaveDacs_4mA25kV//dacParameters35_C2.dat
[17:48:46.124] <TB1> INFO: write dac parameters into /usr/local/data/M2081_XrayQualification2_2015-08-24_19h24m_1440437058//006_CalDelScanAndSaveDacs_4mA25kV//dacParameters35_C3.dat
[17:48:46.124] <TB1> INFO: write dac parameters into /usr/local/data/M2081_XrayQualification2_2015-08-24_19h24m_1440437058//006_CalDelScanAndSaveDacs_4mA25kV//dacParameters35_C4.dat
[17:48:46.124] <TB1> INFO: write dac parameters into /usr/local/data/M2081_XrayQualification2_2015-08-24_19h24m_1440437058//006_CalDelScanAndSaveDacs_4mA25kV//dacParameters35_C5.dat
[17:48:46.125] <TB1> INFO: write dac parameters into /usr/local/data/M2081_XrayQualification2_2015-08-24_19h24m_1440437058//006_CalDelScanAndSaveDacs_4mA25kV//dacParameters35_C6.dat
[17:48:46.125] <TB1> INFO: write dac parameters into /usr/local/data/M2081_XrayQualification2_2015-08-24_19h24m_1440437058//006_CalDelScanAndSaveDacs_4mA25kV//dacParameters35_C7.dat
[17:48:46.125] <TB1> INFO: write dac parameters into /usr/local/data/M2081_XrayQualification2_2015-08-24_19h24m_1440437058//006_CalDelScanAndSaveDacs_4mA25kV//dacParameters35_C8.dat
[17:48:46.125] <TB1> INFO: write dac parameters into /usr/local/data/M2081_XrayQualification2_2015-08-24_19h24m_1440437058//006_CalDelScanAndSaveDacs_4mA25kV//dacParameters35_C9.dat
[17:48:46.125] <TB1> INFO: write dac parameters into /usr/local/data/M2081_XrayQualification2_2015-08-24_19h24m_1440437058//006_CalDelScanAndSaveDacs_4mA25kV//dacParameters35_C10.dat
[17:48:46.125] <TB1> INFO: write dac parameters into /usr/local/data/M2081_XrayQualification2_2015-08-24_19h24m_1440437058//006_CalDelScanAndSaveDacs_4mA25kV//dacParameters35_C11.dat
[17:48:46.125] <TB1> INFO: write dac parameters into /usr/local/data/M2081_XrayQualification2_2015-08-24_19h24m_1440437058//006_CalDelScanAndSaveDacs_4mA25kV//dacParameters35_C12.dat
[17:48:46.125] <TB1> INFO: write dac parameters into /usr/local/data/M2081_XrayQualification2_2015-08-24_19h24m_1440437058//006_CalDelScanAndSaveDacs_4mA25kV//dacParameters35_C13.dat
[17:48:46.125] <TB1> INFO: write dac parameters into /usr/local/data/M2081_XrayQualification2_2015-08-24_19h24m_1440437058//006_CalDelScanAndSaveDacs_4mA25kV//dacParameters35_C14.dat
[17:48:46.125] <TB1> INFO: write dac parameters into /usr/local/data/M2081_XrayQualification2_2015-08-24_19h24m_1440437058//006_CalDelScanAndSaveDacs_4mA25kV//dacParameters35_C15.dat
[17:48:46.125] <TB1> INFO: enter test to run
[17:48:46.125] <TB1> INFO: test: no parameter change
[17:48:46.299] <TB1> QUIET: Connection to board 83 closed.
[17:48:46.379] <TB1> INFO: pXar: this is the end, my friend

[17:49:16.279] <TB1> INFO: *** Welcome to pxar ***
[17:49:16.279] <TB1> INFO: *** Today: 2015/08/24
[17:49:16.279] <TB1> INFO: readRocDacs: /usr/local/data/M2081_XrayQualification2_2015-08-24_19h24m_1440437058//007_HREfficiency_50//dacParameters35_C0.dat .. /usr/local/data/M2081_XrayQualification2_2015-08-24_19h24m_1440437058//007_HREfficiency_50//dacParameters35_C15.dat
[17:49:16.280] <TB1> INFO: readTbmDacs: /usr/local/data/M2081_XrayQualification2_2015-08-24_19h24m_1440437058//007_HREfficiency_50//tbmParameters_C0a.dat .. /usr/local/data/M2081_XrayQualification2_2015-08-24_19h24m_1440437058//007_HREfficiency_50//tbmParameters_C0b.dat
[17:49:16.280] <TB1> INFO: readMaskFile: /usr/local/data/M2081_XrayQualification2_2015-08-24_19h24m_1440437058//007_HREfficiency_50//defaultMaskFile.dat
[17:49:16.280] <TB1> INFO: readTrimFile: /usr/local/data/M2081_XrayQualification2_2015-08-24_19h24m_1440437058//007_HREfficiency_50//trimParameters35_C0.dat .. /usr/local/data/M2081_XrayQualification2_2015-08-24_19h24m_1440437058//007_HREfficiency_50//trimParameters35_C15.dat
[17:49:16.349] <TB1> INFO: clk: 4
[17:49:16.349] <TB1> INFO: ctr: 4
[17:49:16.349] <TB1> INFO: sda: 19
[17:49:16.349] <TB1> INFO: tin: 9
[17:49:16.349] <TB1> INFO: level: 15
[17:49:16.349] <TB1> INFO: triggerdelay: 0
[17:49:16.349] <TB1> QUIET: Instanciating API for pxar prod-01+86~g1838649
[17:49:16.349] <TB1> INFO: Log level: INFO
[17:49:16.362] <TB1> INFO: Found DTB DTB_WS6UZO
[17:49:16.371] <TB1> QUIET: Connection to board DTB_WS6UZO opened.
[17:49:16.374] <TB1> INFO: DTB startup information
--- DTB info------------------------------------------
Board id: 83
HW version: DTB1.2
FW version: 4.0
SW version: 4.0
USB id: DTB_WS6UZO
MAC address: 40D855118053
Hostname: pixelDTB083
Comment:
------------------------------------------------------
[17:49:16.377] <TB1> INFO: RPC call hashes of host and DTB match: 447413373
[17:49:17.919] <TB1> INFO: DUT info:
[17:49:17.919] <TB1> INFO: The DUT currently contains the following objects:
[17:49:17.919] <TB1> INFO: 2 TBM Cores tbm09c (2 ON)
[17:49:17.919] <TB1> INFO: TBM Core alpha (0): 7 registers set
[17:49:17.919] <TB1> INFO: TBM Core beta (1): 7 registers set
[17:49:17.919] <TB1> INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[17:49:17.919] <TB1> INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[17:49:17.919] <TB1> INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[17:49:17.919] <TB1> INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[17:49:17.919] <TB1> INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[17:49:17.919] <TB1> INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[17:49:17.919] <TB1> INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[17:49:17.919] <TB1> INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[17:49:17.919] <TB1> INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[17:49:17.919] <TB1> INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[17:49:17.919] <TB1> INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[17:49:17.920] <TB1> INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[17:49:17.920] <TB1> INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[17:49:17.920] <TB1> INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[17:49:17.920] <TB1> INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[17:49:17.920] <TB1> INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[17:49:17.920] <TB1> INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[17:49:18.321] <TB1> INFO: enter 'restricted' command line mode
[17:49:18.321] <TB1> INFO: enter test to run
[17:49:18.321] <TB1> INFO: test: HighRate no parameter change
[17:49:18.321] <TB1> INFO: running: highrate
[17:49:18.322] <TB1> INFO: readGainPedestalParameters /usr/local/data/M2081_XrayQualification2_2015-08-24_19h24m_1440437058//007_HREfficiency_50//phCalibrationFitErr35_C0.dat .. /usr/local/data/M2081_XrayQualification2_2015-08-24_19h24m_1440437058//007_HREfficiency_50//phCalibrationFitErr35_C15.dat
[17:49:18.600] <TB1> INFO: ----------------------------------------------------------------------
[17:49:18.600] <TB1> INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[17:49:18.600] <TB1> INFO: ----------------------------------------------------------------------
[17:49:30.034] <TB1> INFO: Test took 11426ms.
[17:49:31.008] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[17:49:31.335] <TB1> INFO: number of dead pixels (per ROC): 1 1 0 2 0 0 0 1 0 0 0 2 0 0 0 0
[17:49:31.335] <TB1> INFO: number of red-efficiency pixels: 119 119 189 288 355 507 428 473 471 453 403 309 304 168 164 181
[17:49:31.336] <TB1> INFO: number of X-ray hits detected: 101706 103604 104184 179078 195998 244905 221820 252082 249518 232407 228515 185350 178293 98437 105322 101396
[17:49:31.336] <TB1> INFO: number of triggers sent (total per ROC): 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000
[17:49:31.336] <TB1> INFO: number of Vcal hits detected: 207828 207830 207800 207597 207620 207448 207539 207436 207491 207506 207576 207566 207686 207823 207832 207812
[17:49:31.336] <TB1> INFO: Vcal hit fiducial efficiency (%): 99.9 99.9 99.9 99.9 99.8 99.8 99.8 99.8 99.8 99.8 99.8 99.9 99.9 99.9 99.9 99.9
[17:49:31.336] <TB1> INFO: Vcal hit overall efficiency (%): 99.9 99.9 99.9 99.8 99.8 99.7 99.8 99.7 99.8 99.8 99.8 99.8 99.8 99.9 99.9 99.9
[17:49:31.336] <TB1> INFO: X-ray hit rate [MHz/cm2]: 29.8 30.4 30.5 52.5 57.4 71.8 65.0 73.9 73.1 68.1 67.0 54.3 52.3 28.9 30.9 29.7
[17:49:31.336] <TB1> INFO: PixTestHighRate::doXPixelAlive() done
[17:49:31.367] <TB1> INFO: PixTest:: pg_setup set to default.
[17:49:31.403] <TB1> INFO: enter test to run
[17:49:31.403] <TB1> INFO: test: no parameter change
[17:49:31.578] <TB1> QUIET: Connection to board 83 closed.
[17:49:31.658] <TB1> INFO: pXar: this is the end, my friend

[17:50:07.347] <TB1> INFO: *** Welcome to pxar ***
[17:50:07.347] <TB1> INFO: *** Today: 2015/08/24
[17:50:07.347] <TB1> INFO: readRocDacs: /usr/local/data/M2081_XrayQualification2_2015-08-24_19h24m_1440437058//008_HREfficiency_100//dacParameters35_C0.dat .. /usr/local/data/M2081_XrayQualification2_2015-08-24_19h24m_1440437058//008_HREfficiency_100//dacParameters35_C15.dat
[17:50:07.347] <TB1> INFO: readTbmDacs: /usr/local/data/M2081_XrayQualification2_2015-08-24_19h24m_1440437058//008_HREfficiency_100//tbmParameters_C0a.dat .. /usr/local/data/M2081_XrayQualification2_2015-08-24_19h24m_1440437058//008_HREfficiency_100//tbmParameters_C0b.dat
[17:50:07.347] <TB1> INFO: readMaskFile: /usr/local/data/M2081_XrayQualification2_2015-08-24_19h24m_1440437058//008_HREfficiency_100//defaultMaskFile.dat
[17:50:07.348] <TB1> INFO: readTrimFile: /usr/local/data/M2081_XrayQualification2_2015-08-24_19h24m_1440437058//008_HREfficiency_100//trimParameters35_C0.dat .. /usr/local/data/M2081_XrayQualification2_2015-08-24_19h24m_1440437058//008_HREfficiency_100//trimParameters35_C15.dat
[17:50:07.421] <TB1> INFO: clk: 4
[17:50:07.421] <TB1> INFO: ctr: 4
[17:50:07.421] <TB1> INFO: sda: 19
[17:50:07.421] <TB1> INFO: tin: 9
[17:50:07.421] <TB1> INFO: level: 15
[17:50:07.421] <TB1> INFO: triggerdelay: 0
[17:50:07.421] <TB1> QUIET: Instanciating API for pxar prod-01+86~g1838649
[17:50:07.421] <TB1> INFO: Log level: INFO
[17:50:07.434] <TB1> INFO: Found DTB DTB_WS6UZO
[17:50:07.443] <TB1> QUIET: Connection to board DTB_WS6UZO opened.
[17:50:07.446] <TB1> INFO: DTB startup information
--- DTB info------------------------------------------
Board id: 83
HW version: DTB1.2
FW version: 4.0
SW version: 4.0
USB id: DTB_WS6UZO
MAC address: 40D855118053
Hostname: pixelDTB083
Comment:
------------------------------------------------------
[17:50:07.448] <TB1> INFO: RPC call hashes of host and DTB match: 447413373
[17:50:08.986] <TB1> INFO: DUT info:
[17:50:08.986] <TB1> INFO: The DUT currently contains the following objects:
[17:50:08.986] <TB1> INFO: 2 TBM Cores tbm09c (2 ON)
[17:50:08.986] <TB1> INFO: TBM Core alpha (0): 7 registers set
[17:50:08.986] <TB1> INFO: TBM Core beta (1): 7 registers set
[17:50:08.986] <TB1> INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[17:50:08.986] <TB1> INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[17:50:08.986] <TB1> INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[17:50:08.986] <TB1> INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[17:50:08.986] <TB1> INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[17:50:08.986] <TB1> INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[17:50:08.986] <TB1> INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[17:50:08.986] <TB1> INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[17:50:08.986] <TB1> INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[17:50:08.986] <TB1> INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[17:50:08.986] <TB1> INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[17:50:08.986] <TB1> INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[17:50:08.986] <TB1> INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[17:50:08.986] <TB1> INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[17:50:08.986] <TB1> INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[17:50:08.986] <TB1> INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[17:50:08.986] <TB1> INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[17:50:09.388] <TB1> INFO: enter 'restricted' command line mode
[17:50:09.388] <TB1> INFO: enter test to run
[17:50:09.388] <TB1> INFO: test: HighRate no parameter change
[17:50:09.388] <TB1> INFO: running: highrate
[17:50:09.389] <TB1> INFO: readGainPedestalParameters /usr/local/data/M2081_XrayQualification2_2015-08-24_19h24m_1440437058//008_HREfficiency_100//phCalibrationFitErr35_C0.dat .. /usr/local/data/M2081_XrayQualification2_2015-08-24_19h24m_1440437058//008_HREfficiency_100//phCalibrationFitErr35_C15.dat
[17:50:09.568] <TB1> INFO: ----------------------------------------------------------------------
[17:50:09.568] <TB1> INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[17:50:09.568] <TB1> INFO: ----------------------------------------------------------------------
[17:50:24.603] <TB1> INFO: Test took 15027ms.
[17:50:27.449] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[17:50:27.945] <TB1> INFO: number of dead pixels (per ROC): 1 1 0 2 0 0 0 1 0 0 0 2 0 0 0 0
[17:50:27.946] <TB1> INFO: number of red-efficiency pixels: 331 379 658 965 1160 1846 1529 1979 1974 1701 1526 1079 988 602 393 460
[17:50:27.946] <TB1> INFO: number of X-ray hits detected: 204249 211744 212614 355898 387561 478494 438658 499295 493292 456339 448068 363956 356608 202409 216006 205654
[17:50:27.946] <TB1> INFO: number of triggers sent (total per ROC): 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000
[17:50:27.946] <TB1> INFO: number of Vcal hits detected: 207598 207549 207076 206702 206422 205111 205661 205000 205038 205376 205784 206492 206797 207166 207579 207506
[17:50:27.946] <TB1> INFO: Vcal hit fiducial efficiency (%): 99.8 99.8 99.6 99.5 99.3 98.8 99.0 98.7 98.7 98.9 99.1 99.4 99.5 99.7 99.8 99.8
[17:50:27.946] <TB1> INFO: Vcal hit overall efficiency (%): 99.8 99.8 99.6 99.4 99.2 98.6 98.9 98.6 98.6 98.7 98.9 99.3 99.4 99.6 99.8 99.8
[17:50:27.946] <TB1> INFO: X-ray hit rate [MHz/cm2]: 59.9 62.1 62.3 104.3 113.6 140.3 128.6 146.3 144.6 133.8 131.3 106.7 104.5 59.3 63.3 60.3
[17:50:27.946] <TB1> INFO: PixTestHighRate::doXPixelAlive() done
[17:50:27.974] <TB1> INFO: PixTest:: pg_setup set to default.
[17:50:28.014] <TB1> INFO: enter test to run
[17:50:28.014] <TB1> INFO: test: no parameter change
[17:50:28.206] <TB1> QUIET: Connection to board 83 closed.
[17:50:28.285] <TB1> INFO: pXar: this is the end, my friend

[17:51:00.968] <TB1> INFO: *** Welcome to pxar ***
[17:51:00.968] <TB1> INFO: *** Today: 2015/08/24
[17:51:00.968] <TB1> INFO: readRocDacs: /usr/local/data/M2081_XrayQualification2_2015-08-24_19h24m_1440437058//009_HREfficiency_150//dacParameters35_C0.dat .. /usr/local/data/M2081_XrayQualification2_2015-08-24_19h24m_1440437058//009_HREfficiency_150//dacParameters35_C15.dat
[17:51:00.969] <TB1> INFO: readTbmDacs: /usr/local/data/M2081_XrayQualification2_2015-08-24_19h24m_1440437058//009_HREfficiency_150//tbmParameters_C0a.dat .. /usr/local/data/M2081_XrayQualification2_2015-08-24_19h24m_1440437058//009_HREfficiency_150//tbmParameters_C0b.dat
[17:51:00.969] <TB1> INFO: readMaskFile: /usr/local/data/M2081_XrayQualification2_2015-08-24_19h24m_1440437058//009_HREfficiency_150//defaultMaskFile.dat
[17:51:00.969] <TB1> INFO: readTrimFile: /usr/local/data/M2081_XrayQualification2_2015-08-24_19h24m_1440437058//009_HREfficiency_150//trimParameters35_C0.dat .. /usr/local/data/M2081_XrayQualification2_2015-08-24_19h24m_1440437058//009_HREfficiency_150//trimParameters35_C15.dat
[17:51:01.044] <TB1> INFO: clk: 4
[17:51:01.044] <TB1> INFO: ctr: 4
[17:51:01.044] <TB1> INFO: sda: 19
[17:51:01.044] <TB1> INFO: tin: 9
[17:51:01.044] <TB1> INFO: level: 15
[17:51:01.044] <TB1> INFO: triggerdelay: 0
[17:51:01.044] <TB1> QUIET: Instanciating API for pxar prod-01+86~g1838649
[17:51:01.044] <TB1> INFO: Log level: INFO
[17:51:01.057] <TB1> INFO: Found DTB DTB_WS6UZO
[17:51:01.066] <TB1> QUIET: Connection to board DTB_WS6UZO opened.
[17:51:01.069] <TB1> INFO: DTB startup information
--- DTB info------------------------------------------
Board id: 83
HW version: DTB1.2
FW version: 4.0
SW version: 4.0
USB id: DTB_WS6UZO
MAC address: 40D855118053
Hostname: pixelDTB083
Comment:
------------------------------------------------------
[17:51:01.072] <TB1> INFO: RPC call hashes of host and DTB match: 447413373
[17:51:02.598] <TB1> INFO: DUT info:
[17:51:02.598] <TB1> INFO: The DUT currently contains the following objects:
[17:51:02.598] <TB1> INFO: 2 TBM Cores tbm09c (2 ON)
[17:51:02.598] <TB1> INFO: TBM Core alpha (0): 7 registers set
[17:51:02.598] <TB1> INFO: TBM Core beta (1): 7 registers set
[17:51:02.598] <TB1> INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[17:51:02.599] <TB1> INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[17:51:02.599] <TB1> INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[17:51:02.599] <TB1> INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[17:51:02.599] <TB1> INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[17:51:02.599] <TB1> INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[17:51:02.599] <TB1> INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[17:51:02.599] <TB1> INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[17:51:02.599] <TB1> INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[17:51:02.599] <TB1> INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[17:51:02.599] <TB1> INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[17:51:02.599] <TB1> INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[17:51:02.599] <TB1> INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[17:51:02.599] <TB1> INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[17:51:02.599] <TB1> INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[17:51:02.599] <TB1> INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[17:51:02.599] <TB1> INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[17:51:03.000] <TB1> INFO: enter 'restricted' command line mode
[17:51:03.000] <TB1> INFO: enter test to run
[17:51:03.000] <TB1> INFO: test: HighRate no parameter change
[17:51:03.000] <TB1> INFO: running: highrate
[17:51:03.001] <TB1> INFO: readGainPedestalParameters /usr/local/data/M2081_XrayQualification2_2015-08-24_19h24m_1440437058//009_HREfficiency_150//phCalibrationFitErr35_C0.dat .. /usr/local/data/M2081_XrayQualification2_2015-08-24_19h24m_1440437058//009_HREfficiency_150//phCalibrationFitErr35_C15.dat
[17:51:03.148] <TB1> INFO: ----------------------------------------------------------------------
[17:51:03.148] <TB1> INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[17:51:03.148] <TB1> INFO: ----------------------------------------------------------------------
[17:51:21.997] <TB1> INFO: Test took 18842ms.
[17:51:27.748] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[17:51:28.293] <TB1> INFO: number of dead pixels (per ROC): 1 1 0 2 0 0 0 1 0 0 0 2 0 0 0 0
[17:51:28.293] <TB1> INFO: number of red-efficiency pixels: 687 778 1175 1964 2289 3168 2743 3446 3371 3081 2943 2065 2030 1110 771 926
[17:51:28.293] <TB1> INFO: number of X-ray hits detected: 294288 307281 308745 508312 554442 677811 620867 710414 705138 652852 638127 520951 510770 293544 311819 296826
[17:51:28.293] <TB1> INFO: number of triggers sent (total per ROC): 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000
[17:51:28.293] <TB1> INFO: number of Vcal hits detected: 207190 207041 205722 204600 203516 200153 201581 199382 199451 200427 201473 204212 204722 205776 207143 206855
[17:51:28.293] <TB1> INFO: Vcal hit fiducial efficiency (%): 99.7 99.6 99.1 98.6 98.1 96.7 97.3 96.3 96.3 96.8 97.2 98.5 98.6 99.1 99.6 99.5
[17:51:28.293] <TB1> INFO: Vcal hit overall efficiency (%): 99.6 99.5 98.9 98.4 97.8 96.2 96.9 95.9 95.9 96.4 96.9 98.2 98.4 98.9 99.6 99.4
[17:51:28.293] <TB1> INFO: X-ray hit rate [MHz/cm2]: 86.3 90.1 90.5 149.0 162.5 198.7 182.0 208.2 206.7 191.4 187.0 152.7 149.7 86.0 91.4 87.0
[17:51:28.293] <TB1> INFO: PixTestHighRate::doXPixelAlive() done
[17:51:28.331] <TB1> INFO: PixTest:: pg_setup set to default.
[17:51:28.379] <TB1> INFO: enter test to run
[17:51:28.379] <TB1> INFO: test: no parameter change
[17:51:28.496] <TB1> QUIET: Connection to board 83 closed.
[17:51:28.576] <TB1> INFO: pXar: this is the end, my friend

[17:51:58.686] <TB1> INFO: *** Welcome to pxar ***
[17:51:58.686] <TB1> INFO: *** Today: 2015/08/24
[17:51:58.686] <TB1> INFO: readRocDacs: /usr/local/data/M2081_XrayQualification2_2015-08-24_19h24m_1440437058//010_HREfficiency_200//dacParameters35_C0.dat .. /usr/local/data/M2081_XrayQualification2_2015-08-24_19h24m_1440437058//010_HREfficiency_200//dacParameters35_C15.dat
[17:51:58.687] <TB1> INFO: readTbmDacs: /usr/local/data/M2081_XrayQualification2_2015-08-24_19h24m_1440437058//010_HREfficiency_200//tbmParameters_C0a.dat .. /usr/local/data/M2081_XrayQualification2_2015-08-24_19h24m_1440437058//010_HREfficiency_200//tbmParameters_C0b.dat
[17:51:58.687] <TB1> INFO: readMaskFile: /usr/local/data/M2081_XrayQualification2_2015-08-24_19h24m_1440437058//010_HREfficiency_200//defaultMaskFile.dat
[17:51:58.687] <TB1> INFO: readTrimFile: /usr/local/data/M2081_XrayQualification2_2015-08-24_19h24m_1440437058//010_HREfficiency_200//trimParameters35_C0.dat .. /usr/local/data/M2081_XrayQualification2_2015-08-24_19h24m_1440437058//010_HREfficiency_200//trimParameters35_C15.dat
[17:51:58.758] <TB1> INFO: clk: 4
[17:51:58.758] <TB1> INFO: ctr: 4
[17:51:58.758] <TB1> INFO: sda: 19
[17:51:58.758] <TB1> INFO: tin: 9
[17:51:58.758] <TB1> INFO: level: 15
[17:51:58.758] <TB1> INFO: triggerdelay: 0
[17:51:58.758] <TB1> QUIET: Instanciating API for pxar prod-01+86~g1838649
[17:51:58.758] <TB1> INFO: Log level: INFO
[17:51:58.772] <TB1> INFO: Found DTB DTB_WS6UZO
[17:51:58.781] <TB1> QUIET: Connection to board DTB_WS6UZO opened.
[17:51:58.784] <TB1> INFO: DTB startup information
--- DTB info------------------------------------------
Board id: 83
HW version: DTB1.2
FW version: 4.0
SW version: 4.0
USB id: DTB_WS6UZO
MAC address: 40D855118053
Hostname: pixelDTB083
Comment:
------------------------------------------------------
[17:51:58.786] <TB1> INFO: RPC call hashes of host and DTB match: 447413373
[17:52:00.317] <TB1> INFO: DUT info:
[17:52:00.317] <TB1> INFO: The DUT currently contains the following objects:
[17:52:00.317] <TB1> INFO: 2 TBM Cores tbm09c (2 ON)
[17:52:00.317] <TB1> INFO: TBM Core alpha (0): 7 registers set
[17:52:00.317] <TB1> INFO: TBM Core beta (1): 7 registers set
[17:52:00.317] <TB1> INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[17:52:00.317] <TB1> INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[17:52:00.317] <TB1> INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[17:52:00.317] <TB1> INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[17:52:00.317] <TB1> INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[17:52:00.317] <TB1> INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[17:52:00.317] <TB1> INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[17:52:00.317] <TB1> INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[17:52:00.317] <TB1> INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[17:52:00.317] <TB1> INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[17:52:00.318] <TB1> INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[17:52:00.318] <TB1> INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[17:52:00.318] <TB1> INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[17:52:00.318] <TB1> INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[17:52:00.318] <TB1> INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[17:52:00.318] <TB1> INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[17:52:00.318] <TB1> INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[17:52:00.719] <TB1> INFO: enter 'restricted' command line mode
[17:52:00.719] <TB1> INFO: enter test to run
[17:52:00.719] <TB1> INFO: test: HighRate no parameter change
[17:52:00.719] <TB1> INFO: running: highrate
[17:52:00.720] <TB1> INFO: readGainPedestalParameters /usr/local/data/M2081_XrayQualification2_2015-08-24_19h24m_1440437058//010_HREfficiency_200//phCalibrationFitErr35_C0.dat .. /usr/local/data/M2081_XrayQualification2_2015-08-24_19h24m_1440437058//010_HREfficiency_200//phCalibrationFitErr35_C15.dat
[17:52:00.868] <TB1> INFO: ----------------------------------------------------------------------
[17:52:00.868] <TB1> INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[17:52:00.868] <TB1> INFO: ----------------------------------------------------------------------
[17:52:23.185] <TB1> INFO: Test took 22310ms.
[17:52:32.307] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[17:52:32.896] <TB1> INFO: number of dead pixels (per ROC): 1 1 0 2 0 0 0 1 0 0 0 2 0 0 0 0
[17:52:32.896] <TB1> INFO: number of red-efficiency pixels: 1285 1416 1747 3189 3396 3974 3803 4095 4050 3904 3877 3237 3228 1667 1493 1713
[17:52:32.896] <TB1> INFO: number of X-ray hits detected: 399165 416962 418774 678278 742461 903563 830043 950960 946171 874986 852435 698854 685870 400386 422015 401910
[17:52:32.896] <TB1> INFO: number of triggers sent (total per ROC): 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000
[17:52:32.896] <TB1> INFO: number of Vcal hits detected: 206271 206030 203206 200053 197699 189340 192365 187559 187229 190505 192779 198976 199765 203150 206038 205498
[17:52:32.896] <TB1> INFO: Vcal hit fiducial efficiency (%): 99.3 99.1 98.1 96.6 95.7 92.0 93.4 90.9 90.8 92.6 93.5 96.3 96.5 98.1 99.1 98.9
[17:52:32.896] <TB1> INFO: Vcal hit overall efficiency (%): 99.2 99.1 97.7 96.2 95.0 91.0 92.5 90.2 90.0 91.6 92.7 95.7 96.0 97.7 99.1 98.8
[17:52:32.896] <TB1> INFO: X-ray hit rate [MHz/cm2]: 117.0 122.2 122.7 198.8 217.6 264.8 243.3 278.7 277.3 256.5 249.9 204.8 201.0 117.4 123.7 117.8
[17:52:32.896] <TB1> INFO: PixTestHighRate::doXPixelAlive() done
[17:52:32.923] <TB1> INFO: PixTest:: pg_setup set to default.
[17:52:32.966] <TB1> INFO: enter test to run
[17:52:32.966] <TB1> INFO: test: no parameter change
[17:52:33.148] <TB1> QUIET: Connection to board 83 closed.
[17:52:33.227] <TB1> INFO: pXar: this is the end, my friend

[17:53:02.554] <TB1> INFO: *** Welcome to pxar ***
[17:53:02.554] <TB1> INFO: *** Today: 2015/08/24
[17:53:02.554] <TB1> INFO: readRocDacs: /usr/local/data/M2081_XrayQualification2_2015-08-24_19h24m_1440437058//011_HREfficiency_250//dacParameters35_C0.dat .. /usr/local/data/M2081_XrayQualification2_2015-08-24_19h24m_1440437058//011_HREfficiency_250//dacParameters35_C15.dat
[17:53:02.555] <TB1> INFO: readTbmDacs: /usr/local/data/M2081_XrayQualification2_2015-08-24_19h24m_1440437058//011_HREfficiency_250//tbmParameters_C0a.dat .. /usr/local/data/M2081_XrayQualification2_2015-08-24_19h24m_1440437058//011_HREfficiency_250//tbmParameters_C0b.dat
[17:53:02.555] <TB1> INFO: readMaskFile: /usr/local/data/M2081_XrayQualification2_2015-08-24_19h24m_1440437058//011_HREfficiency_250//defaultMaskFile.dat
[17:53:02.555] <TB1> INFO: readTrimFile: /usr/local/data/M2081_XrayQualification2_2015-08-24_19h24m_1440437058//011_HREfficiency_250//trimParameters35_C0.dat .. /usr/local/data/M2081_XrayQualification2_2015-08-24_19h24m_1440437058//011_HREfficiency_250//trimParameters35_C15.dat
[17:53:02.649] <TB1> INFO: clk: 4
[17:53:02.649] <TB1> INFO: ctr: 4
[17:53:02.649] <TB1> INFO: sda: 19
[17:53:02.649] <TB1> INFO: tin: 9
[17:53:02.649] <TB1> INFO: level: 15
[17:53:02.649] <TB1> INFO: triggerdelay: 0
[17:53:02.649] <TB1> QUIET: Instanciating API for pxar prod-01+86~g1838649
[17:53:02.649] <TB1> INFO: Log level: INFO
[17:53:02.663] <TB1> INFO: Found DTB DTB_WS6UZO
[17:53:02.671] <TB1> QUIET: Connection to board DTB_WS6UZO opened.
[17:53:02.675] <TB1> INFO: DTB startup information
--- DTB info------------------------------------------
Board id: 83
HW version: DTB1.2
FW version: 4.0
SW version: 4.0
USB id: DTB_WS6UZO
MAC address: 40D855118053
Hostname: pixelDTB083
Comment:
------------------------------------------------------
[17:53:02.677] <TB1> INFO: RPC call hashes of host and DTB match: 447413373
[17:53:04.198] <TB1> INFO: DUT info:
[17:53:04.198] <TB1> INFO: The DUT currently contains the following objects:
[17:53:04.198] <TB1> INFO: 2 TBM Cores tbm09c (2 ON)
[17:53:04.198] <TB1> INFO: TBM Core alpha (0): 7 registers set
[17:53:04.198] <TB1> INFO: TBM Core beta (1): 7 registers set
[17:53:04.198] <TB1> INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[17:53:04.198] <TB1> INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[17:53:04.198] <TB1> INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[17:53:04.198] <TB1> INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[17:53:04.198] <TB1> INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[17:53:04.198] <TB1> INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[17:53:04.198] <TB1> INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[17:53:04.199] <TB1> INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[17:53:04.199] <TB1> INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[17:53:04.199] <TB1> INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[17:53:04.199] <TB1> INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[17:53:04.199] <TB1> INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[17:53:04.199] <TB1> INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[17:53:04.199] <TB1> INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[17:53:04.199] <TB1> INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[17:53:04.199] <TB1> INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[17:53:04.199] <TB1> INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[17:53:04.600] <TB1> INFO: enter 'restricted' command line mode
[17:53:04.600] <TB1> INFO: enter test to run
[17:53:04.600] <TB1> INFO: test: HighRate no parameter change
[17:53:04.600] <TB1> INFO: running: highrate
[17:53:04.601] <TB1> INFO: readGainPedestalParameters /usr/local/data/M2081_XrayQualification2_2015-08-24_19h24m_1440437058//011_HREfficiency_250//phCalibrationFitErr35_C0.dat .. /usr/local/data/M2081_XrayQualification2_2015-08-24_19h24m_1440437058//011_HREfficiency_250//phCalibrationFitErr35_C15.dat
[17:53:04.758] <TB1> INFO: ----------------------------------------------------------------------
[17:53:04.758] <TB1> INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[17:53:04.758] <TB1> INFO: ----------------------------------------------------------------------
[17:53:26.721] <TB1> INFO: Test took 21957ms.
[17:53:37.082] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[17:53:37.828] <TB1> INFO: number of dead pixels (per ROC): 1 1 0 2 0 0 0 1 0 0 0 2 0 0 0 0
[17:53:37.828] <TB1> INFO: number of red-efficiency pixels: 1300 1488 1850 3239 3522 4026 3845 4090 4092 3945 3932 3369 3328 1760 1553 1793
[17:53:37.828] <TB1> INFO: number of X-ray hits detected: 410142 432157 433758 697625 760582 926310 851483 976488 971954 895692 873073 719474 703942 415866 437595 414624
[17:53:37.828] <TB1> INFO: number of triggers sent (total per ROC): 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000
[17:53:37.828] <TB1> INFO: number of Vcal hits detected: 206208 205871 202921 199560 196903 188413 191639 186149 185682 189434 191715 198333 199508 202884 205939 205349
[17:53:37.828] <TB1> INFO: Vcal hit fiducial efficiency (%): 99.3 99.1 98.0 96.5 95.3 91.6 93.0 90.3 90.1 92.1 93.0 96.0 96.4 98.0 99.1 98.9
[17:53:37.828] <TB1> INFO: Vcal hit overall efficiency (%): 99.1 99.0 97.6 95.9 94.7 90.6 92.1 89.5 89.3 91.1 92.2 95.4 95.9 97.5 99.0 98.7
[17:53:37.828] <TB1> INFO: X-ray hit rate [MHz/cm2]: 120.2 126.7 127.1 204.5 222.9 271.5 249.6 286.2 284.9 262.5 255.9 210.9 206.3 121.9 128.3 121.5
[17:53:37.828] <TB1> INFO: PixTestHighRate::doXPixelAlive() done
[17:53:37.858] <TB1> INFO: PixTest:: pg_setup set to default.
[17:53:37.906] <TB1> INFO: enter test to run
[17:53:37.906] <TB1> INFO: test: no parameter change
[17:53:38.059] <TB1> QUIET: Connection to board 83 closed.
[17:53:38.138] <TB1> INFO: pXar: this is the end, my friend