Test Date: 2015-07-07 08:06
Analysis date: 2016-05-25 21:35
Logfile
commander_PixelAlive.log
[06:06:45.831] <TB3> INFO: *** Welcome to pxar ***
[06:06:45.831] <TB3> INFO: *** Today: 2015/07/07
[06:06:45.831] <TB3> INFO: readRocDacs: /usr/local/data/M2031_XRayHRQualification_2015-07-07_08h06m_1436249186//000_PixelAlive_p17//dacParameters35_C0.dat .. /usr/local/data/M2031_XRayHRQualification_2015-07-07_08h06m_1436249186//000_PixelAlive_p17//dacParameters35_C15.dat
[06:06:45.832] <TB3> INFO: readTbmDacs: /usr/local/data/M2031_XRayHRQualification_2015-07-07_08h06m_1436249186//000_PixelAlive_p17//tbmParameters_C0a.dat .. /usr/local/data/M2031_XRayHRQualification_2015-07-07_08h06m_1436249186//000_PixelAlive_p17//tbmParameters_C0b.dat
[06:06:45.832] <TB3> INFO: readMaskFile: /usr/local/data/M2031_XRayHRQualification_2015-07-07_08h06m_1436249186//000_PixelAlive_p17//defaultMaskFile.dat
[06:06:45.832] <TB3> INFO: readTrimFile: /usr/local/data/M2031_XRayHRQualification_2015-07-07_08h06m_1436249186//000_PixelAlive_p17//trimParameters35_C0.dat .. /usr/local/data/M2031_XRayHRQualification_2015-07-07_08h06m_1436249186//000_PixelAlive_p17//trimParameters35_C15.dat
[06:06:45.907] <TB3> INFO: clk: 4
[06:06:45.907] <TB3> INFO: ctr: 4
[06:06:45.907] <TB3> INFO: sda: 19
[06:06:45.907] <TB3> INFO: tin: 9
[06:06:45.907] <TB3> INFO: level: 15
[06:06:45.907] <TB3> INFO: triggerdelay: 0
[06:06:45.907] <TB3> QUIET: Instanciating API for pxar v2.2.5+45~gbf85984
[06:06:45.907] <TB3> INFO: Log level: INFO
[06:06:45.921] <TB3> INFO: Found DTB DTB_WWV6Z5
[06:06:45.937] <TB3> QUIET: Connection to board DTB_WWV6Z5 opened.
[06:06:45.940] <TB3> INFO: DTB startup information
--- DTB info------------------------------------------
Board id: 121
HW version: DTB1.2
FW version: 4.0
SW version: 4.0
USB id: DTB_WWV6Z5
MAC address: 40D855118079
Hostname: pixelDTB121
Comment:
------------------------------------------------------
[06:06:45.943] <TB3> INFO: RPC call hashes of host and DTB match: 447413373
[06:06:47.597] <TB3> INFO: DUT info:
[06:06:47.597] <TB3> INFO: The DUT currently contains the following objects:
[06:06:47.597] <TB3> INFO: 2 TBM Cores tbm09c (2 ON)
[06:06:47.597] <TB3> INFO: TBM Core alpha (0): 7 registers set
[06:06:47.597] <TB3> INFO: TBM Core beta (1): 7 registers set
[06:06:47.597] <TB3> INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[06:06:47.597] <TB3> INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[06:06:47.597] <TB3> INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[06:06:47.597] <TB3> INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[06:06:47.597] <TB3> INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[06:06:47.597] <TB3> INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[06:06:47.597] <TB3> INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[06:06:47.597] <TB3> INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[06:06:47.597] <TB3> INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[06:06:47.597] <TB3> INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[06:06:47.597] <TB3> INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[06:06:47.597] <TB3> INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[06:06:47.597] <TB3> INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[06:06:47.597] <TB3> INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[06:06:47.598] <TB3> INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[06:06:47.598] <TB3> INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[06:06:47.598] <TB3> INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[06:06:47.999] <TB3> INFO: enter 'restricted' command line mode
[06:06:47.999] <TB3> INFO: enter test to run
[06:06:47.999] <TB3> INFO: test: PixelAlive no parameter change
[06:06:47.999] <TB3> INFO: running: pixelalive
[06:06:48.003] <TB3> INFO: ######################################################################
[06:06:48.003] <TB3> INFO: PixTestAlive::doTest()
[06:06:48.003] <TB3> INFO: ######################################################################
[06:06:48.005] <TB3> INFO: ----------------------------------------------------------------------
[06:06:48.005] <TB3> INFO: PixTestAlive::aliveTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[06:06:48.005] <TB3> INFO: ----------------------------------------------------------------------
[06:06:51.877] <TB3> INFO: Test took 3871ms.
[06:06:51.897] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[06:06:52.150] <TB3> INFO: PixTestAlive::aliveTest() done
[06:06:52.150] <TB3> INFO: number of dead pixels (per ROC): 0 0 0 3 0 0 0 1 0 0 0 0 1 0 0 0
[06:06:52.151] <TB3> INFO: ----------------------------------------------------------------------
[06:06:52.151] <TB3> INFO: PixTestAlive::maskTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[06:06:52.151] <TB3> INFO: ----------------------------------------------------------------------
[06:06:54.983] <TB3> INFO: Test took 2829ms.
[06:06:54.985] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[06:06:54.986] <TB3> INFO: mask vs. old pixelAlive PixelAlive_C0_V0 .. PixelAlive_C15_V0
[06:06:55.202] <TB3> INFO: PixTestAlive::maskTest() done
[06:06:55.202] <TB3> INFO: number of mask-defect pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[06:06:55.204] <TB3> INFO: ----------------------------------------------------------------------
[06:06:55.204] <TB3> INFO: PixTestAlive::addressDecodingTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[06:06:55.204] <TB3> INFO: ----------------------------------------------------------------------
[06:06:58.993] <TB3> INFO: Test took 3789ms.
[06:06:59.018] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[06:06:59.245] <TB3> INFO: PixTestAlive::addressDecodingTest() done
[06:06:59.245] <TB3> INFO: number of address-decoding pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[06:06:59.245] <TB3> INFO: PixTestAlive::doTest() done, duration: 11 seconds
[06:06:59.282] <TB3> INFO: enter test to run
[06:06:59.282] <TB3> INFO: test: no parameter change
[06:06:59.396] <TB3> QUIET: Connection to board 121 closed.
[06:06:59.476] <TB3> INFO: pXar: this is the end, my friend
commander_RetrimHotPixels.log
[06:07:10.799] <TB3> INFO: *** Welcome to pxar ***
[06:07:10.799] <TB3> INFO: *** Today: 2015/07/07
[06:07:10.799] <TB3> INFO: readRocDacs: /usr/local/data/M2031_XRayHRQualification_2015-07-07_08h06m_1436249186//001_RetrimHotPixels_p17//dacParameters35_C0.dat .. /usr/local/data/M2031_XRayHRQualification_2015-07-07_08h06m_1436249186//001_RetrimHotPixels_p17//dacParameters35_C15.dat
[06:07:10.799] <TB3> INFO: readTbmDacs: /usr/local/data/M2031_XRayHRQualification_2015-07-07_08h06m_1436249186//001_RetrimHotPixels_p17//tbmParameters_C0a.dat .. /usr/local/data/M2031_XRayHRQualification_2015-07-07_08h06m_1436249186//001_RetrimHotPixels_p17//tbmParameters_C0b.dat
[06:07:10.800] <TB3> INFO: readMaskFile: /usr/local/data/M2031_XRayHRQualification_2015-07-07_08h06m_1436249186//001_RetrimHotPixels_p17//defaultMaskFile.dat
[06:07:10.800] <TB3> INFO: readTrimFile: /usr/local/data/M2031_XRayHRQualification_2015-07-07_08h06m_1436249186//001_RetrimHotPixels_p17//trimParameters35_C0.dat .. /usr/local/data/M2031_XRayHRQualification_2015-07-07_08h06m_1436249186//001_RetrimHotPixels_p17//trimParameters35_C15.dat
[06:07:10.881] <TB3> INFO: clk: 4
[06:07:10.881] <TB3> INFO: ctr: 4
[06:07:10.881] <TB3> INFO: sda: 19
[06:07:10.881] <TB3> INFO: tin: 9
[06:07:10.881] <TB3> INFO: level: 15
[06:07:10.881] <TB3> INFO: triggerdelay: 0
[06:07:10.881] <TB3> QUIET: Instanciating API for pxar v2.2.5+45~gbf85984
[06:07:10.881] <TB3> INFO: Log level: INFO
[06:07:10.895] <TB3> INFO: Found DTB DTB_WWV6Z5
[06:07:10.912] <TB3> QUIET: Connection to board DTB_WWV6Z5 opened.
[06:07:10.915] <TB3> INFO: DTB startup information
--- DTB info------------------------------------------
Board id: 121
HW version: DTB1.2
FW version: 4.0
SW version: 4.0
USB id: DTB_WWV6Z5
MAC address: 40D855118079
Hostname: pixelDTB121
Comment:
------------------------------------------------------
[06:07:10.918] <TB3> INFO: RPC call hashes of host and DTB match: 447413373
[06:07:12.466] <TB3> INFO: DUT info:
[06:07:12.466] <TB3> INFO: The DUT currently contains the following objects:
[06:07:12.466] <TB3> INFO: 2 TBM Cores tbm09c (2 ON)
[06:07:12.466] <TB3> INFO: TBM Core alpha (0): 7 registers set
[06:07:12.466] <TB3> INFO: TBM Core beta (1): 7 registers set
[06:07:12.466] <TB3> INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[06:07:12.466] <TB3> INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[06:07:12.466] <TB3> INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[06:07:12.466] <TB3> INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[06:07:12.466] <TB3> INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[06:07:12.466] <TB3> INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[06:07:12.466] <TB3> INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[06:07:12.466] <TB3> INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[06:07:12.466] <TB3> INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[06:07:12.466] <TB3> INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[06:07:12.466] <TB3> INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[06:07:12.466] <TB3> INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[06:07:12.466] <TB3> INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[06:07:12.466] <TB3> INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[06:07:12.466] <TB3> INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[06:07:12.466] <TB3> INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[06:07:12.466] <TB3> INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[06:07:12.867] <TB3> INFO: enter 'restricted' command line mode
[06:07:12.867] <TB3> INFO: enter test to run
[06:07:12.867] <TB3> INFO: test: delay setting parameters: ->5<-
[06:07:12.867] <TB3> INFO: delay test by 5 seconds...
[06:07:17.867] <TB3> INFO: enter test to run
[06:07:17.867] <TB3> INFO: test: highrate no parameter change
[06:07:17.867] <TB3> INFO: running: highrate
[06:07:17.867] <TB3> INFO: readGainPedestalParameters /usr/local/data/M2031_XRayHRQualification_2015-07-07_08h06m_1436249186//001_RetrimHotPixels_p17//phCalibrationFitErr35_C0.dat .. /usr/local/data/M2031_XRayHRQualification_2015-07-07_08h06m_1436249186//001_RetrimHotPixels_p17//phCalibrationFitErr35_C15.dat
[06:07:18.013] <TB3> INFO: ----------------------------------------------------------------------
[06:07:18.013] <TB3> INFO: PixTest::trimHotPixels() running for 1 seconds with 100 kHz trigger rate
[06:07:18.013] <TB3> INFO: ----------------------------------------------------------------------
[06:07:18.013] <TB3> INFO: THR = 150, corresponding to ~ 400 MHz/cm2
[06:07:18.013] <TB3> INFO: edge/corner pixel THR is adjusted
[06:07:18.013] <TB3> INFO: PixTestHighRate::trimHotPixels: step 0...
[06:07:18.976] <TB3> INFO: Collecting data for 1 seconds...
[06:07:19.977] <TB3> INFO: Done with hot pixel readout
[06:07:25.662] <TB3> INFO: PixTest:: pg_setup set to default.
[06:07:25.663] <TB3> INFO: 7 hot pixels found in step 0
[06:07:26.626] <TB3> INFO: Collecting data for 1 seconds...
[06:07:27.628] <TB3> INFO: Done with hot pixel readout
[06:07:33.379] <TB3> INFO: PixTest:: pg_setup set to default.
[06:07:33.380] <TB3> INFO: 4 hot pixels found in step 1
[06:07:34.343] <TB3> INFO: Collecting data for 1 seconds...
[06:07:35.345] <TB3> INFO: Done with hot pixel readout
[06:07:40.849] <TB3> INFO: PixTest:: pg_setup set to default.
[06:07:40.850] <TB3> INFO: 4 hot pixels found in step 2
[06:07:41.813] <TB3> INFO: Collecting data for 1 seconds...
[06:07:42.815] <TB3> INFO: Done with hot pixel readout
[06:07:48.429] <TB3> INFO: PixTest:: pg_setup set to default.
[06:07:48.430] <TB3> INFO: 1 hot pixels found in step 3
[06:07:49.393] <TB3> INFO: Collecting data for 1 seconds...
[06:07:50.394] <TB3> INFO: Done with hot pixel readout
[06:07:56.060] <TB3> INFO: PixTest:: pg_setup set to default.
[06:07:56.061] <TB3> INFO: 0 hot pixels found in step 4
[06:07:56.071] <TB3> INFO: 0 hot pixels could not be trimmed and have been masked.
[06:07:56.099] <TB3> INFO: PixTest::trimHotPixels() done
[06:07:56.099] <TB3> INFO: write trim parameters into /usr/local/data/M2031_XRayHRQualification_2015-07-07_08h06m_1436249186//001_RetrimHotPixels_p17//trimParameters35_C0.dat
[06:07:56.105] <TB3> INFO: write trim parameters into /usr/local/data/M2031_XRayHRQualification_2015-07-07_08h06m_1436249186//001_RetrimHotPixels_p17//trimParameters35_C1.dat
[06:07:56.111] <TB3> INFO: write trim parameters into /usr/local/data/M2031_XRayHRQualification_2015-07-07_08h06m_1436249186//001_RetrimHotPixels_p17//trimParameters35_C2.dat
[06:07:56.118] <TB3> INFO: write trim parameters into /usr/local/data/M2031_XRayHRQualification_2015-07-07_08h06m_1436249186//001_RetrimHotPixels_p17//trimParameters35_C3.dat
[06:07:56.123] <TB3> INFO: write trim parameters into /usr/local/data/M2031_XRayHRQualification_2015-07-07_08h06m_1436249186//001_RetrimHotPixels_p17//trimParameters35_C4.dat
[06:07:56.129] <TB3> INFO: write trim parameters into /usr/local/data/M2031_XRayHRQualification_2015-07-07_08h06m_1436249186//001_RetrimHotPixels_p17//trimParameters35_C5.dat
[06:07:56.136] <TB3> INFO: write trim parameters into /usr/local/data/M2031_XRayHRQualification_2015-07-07_08h06m_1436249186//001_RetrimHotPixels_p17//trimParameters35_C6.dat
[06:07:56.142] <TB3> INFO: write trim parameters into /usr/local/data/M2031_XRayHRQualification_2015-07-07_08h06m_1436249186//001_RetrimHotPixels_p17//trimParameters35_C7.dat
[06:07:56.148] <TB3> INFO: write trim parameters into /usr/local/data/M2031_XRayHRQualification_2015-07-07_08h06m_1436249186//001_RetrimHotPixels_p17//trimParameters35_C8.dat
[06:07:56.154] <TB3> INFO: write trim parameters into /usr/local/data/M2031_XRayHRQualification_2015-07-07_08h06m_1436249186//001_RetrimHotPixels_p17//trimParameters35_C9.dat
[06:07:56.160] <TB3> INFO: write trim parameters into /usr/local/data/M2031_XRayHRQualification_2015-07-07_08h06m_1436249186//001_RetrimHotPixels_p17//trimParameters35_C10.dat
[06:07:56.167] <TB3> INFO: write trim parameters into /usr/local/data/M2031_XRayHRQualification_2015-07-07_08h06m_1436249186//001_RetrimHotPixels_p17//trimParameters35_C11.dat
[06:07:56.173] <TB3> INFO: write trim parameters into /usr/local/data/M2031_XRayHRQualification_2015-07-07_08h06m_1436249186//001_RetrimHotPixels_p17//trimParameters35_C12.dat
[06:07:56.179] <TB3> INFO: write trim parameters into /usr/local/data/M2031_XRayHRQualification_2015-07-07_08h06m_1436249186//001_RetrimHotPixels_p17//trimParameters35_C13.dat
[06:07:56.185] <TB3> INFO: write trim parameters into /usr/local/data/M2031_XRayHRQualification_2015-07-07_08h06m_1436249186//001_RetrimHotPixels_p17//trimParameters35_C14.dat
[06:07:56.191] <TB3> INFO: write trim parameters into /usr/local/data/M2031_XRayHRQualification_2015-07-07_08h06m_1436249186//001_RetrimHotPixels_p17//trimParameters35_C15.dat
[06:07:56.197] <TB3> INFO: write masked pixels into /usr/local/data/M2031_XRayHRQualification_2015-07-07_08h06m_1436249186//001_RetrimHotPixels_p17//defaultMaskFile.dat
[06:07:56.229] <TB3> INFO: enter test to run
[06:07:56.229] <TB3> INFO: test: no parameter change
[06:07:56.396] <TB3> QUIET: Connection to board 121 closed.
[06:07:56.476] <TB3> INFO: pXar: this is the end, my friend
commander_CalDelScanAndSaveDacs.log
[06:09:10.351] <TB3> INFO: *** Welcome to pxar ***
[06:09:10.351] <TB3> INFO: *** Today: 2015/07/07
[06:09:10.351] <TB3> INFO: readRocDacs: /usr/local/data/M2031_XRayHRQualification_2015-07-07_08h06m_1436249186//002_CalDelScanAndSaveDacs_p17//dacParameters35_C0.dat .. /usr/local/data/M2031_XRayHRQualification_2015-07-07_08h06m_1436249186//002_CalDelScanAndSaveDacs_p17//dacParameters35_C15.dat
[06:09:10.352] <TB3> INFO: readTbmDacs: /usr/local/data/M2031_XRayHRQualification_2015-07-07_08h06m_1436249186//002_CalDelScanAndSaveDacs_p17//tbmParameters_C0a.dat .. /usr/local/data/M2031_XRayHRQualification_2015-07-07_08h06m_1436249186//002_CalDelScanAndSaveDacs_p17//tbmParameters_C0b.dat
[06:09:10.352] <TB3> INFO: readMaskFile: /usr/local/data/M2031_XRayHRQualification_2015-07-07_08h06m_1436249186//002_CalDelScanAndSaveDacs_p17//defaultMaskFile.dat
[06:09:10.352] <TB3> INFO: readTrimFile: /usr/local/data/M2031_XRayHRQualification_2015-07-07_08h06m_1436249186//002_CalDelScanAndSaveDacs_p17//trimParameters35_C0.dat .. /usr/local/data/M2031_XRayHRQualification_2015-07-07_08h06m_1436249186//002_CalDelScanAndSaveDacs_p17//trimParameters35_C15.dat
[06:09:10.429] <TB3> INFO: clk: 4
[06:09:10.429] <TB3> INFO: ctr: 4
[06:09:10.429] <TB3> INFO: sda: 19
[06:09:10.429] <TB3> INFO: tin: 9
[06:09:10.429] <TB3> INFO: level: 15
[06:09:10.429] <TB3> INFO: triggerdelay: 0
[06:09:10.429] <TB3> QUIET: Instanciating API for pxar v2.2.5+45~gbf85984
[06:09:10.429] <TB3> INFO: Log level: INFO
[06:09:10.443] <TB3> INFO: Found DTB DTB_WWV6Z5
[06:09:10.459] <TB3> QUIET: Connection to board DTB_WWV6Z5 opened.
[06:09:10.463] <TB3> INFO: DTB startup information
--- DTB info------------------------------------------
Board id: 121
HW version: DTB1.2
FW version: 4.0
SW version: 4.0
USB id: DTB_WWV6Z5
MAC address: 40D855118079
Hostname: pixelDTB121
Comment:
------------------------------------------------------
[06:09:10.465] <TB3> INFO: RPC call hashes of host and DTB match: 447413373
[06:09:12.009] <TB3> INFO: DUT info:
[06:09:12.009] <TB3> INFO: The DUT currently contains the following objects:
[06:09:12.009] <TB3> INFO: 2 TBM Cores tbm09c (2 ON)
[06:09:12.009] <TB3> INFO: TBM Core alpha (0): 7 registers set
[06:09:12.009] <TB3> INFO: TBM Core beta (1): 7 registers set
[06:09:12.009] <TB3> INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[06:09:12.009] <TB3> INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[06:09:12.009] <TB3> INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[06:09:12.009] <TB3> INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[06:09:12.009] <TB3> INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[06:09:12.009] <TB3> INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[06:09:12.009] <TB3> INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[06:09:12.009] <TB3> INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[06:09:12.009] <TB3> INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[06:09:12.010] <TB3> INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[06:09:12.010] <TB3> INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[06:09:12.010] <TB3> INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[06:09:12.010] <TB3> INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[06:09:12.010] <TB3> INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[06:09:12.010] <TB3> INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[06:09:12.010] <TB3> INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[06:09:12.010] <TB3> INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[06:09:12.411] <TB3> INFO: enter 'restricted' command line mode
[06:09:12.411] <TB3> INFO: enter test to run
[06:09:12.411] <TB3> INFO: test: HighRate no parameter change
[06:09:12.411] <TB3> INFO: running: highrate
[06:09:12.411] <TB3> INFO: readGainPedestalParameters /usr/local/data/M2031_XRayHRQualification_2015-07-07_08h06m_1436249186//002_CalDelScanAndSaveDacs_p17//phCalibrationFitErr35_C0.dat .. /usr/local/data/M2031_XRayHRQualification_2015-07-07_08h06m_1436249186//002_CalDelScanAndSaveDacs_p17//phCalibrationFitErr35_C15.dat
[06:09:12.607] <TB3> INFO: ----------------------------------------------------------------------
[06:09:12.607] <TB3> INFO: PixTestHighRate::calDelScan() ntrig = 10, vcal = 200
[06:09:12.607] <TB3> INFO: ----------------------------------------------------------------------
[06:09:13.689] <TB3> INFO: Test took 1076ms.
[06:09:17.256] <TB3> INFO: Test took 3564ms.
[06:09:17.373] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[06:09:21.254] <TB3> INFO: Test took 3857ms.
[06:09:21.380] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[06:09:25.350] <TB3> INFO: Test took 3945ms.
[06:09:25.491] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[06:09:29.302] <TB3> INFO: Test took 3785ms.
[06:09:29.443] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[06:09:33.167] <TB3> INFO: Test took 3698ms.
[06:09:33.305] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[06:09:37.165] <TB3> INFO: Test took 3834ms.
[06:09:37.306] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[06:09:41.136] <TB3> INFO: Test took 3804ms.
[06:09:41.281] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[06:09:45.311] <TB3> INFO: Test took 3997ms.
[06:09:45.448] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[06:09:49.053] <TB3> ERROR: <datapipe.cc/CheckEventValidity:L437> Number of ROCs (3) != Token Chain Length (4)

[06:09:49.054] <TB3> ERROR: <datapipe.cc/CheckEventValidity:L437> Number of ROCs (3) != Token Chain Length (4)

[06:09:49.054] <TB3> WARNING: ROC 3: Readback start marker after 15 readouts!

[06:09:49.054] <TB3> WARNING: ROC 3: Readback start marker after 15 readouts!

[06:09:49.375] <TB3> ERROR: <datapipe.cc/CheckEventValidity:L437> Number of ROCs (2) != Token Chain Length (4)

[06:09:49.375] <TB3> ERROR: <datapipe.cc/CheckEventValidity:L437> Number of ROCs (2) != Token Chain Length (4)

[06:09:49.375] <TB3> WARNING: ROC 2: Readback start marker after 15 readouts!

[06:09:49.375] <TB3> WARNING: ROC 2: Readback start marker after 15 readouts!

[06:09:49.375] <TB3> WARNING: ROC 3: Readback start marker after 15 readouts!

[06:09:49.375] <TB3> WARNING: ROC 3: Readback start marker after 15 readouts!

[06:09:49.382] <TB3> INFO: Test took 3910ms.
[06:09:49.513] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[06:09:53.373] <TB3> INFO: Test took 3835ms.
[06:09:53.506] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[06:09:57.425] <TB3> INFO: Test took 3894ms.
[06:09:57.562] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[06:10:01.470] <TB3> INFO: Test took 3882ms.
[06:10:01.608] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[06:10:05.288] <TB3> INFO: Test took 3655ms.
[06:10:05.417] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[06:10:09.327] <TB3> INFO: Test took 3885ms.
[06:10:09.463] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[06:10:13.300] <TB3> INFO: Test took 3811ms.
[06:10:13.450] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[06:10:17.405] <TB3> INFO: Test took 3928ms.
[06:10:17.537] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[06:10:21.624] <TB3> INFO: Test took 4062ms.
[06:10:21.750] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[06:10:25.721] <TB3> INFO: Test took 3946ms.
[06:10:25.856] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[06:10:29.839] <TB3> INFO: Test took 3952ms.
[06:10:29.968] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[06:10:33.974] <TB3> INFO: Test took 3981ms.
[06:10:34.119] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[06:10:34.438] <TB3> INFO: enter test to run
[06:10:34.438] <TB3> INFO: test: Pretest no parameter change
[06:10:34.438] <TB3> INFO: running: pretest
[06:10:34.438] <TB3> INFO: write dac parameters into /usr/local/data/M2031_XRayHRQualification_2015-07-07_08h06m_1436249186//002_CalDelScanAndSaveDacs_p17//dacParameters35_C0.dat
[06:10:34.439] <TB3> INFO: write dac parameters into /usr/local/data/M2031_XRayHRQualification_2015-07-07_08h06m_1436249186//002_CalDelScanAndSaveDacs_p17//dacParameters35_C1.dat
[06:10:34.439] <TB3> INFO: write dac parameters into /usr/local/data/M2031_XRayHRQualification_2015-07-07_08h06m_1436249186//002_CalDelScanAndSaveDacs_p17//dacParameters35_C2.dat
[06:10:34.439] <TB3> INFO: write dac parameters into /usr/local/data/M2031_XRayHRQualification_2015-07-07_08h06m_1436249186//002_CalDelScanAndSaveDacs_p17//dacParameters35_C3.dat
[06:10:34.439] <TB3> INFO: write dac parameters into /usr/local/data/M2031_XRayHRQualification_2015-07-07_08h06m_1436249186//002_CalDelScanAndSaveDacs_p17//dacParameters35_C4.dat
[06:10:34.439] <TB3> INFO: write dac parameters into /usr/local/data/M2031_XRayHRQualification_2015-07-07_08h06m_1436249186//002_CalDelScanAndSaveDacs_p17//dacParameters35_C5.dat
[06:10:34.439] <TB3> INFO: write dac parameters into /usr/local/data/M2031_XRayHRQualification_2015-07-07_08h06m_1436249186//002_CalDelScanAndSaveDacs_p17//dacParameters35_C6.dat
[06:10:34.439] <TB3> INFO: write dac parameters into /usr/local/data/M2031_XRayHRQualification_2015-07-07_08h06m_1436249186//002_CalDelScanAndSaveDacs_p17//dacParameters35_C7.dat
[06:10:34.439] <TB3> INFO: write dac parameters into /usr/local/data/M2031_XRayHRQualification_2015-07-07_08h06m_1436249186//002_CalDelScanAndSaveDacs_p17//dacParameters35_C8.dat
[06:10:34.439] <TB3> INFO: write dac parameters into /usr/local/data/M2031_XRayHRQualification_2015-07-07_08h06m_1436249186//002_CalDelScanAndSaveDacs_p17//dacParameters35_C9.dat
[06:10:34.440] <TB3> INFO: write dac parameters into /usr/local/data/M2031_XRayHRQualification_2015-07-07_08h06m_1436249186//002_CalDelScanAndSaveDacs_p17//dacParameters35_C10.dat
[06:10:34.440] <TB3> INFO: write dac parameters into /usr/local/data/M2031_XRayHRQualification_2015-07-07_08h06m_1436249186//002_CalDelScanAndSaveDacs_p17//dacParameters35_C11.dat
[06:10:34.440] <TB3> INFO: write dac parameters into /usr/local/data/M2031_XRayHRQualification_2015-07-07_08h06m_1436249186//002_CalDelScanAndSaveDacs_p17//dacParameters35_C12.dat
[06:10:34.440] <TB3> INFO: write dac parameters into /usr/local/data/M2031_XRayHRQualification_2015-07-07_08h06m_1436249186//002_CalDelScanAndSaveDacs_p17//dacParameters35_C13.dat
[06:10:34.440] <TB3> INFO: write dac parameters into /usr/local/data/M2031_XRayHRQualification_2015-07-07_08h06m_1436249186//002_CalDelScanAndSaveDacs_p17//dacParameters35_C14.dat
[06:10:34.440] <TB3> INFO: write dac parameters into /usr/local/data/M2031_XRayHRQualification_2015-07-07_08h06m_1436249186//002_CalDelScanAndSaveDacs_p17//dacParameters35_C15.dat
[06:10:34.440] <TB3> INFO: enter test to run
[06:10:34.440] <TB3> INFO: test: no parameter change
[06:10:34.615] <TB3> QUIET: Connection to board 121 closed.
[06:10:34.695] <TB3> INFO: pXar: this is the end, my friend
commander_HREfficiency.log
[06:10:59.034] <TB3> INFO: *** Welcome to pxar ***
[06:10:59.034] <TB3> INFO: *** Today: 2015/07/07
[06:10:59.034] <TB3> INFO: readRocDacs: /usr/local/data/M2031_XRayHRQualification_2015-07-07_08h06m_1436249186//003_HREfficiency_p17//dacParameters35_C0.dat .. /usr/local/data/M2031_XRayHRQualification_2015-07-07_08h06m_1436249186//003_HREfficiency_p17//dacParameters35_C15.dat
[06:10:59.035] <TB3> INFO: readTbmDacs: /usr/local/data/M2031_XRayHRQualification_2015-07-07_08h06m_1436249186//003_HREfficiency_p17//tbmParameters_C0a.dat .. /usr/local/data/M2031_XRayHRQualification_2015-07-07_08h06m_1436249186//003_HREfficiency_p17//tbmParameters_C0b.dat
[06:10:59.035] <TB3> INFO: readMaskFile: /usr/local/data/M2031_XRayHRQualification_2015-07-07_08h06m_1436249186//003_HREfficiency_p17//defaultMaskFile.dat
[06:10:59.035] <TB3> INFO: readTrimFile: /usr/local/data/M2031_XRayHRQualification_2015-07-07_08h06m_1436249186//003_HREfficiency_p17//trimParameters35_C0.dat .. /usr/local/data/M2031_XRayHRQualification_2015-07-07_08h06m_1436249186//003_HREfficiency_p17//trimParameters35_C15.dat
[06:10:59.117] <TB3> INFO: clk: 4
[06:10:59.117] <TB3> INFO: ctr: 4
[06:10:59.117] <TB3> INFO: sda: 19
[06:10:59.117] <TB3> INFO: tin: 9
[06:10:59.117] <TB3> INFO: level: 15
[06:10:59.117] <TB3> INFO: triggerdelay: 0
[06:10:59.117] <TB3> QUIET: Instanciating API for pxar v2.2.5+45~gbf85984
[06:10:59.117] <TB3> INFO: Log level: INFO
[06:10:59.129] <TB3> INFO: Found DTB DTB_WWV6Z5
[06:10:59.143] <TB3> QUIET: Connection to board DTB_WWV6Z5 opened.
[06:10:59.146] <TB3> INFO: DTB startup information
--- DTB info------------------------------------------
Board id: 121
HW version: DTB1.2
FW version: 4.0
SW version: 4.0
USB id: DTB_WWV6Z5
MAC address: 40D855118079
Hostname: pixelDTB121
Comment:
------------------------------------------------------
[06:10:59.149] <TB3> INFO: RPC call hashes of host and DTB match: 447413373
[06:11:00.767] <TB3> INFO: DUT info:
[06:11:00.767] <TB3> INFO: The DUT currently contains the following objects:
[06:11:00.767] <TB3> INFO: 2 TBM Cores tbm09c (2 ON)
[06:11:00.767] <TB3> INFO: TBM Core alpha (0): 7 registers set
[06:11:00.767] <TB3> INFO: TBM Core beta (1): 7 registers set
[06:11:00.767] <TB3> INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[06:11:00.767] <TB3> INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[06:11:00.767] <TB3> INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[06:11:00.767] <TB3> INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[06:11:00.767] <TB3> INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[06:11:00.767] <TB3> INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[06:11:00.767] <TB3> INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[06:11:00.767] <TB3> INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[06:11:00.767] <TB3> INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[06:11:00.767] <TB3> INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[06:11:00.767] <TB3> INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[06:11:00.767] <TB3> INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[06:11:00.767] <TB3> INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[06:11:00.767] <TB3> INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[06:11:00.768] <TB3> INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[06:11:00.768] <TB3> INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[06:11:00.768] <TB3> INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[06:11:01.169] <TB3> INFO: enter 'restricted' command line mode
[06:11:01.169] <TB3> INFO: enter test to run
[06:11:01.169] <TB3> INFO: test: delay setting parameters: ->10<-
[06:11:01.169] <TB3> INFO: delay test by 10 seconds...
[06:11:11.169] <TB3> INFO: enter test to run
[06:11:11.169] <TB3> INFO: test: HighRate no parameter change
[06:11:11.169] <TB3> INFO: running: highrate
[06:11:11.170] <TB3> INFO: readGainPedestalParameters /usr/local/data/M2031_XRayHRQualification_2015-07-07_08h06m_1436249186//003_HREfficiency_p17//phCalibrationFitErr35_C0.dat .. /usr/local/data/M2031_XRayHRQualification_2015-07-07_08h06m_1436249186//003_HREfficiency_p17//phCalibrationFitErr35_C15.dat
[06:11:11.369] <TB3> INFO: ----------------------------------------------------------------------
[06:11:11.369] <TB3> INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[06:11:11.369] <TB3> INFO: ----------------------------------------------------------------------
[06:11:23.369] <TB3> ERROR: <datapipe.cc/CheckEventValidity:L437> Number of ROCs (3) != Token Chain Length (4)

[06:11:23.369] <TB3> WARNING: ROC 3: Readback start marker after 15 readouts!

[06:11:24.884] <TB3> INFO: Test took 13507ms.
[06:11:25.669] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[06:11:25.995] <TB3> INFO: number of dead pixels (per ROC): 0 0 0 2 0 0 0 0 0 0 0 0 0 0 0 0
[06:11:25.995] <TB3> INFO: number of red-efficiency pixels: 125 114 87 203 188 238 204 201 226 280 343 205 183 121 117 105
[06:11:25.995] <TB3> INFO: number of X-ray hits detected: 80478 79626 71819 144945 135614 164642 156799 147120 154241 169054 182958 143916 140772 81904 78930 86696
[06:11:25.995] <TB3> INFO: number of triggers sent (total per ROC): 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000
[06:11:25.995] <TB3> INFO: number of Vcal hits detected: 207872 207885 207913 207688 207790 207750 207779 207793 207763 207702 207631 207782 207805 207872 207882 207893
[06:11:25.995] <TB3> INFO: Vcal hit fiducial efficiency (%): 99.9 99.9 100.0 99.9 99.9 99.9 99.9 99.9 99.9 99.9 99.8 99.9 99.9 99.9 99.9 100.0
[06:11:25.995] <TB3> INFO: Vcal hit overall efficiency (%): 99.9 99.9 100.0 99.9 99.9 99.9 99.9 99.9 99.9 99.9 99.8 99.9 99.9 99.9 99.9 99.9
[06:11:25.995] <TB3> INFO: X-ray hit rate [MHz/cm2]: 23.6 23.3 21.1 42.5 39.7 48.3 46.0 43.1 45.2 49.6 53.6 42.2 41.3 24.0 23.1 25.4
[06:11:25.995] <TB3> INFO: PixTestHighRate::doXPixelAlive() done
[06:11:26.027] <TB3> INFO: PixTest:: pg_setup set to default.
[06:11:26.067] <TB3> INFO: enter test to run
[06:11:26.067] <TB3> INFO: test: no parameter change
[06:11:26.239] <TB3> QUIET: Connection to board 121 closed.
[06:11:26.321] <TB3> INFO: pXar: this is the end, my friend
commander_HREfficiency.log
[06:11:38.983] <TB3> INFO: *** Welcome to pxar ***
[06:11:38.983] <TB3> INFO: *** Today: 2015/07/07
[06:11:38.983] <TB3> INFO: readRocDacs: /usr/local/data/M2031_XRayHRQualification_2015-07-07_08h06m_1436249186//004_HREfficiency_p17//dacParameters35_C0.dat .. /usr/local/data/M2031_XRayHRQualification_2015-07-07_08h06m_1436249186//004_HREfficiency_p17//dacParameters35_C15.dat
[06:11:38.984] <TB3> INFO: readTbmDacs: /usr/local/data/M2031_XRayHRQualification_2015-07-07_08h06m_1436249186//004_HREfficiency_p17//tbmParameters_C0a.dat .. /usr/local/data/M2031_XRayHRQualification_2015-07-07_08h06m_1436249186//004_HREfficiency_p17//tbmParameters_C0b.dat
[06:11:38.984] <TB3> INFO: readMaskFile: /usr/local/data/M2031_XRayHRQualification_2015-07-07_08h06m_1436249186//004_HREfficiency_p17//defaultMaskFile.dat
[06:11:38.984] <TB3> INFO: readTrimFile: /usr/local/data/M2031_XRayHRQualification_2015-07-07_08h06m_1436249186//004_HREfficiency_p17//trimParameters35_C0.dat .. /usr/local/data/M2031_XRayHRQualification_2015-07-07_08h06m_1436249186//004_HREfficiency_p17//trimParameters35_C15.dat
[06:11:39.075] <TB3> INFO: clk: 4
[06:11:39.076] <TB3> INFO: ctr: 4
[06:11:39.076] <TB3> INFO: sda: 19
[06:11:39.076] <TB3> INFO: tin: 9
[06:11:39.076] <TB3> INFO: level: 15
[06:11:39.076] <TB3> INFO: triggerdelay: 0
[06:11:39.076] <TB3> QUIET: Instanciating API for pxar v2.2.5+45~gbf85984
[06:11:39.076] <TB3> INFO: Log level: INFO
[06:11:39.090] <TB3> INFO: Found DTB DTB_WWV6Z5
[06:11:39.106] <TB3> QUIET: Connection to board DTB_WWV6Z5 opened.
[06:11:39.109] <TB3> INFO: DTB startup information
--- DTB info------------------------------------------
Board id: 121
HW version: DTB1.2
FW version: 4.0
SW version: 4.0
USB id: DTB_WWV6Z5
MAC address: 40D855118079
Hostname: pixelDTB121
Comment:
------------------------------------------------------
[06:11:39.112] <TB3> INFO: RPC call hashes of host and DTB match: 447413373
[06:11:40.680] <TB3> INFO: DUT info:
[06:11:40.680] <TB3> INFO: The DUT currently contains the following objects:
[06:11:40.680] <TB3> INFO: 2 TBM Cores tbm09c (2 ON)
[06:11:40.681] <TB3> INFO: TBM Core alpha (0): 7 registers set
[06:11:40.681] <TB3> INFO: TBM Core beta (1): 7 registers set
[06:11:40.681] <TB3> INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[06:11:40.681] <TB3> INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[06:11:40.681] <TB3> INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[06:11:40.681] <TB3> INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[06:11:40.681] <TB3> INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[06:11:40.681] <TB3> INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[06:11:40.681] <TB3> INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[06:11:40.681] <TB3> INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[06:11:40.681] <TB3> INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[06:11:40.681] <TB3> INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[06:11:40.681] <TB3> INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[06:11:40.681] <TB3> INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[06:11:40.681] <TB3> INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[06:11:40.681] <TB3> INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[06:11:40.681] <TB3> INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[06:11:40.681] <TB3> INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[06:11:40.681] <TB3> INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[06:11:41.082] <TB3> INFO: enter 'restricted' command line mode
[06:11:41.082] <TB3> INFO: enter test to run
[06:11:41.082] <TB3> INFO: test: delay setting parameters: ->10<-
[06:11:41.082] <TB3> INFO: delay test by 10 seconds...
[06:11:51.082] <TB3> INFO: enter test to run
[06:11:51.082] <TB3> INFO: test: HighRate no parameter change
[06:11:51.082] <TB3> INFO: running: highrate
[06:11:51.083] <TB3> INFO: readGainPedestalParameters /usr/local/data/M2031_XRayHRQualification_2015-07-07_08h06m_1436249186//004_HREfficiency_p17//phCalibrationFitErr35_C0.dat .. /usr/local/data/M2031_XRayHRQualification_2015-07-07_08h06m_1436249186//004_HREfficiency_p17//phCalibrationFitErr35_C15.dat
[06:11:51.265] <TB3> INFO: ----------------------------------------------------------------------
[06:11:51.265] <TB3> INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[06:11:51.265] <TB3> INFO: ----------------------------------------------------------------------
[06:12:07.297] <TB3> INFO: Test took 16026ms.
[06:12:09.117] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[06:12:09.500] <TB3> INFO: number of dead pixels (per ROC): 0 0 0 2 0 0 0 0 0 0 0 0 0 0 0 0
[06:12:09.500] <TB3> INFO: number of red-efficiency pixels: 280 296 252 742 653 869 796 699 781 1000 1147 697 657 355 285 268
[06:12:09.500] <TB3> INFO: number of X-ray hits detected: 163290 161671 148831 289374 273085 328615 313454 300448 312697 338345 361975 288217 282209 168930 161413 175552
[06:12:09.500] <TB3> INFO: number of triggers sent (total per ROC): 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000
[06:12:09.500] <TB3> INFO: number of Vcal hits detected: 207704 207691 207723 207039 207196 206928 207019 207212 207121 206734 206509 207195 207265 207573 207703 207716
[06:12:09.500] <TB3> INFO: Vcal hit fiducial efficiency (%): 99.9 99.9 99.9 99.6 99.6 99.6 99.6 99.6 99.6 99.5 99.4 99.7 99.7 99.8 99.9 99.9
[06:12:09.500] <TB3> INFO: Vcal hit overall efficiency (%): 99.9 99.9 99.9 99.5 99.6 99.5 99.5 99.6 99.6 99.4 99.3 99.6 99.6 99.8 99.9 99.9
[06:12:09.500] <TB3> INFO: X-ray hit rate [MHz/cm2]: 47.9 47.4 43.6 84.8 80.0 96.3 91.9 88.1 91.7 99.2 106.1 84.5 82.7 49.5 47.3 51.5
[06:12:09.500] <TB3> INFO: PixTestHighRate::doXPixelAlive() done
[06:12:09.526] <TB3> INFO: PixTest:: pg_setup set to default.
[06:12:09.563] <TB3> INFO: enter test to run
[06:12:09.563] <TB3> INFO: test: no parameter change
[06:12:09.706] <TB3> QUIET: Connection to board 121 closed.
[06:12:09.786] <TB3> INFO: pXar: this is the end, my friend
commander_HREfficiency.log
[06:12:24.267] <TB3> INFO: *** Welcome to pxar ***
[06:12:24.267] <TB3> INFO: *** Today: 2015/07/07
[06:12:24.267] <TB3> INFO: readRocDacs: /usr/local/data/M2031_XRayHRQualification_2015-07-07_08h06m_1436249186//005_HREfficiency_p17//dacParameters35_C0.dat .. /usr/local/data/M2031_XRayHRQualification_2015-07-07_08h06m_1436249186//005_HREfficiency_p17//dacParameters35_C15.dat
[06:12:24.268] <TB3> INFO: readTbmDacs: /usr/local/data/M2031_XRayHRQualification_2015-07-07_08h06m_1436249186//005_HREfficiency_p17//tbmParameters_C0a.dat .. /usr/local/data/M2031_XRayHRQualification_2015-07-07_08h06m_1436249186//005_HREfficiency_p17//tbmParameters_C0b.dat
[06:12:24.268] <TB3> INFO: readMaskFile: /usr/local/data/M2031_XRayHRQualification_2015-07-07_08h06m_1436249186//005_HREfficiency_p17//defaultMaskFile.dat
[06:12:24.268] <TB3> INFO: readTrimFile: /usr/local/data/M2031_XRayHRQualification_2015-07-07_08h06m_1436249186//005_HREfficiency_p17//trimParameters35_C0.dat .. /usr/local/data/M2031_XRayHRQualification_2015-07-07_08h06m_1436249186//005_HREfficiency_p17//trimParameters35_C15.dat
[06:12:24.347] <TB3> INFO: clk: 4
[06:12:24.347] <TB3> INFO: ctr: 4
[06:12:24.347] <TB3> INFO: sda: 19
[06:12:24.347] <TB3> INFO: tin: 9
[06:12:24.347] <TB3> INFO: level: 15
[06:12:24.347] <TB3> INFO: triggerdelay: 0
[06:12:24.347] <TB3> QUIET: Instanciating API for pxar v2.2.5+45~gbf85984
[06:12:24.347] <TB3> INFO: Log level: INFO
[06:12:24.360] <TB3> INFO: Found DTB DTB_WWV6Z5
[06:12:24.374] <TB3> QUIET: Connection to board DTB_WWV6Z5 opened.
[06:12:24.378] <TB3> INFO: DTB startup information
--- DTB info------------------------------------------
Board id: 121
HW version: DTB1.2
FW version: 4.0
SW version: 4.0
USB id: DTB_WWV6Z5
MAC address: 40D855118079
Hostname: pixelDTB121
Comment:
------------------------------------------------------
[06:12:24.380] <TB3> INFO: RPC call hashes of host and DTB match: 447413373
[06:12:25.936] <TB3> INFO: DUT info:
[06:12:25.936] <TB3> INFO: The DUT currently contains the following objects:
[06:12:25.936] <TB3> INFO: 2 TBM Cores tbm09c (2 ON)
[06:12:25.936] <TB3> INFO: TBM Core alpha (0): 7 registers set
[06:12:25.936] <TB3> INFO: TBM Core beta (1): 7 registers set
[06:12:25.936] <TB3> INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[06:12:25.936] <TB3> INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[06:12:25.936] <TB3> INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[06:12:25.937] <TB3> INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[06:12:25.937] <TB3> INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[06:12:25.937] <TB3> INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[06:12:25.937] <TB3> INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[06:12:25.937] <TB3> INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[06:12:25.937] <TB3> INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[06:12:25.937] <TB3> INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[06:12:25.937] <TB3> INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[06:12:25.937] <TB3> INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[06:12:25.937] <TB3> INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[06:12:25.937] <TB3> INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[06:12:25.937] <TB3> INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[06:12:25.937] <TB3> INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[06:12:25.937] <TB3> INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[06:12:26.338] <TB3> INFO: enter 'restricted' command line mode
[06:12:26.338] <TB3> INFO: enter test to run
[06:12:26.339] <TB3> INFO: test: delay setting parameters: ->10<-
[06:12:26.339] <TB3> INFO: delay test by 10 seconds...
[06:12:36.339] <TB3> INFO: enter test to run
[06:12:36.339] <TB3> INFO: test: HighRate no parameter change
[06:12:36.339] <TB3> INFO: running: highrate
[06:12:36.339] <TB3> INFO: readGainPedestalParameters /usr/local/data/M2031_XRayHRQualification_2015-07-07_08h06m_1436249186//005_HREfficiency_p17//phCalibrationFitErr35_C0.dat .. /usr/local/data/M2031_XRayHRQualification_2015-07-07_08h06m_1436249186//005_HREfficiency_p17//phCalibrationFitErr35_C15.dat
[06:12:36.555] <TB3> INFO: ----------------------------------------------------------------------
[06:12:36.555] <TB3> INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[06:12:36.555] <TB3> INFO: ----------------------------------------------------------------------
[06:12:55.329] <TB3> INFO: Test took 18767ms.
[06:12:58.527] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[06:12:58.960] <TB3> INFO: number of dead pixels (per ROC): 0 0 0 2 0 0 0 0 0 0 0 0 0 0 0 0
[06:12:58.960] <TB3> INFO: number of red-efficiency pixels: 561 627 484 1458 1335 1754 1623 1439 1628 1997 2198 1482 1371 668 598 549
[06:12:58.960] <TB3> INFO: number of X-ray hits detected: 236634 237899 215850 418412 395702 470193 450857 435775 451906 489684 521426 416441 409556 247388 236222 254004
[06:12:58.960] <TB3> INFO: number of triggers sent (total per ROC): 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000
[06:12:58.960] <TB3> INFO: number of Vcal hits detected: 207374 207299 207365 205825 206108 205239 205478 206096 205657 204617 204028 205863 206150 207006 207342 207390
[06:12:58.960] <TB3> INFO: Vcal hit fiducial efficiency (%): 99.7 99.7 99.8 99.1 99.2 98.9 98.9 99.2 99.0 98.6 98.3 99.1 99.2 99.6 99.7 99.7
[06:12:58.960] <TB3> INFO: Vcal hit overall efficiency (%): 99.7 99.7 99.7 99.0 99.1 98.7 98.8 99.1 98.9 98.4 98.1 99.0 99.1 99.5 99.7 99.7
[06:12:58.960] <TB3> INFO: X-ray hit rate [MHz/cm2]: 69.4 69.7 63.3 122.6 116.0 137.8 132.1 127.7 132.5 143.5 152.8 122.1 120.0 72.5 69.2 74.5
[06:12:58.960] <TB3> INFO: PixTestHighRate::doXPixelAlive() done
[06:12:58.984] <TB3> INFO: PixTest:: pg_setup set to default.
[06:12:59.020] <TB3> INFO: enter test to run
[06:12:59.020] <TB3> INFO: test: no parameter change
[06:12:59.191] <TB3> QUIET: Connection to board 121 closed.
[06:12:59.270] <TB3> INFO: pXar: this is the end, my friend
commander_HREfficiency.log
[06:13:15.830] <TB3> INFO: *** Welcome to pxar ***
[06:13:15.830] <TB3> INFO: *** Today: 2015/07/07
[06:13:15.831] <TB3> INFO: readRocDacs: /usr/local/data/M2031_XRayHRQualification_2015-07-07_08h06m_1436249186//006_HREfficiency_p17//dacParameters35_C0.dat .. /usr/local/data/M2031_XRayHRQualification_2015-07-07_08h06m_1436249186//006_HREfficiency_p17//dacParameters35_C15.dat
[06:13:15.832] <TB3> INFO: readTbmDacs: /usr/local/data/M2031_XRayHRQualification_2015-07-07_08h06m_1436249186//006_HREfficiency_p17//tbmParameters_C0a.dat .. /usr/local/data/M2031_XRayHRQualification_2015-07-07_08h06m_1436249186//006_HREfficiency_p17//tbmParameters_C0b.dat
[06:13:15.832] <TB3> INFO: readMaskFile: /usr/local/data/M2031_XRayHRQualification_2015-07-07_08h06m_1436249186//006_HREfficiency_p17//defaultMaskFile.dat
[06:13:15.832] <TB3> INFO: readTrimFile: /usr/local/data/M2031_XRayHRQualification_2015-07-07_08h06m_1436249186//006_HREfficiency_p17//trimParameters35_C0.dat .. /usr/local/data/M2031_XRayHRQualification_2015-07-07_08h06m_1436249186//006_HREfficiency_p17//trimParameters35_C15.dat
[06:13:15.901] <TB3> INFO: clk: 4
[06:13:15.901] <TB3> INFO: ctr: 4
[06:13:15.901] <TB3> INFO: sda: 19
[06:13:15.901] <TB3> INFO: tin: 9
[06:13:15.901] <TB3> INFO: level: 15
[06:13:15.901] <TB3> INFO: triggerdelay: 0
[06:13:15.901] <TB3> QUIET: Instanciating API for pxar v2.2.5+45~gbf85984
[06:13:15.901] <TB3> INFO: Log level: INFO
[06:13:15.915] <TB3> INFO: Found DTB DTB_WWV6Z5
[06:13:15.931] <TB3> QUIET: Connection to board DTB_WWV6Z5 opened.
[06:13:15.934] <TB3> INFO: DTB startup information
--- DTB info------------------------------------------
Board id: 121
HW version: DTB1.2
FW version: 4.0
SW version: 4.0
USB id: DTB_WWV6Z5
MAC address: 40D855118079
Hostname: pixelDTB121
Comment:
------------------------------------------------------
[06:13:15.937] <TB3> INFO: RPC call hashes of host and DTB match: 447413373
[06:13:17.509] <TB3> INFO: DUT info:
[06:13:17.509] <TB3> INFO: The DUT currently contains the following objects:
[06:13:17.509] <TB3> INFO: 2 TBM Cores tbm09c (2 ON)
[06:13:17.509] <TB3> INFO: TBM Core alpha (0): 7 registers set
[06:13:17.509] <TB3> INFO: TBM Core beta (1): 7 registers set
[06:13:17.509] <TB3> INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[06:13:17.510] <TB3> INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[06:13:17.510] <TB3> INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[06:13:17.510] <TB3> INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[06:13:17.510] <TB3> INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[06:13:17.510] <TB3> INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[06:13:17.510] <TB3> INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[06:13:17.510] <TB3> INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[06:13:17.510] <TB3> INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[06:13:17.510] <TB3> INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[06:13:17.510] <TB3> INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[06:13:17.510] <TB3> INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[06:13:17.510] <TB3> INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[06:13:17.510] <TB3> INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[06:13:17.510] <TB3> INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[06:13:17.510] <TB3> INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[06:13:17.510] <TB3> INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[06:13:17.911] <TB3> INFO: enter 'restricted' command line mode
[06:13:17.911] <TB3> INFO: enter test to run
[06:13:17.911] <TB3> INFO: test: delay setting parameters: ->10<-
[06:13:17.911] <TB3> INFO: delay test by 10 seconds...
[06:13:27.911] <TB3> INFO: enter test to run
[06:13:27.911] <TB3> INFO: test: HighRate no parameter change
[06:13:27.911] <TB3> INFO: running: highrate
[06:13:27.912] <TB3> INFO: readGainPedestalParameters /usr/local/data/M2031_XRayHRQualification_2015-07-07_08h06m_1436249186//006_HREfficiency_p17//phCalibrationFitErr35_C0.dat .. /usr/local/data/M2031_XRayHRQualification_2015-07-07_08h06m_1436249186//006_HREfficiency_p17//phCalibrationFitErr35_C15.dat
[06:13:28.058] <TB3> INFO: ----------------------------------------------------------------------
[06:13:28.058] <TB3> INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[06:13:28.058] <TB3> INFO: ----------------------------------------------------------------------
[06:13:49.240] <TB3> INFO: Test took 21176ms.
[06:13:55.263] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[06:13:55.769] <TB3> INFO: number of dead pixels (per ROC): 0 0 0 2 0 0 0 0 0 0 0 0 0 0 0 0
[06:13:55.769] <TB3> INFO: number of red-efficiency pixels: 1080 1139 815 2487 2370 2891 2695 2503 2762 3118 3294 2413 2395 1086 1113 1016
[06:13:55.769] <TB3> INFO: number of X-ray hits detected: 322620 323634 295591 566925 534713 634799 611185 588035 613593 659937 700273 561737 552179 335426 324479 345207
[06:13:55.769] <TB3> INFO: number of triggers sent (total per ROC): 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000
[06:13:55.769] <TB3> INFO: number of Vcal hits detected: 206684 206599 206585 202982 203593 201325 201956 203423 202450 200064 198791 203187 203646 205774 206639 206733
[06:13:55.769] <TB3> INFO: Vcal hit fiducial efficiency (%): 99.4 99.4 99.5 97.9 98.1 97.2 97.5 98.0 97.6 96.7 96.1 98.0 98.1 99.1 99.4 99.5
[06:13:55.769] <TB3> INFO: Vcal hit overall efficiency (%): 99.4 99.3 99.3 97.6 97.9 96.8 97.1 97.8 97.3 96.2 95.6 97.7 97.9 98.9 99.3 99.4
[06:13:55.769] <TB3> INFO: X-ray hit rate [MHz/cm2]: 94.6 94.9 86.6 166.2 156.7 186.1 179.1 172.4 179.8 193.4 205.3 164.6 161.8 98.3 95.1 101.2
[06:13:55.769] <TB3> INFO: PixTestHighRate::doXPixelAlive() done
[06:13:55.795] <TB3> INFO: PixTest:: pg_setup set to default.
[06:13:55.850] <TB3> INFO: enter test to run
[06:13:55.850] <TB3> INFO: test: no parameter change
[06:13:55.989] <TB3> QUIET: Connection to board 121 closed.
[06:13:56.073] <TB3> INFO: pXar: this is the end, my friend
commander_HREfficiency.log
[06:14:15.130] <TB3> INFO: *** Welcome to pxar ***
[06:14:15.130] <TB3> INFO: *** Today: 2015/07/07
[06:14:15.130] <TB3> INFO: readRocDacs: /usr/local/data/M2031_XRayHRQualification_2015-07-07_08h06m_1436249186//007_HREfficiency_p17//dacParameters35_C0.dat .. /usr/local/data/M2031_XRayHRQualification_2015-07-07_08h06m_1436249186//007_HREfficiency_p17//dacParameters35_C15.dat
[06:14:15.131] <TB3> INFO: readTbmDacs: /usr/local/data/M2031_XRayHRQualification_2015-07-07_08h06m_1436249186//007_HREfficiency_p17//tbmParameters_C0a.dat .. /usr/local/data/M2031_XRayHRQualification_2015-07-07_08h06m_1436249186//007_HREfficiency_p17//tbmParameters_C0b.dat
[06:14:15.131] <TB3> INFO: readMaskFile: /usr/local/data/M2031_XRayHRQualification_2015-07-07_08h06m_1436249186//007_HREfficiency_p17//defaultMaskFile.dat
[06:14:15.131] <TB3> INFO: readTrimFile: /usr/local/data/M2031_XRayHRQualification_2015-07-07_08h06m_1436249186//007_HREfficiency_p17//trimParameters35_C0.dat .. /usr/local/data/M2031_XRayHRQualification_2015-07-07_08h06m_1436249186//007_HREfficiency_p17//trimParameters35_C15.dat
[06:14:15.207] <TB3> INFO: clk: 4
[06:14:15.207] <TB3> INFO: ctr: 4
[06:14:15.207] <TB3> INFO: sda: 19
[06:14:15.207] <TB3> INFO: tin: 9
[06:14:15.207] <TB3> INFO: level: 15
[06:14:15.207] <TB3> INFO: triggerdelay: 0
[06:14:15.207] <TB3> QUIET: Instanciating API for pxar v2.2.5+45~gbf85984
[06:14:15.207] <TB3> INFO: Log level: INFO
[06:14:15.220] <TB3> INFO: Found DTB DTB_WWV6Z5
[06:14:15.235] <TB3> QUIET: Connection to board DTB_WWV6Z5 opened.
[06:14:15.238] <TB3> INFO: DTB startup information
--- DTB info------------------------------------------
Board id: 121
HW version: DTB1.2
FW version: 4.0
SW version: 4.0
USB id: DTB_WWV6Z5
MAC address: 40D855118079
Hostname: pixelDTB121
Comment:
------------------------------------------------------
[06:14:15.241] <TB3> INFO: RPC call hashes of host and DTB match: 447413373
[06:14:16.882] <TB3> INFO: DUT info:
[06:14:16.882] <TB3> INFO: The DUT currently contains the following objects:
[06:14:16.882] <TB3> INFO: 2 TBM Cores tbm09c (2 ON)
[06:14:16.883] <TB3> INFO: TBM Core alpha (0): 7 registers set
[06:14:16.883] <TB3> INFO: TBM Core beta (1): 7 registers set
[06:14:16.883] <TB3> INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[06:14:16.883] <TB3> INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[06:14:16.883] <TB3> INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[06:14:16.883] <TB3> INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[06:14:16.883] <TB3> INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[06:14:16.883] <TB3> INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[06:14:16.883] <TB3> INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[06:14:16.883] <TB3> INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[06:14:16.883] <TB3> INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[06:14:16.883] <TB3> INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[06:14:16.883] <TB3> INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[06:14:16.883] <TB3> INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[06:14:16.883] <TB3> INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[06:14:16.883] <TB3> INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[06:14:16.883] <TB3> INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[06:14:16.883] <TB3> INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[06:14:16.883] <TB3> INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[06:14:17.284] <TB3> INFO: enter 'restricted' command line mode
[06:14:17.284] <TB3> INFO: enter test to run
[06:14:17.284] <TB3> INFO: test: delay setting parameters: ->10<-
[06:14:17.284] <TB3> INFO: delay test by 10 seconds...
[06:14:27.285] <TB3> INFO: enter test to run
[06:14:27.285] <TB3> INFO: test: HighRate no parameter change
[06:14:27.285] <TB3> INFO: running: highrate
[06:14:27.285] <TB3> INFO: readGainPedestalParameters /usr/local/data/M2031_XRayHRQualification_2015-07-07_08h06m_1436249186//007_HREfficiency_p17//phCalibrationFitErr35_C0.dat .. /usr/local/data/M2031_XRayHRQualification_2015-07-07_08h06m_1436249186//007_HREfficiency_p17//phCalibrationFitErr35_C15.dat
[06:14:27.445] <TB3> INFO: ----------------------------------------------------------------------
[06:14:27.445] <TB3> INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[06:14:27.445] <TB3> INFO: ----------------------------------------------------------------------
[06:14:31.530] <TB3> ERROR: <datapipe.cc/CheckEventValidity:L437> Number of ROCs (1) != Token Chain Length (4)

[06:14:31.531] <TB3> ERROR: <datapipe.cc/CheckEventValidity:L437> Number of ROCs (2) != Token Chain Length (4)

[06:14:31.531] <TB3> WARNING: ROC 2: Readback start marker after 15 readouts!

[06:14:31.531] <TB3> WARNING: ROC 1: Readback start marker after 15 readouts!

[06:14:31.531] <TB3> WARNING: ROC 2: Readback start marker after 15 readouts!

[06:14:31.531] <TB3> WARNING: ROC 3: Readback start marker after 15 readouts!

[06:14:31.531] <TB3> WARNING: ROC 3: Readback start marker after 15 readouts!

[06:14:48.886] <TB3> INFO: Test took 21435ms.
[06:14:54.789] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[06:14:55.498] <TB3> INFO: number of dead pixels (per ROC): 0 0 0 2 0 0 0 0 0 0 0 0 0 0 0 0
[06:14:55.498] <TB3> INFO: number of red-efficiency pixels: 1169 1297 925 2629 2510 3025 2837 2719 2988 3208 3442 2575 2517 1150 1222 1101
[06:14:55.498] <TB3> INFO: number of X-ray hits detected: 334103 338588 307800 582644 551550 651332 628166 610802 637439 683067 718005 580399 565567 350744 338991 355781
[06:14:55.498] <TB3> INFO: number of triggers sent (total per ROC): 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000
[06:14:55.498] <TB3> INFO: number of Vcal hits detected: 206577 206366 206390 202602 203053 200917 201418 202840 201648 199169 197971 202748 203409 205589 206484 206593
[06:14:55.498] <TB3> INFO: Vcal hit fiducial efficiency (%): 99.4 99.3 99.4 97.7 97.9 97.1 97.2 97.7 97.3 96.3 95.8 97.8 98.0 99.0 99.3 99.4
[06:14:55.498] <TB3> INFO: Vcal hit overall efficiency (%): 99.3 99.2 99.2 97.4 97.6 96.6 96.8 97.5 96.9 95.8 95.2 97.5 97.8 98.8 99.3 99.3
[06:14:55.498] <TB3> INFO: X-ray hit rate [MHz/cm2]: 97.9 99.2 90.2 170.8 161.7 190.9 184.1 179.0 186.8 200.2 210.5 170.1 165.8 102.8 99.4 104.3
[06:14:55.498] <TB3> INFO: PixTestHighRate::doXPixelAlive() done
[06:14:55.525] <TB3> INFO: PixTest:: pg_setup set to default.
[06:14:55.581] <TB3> INFO: enter test to run
[06:14:55.581] <TB3> INFO: test: no parameter change
[06:14:55.763] <TB3> QUIET: Connection to board 121 closed.
[06:14:55.842] <TB3> INFO: pXar: this is the end, my friend
commander_HRData.log
[07:31:48.126] <TB3> INFO: *** Welcome to pxar ***
[07:31:48.126] <TB3> INFO: *** Today: 2015/07/07
[07:31:48.126] <TB3> INFO: readRocDacs: /usr/local/data/M2031_XRayHRQualification_2015-07-07_09h27m_1436254057//003_HRData_p17//dacParameters35_C0.dat .. /usr/local/data/M2031_XRayHRQualification_2015-07-07_09h27m_1436254057//003_HRData_p17//dacParameters35_C15.dat
[07:31:48.127] <TB3> INFO: readTbmDacs: /usr/local/data/M2031_XRayHRQualification_2015-07-07_09h27m_1436254057//003_HRData_p17//tbmParameters_C0a.dat .. /usr/local/data/M2031_XRayHRQualification_2015-07-07_09h27m_1436254057//003_HRData_p17//tbmParameters_C0b.dat
[07:31:48.128] <TB3> INFO: readMaskFile: /usr/local/data/M2031_XRayHRQualification_2015-07-07_09h27m_1436254057//003_HRData_p17//defaultMaskFile.dat
[07:31:48.128] <TB3> INFO: readTrimFile: /usr/local/data/M2031_XRayHRQualification_2015-07-07_09h27m_1436254057//003_HRData_p17//trimParameters35_C0.dat .. /usr/local/data/M2031_XRayHRQualification_2015-07-07_09h27m_1436254057//003_HRData_p17//trimParameters35_C15.dat
[07:31:48.250] <TB3> INFO: clk: 4
[07:31:48.250] <TB3> INFO: ctr: 4
[07:31:48.250] <TB3> INFO: sda: 19
[07:31:48.250] <TB3> INFO: tin: 9
[07:31:48.250] <TB3> INFO: level: 15
[07:31:48.250] <TB3> INFO: triggerdelay: 0
[07:31:48.250] <TB3> QUIET: Instanciating API for pxar v2.2.5+45~gbf85984
[07:31:48.250] <TB3> INFO: Log level: INFO
[07:31:48.265] <TB3> INFO: Found DTB DTB_WWV6Z5
[07:31:48.282] <TB3> QUIET: Connection to board DTB_WWV6Z5 opened.
[07:31:48.285] <TB3> INFO: DTB startup information
--- DTB info------------------------------------------
Board id: 121
HW version: DTB1.2
FW version: 4.0
SW version: 4.0
USB id: DTB_WWV6Z5
MAC address: 40D855118079
Hostname: pixelDTB121
Comment:
------------------------------------------------------
[07:31:48.288] <TB3> INFO: RPC call hashes of host and DTB match: 447413373
[07:31:49.915] <TB3> INFO: DUT info:
[07:31:49.915] <TB3> INFO: The DUT currently contains the following objects:
[07:31:49.915] <TB3> INFO: 2 TBM Cores tbm09c (2 ON)
[07:31:49.915] <TB3> INFO: TBM Core alpha (0): 7 registers set
[07:31:49.915] <TB3> INFO: TBM Core beta (1): 7 registers set
[07:31:49.915] <TB3> INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[07:31:49.915] <TB3> INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[07:31:49.915] <TB3> INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[07:31:49.915] <TB3> INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[07:31:49.915] <TB3> INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[07:31:49.915] <TB3> INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[07:31:49.915] <TB3> INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[07:31:49.915] <TB3> INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[07:31:49.915] <TB3> INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[07:31:49.915] <TB3> INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[07:31:49.915] <TB3> INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[07:31:49.915] <TB3> INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[07:31:49.916] <TB3> INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[07:31:49.916] <TB3> INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[07:31:49.916] <TB3> INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[07:31:49.916] <TB3> INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[07:31:49.916] <TB3> INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[07:31:50.317] <TB3> INFO: enter 'restricted' command line mode
[07:31:50.317] <TB3> INFO: enter test to run
[07:31:50.317] <TB3> INFO: test: delay setting parameters: ->5<-
[07:31:50.317] <TB3> INFO: delay test by 5 seconds...
[07:31:55.317] <TB3> INFO: enter test to run
[07:31:55.317] <TB3> INFO: test: Xray no parameter change
[07:31:55.317] <TB3> INFO: running: xray
[07:31:55.317] <TB3> INFO: readGainPedestalParameters /usr/local/data/M2031_XRayHRQualification_2015-07-07_09h27m_1436254057//003_HRData_p17//phCalibrationFitErr35_C0.dat .. /usr/local/data/M2031_XRayHRQualification_2015-07-07_09h27m_1436254057//003_HRData_p17//phCalibrationFitErr35_C15.dat
[07:31:55.493] <TB3> INFO: ######################################################################
[07:31:55.493] <TB3> INFO: PixTestXray::doTest()
[07:31:55.493] <TB3> INFO: ######################################################################
[07:31:55.493] <TB3> INFO: ----------------------------------------------------------------------
[07:31:55.493] <TB3> INFO: PixTestXray::doPhRun() fParRunSeconds = 100
[07:31:55.493] <TB3> INFO: ----------------------------------------------------------------------
[07:31:56.463] <TB3> INFO: PixTestXray::doPhRun start TriggerLoop with trigger frequency 100 kHz, period 405 and duration 100 seconds, fEventsMax = 10000000
[07:32:03.416] <TB3> INFO: run duration 6 seconds, buffer almost full (81%), pausing triggers.
[07:32:28.825] <TB3> INFO: Resuming triggers.
[07:32:35.787] <TB3> INFO: run duration 13 seconds, buffer almost full (81%), pausing triggers.
[07:32:39.699] <TB3> ERROR: <datapipe.cc/CheckEventID:L420> Event ID mismatch: local ID (239) != TBM ID (71)

[07:32:39.699] <TB3> ERROR: <datapipe.cc/CheckEventValidity:L437> Number of ROCs (0) != Token Chain Length (4)

[07:32:39.699] <TB3> ERROR: <datapipe.cc/CheckEventID:L420> Event ID mismatch: local ID (72) != TBM ID (239)

[07:32:58.360] <TB3> INFO: Resuming triggers.
[07:33:05.316] <TB3> INFO: run duration 20 seconds, buffer almost full (81%), pausing triggers.
[07:33:26.615] <TB3> INFO: Resuming triggers.
[07:33:33.576] <TB3> INFO: run duration 27 seconds, buffer almost full (81%), pausing triggers.
[07:33:55.406] <TB3> INFO: Resuming triggers.
[07:34:02.370] <TB3> INFO: run duration 34 seconds, buffer almost full (81%), pausing triggers.
[07:34:24.579] <TB3> INFO: Resuming triggers.
[07:34:31.553] <TB3> INFO: run duration 41 seconds, buffer almost full (81%), pausing triggers.
[07:34:55.378] <TB3> INFO: Resuming triggers.
[07:35:02.345] <TB3> INFO: run duration 48 seconds, buffer almost full (81%), pausing triggers.
[07:35:23.075] <TB3> INFO: Resuming triggers.
[07:35:30.037] <TB3> INFO: run duration 55 seconds, buffer almost full (81%), pausing triggers.
[07:35:52.936] <TB3> INFO: Resuming triggers.
[07:35:59.895] <TB3> INFO: run duration 62 seconds, buffer almost full (81%), pausing triggers.
[07:36:22.718] <TB3> INFO: Resuming triggers.
[07:36:29.674] <TB3> INFO: run duration 69 seconds, buffer almost full (81%), pausing triggers.
[07:36:52.836] <TB3> INFO: Resuming triggers.
[07:36:59.797] <TB3> INFO: run duration 76 seconds, buffer almost full (81%), pausing triggers.
[07:37:19.042] <TB3> INFO: Resuming triggers.
[07:37:26.002] <TB3> INFO: run duration 83 seconds, buffer almost full (81%), pausing triggers.
[07:37:45.551] <TB3> INFO: Resuming triggers.
[07:37:52.510] <TB3> INFO: run duration 90 seconds, buffer almost full (81%), pausing triggers.
[07:38:13.711] <TB3> INFO: Resuming triggers.
[07:38:20.678] <TB3> INFO: run duration 97 seconds, buffer almost full (81%), pausing triggers.
[07:38:42.302] <TB3> INFO: Resuming triggers.
[07:38:44.950] <TB3> INFO: data taking finished, elapsed time: 100 seconds.
[07:38:52.996] <TB3> INFO: PixTest:: pg_setup set to default.
[07:38:52.997] <TB3> INFO: PixTestXray::doPhRun() done
[07:38:52.997] <TB3> INFO: PixTestXray::doTest() done
[07:38:53.189] <TB3> INFO: enter test to run
[07:38:53.189] <TB3> INFO: test: no parameter change
[07:38:53.380] <TB3> QUIET: Connection to board 121 closed.
[07:38:53.459] <TB3> INFO: pXar: this is the end, my friend
commander_HRData.log
[07:39:37.243] <TB3> INFO: *** Welcome to pxar ***
[07:39:37.243] <TB3> INFO: *** Today: 2015/07/07
[07:39:37.243] <TB3> INFO: readRocDacs: /usr/local/data/M2031_XRayHRQualification_2015-07-07_09h27m_1436254057//004_HRData_p17//dacParameters35_C0.dat .. /usr/local/data/M2031_XRayHRQualification_2015-07-07_09h27m_1436254057//004_HRData_p17//dacParameters35_C15.dat
[07:39:37.244] <TB3> INFO: readTbmDacs: /usr/local/data/M2031_XRayHRQualification_2015-07-07_09h27m_1436254057//004_HRData_p17//tbmParameters_C0a.dat .. /usr/local/data/M2031_XRayHRQualification_2015-07-07_09h27m_1436254057//004_HRData_p17//tbmParameters_C0b.dat
[07:39:37.244] <TB3> INFO: readMaskFile: /usr/local/data/M2031_XRayHRQualification_2015-07-07_09h27m_1436254057//004_HRData_p17//defaultMaskFile.dat
[07:39:37.244] <TB3> INFO: readTrimFile: /usr/local/data/M2031_XRayHRQualification_2015-07-07_09h27m_1436254057//004_HRData_p17//trimParameters35_C0.dat .. /usr/local/data/M2031_XRayHRQualification_2015-07-07_09h27m_1436254057//004_HRData_p17//trimParameters35_C15.dat
[07:39:37.333] <TB3> INFO: clk: 4
[07:39:37.333] <TB3> INFO: ctr: 4
[07:39:37.333] <TB3> INFO: sda: 19
[07:39:37.333] <TB3> INFO: tin: 9
[07:39:37.333] <TB3> INFO: level: 15
[07:39:37.333] <TB3> INFO: triggerdelay: 0
[07:39:37.333] <TB3> QUIET: Instanciating API for pxar v2.2.5+45~gbf85984
[07:39:37.333] <TB3> INFO: Log level: INFO
[07:39:37.345] <TB3> INFO: Found DTB DTB_WWV6Z5
[07:39:37.366] <TB3> QUIET: Connection to board DTB_WWV6Z5 opened.
[07:39:37.369] <TB3> INFO: DTB startup information
--- DTB info------------------------------------------
Board id: 121
HW version: DTB1.2
FW version: 4.0
SW version: 4.0
USB id: DTB_WWV6Z5
MAC address: 40D855118079
Hostname: pixelDTB121
Comment:
------------------------------------------------------
[07:39:37.372] <TB3> INFO: RPC call hashes of host and DTB match: 447413373
[07:39:38.935] <TB3> INFO: DUT info:
[07:39:38.935] <TB3> INFO: The DUT currently contains the following objects:
[07:39:38.935] <TB3> INFO: 2 TBM Cores tbm09c (2 ON)
[07:39:38.935] <TB3> INFO: TBM Core alpha (0): 7 registers set
[07:39:38.935] <TB3> INFO: TBM Core beta (1): 7 registers set
[07:39:38.935] <TB3> INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[07:39:38.935] <TB3> INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[07:39:38.935] <TB3> INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[07:39:38.935] <TB3> INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[07:39:38.935] <TB3> INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[07:39:38.935] <TB3> INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[07:39:38.935] <TB3> INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[07:39:38.935] <TB3> INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[07:39:38.935] <TB3> INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[07:39:38.935] <TB3> INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[07:39:38.935] <TB3> INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[07:39:38.935] <TB3> INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[07:39:38.936] <TB3> INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[07:39:38.936] <TB3> INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[07:39:38.936] <TB3> INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[07:39:38.936] <TB3> INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[07:39:38.936] <TB3> INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[07:39:39.337] <TB3> INFO: enter 'restricted' command line mode
[07:39:39.337] <TB3> INFO: enter test to run
[07:39:39.337] <TB3> INFO: test: delay setting parameters: ->5<-
[07:39:39.337] <TB3> INFO: delay test by 5 seconds...
[07:39:44.337] <TB3> INFO: enter test to run
[07:39:44.337] <TB3> INFO: test: Xray no parameter change
[07:39:44.337] <TB3> INFO: running: xray
[07:39:44.337] <TB3> INFO: readGainPedestalParameters /usr/local/data/M2031_XRayHRQualification_2015-07-07_09h27m_1436254057//004_HRData_p17//phCalibrationFitErr35_C0.dat .. /usr/local/data/M2031_XRayHRQualification_2015-07-07_09h27m_1436254057//004_HRData_p17//phCalibrationFitErr35_C15.dat
[07:39:44.512] <TB3> INFO: ######################################################################
[07:39:44.512] <TB3> INFO: PixTestXray::doTest()
[07:39:44.512] <TB3> INFO: ######################################################################
[07:39:44.512] <TB3> INFO: ----------------------------------------------------------------------
[07:39:44.512] <TB3> INFO: PixTestXray::doPhRun() fParRunSeconds = 100
[07:39:44.512] <TB3> INFO: ----------------------------------------------------------------------
[07:39:45.482] <TB3> INFO: PixTestXray::doPhRun start TriggerLoop with trigger frequency 100 kHz, period 405 and duration 100 seconds, fEventsMax = 10000000
[07:39:49.395] <TB3> INFO: run duration 3 seconds, buffer almost full (81%), pausing triggers.
[07:39:49.400] <TB3> ERROR: <datapipe.cc/CheckEventValidity:L437> Number of ROCs (0) != Token Chain Length (4)

[07:39:49.403] <TB3> ERROR: <datapipe.cc/CheckEventValidity:L437> Number of ROCs (0) != Token Chain Length (4)

[07:39:49.405] <TB3> ERROR: <datapipe.cc/CheckEventValidity:L437> Number of ROCs (0) != Token Chain Length (4)

[07:39:49.408] <TB3> ERROR: <datapipe.cc/CheckEventValidity:L437> Number of ROCs (0) != Token Chain Length (4)

[07:40:16.991] <TB3> INFO: Resuming triggers.
[07:40:20.922] <TB3> INFO: run duration 7 seconds, buffer almost full (81%), pausing triggers.
[07:40:44.715] <TB3> INFO: Resuming triggers.
[07:40:48.643] <TB3> INFO: run duration 11 seconds, buffer almost full (81%), pausing triggers.
[07:41:11.889] <TB3> INFO: Resuming triggers.
[07:41:15.820] <TB3> INFO: run duration 15 seconds, buffer almost full (81%), pausing triggers.
[07:41:37.887] <TB3> INFO: Resuming triggers.
[07:41:41.815] <TB3> INFO: run duration 19 seconds, buffer almost full (81%), pausing triggers.
[07:42:04.639] <TB3> INFO: Resuming triggers.
[07:42:08.563] <TB3> INFO: run duration 23 seconds, buffer almost full (81%), pausing triggers.
[07:42:32.439] <TB3> INFO: Resuming triggers.
[07:42:36.365] <TB3> INFO: run duration 27 seconds, buffer almost full (81%), pausing triggers.
[07:42:59.622] <TB3> INFO: Resuming triggers.
[07:43:03.551] <TB3> INFO: run duration 31 seconds, buffer almost full (81%), pausing triggers.
[07:43:27.380] <TB3> INFO: Resuming triggers.
[07:43:31.310] <TB3> INFO: run duration 35 seconds, buffer almost full (81%), pausing triggers.
[07:43:52.714] <TB3> INFO: Resuming triggers.
[07:43:56.639] <TB3> INFO: run duration 39 seconds, buffer almost full (81%), pausing triggers.
[07:44:22.950] <TB3> INFO: Resuming triggers.
[07:44:26.877] <TB3> INFO: run duration 43 seconds, buffer almost full (81%), pausing triggers.
[07:44:48.926] <TB3> INFO: Resuming triggers.
[07:44:52.852] <TB3> INFO: run duration 47 seconds, buffer almost full (81%), pausing triggers.
[07:45:16.577] <TB3> INFO: Resuming triggers.
[07:45:20.505] <TB3> INFO: run duration 50 seconds, buffer almost full (81%), pausing triggers.
[07:45:44.728] <TB3> INFO: Resuming triggers.
[07:45:48.654] <TB3> INFO: run duration 54 seconds, buffer almost full (81%), pausing triggers.
[07:46:11.863] <TB3> INFO: Resuming triggers.
[07:46:15.786] <TB3> INFO: run duration 58 seconds, buffer almost full (81%), pausing triggers.
[07:46:39.109] <TB3> INFO: Resuming triggers.
[07:46:43.031] <TB3> INFO: run duration 62 seconds, buffer almost full (81%), pausing triggers.
[07:47:03.982] <TB3> INFO: Resuming triggers.
[07:47:07.904] <TB3> INFO: run duration 66 seconds, buffer almost full (81%), pausing triggers.
[07:47:30.608] <TB3> INFO: Resuming triggers.
[07:47:34.533] <TB3> INFO: run duration 70 seconds, buffer almost full (81%), pausing triggers.
[07:47:54.884] <TB3> INFO: Resuming triggers.
[07:47:58.806] <TB3> INFO: run duration 74 seconds, buffer almost full (81%), pausing triggers.
[07:48:19.084] <TB3> INFO: Resuming triggers.
[07:48:23.013] <TB3> INFO: run duration 78 seconds, buffer almost full (81%), pausing triggers.
[07:48:43.523] <TB3> INFO: Resuming triggers.
[07:48:47.445] <TB3> INFO: run duration 82 seconds, buffer almost full (81%), pausing triggers.
[07:49:09.757] <TB3> INFO: Resuming triggers.
[07:49:13.685] <TB3> INFO: run duration 86 seconds, buffer almost full (81%), pausing triggers.
[07:49:36.059] <TB3> INFO: Resuming triggers.
[07:49:39.980] <TB3> INFO: run duration 90 seconds, buffer almost full (81%), pausing triggers.
[07:50:01.988] <TB3> INFO: Resuming triggers.
[07:50:05.916] <TB3> INFO: run duration 94 seconds, buffer almost full (81%), pausing triggers.
[07:50:26.758] <TB3> INFO: Resuming triggers.
[07:50:30.682] <TB3> INFO: run duration 98 seconds, buffer almost full (81%), pausing triggers.
[07:50:53.550] <TB3> INFO: Resuming triggers.
[07:50:55.538] <TB3> INFO: data taking finished, elapsed time: 100 seconds.
[07:51:05.843] <TB3> INFO: PixTest:: pg_setup set to default.
[07:51:05.845] <TB3> INFO: PixTestXray::doPhRun() done
[07:51:05.845] <TB3> INFO: PixTestXray::doTest() done
[07:51:06.044] <TB3> INFO: enter test to run
[07:51:06.044] <TB3> INFO: test: no parameter change
[07:51:06.236] <TB3> QUIET: Connection to board 121 closed.
[07:51:06.315] <TB3> INFO: pXar: this is the end, my friend
commander_HRSCurves.log
[13:20:34.651] <TB0> INFO: *** Welcome to pxar ***
[13:20:34.651] <TB0> INFO: *** Today: 2015/07/17
[13:20:34.651] <TB0> INFO: readRocDacs: /usr/local/data/M2031_XrayQualification_2015-07-17_15h18m_1437139085//001_HRSCurves_100//dacParameters35_C0.dat .. /usr/local/data/M2031_XrayQualification_2015-07-17_15h18m_1437139085//001_HRSCurves_100//dacParameters35_C15.dat
[13:20:34.651] <TB0> INFO: readTbmDacs: /usr/local/data/M2031_XrayQualification_2015-07-17_15h18m_1437139085//001_HRSCurves_100//tbmParameters_C0a.dat .. /usr/local/data/M2031_XrayQualification_2015-07-17_15h18m_1437139085//001_HRSCurves_100//tbmParameters_C0b.dat
[13:20:34.651] <TB0> INFO: readMaskFile: /usr/local/data/M2031_XrayQualification_2015-07-17_15h18m_1437139085//001_HRSCurves_100//defaultMaskFile.dat
[13:20:34.651] <TB0> INFO: readTrimFile: /usr/local/data/M2031_XrayQualification_2015-07-17_15h18m_1437139085//001_HRSCurves_100//trimParameters35_C0.dat .. /usr/local/data/M2031_XrayQualification_2015-07-17_15h18m_1437139085//001_HRSCurves_100//trimParameters35_C15.dat
[13:20:34.719] <TB0> INFO: clk: 4
[13:20:34.719] <TB0> INFO: ctr: 4
[13:20:34.719] <TB0> INFO: sda: 19
[13:20:34.719] <TB0> INFO: tin: 9
[13:20:34.719] <TB0> INFO: level: 15
[13:20:34.719] <TB0> INFO: triggerdelay: 0
[13:20:34.719] <TB0> QUIET: Instanciating API for pxar v2.2.5+45~gbf85984
[13:20:34.719] <TB0> INFO: Log level: INFO
[13:20:34.733] <TB0> INFO: Found DTB DTB_WWXTQT
[13:20:34.749] <TB0> QUIET: Connection to board DTB_WWXTQT opened.
[13:20:34.752] <TB0> INFO: DTB startup information
--- DTB info------------------------------------------
Board id: 147
HW version: DTB1.2
FW version: 4.0
SW version: 4.0
USB id: DTB_WWXTQT
MAC address: 40D855118093
Hostname: pixelDTB147
Comment:
------------------------------------------------------
[13:20:34.755] <TB0> INFO: RPC call hashes of host and DTB match: 447413373
[13:20:36.296] <TB0> INFO: DUT info:
[13:20:36.296] <TB0> INFO: The DUT currently contains the following objects:
[13:20:36.296] <TB0> INFO: 2 TBM Cores tbm09c (2 ON)
[13:20:36.296] <TB0> INFO: TBM Core alpha (0): 7 registers set
[13:20:36.296] <TB0> INFO: TBM Core beta (1): 7 registers set
[13:20:36.296] <TB0> INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[13:20:36.296] <TB0> INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[13:20:36.296] <TB0> INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[13:20:36.296] <TB0> INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[13:20:36.296] <TB0> INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[13:20:36.296] <TB0> INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[13:20:36.296] <TB0> INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[13:20:36.296] <TB0> INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[13:20:36.296] <TB0> INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[13:20:36.296] <TB0> INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[13:20:36.296] <TB0> INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[13:20:36.296] <TB0> INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[13:20:36.296] <TB0> INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[13:20:36.296] <TB0> INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[13:20:36.296] <TB0> INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[13:20:36.296] <TB0> INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[13:20:36.296] <TB0> INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[13:20:36.697] <TB0> INFO: enter 'restricted' command line mode
[13:20:36.697] <TB0> INFO: enter test to run
[13:20:36.697] <TB0> INFO: test: Highrate no parameter change
[13:20:36.697] <TB0> INFO: running: highrate
[13:20:36.697] <TB0> INFO: readGainPedestalParameters /usr/local/data/M2031_XrayQualification_2015-07-17_15h18m_1437139085//001_HRSCurves_100//phCalibrationFitErr35_C0.dat .. /usr/local/data/M2031_XrayQualification_2015-07-17_15h18m_1437139085//001_HRSCurves_100//phCalibrationFitErr35_C15.dat
[13:20:36.827] <TB0> INFO: ----------------------------------------------------------------------
[13:20:36.827] <TB0> INFO: PixTestHighRate::xNoiseMaps() ntrig = 50, vcal = 200
[13:20:36.827] <TB0> INFO: ----------------------------------------------------------------------
[13:20:36.833] <TB0> INFO: ---> dac: vcal name: xNoiseMap ntrig: 50 dacrange: 0 .. 150 (1) hits flags = 384 (plus default)
[13:20:36.844] <TB0> INFO: dacScan step from 0 .. 0
[13:20:48.277] <TB0> INFO: Test took 11433ms.
[13:20:49.527] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:20:49.746] <TB0> INFO: dacScan step from 1 .. 1
[13:21:03.870] <TB0> INFO: Test took 14124ms.
[13:21:05.225] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:21:05.551] <TB0> INFO: dacScan step from 2 .. 2
[13:21:20.781] <TB0> INFO: Test took 15229ms.
[13:21:22.082] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:21:22.304] <TB0> INFO: dacScan step from 3 .. 3
[13:21:36.609] <TB0> INFO: Test took 14305ms.
[13:21:38.509] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:21:38.744] <TB0> INFO: dacScan step from 4 .. 4
[13:21:52.182] <TB0> INFO: Test took 13438ms.
[13:21:53.454] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:21:53.684] <TB0> INFO: dacScan step from 5 .. 5
[13:22:05.555] <TB0> INFO: Test took 11871ms.
[13:22:06.842] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:22:07.069] <TB0> INFO: dacScan step from 6 .. 6
[13:22:19.468] <TB0> INFO: Test took 12398ms.
[13:22:20.697] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:22:20.915] <TB0> INFO: dacScan step from 7 .. 7
[13:22:34.662] <TB0> INFO: Test took 13746ms.
[13:22:36.261] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:22:36.488] <TB0> INFO: dacScan step from 8 .. 8
[13:22:50.524] <TB0> INFO: Test took 14036ms.
[13:22:51.823] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:22:52.053] <TB0> INFO: dacScan step from 9 .. 9
[13:23:05.889] <TB0> INFO: Test took 13836ms.
[13:23:07.223] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:23:07.451] <TB0> INFO: dacScan step from 10 .. 10
[13:23:20.541] <TB0> INFO: Test took 13090ms.
[13:23:21.977] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:23:22.217] <TB0> INFO: dacScan step from 11 .. 11
[13:23:34.363] <TB0> INFO: Test took 12146ms.
[13:23:35.684] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:23:35.922] <TB0> INFO: dacScan step from 12 .. 12
[13:23:48.356] <TB0> INFO: Test took 12434ms.
[13:23:49.960] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:23:50.254] <TB0> INFO: dacScan step from 13 .. 13
[13:24:03.969] <TB0> INFO: Test took 13714ms.
[13:24:05.283] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:24:05.614] <TB0> INFO: dacScan step from 14 .. 14
[13:24:20.319] <TB0> INFO: Test took 14704ms.
[13:24:21.778] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:24:21.999] <TB0> INFO: dacScan step from 15 .. 15
[13:24:36.352] <TB0> INFO: Test took 14352ms.
[13:24:37.634] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:24:37.863] <TB0> INFO: dacScan step from 16 .. 16
[13:24:51.844] <TB0> INFO: Test took 13981ms.
[13:24:53.183] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:24:53.424] <TB0> INFO: dacScan step from 17 .. 17
[13:25:06.139] <TB0> INFO: Test took 12715ms.
[13:25:07.482] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:25:07.720] <TB0> INFO: dacScan step from 18 .. 18
[13:25:19.898] <TB0> INFO: Test took 12178ms.
[13:25:21.184] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:25:21.417] <TB0> INFO: dacScan step from 19 .. 19
[13:25:34.434] <TB0> INFO: Test took 13017ms.
[13:25:35.689] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:25:35.910] <TB0> INFO: dacScan step from 20 .. 20
[13:25:49.704] <TB0> INFO: Test took 13793ms.
[13:25:51.327] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:25:51.587] <TB0> INFO: dacScan step from 21 .. 21
[13:26:06.420] <TB0> INFO: Test took 14832ms.
[13:26:08.142] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:26:08.360] <TB0> INFO: dacScan step from 22 .. 22
[13:26:22.670] <TB0> INFO: Test took 14309ms.
[13:26:23.910] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:26:24.162] <TB0> INFO: dacScan step from 23 .. 23
[13:26:37.474] <TB0> INFO: Test took 13311ms.
[13:26:38.961] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:26:39.188] <TB0> INFO: dacScan step from 24 .. 24
[13:26:51.306] <TB0> INFO: Test took 12117ms.
[13:26:52.552] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:26:52.772] <TB0> INFO: dacScan step from 25 .. 25
[13:27:05.363] <TB0> INFO: Test took 12590ms.
[13:27:06.648] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:27:06.877] <TB0> INFO: dacScan step from 26 .. 26
[13:27:20.362] <TB0> INFO: Test took 13485ms.
[13:27:21.617] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:27:21.846] <TB0> INFO: dacScan step from 27 .. 27
[13:27:36.893] <TB0> INFO: Test took 15047ms.
[13:27:38.301] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:27:38.646] <TB0> INFO: dacScan step from 28 .. 28
[13:27:52.728] <TB0> INFO: Test took 14081ms.
[13:27:54.066] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:27:54.294] <TB0> INFO: dacScan step from 29 .. 29
[13:28:07.392] <TB0> INFO: Test took 13098ms.
[13:28:08.756] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:28:08.990] <TB0> INFO: dacScan step from 30 .. 30
[13:28:21.106] <TB0> INFO: Test took 12116ms.
[13:28:22.383] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:28:22.613] <TB0> INFO: dacScan step from 31 .. 31
[13:28:35.314] <TB0> INFO: Test took 12700ms.
[13:28:36.653] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:28:36.876] <TB0> INFO: dacScan step from 32 .. 32
[13:28:50.398] <TB0> INFO: Test took 13521ms.
[13:28:51.818] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:28:52.062] <TB0> INFO: dacScan step from 33 .. 33
[13:29:07.351] <TB0> INFO: Test took 15288ms.
[13:29:08.714] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:29:08.941] <TB0> INFO: dacScan step from 34 .. 34
[13:29:23.063] <TB0> INFO: Test took 14121ms.
[13:29:24.400] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:29:24.626] <TB0> INFO: dacScan step from 35 .. 35
[13:29:37.946] <TB0> INFO: Test took 13319ms.
[13:29:39.288] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:29:39.526] <TB0> INFO: dacScan step from 36 .. 36
[13:29:52.270] <TB0> INFO: Test took 12743ms.
[13:29:53.713] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:29:53.956] <TB0> INFO: dacScan step from 37 .. 37
[13:30:07.545] <TB0> INFO: Test took 13588ms.
[13:30:08.943] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:30:09.197] <TB0> INFO: dacScan step from 38 .. 38
[13:30:23.073] <TB0> INFO: Test took 13876ms.
[13:30:24.815] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:30:25.052] <TB0> INFO: dacScan step from 39 .. 39
[13:30:39.687] <TB0> INFO: Test took 14634ms.
[13:30:41.181] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:30:41.426] <TB0> INFO: dacScan step from 40 .. 40
[13:30:57.510] <TB0> INFO: Test took 16084ms.
[13:30:58.888] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:30:59.117] <TB0> INFO: dacScan step from 41 .. 41
[13:31:14.941] <TB0> INFO: Test took 15823ms.
[13:31:16.378] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:31:16.606] <TB0> INFO: dacScan step from 42 .. 42
[13:31:33.089] <TB0> INFO: Test took 16483ms.
[13:31:34.488] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:31:34.715] <TB0> INFO: dacScan step from 43 .. 43
[13:31:50.877] <TB0> INFO: Test took 16162ms.
[13:31:52.456] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:31:52.681] <TB0> INFO: dacScan step from 44 .. 44
[13:32:08.620] <TB0> INFO: Test took 15938ms.
[13:32:10.118] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:32:10.351] <TB0> INFO: dacScan step from 45 .. 45
[13:32:27.218] <TB0> INFO: Test took 16867ms.
[13:32:28.737] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:32:28.984] <TB0> INFO: dacScan step from 46 .. 46
[13:32:46.002] <TB0> INFO: Test took 17017ms.
[13:32:47.441] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:32:47.671] <TB0> INFO: dacScan step from 47 .. 47
[13:33:04.282] <TB0> INFO: Test took 16610ms.
[13:33:05.814] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:33:06.062] <TB0> INFO: dacScan step from 48 .. 48
[13:33:22.715] <TB0> INFO: Test took 16653ms.
[13:33:24.216] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:33:24.452] <TB0> INFO: dacScan step from 49 .. 49
[13:33:40.158] <TB0> INFO: Test took 15705ms.
[13:33:41.697] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:33:41.943] <TB0> INFO: dacScan step from 50 .. 50
[13:33:57.373] <TB0> INFO: Test took 15429ms.
[13:33:58.896] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:33:59.139] <TB0> INFO: dacScan step from 51 .. 51
[13:34:14.478] <TB0> INFO: Test took 15338ms.
[13:34:15.906] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:34:16.137] <TB0> INFO: dacScan step from 52 .. 52
[13:34:31.844] <TB0> INFO: Test took 15707ms.
[13:34:33.355] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:34:33.581] <TB0> INFO: dacScan step from 53 .. 53
[13:34:49.417] <TB0> INFO: Test took 15835ms.
[13:34:50.937] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:34:51.178] <TB0> INFO: dacScan step from 54 .. 54
[13:35:08.402] <TB0> INFO: Test took 17224ms.
[13:35:10.137] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:35:10.381] <TB0> INFO: dacScan step from 55 .. 55
[13:35:27.978] <TB0> INFO: Test took 17597ms.
[13:35:29.524] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:35:29.772] <TB0> INFO: dacScan step from 56 .. 56
[13:35:46.480] <TB0> INFO: Test took 16708ms.
[13:35:48.012] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:35:48.273] <TB0> INFO: dacScan step from 57 .. 57
[13:36:04.509] <TB0> INFO: Test took 16235ms.
[13:36:06.061] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:36:06.303] <TB0> INFO: dacScan step from 58 .. 58
[13:36:21.800] <TB0> INFO: Test took 15497ms.
[13:36:23.347] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:36:23.596] <TB0> INFO: dacScan step from 59 .. 59
[13:36:39.154] <TB0> INFO: Test took 15558ms.
[13:36:40.654] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:36:40.897] <TB0> INFO: dacScan step from 60 .. 60
[13:36:57.798] <TB0> INFO: Test took 16901ms.
[13:36:59.326] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:36:59.567] <TB0> INFO: dacScan step from 61 .. 61
[13:37:17.690] <TB0> INFO: Test took 18122ms.
[13:37:19.202] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:37:19.434] <TB0> INFO: dacScan step from 62 .. 62
[13:37:36.625] <TB0> INFO: Test took 17190ms.
[13:37:38.179] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:37:38.425] <TB0> INFO: dacScan step from 63 .. 63
[13:37:55.254] <TB0> INFO: Test took 16828ms.
[13:37:56.750] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:37:56.999] <TB0> INFO: dacScan step from 64 .. 64
[13:38:12.604] <TB0> INFO: Test took 15604ms.
[13:38:14.151] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:38:14.396] <TB0> INFO: dacScan step from 65 .. 65
[13:38:30.221] <TB0> INFO: Test took 15824ms.
[13:38:31.750] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:38:32.008] <TB0> INFO: dacScan step from 66 .. 66
[13:38:47.815] <TB0> INFO: Test took 15806ms.
[13:38:49.310] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:38:49.535] <TB0> INFO: dacScan step from 67 .. 67
[13:39:07.093] <TB0> INFO: Test took 17557ms.
[13:39:08.622] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:39:08.913] <TB0> INFO: dacScan step from 68 .. 68
[13:39:27.293] <TB0> INFO: Test took 18379ms.
[13:39:28.791] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:39:29.036] <TB0> INFO: dacScan step from 69 .. 69
[13:39:46.744] <TB0> INFO: Test took 17707ms.
[13:39:48.225] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:39:48.475] <TB0> INFO: dacScan step from 70 .. 70
[13:40:05.166] <TB0> INFO: Test took 16690ms.
[13:40:06.736] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:40:06.989] <TB0> INFO: dacScan step from 71 .. 71
[13:40:23.079] <TB0> INFO: Test took 16089ms.
[13:40:25.013] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:40:25.372] <TB0> INFO: dacScan step from 72 .. 72
[13:40:41.175] <TB0> INFO: Test took 15802ms.
[13:40:42.641] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:40:42.875] <TB0> INFO: dacScan step from 73 .. 73
[13:40:58.237] <TB0> INFO: Test took 15361ms.
[13:40:59.649] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:40:59.882] <TB0> INFO: dacScan step from 74 .. 74
[13:41:16.620] <TB0> INFO: Test took 16737ms.
[13:41:18.157] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:41:18.394] <TB0> INFO: dacScan step from 75 .. 75
[13:41:36.630] <TB0> INFO: Test took 18235ms.
[13:41:38.119] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:41:38.371] <TB0> INFO: dacScan step from 76 .. 76
[13:41:56.250] <TB0> INFO: Test took 17878ms.
[13:41:57.781] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:41:58.031] <TB0> INFO: dacScan step from 77 .. 77
[13:42:14.628] <TB0> INFO: Test took 16596ms.
[13:42:16.127] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:42:16.360] <TB0> INFO: dacScan step from 78 .. 78
[13:42:32.850] <TB0> INFO: Test took 16489ms.
[13:42:34.421] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:42:34.666] <TB0> INFO: dacScan step from 79 .. 79
[13:42:50.317] <TB0> INFO: Test took 15650ms.
[13:42:51.758] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:42:52.037] <TB0> INFO: dacScan step from 80 .. 80
[13:43:07.887] <TB0> INFO: Test took 15849ms.
[13:43:09.433] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:43:09.669] <TB0> INFO: dacScan step from 81 .. 81
[13:43:26.273] <TB0> INFO: Test took 16603ms.
[13:43:27.774] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:43:28.031] <TB0> INFO: dacScan step from 82 .. 82
[13:43:45.765] <TB0> INFO: Test took 17733ms.
[13:43:47.327] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:43:47.656] <TB0> INFO: dacScan step from 83 .. 83
[13:44:05.957] <TB0> INFO: Test took 18300ms.
[13:44:07.480] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:44:07.710] <TB0> INFO: dacScan step from 84 .. 84
[13:44:25.259] <TB0> INFO: Test took 17548ms.
[13:44:26.747] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:44:26.981] <TB0> INFO: dacScan step from 85 .. 85
[13:44:43.780] <TB0> INFO: Test took 16798ms.
[13:44:45.233] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:44:45.466] <TB0> INFO: dacScan step from 86 .. 86
[13:45:00.605] <TB0> INFO: Test took 15138ms.
[13:45:02.151] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:45:02.391] <TB0> INFO: dacScan step from 87 .. 87
[13:45:17.849] <TB0> INFO: Test took 15457ms.
[13:45:19.372] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:45:19.614] <TB0> INFO: dacScan step from 88 .. 88
[13:45:36.181] <TB0> INFO: Test took 16567ms.
[13:45:37.706] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:45:37.937] <TB0> INFO: dacScan step from 89 .. 89
[13:45:55.964] <TB0> INFO: Test took 18026ms.
[13:45:57.465] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:45:57.707] <TB0> INFO: dacScan step from 90 .. 90
[13:46:15.480] <TB0> INFO: Test took 17772ms.
[13:46:16.946] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:46:17.181] <TB0> INFO: dacScan step from 91 .. 91
[13:46:34.797] <TB0> INFO: Test took 17615ms.
[13:46:36.284] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:46:36.516] <TB0> INFO: dacScan step from 92 .. 92
[13:46:52.850] <TB0> INFO: Test took 16333ms.
[13:46:54.378] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:46:54.643] <TB0> INFO: dacScan step from 93 .. 93
[13:47:10.096] <TB0> INFO: Test took 15452ms.
[13:47:11.535] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:47:11.766] <TB0> INFO: dacScan step from 94 .. 94
[13:47:27.706] <TB0> INFO: Test took 15939ms.
[13:47:29.195] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:47:29.426] <TB0> INFO: dacScan step from 95 .. 95
[13:47:46.207] <TB0> INFO: Test took 16780ms.
[13:47:47.694] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:47:47.931] <TB0> INFO: dacScan step from 96 .. 96
[13:48:05.410] <TB0> INFO: Test took 17478ms.
[13:48:06.914] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:48:07.159] <TB0> INFO: dacScan step from 97 .. 97
[13:48:24.892] <TB0> INFO: Test took 17732ms.
[13:48:26.413] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:48:26.658] <TB0> INFO: dacScan step from 98 .. 98
[13:48:43.262] <TB0> INFO: Test took 16603ms.
[13:48:44.812] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:48:45.061] <TB0> INFO: dacScan step from 99 .. 99
[13:49:01.285] <TB0> INFO: Test took 16223ms.
[13:49:02.979] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:49:03.225] <TB0> INFO: dacScan step from 100 .. 100
[13:49:18.814] <TB0> INFO: Test took 15588ms.
[13:49:20.341] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:49:20.588] <TB0> INFO: dacScan step from 101 .. 101
[13:49:36.268] <TB0> INFO: Test took 15679ms.
[13:49:37.798] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:49:38.055] <TB0> INFO: dacScan step from 102 .. 102
[13:49:54.869] <TB0> INFO: Test took 16813ms.
[13:49:56.366] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:49:56.605] <TB0> INFO: dacScan step from 103 .. 103
[13:50:14.534] <TB0> INFO: Test took 17928ms.
[13:50:16.067] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:50:16.318] <TB0> INFO: dacScan step from 104 .. 104
[13:50:34.572] <TB0> INFO: Test took 18253ms.
[13:50:36.119] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:50:36.351] <TB0> INFO: dacScan step from 105 .. 105
[13:50:53.730] <TB0> INFO: Test took 17378ms.
[13:50:55.188] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:50:55.420] <TB0> INFO: dacScan step from 106 .. 106
[13:51:11.524] <TB0> INFO: Test took 16103ms.
[13:51:13.058] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:51:13.308] <TB0> INFO: dacScan step from 107 .. 107
[13:51:29.121] <TB0> INFO: Test took 15812ms.
[13:51:30.622] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:51:30.865] <TB0> INFO: dacScan step from 108 .. 108
[13:51:46.460] <TB0> INFO: Test took 15594ms.
[13:51:47.979] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:51:48.227] <TB0> INFO: dacScan step from 109 .. 109
[13:52:04.799] <TB0> INFO: Test took 16571ms.
[13:52:06.406] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:52:06.659] <TB0> INFO: dacScan step from 110 .. 110
[13:52:24.185] <TB0> INFO: Test took 17525ms.
[13:52:25.733] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:52:25.979] <TB0> INFO: dacScan step from 111 .. 111
[13:52:43.935] <TB0> INFO: Test took 17954ms.
[13:52:45.433] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:52:45.671] <TB0> INFO: dacScan step from 112 .. 112
[13:53:03.176] <TB0> INFO: Test took 17504ms.
[13:53:04.701] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:53:04.956] <TB0> INFO: dacScan step from 113 .. 113
[13:53:22.185] <TB0> INFO: Test took 17227ms.
[13:53:23.701] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:53:23.945] <TB0> INFO: dacScan step from 114 .. 114
[13:53:39.506] <TB0> INFO: Test took 15560ms.
[13:53:41.034] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:53:41.271] <TB0> INFO: dacScan step from 115 .. 115
[13:53:56.162] <TB0> INFO: Test took 14890ms.
[13:53:57.611] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:53:57.845] <TB0> INFO: dacScan step from 116 .. 116
[13:54:13.888] <TB0> INFO: Test took 16042ms.
[13:54:15.406] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:54:15.672] <TB0> INFO: dacScan step from 117 .. 117
[13:54:33.378] <TB0> INFO: Test took 17705ms.
[13:54:35.010] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:54:35.258] <TB0> INFO: dacScan step from 118 .. 118
[13:54:53.271] <TB0> INFO: Test took 18012ms.
[13:54:54.737] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:54:54.984] <TB0> INFO: dacScan step from 119 .. 119
[13:55:12.178] <TB0> INFO: Test took 17193ms.
[13:55:13.652] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:55:13.895] <TB0> INFO: dacScan step from 120 .. 120
[13:55:30.779] <TB0> INFO: Test took 16882ms.
[13:55:32.225] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:55:32.468] <TB0> INFO: dacScan step from 121 .. 121
[13:55:48.333] <TB0> INFO: Test took 15864ms.
[13:55:49.829] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:55:50.074] <TB0> INFO: dacScan step from 122 .. 122
[13:56:05.428] <TB0> INFO: Test took 15352ms.
[13:56:06.953] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:56:07.188] <TB0> INFO: dacScan step from 123 .. 123
[13:56:23.244] <TB0> INFO: Test took 16054ms.
[13:56:24.772] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:56:24.999] <TB0> INFO: dacScan step from 124 .. 124
[13:56:41.732] <TB0> INFO: Test took 16732ms.
[13:56:43.171] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:56:43.435] <TB0> INFO: dacScan step from 125 .. 125
[13:57:00.429] <TB0> INFO: Test took 16993ms.
[13:57:01.863] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:57:02.095] <TB0> INFO: dacScan step from 126 .. 126
[13:57:19.374] <TB0> INFO: Test took 17278ms.
[13:57:20.944] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:57:21.193] <TB0> INFO: dacScan step from 127 .. 127
[13:57:37.868] <TB0> INFO: Test took 16674ms.
[13:57:39.369] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:57:39.604] <TB0> INFO: dacScan step from 128 .. 128
[13:57:55.188] <TB0> INFO: Test took 15583ms.
[13:57:56.700] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:57:56.932] <TB0> INFO: dacScan step from 129 .. 129
[13:58:12.265] <TB0> INFO: Test took 15332ms.
[13:58:13.822] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:58:14.074] <TB0> INFO: dacScan step from 130 .. 130
[13:58:30.792] <TB0> INFO: Test took 16716ms.
[13:58:32.256] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:58:32.486] <TB0> INFO: dacScan step from 131 .. 131
[13:58:50.204] <TB0> INFO: Test took 17717ms.
[13:58:51.711] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:58:51.958] <TB0> INFO: dacScan step from 132 .. 132
[13:59:09.633] <TB0> INFO: Test took 17674ms.
[13:59:11.112] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:59:11.344] <TB0> INFO: dacScan step from 133 .. 133
[13:59:28.394] <TB0> INFO: Test took 17049ms.
[13:59:29.974] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:59:30.225] <TB0> INFO: dacScan step from 134 .. 134
[13:59:46.823] <TB0> INFO: Test took 16597ms.
[13:59:48.364] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:59:48.611] <TB0> INFO: dacScan step from 135 .. 135
[14:00:03.266] <TB0> INFO: Test took 14654ms.
[14:00:04.744] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[14:00:04.989] <TB0> INFO: dacScan step from 136 .. 136
[14:00:20.290] <TB0> INFO: Test took 15300ms.
[14:00:21.762] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[14:00:21.991] <TB0> INFO: dacScan step from 137 .. 137
[14:00:38.335] <TB0> INFO: Test took 16342ms.
[14:00:40.089] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[14:00:40.323] <TB0> INFO: dacScan step from 138 .. 138
[14:00:58.683] <TB0> INFO: Test took 18359ms.
[14:01:00.163] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[14:01:00.399] <TB0> INFO: dacScan step from 139 .. 139
[14:01:18.027] <TB0> INFO: Test took 17628ms.
[14:01:19.497] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[14:01:19.733] <TB0> INFO: dacScan step from 140 .. 140
[14:01:37.031] <TB0> INFO: Test took 17297ms.
[14:01:38.528] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[14:01:38.804] <TB0> INFO: dacScan step from 141 .. 141
[14:01:54.577] <TB0> INFO: Test took 15771ms.
[14:01:56.006] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[14:01:56.236] <TB0> INFO: dacScan step from 142 .. 142
[14:02:11.810] <TB0> INFO: Test took 15573ms.
[14:02:13.277] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[14:02:13.515] <TB0> INFO: dacScan step from 143 .. 143
[14:02:29.143] <TB0> INFO: Test took 15627ms.
[14:02:30.612] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[14:02:30.845] <TB0> INFO: dacScan step from 144 .. 144
[14:02:47.596] <TB0> INFO: Test took 16749ms.
[14:02:49.193] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[14:02:49.422] <TB0> INFO: dacScan step from 145 .. 145
[14:03:07.657] <TB0> INFO: Test took 18233ms.
[14:03:09.160] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[14:03:09.403] <TB0> INFO: dacScan step from 146 .. 146
[14:03:27.124] <TB0> INFO: Test took 17720ms.
[14:03:28.553] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[14:03:28.784] <TB0> INFO: dacScan step from 147 .. 147
[14:03:45.606] <TB0> INFO: Test took 16821ms.
[14:03:47.059] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[14:03:47.290] <TB0> INFO: dacScan step from 148 .. 148
[14:04:03.241] <TB0> INFO: Test took 15950ms.
[14:04:04.694] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[14:04:04.926] <TB0> INFO: dacScan step from 149 .. 149
[14:04:20.682] <TB0> INFO: Test took 15755ms.
[14:04:22.226] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[14:04:22.463] <TB0> INFO: dacScan step from 150 .. 150
[14:04:38.067] <TB0> INFO: Test took 15603ms.
[14:04:39.696] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[14:04:39.937] <TB0> INFO: dumping ASCII scurve output file: XSCurveData
[14:04:41.634] <TB0> INFO: dumping ASCII scurve output file: XSCurveData
[14:04:43.297] <TB0> INFO: dumping ASCII scurve output file: XSCurveData
[14:04:44.929] <TB0> INFO: dumping ASCII scurve output file: XSCurveData
[14:04:46.671] <TB0> INFO: dumping ASCII scurve output file: XSCurveData
[14:04:48.410] <TB0> INFO: dumping ASCII scurve output file: XSCurveData
[14:04:50.186] <TB0> INFO: dumping ASCII scurve output file: XSCurveData
[14:04:51.916] <TB0> INFO: dumping ASCII scurve output file: XSCurveData
[14:04:53.633] <TB0> INFO: dumping ASCII scurve output file: XSCurveData
[14:04:55.336] <TB0> INFO: dumping ASCII scurve output file: XSCurveData
[14:04:57.039] <TB0> INFO: dumping ASCII scurve output file: XSCurveData
[14:04:58.764] <TB0> INFO: dumping ASCII scurve output file: XSCurveData
[14:05:00.479] <TB0> INFO: dumping ASCII scurve output file: XSCurveData
[14:05:02.176] <TB0> INFO: dumping ASCII scurve output file: XSCurveData
[14:05:03.801] <TB0> INFO: dumping ASCII scurve output file: XSCurveData
[14:05:05.434] <TB0> INFO: dumping ASCII scurve output file: XSCurveData
[14:05:07.087] <TB0> INFO: PixTest:: pg_setup set to default.
[14:05:07.089] <TB0> INFO: vcal mean: 37.44 36.92 36.39 40.32 39.95 41.33 41.05 41.37 40.53 40.03 40.90 39.48 39.13 37.00 37.03 36.92
[14:05:07.089] <TB0> INFO: vcal RMS: 1.26 1.19 1.21 1.81 1.31 1.41 1.60 1.72 1.52 1.43 1.62 1.44 1.41 1.26 1.08 1.18
[14:05:07.089] <TB0> INFO: number of X-ray hits detected: 180584 183479 171131 323099 310226 368592 365729 367792 321733 326748 348579 269771 260406 151388 143464 155834
[14:05:07.089] <TB0> INFO: number of triggers sent (total per ROC): 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000
[14:05:07.089] <TB0> INFO: X-ray hit rate [MHz/cm2]: 52.9 53.8 50.2 94.7 90.9 108.0 107.2 107.8 94.3 95.8 102.2 79.1 76.3 44.4 42.1 45.7
[14:05:07.089] <TB0> INFO: PixTestHighRate::doXNoiseMaps() done
[14:05:07.186] <TB0> INFO: enter test to run
[14:05:07.186] <TB0> INFO: test: no parameter change
[14:05:07.380] <TB0> QUIET: Connection to board 147 closed.
[14:05:07.459] <TB0> INFO: pXar: this is the end, my friend
MoReWeb-v1.0.0-5-g82d9ff6 on branch psi46master