Test Date: 2015-09-01 13:21
Analysis date: 2016-05-26 03:58
Logfile
commander_PixelAlive.log
[11:22:37.286] <TB2> INFO: *** Welcome to pxar ***
[11:22:37.286] <TB2> INFO: *** Today: 2015/09/01
[11:22:37.286] <TB2> INFO: readRocDacs: /usr/local/data/M2096_XrayQualification_2015-09-01_13h21m_1441106515//000_PixelAlive_p17//dacParameters35_C0.dat .. /usr/local/data/M2096_XrayQualification_2015-09-01_13h21m_1441106515//000_PixelAlive_p17//dacParameters35_C15.dat
[11:22:37.287] <TB2> INFO: readTbmDacs: /usr/local/data/M2096_XrayQualification_2015-09-01_13h21m_1441106515//000_PixelAlive_p17//tbmParameters_C0a.dat .. /usr/local/data/M2096_XrayQualification_2015-09-01_13h21m_1441106515//000_PixelAlive_p17//tbmParameters_C0b.dat
[11:22:37.287] <TB2> INFO: readMaskFile: /usr/local/data/M2096_XrayQualification_2015-09-01_13h21m_1441106515//000_PixelAlive_p17//defaultMaskFile.dat
[11:22:37.287] <TB2> INFO: readTrimFile: /usr/local/data/M2096_XrayQualification_2015-09-01_13h21m_1441106515//000_PixelAlive_p17//trimParameters35_C0.dat .. /usr/local/data/M2096_XrayQualification_2015-09-01_13h21m_1441106515//000_PixelAlive_p17//trimParameters35_C15.dat
[11:22:37.359] <TB2> INFO: clk: 4
[11:22:37.359] <TB2> INFO: ctr: 4
[11:22:37.359] <TB2> INFO: sda: 19
[11:22:37.359] <TB2> INFO: tin: 9
[11:22:37.360] <TB2> INFO: level: 15
[11:22:37.360] <TB2> INFO: triggerdelay: 0
[11:22:37.360] <TB2> QUIET: Instanciating API for pxar prod-10
[11:22:37.360] <TB2> INFO: Log level: INFO
[11:22:37.371] <TB2> INFO: Found DTB DTB_WXENWR
[11:22:37.381] <TB2> QUIET: Connection to board DTB_WXENWR opened.
[11:22:37.384] <TB2> INFO: DTB startup information
--- DTB info------------------------------------------
Board id: 162
HW version: DTB1.2
FW version: 4.2
SW version: 4.4
USB id: DTB_WXENWR
MAC address: 40D8551180A2
Hostname: pixelDTB162
Comment:
------------------------------------------------------
[11:22:37.387] <TB2> INFO: RPC call hashes of host and DTB match: 397073690
[11:22:38.920] <TB2> INFO: DUT info:
[11:22:38.920] <TB2> INFO: The DUT currently contains the following objects:
[11:22:38.920] <TB2> INFO: 2 TBM Cores tbm09c (2 ON)
[11:22:38.920] <TB2> INFO: TBM Core alpha (0): 7 registers set
[11:22:38.920] <TB2> INFO: TBM Core beta (1): 7 registers set
[11:22:38.920] <TB2> INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[11:22:38.920] <TB2> INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[11:22:38.920] <TB2> INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[11:22:38.920] <TB2> INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[11:22:38.920] <TB2> INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[11:22:38.920] <TB2> INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[11:22:38.920] <TB2> INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[11:22:38.920] <TB2> INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[11:22:38.920] <TB2> INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[11:22:38.920] <TB2> INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[11:22:38.920] <TB2> INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[11:22:38.920] <TB2> INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[11:22:38.920] <TB2> INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[11:22:38.920] <TB2> INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[11:22:38.920] <TB2> INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[11:22:38.920] <TB2> INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[11:22:38.920] <TB2> INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[11:22:39.322] <TB2> INFO: enter 'restricted' command line mode
[11:22:39.322] <TB2> INFO: enter test to run
[11:22:39.322] <TB2> INFO: test: PixelAlive no parameter change
[11:22:39.322] <TB2> INFO: running: pixelalive
[11:22:39.332] <TB2> INFO: ######################################################################
[11:22:39.332] <TB2> INFO: PixTestAlive::doTest()
[11:22:39.332] <TB2> INFO: ######################################################################
[11:22:39.334] <TB2> INFO: ----------------------------------------------------------------------
[11:22:39.334] <TB2> INFO: PixTestAlive::aliveTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[11:22:39.334] <TB2> INFO: ----------------------------------------------------------------------
[11:22:39.671] <TB2> INFO: Expecting 41600 events.
[11:22:44.132] <TB2> INFO: 41600 events read in total (3741ms).
[11:22:44.210] <TB2> INFO: Test took 4873ms.
[11:22:44.221] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[11:22:44.488] <TB2> INFO: PixTestAlive::aliveTest() done
[11:22:44.488] <TB2> INFO: number of dead pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 1 0 0 0 0
[11:22:44.490] <TB2> INFO: ----------------------------------------------------------------------
[11:22:44.490] <TB2> INFO: PixTestAlive::maskTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[11:22:44.490] <TB2> INFO: ----------------------------------------------------------------------
[11:22:44.797] <TB2> INFO: Expecting 41600 events.
[11:22:47.886] <TB2> INFO: 41600 events read in total (2371ms).
[11:22:47.887] <TB2> INFO: Test took 3395ms.
[11:22:47.887] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[11:22:47.887] <TB2> INFO: mask vs. old pixelAlive PixelAlive_C0_V0 .. PixelAlive_C15_V0
[11:22:48.217] <TB2> INFO: PixTestAlive::maskTest() done
[11:22:48.217] <TB2> INFO: number of mask-defect pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[11:22:48.218] <TB2> INFO: ----------------------------------------------------------------------
[11:22:48.218] <TB2> INFO: PixTestAlive::addressDecodingTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[11:22:48.218] <TB2> INFO: ----------------------------------------------------------------------
[11:22:48.524] <TB2> INFO: Expecting 41600 events.
[11:22:52.797] <TB2> INFO: 41600 events read in total (3556ms).
[11:22:52.798] <TB2> INFO: Test took 4578ms.
[11:22:52.804] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[11:22:53.112] <TB2> INFO: PixTestAlive::addressDecodingTest() done
[11:22:53.112] <TB2> INFO: number of address-decoding pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[11:22:53.112] <TB2> INFO: PixTestAlive::doTest() done, duration: 13 seconds
[11:22:53.143] <TB2> INFO: enter test to run
[11:22:53.143] <TB2> INFO: test: no parameter change
[11:22:53.341] <TB2> QUIET: Connection to board 162 closed.
[11:22:53.421] <TB2> INFO: pXar: this is the end, my friend
commander_RetrimHotPixels.log
[11:27:42.859] <TB2> INFO: *** Welcome to pxar ***
[11:27:42.859] <TB2> INFO: *** Today: 2015/09/01
[11:27:42.859] <TB2> INFO: readRocDacs: /usr/local/data/M2096_XrayQualification_2015-09-01_13h21m_1441106515//002_RetrimHotPixels_150//dacParameters35_C0.dat .. /usr/local/data/M2096_XrayQualification_2015-09-01_13h21m_1441106515//002_RetrimHotPixels_150//dacParameters35_C15.dat
[11:27:42.859] <TB2> INFO: readTbmDacs: /usr/local/data/M2096_XrayQualification_2015-09-01_13h21m_1441106515//002_RetrimHotPixels_150//tbmParameters_C0a.dat .. /usr/local/data/M2096_XrayQualification_2015-09-01_13h21m_1441106515//002_RetrimHotPixels_150//tbmParameters_C0b.dat
[11:27:42.859] <TB2> INFO: readMaskFile: /usr/local/data/M2096_XrayQualification_2015-09-01_13h21m_1441106515//002_RetrimHotPixels_150//defaultMaskFile.dat
[11:27:42.859] <TB2> INFO: readTrimFile: /usr/local/data/M2096_XrayQualification_2015-09-01_13h21m_1441106515//002_RetrimHotPixels_150//trimParameters35_C0.dat .. /usr/local/data/M2096_XrayQualification_2015-09-01_13h21m_1441106515//002_RetrimHotPixels_150//trimParameters35_C15.dat
[11:27:42.934] <TB2> INFO: clk: 4
[11:27:42.934] <TB2> INFO: ctr: 4
[11:27:42.934] <TB2> INFO: sda: 19
[11:27:42.934] <TB2> INFO: tin: 9
[11:27:42.934] <TB2> INFO: level: 15
[11:27:42.934] <TB2> INFO: triggerdelay: 0
[11:27:42.934] <TB2> QUIET: Instanciating API for pxar prod-10
[11:27:42.934] <TB2> INFO: Log level: INFO
[11:27:42.947] <TB2> INFO: Found DTB DTB_WXENWR
[11:27:42.956] <TB2> QUIET: Connection to board DTB_WXENWR opened.
[11:27:42.960] <TB2> INFO: DTB startup information
--- DTB info------------------------------------------
Board id: 162
HW version: DTB1.2
FW version: 4.2
SW version: 4.4
USB id: DTB_WXENWR
MAC address: 40D8551180A2
Hostname: pixelDTB162
Comment:
------------------------------------------------------
[11:27:42.963] <TB2> INFO: RPC call hashes of host and DTB match: 397073690
[11:27:44.507] <TB2> INFO: DUT info:
[11:27:44.507] <TB2> INFO: The DUT currently contains the following objects:
[11:27:44.508] <TB2> INFO: 2 TBM Cores tbm09c (2 ON)
[11:27:44.508] <TB2> INFO: TBM Core alpha (0): 7 registers set
[11:27:44.508] <TB2> INFO: TBM Core beta (1): 7 registers set
[11:27:44.508] <TB2> INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[11:27:44.508] <TB2> INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[11:27:44.508] <TB2> INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[11:27:44.508] <TB2> INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[11:27:44.508] <TB2> INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[11:27:44.508] <TB2> INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[11:27:44.508] <TB2> INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[11:27:44.508] <TB2> INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[11:27:44.508] <TB2> INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[11:27:44.508] <TB2> INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[11:27:44.508] <TB2> INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[11:27:44.508] <TB2> INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[11:27:44.508] <TB2> INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[11:27:44.508] <TB2> INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[11:27:44.509] <TB2> INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[11:27:44.509] <TB2> INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[11:27:44.509] <TB2> INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[11:27:44.910] <TB2> INFO: enter 'restricted' command line mode
[11:27:44.910] <TB2> INFO: enter test to run
[11:27:44.910] <TB2> INFO: test: delay setting parameters: ->5<-
[11:27:44.910] <TB2> INFO: delay test by 5 seconds...
[11:27:49.910] <TB2> INFO: enter test to run
[11:27:49.910] <TB2> INFO: test: highrate no parameter change
[11:27:49.910] <TB2> INFO: running: highrate
[11:27:49.911] <TB2> INFO: readGainPedestalParameters /usr/local/data/M2096_XrayQualification_2015-09-01_13h21m_1441106515//002_RetrimHotPixels_150//phCalibrationFitErr35_C0.dat .. /usr/local/data/M2096_XrayQualification_2015-09-01_13h21m_1441106515//002_RetrimHotPixels_150//phCalibrationFitErr35_C15.dat
[11:27:50.048] <TB2> INFO: ----------------------------------------------------------------------
[11:27:50.048] <TB2> INFO: PixTest::trimHotPixels() running for 1 seconds with 100 kHz trigger rate
[11:27:50.048] <TB2> INFO: ----------------------------------------------------------------------
[11:27:50.048] <TB2> INFO: THR = 200, corresponding to ~ 533.333 MHz/cm2
[11:27:50.048] <TB2> INFO: edge/corner pixel THR is adjusted
[11:27:50.048] <TB2> INFO: PixTestHighRate::trimHotPixels: step 0...
[11:27:51.013] <TB2> INFO: Collecting data for 1 seconds...
[11:27:52.014] <TB2> INFO: Done with hot pixel readout
[11:27:57.093] <TB2> INFO: PixTest:: pg_setup set to default.
[11:27:57.094] <TB2> INFO: 4 hot pixels found in step 0
[11:27:58.064] <TB2> INFO: Collecting data for 1 seconds...
[11:27:59.065] <TB2> INFO: Done with hot pixel readout
[11:28:04.218] <TB2> INFO: PixTest:: pg_setup set to default.
[11:28:04.219] <TB2> INFO: 3 hot pixels found in step 1
[11:28:05.189] <TB2> INFO: Collecting data for 1 seconds...
[11:28:06.190] <TB2> INFO: Done with hot pixel readout
[11:28:11.189] <TB2> INFO: PixTest:: pg_setup set to default.
[11:28:11.190] <TB2> INFO: 2 hot pixels found in step 2
[11:28:12.160] <TB2> INFO: Collecting data for 1 seconds...
[11:28:13.161] <TB2> INFO: Done with hot pixel readout
[11:28:18.198] <TB2> INFO: PixTest:: pg_setup set to default.
[11:28:18.199] <TB2> INFO: 0 hot pixels found in step 3
[11:28:18.210] <TB2> INFO: 0 hot pixels could not be trimmed and have been masked.
[11:28:18.238] <TB2> INFO: PixTest::trimHotPixels() done
[11:28:18.238] <TB2> INFO: write trim parameters into /usr/local/data/M2096_XrayQualification_2015-09-01_13h21m_1441106515//002_RetrimHotPixels_150//trimParameters35_C0.dat
[11:28:18.244] <TB2> INFO: write trim parameters into /usr/local/data/M2096_XrayQualification_2015-09-01_13h21m_1441106515//002_RetrimHotPixels_150//trimParameters35_C1.dat
[11:28:18.250] <TB2> INFO: write trim parameters into /usr/local/data/M2096_XrayQualification_2015-09-01_13h21m_1441106515//002_RetrimHotPixels_150//trimParameters35_C2.dat
[11:28:18.256] <TB2> INFO: write trim parameters into /usr/local/data/M2096_XrayQualification_2015-09-01_13h21m_1441106515//002_RetrimHotPixels_150//trimParameters35_C3.dat
[11:28:18.262] <TB2> INFO: write trim parameters into /usr/local/data/M2096_XrayQualification_2015-09-01_13h21m_1441106515//002_RetrimHotPixels_150//trimParameters35_C4.dat
[11:28:18.268] <TB2> INFO: write trim parameters into /usr/local/data/M2096_XrayQualification_2015-09-01_13h21m_1441106515//002_RetrimHotPixels_150//trimParameters35_C5.dat
[11:28:18.274] <TB2> INFO: write trim parameters into /usr/local/data/M2096_XrayQualification_2015-09-01_13h21m_1441106515//002_RetrimHotPixels_150//trimParameters35_C6.dat
[11:28:18.280] <TB2> INFO: write trim parameters into /usr/local/data/M2096_XrayQualification_2015-09-01_13h21m_1441106515//002_RetrimHotPixels_150//trimParameters35_C7.dat
[11:28:18.287] <TB2> INFO: write trim parameters into /usr/local/data/M2096_XrayQualification_2015-09-01_13h21m_1441106515//002_RetrimHotPixels_150//trimParameters35_C8.dat
[11:28:18.293] <TB2> INFO: write trim parameters into /usr/local/data/M2096_XrayQualification_2015-09-01_13h21m_1441106515//002_RetrimHotPixels_150//trimParameters35_C9.dat
[11:28:18.299] <TB2> INFO: write trim parameters into /usr/local/data/M2096_XrayQualification_2015-09-01_13h21m_1441106515//002_RetrimHotPixels_150//trimParameters35_C10.dat
[11:28:18.305] <TB2> INFO: write trim parameters into /usr/local/data/M2096_XrayQualification_2015-09-01_13h21m_1441106515//002_RetrimHotPixels_150//trimParameters35_C11.dat
[11:28:18.311] <TB2> INFO: write trim parameters into /usr/local/data/M2096_XrayQualification_2015-09-01_13h21m_1441106515//002_RetrimHotPixels_150//trimParameters35_C12.dat
[11:28:18.317] <TB2> INFO: write trim parameters into /usr/local/data/M2096_XrayQualification_2015-09-01_13h21m_1441106515//002_RetrimHotPixels_150//trimParameters35_C13.dat
[11:28:18.323] <TB2> INFO: write trim parameters into /usr/local/data/M2096_XrayQualification_2015-09-01_13h21m_1441106515//002_RetrimHotPixels_150//trimParameters35_C14.dat
[11:28:18.329] <TB2> INFO: write trim parameters into /usr/local/data/M2096_XrayQualification_2015-09-01_13h21m_1441106515//002_RetrimHotPixels_150//trimParameters35_C15.dat
[11:28:18.335] <TB2> INFO: write masked pixels into /usr/local/data/M2096_XrayQualification_2015-09-01_13h21m_1441106515//002_RetrimHotPixels_150//defaultMaskFile.dat
[11:28:18.366] <TB2> INFO: enter test to run
[11:28:18.366] <TB2> INFO: test: no parameter change
[11:28:18.553] <TB2> QUIET: Connection to board 162 closed.
[11:28:18.632] <TB2> INFO: pXar: this is the end, my friend
commander_HRData.log
[11:28:44.188] <TB2> INFO: *** Welcome to pxar ***
[11:28:44.188] <TB2> INFO: *** Today: 2015/09/01
[11:28:44.188] <TB2> INFO: readRocDacs: /usr/local/data/M2096_XrayQualification_2015-09-01_13h21m_1441106515//003_HRData_50//dacParameters35_C0.dat .. /usr/local/data/M2096_XrayQualification_2015-09-01_13h21m_1441106515//003_HRData_50//dacParameters35_C15.dat
[11:28:44.189] <TB2> INFO: readTbmDacs: /usr/local/data/M2096_XrayQualification_2015-09-01_13h21m_1441106515//003_HRData_50//tbmParameters_C0a.dat .. /usr/local/data/M2096_XrayQualification_2015-09-01_13h21m_1441106515//003_HRData_50//tbmParameters_C0b.dat
[11:28:44.190] <TB2> INFO: readMaskFile: /usr/local/data/M2096_XrayQualification_2015-09-01_13h21m_1441106515//003_HRData_50//defaultMaskFile.dat
[11:28:44.190] <TB2> INFO: readTrimFile: /usr/local/data/M2096_XrayQualification_2015-09-01_13h21m_1441106515//003_HRData_50//trimParameters35_C0.dat .. /usr/local/data/M2096_XrayQualification_2015-09-01_13h21m_1441106515//003_HRData_50//trimParameters35_C15.dat
[11:28:44.270] <TB2> INFO: clk: 4
[11:28:44.270] <TB2> INFO: ctr: 4
[11:28:44.270] <TB2> INFO: sda: 19
[11:28:44.270] <TB2> INFO: tin: 9
[11:28:44.270] <TB2> INFO: level: 15
[11:28:44.270] <TB2> INFO: triggerdelay: 0
[11:28:44.270] <TB2> QUIET: Instanciating API for pxar prod-10
[11:28:44.270] <TB2> INFO: Log level: INFO
[11:28:44.283] <TB2> INFO: Found DTB DTB_WXENWR
[11:28:44.292] <TB2> QUIET: Connection to board DTB_WXENWR opened.
[11:28:44.296] <TB2> INFO: DTB startup information
--- DTB info------------------------------------------
Board id: 162
HW version: DTB1.2
FW version: 4.2
SW version: 4.4
USB id: DTB_WXENWR
MAC address: 40D8551180A2
Hostname: pixelDTB162
Comment:
------------------------------------------------------
[11:28:44.298] <TB2> INFO: RPC call hashes of host and DTB match: 397073690
[11:28:45.838] <TB2> INFO: DUT info:
[11:28:45.838] <TB2> INFO: The DUT currently contains the following objects:
[11:28:45.838] <TB2> INFO: 2 TBM Cores tbm09c (2 ON)
[11:28:45.838] <TB2> INFO: TBM Core alpha (0): 7 registers set
[11:28:45.838] <TB2> INFO: TBM Core beta (1): 7 registers set
[11:28:45.838] <TB2> INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[11:28:45.838] <TB2> INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[11:28:45.839] <TB2> INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[11:28:45.839] <TB2> INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[11:28:45.839] <TB2> INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[11:28:45.839] <TB2> INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[11:28:45.839] <TB2> INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[11:28:45.839] <TB2> INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[11:28:45.839] <TB2> INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[11:28:45.839] <TB2> INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[11:28:45.839] <TB2> INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[11:28:45.839] <TB2> INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[11:28:45.839] <TB2> INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[11:28:45.839] <TB2> INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[11:28:45.839] <TB2> INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[11:28:45.839] <TB2> INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[11:28:45.839] <TB2> INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[11:28:46.240] <TB2> INFO: enter 'restricted' command line mode
[11:28:46.240] <TB2> INFO: enter test to run
[11:28:46.240] <TB2> INFO: test: delay setting parameters: ->5<-
[11:28:46.240] <TB2> INFO: delay test by 5 seconds...
[11:28:51.241] <TB2> INFO: enter test to run
[11:28:51.241] <TB2> INFO: test: Xray no parameter change
[11:28:51.241] <TB2> INFO: running: xray
[11:28:51.241] <TB2> INFO: readGainPedestalParameters /usr/local/data/M2096_XrayQualification_2015-09-01_13h21m_1441106515//003_HRData_50//phCalibrationFitErr35_C0.dat .. /usr/local/data/M2096_XrayQualification_2015-09-01_13h21m_1441106515//003_HRData_50//phCalibrationFitErr35_C15.dat
[11:28:51.394] <TB2> INFO: ######################################################################
[11:28:51.394] <TB2> INFO: PixTestXray::doTest()
[11:28:51.394] <TB2> INFO: ######################################################################
[11:28:51.394] <TB2> INFO: ----------------------------------------------------------------------
[11:28:51.394] <TB2> INFO: PixTestXray::doPhRun() fParRunSeconds = 100
[11:28:51.394] <TB2> INFO: ----------------------------------------------------------------------
[11:28:52.368] <TB2> INFO: PixTestXray::doPhRun start TriggerLoop with trigger frequency 100 kHz, period 405 and duration 100 seconds, fEventsMax = 10000000
[11:28:58.243] <TB2> INFO: run duration 5 seconds, buffer almost full (81%), pausing triggers.
[11:29:18.560] <TB2> INFO: Resuming triggers.
[11:29:24.439] <TB2> INFO: run duration 11 seconds, buffer almost full (81%), pausing triggers.
[11:29:45.229] <TB2> INFO: Resuming triggers.
[11:29:51.102] <TB2> INFO: run duration 17 seconds, buffer almost full (81%), pausing triggers.
[11:30:12.173] <TB2> INFO: Resuming triggers.
[11:30:18.052] <TB2> INFO: run duration 23 seconds, buffer almost full (81%), pausing triggers.
[11:30:37.995] <TB2> INFO: Resuming triggers.
[11:30:43.871] <TB2> INFO: run duration 29 seconds, buffer almost full (81%), pausing triggers.
[11:31:03.567] <TB2> INFO: Resuming triggers.
[11:31:09.444] <TB2> INFO: run duration 35 seconds, buffer almost full (81%), pausing triggers.
[11:31:31.266] <TB2> INFO: Resuming triggers.
[11:31:37.141] <TB2> INFO: run duration 41 seconds, buffer almost full (81%), pausing triggers.
[11:31:58.468] <TB2> INFO: Resuming triggers.
[11:32:04.347] <TB2> INFO: run duration 46 seconds, buffer almost full (81%), pausing triggers.
[11:32:24.834] <TB2> INFO: Resuming triggers.
[11:32:30.709] <TB2> INFO: run duration 52 seconds, buffer almost full (81%), pausing triggers.
[11:32:50.648] <TB2> INFO: Resuming triggers.
[11:32:56.531] <TB2> INFO: run duration 58 seconds, buffer almost full (81%), pausing triggers.
[11:33:18.153] <TB2> INFO: Resuming triggers.
[11:33:24.031] <TB2> INFO: run duration 64 seconds, buffer almost full (81%), pausing triggers.
[11:33:44.937] <TB2> INFO: Resuming triggers.
[11:33:50.820] <TB2> INFO: run duration 70 seconds, buffer almost full (81%), pausing triggers.
[11:34:11.040] <TB2> INFO: Resuming triggers.
[11:34:16.917] <TB2> INFO: run duration 76 seconds, buffer almost full (81%), pausing triggers.
[11:34:36.674] <TB2> INFO: Resuming triggers.
[11:34:42.551] <TB2> INFO: run duration 82 seconds, buffer almost full (81%), pausing triggers.
[11:35:04.130] <TB2> INFO: Resuming triggers.
[11:35:10.010] <TB2> INFO: run duration 88 seconds, buffer almost full (81%), pausing triggers.
[11:35:30.770] <TB2> INFO: Resuming triggers.
[11:35:36.657] <TB2> INFO: run duration 93 seconds, buffer almost full (81%), pausing triggers.
[11:35:56.795] <TB2> INFO: Resuming triggers.
[11:36:02.676] <TB2> INFO: run duration 99 seconds, buffer almost full (81%), pausing triggers.
[11:36:22.632] <TB2> INFO: Resuming triggers.
[11:36:22.792] <TB2> INFO: data taking finished, elapsed time: 100 seconds.
[11:36:23.784] <TB2> INFO: PixTest:: pg_setup set to default.
[11:36:23.786] <TB2> INFO: PixTestXray::doPhRun() done
[11:36:23.786] <TB2> INFO: PixTestXray::doTest() done
[11:36:23.971] <TB2> INFO: enter test to run
[11:36:23.972] <TB2> INFO: test: no parameter change
[11:36:24.176] <TB2> QUIET: Connection to board 162 closed.
[11:36:24.259] <TB2> INFO: pXar: this is the end, my friend
commander_HRData.log
[11:38:43.177] <TB2> INFO: *** Welcome to pxar ***
[11:38:43.177] <TB2> INFO: *** Today: 2015/09/01
[11:38:43.178] <TB2> INFO: readRocDacs: /usr/local/data/M2096_XrayQualification_2015-09-01_13h21m_1441106515//004_HRData_150//dacParameters35_C0.dat .. /usr/local/data/M2096_XrayQualification_2015-09-01_13h21m_1441106515//004_HRData_150//dacParameters35_C15.dat
[11:38:43.180] <TB2> INFO: readTbmDacs: /usr/local/data/M2096_XrayQualification_2015-09-01_13h21m_1441106515//004_HRData_150//tbmParameters_C0a.dat .. /usr/local/data/M2096_XrayQualification_2015-09-01_13h21m_1441106515//004_HRData_150//tbmParameters_C0b.dat
[11:38:43.180] <TB2> INFO: readMaskFile: /usr/local/data/M2096_XrayQualification_2015-09-01_13h21m_1441106515//004_HRData_150//defaultMaskFile.dat
[11:38:43.180] <TB2> INFO: readTrimFile: /usr/local/data/M2096_XrayQualification_2015-09-01_13h21m_1441106515//004_HRData_150//trimParameters35_C0.dat .. /usr/local/data/M2096_XrayQualification_2015-09-01_13h21m_1441106515//004_HRData_150//trimParameters35_C15.dat
[11:38:43.261] <TB2> INFO: clk: 4
[11:38:43.261] <TB2> INFO: ctr: 4
[11:38:43.261] <TB2> INFO: sda: 19
[11:38:43.261] <TB2> INFO: tin: 9
[11:38:43.261] <TB2> INFO: level: 15
[11:38:43.261] <TB2> INFO: triggerdelay: 0
[11:38:43.261] <TB2> QUIET: Instanciating API for pxar prod-10
[11:38:43.261] <TB2> INFO: Log level: INFO
[11:38:43.275] <TB2> INFO: Found DTB DTB_WXENWR
[11:38:43.284] <TB2> QUIET: Connection to board DTB_WXENWR opened.
[11:38:43.287] <TB2> INFO: DTB startup information
--- DTB info------------------------------------------
Board id: 162
HW version: DTB1.2
FW version: 4.2
SW version: 4.4
USB id: DTB_WXENWR
MAC address: 40D8551180A2
Hostname: pixelDTB162
Comment:
------------------------------------------------------
[11:38:43.290] <TB2> INFO: RPC call hashes of host and DTB match: 397073690
[11:38:44.821] <TB2> INFO: DUT info:
[11:38:44.821] <TB2> INFO: The DUT currently contains the following objects:
[11:38:44.821] <TB2> INFO: 2 TBM Cores tbm09c (2 ON)
[11:38:44.821] <TB2> INFO: TBM Core alpha (0): 7 registers set
[11:38:44.821] <TB2> INFO: TBM Core beta (1): 7 registers set
[11:38:44.821] <TB2> INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[11:38:44.821] <TB2> INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[11:38:44.821] <TB2> INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[11:38:44.821] <TB2> INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[11:38:44.821] <TB2> INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[11:38:44.821] <TB2> INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[11:38:44.821] <TB2> INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[11:38:44.821] <TB2> INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[11:38:44.821] <TB2> INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[11:38:44.821] <TB2> INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[11:38:44.821] <TB2> INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[11:38:44.822] <TB2> INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[11:38:44.822] <TB2> INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[11:38:44.822] <TB2> INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[11:38:44.822] <TB2> INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[11:38:44.822] <TB2> INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[11:38:44.822] <TB2> INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[11:38:45.223] <TB2> INFO: enter 'restricted' command line mode
[11:38:45.223] <TB2> INFO: enter test to run
[11:38:45.223] <TB2> INFO: test: delay setting parameters: ->5<-
[11:38:45.223] <TB2> INFO: delay test by 5 seconds...
[11:38:50.223] <TB2> INFO: enter test to run
[11:38:50.223] <TB2> INFO: test: Xray no parameter change
[11:38:50.223] <TB2> INFO: running: xray
[11:38:50.224] <TB2> INFO: readGainPedestalParameters /usr/local/data/M2096_XrayQualification_2015-09-01_13h21m_1441106515//004_HRData_150//phCalibrationFitErr35_C0.dat .. /usr/local/data/M2096_XrayQualification_2015-09-01_13h21m_1441106515//004_HRData_150//phCalibrationFitErr35_C15.dat
[11:38:50.374] <TB2> INFO: ######################################################################
[11:38:50.374] <TB2> INFO: PixTestXray::doTest()
[11:38:50.374] <TB2> INFO: ######################################################################
[11:38:50.374] <TB2> INFO: ----------------------------------------------------------------------
[11:38:50.374] <TB2> INFO: PixTestXray::doPhRun() fParRunSeconds = 100
[11:38:50.374] <TB2> INFO: ----------------------------------------------------------------------
[11:38:51.347] <TB2> INFO: PixTestXray::doPhRun start TriggerLoop with trigger frequency 100 kHz, period 405 and duration 100 seconds, fEventsMax = 10000000
[11:38:54.397] <TB2> INFO: run duration 3 seconds, buffer almost full (81%), pausing triggers.
[11:39:15.640] <TB2> INFO: Resuming triggers.
[11:39:18.720] <TB2> INFO: run duration 6 seconds, buffer almost full (81%), pausing triggers.
[11:39:41.177] <TB2> INFO: Resuming triggers.
[11:39:44.238] <TB2> INFO: run duration 9 seconds, buffer almost full (81%), pausing triggers.
[11:40:06.704] <TB2> INFO: Resuming triggers.
[11:40:09.766] <TB2> INFO: run duration 12 seconds, buffer almost full (81%), pausing triggers.
[11:40:30.905] <TB2> INFO: Resuming triggers.
[11:40:33.964] <TB2> INFO: run duration 15 seconds, buffer almost full (81%), pausing triggers.
[11:40:54.605] <TB2> INFO: Resuming triggers.
[11:40:57.663] <TB2> INFO: run duration 18 seconds, buffer almost full (81%), pausing triggers.
[11:41:19.999] <TB2> INFO: Resuming triggers.
[11:41:23.056] <TB2> INFO: run duration 21 seconds, buffer almost full (81%), pausing triggers.
[11:41:45.583] <TB2> INFO: Resuming triggers.
[11:41:48.641] <TB2> INFO: run duration 24 seconds, buffer almost full (81%), pausing triggers.
[11:41:48.686] <TB2> WARNING: Channel 0 ROC 3: Readback start marker after 8 readouts!

[11:41:48.686] <TB2> WARNING: Channel 1 ROC 2: Readback start marker after 10 readouts!

[11:41:48.686] <TB2> ERROR: <datapipe.cc/CheckEventValidity:L480> Channel 1 Number of ROCs (3) != Token Chain Length (4)

[11:41:48.686] <TB2> WARNING: Channel 0 ROC 3: Readback start marker after 8 readouts!

[11:41:48.686] <TB2> WARNING: Channel 1 ROC 3: Readback start marker after 15 readouts!

[11:42:10.120] <TB2> INFO: Resuming triggers.
[11:42:13.178] <TB2> INFO: run duration 27 seconds, buffer almost full (81%), pausing triggers.
[11:42:13.234] <TB2> ERROR: <datapipe.cc/CheckEventValidity:L469> Channel 0 has NoTokenPass but 1 ROCs were found

[11:42:13.234] <TB2> ERROR: <datapipe.cc/CheckEventValidity:L480> Channel 1 Number of ROCs (1) != Token Chain Length (4)

[11:42:13.234] <TB2> ERROR: <datapipe.cc/CheckEventID:L453> Channel 0 Event ID mismatch: local ID (118) != TBM ID (54)

[11:42:13.234] <TB2> ERROR: <datapipe.cc/CheckEventValidity:L480> Channel 0 Number of ROCs (1) != Token Chain Length (4)

[11:42:13.234] <TB2> ERROR: <datapipe.cc/CheckEventID:L453> Channel 0 Event ID mismatch: local ID (55) != TBM ID (118)

[11:42:13.234] <TB2> WARNING: Channel 1 ROC 1: Readback start marker after 15 readouts!

[11:42:13.234] <TB2> WARNING: Channel 1 ROC 3: Readback start marker after 15 readouts!

[11:42:13.234] <TB2> WARNING: Channel 0 ROC 1: Readback start marker after 15 readouts!

[11:42:13.234] <TB2> WARNING: Channel 0 ROC 2: Readback start marker after 15 readouts!

[11:42:13.234] <TB2> WARNING: Channel 0 ROC 3: Readback start marker after 15 readouts!

[11:42:34.446] <TB2> INFO: Resuming triggers.
[11:42:37.503] <TB2> INFO: run duration 30 seconds, buffer almost full (81%), pausing triggers.
[11:42:59.826] <TB2> INFO: Resuming triggers.
[11:43:02.880] <TB2> INFO: run duration 33 seconds, buffer almost full (81%), pausing triggers.
[11:43:02.915] <TB2> ERROR: <datapipe.cc/CheckEventValidity:L480> Channel 1 Number of ROCs (2) != Token Chain Length (4)

[11:43:02.915] <TB2> WARNING: Channel 1 ROC 3: Readback start marker after 15 readouts!

[11:43:24.829] <TB2> INFO: Resuming triggers.
[11:43:27.882] <TB2> INFO: run duration 36 seconds, buffer almost full (81%), pausing triggers.
[11:43:29.496] <TB2> ERROR: <datapipe.cc/CheckEventValidity:L480> Channel 1 Number of ROCs (5) != Token Chain Length (4)

[11:43:49.475] <TB2> INFO: Resuming triggers.
[11:43:52.526] <TB2> INFO: run duration 39 seconds, buffer almost full (81%), pausing triggers.
[11:44:13.220] <TB2> INFO: Resuming triggers.
[11:44:16.273] <TB2> INFO: run duration 42 seconds, buffer almost full (81%), pausing triggers.
[11:44:39.183] <TB2> INFO: Resuming triggers.
[11:44:42.235] <TB2> INFO: run duration 45 seconds, buffer almost full (81%), pausing triggers.
[11:45:05.058] <TB2> INFO: Resuming triggers.
[11:45:08.110] <TB2> INFO: run duration 48 seconds, buffer almost full (81%), pausing triggers.
[11:45:10.353] <TB2> ERROR: <datapipe.cc/CheckEventValidity:L469> Channel 2 has NoTokenPass but 3 ROCs were found

[11:45:10.353] <TB2> ERROR: <datapipe.cc/CheckEventValidity:L469> Channel 3 has NoTokenPass but 4 ROCs were found

[11:45:29.903] <TB2> INFO: Resuming triggers.
[11:45:32.954] <TB2> INFO: run duration 51 seconds, buffer almost full (81%), pausing triggers.
[11:45:53.732] <TB2> INFO: Resuming triggers.
[11:45:56.784] <TB2> INFO: run duration 54 seconds, buffer almost full (81%), pausing triggers.
[11:46:19.580] <TB2> INFO: Resuming triggers.
[11:46:22.631] <TB2> INFO: run duration 58 seconds, buffer almost full (81%), pausing triggers.
[11:46:45.197] <TB2> INFO: Resuming triggers.
[11:46:48.248] <TB2> INFO: run duration 61 seconds, buffer almost full (81%), pausing triggers.
[11:47:10.270] <TB2> INFO: Resuming triggers.
[11:47:13.321] <TB2> INFO: run duration 64 seconds, buffer almost full (81%), pausing triggers.
[11:47:34.645] <TB2> INFO: Resuming triggers.
[11:47:37.694] <TB2> INFO: run duration 67 seconds, buffer almost full (81%), pausing triggers.
[11:48:00.641] <TB2> INFO: Resuming triggers.
[11:48:03.691] <TB2> INFO: run duration 70 seconds, buffer almost full (81%), pausing triggers.
[11:48:26.322] <TB2> INFO: Resuming triggers.
[11:48:29.387] <TB2> INFO: run duration 73 seconds, buffer almost full (81%), pausing triggers.
[11:48:51.399] <TB2> INFO: Resuming triggers.
[11:48:54.447] <TB2> INFO: run duration 76 seconds, buffer almost full (81%), pausing triggers.
[11:49:15.177] <TB2> INFO: Resuming triggers.
[11:49:18.226] <TB2> INFO: run duration 79 seconds, buffer almost full (81%), pausing triggers.
[11:49:40.996] <TB2> INFO: Resuming triggers.
[11:49:44.044] <TB2> INFO: run duration 82 seconds, buffer almost full (81%), pausing triggers.
[11:50:06.758] <TB2> INFO: Resuming triggers.
[11:50:09.806] <TB2> INFO: run duration 85 seconds, buffer almost full (81%), pausing triggers.
[11:50:15.582] <TB2> ERROR: <datapipe.cc/CheckEventValidity:L480> Channel 2 Number of ROCs (2) != Token Chain Length (4)

[11:50:15.582] <TB2> ERROR: <datapipe.cc/CheckEventValidity:L480> Channel 3 Number of ROCs (1) != Token Chain Length (4)

[11:50:15.582] <TB2> WARNING: Channel 2 ROC 3: Readback start marker after 15 readouts!

[11:50:15.582] <TB2> WARNING: Channel 3 ROC 2: Readback start marker after 15 readouts!

[11:50:15.582] <TB2> WARNING: Channel 3 ROC 3: Readback start marker after 15 readouts!

[11:50:31.413] <TB2> INFO: Resuming triggers.
[11:50:34.460] <TB2> INFO: run duration 88 seconds, buffer almost full (81%), pausing triggers.
[11:50:43.361] <TB2> WARNING: Channel 2 ROC 3: Readback start marker after 15 readouts!

[11:50:43.361] <TB2> ERROR: <datapipe.cc/CheckEventValidity:L480> Channel 3 Number of ROCs (3) != Token Chain Length (4)

[11:50:43.361] <TB2> WARNING: Channel 2 ROC 3: Readback start marker after 1 readouts!

[11:50:55.353] <TB2> INFO: Resuming triggers.
[11:50:58.399] <TB2> INFO: run duration 91 seconds, buffer almost full (81%), pausing triggers.
[11:51:04.459] <TB2> ERROR: <datapipe.cc/CheckEventValidity:L480> Channel 2 Number of ROCs (3) != Token Chain Length (4)

[11:51:04.460] <TB2> ERROR: <datapipe.cc/CheckEventValidity:L480> Channel 3 Number of ROCs (2) != Token Chain Length (4)

[11:51:04.460] <TB2> WARNING: Channel 3 ROC 3: Readback start marker after 15 readouts!

[11:51:21.141] <TB2> INFO: Resuming triggers.
[11:51:24.188] <TB2> INFO: run duration 94 seconds, buffer almost full (81%), pausing triggers.
[11:51:24.828] <TB2> ERROR: <datapipe.cc/CheckEventValidity:L480> Channel 2 Number of ROCs (1) != Token Chain Length (4)

[11:51:24.828] <TB2> WARNING: Channel 3 ROC 0: Readback start marker after 10 readouts!

[11:51:24.828] <TB2> WARNING: Channel 2 ROC 2: Readback start marker after 15 readouts!

[11:51:24.828] <TB2> WARNING: Channel 2 ROC 3: Readback start marker after 15 readouts!

[11:51:24.828] <TB2> WARNING: Channel 3 ROC 0: Readback start marker after 6 readouts!

[11:51:30.247] <TB2> ERROR: <datapipe.cc/CheckEventID:L453> Channel 2 Event ID mismatch: local ID (85) != TBM ID (86)

[11:51:30.247] <TB2> ERROR: <datapipe.cc/CheckEventID:L453> Channel 3 Event ID mismatch: local ID (85) != TBM ID (86)

[11:51:30.247] <TB2> WARNING: Channel 2 ROC 0: Readback start marker after 15 readouts!

[11:51:30.247] <TB2> WARNING: Channel 2 ROC 1: Readback start marker after 15 readouts!

[11:51:30.247] <TB2> WARNING: Channel 2 ROC 2: Readback start marker after 15 readouts!

[11:51:30.247] <TB2> WARNING: Channel 2 ROC 3: Readback start marker after 15 readouts!

[11:51:30.247] <TB2> WARNING: Channel 3 ROC 0: Readback start marker after 15 readouts!

[11:51:30.247] <TB2> WARNING: Channel 3 ROC 1: Readback start marker after 15 readouts!

[11:51:30.247] <TB2> WARNING: Channel 3 ROC 2: Readback start marker after 15 readouts!

[11:51:30.247] <TB2> WARNING: Channel 3 ROC 3: Readback start marker after 15 readouts!

[11:51:46.782] <TB2> INFO: Resuming triggers.
[11:51:49.831] <TB2> INFO: run duration 97 seconds, buffer almost full (81%), pausing triggers.
[11:51:53.942] <TB2> ERROR: <datapipe.cc/CheckEventValidity:L469> Channel 2 has NoTokenPass but 4 ROCs were found

[11:51:53.942] <TB2> ERROR: <datapipe.cc/CheckEventValidity:L469> Channel 3 has NoTokenPass but 4 ROCs were found

[11:52:11.309] <TB2> INFO: Resuming triggers.
[11:52:13.679] <TB2> INFO: data taking finished, elapsed time: 100 seconds.
[11:52:30.274] <TB2> INFO: PixTest:: pg_setup set to default.
[11:52:30.275] <TB2> INFO: PixTestXray::doPhRun() done
[11:52:30.275] <TB2> INFO: PixTestXray::doTest() done
[11:52:30.464] <TB2> INFO: enter test to run
[11:52:30.464] <TB2> INFO: test: no parameter change
[11:52:30.637] <TB2> QUIET: Connection to board 162 closed.
[11:52:30.724] <TB2> INFO: pXar: this is the end, my friend
commander_HRSCurves.log
[11:56:10.155] <TB2> INFO: *** Welcome to pxar ***
[11:56:10.155] <TB2> INFO: *** Today: 2015/09/01
[11:56:10.155] <TB2> INFO: readRocDacs: /usr/local/data/M2096_XrayQualification_2015-09-01_13h21m_1441106515//005_HRSCurves_100//dacParameters35_C0.dat .. /usr/local/data/M2096_XrayQualification_2015-09-01_13h21m_1441106515//005_HRSCurves_100//dacParameters35_C15.dat
[11:56:10.155] <TB2> INFO: readTbmDacs: /usr/local/data/M2096_XrayQualification_2015-09-01_13h21m_1441106515//005_HRSCurves_100//tbmParameters_C0a.dat .. /usr/local/data/M2096_XrayQualification_2015-09-01_13h21m_1441106515//005_HRSCurves_100//tbmParameters_C0b.dat
[11:56:10.155] <TB2> INFO: readMaskFile: /usr/local/data/M2096_XrayQualification_2015-09-01_13h21m_1441106515//005_HRSCurves_100//defaultMaskFile.dat
[11:56:10.156] <TB2> INFO: readTrimFile: /usr/local/data/M2096_XrayQualification_2015-09-01_13h21m_1441106515//005_HRSCurves_100//trimParameters35_C0.dat .. /usr/local/data/M2096_XrayQualification_2015-09-01_13h21m_1441106515//005_HRSCurves_100//trimParameters35_C15.dat
[11:56:10.227] <TB2> INFO: clk: 4
[11:56:10.227] <TB2> INFO: ctr: 4
[11:56:10.227] <TB2> INFO: sda: 19
[11:56:10.227] <TB2> INFO: tin: 9
[11:56:10.227] <TB2> INFO: level: 15
[11:56:10.227] <TB2> INFO: triggerdelay: 0
[11:56:10.227] <TB2> QUIET: Instanciating API for pxar prod-10
[11:56:10.227] <TB2> INFO: Log level: INFO
[11:56:10.239] <TB2> INFO: Found DTB DTB_WXENWR
[11:56:10.248] <TB2> QUIET: Connection to board DTB_WXENWR opened.
[11:56:10.252] <TB2> INFO: DTB startup information
--- DTB info------------------------------------------
Board id: 162
HW version: DTB1.2
FW version: 4.2
SW version: 4.4
USB id: DTB_WXENWR
MAC address: 40D8551180A2
Hostname: pixelDTB162
Comment:
------------------------------------------------------
[11:56:10.254] <TB2> INFO: RPC call hashes of host and DTB match: 397073690
[11:56:11.793] <TB2> INFO: DUT info:
[11:56:11.793] <TB2> INFO: The DUT currently contains the following objects:
[11:56:11.793] <TB2> INFO: 2 TBM Cores tbm09c (2 ON)
[11:56:11.793] <TB2> INFO: TBM Core alpha (0): 7 registers set
[11:56:11.793] <TB2> INFO: TBM Core beta (1): 7 registers set
[11:56:11.793] <TB2> INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[11:56:11.793] <TB2> INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[11:56:11.793] <TB2> INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[11:56:11.793] <TB2> INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[11:56:11.793] <TB2> INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[11:56:11.794] <TB2> INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[11:56:11.794] <TB2> INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[11:56:11.794] <TB2> INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[11:56:11.794] <TB2> INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[11:56:11.794] <TB2> INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[11:56:11.794] <TB2> INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[11:56:11.794] <TB2> INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[11:56:11.794] <TB2> INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[11:56:11.794] <TB2> INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[11:56:11.794] <TB2> INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[11:56:11.794] <TB2> INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[11:56:11.794] <TB2> INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[11:56:12.197] <TB2> INFO: enter 'restricted' command line mode
[11:56:12.197] <TB2> INFO: enter test to run
[11:56:12.197] <TB2> INFO: test: Highrate no parameter change
[11:56:12.197] <TB2> INFO: running: highrate
[11:56:12.198] <TB2> INFO: readGainPedestalParameters /usr/local/data/M2096_XrayQualification_2015-09-01_13h21m_1441106515//005_HRSCurves_100//phCalibrationFitErr35_C0.dat .. /usr/local/data/M2096_XrayQualification_2015-09-01_13h21m_1441106515//005_HRSCurves_100//phCalibrationFitErr35_C15.dat
[11:56:12.406] <TB2> INFO: ----------------------------------------------------------------------
[11:56:12.406] <TB2> INFO: PixTestHighRate::xNoiseMaps() ntrig = 50, vcal = 200
[11:56:12.406] <TB2> INFO: ----------------------------------------------------------------------
[11:56:12.413] <TB2> INFO: ---> dac: vcal name: xNoiseMap ntrig: 50 dacrange: 0 .. 100 (20/-1) hits flags = 384 (plus default)
[11:56:12.427] <TB2> INFO: dacScan step from 0 .. 19
[11:56:12.427] <TB2> INFO: dacScan split into 1 runs with ntrig = 50
[11:56:12.427] <TB2> INFO: run 1 of 1
[11:56:13.039] <TB2> INFO: Expecting 4160000 events.
[11:56:38.741] <TB2> INFO: 395250 events read in total (25171ms).
[11:57:04.707] <TB2> INFO: 791800 events read in total (51137ms).
[11:57:30.154] <TB2> INFO: 1188450 events read in total (76584ms).
[11:57:56.472] <TB2> INFO: 1585150 events read in total (102902ms).
[11:58:21.689] <TB2> INFO: 1981500 events read in total (128119ms).
[11:58:46.899] <TB2> INFO: 2378200 events read in total (153329ms).
[11:59:11.937] <TB2> INFO: 2774900 events read in total (178367ms).
[11:59:37.512] <TB2> INFO: 3171350 events read in total (203942ms).
[12:00:03.046] <TB2> INFO: 3567950 events read in total (229476ms).
[12:00:28.535] <TB2> INFO: 3964550 events read in total (254965ms).
[12:00:40.337] <TB2> INFO: 4160000 events read in total (266767ms).
[12:00:40.520] <TB2> INFO: Test took 268093ms.
[12:00:42.412] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[12:00:48.675] <TB2> INFO: dacScan step from 20 .. 39
[12:00:48.675] <TB2> INFO: dacScan split into 1 runs with ntrig = 50
[12:00:48.675] <TB2> INFO: run 1 of 1
[12:00:49.275] <TB2> INFO: Expecting 4160000 events.
[12:01:14.713] <TB2> INFO: 393500 events read in total (24910ms).
[12:01:38.854] <TB2> INFO: 787900 events read in total (49051ms).
[12:02:04.511] <TB2> INFO: 1182150 events read in total (74708ms).
[12:02:30.739] <TB2> INFO: 1576250 events read in total (100936ms).
[12:02:55.877] <TB2> INFO: 1970350 events read in total (126074ms).
[12:03:21.161] <TB2> INFO: 2364100 events read in total (151359ms).
[12:03:47.302] <TB2> INFO: 2757250 events read in total (177499ms).
[12:04:13.265] <TB2> INFO: 3149300 events read in total (203462ms).
[12:04:39.237] <TB2> INFO: 3540400 events read in total (229434ms).
[12:05:04.562] <TB2> INFO: 3930900 events read in total (254759ms).
[12:05:19.613] <TB2> INFO: 4160000 events read in total (269810ms).
[12:05:19.862] <TB2> INFO: Test took 271186ms.
[12:05:22.789] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[12:05:29.116] <TB2> INFO: dacScan step from 40 .. 59
[12:05:29.116] <TB2> INFO: dacScan split into 1 runs with ntrig = 50
[12:05:29.116] <TB2> INFO: run 1 of 1
[12:05:29.711] <TB2> INFO: Expecting 4160000 events.
[12:05:54.521] <TB2> INFO: 324400 events read in total (24282ms).
[12:06:18.082] <TB2> INFO: 649250 events read in total (47843ms).
[12:06:41.678] <TB2> INFO: 973600 events read in total (71439ms).
[12:07:06.042] <TB2> INFO: 1297350 events read in total (95803ms).
[12:07:29.682] <TB2> INFO: 1620250 events read in total (119443ms).
[12:07:53.666] <TB2> INFO: 1942150 events read in total (143427ms).
[12:08:16.775] <TB2> INFO: 2262700 events read in total (166536ms).
[12:08:39.955] <TB2> INFO: 2581700 events read in total (189716ms).
[12:09:04.517] <TB2> INFO: 2898900 events read in total (214278ms).
[12:09:29.121] <TB2> INFO: 3214600 events read in total (238882ms).
[12:09:52.917] <TB2> INFO: 3529050 events read in total (262678ms).
[12:10:16.474] <TB2> INFO: 3842850 events read in total (286235ms).
[12:10:40.631] <TB2> INFO: 4156450 events read in total (310392ms).
[12:10:41.185] <TB2> INFO: 4160000 events read in total (310946ms).
[12:10:41.426] <TB2> INFO: Test took 312310ms.
[12:10:44.258] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[12:10:50.982] <TB2> INFO: dacScan step from 60 .. 79
[12:10:50.982] <TB2> INFO: dacScan split into 1 runs with ntrig = 50
[12:10:50.982] <TB2> INFO: run 1 of 1
[12:10:51.581] <TB2> INFO: Expecting 4160000 events.
[12:11:15.924] <TB2> INFO: 303200 events read in total (23814ms).
[12:11:39.715] <TB2> INFO: 606900 events read in total (47605ms).
[12:12:03.849] <TB2> INFO: 910900 events read in total (71739ms).
[12:12:27.231] <TB2> INFO: 1215100 events read in total (95121ms).
[12:12:50.524] <TB2> INFO: 1518900 events read in total (118414ms).
[12:13:14.741] <TB2> INFO: 1822800 events read in total (142631ms).
[12:13:38.567] <TB2> INFO: 2126750 events read in total (166457ms).
[12:14:01.496] <TB2> INFO: 2430650 events read in total (189386ms).
[12:14:24.663] <TB2> INFO: 2734500 events read in total (212553ms).
[12:14:48.934] <TB2> INFO: 3038350 events read in total (236824ms).
[12:15:12.240] <TB2> INFO: 3342400 events read in total (260130ms).
[12:15:35.973] <TB2> INFO: 3646350 events read in total (283863ms).
[12:15:59.470] <TB2> INFO: 3950000 events read in total (307360ms).
[12:16:15.993] <TB2> INFO: 4160000 events read in total (323883ms).
[12:16:16.240] <TB2> INFO: Test took 325257ms.
[12:16:18.426] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[12:16:24.852] <TB2> INFO: dacScan step from 80 .. 99
[12:16:24.852] <TB2> INFO: dacScan split into 1 runs with ntrig = 50
[12:16:24.852] <TB2> INFO: run 1 of 1
[12:16:25.460] <TB2> INFO: Expecting 4160000 events.
[12:16:50.048] <TB2> INFO: 303000 events read in total (24052ms).
[12:17:13.513] <TB2> INFO: 606600 events read in total (47517ms).
[12:17:37.253] <TB2> INFO: 910350 events read in total (71257ms).
[12:17:41.225] <TB2> ERROR: <datapipe.cc/CheckEventValidity:L480> Channel 0 Number of ROCs (1) != Token Chain Length (4)

[12:17:41.225] <TB2> ERROR: <datapipe.cc/CheckEventValidity:L480> Channel 1 Number of ROCs (2) != Token Chain Length (4)

[12:17:41.225] <TB2> ERROR: <datapipe.cc/CheckEventID:L453> Channel 0 Event ID mismatch: local ID (209) != TBM ID (27)

[12:17:41.225] <TB2> ERROR: <datapipe.cc/CheckEventValidity:L480> Channel 0 Number of ROCs (0) != Token Chain Length (4)

[12:17:41.225] <TB2> ERROR: <datapipe.cc/CheckEventID:L453> Channel 0 Event ID mismatch: local ID (28) != TBM ID (209)

[12:17:41.225] <TB2> WARNING: Channel 1 ROC 3: Readback start marker after 15 readouts!

[12:17:41.225] <TB2> WARNING: Channel 0 ROC 2: Readback start marker after 15 readouts!

[12:17:41.225] <TB2> WARNING: Channel 0 ROC 3: Readback start marker after 15 readouts!

[12:17:56.621] <TB2> CRITICAL: <hal.cc/condenseTriggers:L1895> Data size does not correspond to 50 triggers! Aborting data processing!

[12:17:56.640] <TB2> INFO: 910350 events read in total (90644ms).
[12:18:21.462] <TB2> INFO: 1214150 events read in total (115466ms).
[12:18:45.581] <TB2> INFO: 1518000 events read in total (139585ms).
[12:19:09.690] <TB2> INFO: 1821850 events read in total (163694ms).
[12:19:34.106] <TB2> INFO: 2125550 events read in total (188110ms).
[12:19:58.111] <TB2> INFO: 2429150 events read in total (212115ms).
[12:20:21.919] <TB2> INFO: 2732800 events read in total (235923ms).
[12:20:45.612] <TB2> INFO: 3036350 events read in total (259616ms).
[12:21:08.932] <TB2> INFO: 3340050 events read in total (282936ms).
[12:21:31.812] <TB2> INFO: 3643850 events read in total (305816ms).
[12:21:48.802] <TB2> INFO: 3856200 events read in total (322806ms).
[12:21:48.802] <TB2> CRITICAL: <hal.cc/MultiRocAllPixelsDacScan:L954> Incomplete DAQ data readout! Missing 6076 Events.

[12:21:49.801] <TB2> INFO: Expecting 4160000 events.
[12:22:13.578] <TB2> INFO: 302650 events read in total (23249ms).
[12:22:37.413] <TB2> INFO: 606150 events read in total (47084ms).
[12:23:01.692] <TB2> INFO: 909900 events read in total (71363ms).
[12:23:25.157] <TB2> INFO: 1213700 events read in total (94829ms).
[12:23:48.507] <TB2> INFO: 1517150 events read in total (118178ms).
[12:24:12.404] <TB2> INFO: 1820650 events read in total (142075ms).
[12:24:35.669] <TB2> INFO: 2124350 events read in total (165340ms).
[12:24:57.592] <TB2> INFO: 2428100 events read in total (187263ms).
[12:25:21.066] <TB2> INFO: 2731700 events read in total (210737ms).
[12:25:44.972] <TB2> INFO: 3035100 events read in total (234643ms).
[12:26:08.346] <TB2> INFO: 3338600 events read in total (258017ms).
[12:26:32.700] <TB2> INFO: 3642450 events read in total (282371ms).
[12:26:56.734] <TB2> INFO: 3946150 events read in total (306405ms).
[12:27:13.189] <TB2> INFO: 4160000 events read in total (322860ms).
[12:27:13.418] <TB2> INFO: Test took 324239ms.
[12:27:15.532] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[12:27:21.964] <TB2> INFO: dacScan step from 100 .. 100
[12:27:21.964] <TB2> INFO: dacScan split into 1 runs with ntrig = 50
[12:27:21.964] <TB2> INFO: run 1 of 1
[12:27:22.562] <TB2> INFO: Expecting 208000 events.
[12:27:39.969] <TB2> INFO: 208000 events read in total (16878ms).
[12:27:39.977] <TB2> INFO: Test took 18013ms.
[12:27:40.078] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[12:27:40.385] <TB2> INFO: dumping ASCII scurve output file: XSCurveData
[12:27:41.762] <TB2> INFO: dumping ASCII scurve output file: XSCurveData
[12:27:43.047] <TB2> INFO: dumping ASCII scurve output file: XSCurveData
[12:27:44.321] <TB2> INFO: dumping ASCII scurve output file: XSCurveData
[12:27:45.671] <TB2> INFO: dumping ASCII scurve output file: XSCurveData
[12:27:47.058] <TB2> INFO: dumping ASCII scurve output file: XSCurveData
[12:27:48.485] <TB2> INFO: dumping ASCII scurve output file: XSCurveData
[12:27:49.903] <TB2> INFO: dumping ASCII scurve output file: XSCurveData
[12:27:51.334] <TB2> INFO: dumping ASCII scurve output file: XSCurveData
[12:27:52.758] <TB2> INFO: dumping ASCII scurve output file: XSCurveData
[12:27:54.148] <TB2> INFO: dumping ASCII scurve output file: XSCurveData
[12:27:55.569] <TB2> INFO: dumping ASCII scurve output file: XSCurveData
[12:27:56.947] <TB2> INFO: dumping ASCII scurve output file: XSCurveData
[12:27:58.306] <TB2> INFO: dumping ASCII scurve output file: XSCurveData
[12:27:59.588] <TB2> INFO: dumping ASCII scurve output file: XSCurveData
[12:28:00.873] <TB2> INFO: dumping ASCII scurve output file: XSCurveData
[12:28:02.161] <TB2> INFO: PixTest:: pg_setup set to default.
[12:28:02.163] <TB2> INFO: vcal mean: 39.11 39.07 38.20 42.40 43.13 43.82 44.10 43.42 44.30 44.20 43.73 43.02 40.97 39.23 39.63 38.97
[12:28:02.163] <TB2> INFO: vcal RMS: 1.16 1.18 0.89 1.54 1.35 1.53 1.64 1.51 1.88 1.64 1.50 1.78 1.27 1.19 1.12 1.09
[12:28:02.163] <TB2> INFO: number of X-ray hits detected: 209683 231301 203721 388763 413932 457465 471277 461720 507461 499380 477336 422203 351828 217751 247255 223097
[12:28:02.163] <TB2> INFO: number of triggers sent (total per ROC): 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000
[12:28:02.163] <TB2> INFO: X-ray hit rate [MHz/cm2]: 61.5 67.8 59.7 113.9 121.3 134.1 138.1 135.3 148.7 146.4 139.9 123.8 103.1 63.8 72.5 65.4
[12:28:02.163] <TB2> INFO: PixTestHighRate::doXNoiseMaps() done
[12:28:02.260] <TB2> INFO: enter test to run
[12:28:02.260] <TB2> INFO: test: no parameter change
[12:28:02.441] <TB2> QUIET: Connection to board 162 closed.
[12:28:02.522] <TB2> INFO: pXar: this is the end, my friend
commander_CalDelScanAndSaveDacs.log
[12:45:38.475] <TB2> INFO: *** Welcome to pxar ***
[12:45:38.475] <TB2> INFO: *** Today: 2015/09/01
[12:45:38.475] <TB2> INFO: readRocDacs: /usr/local/data/M2096_XrayQualification_2015-09-01_13h21m_1441106515//010_CalDelScanAndSaveDacs_4mA25kV//dacParameters35_C0.dat .. /usr/local/data/M2096_XrayQualification_2015-09-01_13h21m_1441106515//010_CalDelScanAndSaveDacs_4mA25kV//dacParameters35_C15.dat
[12:45:38.476] <TB2> INFO: readTbmDacs: /usr/local/data/M2096_XrayQualification_2015-09-01_13h21m_1441106515//010_CalDelScanAndSaveDacs_4mA25kV//tbmParameters_C0a.dat .. /usr/local/data/M2096_XrayQualification_2015-09-01_13h21m_1441106515//010_CalDelScanAndSaveDacs_4mA25kV//tbmParameters_C0b.dat
[12:45:38.476] <TB2> INFO: readMaskFile: /usr/local/data/M2096_XrayQualification_2015-09-01_13h21m_1441106515//010_CalDelScanAndSaveDacs_4mA25kV//defaultMaskFile.dat
[12:45:38.476] <TB2> INFO: readTrimFile: /usr/local/data/M2096_XrayQualification_2015-09-01_13h21m_1441106515//010_CalDelScanAndSaveDacs_4mA25kV//trimParameters35_C0.dat .. /usr/local/data/M2096_XrayQualification_2015-09-01_13h21m_1441106515//010_CalDelScanAndSaveDacs_4mA25kV//trimParameters35_C15.dat
[12:45:38.545] <TB2> INFO: clk: 4
[12:45:38.545] <TB2> INFO: ctr: 4
[12:45:38.545] <TB2> INFO: sda: 19
[12:45:38.545] <TB2> INFO: tin: 9
[12:45:38.545] <TB2> INFO: level: 15
[12:45:38.545] <TB2> INFO: triggerdelay: 0
[12:45:38.545] <TB2> QUIET: Instanciating API for pxar prod-10
[12:45:38.545] <TB2> INFO: Log level: INFO
[12:45:38.558] <TB2> INFO: Found DTB DTB_WXENWR
[12:45:38.567] <TB2> QUIET: Connection to board DTB_WXENWR opened.
[12:45:38.570] <TB2> INFO: DTB startup information
--- DTB info------------------------------------------
Board id: 162
HW version: DTB1.2
FW version: 4.2
SW version: 4.4
USB id: DTB_WXENWR
MAC address: 40D8551180A2
Hostname: pixelDTB162
Comment:
------------------------------------------------------
[12:45:38.572] <TB2> INFO: RPC call hashes of host and DTB match: 397073690
[12:45:40.100] <TB2> INFO: DUT info:
[12:45:40.100] <TB2> INFO: The DUT currently contains the following objects:
[12:45:40.100] <TB2> INFO: 2 TBM Cores tbm09c (2 ON)
[12:45:40.100] <TB2> INFO: TBM Core alpha (0): 7 registers set
[12:45:40.100] <TB2> INFO: TBM Core beta (1): 7 registers set
[12:45:40.100] <TB2> INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[12:45:40.100] <TB2> INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[12:45:40.100] <TB2> INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[12:45:40.100] <TB2> INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[12:45:40.100] <TB2> INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[12:45:40.100] <TB2> INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[12:45:40.100] <TB2> INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[12:45:40.100] <TB2> INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[12:45:40.100] <TB2> INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[12:45:40.101] <TB2> INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[12:45:40.101] <TB2> INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[12:45:40.101] <TB2> INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[12:45:40.101] <TB2> INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[12:45:40.101] <TB2> INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[12:45:40.101] <TB2> INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[12:45:40.101] <TB2> INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[12:45:40.101] <TB2> INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[12:45:40.502] <TB2> INFO: enter 'restricted' command line mode
[12:45:40.502] <TB2> INFO: enter test to run
[12:45:40.502] <TB2> INFO: test: HighRate no parameter change
[12:45:40.502] <TB2> INFO: running: highrate
[12:45:40.503] <TB2> INFO: readGainPedestalParameters /usr/local/data/M2096_XrayQualification_2015-09-01_13h21m_1441106515//010_CalDelScanAndSaveDacs_4mA25kV//phCalibrationFitErr35_C0.dat .. /usr/local/data/M2096_XrayQualification_2015-09-01_13h21m_1441106515//010_CalDelScanAndSaveDacs_4mA25kV//phCalibrationFitErr35_C15.dat
[12:45:40.650] <TB2> INFO: ----------------------------------------------------------------------
[12:45:40.650] <TB2> INFO: PixTestHighRate::calDelScan() ntrig = 10, vcal = 200
[12:45:40.650] <TB2> INFO: ----------------------------------------------------------------------
[12:45:40.791] <TB2> INFO: Expecting 768 events.
[12:45:42.023] <TB2> INFO: 768 events read in total (511ms).
[12:45:42.119] <TB2> INFO: Test took 1462ms.
[12:45:42.747] <TB2> INFO: Expecting 41600 events.
[12:45:46.175] <TB2> INFO: 41600 events read in total (2900ms).
[12:45:46.177] <TB2> INFO: Test took 4052ms.
[12:45:46.263] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[12:45:46.910] <TB2> INFO: Expecting 41600 events.
[12:45:50.358] <TB2> INFO: 41600 events read in total (2921ms).
[12:45:50.360] <TB2> INFO: Test took 4065ms.
[12:45:50.449] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[12:45:51.098] <TB2> INFO: Expecting 41600 events.
[12:45:54.643] <TB2> INFO: 41600 events read in total (3017ms).
[12:45:54.645] <TB2> INFO: Test took 4162ms.
[12:45:54.740] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[12:45:55.393] <TB2> INFO: Expecting 41600 events.
[12:45:58.879] <TB2> INFO: 41600 events read in total (2958ms).
[12:45:58.881] <TB2> INFO: Test took 4103ms.
[12:45:58.967] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[12:45:59.652] <TB2> INFO: Expecting 41600 events.
[12:46:03.168] <TB2> INFO: 41600 events read in total (2987ms).
[12:46:03.170] <TB2> INFO: Test took 4156ms.
[12:46:03.255] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[12:46:03.906] <TB2> INFO: Expecting 41600 events.
[12:46:07.385] <TB2> INFO: 41600 events read in total (2951ms).
[12:46:07.387] <TB2> INFO: Test took 4098ms.
[12:46:07.476] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[12:46:08.136] <TB2> INFO: Expecting 41600 events.
[12:46:11.607] <TB2> INFO: 41600 events read in total (2942ms).
[12:46:11.609] <TB2> INFO: Test took 4099ms.
[12:46:11.706] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[12:46:12.355] <TB2> INFO: Expecting 41600 events.
[12:46:15.733] <TB2> INFO: 41600 events read in total (2850ms).
[12:46:15.734] <TB2> INFO: Test took 3994ms.
[12:46:15.838] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[12:46:16.521] <TB2> INFO: Expecting 41600 events.
[12:46:19.931] <TB2> INFO: 41600 events read in total (2881ms).
[12:46:19.933] <TB2> INFO: Test took 4046ms.
[12:46:20.025] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[12:46:20.687] <TB2> INFO: Expecting 41600 events.
[12:46:24.273] <TB2> INFO: 41600 events read in total (3057ms).
[12:46:24.275] <TB2> INFO: Test took 4206ms.
[12:46:24.364] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[12:46:25.013] <TB2> INFO: Expecting 41600 events.
[12:46:28.522] <TB2> INFO: 41600 events read in total (2981ms).
[12:46:28.524] <TB2> INFO: Test took 4127ms.
[12:46:28.614] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[12:46:29.274] <TB2> INFO: Expecting 41600 events.
[12:46:32.832] <TB2> INFO: 41600 events read in total (3030ms).
[12:46:32.834] <TB2> INFO: Test took 4175ms.
[12:46:32.926] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[12:46:33.577] <TB2> INFO: Expecting 41600 events.
[12:46:37.136] <TB2> INFO: 41600 events read in total (3031ms).
[12:46:37.138] <TB2> INFO: Test took 4176ms.
[12:46:37.247] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[12:46:37.935] <TB2> INFO: Expecting 41600 events.
[12:46:41.435] <TB2> INFO: 41600 events read in total (2972ms).
[12:46:41.439] <TB2> INFO: Test took 4146ms.
[12:46:41.535] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[12:46:42.185] <TB2> INFO: Expecting 41600 events.
[12:46:45.588] <TB2> INFO: 41600 events read in total (2875ms).
[12:46:45.590] <TB2> INFO: Test took 4020ms.
[12:46:45.684] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[12:46:46.348] <TB2> INFO: Expecting 41600 events.
[12:46:49.818] <TB2> INFO: 41600 events read in total (2941ms).
[12:46:49.820] <TB2> INFO: Test took 4087ms.
[12:46:49.915] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[12:46:50.564] <TB2> INFO: Expecting 41600 events.
[12:46:54.099] <TB2> INFO: 41600 events read in total (3007ms).
[12:46:54.101] <TB2> INFO: Test took 4152ms.
[12:46:54.190] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[12:46:54.837] <TB2> INFO: Expecting 41600 events.
[12:46:58.420] <TB2> INFO: 41600 events read in total (3054ms).
[12:46:58.422] <TB2> INFO: Test took 4199ms.
[12:46:58.529] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[12:46:59.217] <TB2> INFO: Expecting 41600 events.
[12:47:02.822] <TB2> INFO: 41600 events read in total (3077ms).
[12:47:02.825] <TB2> INFO: Test took 4244ms.
[12:47:02.933] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[12:47:03.601] <TB2> INFO: Expecting 41600 events.
[12:47:06.850] <TB2> INFO: 41600 events read in total (2721ms).
[12:47:06.852] <TB2> INFO: Test took 3865ms.
[12:47:06.940] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[12:47:07.276] <TB2> INFO: enter test to run
[12:47:07.276] <TB2> INFO: test: Pretest no parameter change
[12:47:07.276] <TB2> INFO: running: pretest
[12:47:07.276] <TB2> INFO: write dac parameters into /usr/local/data/M2096_XrayQualification_2015-09-01_13h21m_1441106515//010_CalDelScanAndSaveDacs_4mA25kV//dacParameters35_C0.dat
[12:47:07.276] <TB2> INFO: write dac parameters into /usr/local/data/M2096_XrayQualification_2015-09-01_13h21m_1441106515//010_CalDelScanAndSaveDacs_4mA25kV//dacParameters35_C1.dat
[12:47:07.276] <TB2> INFO: write dac parameters into /usr/local/data/M2096_XrayQualification_2015-09-01_13h21m_1441106515//010_CalDelScanAndSaveDacs_4mA25kV//dacParameters35_C2.dat
[12:47:07.276] <TB2> INFO: write dac parameters into /usr/local/data/M2096_XrayQualification_2015-09-01_13h21m_1441106515//010_CalDelScanAndSaveDacs_4mA25kV//dacParameters35_C3.dat
[12:47:07.276] <TB2> INFO: write dac parameters into /usr/local/data/M2096_XrayQualification_2015-09-01_13h21m_1441106515//010_CalDelScanAndSaveDacs_4mA25kV//dacParameters35_C4.dat
[12:47:07.276] <TB2> INFO: write dac parameters into /usr/local/data/M2096_XrayQualification_2015-09-01_13h21m_1441106515//010_CalDelScanAndSaveDacs_4mA25kV//dacParameters35_C5.dat
[12:47:07.276] <TB2> INFO: write dac parameters into /usr/local/data/M2096_XrayQualification_2015-09-01_13h21m_1441106515//010_CalDelScanAndSaveDacs_4mA25kV//dacParameters35_C6.dat
[12:47:07.276] <TB2> INFO: write dac parameters into /usr/local/data/M2096_XrayQualification_2015-09-01_13h21m_1441106515//010_CalDelScanAndSaveDacs_4mA25kV//dacParameters35_C7.dat
[12:47:07.277] <TB2> INFO: write dac parameters into /usr/local/data/M2096_XrayQualification_2015-09-01_13h21m_1441106515//010_CalDelScanAndSaveDacs_4mA25kV//dacParameters35_C8.dat
[12:47:07.277] <TB2> INFO: write dac parameters into /usr/local/data/M2096_XrayQualification_2015-09-01_13h21m_1441106515//010_CalDelScanAndSaveDacs_4mA25kV//dacParameters35_C9.dat
[12:47:07.277] <TB2> INFO: write dac parameters into /usr/local/data/M2096_XrayQualification_2015-09-01_13h21m_1441106515//010_CalDelScanAndSaveDacs_4mA25kV//dacParameters35_C10.dat
[12:47:07.277] <TB2> INFO: write dac parameters into /usr/local/data/M2096_XrayQualification_2015-09-01_13h21m_1441106515//010_CalDelScanAndSaveDacs_4mA25kV//dacParameters35_C11.dat
[12:47:07.277] <TB2> INFO: write dac parameters into /usr/local/data/M2096_XrayQualification_2015-09-01_13h21m_1441106515//010_CalDelScanAndSaveDacs_4mA25kV//dacParameters35_C12.dat
[12:47:07.277] <TB2> INFO: write dac parameters into /usr/local/data/M2096_XrayQualification_2015-09-01_13h21m_1441106515//010_CalDelScanAndSaveDacs_4mA25kV//dacParameters35_C13.dat
[12:47:07.277] <TB2> INFO: write dac parameters into /usr/local/data/M2096_XrayQualification_2015-09-01_13h21m_1441106515//010_CalDelScanAndSaveDacs_4mA25kV//dacParameters35_C14.dat
[12:47:07.277] <TB2> INFO: write dac parameters into /usr/local/data/M2096_XrayQualification_2015-09-01_13h21m_1441106515//010_CalDelScanAndSaveDacs_4mA25kV//dacParameters35_C15.dat
[12:47:07.277] <TB2> INFO: enter test to run
[12:47:07.277] <TB2> INFO: test: no parameter change
[12:47:07.450] <TB2> QUIET: Connection to board 162 closed.
[12:47:07.529] <TB2> INFO: pXar: this is the end, my friend
commander_HREfficiency.log
[12:47:32.321] <TB2> INFO: *** Welcome to pxar ***
[12:47:32.321] <TB2> INFO: *** Today: 2015/09/01
[12:47:32.321] <TB2> INFO: readRocDacs: /usr/local/data/M2096_XrayQualification_2015-09-01_13h21m_1441106515//011_HREfficiency_50//dacParameters35_C0.dat .. /usr/local/data/M2096_XrayQualification_2015-09-01_13h21m_1441106515//011_HREfficiency_50//dacParameters35_C15.dat
[12:47:32.321] <TB2> INFO: readTbmDacs: /usr/local/data/M2096_XrayQualification_2015-09-01_13h21m_1441106515//011_HREfficiency_50//tbmParameters_C0a.dat .. /usr/local/data/M2096_XrayQualification_2015-09-01_13h21m_1441106515//011_HREfficiency_50//tbmParameters_C0b.dat
[12:47:32.321] <TB2> INFO: readMaskFile: /usr/local/data/M2096_XrayQualification_2015-09-01_13h21m_1441106515//011_HREfficiency_50//defaultMaskFile.dat
[12:47:32.321] <TB2> INFO: readTrimFile: /usr/local/data/M2096_XrayQualification_2015-09-01_13h21m_1441106515//011_HREfficiency_50//trimParameters35_C0.dat .. /usr/local/data/M2096_XrayQualification_2015-09-01_13h21m_1441106515//011_HREfficiency_50//trimParameters35_C15.dat
[12:47:32.397] <TB2> INFO: clk: 4
[12:47:32.397] <TB2> INFO: ctr: 4
[12:47:32.397] <TB2> INFO: sda: 19
[12:47:32.397] <TB2> INFO: tin: 9
[12:47:32.397] <TB2> INFO: level: 15
[12:47:32.397] <TB2> INFO: triggerdelay: 0
[12:47:32.397] <TB2> QUIET: Instanciating API for pxar prod-10
[12:47:32.397] <TB2> INFO: Log level: INFO
[12:47:32.410] <TB2> INFO: Found DTB DTB_WXENWR
[12:47:32.419] <TB2> QUIET: Connection to board DTB_WXENWR opened.
[12:47:32.423] <TB2> INFO: DTB startup information
--- DTB info------------------------------------------
Board id: 162
HW version: DTB1.2
FW version: 4.2
SW version: 4.4
USB id: DTB_WXENWR
MAC address: 40D8551180A2
Hostname: pixelDTB162
Comment:
------------------------------------------------------
[12:47:32.425] <TB2> INFO: RPC call hashes of host and DTB match: 397073690
[12:47:33.961] <TB2> INFO: DUT info:
[12:47:33.962] <TB2> INFO: The DUT currently contains the following objects:
[12:47:33.962] <TB2> INFO: 2 TBM Cores tbm09c (2 ON)
[12:47:33.962] <TB2> INFO: TBM Core alpha (0): 7 registers set
[12:47:33.962] <TB2> INFO: TBM Core beta (1): 7 registers set
[12:47:33.962] <TB2> INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[12:47:33.962] <TB2> INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[12:47:33.962] <TB2> INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[12:47:33.962] <TB2> INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[12:47:33.962] <TB2> INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[12:47:33.962] <TB2> INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[12:47:33.962] <TB2> INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[12:47:33.962] <TB2> INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[12:47:33.962] <TB2> INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[12:47:33.962] <TB2> INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[12:47:33.962] <TB2> INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[12:47:33.962] <TB2> INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[12:47:33.962] <TB2> INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[12:47:33.962] <TB2> INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[12:47:33.962] <TB2> INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[12:47:33.963] <TB2> INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[12:47:33.963] <TB2> INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[12:47:34.364] <TB2> INFO: enter 'restricted' command line mode
[12:47:34.364] <TB2> INFO: enter test to run
[12:47:34.364] <TB2> INFO: test: HighRate no parameter change
[12:47:34.364] <TB2> INFO: running: highrate
[12:47:34.365] <TB2> INFO: readGainPedestalParameters /usr/local/data/M2096_XrayQualification_2015-09-01_13h21m_1441106515//011_HREfficiency_50//phCalibrationFitErr35_C0.dat .. /usr/local/data/M2096_XrayQualification_2015-09-01_13h21m_1441106515//011_HREfficiency_50//phCalibrationFitErr35_C15.dat
[12:47:34.508] <TB2> INFO: ----------------------------------------------------------------------
[12:47:34.508] <TB2> INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[12:47:34.508] <TB2> INFO: ----------------------------------------------------------------------
[12:47:35.117] <TB2> INFO: Expecting 208000 events.
[12:47:46.680] <TB2> INFO: 208000 events read in total (11032ms).
[12:47:46.688] <TB2> INFO: Test took 12172ms.
[12:47:47.005] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[12:47:47.328] <TB2> INFO: number of dead pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 1 0 0 0 0
[12:47:47.328] <TB2> INFO: number of red-efficiency pixels: 131 151 140 336 380 449 489 422 599 517 474 380 281 194 180 172
[12:47:47.328] <TB2> INFO: number of X-ray hits detected: 105484 115257 99872 195911 211023 235772 239883 234751 258733 252976 244852 213811 178607 107528 122899 111885
[12:47:47.328] <TB2> INFO: number of triggers sent (total per ROC): 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000
[12:47:47.328] <TB2> INFO: number of Vcal hits detected: 207865 207846 207854 207647 207580 207509 207446 207545 207337 207423 207490 207548 207706 207797 207816 207821
[12:47:47.328] <TB2> INFO: Vcal hit fiducial efficiency (%): 99.9 99.9 99.9 99.8 99.8 99.8 99.8 99.8 99.7 99.8 99.8 99.8 99.9 99.9 99.9 99.9
[12:47:47.328] <TB2> INFO: Vcal hit overall efficiency (%): 99.9 99.9 99.9 99.8 99.8 99.8 99.7 99.8 99.7 99.7 99.8 99.8 99.9 99.9 99.9 99.9
[12:47:47.328] <TB2> INFO: X-ray hit rate [MHz/cm2]: 30.9 33.8 29.3 57.4 61.9 69.1 70.3 68.8 75.8 74.1 71.8 62.7 52.4 31.5 36.0 32.8
[12:47:47.328] <TB2> INFO: PixTestHighRate::doXPixelAlive() done
[12:47:47.372] <TB2> INFO: PixTest:: pg_setup set to default.
[12:47:47.404] <TB2> INFO: enter test to run
[12:47:47.404] <TB2> INFO: test: no parameter change
[12:47:47.544] <TB2> QUIET: Connection to board 162 closed.
[12:47:47.624] <TB2> INFO: pXar: this is the end, my friend
commander_HREfficiency.log
[12:48:12.030] <TB2> INFO: *** Welcome to pxar ***
[12:48:12.030] <TB2> INFO: *** Today: 2015/09/01
[12:48:12.031] <TB2> INFO: readRocDacs: /usr/local/data/M2096_XrayQualification_2015-09-01_13h21m_1441106515//012_HREfficiency_100//dacParameters35_C0.dat .. /usr/local/data/M2096_XrayQualification_2015-09-01_13h21m_1441106515//012_HREfficiency_100//dacParameters35_C15.dat
[12:48:12.031] <TB2> INFO: readTbmDacs: /usr/local/data/M2096_XrayQualification_2015-09-01_13h21m_1441106515//012_HREfficiency_100//tbmParameters_C0a.dat .. /usr/local/data/M2096_XrayQualification_2015-09-01_13h21m_1441106515//012_HREfficiency_100//tbmParameters_C0b.dat
[12:48:12.031] <TB2> INFO: readMaskFile: /usr/local/data/M2096_XrayQualification_2015-09-01_13h21m_1441106515//012_HREfficiency_100//defaultMaskFile.dat
[12:48:12.031] <TB2> INFO: readTrimFile: /usr/local/data/M2096_XrayQualification_2015-09-01_13h21m_1441106515//012_HREfficiency_100//trimParameters35_C0.dat .. /usr/local/data/M2096_XrayQualification_2015-09-01_13h21m_1441106515//012_HREfficiency_100//trimParameters35_C15.dat
[12:48:12.102] <TB2> INFO: clk: 4
[12:48:12.102] <TB2> INFO: ctr: 4
[12:48:12.102] <TB2> INFO: sda: 19
[12:48:12.102] <TB2> INFO: tin: 9
[12:48:12.102] <TB2> INFO: level: 15
[12:48:12.102] <TB2> INFO: triggerdelay: 0
[12:48:12.102] <TB2> QUIET: Instanciating API for pxar prod-10
[12:48:12.102] <TB2> INFO: Log level: INFO
[12:48:12.115] <TB2> INFO: Found DTB DTB_WXENWR
[12:48:12.125] <TB2> QUIET: Connection to board DTB_WXENWR opened.
[12:48:12.128] <TB2> INFO: DTB startup information
--- DTB info------------------------------------------
Board id: 162
HW version: DTB1.2
FW version: 4.2
SW version: 4.4
USB id: DTB_WXENWR
MAC address: 40D8551180A2
Hostname: pixelDTB162
Comment:
------------------------------------------------------
[12:48:12.131] <TB2> INFO: RPC call hashes of host and DTB match: 397073690
[12:48:13.660] <TB2> INFO: DUT info:
[12:48:13.660] <TB2> INFO: The DUT currently contains the following objects:
[12:48:13.660] <TB2> INFO: 2 TBM Cores tbm09c (2 ON)
[12:48:13.660] <TB2> INFO: TBM Core alpha (0): 7 registers set
[12:48:13.660] <TB2> INFO: TBM Core beta (1): 7 registers set
[12:48:13.660] <TB2> INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[12:48:13.660] <TB2> INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[12:48:13.660] <TB2> INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[12:48:13.660] <TB2> INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[12:48:13.660] <TB2> INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[12:48:13.660] <TB2> INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[12:48:13.660] <TB2> INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[12:48:13.660] <TB2> INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[12:48:13.660] <TB2> INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[12:48:13.660] <TB2> INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[12:48:13.660] <TB2> INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[12:48:13.660] <TB2> INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[12:48:13.660] <TB2> INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[12:48:13.660] <TB2> INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[12:48:13.660] <TB2> INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[12:48:13.660] <TB2> INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[12:48:13.660] <TB2> INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[12:48:14.062] <TB2> INFO: enter 'restricted' command line mode
[12:48:14.062] <TB2> INFO: enter test to run
[12:48:14.062] <TB2> INFO: test: HighRate no parameter change
[12:48:14.062] <TB2> INFO: running: highrate
[12:48:14.062] <TB2> INFO: readGainPedestalParameters /usr/local/data/M2096_XrayQualification_2015-09-01_13h21m_1441106515//012_HREfficiency_100//phCalibrationFitErr35_C0.dat .. /usr/local/data/M2096_XrayQualification_2015-09-01_13h21m_1441106515//012_HREfficiency_100//phCalibrationFitErr35_C15.dat
[12:48:14.277] <TB2> INFO: ----------------------------------------------------------------------
[12:48:14.277] <TB2> INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[12:48:14.277] <TB2> INFO: ----------------------------------------------------------------------
[12:48:14.886] <TB2> INFO: Expecting 208000 events.
[12:48:31.103] <TB2> INFO: 208000 events read in total (15686ms).
[12:48:31.113] <TB2> INFO: Test took 16829ms.
[12:48:31.730] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[12:48:32.150] <TB2> INFO: number of dead pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 1 0 0 0 0
[12:48:32.150] <TB2> INFO: number of red-efficiency pixels: 475 423 495 1251 1383 1696 1764 1653 2206 1976 1804 1391 1032 617 496 588
[12:48:32.150] <TB2> INFO: number of X-ray hits detected: 211432 232370 203875 390596 416275 464073 477341 465353 512664 501432 480433 423353 354800 217078 248165 225569
[12:48:32.150] <TB2> INFO: number of triggers sent (total per ROC): 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000
[12:48:32.150] <TB2> INFO: number of Vcal hits detected: 207498 207539 207360 206383 206012 205309 205019 205718 204384 204716 205200 205937 206743 207155 207463 207368
[12:48:32.150] <TB2> INFO: Vcal hit fiducial efficiency (%): 99.8 99.8 99.8 99.3 99.2 98.9 98.8 99.0 98.4 98.6 98.8 99.2 99.5 99.7 99.8 99.7
[12:48:32.150] <TB2> INFO: Vcal hit overall efficiency (%): 99.8 99.8 99.7 99.2 99.0 98.7 98.6 98.9 98.3 98.4 98.7 99.0 99.4 99.6 99.7 99.7
[12:48:32.150] <TB2> INFO: X-ray hit rate [MHz/cm2]: 62.0 68.1 59.8 114.5 122.0 136.0 139.9 136.4 150.3 147.0 140.8 124.1 104.0 63.6 72.7 66.1
[12:48:32.150] <TB2> INFO: PixTestHighRate::doXPixelAlive() done
[12:48:32.194] <TB2> INFO: PixTest:: pg_setup set to default.
[12:48:32.228] <TB2> INFO: enter test to run
[12:48:32.228] <TB2> INFO: test: no parameter change
[12:48:32.381] <TB2> QUIET: Connection to board 162 closed.
[12:48:32.463] <TB2> INFO: pXar: this is the end, my friend
commander_HREfficiency.log
[12:48:58.000] <TB2> INFO: *** Welcome to pxar ***
[12:48:58.000] <TB2> INFO: *** Today: 2015/09/01
[12:48:58.000] <TB2> INFO: readRocDacs: /usr/local/data/M2096_XrayQualification_2015-09-01_13h21m_1441106515//013_HREfficiency_150//dacParameters35_C0.dat .. /usr/local/data/M2096_XrayQualification_2015-09-01_13h21m_1441106515//013_HREfficiency_150//dacParameters35_C15.dat
[12:48:58.001] <TB2> INFO: readTbmDacs: /usr/local/data/M2096_XrayQualification_2015-09-01_13h21m_1441106515//013_HREfficiency_150//tbmParameters_C0a.dat .. /usr/local/data/M2096_XrayQualification_2015-09-01_13h21m_1441106515//013_HREfficiency_150//tbmParameters_C0b.dat
[12:48:58.001] <TB2> INFO: readMaskFile: /usr/local/data/M2096_XrayQualification_2015-09-01_13h21m_1441106515//013_HREfficiency_150//defaultMaskFile.dat
[12:48:58.001] <TB2> INFO: readTrimFile: /usr/local/data/M2096_XrayQualification_2015-09-01_13h21m_1441106515//013_HREfficiency_150//trimParameters35_C0.dat .. /usr/local/data/M2096_XrayQualification_2015-09-01_13h21m_1441106515//013_HREfficiency_150//trimParameters35_C15.dat
[12:48:58.081] <TB2> INFO: clk: 4
[12:48:58.081] <TB2> INFO: ctr: 4
[12:48:58.081] <TB2> INFO: sda: 19
[12:48:58.081] <TB2> INFO: tin: 9
[12:48:58.081] <TB2> INFO: level: 15
[12:48:58.081] <TB2> INFO: triggerdelay: 0
[12:48:58.081] <TB2> QUIET: Instanciating API for pxar prod-10
[12:48:58.081] <TB2> INFO: Log level: INFO
[12:48:58.095] <TB2> INFO: Found DTB DTB_WXENWR
[12:48:58.105] <TB2> QUIET: Connection to board DTB_WXENWR opened.
[12:48:58.108] <TB2> INFO: DTB startup information
--- DTB info------------------------------------------
Board id: 162
HW version: DTB1.2
FW version: 4.2
SW version: 4.4
USB id: DTB_WXENWR
MAC address: 40D8551180A2
Hostname: pixelDTB162
Comment:
------------------------------------------------------
[12:48:58.111] <TB2> INFO: RPC call hashes of host and DTB match: 397073690
[12:48:59.634] <TB2> INFO: DUT info:
[12:48:59.634] <TB2> INFO: The DUT currently contains the following objects:
[12:48:59.634] <TB2> INFO: 2 TBM Cores tbm09c (2 ON)
[12:48:59.634] <TB2> INFO: TBM Core alpha (0): 7 registers set
[12:48:59.634] <TB2> INFO: TBM Core beta (1): 7 registers set
[12:48:59.634] <TB2> INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[12:48:59.634] <TB2> INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[12:48:59.634] <TB2> INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[12:48:59.634] <TB2> INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[12:48:59.634] <TB2> INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[12:48:59.634] <TB2> INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[12:48:59.634] <TB2> INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[12:48:59.634] <TB2> INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[12:48:59.634] <TB2> INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[12:48:59.634] <TB2> INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[12:48:59.634] <TB2> INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[12:48:59.634] <TB2> INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[12:48:59.634] <TB2> INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[12:48:59.634] <TB2> INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[12:48:59.634] <TB2> INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[12:48:59.634] <TB2> INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[12:48:59.634] <TB2> INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[12:49:00.035] <TB2> INFO: enter 'restricted' command line mode
[12:49:00.035] <TB2> INFO: enter test to run
[12:49:00.035] <TB2> INFO: test: HighRate no parameter change
[12:49:00.035] <TB2> INFO: running: highrate
[12:49:00.036] <TB2> INFO: readGainPedestalParameters /usr/local/data/M2096_XrayQualification_2015-09-01_13h21m_1441106515//013_HREfficiency_150//phCalibrationFitErr35_C0.dat .. /usr/local/data/M2096_XrayQualification_2015-09-01_13h21m_1441106515//013_HREfficiency_150//phCalibrationFitErr35_C15.dat
[12:49:00.191] <TB2> INFO: ----------------------------------------------------------------------
[12:49:00.191] <TB2> INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[12:49:00.191] <TB2> INFO: ----------------------------------------------------------------------
[12:49:00.805] <TB2> INFO: Expecting 208000 events.
[12:49:21.982] <TB2> INFO: 208000 events read in total (20647ms).
[12:49:21.997] <TB2> INFO: Test took 21799ms.
[12:49:22.887] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[12:49:23.385] <TB2> INFO: number of dead pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 1 0 0 0 0
[12:49:23.386] <TB2> INFO: number of red-efficiency pixels: 973 913 875 2393 2518 2982 3128 3074 3587 3305 3172 2670 2043 1146 1077 1181
[12:49:23.386] <TB2> INFO: number of X-ray hits detected: 304529 338181 297282 559760 597178 659391 682911 662124 732400 717327 686741 609208 507388 317922 359385 325120
[12:49:23.386] <TB2> INFO: number of triggers sent (total per ROC): 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000
[12:49:23.386] <TB2> INFO: number of Vcal hits detected: 206829 206900 206463 203485 202643 200562 199629 201491 197643 198312 200062 202109 204718 205762 206718 206516
[12:49:23.386] <TB2> INFO: Vcal hit fiducial efficiency (%): 99.5 99.5 99.4 98.1 97.8 96.8 96.6 97.2 95.5 95.9 96.7 97.6 98.6 99.1 99.4 99.4
[12:49:23.386] <TB2> INFO: Vcal hit overall efficiency (%): 99.4 99.5 99.3 97.8 97.4 96.4 96.0 96.9 95.0 95.3 96.2 97.2 98.4 98.9 99.4 99.3
[12:49:23.386] <TB2> INFO: X-ray hit rate [MHz/cm2]: 89.3 99.1 87.1 164.1 175.0 193.3 200.2 194.1 214.7 210.3 201.3 178.6 148.7 93.2 105.3 95.3
[12:49:23.386] <TB2> INFO: PixTestHighRate::doXPixelAlive() done
[12:49:23.430] <TB2> INFO: PixTest:: pg_setup set to default.
[12:49:23.464] <TB2> INFO: enter test to run
[12:49:23.464] <TB2> INFO: test: no parameter change
[12:49:23.604] <TB2> QUIET: Connection to board 162 closed.
[12:49:23.685] <TB2> INFO: pXar: this is the end, my friend
commander_HREfficiency.log
[12:49:48.316] <TB2> INFO: *** Welcome to pxar ***
[12:49:48.316] <TB2> INFO: *** Today: 2015/09/01
[12:49:48.316] <TB2> INFO: readRocDacs: /usr/local/data/M2096_XrayQualification_2015-09-01_13h21m_1441106515//014_HREfficiency_200//dacParameters35_C0.dat .. /usr/local/data/M2096_XrayQualification_2015-09-01_13h21m_1441106515//014_HREfficiency_200//dacParameters35_C15.dat
[12:49:48.317] <TB2> INFO: readTbmDacs: /usr/local/data/M2096_XrayQualification_2015-09-01_13h21m_1441106515//014_HREfficiency_200//tbmParameters_C0a.dat .. /usr/local/data/M2096_XrayQualification_2015-09-01_13h21m_1441106515//014_HREfficiency_200//tbmParameters_C0b.dat
[12:49:48.318] <TB2> INFO: readMaskFile: /usr/local/data/M2096_XrayQualification_2015-09-01_13h21m_1441106515//014_HREfficiency_200//defaultMaskFile.dat
[12:49:48.318] <TB2> INFO: readTrimFile: /usr/local/data/M2096_XrayQualification_2015-09-01_13h21m_1441106515//014_HREfficiency_200//trimParameters35_C0.dat .. /usr/local/data/M2096_XrayQualification_2015-09-01_13h21m_1441106515//014_HREfficiency_200//trimParameters35_C15.dat
[12:49:48.402] <TB2> INFO: clk: 4
[12:49:48.402] <TB2> INFO: ctr: 4
[12:49:48.402] <TB2> INFO: sda: 19
[12:49:48.402] <TB2> INFO: tin: 9
[12:49:48.402] <TB2> INFO: level: 15
[12:49:48.402] <TB2> INFO: triggerdelay: 0
[12:49:48.402] <TB2> QUIET: Instanciating API for pxar prod-10
[12:49:48.402] <TB2> INFO: Log level: INFO
[12:49:48.414] <TB2> INFO: Found DTB DTB_WXENWR
[12:49:48.423] <TB2> QUIET: Connection to board DTB_WXENWR opened.
[12:49:48.426] <TB2> INFO: DTB startup information
--- DTB info------------------------------------------
Board id: 162
HW version: DTB1.2
FW version: 4.2
SW version: 4.4
USB id: DTB_WXENWR
MAC address: 40D8551180A2
Hostname: pixelDTB162
Comment:
------------------------------------------------------
[12:49:48.429] <TB2> INFO: RPC call hashes of host and DTB match: 397073690
[12:49:49.969] <TB2> INFO: DUT info:
[12:49:49.969] <TB2> INFO: The DUT currently contains the following objects:
[12:49:49.969] <TB2> INFO: 2 TBM Cores tbm09c (2 ON)
[12:49:49.969] <TB2> INFO: TBM Core alpha (0): 7 registers set
[12:49:49.969] <TB2> INFO: TBM Core beta (1): 7 registers set
[12:49:49.969] <TB2> INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[12:49:49.969] <TB2> INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[12:49:49.969] <TB2> INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[12:49:49.969] <TB2> INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[12:49:49.969] <TB2> INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[12:49:49.969] <TB2> INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[12:49:49.969] <TB2> INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[12:49:49.969] <TB2> INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[12:49:49.969] <TB2> INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[12:49:49.969] <TB2> INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[12:49:49.969] <TB2> INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[12:49:49.969] <TB2> INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[12:49:49.969] <TB2> INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[12:49:49.969] <TB2> INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[12:49:49.969] <TB2> INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[12:49:49.969] <TB2> INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[12:49:49.969] <TB2> INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[12:49:50.371] <TB2> INFO: enter 'restricted' command line mode
[12:49:50.371] <TB2> INFO: enter test to run
[12:49:50.371] <TB2> INFO: test: HighRate no parameter change
[12:49:50.371] <TB2> INFO: running: highrate
[12:49:50.371] <TB2> INFO: readGainPedestalParameters /usr/local/data/M2096_XrayQualification_2015-09-01_13h21m_1441106515//014_HREfficiency_200//phCalibrationFitErr35_C0.dat .. /usr/local/data/M2096_XrayQualification_2015-09-01_13h21m_1441106515//014_HREfficiency_200//phCalibrationFitErr35_C15.dat
[12:49:50.530] <TB2> INFO: ----------------------------------------------------------------------
[12:49:50.530] <TB2> INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[12:49:50.530] <TB2> INFO: ----------------------------------------------------------------------
[12:49:51.146] <TB2> INFO: Expecting 208000 events.
[12:50:07.889] <TB2> ERROR: <datapipe.cc/CheckEventValidity:L469> Channel 2 has NoTokenPass but 2 ROCs were found

[12:50:07.889] <TB2> ERROR: <datapipe.cc/CheckEventValidity:L480> Channel 3 Number of ROCs (3) != Token Chain Length (4)

[12:50:07.889] <TB2> WARNING: Channel 2 ROC 3: Readback start marker after 15 readouts!

[12:50:08.431] <TB2> ERROR: <datapipe.cc/CheckEventValidity:L480> Channel 2 Number of ROCs (1) != Token Chain Length (4)

[12:50:08.431] <TB2> WARNING: Channel 3 ROC 1: Readback start marker after 12 readouts!

[12:50:08.431] <TB2> WARNING: Channel 3 ROC 2: Readback start marker after 12 readouts!

[12:50:08.431] <TB2> ERROR: <datapipe.cc/CheckEventValidity:L480> Channel 3 Number of ROCs (5) != Token Chain Length (4)

[12:50:08.431] <TB2> ERROR: <datapipe.cc/CheckEventID:L453> Channel 2 Event ID mismatch: local ID (88) != TBM ID (60)

[12:50:08.431] <TB2> WARNING: Channel 3 ROC 2: Readback start marker after 4 readouts!

[12:50:08.431] <TB2> WARNING: Channel 2 ROC 2: Readback start marker after 15 readouts!

[12:50:08.431] <TB2> WARNING: Channel 2 ROC 3: Readback start marker after 15 readouts!

[12:50:10.000] <TB2> CRITICAL: <hal.cc/condenseTriggers:L1895> Data size does not correspond to 50 triggers! Aborting data processing!

[12:50:10.018] <TB2> INFO: 0 events read in total (18341ms).
[12:50:10.177] <TB2> ERROR: <datapipe.cc/CheckEventValidity:L480> Channel 0 Number of ROCs (2) != Token Chain Length (4)

[12:50:10.177] <TB2> ERROR: <datapipe.cc/CheckEventValidity:L480> Channel 1 Number of ROCs (3) != Token Chain Length (4)

[12:50:10.177] <TB2> ERROR: <datapipe.cc/CheckEventValidity:L480> Channel 2 Number of ROCs (2) != Token Chain Length (4)

[12:50:10.177] <TB2> ERROR: <datapipe.cc/CheckEventValidity:L480> Channel 3 Number of ROCs (2) != Token Chain Length (4)

[12:50:10.177] <TB2> WARNING: Channel 0 ROC 3: Readback start marker after 15 readouts!

[12:50:10.177] <TB2> WARNING: Channel 2 ROC 3: Readback start marker after 15 readouts!

[12:50:10.177] <TB2> WARNING: Channel 3 ROC 3: Readback start marker after 15 readouts!

[12:50:10.962] <TB2> INFO: 5150 events read in total (19286ms).
[12:50:10.964] <TB2> CRITICAL: <hal.cc/MultiRocAllPixelsCalibrate:L779> Incomplete DAQ data readout! Missing 4057 Events.

[12:50:11.558] <TB2> INFO: Expecting 208000 events.
[12:50:36.464] <TB2> INFO: 203600 events read in total (24375ms).
[12:50:37.327] <TB2> INFO: 208000 events read in total (25238ms).
[12:50:37.355] <TB2> INFO: Test took 26391ms.
[12:50:38.501] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[12:50:39.099] <TB2> INFO: number of dead pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 1 0 0 0 0
[12:50:39.099] <TB2> INFO: number of red-efficiency pixels: 1751 1647 1319 3480 3572 3920 3936 3981 4108 4041 4006 3674 3151 1725 1920 1993
[12:50:39.099] <TB2> INFO: number of X-ray hits detected: 410125 455351 401591 745566 794357 875988 910925 885705 974267 963115 911353 813036 670402 429934 484767 436844
[12:50:39.099] <TB2> INFO: number of triggers sent (total per ROC): 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000
[12:50:39.099] <TB2> INFO: number of Vcal hits detected: 205408 205802 204722 197404 195592 190737 189147 192492 184580 185912 189095 194642 200535 203004 205162 204781
[12:50:39.099] <TB2> INFO: Vcal hit fiducial efficiency (%): 98.9 99.0 98.8 95.5 94.8 92.5 92.0 93.2 89.6 90.4 91.9 94.4 96.8 98.0 98.7 98.6
[12:50:39.099] <TB2> INFO: Vcal hit overall efficiency (%): 98.8 98.9 98.4 94.9 94.0 91.7 90.9 92.5 88.7 89.4 90.9 93.6 96.4 97.6 98.6 98.5
[12:50:39.099] <TB2> INFO: X-ray hit rate [MHz/cm2]: 120.2 133.5 117.7 218.5 232.8 256.8 267.0 259.6 285.6 282.3 267.1 238.3 196.5 126.0 142.1 128.0
[12:50:39.099] <TB2> INFO: PixTestHighRate::doXPixelAlive() done
[12:50:39.143] <TB2> INFO: PixTest:: pg_setup set to default.
[12:50:39.180] <TB2> INFO: enter test to run
[12:50:39.180] <TB2> INFO: test: no parameter change
[12:50:39.353] <TB2> QUIET: Connection to board 162 closed.
[12:50:39.434] <TB2> INFO: pXar: this is the end, my friend
commander_HREfficiency.log
[12:51:05.447] <TB2> INFO: *** Welcome to pxar ***
[12:51:05.447] <TB2> INFO: *** Today: 2015/09/01
[12:51:05.447] <TB2> INFO: readRocDacs: /usr/local/data/M2096_XrayQualification_2015-09-01_13h21m_1441106515//015_HREfficiency_250//dacParameters35_C0.dat .. /usr/local/data/M2096_XrayQualification_2015-09-01_13h21m_1441106515//015_HREfficiency_250//dacParameters35_C15.dat
[12:51:05.448] <TB2> INFO: readTbmDacs: /usr/local/data/M2096_XrayQualification_2015-09-01_13h21m_1441106515//015_HREfficiency_250//tbmParameters_C0a.dat .. /usr/local/data/M2096_XrayQualification_2015-09-01_13h21m_1441106515//015_HREfficiency_250//tbmParameters_C0b.dat
[12:51:05.448] <TB2> INFO: readMaskFile: /usr/local/data/M2096_XrayQualification_2015-09-01_13h21m_1441106515//015_HREfficiency_250//defaultMaskFile.dat
[12:51:05.448] <TB2> INFO: readTrimFile: /usr/local/data/M2096_XrayQualification_2015-09-01_13h21m_1441106515//015_HREfficiency_250//trimParameters35_C0.dat .. /usr/local/data/M2096_XrayQualification_2015-09-01_13h21m_1441106515//015_HREfficiency_250//trimParameters35_C15.dat
[12:51:05.521] <TB2> INFO: clk: 4
[12:51:05.521] <TB2> INFO: ctr: 4
[12:51:05.521] <TB2> INFO: sda: 19
[12:51:05.521] <TB2> INFO: tin: 9
[12:51:05.521] <TB2> INFO: level: 15
[12:51:05.521] <TB2> INFO: triggerdelay: 0
[12:51:05.522] <TB2> QUIET: Instanciating API for pxar prod-10
[12:51:05.522] <TB2> INFO: Log level: INFO
[12:51:05.534] <TB2> INFO: Found DTB DTB_WXENWR
[12:51:05.544] <TB2> QUIET: Connection to board DTB_WXENWR opened.
[12:51:05.547] <TB2> INFO: DTB startup information
--- DTB info------------------------------------------
Board id: 162
HW version: DTB1.2
FW version: 4.2
SW version: 4.4
USB id: DTB_WXENWR
MAC address: 40D8551180A2
Hostname: pixelDTB162
Comment:
------------------------------------------------------
[12:51:05.550] <TB2> INFO: RPC call hashes of host and DTB match: 397073690
[12:51:07.089] <TB2> INFO: DUT info:
[12:51:07.089] <TB2> INFO: The DUT currently contains the following objects:
[12:51:07.089] <TB2> INFO: 2 TBM Cores tbm09c (2 ON)
[12:51:07.089] <TB2> INFO: TBM Core alpha (0): 7 registers set
[12:51:07.089] <TB2> INFO: TBM Core beta (1): 7 registers set
[12:51:07.089] <TB2> INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[12:51:07.089] <TB2> INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[12:51:07.089] <TB2> INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[12:51:07.089] <TB2> INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[12:51:07.089] <TB2> INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[12:51:07.089] <TB2> INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[12:51:07.089] <TB2> INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[12:51:07.089] <TB2> INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[12:51:07.089] <TB2> INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[12:51:07.089] <TB2> INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[12:51:07.089] <TB2> INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[12:51:07.089] <TB2> INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[12:51:07.089] <TB2> INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[12:51:07.089] <TB2> INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[12:51:07.090] <TB2> INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[12:51:07.090] <TB2> INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[12:51:07.090] <TB2> INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[12:51:07.491] <TB2> INFO: enter 'restricted' command line mode
[12:51:07.491] <TB2> INFO: enter test to run
[12:51:07.491] <TB2> INFO: test: HighRate no parameter change
[12:51:07.491] <TB2> INFO: running: highrate
[12:51:07.491] <TB2> INFO: readGainPedestalParameters /usr/local/data/M2096_XrayQualification_2015-09-01_13h21m_1441106515//015_HREfficiency_250//phCalibrationFitErr35_C0.dat .. /usr/local/data/M2096_XrayQualification_2015-09-01_13h21m_1441106515//015_HREfficiency_250//phCalibrationFitErr35_C15.dat
[12:51:07.638] <TB2> INFO: ----------------------------------------------------------------------
[12:51:07.638] <TB2> INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[12:51:07.639] <TB2> INFO: ----------------------------------------------------------------------
[12:51:08.284] <TB2> INFO: Expecting 208000 events.
[12:51:34.441] <TB2> INFO: 199250 events read in total (25626ms).
[12:51:35.841] <TB2> INFO: 208000 events read in total (27026ms).
[12:51:35.862] <TB2> INFO: Test took 28216ms.
[12:51:37.058] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[12:51:37.672] <TB2> INFO: number of dead pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 1 0 0 0 0
[12:51:37.672] <TB2> INFO: number of red-efficiency pixels: 1874 1772 1456 3579 3693 3976 4002 4018 4116 4076 4045 3765 3258 1864 2010 2135
[12:51:37.672] <TB2> INFO: number of X-ray hits detected: 423652 472100 420674 766169 819081 899968 935268 916931 1012376 991490 938060 835312 696248 447493 502564 449710
[12:51:37.672] <TB2> INFO: number of triggers sent (total per ROC): 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000
[12:51:37.672] <TB2> INFO: number of Vcal hits detected: 205184 205466 204351 196755 194512 189601 187532 190837 182140 184339 187629 193406 199844 202556 204874 204564
[12:51:37.672] <TB2> INFO: Vcal hit fiducial efficiency (%): 98.8 98.9 98.6 95.2 94.3 92.0 91.3 92.5 88.6 89.7 91.2 93.8 96.5 97.8 98.6 98.5
[12:51:37.672] <TB2> INFO: Vcal hit overall efficiency (%): 98.6 98.8 98.2 94.6 93.5 91.2 90.2 91.7 87.6 88.6 90.2 93.0 96.1 97.4 98.5 98.3
[12:51:37.672] <TB2> INFO: X-ray hit rate [MHz/cm2]: 124.2 138.4 123.3 224.6 240.1 263.8 274.1 268.8 296.7 290.6 275.0 244.8 204.1 131.2 147.3 131.8
[12:51:37.672] <TB2> INFO: PixTestHighRate::doXPixelAlive() done
[12:51:37.717] <TB2> INFO: PixTest:: pg_setup set to default.
[12:51:37.751] <TB2> INFO: enter test to run
[12:51:37.751] <TB2> INFO: test: no parameter change
[12:51:37.919] <TB2> QUIET: Connection to board 162 closed.
[12:51:37.999] <TB2> INFO: pXar: this is the end, my friend
MoReWeb-v1.0.0-5-g82d9ff6 on branch psi46master