Test Date: 2015-08-11 16:58
Analysis date: 2016-05-25 22:59
Logfile
commander_PixelAlive.log
[14:58:52.551] <TB0> INFO: *** Welcome to pxar ***
[14:58:52.551] <TB0> INFO: *** Today: 2015/08/11
[14:58:52.551] <TB0> INFO: readRocDacs: /usr/local/data/M2045_XrayQualification_2015-08-11_16h58m_1439305117//000_PixelAlive_p17//dacParameters35_C0.dat .. /usr/local/data/M2045_XrayQualification_2015-08-11_16h58m_1439305117//000_PixelAlive_p17//dacParameters35_C15.dat
[14:58:52.551] <TB0> INFO: readTbmDacs: /usr/local/data/M2045_XrayQualification_2015-08-11_16h58m_1439305117//000_PixelAlive_p17//tbmParameters_C0a.dat .. /usr/local/data/M2045_XrayQualification_2015-08-11_16h58m_1439305117//000_PixelAlive_p17//tbmParameters_C0b.dat
[14:58:52.551] <TB0> INFO: readMaskFile: /usr/local/data/M2045_XrayQualification_2015-08-11_16h58m_1439305117//000_PixelAlive_p17//defaultMaskFile.dat
[14:58:52.551] <TB0> INFO: readTrimFile: /usr/local/data/M2045_XrayQualification_2015-08-11_16h58m_1439305117//000_PixelAlive_p17//trimParameters35_C0.dat .. /usr/local/data/M2045_XrayQualification_2015-08-11_16h58m_1439305117//000_PixelAlive_p17//trimParameters35_C15.dat
[14:58:52.617] <TB0> INFO: clk: 4
[14:58:52.617] <TB0> INFO: ctr: 4
[14:58:52.617] <TB0> INFO: sda: 19
[14:58:52.617] <TB0> INFO: tin: 9
[14:58:52.617] <TB0> INFO: level: 15
[14:58:52.617] <TB0> INFO: triggerdelay: 0
[14:58:52.617] <TB0> QUIET: Instanciating API for pxar v2.2.5+88~g694c14c
[14:58:52.617] <TB0> INFO: Log level: INFO
[14:58:53.630] <TB0> INFO: Found DTB DTB_WWXTQT
[14:58:56.641] <TB0> QUIET: Connection to board DTB_WWXTQT opened.
[14:58:56.644] <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:
------------------------------------------------------
[14:58:56.647] <TB0> INFO: RPC call hashes of host and DTB match: 447413373
[14:58:58.160] <TB0> INFO: DUT info:
[14:58:58.160] <TB0> INFO: The DUT currently contains the following objects:
[14:58:58.160] <TB0> INFO: 2 TBM Cores tbm09c (2 ON)
[14:58:58.160] <TB0> INFO: TBM Core alpha (0): 7 registers set
[14:58:58.160] <TB0> INFO: TBM Core beta (1): 7 registers set
[14:58:58.160] <TB0> INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[14:58:58.160] <TB0> INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[14:58:58.160] <TB0> INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[14:58:58.160] <TB0> INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[14:58:58.160] <TB0> INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[14:58:58.160] <TB0> INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[14:58:58.160] <TB0> INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[14:58:58.160] <TB0> INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[14:58:58.160] <TB0> INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[14:58:58.160] <TB0> INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[14:58:58.160] <TB0> INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[14:58:58.160] <TB0> INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[14:58:58.160] <TB0> INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[14:58:58.160] <TB0> INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[14:58:58.160] <TB0> INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[14:58:58.160] <TB0> INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[14:58:58.160] <TB0> INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[14:58:58.561] <TB0> INFO: enter 'restricted' command line mode
[14:58:58.561] <TB0> INFO: enter test to run
[14:58:58.561] <TB0> INFO: test: PixelAlive no parameter change
[14:58:58.561] <TB0> INFO: running: pixelalive
[14:58:58.565] <TB0> INFO: ######################################################################
[14:58:58.565] <TB0> INFO: PixTestAlive::doTest()
[14:58:58.565] <TB0> INFO: ######################################################################
[14:58:58.567] <TB0> INFO: ----------------------------------------------------------------------
[14:58:58.567] <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)
[14:58:58.567] <TB0> INFO: ----------------------------------------------------------------------
[14:59:01.894] <TB0> INFO: Test took 3326ms.
[14:59:01.911] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[14:59:02.163] <TB0> INFO: PixTestAlive::aliveTest() done
[14:59:02.163] <TB0> INFO: number of dead pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[14:59:02.165] <TB0> INFO: ----------------------------------------------------------------------
[14:59:02.165] <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)
[14:59:02.165] <TB0> INFO: ----------------------------------------------------------------------
[14:59:04.977] <TB0> INFO: Test took 2811ms.
[14:59:04.980] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[14:59:04.980] <TB0> INFO: mask vs. old pixelAlive PixelAlive_C0_V0 .. PixelAlive_C15_V0
[14:59:05.211] <TB0> INFO: PixTestAlive::maskTest() done
[14:59:05.211] <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
[14:59:05.213] <TB0> INFO: ----------------------------------------------------------------------
[14:59:05.213] <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)
[14:59:05.213] <TB0> INFO: ----------------------------------------------------------------------
[14:59:08.832] <TB0> INFO: Test took 3617ms.
[14:59:08.857] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[14:59:09.088] <TB0> INFO: PixTestAlive::addressDecodingTest() done
[14:59:09.088] <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
[14:59:09.088] <TB0> INFO: PixTestAlive::doTest() done, duration: 10 seconds
[14:59:09.139] <TB0> INFO: enter test to run
[14:59:09.139] <TB0> INFO: test: no parameter change
[14:59:09.320] <TB0> QUIET: Connection to board 147 closed.
[14:59:09.401] <TB0> INFO: pXar: this is the end, my friend
commander_RetrimHotPixels.log
[15:04:19.320] <TB0> INFO: *** Welcome to pxar ***
[15:04:19.320] <TB0> INFO: *** Today: 2015/08/11
[15:04:19.320] <TB0> INFO: readRocDacs: /usr/local/data/M2045_XrayQualification_2015-08-11_16h58m_1439305117//002_RetrimHotPixels_150//dacParameters35_C0.dat .. /usr/local/data/M2045_XrayQualification_2015-08-11_16h58m_1439305117//002_RetrimHotPixels_150//dacParameters35_C15.dat
[15:04:19.321] <TB0> INFO: readTbmDacs: /usr/local/data/M2045_XrayQualification_2015-08-11_16h58m_1439305117//002_RetrimHotPixels_150//tbmParameters_C0a.dat .. /usr/local/data/M2045_XrayQualification_2015-08-11_16h58m_1439305117//002_RetrimHotPixels_150//tbmParameters_C0b.dat
[15:04:19.321] <TB0> INFO: readMaskFile: /usr/local/data/M2045_XrayQualification_2015-08-11_16h58m_1439305117//002_RetrimHotPixels_150//defaultMaskFile.dat
[15:04:19.321] <TB0> INFO: readTrimFile: /usr/local/data/M2045_XrayQualification_2015-08-11_16h58m_1439305117//002_RetrimHotPixels_150//trimParameters35_C0.dat .. /usr/local/data/M2045_XrayQualification_2015-08-11_16h58m_1439305117//002_RetrimHotPixels_150//trimParameters35_C15.dat
[15:04:19.386] <TB0> INFO: clk: 4
[15:04:19.386] <TB0> INFO: ctr: 4
[15:04:19.386] <TB0> INFO: sda: 19
[15:04:19.386] <TB0> INFO: tin: 9
[15:04:19.386] <TB0> INFO: level: 15
[15:04:19.386] <TB0> INFO: triggerdelay: 0
[15:04:19.386] <TB0> QUIET: Instanciating API for pxar v2.2.5+88~g694c14c
[15:04:19.386] <TB0> INFO: Log level: INFO
[15:04:20.395] <TB0> INFO: Found DTB DTB_WWXTQT
[15:04:23.406] <TB0> QUIET: Connection to board DTB_WWXTQT opened.
[15:04:23.409] <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:
------------------------------------------------------
[15:04:23.412] <TB0> INFO: RPC call hashes of host and DTB match: 447413373
[15:04:24.925] <TB0> INFO: DUT info:
[15:04:24.925] <TB0> INFO: The DUT currently contains the following objects:
[15:04:24.925] <TB0> INFO: 2 TBM Cores tbm09c (2 ON)
[15:04:24.925] <TB0> INFO: TBM Core alpha (0): 7 registers set
[15:04:24.925] <TB0> INFO: TBM Core beta (1): 7 registers set
[15:04:24.925] <TB0> INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[15:04:24.925] <TB0> INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[15:04:24.925] <TB0> INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[15:04:24.925] <TB0> INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[15:04:24.925] <TB0> INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[15:04:24.925] <TB0> INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[15:04:24.925] <TB0> INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[15:04:24.925] <TB0> INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[15:04:24.925] <TB0> INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[15:04:24.925] <TB0> INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[15:04:24.925] <TB0> INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[15:04:24.925] <TB0> INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[15:04:24.925] <TB0> INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[15:04:24.926] <TB0> INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[15:04:24.926] <TB0> INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[15:04:24.926] <TB0> INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[15:04:24.926] <TB0> INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[15:04:25.326] <TB0> INFO: enter 'restricted' command line mode
[15:04:25.326] <TB0> INFO: enter test to run
[15:04:25.326] <TB0> INFO: test: delay setting parameters: ->5<-
[15:04:25.327] <TB0> INFO: delay test by 5 seconds...
[15:04:30.327] <TB0> INFO: enter test to run
[15:04:30.327] <TB0> INFO: test: highrate no parameter change
[15:04:30.327] <TB0> INFO: running: highrate
[15:04:30.327] <TB0> INFO: readGainPedestalParameters /usr/local/data/M2045_XrayQualification_2015-08-11_16h58m_1439305117//002_RetrimHotPixels_150//phCalibrationFitErr35_C0.dat .. /usr/local/data/M2045_XrayQualification_2015-08-11_16h58m_1439305117//002_RetrimHotPixels_150//phCalibrationFitErr35_C15.dat
[15:04:30.497] <TB0> INFO: ----------------------------------------------------------------------
[15:04:30.497] <TB0> INFO: PixTest::trimHotPixels() running for 1 seconds with 100 kHz trigger rate
[15:04:30.497] <TB0> INFO: ----------------------------------------------------------------------
[15:04:30.497] <TB0> INFO: THR = 150, corresponding to ~ 400 MHz/cm2
[15:04:30.497] <TB0> INFO: edge/corner pixel THR is adjusted
[15:04:30.497] <TB0> INFO: PixTestHighRate::trimHotPixels: step 0...
[15:04:31.459] <TB0> INFO: Collecting data for 1 seconds...
[15:04:32.461] <TB0> INFO: Done with hot pixel readout
[15:04:37.424] <TB0> INFO: PixTest:: pg_setup set to default.
[15:04:37.426] <TB0> INFO: 2 hot pixels found in step 0
[15:04:38.390] <TB0> INFO: Collecting data for 1 seconds...
[15:04:39.392] <TB0> INFO: Done with hot pixel readout
[15:04:45.281] <TB0> INFO: PixTest:: pg_setup set to default.
[15:04:45.282] <TB0> INFO: 1 hot pixels found in step 1
[15:04:46.244] <TB0> INFO: Collecting data for 1 seconds...
[15:04:47.246] <TB0> INFO: Done with hot pixel readout
[15:04:53.152] <TB0> INFO: PixTest:: pg_setup set to default.
[15:04:53.153] <TB0> INFO: 0 hot pixels found in step 2
[15:04:53.161] <TB0> INFO: 0 hot pixels could not be trimmed and have been masked.
[15:04:53.191] <TB0> INFO: PixTest::trimHotPixels() done
[15:04:53.192] <TB0> INFO: write trim parameters into /usr/local/data/M2045_XrayQualification_2015-08-11_16h58m_1439305117//002_RetrimHotPixels_150//trimParameters35_C0.dat
[15:04:53.199] <TB0> INFO: write trim parameters into /usr/local/data/M2045_XrayQualification_2015-08-11_16h58m_1439305117//002_RetrimHotPixels_150//trimParameters35_C1.dat
[15:04:53.206] <TB0> INFO: write trim parameters into /usr/local/data/M2045_XrayQualification_2015-08-11_16h58m_1439305117//002_RetrimHotPixels_150//trimParameters35_C2.dat
[15:04:53.213] <TB0> INFO: write trim parameters into /usr/local/data/M2045_XrayQualification_2015-08-11_16h58m_1439305117//002_RetrimHotPixels_150//trimParameters35_C3.dat
[15:04:53.220] <TB0> INFO: write trim parameters into /usr/local/data/M2045_XrayQualification_2015-08-11_16h58m_1439305117//002_RetrimHotPixels_150//trimParameters35_C4.dat
[15:04:53.227] <TB0> INFO: write trim parameters into /usr/local/data/M2045_XrayQualification_2015-08-11_16h58m_1439305117//002_RetrimHotPixels_150//trimParameters35_C5.dat
[15:04:53.234] <TB0> INFO: write trim parameters into /usr/local/data/M2045_XrayQualification_2015-08-11_16h58m_1439305117//002_RetrimHotPixels_150//trimParameters35_C6.dat
[15:04:53.241] <TB0> INFO: write trim parameters into /usr/local/data/M2045_XrayQualification_2015-08-11_16h58m_1439305117//002_RetrimHotPixels_150//trimParameters35_C7.dat
[15:04:53.248] <TB0> INFO: write trim parameters into /usr/local/data/M2045_XrayQualification_2015-08-11_16h58m_1439305117//002_RetrimHotPixels_150//trimParameters35_C8.dat
[15:04:53.255] <TB0> INFO: write trim parameters into /usr/local/data/M2045_XrayQualification_2015-08-11_16h58m_1439305117//002_RetrimHotPixels_150//trimParameters35_C9.dat
[15:04:53.262] <TB0> INFO: write trim parameters into /usr/local/data/M2045_XrayQualification_2015-08-11_16h58m_1439305117//002_RetrimHotPixels_150//trimParameters35_C10.dat
[15:04:53.269] <TB0> INFO: write trim parameters into /usr/local/data/M2045_XrayQualification_2015-08-11_16h58m_1439305117//002_RetrimHotPixels_150//trimParameters35_C11.dat
[15:04:53.276] <TB0> INFO: write trim parameters into /usr/local/data/M2045_XrayQualification_2015-08-11_16h58m_1439305117//002_RetrimHotPixels_150//trimParameters35_C12.dat
[15:04:53.283] <TB0> INFO: write trim parameters into /usr/local/data/M2045_XrayQualification_2015-08-11_16h58m_1439305117//002_RetrimHotPixels_150//trimParameters35_C13.dat
[15:04:53.290] <TB0> INFO: write trim parameters into /usr/local/data/M2045_XrayQualification_2015-08-11_16h58m_1439305117//002_RetrimHotPixels_150//trimParameters35_C14.dat
[15:04:53.297] <TB0> INFO: write trim parameters into /usr/local/data/M2045_XrayQualification_2015-08-11_16h58m_1439305117//002_RetrimHotPixels_150//trimParameters35_C15.dat
[15:04:53.304] <TB0> INFO: write masked pixels into /usr/local/data/M2045_XrayQualification_2015-08-11_16h58m_1439305117//002_RetrimHotPixels_150//defaultMaskFile.dat
[15:04:53.337] <TB0> INFO: enter test to run
[15:04:53.337] <TB0> INFO: test: no parameter change
[15:04:53.521] <TB0> QUIET: Connection to board 147 closed.
[15:04:53.600] <TB0> INFO: pXar: this is the end, my friend
commander_HRData.log
[15:06:11.656] <TB0> INFO: *** Welcome to pxar ***
[15:06:11.656] <TB0> INFO: *** Today: 2015/08/11
[15:06:11.656] <TB0> INFO: readRocDacs: /usr/local/data/M2045_XrayQualification_2015-08-11_16h58m_1439305117//003_HRData_50//dacParameters35_C0.dat .. /usr/local/data/M2045_XrayQualification_2015-08-11_16h58m_1439305117//003_HRData_50//dacParameters35_C15.dat
[15:06:11.656] <TB0> INFO: readTbmDacs: /usr/local/data/M2045_XrayQualification_2015-08-11_16h58m_1439305117//003_HRData_50//tbmParameters_C0a.dat .. /usr/local/data/M2045_XrayQualification_2015-08-11_16h58m_1439305117//003_HRData_50//tbmParameters_C0b.dat
[15:06:11.657] <TB0> INFO: readMaskFile: /usr/local/data/M2045_XrayQualification_2015-08-11_16h58m_1439305117//003_HRData_50//defaultMaskFile.dat
[15:06:11.657] <TB0> INFO: readTrimFile: /usr/local/data/M2045_XrayQualification_2015-08-11_16h58m_1439305117//003_HRData_50//trimParameters35_C0.dat .. /usr/local/data/M2045_XrayQualification_2015-08-11_16h58m_1439305117//003_HRData_50//trimParameters35_C15.dat
[15:06:11.722] <TB0> INFO: clk: 4
[15:06:11.722] <TB0> INFO: ctr: 4
[15:06:11.722] <TB0> INFO: sda: 19
[15:06:11.722] <TB0> INFO: tin: 9
[15:06:11.722] <TB0> INFO: level: 15
[15:06:11.722] <TB0> INFO: triggerdelay: 0
[15:06:11.722] <TB0> QUIET: Instanciating API for pxar v2.2.5+88~g694c14c
[15:06:11.722] <TB0> INFO: Log level: INFO
[15:06:12.732] <TB0> INFO: Found DTB DTB_WWXTQT
[15:06:15.742] <TB0> QUIET: Connection to board DTB_WWXTQT opened.
[15:06:15.746] <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:
------------------------------------------------------
[15:06:15.748] <TB0> INFO: RPC call hashes of host and DTB match: 447413373
[15:06:17.263] <TB0> INFO: DUT info:
[15:06:17.263] <TB0> INFO: The DUT currently contains the following objects:
[15:06:17.263] <TB0> INFO: 2 TBM Cores tbm09c (2 ON)
[15:06:17.263] <TB0> INFO: TBM Core alpha (0): 7 registers set
[15:06:17.263] <TB0> INFO: TBM Core beta (1): 7 registers set
[15:06:17.263] <TB0> INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[15:06:17.263] <TB0> INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[15:06:17.263] <TB0> INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[15:06:17.263] <TB0> INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[15:06:17.263] <TB0> INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[15:06:17.263] <TB0> INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[15:06:17.263] <TB0> INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[15:06:17.263] <TB0> INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[15:06:17.263] <TB0> INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[15:06:17.263] <TB0> INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[15:06:17.263] <TB0> INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[15:06:17.263] <TB0> INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[15:06:17.263] <TB0> INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[15:06:17.263] <TB0> INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[15:06:17.263] <TB0> INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[15:06:17.263] <TB0> INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[15:06:17.263] <TB0> INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[15:06:17.664] <TB0> INFO: enter 'restricted' command line mode
[15:06:17.664] <TB0> INFO: enter test to run
[15:06:17.664] <TB0> INFO: test: delay setting parameters: ->5<-
[15:06:17.664] <TB0> INFO: delay test by 5 seconds...
[15:06:22.664] <TB0> INFO: enter test to run
[15:06:22.664] <TB0> INFO: test: Xray no parameter change
[15:06:22.664] <TB0> INFO: running: xray
[15:06:22.665] <TB0> INFO: readGainPedestalParameters /usr/local/data/M2045_XrayQualification_2015-08-11_16h58m_1439305117//003_HRData_50//phCalibrationFitErr35_C0.dat .. /usr/local/data/M2045_XrayQualification_2015-08-11_16h58m_1439305117//003_HRData_50//phCalibrationFitErr35_C15.dat
[15:06:22.803] <TB0> INFO: ######################################################################
[15:06:22.803] <TB0> INFO: PixTestXray::doTest()
[15:06:22.803] <TB0> INFO: ######################################################################
[15:06:22.803] <TB0> INFO: ----------------------------------------------------------------------
[15:06:22.803] <TB0> INFO: PixTestXray::doPhRun() fParRunSeconds = 100
[15:06:22.803] <TB0> INFO: ----------------------------------------------------------------------
[15:06:23.774] <TB0> INFO: PixTestXray::doPhRun start TriggerLoop with trigger frequency 100 kHz, period 405 and duration 100 seconds, fEventsMax = 10000000
[15:06:30.569] <TB0> INFO: run duration 6 seconds, buffer almost full (81%), pausing triggers.
[15:06:56.870] <TB0> INFO: Resuming triggers.
[15:07:03.672] <TB0> INFO: run duration 13 seconds, buffer almost full (81%), pausing triggers.
[15:07:05.964] <TB0> ERROR: <datapipe.cc/CheckEventValidity:L437> Channel 2 Number of ROCs (0) != Token Chain Length (4)

[15:07:05.964] <TB0> ERROR: <datapipe.cc/CheckEventValidity:L437> Channel 3 Number of ROCs (0) != Token Chain Length (4)

[15:07:05.964] <TB0> WARNING: Channel 2 ROC 0: Readback start marker after 15 readouts!

[15:07:05.964] <TB0> WARNING: Channel 2 ROC 1: Readback start marker after 15 readouts!

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

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

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

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

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

[15:07:07.100] <TB0> ERROR: <datapipe.cc/CheckEventValidity:L437> Channel 3 Number of ROCs (3) != Token Chain Length (4)

[15:07:07.100] <TB0> WARNING: Channel 2 ROC 2: Readback start marker after 15 readouts!

[15:07:07.169] <TB0> ERROR: <datapipe.cc/CheckEventValidity:L437> Channel 3 Number of ROCs (6) != Token Chain Length (4)

[15:07:24.380] <TB0> INFO: Resuming triggers.
[15:07:31.182] <TB0> INFO: run duration 20 seconds, buffer almost full (81%), pausing triggers.
[15:07:35.167] <TB0> ERROR: <datapipe.cc/CheckEventValidity:L437> Channel 2 Number of ROCs (1) != Token Chain Length (4)

[15:07:35.167] <TB0> ERROR: <datapipe.cc/CheckEventValidity:L437> Channel 3 Number of ROCs (2) != Token Chain Length (4)

[15:07:35.167] <TB0> WARNING: Channel 2 ROC 1: Readback start marker after 15 readouts!

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

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

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

[15:07:38.454] <TB0> ERROR: <datapipe.cc/CheckEventValidity:L437> Channel 3 Number of ROCs (3) != Token Chain Length (4)

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

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

[15:07:39.779] <TB0> ERROR: <datapipe.cc/CheckEventValidity:L437> Channel 3 Number of ROCs (3) != Token Chain Length (4)

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

[15:07:51.773] <TB0> INFO: Resuming triggers.
[15:07:58.572] <TB0> INFO: run duration 27 seconds, buffer almost full (81%), pausing triggers.
[15:08:07.470] <TB0> ERROR: <datapipe.cc/CheckEventValidity:L437> Channel 3 Number of ROCs (3) != Token Chain Length (4)

[15:08:08.934] <TB0> ERROR: <datapipe.cc/CheckEventValidity:L437> Channel 3 Number of ROCs (3) != Token Chain Length (4)

[15:08:11.837] <TB0> ERROR: <datapipe.cc/CheckEventValidity:L437> Channel 2 Number of ROCs (1) != Token Chain Length (4)

[15:08:11.837] <TB0> ERROR: <datapipe.cc/CheckEventValidity:L437> Channel 3 Number of ROCs (2) != Token Chain Length (4)

[15:08:11.837] <TB0> WARNING: Channel 2 ROC 1: Readback start marker after 15 readouts!

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

[15:08:11.837] <TB0> WARNING: Channel 3 ROC 3: Readback start marker after 15 readouts!

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

[15:08:13.253] <TB0> ERROR: <datapipe.cc/CheckEventValidity:L437> Channel 3 Number of ROCs (2) != Token Chain Length (4)

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

[15:08:13.253] <TB0> WARNING: Channel 3 ROC 3: Readback start marker after 15 readouts!

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

[15:08:14.841] <TB0> ERROR: <datapipe.cc/CheckEventValidity:L437> Channel 3 Number of ROCs (2) != Token Chain Length (4)

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

[15:08:14.841] <TB0> WARNING: Channel 3 ROC 3: Readback start marker after 15 readouts!

[15:08:21.442] <TB0> INFO: Resuming triggers.
[15:08:28.239] <TB0> INFO: run duration 33 seconds, buffer almost full (81%), pausing triggers.
[15:08:42.347] <TB0> ERROR: <datapipe.cc/CheckEventValidity:L437> Channel 2 Number of ROCs (3) != Token Chain Length (4)

[15:08:42.347] <TB0> ERROR: <datapipe.cc/CheckEventValidity:L437> Channel 3 Number of ROCs (2) != Token Chain Length (4)

[15:08:42.348] <TB0> WARNING: Channel 3 ROC 3: Readback start marker after 31 readouts!

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

[15:08:43.704] <TB0> WARNING: Channel 3 ROC 1: Readback start marker after 6 readouts!

[15:08:43.704] <TB0> ERROR: <datapipe.cc/CheckEventValidity:L437> Channel 3 Number of ROCs (2) != Token Chain Length (4)

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

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

[15:08:43.705] <TB0> WARNING: Channel 3 ROC 3: Readback start marker after 15 readouts!

[15:08:49.147] <TB0> INFO: Resuming triggers.
[15:08:55.945] <TB0> INFO: run duration 40 seconds, buffer almost full (81%), pausing triggers.
[15:09:02.161] <TB0> ERROR: <datapipe.cc/CheckEventValidity:L437> Channel 3 Number of ROCs (3) != Token Chain Length (4)

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

[15:09:03.484] <TB0> ERROR: <datapipe.cc/CheckEventValidity:L437> Channel 3 Number of ROCs (2) != Token Chain Length (4)

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

[15:09:03.485] <TB0> WARNING: Channel 3 ROC 3: Readback start marker after 15 readouts!

[15:09:07.099] <TB0> WARNING: Channel 2 ROC 0: Readback start marker after 14 readouts!

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

[15:09:07.099] <TB0> ERROR: <datapipe.cc/CheckEventValidity:L437> Channel 3 Number of ROCs (2) != Token Chain Length (4)

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

[15:09:07.099] <TB0> WARNING: Channel 3 ROC 3: Readback start marker after 15 readouts!

[15:09:07.099] <TB0> WARNING: Channel 2 ROC 2: Readback start marker after 31 readouts!

[15:09:09.332] <TB0> ERROR: <datapipe.cc/CheckEventID:L420> Channel 2 Event ID mismatch: local ID (185) != TBM ID (186)

[15:09:09.332] <TB0> ERROR: <datapipe.cc/CheckEventID:L420> Channel 3 Event ID mismatch: local ID (185) != TBM ID (186)

[15:09:09.333] <TB0> WARNING: Channel 2 ROC 2: Readback start marker after 15 readouts!

[15:09:09.333] <TB0> WARNING: Channel 2 ROC 0: Readback start marker after 15 readouts!

[15:09:09.333] <TB0> WARNING: Channel 2 ROC 1: Readback start marker after 15 readouts!

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

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

[15:09:09.333] <TB0> WARNING: Channel 3 ROC 1: Readback start marker after 15 readouts!

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

[15:09:09.333] <TB0> WARNING: Channel 3 ROC 3: Readback start marker after 15 readouts!

[15:09:17.879] <TB0> INFO: Resuming triggers.
[15:09:24.677] <TB0> INFO: run duration 47 seconds, buffer almost full (81%), pausing triggers.
[15:09:26.527] <TB0> ERROR: <datapipe.cc/CheckEventValidity:L437> Channel 2 Number of ROCs (2) != Token Chain Length (4)

[15:09:26.527] <TB0> ERROR: <datapipe.cc/CheckEventValidity:L437> Channel 3 Number of ROCs (3) != Token Chain Length (4)

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

[15:09:27.934] <TB0> ERROR: <datapipe.cc/CheckEventValidity:L437> Channel 3 Number of ROCs (2) != Token Chain Length (4)

[15:09:27.935] <TB0> WARNING: Channel 3 ROC 3: Readback start marker after 15 readouts!

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

[15:09:32.013] <TB0> ERROR: <datapipe.cc/CheckEventValidity:L437> Channel 3 Number of ROCs (3) != Token Chain Length (4)

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

[15:09:33.190] <TB0> ERROR: <datapipe.cc/CheckEventID:L420> Channel 2 Event ID mismatch: local ID (240) != TBM ID (227)

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

[15:09:33.190] <TB0> ERROR: <datapipe.cc/CheckEventID:L420> Channel 3 Event ID mismatch: local ID (240) != TBM ID (245)

[15:09:33.190] <TB0> ERROR: <datapipe.cc/CheckEventID:L420> Channel 2 Event ID mismatch: local ID (228) != TBM ID (241)

[15:09:33.190] <TB0> ERROR: <datapipe.cc/CheckEventID:L420> Channel 3 Event ID mismatch: local ID (246) != TBM ID (241)

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

[15:09:38.555] <TB0> ERROR: <datapipe.cc/CheckEventValidity:L437> Channel 2 Number of ROCs (0) != Token Chain Length (4)

[15:09:45.933] <TB0> INFO: Resuming triggers.
[15:09:52.728] <TB0> INFO: run duration 54 seconds, buffer almost full (81%), pausing triggers.
[15:09:57.015] <TB0> ERROR: <datapipe.cc/CheckEventValidity:L437> Channel 2 Number of ROCs (3) != Token Chain Length (4)

[15:09:57.015] <TB0> WARNING: Channel 3 ROC 3: Readback start marker after 9 readouts!

[15:09:57.015] <TB0> WARNING: Channel 3 ROC 3: Readback start marker after 7 readouts!

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

[15:10:01.900] <TB0> ERROR: <datapipe.cc/CheckEventValidity:L437> Channel 3 Number of ROCs (3) != Token Chain Length (4)

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

[15:10:16.629] <TB0> INFO: Resuming triggers.
[15:10:23.425] <TB0> INFO: run duration 61 seconds, buffer almost full (81%), pausing triggers.
[15:10:48.116] <TB0> INFO: Resuming triggers.
[15:10:54.917] <TB0> INFO: run duration 67 seconds, buffer almost full (81%), pausing triggers.
[15:11:20.733] <TB0> INFO: Resuming triggers.
[15:11:27.532] <TB0> INFO: run duration 74 seconds, buffer almost full (81%), pausing triggers.
[15:11:28.447] <TB0> ERROR: <datapipe.cc/CheckEventValidity:L437> Channel 2 Number of ROCs (3) != Token Chain Length (4)

[15:11:28.447] <TB0> ERROR: <datapipe.cc/CheckEventValidity:L437> Channel 3 Number of ROCs (2) != Token Chain Length (4)

[15:11:28.447] <TB0> WARNING: Channel 3 ROC 3: Readback start marker after 31 readouts!

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

[15:11:32.732] <TB0> ERROR: <datapipe.cc/CheckEventValidity:L437> Channel 3 Number of ROCs (3) != Token Chain Length (4)

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

[15:11:48.494] <TB0> INFO: Resuming triggers.
[15:11:55.289] <TB0> INFO: run duration 81 seconds, buffer almost full (81%), pausing triggers.
[15:11:55.446] <TB0> ERROR: <datapipe.cc/CheckEventValidity:L437> Channel 2 Number of ROCs (2) != Token Chain Length (4)

[15:11:55.446] <TB0> ERROR: <datapipe.cc/CheckEventValidity:L437> Channel 3 Number of ROCs (2) != Token Chain Length (4)

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

[15:11:55.446] <TB0> WARNING: Channel 3 ROC 3: Readback start marker after 15 readouts!

[15:11:58.642] <TB0> ERROR: <datapipe.cc/CheckEventID:L420> Channel 2 Event ID mismatch: local ID (172) != TBM ID (173)

[15:11:58.642] <TB0> WARNING: Channel 2 ROC 2: Readback start marker after 15 readouts!

[15:11:58.642] <TB0> ERROR: <datapipe.cc/CheckEventID:L420> Channel 3 Event ID mismatch: local ID (172) != TBM ID (173)

[15:11:58.642] <TB0> WARNING: Channel 2 ROC 0: Readback start marker after 15 readouts!

[15:11:58.642] <TB0> WARNING: Channel 2 ROC 1: Readback start marker after 15 readouts!

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

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

[15:11:58.642] <TB0> WARNING: Channel 3 ROC 1: Readback start marker after 15 readouts!

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

[15:11:58.642] <TB0> WARNING: Channel 3 ROC 3: Readback start marker after 15 readouts!

[15:12:18.717] <TB0> INFO: Resuming triggers.
[15:12:25.514] <TB0> INFO: run duration 88 seconds, buffer almost full (81%), pausing triggers.
[15:12:47.199] <TB0> INFO: Resuming triggers.
[15:12:53.996] <TB0> INFO: run duration 95 seconds, buffer almost full (81%), pausing triggers.
[15:13:17.501] <TB0> INFO: Resuming triggers.
[15:13:22.461] <TB0> INFO: data taking finished, elapsed time: 100 seconds.
[15:13:22.947] <TB0> ERROR: <datapipe.cc/CheckEventValidity:L437> Channel 2 Number of ROCs (3) != Token Chain Length (4)

[15:13:22.947] <TB0> ERROR: <datapipe.cc/CheckEventValidity:L437> Channel 3 Number of ROCs (2) != Token Chain Length (4)

[15:13:22.947] <TB0> WARNING: Channel 3 ROC 3: Readback start marker after 15 readouts!

[15:13:46.734] <TB0> INFO: PixTest:: pg_setup set to default.
[15:13:46.735] <TB0> INFO: PixTestXray::doPhRun() done
[15:13:46.735] <TB0> INFO: PixTestXray::doTest() done
[15:13:46.934] <TB0> INFO: enter test to run
[15:13:46.934] <TB0> INFO: test: no parameter change
[15:13:47.134] <TB0> QUIET: Connection to board 147 closed.
[15:13:47.213] <TB0> INFO: pXar: this is the end, my friend
commander_HRData.log
[15:14:53.178] <TB0> INFO: *** Welcome to pxar ***
[15:14:53.178] <TB0> INFO: *** Today: 2015/08/11
[15:14:53.178] <TB0> INFO: readRocDacs: /usr/local/data/M2045_XrayQualification_2015-08-11_16h58m_1439305117//004_HRData_150//dacParameters35_C0.dat .. /usr/local/data/M2045_XrayQualification_2015-08-11_16h58m_1439305117//004_HRData_150//dacParameters35_C15.dat
[15:14:53.179] <TB0> INFO: readTbmDacs: /usr/local/data/M2045_XrayQualification_2015-08-11_16h58m_1439305117//004_HRData_150//tbmParameters_C0a.dat .. /usr/local/data/M2045_XrayQualification_2015-08-11_16h58m_1439305117//004_HRData_150//tbmParameters_C0b.dat
[15:14:53.179] <TB0> INFO: readMaskFile: /usr/local/data/M2045_XrayQualification_2015-08-11_16h58m_1439305117//004_HRData_150//defaultMaskFile.dat
[15:14:53.179] <TB0> INFO: readTrimFile: /usr/local/data/M2045_XrayQualification_2015-08-11_16h58m_1439305117//004_HRData_150//trimParameters35_C0.dat .. /usr/local/data/M2045_XrayQualification_2015-08-11_16h58m_1439305117//004_HRData_150//trimParameters35_C15.dat
[15:14:53.245] <TB0> INFO: clk: 4
[15:14:53.245] <TB0> INFO: ctr: 4
[15:14:53.245] <TB0> INFO: sda: 19
[15:14:53.245] <TB0> INFO: tin: 9
[15:14:53.245] <TB0> INFO: level: 15
[15:14:53.245] <TB0> INFO: triggerdelay: 0
[15:14:53.245] <TB0> QUIET: Instanciating API for pxar v2.2.5+88~g694c14c
[15:14:53.245] <TB0> INFO: Log level: INFO
[15:14:54.255] <TB0> INFO: Found DTB DTB_WWXTQT
[15:14:57.266] <TB0> QUIET: Connection to board DTB_WWXTQT opened.
[15:14:57.269] <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:
------------------------------------------------------
[15:14:57.272] <TB0> INFO: RPC call hashes of host and DTB match: 447413373
[15:14:58.785] <TB0> INFO: DUT info:
[15:14:58.785] <TB0> INFO: The DUT currently contains the following objects:
[15:14:58.785] <TB0> INFO: 2 TBM Cores tbm09c (2 ON)
[15:14:58.785] <TB0> INFO: TBM Core alpha (0): 7 registers set
[15:14:58.785] <TB0> INFO: TBM Core beta (1): 7 registers set
[15:14:58.785] <TB0> INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[15:14:58.785] <TB0> INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[15:14:58.785] <TB0> INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[15:14:58.785] <TB0> INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[15:14:58.785] <TB0> INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[15:14:58.785] <TB0> INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[15:14:58.785] <TB0> INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[15:14:58.785] <TB0> INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[15:14:58.785] <TB0> INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[15:14:58.785] <TB0> INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[15:14:58.785] <TB0> INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[15:14:58.785] <TB0> INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[15:14:58.785] <TB0> INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[15:14:58.785] <TB0> INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[15:14:58.785] <TB0> INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[15:14:58.785] <TB0> INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[15:14:58.785] <TB0> INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[15:14:59.196] <TB0> INFO: enter 'restricted' command line mode
[15:14:59.196] <TB0> INFO: enter test to run
[15:14:59.196] <TB0> INFO: test: delay setting parameters: ->5<-
[15:14:59.196] <TB0> INFO: delay test by 5 seconds...
[15:15:04.196] <TB0> INFO: enter test to run
[15:15:04.197] <TB0> INFO: test: Xray no parameter change
[15:15:04.197] <TB0> INFO: running: xray
[15:15:04.197] <TB0> INFO: readGainPedestalParameters /usr/local/data/M2045_XrayQualification_2015-08-11_16h58m_1439305117//004_HRData_150//phCalibrationFitErr35_C0.dat .. /usr/local/data/M2045_XrayQualification_2015-08-11_16h58m_1439305117//004_HRData_150//phCalibrationFitErr35_C15.dat
[15:15:04.340] <TB0> INFO: ######################################################################
[15:15:04.340] <TB0> INFO: PixTestXray::doTest()
[15:15:04.340] <TB0> INFO: ######################################################################
[15:15:04.340] <TB0> INFO: ----------------------------------------------------------------------
[15:15:04.340] <TB0> INFO: PixTestXray::doPhRun() fParRunSeconds = 100
[15:15:04.340] <TB0> INFO: ----------------------------------------------------------------------
[15:15:05.313] <TB0> INFO: PixTestXray::doPhRun start TriggerLoop with trigger frequency 100 kHz, period 405 and duration 100 seconds, fEventsMax = 10000000
[15:15:09.091] <TB0> INFO: run duration 3 seconds, buffer almost full (81%), pausing triggers.
[15:15:36.545] <TB0> INFO: Resuming triggers.
[15:15:40.336] <TB0> INFO: run duration 7 seconds, buffer almost full (81%), pausing triggers.
[15:15:43.879] <TB0> ERROR: <datapipe.cc/CheckEventValidity:L437> Channel 3 Number of ROCs (8) != Token Chain Length (4)

[15:15:52.388] <TB0> ERROR: <datapipe.cc/CheckEventValidity:L437> Channel 0 Number of ROCs (1) != Token Chain Length (4)

[15:15:52.388] <TB0> WARNING: Channel 0 ROC 2: Readback start marker after 31 readouts!

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

[15:15:54.237] <TB0> ERROR: <datapipe.cc/CheckEventValidity:L437> Channel 1 Number of ROCs (3) != Token Chain Length (4)

[15:15:54.746] <TB0> ERROR: <datapipe.cc/CheckEventValidity:L437> Channel 0 Number of ROCs (1) != Token Chain Length (4)

[15:15:54.746] <TB0> WARNING: Channel 0 ROC 2: Readback start marker after 15 readouts!

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

[15:16:02.542] <TB0> INFO: Resuming triggers.
[15:16:06.330] <TB0> INFO: run duration 11 seconds, buffer almost full (81%), pausing triggers.
[15:16:12.762] <TB0> ERROR: <datapipe.cc/CheckEventValidity:L437> Channel 1 Number of ROCs (3) != Token Chain Length (4)

[15:16:27.712] <TB0> INFO: Resuming triggers.
[15:16:31.499] <TB0> INFO: run duration 15 seconds, buffer almost full (81%), pausing triggers.
[15:16:37.702] <TB0> ERROR: <datapipe.cc/CheckEventID:L420> Channel 0 Event ID mismatch: local ID (83) != TBM ID (174)

[15:16:37.702] <TB0> ERROR: <datapipe.cc/CheckEventValidity:L437> Channel 0 Number of ROCs (2) != Token Chain Length (4)

[15:16:37.702] <TB0> ERROR: <datapipe.cc/CheckEventID:L420> Channel 1 Event ID mismatch: local ID (83) != TBM ID (84)

[15:16:37.702] <TB0> ERROR: <datapipe.cc/CheckEventID:L420> Channel 0 Event ID mismatch: local ID (175) != TBM ID (84)

[15:16:37.708] <TB0> WARNING: Channel 1 ROC 0: Readback start marker after 15 readouts!

[15:16:37.708] <TB0> WARNING: Channel 1 ROC 1: Readback start marker after 15 readouts!

[15:16:37.708] <TB0> WARNING: Channel 1 ROC 2: Readback start marker after 15 readouts!

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

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

[15:16:54.577] <TB0> INFO: Resuming triggers.
[15:16:58.363] <TB0> INFO: run duration 18 seconds, buffer almost full (81%), pausing triggers.
[15:16:59.809] <TB0> ERROR: <datapipe.cc/CheckEventValidity:L437> Channel 0 Number of ROCs (2) != Token Chain Length (4)

[15:16:59.809] <TB0> ERROR: <datapipe.cc/CheckEventValidity:L437> Channel 1 Number of ROCs (2) != Token Chain Length (4)

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

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

[15:17:04.485] <TB0> ERROR: <datapipe.cc/CheckEventValidity:L437> Channel 1 Number of ROCs (2) != Token Chain Length (4)

[15:17:04.485] <TB0> WARNING: Channel 1 ROC 3: Readback start marker after 15 readouts!

[15:17:22.117] <TB0> INFO: Resuming triggers.
[15:17:25.904] <TB0> INFO: run duration 22 seconds, buffer almost full (81%), pausing triggers.
[15:17:27.791] <TB0> ERROR: <datapipe.cc/CheckEventValidity:L437> Channel 0 Number of ROCs (3) != Token Chain Length (4)

[15:17:27.791] <TB0> ERROR: <datapipe.cc/CheckEventValidity:L437> Channel 1 Number of ROCs (2) != Token Chain Length (4)

[15:17:27.791] <TB0> WARNING: Channel 1 ROC 3: Readback start marker after 15 readouts!

[15:17:31.798] <TB0> ERROR: <datapipe.cc/CheckEventValidity:L437> Channel 0 Number of ROCs (2) != Token Chain Length (4)

[15:17:31.798] <TB0> ERROR: <datapipe.cc/CheckEventValidity:L437> Channel 1 Number of ROCs (2) != Token Chain Length (4)

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

[15:17:31.804] <TB0> WARNING: Channel 1 ROC 3: Readback start marker after 15 readouts!

[15:17:49.539] <TB0> INFO: Resuming triggers.
[15:17:53.324] <TB0> INFO: run duration 26 seconds, buffer almost full (81%), pausing triggers.
[15:17:55.245] <TB0> ERROR: <datapipe.cc/CheckEventValidity:L437> Channel 0 Number of ROCs (3) != Token Chain Length (4)

[15:17:55.245] <TB0> ERROR: <datapipe.cc/CheckEventValidity:L437> Channel 1 Number of ROCs (3) != Token Chain Length (4)

[15:17:59.546] <TB0> ERROR: <datapipe.cc/CheckEventValidity:L437> Channel 0 Number of ROCs (2) != Token Chain Length (4)

[15:17:59.546] <TB0> WARNING: Channel 1 ROC 1: Readback start marker after 11 readouts!

[15:17:59.546] <TB0> ERROR: <datapipe.cc/CheckEventValidity:L437> Channel 1 Number of ROCs (2) != Token Chain Length (4)

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

[15:17:59.546] <TB0> WARNING: Channel 1 ROC 2: Readback start marker after 15 readouts!

[15:17:59.546] <TB0> WARNING: Channel 1 ROC 3: Readback start marker after 15 readouts!

[15:18:08.111] <TB0> ERROR: <datapipe.cc/CheckEventValidity:L437> Channel 0 Number of ROCs (1) != Token Chain Length (4)

[15:18:08.111] <TB0> ERROR: <datapipe.cc/CheckEventValidity:L437> Channel 1 Number of ROCs (1) != Token Chain Length (4)

[15:18:08.111] <TB0> WARNING: Channel 0 ROC 2: Readback start marker after 15 readouts!

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

[15:18:08.111] <TB0> WARNING: Channel 1 ROC 2: Readback start marker after 15 readouts!

[15:18:08.111] <TB0> WARNING: Channel 1 ROC 3: Readback start marker after 15 readouts!

[15:18:20.460] <TB0> INFO: Resuming triggers.
[15:18:24.249] <TB0> INFO: run duration 30 seconds, buffer almost full (81%), pausing triggers.
[15:18:31.566] <TB0> ERROR: <datapipe.cc/CheckEventValidity:L437> Channel 0 Number of ROCs (2) != Token Chain Length (4)

[15:18:31.566] <TB0> ERROR: <datapipe.cc/CheckEventValidity:L437> Channel 1 Number of ROCs (2) != Token Chain Length (4)

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

[15:18:31.567] <TB0> WARNING: Channel 1 ROC 3: Readback start marker after 15 readouts!

[15:18:37.230] <TB0> ERROR: <datapipe.cc/CheckEventValidity:L437> Channel 0 Number of ROCs (0) != Token Chain Length (4)

[15:18:37.231] <TB0> ERROR: <datapipe.cc/CheckEventValidity:L437> Channel 1 Number of ROCs (1) != Token Chain Length (4)

[15:18:37.231] <TB0> WARNING: Channel 0 ROC 1: Readback start marker after 15 readouts!

[15:18:37.231] <TB0> WARNING: Channel 0 ROC 2: Readback start marker after 15 readouts!

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

[15:18:37.231] <TB0> WARNING: Channel 1 ROC 2: Readback start marker after 15 readouts!

[15:18:37.231] <TB0> WARNING: Channel 1 ROC 3: Readback start marker after 15 readouts!

[15:18:49.939] <TB0> INFO: Resuming triggers.
[15:18:53.726] <TB0> INFO: run duration 34 seconds, buffer almost full (81%), pausing triggers.
[15:19:04.014] <TB0> ERROR: <datapipe.cc/CheckEventValidity:L437> Channel 1 Number of ROCs (3) != Token Chain Length (4)

[15:19:08.757] <TB0> ERROR: <datapipe.cc/CheckEventValidity:L437> Channel 0 Number of ROCs (3) != Token Chain Length (4)

[15:19:08.757] <TB0> ERROR: <datapipe.cc/CheckEventValidity:L437> Channel 1 Number of ROCs (3) != Token Chain Length (4)

[15:19:17.769] <TB0> INFO: Resuming triggers.
[15:19:21.556] <TB0> INFO: run duration 37 seconds, buffer almost full (81%), pausing triggers.
[15:19:25.713] <TB0> ERROR: <datapipe.cc/CheckEventValidity:L437> Channel 0 Number of ROCs (2) != Token Chain Length (4)

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

[15:19:28.464] <TB0> ERROR: <datapipe.cc/CheckEventValidity:L437> Channel 0 Number of ROCs (0) != Token Chain Length (4)

[15:19:28.464] <TB0> ERROR: <datapipe.cc/CheckEventID:L420> Channel 1 Event ID mismatch: local ID (3) != TBM ID (24)

[15:19:28.464] <TB0> ERROR: <datapipe.cc/CheckEventValidity:L437> Channel 1 Number of ROCs (2) != Token Chain Length (4)

[15:19:28.464] <TB0> ERROR: <datapipe.cc/CheckEventID:L420> Channel 0 Event ID mismatch: local ID (3) != TBM ID (4)

[15:19:28.464] <TB0> ERROR: <datapipe.cc/CheckEventID:L420> Channel 1 Event ID mismatch: local ID (25) != TBM ID (4)

[15:19:28.465] <TB0> WARNING: Channel 0 ROC 1: Readback start marker after 15 readouts!

[15:19:28.465] <TB0> WARNING: Channel 0 ROC 2: Readback start marker after 15 readouts!

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

[15:19:28.465] <TB0> WARNING: Channel 1 ROC 3: Readback start marker after 15 readouts!

[15:19:31.283] <TB0> ERROR: <datapipe.cc/CheckEventID:L420> Channel 0 Event ID mismatch: local ID (201) != TBM ID (202)

[15:19:31.283] <TB0> ERROR: <datapipe.cc/CheckEventID:L420> Channel 1 Event ID mismatch: local ID (201) != TBM ID (202)

[15:19:31.283] <TB0> WARNING: Channel 0 ROC 0: Readback start marker after 15 readouts!

[15:19:31.283] <TB0> WARNING: Channel 0 ROC 1: Readback start marker after 15 readouts!

[15:19:31.283] <TB0> WARNING: Channel 0 ROC 2: Readback start marker after 15 readouts!

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

[15:19:31.283] <TB0> WARNING: Channel 1 ROC 0: Readback start marker after 15 readouts!

[15:19:31.283] <TB0> WARNING: Channel 1 ROC 1: Readback start marker after 15 readouts!

[15:19:31.283] <TB0> WARNING: Channel 1 ROC 2: Readback start marker after 15 readouts!

[15:19:31.283] <TB0> WARNING: Channel 1 ROC 3: Readback start marker after 15 readouts!

[15:19:47.252] <TB0> INFO: Resuming triggers.
[15:19:51.037] <TB0> INFO: run duration 41 seconds, buffer almost full (81%), pausing triggers.
[15:19:52.151] <TB0> ERROR: <datapipe.cc/CheckEventValidity:L437> Channel 1 Number of ROCs (3) != Token Chain Length (4)

[15:20:02.920] <TB0> ERROR: <datapipe.cc/CheckEventValidity:L437> Channel 0 Number of ROCs (3) != Token Chain Length (4)

[15:20:02.920] <TB0> ERROR: <datapipe.cc/CheckEventValidity:L437> Channel 1 Number of ROCs (2) != Token Chain Length (4)

[15:20:02.921] <TB0> WARNING: Channel 1 ROC 3: Readback start marker after 15 readouts!

[15:20:13.313] <TB0> INFO: Resuming triggers.
[15:20:17.100] <TB0> INFO: run duration 45 seconds, buffer almost full (81%), pausing triggers.
[15:20:17.191] <TB0> ERROR: <datapipe.cc/CheckEventValidity:L437> Channel 0 Number of ROCs (2) != Token Chain Length (4)

[15:20:17.191] <TB0> ERROR: <datapipe.cc/CheckEventValidity:L437> Channel 1 Number of ROCs (2) != Token Chain Length (4)

[15:20:17.191] <TB0> ERROR: <datapipe.cc/CheckEventValidity:L437> Channel 2 Number of ROCs (1) != Token Chain Length (4)

[15:20:17.191] <TB0> ERROR: <datapipe.cc/CheckEventValidity:L437> Channel 3 Number of ROCs (1) != Token Chain Length (4)

[15:20:17.191] <TB0> ERROR: <datapipe.cc/CheckEventID:L420> Channel 2 Event ID mismatch: local ID (168) != TBM ID (87)

[15:20:17.191] <TB0> ERROR: <datapipe.cc/CheckEventValidity:L437> Channel 2 Number of ROCs (0) != Token Chain Length (4)

[15:20:17.191] <TB0> ERROR: <datapipe.cc/CheckEventID:L420> Channel 2 Event ID mismatch: local ID (88) != TBM ID (168)

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

[15:20:17.191] <TB0> WARNING: Channel 1 ROC 3: Readback start marker after 15 readouts!

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

[15:20:17.191] <TB0> WARNING: Channel 3 ROC 3: Readback start marker after 15 readouts!

[15:20:17.191] <TB0> WARNING: Channel 2 ROC 1: Readback start marker after 15 readouts!

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

[15:20:19.232] <TB0> ERROR: <datapipe.cc/CheckEventValidity:L437> Channel 0 Number of ROCs (2) != Token Chain Length (4)

[15:20:19.232] <TB0> ERROR: <datapipe.cc/CheckEventValidity:L437> Channel 1 Number of ROCs (2) != Token Chain Length (4)

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

[15:20:19.232] <TB0> WARNING: Channel 1 ROC 3: Readback start marker after 15 readouts!

[15:20:23.220] <TB0> ERROR: <datapipe.cc/CheckEventID:L420> Channel 0 Event ID mismatch: local ID (249) != TBM ID (250)

[15:20:23.220] <TB0> ERROR: <datapipe.cc/CheckEventID:L420> Channel 1 Event ID mismatch: local ID (249) != TBM ID (250)

[15:20:23.220] <TB0> WARNING: Channel 0 ROC 0: Readback start marker after 15 readouts!

[15:20:23.220] <TB0> WARNING: Channel 0 ROC 1: Readback start marker after 15 readouts!

[15:20:23.220] <TB0> WARNING: Channel 0 ROC 2: Readback start marker after 15 readouts!

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

[15:20:23.220] <TB0> WARNING: Channel 1 ROC 0: Readback start marker after 15 readouts!

[15:20:23.220] <TB0> WARNING: Channel 1 ROC 1: Readback start marker after 15 readouts!

[15:20:23.220] <TB0> WARNING: Channel 1 ROC 2: Readback start marker after 15 readouts!

[15:20:23.220] <TB0> WARNING: Channel 1 ROC 3: Readback start marker after 15 readouts!

[15:20:40.360] <TB0> INFO: Resuming triggers.
[15:20:44.145] <TB0> INFO: run duration 49 seconds, buffer almost full (81%), pausing triggers.
[15:20:44.698] <TB0> ERROR: <datapipe.cc/CheckEventValidity:L437> Channel 1 Number of ROCs (2) != Token Chain Length (4)

[15:20:44.698] <TB0> WARNING: Channel 1 ROC 3: Readback start marker after 15 readouts!

[15:20:46.741] <TB0> ERROR: <datapipe.cc/CheckEventValidity:L437> Channel 0 Number of ROCs (2) != Token Chain Length (4)

[15:20:46.741] <TB0> ERROR: <datapipe.cc/CheckEventValidity:L437> Channel 1 Number of ROCs (3) != Token Chain Length (4)

[15:20:46.741] <TB0> ERROR: <datapipe.cc/CheckEventID:L420> Channel 1 Event ID mismatch: local ID (80) != TBM ID (12)

[15:20:46.741] <TB0> ERROR: <datapipe.cc/CheckEventValidity:L437> Channel 1 Number of ROCs (0) != Token Chain Length (4)

[15:20:46.741] <TB0> ERROR: <datapipe.cc/CheckEventID:L420> Channel 1 Event ID mismatch: local ID (13) != TBM ID (80)

[15:20:46.746] <TB0> WARNING: Channel 0 ROC 3: Readback start marker after 31 readouts!

[15:20:55.784] <TB0> ERROR: <datapipe.cc/CheckEventValidity:L437> Channel 1 Number of ROCs (1) != Token Chain Length (4)

[15:20:55.784] <TB0> ERROR: <datapipe.cc/CheckEventID:L420> Channel 1 Event ID mismatch: local ID (104) != TBM ID (48)

[15:20:55.784] <TB0> ERROR: <datapipe.cc/CheckEventValidity:L437> Channel 1 Number of ROCs (1) != Token Chain Length (4)

[15:20:55.784] <TB0> ERROR: <datapipe.cc/CheckEventID:L420> Channel 1 Event ID mismatch: local ID (49) != TBM ID (104)

[15:20:55.784] <TB0> WARNING: Channel 1 ROC 1: Readback start marker after 15 readouts!

[15:20:55.784] <TB0> WARNING: Channel 1 ROC 2: Readback start marker after 15 readouts!

[15:20:55.784] <TB0> WARNING: Channel 1 ROC 3: Readback start marker after 15 readouts!

[15:21:08.662] <TB0> INFO: Resuming triggers.
[15:21:12.450] <TB0> INFO: run duration 52 seconds, buffer almost full (81%), pausing triggers.
[15:21:38.930] <TB0> INFO: Resuming triggers.
[15:21:42.717] <TB0> INFO: run duration 56 seconds, buffer almost full (81%), pausing triggers.
[15:21:43.021] <TB0> ERROR: <datapipe.cc/CheckEventValidity:L437> Channel 2 Number of ROCs (3) != Token Chain Length (4)

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

[15:21:43.021] <TB0> WARNING: Channel 3 ROC 3: Readback start marker after 14 readouts!

[15:21:49.873] <TB0> ERROR: <datapipe.cc/CheckEventValidity:L437> Channel 0 Number of ROCs (3) != Token Chain Length (4)

[15:21:49.874] <TB0> ERROR: <datapipe.cc/CheckEventValidity:L437> Channel 1 Number of ROCs (2) != Token Chain Length (4)

[15:21:49.874] <TB0> WARNING: Channel 1 ROC 3: Readback start marker after 15 readouts!

[15:21:59.287] <TB0> ERROR: <datapipe.cc/CheckEventValidity:L437> Channel 0 Number of ROCs (2) != Token Chain Length (4)

[15:21:59.287] <TB0> ERROR: <datapipe.cc/CheckEventValidity:L437> Channel 1 Number of ROCs (2) != Token Chain Length (4)

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

[15:21:59.287] <TB0> WARNING: Channel 1 ROC 3: Readback start marker after 15 readouts!

[15:22:02.339] <TB0> ERROR: <datapipe.cc/CheckEventID:L420> Channel 0 Event ID mismatch: local ID (91) != TBM ID (84)

[15:22:02.339] <TB0> ERROR: <datapipe.cc/CheckEventValidity:L437> Channel 0 Number of ROCs (2) != Token Chain Length (4)

[15:22:02.339] <TB0> ERROR: <datapipe.cc/CheckEventID:L420> Channel 1 Event ID mismatch: local ID (91) != TBM ID (97)

[15:22:02.339] <TB0> ERROR: <datapipe.cc/CheckEventValidity:L437> Channel 1 Number of ROCs (2) != Token Chain Length (4)

[15:22:02.339] <TB0> ERROR: <datapipe.cc/CheckEventID:L420> Channel 0 Event ID mismatch: local ID (85) != TBM ID (92)

[15:22:02.339] <TB0> ERROR: <datapipe.cc/CheckEventID:L420> Channel 1 Event ID mismatch: local ID (98) != TBM ID (92)

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

[15:22:02.339] <TB0> WARNING: Channel 1 ROC 3: Readback start marker after 15 readouts!

[15:22:09.904] <TB0> INFO: Resuming triggers.
[15:22:13.691] <TB0> INFO: run duration 60 seconds, buffer almost full (81%), pausing triggers.
[15:22:14.336] <TB0> ERROR: <datapipe.cc/CheckEventValidity:L437> Channel 0 Number of ROCs (1) != Token Chain Length (4)

[15:22:14.336] <TB0> WARNING: Channel 1 ROC 1: Readback start marker after 13 readouts!

[15:22:14.336] <TB0> WARNING: Channel 1 ROC 2: Readback start marker after 13 readouts!

[15:22:14.336] <TB0> ERROR: <datapipe.cc/CheckEventValidity:L437> Channel 1 Number of ROCs (5) != Token Chain Length (4)

[15:22:14.336] <TB0> WARNING: Channel 0 ROC 2: Readback start marker after 15 readouts!

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

[15:22:14.336] <TB0> WARNING: Channel 1 ROC 2: Readback start marker after 3 readouts!

[15:22:16.640] <TB0> ERROR: <datapipe.cc/CheckEventValidity:L437> Channel 0 Number of ROCs (2) != Token Chain Length (4)

[15:22:16.640] <TB0> WARNING: Channel 1 ROC 0: Readback start marker after 3 readouts!

[15:22:16.640] <TB0> ERROR: <datapipe.cc/CheckEventValidity:L437> Channel 1 Number of ROCs (2) != Token Chain Length (4)

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

[15:22:16.641] <TB0> WARNING: Channel 1 ROC 2: Readback start marker after 15 readouts!

[15:22:16.641] <TB0> WARNING: Channel 1 ROC 3: Readback start marker after 15 readouts!

[15:22:21.292] <TB0> ERROR: <datapipe.cc/CheckEventID:L420> Channel 0 Event ID mismatch: local ID (110) != TBM ID (111)

[15:22:21.292] <TB0> WARNING: Channel 0 ROC 0: Readback start marker after 15 readouts!

[15:22:21.292] <TB0> WARNING: Channel 0 ROC 1: Readback start marker after 15 readouts!

[15:22:21.292] <TB0> WARNING: Channel 0 ROC 2: Readback start marker after 15 readouts!

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

[15:22:21.292] <TB0> ERROR: <datapipe.cc/CheckEventID:L420> Channel 1 Event ID mismatch: local ID (110) != TBM ID (111)

[15:22:21.292] <TB0> WARNING: Channel 1 ROC 0: Readback start marker after 15 readouts!

[15:22:21.292] <TB0> WARNING: Channel 1 ROC 1: Readback start marker after 15 readouts!

[15:22:21.292] <TB0> WARNING: Channel 1 ROC 2: Readback start marker after 15 readouts!

[15:22:21.292] <TB0> WARNING: Channel 1 ROC 3: Readback start marker after 15 readouts!

[15:22:36.443] <TB0> INFO: Resuming triggers.
[15:22:40.230] <TB0> INFO: run duration 64 seconds, buffer almost full (81%), pausing triggers.
[15:22:44.907] <TB0> ERROR: <datapipe.cc/CheckEventValidity:L437> Channel 0 Number of ROCs (2) != Token Chain Length (4)

[15:22:44.907] <TB0> ERROR: <datapipe.cc/CheckEventValidity:L437> Channel 1 Number of ROCs (2) != Token Chain Length (4)

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

[15:22:44.907] <TB0> WARNING: Channel 1 ROC 3: Readback start marker after 15 readouts!

[15:22:46.953] <TB0> ERROR: <datapipe.cc/CheckEventValidity:L437> Channel 0 Number of ROCs (2) != Token Chain Length (4)

[15:22:46.953] <TB0> ERROR: <datapipe.cc/CheckEventValidity:L437> Channel 1 Number of ROCs (1) != Token Chain Length (4)

[15:22:46.953] <TB0> WARNING: Channel 0 ROC 3: Readback start marker after 31 readouts!

[15:22:46.953] <TB0> WARNING: Channel 1 ROC 2: Readback start marker after 31 readouts!

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

[15:22:49.278] <TB0> ERROR: <datapipe.cc/CheckEventValidity:L437> Channel 0 Number of ROCs (1) != Token Chain Length (4)

[15:22:49.278] <TB0> WARNING: Channel 1 ROC 0: Readback start marker after 6 readouts!

[15:22:49.278] <TB0> ERROR: <datapipe.cc/CheckEventValidity:L437> Channel 1 Number of ROCs (1) != Token Chain Length (4)

[15:22:49.278] <TB0> WARNING: Channel 0 ROC 2: Readback start marker after 15 readouts!

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

[15:22:49.278] <TB0> WARNING: Channel 1 ROC 1: Readback start marker after 15 readouts!

[15:22:49.278] <TB0> WARNING: Channel 1 ROC 2: Readback start marker after 15 readouts!

[15:22:49.278] <TB0> WARNING: Channel 1 ROC 3: Readback start marker after 15 readouts!

[15:22:53.343] <TB0> ERROR: <datapipe.cc/CheckEventID:L420> Channel 0 Event ID mismatch: local ID (145) != TBM ID (146)

[15:22:53.343] <TB0> ERROR: <datapipe.cc/CheckEventID:L420> Channel 1 Event ID mismatch: local ID (145) != TBM ID (146)

[15:22:53.343] <TB0> WARNING: Channel 0 ROC 0: Readback start marker after 15 readouts!

[15:22:53.343] <TB0> WARNING: Channel 0 ROC 1: Readback start marker after 15 readouts!

[15:22:53.343] <TB0> WARNING: Channel 0 ROC 2: Readback start marker after 15 readouts!

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

[15:22:53.343] <TB0> WARNING: Channel 1 ROC 0: Readback start marker after 15 readouts!

[15:22:53.343] <TB0> WARNING: Channel 1 ROC 1: Readback start marker after 15 readouts!

[15:22:53.343] <TB0> WARNING: Channel 1 ROC 2: Readback start marker after 15 readouts!

[15:22:53.343] <TB0> WARNING: Channel 1 ROC 3: Readback start marker after 15 readouts!

[15:23:01.836] <TB0> INFO: Resuming triggers.
[15:23:05.624] <TB0> INFO: run duration 68 seconds, buffer almost full (81%), pausing triggers.
[15:23:06.452] <TB0> ERROR: <datapipe.cc/CheckEventValidity:L437> Channel 1 Number of ROCs (2) != Token Chain Length (4)

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

[15:23:08.385] <TB0> ERROR: <datapipe.cc/CheckEventValidity:L437> Channel 0 Number of ROCs (3) != Token Chain Length (4)

[15:23:08.385] <TB0> ERROR: <datapipe.cc/CheckEventValidity:L437> Channel 1 Number of ROCs (3) != Token Chain Length (4)

[15:23:14.760] <TB0> ERROR: <datapipe.cc/CheckEventValidity:L437> Channel 0 Number of ROCs (2) != Token Chain Length (4)

[15:23:14.760] <TB0> ERROR: <datapipe.cc/CheckEventValidity:L437> Channel 1 Number of ROCs (2) != Token Chain Length (4)

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

[15:23:14.760] <TB0> WARNING: Channel 1 ROC 3: Readback start marker after 15 readouts!

[15:23:17.106] <TB0> ERROR: <datapipe.cc/CheckEventValidity:L437> Channel 0 Number of ROCs (2) != Token Chain Length (4)

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

[15:23:27.781] <TB0> INFO: Resuming triggers.
[15:23:31.569] <TB0> INFO: run duration 71 seconds, buffer almost full (81%), pausing triggers.
[15:23:32.863] <TB0> ERROR: <datapipe.cc/CheckEventValidity:L437> Channel 0 Number of ROCs (2) != Token Chain Length (4)

[15:23:32.863] <TB0> ERROR: <datapipe.cc/CheckEventValidity:L437> Channel 1 Number of ROCs (2) != Token Chain Length (4)

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

[15:23:32.863] <TB0> WARNING: Channel 1 ROC 3: Readback start marker after 15 readouts!

[15:23:36.946] <TB0> ERROR: <datapipe.cc/CheckEventID:L420> Channel 0 Event ID mismatch: local ID (154) != TBM ID (155)

[15:23:36.946] <TB0> ERROR: <datapipe.cc/CheckEventID:L420> Channel 1 Event ID mismatch: local ID (154) != TBM ID (155)

[15:23:36.946] <TB0> WARNING: Channel 0 ROC 0: Readback start marker after 15 readouts!

[15:23:36.946] <TB0> WARNING: Channel 0 ROC 1: Readback start marker after 15 readouts!

[15:23:36.946] <TB0> WARNING: Channel 0 ROC 2: Readback start marker after 15 readouts!

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

[15:23:36.946] <TB0> WARNING: Channel 1 ROC 0: Readback start marker after 15 readouts!

[15:23:36.946] <TB0> WARNING: Channel 1 ROC 1: Readback start marker after 15 readouts!

[15:23:36.946] <TB0> WARNING: Channel 1 ROC 2: Readback start marker after 15 readouts!

[15:23:36.946] <TB0> WARNING: Channel 1 ROC 3: Readback start marker after 15 readouts!

[15:23:54.067] <TB0> INFO: Resuming triggers.
[15:23:57.857] <TB0> INFO: run duration 75 seconds, buffer almost full (81%), pausing triggers.
[15:23:58.746] <TB0> ERROR: <datapipe.cc/CheckEventID:L420> Channel 0 Event ID mismatch: local ID (123) != TBM ID (124)

[15:23:58.746] <TB0> ERROR: <datapipe.cc/CheckEventID:L420> Channel 1 Event ID mismatch: local ID (123) != TBM ID (124)

[15:23:58.746] <TB0> WARNING: Channel 0 ROC 0: Readback start marker after 15 readouts!

[15:23:58.746] <TB0> WARNING: Channel 0 ROC 1: Readback start marker after 15 readouts!

[15:23:58.746] <TB0> WARNING: Channel 0 ROC 2: Readback start marker after 15 readouts!

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

[15:23:58.746] <TB0> WARNING: Channel 1 ROC 0: Readback start marker after 15 readouts!

[15:23:58.746] <TB0> WARNING: Channel 1 ROC 1: Readback start marker after 15 readouts!

[15:23:58.746] <TB0> WARNING: Channel 1 ROC 2: Readback start marker after 15 readouts!

[15:23:58.746] <TB0> WARNING: Channel 1 ROC 3: Readback start marker after 15 readouts!

[15:24:19.770] <TB0> INFO: Resuming triggers.
[15:24:23.555] <TB0> INFO: run duration 79 seconds, buffer almost full (81%), pausing triggers.
[15:24:25.384] <TB0> ERROR: <datapipe.cc/CheckEventID:L420> Channel 0 Event ID mismatch: local ID (158) != TBM ID (159)

[15:24:25.384] <TB0> WARNING: Channel 0 ROC 0: Readback start marker after 15 readouts!

[15:24:25.384] <TB0> WARNING: Channel 0 ROC 1: Readback start marker after 15 readouts!

[15:24:25.384] <TB0> WARNING: Channel 0 ROC 2: Readback start marker after 15 readouts!

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

[15:24:25.384] <TB0> ERROR: <datapipe.cc/CheckEventID:L420> Channel 1 Event ID mismatch: local ID (158) != TBM ID (159)

[15:24:25.384] <TB0> WARNING: Channel 1 ROC 0: Readback start marker after 15 readouts!

[15:24:25.384] <TB0> WARNING: Channel 1 ROC 1: Readback start marker after 15 readouts!

[15:24:25.384] <TB0> WARNING: Channel 1 ROC 2: Readback start marker after 15 readouts!

[15:24:25.384] <TB0> WARNING: Channel 1 ROC 3: Readback start marker after 15 readouts!

[15:24:35.489] <TB0> ERROR: <datapipe.cc/CheckEventValidity:L437> Channel 1 Number of ROCs (3) != Token Chain Length (4)

[15:24:46.240] <TB0> INFO: Resuming triggers.
[15:24:50.030] <TB0> INFO: run duration 83 seconds, buffer almost full (81%), pausing triggers.
[15:24:55.819] <TB0> ERROR: <datapipe.cc/CheckEventValidity:L437> Channel 1 Number of ROCs (2) != Token Chain Length (4)

[15:24:55.819] <TB0> WARNING: Channel 1 ROC 3: Readback start marker after 15 readouts!

[15:25:02.189] <TB0> ERROR: <datapipe.cc/CheckEventValidity:L437> Channel 1 Number of ROCs (3) != Token Chain Length (4)

[15:25:04.160] <TB0> ERROR: <datapipe.cc/CheckEventValidity:L437> Channel 0 Number of ROCs (3) != Token Chain Length (4)

[15:25:13.316] <TB0> INFO: Resuming triggers.
[15:25:17.103] <TB0> INFO: run duration 86 seconds, buffer almost full (81%), pausing triggers.
[15:25:17.689] <TB0> ERROR: <datapipe.cc/CheckEventValidity:L437> Channel 1 Number of ROCs (3) != Token Chain Length (4)

[15:25:28.787] <TB0> ERROR: <datapipe.cc/CheckEventValidity:L437> Channel 0 Number of ROCs (3) != Token Chain Length (4)

[15:25:28.787] <TB0> ERROR: <datapipe.cc/CheckEventValidity:L437> Channel 1 Number of ROCs (2) != Token Chain Length (4)

[15:25:28.787] <TB0> WARNING: Channel 1 ROC 3: Readback start marker after 15 readouts!

[15:25:30.710] <TB0> ERROR: <datapipe.cc/CheckEventValidity:L437> Channel 0 Number of ROCs (1) != Token Chain Length (4)

[15:25:30.710] <TB0> ERROR: <datapipe.cc/CheckEventValidity:L437> Channel 1 Number of ROCs (1) != Token Chain Length (4)

[15:25:30.710] <TB0> WARNING: Channel 0 ROC 2: Readback start marker after 15 readouts!

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

[15:25:30.710] <TB0> WARNING: Channel 1 ROC 2: Readback start marker after 15 readouts!

[15:25:30.710] <TB0> WARNING: Channel 1 ROC 3: Readback start marker after 15 readouts!

[15:25:40.092] <TB0> INFO: Resuming triggers.
[15:25:43.877] <TB0> INFO: run duration 90 seconds, buffer almost full (81%), pausing triggers.
[15:25:46.553] <TB0> ERROR: <datapipe.cc/CheckEventValidity:L437> Channel 1 Number of ROCs (3) != Token Chain Length (4)

[15:26:08.453] <TB0> INFO: Resuming triggers.
[15:26:12.240] <TB0> INFO: run duration 94 seconds, buffer almost full (81%), pausing triggers.
[15:26:19.057] <TB0> ERROR: <datapipe.cc/CheckEventValidity:L437> Channel 1 Number of ROCs (2) != Token Chain Length (4)

[15:26:19.063] <TB0> WARNING: Channel 1 ROC 3: Readback start marker after 15 readouts!

[15:26:24.106] <TB0> ERROR: <datapipe.cc/CheckEventValidity:L437> Channel 0 Number of ROCs (2) != Token Chain Length (4)

[15:26:24.106] <TB0> ERROR: <datapipe.cc/CheckEventValidity:L437> Channel 1 Number of ROCs (2) != Token Chain Length (4)

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

[15:26:24.106] <TB0> WARNING: Channel 1 ROC 3: Readback start marker after 15 readouts!

[15:26:26.260] <TB0> ERROR: <datapipe.cc/CheckEventValidity:L437> Channel 0 Number of ROCs (2) != Token Chain Length (4)

[15:26:26.260] <TB0> ERROR: <datapipe.cc/CheckEventValidity:L437> Channel 1 Number of ROCs (3) != Token Chain Length (4)

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

[15:26:37.207] <TB0> INFO: Resuming triggers.
[15:26:40.990] <TB0> INFO: run duration 98 seconds, buffer almost full (81%), pausing triggers.
[15:26:41.608] <TB0> ERROR: <datapipe.cc/CheckEventValidity:L437> Channel 1 Number of ROCs (3) != Token Chain Length (4)

[15:26:43.816] <TB0> ERROR: <datapipe.cc/CheckEventValidity:L437> Channel 1 Number of ROCs (2) != Token Chain Length (4)

[15:26:43.816] <TB0> WARNING: Channel 1 ROC 3: Readback start marker after 15 readouts!

[15:26:46.165] <TB0> WARNING: Channel 0 ROC 2: Readback start marker after 11 readouts!

[15:26:46.165] <TB0> ERROR: <datapipe.cc/CheckEventValidity:L437> Channel 1 Number of ROCs (2) != Token Chain Length (4)

[15:26:46.165] <TB0> WARNING: Channel 0 ROC 2: Readback start marker after 5 readouts!

[15:26:46.165] <TB0> WARNING: Channel 1 ROC 3: Readback start marker after 15 readouts!

[15:26:50.444] <TB0> ERROR: <datapipe.cc/CheckEventValidity:L437> Channel 1 Number of ROCs (2) != Token Chain Length (4)

[15:26:50.444] <TB0> WARNING: Channel 1 ROC 3: Readback start marker after 15 readouts!

[15:26:54.598] <TB0> ERROR: <datapipe.cc/CheckEventValidity:L437> Channel 0 Number of ROCs (2) != Token Chain Length (4)

[15:26:54.598] <TB0> ERROR: <datapipe.cc/CheckEventValidity:L437> Channel 1 Number of ROCs (1) != Token Chain Length (4)

[15:26:54.598] <TB0> ERROR: <datapipe.cc/CheckEventID:L420> Channel 1 Event ID mismatch: local ID (99) != TBM ID (14)

[15:26:54.598] <TB0> ERROR: <datapipe.cc/CheckEventValidity:L437> Channel 1 Number of ROCs (1) != Token Chain Length (4)

[15:26:54.598] <TB0> ERROR: <datapipe.cc/CheckEventID:L420> Channel 1 Event ID mismatch: local ID (15) != TBM ID (99)

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

[15:26:54.603] <TB0> WARNING: Channel 1 ROC 1: Readback start marker after 15 readouts!

[15:26:54.603] <TB0> WARNING: Channel 1 ROC 2: Readback start marker after 15 readouts!

[15:26:54.603] <TB0> WARNING: Channel 1 ROC 3: Readback start marker after 15 readouts!

[15:27:04.199] <TB0> INFO: Resuming triggers.
[15:27:05.888] <TB0> INFO: data taking finished, elapsed time: 100 seconds.
[15:27:19.491] <TB0> INFO: PixTest:: pg_setup set to default.
[15:27:19.492] <TB0> INFO: PixTestXray::doPhRun() done
[15:27:19.492] <TB0> INFO: PixTestXray::doTest() done
[15:27:19.702] <TB0> INFO: enter test to run
[15:27:19.702] <TB0> INFO: test: no parameter change
[15:27:19.910] <TB0> QUIET: Connection to board 147 closed.
[15:27:19.990] <TB0> INFO: pXar: this is the end, my friend
commander_HRSCurves.log
[15:29:28.623] <TB0> INFO: *** Welcome to pxar ***
[15:29:28.623] <TB0> INFO: *** Today: 2015/08/11
[15:29:28.623] <TB0> INFO: readRocDacs: /usr/local/data/M2045_XrayQualification_2015-08-11_16h58m_1439305117//005_HRSCurves_100//dacParameters35_C0.dat .. /usr/local/data/M2045_XrayQualification_2015-08-11_16h58m_1439305117//005_HRSCurves_100//dacParameters35_C15.dat
[15:29:28.624] <TB0> INFO: readTbmDacs: /usr/local/data/M2045_XrayQualification_2015-08-11_16h58m_1439305117//005_HRSCurves_100//tbmParameters_C0a.dat .. /usr/local/data/M2045_XrayQualification_2015-08-11_16h58m_1439305117//005_HRSCurves_100//tbmParameters_C0b.dat
[15:29:28.624] <TB0> INFO: readMaskFile: /usr/local/data/M2045_XrayQualification_2015-08-11_16h58m_1439305117//005_HRSCurves_100//defaultMaskFile.dat
[15:29:28.624] <TB0> INFO: readTrimFile: /usr/local/data/M2045_XrayQualification_2015-08-11_16h58m_1439305117//005_HRSCurves_100//trimParameters35_C0.dat .. /usr/local/data/M2045_XrayQualification_2015-08-11_16h58m_1439305117//005_HRSCurves_100//trimParameters35_C15.dat
[15:29:28.692] <TB0> INFO: clk: 4
[15:29:28.692] <TB0> INFO: ctr: 4
[15:29:28.692] <TB0> INFO: sda: 19
[15:29:28.692] <TB0> INFO: tin: 9
[15:29:28.692] <TB0> INFO: level: 15
[15:29:28.692] <TB0> INFO: triggerdelay: 0
[15:29:28.692] <TB0> QUIET: Instanciating API for pxar v2.2.5+88~g694c14c
[15:29:28.692] <TB0> INFO: Log level: INFO
[15:29:29.702] <TB0> INFO: Found DTB DTB_WWXTQT
[15:29:32.713] <TB0> QUIET: Connection to board DTB_WWXTQT opened.
[15:29:32.716] <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:
------------------------------------------------------
[15:29:32.719] <TB0> INFO: RPC call hashes of host and DTB match: 447413373
[15:29:34.232] <TB0> INFO: DUT info:
[15:29:34.232] <TB0> INFO: The DUT currently contains the following objects:
[15:29:34.232] <TB0> INFO: 2 TBM Cores tbm09c (2 ON)
[15:29:34.232] <TB0> INFO: TBM Core alpha (0): 7 registers set
[15:29:34.232] <TB0> INFO: TBM Core beta (1): 7 registers set
[15:29:34.232] <TB0> INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[15:29:34.232] <TB0> INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[15:29:34.232] <TB0> INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[15:29:34.232] <TB0> INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[15:29:34.232] <TB0> INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[15:29:34.232] <TB0> INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[15:29:34.232] <TB0> INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[15:29:34.232] <TB0> INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[15:29:34.232] <TB0> INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[15:29:34.232] <TB0> INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[15:29:34.232] <TB0> INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[15:29:34.232] <TB0> INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[15:29:34.232] <TB0> INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[15:29:34.232] <TB0> INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[15:29:34.232] <TB0> INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[15:29:34.232] <TB0> INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[15:29:34.232] <TB0> INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[15:29:34.633] <TB0> INFO: enter 'restricted' command line mode
[15:29:34.633] <TB0> INFO: enter test to run
[15:29:34.633] <TB0> INFO: test: Highrate no parameter change
[15:29:34.633] <TB0> INFO: running: highrate
[15:29:34.633] <TB0> INFO: readGainPedestalParameters /usr/local/data/M2045_XrayQualification_2015-08-11_16h58m_1439305117//005_HRSCurves_100//phCalibrationFitErr35_C0.dat .. /usr/local/data/M2045_XrayQualification_2015-08-11_16h58m_1439305117//005_HRSCurves_100//phCalibrationFitErr35_C15.dat
[15:29:34.760] <TB0> INFO: ----------------------------------------------------------------------
[15:29:34.760] <TB0> INFO: PixTestHighRate::xNoiseMaps() ntrig = 50, vcal = 200
[15:29:34.760] <TB0> INFO: ----------------------------------------------------------------------
[15:29:34.766] <TB0> INFO: ---> dac: vcal name: xNoiseMap ntrig: 50 dacrange: 0 .. 100 (1) hits flags = 384 (plus default)
[15:29:34.777] <TB0> INFO: dacScan step from 0 .. 0
[15:29:41.051] <TB0> WARNING: Channel 3 ROC 3: Readback start marker after 14 readouts!

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

[15:29:46.693] <TB0> INFO: Test took 11916ms.
[15:29:48.047] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[15:29:48.337] <TB0> INFO: dacScan step from 1 .. 1
[15:30:02.033] <TB0> INFO: Test took 13696ms.
[15:30:03.410] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[15:30:03.666] <TB0> INFO: dacScan step from 2 .. 2
[15:30:08.105] <TB0> ERROR: <datapipe.cc/CheckEventValidity:L437> Channel 2 Number of ROCs (3) != Token Chain Length (4)

[15:30:18.253] <TB0> INFO: Test took 14587ms.
[15:30:19.860] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[15:30:20.098] <TB0> INFO: dacScan step from 3 .. 3
[15:30:34.406] <TB0> INFO: Test took 14308ms.
[15:30:35.852] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[15:30:36.125] <TB0> INFO: dacScan step from 4 .. 4
[15:30:49.900] <TB0> INFO: Test took 13774ms.
[15:30:51.537] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[15:30:51.804] <TB0> INFO: dacScan step from 5 .. 5
[15:31:04.998] <TB0> INFO: Test took 13193ms.
[15:31:06.284] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[15:31:06.502] <TB0> INFO: dacScan step from 6 .. 6
[15:31:19.176] <TB0> INFO: Test took 12674ms.
[15:31:20.699] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[15:31:20.955] <TB0> INFO: dacScan step from 7 .. 7
[15:31:32.900] <TB0> INFO: Test took 11945ms.
[15:31:34.246] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[15:31:34.494] <TB0> INFO: dacScan step from 8 .. 8
[15:31:47.106] <TB0> INFO: Test took 12612ms.
[15:31:48.696] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[15:31:48.951] <TB0> INFO: dacScan step from 9 .. 9
[15:32:02.359] <TB0> INFO: Test took 13408ms.
[15:32:03.719] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[15:32:03.963] <TB0> INFO: dacScan step from 10 .. 10
[15:32:17.766] <TB0> INFO: Test took 13802ms.
[15:32:19.495] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[15:32:19.737] <TB0> INFO: dacScan step from 11 .. 11
[15:32:34.341] <TB0> INFO: Test took 14603ms.
[15:32:35.686] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[15:32:35.915] <TB0> INFO: dacScan step from 12 .. 12
[15:32:49.866] <TB0> INFO: Test took 13951ms.
[15:32:51.231] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[15:32:51.494] <TB0> INFO: dacScan step from 13 .. 13
[15:33:04.814] <TB0> INFO: Test took 13320ms.
[15:33:06.094] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[15:33:06.323] <TB0> INFO: dacScan step from 14 .. 14
[15:33:19.059] <TB0> INFO: Test took 12736ms.
[15:33:20.478] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[15:33:20.719] <TB0> INFO: dacScan step from 15 .. 15
[15:33:32.759] <TB0> INFO: Test took 12039ms.
[15:33:34.224] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[15:33:34.483] <TB0> INFO: dacScan step from 16 .. 16
[15:33:47.069] <TB0> INFO: Test took 12585ms.
[15:33:48.639] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[15:33:48.860] <TB0> INFO: dacScan step from 17 .. 17
[15:34:01.944] <TB0> INFO: Test took 13083ms.
[15:34:03.335] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[15:34:03.552] <TB0> INFO: dacScan step from 18 .. 18
[15:34:08.173] <TB0> ERROR: <datapipe.cc/CheckEventID:L420> Channel 2 Event ID mismatch: local ID (23) != TBM ID (111)

[15:34:08.173] <TB0> ERROR: <datapipe.cc/CheckEventID:L420> Channel 3 Event ID mismatch: local ID (23) != TBM ID (43)

[15:34:08.173] <TB0> ERROR: <datapipe.cc/CheckEventID:L420> Channel 2 Event ID mismatch: local ID (112) != TBM ID (24)

[15:34:08.173] <TB0> ERROR: <datapipe.cc/CheckEventID:L420> Channel 3 Event ID mismatch: local ID (44) != TBM ID (24)

[15:34:17.987] <TB0> INFO: Test took 14435ms.
[15:34:19.556] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[15:34:19.795] <TB0> INFO: dacScan step from 19 .. 19
[15:34:34.332] <TB0> INFO: Test took 14536ms.
[15:34:36.349] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[15:34:36.601] <TB0> INFO: dacScan step from 20 .. 20
[15:34:51.027] <TB0> INFO: Test took 14425ms.
[15:34:52.428] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[15:34:52.671] <TB0> INFO: dacScan step from 21 .. 21
[15:35:06.604] <TB0> INFO: Test took 13933ms.
[15:35:07.960] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[15:35:08.207] <TB0> INFO: dacScan step from 22 .. 22
[15:35:21.254] <TB0> INFO: Test took 13047ms.
[15:35:22.513] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[15:35:22.783] <TB0> INFO: dacScan step from 23 .. 23
[15:35:34.581] <TB0> INFO: Test took 11798ms.
[15:35:35.930] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[15:35:36.165] <TB0> INFO: dacScan step from 24 .. 24
[15:35:48.626] <TB0> INFO: Test took 12461ms.
[15:35:50.018] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[15:35:50.281] <TB0> INFO: dacScan step from 25 .. 25
[15:36:04.256] <TB0> INFO: Test took 13975ms.
[15:36:05.495] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[15:36:05.724] <TB0> INFO: dacScan step from 26 .. 26
[15:36:20.370] <TB0> INFO: Test took 14645ms.
[15:36:21.662] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[15:36:21.881] <TB0> INFO: dacScan step from 27 .. 27
[15:36:36.352] <TB0> INFO: Test took 14470ms.
[15:36:37.646] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[15:36:37.870] <TB0> INFO: dacScan step from 28 .. 28
[15:36:50.949] <TB0> INFO: Test took 13079ms.
[15:36:52.278] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[15:36:52.508] <TB0> INFO: dacScan step from 29 .. 29
[15:36:56.961] <TB0> ERROR: <datapipe.cc/CheckEventValidity:L437> Channel 2 Number of ROCs (1) != Token Chain Length (4)

[15:36:56.961] <TB0> ERROR: <datapipe.cc/CheckEventValidity:L437> Channel 3 Number of ROCs (1) != Token Chain Length (4)

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

[15:36:56.961] <TB0> WARNING: Channel 2 ROC 1: Readback start marker after 15 readouts!

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

[15:36:56.961] <TB0> WARNING: Channel 3 ROC 3: Readback start marker after 15 readouts!

[15:37:05.443] <TB0> INFO: Test took 12935ms.
[15:37:06.740] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[15:37:06.969] <TB0> INFO: dacScan step from 30 .. 30
[15:37:18.534] <TB0> INFO: Test took 11565ms.
[15:37:20.059] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[15:37:20.286] <TB0> INFO: dacScan step from 31 .. 31
[15:37:32.770] <TB0> INFO: Test took 12484ms.
[15:37:34.093] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[15:37:34.321] <TB0> INFO: dacScan step from 32 .. 32
[15:37:48.088] <TB0> INFO: Test took 13766ms.
[15:37:49.531] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[15:37:49.758] <TB0> INFO: dacScan step from 33 .. 33
[15:38:04.197] <TB0> INFO: Test took 14438ms.
[15:38:05.657] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[15:38:05.907] <TB0> INFO: dacScan step from 34 .. 34
[15:38:20.402] <TB0> INFO: Test took 14495ms.
[15:38:21.774] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[15:38:21.993] <TB0> INFO: dacScan step from 35 .. 35
[15:38:35.932] <TB0> INFO: Test took 13938ms.
[15:38:37.492] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[15:38:37.733] <TB0> INFO: dacScan step from 36 .. 36
[15:38:51.105] <TB0> INFO: Test took 13371ms.
[15:38:52.480] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[15:38:52.710] <TB0> INFO: dacScan step from 37 .. 37
[15:39:05.792] <TB0> INFO: Test took 13081ms.
[15:39:07.185] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[15:39:07.411] <TB0> INFO: dacScan step from 38 .. 38
[15:39:19.948] <TB0> INFO: Test took 12537ms.
[15:39:21.438] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[15:39:21.703] <TB0> INFO: dacScan step from 39 .. 39
[15:39:34.826] <TB0> INFO: Test took 13122ms.
[15:39:36.151] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[15:39:36.370] <TB0> INFO: dacScan step from 40 .. 40
[15:39:50.623] <TB0> INFO: Test took 14252ms.
[15:39:52.028] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[15:39:52.260] <TB0> INFO: dacScan step from 41 .. 41
[15:40:07.284] <TB0> INFO: Test took 15023ms.
[15:40:08.632] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[15:40:08.851] <TB0> INFO: dacScan step from 42 .. 42
[15:40:24.879] <TB0> INFO: Test took 16027ms.
[15:40:26.332] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[15:40:26.561] <TB0> INFO: dacScan step from 43 .. 43
[15:40:43.377] <TB0> INFO: Test took 16815ms.
[15:40:45.085] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[15:40:45.348] <TB0> INFO: dacScan step from 44 .. 44
[15:41:01.993] <TB0> INFO: Test took 16644ms.
[15:41:03.643] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[15:41:03.895] <TB0> INFO: dacScan step from 45 .. 45
[15:41:20.476] <TB0> INFO: Test took 16580ms.
[15:41:21.861] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[15:41:22.084] <TB0> INFO: dacScan step from 46 .. 46
[15:41:39.141] <TB0> INFO: Test took 17057ms.
[15:41:41.102] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[15:41:41.409] <TB0> INFO: dacScan step from 47 .. 47
[15:41:45.791] <TB0> ERROR: <datapipe.cc/CheckEventValidity:L437> Channel 2 Number of ROCs (3) != Token Chain Length (4)

[15:41:45.791] <TB0> ERROR: <datapipe.cc/CheckEventValidity:L437> Channel 3 Number of ROCs (3) != Token Chain Length (4)

[15:41:58.872] <TB0> INFO: Test took 17463ms.
[15:42:00.443] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[15:42:00.679] <TB0> INFO: dacScan step from 48 .. 48
[15:42:18.552] <TB0> INFO: Test took 17873ms.
[15:42:20.273] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[15:42:20.555] <TB0> INFO: dacScan step from 49 .. 49
[15:42:37.841] <TB0> INFO: Test took 17286ms.
[15:42:39.489] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[15:42:39.716] <TB0> INFO: dacScan step from 50 .. 50
[15:42:56.734] <TB0> INFO: Test took 17018ms.
[15:42:58.345] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[15:42:58.592] <TB0> INFO: dacScan step from 51 .. 51
[15:43:15.573] <TB0> INFO: Test took 16980ms.
[15:43:17.130] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[15:43:17.374] <TB0> INFO: dacScan step from 52 .. 52
[15:43:33.899] <TB0> INFO: Test took 16525ms.
[15:43:35.724] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[15:43:35.953] <TB0> INFO: dacScan step from 53 .. 53
[15:43:51.826] <TB0> INFO: Test took 15872ms.
[15:43:53.345] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[15:43:53.571] <TB0> INFO: dacScan step from 54 .. 54
[15:44:08.889] <TB0> INFO: Test took 15317ms.
[15:44:10.423] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[15:44:10.657] <TB0> INFO: dacScan step from 55 .. 55
[15:44:25.982] <TB0> INFO: Test took 15324ms.
[15:44:27.431] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[15:44:27.663] <TB0> INFO: dacScan step from 56 .. 56
[15:44:44.084] <TB0> INFO: Test took 16421ms.
[15:44:45.558] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[15:44:45.793] <TB0> INFO: dacScan step from 57 .. 57
[15:45:03.049] <TB0> INFO: Test took 17256ms.
[15:45:04.684] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[15:45:04.911] <TB0> INFO: dacScan step from 58 .. 58
[15:45:22.569] <TB0> INFO: Test took 17657ms.
[15:45:24.036] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[15:45:24.287] <TB0> INFO: dacScan step from 59 .. 59
[15:45:28.954] <TB0> ERROR: <datapipe.cc/CheckEventValidity:L437> Channel 2 Number of ROCs (2) != Token Chain Length (4)

[15:45:28.954] <TB0> ERROR: <datapipe.cc/CheckEventValidity:L437> Channel 3 Number of ROCs (2) != Token Chain Length (4)

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

[15:45:28.954] <TB0> WARNING: Channel 3 ROC 3: Readback start marker after 15 readouts!

[15:45:42.064] <TB0> INFO: Test took 17777ms.
[15:45:43.708] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[15:45:43.966] <TB0> INFO: dacScan step from 60 .. 60
[15:46:00.644] <TB0> INFO: Test took 16678ms.
[15:46:02.048] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[15:46:02.270] <TB0> INFO: dacScan step from 61 .. 61
[15:46:17.338] <TB0> INFO: Test took 15067ms.
[15:46:18.848] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[15:46:19.078] <TB0> INFO: dacScan step from 62 .. 62
[15:46:34.177] <TB0> INFO: Test took 15099ms.
[15:46:35.760] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[15:46:35.995] <TB0> INFO: dacScan step from 63 .. 63
[15:46:40.648] <TB0> ERROR: <datapipe.cc/CheckEventValidity:L437> Channel 2 Number of ROCs (2) != Token Chain Length (4)

[15:46:40.649] <TB0> ERROR: <datapipe.cc/CheckEventValidity:L437> Channel 3 Number of ROCs (2) != Token Chain Length (4)

[15:46:40.649] <TB0> ERROR: <datapipe.cc/CheckEventID:L420> Channel 2 Event ID mismatch: local ID (171) != TBM ID (203)

[15:46:40.649] <TB0> ERROR: <datapipe.cc/CheckEventValidity:L437> Channel 2 Number of ROCs (0) != Token Chain Length (4)

[15:46:40.649] <TB0> ERROR: <datapipe.cc/CheckEventID:L420> Channel 2 Event ID mismatch: local ID (204) != TBM ID (171)

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

[15:46:40.649] <TB0> WARNING: Channel 3 ROC 3: Readback start marker after 15 readouts!

[15:46:51.760] <TB0> CRITICAL: <hal.cc/MultiRocAllPixelsDacScan:L1040> Incomplete DAQ data readout! Missing -1 Events.

[15:46:56.242] <TB0> ERROR: <datapipe.cc/CheckEventValidity:L437> Channel 2 Number of ROCs (3) != Token Chain Length (4)

[15:47:09.022] <TB0> INFO: Test took 17233ms.
[15:47:11.001] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[15:47:11.364] <TB0> INFO: dacScan step from 64 .. 64
[15:47:29.582] <TB0> INFO: Test took 18217ms.
[15:47:31.385] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[15:47:31.625] <TB0> INFO: dacScan step from 65 .. 65
[15:47:48.947] <TB0> INFO: Test took 17321ms.
[15:47:50.677] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[15:47:50.963] <TB0> INFO: dacScan step from 66 .. 66
[15:48:07.636] <TB0> INFO: Test took 16672ms.
[15:48:09.125] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[15:48:09.401] <TB0> INFO: dacScan step from 67 .. 67
[15:48:25.425] <TB0> INFO: Test took 16023ms.
[15:48:26.976] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[15:48:27.220] <TB0> INFO: dacScan step from 68 .. 68
[15:48:42.709] <TB0> INFO: Test took 15488ms.
[15:48:44.332] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[15:48:44.582] <TB0> INFO: dacScan step from 69 .. 69
[15:48:59.927] <TB0> INFO: Test took 15345ms.
[15:49:01.413] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[15:49:01.646] <TB0> INFO: dacScan step from 70 .. 70
[15:49:17.390] <TB0> INFO: Test took 15743ms.
[15:49:19.140] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[15:49:19.385] <TB0> INFO: dacScan step from 71 .. 71
[15:49:35.654] <TB0> INFO: Test took 16268ms.
[15:49:37.238] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[15:49:37.489] <TB0> INFO: dacScan step from 72 .. 72
[15:49:54.345] <TB0> INFO: Test took 16856ms.
[15:49:55.913] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[15:49:56.153] <TB0> INFO: dacScan step from 73 .. 73
[15:50:14.455] <TB0> INFO: Test took 18301ms.
[15:50:16.429] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[15:50:16.664] <TB0> INFO: dacScan step from 74 .. 74
[15:50:34.448] <TB0> INFO: Test took 17783ms.
[15:50:35.923] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[15:50:36.157] <TB0> INFO: dacScan step from 75 .. 75
[15:50:53.282] <TB0> INFO: Test took 17124ms.
[15:50:54.878] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[15:50:55.133] <TB0> INFO: dacScan step from 76 .. 76
[15:51:11.768] <TB0> INFO: Test took 16634ms.
[15:51:13.224] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[15:51:13.456] <TB0> INFO: dacScan step from 77 .. 77
[15:51:29.403] <TB0> INFO: Test took 15946ms.
[15:51:30.942] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[15:51:31.219] <TB0> INFO: dacScan step from 78 .. 78
[15:51:46.820] <TB0> INFO: Test took 15600ms.
[15:51:48.412] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[15:51:48.651] <TB0> INFO: dacScan step from 79 .. 79
[15:52:03.953] <TB0> INFO: Test took 15301ms.
[15:52:05.569] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[15:52:05.795] <TB0> INFO: dacScan step from 80 .. 80
[15:52:10.440] <TB0> ERROR: <datapipe.cc/CheckEventValidity:L437> Channel 2 Number of ROCs (2) != Token Chain Length (4)

[15:52:10.440] <TB0> ERROR: <datapipe.cc/CheckEventValidity:L437> Channel 3 Number of ROCs (2) != Token Chain Length (4)

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

[15:52:10.440] <TB0> WARNING: Channel 3 ROC 3: Readback start marker after 15 readouts!

[15:52:22.104] <TB0> INFO: Test took 16308ms.
[15:52:23.750] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[15:52:23.985] <TB0> INFO: dacScan step from 81 .. 81
[15:52:41.595] <TB0> INFO: Test took 17608ms.
[15:52:43.010] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[15:52:43.234] <TB0> INFO: dacScan step from 82 .. 82
[15:53:01.545] <TB0> INFO: Test took 18310ms.
[15:53:03.117] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[15:53:03.403] <TB0> INFO: dacScan step from 83 .. 83
[15:53:21.489] <TB0> INFO: Test took 18085ms.
[15:53:23.154] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[15:53:23.404] <TB0> INFO: dacScan step from 84 .. 84
[15:53:27.966] <TB0> ERROR: <datapipe.cc/CheckEventValidity:L437> Channel 2 Number of ROCs (3) != Token Chain Length (4)

[15:53:40.066] <TB0> INFO: Test took 16661ms.
[15:53:41.575] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[15:53:41.814] <TB0> INFO: dacScan step from 85 .. 85
[15:53:57.009] <TB0> INFO: Test took 15194ms.
[15:53:58.461] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[15:53:58.703] <TB0> INFO: dacScan step from 86 .. 86
[15:54:13.748] <TB0> INFO: Test took 15044ms.
[15:54:15.436] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[15:54:15.707] <TB0> INFO: dacScan step from 87 .. 87
[15:54:31.370] <TB0> INFO: Test took 15661ms.
[15:54:33.250] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[15:54:33.530] <TB0> INFO: dacScan step from 88 .. 88
[15:54:49.935] <TB0> INFO: Test took 16404ms.
[15:54:51.604] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[15:54:51.898] <TB0> INFO: dacScan step from 89 .. 89
[15:55:08.957] <TB0> INFO: Test took 17058ms.
[15:55:10.517] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[15:55:10.801] <TB0> INFO: dacScan step from 90 .. 90
[15:55:28.564] <TB0> INFO: Test took 17762ms.
[15:55:30.145] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[15:55:30.391] <TB0> INFO: dacScan step from 91 .. 91
[15:55:47.494] <TB0> INFO: Test took 17102ms.
[15:55:49.157] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[15:55:49.448] <TB0> INFO: dacScan step from 92 .. 92
[15:56:06.627] <TB0> INFO: Test took 17178ms.
[15:56:08.205] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[15:56:08.451] <TB0> INFO: dacScan step from 93 .. 93
[15:56:24.969] <TB0> INFO: Test took 16517ms.
[15:56:26.514] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[15:56:26.745] <TB0> INFO: dacScan step from 94 .. 94
[15:56:42.425] <TB0> INFO: Test took 15679ms.
[15:56:43.952] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[15:56:44.310] <TB0> INFO: dacScan step from 95 .. 95
[15:57:00.075] <TB0> INFO: Test took 15764ms.
[15:57:01.632] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[15:57:01.896] <TB0> INFO: dacScan step from 96 .. 96
[15:57:17.413] <TB0> INFO: Test took 15516ms.
[15:57:18.917] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[15:57:19.144] <TB0> INFO: dacScan step from 97 .. 97
[15:57:35.358] <TB0> INFO: Test took 16213ms.
[15:57:36.909] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[15:57:37.160] <TB0> INFO: dacScan step from 98 .. 98
[15:57:53.131] <TB0> INFO: Test took 15970ms.
[15:57:54.603] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[15:57:54.830] <TB0> INFO: dacScan step from 99 .. 99
[15:58:11.983] <TB0> INFO: Test took 17152ms.
[15:58:13.481] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[15:58:13.723] <TB0> INFO: dacScan step from 100 .. 100
[15:58:30.640] <TB0> INFO: Test took 16916ms.
[15:58:32.230] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[15:58:32.453] <TB0> INFO: dumping ASCII scurve output file: XSCurveData
[15:58:33.754] <TB0> INFO: dumping ASCII scurve output file: XSCurveData
[15:58:35.150] <TB0> INFO: dumping ASCII scurve output file: XSCurveData
[15:58:36.485] <TB0> INFO: dumping ASCII scurve output file: XSCurveData
[15:58:37.829] <TB0> INFO: dumping ASCII scurve output file: XSCurveData
[15:58:39.245] <TB0> INFO: dumping ASCII scurve output file: XSCurveData
[15:58:40.657] <TB0> INFO: dumping ASCII scurve output file: XSCurveData
[15:58:42.116] <TB0> INFO: dumping ASCII scurve output file: XSCurveData
[15:58:43.525] <TB0> INFO: dumping ASCII scurve output file: XSCurveData
[15:58:44.960] <TB0> INFO: dumping ASCII scurve output file: XSCurveData
[15:58:46.343] <TB0> INFO: dumping ASCII scurve output file: XSCurveData
[15:58:47.736] <TB0> INFO: dumping ASCII scurve output file: XSCurveData
[15:58:49.080] <TB0> INFO: dumping ASCII scurve output file: XSCurveData
[15:58:50.402] <TB0> INFO: dumping ASCII scurve output file: XSCurveData
[15:58:51.652] <TB0> INFO: dumping ASCII scurve output file: XSCurveData
[15:58:52.911] <TB0> INFO: dumping ASCII scurve output file: XSCurveData
[15:58:54.144] <TB0> INFO: PixTest:: pg_setup set to default.
[15:58:54.145] <TB0> INFO: vcal mean: 38.10 38.58 38.19 41.15 41.33 41.53 43.32 42.16 41.43 41.96 42.55 40.83 40.55 37.95 38.91 38.30
[15:58:54.145] <TB0> INFO: vcal RMS: 1.27 1.24 1.15 1.40 1.33 1.44 1.57 1.41 1.57 1.50 1.55 1.52 1.32 1.22 1.19 1.22
[15:58:54.145] <TB0> INFO: number of X-ray hits detected: 164197 174178 168537 297298 306402 343778 379158 369522 320148 344757 367769 292787 274746 150594 168543 161096
[15:58:54.145] <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
[15:58:54.145] <TB0> INFO: X-ray hit rate [MHz/cm2]: 48.1 51.1 49.4 87.1 89.8 100.8 111.1 108.3 93.8 101.1 107.8 85.8 80.5 44.1 49.4 47.2
[15:58:54.145] <TB0> INFO: PixTestHighRate::doXNoiseMaps() done
[15:58:54.240] <TB0> INFO: enter test to run
[15:58:54.240] <TB0> INFO: test: no parameter change
[15:58:54.388] <TB0> QUIET: Connection to board 147 closed.
[15:58:54.468] <TB0> INFO: pXar: this is the end, my friend
commander_CalDelScanAndSaveDacs.log
[16:18:33.788] <TB0> INFO: *** Welcome to pxar ***
[16:18:33.788] <TB0> INFO: *** Today: 2015/08/11
[16:18:33.788] <TB0> INFO: readRocDacs: /usr/local/data/M2045_XrayQualification_2015-08-11_16h58m_1439305117//010_CalDelScanAndSaveDacs_4mA25kV//dacParameters35_C0.dat .. /usr/local/data/M2045_XrayQualification_2015-08-11_16h58m_1439305117//010_CalDelScanAndSaveDacs_4mA25kV//dacParameters35_C15.dat
[16:18:33.789] <TB0> INFO: readTbmDacs: /usr/local/data/M2045_XrayQualification_2015-08-11_16h58m_1439305117//010_CalDelScanAndSaveDacs_4mA25kV//tbmParameters_C0a.dat .. /usr/local/data/M2045_XrayQualification_2015-08-11_16h58m_1439305117//010_CalDelScanAndSaveDacs_4mA25kV//tbmParameters_C0b.dat
[16:18:33.789] <TB0> INFO: readMaskFile: /usr/local/data/M2045_XrayQualification_2015-08-11_16h58m_1439305117//010_CalDelScanAndSaveDacs_4mA25kV//defaultMaskFile.dat
[16:18:33.789] <TB0> INFO: readTrimFile: /usr/local/data/M2045_XrayQualification_2015-08-11_16h58m_1439305117//010_CalDelScanAndSaveDacs_4mA25kV//trimParameters35_C0.dat .. /usr/local/data/M2045_XrayQualification_2015-08-11_16h58m_1439305117//010_CalDelScanAndSaveDacs_4mA25kV//trimParameters35_C15.dat
[16:18:33.860] <TB0> INFO: clk: 4
[16:18:33.860] <TB0> INFO: ctr: 4
[16:18:33.860] <TB0> INFO: sda: 19
[16:18:33.860] <TB0> INFO: tin: 9
[16:18:33.860] <TB0> INFO: level: 15
[16:18:33.860] <TB0> INFO: triggerdelay: 0
[16:18:33.860] <TB0> QUIET: Instanciating API for pxar v2.2.5+88~g694c14c
[16:18:33.860] <TB0> INFO: Log level: INFO
[16:18:34.870] <TB0> INFO: Found DTB DTB_WWXTQT
[16:18:37.880] <TB0> QUIET: Connection to board DTB_WWXTQT opened.
[16:18:37.884] <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:
------------------------------------------------------
[16:18:37.886] <TB0> INFO: RPC call hashes of host and DTB match: 447413373
[16:18:39.403] <TB0> INFO: DUT info:
[16:18:39.403] <TB0> INFO: The DUT currently contains the following objects:
[16:18:39.403] <TB0> INFO: 2 TBM Cores tbm09c (2 ON)
[16:18:39.403] <TB0> INFO: TBM Core alpha (0): 7 registers set
[16:18:39.403] <TB0> INFO: TBM Core beta (1): 7 registers set
[16:18:39.403] <TB0> INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[16:18:39.403] <TB0> INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[16:18:39.403] <TB0> INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[16:18:39.403] <TB0> INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[16:18:39.403] <TB0> INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[16:18:39.403] <TB0> INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[16:18:39.403] <TB0> INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[16:18:39.403] <TB0> INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[16:18:39.403] <TB0> INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[16:18:39.403] <TB0> INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[16:18:39.403] <TB0> INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[16:18:39.403] <TB0> INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[16:18:39.403] <TB0> INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[16:18:39.403] <TB0> INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[16:18:39.403] <TB0> INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[16:18:39.403] <TB0> INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[16:18:39.403] <TB0> INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[16:18:39.804] <TB0> INFO: enter 'restricted' command line mode
[16:18:39.804] <TB0> INFO: enter test to run
[16:18:39.804] <TB0> INFO: test: HighRate no parameter change
[16:18:39.804] <TB0> INFO: running: highrate
[16:18:39.805] <TB0> INFO: readGainPedestalParameters /usr/local/data/M2045_XrayQualification_2015-08-11_16h58m_1439305117//010_CalDelScanAndSaveDacs_4mA25kV//phCalibrationFitErr35_C0.dat .. /usr/local/data/M2045_XrayQualification_2015-08-11_16h58m_1439305117//010_CalDelScanAndSaveDacs_4mA25kV//phCalibrationFitErr35_C15.dat
[16:18:39.948] <TB0> INFO: ----------------------------------------------------------------------
[16:18:39.948] <TB0> INFO: PixTestHighRate::calDelScan() ntrig = 10, vcal = 200
[16:18:39.948] <TB0> INFO: ----------------------------------------------------------------------
[16:18:41.030] <TB0> INFO: Test took 1076ms.
[16:18:44.316] <TB0> INFO: Test took 3283ms.
[16:18:44.449] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[16:18:47.950] <TB0> INFO: Test took 3477ms.
[16:18:48.097] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[16:18:51.210] <TB0> ERROR: <datapipe.cc/CheckEventValidity:L437> Channel 2 Number of ROCs (3) != Token Chain Length (4)

[16:18:51.210] <TB0> ERROR: <datapipe.cc/CheckEventValidity:L437> Channel 3 Number of ROCs (3) != Token Chain Length (4)

[16:18:51.793] <TB0> INFO: Test took 3655ms.
[16:18:51.926] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[16:18:55.820] <TB0> INFO: Test took 3869ms.
[16:18:55.958] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[16:18:59.839] <TB0> INFO: Test took 3855ms.
[16:19:00.013] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[16:19:04.215] <TB0> INFO: Test took 4168ms.
[16:19:04.373] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[16:19:08.331] <TB0> INFO: Test took 3926ms.
[16:19:08.472] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[16:19:12.391] <TB0> INFO: Test took 3892ms.
[16:19:12.535] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[16:19:16.352] <TB0> INFO: Test took 3790ms.
[16:19:16.513] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[16:19:20.659] <TB0> INFO: Test took 4113ms.
[16:19:20.801] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[16:19:24.783] <TB0> INFO: Test took 3955ms.
[16:19:24.932] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[16:19:28.987] <TB0> INFO: Test took 4028ms.
[16:19:29.141] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[16:19:33.353] <TB0> INFO: Test took 4183ms.
[16:19:33.504] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[16:19:35.786] <TB0> ERROR: <datapipe.cc/CheckEventValidity:L437> Channel 2 Number of ROCs (2) != Token Chain Length (4)

[16:19:35.787] <TB0> WARNING: Channel 2 ROC 3: Readback start marker after 15 readouts!

[16:19:37.531] <TB0> INFO: Test took 3998ms.
[16:19:37.682] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[16:19:41.920] <TB0> INFO: Test took 4209ms.
[16:19:42.073] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[16:19:46.127] <TB0> INFO: Test took 4025ms.
[16:19:46.280] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[16:19:48.346] <TB0> ERROR: <datapipe.cc/CheckEventValidity:L437> Channel 2 Number of ROCs (2) != Token Chain Length (4)

[16:19:48.346] <TB0> ERROR: <datapipe.cc/CheckEventValidity:L437> Channel 3 Number of ROCs (2) != Token Chain Length (4)

[16:19:48.346] <TB0> WARNING: Channel 2 ROC 3: Readback start marker after 15 readouts!

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

[16:19:50.186] <TB0> INFO: Test took 3877ms.
[16:19:50.330] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[16:19:54.135] <TB0> INFO: Test took 3779ms.
[16:19:54.274] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[16:19:58.107] <TB0> INFO: Test took 3806ms.
[16:19:58.253] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[16:20:01.806] <TB0> INFO: Test took 3524ms.
[16:20:01.946] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[16:20:02.278] <TB0> INFO: enter test to run
[16:20:02.278] <TB0> INFO: test: Pretest no parameter change
[16:20:02.278] <TB0> INFO: running: pretest
[16:20:02.278] <TB0> INFO: write dac parameters into /usr/local/data/M2045_XrayQualification_2015-08-11_16h58m_1439305117//010_CalDelScanAndSaveDacs_4mA25kV//dacParameters35_C0.dat
[16:20:02.278] <TB0> INFO: write dac parameters into /usr/local/data/M2045_XrayQualification_2015-08-11_16h58m_1439305117//010_CalDelScanAndSaveDacs_4mA25kV//dacParameters35_C1.dat
[16:20:02.278] <TB0> INFO: write dac parameters into /usr/local/data/M2045_XrayQualification_2015-08-11_16h58m_1439305117//010_CalDelScanAndSaveDacs_4mA25kV//dacParameters35_C2.dat
[16:20:02.278] <TB0> INFO: write dac parameters into /usr/local/data/M2045_XrayQualification_2015-08-11_16h58m_1439305117//010_CalDelScanAndSaveDacs_4mA25kV//dacParameters35_C3.dat
[16:20:02.279] <TB0> INFO: write dac parameters into /usr/local/data/M2045_XrayQualification_2015-08-11_16h58m_1439305117//010_CalDelScanAndSaveDacs_4mA25kV//dacParameters35_C4.dat
[16:20:02.279] <TB0> INFO: write dac parameters into /usr/local/data/M2045_XrayQualification_2015-08-11_16h58m_1439305117//010_CalDelScanAndSaveDacs_4mA25kV//dacParameters35_C5.dat
[16:20:02.279] <TB0> INFO: write dac parameters into /usr/local/data/M2045_XrayQualification_2015-08-11_16h58m_1439305117//010_CalDelScanAndSaveDacs_4mA25kV//dacParameters35_C6.dat
[16:20:02.279] <TB0> INFO: write dac parameters into /usr/local/data/M2045_XrayQualification_2015-08-11_16h58m_1439305117//010_CalDelScanAndSaveDacs_4mA25kV//dacParameters35_C7.dat
[16:20:02.279] <TB0> INFO: write dac parameters into /usr/local/data/M2045_XrayQualification_2015-08-11_16h58m_1439305117//010_CalDelScanAndSaveDacs_4mA25kV//dacParameters35_C8.dat
[16:20:02.279] <TB0> INFO: write dac parameters into /usr/local/data/M2045_XrayQualification_2015-08-11_16h58m_1439305117//010_CalDelScanAndSaveDacs_4mA25kV//dacParameters35_C9.dat
[16:20:02.279] <TB0> INFO: write dac parameters into /usr/local/data/M2045_XrayQualification_2015-08-11_16h58m_1439305117//010_CalDelScanAndSaveDacs_4mA25kV//dacParameters35_C10.dat
[16:20:02.279] <TB0> INFO: write dac parameters into /usr/local/data/M2045_XrayQualification_2015-08-11_16h58m_1439305117//010_CalDelScanAndSaveDacs_4mA25kV//dacParameters35_C11.dat
[16:20:02.279] <TB0> INFO: write dac parameters into /usr/local/data/M2045_XrayQualification_2015-08-11_16h58m_1439305117//010_CalDelScanAndSaveDacs_4mA25kV//dacParameters35_C12.dat
[16:20:02.279] <TB0> INFO: write dac parameters into /usr/local/data/M2045_XrayQualification_2015-08-11_16h58m_1439305117//010_CalDelScanAndSaveDacs_4mA25kV//dacParameters35_C13.dat
[16:20:02.279] <TB0> INFO: write dac parameters into /usr/local/data/M2045_XrayQualification_2015-08-11_16h58m_1439305117//010_CalDelScanAndSaveDacs_4mA25kV//dacParameters35_C14.dat
[16:20:02.280] <TB0> INFO: write dac parameters into /usr/local/data/M2045_XrayQualification_2015-08-11_16h58m_1439305117//010_CalDelScanAndSaveDacs_4mA25kV//dacParameters35_C15.dat
[16:20:02.280] <TB0> INFO: enter test to run
[16:20:02.280] <TB0> INFO: test: no parameter change
[16:20:02.442] <TB0> QUIET: Connection to board 147 closed.
[16:20:02.521] <TB0> INFO: pXar: this is the end, my friend
commander_HREfficiency.log
[16:20:35.097] <TB0> INFO: *** Welcome to pxar ***
[16:20:35.097] <TB0> INFO: *** Today: 2015/08/11
[16:20:35.097] <TB0> INFO: readRocDacs: /usr/local/data/M2045_XrayQualification_2015-08-11_16h58m_1439305117//011_HREfficiency_50//dacParameters35_C0.dat .. /usr/local/data/M2045_XrayQualification_2015-08-11_16h58m_1439305117//011_HREfficiency_50//dacParameters35_C15.dat
[16:20:35.098] <TB0> INFO: readTbmDacs: /usr/local/data/M2045_XrayQualification_2015-08-11_16h58m_1439305117//011_HREfficiency_50//tbmParameters_C0a.dat .. /usr/local/data/M2045_XrayQualification_2015-08-11_16h58m_1439305117//011_HREfficiency_50//tbmParameters_C0b.dat
[16:20:35.098] <TB0> INFO: readMaskFile: /usr/local/data/M2045_XrayQualification_2015-08-11_16h58m_1439305117//011_HREfficiency_50//defaultMaskFile.dat
[16:20:35.098] <TB0> INFO: readTrimFile: /usr/local/data/M2045_XrayQualification_2015-08-11_16h58m_1439305117//011_HREfficiency_50//trimParameters35_C0.dat .. /usr/local/data/M2045_XrayQualification_2015-08-11_16h58m_1439305117//011_HREfficiency_50//trimParameters35_C15.dat
[16:20:35.165] <TB0> INFO: clk: 4
[16:20:35.165] <TB0> INFO: ctr: 4
[16:20:35.165] <TB0> INFO: sda: 19
[16:20:35.165] <TB0> INFO: tin: 9
[16:20:35.165] <TB0> INFO: level: 15
[16:20:35.165] <TB0> INFO: triggerdelay: 0
[16:20:35.165] <TB0> QUIET: Instanciating API for pxar v2.2.5+88~g694c14c
[16:20:35.165] <TB0> INFO: Log level: INFO
[16:20:36.175] <TB0> INFO: Found DTB DTB_WWXTQT
[16:20:39.186] <TB0> QUIET: Connection to board DTB_WWXTQT opened.
[16:20:39.190] <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:
------------------------------------------------------
[16:20:39.192] <TB0> INFO: RPC call hashes of host and DTB match: 447413373
[16:20:40.703] <TB0> INFO: DUT info:
[16:20:40.703] <TB0> INFO: The DUT currently contains the following objects:
[16:20:40.703] <TB0> INFO: 2 TBM Cores tbm09c (2 ON)
[16:20:40.703] <TB0> INFO: TBM Core alpha (0): 7 registers set
[16:20:40.703] <TB0> INFO: TBM Core beta (1): 7 registers set
[16:20:40.704] <TB0> INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[16:20:40.704] <TB0> INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[16:20:40.704] <TB0> INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[16:20:40.704] <TB0> INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[16:20:40.704] <TB0> INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[16:20:40.704] <TB0> INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[16:20:40.704] <TB0> INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[16:20:40.704] <TB0> INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[16:20:40.704] <TB0> INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[16:20:40.704] <TB0> INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[16:20:40.704] <TB0> INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[16:20:40.704] <TB0> INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[16:20:40.704] <TB0> INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[16:20:40.704] <TB0> INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[16:20:40.704] <TB0> INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[16:20:40.704] <TB0> INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[16:20:40.704] <TB0> INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[16:20:41.107] <TB0> INFO: enter 'restricted' command line mode
[16:20:41.107] <TB0> INFO: enter test to run
[16:20:41.107] <TB0> INFO: test: HighRate no parameter change
[16:20:41.107] <TB0> INFO: running: highrate
[16:20:41.108] <TB0> INFO: readGainPedestalParameters /usr/local/data/M2045_XrayQualification_2015-08-11_16h58m_1439305117//011_HREfficiency_50//phCalibrationFitErr35_C0.dat .. /usr/local/data/M2045_XrayQualification_2015-08-11_16h58m_1439305117//011_HREfficiency_50//phCalibrationFitErr35_C15.dat
[16:20:41.246] <TB0> INFO: ----------------------------------------------------------------------
[16:20:41.246] <TB0> INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[16:20:41.246] <TB0> INFO: ----------------------------------------------------------------------
[16:20:53.144] <TB0> INFO: Test took 11892ms.
[16:20:53.870] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[16:20:54.175] <TB0> INFO: number of dead pixels (per ROC): 0 0 0 0 0 0 0 0 1 0 0 0 0 0 0 0
[16:20:54.175] <TB0> INFO: number of red-efficiency pixels: 90 113 94 201 212 228 292 319 224 270 290 197 173 91 129 116
[16:20:54.175] <TB0> INFO: number of X-ray hits detected: 80785 86459 82750 150401 153284 171844 191128 187933 159946 174119 186698 147161 138906 74131 83540 80461
[16:20:54.175] <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
[16:20:54.175] <TB0> INFO: number of Vcal hits detected: 207909 207885 207902 207794 207783 207756 207697 207668 207720 207724 207695 207795 207820 207907 207869 207882
[16:20:54.175] <TB0> INFO: Vcal hit fiducial efficiency (%): 100.0 99.9 100.0 99.9 99.9 99.9 99.9 99.9 99.9 99.9 99.9 99.9 99.9 100.0 99.9 99.9
[16:20:54.175] <TB0> INFO: Vcal hit overall efficiency (%): 100.0 99.9 100.0 99.9 99.9 99.9 99.9 99.8 99.9 99.9 99.9 99.9 99.9 100.0 99.9 99.9
[16:20:54.175] <TB0> INFO: X-ray hit rate [MHz/cm2]: 23.7 25.3 24.3 44.1 44.9 50.4 56.0 55.1 46.9 51.0 54.7 43.1 40.7 21.7 24.5 23.6
[16:20:54.175] <TB0> INFO: PixTestHighRate::doXPixelAlive() done
[16:20:54.194] <TB0> INFO: PixTest:: pg_setup set to default.
[16:20:54.228] <TB0> INFO: enter test to run
[16:20:54.228] <TB0> INFO: test: no parameter change
[16:20:54.390] <TB0> QUIET: Connection to board 147 closed.
[16:20:54.470] <TB0> INFO: pXar: this is the end, my friend
commander_HREfficiency.log
[16:21:28.812] <TB0> INFO: *** Welcome to pxar ***
[16:21:28.812] <TB0> INFO: *** Today: 2015/08/11
[16:21:28.812] <TB0> INFO: readRocDacs: /usr/local/data/M2045_XrayQualification_2015-08-11_16h58m_1439305117//012_HREfficiency_100//dacParameters35_C0.dat .. /usr/local/data/M2045_XrayQualification_2015-08-11_16h58m_1439305117//012_HREfficiency_100//dacParameters35_C15.dat
[16:21:28.812] <TB0> INFO: readTbmDacs: /usr/local/data/M2045_XrayQualification_2015-08-11_16h58m_1439305117//012_HREfficiency_100//tbmParameters_C0a.dat .. /usr/local/data/M2045_XrayQualification_2015-08-11_16h58m_1439305117//012_HREfficiency_100//tbmParameters_C0b.dat
[16:21:28.812] <TB0> INFO: readMaskFile: /usr/local/data/M2045_XrayQualification_2015-08-11_16h58m_1439305117//012_HREfficiency_100//defaultMaskFile.dat
[16:21:28.812] <TB0> INFO: readTrimFile: /usr/local/data/M2045_XrayQualification_2015-08-11_16h58m_1439305117//012_HREfficiency_100//trimParameters35_C0.dat .. /usr/local/data/M2045_XrayQualification_2015-08-11_16h58m_1439305117//012_HREfficiency_100//trimParameters35_C15.dat
[16:21:28.876] <TB0> INFO: clk: 4
[16:21:28.876] <TB0> INFO: ctr: 4
[16:21:28.877] <TB0> INFO: sda: 19
[16:21:28.877] <TB0> INFO: tin: 9
[16:21:28.877] <TB0> INFO: level: 15
[16:21:28.877] <TB0> INFO: triggerdelay: 0
[16:21:28.877] <TB0> QUIET: Instanciating API for pxar v2.2.5+88~g694c14c
[16:21:28.877] <TB0> INFO: Log level: INFO
[16:21:29.887] <TB0> INFO: Found DTB DTB_WWXTQT
[16:21:32.898] <TB0> QUIET: Connection to board DTB_WWXTQT opened.
[16:21:32.901] <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:
------------------------------------------------------
[16:21:32.904] <TB0> INFO: RPC call hashes of host and DTB match: 447413373
[16:21:34.418] <TB0> INFO: DUT info:
[16:21:34.418] <TB0> INFO: The DUT currently contains the following objects:
[16:21:34.418] <TB0> INFO: 2 TBM Cores tbm09c (2 ON)
[16:21:34.418] <TB0> INFO: TBM Core alpha (0): 7 registers set
[16:21:34.418] <TB0> INFO: TBM Core beta (1): 7 registers set
[16:21:34.418] <TB0> INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[16:21:34.418] <TB0> INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[16:21:34.418] <TB0> INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[16:21:34.418] <TB0> INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[16:21:34.418] <TB0> INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[16:21:34.418] <TB0> INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[16:21:34.418] <TB0> INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[16:21:34.418] <TB0> INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[16:21:34.418] <TB0> INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[16:21:34.418] <TB0> INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[16:21:34.418] <TB0> INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[16:21:34.418] <TB0> INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[16:21:34.418] <TB0> INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[16:21:34.418] <TB0> INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[16:21:34.418] <TB0> INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[16:21:34.418] <TB0> INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[16:21:34.418] <TB0> INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[16:21:34.819] <TB0> INFO: enter 'restricted' command line mode
[16:21:34.819] <TB0> INFO: enter test to run
[16:21:34.819] <TB0> INFO: test: HighRate no parameter change
[16:21:34.819] <TB0> INFO: running: highrate
[16:21:34.819] <TB0> INFO: readGainPedestalParameters /usr/local/data/M2045_XrayQualification_2015-08-11_16h58m_1439305117//012_HREfficiency_100//phCalibrationFitErr35_C0.dat .. /usr/local/data/M2045_XrayQualification_2015-08-11_16h58m_1439305117//012_HREfficiency_100//phCalibrationFitErr35_C15.dat
[16:21:34.945] <TB0> INFO: ----------------------------------------------------------------------
[16:21:34.945] <TB0> INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[16:21:34.945] <TB0> INFO: ----------------------------------------------------------------------
[16:21:49.238] <TB0> INFO: Test took 14287ms.
[16:21:51.394] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[16:21:51.769] <TB0> INFO: number of dead pixels (per ROC): 0 0 0 0 0 0 0 0 1 0 0 0 0 0 0 0
[16:21:51.769] <TB0> INFO: number of red-efficiency pixels: 235 271 308 655 714 864 999 1028 805 915 1058 690 634 274 345 305
[16:21:51.769] <TB0> INFO: number of X-ray hits detected: 163983 175797 168050 298798 307493 344803 380356 376408 321565 346626 368781 293980 275612 151096 169201 160826
[16:21:51.769] <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
[16:21:51.769] <TB0> INFO: number of Vcal hits detected: 207745 207724 207664 207229 207161 206977 206731 206769 207033 206851 206699 207220 207285 207696 207636 207684
[16:21:51.769] <TB0> INFO: Vcal hit fiducial efficiency (%): 99.9 99.9 99.9 99.7 99.6 99.6 99.5 99.5 99.6 99.5 99.4 99.7 99.7 99.9 99.8 99.9
[16:21:51.769] <TB0> INFO: Vcal hit overall efficiency (%): 99.9 99.9 99.8 99.6 99.6 99.5 99.4 99.4 99.5 99.4 99.4 99.6 99.7 99.9 99.8 99.8
[16:21:51.769] <TB0> INFO: X-ray hit rate [MHz/cm2]: 48.1 51.5 49.3 87.6 90.1 101.1 111.5 110.3 94.3 101.6 108.1 86.2 80.8 44.3 49.6 47.1
[16:21:51.769] <TB0> INFO: PixTestHighRate::doXPixelAlive() done
[16:21:51.799] <TB0> INFO: PixTest:: pg_setup set to default.
[16:21:51.833] <TB0> INFO: enter test to run
[16:21:51.833] <TB0> INFO: test: no parameter change
[16:21:51.987] <TB0> QUIET: Connection to board 147 closed.
[16:21:52.066] <TB0> INFO: pXar: this is the end, my friend
commander_HREfficiency.log
[16:22:29.214] <TB0> INFO: *** Welcome to pxar ***
[16:22:29.214] <TB0> INFO: *** Today: 2015/08/11
[16:22:29.214] <TB0> INFO: readRocDacs: /usr/local/data/M2045_XrayQualification_2015-08-11_16h58m_1439305117//013_HREfficiency_150//dacParameters35_C0.dat .. /usr/local/data/M2045_XrayQualification_2015-08-11_16h58m_1439305117//013_HREfficiency_150//dacParameters35_C15.dat
[16:22:29.215] <TB0> INFO: readTbmDacs: /usr/local/data/M2045_XrayQualification_2015-08-11_16h58m_1439305117//013_HREfficiency_150//tbmParameters_C0a.dat .. /usr/local/data/M2045_XrayQualification_2015-08-11_16h58m_1439305117//013_HREfficiency_150//tbmParameters_C0b.dat
[16:22:29.215] <TB0> INFO: readMaskFile: /usr/local/data/M2045_XrayQualification_2015-08-11_16h58m_1439305117//013_HREfficiency_150//defaultMaskFile.dat
[16:22:29.215] <TB0> INFO: readTrimFile: /usr/local/data/M2045_XrayQualification_2015-08-11_16h58m_1439305117//013_HREfficiency_150//trimParameters35_C0.dat .. /usr/local/data/M2045_XrayQualification_2015-08-11_16h58m_1439305117//013_HREfficiency_150//trimParameters35_C15.dat
[16:22:29.280] <TB0> INFO: clk: 4
[16:22:29.280] <TB0> INFO: ctr: 4
[16:22:29.280] <TB0> INFO: sda: 19
[16:22:29.280] <TB0> INFO: tin: 9
[16:22:29.280] <TB0> INFO: level: 15
[16:22:29.280] <TB0> INFO: triggerdelay: 0
[16:22:29.281] <TB0> QUIET: Instanciating API for pxar v2.2.5+88~g694c14c
[16:22:29.281] <TB0> INFO: Log level: INFO
[16:22:30.290] <TB0> INFO: Found DTB DTB_WWXTQT
[16:22:33.301] <TB0> QUIET: Connection to board DTB_WWXTQT opened.
[16:22:33.304] <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:
------------------------------------------------------
[16:22:33.307] <TB0> INFO: RPC call hashes of host and DTB match: 447413373
[16:22:34.822] <TB0> INFO: DUT info:
[16:22:34.822] <TB0> INFO: The DUT currently contains the following objects:
[16:22:34.822] <TB0> INFO: 2 TBM Cores tbm09c (2 ON)
[16:22:34.822] <TB0> INFO: TBM Core alpha (0): 7 registers set
[16:22:34.822] <TB0> INFO: TBM Core beta (1): 7 registers set
[16:22:34.822] <TB0> INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[16:22:34.822] <TB0> INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[16:22:34.822] <TB0> INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[16:22:34.822] <TB0> INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[16:22:34.822] <TB0> INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[16:22:34.823] <TB0> INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[16:22:34.823] <TB0> INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[16:22:34.823] <TB0> INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[16:22:34.823] <TB0> INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[16:22:34.823] <TB0> INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[16:22:34.823] <TB0> INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[16:22:34.823] <TB0> INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[16:22:34.823] <TB0> INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[16:22:34.823] <TB0> INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[16:22:34.823] <TB0> INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[16:22:34.823] <TB0> INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[16:22:34.823] <TB0> INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[16:22:35.224] <TB0> INFO: enter 'restricted' command line mode
[16:22:35.224] <TB0> INFO: enter test to run
[16:22:35.224] <TB0> INFO: test: HighRate no parameter change
[16:22:35.224] <TB0> INFO: running: highrate
[16:22:35.224] <TB0> INFO: readGainPedestalParameters /usr/local/data/M2045_XrayQualification_2015-08-11_16h58m_1439305117//013_HREfficiency_150//phCalibrationFitErr35_C0.dat .. /usr/local/data/M2045_XrayQualification_2015-08-11_16h58m_1439305117//013_HREfficiency_150//phCalibrationFitErr35_C15.dat
[16:22:35.352] <TB0> INFO: ----------------------------------------------------------------------
[16:22:35.352] <TB0> INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[16:22:35.352] <TB0> INFO: ----------------------------------------------------------------------
[16:22:52.647] <TB0> INFO: Test took 17290ms.
[16:22:56.423] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[16:22:56.882] <TB0> INFO: number of dead pixels (per ROC): 0 0 0 0 0 0 0 0 1 0 0 0 0 0 0 0
[16:22:56.882] <TB0> INFO: number of red-efficiency pixels: 450 588 567 1385 1508 1868 2017 2173 1694 1896 2110 1344 1278 505 629 579
[16:22:56.882] <TB0> INFO: number of X-ray hits detected: 237311 255574 244368 427609 440710 494087 544410 540391 465190 498368 526015 423380 397317 219251 247859 235460
[16:22:56.883] <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
[16:22:56.883] <TB0> INFO: number of Vcal hits detected: 207516 207361 207276 206118 205803 205075 204460 204630 205587 204894 204497 206169 206290 207335 207306 207371
[16:22:56.883] <TB0> INFO: Vcal hit fiducial efficiency (%): 99.8 99.7 99.7 99.2 99.1 98.8 98.6 98.5 99.0 98.7 98.5 99.2 99.3 99.7 99.7 99.7
[16:22:56.883] <TB0> INFO: Vcal hit overall efficiency (%): 99.8 99.7 99.7 99.1 98.9 98.6 98.3 98.4 98.8 98.5 98.3 99.1 99.2 99.7 99.7 99.7
[16:22:56.883] <TB0> INFO: X-ray hit rate [MHz/cm2]: 69.6 74.9 71.6 125.3 129.2 144.8 159.6 158.4 136.4 146.1 154.2 124.1 116.5 64.3 72.6 69.0
[16:22:56.883] <TB0> INFO: PixTestHighRate::doXPixelAlive() done
[16:22:56.916] <TB0> INFO: PixTest:: pg_setup set to default.
[16:22:56.969] <TB0> INFO: enter test to run
[16:22:56.969] <TB0> INFO: test: no parameter change
[16:22:57.099] <TB0> QUIET: Connection to board 147 closed.
[16:22:57.179] <TB0> INFO: pXar: this is the end, my friend
commander_HREfficiency.log
[16:23:30.613] <TB0> INFO: *** Welcome to pxar ***
[16:23:30.613] <TB0> INFO: *** Today: 2015/08/11
[16:23:30.613] <TB0> INFO: readRocDacs: /usr/local/data/M2045_XrayQualification_2015-08-11_16h58m_1439305117//014_HREfficiency_200//dacParameters35_C0.dat .. /usr/local/data/M2045_XrayQualification_2015-08-11_16h58m_1439305117//014_HREfficiency_200//dacParameters35_C15.dat
[16:23:30.614] <TB0> INFO: readTbmDacs: /usr/local/data/M2045_XrayQualification_2015-08-11_16h58m_1439305117//014_HREfficiency_200//tbmParameters_C0a.dat .. /usr/local/data/M2045_XrayQualification_2015-08-11_16h58m_1439305117//014_HREfficiency_200//tbmParameters_C0b.dat
[16:23:30.614] <TB0> INFO: readMaskFile: /usr/local/data/M2045_XrayQualification_2015-08-11_16h58m_1439305117//014_HREfficiency_200//defaultMaskFile.dat
[16:23:30.614] <TB0> INFO: readTrimFile: /usr/local/data/M2045_XrayQualification_2015-08-11_16h58m_1439305117//014_HREfficiency_200//trimParameters35_C0.dat .. /usr/local/data/M2045_XrayQualification_2015-08-11_16h58m_1439305117//014_HREfficiency_200//trimParameters35_C15.dat
[16:23:30.680] <TB0> INFO: clk: 4
[16:23:30.680] <TB0> INFO: ctr: 4
[16:23:30.680] <TB0> INFO: sda: 19
[16:23:30.680] <TB0> INFO: tin: 9
[16:23:30.680] <TB0> INFO: level: 15
[16:23:30.680] <TB0> INFO: triggerdelay: 0
[16:23:30.680] <TB0> QUIET: Instanciating API for pxar v2.2.5+88~g694c14c
[16:23:30.680] <TB0> INFO: Log level: INFO
[16:23:31.689] <TB0> INFO: Found DTB DTB_WWXTQT
[16:23:34.700] <TB0> QUIET: Connection to board DTB_WWXTQT opened.
[16:23:34.703] <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:
------------------------------------------------------
[16:23:34.706] <TB0> INFO: RPC call hashes of host and DTB match: 447413373
[16:23:36.218] <TB0> INFO: DUT info:
[16:23:36.218] <TB0> INFO: The DUT currently contains the following objects:
[16:23:36.218] <TB0> INFO: 2 TBM Cores tbm09c (2 ON)
[16:23:36.218] <TB0> INFO: TBM Core alpha (0): 7 registers set
[16:23:36.218] <TB0> INFO: TBM Core beta (1): 7 registers set
[16:23:36.218] <TB0> INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[16:23:36.218] <TB0> INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[16:23:36.218] <TB0> INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[16:23:36.218] <TB0> INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[16:23:36.218] <TB0> INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[16:23:36.218] <TB0> INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[16:23:36.218] <TB0> INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[16:23:36.218] <TB0> INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[16:23:36.218] <TB0> INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[16:23:36.218] <TB0> INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[16:23:36.218] <TB0> INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[16:23:36.218] <TB0> INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[16:23:36.218] <TB0> INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[16:23:36.218] <TB0> INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[16:23:36.218] <TB0> INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[16:23:36.218] <TB0> INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[16:23:36.218] <TB0> INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[16:23:36.619] <TB0> INFO: enter 'restricted' command line mode
[16:23:36.619] <TB0> INFO: enter test to run
[16:23:36.619] <TB0> INFO: test: HighRate no parameter change
[16:23:36.619] <TB0> INFO: running: highrate
[16:23:36.619] <TB0> INFO: readGainPedestalParameters /usr/local/data/M2045_XrayQualification_2015-08-11_16h58m_1439305117//014_HREfficiency_200//phCalibrationFitErr35_C0.dat .. /usr/local/data/M2045_XrayQualification_2015-08-11_16h58m_1439305117//014_HREfficiency_200//phCalibrationFitErr35_C15.dat
[16:23:36.747] <TB0> INFO: ----------------------------------------------------------------------
[16:23:36.747] <TB0> INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[16:23:36.747] <TB0> INFO: ----------------------------------------------------------------------
[16:23:56.193] <TB0> INFO: Test took 19439ms.
[16:24:02.121] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[16:24:02.698] <TB0> INFO: number of dead pixels (per ROC): 0 0 0 0 0 0 0 0 1 0 0 0 0 0 0 0
[16:24:02.698] <TB0> INFO: number of red-efficiency pixels: 798 1018 985 2301 2487 3025 3189 3399 2818 3042 3269 2324 2308 914 1214 1057
[16:24:02.698] <TB0> INFO: number of X-ray hits detected: 324227 347091 332835 571615 594306 665372 731796 728816 628811 672163 704559 570595 537779 300022 334979 319311
[16:24:02.698] <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
[16:24:02.698] <TB0> INFO: number of Vcal hits detected: 207083 206769 206303 203719 202977 200845 199406 199534 202227 200603 199495 203648 203879 206493 206511 206732
[16:24:02.698] <TB0> INFO: Vcal hit fiducial efficiency (%): 99.6 99.5 99.3 98.2 97.9 97.0 96.4 96.3 97.6 96.9 96.4 98.2 98.3 99.4 99.3 99.5
[16:24:02.698] <TB0> INFO: Vcal hit overall efficiency (%): 99.6 99.4 99.2 97.9 97.6 96.6 95.9 95.9 97.2 96.4 95.9 97.9 98.0 99.3 99.3 99.4
[16:24:02.698] <TB0> INFO: X-ray hit rate [MHz/cm2]: 95.0 101.7 97.6 167.5 174.2 195.0 214.5 213.6 184.3 197.0 206.5 167.2 157.6 87.9 98.2 93.6
[16:24:02.698] <TB0> INFO: PixTestHighRate::doXPixelAlive() done
[16:24:02.739] <TB0> INFO: PixTest:: pg_setup set to default.
[16:24:02.786] <TB0> INFO: enter test to run
[16:24:02.786] <TB0> INFO: test: no parameter change
[16:24:02.997] <TB0> QUIET: Connection to board 147 closed.
[16:24:03.076] <TB0> INFO: pXar: this is the end, my friend
commander_HREfficiency.log
[16:24:36.751] <TB0> INFO: *** Welcome to pxar ***
[16:24:36.751] <TB0> INFO: *** Today: 2015/08/11
[16:24:36.751] <TB0> INFO: readRocDacs: /usr/local/data/M2045_XrayQualification_2015-08-11_16h58m_1439305117//015_HREfficiency_250//dacParameters35_C0.dat .. /usr/local/data/M2045_XrayQualification_2015-08-11_16h58m_1439305117//015_HREfficiency_250//dacParameters35_C15.dat
[16:24:36.751] <TB0> INFO: readTbmDacs: /usr/local/data/M2045_XrayQualification_2015-08-11_16h58m_1439305117//015_HREfficiency_250//tbmParameters_C0a.dat .. /usr/local/data/M2045_XrayQualification_2015-08-11_16h58m_1439305117//015_HREfficiency_250//tbmParameters_C0b.dat
[16:24:36.751] <TB0> INFO: readMaskFile: /usr/local/data/M2045_XrayQualification_2015-08-11_16h58m_1439305117//015_HREfficiency_250//defaultMaskFile.dat
[16:24:36.752] <TB0> INFO: readTrimFile: /usr/local/data/M2045_XrayQualification_2015-08-11_16h58m_1439305117//015_HREfficiency_250//trimParameters35_C0.dat .. /usr/local/data/M2045_XrayQualification_2015-08-11_16h58m_1439305117//015_HREfficiency_250//trimParameters35_C15.dat
[16:24:36.819] <TB0> INFO: clk: 4
[16:24:36.819] <TB0> INFO: ctr: 4
[16:24:36.819] <TB0> INFO: sda: 19
[16:24:36.819] <TB0> INFO: tin: 9
[16:24:36.819] <TB0> INFO: level: 15
[16:24:36.819] <TB0> INFO: triggerdelay: 0
[16:24:36.819] <TB0> QUIET: Instanciating API for pxar v2.2.5+88~g694c14c
[16:24:36.819] <TB0> INFO: Log level: INFO
[16:24:37.829] <TB0> INFO: Found DTB DTB_WWXTQT
[16:24:40.840] <TB0> QUIET: Connection to board DTB_WWXTQT opened.
[16:24:40.843] <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:
------------------------------------------------------
[16:24:40.846] <TB0> INFO: RPC call hashes of host and DTB match: 447413373
[16:24:42.359] <TB0> INFO: DUT info:
[16:24:42.359] <TB0> INFO: The DUT currently contains the following objects:
[16:24:42.359] <TB0> INFO: 2 TBM Cores tbm09c (2 ON)
[16:24:42.359] <TB0> INFO: TBM Core alpha (0): 7 registers set
[16:24:42.359] <TB0> INFO: TBM Core beta (1): 7 registers set
[16:24:42.359] <TB0> INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[16:24:42.360] <TB0> INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[16:24:42.360] <TB0> INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[16:24:42.360] <TB0> INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[16:24:42.360] <TB0> INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[16:24:42.360] <TB0> INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[16:24:42.360] <TB0> INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[16:24:42.360] <TB0> INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[16:24:42.360] <TB0> INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[16:24:42.360] <TB0> INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[16:24:42.360] <TB0> INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[16:24:42.360] <TB0> INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[16:24:42.360] <TB0> INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[16:24:42.360] <TB0> INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[16:24:42.360] <TB0> INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[16:24:42.360] <TB0> INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[16:24:42.360] <TB0> INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[16:24:42.761] <TB0> INFO: enter 'restricted' command line mode
[16:24:42.761] <TB0> INFO: enter test to run
[16:24:42.761] <TB0> INFO: test: HighRate no parameter change
[16:24:42.761] <TB0> INFO: running: highrate
[16:24:42.761] <TB0> INFO: readGainPedestalParameters /usr/local/data/M2045_XrayQualification_2015-08-11_16h58m_1439305117//015_HREfficiency_250//phCalibrationFitErr35_C0.dat .. /usr/local/data/M2045_XrayQualification_2015-08-11_16h58m_1439305117//015_HREfficiency_250//phCalibrationFitErr35_C15.dat
[16:24:42.894] <TB0> INFO: ----------------------------------------------------------------------
[16:24:42.894] <TB0> INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[16:24:42.894] <TB0> INFO: ----------------------------------------------------------------------
[16:24:46.972] <TB0> ERROR: <datapipe.cc/CheckEventValidity:L437> Channel 2 Number of ROCs (3) != Token Chain Length (4)

[16:25:03.081] <TB0> INFO: Test took 20181ms.
[16:25:09.799] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[16:25:10.415] <TB0> INFO: number of dead pixels (per ROC): 0 0 0 0 0 0 0 0 1 0 0 0 0 0 0 0
[16:25:10.415] <TB0> INFO: number of red-efficiency pixels: 931 1118 969 2447 2686 3153 3291 3511 3024 3153 3383 2407 2396 917 1346 1142
[16:25:10.415] <TB0> INFO: number of X-ray hits detected: 333179 360522 346608 586811 612981 683064 753066 751930 649816 689103 723290 587501 552047 313541 348665 329824
[16:25:10.415] <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
[16:25:10.415] <TB0> INFO: number of Vcal hits detected: 206897 206670 206309 203348 202404 200420 198665 198545 201416 199994 198904 203301 203557 206390 206310 206563
[16:25:10.415] <TB0> INFO: Vcal hit fiducial efficiency (%): 99.5 99.4 99.4 98.1 97.6 96.8 96.1 95.9 97.2 96.6 96.2 98.0 98.1 99.4 99.2 99.4
[16:25:10.415] <TB0> INFO: Vcal hit overall efficiency (%): 99.5 99.4 99.2 97.8 97.3 96.4 95.5 95.5 96.8 96.2 95.6 97.7 97.9 99.2 99.2 99.3
[16:25:10.415] <TB0> INFO: X-ray hit rate [MHz/cm2]: 97.7 105.7 101.6 172.0 179.7 200.2 220.7 220.4 190.5 202.0 212.0 172.2 161.8 91.9 102.2 96.7
[16:25:10.415] <TB0> INFO: PixTestHighRate::doXPixelAlive() done
[16:25:10.451] <TB0> INFO: PixTest:: pg_setup set to default.
[16:25:10.497] <TB0> INFO: enter test to run
[16:25:10.497] <TB0> INFO: test: no parameter change
[16:25:10.741] <TB0> QUIET: Connection to board 147 closed.
[16:25:10.821] <TB0> INFO: pXar: this is the end, my friend
MoReWeb-v1.0.0-5-g82d9ff6 on branch psi46master