MORE-Web

Test Date: 2015-12-16 10:27
Analysis date: 2016-05-26 07:06

Logfile

commander_PixelAlive.log

[09:28:07.585] <TB0>     INFO: *** Welcome to pxar ***

[09:28:07.585] <TB0>     INFO: *** Today: 2015/12/16

[09:28:07.883] <TB0>     INFO: *** Version: b2d3

[09:28:07.883] <TB0>     INFO: readRocDacs: /usr/local/data/M2125_XrayQualification_2015-12-16_10h27m_1450258068//000_PixelAlive_p17//dacParameters35_C0.dat .. /usr/local/data/M2125_XrayQualification_2015-12-16_10h27m_1450258068//000_PixelAlive_p17//dacParameters35_C15.dat

[09:28:07.884] <TB0>     INFO: readTbmDacs: /usr/local/data/M2125_XrayQualification_2015-12-16_10h27m_1450258068//000_PixelAlive_p17//tbmParameters_C0a.dat .. /usr/local/data/M2125_XrayQualification_2015-12-16_10h27m_1450258068//000_PixelAlive_p17//tbmParameters_C0b.dat

[09:28:07.884] <TB0>     INFO: readMaskFile: /usr/local/data/M2125_XrayQualification_2015-12-16_10h27m_1450258068//000_PixelAlive_p17//defaultMaskFile.dat

[09:28:07.884] <TB0>     INFO: readTrimFile: /usr/local/data/M2125_XrayQualification_2015-12-16_10h27m_1450258068//000_PixelAlive_p17//trimParameters35_C0.dat .. /usr/local/data/M2125_XrayQualification_2015-12-16_10h27m_1450258068//000_PixelAlive_p17//trimParameters35_C15.dat

[09:28:07.949] <TB0>     INFO:         clk: 4

[09:28:07.949] <TB0>     INFO:         ctr: 4

[09:28:07.949] <TB0>     INFO:         sda: 19

[09:28:07.949] <TB0>     INFO:         tin: 9

[09:28:07.949] <TB0>     INFO:         level: 15

[09:28:07.949] <TB0>     INFO:         triggerdelay: 0

[09:28:07.949] <TB0>    QUIET: Instanciating API for pxar prod-12

[09:28:07.949] <TB0>     INFO: Log level: INFO

[09:28:07.962] <TB0>     INFO: Found DTB DTB_WS6UZO

[09:28:07.973] <TB0>    QUIET: Connection to board DTB_WS6UZO opened.

[09:28:07.976] <TB0>     INFO: DTB startup information

--- DTB info------------------------------------------

Board id:    83

HW version:  DTB1.2

FW version:  4.2

SW version:  4.5

USB id:      DTB_WS6UZO

MAC address: 40D855118053

Hostname:    pixelDTB083

Comment:     

------------------------------------------------------

[09:28:07.978] <TB0>     INFO: RPC call hashes of host and DTB match: 398089610

[09:28:09.489] <TB0>     INFO: DUT info: 

[09:28:09.489] <TB0>     INFO: The DUT currently contains the following objects:

[09:28:09.489] <TB0>     INFO:  2 TBM Cores tbm09c (2 ON)

[09:28:09.489] <TB0>     INFO: 	TBM Core alpha (0): 7 registers set

[09:28:09.489] <TB0>     INFO: 	TBM Core beta  (1): 7 registers set

[09:28:09.489] <TB0>     INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs

[09:28:09.489] <TB0>     INFO: 	ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.

[09:28:09.489] <TB0>     INFO: 	ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.

[09:28:09.489] <TB0>     INFO: 	ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.

[09:28:09.489] <TB0>     INFO: 	ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.

[09:28:09.489] <TB0>     INFO: 	ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.

[09:28:09.489] <TB0>     INFO: 	ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.

[09:28:09.489] <TB0>     INFO: 	ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.

[09:28:09.489] <TB0>     INFO: 	ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.

[09:28:09.489] <TB0>     INFO: 	ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.

[09:28:09.489] <TB0>     INFO: 	ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.

[09:28:09.489] <TB0>     INFO: 	ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.

[09:28:09.489] <TB0>     INFO: 	ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.

[09:28:09.489] <TB0>     INFO: 	ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.

[09:28:09.489] <TB0>     INFO: 	ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.

[09:28:09.489] <TB0>     INFO: 	ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.

[09:28:09.489] <TB0>     INFO: 	ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.

[09:28:09.890] <TB0>     INFO: enter 'restricted' command line mode

[09:28:09.890] <TB0>     INFO: enter test to run

[09:28:09.890] <TB0>     INFO:   test: PixelAlive no parameter change

[09:28:09.890] <TB0>     INFO:   running: pixelalive

[09:28:09.905] <TB0>     INFO: ######################################################################

[09:28:09.905] <TB0>     INFO: PixTestAlive::doTest()

[09:28:09.906] <TB0>     INFO: ######################################################################

[09:28:09.907] <TB0>     INFO:    ----------------------------------------------------------------------

[09:28:09.907] <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)

[09:28:09.907] <TB0>     INFO:    ----------------------------------------------------------------------

[09:28:10.207] <TB0>     INFO: Expecting 41600 events.

[09:28:14.392] <TB0>     INFO: 41600 events read in total (3466ms).

[09:28:14.472] <TB0>     INFO: Test took 4563ms.

[09:28:14.478] <TB0>     INFO: Fetched DAQ statistics. Counters are being reset now.

[09:28:14.882] <TB0>     INFO: PixTestAlive::aliveTest() done

[09:28:14.882] <TB0>     INFO: number of dead pixels (per ROC):     0    0    0    0    3    0    0    2    0    0    1    0    0    0    0    3

[09:28:14.883] <TB0>     INFO:    ----------------------------------------------------------------------

[09:28:14.883] <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)

[09:28:14.883] <TB0>     INFO:    ----------------------------------------------------------------------

[09:28:15.182] <TB0>     INFO: Expecting 41600 events.

[09:28:18.439] <TB0>     INFO: 41600 events read in total (2541ms).

[09:28:18.439] <TB0>     INFO: Test took 3555ms.

[09:28:18.439] <TB0>     INFO: Fetched DAQ statistics. Counters are being reset now.

[09:28:18.440] <TB0>     INFO: mask vs. old pixelAlive PixelAlive_C0_V0 ..  PixelAlive_C15_V0

[09:28:18.751] <TB0>     INFO: PixTestAlive::maskTest() done

[09:28:18.751] <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

[09:28:18.753] <TB0>     INFO:    ----------------------------------------------------------------------

[09:28:18.753] <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)

[09:28:18.753] <TB0>     INFO:    ----------------------------------------------------------------------

[09:28:19.063] <TB0>     INFO: Expecting 41600 events.

[09:28:23.817] <TB0>     INFO: 41600 events read in total (4038ms).

[09:28:23.818] <TB0>     INFO: Test took 5064ms.

[09:28:23.824] <TB0>     INFO: Fetched DAQ statistics. Counters are being reset now.

[09:28:24.116] <TB0>     INFO: PixTestAlive::addressDecodingTest() done

[09:28:24.116] <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

[09:28:24.116] <TB0>     INFO: PixTestAlive::doTest() done, duration: 14 seconds

[09:28:24.224] <TB0>     INFO: enter test to run

[09:28:24.225] <TB0>     INFO:   test:  no parameter change

[09:28:24.262] <TB0>    QUIET: Connection to board 83 closed.

[09:28:24.342] <TB0>     INFO: pXar: this is the end, my friend

commander_RetrimHotPixels.log

[09:33:48.912] <TB0>     INFO: *** Welcome to pxar ***

[09:33:48.912] <TB0>     INFO: *** Today: 2015/12/16

[09:33:49.249] <TB0>     INFO: *** Version: b2d3

[09:33:49.249] <TB0>     INFO: readRocDacs: /usr/local/data/M2125_XrayQualification_2015-12-16_10h27m_1450258068//002_RetrimHotPixels_150//dacParameters35_C0.dat .. /usr/local/data/M2125_XrayQualification_2015-12-16_10h27m_1450258068//002_RetrimHotPixels_150//dacParameters35_C15.dat

[09:33:49.249] <TB0>     INFO: readTbmDacs: /usr/local/data/M2125_XrayQualification_2015-12-16_10h27m_1450258068//002_RetrimHotPixels_150//tbmParameters_C0a.dat .. /usr/local/data/M2125_XrayQualification_2015-12-16_10h27m_1450258068//002_RetrimHotPixels_150//tbmParameters_C0b.dat

[09:33:49.249] <TB0>     INFO: readMaskFile: /usr/local/data/M2125_XrayQualification_2015-12-16_10h27m_1450258068//002_RetrimHotPixels_150//defaultMaskFile.dat

[09:33:49.249] <TB0>     INFO: readTrimFile: /usr/local/data/M2125_XrayQualification_2015-12-16_10h27m_1450258068//002_RetrimHotPixels_150//trimParameters35_C0.dat .. /usr/local/data/M2125_XrayQualification_2015-12-16_10h27m_1450258068//002_RetrimHotPixels_150//trimParameters35_C15.dat

[09:33:49.313] <TB0>     INFO:         clk: 4

[09:33:49.313] <TB0>     INFO:         ctr: 4

[09:33:49.313] <TB0>     INFO:         sda: 19

[09:33:49.313] <TB0>     INFO:         tin: 9

[09:33:49.313] <TB0>     INFO:         level: 15

[09:33:49.313] <TB0>     INFO:         triggerdelay: 0

[09:33:49.313] <TB0>    QUIET: Instanciating API for pxar prod-12

[09:33:49.313] <TB0>     INFO: Log level: INFO

[09:33:49.327] <TB0>     INFO: Found DTB DTB_WS6UZO

[09:33:49.337] <TB0>    QUIET: Connection to board DTB_WS6UZO opened.

[09:33:49.340] <TB0>     INFO: DTB startup information

--- DTB info------------------------------------------

Board id:    83

HW version:  DTB1.2

FW version:  4.2

SW version:  4.5

USB id:      DTB_WS6UZO

MAC address: 40D855118053

Hostname:    pixelDTB083

Comment:     

------------------------------------------------------

[09:33:49.342] <TB0>     INFO: RPC call hashes of host and DTB match: 398089610

[09:33:50.849] <TB0>     INFO: DUT info: 

[09:33:50.849] <TB0>     INFO: The DUT currently contains the following objects:

[09:33:50.849] <TB0>     INFO:  2 TBM Cores tbm09c (2 ON)

[09:33:50.849] <TB0>     INFO: 	TBM Core alpha (0): 7 registers set

[09:33:50.849] <TB0>     INFO: 	TBM Core beta  (1): 7 registers set

[09:33:50.849] <TB0>     INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs

[09:33:50.849] <TB0>     INFO: 	ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.

[09:33:50.849] <TB0>     INFO: 	ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.

[09:33:50.849] <TB0>     INFO: 	ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.

[09:33:50.849] <TB0>     INFO: 	ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.

[09:33:50.849] <TB0>     INFO: 	ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.

[09:33:50.849] <TB0>     INFO: 	ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.

[09:33:50.849] <TB0>     INFO: 	ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.

[09:33:50.849] <TB0>     INFO: 	ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.

[09:33:50.849] <TB0>     INFO: 	ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.

[09:33:50.849] <TB0>     INFO: 	ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.

[09:33:50.849] <TB0>     INFO: 	ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.

[09:33:50.849] <TB0>     INFO: 	ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.

[09:33:50.849] <TB0>     INFO: 	ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.

[09:33:50.849] <TB0>     INFO: 	ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.

[09:33:50.849] <TB0>     INFO: 	ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.

[09:33:50.849] <TB0>     INFO: 	ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.

[09:33:51.250] <TB0>     INFO: enter 'restricted' command line mode

[09:33:51.250] <TB0>     INFO: enter test to run

[09:33:51.250] <TB0>     INFO:   test: delay setting parameters: ->5<-

[09:33:51.250] <TB0>     INFO: delay test by 5 seconds...

[09:33:56.250] <TB0>     INFO: enter test to run

[09:33:56.250] <TB0>     INFO:   test: highrate no parameter change

[09:33:56.250] <TB0>     INFO:   running: highrate

[09:33:56.252] <TB0>     INFO: readGainPedestalParameters /usr/local/data/M2125_XrayQualification_2015-12-16_10h27m_1450258068//002_RetrimHotPixels_150//phCalibrationFitErr35_C0.dat .. /usr/local/data/M2125_XrayQualification_2015-12-16_10h27m_1450258068//002_RetrimHotPixels_150//phCalibrationFitErr35_C15.dat

[09:33:56.380] <TB0>     INFO:    ----------------------------------------------------------------------

[09:33:56.380] <TB0>     INFO:    PixTest::trimHotPixels() running for 1 seconds with 100 kHz trigger rate

[09:33:56.380] <TB0>     INFO:    ----------------------------------------------------------------------

[09:33:56.380] <TB0>     INFO: THR = 200, corresponding to ~ 533.333 MHz/cm2

[09:33:56.380] <TB0>     INFO: edge/corner pixel THR is adjusted

[09:33:56.380] <TB0>     INFO: PixTestHighRate::trimHotPixels: step 0...

[09:33:57.339] <TB0>     INFO: Collecting data for 1 seconds...

[09:33:58.340] <TB0>     INFO: Done with hot pixel readout

[09:34:05.248] <TB0>     INFO: PixTest::       pg_setup set to default.

[09:34:05.249] <TB0>     INFO: 4 hot pixels found in step 0

[09:34:06.213] <TB0>     INFO: Collecting data for 1 seconds...

[09:34:07.215] <TB0>     INFO: Done with hot pixel readout

[09:34:16.154] <TB0>     INFO: PixTest::       pg_setup set to default.

[09:34:16.155] <TB0>     INFO: 4 hot pixels found in step 1

[09:34:17.119] <TB0>     INFO: Collecting data for 1 seconds...

[09:34:18.121] <TB0>     INFO: Done with hot pixel readout

[09:34:26.478] <TB0>     INFO: PixTest::       pg_setup set to default.

[09:34:26.479] <TB0>     INFO: 4 hot pixels found in step 2

[09:34:27.442] <TB0>     INFO: Collecting data for 1 seconds...

[09:34:28.444] <TB0>     INFO: Done with hot pixel readout

[09:34:36.775] <TB0>     INFO: PixTest::       pg_setup set to default.

[09:34:36.776] <TB0>     INFO: 1 hot pixels found in step 3

[09:34:37.737] <TB0>     INFO: Collecting data for 1 seconds...

[09:34:38.739] <TB0>     INFO: Done with hot pixel readout

[09:34:46.853] <TB0>     INFO: PixTest::       pg_setup set to default.

[09:34:46.855] <TB0>     INFO: 1 hot pixels found in step 4

[09:34:47.818] <TB0>     INFO: Collecting data for 1 seconds...

[09:34:48.819] <TB0>     INFO: Done with hot pixel readout

[09:34:55.306] <TB0>     INFO: PixTest::       pg_setup set to default.

[09:34:55.307] <TB0>     INFO: 1 hot pixels found in step 5

[09:34:56.268] <TB0>     INFO: Collecting data for 1 seconds...

[09:34:57.269] <TB0>     INFO: Done with hot pixel readout

[09:35:03.252] <TB0>     INFO: PixTest::       pg_setup set to default.

[09:35:03.253] <TB0>     INFO: 0 hot pixels found in step 6

[09:35:03.260] <TB0>     INFO: 0 hot pixels could not be trimmed and have been masked.

[09:35:03.290] <TB0>     INFO: PixTest::trimHotPixels() done

[09:35:03.290] <TB0>     INFO: write trim parameters into /usr/local/data/M2125_XrayQualification_2015-12-16_10h27m_1450258068//002_RetrimHotPixels_150//trimParameters35_C0.dat

[09:35:03.296] <TB0>     INFO: write trim parameters into /usr/local/data/M2125_XrayQualification_2015-12-16_10h27m_1450258068//002_RetrimHotPixels_150//trimParameters35_C1.dat

[09:35:03.302] <TB0>     INFO: write trim parameters into /usr/local/data/M2125_XrayQualification_2015-12-16_10h27m_1450258068//002_RetrimHotPixels_150//trimParameters35_C2.dat

[09:35:03.308] <TB0>     INFO: write trim parameters into /usr/local/data/M2125_XrayQualification_2015-12-16_10h27m_1450258068//002_RetrimHotPixels_150//trimParameters35_C3.dat

[09:35:03.314] <TB0>     INFO: write trim parameters into /usr/local/data/M2125_XrayQualification_2015-12-16_10h27m_1450258068//002_RetrimHotPixels_150//trimParameters35_C4.dat

[09:35:03.320] <TB0>     INFO: write trim parameters into /usr/local/data/M2125_XrayQualification_2015-12-16_10h27m_1450258068//002_RetrimHotPixels_150//trimParameters35_C5.dat

[09:35:03.326] <TB0>     INFO: write trim parameters into /usr/local/data/M2125_XrayQualification_2015-12-16_10h27m_1450258068//002_RetrimHotPixels_150//trimParameters35_C6.dat

[09:35:03.332] <TB0>     INFO: write trim parameters into /usr/local/data/M2125_XrayQualification_2015-12-16_10h27m_1450258068//002_RetrimHotPixels_150//trimParameters35_C7.dat

[09:35:03.339] <TB0>     INFO: write trim parameters into /usr/local/data/M2125_XrayQualification_2015-12-16_10h27m_1450258068//002_RetrimHotPixels_150//trimParameters35_C8.dat

[09:35:03.345] <TB0>     INFO: write trim parameters into /usr/local/data/M2125_XrayQualification_2015-12-16_10h27m_1450258068//002_RetrimHotPixels_150//trimParameters35_C9.dat

[09:35:03.351] <TB0>     INFO: write trim parameters into /usr/local/data/M2125_XrayQualification_2015-12-16_10h27m_1450258068//002_RetrimHotPixels_150//trimParameters35_C10.dat

[09:35:03.357] <TB0>     INFO: write trim parameters into /usr/local/data/M2125_XrayQualification_2015-12-16_10h27m_1450258068//002_RetrimHotPixels_150//trimParameters35_C11.dat

[09:35:03.363] <TB0>     INFO: write trim parameters into /usr/local/data/M2125_XrayQualification_2015-12-16_10h27m_1450258068//002_RetrimHotPixels_150//trimParameters35_C12.dat

[09:35:03.369] <TB0>     INFO: write trim parameters into /usr/local/data/M2125_XrayQualification_2015-12-16_10h27m_1450258068//002_RetrimHotPixels_150//trimParameters35_C13.dat

[09:35:03.375] <TB0>     INFO: write trim parameters into /usr/local/data/M2125_XrayQualification_2015-12-16_10h27m_1450258068//002_RetrimHotPixels_150//trimParameters35_C14.dat

[09:35:03.381] <TB0>     INFO: write trim parameters into /usr/local/data/M2125_XrayQualification_2015-12-16_10h27m_1450258068//002_RetrimHotPixels_150//trimParameters35_C15.dat

[09:35:03.387] <TB0>     INFO: write masked pixels into /usr/local/data/M2125_XrayQualification_2015-12-16_10h27m_1450258068//002_RetrimHotPixels_150//defaultMaskFile.dat

[09:35:03.418] <TB0>     INFO: enter test to run

[09:35:03.418] <TB0>     INFO:   test:  no parameter change

[09:35:03.496] <TB0>    QUIET: Connection to board 83 closed.

[09:35:03.575] <TB0>     INFO: pXar: this is the end, my friend

commander_RetrimHotPixelsNoRate.log

[09:35:11.685] <TB0>     INFO: *** Welcome to pxar ***

[09:35:11.685] <TB0>     INFO: *** Today: 2015/12/16

[09:35:11.997] <TB0>     INFO: *** Version: b2d3

[09:35:11.997] <TB0>     INFO: readRocDacs: /usr/local/data/M2125_XrayQualification_2015-12-16_10h27m_1450258068//003_RetrimHotPixelsNoRate_p17//dacParameters35_C0.dat .. /usr/local/data/M2125_XrayQualification_2015-12-16_10h27m_1450258068//003_RetrimHotPixelsNoRate_p17//dacParameters35_C15.dat

[09:35:11.998] <TB0>     INFO: readTbmDacs: /usr/local/data/M2125_XrayQualification_2015-12-16_10h27m_1450258068//003_RetrimHotPixelsNoRate_p17//tbmParameters_C0a.dat .. /usr/local/data/M2125_XrayQualification_2015-12-16_10h27m_1450258068//003_RetrimHotPixelsNoRate_p17//tbmParameters_C0b.dat

[09:35:11.998] <TB0>     INFO: readMaskFile: /usr/local/data/M2125_XrayQualification_2015-12-16_10h27m_1450258068//003_RetrimHotPixelsNoRate_p17//defaultMaskFile.dat

[09:35:11.998] <TB0>     INFO: readTrimFile: /usr/local/data/M2125_XrayQualification_2015-12-16_10h27m_1450258068//003_RetrimHotPixelsNoRate_p17//trimParameters35_C0.dat .. /usr/local/data/M2125_XrayQualification_2015-12-16_10h27m_1450258068//003_RetrimHotPixelsNoRate_p17//trimParameters35_C15.dat

[09:35:12.065] <TB0>     INFO:         clk: 4

[09:35:12.065] <TB0>     INFO:         ctr: 4

[09:35:12.065] <TB0>     INFO:         sda: 19

[09:35:12.065] <TB0>     INFO:         tin: 9

[09:35:12.065] <TB0>     INFO:         level: 15

[09:35:12.065] <TB0>     INFO:         triggerdelay: 0

[09:35:12.065] <TB0>    QUIET: Instanciating API for pxar prod-12

[09:35:12.065] <TB0>     INFO: Log level: INFO

[09:35:12.079] <TB0>     INFO: Found DTB DTB_WS6UZO

[09:35:12.089] <TB0>    QUIET: Connection to board DTB_WS6UZO opened.

[09:35:12.092] <TB0>     INFO: DTB startup information

--- DTB info------------------------------------------

Board id:    83

HW version:  DTB1.2

FW version:  4.2

SW version:  4.5

USB id:      DTB_WS6UZO

MAC address: 40D855118053

Hostname:    pixelDTB083

Comment:     

------------------------------------------------------

[09:35:12.094] <TB0>     INFO: RPC call hashes of host and DTB match: 398089610

[09:35:13.601] <TB0>     INFO: DUT info: 

[09:35:13.601] <TB0>     INFO: The DUT currently contains the following objects:

[09:35:13.601] <TB0>     INFO:  2 TBM Cores tbm09c (2 ON)

[09:35:13.601] <TB0>     INFO: 	TBM Core alpha (0): 7 registers set

[09:35:13.601] <TB0>     INFO: 	TBM Core beta  (1): 7 registers set

[09:35:13.601] <TB0>     INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs

[09:35:13.601] <TB0>     INFO: 	ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.

[09:35:13.601] <TB0>     INFO: 	ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.

[09:35:13.601] <TB0>     INFO: 	ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.

[09:35:13.601] <TB0>     INFO: 	ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.

[09:35:13.601] <TB0>     INFO: 	ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.

[09:35:13.601] <TB0>     INFO: 	ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.

[09:35:13.601] <TB0>     INFO: 	ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.

[09:35:13.601] <TB0>     INFO: 	ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.

[09:35:13.601] <TB0>     INFO: 	ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.

[09:35:13.601] <TB0>     INFO: 	ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.

[09:35:13.601] <TB0>     INFO: 	ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.

[09:35:13.601] <TB0>     INFO: 	ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.

[09:35:13.601] <TB0>     INFO: 	ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.

[09:35:13.601] <TB0>     INFO: 	ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.

[09:35:13.601] <TB0>     INFO: 	ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.

[09:35:13.601] <TB0>     INFO: 	ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.

[09:35:14.002] <TB0>     INFO: enter 'restricted' command line mode

[09:35:14.002] <TB0>     INFO: enter test to run

[09:35:14.002] <TB0>     INFO:   test: delay setting parameters: ->5<-

[09:35:14.002] <TB0>     INFO: delay test by 5 seconds...

[09:35:19.002] <TB0>     INFO: enter test to run

[09:35:19.002] <TB0>     INFO:   test: highrate no parameter change

[09:35:19.002] <TB0>     INFO:   running: highrate

[09:35:19.004] <TB0>     INFO: readGainPedestalParameters /usr/local/data/M2125_XrayQualification_2015-12-16_10h27m_1450258068//003_RetrimHotPixelsNoRate_p17//phCalibrationFitErr35_C0.dat .. /usr/local/data/M2125_XrayQualification_2015-12-16_10h27m_1450258068//003_RetrimHotPixelsNoRate_p17//phCalibrationFitErr35_C15.dat

[09:35:19.130] <TB0>     INFO:    ----------------------------------------------------------------------

[09:35:19.130] <TB0>     INFO:    PixTest::trimHotPixels() running for 5 seconds with 100 kHz trigger rate

[09:35:19.130] <TB0>     INFO:    ----------------------------------------------------------------------

[09:35:19.130] <TB0>     INFO: THR = 10, corresponding to ~ 5.33333 MHz/cm2

[09:35:19.130] <TB0>     INFO: edge/corner pixel THR is adjusted

[09:35:19.130] <TB0>     INFO: PixTestHighRate::trimHotPixels: step 0...

[09:35:20.090] <TB0>     INFO: Collecting data for 5 seconds...

[09:35:25.096] <TB0>     INFO: Done with hot pixel readout

[09:35:36.617] <TB0>     INFO: PixTest::       pg_setup set to default.

[09:35:36.618] <TB0>     INFO: 5 hot pixels found in step 0

[09:35:37.659] <TB0>     INFO: Collecting data for 5 seconds...

[09:35:42.665] <TB0>     INFO: Done with hot pixel readout

[09:35:55.110] <TB0>     INFO: PixTest::       pg_setup set to default.

[09:35:55.111] <TB0>     INFO: 0 hot pixels found in step 1

[09:35:55.113] <TB0>     INFO: 0 hot pixels could not be trimmed and have been masked.

[09:35:55.193] <TB0>     INFO: PixTest::trimHotPixels() done

[09:35:55.193] <TB0>     INFO: write trim parameters into /usr/local/data/M2125_XrayQualification_2015-12-16_10h27m_1450258068//003_RetrimHotPixelsNoRate_p17//trimParameters35_C0.dat

[09:35:55.199] <TB0>     INFO: write trim parameters into /usr/local/data/M2125_XrayQualification_2015-12-16_10h27m_1450258068//003_RetrimHotPixelsNoRate_p17//trimParameters35_C1.dat

[09:35:55.205] <TB0>     INFO: write trim parameters into /usr/local/data/M2125_XrayQualification_2015-12-16_10h27m_1450258068//003_RetrimHotPixelsNoRate_p17//trimParameters35_C2.dat

[09:35:55.211] <TB0>     INFO: write trim parameters into /usr/local/data/M2125_XrayQualification_2015-12-16_10h27m_1450258068//003_RetrimHotPixelsNoRate_p17//trimParameters35_C3.dat

[09:35:55.217] <TB0>     INFO: write trim parameters into /usr/local/data/M2125_XrayQualification_2015-12-16_10h27m_1450258068//003_RetrimHotPixelsNoRate_p17//trimParameters35_C4.dat

[09:35:55.223] <TB0>     INFO: write trim parameters into /usr/local/data/M2125_XrayQualification_2015-12-16_10h27m_1450258068//003_RetrimHotPixelsNoRate_p17//trimParameters35_C5.dat

[09:35:55.229] <TB0>     INFO: write trim parameters into /usr/local/data/M2125_XrayQualification_2015-12-16_10h27m_1450258068//003_RetrimHotPixelsNoRate_p17//trimParameters35_C6.dat

[09:35:55.235] <TB0>     INFO: write trim parameters into /usr/local/data/M2125_XrayQualification_2015-12-16_10h27m_1450258068//003_RetrimHotPixelsNoRate_p17//trimParameters35_C7.dat

[09:35:55.241] <TB0>     INFO: write trim parameters into /usr/local/data/M2125_XrayQualification_2015-12-16_10h27m_1450258068//003_RetrimHotPixelsNoRate_p17//trimParameters35_C8.dat

[09:35:55.246] <TB0>     INFO: write trim parameters into /usr/local/data/M2125_XrayQualification_2015-12-16_10h27m_1450258068//003_RetrimHotPixelsNoRate_p17//trimParameters35_C9.dat

[09:35:55.252] <TB0>     INFO: write trim parameters into /usr/local/data/M2125_XrayQualification_2015-12-16_10h27m_1450258068//003_RetrimHotPixelsNoRate_p17//trimParameters35_C10.dat

[09:35:55.258] <TB0>     INFO: write trim parameters into /usr/local/data/M2125_XrayQualification_2015-12-16_10h27m_1450258068//003_RetrimHotPixelsNoRate_p17//trimParameters35_C11.dat

[09:35:55.264] <TB0>     INFO: write trim parameters into /usr/local/data/M2125_XrayQualification_2015-12-16_10h27m_1450258068//003_RetrimHotPixelsNoRate_p17//trimParameters35_C12.dat

[09:35:55.270] <TB0>     INFO: write trim parameters into /usr/local/data/M2125_XrayQualification_2015-12-16_10h27m_1450258068//003_RetrimHotPixelsNoRate_p17//trimParameters35_C13.dat

[09:35:55.276] <TB0>     INFO: write trim parameters into /usr/local/data/M2125_XrayQualification_2015-12-16_10h27m_1450258068//003_RetrimHotPixelsNoRate_p17//trimParameters35_C14.dat

[09:35:55.282] <TB0>     INFO: write trim parameters into /usr/local/data/M2125_XrayQualification_2015-12-16_10h27m_1450258068//003_RetrimHotPixelsNoRate_p17//trimParameters35_C15.dat

[09:35:55.288] <TB0>     INFO: write masked pixels into /usr/local/data/M2125_XrayQualification_2015-12-16_10h27m_1450258068//003_RetrimHotPixelsNoRate_p17//defaultMaskFile.dat

[09:35:55.321] <TB0>     INFO: enter test to run

[09:35:55.321] <TB0>     INFO:   test:  no parameter change

[09:35:55.389] <TB0>    QUIET: Connection to board 83 closed.

[09:35:55.469] <TB0>     INFO: pXar: this is the end, my friend

commander_HRData.log

[09:36:27.044] <TB0>     INFO: *** Welcome to pxar ***

[09:36:27.044] <TB0>     INFO: *** Today: 2015/12/16

[09:36:27.379] <TB0>     INFO: *** Version: b2d3

[09:36:27.379] <TB0>     INFO: readRocDacs: /usr/local/data/M2125_XrayQualification_2015-12-16_10h27m_1450258068//004_HRData_50//dacParameters35_C0.dat .. /usr/local/data/M2125_XrayQualification_2015-12-16_10h27m_1450258068//004_HRData_50//dacParameters35_C15.dat

[09:36:27.380] <TB0>     INFO: readTbmDacs: /usr/local/data/M2125_XrayQualification_2015-12-16_10h27m_1450258068//004_HRData_50//tbmParameters_C0a.dat .. /usr/local/data/M2125_XrayQualification_2015-12-16_10h27m_1450258068//004_HRData_50//tbmParameters_C0b.dat

[09:36:27.380] <TB0>     INFO: readMaskFile: /usr/local/data/M2125_XrayQualification_2015-12-16_10h27m_1450258068//004_HRData_50//defaultMaskFile.dat

[09:36:27.380] <TB0>     INFO: readTrimFile: /usr/local/data/M2125_XrayQualification_2015-12-16_10h27m_1450258068//004_HRData_50//trimParameters35_C0.dat .. /usr/local/data/M2125_XrayQualification_2015-12-16_10h27m_1450258068//004_HRData_50//trimParameters35_C15.dat

[09:36:27.446] <TB0>     INFO:         clk: 4

[09:36:27.446] <TB0>     INFO:         ctr: 4

[09:36:27.446] <TB0>     INFO:         sda: 19

[09:36:27.446] <TB0>     INFO:         tin: 9

[09:36:27.446] <TB0>     INFO:         level: 15

[09:36:27.446] <TB0>     INFO:         triggerdelay: 0

[09:36:27.446] <TB0>    QUIET: Instanciating API for pxar prod-12

[09:36:27.446] <TB0>     INFO: Log level: INFO

[09:36:27.458] <TB0>     INFO: Found DTB DTB_WS6UZO

[09:36:27.468] <TB0>    QUIET: Connection to board DTB_WS6UZO opened.

[09:36:27.471] <TB0>     INFO: DTB startup information

--- DTB info------------------------------------------

Board id:    83

HW version:  DTB1.2

FW version:  4.2

SW version:  4.5

USB id:      DTB_WS6UZO

MAC address: 40D855118053

Hostname:    pixelDTB083

Comment:     

------------------------------------------------------

[09:36:27.474] <TB0>     INFO: RPC call hashes of host and DTB match: 398089610

[09:36:28.981] <TB0>     INFO: DUT info: 

[09:36:28.981] <TB0>     INFO: The DUT currently contains the following objects:

[09:36:28.981] <TB0>     INFO:  2 TBM Cores tbm09c (2 ON)

[09:36:28.981] <TB0>     INFO: 	TBM Core alpha (0): 7 registers set

[09:36:28.981] <TB0>     INFO: 	TBM Core beta  (1): 7 registers set

[09:36:28.981] <TB0>     INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs

[09:36:28.981] <TB0>     INFO: 	ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.

[09:36:28.981] <TB0>     INFO: 	ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.

[09:36:28.981] <TB0>     INFO: 	ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.

[09:36:28.981] <TB0>     INFO: 	ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.

[09:36:28.981] <TB0>     INFO: 	ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.

[09:36:28.981] <TB0>     INFO: 	ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.

[09:36:28.981] <TB0>     INFO: 	ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.

[09:36:28.981] <TB0>     INFO: 	ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.

[09:36:28.981] <TB0>     INFO: 	ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.

[09:36:28.981] <TB0>     INFO: 	ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.

[09:36:28.981] <TB0>     INFO: 	ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.

[09:36:28.981] <TB0>     INFO: 	ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.

[09:36:28.981] <TB0>     INFO: 	ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.

[09:36:28.981] <TB0>     INFO: 	ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.

[09:36:28.981] <TB0>     INFO: 	ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.

[09:36:28.981] <TB0>     INFO: 	ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.

[09:36:29.382] <TB0>     INFO: enter 'restricted' command line mode

[09:36:29.382] <TB0>     INFO: enter test to run

[09:36:29.382] <TB0>     INFO:   test: delay setting parameters: ->5<-

[09:36:29.382] <TB0>     INFO: delay test by 5 seconds...

[09:36:34.382] <TB0>     INFO: enter test to run

[09:36:34.382] <TB0>     INFO:   test: Xray no parameter change

[09:36:34.382] <TB0>     INFO:   running: xray

[09:36:34.384] <TB0>     INFO: readGainPedestalParameters /usr/local/data/M2125_XrayQualification_2015-12-16_10h27m_1450258068//004_HRData_50//phCalibrationFitErr35_C0.dat .. /usr/local/data/M2125_XrayQualification_2015-12-16_10h27m_1450258068//004_HRData_50//phCalibrationFitErr35_C15.dat

[09:36:34.510] <TB0>     INFO: ######################################################################

[09:36:34.510] <TB0>     INFO: PixTestXray::doTest()

[09:36:34.510] <TB0>     INFO: ######################################################################

[09:36:34.510] <TB0>     INFO:    ----------------------------------------------------------------------

[09:36:34.510] <TB0>     INFO:    PixTestXray::doPhRun() fParRunSeconds = 100

[09:36:34.510] <TB0>     INFO:    ----------------------------------------------------------------------

[09:36:35.475] <TB0>     INFO: PixTestXray::doPhRun start TriggerLoop with trigger frequency 100 kHz, period 405 and duration 100 seconds,  fEventsMax = 10000000

[09:36:42.304] <TB0>     INFO: run duration 6 seconds, buffer almost full (81%), pausing triggers.

[09:37:16.610] <TB0>     INFO: Resuming triggers.

[09:37:23.441] <TB0>     INFO: run duration 13 seconds, buffer almost full (81%), pausing triggers.

[09:37:56.871] <TB0>     INFO: Resuming triggers.

[09:38:03.706] <TB0>     INFO: run duration 20 seconds, buffer almost full (81%), pausing triggers.

[09:38:36.994] <TB0>     INFO: Resuming triggers.

[09:38:43.826] <TB0>     INFO: run duration 27 seconds, buffer almost full (81%), pausing triggers.

[09:39:17.008] <TB0>     INFO: Resuming triggers.

[09:39:23.845] <TB0>     INFO: run duration 34 seconds, buffer almost full (81%), pausing triggers.

[09:39:56.009] <TB0>     INFO: Resuming triggers.

[09:40:02.841] <TB0>     INFO: run duration 40 seconds, buffer almost full (81%), pausing triggers.

[09:40:35.796] <TB0>     INFO: Resuming triggers.

[09:40:42.628] <TB0>     INFO: run duration 47 seconds, buffer almost full (81%), pausing triggers.

[09:41:16.369] <TB0>     INFO: Resuming triggers.

[09:41:23.199] <TB0>     INFO: run duration 54 seconds, buffer almost full (81%), pausing triggers.

[09:41:57.448] <TB0>     INFO: Resuming triggers.

[09:42:04.278] <TB0>     INFO: run duration 61 seconds, buffer almost full (81%), pausing triggers.

[09:42:38.019] <TB0>     INFO: Resuming triggers.

[09:42:44.849] <TB0>     INFO: run duration 68 seconds, buffer almost full (81%), pausing triggers.

[09:43:18.456] <TB0>     INFO: Resuming triggers.

[09:43:25.288] <TB0>     INFO: run duration 75 seconds, buffer almost full (81%), pausing triggers.

[09:43:58.999] <TB0>     INFO: Resuming triggers.

[09:44:05.831] <TB0>     INFO: run duration 81 seconds, buffer almost full (81%), pausing triggers.

[09:44:40.563] <TB0>     INFO: Resuming triggers.

[09:44:47.392] <TB0>     INFO: run duration 88 seconds, buffer almost full (81%), pausing triggers.

[09:45:22.127] <TB0>     INFO: Resuming triggers.

[09:45:28.957] <TB0>     INFO: run duration 95 seconds, buffer almost full (81%), pausing triggers.

[09:46:02.920] <TB0>     INFO: Resuming triggers.

[09:46:07.365] <TB0>     INFO: data taking finished, elapsed time: 100 seconds.

[09:46:30.073] <TB0>     INFO: PixTest::       pg_setup set to default.

[09:46:30.075] <TB0>     INFO: PixTestXray::doPhRun() done

[09:46:30.075] <TB0>     INFO: PixTestXray::doTest() done 

[09:46:30.254] <TB0>     INFO: enter test to run

[09:46:30.254] <TB0>     INFO:   test:  no parameter change

[09:46:30.398] <TB0>    QUIET: Connection to board 83 closed.

[09:46:30.481] <TB0>     INFO: pXar: this is the end, my friend

commander_HRData.log

[09:47:02.117] <TB0>     INFO: *** Welcome to pxar ***

[09:47:02.117] <TB0>     INFO: *** Today: 2015/12/16

[09:47:02.454] <TB0>     INFO: *** Version: b2d3

[09:47:02.454] <TB0>     INFO: readRocDacs: /usr/local/data/M2125_XrayQualification_2015-12-16_10h27m_1450258068//005_HRData_150//dacParameters35_C0.dat .. /usr/local/data/M2125_XrayQualification_2015-12-16_10h27m_1450258068//005_HRData_150//dacParameters35_C15.dat

[09:47:02.454] <TB0>     INFO: readTbmDacs: /usr/local/data/M2125_XrayQualification_2015-12-16_10h27m_1450258068//005_HRData_150//tbmParameters_C0a.dat .. /usr/local/data/M2125_XrayQualification_2015-12-16_10h27m_1450258068//005_HRData_150//tbmParameters_C0b.dat

[09:47:02.455] <TB0>     INFO: readMaskFile: /usr/local/data/M2125_XrayQualification_2015-12-16_10h27m_1450258068//005_HRData_150//defaultMaskFile.dat

[09:47:02.455] <TB0>     INFO: readTrimFile: /usr/local/data/M2125_XrayQualification_2015-12-16_10h27m_1450258068//005_HRData_150//trimParameters35_C0.dat .. /usr/local/data/M2125_XrayQualification_2015-12-16_10h27m_1450258068//005_HRData_150//trimParameters35_C15.dat

[09:47:02.520] <TB0>     INFO:         clk: 4

[09:47:02.520] <TB0>     INFO:         ctr: 4

[09:47:02.520] <TB0>     INFO:         sda: 19

[09:47:02.520] <TB0>     INFO:         tin: 9

[09:47:02.520] <TB0>     INFO:         level: 15

[09:47:02.520] <TB0>     INFO:         triggerdelay: 0

[09:47:02.520] <TB0>    QUIET: Instanciating API for pxar prod-12

[09:47:02.520] <TB0>     INFO: Log level: INFO

[09:47:02.534] <TB0>     INFO: Found DTB DTB_WS6UZO

[09:47:02.544] <TB0>    QUIET: Connection to board DTB_WS6UZO opened.

[09:47:02.547] <TB0>     INFO: DTB startup information

--- DTB info------------------------------------------

Board id:    83

HW version:  DTB1.2

FW version:  4.2

SW version:  4.5

USB id:      DTB_WS6UZO

MAC address: 40D855118053

Hostname:    pixelDTB083

Comment:     

------------------------------------------------------

[09:47:02.550] <TB0>     INFO: RPC call hashes of host and DTB match: 398089610

[09:47:04.063] <TB0>     INFO: DUT info: 

[09:47:04.063] <TB0>     INFO: The DUT currently contains the following objects:

[09:47:04.063] <TB0>     INFO:  2 TBM Cores tbm09c (2 ON)

[09:47:04.063] <TB0>     INFO: 	TBM Core alpha (0): 7 registers set

[09:47:04.063] <TB0>     INFO: 	TBM Core beta  (1): 7 registers set

[09:47:04.063] <TB0>     INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs

[09:47:04.063] <TB0>     INFO: 	ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.

[09:47:04.063] <TB0>     INFO: 	ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.

[09:47:04.063] <TB0>     INFO: 	ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.

[09:47:04.063] <TB0>     INFO: 	ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.

[09:47:04.063] <TB0>     INFO: 	ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.

[09:47:04.063] <TB0>     INFO: 	ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.

[09:47:04.063] <TB0>     INFO: 	ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.

[09:47:04.063] <TB0>     INFO: 	ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.

[09:47:04.063] <TB0>     INFO: 	ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.

[09:47:04.063] <TB0>     INFO: 	ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.

[09:47:04.063] <TB0>     INFO: 	ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.

[09:47:04.063] <TB0>     INFO: 	ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.

[09:47:04.063] <TB0>     INFO: 	ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.

[09:47:04.063] <TB0>     INFO: 	ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.

[09:47:04.063] <TB0>     INFO: 	ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.

[09:47:04.063] <TB0>     INFO: 	ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.

[09:47:04.464] <TB0>     INFO: enter 'restricted' command line mode

[09:47:04.464] <TB0>     INFO: enter test to run

[09:47:04.464] <TB0>     INFO:   test: delay setting parameters: ->5<-

[09:47:04.464] <TB0>     INFO: delay test by 5 seconds...

[09:47:09.464] <TB0>     INFO: enter test to run

[09:47:09.464] <TB0>     INFO:   test: Xray no parameter change

[09:47:09.464] <TB0>     INFO:   running: xray

[09:47:09.466] <TB0>     INFO: readGainPedestalParameters /usr/local/data/M2125_XrayQualification_2015-12-16_10h27m_1450258068//005_HRData_150//phCalibrationFitErr35_C0.dat .. /usr/local/data/M2125_XrayQualification_2015-12-16_10h27m_1450258068//005_HRData_150//phCalibrationFitErr35_C15.dat

[09:47:09.594] <TB0>     INFO: ######################################################################

[09:47:09.594] <TB0>     INFO: PixTestXray::doTest()

[09:47:09.594] <TB0>     INFO: ######################################################################

[09:47:09.594] <TB0>     INFO:    ----------------------------------------------------------------------

[09:47:09.594] <TB0>     INFO:    PixTestXray::doPhRun() fParRunSeconds = 100

[09:47:09.594] <TB0>     INFO:    ----------------------------------------------------------------------

[09:47:10.559] <TB0>     INFO: PixTestXray::doPhRun start TriggerLoop with trigger frequency 100 kHz, period 405 and duration 100 seconds,  fEventsMax = 10000000

[09:47:14.345] <TB0>     INFO: run duration 3 seconds, buffer almost full (81%), pausing triggers.

[09:47:49.478] <TB0>     INFO: Resuming triggers.

[09:47:53.273] <TB0>     INFO: run duration 7 seconds, buffer almost full (81%), pausing triggers.

[09:48:25.696] <TB0>     INFO: Resuming triggers.

[09:48:29.492] <TB0>     INFO: run duration 11 seconds, buffer almost full (81%), pausing triggers.

[09:49:02.279] <TB0>     INFO: Resuming triggers.

[09:49:06.074] <TB0>     INFO: run duration 15 seconds, buffer almost full (81%), pausing triggers.

[09:49:36.782] <TB0>     INFO: Resuming triggers.

[09:49:40.578] <TB0>     INFO: run duration 18 seconds, buffer almost full (81%), pausing triggers.

[09:50:11.846] <TB0>     INFO: Resuming triggers.

[09:50:15.644] <TB0>     INFO: run duration 22 seconds, buffer almost full (81%), pausing triggers.

[09:50:48.727] <TB0>     INFO: Resuming triggers.

[09:50:52.523] <TB0>     INFO: run duration 26 seconds, buffer almost full (81%), pausing triggers.

[09:51:24.444] <TB0>     INFO: Resuming triggers.

[09:51:28.238] <TB0>     INFO: run duration 30 seconds, buffer almost full (81%), pausing triggers.

[09:52:01.248] <TB0>     INFO: Resuming triggers.

[09:52:05.040] <TB0>     INFO: run duration 34 seconds, buffer almost full (81%), pausing triggers.

[09:52:37.371] <TB0>     INFO: Resuming triggers.

[09:52:41.165] <TB0>     INFO: run duration 37 seconds, buffer almost full (81%), pausing triggers.

[09:53:13.285] <TB0>     INFO: Resuming triggers.

[09:53:17.078] <TB0>     INFO: run duration 41 seconds, buffer almost full (81%), pausing triggers.

[09:53:47.944] <TB0>     INFO: Resuming triggers.

[09:53:51.737] <TB0>     INFO: run duration 45 seconds, buffer almost full (81%), pausing triggers.

[09:54:23.134] <TB0>     INFO: Resuming triggers.

[09:54:26.925] <TB0>     INFO: run duration 49 seconds, buffer almost full (81%), pausing triggers.

[09:54:57.322] <TB0>     INFO: Resuming triggers.

[09:55:01.116] <TB0>     INFO: run duration 53 seconds, buffer almost full (81%), pausing triggers.

[09:55:31.592] <TB0>     INFO: Resuming triggers.

[09:55:35.384] <TB0>     INFO: run duration 56 seconds, buffer almost full (81%), pausing triggers.

[09:56:07.127] <TB0>     INFO: Resuming triggers.

[09:56:10.916] <TB0>     INFO: run duration 60 seconds, buffer almost full (81%), pausing triggers.

[09:56:41.682] <TB0>     INFO: Resuming triggers.

[09:56:45.473] <TB0>     INFO: run duration 64 seconds, buffer almost full (81%), pausing triggers.

[09:57:16.954] <TB0>     INFO: Resuming triggers.

[09:57:20.746] <TB0>     INFO: run duration 68 seconds, buffer almost full (81%), pausing triggers.

[09:57:52.722] <TB0>     INFO: Resuming triggers.

[09:57:56.513] <TB0>     INFO: run duration 72 seconds, buffer almost full (81%), pausing triggers.

[09:58:27.501] <TB0>     INFO: Resuming triggers.

[09:58:31.291] <TB0>     INFO: run duration 75 seconds, buffer almost full (81%), pausing triggers.

[09:59:03.714] <TB0>     INFO: Resuming triggers.

[09:59:07.505] <TB0>     INFO: run duration 79 seconds, buffer almost full (81%), pausing triggers.

[09:59:40.307] <TB0>     INFO: Resuming triggers.

[09:59:44.095] <TB0>     INFO: run duration 83 seconds, buffer almost full (81%), pausing triggers.

[10:00:17.988] <TB0>     INFO: Resuming triggers.

[10:00:21.774] <TB0>     INFO: run duration 87 seconds, buffer almost full (81%), pausing triggers.

[10:00:55.776] <TB0>     INFO: Resuming triggers.

[10:00:59.565] <TB0>     INFO: run duration 90 seconds, buffer almost full (81%), pausing triggers.

[10:01:33.517] <TB0>     INFO: Resuming triggers.

[10:01:37.303] <TB0>     INFO: run duration 94 seconds, buffer almost full (81%), pausing triggers.

[10:02:11.251] <TB0>     INFO: Resuming triggers.

[10:02:15.039] <TB0>     INFO: run duration 98 seconds, buffer almost full (81%), pausing triggers.

[10:02:50.980] <TB0>     INFO: Resuming triggers.

[10:02:52.479] <TB0>     INFO: data taking finished, elapsed time: 100 seconds.

[10:03:06.881] <TB0>     INFO: PixTest::       pg_setup set to default.

[10:03:06.882] <TB0>     INFO: PixTestXray::doPhRun() done

[10:03:06.882] <TB0>     INFO: PixTestXray::doTest() done 

[10:03:07.071] <TB0>     INFO: enter test to run

[10:03:07.071] <TB0>     INFO:   test:  no parameter change

[10:03:07.192] <TB0>    QUIET: Connection to board 83 closed.

[10:03:07.273] <TB0>     INFO: pXar: this is the end, my friend

commander_HRSCurves.log

[10:04:37.517] <TB0>     INFO: *** Welcome to pxar ***

[10:04:37.517] <TB0>     INFO: *** Today: 2015/12/16

[10:04:37.850] <TB0>     INFO: *** Version: b2d3

[10:04:37.851] <TB0>     INFO: readRocDacs: /usr/local/data/M2125_XrayQualification_2015-12-16_10h27m_1450258068//006_HRSCurves_100//dacParameters35_C0.dat .. /usr/local/data/M2125_XrayQualification_2015-12-16_10h27m_1450258068//006_HRSCurves_100//dacParameters35_C15.dat

[10:04:37.851] <TB0>     INFO: readTbmDacs: /usr/local/data/M2125_XrayQualification_2015-12-16_10h27m_1450258068//006_HRSCurves_100//tbmParameters_C0a.dat .. /usr/local/data/M2125_XrayQualification_2015-12-16_10h27m_1450258068//006_HRSCurves_100//tbmParameters_C0b.dat

[10:04:37.851] <TB0>     INFO: readMaskFile: /usr/local/data/M2125_XrayQualification_2015-12-16_10h27m_1450258068//006_HRSCurves_100//defaultMaskFile.dat

[10:04:37.851] <TB0>     INFO: readTrimFile: /usr/local/data/M2125_XrayQualification_2015-12-16_10h27m_1450258068//006_HRSCurves_100//trimParameters35_C0.dat .. /usr/local/data/M2125_XrayQualification_2015-12-16_10h27m_1450258068//006_HRSCurves_100//trimParameters35_C15.dat

[10:04:37.916] <TB0>     INFO:         clk: 4

[10:04:37.916] <TB0>     INFO:         ctr: 4

[10:04:37.916] <TB0>     INFO:         sda: 19

[10:04:37.916] <TB0>     INFO:         tin: 9

[10:04:37.916] <TB0>     INFO:         level: 15

[10:04:37.916] <TB0>     INFO:         triggerdelay: 0

[10:04:37.916] <TB0>    QUIET: Instanciating API for pxar prod-12

[10:04:37.916] <TB0>     INFO: Log level: INFO

[10:04:37.929] <TB0>     INFO: Found DTB DTB_WS6UZO

[10:04:37.939] <TB0>    QUIET: Connection to board DTB_WS6UZO opened.

[10:04:37.942] <TB0>     INFO: DTB startup information

--- DTB info------------------------------------------

Board id:    83

HW version:  DTB1.2

FW version:  4.2

SW version:  4.5

USB id:      DTB_WS6UZO

MAC address: 40D855118053

Hostname:    pixelDTB083

Comment:     

------------------------------------------------------

[10:04:37.945] <TB0>     INFO: RPC call hashes of host and DTB match: 398089610

[10:04:39.452] <TB0>     INFO: DUT info: 

[10:04:39.452] <TB0>     INFO: The DUT currently contains the following objects:

[10:04:39.452] <TB0>     INFO:  2 TBM Cores tbm09c (2 ON)

[10:04:39.452] <TB0>     INFO: 	TBM Core alpha (0): 7 registers set

[10:04:39.452] <TB0>     INFO: 	TBM Core beta  (1): 7 registers set

[10:04:39.452] <TB0>     INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs

[10:04:39.452] <TB0>     INFO: 	ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.

[10:04:39.452] <TB0>     INFO: 	ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.

[10:04:39.452] <TB0>     INFO: 	ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.

[10:04:39.452] <TB0>     INFO: 	ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.

[10:04:39.452] <TB0>     INFO: 	ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.

[10:04:39.452] <TB0>     INFO: 	ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.

[10:04:39.452] <TB0>     INFO: 	ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.

[10:04:39.452] <TB0>     INFO: 	ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.

[10:04:39.452] <TB0>     INFO: 	ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.

[10:04:39.452] <TB0>     INFO: 	ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.

[10:04:39.452] <TB0>     INFO: 	ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.

[10:04:39.452] <TB0>     INFO: 	ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.

[10:04:39.452] <TB0>     INFO: 	ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.

[10:04:39.452] <TB0>     INFO: 	ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.

[10:04:39.452] <TB0>     INFO: 	ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.

[10:04:39.452] <TB0>     INFO: 	ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.

[10:04:39.853] <TB0>     INFO: enter 'restricted' command line mode

[10:04:39.853] <TB0>     INFO: enter test to run

[10:04:39.853] <TB0>     INFO:   test: Highrate no parameter change

[10:04:39.853] <TB0>     INFO:   running: highrate

[10:04:39.855] <TB0>     INFO: readGainPedestalParameters /usr/local/data/M2125_XrayQualification_2015-12-16_10h27m_1450258068//006_HRSCurves_100//phCalibrationFitErr35_C0.dat .. /usr/local/data/M2125_XrayQualification_2015-12-16_10h27m_1450258068//006_HRSCurves_100//phCalibrationFitErr35_C15.dat

[10:04:39.988] <TB0>     INFO:    ----------------------------------------------------------------------

[10:04:39.988] <TB0>     INFO:    PixTestHighRate::xNoiseMaps() ntrig = 50, vcal = 200

[10:04:39.989] <TB0>     INFO:    ----------------------------------------------------------------------

[10:04:39.993] <TB0>     INFO: ---> dac: vcal name: xNoiseMap ntrig: 50 dacrange: 15 .. 75 (20/-1) hits flags = 896 (plus default)

[10:04:40.003] <TB0>     INFO:   dacScan step  from 15 .. 34

[10:04:40.003] <TB0>     INFO:   dacScan split into 1 runs with ntrig = 50

[10:04:40.003] <TB0>     INFO:     run 1 of 1

[10:04:40.593] <TB0>     INFO: Expecting 4160000 events.

[10:05:17.728] <TB0>     INFO: 470200 events read in total (36606ms).

[10:05:53.088] <TB0>     INFO: 941800 events read in total (71966ms).

[10:06:29.072] <TB0>     INFO: 1413600 events read in total (107950ms).

[10:07:04.040] <TB0>     INFO: 1885100 events read in total (142918ms).

[10:07:38.955] <TB0>     INFO: 2356500 events read in total (177833ms).

[10:08:13.803] <TB0>     INFO: 2827800 events read in total (212682ms).

[10:08:48.186] <TB0>     INFO: 3299000 events read in total (247064ms).

[10:09:23.176] <TB0>     INFO: 3770350 events read in total (282054ms).

[10:09:54.540] <TB0>     INFO: 4160000 events read in total (313418ms).

[10:09:54.893] <TB0>     INFO: Test took 314889ms.

[10:09:57.244] <TB0>     INFO: Fetched DAQ statistics. Counters are being reset now.

[10:10:01.818] <TB0>     INFO:   dacScan step  from 35 .. 54

[10:10:01.818] <TB0>     INFO:   dacScan split into 1 runs with ntrig = 50

[10:10:01.818] <TB0>     INFO:     run 1 of 1

[10:10:02.422] <TB0>     INFO: Expecting 4160000 events.

[10:10:38.984] <TB0>     INFO: 391850 events read in total (36035ms).

[10:11:11.351] <TB0>     INFO: 784200 events read in total (68402ms).

[10:11:45.628] <TB0>     INFO: 1175400 events read in total (102679ms).

[10:12:19.984] <TB0>     INFO: 1565800 events read in total (137035ms).

[10:12:54.747] <TB0>     INFO: 1955050 events read in total (171798ms).

[10:13:30.265] <TB0>     INFO: 2342550 events read in total (207316ms).

[10:14:06.394] <TB0>     INFO: 2727000 events read in total (243445ms).

[10:14:43.447] <TB0>     INFO: 3108550 events read in total (280498ms).

[10:15:19.769] <TB0>     INFO: 3487150 events read in total (316820ms).

[10:15:55.332] <TB0>     INFO: 3864250 events read in total (352383ms).

[10:16:23.569] <TB0>     INFO: 4160000 events read in total (380620ms).

[10:16:23.757] <TB0>     INFO: Test took 381938ms.

[10:16:25.263] <TB0>     INFO: Fetched DAQ statistics. Counters are being reset now.

[10:16:30.191] <TB0>     INFO:   dacScan step  from 55 .. 74

[10:16:30.191] <TB0>     INFO:   dacScan split into 1 runs with ntrig = 50

[10:16:30.191] <TB0>     INFO:     run 1 of 1

[10:16:30.799] <TB0>     INFO: Expecting 4160000 events.

[10:17:06.396] <TB0>     INFO: 345850 events read in total (35058ms).

[10:17:40.926] <TB0>     INFO: 692500 events read in total (69588ms).

[10:18:09.832] <TB0>     INFO: 1039050 events read in total (98494ms).

[10:18:45.866] <TB0>     INFO: 1385750 events read in total (134528ms).

[10:19:21.938] <TB0>     INFO: 1732300 events read in total (170600ms).

[10:19:58.416] <TB0>     INFO: 2079000 events read in total (207078ms).

[10:20:34.851] <TB0>     INFO: 2425550 events read in total (243513ms).

[10:21:09.632] <TB0>     INFO: 2772050 events read in total (278294ms).

[10:21:44.734] <TB0>     INFO: 3118500 events read in total (313396ms).

[10:22:18.282] <TB0>     INFO: 3464800 events read in total (346944ms).

[10:22:49.967] <TB0>     INFO: 3811150 events read in total (378629ms).

[10:23:24.470] <TB0>     INFO: 4157050 events read in total (413132ms).

[10:23:25.074] <TB0>     INFO: 4160000 events read in total (413736ms).

[10:23:25.262] <TB0>     INFO: Test took 415071ms.

[10:23:26.928] <TB0>     INFO: Fetched DAQ statistics. Counters are being reset now.

[10:23:31.826] <TB0>     INFO:   dacScan step  from 75 .. 75

[10:23:31.826] <TB0>     INFO:   dacScan split into 1 runs with ntrig = 50

[10:23:31.826] <TB0>     INFO:     run 1 of 1

[10:23:32.422] <TB0>     INFO: Expecting 208000 events.

[10:23:54.787] <TB0>     INFO: 208000 events read in total (21838ms).

[10:23:54.794] <TB0>     INFO: Test took 22968ms.

[10:23:54.869] <TB0>     INFO: Fetched DAQ statistics. Counters are being reset now.

[10:23:55.100] <TB0>     INFO: dumping ASCII scurve output file: XSCurveData

[10:23:56.199] <TB0>     INFO: dumping ASCII scurve output file: XSCurveData

[10:23:57.235] <TB0>     INFO: dumping ASCII scurve output file: XSCurveData

[10:23:58.271] <TB0>     INFO: dumping ASCII scurve output file: XSCurveData

[10:23:59.335] <TB0>     INFO: dumping ASCII scurve output file: XSCurveData

[10:24:00.394] <TB0>     INFO: dumping ASCII scurve output file: XSCurveData

[10:24:01.484] <TB0>     INFO: dumping ASCII scurve output file: XSCurveData

[10:24:02.564] <TB0>     INFO: dumping ASCII scurve output file: XSCurveData

[10:24:03.651] <TB0>     INFO: dumping ASCII scurve output file: XSCurveData

[10:24:04.724] <TB0>     INFO: dumping ASCII scurve output file: XSCurveData

[10:24:05.784] <TB0>     INFO: dumping ASCII scurve output file: XSCurveData

[10:24:06.846] <TB0>     INFO: dumping ASCII scurve output file: XSCurveData

[10:24:07.898] <TB0>     INFO: dumping ASCII scurve output file: XSCurveData

[10:24:08.992] <TB0>     INFO: dumping ASCII scurve output file: XSCurveData

[10:24:10.054] <TB0>     INFO: dumping ASCII scurve output file: XSCurveData

[10:24:11.127] <TB0>     INFO: dumping ASCII scurve output file: XSCurveData

[10:24:12.270] <TB0>     INFO: PixTest::       pg_setup set to default.

[10:24:12.272] <TB0>     INFO: vcal mean:  38.69  39.01  38.11  40.72  41.68  42.77  42.67  43.70  41.62  42.06  42.26  40.76  40.47  38.00  38.44  37.75 

[10:24:12.272] <TB0>     INFO: vcal RMS:    1.02   1.12   0.91   1.22   1.37   1.33   1.37   1.68   1.32   1.36   1.40   1.37   1.24   0.99   1.01   1.30 

[10:24:12.272] <TB0>     INFO: number of X-ray hits detected:    174334 199404 156864 264220 316004 379645 355875 424405 323833 329156 346255 268957 258443 137948 162622 152409

[10:24:12.272] <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

[10:24:12.273] <TB0>     INFO: X-ray hit rate [MHz/cm2]:  51.1 58.4 46.0 77.4 92.6 111.3 104.3 124.4 94.9 96.5 101.5 78.8 75.8 40.4 47.7 44.7

[10:24:12.273] <TB0>     INFO: PixTestHighRate::doXNoiseMaps() done

[10:24:12.374] <TB0>     INFO: enter test to run

[10:24:12.375] <TB0>     INFO:   test:  no parameter change

[10:24:12.468] <TB0>    QUIET: Connection to board 83 closed.

[10:24:12.547] <TB0>     INFO: pXar: this is the end, my friend

commander_CalDelScanAndSaveDacs.log

[11:47:03.344] <TB0>     INFO: *** Welcome to pxar ***

[11:47:03.344] <TB0>     INFO: *** Today: 2015/12/16

[11:47:03.683] <TB0>     INFO: *** Version: b2d3

[11:47:03.683] <TB0>     INFO: readRocDacs: /usr/local/data/M2125_XrayQualification_2015-12-16_10h27m_1450258068//011_CalDelScanAndSaveDacs_4mA25kV//dacParameters35_C0.dat .. /usr/local/data/M2125_XrayQualification_2015-12-16_10h27m_1450258068//011_CalDelScanAndSaveDacs_4mA25kV//dacParameters35_C15.dat

[11:47:03.684] <TB0>     INFO: readTbmDacs: /usr/local/data/M2125_XrayQualification_2015-12-16_10h27m_1450258068//011_CalDelScanAndSaveDacs_4mA25kV//tbmParameters_C0a.dat .. /usr/local/data/M2125_XrayQualification_2015-12-16_10h27m_1450258068//011_CalDelScanAndSaveDacs_4mA25kV//tbmParameters_C0b.dat

[11:47:03.684] <TB0>     INFO: readMaskFile: /usr/local/data/M2125_XrayQualification_2015-12-16_10h27m_1450258068//011_CalDelScanAndSaveDacs_4mA25kV//defaultMaskFile.dat

[11:47:03.684] <TB0>     INFO: readTrimFile: /usr/local/data/M2125_XrayQualification_2015-12-16_10h27m_1450258068//011_CalDelScanAndSaveDacs_4mA25kV//trimParameters35_C0.dat .. /usr/local/data/M2125_XrayQualification_2015-12-16_10h27m_1450258068//011_CalDelScanAndSaveDacs_4mA25kV//trimParameters35_C15.dat

[11:47:03.754] <TB0>     INFO:         clk: 4

[11:47:03.754] <TB0>     INFO:         ctr: 4

[11:47:03.754] <TB0>     INFO:         sda: 19

[11:47:03.754] <TB0>     INFO:         tin: 9

[11:47:03.754] <TB0>     INFO:         level: 15

[11:47:03.754] <TB0>     INFO:         triggerdelay: 0

[11:47:03.754] <TB0>    QUIET: Instanciating API for pxar prod-12

[11:47:03.754] <TB0>     INFO: Log level: INFO

[11:47:03.767] <TB0>     INFO: Found DTB DTB_WS6UZO

[11:47:03.777] <TB0>    QUIET: Connection to board DTB_WS6UZO opened.

[11:47:03.780] <TB0>     INFO: DTB startup information

--- DTB info------------------------------------------

Board id:    83

HW version:  DTB1.2

FW version:  4.2

SW version:  4.5

USB id:      DTB_WS6UZO

MAC address: 40D855118053

Hostname:    pixelDTB083

Comment:     

------------------------------------------------------

[11:47:03.783] <TB0>     INFO: RPC call hashes of host and DTB match: 398089610

[11:47:05.291] <TB0>     INFO: DUT info: 

[11:47:05.291] <TB0>     INFO: The DUT currently contains the following objects:

[11:47:05.291] <TB0>     INFO:  2 TBM Cores tbm09c (2 ON)

[11:47:05.291] <TB0>     INFO: 	TBM Core alpha (0): 7 registers set

[11:47:05.291] <TB0>     INFO: 	TBM Core beta  (1): 7 registers set

[11:47:05.291] <TB0>     INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs

[11:47:05.291] <TB0>     INFO: 	ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.

[11:47:05.291] <TB0>     INFO: 	ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.

[11:47:05.291] <TB0>     INFO: 	ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.

[11:47:05.291] <TB0>     INFO: 	ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.

[11:47:05.291] <TB0>     INFO: 	ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.

[11:47:05.291] <TB0>     INFO: 	ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.

[11:47:05.291] <TB0>     INFO: 	ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.

[11:47:05.291] <TB0>     INFO: 	ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.

[11:47:05.291] <TB0>     INFO: 	ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.

[11:47:05.291] <TB0>     INFO: 	ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.

[11:47:05.291] <TB0>     INFO: 	ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.

[11:47:05.291] <TB0>     INFO: 	ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.

[11:47:05.291] <TB0>     INFO: 	ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.

[11:47:05.291] <TB0>     INFO: 	ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.

[11:47:05.291] <TB0>     INFO: 	ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.

[11:47:05.291] <TB0>     INFO: 	ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.

[11:47:05.692] <TB0>     INFO: enter 'restricted' command line mode

[11:47:05.692] <TB0>     INFO: enter test to run

[11:47:05.692] <TB0>     INFO:   test: HighRate no parameter change

[11:47:05.692] <TB0>     INFO:   running: highrate

[11:47:05.694] <TB0>     INFO: readGainPedestalParameters /usr/local/data/M2125_XrayQualification_2015-12-16_10h27m_1450258068//011_CalDelScanAndSaveDacs_4mA25kV//phCalibrationFitErr35_C0.dat .. /usr/local/data/M2125_XrayQualification_2015-12-16_10h27m_1450258068//011_CalDelScanAndSaveDacs_4mA25kV//phCalibrationFitErr35_C15.dat

[11:47:05.817] <TB0>     INFO:    ----------------------------------------------------------------------

[11:47:05.817] <TB0>     INFO:    PixTestHighRate::calDelScan() ntrig = 10, vcal = 200

[11:47:05.817] <TB0>     INFO:    ----------------------------------------------------------------------

[11:47:05.954] <TB0>     INFO: Expecting 768 events.

[11:47:07.181] <TB0>     INFO: 768 events read in total (509ms).

[11:47:07.276] <TB0>     INFO: Test took 1454ms.

[11:47:07.891] <TB0>     INFO: Expecting 41600 events.

[11:47:11.256] <TB0>     INFO: 41600 events read in total (2838ms).

[11:47:11.257] <TB0>     INFO: Test took 3977ms.

[11:47:11.320] <TB0>     INFO: Fetched DAQ statistics. Counters are being reset now.

[11:47:11.956] <TB0>     INFO: Expecting 41600 events.

[11:47:16.127] <TB0>     INFO: 41600 events read in total (3644ms).

[11:47:16.129] <TB0>     INFO: Test took 4785ms.

[11:47:16.196] <TB0>     INFO: Fetched DAQ statistics. Counters are being reset now.

[11:47:16.833] <TB0>     INFO: Expecting 41600 events.

[11:47:21.582] <TB0>     INFO: 41600 events read in total (4222ms).

[11:47:21.583] <TB0>     INFO: Test took 5362ms.

[11:47:21.649] <TB0>     INFO: Fetched DAQ statistics. Counters are being reset now.

[11:47:22.287] <TB0>     INFO: Expecting 41600 events.

[11:47:26.842] <TB0>     INFO: 41600 events read in total (4028ms).

[11:47:26.843] <TB0>     INFO: Test took 5168ms.

[11:47:26.909] <TB0>     INFO: Fetched DAQ statistics. Counters are being reset now.

[11:47:27.547] <TB0>     INFO: Expecting 41600 events.

[11:47:31.876] <TB0>     INFO: 41600 events read in total (3802ms).

[11:47:31.878] <TB0>     INFO: Test took 4943ms.

[11:47:31.943] <TB0>     INFO: Fetched DAQ statistics. Counters are being reset now.

[11:47:32.581] <TB0>     INFO: Expecting 41600 events.

[11:47:37.061] <TB0>     INFO: 41600 events read in total (3953ms).

[11:47:37.063] <TB0>     INFO: Test took 5094ms.

[11:47:37.132] <TB0>     INFO: Fetched DAQ statistics. Counters are being reset now.

[11:47:37.770] <TB0>     INFO: Expecting 41600 events.

[11:47:42.206] <TB0>     INFO: 41600 events read in total (3909ms).

[11:47:42.207] <TB0>     INFO: Test took 5049ms.

[11:47:42.274] <TB0>     INFO: Fetched DAQ statistics. Counters are being reset now.

[11:47:42.912] <TB0>     INFO: Expecting 41600 events.

[11:47:47.217] <TB0>     INFO: 41600 events read in total (3778ms).

[11:47:47.219] <TB0>     INFO: Test took 4919ms.

[11:47:47.289] <TB0>     INFO: Fetched DAQ statistics. Counters are being reset now.

[11:47:47.943] <TB0>     INFO: Expecting 41600 events.

[11:47:51.922] <TB0>     INFO: 41600 events read in total (3452ms).

[11:47:51.924] <TB0>     INFO: Test took 4605ms.

[11:47:52.001] <TB0>     INFO: Fetched DAQ statistics. Counters are being reset now.

[11:47:52.653] <TB0>     INFO: Expecting 41600 events.

[11:47:56.634] <TB0>     INFO: 41600 events read in total (3454ms).

[11:47:56.636] <TB0>     INFO: Test took 4602ms.

[11:47:56.712] <TB0>     INFO: Fetched DAQ statistics. Counters are being reset now.

[11:47:57.365] <TB0>     INFO: Expecting 41600 events.

[11:48:01.329] <TB0>     INFO: 41600 events read in total (3436ms).

[11:48:01.331] <TB0>     INFO: Test took 4587ms.

[11:48:01.399] <TB0>     INFO: Fetched DAQ statistics. Counters are being reset now.

[11:48:02.038] <TB0>     INFO: Expecting 41600 events.

[11:48:06.385] <TB0>     INFO: 41600 events read in total (3820ms).

[11:48:06.387] <TB0>     INFO: Test took 4961ms.

[11:48:06.459] <TB0>     INFO: Fetched DAQ statistics. Counters are being reset now.

[11:48:07.099] <TB0>     INFO: Expecting 41600 events.

[11:48:11.454] <TB0>     INFO: 41600 events read in total (3827ms).

[11:48:11.456] <TB0>     INFO: Test took 4969ms.

[11:48:11.523] <TB0>     INFO: Fetched DAQ statistics. Counters are being reset now.

[11:48:12.162] <TB0>     INFO: Expecting 41600 events.

[11:48:16.610] <TB0>     INFO: 41600 events read in total (3921ms).

[11:48:16.612] <TB0>     INFO: Test took 5062ms.

[11:48:16.680] <TB0>     INFO: Fetched DAQ statistics. Counters are being reset now.

[11:48:17.319] <TB0>     INFO: Expecting 41600 events.

[11:48:21.552] <TB0>     INFO: 41600 events read in total (3706ms).

[11:48:21.553] <TB0>     INFO: Test took 4846ms.

[11:48:21.630] <TB0>     INFO: Fetched DAQ statistics. Counters are being reset now.

[11:48:22.273] <TB0>     INFO: Expecting 41600 events.

[11:48:26.995] <TB0>     INFO: 41600 events read in total (4195ms).

[11:48:26.996] <TB0>     INFO: Test took 5335ms.

[11:48:27.073] <TB0>     INFO: Fetched DAQ statistics. Counters are being reset now.

[11:48:27.728] <TB0>     INFO: Expecting 41600 events.

[11:48:32.304] <TB0>     INFO: 41600 events read in total (4049ms).

[11:48:32.306] <TB0>     INFO: Test took 5203ms.

[11:48:32.384] <TB0>     INFO: Fetched DAQ statistics. Counters are being reset now.

[11:48:33.031] <TB0>     INFO: Expecting 41600 events.

[11:48:37.786] <TB0>     INFO: 41600 events read in total (4228ms).

[11:48:37.788] <TB0>     INFO: Test took 5376ms.

[11:48:37.869] <TB0>     INFO: Fetched DAQ statistics. Counters are being reset now.

[11:48:38.609] <TB0>     INFO: Expecting 41600 events.

[11:48:43.123] <TB0>     INFO: 41600 events read in total (3987ms).

[11:48:43.125] <TB0>     INFO: Test took 5215ms.

[11:48:43.194] <TB0>     INFO: Fetched DAQ statistics. Counters are being reset now.

[11:48:43.833] <TB0>     INFO: Expecting 41600 events.

[11:48:48.190] <TB0>     INFO: 41600 events read in total (3829ms).

[11:48:48.192] <TB0>     INFO: Test took 4970ms.

[11:48:48.272] <TB0>     INFO: Fetched DAQ statistics. Counters are being reset now.

[11:48:48.603] <TB0>     INFO: enter test to run

[11:48:48.603] <TB0>     INFO:   test: Pretest no parameter change

[11:48:48.603] <TB0>     INFO:   running: pretest

[11:48:48.604] <TB0>     INFO: write dac parameters into /usr/local/data/M2125_XrayQualification_2015-12-16_10h27m_1450258068//011_CalDelScanAndSaveDacs_4mA25kV//dacParameters35_C0.dat

[11:48:48.604] <TB0>     INFO: write dac parameters into /usr/local/data/M2125_XrayQualification_2015-12-16_10h27m_1450258068//011_CalDelScanAndSaveDacs_4mA25kV//dacParameters35_C1.dat

[11:48:48.604] <TB0>     INFO: write dac parameters into /usr/local/data/M2125_XrayQualification_2015-12-16_10h27m_1450258068//011_CalDelScanAndSaveDacs_4mA25kV//dacParameters35_C2.dat

[11:48:48.604] <TB0>     INFO: write dac parameters into /usr/local/data/M2125_XrayQualification_2015-12-16_10h27m_1450258068//011_CalDelScanAndSaveDacs_4mA25kV//dacParameters35_C3.dat

[11:48:48.604] <TB0>     INFO: write dac parameters into /usr/local/data/M2125_XrayQualification_2015-12-16_10h27m_1450258068//011_CalDelScanAndSaveDacs_4mA25kV//dacParameters35_C4.dat

[11:48:48.604] <TB0>     INFO: write dac parameters into /usr/local/data/M2125_XrayQualification_2015-12-16_10h27m_1450258068//011_CalDelScanAndSaveDacs_4mA25kV//dacParameters35_C5.dat

[11:48:48.604] <TB0>     INFO: write dac parameters into /usr/local/data/M2125_XrayQualification_2015-12-16_10h27m_1450258068//011_CalDelScanAndSaveDacs_4mA25kV//dacParameters35_C6.dat

[11:48:48.605] <TB0>     INFO: write dac parameters into /usr/local/data/M2125_XrayQualification_2015-12-16_10h27m_1450258068//011_CalDelScanAndSaveDacs_4mA25kV//dacParameters35_C7.dat

[11:48:48.605] <TB0>     INFO: write dac parameters into /usr/local/data/M2125_XrayQualification_2015-12-16_10h27m_1450258068//011_CalDelScanAndSaveDacs_4mA25kV//dacParameters35_C8.dat

[11:48:48.605] <TB0>     INFO: write dac parameters into /usr/local/data/M2125_XrayQualification_2015-12-16_10h27m_1450258068//011_CalDelScanAndSaveDacs_4mA25kV//dacParameters35_C9.dat

[11:48:48.605] <TB0>     INFO: write dac parameters into /usr/local/data/M2125_XrayQualification_2015-12-16_10h27m_1450258068//011_CalDelScanAndSaveDacs_4mA25kV//dacParameters35_C10.dat

[11:48:48.605] <TB0>     INFO: write dac parameters into /usr/local/data/M2125_XrayQualification_2015-12-16_10h27m_1450258068//011_CalDelScanAndSaveDacs_4mA25kV//dacParameters35_C11.dat

[11:48:48.605] <TB0>     INFO: write dac parameters into /usr/local/data/M2125_XrayQualification_2015-12-16_10h27m_1450258068//011_CalDelScanAndSaveDacs_4mA25kV//dacParameters35_C12.dat

[11:48:48.605] <TB0>     INFO: write dac parameters into /usr/local/data/M2125_XrayQualification_2015-12-16_10h27m_1450258068//011_CalDelScanAndSaveDacs_4mA25kV//dacParameters35_C13.dat

[11:48:48.605] <TB0>     INFO: write dac parameters into /usr/local/data/M2125_XrayQualification_2015-12-16_10h27m_1450258068//011_CalDelScanAndSaveDacs_4mA25kV//dacParameters35_C14.dat

[11:48:48.605] <TB0>     INFO: write dac parameters into /usr/local/data/M2125_XrayQualification_2015-12-16_10h27m_1450258068//011_CalDelScanAndSaveDacs_4mA25kV//dacParameters35_C15.dat

[11:48:48.605] <TB0>     INFO: enter test to run

[11:48:48.605] <TB0>     INFO:   test:  no parameter change

[11:48:48.687] <TB0>    QUIET: Connection to board 83 closed.

[11:48:48.767] <TB0>     INFO: pXar: this is the end, my friend

commander_HREfficiency.log

[11:49:17.421] <TB0>     INFO: *** Welcome to pxar ***

[11:49:17.421] <TB0>     INFO: *** Today: 2015/12/16

[11:49:17.756] <TB0>     INFO: *** Version: b2d3

[11:49:17.756] <TB0>     INFO: readRocDacs: /usr/local/data/M2125_XrayQualification_2015-12-16_10h27m_1450258068//012_HREfficiency_50//dacParameters35_C0.dat .. /usr/local/data/M2125_XrayQualification_2015-12-16_10h27m_1450258068//012_HREfficiency_50//dacParameters35_C15.dat

[11:49:17.757] <TB0>     INFO: readTbmDacs: /usr/local/data/M2125_XrayQualification_2015-12-16_10h27m_1450258068//012_HREfficiency_50//tbmParameters_C0a.dat .. /usr/local/data/M2125_XrayQualification_2015-12-16_10h27m_1450258068//012_HREfficiency_50//tbmParameters_C0b.dat

[11:49:17.757] <TB0>     INFO: readMaskFile: /usr/local/data/M2125_XrayQualification_2015-12-16_10h27m_1450258068//012_HREfficiency_50//defaultMaskFile.dat

[11:49:17.757] <TB0>     INFO: readTrimFile: /usr/local/data/M2125_XrayQualification_2015-12-16_10h27m_1450258068//012_HREfficiency_50//trimParameters35_C0.dat .. /usr/local/data/M2125_XrayQualification_2015-12-16_10h27m_1450258068//012_HREfficiency_50//trimParameters35_C15.dat

[11:49:17.825] <TB0>     INFO:         clk: 4

[11:49:17.825] <TB0>     INFO:         ctr: 4

[11:49:17.825] <TB0>     INFO:         sda: 19

[11:49:17.825] <TB0>     INFO:         tin: 9

[11:49:17.825] <TB0>     INFO:         level: 15

[11:49:17.825] <TB0>     INFO:         triggerdelay: 0

[11:49:17.825] <TB0>    QUIET: Instanciating API for pxar prod-12

[11:49:17.825] <TB0>     INFO: Log level: INFO

[11:49:17.838] <TB0>     INFO: Found DTB DTB_WS6UZO

[11:49:17.848] <TB0>    QUIET: Connection to board DTB_WS6UZO opened.

[11:49:17.851] <TB0>     INFO: DTB startup information

--- DTB info------------------------------------------

Board id:    83

HW version:  DTB1.2

FW version:  4.2

SW version:  4.5

USB id:      DTB_WS6UZO

MAC address: 40D855118053

Hostname:    pixelDTB083

Comment:     

------------------------------------------------------

[11:49:17.854] <TB0>     INFO: RPC call hashes of host and DTB match: 398089610

[11:49:19.362] <TB0>     INFO: DUT info: 

[11:49:19.362] <TB0>     INFO: The DUT currently contains the following objects:

[11:49:19.362] <TB0>     INFO:  2 TBM Cores tbm09c (2 ON)

[11:49:19.362] <TB0>     INFO: 	TBM Core alpha (0): 7 registers set

[11:49:19.362] <TB0>     INFO: 	TBM Core beta  (1): 7 registers set

[11:49:19.362] <TB0>     INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs

[11:49:19.362] <TB0>     INFO: 	ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.

[11:49:19.362] <TB0>     INFO: 	ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.

[11:49:19.362] <TB0>     INFO: 	ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.

[11:49:19.362] <TB0>     INFO: 	ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.

[11:49:19.362] <TB0>     INFO: 	ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.

[11:49:19.362] <TB0>     INFO: 	ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.

[11:49:19.362] <TB0>     INFO: 	ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.

[11:49:19.362] <TB0>     INFO: 	ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.

[11:49:19.362] <TB0>     INFO: 	ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.

[11:49:19.362] <TB0>     INFO: 	ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.

[11:49:19.362] <TB0>     INFO: 	ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.

[11:49:19.362] <TB0>     INFO: 	ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.

[11:49:19.362] <TB0>     INFO: 	ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.

[11:49:19.362] <TB0>     INFO: 	ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.

[11:49:19.362] <TB0>     INFO: 	ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.

[11:49:19.362] <TB0>     INFO: 	ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.

[11:49:19.763] <TB0>     INFO: enter 'restricted' command line mode

[11:49:19.763] <TB0>     INFO: enter test to run

[11:49:19.763] <TB0>     INFO:   test: PixelAlive no parameter change

[11:49:19.763] <TB0>     INFO:   running: pixelalive

[11:49:19.767] <TB0>     INFO: ######################################################################

[11:49:19.767] <TB0>     INFO: PixTestAlive::doTest()

[11:49:19.767] <TB0>     INFO: ######################################################################

[11:49:19.769] <TB0>     INFO:    ----------------------------------------------------------------------

[11:49:19.769] <TB0>     INFO:    PixTestAlive::aliveTest() ntrig = 50, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)

[11:49:19.769] <TB0>     INFO:    ----------------------------------------------------------------------

[11:49:20.067] <TB0>     INFO: Expecting 208000 events.

[11:49:31.014] <TB0>     INFO: 208000 events read in total (10228ms).

[11:49:31.035] <TB0>     INFO: Test took 11265ms.

[11:49:31.045] <TB0>     INFO: Fetched DAQ statistics. Counters are being reset now.

[11:49:31.305] <TB0>     INFO: PixTestAlive::aliveTest() done

[11:49:31.305] <TB0>     INFO: number of dead pixels (per ROC):     0    0    0    0    1    0    0    1    0    0    0    0    0    0    0    2

[11:49:31.306] <TB0>     INFO:    ----------------------------------------------------------------------

[11:49:31.306] <TB0>     INFO:    PixTestAlive::maskTest() ntrig = 50, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)

[11:49:31.306] <TB0>     INFO:    ----------------------------------------------------------------------

[11:49:31.619] <TB0>     INFO: Expecting 208000 events.

[11:49:40.075] <TB0>     INFO: 208000 events read in total (7740ms).

[11:49:40.076] <TB0>     INFO: Test took 8768ms.

[11:49:40.076] <TB0>     INFO: Fetched DAQ statistics. Counters are being reset now.

[11:49:40.076] <TB0>     INFO: mask vs. old pixelAlive PixelAlive_C0_V0 ..  PixelAlive_C15_V0

[11:49:40.391] <TB0>     INFO: PixTestAlive::maskTest() done

[11:49:40.391] <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

[11:49:40.392] <TB0>     INFO:    ----------------------------------------------------------------------

[11:49:40.392] <TB0>     INFO:    PixTestAlive::addressDecodingTest() ntrig = 50, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)

[11:49:40.392] <TB0>     INFO:    ----------------------------------------------------------------------

[11:49:40.701] <TB0>     INFO: Expecting 208000 events.

[11:49:53.421] <TB0>     INFO: 208000 events read in total (12004ms).

[11:49:53.421] <TB0>     INFO: Test took 13027ms.

[11:49:53.430] <TB0>     INFO: Fetched DAQ statistics. Counters are being reset now.

[11:49:53.672] <TB0>     INFO: PixTestAlive::addressDecodingTest() done

[11:49:53.672] <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

[11:49:53.672] <TB0>     INFO: PixTestAlive::doTest() done, duration: 33 seconds

[11:49:53.717] <TB0>     INFO: enter test to run

[11:49:53.717] <TB0>     INFO:   test: delay setting parameters: ->2<-

[11:49:53.717] <TB0>     INFO: delay test by 2 seconds...

[11:49:55.717] <TB0>     INFO: enter test to run

[11:49:55.717] <TB0>     INFO:   test: HighRate no parameter change

[11:49:55.717] <TB0>     INFO:   running: highrate

[11:49:55.717] <TB0>     INFO: readGainPedestalParameters /usr/local/data/M2125_XrayQualification_2015-12-16_10h27m_1450258068//012_HREfficiency_50//phCalibrationFitErr35_C0.dat .. /usr/local/data/M2125_XrayQualification_2015-12-16_10h27m_1450258068//012_HREfficiency_50//phCalibrationFitErr35_C15.dat

[11:49:55.905] <TB0>     INFO:    ----------------------------------------------------------------------

[11:49:55.905] <TB0>     INFO:    PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200

[11:49:55.905] <TB0>     INFO:    ----------------------------------------------------------------------

[11:49:56.546] <TB0>     INFO: Expecting 208000 events.

[11:50:12.700] <TB0>     INFO: 208000 events read in total (15627ms).

[11:50:12.707] <TB0>     INFO: Test took 16798ms.

[11:50:12.946] <TB0>     INFO: Fetched DAQ statistics. Counters are being reset now.

[11:50:13.221] <TB0>     INFO: number of dead pixels (per ROC):     0    0    0    0    1    0    0    1    0    0    0    0    0    0    0    2

[11:50:13.221] <TB0>     INFO: number of red-efficiency pixels:   103  180  118  204  193  307  291  331  244  242  275  213  192  115   83   96

[11:50:13.221] <TB0>     INFO: number of X-ray hits detected:    83711 96323 75481 129626 156338 189831 178687 212675 161708 163924 174254 132109 127886 64446 77351 72540

[11:50:13.221] <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

[11:50:13.221] <TB0>     INFO: number of Vcal hits detected:  207896 207760 207881 207790 207752 207683 207694 207593 207742 207746 207715 207782 207801 207883 207917 207805

[11:50:13.221] <TB0>     INFO: Vcal hit fiducial efficiency (%):  100.0 99.9 99.9 99.9 99.9 99.9 99.9 99.8 99.9 99.9 99.9 99.9 99.9 100.0 100.0 100.0

[11:50:13.221] <TB0>     INFO: Vcal hit overall efficiency (%):  100.0 99.9 99.9 99.9 99.9 99.8 99.9 99.8 99.9 99.9 99.9 99.9 99.9 99.9 100.0 99.9

[11:50:13.221] <TB0>     INFO: X-ray hit rate [MHz/cm2]:  24.5 28.2 22.1 38.0 45.8 55.6 52.4 62.3 47.4 48.0 51.1 38.7 37.5 18.9 22.7 21.3

[11:50:13.221] <TB0>     INFO: PixTestHighRate::doXPixelAlive() done

[11:50:13.251] <TB0>     INFO: PixTest::       pg_setup set to default.

[11:50:13.259] <TB0>     INFO: enter test to run

[11:50:13.259] <TB0>     INFO:   test:  no parameter change

[11:50:13.332] <TB0>    QUIET: Connection to board 83 closed.

[11:50:13.412] <TB0>     INFO: pXar: this is the end, my friend

commander_HREfficiency.log

[11:50:44.161] <TB0>     INFO: *** Welcome to pxar ***

[11:50:44.161] <TB0>     INFO: *** Today: 2015/12/16

[11:50:44.492] <TB0>     INFO: *** Version: b2d3

[11:50:44.492] <TB0>     INFO: readRocDacs: /usr/local/data/M2125_XrayQualification_2015-12-16_10h27m_1450258068//013_HREfficiency_75//dacParameters35_C0.dat .. /usr/local/data/M2125_XrayQualification_2015-12-16_10h27m_1450258068//013_HREfficiency_75//dacParameters35_C15.dat

[11:50:44.492] <TB0>     INFO: readTbmDacs: /usr/local/data/M2125_XrayQualification_2015-12-16_10h27m_1450258068//013_HREfficiency_75//tbmParameters_C0a.dat .. /usr/local/data/M2125_XrayQualification_2015-12-16_10h27m_1450258068//013_HREfficiency_75//tbmParameters_C0b.dat

[11:50:44.492] <TB0>     INFO: readMaskFile: /usr/local/data/M2125_XrayQualification_2015-12-16_10h27m_1450258068//013_HREfficiency_75//defaultMaskFile.dat

[11:50:44.492] <TB0>     INFO: readTrimFile: /usr/local/data/M2125_XrayQualification_2015-12-16_10h27m_1450258068//013_HREfficiency_75//trimParameters35_C0.dat .. /usr/local/data/M2125_XrayQualification_2015-12-16_10h27m_1450258068//013_HREfficiency_75//trimParameters35_C15.dat

[11:50:44.560] <TB0>     INFO:         clk: 4

[11:50:44.560] <TB0>     INFO:         ctr: 4

[11:50:44.560] <TB0>     INFO:         sda: 19

[11:50:44.560] <TB0>     INFO:         tin: 9

[11:50:44.560] <TB0>     INFO:         level: 15

[11:50:44.560] <TB0>     INFO:         triggerdelay: 0

[11:50:44.560] <TB0>    QUIET: Instanciating API for pxar prod-12

[11:50:44.560] <TB0>     INFO: Log level: INFO

[11:50:44.573] <TB0>     INFO: Found DTB DTB_WS6UZO

[11:50:44.583] <TB0>    QUIET: Connection to board DTB_WS6UZO opened.

[11:50:44.586] <TB0>     INFO: DTB startup information

--- DTB info------------------------------------------

Board id:    83

HW version:  DTB1.2

FW version:  4.2

SW version:  4.5

USB id:      DTB_WS6UZO

MAC address: 40D855118053

Hostname:    pixelDTB083

Comment:     

------------------------------------------------------

[11:50:44.588] <TB0>     INFO: RPC call hashes of host and DTB match: 398089610

[11:50:46.096] <TB0>     INFO: DUT info: 

[11:50:46.096] <TB0>     INFO: The DUT currently contains the following objects:

[11:50:46.097] <TB0>     INFO:  2 TBM Cores tbm09c (2 ON)

[11:50:46.097] <TB0>     INFO: 	TBM Core alpha (0): 7 registers set

[11:50:46.097] <TB0>     INFO: 	TBM Core beta  (1): 7 registers set

[11:50:46.097] <TB0>     INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs

[11:50:46.097] <TB0>     INFO: 	ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.

[11:50:46.097] <TB0>     INFO: 	ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.

[11:50:46.097] <TB0>     INFO: 	ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.

[11:50:46.097] <TB0>     INFO: 	ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.

[11:50:46.097] <TB0>     INFO: 	ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.

[11:50:46.097] <TB0>     INFO: 	ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.

[11:50:46.097] <TB0>     INFO: 	ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.

[11:50:46.097] <TB0>     INFO: 	ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.

[11:50:46.097] <TB0>     INFO: 	ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.

[11:50:46.097] <TB0>     INFO: 	ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.

[11:50:46.097] <TB0>     INFO: 	ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.

[11:50:46.097] <TB0>     INFO: 	ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.

[11:50:46.097] <TB0>     INFO: 	ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.

[11:50:46.097] <TB0>     INFO: 	ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.

[11:50:46.097] <TB0>     INFO: 	ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.

[11:50:46.097] <TB0>     INFO: 	ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.

[11:50:46.497] <TB0>     INFO: enter 'restricted' command line mode

[11:50:46.497] <TB0>     INFO: enter test to run

[11:50:46.497] <TB0>     INFO:   test: PixelAlive no parameter change

[11:50:46.498] <TB0>     INFO:   running: pixelalive

[11:50:46.502] <TB0>     INFO: ######################################################################

[11:50:46.502] <TB0>     INFO: PixTestAlive::doTest()

[11:50:46.502] <TB0>     INFO: ######################################################################

[11:50:46.503] <TB0>     INFO:    ----------------------------------------------------------------------

[11:50:46.503] <TB0>     INFO:    PixTestAlive::aliveTest() ntrig = 50, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)

[11:50:46.503] <TB0>     INFO:    ----------------------------------------------------------------------

[11:50:46.800] <TB0>     INFO: Expecting 208000 events.

[11:50:57.562] <TB0>     INFO: 208000 events read in total (10043ms).

[11:50:57.564] <TB0>     INFO: Test took 11059ms.

[11:50:57.571] <TB0>     INFO: Fetched DAQ statistics. Counters are being reset now.

[11:50:57.835] <TB0>     INFO: PixTestAlive::aliveTest() done

[11:50:57.835] <TB0>     INFO: number of dead pixels (per ROC):     0    0    0    0    1    0    0    1    0    0    0    0    0    0    0    2

[11:50:57.836] <TB0>     INFO:    ----------------------------------------------------------------------

[11:50:57.836] <TB0>     INFO:    PixTestAlive::maskTest() ntrig = 50, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)

[11:50:57.836] <TB0>     INFO:    ----------------------------------------------------------------------

[11:50:58.149] <TB0>     INFO: Expecting 208000 events.

[11:51:06.743] <TB0>     INFO: 208000 events read in total (7878ms).

[11:51:06.744] <TB0>     INFO: Test took 8906ms.

[11:51:06.744] <TB0>     INFO: Fetched DAQ statistics. Counters are being reset now.

[11:51:06.744] <TB0>     INFO: mask vs. old pixelAlive PixelAlive_C0_V0 ..  PixelAlive_C15_V0

[11:51:07.061] <TB0>     INFO: PixTestAlive::maskTest() done

[11:51:07.061] <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

[11:51:07.063] <TB0>     INFO:    ----------------------------------------------------------------------

[11:51:07.063] <TB0>     INFO:    PixTestAlive::addressDecodingTest() ntrig = 50, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)

[11:51:07.063] <TB0>     INFO:    ----------------------------------------------------------------------

[11:51:07.375] <TB0>     INFO: Expecting 208000 events.

[11:51:19.791] <TB0>     INFO: 208000 events read in total (11700ms).

[11:51:19.791] <TB0>     INFO: Test took 12726ms.

[11:51:19.799] <TB0>     INFO: Fetched DAQ statistics. Counters are being reset now.

[11:51:20.036] <TB0>     INFO: PixTestAlive::addressDecodingTest() done

[11:51:20.036] <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

[11:51:20.036] <TB0>     INFO: PixTestAlive::doTest() done, duration: 33 seconds

[11:51:20.068] <TB0>     INFO: enter test to run

[11:51:20.068] <TB0>     INFO:   test: delay setting parameters: ->2<-

[11:51:20.068] <TB0>     INFO: delay test by 2 seconds...

[11:51:22.068] <TB0>     INFO: enter test to run

[11:51:22.068] <TB0>     INFO:   test: HighRate no parameter change

[11:51:22.068] <TB0>     INFO:   running: highrate

[11:51:22.068] <TB0>     INFO: readGainPedestalParameters /usr/local/data/M2125_XrayQualification_2015-12-16_10h27m_1450258068//013_HREfficiency_75//phCalibrationFitErr35_C0.dat .. /usr/local/data/M2125_XrayQualification_2015-12-16_10h27m_1450258068//013_HREfficiency_75//phCalibrationFitErr35_C15.dat

[11:51:22.240] <TB0>     INFO:    ----------------------------------------------------------------------

[11:51:22.240] <TB0>     INFO:    PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200

[11:51:22.240] <TB0>     INFO:    ----------------------------------------------------------------------

[11:51:22.869] <TB0>     INFO: Expecting 208000 events.

[11:51:42.057] <TB0>     INFO: 208000 events read in total (18661ms).

[11:51:42.063] <TB0>     INFO: Test took 19819ms.

[11:51:42.453] <TB0>     INFO: Fetched DAQ statistics. Counters are being reset now.

[11:51:42.785] <TB0>     INFO: number of dead pixels (per ROC):     0    0    0    0    1    0    0    1    0    0    0    0    0    0    0    2

[11:51:42.785] <TB0>     INFO: number of red-efficiency pixels:   187  227  202  320  416  579  564  687  438  496  480  326  308  160  167  153

[11:51:42.785] <TB0>     INFO: number of X-ray hits detected:    123700 141456 111609 190865 229837 276630 260041 309528 234939 239085 253312 194305 186910 96164 114899 106815

[11:51:42.785] <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

[11:51:42.785] <TB0>     INFO: number of Vcal hits detected:  207808 207750 207787 207667 207507 207360 207376 207170 207520 207456 207478 207660 207680 207830 207827 207748

[11:51:42.785] <TB0>     INFO: Vcal hit fiducial efficiency (%):  99.9 99.9 99.9 99.9 99.8 99.7 99.7 99.7 99.8 99.8 99.8 99.9 99.9 99.9 99.9 99.9

[11:51:42.785] <TB0>     INFO: Vcal hit overall efficiency (%):  99.9 99.9 99.9 99.8 99.8 99.7 99.7 99.6 99.8 99.7 99.7 99.8 99.8 99.9 99.9 99.9

[11:51:42.785] <TB0>     INFO: X-ray hit rate [MHz/cm2]:  36.3 41.5 32.7 55.9 67.4 81.1 76.2 90.7 68.9 70.1 74.2 57.0 54.8 28.2 33.7 31.3

[11:51:42.785] <TB0>     INFO: PixTestHighRate::doXPixelAlive() done

[11:51:42.812] <TB0>     INFO: PixTest::       pg_setup set to default.

[11:51:42.824] <TB0>     INFO: enter test to run

[11:51:42.824] <TB0>     INFO:   test:  no parameter change

[11:51:42.903] <TB0>    QUIET: Connection to board 83 closed.

[11:51:42.982] <TB0>     INFO: pXar: this is the end, my friend

commander_HREfficiency.log

[11:52:49.389] <TB0>     INFO: *** Welcome to pxar ***

[11:52:49.389] <TB0>     INFO: *** Today: 2015/12/16

[11:52:49.726] <TB0>     INFO: *** Version: b2d3

[11:52:49.726] <TB0>     INFO: readRocDacs: /usr/local/data/M2125_XrayQualification_2015-12-16_10h27m_1450258068//014_HREfficiency_100//dacParameters35_C0.dat .. /usr/local/data/M2125_XrayQualification_2015-12-16_10h27m_1450258068//014_HREfficiency_100//dacParameters35_C15.dat

[11:52:49.726] <TB0>     INFO: readTbmDacs: /usr/local/data/M2125_XrayQualification_2015-12-16_10h27m_1450258068//014_HREfficiency_100//tbmParameters_C0a.dat .. /usr/local/data/M2125_XrayQualification_2015-12-16_10h27m_1450258068//014_HREfficiency_100//tbmParameters_C0b.dat

[11:52:49.727] <TB0>     INFO: readMaskFile: /usr/local/data/M2125_XrayQualification_2015-12-16_10h27m_1450258068//014_HREfficiency_100//defaultMaskFile.dat

[11:52:49.727] <TB0>     INFO: readTrimFile: /usr/local/data/M2125_XrayQualification_2015-12-16_10h27m_1450258068//014_HREfficiency_100//trimParameters35_C0.dat .. /usr/local/data/M2125_XrayQualification_2015-12-16_10h27m_1450258068//014_HREfficiency_100//trimParameters35_C15.dat

[11:52:49.794] <TB0>     INFO:         clk: 4

[11:52:49.794] <TB0>     INFO:         ctr: 4

[11:52:49.794] <TB0>     INFO:         sda: 19

[11:52:49.794] <TB0>     INFO:         tin: 9

[11:52:49.794] <TB0>     INFO:         level: 15

[11:52:49.794] <TB0>     INFO:         triggerdelay: 0

[11:52:49.794] <TB0>    QUIET: Instanciating API for pxar prod-12

[11:52:49.794] <TB0>     INFO: Log level: INFO

[11:52:49.806] <TB0>     INFO: Found DTB DTB_WS6UZO

[11:52:49.816] <TB0>    QUIET: Connection to board DTB_WS6UZO opened.

[11:52:49.820] <TB0>     INFO: DTB startup information

--- DTB info------------------------------------------

Board id:    83

HW version:  DTB1.2

FW version:  4.2

SW version:  4.5

USB id:      DTB_WS6UZO

MAC address: 40D855118053

Hostname:    pixelDTB083

Comment:     

------------------------------------------------------

[11:52:49.822] <TB0>     INFO: RPC call hashes of host and DTB match: 398089610

[11:52:51.329] <TB0>     INFO: DUT info: 

[11:52:51.329] <TB0>     INFO: The DUT currently contains the following objects:

[11:52:51.329] <TB0>     INFO:  2 TBM Cores tbm09c (2 ON)

[11:52:51.329] <TB0>     INFO: 	TBM Core alpha (0): 7 registers set

[11:52:51.329] <TB0>     INFO: 	TBM Core beta  (1): 7 registers set

[11:52:51.329] <TB0>     INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs

[11:52:51.329] <TB0>     INFO: 	ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.

[11:52:51.329] <TB0>     INFO: 	ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.

[11:52:51.329] <TB0>     INFO: 	ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.

[11:52:51.329] <TB0>     INFO: 	ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.

[11:52:51.329] <TB0>     INFO: 	ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.

[11:52:51.329] <TB0>     INFO: 	ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.

[11:52:51.329] <TB0>     INFO: 	ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.

[11:52:51.329] <TB0>     INFO: 	ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.

[11:52:51.329] <TB0>     INFO: 	ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.

[11:52:51.329] <TB0>     INFO: 	ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.

[11:52:51.329] <TB0>     INFO: 	ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.

[11:52:51.329] <TB0>     INFO: 	ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.

[11:52:51.329] <TB0>     INFO: 	ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.

[11:52:51.329] <TB0>     INFO: 	ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.

[11:52:51.329] <TB0>     INFO: 	ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.

[11:52:51.329] <TB0>     INFO: 	ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.

[11:52:51.730] <TB0>     INFO: enter 'restricted' command line mode

[11:52:51.730] <TB0>     INFO: enter test to run

[11:52:51.730] <TB0>     INFO:   test: PixelAlive no parameter change

[11:52:51.730] <TB0>     INFO:   running: pixelalive

[11:52:51.735] <TB0>     INFO: ######################################################################

[11:52:51.735] <TB0>     INFO: PixTestAlive::doTest()

[11:52:51.735] <TB0>     INFO: ######################################################################

[11:52:51.736] <TB0>     INFO:    ----------------------------------------------------------------------

[11:52:51.736] <TB0>     INFO:    PixTestAlive::aliveTest() ntrig = 50, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)

[11:52:51.736] <TB0>     INFO:    ----------------------------------------------------------------------

[11:52:52.033] <TB0>     INFO: Expecting 208000 events.

[11:53:03.117] <TB0>     INFO: 208000 events read in total (10365ms).

[11:53:03.137] <TB0>     INFO: Test took 11400ms.

[11:53:03.148] <TB0>     INFO: Fetched DAQ statistics. Counters are being reset now.

[11:53:03.408] <TB0>     INFO: PixTestAlive::aliveTest() done

[11:53:03.408] <TB0>     INFO: number of dead pixels (per ROC):     0    0    0    0    1    0    0    1    0    0    0    0    0    0    0    2

[11:53:03.409] <TB0>     INFO:    ----------------------------------------------------------------------

[11:53:03.409] <TB0>     INFO:    PixTestAlive::maskTest() ntrig = 50, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)

[11:53:03.409] <TB0>     INFO:    ----------------------------------------------------------------------

[11:53:03.721] <TB0>     INFO: Expecting 208000 events.

[11:53:12.346] <TB0>     INFO: 208000 events read in total (7909ms).

[11:53:12.347] <TB0>     INFO: Test took 8936ms.

[11:53:12.347] <TB0>     INFO: Fetched DAQ statistics. Counters are being reset now.

[11:53:12.348] <TB0>     INFO: mask vs. old pixelAlive PixelAlive_C0_V0 ..  PixelAlive_C15_V0

[11:53:12.663] <TB0>     INFO: PixTestAlive::maskTest() done

[11:53:12.663] <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

[11:53:12.664] <TB0>     INFO:    ----------------------------------------------------------------------

[11:53:12.664] <TB0>     INFO:    PixTestAlive::addressDecodingTest() ntrig = 50, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)

[11:53:12.664] <TB0>     INFO:    ----------------------------------------------------------------------

[11:53:12.971] <TB0>     INFO: Expecting 208000 events.

[11:53:25.877] <TB0>     INFO: 208000 events read in total (12191ms).

[11:53:25.878] <TB0>     INFO: Test took 13212ms.

[11:53:25.886] <TB0>     INFO: Fetched DAQ statistics. Counters are being reset now.

[11:53:26.119] <TB0>     INFO: PixTestAlive::addressDecodingTest() done

[11:53:26.119] <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

[11:53:26.119] <TB0>     INFO: PixTestAlive::doTest() done, duration: 34 seconds

[11:53:26.158] <TB0>     INFO: enter test to run

[11:53:26.158] <TB0>     INFO:   test: delay setting parameters: ->2<-

[11:53:26.158] <TB0>     INFO: delay test by 2 seconds...

[11:53:28.158] <TB0>     INFO: enter test to run

[11:53:28.159] <TB0>     INFO:   test: HighRate no parameter change

[11:53:28.159] <TB0>     INFO:   running: highrate

[11:53:28.159] <TB0>     INFO: readGainPedestalParameters /usr/local/data/M2125_XrayQualification_2015-12-16_10h27m_1450258068//014_HREfficiency_100//phCalibrationFitErr35_C0.dat .. /usr/local/data/M2125_XrayQualification_2015-12-16_10h27m_1450258068//014_HREfficiency_100//phCalibrationFitErr35_C15.dat

[11:53:28.300] <TB0>     INFO:    ----------------------------------------------------------------------

[11:53:28.300] <TB0>     INFO:    PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200

[11:53:28.300] <TB0>     INFO:    ----------------------------------------------------------------------

[11:53:28.908] <TB0>     INFO: Expecting 208000 events.

[11:53:50.224] <TB0>     INFO: 208000 events read in total (20789ms).

[11:53:50.232] <TB0>     INFO: Test took 21928ms.

[11:53:50.745] <TB0>     INFO: Fetched DAQ statistics. Counters are being reset now.

[11:53:51.132] <TB0>     INFO: number of dead pixels (per ROC):     0    0    0    0    1    0    0    1    0    0    0    0    0    0    0    2

[11:53:51.132] <TB0>     INFO: number of red-efficiency pixels:   289  318  347  610  758 1057 1019 1356  821  849  959  608  506  296  278  248

[11:53:51.132] <TB0>     INFO: number of X-ray hits detected:    170695 195635 154741 262217 313812 377301 355506 422876 323375 326662 345256 267435 257824 133689 159458 149095

[11:53:51.132] <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

[11:53:51.132] <TB0>     INFO: number of Vcal hits detected:  207698 207641 207609 207328 207059 206693 206704 206183 207039 207014 206840 207296 207440 207678 207709 207649

[11:53:51.132] <TB0>     INFO: Vcal hit fiducial efficiency (%):  99.9 99.8 99.8 99.7 99.6 99.4 99.5 99.2 99.6 99.6 99.5 99.7 99.8 99.9 99.9 99.9

[11:53:51.132] <TB0>     INFO: Vcal hit overall efficiency (%):  99.9 99.8 99.8 99.7 99.5 99.4 99.4 99.1 99.5 99.5 99.4 99.7 99.7 99.8 99.9 99.8

[11:53:51.132] <TB0>     INFO: X-ray hit rate [MHz/cm2]:  50.0 57.3 45.4 76.9 92.0 110.6 104.2 123.9 94.8 95.7 101.2 78.4 75.6 39.2 46.7 43.7

[11:53:51.132] <TB0>     INFO: PixTestHighRate::doXPixelAlive() done

[11:53:51.163] <TB0>     INFO: PixTest::       pg_setup set to default.

[11:53:51.173] <TB0>     INFO: enter test to run

[11:53:51.173] <TB0>     INFO:   test:  no parameter change

[11:53:51.252] <TB0>    QUIET: Connection to board 83 closed.

[11:53:51.331] <TB0>     INFO: pXar: this is the end, my friend

commander_HREfficiency.log

[11:55:15.386] <TB0>     INFO: *** Welcome to pxar ***

[11:55:15.386] <TB0>     INFO: *** Today: 2015/12/16

[11:55:15.716] <TB0>     INFO: *** Version: b2d3

[11:55:15.716] <TB0>     INFO: readRocDacs: /usr/local/data/M2125_XrayQualification_2015-12-16_10h27m_1450258068//015_HREfficiency_125//dacParameters35_C0.dat .. /usr/local/data/M2125_XrayQualification_2015-12-16_10h27m_1450258068//015_HREfficiency_125//dacParameters35_C15.dat

[11:55:15.717] <TB0>     INFO: readTbmDacs: /usr/local/data/M2125_XrayQualification_2015-12-16_10h27m_1450258068//015_HREfficiency_125//tbmParameters_C0a.dat .. /usr/local/data/M2125_XrayQualification_2015-12-16_10h27m_1450258068//015_HREfficiency_125//tbmParameters_C0b.dat

[11:55:15.717] <TB0>     INFO: readMaskFile: /usr/local/data/M2125_XrayQualification_2015-12-16_10h27m_1450258068//015_HREfficiency_125//defaultMaskFile.dat

[11:55:15.717] <TB0>     INFO: readTrimFile: /usr/local/data/M2125_XrayQualification_2015-12-16_10h27m_1450258068//015_HREfficiency_125//trimParameters35_C0.dat .. /usr/local/data/M2125_XrayQualification_2015-12-16_10h27m_1450258068//015_HREfficiency_125//trimParameters35_C15.dat

[11:55:15.782] <TB0>     INFO:         clk: 4

[11:55:15.782] <TB0>     INFO:         ctr: 4

[11:55:15.782] <TB0>     INFO:         sda: 19

[11:55:15.782] <TB0>     INFO:         tin: 9

[11:55:15.782] <TB0>     INFO:         level: 15

[11:55:15.782] <TB0>     INFO:         triggerdelay: 0

[11:55:15.782] <TB0>    QUIET: Instanciating API for pxar prod-12

[11:55:15.782] <TB0>     INFO: Log level: INFO

[11:55:15.796] <TB0>     INFO: Found DTB DTB_WS6UZO

[11:55:15.806] <TB0>    QUIET: Connection to board DTB_WS6UZO opened.

[11:55:15.809] <TB0>     INFO: DTB startup information

--- DTB info------------------------------------------

Board id:    83

HW version:  DTB1.2

FW version:  4.2

SW version:  4.5

USB id:      DTB_WS6UZO

MAC address: 40D855118053

Hostname:    pixelDTB083

Comment:     

------------------------------------------------------

[11:55:15.811] <TB0>     INFO: RPC call hashes of host and DTB match: 398089610

[11:55:17.318] <TB0>     INFO: DUT info: 

[11:55:17.318] <TB0>     INFO: The DUT currently contains the following objects:

[11:55:17.318] <TB0>     INFO:  2 TBM Cores tbm09c (2 ON)

[11:55:17.318] <TB0>     INFO: 	TBM Core alpha (0): 7 registers set

[11:55:17.318] <TB0>     INFO: 	TBM Core beta  (1): 7 registers set

[11:55:17.318] <TB0>     INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs

[11:55:17.318] <TB0>     INFO: 	ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.

[11:55:17.318] <TB0>     INFO: 	ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.

[11:55:17.318] <TB0>     INFO: 	ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.

[11:55:17.318] <TB0>     INFO: 	ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.

[11:55:17.318] <TB0>     INFO: 	ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.

[11:55:17.318] <TB0>     INFO: 	ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.

[11:55:17.318] <TB0>     INFO: 	ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.

[11:55:17.318] <TB0>     INFO: 	ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.

[11:55:17.318] <TB0>     INFO: 	ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.

[11:55:17.318] <TB0>     INFO: 	ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.

[11:55:17.318] <TB0>     INFO: 	ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.

[11:55:17.318] <TB0>     INFO: 	ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.

[11:55:17.318] <TB0>     INFO: 	ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.

[11:55:17.318] <TB0>     INFO: 	ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.

[11:55:17.318] <TB0>     INFO: 	ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.

[11:55:17.318] <TB0>     INFO: 	ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.

[11:55:17.719] <TB0>     INFO: enter 'restricted' command line mode

[11:55:17.719] <TB0>     INFO: enter test to run

[11:55:17.719] <TB0>     INFO:   test: PixelAlive no parameter change

[11:55:17.719] <TB0>     INFO:   running: pixelalive

[11:55:17.723] <TB0>     INFO: ######################################################################

[11:55:17.723] <TB0>     INFO: PixTestAlive::doTest()

[11:55:17.724] <TB0>     INFO: ######################################################################

[11:55:17.725] <TB0>     INFO:    ----------------------------------------------------------------------

[11:55:17.725] <TB0>     INFO:    PixTestAlive::aliveTest() ntrig = 50, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)

[11:55:17.725] <TB0>     INFO:    ----------------------------------------------------------------------

[11:55:18.023] <TB0>     INFO: Expecting 208000 events.

[11:55:28.883] <TB0>     INFO: 208000 events read in total (10141ms).

[11:55:28.906] <TB0>     INFO: Test took 11179ms.

[11:55:28.916] <TB0>     INFO: Fetched DAQ statistics. Counters are being reset now.

[11:55:29.175] <TB0>     INFO: PixTestAlive::aliveTest() done

[11:55:29.175] <TB0>     INFO: number of dead pixels (per ROC):     0    0    0    0    1    0    0    1    0    0    0    0    0    0    0    2

[11:55:29.176] <TB0>     INFO:    ----------------------------------------------------------------------

[11:55:29.176] <TB0>     INFO:    PixTestAlive::maskTest() ntrig = 50, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)

[11:55:29.176] <TB0>     INFO:    ----------------------------------------------------------------------

[11:55:29.513] <TB0>     INFO: Expecting 208000 events.

[11:55:38.414] <TB0>     INFO: 208000 events read in total (8186ms).

[11:55:38.415] <TB0>     INFO: Test took 9237ms.

[11:55:38.415] <TB0>     INFO: Fetched DAQ statistics. Counters are being reset now.

[11:55:38.416] <TB0>     INFO: mask vs. old pixelAlive PixelAlive_C0_V0 ..  PixelAlive_C15_V0

[11:55:38.725] <TB0>     INFO: PixTestAlive::maskTest() done

[11:55:38.725] <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

[11:55:38.727] <TB0>     INFO:    ----------------------------------------------------------------------

[11:55:38.727] <TB0>     INFO:    PixTestAlive::addressDecodingTest() ntrig = 50, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)

[11:55:38.727] <TB0>     INFO:    ----------------------------------------------------------------------

[11:55:39.034] <TB0>     INFO: Expecting 208000 events.

[11:55:51.642] <TB0>     INFO: 208000 events read in total (11893ms).

[11:55:51.643] <TB0>     INFO: Test took 12914ms.

[11:55:51.651] <TB0>     INFO: Fetched DAQ statistics. Counters are being reset now.

[11:55:51.885] <TB0>     INFO: PixTestAlive::addressDecodingTest() done

[11:55:51.885] <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

[11:55:51.885] <TB0>     INFO: PixTestAlive::doTest() done, duration: 34 seconds

[11:55:51.922] <TB0>     INFO: enter test to run

[11:55:51.922] <TB0>     INFO:   test: delay setting parameters: ->2<-

[11:55:51.922] <TB0>     INFO: delay test by 2 seconds...

[11:55:53.922] <TB0>     INFO: enter test to run

[11:55:53.922] <TB0>     INFO:   test: HighRate no parameter change

[11:55:53.922] <TB0>     INFO:   running: highrate

[11:55:53.923] <TB0>     INFO: readGainPedestalParameters /usr/local/data/M2125_XrayQualification_2015-12-16_10h27m_1450258068//015_HREfficiency_125//phCalibrationFitErr35_C0.dat .. /usr/local/data/M2125_XrayQualification_2015-12-16_10h27m_1450258068//015_HREfficiency_125//phCalibrationFitErr35_C15.dat

[11:55:54.057] <TB0>     INFO:    ----------------------------------------------------------------------

[11:55:54.057] <TB0>     INFO:    PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200

[11:55:54.057] <TB0>     INFO:    ----------------------------------------------------------------------

[11:55:54.680] <TB0>     INFO: Expecting 208000 events.

[11:56:18.279] <TB0>     INFO: 208000 events read in total (23072ms).

[11:56:18.288] <TB0>     INFO: Test took 24227ms.

[11:56:18.867] <TB0>     INFO: Fetched DAQ statistics. Counters are being reset now.

[11:56:19.253] <TB0>     INFO: number of dead pixels (per ROC):     0    0    0    0    1    0    0    1    0    0    0    0    0    0    0    2

[11:56:19.253] <TB0>     INFO: number of red-efficiency pixels:   401  455  526  807 1217 1573 1468 2035 1195 1200 1400  879  733  409  396  343

[11:56:19.253] <TB0>     INFO: number of X-ray hits detected:    207449 238498 189155 317783 379824 454802 431392 510629 393052 397157 417485 324110 312929 164941 195166 181829

[11:56:19.253] <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

[11:56:19.253] <TB0>     INFO: number of Vcal hits detected:  207569 207489 207356 207036 206355 205667 205852 204865 206490 206389 206091 206889 207160 207530 207574 207536

[11:56:19.253] <TB0>     INFO: Vcal hit fiducial efficiency (%):  99.8 99.8 99.7 99.6 99.3 99.0 99.1 98.6 99.3 99.3 99.2 99.5 99.6 99.8 99.8 99.8

[11:56:19.253] <TB0>     INFO: Vcal hit overall efficiency (%):  99.8 99.8 99.7 99.5 99.2 98.9 99.0 98.5 99.3 99.2 99.1 99.5 99.6 99.8 99.8 99.8

[11:56:19.253] <TB0>     INFO: X-ray hit rate [MHz/cm2]:  60.8 69.9 55.4 93.1 111.3 133.3 126.4 149.7 115.2 116.4 122.4 95.0 91.7 48.3 57.2 53.3

[11:56:19.253] <TB0>     INFO: PixTestHighRate::doXPixelAlive() done

[11:56:19.277] <TB0>     INFO: PixTest::       pg_setup set to default.

[11:56:19.291] <TB0>     INFO: enter test to run

[11:56:19.291] <TB0>     INFO:   test:  no parameter change

[11:56:19.380] <TB0>    QUIET: Connection to board 83 closed.

[11:56:19.460] <TB0>     INFO: pXar: this is the end, my friend

commander_HREfficiency.log

[11:57:14.176] <TB0>     INFO: *** Welcome to pxar ***

[11:57:14.176] <TB0>     INFO: *** Today: 2015/12/16

[11:57:14.514] <TB0>     INFO: *** Version: b2d3

[11:57:14.514] <TB0>     INFO: readRocDacs: /usr/local/data/M2125_XrayQualification_2015-12-16_10h27m_1450258068//016_HREfficiency_150//dacParameters35_C0.dat .. /usr/local/data/M2125_XrayQualification_2015-12-16_10h27m_1450258068//016_HREfficiency_150//dacParameters35_C15.dat

[11:57:14.514] <TB0>     INFO: readTbmDacs: /usr/local/data/M2125_XrayQualification_2015-12-16_10h27m_1450258068//016_HREfficiency_150//tbmParameters_C0a.dat .. /usr/local/data/M2125_XrayQualification_2015-12-16_10h27m_1450258068//016_HREfficiency_150//tbmParameters_C0b.dat

[11:57:14.514] <TB0>     INFO: readMaskFile: /usr/local/data/M2125_XrayQualification_2015-12-16_10h27m_1450258068//016_HREfficiency_150//defaultMaskFile.dat

[11:57:14.514] <TB0>     INFO: readTrimFile: /usr/local/data/M2125_XrayQualification_2015-12-16_10h27m_1450258068//016_HREfficiency_150//trimParameters35_C0.dat .. /usr/local/data/M2125_XrayQualification_2015-12-16_10h27m_1450258068//016_HREfficiency_150//trimParameters35_C15.dat

[11:57:14.580] <TB0>     INFO:         clk: 4

[11:57:14.580] <TB0>     INFO:         ctr: 4

[11:57:14.580] <TB0>     INFO:         sda: 19

[11:57:14.580] <TB0>     INFO:         tin: 9

[11:57:14.580] <TB0>     INFO:         level: 15

[11:57:14.580] <TB0>     INFO:         triggerdelay: 0

[11:57:14.580] <TB0>    QUIET: Instanciating API for pxar prod-12

[11:57:14.580] <TB0>     INFO: Log level: INFO

[11:57:14.593] <TB0>     INFO: Found DTB DTB_WS6UZO

[11:57:14.603] <TB0>    QUIET: Connection to board DTB_WS6UZO opened.

[11:57:14.606] <TB0>     INFO: DTB startup information

--- DTB info------------------------------------------

Board id:    83

HW version:  DTB1.2

FW version:  4.2

SW version:  4.5

USB id:      DTB_WS6UZO

MAC address: 40D855118053

Hostname:    pixelDTB083

Comment:     

------------------------------------------------------

[11:57:14.608] <TB0>     INFO: RPC call hashes of host and DTB match: 398089610

[11:57:16.117] <TB0>     INFO: DUT info: 

[11:57:16.117] <TB0>     INFO: The DUT currently contains the following objects:

[11:57:16.117] <TB0>     INFO:  2 TBM Cores tbm09c (2 ON)

[11:57:16.117] <TB0>     INFO: 	TBM Core alpha (0): 7 registers set

[11:57:16.117] <TB0>     INFO: 	TBM Core beta  (1): 7 registers set

[11:57:16.117] <TB0>     INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs

[11:57:16.117] <TB0>     INFO: 	ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.

[11:57:16.117] <TB0>     INFO: 	ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.

[11:57:16.117] <TB0>     INFO: 	ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.

[11:57:16.118] <TB0>     INFO: 	ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.

[11:57:16.118] <TB0>     INFO: 	ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.

[11:57:16.118] <TB0>     INFO: 	ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.

[11:57:16.118] <TB0>     INFO: 	ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.

[11:57:16.118] <TB0>     INFO: 	ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.

[11:57:16.118] <TB0>     INFO: 	ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.

[11:57:16.118] <TB0>     INFO: 	ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.

[11:57:16.118] <TB0>     INFO: 	ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.

[11:57:16.118] <TB0>     INFO: 	ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.

[11:57:16.118] <TB0>     INFO: 	ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.

[11:57:16.118] <TB0>     INFO: 	ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.

[11:57:16.118] <TB0>     INFO: 	ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.

[11:57:16.118] <TB0>     INFO: 	ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.

[11:57:16.518] <TB0>     INFO: enter 'restricted' command line mode

[11:57:16.518] <TB0>     INFO: enter test to run

[11:57:16.518] <TB0>     INFO:   test: PixelAlive no parameter change

[11:57:16.518] <TB0>     INFO:   running: pixelalive

[11:57:16.523] <TB0>     INFO: ######################################################################

[11:57:16.523] <TB0>     INFO: PixTestAlive::doTest()

[11:57:16.523] <TB0>     INFO: ######################################################################

[11:57:16.524] <TB0>     INFO:    ----------------------------------------------------------------------

[11:57:16.524] <TB0>     INFO:    PixTestAlive::aliveTest() ntrig = 50, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)

[11:57:16.524] <TB0>     INFO:    ----------------------------------------------------------------------

[11:57:16.823] <TB0>     INFO: Expecting 208000 events.

[11:57:27.869] <TB0>     INFO: 208000 events read in total (10328ms).

[11:57:27.871] <TB0>     INFO: Test took 11345ms.

[11:57:27.877] <TB0>     INFO: Fetched DAQ statistics. Counters are being reset now.

[11:57:28.155] <TB0>     INFO: PixTestAlive::aliveTest() done

[11:57:28.155] <TB0>     INFO: number of dead pixels (per ROC):     0    0    0    0    1    0    0    1    0    0    0    0    0    0    0    2

[11:57:28.157] <TB0>     INFO:    ----------------------------------------------------------------------

[11:57:28.157] <TB0>     INFO:    PixTestAlive::maskTest() ntrig = 50, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)

[11:57:28.157] <TB0>     INFO:    ----------------------------------------------------------------------

[11:57:28.509] <TB0>     INFO: Expecting 208000 events.

[11:57:37.179] <TB0>     INFO: 208000 events read in total (7954ms).

[11:57:37.180] <TB0>     INFO: Test took 9021ms.

[11:57:37.180] <TB0>     INFO: Fetched DAQ statistics. Counters are being reset now.

[11:57:37.181] <TB0>     INFO: mask vs. old pixelAlive PixelAlive_C0_V0 ..  PixelAlive_C15_V0

[11:57:37.491] <TB0>     INFO: PixTestAlive::maskTest() done

[11:57:37.491] <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

[11:57:37.492] <TB0>     INFO:    ----------------------------------------------------------------------

[11:57:37.492] <TB0>     INFO:    PixTestAlive::addressDecodingTest() ntrig = 50, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)

[11:57:37.492] <TB0>     INFO:    ----------------------------------------------------------------------

[11:57:37.798] <TB0>     INFO: Expecting 208000 events.

[11:57:50.790] <TB0>     INFO: 208000 events read in total (12276ms).

[11:57:50.790] <TB0>     INFO: Test took 13296ms.

[11:57:50.797] <TB0>     INFO: Fetched DAQ statistics. Counters are being reset now.

[11:57:51.031] <TB0>     INFO: PixTestAlive::addressDecodingTest() done

[11:57:51.031] <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

[11:57:51.031] <TB0>     INFO: PixTestAlive::doTest() done, duration: 34 seconds

[11:57:51.065] <TB0>     INFO: enter test to run

[11:57:51.065] <TB0>     INFO:   test: delay setting parameters: ->2<-

[11:57:51.065] <TB0>     INFO: delay test by 2 seconds...

[11:57:53.065] <TB0>     INFO: enter test to run

[11:57:53.065] <TB0>     INFO:   test: HighRate no parameter change

[11:57:53.065] <TB0>     INFO:   running: highrate

[11:57:53.066] <TB0>     INFO: readGainPedestalParameters /usr/local/data/M2125_XrayQualification_2015-12-16_10h27m_1450258068//016_HREfficiency_150//phCalibrationFitErr35_C0.dat .. /usr/local/data/M2125_XrayQualification_2015-12-16_10h27m_1450258068//016_HREfficiency_150//phCalibrationFitErr35_C15.dat

[11:57:53.209] <TB0>     INFO:    ----------------------------------------------------------------------

[11:57:53.209] <TB0>     INFO:    PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200

[11:57:53.209] <TB0>     INFO:    ----------------------------------------------------------------------

[11:57:53.816] <TB0>     INFO: Expecting 208000 events.

[11:58:19.983] <TB0>     INFO: 208000 events read in total (25640ms).

[11:58:19.994] <TB0>     INFO: Test took 26782ms.

[11:58:20.675] <TB0>     INFO: Fetched DAQ statistics. Counters are being reset now.

[11:58:21.093] <TB0>     INFO: number of dead pixels (per ROC):     0    0    0    0    1    0    0    1    0    0    0    0    0    0    0    2

[11:58:21.093] <TB0>     INFO: number of red-efficiency pixels:   585  643  662 1144 1517 2197 2018 2648 1716 1767 1882 1265 1125  550  527  501

[11:58:21.093] <TB0>     INFO: number of X-ray hits detected:    247563 285030 228098 378678 453072 541298 512400 606171 466470 471314 495539 386943 371565 196841 233220 216524

[11:58:21.093] <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

[11:58:21.093] <TB0>     INFO: number of Vcal hits detected:  207336 207243 207045 206483 205611 204191 204451 203032 205597 205361 205076 206214 206623 207267 207424 207366

[11:58:21.093] <TB0>     INFO: Vcal hit fiducial efficiency (%):  99.7 99.7 99.6 99.4 99.1 98.4 98.5 97.9 98.9 98.9 98.8 99.3 99.4 99.7 99.7 99.8

[11:58:21.093] <TB0>     INFO: Vcal hit overall efficiency (%):  99.7 99.6 99.5 99.3 98.9 98.2 98.3 97.6 98.8 98.7 98.6 99.1 99.3 99.6 99.7 99.7

[11:58:21.093] <TB0>     INFO: X-ray hit rate [MHz/cm2]:  72.6 83.5 66.9 111.0 132.8 158.7 150.2 177.7 136.7 138.1 145.2 113.4 108.9 57.7 68.4 63.5

[11:58:21.093] <TB0>     INFO: PixTestHighRate::doXPixelAlive() done

[11:58:21.129] <TB0>     INFO: PixTest::       pg_setup set to default.

[11:58:21.148] <TB0>     INFO: enter test to run

[11:58:21.148] <TB0>     INFO:   test:  no parameter change

[11:58:21.226] <TB0>    QUIET: Connection to board 83 closed.

[11:58:21.306] <TB0>     INFO: pXar: this is the end, my friend

commander_HREfficiency.log

[11:59:54.634] <TB0>     INFO: *** Welcome to pxar ***

[11:59:54.634] <TB0>     INFO: *** Today: 2015/12/16

[11:59:54.970] <TB0>     INFO: *** Version: b2d3

[11:59:54.970] <TB0>     INFO: readRocDacs: /usr/local/data/M2125_XrayQualification_2015-12-16_10h27m_1450258068//017_HREfficiency_200//dacParameters35_C0.dat .. /usr/local/data/M2125_XrayQualification_2015-12-16_10h27m_1450258068//017_HREfficiency_200//dacParameters35_C15.dat

[11:59:54.970] <TB0>     INFO: readTbmDacs: /usr/local/data/M2125_XrayQualification_2015-12-16_10h27m_1450258068//017_HREfficiency_200//tbmParameters_C0a.dat .. /usr/local/data/M2125_XrayQualification_2015-12-16_10h27m_1450258068//017_HREfficiency_200//tbmParameters_C0b.dat

[11:59:54.970] <TB0>     INFO: readMaskFile: /usr/local/data/M2125_XrayQualification_2015-12-16_10h27m_1450258068//017_HREfficiency_200//defaultMaskFile.dat

[11:59:54.970] <TB0>     INFO: readTrimFile: /usr/local/data/M2125_XrayQualification_2015-12-16_10h27m_1450258068//017_HREfficiency_200//trimParameters35_C0.dat .. /usr/local/data/M2125_XrayQualification_2015-12-16_10h27m_1450258068//017_HREfficiency_200//trimParameters35_C15.dat

[11:59:55.036] <TB0>     INFO:         clk: 4

[11:59:55.036] <TB0>     INFO:         ctr: 4

[11:59:55.036] <TB0>     INFO:         sda: 19

[11:59:55.036] <TB0>     INFO:         tin: 9

[11:59:55.036] <TB0>     INFO:         level: 15

[11:59:55.036] <TB0>     INFO:         triggerdelay: 0

[11:59:55.036] <TB0>    QUIET: Instanciating API for pxar prod-12

[11:59:55.036] <TB0>     INFO: Log level: INFO

[11:59:55.050] <TB0>     INFO: Found DTB DTB_WS6UZO

[11:59:55.060] <TB0>    QUIET: Connection to board DTB_WS6UZO opened.

[11:59:55.063] <TB0>     INFO: DTB startup information

--- DTB info------------------------------------------

Board id:    83

HW version:  DTB1.2

FW version:  4.2

SW version:  4.5

USB id:      DTB_WS6UZO

MAC address: 40D855118053

Hostname:    pixelDTB083

Comment:     

------------------------------------------------------

[11:59:55.065] <TB0>     INFO: RPC call hashes of host and DTB match: 398089610

[11:59:56.573] <TB0>     INFO: DUT info: 

[11:59:56.573] <TB0>     INFO: The DUT currently contains the following objects:

[11:59:56.573] <TB0>     INFO:  2 TBM Cores tbm09c (2 ON)

[11:59:56.573] <TB0>     INFO: 	TBM Core alpha (0): 7 registers set

[11:59:56.573] <TB0>     INFO: 	TBM Core beta  (1): 7 registers set

[11:59:56.574] <TB0>     INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs

[11:59:56.574] <TB0>     INFO: 	ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.

[11:59:56.574] <TB0>     INFO: 	ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.

[11:59:56.574] <TB0>     INFO: 	ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.

[11:59:56.574] <TB0>     INFO: 	ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.

[11:59:56.574] <TB0>     INFO: 	ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.

[11:59:56.574] <TB0>     INFO: 	ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.

[11:59:56.574] <TB0>     INFO: 	ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.

[11:59:56.574] <TB0>     INFO: 	ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.

[11:59:56.574] <TB0>     INFO: 	ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.

[11:59:56.574] <TB0>     INFO: 	ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.

[11:59:56.574] <TB0>     INFO: 	ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.

[11:59:56.574] <TB0>     INFO: 	ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.

[11:59:56.574] <TB0>     INFO: 	ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.

[11:59:56.574] <TB0>     INFO: 	ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.

[11:59:56.574] <TB0>     INFO: 	ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.

[11:59:56.574] <TB0>     INFO: 	ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.

[11:59:56.975] <TB0>     INFO: enter 'restricted' command line mode

[11:59:56.975] <TB0>     INFO: enter test to run

[11:59:56.975] <TB0>     INFO:   test: PixelAlive no parameter change

[11:59:56.975] <TB0>     INFO:   running: pixelalive

[11:59:56.979] <TB0>     INFO: ######################################################################

[11:59:56.979] <TB0>     INFO: PixTestAlive::doTest()

[11:59:56.979] <TB0>     INFO: ######################################################################

[11:59:56.981] <TB0>     INFO:    ----------------------------------------------------------------------

[11:59:56.981] <TB0>     INFO:    PixTestAlive::aliveTest() ntrig = 50, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)

[11:59:56.981] <TB0>     INFO:    ----------------------------------------------------------------------

[11:59:57.278] <TB0>     INFO: Expecting 208000 events.

[12:00:08.010] <TB0>     INFO: 208000 events read in total (10014ms).

[12:00:08.029] <TB0>     INFO: Test took 11047ms.

[12:00:08.037] <TB0>     INFO: Fetched DAQ statistics. Counters are being reset now.

[12:00:08.297] <TB0>     INFO: PixTestAlive::aliveTest() done

[12:00:08.297] <TB0>     INFO: number of dead pixels (per ROC):     0    0    0    0    1    0    0    1    0    0    0    0    0    0    0    2

[12:00:08.299] <TB0>     INFO:    ----------------------------------------------------------------------

[12:00:08.299] <TB0>     INFO:    PixTestAlive::maskTest() ntrig = 50, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)

[12:00:08.299] <TB0>     INFO:    ----------------------------------------------------------------------

[12:00:08.610] <TB0>     INFO: Expecting 208000 events.

[12:00:17.193] <TB0>     INFO: 208000 events read in total (7867ms).

[12:00:17.193] <TB0>     INFO: Test took 8892ms.

[12:00:17.193] <TB0>     INFO: Fetched DAQ statistics. Counters are being reset now.

[12:00:17.194] <TB0>     INFO: mask vs. old pixelAlive PixelAlive_C0_V0 ..  PixelAlive_C15_V0

[12:00:17.503] <TB0>     INFO: PixTestAlive::maskTest() done

[12:00:17.503] <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

[12:00:17.505] <TB0>     INFO:    ----------------------------------------------------------------------

[12:00:17.505] <TB0>     INFO:    PixTestAlive::addressDecodingTest() ntrig = 50, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)

[12:00:17.505] <TB0>     INFO:    ----------------------------------------------------------------------

[12:00:17.809] <TB0>     INFO: Expecting 208000 events.

[12:00:30.866] <TB0>     INFO: 208000 events read in total (12341ms).

[12:00:30.867] <TB0>     INFO: Test took 13360ms.

[12:00:30.875] <TB0>     INFO: Fetched DAQ statistics. Counters are being reset now.

[12:00:31.100] <TB0>     INFO: PixTestAlive::addressDecodingTest() done

[12:00:31.100] <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

[12:00:31.100] <TB0>     INFO: PixTestAlive::doTest() done, duration: 34 seconds

[12:00:31.140] <TB0>     INFO: enter test to run

[12:00:31.140] <TB0>     INFO:   test: delay setting parameters: ->2<-

[12:00:31.140] <TB0>     INFO: delay test by 2 seconds...

[12:00:33.140] <TB0>     INFO: enter test to run

[12:00:33.140] <TB0>     INFO:   test: HighRate no parameter change

[12:00:33.140] <TB0>     INFO:   running: highrate

[12:00:33.140] <TB0>     INFO: readGainPedestalParameters /usr/local/data/M2125_XrayQualification_2015-12-16_10h27m_1450258068//017_HREfficiency_200//phCalibrationFitErr35_C0.dat .. /usr/local/data/M2125_XrayQualification_2015-12-16_10h27m_1450258068//017_HREfficiency_200//phCalibrationFitErr35_C15.dat

[12:00:33.321] <TB0>     INFO:    ----------------------------------------------------------------------

[12:00:33.321] <TB0>     INFO:    PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200

[12:00:33.321] <TB0>     INFO:    ----------------------------------------------------------------------

[12:00:33.948] <TB0>     INFO: Expecting 208000 events.

[12:00:55.273] <TB0>    ERROR: <datapipe.cc/CheckEventValidity:L491> Channel 3 Number of ROCs (1) != Token Chain Length (4)

[12:00:55.273] <TB0>    ERROR: <datapipe.cc/Read:L164> Dumping the flawed event +- 3 events:

[12:00:55.273] <TB0>    ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a237 8000 4188 693 24a2 4189 2c1 2280 2c8 2249 4da 2240 693 24a8 4189 141 2645 20c 204a 693 248c 860 2264 4189 45 264f 48 2a4e 4d2 246d 693 248e 855 262e 855 2a40 e002 c000 

[12:00:55.273] <TB0>    ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a231 80b1 4389 65c 2842 693 24af 4388 283 246c 4c2 2262 512 2a48 693 24af 4389 11 2040 0 2441 420 2063 44c 2466 693 248d 4389 94 266c 693 248f e002 c000 

[12:00:55.273] <TB0>    ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a232 80c0 4388 28a 2842 2c4 2866 2c2 2246 604 2060 693 24a6 4389 11 242f 34c 246c 50c 2a64 55a 2682 693 24a6 4388 693 248f 4388 693 248d e002 c000 

[12:00:55.273] <TB0>    ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a233 8000 4388 2d8 2042 419 246d 693 24c5 4388 19 204f 213 286f 693 24ad 804 262d 4388 20 2844 3 2480 18 224c 549 2062 693 248f 815 2a43 4388 20 204c d 2046 114 2448 693 2481 688 2a2a 84c 2249 e002 c000 

[12:00:55.273] <TB0>    ERROR: <datapipe.cc/Read:L166> ====== 0 ====== b234 9040 7ff 2e4b f0de c0b9 

[12:00:55.273] <TB0>    ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a235 80b1 4188 405 2060 542 244d 550 2260 601 2445 693 24aa 709 2442 715 2a6c 4188 c2 244f 4cb 2021 4c1 244f 514 202d 542 2848 693 24aa 761 22a8 4188 c4 244f 144 202f 29b 244f 288 2061 693 2488 4188 35a 2864 359 244f 353 2086 693 248b 68c 2624 815 2847 845 2041 e002 c000 

[12:00:55.273] <TB0>    ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a236 80c0 4188 693 24a2 6c9 206f 6c4 246c 75a 2847 4188 20a 226e 28a 2a48 2d0 2066 2c8 246d 2c4 226b 344 2a4f 693 24a2 762 2882 4188 693 24a0 800 2a2f 4189 25b 244e 55d 264c 693 248f e002 c000 

[12:00:55.273] <TB0>    ERROR: <datapipe.cc/Read:L164> Dumping the flawed event +- 3 events:

[12:00:55.273] <TB0>    ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a337 8000 4188 693 24ac 684 224e 70d 2422 4188 693 2486 4188 693 24a5 4188 693 24a5 fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff e002 c000 

[12:00:55.273] <TB0>    ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a331 80b1 4389 41 2a68 693 2487 4389 693 2489 4389 4b 206f 693 248e 4389 112 2240 112 262f 15b 226b 693 24af 690 284a 6cc 2445 fff 2fff fff 2fff e002 c000 

[12:00:55.273] <TB0>    ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a332 80c0 4389 259 244f 4d0 2a45 54a 2840 693 24ac 81b 2047 4389 152 2662 693 2489 4389 11d 2a4f 693 248f 4389 693 24ad 81b 264d fff 2fff e002 c000 

[12:00:55.273] <TB0>    ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a333 8000 4388 54c 2424 693 24a5 740 260a 4388 14 2040 153 2669 51c 2868 693 248d 4389 693 24a6 4389 b 266a 693 24c5 fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff e002 c000 

[12:00:55.273] <TB0>    ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a334 8040 7ff 25df 5e1 350c 1fbd 418b aa7 30c7 de7 2efe 1800 2eb9 aa6 201a af4 2568 86e 2f56 6 2e93 cb7 210b 8b7 200b da4 2d2b 4189 2dd1 493 25a4 d2b e002 c000 

[12:00:55.273] <TB0>    ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a335 80b1 4188 544 204e 693 24af 4188 693 248c 4188 109 2a68 693 24ad 860 224c 4188 693 24a9 fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff e002 c000 

[12:00:55.273] <TB0>    ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a336 80c0 4188 693 24a0 4188 ca 2242 554 284c 693 24a3 4188 693 24a8 4188 605 2a42 693 24a2 819 2442 fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff e002 c000 

[12:00:55.273] <TB0>  WARNING: Channel 2 ROC 3: Readback start marker after 15 readouts!

[12:00:55.273] <TB0>  WARNING: Channel 2 ROC 1: Readback start marker after 15 readouts!

[12:00:55.273] <TB0>  WARNING: Channel 2 ROC 2: Readback start marker after 15 readouts!

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

[12:00:55.274] <TB0>    ERROR: <datapipe.cc/Read:L164> Dumping the flawed event +- 3 events:

[12:00:55.274] <TB0>    ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a243 8000 4188 28d 204a 28a 242e 2e2 2644 4d2 284b 693 24af 4188 693 248f 754 2045 4188 214 2a6f 248 2647 2c8 204f 2c8 226b 2d5 226a 693 248b 721 264d 4188 5b 264f 449 2a43 693 248c 804 2846 812 2a6e e002 c000 

[12:00:55.274] <TB0>    ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a23d 80b1 4189 29a 2444 693 24af 4188 8 242f 15a 284c 204 2666 218 2668 693 248e 4188 d 264d 30b 204f 601 2a60 693 2481 70d 2245 418a 2c2 244c 491 2a45 693 248c fff 2fff e002 c000 

[12:00:55.274] <TB0>    ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a23e 80c0 4188 25d 222c 292 2a42 693 24ae 80b 2462 80b 204f 418a 120 2668 359 264a 4a0 266f 693 248c 418a 2cb 204f 2ca 204e 642 2445 693 2489 68a 220f 4189 693 2484 84d 262c e002 c000 

[12:00:55.274] <TB0>    ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a23f 8000 4188 a2 2248 c8 2266 2c9 244a 354 220e 693 2488 721 2065 811 2249 4189 693 248e 4189 245 2a4c 693 248f 852 226a 855 2663 4188 515 2a4a 693 246a e002 c000 

[12:00:55.274] <TB0>    ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a240 8040 418a 143 2448 484 246c 693 248d 6c1 2062 84d 2a48 4188 693 2489 713 2642 859 264f 4188 25d 2848 50d 2a69 693 246d 683 2228 6d3 204f 4189 4 2849 3 284c 353 2688 693 2481 e002 c000 

[12:00:55.274] <TB0>    ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a241 80b1 4189 28c 260e 561 2262 693 24ac 81a 262f 4188 58 264c 14a 224f 658 2049 693 24a4 4189 d2 224e 28b 2a68 340 2445 693 248f 4189 48 2a2d 2cb 244d 319 224a 693 2481 e002 c000 

[12:00:55.274] <TB0>    ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a242 80c0 4188 440 244c 4da 2460 693 24a8 4189 35a 224e 559 2264 693 24a4 4188 558 2842 65d 284f 649 2a62 693 2484 820 266a 4188 402 264f 40c 224d 693 248c 81a 204f e002 c000 

[12:00:57.855] <TB0>    ERROR: <datapipe.cc/CheckEventID:L463> Channel 2 Event ID mismatch:  local ID (5) !=  TBM ID (11)

[12:00:57.855] <TB0>    ERROR: <datapipe.cc/CheckEventValidity:L491> Channel 2 Number of ROCs (2) != Token Chain Length (4)

[12:00:57.855] <TB0>    ERROR: <datapipe.cc/CheckEventID:L463> Channel 3 Event ID mismatch:  local ID (5) !=  TBM ID (52)

[12:00:57.855] <TB0>    ERROR: <datapipe.cc/CheckEventValidity:L491> Channel 3 Number of ROCs (2) != Token Chain Length (4)

[12:00:57.855] <TB0>    ERROR: <datapipe.cc/CheckEventID:L463> Channel 2 Event ID mismatch:  local ID (12) !=  TBM ID (6)

[12:00:57.855] <TB0>    ERROR: <datapipe.cc/CheckEventID:L463> Channel 3 Event ID mismatch:  local ID (53) !=  TBM ID (6)

[12:00:57.855] <TB0>    ERROR: <datapipe.cc/Read:L164> Dumping the flawed event +- 3 events:

[12:00:57.855] <TB0>    ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a209 80b1 4389 9a 2a28 120 2266 25d 2842 420 246e 415 2046 40c 2048 4c9 2462 645 2440 758 288d 753 2081 4388 294 2a44 54d 202e 558 2248 758 28c9 758 2483 4388 101 2864 153 226c 758 2886 752 2663 4388 19 204d 1 2864 119 222c 60b 2069 e002 c000 

[12:00:57.856] <TB0>    ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a203 8000 4188 2c9 2a45 758 288c 4188 4b 2265 283 2469 758 288e 4188 151 2664 151 2a61 55d 242f 6da 2862 758 286f 4188 114 2842 141 204f 509 2a6f 562 244d 758 2863 e002 c000 

[12:00:57.856] <TB0>    ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a204 8040 4188 254 2464 2e0 2467 4d1 2829 758 288a 4188 54d 2649 758 28a5 4188 10b 226b 658 284a 758 2869 4188 204 2043 758 2888 85c 2240 e002 c000 

[12:00:57.856] <TB0>    ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a20b 8042 ab8 2758 88e 4388 15a 2049 758 28ab 4388 45 2465 8d 2880 404 244e 654 2064 64b 286e 758 286d 4388 92 2081 758 2865 e002 c000 

[12:00:57.856] <TB0>    ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a206 80c0 4388 2 2024 4c 2642 650 240e 758 288a 4388 698 204c 758 28a2 4388 154 2a63 411 284f 522 2264 758 288c 842 284a 4389 14c 2445 294 2448 758 2880 e002 c000 

[12:00:57.856] <TB0>    ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a207 8000 4388 145 2468 21d 2868 2d1 222a 758 2886 4389 c8 284c dd 224f 24a 206e 29b 268f 758 28ad 74b 2246 850 224a 854 2a4e 4389 648 204a 6c3 2a4e 758 288f 4389 13 2645 44b 2a6e 758 2883 841 2a48 e002 c000 

[12:00:57.856] <TB0>    ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a208 8040 4388 34c 262a 612 2068 613 2686 758 2882 4389 c1 2a64 343 2a65 758 28a2 4389 49a 244f 489 244e 480 2460 603 244d 605 2662 758 2867 4388 54b 2449 758 288c e002 c000 

[12:00:57.856] <TB0>    ERROR: <datapipe.cc/Read:L164> Dumping the flawed event +- 3 events:

[12:00:57.856] <TB0>    ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a309 80b1 4389 55a 2848 6c3 284c 758 286f 4389 98 2449 758 2884 854 2449 4389 142 2842 65c 2a2c 758 2885 4389 2e2 2248 758 288d fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff e002 c000 

[12:00:57.856] <TB0>    ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a303 8000 4188 18 2468 651 2863 758 2869 4188 59 2848 4b 2061 4a 2849 148 2445 144 284f 75c 204a 758 288e 4189 252 2882 758 2882 4189 758 28ac fff 2fff fff 2fff e002 c000 

[12:00:57.856] <TB0>    ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a304 8040 4188 758 2869 4189 8c 242f dd 2480 758 288d 4188 758 2885 4189 758 288f fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff e002 c000 

[12:00:57.856] <TB0>    ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a334 8014 4ac 22a0 c21 23ac 432 4388 4388 758 288f 4388 11d 2440 208 244f 2e2 262e 758 28c6 fff 2fff fff 2fff e002 c000 

[12:00:57.856] <TB0>    ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a306 80c0 4388 11 2848 298 286f 419 2a6a 4ca 202f 699 2461 758 2889 4388 758 286d 4388 24c 2446 758 286d 4388 c3 2462 758 288f fff 2fff fff 2fff fff 2fff e002 c000 

[12:00:57.856] <TB0>    ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a307 8000 4388 dc 2048 40b 228e 758 286e 4388 758 286f 750 2842 4388 758 2885 840 246f 4388 41b 2062 758 2888 741 246b fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff e002 c000 

[12:00:57.856] <TB0>    ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a308 8040 4388 81 2060 549 202f 758 2866 4388 758 2888 4388 11a 2242 758 286f 4388 509 2442 758 28a2 fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff e002 c000 

[12:01:05.688] <TB0>     INFO: 208000 events read in total (31213ms).

[12:01:05.705] <TB0>     INFO: Test took 32380ms.

[12:01:06.587] <TB0>     INFO: Fetched DAQ statistics. Counters are being reset now.

[12:01:07.074] <TB0>     INFO: number of dead pixels (per ROC):     0    0    0    0    1    0    0    0    0    0    0    0    0    0    0    2

[12:01:07.074] <TB0>     INFO: number of red-efficiency pixels:  1085 1211 1149 2026 2610 3377 3121 3832 2914 2849 3006 2115 1912  918 1051  822

[12:01:07.074] <TB0>     INFO: number of X-ray hits detected:    335510 388761 310117 511268 611402 725271 687934 818097 628715 631573 666161 522257 504807 269502 318126 296408

[12:01:07.074] <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

[12:01:07.074] <TB0>     INFO: number of Vcal hits detected:  206650 206387 205806 204522 202159 198721 199712 195350 202150 201610 201004 204046 204966 206372 206747 206947

[12:01:07.074] <TB0>     INFO: Vcal hit fiducial efficiency (%):  99.4 99.3 99.1 98.6 97.6 96.1 96.6 94.4 97.4 97.3 97.1 98.4 98.7 99.4 99.4 99.6

[12:01:07.074] <TB0>     INFO: Vcal hit overall efficiency (%):  99.4 99.2 98.9 98.3 97.2 95.5 96.0 93.9 97.2 96.9 96.6 98.1 98.5 99.2 99.4 99.5

[12:01:07.074] <TB0>     INFO: X-ray hit rate [MHz/cm2]:  98.3 113.9 90.9 149.9 179.2 212.6 201.6 239.8 184.3 185.1 195.3 153.1 148.0 79.0 93.2 86.9

[12:01:07.074] <TB0>     INFO: PixTestHighRate::doXPixelAlive() done

[12:01:07.103] <TB0>     INFO: PixTest::       pg_setup set to default.

[12:01:07.116] <TB0>     INFO: enter test to run

[12:01:07.116] <TB0>     INFO:   test:  no parameter change

[12:01:07.187] <TB0>    QUIET: Connection to board 83 closed.

[12:01:07.267] <TB0>     INFO: pXar: this is the end, my friend

commander_HREfficiency.log

[12:01:35.786] <TB0>     INFO: *** Welcome to pxar ***

[12:01:35.786] <TB0>     INFO: *** Today: 2015/12/16

[12:01:36.125] <TB0>     INFO: *** Version: b2d3

[12:01:36.125] <TB0>     INFO: readRocDacs: /usr/local/data/M2125_XrayQualification_2015-12-16_10h27m_1450258068//018_HREfficiency_250//dacParameters35_C0.dat .. /usr/local/data/M2125_XrayQualification_2015-12-16_10h27m_1450258068//018_HREfficiency_250//dacParameters35_C15.dat

[12:01:36.126] <TB0>     INFO: readTbmDacs: /usr/local/data/M2125_XrayQualification_2015-12-16_10h27m_1450258068//018_HREfficiency_250//tbmParameters_C0a.dat .. /usr/local/data/M2125_XrayQualification_2015-12-16_10h27m_1450258068//018_HREfficiency_250//tbmParameters_C0b.dat

[12:01:36.126] <TB0>     INFO: readMaskFile: /usr/local/data/M2125_XrayQualification_2015-12-16_10h27m_1450258068//018_HREfficiency_250//defaultMaskFile.dat

[12:01:36.126] <TB0>     INFO: readTrimFile: /usr/local/data/M2125_XrayQualification_2015-12-16_10h27m_1450258068//018_HREfficiency_250//trimParameters35_C0.dat .. /usr/local/data/M2125_XrayQualification_2015-12-16_10h27m_1450258068//018_HREfficiency_250//trimParameters35_C15.dat

[12:01:36.192] <TB0>     INFO:         clk: 4

[12:01:36.192] <TB0>     INFO:         ctr: 4

[12:01:36.192] <TB0>     INFO:         sda: 19

[12:01:36.192] <TB0>     INFO:         tin: 9

[12:01:36.192] <TB0>     INFO:         level: 15

[12:01:36.192] <TB0>     INFO:         triggerdelay: 0

[12:01:36.192] <TB0>    QUIET: Instanciating API for pxar prod-12

[12:01:36.192] <TB0>     INFO: Log level: INFO

[12:01:36.205] <TB0>     INFO: Found DTB DTB_WS6UZO

[12:01:36.215] <TB0>    QUIET: Connection to board DTB_WS6UZO opened.

[12:01:36.218] <TB0>     INFO: DTB startup information

--- DTB info------------------------------------------

Board id:    83

HW version:  DTB1.2

FW version:  4.2

SW version:  4.5

USB id:      DTB_WS6UZO

MAC address: 40D855118053

Hostname:    pixelDTB083

Comment:     

------------------------------------------------------

[12:01:36.220] <TB0>     INFO: RPC call hashes of host and DTB match: 398089610

[12:01:37.729] <TB0>     INFO: DUT info: 

[12:01:37.729] <TB0>     INFO: The DUT currently contains the following objects:

[12:01:37.729] <TB0>     INFO:  2 TBM Cores tbm09c (2 ON)

[12:01:37.729] <TB0>     INFO: 	TBM Core alpha (0): 7 registers set

[12:01:37.729] <TB0>     INFO: 	TBM Core beta  (1): 7 registers set

[12:01:37.729] <TB0>     INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs

[12:01:37.729] <TB0>     INFO: 	ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.

[12:01:37.729] <TB0>     INFO: 	ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.

[12:01:37.729] <TB0>     INFO: 	ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.

[12:01:37.729] <TB0>     INFO: 	ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.

[12:01:37.729] <TB0>     INFO: 	ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.

[12:01:37.729] <TB0>     INFO: 	ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.

[12:01:37.729] <TB0>     INFO: 	ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.

[12:01:37.729] <TB0>     INFO: 	ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.

[12:01:37.729] <TB0>     INFO: 	ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.

[12:01:37.729] <TB0>     INFO: 	ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.

[12:01:37.729] <TB0>     INFO: 	ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.

[12:01:37.729] <TB0>     INFO: 	ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.

[12:01:37.729] <TB0>     INFO: 	ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.

[12:01:37.729] <TB0>     INFO: 	ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.

[12:01:37.729] <TB0>     INFO: 	ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.

[12:01:37.729] <TB0>     INFO: 	ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.

[12:01:38.130] <TB0>     INFO: enter 'restricted' command line mode

[12:01:38.130] <TB0>     INFO: enter test to run

[12:01:38.130] <TB0>     INFO:   test: PixelAlive no parameter change

[12:01:38.130] <TB0>     INFO:   running: pixelalive

[12:01:38.134] <TB0>     INFO: ######################################################################

[12:01:38.134] <TB0>     INFO: PixTestAlive::doTest()

[12:01:38.134] <TB0>     INFO: ######################################################################

[12:01:38.136] <TB0>     INFO:    ----------------------------------------------------------------------

[12:01:38.136] <TB0>     INFO:    PixTestAlive::aliveTest() ntrig = 50, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)

[12:01:38.136] <TB0>     INFO:    ----------------------------------------------------------------------

[12:01:38.434] <TB0>     INFO: Expecting 208000 events.

[12:01:49.552] <TB0>     INFO: 208000 events read in total (10400ms).

[12:01:49.554] <TB0>     INFO: Test took 11417ms.

[12:01:49.560] <TB0>     INFO: Fetched DAQ statistics. Counters are being reset now.

[12:01:49.818] <TB0>     INFO: PixTestAlive::aliveTest() done

[12:01:49.818] <TB0>     INFO: number of dead pixels (per ROC):     0    0    0    0    1    0    0    1    0    0    0    0    0    0    0    2

[12:01:49.820] <TB0>     INFO:    ----------------------------------------------------------------------

[12:01:49.820] <TB0>     INFO:    PixTestAlive::maskTest() ntrig = 50, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)

[12:01:49.820] <TB0>     INFO:    ----------------------------------------------------------------------

[12:01:50.131] <TB0>     INFO: Expecting 208000 events.

[12:01:58.906] <TB0>     INFO: 208000 events read in total (8060ms).

[12:01:58.906] <TB0>     INFO: Test took 9085ms.

[12:01:58.906] <TB0>     INFO: Fetched DAQ statistics. Counters are being reset now.

[12:01:58.907] <TB0>     INFO: mask vs. old pixelAlive PixelAlive_C0_V0 ..  PixelAlive_C15_V0

[12:01:59.219] <TB0>     INFO: PixTestAlive::maskTest() done

[12:01:59.219] <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

[12:01:59.221] <TB0>     INFO:    ----------------------------------------------------------------------

[12:01:59.221] <TB0>     INFO:    PixTestAlive::addressDecodingTest() ntrig = 50, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)

[12:01:59.221] <TB0>     INFO:    ----------------------------------------------------------------------

[12:01:59.530] <TB0>     INFO: Expecting 208000 events.

[12:02:12.622] <TB0>     INFO: 208000 events read in total (12376ms).

[12:02:12.623] <TB0>     INFO: Test took 13401ms.

[12:02:12.629] <TB0>     INFO: Fetched DAQ statistics. Counters are being reset now.

[12:02:12.855] <TB0>     INFO: PixTestAlive::addressDecodingTest() done

[12:02:12.855] <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

[12:02:12.855] <TB0>     INFO: PixTestAlive::doTest() done, duration: 34 seconds

[12:02:12.891] <TB0>     INFO: enter test to run

[12:02:12.891] <TB0>     INFO:   test: delay setting parameters: ->2<-

[12:02:12.891] <TB0>     INFO: delay test by 2 seconds...

[12:02:14.891] <TB0>     INFO: enter test to run

[12:02:14.891] <TB0>     INFO:   test: HighRate no parameter change

[12:02:14.891] <TB0>     INFO:   running: highrate

[12:02:14.891] <TB0>     INFO: readGainPedestalParameters /usr/local/data/M2125_XrayQualification_2015-12-16_10h27m_1450258068//018_HREfficiency_250//phCalibrationFitErr35_C0.dat .. /usr/local/data/M2125_XrayQualification_2015-12-16_10h27m_1450258068//018_HREfficiency_250//phCalibrationFitErr35_C15.dat

[12:02:15.036] <TB0>     INFO:    ----------------------------------------------------------------------

[12:02:15.036] <TB0>     INFO:    PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200

[12:02:15.036] <TB0>     INFO:    ----------------------------------------------------------------------

[12:02:15.658] <TB0>     INFO: Expecting 208000 events.

[12:02:19.677] <TB0>    ERROR: <datapipe.cc/CheckEventValidity:L491> Channel 2 Number of ROCs (2) != Token Chain Length (4)

[12:02:19.677] <TB0>    ERROR: <datapipe.cc/Read:L164> Dumping the flawed event +- 3 events:

[12:02:19.677] <TB0>    ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a2d8 80b1 4389 18 2a81 d3 2685 4389 18 2a61 48 2a68 59 2665 59 2a66 411 224a 4cc 2622 50d 266c 842 286c 4389 18 2a8f 1a 2a85 49c 206c 4388 18 2a8f 9a 2468 108 2660 28b 2681 6d9 2849 e002 c000 

[12:02:19.677] <TB0>    ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a2d2 8000 4088 2 2440 18 2a81 31a 2266 742 2060 4089 861 2a6f 4088 204 2064 2c4 2a6f 70c 2426 4088 55 2848 104 244f 14d 2262 fff 2fff e002 c000 

[12:02:19.677] <TB0>    ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a2d3 8040 4088 18 2a6a a1 2860 d0 2260 d0 266a 305 2a48 69b 2a61 801 2442 4088 18 2a8e 1c 222f 80 2848 103 2862 4ca 282f 812 2a62 4088 18 2a8f 2cb 2a4f 694 2862 682 2260 808 2482 4088 c8 2627 2a0 224f 6cc 2a62 855 2a43 e002 c000 

[12:02:19.677] <TB0>    ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a2d4 80b1 4088 18 2a88 2c1 2664 34b 2a45 822 222b 4088 18 2a6d 54d 2445 4088 18 2a87 2e1 228c 518 2666 755 2a2d 4088 18 2a83 fff 2fff e002 c000 

[12:02:19.677] <TB0>    ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a2d5 80c0 4388 18 2a6c 610 22c8 2f8 2018 aa5 2022 a4f 2642 44a 2800 44a 4388 20 246a 1a 2047 11 2867 b 2484 18 2a8e 108 246f 485 266c 71c 2628 74b 2866 813 264b 4388 18 2a82 612 2a69 64d 2460 6d3 204f e002 c000 

[12:02:19.677] <TB0>    ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a2d6 8000 4388 8 2822 18 2a82 142 2246 149 2a4e 560 2260 683 264c 71b 2860 4388 13 224b 18 2aa1 9a 284f 9a 226c 310 284c 451 204c 450 2844 604 2447 6ca 2a40 4388 43 2266 59 266b 61 268d 11a 204c 100 264f 49a 2a6a 4389 18 2a8d c8 242d 2db 226c 61a 206a 60a 2a4f e002 c000 

[12:02:19.677] <TB0>    ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a2d7 8040 4388 4a 284e a1 2667 a1 2a62 459 266b 50b 2468 4389 a0 204f 9b 2861 88 2861 88 2261 160 2081 2dc 246f 359 206f 48c 2668 4389 3 206f 18 2a88 159 2a64 69c 2229 4389 18 2aa2 292 264f 522 2468 e002 c000 

[12:02:53.887] <TB0>     INFO: 208000 events read in total (37702ms).

[12:02:53.907] <TB0>     INFO: Test took 38867ms.

[12:02:55.074] <TB0>     INFO: Fetched DAQ statistics. Counters are being reset now.

[12:02:55.636] <TB0>     INFO: number of dead pixels (per ROC):     0    0    0    0    1    0    0    1    0    0    0    0    0    0    0    2

[12:02:55.636] <TB0>     INFO: number of red-efficiency pixels:  1610 1899 1528 2801 3435 3968 3792 4126 3669 3590 3725 3014 2726 1266 1614 1292

[12:02:55.636] <TB0>     INFO: number of X-ray hits detected:    415485 482861 384026 630202 751929 889379 842331 1010490 775432 776463 817774 643220 621124 333204 393101 366051

[12:02:55.636] <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

[12:02:55.636] <TB0>     INFO: number of Vcal hits detected:  205637 205068 204172 201603 197121 191066 192677 184348 196958 196038 195116 200367 202412 205157 205826 206211

[12:02:55.636] <TB0>     INFO: Vcal hit fiducial efficiency (%):  99.0 98.7 98.5 97.3 95.5 92.7 93.5 89.5 95.1 94.9 94.5 96.8 97.6 98.9 99.0 99.3

[12:02:55.636] <TB0>     INFO: Vcal hit overall efficiency (%):  98.9 98.6 98.2 96.9 94.8 91.9 92.6 88.6 94.7 94.2 93.8 96.3 97.3 98.6 99.0 99.1

[12:02:55.636] <TB0>     INFO: X-ray hit rate [MHz/cm2]:  121.8 141.5 112.6 184.7 220.4 260.7 246.9 296.2 227.3 227.6 239.7 188.5 182.1 97.7 115.2 107.3

[12:02:55.636] <TB0>     INFO: PixTestHighRate::doXPixelAlive() done

[12:02:55.661] <TB0>     INFO: PixTest::       pg_setup set to default.

[12:02:55.674] <TB0>     INFO: enter test to run

[12:02:55.675] <TB0>     INFO:   test:  no parameter change

[12:02:55.773] <TB0>    QUIET: Connection to board 83 closed.

[12:02:55.853] <TB0>     INFO: pXar: this is the end, my friend

MoReWeb-v1.0.0-5-g82d9ff6 on branch psi46master