Test Date: 2015-11-23 13:32
Analysis date: 2016-05-26 06:35
Logfile
commander_PixelAlive.log
[12:33:24.989] <TB1> INFO: *** Welcome to pxar ***
[12:33:24.989] <TB1> INFO: *** Today: 2015/11/23
[12:33:25.064] <TB1> INFO: *** Version: 206d-dirty
[12:33:25.064] <TB1> INFO: readRocDacs: /usr/local/data/M2120_XrayQualification_2015-11-23_13h32m_1448281936//000_PixelAlive_p17//dacParameters35_C0.dat .. /usr/local/data/M2120_XrayQualification_2015-11-23_13h32m_1448281936//000_PixelAlive_p17//dacParameters35_C15.dat
[12:33:25.065] <TB1> INFO: readTbmDacs: /usr/local/data/M2120_XrayQualification_2015-11-23_13h32m_1448281936//000_PixelAlive_p17//tbmParameters_C0a.dat .. /usr/local/data/M2120_XrayQualification_2015-11-23_13h32m_1448281936//000_PixelAlive_p17//tbmParameters_C0b.dat
[12:33:25.065] <TB1> INFO: readMaskFile: /usr/local/data/M2120_XrayQualification_2015-11-23_13h32m_1448281936//000_PixelAlive_p17//defaultMaskFile.dat
[12:33:25.065] <TB1> INFO: readTrimFile: /usr/local/data/M2120_XrayQualification_2015-11-23_13h32m_1448281936//000_PixelAlive_p17//trimParameters35_C0.dat .. /usr/local/data/M2120_XrayQualification_2015-11-23_13h32m_1448281936//000_PixelAlive_p17//trimParameters35_C15.dat
[12:33:25.136] <TB1> INFO: clk: 4
[12:33:25.136] <TB1> INFO: ctr: 4
[12:33:25.136] <TB1> INFO: sda: 19
[12:33:25.136] <TB1> INFO: tin: 9
[12:33:25.136] <TB1> INFO: level: 15
[12:33:25.136] <TB1> INFO: triggerdelay: 0
[12:33:25.136] <TB1> QUIET: Instanciating API for pxar v2.6.0+26~g89693ff
[12:33:25.136] <TB1> INFO: Log level: INFO
[12:33:25.150] <TB1> INFO: Found DTB DTB_WWXTQT
[12:33:25.165] <TB1> QUIET: Connection to board DTB_WWXTQT opened.
[12:33:25.168] <TB1> INFO: DTB startup information
--- DTB info------------------------------------------
Board id: 147
HW version: DTB1.2
FW version: 4.2
SW version: 4.5
USB id: DTB_WWXTQT
MAC address: 40D855118093
Hostname: pixelDTB147
Comment:
------------------------------------------------------
[12:33:25.171] <TB1> INFO: RPC call hashes of host and DTB match: 398089610
[12:33:26.718] <TB1> INFO: DUT info:
[12:33:26.718] <TB1> INFO: The DUT currently contains the following objects:
[12:33:26.718] <TB1> INFO: 2 TBM Cores tbm09c (2 ON)
[12:33:26.718] <TB1> INFO: TBM Core alpha (0): 7 registers set
[12:33:26.718] <TB1> INFO: TBM Core beta (1): 7 registers set
[12:33:26.718] <TB1> INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[12:33:26.718] <TB1> INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[12:33:26.718] <TB1> INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[12:33:26.718] <TB1> INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[12:33:26.718] <TB1> INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[12:33:26.718] <TB1> INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[12:33:26.718] <TB1> INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[12:33:26.718] <TB1> INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[12:33:26.718] <TB1> INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[12:33:26.718] <TB1> INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[12:33:26.718] <TB1> INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[12:33:26.718] <TB1> INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[12:33:26.718] <TB1> INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[12:33:26.718] <TB1> INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[12:33:26.719] <TB1> INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[12:33:26.719] <TB1> INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[12:33:26.719] <TB1> INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[12:33:27.120] <TB1> INFO: enter 'restricted' command line mode
[12:33:27.120] <TB1> INFO: enter test to run
[12:33:27.120] <TB1> INFO: test: PixelAlive no parameter change
[12:33:27.120] <TB1> INFO: running: pixelalive
[12:33:27.126] <TB1> INFO: ######################################################################
[12:33:27.126] <TB1> INFO: PixTestAlive::doTest()
[12:33:27.127] <TB1> INFO: ######################################################################
[12:33:27.128] <TB1> INFO: ----------------------------------------------------------------------
[12:33:27.128] <TB1> INFO: PixTestAlive::aliveTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[12:33:27.128] <TB1> INFO: ----------------------------------------------------------------------
[12:33:27.445] <TB1> INFO: Expecting 41600 events.
[12:33:32.024] <TB1> INFO: 41600 events read in total (3861ms).
[12:33:32.104] <TB1> INFO: Test took 4974ms.
[12:33:32.110] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[12:33:32.364] <TB1> INFO: PixTestAlive::aliveTest() done
[12:33:32.364] <TB1> INFO: number of dead pixels (per ROC): 4 1 0 0 0 1 0 0 0 1 0 1 1 0 2 1
[12:33:32.365] <TB1> INFO: ----------------------------------------------------------------------
[12:33:32.365] <TB1> INFO: PixTestAlive::maskTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[12:33:32.365] <TB1> INFO: ----------------------------------------------------------------------
[12:33:32.665] <TB1> INFO: Expecting 41600 events.
[12:33:35.875] <TB1> INFO: 41600 events read in total (2494ms).
[12:33:35.875] <TB1> INFO: Test took 3508ms.
[12:33:35.875] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[12:33:35.876] <TB1> INFO: mask vs. old pixelAlive PixelAlive_C0_V0 .. PixelAlive_C15_V0
[12:33:36.190] <TB1> INFO: PixTestAlive::maskTest() done
[12:33:36.190] <TB1> INFO: number of mask-defect pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[12:33:36.192] <TB1> INFO: ----------------------------------------------------------------------
[12:33:36.192] <TB1> INFO: PixTestAlive::addressDecodingTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[12:33:36.192] <TB1> INFO: ----------------------------------------------------------------------
[12:33:36.513] <TB1> INFO: Expecting 41600 events.
[12:33:41.002] <TB1> INFO: 41600 events read in total (3773ms).
[12:33:41.004] <TB1> INFO: Test took 4811ms.
[12:33:41.012] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[12:33:41.291] <TB1> INFO: PixTestAlive::addressDecodingTest() done
[12:33:41.292] <TB1> INFO: number of address-decoding pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[12:33:41.292] <TB1> INFO: PixTestAlive::doTest() done, duration: 14 seconds
[12:33:41.336] <TB1> INFO: enter test to run
[12:33:41.336] <TB1> INFO: test: no parameter change
[12:33:41.394] <TB1> QUIET: Connection to board 147 closed.
[12:33:41.474] <TB1> INFO: pXar: this is the end, my friend
commander_RetrimHotPixels.log
[12:39:06.325] <TB1> INFO: *** Welcome to pxar ***
[12:39:06.325] <TB1> INFO: *** Today: 2015/11/23
[12:39:06.423] <TB1> INFO: *** Version: 206d-dirty
[12:39:06.423] <TB1> INFO: readRocDacs: /usr/local/data/M2120_XrayQualification_2015-11-23_13h32m_1448281936//002_RetrimHotPixels_150//dacParameters35_C0.dat .. /usr/local/data/M2120_XrayQualification_2015-11-23_13h32m_1448281936//002_RetrimHotPixels_150//dacParameters35_C15.dat
[12:39:06.423] <TB1> INFO: readTbmDacs: /usr/local/data/M2120_XrayQualification_2015-11-23_13h32m_1448281936//002_RetrimHotPixels_150//tbmParameters_C0a.dat .. /usr/local/data/M2120_XrayQualification_2015-11-23_13h32m_1448281936//002_RetrimHotPixels_150//tbmParameters_C0b.dat
[12:39:06.424] <TB1> INFO: readMaskFile: /usr/local/data/M2120_XrayQualification_2015-11-23_13h32m_1448281936//002_RetrimHotPixels_150//defaultMaskFile.dat
[12:39:06.424] <TB1> INFO: readTrimFile: /usr/local/data/M2120_XrayQualification_2015-11-23_13h32m_1448281936//002_RetrimHotPixels_150//trimParameters35_C0.dat .. /usr/local/data/M2120_XrayQualification_2015-11-23_13h32m_1448281936//002_RetrimHotPixels_150//trimParameters35_C15.dat
[12:39:06.515] <TB1> INFO: clk: 4
[12:39:06.515] <TB1> INFO: ctr: 4
[12:39:06.515] <TB1> INFO: sda: 19
[12:39:06.515] <TB1> INFO: tin: 9
[12:39:06.515] <TB1> INFO: level: 15
[12:39:06.515] <TB1> INFO: triggerdelay: 0
[12:39:06.515] <TB1> QUIET: Instanciating API for pxar v2.6.0+26~g89693ff
[12:39:06.515] <TB1> INFO: Log level: INFO
[12:39:06.528] <TB1> INFO: Found DTB DTB_WWXTQT
[12:39:06.544] <TB1> QUIET: Connection to board DTB_WWXTQT opened.
[12:39:06.547] <TB1> INFO: DTB startup information
--- DTB info------------------------------------------
Board id: 147
HW version: DTB1.2
FW version: 4.2
SW version: 4.5
USB id: DTB_WWXTQT
MAC address: 40D855118093
Hostname: pixelDTB147
Comment:
------------------------------------------------------
[12:39:06.550] <TB1> INFO: RPC call hashes of host and DTB match: 398089610
[12:39:08.106] <TB1> INFO: DUT info:
[12:39:08.106] <TB1> INFO: The DUT currently contains the following objects:
[12:39:08.106] <TB1> INFO: 2 TBM Cores tbm09c (2 ON)
[12:39:08.106] <TB1> INFO: TBM Core alpha (0): 7 registers set
[12:39:08.106] <TB1> INFO: TBM Core beta (1): 7 registers set
[12:39:08.106] <TB1> INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[12:39:08.107] <TB1> INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[12:39:08.107] <TB1> INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[12:39:08.107] <TB1> INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[12:39:08.107] <TB1> INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[12:39:08.107] <TB1> INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[12:39:08.107] <TB1> INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[12:39:08.107] <TB1> INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[12:39:08.107] <TB1> INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[12:39:08.107] <TB1> INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[12:39:08.107] <TB1> INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[12:39:08.107] <TB1> INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[12:39:08.107] <TB1> INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[12:39:08.107] <TB1> INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[12:39:08.107] <TB1> INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[12:39:08.107] <TB1> INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[12:39:08.107] <TB1> INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[12:39:08.509] <TB1> INFO: enter 'restricted' command line mode
[12:39:08.509] <TB1> INFO: enter test to run
[12:39:08.509] <TB1> INFO: test: delay setting parameters: ->5<-
[12:39:08.509] <TB1> INFO: delay test by 5 seconds...
[12:39:13.510] <TB1> INFO: enter test to run
[12:39:13.510] <TB1> INFO: test: highrate no parameter change
[12:39:13.510] <TB1> INFO: running: highrate
[12:39:13.514] <TB1> INFO: readGainPedestalParameters /usr/local/data/M2120_XrayQualification_2015-11-23_13h32m_1448281936//002_RetrimHotPixels_150//phCalibrationFitErr35_C0.dat .. /usr/local/data/M2120_XrayQualification_2015-11-23_13h32m_1448281936//002_RetrimHotPixels_150//phCalibrationFitErr35_C15.dat
[12:39:13.650] <TB1> INFO: ----------------------------------------------------------------------
[12:39:13.650] <TB1> INFO: PixTest::trimHotPixels() running for 1 seconds with 100 kHz trigger rate
[12:39:13.650] <TB1> INFO: ----------------------------------------------------------------------
[12:39:13.650] <TB1> INFO: THR = 200, corresponding to ~ 533.333 MHz/cm2
[12:39:13.650] <TB1> INFO: edge/corner pixel THR is adjusted
[12:39:13.650] <TB1> INFO: PixTestHighRate::trimHotPixels: step 0...
[12:39:14.610] <TB1> INFO: Collecting data for 1 seconds...
[12:39:15.612] <TB1> INFO: Done with hot pixel readout
[12:39:26.192] <TB1> INFO: PixTest:: pg_setup set to default.
[12:39:26.193] <TB1> INFO: 3 hot pixels found in step 0
[12:39:27.156] <TB1> INFO: Collecting data for 1 seconds...
[12:39:28.158] <TB1> INFO: Done with hot pixel readout
[12:39:38.226] <TB1> INFO: PixTest:: pg_setup set to default.
[12:39:38.227] <TB1> INFO: 3 hot pixels found in step 1
[12:39:39.191] <TB1> INFO: Collecting data for 1 seconds...
[12:39:40.192] <TB1> INFO: Done with hot pixel readout
[12:39:50.198] <TB1> INFO: PixTest:: pg_setup set to default.
[12:39:50.199] <TB1> INFO: 3 hot pixels found in step 2
[12:39:51.163] <TB1> INFO: Collecting data for 1 seconds...
[12:39:52.164] <TB1> INFO: Done with hot pixel readout
[12:40:00.900] <TB1> INFO: PixTest:: pg_setup set to default.
[12:40:00.901] <TB1> INFO: 0 hot pixels found in step 3
[12:40:00.912] <TB1> INFO: 0 hot pixels could not be trimmed and have been masked.
[12:40:00.951] <TB1> INFO: PixTest::trimHotPixels() done
[12:40:00.952] <TB1> INFO: write trim parameters into /usr/local/data/M2120_XrayQualification_2015-11-23_13h32m_1448281936//002_RetrimHotPixels_150//trimParameters35_C0.dat
[12:40:00.958] <TB1> INFO: write trim parameters into /usr/local/data/M2120_XrayQualification_2015-11-23_13h32m_1448281936//002_RetrimHotPixels_150//trimParameters35_C1.dat
[12:40:00.964] <TB1> INFO: write trim parameters into /usr/local/data/M2120_XrayQualification_2015-11-23_13h32m_1448281936//002_RetrimHotPixels_150//trimParameters35_C2.dat
[12:40:00.972] <TB1> INFO: write trim parameters into /usr/local/data/M2120_XrayQualification_2015-11-23_13h32m_1448281936//002_RetrimHotPixels_150//trimParameters35_C3.dat
[12:40:00.984] <TB1> INFO: write trim parameters into /usr/local/data/M2120_XrayQualification_2015-11-23_13h32m_1448281936//002_RetrimHotPixels_150//trimParameters35_C4.dat
[12:40:00.991] <TB1> INFO: write trim parameters into /usr/local/data/M2120_XrayQualification_2015-11-23_13h32m_1448281936//002_RetrimHotPixels_150//trimParameters35_C5.dat
[12:40:00.998] <TB1> INFO: write trim parameters into /usr/local/data/M2120_XrayQualification_2015-11-23_13h32m_1448281936//002_RetrimHotPixels_150//trimParameters35_C6.dat
[12:40:01.010] <TB1> INFO: write trim parameters into /usr/local/data/M2120_XrayQualification_2015-11-23_13h32m_1448281936//002_RetrimHotPixels_150//trimParameters35_C7.dat
[12:40:01.022] <TB1> INFO: write trim parameters into /usr/local/data/M2120_XrayQualification_2015-11-23_13h32m_1448281936//002_RetrimHotPixels_150//trimParameters35_C8.dat
[12:40:01.029] <TB1> INFO: write trim parameters into /usr/local/data/M2120_XrayQualification_2015-11-23_13h32m_1448281936//002_RetrimHotPixels_150//trimParameters35_C9.dat
[12:40:01.036] <TB1> INFO: write trim parameters into /usr/local/data/M2120_XrayQualification_2015-11-23_13h32m_1448281936//002_RetrimHotPixels_150//trimParameters35_C10.dat
[12:40:01.047] <TB1> INFO: write trim parameters into /usr/local/data/M2120_XrayQualification_2015-11-23_13h32m_1448281936//002_RetrimHotPixels_150//trimParameters35_C11.dat
[12:40:01.058] <TB1> INFO: write trim parameters into /usr/local/data/M2120_XrayQualification_2015-11-23_13h32m_1448281936//002_RetrimHotPixels_150//trimParameters35_C12.dat
[12:40:01.071] <TB1> INFO: write trim parameters into /usr/local/data/M2120_XrayQualification_2015-11-23_13h32m_1448281936//002_RetrimHotPixels_150//trimParameters35_C13.dat
[12:40:01.078] <TB1> INFO: write trim parameters into /usr/local/data/M2120_XrayQualification_2015-11-23_13h32m_1448281936//002_RetrimHotPixels_150//trimParameters35_C14.dat
[12:40:01.084] <TB1> INFO: write trim parameters into /usr/local/data/M2120_XrayQualification_2015-11-23_13h32m_1448281936//002_RetrimHotPixels_150//trimParameters35_C15.dat
[12:40:01.090] <TB1> INFO: write masked pixels into /usr/local/data/M2120_XrayQualification_2015-11-23_13h32m_1448281936//002_RetrimHotPixels_150//defaultMaskFile.dat
[12:40:01.131] <TB1> INFO: enter test to run
[12:40:01.131] <TB1> INFO: test: no parameter change
[12:40:01.248] <TB1> QUIET: Connection to board 147 closed.
[12:40:01.329] <TB1> INFO: pXar: this is the end, my friend
commander_RetrimHotPixelsNoRate.log
[12:40:35.183] <TB1> INFO: *** Welcome to pxar ***
[12:40:35.183] <TB1> INFO: *** Today: 2015/11/23
[12:40:35.424] <TB1> INFO: *** Version: 206d-dirty
[12:40:35.425] <TB1> INFO: readRocDacs: /usr/local/data/M2120_XrayQualification_2015-11-23_13h32m_1448281936//003_RetrimHotPixelsNoRate_p17//dacParameters35_C0.dat .. /usr/local/data/M2120_XrayQualification_2015-11-23_13h32m_1448281936//003_RetrimHotPixelsNoRate_p17//dacParameters35_C15.dat
[12:40:35.427] <TB1> INFO: readTbmDacs: /usr/local/data/M2120_XrayQualification_2015-11-23_13h32m_1448281936//003_RetrimHotPixelsNoRate_p17//tbmParameters_C0a.dat .. /usr/local/data/M2120_XrayQualification_2015-11-23_13h32m_1448281936//003_RetrimHotPixelsNoRate_p17//tbmParameters_C0b.dat
[12:40:35.427] <TB1> INFO: readMaskFile: /usr/local/data/M2120_XrayQualification_2015-11-23_13h32m_1448281936//003_RetrimHotPixelsNoRate_p17//defaultMaskFile.dat
[12:40:35.427] <TB1> INFO: readTrimFile: /usr/local/data/M2120_XrayQualification_2015-11-23_13h32m_1448281936//003_RetrimHotPixelsNoRate_p17//trimParameters35_C0.dat .. /usr/local/data/M2120_XrayQualification_2015-11-23_13h32m_1448281936//003_RetrimHotPixelsNoRate_p17//trimParameters35_C15.dat
[12:40:35.525] <TB1> INFO: clk: 4
[12:40:35.525] <TB1> INFO: ctr: 4
[12:40:35.525] <TB1> INFO: sda: 19
[12:40:35.525] <TB1> INFO: tin: 9
[12:40:35.525] <TB1> INFO: level: 15
[12:40:35.525] <TB1> INFO: triggerdelay: 0
[12:40:35.525] <TB1> QUIET: Instanciating API for pxar v2.6.0+26~g89693ff
[12:40:35.525] <TB1> INFO: Log level: INFO
[12:40:35.538] <TB1> INFO: Found DTB DTB_WWXTQT
[12:40:35.554] <TB1> QUIET: Connection to board DTB_WWXTQT opened.
[12:40:35.557] <TB1> INFO: DTB startup information
--- DTB info------------------------------------------
Board id: 147
HW version: DTB1.2
FW version: 4.2
SW version: 4.5
USB id: DTB_WWXTQT
MAC address: 40D855118093
Hostname: pixelDTB147
Comment:
------------------------------------------------------
[12:40:35.559] <TB1> INFO: RPC call hashes of host and DTB match: 398089610
[12:40:37.124] <TB1> INFO: DUT info:
[12:40:37.125] <TB1> INFO: The DUT currently contains the following objects:
[12:40:37.125] <TB1> INFO: 2 TBM Cores tbm09c (2 ON)
[12:40:37.125] <TB1> INFO: TBM Core alpha (0): 7 registers set
[12:40:37.125] <TB1> INFO: TBM Core beta (1): 7 registers set
[12:40:37.125] <TB1> INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[12:40:37.125] <TB1> INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[12:40:37.125] <TB1> INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[12:40:37.125] <TB1> INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[12:40:37.125] <TB1> INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[12:40:37.125] <TB1> INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[12:40:37.125] <TB1> INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[12:40:37.125] <TB1> INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[12:40:37.125] <TB1> INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[12:40:37.125] <TB1> INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[12:40:37.125] <TB1> INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[12:40:37.125] <TB1> INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[12:40:37.125] <TB1> INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[12:40:37.125] <TB1> INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[12:40:37.125] <TB1> INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[12:40:37.125] <TB1> INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[12:40:37.125] <TB1> INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[12:40:37.526] <TB1> INFO: enter 'restricted' command line mode
[12:40:37.526] <TB1> INFO: enter test to run
[12:40:37.526] <TB1> INFO: test: delay setting parameters: ->5<-
[12:40:37.526] <TB1> INFO: delay test by 5 seconds...
[12:40:42.526] <TB1> INFO: enter test to run
[12:40:42.526] <TB1> INFO: test: highrate no parameter change
[12:40:42.527] <TB1> INFO: running: highrate
[12:40:42.532] <TB1> INFO: readGainPedestalParameters /usr/local/data/M2120_XrayQualification_2015-11-23_13h32m_1448281936//003_RetrimHotPixelsNoRate_p17//phCalibrationFitErr35_C0.dat .. /usr/local/data/M2120_XrayQualification_2015-11-23_13h32m_1448281936//003_RetrimHotPixelsNoRate_p17//phCalibrationFitErr35_C15.dat
[12:40:42.666] <TB1> INFO: ----------------------------------------------------------------------
[12:40:42.666] <TB1> INFO: PixTest::trimHotPixels() running for 5 seconds with 100 kHz trigger rate
[12:40:42.666] <TB1> INFO: ----------------------------------------------------------------------
[12:40:42.666] <TB1> INFO: THR = 10, corresponding to ~ 5.33333 MHz/cm2
[12:40:42.666] <TB1> INFO: edge/corner pixel THR is adjusted
[12:40:42.666] <TB1> INFO: PixTestHighRate::trimHotPixels: step 0...
[12:40:43.626] <TB1> INFO: Collecting data for 5 seconds...
[12:40:48.631] <TB1> INFO: Done with hot pixel readout
[12:41:01.055] <TB1> INFO: PixTest:: pg_setup set to default.
[12:41:01.056] <TB1> INFO: 2 hot pixels found in step 0
[12:41:02.095] <TB1> INFO: Collecting data for 5 seconds...
[12:41:07.100] <TB1> INFO: Done with hot pixel readout
[12:41:19.587] <TB1> INFO: PixTest:: pg_setup set to default.
[12:41:19.588] <TB1> INFO: 0 hot pixels found in step 1
[12:41:19.591] <TB1> INFO: 0 hot pixels could not be trimmed and have been masked.
[12:41:19.670] <TB1> INFO: PixTest::trimHotPixels() done
[12:41:19.670] <TB1> INFO: write trim parameters into /usr/local/data/M2120_XrayQualification_2015-11-23_13h32m_1448281936//003_RetrimHotPixelsNoRate_p17//trimParameters35_C0.dat
[12:41:19.680] <TB1> INFO: write trim parameters into /usr/local/data/M2120_XrayQualification_2015-11-23_13h32m_1448281936//003_RetrimHotPixelsNoRate_p17//trimParameters35_C1.dat
[12:41:19.691] <TB1> INFO: write trim parameters into /usr/local/data/M2120_XrayQualification_2015-11-23_13h32m_1448281936//003_RetrimHotPixelsNoRate_p17//trimParameters35_C2.dat
[12:41:19.698] <TB1> INFO: write trim parameters into /usr/local/data/M2120_XrayQualification_2015-11-23_13h32m_1448281936//003_RetrimHotPixelsNoRate_p17//trimParameters35_C3.dat
[12:41:19.708] <TB1> INFO: write trim parameters into /usr/local/data/M2120_XrayQualification_2015-11-23_13h32m_1448281936//003_RetrimHotPixelsNoRate_p17//trimParameters35_C4.dat
[12:41:19.714] <TB1> INFO: write trim parameters into /usr/local/data/M2120_XrayQualification_2015-11-23_13h32m_1448281936//003_RetrimHotPixelsNoRate_p17//trimParameters35_C5.dat
[12:41:19.720] <TB1> INFO: write trim parameters into /usr/local/data/M2120_XrayQualification_2015-11-23_13h32m_1448281936//003_RetrimHotPixelsNoRate_p17//trimParameters35_C6.dat
[12:41:19.727] <TB1> INFO: write trim parameters into /usr/local/data/M2120_XrayQualification_2015-11-23_13h32m_1448281936//003_RetrimHotPixelsNoRate_p17//trimParameters35_C7.dat
[12:41:19.733] <TB1> INFO: write trim parameters into /usr/local/data/M2120_XrayQualification_2015-11-23_13h32m_1448281936//003_RetrimHotPixelsNoRate_p17//trimParameters35_C8.dat
[12:41:19.740] <TB1> INFO: write trim parameters into /usr/local/data/M2120_XrayQualification_2015-11-23_13h32m_1448281936//003_RetrimHotPixelsNoRate_p17//trimParameters35_C9.dat
[12:41:19.746] <TB1> INFO: write trim parameters into /usr/local/data/M2120_XrayQualification_2015-11-23_13h32m_1448281936//003_RetrimHotPixelsNoRate_p17//trimParameters35_C10.dat
[12:41:19.753] <TB1> INFO: write trim parameters into /usr/local/data/M2120_XrayQualification_2015-11-23_13h32m_1448281936//003_RetrimHotPixelsNoRate_p17//trimParameters35_C11.dat
[12:41:19.760] <TB1> INFO: write trim parameters into /usr/local/data/M2120_XrayQualification_2015-11-23_13h32m_1448281936//003_RetrimHotPixelsNoRate_p17//trimParameters35_C12.dat
[12:41:19.767] <TB1> INFO: write trim parameters into /usr/local/data/M2120_XrayQualification_2015-11-23_13h32m_1448281936//003_RetrimHotPixelsNoRate_p17//trimParameters35_C13.dat
[12:41:19.774] <TB1> INFO: write trim parameters into /usr/local/data/M2120_XrayQualification_2015-11-23_13h32m_1448281936//003_RetrimHotPixelsNoRate_p17//trimParameters35_C14.dat
[12:41:19.781] <TB1> INFO: write trim parameters into /usr/local/data/M2120_XrayQualification_2015-11-23_13h32m_1448281936//003_RetrimHotPixelsNoRate_p17//trimParameters35_C15.dat
[12:41:19.788] <TB1> INFO: write masked pixels into /usr/local/data/M2120_XrayQualification_2015-11-23_13h32m_1448281936//003_RetrimHotPixelsNoRate_p17//defaultMaskFile.dat
[12:41:19.820] <TB1> INFO: enter test to run
[12:41:19.820] <TB1> INFO: test: no parameter change
[12:41:19.902] <TB1> QUIET: Connection to board 147 closed.
[12:41:19.982] <TB1> INFO: pXar: this is the end, my friend
commander_HRData.log
[12:42:30.444] <TB1> INFO: *** Welcome to pxar ***
[12:42:30.444] <TB1> INFO: *** Today: 2015/11/23
[12:42:30.533] <TB1> INFO: *** Version: 206d-dirty
[12:42:30.534] <TB1> INFO: readRocDacs: /usr/local/data/M2120_XrayQualification_2015-11-23_13h32m_1448281936//004_HRData_50//dacParameters35_C0.dat .. /usr/local/data/M2120_XrayQualification_2015-11-23_13h32m_1448281936//004_HRData_50//dacParameters35_C15.dat
[12:42:30.535] <TB1> INFO: readTbmDacs: /usr/local/data/M2120_XrayQualification_2015-11-23_13h32m_1448281936//004_HRData_50//tbmParameters_C0a.dat .. /usr/local/data/M2120_XrayQualification_2015-11-23_13h32m_1448281936//004_HRData_50//tbmParameters_C0b.dat
[12:42:30.535] <TB1> INFO: readMaskFile: /usr/local/data/M2120_XrayQualification_2015-11-23_13h32m_1448281936//004_HRData_50//defaultMaskFile.dat
[12:42:30.535] <TB1> INFO: readTrimFile: /usr/local/data/M2120_XrayQualification_2015-11-23_13h32m_1448281936//004_HRData_50//trimParameters35_C0.dat .. /usr/local/data/M2120_XrayQualification_2015-11-23_13h32m_1448281936//004_HRData_50//trimParameters35_C15.dat
[12:42:30.629] <TB1> INFO: clk: 4
[12:42:30.629] <TB1> INFO: ctr: 4
[12:42:30.629] <TB1> INFO: sda: 19
[12:42:30.629] <TB1> INFO: tin: 9
[12:42:30.629] <TB1> INFO: level: 15
[12:42:30.629] <TB1> INFO: triggerdelay: 0
[12:42:30.629] <TB1> QUIET: Instanciating API for pxar v2.6.0+26~g89693ff
[12:42:30.629] <TB1> INFO: Log level: INFO
[12:42:30.643] <TB1> INFO: Found DTB DTB_WWXTQT
[12:42:30.661] <TB1> QUIET: Connection to board DTB_WWXTQT opened.
[12:42:30.664] <TB1> INFO: DTB startup information
--- DTB info------------------------------------------
Board id: 147
HW version: DTB1.2
FW version: 4.2
SW version: 4.5
USB id: DTB_WWXTQT
MAC address: 40D855118093
Hostname: pixelDTB147
Comment:
------------------------------------------------------
[12:42:30.666] <TB1> INFO: RPC call hashes of host and DTB match: 398089610
[12:42:32.210] <TB1> INFO: DUT info:
[12:42:32.210] <TB1> INFO: The DUT currently contains the following objects:
[12:42:32.210] <TB1> INFO: 2 TBM Cores tbm09c (2 ON)
[12:42:32.211] <TB1> INFO: TBM Core alpha (0): 7 registers set
[12:42:32.211] <TB1> INFO: TBM Core beta (1): 7 registers set
[12:42:32.211] <TB1> INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[12:42:32.211] <TB1> INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[12:42:32.211] <TB1> INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[12:42:32.211] <TB1> INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[12:42:32.211] <TB1> INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[12:42:32.211] <TB1> INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[12:42:32.211] <TB1> INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[12:42:32.211] <TB1> INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[12:42:32.211] <TB1> INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[12:42:32.211] <TB1> INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[12:42:32.211] <TB1> INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[12:42:32.211] <TB1> INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[12:42:32.211] <TB1> INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[12:42:32.211] <TB1> INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[12:42:32.211] <TB1> INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[12:42:32.211] <TB1> INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[12:42:32.211] <TB1> INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[12:42:32.612] <TB1> INFO: enter 'restricted' command line mode
[12:42:32.612] <TB1> INFO: enter test to run
[12:42:32.613] <TB1> INFO: test: delay setting parameters: ->5<-
[12:42:32.613] <TB1> INFO: delay test by 5 seconds...
[12:42:37.613] <TB1> INFO: enter test to run
[12:42:37.613] <TB1> INFO: test: Xray no parameter change
[12:42:37.613] <TB1> INFO: running: xray
[12:42:37.615] <TB1> INFO: readGainPedestalParameters /usr/local/data/M2120_XrayQualification_2015-11-23_13h32m_1448281936//004_HRData_50//phCalibrationFitErr35_C0.dat .. /usr/local/data/M2120_XrayQualification_2015-11-23_13h32m_1448281936//004_HRData_50//phCalibrationFitErr35_C15.dat
[12:42:37.874] <TB1> INFO: ######################################################################
[12:42:37.874] <TB1> INFO: PixTestXray::doTest()
[12:42:37.874] <TB1> INFO: ######################################################################
[12:42:37.874] <TB1> INFO: ----------------------------------------------------------------------
[12:42:37.874] <TB1> INFO: PixTestXray::doPhRun() fParRunSeconds = 100
[12:42:37.874] <TB1> INFO: ----------------------------------------------------------------------
[12:42:38.839] <TB1> INFO: PixTestXray::doPhRun start TriggerLoop with trigger frequency 100 kHz, period 405 and duration 100 seconds, fEventsMax = 10000000
[12:42:44.589] <TB1> INFO: run duration 5 seconds, buffer almost full (81%), pausing triggers.
[12:43:18.719] <TB1> INFO: Resuming triggers.
[12:43:24.473] <TB1> INFO: run duration 11 seconds, buffer almost full (81%), pausing triggers.
[12:43:57.367] <TB1> INFO: Resuming triggers.
[12:44:03.121] <TB1> INFO: run duration 17 seconds, buffer almost full (81%), pausing triggers.
[12:44:36.783] <TB1> INFO: Resuming triggers.
[12:44:42.534] <TB1> INFO: run duration 22 seconds, buffer almost full (81%), pausing triggers.
[12:45:17.143] <TB1> INFO: Resuming triggers.
[12:45:22.889] <TB1> INFO: run duration 28 seconds, buffer almost full (81%), pausing triggers.
[12:45:57.998] <TB1> INFO: Resuming triggers.
[12:46:03.746] <TB1> INFO: run duration 34 seconds, buffer almost full (81%), pausing triggers.
[12:46:39.282] <TB1> INFO: Resuming triggers.
[12:46:45.029] <TB1> INFO: run duration 40 seconds, buffer almost full (81%), pausing triggers.
[12:47:20.300] <TB1> INFO: Resuming triggers.
[12:47:26.048] <TB1> INFO: run duration 45 seconds, buffer almost full (81%), pausing triggers.
[12:48:01.528] <TB1> INFO: Resuming triggers.
[12:48:07.277] <TB1> INFO: run duration 51 seconds, buffer almost full (81%), pausing triggers.
[12:48:42.711] <TB1> INFO: Resuming triggers.
[12:48:48.460] <TB1> INFO: run duration 57 seconds, buffer almost full (81%), pausing triggers.
[12:49:23.450] <TB1> INFO: Resuming triggers.
[12:49:29.197] <TB1> INFO: run duration 63 seconds, buffer almost full (81%), pausing triggers.
[12:50:04.158] <TB1> INFO: Resuming triggers.
[12:50:09.904] <TB1> INFO: run duration 68 seconds, buffer almost full (81%), pausing triggers.
[12:50:45.238] <TB1> INFO: Resuming triggers.
[12:50:50.987] <TB1> INFO: run duration 74 seconds, buffer almost full (81%), pausing triggers.
[12:51:24.252] <TB1> INFO: Resuming triggers.
[12:51:29.999] <TB1> INFO: run duration 80 seconds, buffer almost full (81%), pausing triggers.
[12:52:05.083] <TB1> INFO: Resuming triggers.
[12:52:10.831] <TB1> INFO: run duration 86 seconds, buffer almost full (81%), pausing triggers.
[12:52:46.007] <TB1> INFO: Resuming triggers.
[12:52:51.754] <TB1> INFO: run duration 91 seconds, buffer almost full (81%), pausing triggers.
[12:53:16.677] <TB1> INFO: Resuming triggers.
[12:53:22.421] <TB1> INFO: run duration 97 seconds, buffer almost full (81%), pausing triggers.
[12:53:45.387] <TB1> INFO: Resuming triggers.
[12:53:47.767] <TB1> INFO: data taking finished, elapsed time: 100 seconds.
[12:53:57.596] <TB1> INFO: PixTest:: pg_setup set to default.
[12:53:57.598] <TB1> INFO: PixTestXray::doPhRun() done
[12:53:57.598] <TB1> INFO: PixTestXray::doTest() done
[12:53:57.776] <TB1> INFO: enter test to run
[12:53:57.776] <TB1> INFO: test: no parameter change
[12:53:57.914] <TB1> QUIET: Connection to board 147 closed.
[12:53:57.949] <TB1> INFO: pXar: this is the end, my friend
commander_HRData.log
[12:54:13.600] <TB1> INFO: *** Welcome to pxar ***
[12:54:13.600] <TB1> INFO: *** Today: 2015/11/23
[12:54:13.830] <TB1> INFO: *** Version: 206d-dirty
[12:54:13.831] <TB1> INFO: readRocDacs: /usr/local/data/M2120_XrayQualification_2015-11-23_13h32m_1448281936//005_HRData_150//dacParameters35_C0.dat .. /usr/local/data/M2120_XrayQualification_2015-11-23_13h32m_1448281936//005_HRData_150//dacParameters35_C15.dat
[12:54:13.833] <TB1> INFO: readTbmDacs: /usr/local/data/M2120_XrayQualification_2015-11-23_13h32m_1448281936//005_HRData_150//tbmParameters_C0a.dat .. /usr/local/data/M2120_XrayQualification_2015-11-23_13h32m_1448281936//005_HRData_150//tbmParameters_C0b.dat
[12:54:13.833] <TB1> INFO: readMaskFile: /usr/local/data/M2120_XrayQualification_2015-11-23_13h32m_1448281936//005_HRData_150//defaultMaskFile.dat
[12:54:13.833] <TB1> INFO: readTrimFile: /usr/local/data/M2120_XrayQualification_2015-11-23_13h32m_1448281936//005_HRData_150//trimParameters35_C0.dat .. /usr/local/data/M2120_XrayQualification_2015-11-23_13h32m_1448281936//005_HRData_150//trimParameters35_C15.dat
[12:54:13.907] <TB1> INFO: clk: 4
[12:54:13.907] <TB1> INFO: ctr: 4
[12:54:13.907] <TB1> INFO: sda: 19
[12:54:13.907] <TB1> INFO: tin: 9
[12:54:13.907] <TB1> INFO: level: 15
[12:54:13.907] <TB1> INFO: triggerdelay: 0
[12:54:13.907] <TB1> QUIET: Instanciating API for pxar v2.6.0+26~g89693ff
[12:54:13.907] <TB1> INFO: Log level: INFO
[12:54:13.920] <TB1> INFO: Found DTB DTB_WWXTQT
[12:54:13.935] <TB1> QUIET: Connection to board DTB_WWXTQT opened.
[12:54:13.938] <TB1> INFO: DTB startup information
--- DTB info------------------------------------------
Board id: 147
HW version: DTB1.2
FW version: 4.2
SW version: 4.5
USB id: DTB_WWXTQT
MAC address: 40D855118093
Hostname: pixelDTB147
Comment:
------------------------------------------------------
[12:54:13.941] <TB1> INFO: RPC call hashes of host and DTB match: 398089610
[12:54:15.498] <TB1> INFO: DUT info:
[12:54:15.498] <TB1> INFO: The DUT currently contains the following objects:
[12:54:15.498] <TB1> INFO: 2 TBM Cores tbm09c (2 ON)
[12:54:15.498] <TB1> INFO: TBM Core alpha (0): 7 registers set
[12:54:15.498] <TB1> INFO: TBM Core beta (1): 7 registers set
[12:54:15.498] <TB1> INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[12:54:15.498] <TB1> INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[12:54:15.498] <TB1> INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[12:54:15.498] <TB1> INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[12:54:15.498] <TB1> INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[12:54:15.498] <TB1> INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[12:54:15.498] <TB1> INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[12:54:15.498] <TB1> INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[12:54:15.498] <TB1> INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[12:54:15.498] <TB1> INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[12:54:15.498] <TB1> INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[12:54:15.498] <TB1> INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[12:54:15.498] <TB1> INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[12:54:15.498] <TB1> INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[12:54:15.498] <TB1> INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[12:54:15.498] <TB1> INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[12:54:15.499] <TB1> INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[12:54:15.899] <TB1> INFO: enter 'restricted' command line mode
[12:54:15.900] <TB1> INFO: enter test to run
[12:54:15.900] <TB1> INFO: test: delay setting parameters: ->5<-
[12:54:15.900] <TB1> INFO: delay test by 5 seconds...
[12:54:20.900] <TB1> INFO: enter test to run
[12:54:20.900] <TB1> INFO: test: Xray no parameter change
[12:54:20.900] <TB1> INFO: running: xray
[12:54:20.907] <TB1> INFO: readGainPedestalParameters /usr/local/data/M2120_XrayQualification_2015-11-23_13h32m_1448281936//005_HRData_150//phCalibrationFitErr35_C0.dat .. /usr/local/data/M2120_XrayQualification_2015-11-23_13h32m_1448281936//005_HRData_150//phCalibrationFitErr35_C15.dat
[12:54:21.164] <TB1> INFO: ######################################################################
[12:54:21.164] <TB1> INFO: PixTestXray::doTest()
[12:54:21.164] <TB1> INFO: ######################################################################
[12:54:21.164] <TB1> INFO: ----------------------------------------------------------------------
[12:54:21.164] <TB1> INFO: PixTestXray::doPhRun() fParRunSeconds = 100
[12:54:21.164] <TB1> INFO: ----------------------------------------------------------------------
[12:54:22.132] <TB1> INFO: PixTestXray::doPhRun start TriggerLoop with trigger frequency 100 kHz, period 405 and duration 100 seconds, fEventsMax = 10000000
[12:54:25.078] <TB1> INFO: run duration 2 seconds, buffer almost full (81%), pausing triggers.
[12:54:58.664] <TB1> INFO: Resuming triggers.
[12:55:01.620] <TB1> INFO: run duration 5 seconds, buffer almost full (81%), pausing triggers.
[12:55:35.473] <TB1> INFO: Resuming triggers.
[12:55:38.426] <TB1> INFO: run duration 8 seconds, buffer almost full (81%), pausing triggers.
[12:56:11.402] <TB1> INFO: Resuming triggers.
[12:56:14.358] <TB1> INFO: run duration 11 seconds, buffer almost full (81%), pausing triggers.
[12:56:48.891] <TB1> INFO: Resuming triggers.
[12:56:51.846] <TB1> INFO: run duration 14 seconds, buffer almost full (81%), pausing triggers.
[12:57:26.136] <TB1> INFO: Resuming triggers.
[12:57:29.089] <TB1> INFO: run duration 17 seconds, buffer almost full (81%), pausing triggers.
[12:58:03.567] <TB1> INFO: Resuming triggers.
[12:58:06.515] <TB1> INFO: run duration 20 seconds, buffer almost full (81%), pausing triggers.
[12:58:40.629] <TB1> INFO: Resuming triggers.
[12:58:43.580] <TB1> INFO: run duration 23 seconds, buffer almost full (81%), pausing triggers.
[12:59:17.192] <TB1> INFO: Resuming triggers.
[12:59:20.141] <TB1> INFO: run duration 26 seconds, buffer almost full (81%), pausing triggers.
[12:59:53.768] <TB1> INFO: Resuming triggers.
[12:59:56.719] <TB1> INFO: run duration 29 seconds, buffer almost full (81%), pausing triggers.
[13:00:30.009] <TB1> INFO: Resuming triggers.
[13:00:32.962] <TB1> INFO: run duration 32 seconds, buffer almost full (81%), pausing triggers.
[13:01:07.078] <TB1> INFO: Resuming triggers.
[13:01:10.028] <TB1> INFO: run duration 35 seconds, buffer almost full (81%), pausing triggers.
[13:01:43.705] <TB1> INFO: Resuming triggers.
[13:01:46.654] <TB1> INFO: run duration 38 seconds, buffer almost full (81%), pausing triggers.
[13:02:20.159] <TB1> INFO: Resuming triggers.
[13:02:23.107] <TB1> INFO: run duration 41 seconds, buffer almost full (81%), pausing triggers.
[13:02:57.205] <TB1> INFO: Resuming triggers.
[13:03:00.154] <TB1> INFO: run duration 44 seconds, buffer almost full (81%), pausing triggers.
[13:03:32.656] <TB1> INFO: Resuming triggers.
[13:03:35.602] <TB1> INFO: run duration 47 seconds, buffer almost full (81%), pausing triggers.
[13:04:07.519] <TB1> INFO: Resuming triggers.
[13:04:10.466] <TB1> INFO: run duration 50 seconds, buffer almost full (81%), pausing triggers.
[13:04:41.234] <TB1> INFO: Resuming triggers.
[13:04:44.179] <TB1> INFO: run duration 53 seconds, buffer almost full (81%), pausing triggers.
[13:05:15.874] <TB1> INFO: Resuming triggers.
[13:05:18.821] <TB1> INFO: run duration 56 seconds, buffer almost full (81%), pausing triggers.
[13:05:51.513] <TB1> INFO: Resuming triggers.
[13:05:54.459] <TB1> INFO: run duration 58 seconds, buffer almost full (81%), pausing triggers.
[13:06:26.960] <TB1> INFO: Resuming triggers.
[13:06:29.908] <TB1> INFO: run duration 61 seconds, buffer almost full (81%), pausing triggers.
[13:07:01.068] <TB1> INFO: Resuming triggers.
[13:07:04.016] <TB1> INFO: run duration 64 seconds, buffer almost full (81%), pausing triggers.
[13:07:35.763] <TB1> INFO: Resuming triggers.
[13:07:38.709] <TB1> INFO: run duration 67 seconds, buffer almost full (81%), pausing triggers.
[13:07:46.515] <TB1> ERROR: <datapipe.cc/CheckEventValidity:L488> Channel 1 Number of ROCs (5) != Token Chain Length (4)

[13:07:46.515] <TB1> ERROR: <datapipe.cc/Read:L164> Dumping the flawed event +- 3 events:

[13:07:46.515] <TB1> ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a16a 8000 4078 249 2462 4078 290 286c 28a 2281 4078 113 226f 543 266c 804 244e 804 2641 407a 22 206e 1d 2069 18 2482 15 2aa8 1b 2681 45b 2484 848 2068 e000 c000

[13:07:46.515] <TB1> ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a164 80b1 4078 154 2a2e 4078 304 242e 348 2040 718 2261 4078 6c2 2245 4078 108 242a 145 226f 212 2a8f 30d 2a4e 608 284f 600 284d 810 2a4c e000 c000

[13:07:46.515] <TB1> ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a165 80c0 4078 45 2a4e 85 2260 4078 c5 2064 353 2861 422 266e 495 2868 613 2a82 4078 11a 208e 803 2062 4078 94 244a 34b 264f 653 2484 743 2048 743 2267 e000 c000

[13:07:46.515] <TB1> ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a166 8000 4078 4078 4d3 286f 4078 51 288c 5a 2280 499 2048 500 2668 81c 2a63 4078 34b 2482 60b 204f 642 2888 e000 c000

[13:07:46.515] <TB1> ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a167 8040 4078 681 2268 85a 2662 860 2660 4078 9d 286f 84 204f 21c 246f 644 2066 849 2483 840 266f 84d 2a68 4078 60 2881 113 2a6c 4078 8d 2442 4079 24ef 151 208c 142 286c 140 266a 411 266c 460 2281 64d 2849 750 206e e000 c000

[13:07:46.515] <TB1> ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a168 80b1 4078 100 2668 40c 2042 6cc 2a62 4078 11b 2264 299 24a5 45c 2248 4078 151 2662 404 2045 4078 10c 2648 118 2261 845 2a4f e000 c000

[13:07:46.515] <TB1> ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a169 80c0 4078 102 264f 405 266a 513 248c 65c 222e 4078 8b 2482 351 2a4a 655 2489 651 246e 810 284f 80c 222f 4078 412 204a 415 222e 45b 2862 6db 226d 4078 5 244a 449 264f 544 2869 844 2668 e000 c000

[13:08:10.927] <TB1> INFO: Resuming triggers.
[13:08:13.870] <TB1> INFO: run duration 70 seconds, buffer almost full (81%), pausing triggers.
[13:08:46.159] <TB1> INFO: Resuming triggers.
[13:08:49.103] <TB1> INFO: run duration 73 seconds, buffer almost full (81%), pausing triggers.
[13:09:20.378] <TB1> INFO: Resuming triggers.
[13:09:23.322] <TB1> INFO: run duration 76 seconds, buffer almost full (81%), pausing triggers.
[13:09:54.797] <TB1> INFO: Resuming triggers.
[13:09:57.743] <TB1> INFO: run duration 79 seconds, buffer almost full (81%), pausing triggers.
[13:10:29.146] <TB1> INFO: Resuming triggers.
[13:10:32.088] <TB1> INFO: run duration 82 seconds, buffer almost full (81%), pausing triggers.
[13:10:56.743] <TB1> INFO: Resuming triggers.
[13:10:59.686] <TB1> INFO: run duration 85 seconds, buffer almost full (81%), pausing triggers.
[13:11:21.888] <TB1> INFO: Resuming triggers.
[13:11:24.829] <TB1> INFO: run duration 88 seconds, buffer almost full (81%), pausing triggers.
[13:11:47.129] <TB1> INFO: Resuming triggers.
[13:11:50.072] <TB1> INFO: run duration 91 seconds, buffer almost full (81%), pausing triggers.
[13:12:12.358] <TB1> INFO: Resuming triggers.
[13:12:15.299] <TB1> INFO: run duration 94 seconds, buffer almost full (81%), pausing triggers.
[13:12:37.604] <TB1> INFO: Resuming triggers.
[13:12:40.546] <TB1> INFO: run duration 97 seconds, buffer almost full (81%), pausing triggers.
[13:13:02.682] <TB1> INFO: Resuming triggers.
[13:13:05.498] <TB1> INFO: data taking finished, elapsed time: 100 seconds.
[13:13:27.032] <TB1> INFO: PixTest:: pg_setup set to default.
[13:13:27.033] <TB1> INFO: PixTestXray::doPhRun() done
[13:13:27.033] <TB1> INFO: PixTestXray::doTest() done
[13:13:27.228] <TB1> INFO: enter test to run
[13:13:27.228] <TB1> INFO: test: no parameter change
[13:13:27.327] <TB1> QUIET: Connection to board 147 closed.
[13:13:27.406] <TB1> INFO: pXar: this is the end, my friend
commander_HRSCurves.log
[13:13:43.398] <TB1> INFO: *** Welcome to pxar ***
[13:13:43.398] <TB1> INFO: *** Today: 2015/11/23
[13:13:43.491] <TB1> INFO: *** Version: 206d-dirty
[13:13:43.492] <TB1> INFO: readRocDacs: /usr/local/data/M2120_XrayQualification_2015-11-23_13h32m_1448281936//006_HRSCurves_100//dacParameters35_C0.dat .. /usr/local/data/M2120_XrayQualification_2015-11-23_13h32m_1448281936//006_HRSCurves_100//dacParameters35_C15.dat
[13:13:43.493] <TB1> INFO: readTbmDacs: /usr/local/data/M2120_XrayQualification_2015-11-23_13h32m_1448281936//006_HRSCurves_100//tbmParameters_C0a.dat .. /usr/local/data/M2120_XrayQualification_2015-11-23_13h32m_1448281936//006_HRSCurves_100//tbmParameters_C0b.dat
[13:13:43.493] <TB1> INFO: readMaskFile: /usr/local/data/M2120_XrayQualification_2015-11-23_13h32m_1448281936//006_HRSCurves_100//defaultMaskFile.dat
[13:13:43.493] <TB1> INFO: readTrimFile: /usr/local/data/M2120_XrayQualification_2015-11-23_13h32m_1448281936//006_HRSCurves_100//trimParameters35_C0.dat .. /usr/local/data/M2120_XrayQualification_2015-11-23_13h32m_1448281936//006_HRSCurves_100//trimParameters35_C15.dat
[13:13:43.585] <TB1> INFO: clk: 4
[13:13:43.585] <TB1> INFO: ctr: 4
[13:13:43.585] <TB1> INFO: sda: 19
[13:13:43.585] <TB1> INFO: tin: 9
[13:13:43.585] <TB1> INFO: level: 15
[13:13:43.585] <TB1> INFO: triggerdelay: 0
[13:13:43.585] <TB1> QUIET: Instanciating API for pxar v2.6.0+26~g89693ff
[13:13:43.585] <TB1> INFO: Log level: INFO
[13:13:43.598] <TB1> INFO: Found DTB DTB_WWXTQT
[13:13:43.614] <TB1> QUIET: Connection to board DTB_WWXTQT opened.
[13:13:43.617] <TB1> INFO: DTB startup information
--- DTB info------------------------------------------
Board id: 147
HW version: DTB1.2
FW version: 4.2
SW version: 4.5
USB id: DTB_WWXTQT
MAC address: 40D855118093
Hostname: pixelDTB147
Comment:
------------------------------------------------------
[13:13:43.619] <TB1> INFO: RPC call hashes of host and DTB match: 398089610
[13:13:45.166] <TB1> INFO: DUT info:
[13:13:45.166] <TB1> INFO: The DUT currently contains the following objects:
[13:13:45.167] <TB1> INFO: 2 TBM Cores tbm09c (2 ON)
[13:13:45.167] <TB1> INFO: TBM Core alpha (0): 7 registers set
[13:13:45.167] <TB1> INFO: TBM Core beta (1): 7 registers set
[13:13:45.167] <TB1> INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[13:13:45.167] <TB1> INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[13:13:45.167] <TB1> INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[13:13:45.167] <TB1> INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[13:13:45.167] <TB1> INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[13:13:45.167] <TB1> INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[13:13:45.167] <TB1> INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[13:13:45.167] <TB1> INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[13:13:45.167] <TB1> INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[13:13:45.167] <TB1> INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[13:13:45.167] <TB1> INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[13:13:45.167] <TB1> INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[13:13:45.167] <TB1> INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[13:13:45.167] <TB1> INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[13:13:45.167] <TB1> INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[13:13:45.167] <TB1> INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[13:13:45.167] <TB1> INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[13:13:45.568] <TB1> INFO: enter 'restricted' command line mode
[13:13:45.568] <TB1> INFO: enter test to run
[13:13:45.568] <TB1> INFO: test: Highrate no parameter change
[13:13:45.568] <TB1> INFO: running: highrate
[13:13:45.571] <TB1> INFO: readGainPedestalParameters /usr/local/data/M2120_XrayQualification_2015-11-23_13h32m_1448281936//006_HRSCurves_100//phCalibrationFitErr35_C0.dat .. /usr/local/data/M2120_XrayQualification_2015-11-23_13h32m_1448281936//006_HRSCurves_100//phCalibrationFitErr35_C15.dat
[13:13:45.727] <TB1> INFO: ----------------------------------------------------------------------
[13:13:45.727] <TB1> INFO: PixTestHighRate::xNoiseMaps() ntrig = 50, vcal = 200
[13:13:45.727] <TB1> INFO: ----------------------------------------------------------------------
[13:13:45.732] <TB1> INFO: ---> dac: vcal name: xNoiseMap ntrig: 50 dacrange: 15 .. 75 (20/-1) hits flags = 896 (plus default)
[13:13:45.744] <TB1> INFO: dacScan step from 15 .. 34
[13:13:45.744] <TB1> INFO: dacScan split into 1 runs with ntrig = 50
[13:13:45.744] <TB1> INFO: run 1 of 1
[13:13:46.358] <TB1> INFO: Expecting 4160000 events.
[13:14:25.131] <TB1> INFO: 390000 events read in total (38243ms).
[13:15:02.382] <TB1> INFO: 781750 events read in total (75494ms).
[13:15:40.737] <TB1> INFO: 1173300 events read in total (113849ms).
[13:16:19.015] <TB1> INFO: 1564900 events read in total (152127ms).
[13:16:57.079] <TB1> INFO: 1956450 events read in total (190191ms).
[13:17:34.897] <TB1> INFO: 2347800 events read in total (228009ms).
[13:18:12.510] <TB1> INFO: 2739050 events read in total (265622ms).
[13:18:50.314] <TB1> INFO: 3130200 events read in total (303426ms).
[13:19:24.848] <TB1> INFO: 3521300 events read in total (337960ms).
[13:20:02.953] <TB1> INFO: 3911750 events read in total (376065ms).
[13:20:28.290] <TB1> INFO: 4160000 events read in total (401402ms).
[13:20:28.737] <TB1> INFO: Test took 402992ms.
[13:20:36.451] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:20:43.181] <TB1> INFO: dacScan step from 35 .. 54
[13:20:43.181] <TB1> INFO: dacScan split into 1 runs with ntrig = 50
[13:20:43.181] <TB1> INFO: run 1 of 1
[13:20:43.786] <TB1> INFO: Expecting 4160000 events.
[13:21:21.798] <TB1> INFO: 338050 events read in total (37484ms).
[13:21:58.715] <TB1> INFO: 677450 events read in total (74401ms).
[13:22:35.245] <TB1> INFO: 1015950 events read in total (110931ms).
[13:23:13.655] <TB1> INFO: 1354300 events read in total (149341ms).
[13:23:52.340] <TB1> INFO: 1691650 events read in total (188026ms).
[13:24:31.526] <TB1> INFO: 2027900 events read in total (227212ms).
[13:25:10.548] <TB1> INFO: 2362750 events read in total (266234ms).
[13:25:48.136] <TB1> INFO: 2695300 events read in total (303822ms).
[13:26:22.623] <TB1> INFO: 3025350 events read in total (338309ms).
[13:26:59.705] <TB1> INFO: 3352900 events read in total (375391ms).
[13:27:37.181] <TB1> INFO: 3679150 events read in total (412867ms).
[13:28:16.881] <TB1> INFO: 4004800 events read in total (452567ms).
[13:28:35.594] <TB1> INFO: 4160000 events read in total (471280ms).
[13:28:35.874] <TB1> INFO: Test took 472692ms.
[13:28:37.889] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:28:44.375] <TB1> INFO: dacScan step from 55 .. 74
[13:28:44.375] <TB1> INFO: dacScan split into 1 runs with ntrig = 50
[13:28:44.375] <TB1> INFO: run 1 of 1
[13:28:44.986] <TB1> INFO: Expecting 4160000 events.
[13:29:23.733] <TB1> INFO: 300200 events read in total (38219ms).
[13:30:01.858] <TB1> INFO: 601200 events read in total (76344ms).
[13:30:39.897] <TB1> INFO: 902200 events read in total (114383ms).
[13:31:18.014] <TB1> INFO: 1203000 events read in total (152500ms).
[13:31:54.846] <TB1> INFO: 1503800 events read in total (189332ms).
[13:32:30.352] <TB1> INFO: 1804650 events read in total (224838ms).
[13:33:06.639] <TB1> INFO: 2105450 events read in total (261125ms).
[13:33:33.742] <TB1> WARNING: Channel 1 ROC 1: Readback start marker after 3 readouts!

[13:33:33.742] <TB1> ERROR: <datapipe.cc/Read:L164> Dumping the flawed event +- 3 events:

[13:33:33.742] <TB1> ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a1f5 80c0 4878 1d 2048 c1 2045 215 206e 241 2a4e 249 224f 492 2646 4878 492 264a 4dc 204f 4db 2869 65c 204b 4878 b 286f a 2466 492 264f 4878 22 246f 18 286d 8 264a 4c 266d c8 2a66 492 264c 513 2a69 682 2842 e002 c000

[13:33:33.742] <TB1> ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a1ef 8040 487a 492 2640 60b 2468 487a 492 2665 4879 108 2642 492 2649 4878 18 284c 9a 2443 2ca 204f 492 2648 849 2044 e002 c000

[13:33:33.742] <TB1> ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a1f0 80b1 4878 10b 2a4e 243 244a 492 264a 4c1 2462 74b 2669 4878 492 2645 659 2689 4879 8a 2028 89 284c 492 262f 81d 2a4d 820 226f 4878 215 2a66 262 2480 243 2662 492 2649 e002 c000

[13:33:33.742] <TB1> ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a1f1 80c0 4879 492 2648 843 2244 852 224f 4879 492 266a 68c 2440 4878 492 2649 4878 492 264d 6a2 2885 fff 2fff fff 2fff e002 c000

[13:33:33.742] <TB1> ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a1f2 8000 4878 487b 2f6f 214 242c 299 2848 481 2a2d 492 2648 720 2442 4878 108 2685 492 2244 492 264f 4878 119 2069 492 2648 54b 2a67 4878 205 2265 492 262f e002 c000

[13:33:33.742] <TB1> ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a1f3 8040 4878 41c 284a 488 264f 492 2644 4879 314 2048 492 2669 4878 109 246a 758 2241 4878 492 262d e002 c000

[13:33:33.742] <TB1> ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a1f4 80b1 4878 492 2640 641 2866 4878 c0 2665 103 206f 492 2642 4878 492 264f 4879 a 284f 402 2888 492 262e 692 246c 6db 2067 fff 2fff e002 c000

[13:33:33.743] <TB1> WARNING: Channel 1 ROC 1: Readback start marker after 13 readouts!

[13:33:33.743] <TB1> ERROR: <datapipe.cc/Read:L164> Dumping the flawed event +- 3 events:

[13:33:33.743] <TB1> ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a102 8000 4878 492 2646 4878 204 2245 492 262f 4878 22 2089 492 262e 4878 492 264f 809 2a63 80b 2268 fff 2fff e002 c000

[13:33:33.743] <TB1> ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a1fc 80b1 4878 492 2642 4878 492 264f 4878 145 248d 492 264f 49b 2265 4879 490 204c 492 264f 68b 268f fff 2fff e002 c000

[13:33:33.743] <TB1> ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a1fd 80c0 4878 298 264c 492 2668 6dc 2245 4878 492 262c 487a 11c 286a 492 264f 4879 240 2449 34a 206f 492 264a 4d1 262d 700 244f e002 c000

[13:33:33.743] <TB1> ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a1fe 8000 4878 41 202e 492 2646 4d8 262c 562 2265 4878 352 2a29 353 226d 48a 2888 492 264d 545 2048 6d1 2043 4878 492 2661 4879 492 264c 65a 2449 70a 204c e002 c000

[13:33:33.743] <TB1> ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a1ff 8040 487a 492 2648 709 244e 487a 4d 2465 200 242c 492 264f 6c4 2220 4879 9b 2640 115 2a6e 492 264f 849 2266 4878 492 2648 e002 c000

[13:33:33.743] <TB1> ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a100 80b1 4878 302 206a 492 2660 854 2642 4878 40c 226c 492 2648 648 2a4f 4879 455 2068 492 2645 4878 10b 2a64 492 2645 645 2066 649 2262 652 2260 685 2061 683 2a4c e002 c000

[13:33:33.743] <TB1> ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a101 80c0 4879 492 2648 4879 a1 224a 141 2464 419 2863 492 2683 4878 4b 228c 282 246c 492 2649 68a 2a6f 4878 93 2660 320 264d 492 2640 653 266f 6c0 2665 e002 c000

[13:33:42.245] <TB1> INFO: 2406250 events read in total (296731ms).
[13:34:09.982] <TB1> INFO: 2706950 events read in total (324468ms).
[13:34:35.579] <TB1> INFO: 3007500 events read in total (350065ms).
[13:35:01.419] <TB1> INFO: 3308200 events read in total (375905ms).
[13:35:27.056] <TB1> INFO: 3608850 events read in total (401542ms).
[13:35:52.439] <TB1> INFO: 3909400 events read in total (426925ms).
[13:36:13.950] <TB1> INFO: 4160000 events read in total (448436ms).
[13:36:14.191] <TB1> INFO: Test took 449816ms.
[13:36:16.153] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:36:22.314] <TB1> INFO: dacScan step from 75 .. 75
[13:36:22.314] <TB1> INFO: dacScan split into 1 runs with ntrig = 50
[13:36:22.314] <TB1> INFO: run 1 of 1
[13:36:22.905] <TB1> INFO: Expecting 208000 events.
[13:36:42.114] <TB1> INFO: 208000 events read in total (18682ms).
[13:36:42.123] <TB1> INFO: Test took 19809ms.
[13:36:42.220] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:36:42.525] <TB1> INFO: dumping ASCII scurve output file: XSCurveData
[13:36:43.609] <TB1> INFO: dumping ASCII scurve output file: XSCurveData
[13:36:44.641] <TB1> INFO: dumping ASCII scurve output file: XSCurveData
[13:36:45.669] <TB1> INFO: dumping ASCII scurve output file: XSCurveData
[13:36:46.741] <TB1> INFO: dumping ASCII scurve output file: XSCurveData
[13:36:47.805] <TB1> INFO: dumping ASCII scurve output file: XSCurveData
[13:36:48.893] <TB1> INFO: dumping ASCII scurve output file: XSCurveData
[13:36:49.987] <TB1> INFO: dumping ASCII scurve output file: XSCurveData
[13:36:51.058] <TB1> INFO: dumping ASCII scurve output file: XSCurveData
[13:36:52.126] <TB1> INFO: dumping ASCII scurve output file: XSCurveData
[13:36:53.213] <TB1> INFO: dumping ASCII scurve output file: XSCurveData
[13:36:54.324] <TB1> INFO: dumping ASCII scurve output file: XSCurveData
[13:36:55.399] <TB1> INFO: dumping ASCII scurve output file: XSCurveData
[13:36:56.454] <TB1> INFO: dumping ASCII scurve output file: XSCurveData
[13:36:57.484] <TB1> INFO: dumping ASCII scurve output file: XSCurveData
[13:36:58.507] <TB1> INFO: dumping ASCII scurve output file: XSCurveData
[13:36:59.542] <TB1> INFO: PixTest:: pg_setup set to default.
[13:36:59.544] <TB1> INFO: vcal mean: 38.46 39.42 38.84 42.13 41.74 44.72 43.52 44.15 44.06 43.59 43.88 43.79 41.28 38.26 38.56 38.67
[13:36:59.544] <TB1> INFO: vcal RMS: 1.72 1.42 1.09 1.46 1.25 1.55 1.51 1.74 1.88 1.65 1.59 1.89 1.74 1.56 1.45 1.32
[13:36:59.544] <TB1> INFO: number of X-ray hits detected: 234400 242129 246122 380424 372482 527308 486069 553559 517583 468898 482994 457871 392859 229475 239298 236374
[13:36:59.544] <TB1> INFO: number of triggers sent (total per ROC): 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000
[13:36:59.544] <TB1> INFO: X-ray hit rate [MHz/cm2]: 68.7 71.0 72.1 111.5 109.2 154.6 142.5 162.3 151.7 137.4 141.6 134.2 115.1 67.3 70.1 69.3
[13:36:59.544] <TB1> INFO: PixTestHighRate::doXNoiseMaps() done
[13:36:59.664] <TB1> INFO: enter test to run
[13:36:59.664] <TB1> INFO: test: no parameter change
[13:36:59.757] <TB1> QUIET: Connection to board 147 closed.
[13:36:59.844] <TB1> INFO: pXar: this is the end, my friend
commander_CalDelScanAndSaveDacs.log
[13:56:39.204] <TB1> INFO: *** Welcome to pxar ***
[13:56:39.204] <TB1> INFO: *** Today: 2015/11/23
[13:56:39.309] <TB1> INFO: *** Version: 206d-dirty
[13:56:39.309] <TB1> INFO: readRocDacs: /usr/local/data/M2120_XrayQualification_2015-11-23_13h32m_1448281936//011_CalDelScanAndSaveDacs_4mA25kV//dacParameters35_C0.dat .. /usr/local/data/M2120_XrayQualification_2015-11-23_13h32m_1448281936//011_CalDelScanAndSaveDacs_4mA25kV//dacParameters35_C15.dat
[13:56:39.310] <TB1> INFO: readTbmDacs: /usr/local/data/M2120_XrayQualification_2015-11-23_13h32m_1448281936//011_CalDelScanAndSaveDacs_4mA25kV//tbmParameters_C0a.dat .. /usr/local/data/M2120_XrayQualification_2015-11-23_13h32m_1448281936//011_CalDelScanAndSaveDacs_4mA25kV//tbmParameters_C0b.dat
[13:56:39.310] <TB1> INFO: readMaskFile: /usr/local/data/M2120_XrayQualification_2015-11-23_13h32m_1448281936//011_CalDelScanAndSaveDacs_4mA25kV//defaultMaskFile.dat
[13:56:39.310] <TB1> INFO: readTrimFile: /usr/local/data/M2120_XrayQualification_2015-11-23_13h32m_1448281936//011_CalDelScanAndSaveDacs_4mA25kV//trimParameters35_C0.dat .. /usr/local/data/M2120_XrayQualification_2015-11-23_13h32m_1448281936//011_CalDelScanAndSaveDacs_4mA25kV//trimParameters35_C15.dat
[13:56:39.394] <TB1> INFO: clk: 4
[13:56:39.394] <TB1> INFO: ctr: 4
[13:56:39.394] <TB1> INFO: sda: 19
[13:56:39.394] <TB1> INFO: tin: 9
[13:56:39.394] <TB1> INFO: level: 15
[13:56:39.394] <TB1> INFO: triggerdelay: 0
[13:56:39.394] <TB1> QUIET: Instanciating API for pxar v2.6.0+26~g89693ff
[13:56:39.394] <TB1> INFO: Log level: INFO
[13:56:39.408] <TB1> INFO: Found DTB DTB_WWXTQT
[13:56:39.422] <TB1> QUIET: Connection to board DTB_WWXTQT opened.
[13:56:39.425] <TB1> INFO: DTB startup information
--- DTB info------------------------------------------
Board id: 147
HW version: DTB1.2
FW version: 4.2
SW version: 4.5
USB id: DTB_WWXTQT
MAC address: 40D855118093
Hostname: pixelDTB147
Comment:
------------------------------------------------------
[13:56:39.427] <TB1> INFO: RPC call hashes of host and DTB match: 398089610
[13:56:40.996] <TB1> INFO: DUT info:
[13:56:40.996] <TB1> INFO: The DUT currently contains the following objects:
[13:56:40.996] <TB1> INFO: 2 TBM Cores tbm09c (2 ON)
[13:56:40.996] <TB1> INFO: TBM Core alpha (0): 7 registers set
[13:56:40.996] <TB1> INFO: TBM Core beta (1): 7 registers set
[13:56:40.996] <TB1> INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[13:56:40.996] <TB1> INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[13:56:40.996] <TB1> INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[13:56:40.996] <TB1> INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[13:56:40.996] <TB1> INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[13:56:40.996] <TB1> INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[13:56:40.996] <TB1> INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[13:56:40.996] <TB1> INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[13:56:40.996] <TB1> INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[13:56:40.996] <TB1> INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[13:56:40.996] <TB1> INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[13:56:40.996] <TB1> INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[13:56:40.996] <TB1> INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[13:56:40.996] <TB1> INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[13:56:40.996] <TB1> INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[13:56:40.997] <TB1> INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[13:56:40.997] <TB1> INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[13:56:41.398] <TB1> INFO: enter 'restricted' command line mode
[13:56:41.398] <TB1> INFO: enter test to run
[13:56:41.399] <TB1> INFO: test: HighRate no parameter change
[13:56:41.399] <TB1> INFO: running: highrate
[13:56:41.401] <TB1> INFO: readGainPedestalParameters /usr/local/data/M2120_XrayQualification_2015-11-23_13h32m_1448281936//011_CalDelScanAndSaveDacs_4mA25kV//phCalibrationFitErr35_C0.dat .. /usr/local/data/M2120_XrayQualification_2015-11-23_13h32m_1448281936//011_CalDelScanAndSaveDacs_4mA25kV//phCalibrationFitErr35_C15.dat
[13:56:41.626] <TB1> INFO: ----------------------------------------------------------------------
[13:56:41.626] <TB1> INFO: PixTestHighRate::calDelScan() ntrig = 10, vcal = 200
[13:56:41.626] <TB1> INFO: ----------------------------------------------------------------------
[13:56:41.763] <TB1> INFO: Expecting 768 events.
[13:56:42.992] <TB1> INFO: 768 events read in total (511ms).
[13:56:43.087] <TB1> INFO: Test took 1457ms.
[13:56:43.726] <TB1> INFO: Expecting 41600 events.
[13:56:48.554] <TB1> INFO: 41600 events read in total (4301ms).
[13:56:48.557] <TB1> INFO: Test took 5464ms.
[13:56:48.645] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:56:49.289] <TB1> INFO: Expecting 41600 events.
[13:56:54.401] <TB1> INFO: 41600 events read in total (4585ms).
[13:56:54.404] <TB1> INFO: Test took 5727ms.
[13:56:54.508] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:56:55.155] <TB1> INFO: Expecting 41600 events.
[13:56:59.971] <TB1> INFO: 41600 events read in total (4289ms).
[13:56:59.973] <TB1> INFO: Test took 5431ms.
[13:57:00.073] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:57:00.719] <TB1> INFO: Expecting 41600 events.
[13:57:05.311] <TB1> INFO: 41600 events read in total (4065ms).
[13:57:05.313] <TB1> INFO: Test took 5206ms.
[13:57:05.417] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:57:06.077] <TB1> INFO: Expecting 41600 events.
[13:57:10.748] <TB1> INFO: 41600 events read in total (4144ms).
[13:57:10.750] <TB1> INFO: Test took 5292ms.
[13:57:10.851] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:57:11.497] <TB1> INFO: Expecting 41600 events.
[13:57:16.011] <TB1> INFO: 41600 events read in total (3987ms).
[13:57:16.013] <TB1> INFO: Test took 5127ms.
[13:57:16.105] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:57:16.756] <TB1> INFO: Expecting 41600 events.
[13:57:21.347] <TB1> INFO: 41600 events read in total (4064ms).
[13:57:21.349] <TB1> INFO: Test took 5209ms.
[13:57:21.451] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:57:22.098] <TB1> INFO: Expecting 41600 events.
[13:57:26.673] <TB1> INFO: 41600 events read in total (4048ms).
[13:57:26.675] <TB1> INFO: Test took 5189ms.
[13:57:26.776] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:57:27.423] <TB1> INFO: Expecting 41600 events.
[13:57:32.237] <TB1> INFO: 41600 events read in total (4287ms).
[13:57:32.239] <TB1> INFO: Test took 5428ms.
[13:57:32.333] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:57:32.979] <TB1> INFO: Expecting 41600 events.
[13:57:37.964] <TB1> INFO: 41600 events read in total (4458ms).
[13:57:37.966] <TB1> INFO: Test took 5599ms.
[13:57:38.060] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:57:38.711] <TB1> INFO: Expecting 41600 events.
[13:57:43.717] <TB1> INFO: 41600 events read in total (4477ms).
[13:57:43.720] <TB1> INFO: Test took 5621ms.
[13:57:43.823] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:57:44.488] <TB1> INFO: Expecting 41600 events.
[13:57:49.302] <TB1> INFO: 41600 events read in total (4287ms).
[13:57:49.304] <TB1> INFO: Test took 5438ms.
[13:57:49.398] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:57:50.045] <TB1> INFO: Expecting 41600 events.
[13:57:55.094] <TB1> INFO: 41600 events read in total (4522ms).
[13:57:55.096] <TB1> INFO: Test took 5662ms.
[13:57:55.193] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:57:55.898] <TB1> INFO: Expecting 41600 events.
[13:58:00.973] <TB1> INFO: 41600 events read in total (4548ms).
[13:58:00.976] <TB1> INFO: Test took 5748ms.
[13:58:01.070] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:58:01.724] <TB1> INFO: Expecting 41600 events.
[13:58:06.788] <TB1> INFO: 41600 events read in total (4537ms).
[13:58:06.791] <TB1> INFO: Test took 5679ms.
[13:58:06.888] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:58:07.536] <TB1> INFO: Expecting 41600 events.
[13:58:12.462] <TB1> INFO: 41600 events read in total (4399ms).
[13:58:12.464] <TB1> INFO: Test took 5540ms.
[13:58:12.563] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:58:13.211] <TB1> INFO: Expecting 41600 events.
[13:58:18.065] <TB1> INFO: 41600 events read in total (4327ms).
[13:58:18.067] <TB1> INFO: Test took 5469ms.
[13:58:18.169] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:58:18.825] <TB1> INFO: Expecting 41600 events.
[13:58:23.151] <TB1> INFO: 41600 events read in total (3798ms).
[13:58:23.154] <TB1> INFO: Test took 4941ms.
[13:58:23.252] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:58:23.899] <TB1> INFO: Expecting 41600 events.
[13:58:28.136] <TB1> INFO: 41600 events read in total (3710ms).
[13:58:28.139] <TB1> INFO: Test took 4852ms.
[13:58:28.233] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:58:28.891] <TB1> INFO: Expecting 41600 events.
[13:58:32.613] <TB1> INFO: 41600 events read in total (3195ms).
[13:58:32.615] <TB1> INFO: Test took 4347ms.
[13:58:32.704] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:58:33.014] <TB1> INFO: enter test to run
[13:58:33.014] <TB1> INFO: test: Pretest no parameter change
[13:58:33.014] <TB1> INFO: running: pretest
[13:58:33.014] <TB1> INFO: write dac parameters into /usr/local/data/M2120_XrayQualification_2015-11-23_13h32m_1448281936//011_CalDelScanAndSaveDacs_4mA25kV//dacParameters35_C0.dat
[13:58:33.014] <TB1> INFO: write dac parameters into /usr/local/data/M2120_XrayQualification_2015-11-23_13h32m_1448281936//011_CalDelScanAndSaveDacs_4mA25kV//dacParameters35_C1.dat
[13:58:33.014] <TB1> INFO: write dac parameters into /usr/local/data/M2120_XrayQualification_2015-11-23_13h32m_1448281936//011_CalDelScanAndSaveDacs_4mA25kV//dacParameters35_C2.dat
[13:58:33.015] <TB1> INFO: write dac parameters into /usr/local/data/M2120_XrayQualification_2015-11-23_13h32m_1448281936//011_CalDelScanAndSaveDacs_4mA25kV//dacParameters35_C3.dat
[13:58:33.015] <TB1> INFO: write dac parameters into /usr/local/data/M2120_XrayQualification_2015-11-23_13h32m_1448281936//011_CalDelScanAndSaveDacs_4mA25kV//dacParameters35_C4.dat
[13:58:33.015] <TB1> INFO: write dac parameters into /usr/local/data/M2120_XrayQualification_2015-11-23_13h32m_1448281936//011_CalDelScanAndSaveDacs_4mA25kV//dacParameters35_C5.dat
[13:58:33.015] <TB1> INFO: write dac parameters into /usr/local/data/M2120_XrayQualification_2015-11-23_13h32m_1448281936//011_CalDelScanAndSaveDacs_4mA25kV//dacParameters35_C6.dat
[13:58:33.015] <TB1> INFO: write dac parameters into /usr/local/data/M2120_XrayQualification_2015-11-23_13h32m_1448281936//011_CalDelScanAndSaveDacs_4mA25kV//dacParameters35_C7.dat
[13:58:33.015] <TB1> INFO: write dac parameters into /usr/local/data/M2120_XrayQualification_2015-11-23_13h32m_1448281936//011_CalDelScanAndSaveDacs_4mA25kV//dacParameters35_C8.dat
[13:58:33.015] <TB1> INFO: write dac parameters into /usr/local/data/M2120_XrayQualification_2015-11-23_13h32m_1448281936//011_CalDelScanAndSaveDacs_4mA25kV//dacParameters35_C9.dat
[13:58:33.015] <TB1> INFO: write dac parameters into /usr/local/data/M2120_XrayQualification_2015-11-23_13h32m_1448281936//011_CalDelScanAndSaveDacs_4mA25kV//dacParameters35_C10.dat
[13:58:33.015] <TB1> INFO: write dac parameters into /usr/local/data/M2120_XrayQualification_2015-11-23_13h32m_1448281936//011_CalDelScanAndSaveDacs_4mA25kV//dacParameters35_C11.dat
[13:58:33.015] <TB1> INFO: write dac parameters into /usr/local/data/M2120_XrayQualification_2015-11-23_13h32m_1448281936//011_CalDelScanAndSaveDacs_4mA25kV//dacParameters35_C12.dat
[13:58:33.015] <TB1> INFO: write dac parameters into /usr/local/data/M2120_XrayQualification_2015-11-23_13h32m_1448281936//011_CalDelScanAndSaveDacs_4mA25kV//dacParameters35_C13.dat
[13:58:33.015] <TB1> INFO: write dac parameters into /usr/local/data/M2120_XrayQualification_2015-11-23_13h32m_1448281936//011_CalDelScanAndSaveDacs_4mA25kV//dacParameters35_C14.dat
[13:58:33.016] <TB1> INFO: write dac parameters into /usr/local/data/M2120_XrayQualification_2015-11-23_13h32m_1448281936//011_CalDelScanAndSaveDacs_4mA25kV//dacParameters35_C15.dat
[13:58:33.016] <TB1> INFO: enter test to run
[13:58:33.016] <TB1> INFO: test: no parameter change
[13:58:33.129] <TB1> QUIET: Connection to board 147 closed.
[13:58:33.208] <TB1> INFO: pXar: this is the end, my friend
commander_HREfficiency.log
[13:58:48.925] <TB1> INFO: *** Welcome to pxar ***
[13:58:48.925] <TB1> INFO: *** Today: 2015/11/23
[13:58:49.275] <TB1> INFO: *** Version: 206d-dirty
[13:58:49.275] <TB1> INFO: readRocDacs: /usr/local/data/M2120_XrayQualification_2015-11-23_13h32m_1448281936//012_HREfficiency_50//dacParameters35_C0.dat .. /usr/local/data/M2120_XrayQualification_2015-11-23_13h32m_1448281936//012_HREfficiency_50//dacParameters35_C15.dat
[13:58:49.276] <TB1> INFO: readTbmDacs: /usr/local/data/M2120_XrayQualification_2015-11-23_13h32m_1448281936//012_HREfficiency_50//tbmParameters_C0a.dat .. /usr/local/data/M2120_XrayQualification_2015-11-23_13h32m_1448281936//012_HREfficiency_50//tbmParameters_C0b.dat
[13:58:49.276] <TB1> INFO: readMaskFile: /usr/local/data/M2120_XrayQualification_2015-11-23_13h32m_1448281936//012_HREfficiency_50//defaultMaskFile.dat
[13:58:49.276] <TB1> INFO: readTrimFile: /usr/local/data/M2120_XrayQualification_2015-11-23_13h32m_1448281936//012_HREfficiency_50//trimParameters35_C0.dat .. /usr/local/data/M2120_XrayQualification_2015-11-23_13h32m_1448281936//012_HREfficiency_50//trimParameters35_C15.dat
[13:58:49.348] <TB1> INFO: clk: 4
[13:58:49.348] <TB1> INFO: ctr: 4
[13:58:49.348] <TB1> INFO: sda: 19
[13:58:49.348] <TB1> INFO: tin: 9
[13:58:49.348] <TB1> INFO: level: 15
[13:58:49.348] <TB1> INFO: triggerdelay: 0
[13:58:49.348] <TB1> QUIET: Instanciating API for pxar v2.6.0+26~g89693ff
[13:58:49.348] <TB1> INFO: Log level: INFO
[13:58:49.360] <TB1> INFO: Found DTB DTB_WWXTQT
[13:58:49.375] <TB1> QUIET: Connection to board DTB_WWXTQT opened.
[13:58:49.378] <TB1> INFO: DTB startup information
--- DTB info------------------------------------------
Board id: 147
HW version: DTB1.2
FW version: 4.2
SW version: 4.5
USB id: DTB_WWXTQT
MAC address: 40D855118093
Hostname: pixelDTB147
Comment:
------------------------------------------------------
[13:58:49.380] <TB1> INFO: RPC call hashes of host and DTB match: 398089610
[13:58:50.958] <TB1> INFO: DUT info:
[13:58:50.958] <TB1> INFO: The DUT currently contains the following objects:
[13:58:50.958] <TB1> INFO: 2 TBM Cores tbm09c (2 ON)
[13:58:50.958] <TB1> INFO: TBM Core alpha (0): 7 registers set
[13:58:50.958] <TB1> INFO: TBM Core beta (1): 7 registers set
[13:58:50.958] <TB1> INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[13:58:50.958] <TB1> INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[13:58:50.958] <TB1> INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[13:58:50.958] <TB1> INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[13:58:50.958] <TB1> INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[13:58:50.958] <TB1> INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[13:58:50.958] <TB1> INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[13:58:50.958] <TB1> INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[13:58:50.958] <TB1> INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[13:58:50.958] <TB1> INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[13:58:50.958] <TB1> INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[13:58:50.958] <TB1> INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[13:58:50.958] <TB1> INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[13:58:50.958] <TB1> INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[13:58:50.958] <TB1> INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[13:58:50.958] <TB1> INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[13:58:50.958] <TB1> INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[13:58:51.359] <TB1> INFO: enter 'restricted' command line mode
[13:58:51.359] <TB1> INFO: enter test to run
[13:58:51.359] <TB1> INFO: test: PixelAlive no parameter change
[13:58:51.359] <TB1> INFO: running: pixelalive
[13:58:51.365] <TB1> INFO: ######################################################################
[13:58:51.365] <TB1> INFO: PixTestAlive::doTest()
[13:58:51.365] <TB1> INFO: ######################################################################
[13:58:51.367] <TB1> INFO: ----------------------------------------------------------------------
[13:58:51.367] <TB1> INFO: PixTestAlive::aliveTest() ntrig = 50, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[13:58:51.367] <TB1> INFO: ----------------------------------------------------------------------
[13:58:51.676] <TB1> INFO: Expecting 208000 events.
[13:59:05.132] <TB1> INFO: 208000 events read in total (12737ms).
[13:59:05.135] <TB1> INFO: Test took 13767ms.
[13:59:05.146] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:59:05.408] <TB1> INFO: PixTestAlive::aliveTest() done
[13:59:05.408] <TB1> INFO: number of dead pixels (per ROC): 4 1 0 0 0 0 0 0 0 0 0 0 1 0 2 1
[13:59:05.410] <TB1> INFO: ----------------------------------------------------------------------
[13:59:05.410] <TB1> INFO: PixTestAlive::maskTest() ntrig = 50, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[13:59:05.410] <TB1> INFO: ----------------------------------------------------------------------
[13:59:05.729] <TB1> INFO: Expecting 208000 events.
[13:59:14.377] <TB1> INFO: 208000 events read in total (7932ms).
[13:59:14.378] <TB1> INFO: Test took 8967ms.
[13:59:14.378] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:59:14.378] <TB1> INFO: mask vs. old pixelAlive PixelAlive_C0_V0 .. PixelAlive_C15_V0
[13:59:14.695] <TB1> INFO: PixTestAlive::maskTest() done
[13:59:14.695] <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
[13:59:14.697] <TB1> INFO: ----------------------------------------------------------------------
[13:59:14.697] <TB1> INFO: PixTestAlive::addressDecodingTest() ntrig = 50, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[13:59:14.697] <TB1> INFO: ----------------------------------------------------------------------
[13:59:15.009] <TB1> INFO: Expecting 208000 events.
[13:59:28.179] <TB1> INFO: 208000 events read in total (12454ms).
[13:59:28.179] <TB1> INFO: Test took 13480ms.
[13:59:28.189] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:59:28.413] <TB1> INFO: PixTestAlive::addressDecodingTest() done
[13:59:28.413] <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
[13:59:28.413] <TB1> INFO: PixTestAlive::doTest() done, duration: 37 seconds
[13:59:28.450] <TB1> INFO: enter test to run
[13:59:28.450] <TB1> INFO: test: delay setting parameters: ->2<-
[13:59:28.450] <TB1> INFO: delay test by 2 seconds...
[13:59:30.450] <TB1> INFO: enter test to run
[13:59:30.450] <TB1> INFO: test: HighRate no parameter change
[13:59:30.450] <TB1> INFO: running: highrate
[13:59:30.450] <TB1> INFO: readGainPedestalParameters /usr/local/data/M2120_XrayQualification_2015-11-23_13h32m_1448281936//012_HREfficiency_50//phCalibrationFitErr35_C0.dat .. /usr/local/data/M2120_XrayQualification_2015-11-23_13h32m_1448281936//012_HREfficiency_50//phCalibrationFitErr35_C15.dat
[13:59:30.618] <TB1> INFO: ----------------------------------------------------------------------
[13:59:30.618] <TB1> INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[13:59:30.618] <TB1> INFO: ----------------------------------------------------------------------
[13:59:31.232] <TB1> INFO: Expecting 208000 events.
[13:59:47.623] <TB1> INFO: 208000 events read in total (15864ms).
[13:59:47.633] <TB1> INFO: Test took 17011ms.
[13:59:47.976] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:59:48.283] <TB1> INFO: number of dead pixels (per ROC): 4 1 0 0 0 0 0 0 0 0 0 0 1 0 2 1
[13:59:48.283] <TB1> INFO: number of red-efficiency pixels: 153 154 225 336 281 531 467 546 457 451 457 445 301 164 153 188
[13:59:48.283] <TB1> INFO: number of X-ray hits detected: 115646 120587 121000 193682 185704 268593 244493 279868 263000 238173 246797 230097 198650 112686 119030 118978
[13:59:48.283] <TB1> INFO: number of triggers sent (total per ROC): 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000
[13:59:48.283] <TB1> INFO: number of Vcal hits detected: 207650 207787 207752 207645 207705 207422 207486 207414 207505 207517 207490 207519 207633 207831 207747 207757
[13:59:48.283] <TB1> INFO: Vcal hit fiducial efficiency (%): 99.9 99.9 99.9 99.8 99.9 99.7 99.8 99.7 99.8 99.8 99.8 99.8 99.9 99.9 99.9 99.9
[13:59:48.283] <TB1> INFO: Vcal hit overall efficiency (%): 99.8 99.9 99.9 99.8 99.9 99.7 99.8 99.7 99.8 99.8 99.8 99.8 99.8 99.9 99.9 99.9
[13:59:48.283] <TB1> INFO: X-ray hit rate [MHz/cm2]: 33.9 35.3 35.5 56.8 54.4 78.7 71.7 82.0 77.1 69.8 72.3 67.4 58.2 33.0 34.9 34.9
[13:59:48.283] <TB1> INFO: PixTestHighRate::doXPixelAlive() done
[13:59:48.315] <TB1> INFO: PixTest:: pg_setup set to default.
[13:59:48.327] <TB1> INFO: enter test to run
[13:59:48.327] <TB1> INFO: test: no parameter change
[13:59:48.447] <TB1> QUIET: Connection to board 147 closed.
[13:59:48.529] <TB1> INFO: pXar: this is the end, my friend
commander_HREfficiency.log
[14:00:09.378] <TB1> INFO: *** Welcome to pxar ***
[14:00:09.378] <TB1> INFO: *** Today: 2015/11/23
[14:00:09.458] <TB1> INFO: *** Version: 206d-dirty
[14:00:09.458] <TB1> INFO: readRocDacs: /usr/local/data/M2120_XrayQualification_2015-11-23_13h32m_1448281936//013_HREfficiency_100//dacParameters35_C0.dat .. /usr/local/data/M2120_XrayQualification_2015-11-23_13h32m_1448281936//013_HREfficiency_100//dacParameters35_C15.dat
[14:00:09.459] <TB1> INFO: readTbmDacs: /usr/local/data/M2120_XrayQualification_2015-11-23_13h32m_1448281936//013_HREfficiency_100//tbmParameters_C0a.dat .. /usr/local/data/M2120_XrayQualification_2015-11-23_13h32m_1448281936//013_HREfficiency_100//tbmParameters_C0b.dat
[14:00:09.459] <TB1> INFO: readMaskFile: /usr/local/data/M2120_XrayQualification_2015-11-23_13h32m_1448281936//013_HREfficiency_100//defaultMaskFile.dat
[14:00:09.459] <TB1> INFO: readTrimFile: /usr/local/data/M2120_XrayQualification_2015-11-23_13h32m_1448281936//013_HREfficiency_100//trimParameters35_C0.dat .. /usr/local/data/M2120_XrayQualification_2015-11-23_13h32m_1448281936//013_HREfficiency_100//trimParameters35_C15.dat
[14:00:09.530] <TB1> INFO: clk: 4
[14:00:09.530] <TB1> INFO: ctr: 4
[14:00:09.530] <TB1> INFO: sda: 19
[14:00:09.530] <TB1> INFO: tin: 9
[14:00:09.530] <TB1> INFO: level: 15
[14:00:09.530] <TB1> INFO: triggerdelay: 0
[14:00:09.530] <TB1> QUIET: Instanciating API for pxar v2.6.0+26~g89693ff
[14:00:09.530] <TB1> INFO: Log level: INFO
[14:00:09.543] <TB1> INFO: Found DTB DTB_WWXTQT
[14:00:09.557] <TB1> QUIET: Connection to board DTB_WWXTQT opened.
[14:00:09.560] <TB1> INFO: DTB startup information
--- DTB info------------------------------------------
Board id: 147
HW version: DTB1.2
FW version: 4.2
SW version: 4.5
USB id: DTB_WWXTQT
MAC address: 40D855118093
Hostname: pixelDTB147
Comment:
------------------------------------------------------
[14:00:09.563] <TB1> INFO: RPC call hashes of host and DTB match: 398089610
[14:00:11.127] <TB1> INFO: DUT info:
[14:00:11.127] <TB1> INFO: The DUT currently contains the following objects:
[14:00:11.127] <TB1> INFO: 2 TBM Cores tbm09c (2 ON)
[14:00:11.127] <TB1> INFO: TBM Core alpha (0): 7 registers set
[14:00:11.127] <TB1> INFO: TBM Core beta (1): 7 registers set
[14:00:11.127] <TB1> INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[14:00:11.127] <TB1> INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[14:00:11.127] <TB1> INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[14:00:11.127] <TB1> INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[14:00:11.127] <TB1> INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[14:00:11.127] <TB1> INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[14:00:11.127] <TB1> INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[14:00:11.127] <TB1> INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[14:00:11.128] <TB1> INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[14:00:11.128] <TB1> INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[14:00:11.128] <TB1> INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[14:00:11.128] <TB1> INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[14:00:11.128] <TB1> INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[14:00:11.128] <TB1> INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[14:00:11.128] <TB1> INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[14:00:11.128] <TB1> INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[14:00:11.128] <TB1> INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[14:00:11.529] <TB1> INFO: enter 'restricted' command line mode
[14:00:11.529] <TB1> INFO: enter test to run
[14:00:11.529] <TB1> INFO: test: PixelAlive no parameter change
[14:00:11.529] <TB1> INFO: running: pixelalive
[14:00:11.535] <TB1> INFO: ######################################################################
[14:00:11.535] <TB1> INFO: PixTestAlive::doTest()
[14:00:11.535] <TB1> INFO: ######################################################################
[14:00:11.537] <TB1> INFO: ----------------------------------------------------------------------
[14:00:11.537] <TB1> INFO: PixTestAlive::aliveTest() ntrig = 50, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[14:00:11.537] <TB1> INFO: ----------------------------------------------------------------------
[14:00:11.883] <TB1> INFO: Expecting 208000 events.
[14:00:24.971] <TB1> INFO: 208000 events read in total (12370ms).
[14:00:24.998] <TB1> INFO: Test took 13460ms.
[14:00:25.007] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[14:00:25.274] <TB1> INFO: PixTestAlive::aliveTest() done
[14:00:25.274] <TB1> INFO: number of dead pixels (per ROC): 4 1 0 0 0 0 0 0 0 0 0 0 1 0 2 1
[14:00:25.276] <TB1> INFO: ----------------------------------------------------------------------
[14:00:25.276] <TB1> INFO: PixTestAlive::maskTest() ntrig = 50, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[14:00:25.276] <TB1> INFO: ----------------------------------------------------------------------
[14:00:25.604] <TB1> INFO: Expecting 208000 events.
[14:00:34.363] <TB1> INFO: 208000 events read in total (8043ms).
[14:00:34.363] <TB1> INFO: Test took 9085ms.
[14:00:34.363] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[14:00:34.364] <TB1> INFO: mask vs. old pixelAlive PixelAlive_C0_V0 .. PixelAlive_C15_V0
[14:00:34.674] <TB1> INFO: PixTestAlive::maskTest() done
[14:00:34.674] <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
[14:00:34.676] <TB1> INFO: ----------------------------------------------------------------------
[14:00:34.676] <TB1> INFO: PixTestAlive::addressDecodingTest() ntrig = 50, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[14:00:34.676] <TB1> INFO: ----------------------------------------------------------------------
[14:00:34.994] <TB1> INFO: Expecting 208000 events.
[14:00:48.443] <TB1> INFO: 208000 events read in total (12733ms).
[14:00:48.444] <TB1> INFO: Test took 13766ms.
[14:00:48.452] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[14:00:48.681] <TB1> INFO: PixTestAlive::addressDecodingTest() done
[14:00:48.681] <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
[14:00:48.681] <TB1> INFO: PixTestAlive::doTest() done, duration: 37 seconds
[14:00:48.718] <TB1> INFO: enter test to run
[14:00:48.718] <TB1> INFO: test: delay setting parameters: ->2<-
[14:00:48.718] <TB1> INFO: delay test by 2 seconds...
[14:00:50.718] <TB1> INFO: enter test to run
[14:00:50.718] <TB1> INFO: test: HighRate no parameter change
[14:00:50.718] <TB1> INFO: running: highrate
[14:00:50.718] <TB1> INFO: readGainPedestalParameters /usr/local/data/M2120_XrayQualification_2015-11-23_13h32m_1448281936//013_HREfficiency_100//phCalibrationFitErr35_C0.dat .. /usr/local/data/M2120_XrayQualification_2015-11-23_13h32m_1448281936//013_HREfficiency_100//phCalibrationFitErr35_C15.dat
[14:00:50.887] <TB1> INFO: ----------------------------------------------------------------------
[14:00:50.887] <TB1> INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[14:00:50.887] <TB1> INFO: ----------------------------------------------------------------------
[14:00:51.521] <TB1> INFO: Expecting 208000 events.
[14:01:14.586] <TB1> INFO: 208000 events read in total (22538ms).
[14:01:14.596] <TB1> INFO: Test took 23705ms.
[14:01:15.256] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[14:01:15.667] <TB1> INFO: number of dead pixels (per ROC): 4 1 0 0 0 0 0 0 0 0 0 0 1 0 2 1
[14:01:15.667] <TB1> INFO: number of red-efficiency pixels: 459 481 769 1146 969 2035 1753 2257 1954 1698 1849 1607 1362 577 476 584
[14:01:15.667] <TB1> INFO: number of X-ray hits detected: 233526 243621 248021 380899 371605 530306 483511 554429 517443 469531 484048 458165 394528 230074 240998 237970
[14:01:15.667] <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
[14:01:15.667] <TB1> INFO: number of Vcal hits detected: 207316 207432 206862 206527 206797 204747 205291 204417 205139 205517 205244 205585 206164 207240 207395 207303
[14:01:15.667] <TB1> INFO: Vcal hit fiducial efficiency (%): 99.8 99.8 99.5 99.4 99.5 98.6 98.9 98.4 98.7 98.9 98.8 99.0 99.2 99.7 99.8 99.7
[14:01:15.667] <TB1> INFO: Vcal hit overall efficiency (%): 99.7 99.7 99.5 99.3 99.4 98.4 98.7 98.3 98.6 98.8 98.7 98.8 99.1 99.6 99.7 99.7
[14:01:15.667] <TB1> INFO: X-ray hit rate [MHz/cm2]: 68.4 71.4 72.7 111.6 108.9 155.4 141.7 162.5 151.7 137.6 141.9 134.3 115.6 67.4 70.6 69.8
[14:01:15.667] <TB1> INFO: PixTestHighRate::doXPixelAlive() done
[14:01:15.693] <TB1> INFO: PixTest:: pg_setup set to default.
[14:01:15.704] <TB1> INFO: enter test to run
[14:01:15.704] <TB1> INFO: test: no parameter change
[14:01:15.794] <TB1> QUIET: Connection to board 147 closed.
[14:01:15.873] <TB1> INFO: pXar: this is the end, my friend
commander_HREfficiency.log
[14:01:33.927] <TB1> INFO: *** Welcome to pxar ***
[14:01:33.927] <TB1> INFO: *** Today: 2015/11/23
[14:01:34.119] <TB1> INFO: *** Version: 206d-dirty
[14:01:34.119] <TB1> INFO: readRocDacs: /usr/local/data/M2120_XrayQualification_2015-11-23_13h32m_1448281936//014_HREfficiency_150//dacParameters35_C0.dat .. /usr/local/data/M2120_XrayQualification_2015-11-23_13h32m_1448281936//014_HREfficiency_150//dacParameters35_C15.dat
[14:01:34.120] <TB1> INFO: readTbmDacs: /usr/local/data/M2120_XrayQualification_2015-11-23_13h32m_1448281936//014_HREfficiency_150//tbmParameters_C0a.dat .. /usr/local/data/M2120_XrayQualification_2015-11-23_13h32m_1448281936//014_HREfficiency_150//tbmParameters_C0b.dat
[14:01:34.120] <TB1> INFO: readMaskFile: /usr/local/data/M2120_XrayQualification_2015-11-23_13h32m_1448281936//014_HREfficiency_150//defaultMaskFile.dat
[14:01:34.120] <TB1> INFO: readTrimFile: /usr/local/data/M2120_XrayQualification_2015-11-23_13h32m_1448281936//014_HREfficiency_150//trimParameters35_C0.dat .. /usr/local/data/M2120_XrayQualification_2015-11-23_13h32m_1448281936//014_HREfficiency_150//trimParameters35_C15.dat
[14:01:34.192] <TB1> INFO: clk: 4
[14:01:34.192] <TB1> INFO: ctr: 4
[14:01:34.192] <TB1> INFO: sda: 19
[14:01:34.192] <TB1> INFO: tin: 9
[14:01:34.192] <TB1> INFO: level: 15
[14:01:34.192] <TB1> INFO: triggerdelay: 0
[14:01:34.192] <TB1> QUIET: Instanciating API for pxar v2.6.0+26~g89693ff
[14:01:34.192] <TB1> INFO: Log level: INFO
[14:01:34.206] <TB1> INFO: Found DTB DTB_WWXTQT
[14:01:34.221] <TB1> QUIET: Connection to board DTB_WWXTQT opened.
[14:01:34.224] <TB1> INFO: DTB startup information
--- DTB info------------------------------------------
Board id: 147
HW version: DTB1.2
FW version: 4.2
SW version: 4.5
USB id: DTB_WWXTQT
MAC address: 40D855118093
Hostname: pixelDTB147
Comment:
------------------------------------------------------
[14:01:34.226] <TB1> INFO: RPC call hashes of host and DTB match: 398089610
[14:01:35.780] <TB1> INFO: DUT info:
[14:01:35.780] <TB1> INFO: The DUT currently contains the following objects:
[14:01:35.780] <TB1> INFO: 2 TBM Cores tbm09c (2 ON)
[14:01:35.780] <TB1> INFO: TBM Core alpha (0): 7 registers set
[14:01:35.780] <TB1> INFO: TBM Core beta (1): 7 registers set
[14:01:35.780] <TB1> INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[14:01:35.780] <TB1> INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[14:01:35.780] <TB1> INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[14:01:35.780] <TB1> INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[14:01:35.780] <TB1> INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[14:01:35.780] <TB1> INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[14:01:35.780] <TB1> INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[14:01:35.780] <TB1> INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[14:01:35.780] <TB1> INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[14:01:35.780] <TB1> INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[14:01:35.780] <TB1> INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[14:01:35.780] <TB1> INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[14:01:35.780] <TB1> INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[14:01:35.780] <TB1> INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[14:01:35.780] <TB1> INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[14:01:35.780] <TB1> INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[14:01:35.780] <TB1> INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[14:01:36.181] <TB1> INFO: enter 'restricted' command line mode
[14:01:36.181] <TB1> INFO: enter test to run
[14:01:36.181] <TB1> INFO: test: PixelAlive no parameter change
[14:01:36.181] <TB1> INFO: running: pixelalive
[14:01:36.187] <TB1> INFO: ######################################################################
[14:01:36.187] <TB1> INFO: PixTestAlive::doTest()
[14:01:36.187] <TB1> INFO: ######################################################################
[14:01:36.189] <TB1> INFO: ----------------------------------------------------------------------
[14:01:36.189] <TB1> INFO: PixTestAlive::aliveTest() ntrig = 50, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[14:01:36.189] <TB1> INFO: ----------------------------------------------------------------------
[14:01:36.503] <TB1> INFO: Expecting 208000 events.
[14:01:49.366] <TB1> INFO: 208000 events read in total (12145ms).
[14:01:49.380] <TB1> INFO: Test took 13190ms.
[14:01:49.392] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[14:01:49.655] <TB1> INFO: PixTestAlive::aliveTest() done
[14:01:49.655] <TB1> INFO: number of dead pixels (per ROC): 4 1 0 0 0 0 0 0 0 0 0 0 1 0 2 1
[14:01:49.657] <TB1> INFO: ----------------------------------------------------------------------
[14:01:49.657] <TB1> INFO: PixTestAlive::maskTest() ntrig = 50, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[14:01:49.657] <TB1> INFO: ----------------------------------------------------------------------
[14:01:49.973] <TB1> INFO: Expecting 208000 events.
[14:01:58.390] <TB1> INFO: 208000 events read in total (7701ms).
[14:01:58.391] <TB1> INFO: Test took 8732ms.
[14:01:58.391] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[14:01:58.391] <TB1> INFO: mask vs. old pixelAlive PixelAlive_C0_V0 .. PixelAlive_C15_V0
[14:01:58.700] <TB1> INFO: PixTestAlive::maskTest() done
[14:01:58.700] <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
[14:01:58.702] <TB1> INFO: ----------------------------------------------------------------------
[14:01:58.702] <TB1> INFO: PixTestAlive::addressDecodingTest() ntrig = 50, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[14:01:58.702] <TB1> INFO: ----------------------------------------------------------------------
[14:01:59.014] <TB1> INFO: Expecting 208000 events.
[14:02:11.922] <TB1> INFO: 208000 events read in total (12192ms).
[14:02:11.922] <TB1> INFO: Test took 13218ms.
[14:02:11.932] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[14:02:12.169] <TB1> INFO: PixTestAlive::addressDecodingTest() done
[14:02:12.169] <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
[14:02:12.179] <TB1> INFO: PixTestAlive::doTest() done, duration: 35 seconds
[14:02:12.217] <TB1> INFO: enter test to run
[14:02:12.217] <TB1> INFO: test: delay setting parameters: ->2<-
[14:02:12.217] <TB1> INFO: delay test by 2 seconds...
[14:02:14.217] <TB1> INFO: enter test to run
[14:02:14.218] <TB1> INFO: test: HighRate no parameter change
[14:02:14.218] <TB1> INFO: running: highrate
[14:02:14.218] <TB1> INFO: readGainPedestalParameters /usr/local/data/M2120_XrayQualification_2015-11-23_13h32m_1448281936//014_HREfficiency_150//phCalibrationFitErr35_C0.dat .. /usr/local/data/M2120_XrayQualification_2015-11-23_13h32m_1448281936//014_HREfficiency_150//phCalibrationFitErr35_C15.dat
[14:02:14.410] <TB1> INFO: ----------------------------------------------------------------------
[14:02:14.410] <TB1> INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[14:02:14.410] <TB1> INFO: ----------------------------------------------------------------------
[14:02:15.050] <TB1> INFO: Expecting 208000 events.
[14:02:45.638] <TB1> INFO: 208000 events read in total (30061ms).
[14:02:45.658] <TB1> INFO: Test took 31245ms.
[14:02:46.646] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[14:02:47.141] <TB1> INFO: number of dead pixels (per ROC): 4 1 0 0 0 0 0 0 0 0 0 0 1 0 2 1
[14:02:47.141] <TB1> INFO: number of red-efficiency pixels: 955 987 1427 2335 2095 3485 3190 3763 3485 3157 3280 3022 2561 1067 956 1232
[14:02:47.141] <TB1> INFO: number of X-ray hits detected: 338131 351338 359856 546066 536670 759744 697097 800858 741320 674808 689798 656404 564590 334985 347840 343961
[14:02:47.141] <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
[14:02:47.141] <TB1> INFO: number of Vcal hits detected: 206673 206769 204782 203766 204431 198431 199730 196855 199500 200441 200167 200896 203227 206056 206778 206388
[14:02:47.141] <TB1> INFO: Vcal hit fiducial efficiency (%): 99.5 99.5 98.8 98.2 98.5 95.9 96.5 95.1 96.3 96.7 96.7 97.0 98.0 99.2 99.5 99.3
[14:02:47.141] <TB1> INFO: Vcal hit overall efficiency (%): 99.4 99.4 98.5 98.0 98.3 95.4 96.0 94.6 95.9 96.4 96.2 96.6 97.7 99.1 99.4 99.2
[14:02:47.141] <TB1> INFO: X-ray hit rate [MHz/cm2]: 99.1 103.0 105.5 160.1 157.3 222.7 204.3 234.7 217.3 197.8 202.2 192.4 165.5 98.2 102.0 100.8
[14:02:47.141] <TB1> INFO: PixTestHighRate::doXPixelAlive() done
[14:02:47.170] <TB1> INFO: PixTest:: pg_setup set to default.
[14:02:47.182] <TB1> INFO: enter test to run
[14:02:47.182] <TB1> INFO: test: no parameter change
[14:02:47.269] <TB1> QUIET: Connection to board 147 closed.
[14:02:47.348] <TB1> INFO: pXar: this is the end, my friend
commander_HREfficiency.log
[14:03:04.763] <TB1> INFO: *** Welcome to pxar ***
[14:03:04.763] <TB1> INFO: *** Today: 2015/11/23
[14:03:04.905] <TB1> INFO: *** Version: 206d-dirty
[14:03:04.905] <TB1> INFO: readRocDacs: /usr/local/data/M2120_XrayQualification_2015-11-23_13h32m_1448281936//015_HREfficiency_200//dacParameters35_C0.dat .. /usr/local/data/M2120_XrayQualification_2015-11-23_13h32m_1448281936//015_HREfficiency_200//dacParameters35_C15.dat
[14:03:04.906] <TB1> INFO: readTbmDacs: /usr/local/data/M2120_XrayQualification_2015-11-23_13h32m_1448281936//015_HREfficiency_200//tbmParameters_C0a.dat .. /usr/local/data/M2120_XrayQualification_2015-11-23_13h32m_1448281936//015_HREfficiency_200//tbmParameters_C0b.dat
[14:03:04.907] <TB1> INFO: readMaskFile: /usr/local/data/M2120_XrayQualification_2015-11-23_13h32m_1448281936//015_HREfficiency_200//defaultMaskFile.dat
[14:03:04.907] <TB1> INFO: readTrimFile: /usr/local/data/M2120_XrayQualification_2015-11-23_13h32m_1448281936//015_HREfficiency_200//trimParameters35_C0.dat .. /usr/local/data/M2120_XrayQualification_2015-11-23_13h32m_1448281936//015_HREfficiency_200//trimParameters35_C15.dat
[14:03:04.982] <TB1> INFO: clk: 4
[14:03:04.982] <TB1> INFO: ctr: 4
[14:03:04.982] <TB1> INFO: sda: 19
[14:03:04.982] <TB1> INFO: tin: 9
[14:03:04.982] <TB1> INFO: level: 15
[14:03:04.982] <TB1> INFO: triggerdelay: 0
[14:03:04.982] <TB1> QUIET: Instanciating API for pxar v2.6.0+26~g89693ff
[14:03:04.982] <TB1> INFO: Log level: INFO
[14:03:04.996] <TB1> INFO: Found DTB DTB_WWXTQT
[14:03:05.010] <TB1> QUIET: Connection to board DTB_WWXTQT opened.
[14:03:05.013] <TB1> INFO: DTB startup information
--- DTB info------------------------------------------
Board id: 147
HW version: DTB1.2
FW version: 4.2
SW version: 4.5
USB id: DTB_WWXTQT
MAC address: 40D855118093
Hostname: pixelDTB147
Comment:
------------------------------------------------------
[14:03:05.016] <TB1> INFO: RPC call hashes of host and DTB match: 398089610
[14:03:06.559] <TB1> INFO: DUT info:
[14:03:06.559] <TB1> INFO: The DUT currently contains the following objects:
[14:03:06.559] <TB1> INFO: 2 TBM Cores tbm09c (2 ON)
[14:03:06.559] <TB1> INFO: TBM Core alpha (0): 7 registers set
[14:03:06.559] <TB1> INFO: TBM Core beta (1): 7 registers set
[14:03:06.559] <TB1> INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[14:03:06.560] <TB1> INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[14:03:06.560] <TB1> INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[14:03:06.560] <TB1> INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[14:03:06.560] <TB1> INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[14:03:06.560] <TB1> INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[14:03:06.560] <TB1> INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[14:03:06.560] <TB1> INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[14:03:06.560] <TB1> INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[14:03:06.560] <TB1> INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[14:03:06.560] <TB1> INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[14:03:06.560] <TB1> INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[14:03:06.560] <TB1> INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[14:03:06.560] <TB1> INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[14:03:06.560] <TB1> INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[14:03:06.560] <TB1> INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[14:03:06.560] <TB1> INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[14:03:06.961] <TB1> INFO: enter 'restricted' command line mode
[14:03:06.961] <TB1> INFO: enter test to run
[14:03:06.961] <TB1> INFO: test: PixelAlive no parameter change
[14:03:06.961] <TB1> INFO: running: pixelalive
[14:03:06.966] <TB1> INFO: ######################################################################
[14:03:06.966] <TB1> INFO: PixTestAlive::doTest()
[14:03:06.966] <TB1> INFO: ######################################################################
[14:03:06.968] <TB1> INFO: ----------------------------------------------------------------------
[14:03:06.968] <TB1> INFO: PixTestAlive::aliveTest() ntrig = 50, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[14:03:06.968] <TB1> INFO: ----------------------------------------------------------------------
[14:03:07.277] <TB1> INFO: Expecting 208000 events.
[14:03:20.303] <TB1> INFO: 208000 events read in total (12308ms).
[14:03:20.327] <TB1> INFO: Test took 13358ms.
[14:03:20.337] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[14:03:20.610] <TB1> INFO: PixTestAlive::aliveTest() done
[14:03:20.610] <TB1> INFO: number of dead pixels (per ROC): 4 1 0 0 0 0 0 0 0 0 0 0 1 0 2 1
[14:03:20.612] <TB1> INFO: ----------------------------------------------------------------------
[14:03:20.612] <TB1> INFO: PixTestAlive::maskTest() ntrig = 50, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[14:03:20.612] <TB1> INFO: ----------------------------------------------------------------------
[14:03:20.922] <TB1> INFO: Expecting 208000 events.
[14:03:29.497] <TB1> INFO: 208000 events read in total (7859ms).
[14:03:29.497] <TB1> INFO: Test took 8883ms.
[14:03:29.497] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[14:03:29.498] <TB1> INFO: mask vs. old pixelAlive PixelAlive_C0_V0 .. PixelAlive_C15_V0
[14:03:29.815] <TB1> INFO: PixTestAlive::maskTest() done
[14:03:29.815] <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
[14:03:29.816] <TB1> INFO: ----------------------------------------------------------------------
[14:03:29.816] <TB1> INFO: PixTestAlive::addressDecodingTest() ntrig = 50, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[14:03:29.816] <TB1> INFO: ----------------------------------------------------------------------
[14:03:30.126] <TB1> INFO: Expecting 208000 events.
[14:03:43.310] <TB1> INFO: 208000 events read in total (12469ms).
[14:03:43.311] <TB1> INFO: Test took 13493ms.
[14:03:43.319] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[14:03:43.549] <TB1> INFO: PixTestAlive::addressDecodingTest() done
[14:03:43.549] <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
[14:03:43.550] <TB1> INFO: PixTestAlive::doTest() done, duration: 36 seconds
[14:03:43.588] <TB1> INFO: enter test to run
[14:03:43.588] <TB1> INFO: test: delay setting parameters: ->2<-
[14:03:43.588] <TB1> INFO: delay test by 2 seconds...
[14:03:45.588] <TB1> INFO: enter test to run
[14:03:45.588] <TB1> INFO: test: HighRate no parameter change
[14:03:45.588] <TB1> INFO: running: highrate
[14:03:45.588] <TB1> INFO: readGainPedestalParameters /usr/local/data/M2120_XrayQualification_2015-11-23_13h32m_1448281936//015_HREfficiency_200//phCalibrationFitErr35_C0.dat .. /usr/local/data/M2120_XrayQualification_2015-11-23_13h32m_1448281936//015_HREfficiency_200//phCalibrationFitErr35_C15.dat
[14:03:45.738] <TB1> INFO: ----------------------------------------------------------------------
[14:03:45.738] <TB1> INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[14:03:45.738] <TB1> INFO: ----------------------------------------------------------------------
[14:03:46.356] <TB1> INFO: Expecting 208000 events.
[14:04:05.044] <TB1> ERROR: <datapipe.cc/CheckEventValidity:L488> Channel 1 Number of ROCs (5) != Token Chain Length (4)

[14:04:05.045] <TB1> ERROR: <datapipe.cc/Read:L164> Dumping the flawed event +- 3 events:

[14:04:05.045] <TB1> ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a135 80b1 40e8 458 2aaa 55d 2a66 603 2644 698 2064 40e8 15 282c 14 2a4f 458 2aa4 688 268a 715 2a6c 745 2068 81a 244c 855 226e 40e8 48 246c 8a 2465 215 2285 28d 2a6d 299 2a81 458 2aaf 650 2a47 84a 2248 85a 2640 40e8 99 2a2c 152 246a 299 24a2 294 2280 458 2aaf e002 c000

[14:04:05.045] <TB1> ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a12f 8000 40e8 458 2aa2 543 2260 744 2220 853 246d 40e8 90 286f 99 2668 d3 2680 e2 228e 14a 268f 14c 268c 458 2a88 480 2662 648 2262 40ea 344 2260 348 2283 348 2a8f 458 2aae 602 286f 40e9 59 22c0 2d8 288e 2cc 2268 458 2aaf 4c8 244f 849 2881 e002 c000

[14:04:05.045] <TB1> ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a130 8040 40ea 115 2a64 211 246b 211 2685 352 266f 458 2aa2 488 2243 492 2a82 4cd 2a6f 40ea 289 2685 440 262b 458 2a85 6dd 2a6e 842 2846 844 264f 40e8 458 2aa6 48d 2a6f 559 288c 681 2a69 862 2a8e 40e9 20a 2688 40c 226f 44b 26a4 458 2aae 4cc 226c e002 c000

[14:04:05.045] <TB1> ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a131 80b1 40e8 2c4 226a 458 2aaf 6d5 204f 40e8 102 208c 515 2462 645 26af 64b 2a6a 6cb 2041 6c3 2a2e 749 2682 40e9 10 2263 14 2280 e0 288d 293 2082 289 2683 44c 2a4e 458 2aae 483 2682 81c 2845 40e9 5d 2462 2c3 244f 44a 2664 458 2a8f 495 2663 e002 c000

[14:04:05.045] <TB1> ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a132 80c0 40e9 98 2a62 e1 248f 113 24a0 2a0 244d 413 2462 458 2a8f 40e9 c8 2468 11d 246f 109 2868 145 2889 412 206f 404 2889 40c 2a6f 410 2664 458 2a6f 459 2a42 4d2 2862 748 284a 40e9 400 2644 409 264f 458 2aa7 40e8 340 244c 40eb 246f 41a 24a1 40b 266c 458 2aac 48c 2044 508 244c 508 206d e002 c000

[14:04:05.045] <TB1> ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a133 8000 40e8 43 2284 240 244a 30c 2462 458 2ac1 649 2684 718 2665 750 282e 854 224e 40e8 400 266c 440 2449 458 2aa5 40e8 2d1 284c 411 2080 503 2a68 6d5 244e 6c3 224f 40e8 2cc 244f 2cc 2068 2c5 2865 442 226f 458 2ac6 682 264f fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff e002 c000

[14:04:05.045] <TB1> ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a134 8040 40e8 458 2a8f 681 2066 705 2a6d 721 266c 75b 264a 40e9 305 2468 304 246e 303 2049 309 2686 320 2aa5 458 2aa4 61a 2682 6db 2462 6d4 204f 6d2 2243 749 206a 40e8 458 2ac3 549 224a 40e8 10c 2865 344 284f 41b 24a4 708 284c e002 c000

[14:04:24.284] <TB1> INFO: 198400 events read in total (37401ms).
[14:04:26.070] <TB1> INFO: 208000 events read in total (39187ms).
[14:04:26.089] <TB1> INFO: Test took 40348ms.
[14:04:27.281] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[14:04:27.872] <TB1> INFO: number of dead pixels (per ROC): 4 1 0 0 0 0 0 0 0 0 0 0 1 0 2 1
[14:04:27.872] <TB1> INFO: number of red-efficiency pixels: 1823 1828 2115 3488 3399 4108 4048 4143 4103 4019 4051 3915 3683 1668 1762 2214
[14:04:27.872] <TB1> INFO: number of X-ray hits detected: 460400 477111 489592 735446 729252 1026733 944639 1093843 1001842 907544 927316 885802 758890 455054 469704 464922
[14:04:27.872] <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
[14:04:27.872] <TB1> INFO: number of Vcal hits detected: 205151 205280 200842 197679 198822 184144 187312 179403 186204 189461 188890 190626 196454 203349 205380 204366
[14:04:27.872] <TB1> INFO: Vcal hit fiducial efficiency (%): 98.8 98.8 97.2 95.6 96.1 89.6 91.1 87.1 90.3 91.9 91.7 92.4 95.1 98.2 98.9 98.4
[14:04:27.872] <TB1> INFO: Vcal hit overall efficiency (%): 98.6 98.7 96.6 95.0 95.6 88.5 90.1 86.3 89.5 91.1 90.8 91.6 94.4 97.8 98.7 98.3
[14:04:27.872] <TB1> INFO: X-ray hit rate [MHz/cm2]: 134.9 139.8 143.5 215.6 213.7 300.9 276.9 320.6 293.6 266.0 271.8 259.6 222.4 133.4 137.7 136.3
[14:04:27.872] <TB1> INFO: PixTestHighRate::doXPixelAlive() done
[14:04:27.901] <TB1> INFO: PixTest:: pg_setup set to default.
[14:04:27.913] <TB1> INFO: enter test to run
[14:04:27.913] <TB1> INFO: test: no parameter change
[14:04:28.045] <TB1> QUIET: Connection to board 147 closed.
[14:04:28.126] <TB1> INFO: pXar: this is the end, my friend
commander_HREfficiency.log
[14:04:43.689] <TB1> INFO: *** Welcome to pxar ***
[14:04:43.689] <TB1> INFO: *** Today: 2015/11/23
[14:04:44.231] <TB1> INFO: *** Version: 206d-dirty
[14:04:44.232] <TB1> INFO: readRocDacs: /usr/local/data/M2120_XrayQualification_2015-11-23_13h32m_1448281936//016_HREfficiency_250//dacParameters35_C0.dat .. /usr/local/data/M2120_XrayQualification_2015-11-23_13h32m_1448281936//016_HREfficiency_250//dacParameters35_C15.dat
[14:04:44.232] <TB1> INFO: readTbmDacs: /usr/local/data/M2120_XrayQualification_2015-11-23_13h32m_1448281936//016_HREfficiency_250//tbmParameters_C0a.dat .. /usr/local/data/M2120_XrayQualification_2015-11-23_13h32m_1448281936//016_HREfficiency_250//tbmParameters_C0b.dat
[14:04:44.232] <TB1> INFO: readMaskFile: /usr/local/data/M2120_XrayQualification_2015-11-23_13h32m_1448281936//016_HREfficiency_250//defaultMaskFile.dat
[14:04:44.232] <TB1> INFO: readTrimFile: /usr/local/data/M2120_XrayQualification_2015-11-23_13h32m_1448281936//016_HREfficiency_250//trimParameters35_C0.dat .. /usr/local/data/M2120_XrayQualification_2015-11-23_13h32m_1448281936//016_HREfficiency_250//trimParameters35_C15.dat
[14:04:44.304] <TB1> INFO: clk: 4
[14:04:44.304] <TB1> INFO: ctr: 4
[14:04:44.304] <TB1> INFO: sda: 19
[14:04:44.304] <TB1> INFO: tin: 9
[14:04:44.304] <TB1> INFO: level: 15
[14:04:44.304] <TB1> INFO: triggerdelay: 0
[14:04:44.304] <TB1> QUIET: Instanciating API for pxar v2.6.0+26~g89693ff
[14:04:44.304] <TB1> INFO: Log level: INFO
[14:04:44.317] <TB1> INFO: Found DTB DTB_WWXTQT
[14:04:44.332] <TB1> QUIET: Connection to board DTB_WWXTQT opened.
[14:04:44.336] <TB1> INFO: DTB startup information
--- DTB info------------------------------------------
Board id: 147
HW version: DTB1.2
FW version: 4.2
SW version: 4.5
USB id: DTB_WWXTQT
MAC address: 40D855118093
Hostname: pixelDTB147
Comment:
------------------------------------------------------
[14:04:44.338] <TB1> INFO: RPC call hashes of host and DTB match: 398089610
[14:04:45.915] <TB1> INFO: DUT info:
[14:04:45.915] <TB1> INFO: The DUT currently contains the following objects:
[14:04:45.915] <TB1> INFO: 2 TBM Cores tbm09c (2 ON)
[14:04:45.915] <TB1> INFO: TBM Core alpha (0): 7 registers set
[14:04:45.915] <TB1> INFO: TBM Core beta (1): 7 registers set
[14:04:45.915] <TB1> INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[14:04:45.915] <TB1> INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[14:04:45.915] <TB1> INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[14:04:45.915] <TB1> INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[14:04:45.915] <TB1> INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[14:04:45.915] <TB1> INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[14:04:45.915] <TB1> INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[14:04:45.915] <TB1> INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[14:04:45.915] <TB1> INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[14:04:45.915] <TB1> INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[14:04:45.915] <TB1> INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[14:04:45.915] <TB1> INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[14:04:45.916] <TB1> INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[14:04:45.916] <TB1> INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[14:04:45.916] <TB1> INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[14:04:45.916] <TB1> INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[14:04:45.916] <TB1> INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[14:04:46.317] <TB1> INFO: enter 'restricted' command line mode
[14:04:46.317] <TB1> INFO: enter test to run
[14:04:46.317] <TB1> INFO: test: PixelAlive no parameter change
[14:04:46.317] <TB1> INFO: running: pixelalive
[14:04:46.323] <TB1> INFO: ######################################################################
[14:04:46.323] <TB1> INFO: PixTestAlive::doTest()
[14:04:46.323] <TB1> INFO: ######################################################################
[14:04:46.325] <TB1> INFO: ----------------------------------------------------------------------
[14:04:46.325] <TB1> INFO: PixTestAlive::aliveTest() ntrig = 50, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[14:04:46.325] <TB1> INFO: ----------------------------------------------------------------------
[14:04:46.666] <TB1> INFO: Expecting 208000 events.
[14:05:00.112] <TB1> INFO: 208000 events read in total (12728ms).
[14:05:00.114] <TB1> INFO: Test took 13788ms.
[14:05:00.124] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[14:05:00.396] <TB1> INFO: PixTestAlive::aliveTest() done
[14:05:00.396] <TB1> INFO: number of dead pixels (per ROC): 4 1 0 0 0 0 0 0 0 0 0 0 1 0 2 1
[14:05:00.397] <TB1> INFO: ----------------------------------------------------------------------
[14:05:00.398] <TB1> INFO: PixTestAlive::maskTest() ntrig = 50, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[14:05:00.398] <TB1> INFO: ----------------------------------------------------------------------
[14:05:00.734] <TB1> INFO: Expecting 208000 events.
[14:05:09.313] <TB1> INFO: 208000 events read in total (7863ms).
[14:05:09.315] <TB1> INFO: Test took 8915ms.
[14:05:09.315] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[14:05:09.315] <TB1> INFO: mask vs. old pixelAlive PixelAlive_C0_V0 .. PixelAlive_C15_V0
[14:05:09.628] <TB1> INFO: PixTestAlive::maskTest() done
[14:05:09.628] <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
[14:05:09.630] <TB1> INFO: ----------------------------------------------------------------------
[14:05:09.630] <TB1> INFO: PixTestAlive::addressDecodingTest() ntrig = 50, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[14:05:09.630] <TB1> INFO: ----------------------------------------------------------------------
[14:05:09.973] <TB1> INFO: Expecting 208000 events.
[14:05:23.662] <TB1> INFO: 208000 events read in total (12973ms).
[14:05:23.663] <TB1> INFO: Test took 14031ms.
[14:05:23.673] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[14:05:23.900] <TB1> INFO: PixTestAlive::addressDecodingTest() done
[14:05:23.900] <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
[14:05:23.900] <TB1> INFO: PixTestAlive::doTest() done, duration: 37 seconds
[14:05:23.932] <TB1> INFO: enter test to run
[14:05:23.932] <TB1> INFO: test: delay setting parameters: ->2<-
[14:05:23.932] <TB1> INFO: delay test by 2 seconds...
[14:05:25.932] <TB1> INFO: enter test to run
[14:05:25.932] <TB1> INFO: test: HighRate no parameter change
[14:05:25.932] <TB1> INFO: running: highrate
[14:05:25.932] <TB1> INFO: readGainPedestalParameters /usr/local/data/M2120_XrayQualification_2015-11-23_13h32m_1448281936//016_HREfficiency_250//phCalibrationFitErr35_C0.dat .. /usr/local/data/M2120_XrayQualification_2015-11-23_13h32m_1448281936//016_HREfficiency_250//phCalibrationFitErr35_C15.dat
[14:05:26.083] <TB1> INFO: ----------------------------------------------------------------------
[14:05:26.083] <TB1> INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[14:05:26.083] <TB1> INFO: ----------------------------------------------------------------------
[14:05:26.685] <TB1> INFO: Expecting 208000 events.
[14:05:38.645] <TB1> ERROR: <datapipe.cc/CheckEventValidity:L488> Channel 3 Number of ROCs (5) != Token Chain Length (4)

[14:05:38.645] <TB1> ERROR: <datapipe.cc/Read:L164> Dumping the flawed event +- 3 events:

[14:05:38.645] <TB1> ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a3c5 80c0 41e8 21d 2842 21b 2882 21b 248c 218 28a0 20d 2846 2d8 2a6b 762 264c 41e8 4d 264d 218 288d 55c 24c2 41e8 218 28a5 804 2441 41e8 c0 2689 218 2886 fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff e002 c000

[14:05:38.645] <TB1> ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a3bf 8040 41ea ca 2262 218 286e 290 2240 2d9 228a 615 286a 69b 2666 41ea d4 2486 218 288f 200 2461 55a 24a4 6cc 244f 6e2 2a64 41ea dc 2485 d9 226f 218 28a4 692 2065 41ea c0 2468 218 2886 4d5 2688 614 2465 614 264f 6d9 2666 708 2442 fff 2fff fff 2fff fff 2fff fff 2fff e002 c000

[14:05:38.645] <TB1> ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a3c0 80b1 41e9 49 264f d1 246f d1 2068 d2 2682 141 2289 218 28a0 44a 2660 459 2266 541 2886 41e9 4a 204f 218 286f 41e9 1c 284f 218 288f 212 224f 41e9 105 264d 218 2887 20d 2848 20d 2a62 fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff e002 c000

[14:05:38.645] <TB1> ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a3c1 80c0 41e9 40 264e c1 226e c8 226a 218 28a2 25b 28a0 255 28c2 6c2 2460 714 282f 741 2a48 41e9 218 288f 6c3 2685 41e9 89 2a41 9a 2a6d 218 288f 245 2a65 521 22a2 41e9 11 2461 161 28a2 218 2885 29a 284f 2c8 2469 fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff e002 c000

[14:05:38.645] <TB1> ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a3c2 8000 41e8 c4 2884 c4 226a 218 288a 4c9 2a4e 604 2a6f 41e8 b 2081 115 2a6f 218 288d 444 266a 554 2884 41e9 220 2865 218 28a8 41e9 218 288a 41eb 2844 654 204f fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff e002 c000

[14:05:38.645] <TB1> ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a3c3 8040 41e8 93 2a8e 28b 2486 302 24a0 30c 2a6f 503 2660 649 228a 41e9 218 28a2 240 2669 283 2666 41e8 218 28a4 852 2668 41e9 58 286a 218 28a0 242 2865 80d 2268 fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff e002 c000

[14:05:38.645] <TB1> ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a3c4 80b1 41e8 218 288a 401 2a8f 454 2a4e 859 2888 841 2aa3 41e8 218 288c 55a 286f 41e8 14 2845 b 2465 218 2886 253 2661 41e8 4c 2463 114 224f 218 2887 48b 248a fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff e002 c000

[14:06:07.358] <TB1> INFO: 169000 events read in total (40146ms).
[14:06:15.033] <TB1> INFO: 208000 events read in total (47821ms).
[14:06:15.056] <TB1> INFO: Test took 48969ms.
[14:06:16.515] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[14:06:17.203] <TB1> INFO: number of dead pixels (per ROC): 4 1 0 0 0 0 0 0 0 0 0 0 1 0 1 1
[14:06:17.203] <TB1> INFO: number of red-efficiency pixels: 2654 2705 2661 4008 4006 4160 4156 4160 4158 4143 4156 4127 4057 2203 2662 3114
[14:06:17.203] <TB1> INFO: number of X-ray hits detected: 571769 587775 609689 908910 914803 1274762 1184112 1363016 1247686 1130212 1150600 1095501 934920 562821 579942 575017
[14:06:17.203] <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
[14:06:17.203] <TB1> INFO: number of Vcal hits detected: 202937 202988 195462 188904 189957 164201 169193 155018 166809 173765 172540 175883 186541 199411 203072 201233
[14:06:17.203] <TB1> INFO: Vcal hit fiducial efficiency (%): 97.9 97.8 94.9 91.7 92.2 80.4 82.8 75.8 81.3 84.8 84.3 85.7 90.7 96.6 97.9 97.1
[14:06:17.203] <TB1> INFO: Vcal hit overall efficiency (%): 97.6 97.6 94.0 90.8 91.3 78.9 81.3 74.5 80.2 83.5 83.0 84.6 89.7 95.9 97.6 96.7
[14:06:17.203] <TB1> INFO: X-ray hit rate [MHz/cm2]: 167.6 172.3 178.7 266.4 268.1 373.6 347.1 399.5 365.7 331.3 337.2 321.1 274.0 165.0 170.0 168.5
[14:06:17.203] <TB1> INFO: PixTestHighRate::doXPixelAlive() done
[14:06:17.233] <TB1> INFO: PixTest:: pg_setup set to default.
[14:06:17.246] <TB1> INFO: enter test to run
[14:06:17.246] <TB1> INFO: test: no parameter change
[14:06:17.327] <TB1> QUIET: Connection to board 147 closed.
[14:06:17.408] <TB1> INFO: pXar: this is the end, my friend
MoReWeb-v1.0.0-5-g82d9ff6 on branch psi46master