Test Date: 2015-08-24 08:08
Analysis date: 2016-05-26 01:11
Logfile
commander_PixelAlive.log
[06:09:06.289] <TB0> INFO: *** Welcome to pxar ***
[06:09:06.289] <TB0> INFO: *** Today: 2015/08/24
[06:09:06.289] <TB0> INFO: readRocDacs: /usr/local/data/M2068_XrayQualification_2015-08-24_08h08m_1440396521//000_PixelAlive_p17//dacParameters35_C0.dat .. /usr/local/data/M2068_XrayQualification_2015-08-24_08h08m_1440396521//000_PixelAlive_p17//dacParameters35_C15.dat
[06:09:06.290] <TB0> INFO: readTbmDacs: /usr/local/data/M2068_XrayQualification_2015-08-24_08h08m_1440396521//000_PixelAlive_p17//tbmParameters_C0a.dat .. /usr/local/data/M2068_XrayQualification_2015-08-24_08h08m_1440396521//000_PixelAlive_p17//tbmParameters_C0b.dat
[06:09:06.290] <TB0> INFO: readMaskFile: /usr/local/data/M2068_XrayQualification_2015-08-24_08h08m_1440396521//000_PixelAlive_p17//defaultMaskFile.dat
[06:09:06.290] <TB0> INFO: readTrimFile: /usr/local/data/M2068_XrayQualification_2015-08-24_08h08m_1440396521//000_PixelAlive_p17//trimParameters35_C0.dat .. /usr/local/data/M2068_XrayQualification_2015-08-24_08h08m_1440396521//000_PixelAlive_p17//trimParameters35_C15.dat
[06:09:06.357] <TB0> INFO: clk: 4
[06:09:06.357] <TB0> INFO: ctr: 4
[06:09:06.357] <TB0> INFO: sda: 19
[06:09:06.357] <TB0> INFO: tin: 9
[06:09:06.357] <TB0> INFO: level: 15
[06:09:06.357] <TB0> INFO: triggerdelay: 0
[06:09:06.357] <TB0> QUIET: Instanciating API for pxar prod-01+80~g16e673b
[06:09:06.357] <TB0> INFO: Log level: INFO
[06:09:06.371] <TB0> INFO: Found DTB DTB_WWXTQT
[06:09:06.381] <TB0> QUIET: Connection to board DTB_WWXTQT opened.
[06:09:06.384] <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:
------------------------------------------------------
[06:09:06.386] <TB0> INFO: RPC call hashes of host and DTB match: 447413373
[06:09:07.903] <TB0> INFO: DUT info:
[06:09:07.903] <TB0> INFO: The DUT currently contains the following objects:
[06:09:07.903] <TB0> INFO: 2 TBM Cores tbm09c (2 ON)
[06:09:07.903] <TB0> INFO: TBM Core alpha (0): 7 registers set
[06:09:07.903] <TB0> INFO: TBM Core beta (1): 7 registers set
[06:09:07.903] <TB0> INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[06:09:07.903] <TB0> INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[06:09:07.903] <TB0> INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[06:09:07.903] <TB0> INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[06:09:07.903] <TB0> INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[06:09:07.903] <TB0> INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[06:09:07.903] <TB0> INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[06:09:07.903] <TB0> INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[06:09:07.903] <TB0> INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[06:09:07.903] <TB0> INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[06:09:07.903] <TB0> INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[06:09:07.903] <TB0> INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[06:09:07.903] <TB0> INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[06:09:07.903] <TB0> INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[06:09:07.903] <TB0> INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[06:09:07.903] <TB0> INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[06:09:07.903] <TB0> INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[06:09:08.304] <TB0> INFO: enter 'restricted' command line mode
[06:09:08.304] <TB0> INFO: enter test to run
[06:09:08.304] <TB0> INFO: test: PixelAlive no parameter change
[06:09:08.304] <TB0> INFO: running: pixelalive
[06:09:08.308] <TB0> INFO: ######################################################################
[06:09:08.308] <TB0> INFO: PixTestAlive::doTest()
[06:09:08.308] <TB0> INFO: ######################################################################
[06:09:08.309] <TB0> INFO: ----------------------------------------------------------------------
[06:09:08.309] <TB0> INFO: PixTestAlive::aliveTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[06:09:08.309] <TB0> INFO: ----------------------------------------------------------------------
[06:09:11.681] <TB0> INFO: Test took 3370ms.
[06:09:11.698] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[06:09:11.948] <TB0> INFO: PixTestAlive::aliveTest() done
[06:09:11.948] <TB0> INFO: number of dead pixels (per ROC): 0 0 0 1 1 0 0 0 0 0 0 0 0 1 1 1
[06:09:11.950] <TB0> INFO: ----------------------------------------------------------------------
[06:09:11.950] <TB0> INFO: PixTestAlive::maskTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[06:09:11.950] <TB0> INFO: ----------------------------------------------------------------------
[06:09:14.631] <TB0> INFO: Test took 2679ms.
[06:09:14.633] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[06:09:14.633] <TB0> INFO: mask vs. old pixelAlive PixelAlive_C0_V0 .. PixelAlive_C15_V0
[06:09:14.857] <TB0> INFO: PixTestAlive::maskTest() done
[06:09:14.857] <TB0> INFO: number of mask-defect pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[06:09:14.858] <TB0> INFO: ----------------------------------------------------------------------
[06:09:14.858] <TB0> INFO: PixTestAlive::addressDecodingTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[06:09:14.858] <TB0> INFO: ----------------------------------------------------------------------
[06:09:18.440] <TB0> INFO: Test took 3580ms.
[06:09:18.471] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[06:09:18.712] <TB0> INFO: PixTestAlive::addressDecodingTest() done
[06:09:18.712] <TB0> INFO: number of address-decoding pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[06:09:18.712] <TB0> INFO: PixTestAlive::doTest() done, duration: 10 seconds
[06:09:18.769] <TB0> INFO: enter test to run
[06:09:18.769] <TB0> INFO: test: no parameter change
[06:09:18.922] <TB0> QUIET: Connection to board 147 closed.
[06:09:19.001] <TB0> INFO: pXar: this is the end, my friend
commander_RetrimHotPixels.log
[06:14:30.033] <TB0> INFO: *** Welcome to pxar ***
[06:14:30.033] <TB0> INFO: *** Today: 2015/08/24
[06:14:30.033] <TB0> INFO: readRocDacs: /usr/local/data/M2068_XrayQualification_2015-08-24_08h08m_1440396521//002_RetrimHotPixels_150//dacParameters35_C0.dat .. /usr/local/data/M2068_XrayQualification_2015-08-24_08h08m_1440396521//002_RetrimHotPixels_150//dacParameters35_C15.dat
[06:14:30.033] <TB0> INFO: readTbmDacs: /usr/local/data/M2068_XrayQualification_2015-08-24_08h08m_1440396521//002_RetrimHotPixels_150//tbmParameters_C0a.dat .. /usr/local/data/M2068_XrayQualification_2015-08-24_08h08m_1440396521//002_RetrimHotPixels_150//tbmParameters_C0b.dat
[06:14:30.033] <TB0> INFO: readMaskFile: /usr/local/data/M2068_XrayQualification_2015-08-24_08h08m_1440396521//002_RetrimHotPixels_150//defaultMaskFile.dat
[06:14:30.034] <TB0> INFO: readTrimFile: /usr/local/data/M2068_XrayQualification_2015-08-24_08h08m_1440396521//002_RetrimHotPixels_150//trimParameters35_C0.dat .. /usr/local/data/M2068_XrayQualification_2015-08-24_08h08m_1440396521//002_RetrimHotPixels_150//trimParameters35_C15.dat
[06:14:30.097] <TB0> INFO: clk: 4
[06:14:30.097] <TB0> INFO: ctr: 4
[06:14:30.097] <TB0> INFO: sda: 19
[06:14:30.097] <TB0> INFO: tin: 9
[06:14:30.097] <TB0> INFO: level: 15
[06:14:30.097] <TB0> INFO: triggerdelay: 0
[06:14:30.097] <TB0> QUIET: Instanciating API for pxar prod-01+80~g16e673b
[06:14:30.098] <TB0> INFO: Log level: INFO
[06:14:30.111] <TB0> INFO: Found DTB DTB_WWXTQT
[06:14:30.121] <TB0> QUIET: Connection to board DTB_WWXTQT opened.
[06:14:30.124] <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:
------------------------------------------------------
[06:14:30.127] <TB0> INFO: RPC call hashes of host and DTB match: 447413373
[06:14:31.642] <TB0> INFO: DUT info:
[06:14:31.642] <TB0> INFO: The DUT currently contains the following objects:
[06:14:31.642] <TB0> INFO: 2 TBM Cores tbm09c (2 ON)
[06:14:31.642] <TB0> INFO: TBM Core alpha (0): 7 registers set
[06:14:31.642] <TB0> INFO: TBM Core beta (1): 7 registers set
[06:14:31.642] <TB0> INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[06:14:31.642] <TB0> INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[06:14:31.642] <TB0> INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[06:14:31.643] <TB0> INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[06:14:31.643] <TB0> INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[06:14:31.643] <TB0> INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[06:14:31.643] <TB0> INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[06:14:31.643] <TB0> INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[06:14:31.643] <TB0> INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[06:14:31.643] <TB0> INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[06:14:31.643] <TB0> INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[06:14:31.643] <TB0> INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[06:14:31.643] <TB0> INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[06:14:31.643] <TB0> INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[06:14:31.643] <TB0> INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[06:14:31.643] <TB0> INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[06:14:31.643] <TB0> INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[06:14:32.044] <TB0> INFO: enter 'restricted' command line mode
[06:14:32.044] <TB0> INFO: enter test to run
[06:14:32.044] <TB0> INFO: test: delay setting parameters: ->5<-
[06:14:32.044] <TB0> INFO: delay test by 5 seconds...
[06:14:37.044] <TB0> INFO: enter test to run
[06:14:37.044] <TB0> INFO: test: highrate no parameter change
[06:14:37.044] <TB0> INFO: running: highrate
[06:14:37.044] <TB0> INFO: readGainPedestalParameters /usr/local/data/M2068_XrayQualification_2015-08-24_08h08m_1440396521//002_RetrimHotPixels_150//phCalibrationFitErr35_C0.dat .. /usr/local/data/M2068_XrayQualification_2015-08-24_08h08m_1440396521//002_RetrimHotPixels_150//phCalibrationFitErr35_C15.dat
[06:14:37.174] <TB0> INFO: ----------------------------------------------------------------------
[06:14:37.174] <TB0> INFO: PixTest::trimHotPixels() running for 1 seconds with 100 kHz trigger rate
[06:14:37.174] <TB0> INFO: ----------------------------------------------------------------------
[06:14:37.174] <TB0> INFO: THR = 150, corresponding to ~ 400 MHz/cm2
[06:14:37.174] <TB0> INFO: edge/corner pixel THR is adjusted
[06:14:37.174] <TB0> INFO: PixTestHighRate::trimHotPixels: step 0...
[06:14:38.136] <TB0> INFO: Collecting data for 1 seconds...
[06:14:39.137] <TB0> INFO: Done with hot pixel readout
[06:14:44.391] <TB0> INFO: PixTest:: pg_setup set to default.
[06:14:44.392] <TB0> INFO: 5 hot pixels found in step 0
[06:14:45.356] <TB0> INFO: Collecting data for 1 seconds...
[06:14:46.358] <TB0> INFO: Done with hot pixel readout
[06:14:52.674] <TB0> INFO: PixTest:: pg_setup set to default.
[06:14:52.675] <TB0> INFO: 5 hot pixels found in step 1
[06:14:53.639] <TB0> INFO: Collecting data for 1 seconds...
[06:14:54.643] <TB0> INFO: Done with hot pixel readout
[06:15:01.022] <TB0> INFO: PixTest:: pg_setup set to default.
[06:15:01.023] <TB0> INFO: 3 hot pixels found in step 2
[06:15:01.987] <TB0> INFO: Collecting data for 1 seconds...
[06:15:02.989] <TB0> INFO: Done with hot pixel readout
[06:15:09.322] <TB0> INFO: PixTest:: pg_setup set to default.
[06:15:09.323] <TB0> INFO: 0 hot pixels found in step 3
[06:15:09.331] <TB0> INFO: 0 hot pixels could not be trimmed and have been masked.
[06:15:09.365] <TB0> INFO: PixTest::trimHotPixels() done
[06:15:09.365] <TB0> INFO: write trim parameters into /usr/local/data/M2068_XrayQualification_2015-08-24_08h08m_1440396521//002_RetrimHotPixels_150//trimParameters35_C0.dat
[06:15:09.373] <TB0> INFO: write trim parameters into /usr/local/data/M2068_XrayQualification_2015-08-24_08h08m_1440396521//002_RetrimHotPixels_150//trimParameters35_C1.dat
[06:15:09.380] <TB0> INFO: write trim parameters into /usr/local/data/M2068_XrayQualification_2015-08-24_08h08m_1440396521//002_RetrimHotPixels_150//trimParameters35_C2.dat
[06:15:09.388] <TB0> INFO: write trim parameters into /usr/local/data/M2068_XrayQualification_2015-08-24_08h08m_1440396521//002_RetrimHotPixels_150//trimParameters35_C3.dat
[06:15:09.395] <TB0> INFO: write trim parameters into /usr/local/data/M2068_XrayQualification_2015-08-24_08h08m_1440396521//002_RetrimHotPixels_150//trimParameters35_C4.dat
[06:15:09.402] <TB0> INFO: write trim parameters into /usr/local/data/M2068_XrayQualification_2015-08-24_08h08m_1440396521//002_RetrimHotPixels_150//trimParameters35_C5.dat
[06:15:09.409] <TB0> INFO: write trim parameters into /usr/local/data/M2068_XrayQualification_2015-08-24_08h08m_1440396521//002_RetrimHotPixels_150//trimParameters35_C6.dat
[06:15:09.416] <TB0> INFO: write trim parameters into /usr/local/data/M2068_XrayQualification_2015-08-24_08h08m_1440396521//002_RetrimHotPixels_150//trimParameters35_C7.dat
[06:15:09.423] <TB0> INFO: write trim parameters into /usr/local/data/M2068_XrayQualification_2015-08-24_08h08m_1440396521//002_RetrimHotPixels_150//trimParameters35_C8.dat
[06:15:09.430] <TB0> INFO: write trim parameters into /usr/local/data/M2068_XrayQualification_2015-08-24_08h08m_1440396521//002_RetrimHotPixels_150//trimParameters35_C9.dat
[06:15:09.437] <TB0> INFO: write trim parameters into /usr/local/data/M2068_XrayQualification_2015-08-24_08h08m_1440396521//002_RetrimHotPixels_150//trimParameters35_C10.dat
[06:15:09.444] <TB0> INFO: write trim parameters into /usr/local/data/M2068_XrayQualification_2015-08-24_08h08m_1440396521//002_RetrimHotPixels_150//trimParameters35_C11.dat
[06:15:09.451] <TB0> INFO: write trim parameters into /usr/local/data/M2068_XrayQualification_2015-08-24_08h08m_1440396521//002_RetrimHotPixels_150//trimParameters35_C12.dat
[06:15:09.458] <TB0> INFO: write trim parameters into /usr/local/data/M2068_XrayQualification_2015-08-24_08h08m_1440396521//002_RetrimHotPixels_150//trimParameters35_C13.dat
[06:15:09.465] <TB0> INFO: write trim parameters into /usr/local/data/M2068_XrayQualification_2015-08-24_08h08m_1440396521//002_RetrimHotPixels_150//trimParameters35_C14.dat
[06:15:09.472] <TB0> INFO: write trim parameters into /usr/local/data/M2068_XrayQualification_2015-08-24_08h08m_1440396521//002_RetrimHotPixels_150//trimParameters35_C15.dat
[06:15:09.479] <TB0> INFO: write masked pixels into /usr/local/data/M2068_XrayQualification_2015-08-24_08h08m_1440396521//002_RetrimHotPixels_150//defaultMaskFile.dat
[06:15:09.515] <TB0> INFO: enter test to run
[06:15:09.515] <TB0> INFO: test: no parameter change
[06:15:09.701] <TB0> QUIET: Connection to board 147 closed.
[06:15:09.780] <TB0> INFO: pXar: this is the end, my friend
commander_HRData.log
[06:15:44.737] <TB0> INFO: *** Welcome to pxar ***
[06:15:44.737] <TB0> INFO: *** Today: 2015/08/24
[06:15:44.737] <TB0> INFO: readRocDacs: /usr/local/data/M2068_XrayQualification_2015-08-24_08h08m_1440396521//003_HRData_50//dacParameters35_C0.dat .. /usr/local/data/M2068_XrayQualification_2015-08-24_08h08m_1440396521//003_HRData_50//dacParameters35_C15.dat
[06:15:44.737] <TB0> INFO: readTbmDacs: /usr/local/data/M2068_XrayQualification_2015-08-24_08h08m_1440396521//003_HRData_50//tbmParameters_C0a.dat .. /usr/local/data/M2068_XrayQualification_2015-08-24_08h08m_1440396521//003_HRData_50//tbmParameters_C0b.dat
[06:15:44.737] <TB0> INFO: readMaskFile: /usr/local/data/M2068_XrayQualification_2015-08-24_08h08m_1440396521//003_HRData_50//defaultMaskFile.dat
[06:15:44.737] <TB0> INFO: readTrimFile: /usr/local/data/M2068_XrayQualification_2015-08-24_08h08m_1440396521//003_HRData_50//trimParameters35_C0.dat .. /usr/local/data/M2068_XrayQualification_2015-08-24_08h08m_1440396521//003_HRData_50//trimParameters35_C15.dat
[06:15:44.805] <TB0> INFO: clk: 4
[06:15:44.805] <TB0> INFO: ctr: 4
[06:15:44.805] <TB0> INFO: sda: 19
[06:15:44.805] <TB0> INFO: tin: 9
[06:15:44.805] <TB0> INFO: level: 15
[06:15:44.805] <TB0> INFO: triggerdelay: 0
[06:15:44.805] <TB0> QUIET: Instanciating API for pxar prod-01+80~g16e673b
[06:15:44.805] <TB0> INFO: Log level: INFO
[06:15:44.818] <TB0> INFO: Found DTB DTB_WWXTQT
[06:15:44.828] <TB0> QUIET: Connection to board DTB_WWXTQT opened.
[06:15:44.831] <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:
------------------------------------------------------
[06:15:44.833] <TB0> INFO: RPC call hashes of host and DTB match: 447413373
[06:15:46.344] <TB0> INFO: DUT info:
[06:15:46.344] <TB0> INFO: The DUT currently contains the following objects:
[06:15:46.344] <TB0> INFO: 2 TBM Cores tbm09c (2 ON)
[06:15:46.345] <TB0> INFO: TBM Core alpha (0): 7 registers set
[06:15:46.345] <TB0> INFO: TBM Core beta (1): 7 registers set
[06:15:46.345] <TB0> INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[06:15:46.345] <TB0> INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[06:15:46.345] <TB0> INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[06:15:46.345] <TB0> INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[06:15:46.345] <TB0> INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[06:15:46.345] <TB0> INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[06:15:46.345] <TB0> INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[06:15:46.345] <TB0> INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[06:15:46.345] <TB0> INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[06:15:46.345] <TB0> INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[06:15:46.345] <TB0> INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[06:15:46.345] <TB0> INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[06:15:46.345] <TB0> INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[06:15:46.345] <TB0> INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[06:15:46.345] <TB0> INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[06:15:46.345] <TB0> INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[06:15:46.345] <TB0> INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[06:15:46.746] <TB0> INFO: enter 'restricted' command line mode
[06:15:46.746] <TB0> INFO: enter test to run
[06:15:46.746] <TB0> INFO: test: delay setting parameters: ->5<-
[06:15:46.746] <TB0> INFO: delay test by 5 seconds...
[06:15:51.746] <TB0> INFO: enter test to run
[06:15:51.746] <TB0> INFO: test: Xray no parameter change
[06:15:51.746] <TB0> INFO: running: xray
[06:15:51.746] <TB0> INFO: readGainPedestalParameters /usr/local/data/M2068_XrayQualification_2015-08-24_08h08m_1440396521//003_HRData_50//phCalibrationFitErr35_C0.dat .. /usr/local/data/M2068_XrayQualification_2015-08-24_08h08m_1440396521//003_HRData_50//phCalibrationFitErr35_C15.dat
[06:15:51.872] <TB0> INFO: ######################################################################
[06:15:51.872] <TB0> INFO: PixTestXray::doTest()
[06:15:51.872] <TB0> INFO: ######################################################################
[06:15:51.872] <TB0> INFO: ----------------------------------------------------------------------
[06:15:51.872] <TB0> INFO: PixTestXray::doPhRun() fParRunSeconds = 100
[06:15:51.872] <TB0> INFO: ----------------------------------------------------------------------
[06:15:52.840] <TB0> INFO: PixTestXray::doPhRun start TriggerLoop with trigger frequency 100 kHz, period 405 and duration 100 seconds, fEventsMax = 10000000
[06:15:59.508] <TB0> INFO: run duration 6 seconds, buffer almost full (81%), pausing triggers.
[06:15:59.671] <TB0> ERROR: <datapipe.cc/CheckEventValidity:L470> Channel 0 Number of ROCs (2) != Token Chain Length (4)

[06:15:59.672] <TB0> ERROR: <datapipe.cc/CheckEventValidity:L470> Channel 2 Number of ROCs (2) != Token Chain Length (4)

[06:15:59.672] <TB0> WARNING: Channel 0 ROC 3: Readback start marker after 15 readouts!

[06:15:59.672] <TB0> WARNING: Channel 2 ROC 3: Readback start marker after 15 readouts!

[06:16:26.476] <TB0> INFO: Resuming triggers.
[06:16:33.151] <TB0> INFO: run duration 13 seconds, buffer almost full (81%), pausing triggers.
[06:16:36.898] <TB0> ERROR: <datapipe.cc/CheckEventValidity:L470> Channel 3 Number of ROCs (2) != Token Chain Length (4)

[06:16:36.898] <TB0> WARNING: Channel 3 ROC 3: Readback start marker after 15 readouts!

[06:16:54.193] <TB0> INFO: Resuming triggers.
[06:17:00.865] <TB0> INFO: run duration 19 seconds, buffer almost full (81%), pausing triggers.
[06:17:24.559] <TB0> INFO: Resuming triggers.
[06:17:31.234] <TB0> INFO: run duration 26 seconds, buffer almost full (81%), pausing triggers.
[06:17:55.530] <TB0> INFO: Resuming triggers.
[06:18:02.198] <TB0> INFO: run duration 33 seconds, buffer almost full (81%), pausing triggers.
[06:18:27.372] <TB0> INFO: Resuming triggers.
[06:18:34.043] <TB0> INFO: run duration 39 seconds, buffer almost full (81%), pausing triggers.
[06:18:58.159] <TB0> INFO: Resuming triggers.
[06:19:04.833] <TB0> INFO: run duration 46 seconds, buffer almost full (81%), pausing triggers.
[06:19:29.719] <TB0> INFO: Resuming triggers.
[06:19:36.390] <TB0> INFO: run duration 53 seconds, buffer almost full (81%), pausing triggers.
[06:19:57.582] <TB0> INFO: Resuming triggers.
[06:20:04.250] <TB0> INFO: run duration 59 seconds, buffer almost full (81%), pausing triggers.
[06:20:28.637] <TB0> INFO: Resuming triggers.
[06:20:35.310] <TB0> INFO: run duration 66 seconds, buffer almost full (81%), pausing triggers.
[06:20:59.777] <TB0> INFO: Resuming triggers.
[06:21:06.445] <TB0> INFO: run duration 73 seconds, buffer almost full (81%), pausing triggers.
[06:21:31.903] <TB0> INFO: Resuming triggers.
[06:21:38.577] <TB0> INFO: run duration 79 seconds, buffer almost full (81%), pausing triggers.
[06:22:06.795] <TB0> INFO: Resuming triggers.
[06:22:13.465] <TB0> INFO: run duration 86 seconds, buffer almost full (81%), pausing triggers.
[06:22:36.764] <TB0> INFO: Resuming triggers.
[06:22:43.432] <TB0> INFO: run duration 93 seconds, buffer almost full (81%), pausing triggers.
[06:23:03.742] <TB0> INFO: Resuming triggers.
[06:23:10.416] <TB0> INFO: run duration 99 seconds, buffer almost full (81%), pausing triggers.
[06:23:33.419] <TB0> INFO: Resuming triggers.
[06:23:33.463] <TB0> INFO: data taking finished, elapsed time: 100 seconds.
[06:23:33.804] <TB0> INFO: PixTest:: pg_setup set to default.
[06:23:33.806] <TB0> INFO: PixTestXray::doPhRun() done
[06:23:33.806] <TB0> INFO: PixTestXray::doTest() done
[06:23:34.018] <TB0> INFO: enter test to run
[06:23:34.018] <TB0> INFO: test: no parameter change
[06:23:34.229] <TB0> QUIET: Connection to board 147 closed.
[06:23:34.309] <TB0> INFO: pXar: this is the end, my friend
commander_HRData.log
[06:24:35.700] <TB0> INFO: *** Welcome to pxar ***
[06:24:35.700] <TB0> INFO: *** Today: 2015/08/24
[06:24:35.700] <TB0> INFO: readRocDacs: /usr/local/data/M2068_XrayQualification_2015-08-24_08h08m_1440396521//004_HRData_150//dacParameters35_C0.dat .. /usr/local/data/M2068_XrayQualification_2015-08-24_08h08m_1440396521//004_HRData_150//dacParameters35_C15.dat
[06:24:35.701] <TB0> INFO: readTbmDacs: /usr/local/data/M2068_XrayQualification_2015-08-24_08h08m_1440396521//004_HRData_150//tbmParameters_C0a.dat .. /usr/local/data/M2068_XrayQualification_2015-08-24_08h08m_1440396521//004_HRData_150//tbmParameters_C0b.dat
[06:24:35.701] <TB0> INFO: readMaskFile: /usr/local/data/M2068_XrayQualification_2015-08-24_08h08m_1440396521//004_HRData_150//defaultMaskFile.dat
[06:24:35.701] <TB0> INFO: readTrimFile: /usr/local/data/M2068_XrayQualification_2015-08-24_08h08m_1440396521//004_HRData_150//trimParameters35_C0.dat .. /usr/local/data/M2068_XrayQualification_2015-08-24_08h08m_1440396521//004_HRData_150//trimParameters35_C15.dat
[06:24:35.766] <TB0> INFO: clk: 4
[06:24:35.766] <TB0> INFO: ctr: 4
[06:24:35.766] <TB0> INFO: sda: 19
[06:24:35.766] <TB0> INFO: tin: 9
[06:24:35.766] <TB0> INFO: level: 15
[06:24:35.766] <TB0> INFO: triggerdelay: 0
[06:24:35.766] <TB0> QUIET: Instanciating API for pxar prod-01+80~g16e673b
[06:24:35.766] <TB0> INFO: Log level: INFO
[06:24:35.779] <TB0> INFO: Found DTB DTB_WWXTQT
[06:24:35.789] <TB0> QUIET: Connection to board DTB_WWXTQT opened.
[06:24:35.792] <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:
------------------------------------------------------
[06:24:35.795] <TB0> INFO: RPC call hashes of host and DTB match: 447413373
[06:24:37.309] <TB0> INFO: DUT info:
[06:24:37.309] <TB0> INFO: The DUT currently contains the following objects:
[06:24:37.309] <TB0> INFO: 2 TBM Cores tbm09c (2 ON)
[06:24:37.309] <TB0> INFO: TBM Core alpha (0): 7 registers set
[06:24:37.309] <TB0> INFO: TBM Core beta (1): 7 registers set
[06:24:37.309] <TB0> INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[06:24:37.309] <TB0> INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[06:24:37.309] <TB0> INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[06:24:37.309] <TB0> INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[06:24:37.309] <TB0> INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[06:24:37.309] <TB0> INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[06:24:37.309] <TB0> INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[06:24:37.309] <TB0> INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[06:24:37.309] <TB0> INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[06:24:37.309] <TB0> INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[06:24:37.309] <TB0> INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[06:24:37.309] <TB0> INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[06:24:37.309] <TB0> INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[06:24:37.309] <TB0> INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[06:24:37.309] <TB0> INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[06:24:37.309] <TB0> INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[06:24:37.309] <TB0> INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[06:24:37.710] <TB0> INFO: enter 'restricted' command line mode
[06:24:37.710] <TB0> INFO: enter test to run
[06:24:37.710] <TB0> INFO: test: delay setting parameters: ->5<-
[06:24:37.710] <TB0> INFO: delay test by 5 seconds...
[06:24:42.710] <TB0> INFO: enter test to run
[06:24:42.710] <TB0> INFO: test: Xray no parameter change
[06:24:42.710] <TB0> INFO: running: xray
[06:24:42.710] <TB0> INFO: readGainPedestalParameters /usr/local/data/M2068_XrayQualification_2015-08-24_08h08m_1440396521//004_HRData_150//phCalibrationFitErr35_C0.dat .. /usr/local/data/M2068_XrayQualification_2015-08-24_08h08m_1440396521//004_HRData_150//phCalibrationFitErr35_C15.dat
[06:24:42.838] <TB0> INFO: ######################################################################
[06:24:42.838] <TB0> INFO: PixTestXray::doTest()
[06:24:42.838] <TB0> INFO: ######################################################################
[06:24:42.838] <TB0> INFO: ----------------------------------------------------------------------
[06:24:42.838] <TB0> INFO: PixTestXray::doPhRun() fParRunSeconds = 100
[06:24:42.838] <TB0> INFO: ----------------------------------------------------------------------
[06:24:43.807] <TB0> INFO: PixTestXray::doPhRun start TriggerLoop with trigger frequency 100 kHz, period 405 and duration 100 seconds, fEventsMax = 10000000
[06:24:47.470] <TB0> INFO: run duration 3 seconds, buffer almost full (81%), pausing triggers.
[06:25:14.932] <TB0> INFO: Resuming triggers.
[06:25:18.604] <TB0> INFO: run duration 7 seconds, buffer almost full (81%), pausing triggers.
[06:25:22.576] <TB0> ERROR: <datapipe.cc/CheckEventValidity:L470> Channel 3 Number of ROCs (6) != Token Chain Length (4)

[06:25:43.635] <TB0> INFO: Resuming triggers.
[06:25:47.307] <TB0> INFO: run duration 10 seconds, buffer almost full (81%), pausing triggers.
[06:26:14.075] <TB0> INFO: Resuming triggers.
[06:26:17.748] <TB0> INFO: run duration 14 seconds, buffer almost full (81%), pausing triggers.
[06:26:41.052] <TB0> INFO: Resuming triggers.
[06:26:44.723] <TB0> INFO: run duration 18 seconds, buffer almost full (81%), pausing triggers.
[06:27:07.783] <TB0> INFO: Resuming triggers.
[06:27:11.456] <TB0> INFO: run duration 21 seconds, buffer almost full (81%), pausing triggers.
[06:27:35.996] <TB0> INFO: Resuming triggers.
[06:27:39.667] <TB0> INFO: run duration 25 seconds, buffer almost full (81%), pausing triggers.
[06:28:02.051] <TB0> INFO: Resuming triggers.
[06:28:05.722] <TB0> INFO: run duration 29 seconds, buffer almost full (81%), pausing triggers.
[06:28:30.186] <TB0> INFO: Resuming triggers.
[06:28:33.858] <TB0> INFO: run duration 32 seconds, buffer almost full (81%), pausing triggers.
[06:28:59.943] <TB0> INFO: Resuming triggers.
[06:29:03.619] <TB0> INFO: run duration 36 seconds, buffer almost full (81%), pausing triggers.
[06:29:25.435] <TB0> INFO: Resuming triggers.
[06:29:29.105] <TB0> INFO: run duration 40 seconds, buffer almost full (81%), pausing triggers.
[06:29:56.464] <TB0> INFO: Resuming triggers.
[06:30:00.134] <TB0> INFO: run duration 44 seconds, buffer almost full (81%), pausing triggers.
[06:30:22.400] <TB0> INFO: Resuming triggers.
[06:30:26.068] <TB0> INFO: run duration 47 seconds, buffer almost full (81%), pausing triggers.
[06:30:51.376] <TB0> INFO: Resuming triggers.
[06:30:55.046] <TB0> INFO: run duration 51 seconds, buffer almost full (81%), pausing triggers.
[06:31:20.965] <TB0> INFO: Resuming triggers.
[06:31:24.633] <TB0> INFO: run duration 54 seconds, buffer almost full (81%), pausing triggers.
[06:31:54.523] <TB0> INFO: Resuming triggers.
[06:31:58.193] <TB0> INFO: run duration 58 seconds, buffer almost full (81%), pausing triggers.
[06:32:26.889] <TB0> INFO: Resuming triggers.
[06:32:30.556] <TB0> INFO: run duration 62 seconds, buffer almost full (81%), pausing triggers.
[06:32:55.671] <TB0> INFO: Resuming triggers.
[06:32:59.341] <TB0> INFO: run duration 65 seconds, buffer almost full (81%), pausing triggers.
[06:33:23.896] <TB0> INFO: Resuming triggers.
[06:33:27.565] <TB0> INFO: run duration 69 seconds, buffer almost full (81%), pausing triggers.
[06:33:52.050] <TB0> INFO: Resuming triggers.
[06:33:55.717] <TB0> INFO: run duration 73 seconds, buffer almost full (81%), pausing triggers.
[06:34:20.300] <TB0> INFO: Resuming triggers.
[06:34:23.966] <TB0> INFO: run duration 76 seconds, buffer almost full (81%), pausing triggers.
[06:34:47.954] <TB0> INFO: Resuming triggers.
[06:34:51.622] <TB0> INFO: run duration 80 seconds, buffer almost full (81%), pausing triggers.
[06:35:15.714] <TB0> INFO: Resuming triggers.
[06:35:19.385] <TB0> INFO: run duration 84 seconds, buffer almost full (81%), pausing triggers.
[06:35:41.053] <TB0> INFO: Resuming triggers.
[06:35:44.720] <TB0> INFO: run duration 87 seconds, buffer almost full (81%), pausing triggers.
[06:36:10.717] <TB0> INFO: Resuming triggers.
[06:36:14.386] <TB0> INFO: run duration 91 seconds, buffer almost full (81%), pausing triggers.
[06:36:36.980] <TB0> INFO: Resuming triggers.
[06:36:40.647] <TB0> INFO: run duration 95 seconds, buffer almost full (81%), pausing triggers.
[06:37:06.809] <TB0> INFO: Resuming triggers.
[06:37:10.476] <TB0> INFO: run duration 98 seconds, buffer almost full (81%), pausing triggers.
[06:37:33.553] <TB0> INFO: Resuming triggers.
[06:37:34.603] <TB0> INFO: data taking finished, elapsed time: 100 seconds.
[06:37:41.849] <TB0> INFO: PixTest:: pg_setup set to default.
[06:37:41.850] <TB0> INFO: PixTestXray::doPhRun() done
[06:37:41.850] <TB0> INFO: PixTestXray::doTest() done
[06:37:42.086] <TB0> INFO: enter test to run
[06:37:42.087] <TB0> INFO: test: no parameter change
[06:37:42.290] <TB0> QUIET: Connection to board 147 closed.
[06:37:42.370] <TB0> INFO: pXar: this is the end, my friend
commander_HRSCurves.log
[06:40:12.394] <TB0> INFO: *** Welcome to pxar ***
[06:40:12.394] <TB0> INFO: *** Today: 2015/08/24
[06:40:12.394] <TB0> INFO: readRocDacs: /usr/local/data/M2068_XrayQualification_2015-08-24_08h08m_1440396521//005_HRSCurves_100//dacParameters35_C0.dat .. /usr/local/data/M2068_XrayQualification_2015-08-24_08h08m_1440396521//005_HRSCurves_100//dacParameters35_C15.dat
[06:40:12.395] <TB0> INFO: readTbmDacs: /usr/local/data/M2068_XrayQualification_2015-08-24_08h08m_1440396521//005_HRSCurves_100//tbmParameters_C0a.dat .. /usr/local/data/M2068_XrayQualification_2015-08-24_08h08m_1440396521//005_HRSCurves_100//tbmParameters_C0b.dat
[06:40:12.395] <TB0> INFO: readMaskFile: /usr/local/data/M2068_XrayQualification_2015-08-24_08h08m_1440396521//005_HRSCurves_100//defaultMaskFile.dat
[06:40:12.395] <TB0> INFO: readTrimFile: /usr/local/data/M2068_XrayQualification_2015-08-24_08h08m_1440396521//005_HRSCurves_100//trimParameters35_C0.dat .. /usr/local/data/M2068_XrayQualification_2015-08-24_08h08m_1440396521//005_HRSCurves_100//trimParameters35_C15.dat
[06:40:12.462] <TB0> INFO: clk: 4
[06:40:12.462] <TB0> INFO: ctr: 4
[06:40:12.462] <TB0> INFO: sda: 19
[06:40:12.462] <TB0> INFO: tin: 9
[06:40:12.462] <TB0> INFO: level: 15
[06:40:12.462] <TB0> INFO: triggerdelay: 0
[06:40:12.462] <TB0> QUIET: Instanciating API for pxar prod-01+80~g16e673b
[06:40:12.462] <TB0> INFO: Log level: INFO
[06:40:12.475] <TB0> INFO: Found DTB DTB_WWXTQT
[06:40:12.485] <TB0> QUIET: Connection to board DTB_WWXTQT opened.
[06:40:12.488] <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:
------------------------------------------------------
[06:40:12.491] <TB0> INFO: RPC call hashes of host and DTB match: 447413373
[06:40:14.006] <TB0> INFO: DUT info:
[06:40:14.006] <TB0> INFO: The DUT currently contains the following objects:
[06:40:14.006] <TB0> INFO: 2 TBM Cores tbm09c (2 ON)
[06:40:14.006] <TB0> INFO: TBM Core alpha (0): 7 registers set
[06:40:14.006] <TB0> INFO: TBM Core beta (1): 7 registers set
[06:40:14.006] <TB0> INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[06:40:14.006] <TB0> INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[06:40:14.006] <TB0> INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[06:40:14.006] <TB0> INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[06:40:14.006] <TB0> INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[06:40:14.006] <TB0> INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[06:40:14.006] <TB0> INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[06:40:14.006] <TB0> INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[06:40:14.006] <TB0> INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[06:40:14.006] <TB0> INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[06:40:14.006] <TB0> INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[06:40:14.006] <TB0> INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[06:40:14.006] <TB0> INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[06:40:14.006] <TB0> INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[06:40:14.006] <TB0> INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[06:40:14.006] <TB0> INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[06:40:14.006] <TB0> INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[06:40:14.407] <TB0> INFO: enter 'restricted' command line mode
[06:40:14.407] <TB0> INFO: enter test to run
[06:40:14.407] <TB0> INFO: test: Highrate no parameter change
[06:40:14.407] <TB0> INFO: running: highrate
[06:40:14.407] <TB0> INFO: readGainPedestalParameters /usr/local/data/M2068_XrayQualification_2015-08-24_08h08m_1440396521//005_HRSCurves_100//phCalibrationFitErr35_C0.dat .. /usr/local/data/M2068_XrayQualification_2015-08-24_08h08m_1440396521//005_HRSCurves_100//phCalibrationFitErr35_C15.dat
[06:40:14.533] <TB0> INFO: ----------------------------------------------------------------------
[06:40:14.533] <TB0> INFO: PixTestHighRate::xNoiseMaps() ntrig = 50, vcal = 200
[06:40:14.533] <TB0> INFO: ----------------------------------------------------------------------
[06:40:14.539] <TB0> INFO: ---> dac: vcal name: xNoiseMap ntrig: 50 dacrange: 0 .. 100 (1/-1) hits flags = 384 (plus default)
[06:40:14.549] <TB0> INFO: dacScan step from 0 .. 0
[06:40:14.549] <TB0> INFO: dacScan split into 1 runs with ntrig = 50
[06:40:14.549] <TB0> INFO: run 1 of 1
[06:40:19.088] <TB0> ERROR: <datapipe.cc/CheckEventValidity:L470> Channel 1 Number of ROCs (2) != Token Chain Length (4)

[06:40:19.088] <TB0> WARNING: Channel 1 ROC 3: Readback start marker after 15 readouts!

[06:40:26.487] <TB0> INFO: Test took 11938ms.
[06:40:27.937] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[06:40:28.241] <TB0> INFO: dacScan step from 1 .. 1
[06:40:28.241] <TB0> INFO: dacScan split into 1 runs with ntrig = 50
[06:40:28.241] <TB0> INFO: run 1 of 1
[06:40:42.613] <TB0> INFO: Test took 14371ms.
[06:40:44.175] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[06:40:44.435] <TB0> INFO: dacScan step from 2 .. 2
[06:40:44.435] <TB0> INFO: dacScan split into 1 runs with ntrig = 50
[06:40:44.435] <TB0> INFO: run 1 of 1
[06:40:59.565] <TB0> INFO: Test took 15130ms.
[06:41:01.001] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[06:41:01.235] <TB0> INFO: dacScan step from 3 .. 3
[06:41:01.235] <TB0> INFO: dacScan split into 1 runs with ntrig = 50
[06:41:01.235] <TB0> INFO: run 1 of 1
[06:41:15.173] <TB0> INFO: Test took 13937ms.
[06:41:16.541] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[06:41:16.770] <TB0> INFO: dacScan step from 4 .. 4
[06:41:16.770] <TB0> INFO: dacScan split into 1 runs with ntrig = 50
[06:41:16.770] <TB0> INFO: run 1 of 1
[06:41:30.240] <TB0> INFO: Test took 13470ms.
[06:41:31.706] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[06:41:31.965] <TB0> INFO: dacScan step from 5 .. 5
[06:41:31.965] <TB0> INFO: dacScan split into 1 runs with ntrig = 50
[06:41:31.965] <TB0> INFO: run 1 of 1
[06:41:44.303] <TB0> INFO: Test took 12338ms.
[06:41:45.739] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[06:41:45.982] <TB0> INFO: dacScan step from 6 .. 6
[06:41:45.982] <TB0> INFO: dacScan split into 1 runs with ntrig = 50
[06:41:45.982] <TB0> INFO: run 1 of 1
[06:41:59.546] <TB0> INFO: Test took 13564ms.
[06:42:00.981] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[06:42:01.207] <TB0> INFO: dacScan step from 7 .. 7
[06:42:01.208] <TB0> INFO: dacScan split into 1 runs with ntrig = 50
[06:42:01.208] <TB0> INFO: run 1 of 1
[06:42:15.570] <TB0> INFO: Test took 14362ms.
[06:42:17.201] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[06:42:17.432] <TB0> INFO: dacScan step from 8 .. 8
[06:42:17.432] <TB0> INFO: dacScan split into 1 runs with ntrig = 50
[06:42:17.432] <TB0> INFO: run 1 of 1
[06:42:32.606] <TB0> INFO: Test took 15174ms.
[06:42:34.049] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[06:42:34.284] <TB0> INFO: dacScan step from 9 .. 9
[06:42:34.284] <TB0> INFO: dacScan split into 1 runs with ntrig = 50
[06:42:34.284] <TB0> INFO: run 1 of 1
[06:42:48.153] <TB0> INFO: Test took 13868ms.
[06:42:49.534] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[06:42:49.770] <TB0> INFO: dacScan step from 10 .. 10
[06:42:49.770] <TB0> INFO: dacScan split into 1 runs with ntrig = 50
[06:42:49.770] <TB0> INFO: run 1 of 1
[06:43:01.860] <TB0> INFO: Test took 12090ms.
[06:43:03.213] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[06:43:03.442] <TB0> INFO: dacScan step from 11 .. 11
[06:43:03.442] <TB0> INFO: dacScan split into 1 runs with ntrig = 50
[06:43:03.442] <TB0> INFO: run 1 of 1
[06:43:15.756] <TB0> INFO: Test took 12313ms.
[06:43:17.169] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[06:43:17.413] <TB0> INFO: dacScan step from 12 .. 12
[06:43:17.413] <TB0> INFO: dacScan split into 1 runs with ntrig = 50
[06:43:17.413] <TB0> INFO: run 1 of 1
[06:43:30.854] <TB0> INFO: Test took 13440ms.
[06:43:32.230] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[06:43:32.453] <TB0> INFO: dacScan step from 13 .. 13
[06:43:32.453] <TB0> INFO: dacScan split into 1 runs with ntrig = 50
[06:43:32.453] <TB0> INFO: run 1 of 1
[06:43:47.067] <TB0> INFO: Test took 14614ms.
[06:43:48.460] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[06:43:48.696] <TB0> INFO: dacScan step from 14 .. 14
[06:43:48.696] <TB0> INFO: dacScan split into 1 runs with ntrig = 50
[06:43:48.696] <TB0> INFO: run 1 of 1
[06:44:02.617] <TB0> INFO: Test took 13921ms.
[06:44:04.056] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[06:44:04.303] <TB0> INFO: dacScan step from 15 .. 15
[06:44:04.304] <TB0> INFO: dacScan split into 1 runs with ntrig = 50
[06:44:04.304] <TB0> INFO: run 1 of 1
[06:44:16.974] <TB0> INFO: Test took 12670ms.
[06:44:18.441] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[06:44:18.678] <TB0> INFO: dacScan step from 16 .. 16
[06:44:18.678] <TB0> INFO: dacScan split into 1 runs with ntrig = 50
[06:44:18.678] <TB0> INFO: run 1 of 1
[06:44:31.244] <TB0> INFO: Test took 12566ms.
[06:44:32.724] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[06:44:32.971] <TB0> INFO: dacScan step from 17 .. 17
[06:44:32.972] <TB0> INFO: dacScan split into 1 runs with ntrig = 50
[06:44:32.972] <TB0> INFO: run 1 of 1
[06:44:46.078] <TB0> INFO: Test took 13106ms.
[06:44:47.650] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[06:44:47.911] <TB0> INFO: dacScan step from 18 .. 18
[06:44:47.911] <TB0> INFO: dacScan split into 1 runs with ntrig = 50
[06:44:47.911] <TB0> INFO: run 1 of 1
[06:45:02.167] <TB0> INFO: Test took 14255ms.
[06:45:03.748] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[06:45:03.976] <TB0> INFO: dacScan step from 19 .. 19
[06:45:03.976] <TB0> INFO: dacScan split into 1 runs with ntrig = 50
[06:45:03.976] <TB0> INFO: run 1 of 1
[06:45:17.991] <TB0> INFO: Test took 14014ms.
[06:45:19.390] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[06:45:19.626] <TB0> INFO: dacScan step from 20 .. 20
[06:45:19.626] <TB0> INFO: dacScan split into 1 runs with ntrig = 50
[06:45:19.626] <TB0> INFO: run 1 of 1
[06:45:32.652] <TB0> INFO: Test took 13026ms.
[06:45:34.254] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[06:45:34.608] <TB0> INFO: dacScan step from 21 .. 21
[06:45:34.608] <TB0> INFO: dacScan split into 1 runs with ntrig = 50
[06:45:34.608] <TB0> INFO: run 1 of 1
[06:45:46.673] <TB0> INFO: Test took 12064ms.
[06:45:48.177] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[06:45:48.418] <TB0> INFO: dacScan step from 22 .. 22
[06:45:48.418] <TB0> INFO: dacScan split into 1 runs with ntrig = 50
[06:45:48.418] <TB0> INFO: run 1 of 1
[06:46:01.794] <TB0> INFO: Test took 13375ms.
[06:46:03.177] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[06:46:03.401] <TB0> INFO: dacScan step from 23 .. 23
[06:46:03.401] <TB0> INFO: dacScan split into 1 runs with ntrig = 50
[06:46:03.401] <TB0> INFO: run 1 of 1
[06:46:18.253] <TB0> INFO: Test took 14852ms.
[06:46:19.659] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[06:46:19.891] <TB0> INFO: dacScan step from 24 .. 24
[06:46:19.891] <TB0> INFO: dacScan split into 1 runs with ntrig = 50
[06:46:19.891] <TB0> INFO: run 1 of 1
[06:46:35.261] <TB0> INFO: Test took 15369ms.
[06:46:36.812] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[06:46:37.043] <TB0> INFO: dacScan step from 25 .. 25
[06:46:37.043] <TB0> INFO: dacScan split into 1 runs with ntrig = 50
[06:46:37.043] <TB0> INFO: run 1 of 1
[06:46:51.201] <TB0> INFO: Test took 14158ms.
[06:46:52.695] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[06:46:52.942] <TB0> INFO: dacScan step from 26 .. 26
[06:46:52.942] <TB0> INFO: dacScan split into 1 runs with ntrig = 50
[06:46:52.942] <TB0> INFO: run 1 of 1
[06:47:05.476] <TB0> INFO: Test took 12534ms.
[06:47:06.976] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[06:47:07.244] <TB0> INFO: dacScan step from 27 .. 27
[06:47:07.244] <TB0> INFO: dacScan split into 1 runs with ntrig = 50
[06:47:07.244] <TB0> INFO: run 1 of 1
[06:47:19.734] <TB0> INFO: Test took 12489ms.
[06:47:21.183] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[06:47:21.410] <TB0> INFO: dacScan step from 28 .. 28
[06:47:21.410] <TB0> INFO: dacScan split into 1 runs with ntrig = 50
[06:47:21.410] <TB0> INFO: run 1 of 1
[06:47:35.564] <TB0> INFO: Test took 14153ms.
[06:47:37.202] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[06:47:37.457] <TB0> INFO: dacScan step from 29 .. 29
[06:47:37.457] <TB0> INFO: dacScan split into 1 runs with ntrig = 50
[06:47:37.458] <TB0> INFO: run 1 of 1
[06:47:51.989] <TB0> INFO: Test took 14531ms.
[06:47:53.404] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[06:47:53.627] <TB0> INFO: dacScan step from 30 .. 30
[06:47:53.627] <TB0> INFO: dacScan split into 1 runs with ntrig = 50
[06:47:53.627] <TB0> INFO: run 1 of 1
[06:48:08.042] <TB0> INFO: Test took 14415ms.
[06:48:09.445] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[06:48:09.695] <TB0> INFO: dacScan step from 31 .. 31
[06:48:09.695] <TB0> INFO: dacScan split into 1 runs with ntrig = 50
[06:48:09.695] <TB0> INFO: run 1 of 1
[06:48:22.740] <TB0> INFO: Test took 13044ms.
[06:48:24.182] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[06:48:24.423] <TB0> INFO: dacScan step from 32 .. 32
[06:48:24.424] <TB0> INFO: dacScan split into 1 runs with ntrig = 50
[06:48:24.424] <TB0> INFO: run 1 of 1
[06:48:36.099] <TB0> INFO: Test took 11675ms.
[06:48:37.477] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[06:48:37.702] <TB0> INFO: dacScan step from 33 .. 33
[06:48:37.702] <TB0> INFO: dacScan split into 1 runs with ntrig = 50
[06:48:37.703] <TB0> INFO: run 1 of 1
[06:48:51.056] <TB0> INFO: Test took 13353ms.
[06:48:52.675] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[06:48:52.954] <TB0> INFO: dacScan step from 34 .. 34
[06:48:52.954] <TB0> INFO: dacScan split into 1 runs with ntrig = 50
[06:48:52.954] <TB0> INFO: run 1 of 1
[06:49:08.239] <TB0> INFO: Test took 15284ms.
[06:49:09.657] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[06:49:09.885] <TB0> INFO: dacScan step from 35 .. 35
[06:49:09.885] <TB0> INFO: dacScan split into 1 runs with ntrig = 50
[06:49:09.885] <TB0> INFO: run 1 of 1
[06:49:25.116] <TB0> INFO: Test took 15231ms.
[06:49:26.640] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[06:49:26.881] <TB0> INFO: dacScan step from 36 .. 36
[06:49:26.881] <TB0> INFO: dacScan split into 1 runs with ntrig = 50
[06:49:26.881] <TB0> INFO: run 1 of 1
[06:49:40.862] <TB0> INFO: Test took 13981ms.
[06:49:42.306] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[06:49:42.557] <TB0> INFO: dacScan step from 37 .. 37
[06:49:42.557] <TB0> INFO: dacScan split into 1 runs with ntrig = 50
[06:49:42.557] <TB0> INFO: run 1 of 1
[06:49:55.545] <TB0> INFO: Test took 12988ms.
[06:49:57.089] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[06:49:57.333] <TB0> INFO: dacScan step from 38 .. 38
[06:49:57.333] <TB0> INFO: dacScan split into 1 runs with ntrig = 50
[06:49:57.333] <TB0> INFO: run 1 of 1
[06:50:10.572] <TB0> INFO: Test took 13238ms.
[06:50:12.148] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[06:50:12.397] <TB0> INFO: dacScan step from 39 .. 39
[06:50:12.397] <TB0> INFO: dacScan split into 1 runs with ntrig = 50
[06:50:12.397] <TB0> INFO: run 1 of 1
[06:50:26.683] <TB0> INFO: Test took 14285ms.
[06:50:28.155] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[06:50:28.407] <TB0> INFO: dacScan step from 40 .. 40
[06:50:28.407] <TB0> INFO: dacScan split into 1 runs with ntrig = 50
[06:50:28.407] <TB0> INFO: run 1 of 1
[06:50:44.016] <TB0> INFO: Test took 15608ms.
[06:50:46.029] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[06:50:46.311] <TB0> INFO: dacScan step from 41 .. 41
[06:50:46.311] <TB0> INFO: dacScan split into 1 runs with ntrig = 50
[06:50:46.311] <TB0> INFO: run 1 of 1
[06:51:01.596] <TB0> INFO: Test took 15284ms.
[06:51:03.373] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[06:51:03.613] <TB0> INFO: dacScan step from 42 .. 42
[06:51:03.613] <TB0> INFO: dacScan split into 1 runs with ntrig = 50
[06:51:03.613] <TB0> INFO: run 1 of 1
[06:51:20.412] <TB0> INFO: Test took 16798ms.
[06:51:22.018] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[06:51:22.250] <TB0> INFO: dacScan step from 43 .. 43
[06:51:22.250] <TB0> INFO: dacScan split into 1 runs with ntrig = 50
[06:51:22.250] <TB0> INFO: run 1 of 1
[06:51:38.872] <TB0> INFO: Test took 16622ms.
[06:51:40.521] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[06:51:40.794] <TB0> INFO: dacScan step from 44 .. 44
[06:51:40.794] <TB0> INFO: dacScan split into 1 runs with ntrig = 50
[06:51:40.794] <TB0> INFO: run 1 of 1
[06:51:57.422] <TB0> INFO: Test took 16628ms.
[06:51:59.170] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[06:51:59.416] <TB0> INFO: dacScan step from 45 .. 45
[06:51:59.417] <TB0> INFO: dacScan split into 1 runs with ntrig = 50
[06:51:59.417] <TB0> INFO: run 1 of 1
[06:52:16.400] <TB0> INFO: Test took 16983ms.
[06:52:18.127] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[06:52:18.394] <TB0> INFO: dacScan step from 46 .. 46
[06:52:18.394] <TB0> INFO: dacScan split into 1 runs with ntrig = 50
[06:52:18.394] <TB0> INFO: run 1 of 1
[06:52:35.309] <TB0> INFO: Test took 16914ms.
[06:52:37.045] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[06:52:37.302] <TB0> INFO: dacScan step from 47 .. 47
[06:52:37.303] <TB0> INFO: dacScan split into 1 runs with ntrig = 50
[06:52:37.303] <TB0> INFO: run 1 of 1
[06:52:54.502] <TB0> INFO: Test took 17199ms.
[06:52:56.207] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[06:52:56.449] <TB0> INFO: dacScan step from 48 .. 48
[06:52:56.449] <TB0> INFO: dacScan split into 1 runs with ntrig = 50
[06:52:56.449] <TB0> INFO: run 1 of 1
[06:53:13.636] <TB0> INFO: Test took 17186ms.
[06:53:15.332] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[06:53:15.589] <TB0> INFO: dacScan step from 49 .. 49
[06:53:15.589] <TB0> INFO: dacScan split into 1 runs with ntrig = 50
[06:53:15.589] <TB0> INFO: run 1 of 1
[06:53:32.272] <TB0> INFO: Test took 16682ms.
[06:53:33.979] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[06:53:34.256] <TB0> INFO: dacScan step from 50 .. 50
[06:53:34.256] <TB0> INFO: dacScan split into 1 runs with ntrig = 50
[06:53:34.256] <TB0> INFO: run 1 of 1
[06:53:51.400] <TB0> INFO: Test took 17144ms.
[06:53:53.177] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[06:53:53.436] <TB0> INFO: dacScan step from 51 .. 51
[06:53:53.436] <TB0> INFO: dacScan split into 1 runs with ntrig = 50
[06:53:53.436] <TB0> INFO: run 1 of 1
[06:54:09.797] <TB0> INFO: Test took 16360ms.
[06:54:11.721] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[06:54:11.990] <TB0> INFO: dacScan step from 52 .. 52
[06:54:11.990] <TB0> INFO: dacScan split into 1 runs with ntrig = 50
[06:54:11.990] <TB0> INFO: run 1 of 1
[06:54:28.079] <TB0> INFO: Test took 16089ms.
[06:54:29.943] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[06:54:30.211] <TB0> INFO: dacScan step from 53 .. 53
[06:54:30.211] <TB0> INFO: dacScan split into 1 runs with ntrig = 50
[06:54:30.211] <TB0> INFO: run 1 of 1
[06:54:45.700] <TB0> INFO: Test took 15488ms.
[06:54:47.504] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[06:54:47.759] <TB0> INFO: dacScan step from 54 .. 54
[06:54:47.759] <TB0> INFO: dacScan split into 1 runs with ntrig = 50
[06:54:47.759] <TB0> INFO: run 1 of 1
[06:55:03.604] <TB0> INFO: Test took 15845ms.
[06:55:05.392] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[06:55:05.641] <TB0> INFO: dacScan step from 55 .. 55
[06:55:05.641] <TB0> INFO: dacScan split into 1 runs with ntrig = 50
[06:55:05.641] <TB0> INFO: run 1 of 1
[06:55:21.607] <TB0> INFO: Test took 15965ms.
[06:55:23.517] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[06:55:23.789] <TB0> INFO: dacScan step from 56 .. 56
[06:55:23.789] <TB0> INFO: dacScan split into 1 runs with ntrig = 50
[06:55:23.789] <TB0> INFO: run 1 of 1
[06:55:40.293] <TB0> INFO: Test took 16503ms.
[06:55:42.340] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[06:55:42.634] <TB0> INFO: dacScan step from 57 .. 57
[06:55:42.634] <TB0> INFO: dacScan split into 1 runs with ntrig = 50
[06:55:42.634] <TB0> INFO: run 1 of 1
[06:56:01.427] <TB0> INFO: Test took 18792ms.
[06:56:03.169] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[06:56:03.423] <TB0> INFO: dacScan step from 58 .. 58
[06:56:03.423] <TB0> INFO: dacScan split into 1 runs with ntrig = 50
[06:56:03.423] <TB0> INFO: run 1 of 1
[06:56:22.509] <TB0> INFO: Test took 19085ms.
[06:56:24.283] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[06:56:24.519] <TB0> INFO: dacScan step from 59 .. 59
[06:56:24.519] <TB0> INFO: dacScan split into 1 runs with ntrig = 50
[06:56:24.519] <TB0> INFO: run 1 of 1
[06:56:42.188] <TB0> INFO: Test took 17668ms.
[06:56:44.002] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[06:56:44.269] <TB0> INFO: dacScan step from 60 .. 60
[06:56:44.269] <TB0> INFO: dacScan split into 1 runs with ntrig = 50
[06:56:44.269] <TB0> INFO: run 1 of 1
[06:57:00.767] <TB0> INFO: Test took 16497ms.
[06:57:02.721] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[06:57:03.026] <TB0> INFO: dacScan step from 61 .. 61
[06:57:03.026] <TB0> INFO: dacScan split into 1 runs with ntrig = 50
[06:57:03.026] <TB0> INFO: run 1 of 1
[06:57:18.483] <TB0> INFO: Test took 15456ms.
[06:57:20.258] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[06:57:20.526] <TB0> INFO: dacScan step from 62 .. 62
[06:57:20.526] <TB0> INFO: dacScan split into 1 runs with ntrig = 50
[06:57:20.526] <TB0> INFO: run 1 of 1
[06:57:36.731] <TB0> INFO: Test took 16205ms.
[06:57:38.676] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[06:57:38.935] <TB0> INFO: dacScan step from 63 .. 63
[06:57:38.935] <TB0> INFO: dacScan split into 1 runs with ntrig = 50
[06:57:38.935] <TB0> INFO: run 1 of 1
[06:57:56.289] <TB0> INFO: Test took 17354ms.
[06:57:57.945] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[06:57:58.190] <TB0> INFO: dacScan step from 64 .. 64
[06:57:58.190] <TB0> INFO: dacScan split into 1 runs with ntrig = 50
[06:57:58.190] <TB0> INFO: run 1 of 1
[06:58:16.700] <TB0> INFO: Test took 18509ms.
[06:58:18.429] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[06:58:18.679] <TB0> INFO: dacScan step from 65 .. 65
[06:58:18.679] <TB0> INFO: dacScan split into 1 runs with ntrig = 50
[06:58:18.679] <TB0> INFO: run 1 of 1
[06:58:36.380] <TB0> INFO: Test took 17701ms.
[06:58:38.186] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[06:58:38.464] <TB0> INFO: dacScan step from 66 .. 66
[06:58:38.464] <TB0> INFO: dacScan split into 1 runs with ntrig = 50
[06:58:38.464] <TB0> INFO: run 1 of 1
[06:58:55.684] <TB0> INFO: Test took 17219ms.
[06:58:57.477] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[06:58:57.757] <TB0> INFO: dacScan step from 67 .. 67
[06:58:57.757] <TB0> INFO: dacScan split into 1 runs with ntrig = 50
[06:58:57.757] <TB0> INFO: run 1 of 1
[06:59:13.610] <TB0> INFO: Test took 15852ms.
[06:59:15.569] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[06:59:15.820] <TB0> INFO: dacScan step from 68 .. 68
[06:59:15.820] <TB0> INFO: dacScan split into 1 runs with ntrig = 50
[06:59:15.820] <TB0> INFO: run 1 of 1
[06:59:31.747] <TB0> INFO: Test took 15927ms.
[06:59:33.451] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[06:59:33.684] <TB0> INFO: dacScan step from 69 .. 69
[06:59:33.684] <TB0> INFO: dacScan split into 1 runs with ntrig = 50
[06:59:33.684] <TB0> INFO: run 1 of 1
[06:59:50.414] <TB0> INFO: Test took 16729ms.
[06:59:52.415] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[06:59:52.710] <TB0> INFO: dacScan step from 70 .. 70
[06:59:52.710] <TB0> INFO: dacScan split into 1 runs with ntrig = 50
[06:59:52.710] <TB0> INFO: run 1 of 1
[07:00:10.633] <TB0> INFO: Test took 17923ms.
[07:00:12.234] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[07:00:12.474] <TB0> INFO: dacScan step from 71 .. 71
[07:00:12.474] <TB0> INFO: dacScan split into 1 runs with ntrig = 50
[07:00:12.474] <TB0> INFO: run 1 of 1
[07:00:31.308] <TB0> INFO: Test took 18833ms.
[07:00:33.014] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[07:00:33.270] <TB0> INFO: dacScan step from 72 .. 72
[07:00:33.270] <TB0> INFO: dacScan split into 1 runs with ntrig = 50
[07:00:33.270] <TB0> INFO: run 1 of 1
[07:00:50.657] <TB0> INFO: Test took 17386ms.
[07:00:52.502] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[07:00:52.747] <TB0> INFO: dacScan step from 73 .. 73
[07:00:52.747] <TB0> INFO: dacScan split into 1 runs with ntrig = 50
[07:00:52.751] <TB0> INFO: run 1 of 1
[07:01:08.990] <TB0> INFO: Test took 16238ms.
[07:01:10.739] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[07:01:10.988] <TB0> INFO: dacScan step from 74 .. 74
[07:01:10.988] <TB0> INFO: dacScan split into 1 runs with ntrig = 50
[07:01:10.988] <TB0> INFO: run 1 of 1
[07:01:26.097] <TB0> INFO: Test took 15108ms.
[07:01:27.821] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[07:01:28.068] <TB0> INFO: dacScan step from 75 .. 75
[07:01:28.068] <TB0> INFO: dacScan split into 1 runs with ntrig = 50
[07:01:28.068] <TB0> INFO: run 1 of 1
[07:01:43.764] <TB0> INFO: Test took 15695ms.
[07:01:45.526] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[07:01:45.775] <TB0> INFO: dacScan step from 76 .. 76
[07:01:45.775] <TB0> INFO: dacScan split into 1 runs with ntrig = 50
[07:01:45.775] <TB0> INFO: run 1 of 1
[07:02:03.734] <TB0> INFO: Test took 17958ms.
[07:02:05.395] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[07:02:05.637] <TB0> INFO: dacScan step from 77 .. 77
[07:02:05.637] <TB0> INFO: dacScan split into 1 runs with ntrig = 50
[07:02:05.637] <TB0> INFO: run 1 of 1
[07:02:24.192] <TB0> INFO: Test took 18554ms.
[07:02:25.874] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[07:02:26.118] <TB0> INFO: dacScan step from 78 .. 78
[07:02:26.118] <TB0> INFO: dacScan split into 1 runs with ntrig = 50
[07:02:26.118] <TB0> INFO: run 1 of 1
[07:02:43.693] <TB0> INFO: Test took 17574ms.
[07:02:45.496] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[07:02:45.756] <TB0> INFO: dacScan step from 79 .. 79
[07:02:45.756] <TB0> INFO: dacScan split into 1 runs with ntrig = 50
[07:02:45.756] <TB0> INFO: run 1 of 1
[07:03:02.650] <TB0> INFO: Test took 16894ms.
[07:03:04.470] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[07:03:04.766] <TB0> INFO: dacScan step from 80 .. 80
[07:03:04.766] <TB0> INFO: dacScan split into 1 runs with ntrig = 50
[07:03:04.766] <TB0> INFO: run 1 of 1
[07:03:19.997] <TB0> INFO: Test took 15230ms.
[07:03:21.772] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[07:03:22.020] <TB0> INFO: dacScan step from 81 .. 81
[07:03:22.020] <TB0> INFO: dacScan split into 1 runs with ntrig = 50
[07:03:22.020] <TB0> INFO: run 1 of 1
[07:03:38.277] <TB0> INFO: Test took 16256ms.
[07:03:40.136] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[07:03:40.383] <TB0> INFO: dacScan step from 82 .. 82
[07:03:40.384] <TB0> INFO: dacScan split into 1 runs with ntrig = 50
[07:03:40.384] <TB0> INFO: run 1 of 1
[07:03:57.860] <TB0> INFO: Test took 17476ms.
[07:03:59.628] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[07:03:59.875] <TB0> INFO: dacScan step from 83 .. 83
[07:03:59.875] <TB0> INFO: dacScan split into 1 runs with ntrig = 50
[07:03:59.875] <TB0> INFO: run 1 of 1
[07:04:18.044] <TB0> INFO: Test took 18168ms.
[07:04:19.814] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[07:04:20.068] <TB0> INFO: dacScan step from 84 .. 84
[07:04:20.068] <TB0> INFO: dacScan split into 1 runs with ntrig = 50
[07:04:20.068] <TB0> INFO: run 1 of 1
[07:04:37.948] <TB0> INFO: Test took 17880ms.
[07:04:39.799] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[07:04:40.064] <TB0> INFO: dacScan step from 85 .. 85
[07:04:40.064] <TB0> INFO: dacScan split into 1 runs with ntrig = 50
[07:04:40.064] <TB0> INFO: run 1 of 1
[07:04:56.968] <TB0> INFO: Test took 16903ms.
[07:04:58.697] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[07:04:58.952] <TB0> INFO: dacScan step from 86 .. 86
[07:04:58.952] <TB0> INFO: dacScan split into 1 runs with ntrig = 50
[07:04:58.952] <TB0> INFO: run 1 of 1
[07:05:14.378] <TB0> INFO: Test took 15425ms.
[07:05:16.147] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[07:05:16.405] <TB0> INFO: dacScan step from 87 .. 87
[07:05:16.405] <TB0> INFO: dacScan split into 1 runs with ntrig = 50
[07:05:16.405] <TB0> INFO: run 1 of 1
[07:05:32.280] <TB0> INFO: Test took 15874ms.
[07:05:34.093] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[07:05:34.349] <TB0> INFO: dacScan step from 88 .. 88
[07:05:34.349] <TB0> INFO: dacScan split into 1 runs with ntrig = 50
[07:05:34.349] <TB0> INFO: run 1 of 1
[07:05:51.135] <TB0> INFO: Test took 16785ms.
[07:05:52.769] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[07:05:53.025] <TB0> INFO: dacScan step from 89 .. 89
[07:05:53.025] <TB0> INFO: dacScan split into 1 runs with ntrig = 50
[07:05:53.025] <TB0> INFO: run 1 of 1
[07:06:11.611] <TB0> INFO: Test took 18584ms.
[07:06:13.560] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[07:06:13.796] <TB0> INFO: dacScan step from 90 .. 90
[07:06:13.796] <TB0> INFO: dacScan split into 1 runs with ntrig = 50
[07:06:13.796] <TB0> INFO: run 1 of 1
[07:06:31.907] <TB0> INFO: Test took 18110ms.
[07:06:33.640] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[07:06:33.873] <TB0> INFO: dacScan step from 91 .. 91
[07:06:33.873] <TB0> INFO: dacScan split into 1 runs with ntrig = 50
[07:06:33.873] <TB0> INFO: run 1 of 1
[07:06:51.127] <TB0> INFO: Test took 17253ms.
[07:06:52.863] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[07:06:53.104] <TB0> INFO: dacScan step from 92 .. 92
[07:06:53.105] <TB0> INFO: dacScan split into 1 runs with ntrig = 50
[07:06:53.105] <TB0> INFO: run 1 of 1
[07:07:08.158] <TB0> INFO: Test took 15053ms.
[07:07:09.797] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[07:07:10.049] <TB0> INFO: dacScan step from 93 .. 93
[07:07:10.049] <TB0> INFO: dacScan split into 1 runs with ntrig = 50
[07:07:10.049] <TB0> INFO: run 1 of 1
[07:07:25.702] <TB0> INFO: Test took 15652ms.
[07:07:27.392] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[07:07:27.649] <TB0> INFO: dacScan step from 94 .. 94
[07:07:27.649] <TB0> INFO: dacScan split into 1 runs with ntrig = 50
[07:07:27.649] <TB0> INFO: run 1 of 1
[07:07:44.346] <TB0> INFO: Test took 16696ms.
[07:07:46.387] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[07:07:46.673] <TB0> INFO: dacScan step from 95 .. 95
[07:07:46.680] <TB0> INFO: dacScan split into 1 runs with ntrig = 50
[07:07:46.680] <TB0> INFO: run 1 of 1
[07:08:03.261] <TB0> INFO: Test took 16580ms.
[07:08:04.824] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[07:08:05.129] <TB0> INFO: dacScan step from 96 .. 96
[07:08:05.129] <TB0> INFO: dacScan split into 1 runs with ntrig = 50
[07:08:05.129] <TB0> INFO: run 1 of 1
[07:08:24.694] <TB0> INFO: Test took 19564ms.
[07:08:26.368] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[07:08:26.686] <TB0> INFO: dacScan step from 97 .. 97
[07:08:26.686] <TB0> INFO: dacScan split into 1 runs with ntrig = 50
[07:08:26.686] <TB0> INFO: run 1 of 1
[07:08:44.014] <TB0> INFO: Test took 17327ms.
[07:08:46.009] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[07:08:46.264] <TB0> INFO: dacScan step from 98 .. 98
[07:08:46.264] <TB0> INFO: dacScan split into 1 runs with ntrig = 50
[07:08:46.264] <TB0> INFO: run 1 of 1
[07:09:02.896] <TB0> INFO: Test took 16631ms.
[07:09:04.540] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[07:09:04.795] <TB0> INFO: dacScan step from 99 .. 99
[07:09:04.795] <TB0> INFO: dacScan split into 1 runs with ntrig = 50
[07:09:04.795] <TB0> INFO: run 1 of 1
[07:09:20.315] <TB0> INFO: Test took 15519ms.
[07:09:21.896] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[07:09:22.129] <TB0> INFO: dacScan step from 100 .. 100
[07:09:22.129] <TB0> INFO: dacScan split into 1 runs with ntrig = 50
[07:09:22.129] <TB0> INFO: run 1 of 1
[07:09:38.656] <TB0> INFO: Test took 16526ms.
[07:09:40.269] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[07:09:40.498] <TB0> INFO: dumping ASCII scurve output file: XSCurveData
[07:09:41.948] <TB0> INFO: dumping ASCII scurve output file: XSCurveData
[07:09:43.422] <TB0> INFO: dumping ASCII scurve output file: XSCurveData
[07:09:44.816] <TB0> INFO: dumping ASCII scurve output file: XSCurveData
[07:09:46.252] <TB0> INFO: dumping ASCII scurve output file: XSCurveData
[07:09:47.601] <TB0> INFO: dumping ASCII scurve output file: XSCurveData
[07:09:49.156] <TB0> INFO: dumping ASCII scurve output file: XSCurveData
[07:09:50.592] <TB0> INFO: dumping ASCII scurve output file: XSCurveData
[07:09:52.091] <TB0> INFO: dumping ASCII scurve output file: XSCurveData
[07:09:53.515] <TB0> INFO: dumping ASCII scurve output file: XSCurveData
[07:09:55.024] <TB0> INFO: dumping ASCII scurve output file: XSCurveData
[07:09:56.577] <TB0> INFO: dumping ASCII scurve output file: XSCurveData
[07:09:57.967] <TB0> INFO: dumping ASCII scurve output file: XSCurveData
[07:09:59.510] <TB0> INFO: dumping ASCII scurve output file: XSCurveData
[07:10:00.844] <TB0> INFO: dumping ASCII scurve output file: XSCurveData
[07:10:02.199] <TB0> INFO: dumping ASCII scurve output file: XSCurveData
[07:10:03.613] <TB0> INFO: PixTest:: pg_setup set to default.
[07:10:03.614] <TB0> INFO: vcal mean: 38.76 38.01 37.70 40.39 41.40 42.56 42.42 42.87 41.91 42.14 41.59 40.75 40.06 37.58 37.98 37.77
[07:10:03.614] <TB0> INFO: vcal RMS: 1.30 1.24 1.02 2.07 1.59 1.45 1.54 1.50 1.49 1.50 1.54 1.53 1.32 1.30 1.29 1.30
[07:10:03.614] <TB0> INFO: number of X-ray hits detected: 184073 181110 163356 283760 331824 386035 370330 394085 356166 359426 351706 299243 269402 148352 161610 154065
[07:10:03.614] <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
[07:10:03.614] <TB0> INFO: X-ray hit rate [MHz/cm2]: 54.0 53.1 47.9 83.2 97.3 113.1 108.5 115.5 104.4 105.4 103.1 87.7 79.0 43.5 47.4 45.2
[07:10:03.614] <TB0> INFO: PixTestHighRate::doXNoiseMaps() done
[07:10:03.734] <TB0> INFO: enter test to run
[07:10:03.734] <TB0> INFO: test: no parameter change
[07:10:04.010] <TB0> QUIET: Connection to board 147 closed.
[07:10:04.089] <TB0> INFO: pXar: this is the end, my friend
commander_CalDelScanAndSaveDacs.log
[07:31:14.600] <TB0> INFO: *** Welcome to pxar ***
[07:31:14.600] <TB0> INFO: *** Today: 2015/08/24
[07:31:14.600] <TB0> INFO: readRocDacs: /usr/local/data/M2068_XrayQualification_2015-08-24_08h08m_1440396521//010_CalDelScanAndSaveDacs_4mA25kV//dacParameters35_C0.dat .. /usr/local/data/M2068_XrayQualification_2015-08-24_08h08m_1440396521//010_CalDelScanAndSaveDacs_4mA25kV//dacParameters35_C15.dat
[07:31:14.601] <TB0> INFO: readTbmDacs: /usr/local/data/M2068_XrayQualification_2015-08-24_08h08m_1440396521//010_CalDelScanAndSaveDacs_4mA25kV//tbmParameters_C0a.dat .. /usr/local/data/M2068_XrayQualification_2015-08-24_08h08m_1440396521//010_CalDelScanAndSaveDacs_4mA25kV//tbmParameters_C0b.dat
[07:31:14.601] <TB0> INFO: readMaskFile: /usr/local/data/M2068_XrayQualification_2015-08-24_08h08m_1440396521//010_CalDelScanAndSaveDacs_4mA25kV//defaultMaskFile.dat
[07:31:14.601] <TB0> INFO: readTrimFile: /usr/local/data/M2068_XrayQualification_2015-08-24_08h08m_1440396521//010_CalDelScanAndSaveDacs_4mA25kV//trimParameters35_C0.dat .. /usr/local/data/M2068_XrayQualification_2015-08-24_08h08m_1440396521//010_CalDelScanAndSaveDacs_4mA25kV//trimParameters35_C15.dat
[07:31:14.666] <TB0> INFO: clk: 4
[07:31:14.666] <TB0> INFO: ctr: 4
[07:31:14.666] <TB0> INFO: sda: 19
[07:31:14.666] <TB0> INFO: tin: 9
[07:31:14.666] <TB0> INFO: level: 15
[07:31:14.666] <TB0> INFO: triggerdelay: 0
[07:31:14.666] <TB0> QUIET: Instanciating API for pxar prod-01+86~g1838649
[07:31:14.666] <TB0> INFO: Log level: INFO
[07:31:14.680] <TB0> INFO: Found DTB DTB_WWXTQT
[07:31:14.690] <TB0> QUIET: Connection to board DTB_WWXTQT opened.
[07:31:14.693] <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:
------------------------------------------------------
[07:31:14.695] <TB0> INFO: RPC call hashes of host and DTB match: 447413373
[07:31:16.207] <TB0> INFO: DUT info:
[07:31:16.207] <TB0> INFO: The DUT currently contains the following objects:
[07:31:16.207] <TB0> INFO: 2 TBM Cores tbm09c (2 ON)
[07:31:16.207] <TB0> INFO: TBM Core alpha (0): 7 registers set
[07:31:16.207] <TB0> INFO: TBM Core beta (1): 7 registers set
[07:31:16.207] <TB0> INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[07:31:16.207] <TB0> INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[07:31:16.208] <TB0> INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[07:31:16.208] <TB0> INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[07:31:16.208] <TB0> INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[07:31:16.208] <TB0> INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[07:31:16.208] <TB0> INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[07:31:16.208] <TB0> INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[07:31:16.208] <TB0> INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[07:31:16.208] <TB0> INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[07:31:16.208] <TB0> INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[07:31:16.208] <TB0> INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[07:31:16.208] <TB0> INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[07:31:16.208] <TB0> INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[07:31:16.208] <TB0> INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[07:31:16.208] <TB0> INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[07:31:16.208] <TB0> INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[07:31:16.609] <TB0> INFO: enter 'restricted' command line mode
[07:31:16.609] <TB0> INFO: enter test to run
[07:31:16.609] <TB0> INFO: test: HighRate no parameter change
[07:31:16.609] <TB0> INFO: running: highrate
[07:31:16.609] <TB0> INFO: readGainPedestalParameters /usr/local/data/M2068_XrayQualification_2015-08-24_08h08m_1440396521//010_CalDelScanAndSaveDacs_4mA25kV//phCalibrationFitErr35_C0.dat .. /usr/local/data/M2068_XrayQualification_2015-08-24_08h08m_1440396521//010_CalDelScanAndSaveDacs_4mA25kV//phCalibrationFitErr35_C15.dat
[07:31:16.734] <TB0> INFO: ----------------------------------------------------------------------
[07:31:16.734] <TB0> INFO: PixTestHighRate::calDelScan() ntrig = 10, vcal = 200
[07:31:16.734] <TB0> INFO: ----------------------------------------------------------------------
[07:31:17.815] <TB0> INFO: Test took 1075ms.
[07:31:20.964] <TB0> INFO: Test took 3146ms.
[07:31:21.085] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[07:31:24.268] <TB0> INFO: Test took 3159ms.
[07:31:24.402] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[07:31:28.182] <TB0> INFO: Test took 3754ms.
[07:31:28.346] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[07:31:30.458] <TB0> ERROR: <datapipe.cc/CheckEventValidity:L470> Channel 0 Number of ROCs (3) != Token Chain Length (4)

[07:31:30.458] <TB0> ERROR: <datapipe.cc/CheckEventValidity:L470> Channel 1 Number of ROCs (2) != Token Chain Length (4)

[07:31:30.458] <TB0> ERROR: <datapipe.cc/CheckEventID:L453> Channel 1 Event ID mismatch: local ID (2) != TBM ID (130)

[07:31:30.458] <TB0> ERROR: <datapipe.cc/CheckEventValidity:L470> Channel 1 Number of ROCs (0) != Token Chain Length (4)

[07:31:30.458] <TB0> ERROR: <datapipe.cc/CheckEventID:L453> Channel 1 Event ID mismatch: local ID (131) != TBM ID (2)

[07:31:30.458] <TB0> WARNING: Channel 1 ROC 3: Readback start marker after 15 readouts!

[07:31:32.406] <TB0> CRITICAL: <hal.cc/MultiRocAllPixelsCalibrate:L797> Incomplete DAQ data readout! Missing -1 Events.

[07:31:36.780] <TB0> INFO: Test took 4369ms.
[07:31:36.943] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[07:31:41.375] <TB0> INFO: Test took 4403ms.
[07:31:41.545] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[07:31:45.696] <TB0> INFO: Test took 4111ms.
[07:31:45.835] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[07:31:49.945] <TB0> INFO: Test took 4083ms.
[07:31:50.088] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[07:31:54.068] <TB0> INFO: Test took 3954ms.
[07:31:54.211] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[07:31:57.974] <TB0> INFO: Test took 3736ms.
[07:31:58.120] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[07:32:01.958] <TB0> INFO: Test took 3809ms.
[07:32:02.115] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[07:32:06.011] <TB0> INFO: Test took 3867ms.
[07:32:06.160] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[07:32:09.793] <TB0> INFO: Test took 3607ms.
[07:32:09.954] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[07:32:13.680] <TB0> INFO: Test took 3697ms.
[07:32:13.822] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[07:32:17.609] <TB0> INFO: Test took 3761ms.
[07:32:17.768] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[07:32:21.678] <TB0> INFO: Test took 3878ms.
[07:32:21.821] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[07:32:25.756] <TB0> INFO: Test took 3910ms.
[07:32:25.909] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[07:32:30.073] <TB0> INFO: Test took 4130ms.
[07:32:30.220] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[07:32:34.222] <TB0> INFO: Test took 3970ms.
[07:32:34.361] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[07:32:38.574] <TB0> INFO: Test took 4188ms.
[07:32:38.723] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[07:32:42.595] <TB0> INFO: Test took 3845ms.
[07:32:42.742] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[07:32:43.065] <TB0> INFO: enter test to run
[07:32:43.065] <TB0> INFO: test: Pretest no parameter change
[07:32:43.065] <TB0> INFO: running: pretest
[07:32:43.065] <TB0> INFO: write dac parameters into /usr/local/data/M2068_XrayQualification_2015-08-24_08h08m_1440396521//010_CalDelScanAndSaveDacs_4mA25kV//dacParameters35_C0.dat
[07:32:43.066] <TB0> INFO: write dac parameters into /usr/local/data/M2068_XrayQualification_2015-08-24_08h08m_1440396521//010_CalDelScanAndSaveDacs_4mA25kV//dacParameters35_C1.dat
[07:32:43.066] <TB0> INFO: write dac parameters into /usr/local/data/M2068_XrayQualification_2015-08-24_08h08m_1440396521//010_CalDelScanAndSaveDacs_4mA25kV//dacParameters35_C2.dat
[07:32:43.066] <TB0> INFO: write dac parameters into /usr/local/data/M2068_XrayQualification_2015-08-24_08h08m_1440396521//010_CalDelScanAndSaveDacs_4mA25kV//dacParameters35_C3.dat
[07:32:43.066] <TB0> INFO: write dac parameters into /usr/local/data/M2068_XrayQualification_2015-08-24_08h08m_1440396521//010_CalDelScanAndSaveDacs_4mA25kV//dacParameters35_C4.dat
[07:32:43.066] <TB0> INFO: write dac parameters into /usr/local/data/M2068_XrayQualification_2015-08-24_08h08m_1440396521//010_CalDelScanAndSaveDacs_4mA25kV//dacParameters35_C5.dat
[07:32:43.066] <TB0> INFO: write dac parameters into /usr/local/data/M2068_XrayQualification_2015-08-24_08h08m_1440396521//010_CalDelScanAndSaveDacs_4mA25kV//dacParameters35_C6.dat
[07:32:43.066] <TB0> INFO: write dac parameters into /usr/local/data/M2068_XrayQualification_2015-08-24_08h08m_1440396521//010_CalDelScanAndSaveDacs_4mA25kV//dacParameters35_C7.dat
[07:32:43.066] <TB0> INFO: write dac parameters into /usr/local/data/M2068_XrayQualification_2015-08-24_08h08m_1440396521//010_CalDelScanAndSaveDacs_4mA25kV//dacParameters35_C8.dat
[07:32:43.066] <TB0> INFO: write dac parameters into /usr/local/data/M2068_XrayQualification_2015-08-24_08h08m_1440396521//010_CalDelScanAndSaveDacs_4mA25kV//dacParameters35_C9.dat
[07:32:43.066] <TB0> INFO: write dac parameters into /usr/local/data/M2068_XrayQualification_2015-08-24_08h08m_1440396521//010_CalDelScanAndSaveDacs_4mA25kV//dacParameters35_C10.dat
[07:32:43.067] <TB0> INFO: write dac parameters into /usr/local/data/M2068_XrayQualification_2015-08-24_08h08m_1440396521//010_CalDelScanAndSaveDacs_4mA25kV//dacParameters35_C11.dat
[07:32:43.067] <TB0> INFO: write dac parameters into /usr/local/data/M2068_XrayQualification_2015-08-24_08h08m_1440396521//010_CalDelScanAndSaveDacs_4mA25kV//dacParameters35_C12.dat
[07:32:43.067] <TB0> INFO: write dac parameters into /usr/local/data/M2068_XrayQualification_2015-08-24_08h08m_1440396521//010_CalDelScanAndSaveDacs_4mA25kV//dacParameters35_C13.dat
[07:32:43.067] <TB0> INFO: write dac parameters into /usr/local/data/M2068_XrayQualification_2015-08-24_08h08m_1440396521//010_CalDelScanAndSaveDacs_4mA25kV//dacParameters35_C14.dat
[07:32:43.067] <TB0> INFO: write dac parameters into /usr/local/data/M2068_XrayQualification_2015-08-24_08h08m_1440396521//010_CalDelScanAndSaveDacs_4mA25kV//dacParameters35_C15.dat
[07:32:43.067] <TB0> INFO: enter test to run
[07:32:43.067] <TB0> INFO: test: no parameter change
[07:32:43.267] <TB0> QUIET: Connection to board 147 closed.
[07:32:43.347] <TB0> INFO: pXar: this is the end, my friend
commander_HREfficiency.log
[07:33:14.913] <TB0> INFO: *** Welcome to pxar ***
[07:33:14.914] <TB0> INFO: *** Today: 2015/08/24
[07:33:14.914] <TB0> INFO: readRocDacs: /usr/local/data/M2068_XrayQualification_2015-08-24_08h08m_1440396521//011_HREfficiency_50//dacParameters35_C0.dat .. /usr/local/data/M2068_XrayQualification_2015-08-24_08h08m_1440396521//011_HREfficiency_50//dacParameters35_C15.dat
[07:33:14.914] <TB0> INFO: readTbmDacs: /usr/local/data/M2068_XrayQualification_2015-08-24_08h08m_1440396521//011_HREfficiency_50//tbmParameters_C0a.dat .. /usr/local/data/M2068_XrayQualification_2015-08-24_08h08m_1440396521//011_HREfficiency_50//tbmParameters_C0b.dat
[07:33:14.914] <TB0> INFO: readMaskFile: /usr/local/data/M2068_XrayQualification_2015-08-24_08h08m_1440396521//011_HREfficiency_50//defaultMaskFile.dat
[07:33:14.914] <TB0> INFO: readTrimFile: /usr/local/data/M2068_XrayQualification_2015-08-24_08h08m_1440396521//011_HREfficiency_50//trimParameters35_C0.dat .. /usr/local/data/M2068_XrayQualification_2015-08-24_08h08m_1440396521//011_HREfficiency_50//trimParameters35_C15.dat
[07:33:14.984] <TB0> INFO: clk: 4
[07:33:14.984] <TB0> INFO: ctr: 4
[07:33:14.984] <TB0> INFO: sda: 19
[07:33:14.984] <TB0> INFO: tin: 9
[07:33:14.984] <TB0> INFO: level: 15
[07:33:14.984] <TB0> INFO: triggerdelay: 0
[07:33:14.984] <TB0> QUIET: Instanciating API for pxar prod-01+86~g1838649
[07:33:14.984] <TB0> INFO: Log level: INFO
[07:33:14.997] <TB0> INFO: Found DTB DTB_WWXTQT
[07:33:15.007] <TB0> QUIET: Connection to board DTB_WWXTQT opened.
[07:33:15.010] <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:
------------------------------------------------------
[07:33:15.013] <TB0> INFO: RPC call hashes of host and DTB match: 447413373
[07:33:16.523] <TB0> INFO: DUT info:
[07:33:16.523] <TB0> INFO: The DUT currently contains the following objects:
[07:33:16.523] <TB0> INFO: 2 TBM Cores tbm09c (2 ON)
[07:33:16.523] <TB0> INFO: TBM Core alpha (0): 7 registers set
[07:33:16.524] <TB0> INFO: TBM Core beta (1): 7 registers set
[07:33:16.524] <TB0> INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[07:33:16.524] <TB0> INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[07:33:16.524] <TB0> INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[07:33:16.524] <TB0> INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[07:33:16.524] <TB0> INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[07:33:16.524] <TB0> INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[07:33:16.524] <TB0> INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[07:33:16.524] <TB0> INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[07:33:16.524] <TB0> INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[07:33:16.524] <TB0> INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[07:33:16.524] <TB0> INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[07:33:16.524] <TB0> INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[07:33:16.524] <TB0> INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[07:33:16.524] <TB0> INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[07:33:16.524] <TB0> INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[07:33:16.524] <TB0> INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[07:33:16.524] <TB0> INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[07:33:16.925] <TB0> INFO: enter 'restricted' command line mode
[07:33:16.925] <TB0> INFO: enter test to run
[07:33:16.925] <TB0> INFO: test: HighRate no parameter change
[07:33:16.925] <TB0> INFO: running: highrate
[07:33:16.925] <TB0> INFO: readGainPedestalParameters /usr/local/data/M2068_XrayQualification_2015-08-24_08h08m_1440396521//011_HREfficiency_50//phCalibrationFitErr35_C0.dat .. /usr/local/data/M2068_XrayQualification_2015-08-24_08h08m_1440396521//011_HREfficiency_50//phCalibrationFitErr35_C15.dat
[07:33:17.053] <TB0> INFO: ----------------------------------------------------------------------
[07:33:17.053] <TB0> INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[07:33:17.053] <TB0> INFO: ----------------------------------------------------------------------
[07:33:28.341] <TB0> INFO: Test took 11282ms.
[07:33:29.084] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[07:33:29.386] <TB0> INFO: number of dead pixels (per ROC): 0 0 0 1 1 0 0 0 0 0 0 0 0 0 0 1
[07:33:29.386] <TB0> INFO: number of red-efficiency pixels: 154 109 110 209 233 305 335 319 287 275 338 237 220 102 95 114
[07:33:29.386] <TB0> INFO: number of X-ray hits detected: 91848 89229 80485 141879 167290 196524 187004 198721 179110 181126 180399 149804 134092 72454 78755 75985
[07:33:29.386] <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
[07:33:29.386] <TB0> INFO: number of Vcal hits detected: 207839 207891 207888 207737 207659 207676 207636 207666 207694 207699 207645 207751 207771 207896 207903 207835
[07:33:29.386] <TB0> INFO: Vcal hit fiducial efficiency (%): 99.9 100.0 100.0 99.9 99.9 99.9 99.9 99.9 99.9 99.9 99.8 99.9 99.9 100.0 100.0 99.9
[07:33:29.386] <TB0> INFO: Vcal hit overall efficiency (%): 99.9 99.9 99.9 99.9 99.8 99.8 99.8 99.8 99.9 99.9 99.8 99.9 99.9 100.0 100.0 99.9
[07:33:29.386] <TB0> INFO: X-ray hit rate [MHz/cm2]: 26.9 26.2 23.6 41.6 49.0 57.6 54.8 58.2 52.5 53.1 52.9 43.9 39.3 21.2 23.1 22.3
[07:33:29.386] <TB0> INFO: PixTestHighRate::doXPixelAlive() done
[07:33:29.425] <TB0> INFO: PixTest:: pg_setup set to default.
[07:33:29.457] <TB0> INFO: enter test to run
[07:33:29.457] <TB0> INFO: test: no parameter change
[07:33:29.637] <TB0> QUIET: Connection to board 147 closed.
[07:33:29.716] <TB0> INFO: pXar: this is the end, my friend
commander_HREfficiency.log
[07:34:05.822] <TB0> INFO: *** Welcome to pxar ***
[07:34:05.822] <TB0> INFO: *** Today: 2015/08/24
[07:34:05.822] <TB0> INFO: readRocDacs: /usr/local/data/M2068_XrayQualification_2015-08-24_08h08m_1440396521//012_HREfficiency_100//dacParameters35_C0.dat .. /usr/local/data/M2068_XrayQualification_2015-08-24_08h08m_1440396521//012_HREfficiency_100//dacParameters35_C15.dat
[07:34:05.823] <TB0> INFO: readTbmDacs: /usr/local/data/M2068_XrayQualification_2015-08-24_08h08m_1440396521//012_HREfficiency_100//tbmParameters_C0a.dat .. /usr/local/data/M2068_XrayQualification_2015-08-24_08h08m_1440396521//012_HREfficiency_100//tbmParameters_C0b.dat
[07:34:05.823] <TB0> INFO: readMaskFile: /usr/local/data/M2068_XrayQualification_2015-08-24_08h08m_1440396521//012_HREfficiency_100//defaultMaskFile.dat
[07:34:05.823] <TB0> INFO: readTrimFile: /usr/local/data/M2068_XrayQualification_2015-08-24_08h08m_1440396521//012_HREfficiency_100//trimParameters35_C0.dat .. /usr/local/data/M2068_XrayQualification_2015-08-24_08h08m_1440396521//012_HREfficiency_100//trimParameters35_C15.dat
[07:34:05.888] <TB0> INFO: clk: 4
[07:34:05.888] <TB0> INFO: ctr: 4
[07:34:05.888] <TB0> INFO: sda: 19
[07:34:05.888] <TB0> INFO: tin: 9
[07:34:05.888] <TB0> INFO: level: 15
[07:34:05.888] <TB0> INFO: triggerdelay: 0
[07:34:05.888] <TB0> QUIET: Instanciating API for pxar prod-01+86~g1838649
[07:34:05.888] <TB0> INFO: Log level: INFO
[07:34:05.902] <TB0> INFO: Found DTB DTB_WWXTQT
[07:34:05.911] <TB0> QUIET: Connection to board DTB_WWXTQT opened.
[07:34:05.914] <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:
------------------------------------------------------
[07:34:05.917] <TB0> INFO: RPC call hashes of host and DTB match: 447413373
[07:34:07.432] <TB0> INFO: DUT info:
[07:34:07.432] <TB0> INFO: The DUT currently contains the following objects:
[07:34:07.432] <TB0> INFO: 2 TBM Cores tbm09c (2 ON)
[07:34:07.432] <TB0> INFO: TBM Core alpha (0): 7 registers set
[07:34:07.432] <TB0> INFO: TBM Core beta (1): 7 registers set
[07:34:07.432] <TB0> INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[07:34:07.432] <TB0> INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[07:34:07.432] <TB0> INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[07:34:07.432] <TB0> INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[07:34:07.432] <TB0> INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[07:34:07.432] <TB0> INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[07:34:07.432] <TB0> INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[07:34:07.432] <TB0> INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[07:34:07.432] <TB0> INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[07:34:07.432] <TB0> INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[07:34:07.432] <TB0> INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[07:34:07.432] <TB0> INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[07:34:07.432] <TB0> INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[07:34:07.432] <TB0> INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[07:34:07.432] <TB0> INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[07:34:07.432] <TB0> INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[07:34:07.432] <TB0> INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[07:34:07.833] <TB0> INFO: enter 'restricted' command line mode
[07:34:07.833] <TB0> INFO: enter test to run
[07:34:07.833] <TB0> INFO: test: HighRate no parameter change
[07:34:07.833] <TB0> INFO: running: highrate
[07:34:07.833] <TB0> INFO: readGainPedestalParameters /usr/local/data/M2068_XrayQualification_2015-08-24_08h08m_1440396521//012_HREfficiency_100//phCalibrationFitErr35_C0.dat .. /usr/local/data/M2068_XrayQualification_2015-08-24_08h08m_1440396521//012_HREfficiency_100//phCalibrationFitErr35_C15.dat
[07:34:07.961] <TB0> INFO: ----------------------------------------------------------------------
[07:34:07.961] <TB0> INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[07:34:07.961] <TB0> INFO: ----------------------------------------------------------------------
[07:34:21.617] <TB0> INFO: Test took 13650ms.
[07:34:23.632] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[07:34:24.013] <TB0> INFO: number of dead pixels (per ROC): 0 0 0 1 1 0 0 0 0 0 0 0 0 0 0 1
[07:34:24.013] <TB0> INFO: number of red-efficiency pixels: 342 313 370 689 928 1135 1119 1141 985 1119 1314 783 757 328 230 289
[07:34:24.013] <TB0> INFO: number of X-ray hits detected: 184299 180703 164210 284136 333768 386607 371018 396485 357711 359862 355516 299998 269002 148204 160498 154203
[07:34:24.013] <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
[07:34:24.013] <TB0> INFO: number of Vcal hits detected: 207620 207675 207555 207171 206714 206453 206458 206562 206798 206529 206218 207020 207115 207611 207760 207647
[07:34:24.013] <TB0> INFO: Vcal hit fiducial efficiency (%): 99.8 99.9 99.8 99.7 99.5 99.4 99.4 99.4 99.5 99.4 99.2 99.6 99.6 99.8 99.9 99.9
[07:34:24.013] <TB0> INFO: Vcal hit overall efficiency (%): 99.8 99.8 99.8 99.6 99.4 99.3 99.3 99.3 99.4 99.3 99.1 99.5 99.6 99.8 99.9 99.8
[07:34:24.013] <TB0> INFO: X-ray hit rate [MHz/cm2]: 54.0 53.0 48.1 83.3 97.8 113.3 108.7 116.2 104.8 105.5 104.2 87.9 78.8 43.4 47.0 45.2
[07:34:24.013] <TB0> INFO: PixTestHighRate::doXPixelAlive() done
[07:34:24.049] <TB0> INFO: PixTest:: pg_setup set to default.
[07:34:24.092] <TB0> INFO: enter test to run
[07:34:24.092] <TB0> INFO: test: no parameter change
[07:34:24.240] <TB0> QUIET: Connection to board 147 closed.
[07:34:24.320] <TB0> INFO: pXar: this is the end, my friend
commander_HREfficiency.log
[07:34:59.944] <TB0> INFO: *** Welcome to pxar ***
[07:34:59.944] <TB0> INFO: *** Today: 2015/08/24
[07:34:59.944] <TB0> INFO: readRocDacs: /usr/local/data/M2068_XrayQualification_2015-08-24_08h08m_1440396521//013_HREfficiency_150//dacParameters35_C0.dat .. /usr/local/data/M2068_XrayQualification_2015-08-24_08h08m_1440396521//013_HREfficiency_150//dacParameters35_C15.dat
[07:34:59.944] <TB0> INFO: readTbmDacs: /usr/local/data/M2068_XrayQualification_2015-08-24_08h08m_1440396521//013_HREfficiency_150//tbmParameters_C0a.dat .. /usr/local/data/M2068_XrayQualification_2015-08-24_08h08m_1440396521//013_HREfficiency_150//tbmParameters_C0b.dat
[07:34:59.944] <TB0> INFO: readMaskFile: /usr/local/data/M2068_XrayQualification_2015-08-24_08h08m_1440396521//013_HREfficiency_150//defaultMaskFile.dat
[07:34:59.944] <TB0> INFO: readTrimFile: /usr/local/data/M2068_XrayQualification_2015-08-24_08h08m_1440396521//013_HREfficiency_150//trimParameters35_C0.dat .. /usr/local/data/M2068_XrayQualification_2015-08-24_08h08m_1440396521//013_HREfficiency_150//trimParameters35_C15.dat
[07:35:00.012] <TB0> INFO: clk: 4
[07:35:00.012] <TB0> INFO: ctr: 4
[07:35:00.012] <TB0> INFO: sda: 19
[07:35:00.012] <TB0> INFO: tin: 9
[07:35:00.012] <TB0> INFO: level: 15
[07:35:00.012] <TB0> INFO: triggerdelay: 0
[07:35:00.012] <TB0> QUIET: Instanciating API for pxar prod-01+86~g1838649
[07:35:00.012] <TB0> INFO: Log level: INFO
[07:35:00.025] <TB0> INFO: Found DTB DTB_WWXTQT
[07:35:01.033] <TB0> QUIET: Connection to board DTB_WWXTQT opened.
[07:35:01.037] <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:
------------------------------------------------------
[07:35:01.039] <TB0> INFO: RPC call hashes of host and DTB match: 447413373
[07:35:02.554] <TB0> INFO: DUT info:
[07:35:02.554] <TB0> INFO: The DUT currently contains the following objects:
[07:35:02.554] <TB0> INFO: 2 TBM Cores tbm09c (2 ON)
[07:35:02.554] <TB0> INFO: TBM Core alpha (0): 7 registers set
[07:35:02.554] <TB0> INFO: TBM Core beta (1): 7 registers set
[07:35:02.554] <TB0> INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[07:35:02.554] <TB0> INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[07:35:02.554] <TB0> INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[07:35:02.554] <TB0> INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[07:35:02.554] <TB0> INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[07:35:02.554] <TB0> INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[07:35:02.554] <TB0> INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[07:35:02.554] <TB0> INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[07:35:02.554] <TB0> INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[07:35:02.554] <TB0> INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[07:35:02.554] <TB0> INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[07:35:02.554] <TB0> INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[07:35:02.554] <TB0> INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[07:35:02.554] <TB0> INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[07:35:02.554] <TB0> INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[07:35:02.554] <TB0> INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[07:35:02.554] <TB0> INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[07:35:02.955] <TB0> INFO: enter 'restricted' command line mode
[07:35:02.955] <TB0> INFO: enter test to run
[07:35:02.955] <TB0> INFO: test: HighRate no parameter change
[07:35:02.955] <TB0> INFO: running: highrate
[07:35:02.955] <TB0> INFO: readGainPedestalParameters /usr/local/data/M2068_XrayQualification_2015-08-24_08h08m_1440396521//013_HREfficiency_150//phCalibrationFitErr35_C0.dat .. /usr/local/data/M2068_XrayQualification_2015-08-24_08h08m_1440396521//013_HREfficiency_150//phCalibrationFitErr35_C15.dat
[07:35:03.080] <TB0> INFO: ----------------------------------------------------------------------
[07:35:03.080] <TB0> INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[07:35:03.080] <TB0> INFO: ----------------------------------------------------------------------
[07:35:20.803] <TB0> INFO: Test took 17717ms.
[07:35:24.485] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[07:35:24.948] <TB0> INFO: number of dead pixels (per ROC): 0 0 0 1 1 0 0 0 0 0 0 0 0 0 0 1
[07:35:24.948] <TB0> INFO: number of red-efficiency pixels: 598 553 632 1311 1822 2240 2100 2375 2102 2081 2553 1611 1603 677 404 566
[07:35:24.948] <TB0> INFO: number of X-ray hits detected: 266559 263849 240366 408242 478935 553074 535096 566518 513512 519947 509076 432933 389124 214933 235294 223069
[07:35:24.948] <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
[07:35:24.948] <TB0> INFO: number of Vcal hits detected: 207314 207409 207046 206040 204711 203655 203756 203867 204718 204159 202957 205348 205680 206981 207562 207336
[07:35:24.948] <TB0> INFO: Vcal hit fiducial efficiency (%): 99.7 99.7 99.6 99.2 98.7 98.2 98.3 98.3 98.6 98.4 97.9 98.9 99.0 99.6 99.8 99.7
[07:35:24.949] <TB0> INFO: Vcal hit overall efficiency (%): 99.7 99.7 99.5 99.1 98.4 97.9 98.0 98.0 98.4 98.2 97.6 98.7 98.9 99.5 99.8 99.7
[07:35:24.949] <TB0> INFO: X-ray hit rate [MHz/cm2]: 78.1 77.3 70.5 119.7 140.4 162.1 156.8 166.1 150.5 152.4 149.2 126.9 114.1 63.0 69.0 65.4
[07:35:24.949] <TB0> INFO: PixTestHighRate::doXPixelAlive() done
[07:35:24.988] <TB0> INFO: PixTest:: pg_setup set to default.
[07:35:25.042] <TB0> INFO: enter test to run
[07:35:25.043] <TB0> INFO: test: no parameter change
[07:35:25.239] <TB0> QUIET: Connection to board 147 closed.
[07:35:25.326] <TB0> INFO: pXar: this is the end, my friend
commander_HREfficiency.log
[07:36:00.075] <TB0> INFO: *** Welcome to pxar ***
[07:36:00.075] <TB0> INFO: *** Today: 2015/08/24
[07:36:00.075] <TB0> INFO: readRocDacs: /usr/local/data/M2068_XrayQualification_2015-08-24_08h08m_1440396521//014_HREfficiency_200//dacParameters35_C0.dat .. /usr/local/data/M2068_XrayQualification_2015-08-24_08h08m_1440396521//014_HREfficiency_200//dacParameters35_C15.dat
[07:36:00.076] <TB0> INFO: readTbmDacs: /usr/local/data/M2068_XrayQualification_2015-08-24_08h08m_1440396521//014_HREfficiency_200//tbmParameters_C0a.dat .. /usr/local/data/M2068_XrayQualification_2015-08-24_08h08m_1440396521//014_HREfficiency_200//tbmParameters_C0b.dat
[07:36:00.076] <TB0> INFO: readMaskFile: /usr/local/data/M2068_XrayQualification_2015-08-24_08h08m_1440396521//014_HREfficiency_200//defaultMaskFile.dat
[07:36:00.076] <TB0> INFO: readTrimFile: /usr/local/data/M2068_XrayQualification_2015-08-24_08h08m_1440396521//014_HREfficiency_200//trimParameters35_C0.dat .. /usr/local/data/M2068_XrayQualification_2015-08-24_08h08m_1440396521//014_HREfficiency_200//trimParameters35_C15.dat
[07:36:00.142] <TB0> INFO: clk: 4
[07:36:00.142] <TB0> INFO: ctr: 4
[07:36:00.142] <TB0> INFO: sda: 19
[07:36:00.142] <TB0> INFO: tin: 9
[07:36:00.142] <TB0> INFO: level: 15
[07:36:00.142] <TB0> INFO: triggerdelay: 0
[07:36:00.142] <TB0> QUIET: Instanciating API for pxar prod-01+86~g1838649
[07:36:00.142] <TB0> INFO: Log level: INFO
[07:36:00.156] <TB0> INFO: Found DTB DTB_WWXTQT
[07:36:00.165] <TB0> QUIET: Connection to board DTB_WWXTQT opened.
[07:36:00.168] <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:
------------------------------------------------------
[07:36:00.171] <TB0> INFO: RPC call hashes of host and DTB match: 447413373
[07:36:01.687] <TB0> INFO: DUT info:
[07:36:01.687] <TB0> INFO: The DUT currently contains the following objects:
[07:36:01.687] <TB0> INFO: 2 TBM Cores tbm09c (2 ON)
[07:36:01.687] <TB0> INFO: TBM Core alpha (0): 7 registers set
[07:36:01.687] <TB0> INFO: TBM Core beta (1): 7 registers set
[07:36:01.687] <TB0> INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[07:36:01.687] <TB0> INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[07:36:01.687] <TB0> INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[07:36:01.687] <TB0> INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[07:36:01.687] <TB0> INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[07:36:01.687] <TB0> INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[07:36:01.687] <TB0> INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[07:36:01.687] <TB0> INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[07:36:01.687] <TB0> INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[07:36:01.687] <TB0> INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[07:36:01.687] <TB0> INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[07:36:01.687] <TB0> INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[07:36:01.687] <TB0> INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[07:36:01.687] <TB0> INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[07:36:01.687] <TB0> INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[07:36:01.687] <TB0> INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[07:36:01.687] <TB0> INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[07:36:02.088] <TB0> INFO: enter 'restricted' command line mode
[07:36:02.088] <TB0> INFO: enter test to run
[07:36:02.088] <TB0> INFO: test: HighRate no parameter change
[07:36:02.088] <TB0> INFO: running: highrate
[07:36:02.088] <TB0> INFO: readGainPedestalParameters /usr/local/data/M2068_XrayQualification_2015-08-24_08h08m_1440396521//014_HREfficiency_200//phCalibrationFitErr35_C0.dat .. /usr/local/data/M2068_XrayQualification_2015-08-24_08h08m_1440396521//014_HREfficiency_200//phCalibrationFitErr35_C15.dat
[07:36:02.215] <TB0> INFO: ----------------------------------------------------------------------
[07:36:02.216] <TB0> INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[07:36:02.216] <TB0> INFO: ----------------------------------------------------------------------
[07:36:21.587] <TB0> INFO: Test took 19366ms.
[07:36:27.555] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[07:36:28.103] <TB0> INFO: number of dead pixels (per ROC): 0 0 0 1 1 0 0 0 0 0 0 0 0 0 0 1
[07:36:28.103] <TB0> INFO: number of red-efficiency pixels: 1153 1026 1060 2281 2841 3385 3187 3515 3255 3192 3552 2686 2585 1075 778 1002
[07:36:28.103] <TB0> INFO: number of X-ray hits detected: 360700 357855 325561 547927 645796 740663 716624 765719 690111 700052 680939 584719 525442 296100 321667 304461
[07:36:28.103] <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
[07:36:28.103] <TB0> INFO: number of Vcal hits detected: 206516 206734 205876 203483 200568 197482 197887 197791 199906 198680 196626 201919 202644 205796 207085 206750
[07:36:28.103] <TB0> INFO: Vcal hit fiducial efficiency (%): 99.4 99.5 99.2 98.2 96.9 95.7 95.9 95.6 96.5 96.1 95.2 97.5 97.7 99.1 99.6 99.5
[07:36:28.103] <TB0> INFO: Vcal hit overall efficiency (%): 99.3 99.4 99.0 97.8 96.4 94.9 95.1 95.1 96.1 95.5 94.5 97.1 97.4 98.9 99.6 99.4
[07:36:28.103] <TB0> INFO: X-ray hit rate [MHz/cm2]: 105.7 104.9 95.4 160.6 189.3 217.1 210.0 224.4 202.3 205.2 199.6 171.4 154.0 86.8 94.3 89.2
[07:36:28.103] <TB0> INFO: PixTestHighRate::doXPixelAlive() done
[07:36:28.137] <TB0> INFO: PixTest:: pg_setup set to default.
[07:36:28.178] <TB0> INFO: enter test to run
[07:36:28.178] <TB0> INFO: test: no parameter change
[07:36:28.970] <TB0> QUIET: Connection to board 147 closed.
[07:36:29.051] <TB0> INFO: pXar: this is the end, my friend
commander_HREfficiency.log
[07:37:04.223] <TB0> INFO: *** Welcome to pxar ***
[07:37:04.223] <TB0> INFO: *** Today: 2015/08/24
[07:37:04.223] <TB0> INFO: readRocDacs: /usr/local/data/M2068_XrayQualification_2015-08-24_08h08m_1440396521//015_HREfficiency_250//dacParameters35_C0.dat .. /usr/local/data/M2068_XrayQualification_2015-08-24_08h08m_1440396521//015_HREfficiency_250//dacParameters35_C15.dat
[07:37:04.224] <TB0> INFO: readTbmDacs: /usr/local/data/M2068_XrayQualification_2015-08-24_08h08m_1440396521//015_HREfficiency_250//tbmParameters_C0a.dat .. /usr/local/data/M2068_XrayQualification_2015-08-24_08h08m_1440396521//015_HREfficiency_250//tbmParameters_C0b.dat
[07:37:04.224] <TB0> INFO: readMaskFile: /usr/local/data/M2068_XrayQualification_2015-08-24_08h08m_1440396521//015_HREfficiency_250//defaultMaskFile.dat
[07:37:04.224] <TB0> INFO: readTrimFile: /usr/local/data/M2068_XrayQualification_2015-08-24_08h08m_1440396521//015_HREfficiency_250//trimParameters35_C0.dat .. /usr/local/data/M2068_XrayQualification_2015-08-24_08h08m_1440396521//015_HREfficiency_250//trimParameters35_C15.dat
[07:37:04.293] <TB0> INFO: clk: 4
[07:37:04.293] <TB0> INFO: ctr: 4
[07:37:04.293] <TB0> INFO: sda: 19
[07:37:04.293] <TB0> INFO: tin: 9
[07:37:04.293] <TB0> INFO: level: 15
[07:37:04.293] <TB0> INFO: triggerdelay: 0
[07:37:04.293] <TB0> QUIET: Instanciating API for pxar prod-01+86~g1838649
[07:37:04.293] <TB0> INFO: Log level: INFO
[07:37:04.306] <TB0> INFO: Found DTB DTB_WWXTQT
[07:37:04.316] <TB0> QUIET: Connection to board DTB_WWXTQT opened.
[07:37:04.319] <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:
------------------------------------------------------
[07:37:04.322] <TB0> INFO: RPC call hashes of host and DTB match: 447413373
[07:37:05.834] <TB0> INFO: DUT info:
[07:37:05.834] <TB0> INFO: The DUT currently contains the following objects:
[07:37:05.834] <TB0> INFO: 2 TBM Cores tbm09c (2 ON)
[07:37:05.834] <TB0> INFO: TBM Core alpha (0): 7 registers set
[07:37:05.834] <TB0> INFO: TBM Core beta (1): 7 registers set
[07:37:05.834] <TB0> INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[07:37:05.834] <TB0> INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[07:37:05.834] <TB0> INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[07:37:05.834] <TB0> INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[07:37:05.834] <TB0> INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[07:37:05.834] <TB0> INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[07:37:05.834] <TB0> INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[07:37:05.834] <TB0> INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[07:37:05.834] <TB0> INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[07:37:05.834] <TB0> INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[07:37:05.834] <TB0> INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[07:37:05.834] <TB0> INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[07:37:05.834] <TB0> INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[07:37:05.834] <TB0> INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[07:37:05.834] <TB0> INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[07:37:05.834] <TB0> INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[07:37:05.834] <TB0> INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[07:37:06.235] <TB0> INFO: enter 'restricted' command line mode
[07:37:06.235] <TB0> INFO: enter test to run
[07:37:06.235] <TB0> INFO: test: HighRate no parameter change
[07:37:06.235] <TB0> INFO: running: highrate
[07:37:06.235] <TB0> INFO: readGainPedestalParameters /usr/local/data/M2068_XrayQualification_2015-08-24_08h08m_1440396521//015_HREfficiency_250//phCalibrationFitErr35_C0.dat .. /usr/local/data/M2068_XrayQualification_2015-08-24_08h08m_1440396521//015_HREfficiency_250//phCalibrationFitErr35_C15.dat
[07:37:06.364] <TB0> INFO: ----------------------------------------------------------------------
[07:37:06.364] <TB0> INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[07:37:06.364] <TB0> INFO: ----------------------------------------------------------------------
[07:37:26.335] <TB0> INFO: Test took 19965ms.
[07:37:32.965] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[07:37:33.534] <TB0> INFO: number of dead pixels (per ROC): 0 0 0 1 1 0 0 0 0 0 0 0 0 0 0 1
[07:37:33.534] <TB0> INFO: number of red-efficiency pixels: 1194 1113 1090 2425 2980 3478 3371 3669 3371 3339 3669 2811 2770 1178 920 1058
[07:37:33.535] <TB0> INFO: number of X-ray hits detected: 372389 372127 339583 563802 663466 760874 739445 789769 713131 719887 697888 601315 541069 309276 333117 313991
[07:37:33.535] <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
[07:37:33.535] <TB0> INFO: number of Vcal hits detected: 206430 206611 205711 203111 199959 196862 197067 196804 199007 197827 195655 201495 202086 205596 206916 206665
[07:37:33.535] <TB0> INFO: Vcal hit fiducial efficiency (%): 99.3 99.4 99.1 98.0 96.6 95.4 95.5 95.1 96.1 95.8 94.7 97.3 97.5 99.0 99.5 99.4
[07:37:33.535] <TB0> INFO: Vcal hit overall efficiency (%): 99.2 99.3 98.9 97.6 96.1 94.6 94.7 94.6 95.7 95.1 94.1 96.9 97.2 98.8 99.5 99.4
[07:37:33.535] <TB0> INFO: X-ray hit rate [MHz/cm2]: 109.1 109.1 99.5 165.3 194.5 223.0 216.7 231.5 209.0 211.0 204.6 176.2 158.6 90.7 97.6 92.0
[07:37:33.535] <TB0> INFO: PixTestHighRate::doXPixelAlive() done
[07:37:33.572] <TB0> INFO: PixTest:: pg_setup set to default.
[07:37:33.614] <TB0> INFO: enter test to run
[07:37:33.614] <TB0> INFO: test: no parameter change
[07:37:34.257] <TB0> QUIET: Connection to board 147 closed.
[07:37:34.340] <TB0> INFO: pXar: this is the end, my friend
MoReWeb-v1.0.0-5-g82d9ff6 on branch psi46master