[13:20:37.531] INFO: *** Welcome to pxar ***
[13:20:37.531] INFO: *** Today: 2016/04/05
[13:20:37.650] INFO: *** Version: v1.9.0-793-ge521-dirty
[13:20:37.650] INFO: readRocDacs: /home/uicpirepix2/ProductionTestResults/M-G-3-06_FPIXTest-17C-FNAL-160119-1002_2016-01-19_10h03m_1453219423/000_FPIXTest_p17//dacParameters35_C0.dat .. /home/uicpirepix2/ProductionTestResults/M-G-3-06_FPIXTest-17C-FNAL-160119-1002_2016-01-19_10h03m_1453219423/000_FPIXTest_p17//dacParameters35_C15.dat
[13:20:37.660] INFO: readTbmDacs: /home/uicpirepix2/ProductionTestResults/M-G-3-06_FPIXTest-17C-FNAL-160119-1002_2016-01-19_10h03m_1453219423/000_FPIXTest_p17//tbmParameters_C0a.dat .. /home/uicpirepix2/ProductionTestResults/M-G-3-06_FPIXTest-17C-FNAL-160119-1002_2016-01-19_10h03m_1453219423/000_FPIXTest_p17//tbmParameters_C0b.dat
[13:20:37.660] INFO: readMaskFile: /home/uicpirepix2/ProductionTestResults/M-G-3-06_FPIXTest-17C-FNAL-160119-1002_2016-01-19_10h03m_1453219423/000_FPIXTest_p17//defaultMaskFile.dat
[13:20:37.685] INFO: MASKED Roc 2 col/row: 48 50
[13:20:37.685] INFO: MASKED Roc 9 col/row: 42 75
[13:20:37.685] INFO: MASKED Roc 12 col/row: 3 38
[13:20:37.685] INFO: MASKED Roc 14 col/row: 51 56
[13:20:37.685] INFO: readTrimFile: /home/uicpirepix2/ProductionTestResults/M-G-3-06_FPIXTest-17C-FNAL-160119-1002_2016-01-19_10h03m_1453219423/000_FPIXTest_p17//trimParameters35_C0.dat .. /home/uicpirepix2/ProductionTestResults/M-G-3-06_FPIXTest-17C-FNAL-160119-1002_2016-01-19_10h03m_1453219423/000_FPIXTest_p17//trimParameters35_C15.dat
[13:20:37.706] INFO: masking Roc 2 col/row: 48 50
[13:20:37.739] INFO: masking Roc 9 col/row: 42 75
[13:20:37.752] INFO: masking Roc 12 col/row: 3 38
[13:20:37.761] INFO: masking Roc 14 col/row: 51 56
[13:20:37.774] INFO: clk: 4
[13:20:37.774] INFO: ctr: 4
[13:20:37.774] INFO: sda: 19
[13:20:37.774] INFO: tin: 9
[13:20:37.774] INFO: level: 15
[13:20:37.774] INFO: triggerdelay: 0
[13:20:37.774] QUIET: Instanciating API for pxar v1.9.0+793~ge5211a7
[13:20:37.775] INFO: Log level: INFO
[13:20:37.793] QUIET: Connection to board DTB_WREKRL opened.
[13:20:37.796] INFO: DTB startup information
--- DTB info------------------------------------------
Board id: 33
HW version: DTB1.2
FW version: 4.2
SW version: 4.5
USB id: DTB_WREKRL
MAC address: 40D855118021
Hostname: pixelDTB033
Comment:
------------------------------------------------------
[13:20:37.799] INFO: RPC call hashes of host and DTB match: 398089610
[13:20:39.330] INFO: DUT info:
[13:20:39.330] INFO: The DUT currently contains the following objects:
[13:20:39.331] INFO: 2 TBM Cores tbm08c (2 ON)
[13:20:39.331] INFO: TBM Core alpha (0): 7 registers set
[13:20:39.331] INFO: TBM Core beta (1): 7 registers set
[13:20:39.331] INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[13:20:39.331] INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[13:20:39.331] INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[13:20:39.331] INFO: ROC 2: 19 DACs set, Pixels: 1 masked, 0 active.
[13:20:39.331] INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[13:20:39.331] INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[13:20:39.331] INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[13:20:39.331] INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[13:20:39.331] INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[13:20:39.331] INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[13:20:39.331] INFO: ROC 9: 19 DACs set, Pixels: 1 masked, 0 active.
[13:20:39.331] INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[13:20:39.331] INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[13:20:39.331] INFO: ROC 12: 19 DACs set, Pixels: 1 masked, 0 active.
[13:20:39.331] INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[13:20:39.331] INFO: ROC 14: 19 DACs set, Pixels: 1 masked, 0 active.
[13:20:39.332] INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[13:20:39.735] INFO: enter 'restricted' command line mode
[13:20:39.735] INFO: enter test to run
[13:20:54.981] INFO: test: PixelAlive no parameter change
[13:20:54.981] INFO: running: pixelalive
[13:20:55.003] INFO: ----------------------------------------------------------------------
[13:20:55.003] INFO: PixTestAlive::aliveTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[13:20:55.003] INFO: ----------------------------------------------------------------------
[13:20:55.005] INFO: ROC 2 masking pixel 48/50
[13:20:55.005] INFO: ROC 9 masking pixel 42/75
[13:20:55.005] INFO: ROC 12 masking pixel 3/38
[13:20:55.005] INFO: ROC 14 masking pixel 51/56
[13:20:55.319] INFO: Expecting 41600 events.
[13:20:59.681] INFO: 41600 events read in total (3643ms).
[13:20:59.848] INFO: Test took 4843ms.
[13:20:59.862] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:21:00.123] INFO: PixTestAlive::aliveTest() done
[13:21:00.123] INFO: number of dead pixels (per ROC): 0 0 1 0 1 0 1 0 0 78 0 0 1 0 1 0
[13:21:00.123] INFO: ROC 2 masking pixel 48/50
[13:21:00.123] INFO: ROC 9 masking pixel 42/75
[13:21:00.123] INFO: ROC 12 masking pixel 3/38
[13:21:00.123] INFO: ROC 14 masking pixel 51/56
[13:21:00.150] INFO: enter test to run
[13:21:42.140] INFO: test: HighRate setting parameters: ->runsecondshotpixels=5;trimhotpixelthr=200<-
[13:21:42.140] INFO: running: highrate
[13:21:42.141] INFO: readGainPedestalParameters /home/uicpirepix2/ProductionTestResults/M-G-3-06_FPIXTest-17C-FNAL-160119-1002_2016-01-19_10h03m_1453219423/000_FPIXTest_p17//phCalibrationFitErr35_C0.dat .. /home/uicpirepix2/ProductionTestResults/M-G-3-06_FPIXTest-17C-FNAL-160119-1002_2016-01-19_10h03m_1453219423/000_FPIXTest_p17//phCalibrationFitErr35_C15.dat
[13:21:42.353] INFO: ----------------------------------------------------------------------
[13:21:42.353] INFO: PixTest::trimHotPixels() running for 5 seconds with 100 kHz trigger rate
[13:21:42.353] INFO: ----------------------------------------------------------------------
[13:21:42.353] INFO: THR = 200, corresponding to ~ 106.667 MHz/cm2
[13:21:42.353] INFO: edge/corner pixel THR is adjusted
[13:21:42.353] INFO: PixTestHighRate::trimHotPixels: step 0...
[13:21:43.310] INFO: Collecting data for 5 seconds...
[13:21:48.327] INFO: Done with hot pixel readout
[13:22:00.537] INFO: PixTest:: pg_setup set to default.
[13:22:00.537] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[13:22:00.538] INFO: 37 hot pixels found in step 0
[13:22:01.553] INFO: Collecting data for 5 seconds...
[13:22:06.569] INFO: Done with hot pixel readout
[13:22:18.747] INFO: PixTest:: pg_setup set to default.
[13:22:18.748] INFO: 34 hot pixels found in step 1
[13:22:19.739] INFO: Collecting data for 5 seconds...
[13:22:24.756] INFO: Done with hot pixel readout
[13:22:36.856] INFO: PixTest:: pg_setup set to default.
[13:22:36.857] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[13:22:36.857] INFO: 39 hot pixels found in step 2
[13:22:37.848] INFO: Collecting data for 5 seconds...
[13:22:42.865] INFO: Done with hot pixel readout
[13:22:55.028] INFO: PixTest:: pg_setup set to default.
[13:22:55.028] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[13:22:55.029] INFO: 32 hot pixels found in step 3
[13:22:56.019] INFO: Collecting data for 5 seconds...
[13:23:01.035] INFO: Done with hot pixel readout
[13:23:13.097] INFO: PixTest:: pg_setup set to default.
[13:23:13.098] INFO: 31 hot pixels found in step 4
[13:23:14.089] INFO: Collecting data for 5 seconds...
[13:23:19.106] INFO: Done with hot pixel readout
[13:23:31.213] INFO: PixTest:: pg_setup set to default.
[13:23:31.214] INFO: 24 hot pixels found in step 5
[13:23:32.209] INFO: Collecting data for 5 seconds...
[13:23:37.226] INFO: Done with hot pixel readout
[13:23:49.385] INFO: PixTest:: pg_setup set to default.
[13:23:49.386] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[13:23:49.386] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[13:23:49.386] INFO: 35 hot pixels found in step 6
[13:23:50.377] INFO: Collecting data for 5 seconds...
[13:23:55.393] INFO: Done with hot pixel readout
[13:24:07.508] INFO: PixTest:: pg_setup set to default.
[13:24:07.509] INFO: 23 hot pixels found in step 7
[13:24:08.500] INFO: Collecting data for 5 seconds...
[13:24:13.517] INFO: Done with hot pixel readout
[13:24:25.679] INFO: PixTest:: pg_setup set to default.
[13:24:25.680] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[13:24:25.680] INFO: 27 hot pixels found in step 8
[13:24:26.671] INFO: Collecting data for 5 seconds...
[13:24:31.688] INFO: Done with hot pixel readout
[13:24:43.807] INFO: PixTest:: pg_setup set to default.
[13:24:43.808] INFO: 27 hot pixels found in step 9
[13:24:44.799] INFO: Collecting data for 5 seconds...
[13:24:49.817] INFO: Done with hot pixel readout
[13:25:01.957] INFO: PixTest:: pg_setup set to default.
[13:25:01.957] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[13:25:01.957] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[13:25:01.958] INFO: 17 hot pixels found in step 10
[13:25:02.950] INFO: Collecting data for 5 seconds...
[13:25:07.967] INFO: Done with hot pixel readout
[13:25:19.974] INFO: PixTest:: pg_setup set to default.
[13:25:19.975] INFO: 7 hot pixels found in step 11
[13:25:20.966] INFO: Collecting data for 5 seconds...
[13:25:25.983] INFO: Done with hot pixel readout
[13:25:37.000] INFO: PixTest:: pg_setup set to default.
[13:25:37.001] INFO: 17 hot pixels found in step 12
[13:25:38.992] INFO: Collecting data for 5 seconds...
[13:25:44.009] INFO: Done with hot pixel readout
[13:25:56.108] INFO: PixTest:: pg_setup set to default.
[13:25:56.109] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[13:25:56.109] INFO: 13 hot pixels found in step 13
[13:25:57.105] INFO: Collecting data for 5 seconds...
[13:26:02.121] INFO: Done with hot pixel readout
[13:26:14.245] INFO: PixTest:: pg_setup set to default.
[13:26:14.245] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[13:26:14.246] INFO: 9 hot pixels found in step 14
[13:26:14.280] INFO: 9 hot pixels could not be trimmed and have been masked.
[13:26:14.284] INFO: PixTest::trimHotPixels() done
[13:26:14.289] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-G-3-06_FPIXTest-17C-FNAL-160119-1002_2016-01-19_10h03m_1453219423/000_FPIXTest_p17//trimParameters35_C0.dat
[13:26:14.297] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-G-3-06_FPIXTest-17C-FNAL-160119-1002_2016-01-19_10h03m_1453219423/000_FPIXTest_p17//trimParameters35_C1.dat
[13:26:14.304] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-G-3-06_FPIXTest-17C-FNAL-160119-1002_2016-01-19_10h03m_1453219423/000_FPIXTest_p17//trimParameters35_C2.dat
[13:26:14.311] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-G-3-06_FPIXTest-17C-FNAL-160119-1002_2016-01-19_10h03m_1453219423/000_FPIXTest_p17//trimParameters35_C3.dat
[13:26:14.318] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-G-3-06_FPIXTest-17C-FNAL-160119-1002_2016-01-19_10h03m_1453219423/000_FPIXTest_p17//trimParameters35_C4.dat
[13:26:14.325] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-G-3-06_FPIXTest-17C-FNAL-160119-1002_2016-01-19_10h03m_1453219423/000_FPIXTest_p17//trimParameters35_C5.dat
[13:26:14.332] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-G-3-06_FPIXTest-17C-FNAL-160119-1002_2016-01-19_10h03m_1453219423/000_FPIXTest_p17//trimParameters35_C6.dat
[13:26:14.339] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-G-3-06_FPIXTest-17C-FNAL-160119-1002_2016-01-19_10h03m_1453219423/000_FPIXTest_p17//trimParameters35_C7.dat
[13:26:14.346] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-G-3-06_FPIXTest-17C-FNAL-160119-1002_2016-01-19_10h03m_1453219423/000_FPIXTest_p17//trimParameters35_C8.dat
[13:26:14.353] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-G-3-06_FPIXTest-17C-FNAL-160119-1002_2016-01-19_10h03m_1453219423/000_FPIXTest_p17//trimParameters35_C9.dat
[13:26:14.360] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-G-3-06_FPIXTest-17C-FNAL-160119-1002_2016-01-19_10h03m_1453219423/000_FPIXTest_p17//trimParameters35_C10.dat
[13:26:14.368] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-G-3-06_FPIXTest-17C-FNAL-160119-1002_2016-01-19_10h03m_1453219423/000_FPIXTest_p17//trimParameters35_C11.dat
[13:26:14.375] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-G-3-06_FPIXTest-17C-FNAL-160119-1002_2016-01-19_10h03m_1453219423/000_FPIXTest_p17//trimParameters35_C12.dat
[13:26:14.382] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-G-3-06_FPIXTest-17C-FNAL-160119-1002_2016-01-19_10h03m_1453219423/000_FPIXTest_p17//trimParameters35_C13.dat
[13:26:14.389] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-G-3-06_FPIXTest-17C-FNAL-160119-1002_2016-01-19_10h03m_1453219423/000_FPIXTest_p17//trimParameters35_C14.dat
[13:26:14.396] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-G-3-06_FPIXTest-17C-FNAL-160119-1002_2016-01-19_10h03m_1453219423/000_FPIXTest_p17//trimParameters35_C15.dat
[13:26:14.415] INFO: write masked pixels into /home/uicpirepix2/ProductionTestResults/M-G-3-06_FPIXTest-17C-FNAL-160119-1002_2016-01-19_10h03m_1453219423/000_FPIXTest_p17//defaultMaskFile.dat
[13:26:14.427] INFO: enter test to run
[13:30:07.953] INFO: test: HighRate setting parameters: ->runsecondshotpixels=1;trimhotpixelthr=200<-
[13:30:07.953] INFO: running: highrate
[13:30:07.958] INFO: ----------------------------------------------------------------------
[13:30:07.958] INFO: PixTest::trimHotPixels() running for 1 seconds with 100 kHz trigger rate
[13:30:07.958] INFO: ----------------------------------------------------------------------
[13:30:07.958] INFO: THR = 200, corresponding to ~ 533.333 MHz/cm2
[13:30:07.958] INFO: edge/corner pixel THR is adjusted
[13:30:07.958] INFO: PixTestHighRate::trimHotPixels: step 0...
[13:30:08.916] INFO: Collecting data for 1 seconds...
[13:30:09.919] INFO: Done with hot pixel readout
[13:30:14.260] INFO: PixTest:: pg_setup set to default.
[13:30:14.261] INFO: 0 hot pixels found in step 0
[13:30:14.268] INFO: 0 hot pixels could not be trimmed and have been masked.
[13:30:14.344] INFO: PixTest::trimHotPixels() done
[13:30:14.344] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-G-3-06_FPIXTest-17C-FNAL-160119-1002_2016-01-19_10h03m_1453219423/000_FPIXTest_p17//trimParameters35_C0.dat
[13:30:14.358] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-G-3-06_FPIXTest-17C-FNAL-160119-1002_2016-01-19_10h03m_1453219423/000_FPIXTest_p17//trimParameters35_C1.dat
[13:30:14.365] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-G-3-06_FPIXTest-17C-FNAL-160119-1002_2016-01-19_10h03m_1453219423/000_FPIXTest_p17//trimParameters35_C2.dat
[13:30:14.372] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-G-3-06_FPIXTest-17C-FNAL-160119-1002_2016-01-19_10h03m_1453219423/000_FPIXTest_p17//trimParameters35_C3.dat
[13:30:14.379] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-G-3-06_FPIXTest-17C-FNAL-160119-1002_2016-01-19_10h03m_1453219423/000_FPIXTest_p17//trimParameters35_C4.dat
[13:30:14.387] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-G-3-06_FPIXTest-17C-FNAL-160119-1002_2016-01-19_10h03m_1453219423/000_FPIXTest_p17//trimParameters35_C5.dat
[13:30:14.394] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-G-3-06_FPIXTest-17C-FNAL-160119-1002_2016-01-19_10h03m_1453219423/000_FPIXTest_p17//trimParameters35_C6.dat
[13:30:14.401] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-G-3-06_FPIXTest-17C-FNAL-160119-1002_2016-01-19_10h03m_1453219423/000_FPIXTest_p17//trimParameters35_C7.dat
[13:30:14.408] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-G-3-06_FPIXTest-17C-FNAL-160119-1002_2016-01-19_10h03m_1453219423/000_FPIXTest_p17//trimParameters35_C8.dat
[13:30:14.415] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-G-3-06_FPIXTest-17C-FNAL-160119-1002_2016-01-19_10h03m_1453219423/000_FPIXTest_p17//trimParameters35_C9.dat
[13:30:14.422] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-G-3-06_FPIXTest-17C-FNAL-160119-1002_2016-01-19_10h03m_1453219423/000_FPIXTest_p17//trimParameters35_C10.dat
[13:30:14.429] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-G-3-06_FPIXTest-17C-FNAL-160119-1002_2016-01-19_10h03m_1453219423/000_FPIXTest_p17//trimParameters35_C11.dat
[13:30:14.436] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-G-3-06_FPIXTest-17C-FNAL-160119-1002_2016-01-19_10h03m_1453219423/000_FPIXTest_p17//trimParameters35_C12.dat
[13:30:14.443] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-G-3-06_FPIXTest-17C-FNAL-160119-1002_2016-01-19_10h03m_1453219423/000_FPIXTest_p17//trimParameters35_C13.dat
[13:30:14.450] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-G-3-06_FPIXTest-17C-FNAL-160119-1002_2016-01-19_10h03m_1453219423/000_FPIXTest_p17//trimParameters35_C14.dat
[13:30:14.457] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-G-3-06_FPIXTest-17C-FNAL-160119-1002_2016-01-19_10h03m_1453219423/000_FPIXTest_p17//trimParameters35_C15.dat
[13:30:14.464] INFO: write masked pixels into /home/uicpirepix2/ProductionTestResults/M-G-3-06_FPIXTest-17C-FNAL-160119-1002_2016-01-19_10h03m_1453219423/000_FPIXTest_p17//defaultMaskFile.dat
[13:30:14.474] INFO: enter test to run
[13:33:07.304] INFO: test: Xray setting parameters: ->source=DCLowRate<-
[13:33:07.304] INFO: running: xray
[13:33:07.305] INFO: ----------------------------------------------------------------------
[13:33:07.305] INFO: PixTestXray::doPhRun() fParRunSeconds = 100
[13:33:07.305] INFO: ----------------------------------------------------------------------
[13:33:07.308] INFO: ROC 2 masking pixel 48/50
[13:33:07.308] INFO: ROC 9 masking pixel 42/75
[13:33:07.308] INFO: ROC 12 masking pixel 3/38
[13:33:07.308] INFO: ROC 14 masking pixel 51/56
[13:33:08.271] INFO: PixTestXray::doPhRun start TriggerLoop with trigger frequency 100 kHz, period 405 and duration 100 seconds, fEventsMax = 10000000
[13:33:19.528] INFO: run duration 11 seconds, buffer almost full (81%), pausing triggers.
[13:33:48.882] INFO: Resuming triggers.
[13:34:00.142] INFO: run duration 22 seconds, buffer almost full (81%), pausing triggers.
[13:34:29.474] INFO: Resuming triggers.
[13:34:40.733] INFO: run duration 33 seconds, buffer almost full (81%), pausing triggers.
[13:35:10.021] INFO: Resuming triggers.
[13:35:21.280] INFO: run duration 44 seconds, buffer almost full (81%), pausing triggers.
[13:35:50.720] INFO: Resuming triggers.
[13:36:01.976] INFO: run duration 56 seconds, buffer almost full (81%), pausing triggers.
[13:36:31.344] INFO: Resuming triggers.
[13:36:42.603] INFO: run duration 67 seconds, buffer almost full (81%), pausing triggers.
[13:37:11.965] INFO: Resuming triggers.
[13:37:23.220] INFO: run duration 78 seconds, buffer almost full (81%), pausing triggers.
[13:37:52.641] INFO: Resuming triggers.
[13:38:03.895] INFO: run duration 89 seconds, buffer almost full (81%), pausing triggers.
[13:38:33.247] INFO: Resuming triggers.
[13:38:43.524] INFO: data taking finished, elapsed time: 100 seconds.
[13:39:10.349] INFO: PixTest:: pg_setup set to default.
[13:39:10.353] INFO: PixTestXray::doPhRun() done
[13:39:10.487] INFO: enter test to run
[13:40:06.869] INFO: test: Xray setting parameters: ->source=DCHighRate<-
[13:40:06.869] INFO: running: xray
[13:40:06.870] INFO: ----------------------------------------------------------------------
[13:40:06.870] INFO: PixTestXray::doPhRun() fParRunSeconds = 100
[13:40:06.870] INFO: ----------------------------------------------------------------------
[13:40:06.887] INFO: ROC 2 masking pixel 48/50
[13:40:06.887] INFO: ROC 9 masking pixel 42/75
[13:40:06.887] INFO: ROC 12 masking pixel 3/38
[13:40:06.887] INFO: ROC 14 masking pixel 51/56
[13:40:07.848] INFO: PixTestXray::doPhRun start TriggerLoop with trigger frequency 100 kHz, period 405 and duration 100 seconds, fEventsMax = 10000000
[13:40:13.785] INFO: run duration 5 seconds, buffer almost full (81%), pausing triggers.
[13:40:43.528] INFO: Resuming triggers.
[13:40:49.464] INFO: run duration 11 seconds, buffer almost full (81%), pausing triggers.
[13:41:19.202] INFO: Resuming triggers.
[13:41:25.141] INFO: run duration 17 seconds, buffer almost full (81%), pausing triggers.
[13:41:54.888] INFO: Resuming triggers.
[13:42:00.825] INFO: run duration 23 seconds, buffer almost full (81%), pausing triggers.
[13:42:28.804] INFO: Resuming triggers.
[13:42:34.738] INFO: run duration 29 seconds, buffer almost full (81%), pausing triggers.
[13:43:04.300] INFO: Resuming triggers.
[13:43:10.237] INFO: run duration 35 seconds, buffer almost full (81%), pausing triggers.
[13:43:39.888] INFO: Resuming triggers.
[13:43:45.820] INFO: run duration 41 seconds, buffer almost full (81%), pausing triggers.
[13:44:15.595] INFO: Resuming triggers.
[13:44:21.531] INFO: run duration 47 seconds, buffer almost full (81%), pausing triggers.
[13:44:51.245] INFO: Resuming triggers.
[13:44:57.180] INFO: run duration 53 seconds, buffer almost full (81%), pausing triggers.
[13:45:26.633] INFO: Resuming triggers.
[13:45:32.570] INFO: run duration 59 seconds, buffer almost full (81%), pausing triggers.
[13:46:02.246] INFO: Resuming triggers.
[13:46:08.177] INFO: run duration 65 seconds, buffer almost full (81%), pausing triggers.
[13:46:37.989] INFO: Resuming triggers.
[13:46:43.927] INFO: run duration 70 seconds, buffer almost full (81%), pausing triggers.
[13:47:13.724] INFO: Resuming triggers.
[13:47:19.656] INFO: run duration 76 seconds, buffer almost full (81%), pausing triggers.
[13:47:49.343] INFO: Resuming triggers.
[13:47:55.277] INFO: run duration 82 seconds, buffer almost full (81%), pausing triggers.
[13:48:25.123] INFO: Resuming triggers.
[13:48:31.059] INFO: run duration 88 seconds, buffer almost full (81%), pausing triggers.
[13:49:00.720] INFO: Resuming triggers.
[13:49:06.655] INFO: run duration 94 seconds, buffer almost full (81%), pausing triggers.
[13:49:36.398] INFO: Resuming triggers.
[13:49:41.762] INFO: data taking finished, elapsed time: 100 seconds.
[13:50:08.795] INFO: PixTest:: pg_setup set to default.
[13:50:08.799] INFO: PixTestXray::doPhRun() done
[13:50:08.946] INFO: enter test to run
[13:50:39.368] INFO: test: HighRate no parameter change
[13:50:39.368] INFO: running: highrate
[13:50:39.369] INFO: ----------------------------------------------------------------------
[13:50:39.369] INFO: PixTestHighRate::calDelScan() ntrig = 10, vcal = 200
[13:50:39.369] INFO: ----------------------------------------------------------------------
[13:50:39.510] INFO: Expecting 768 events.
[13:50:40.644] INFO: 768 events read in total (418ms).
[13:50:40.645] INFO: Test took 1269ms.
[13:50:40.649] INFO: ROC 2 masking pixel 48/50
[13:50:40.649] INFO: ROC 9 masking pixel 42/75
[13:50:40.649] INFO: ROC 12 masking pixel 3/38
[13:50:40.649] INFO: ROC 14 masking pixel 51/56
[13:50:41.448] INFO: Expecting 41600 events.
[13:50:44.581] INFO: 41600 events read in total (2606ms).
[13:50:44.583] INFO: Test took 3932ms.
[13:50:44.619] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:50:45.339] INFO: Expecting 41600 events.
[13:50:48.555] INFO: 41600 events read in total (2689ms).
[13:50:48.556] INFO: Test took 3917ms.
[13:50:48.592] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:50:49.309] INFO: Expecting 41600 events.
[13:50:52.565] INFO: 41600 events read in total (2729ms).
[13:50:52.566] INFO: Test took 3956ms.
[13:50:52.602] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:50:53.320] INFO: Expecting 41600 events.
[13:50:56.572] INFO: 41600 events read in total (2726ms).
[13:50:56.574] INFO: Test took 3953ms.
[13:50:56.611] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:50:57.327] INFO: Expecting 41600 events.
[13:51:00.583] INFO: 41600 events read in total (2729ms).
[13:51:00.584] INFO: Test took 3954ms.
[13:51:00.621] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:51:01.341] INFO: Expecting 41600 events.
[13:51:04.601] INFO: 41600 events read in total (2733ms).
[13:51:04.602] INFO: Test took 3961ms.
[13:51:04.640] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:51:05.357] INFO: Expecting 41600 events.
[13:51:08.636] INFO: 41600 events read in total (2752ms).
[13:51:08.637] INFO: Test took 3978ms.
[13:51:08.674] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:51:09.394] INFO: Expecting 41600 events.
[13:51:12.664] INFO: 41600 events read in total (2743ms).
[13:51:12.665] INFO: Test took 3972ms.
[13:51:12.702] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:51:13.417] INFO: Expecting 41600 events.
[13:51:16.690] INFO: 41600 events read in total (2746ms).
[13:51:16.691] INFO: Test took 3970ms.
[13:51:16.728] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:51:17.448] INFO: Expecting 41600 events.
[13:51:20.717] INFO: 41600 events read in total (2742ms).
[13:51:20.718] INFO: Test took 3971ms.
[13:51:20.755] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:51:21.471] INFO: Expecting 41600 events.
[13:51:24.725] INFO: 41600 events read in total (2728ms).
[13:51:24.726] INFO: Test took 3953ms.
[13:51:24.762] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:51:25.481] INFO: Expecting 41600 events.
[13:51:28.747] INFO: 41600 events read in total (2739ms).
[13:51:28.748] INFO: Test took 3966ms.
[13:51:28.785] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:51:29.504] INFO: Expecting 41600 events.
[13:51:32.769] INFO: 41600 events read in total (2738ms).
[13:51:32.770] INFO: Test took 3965ms.
[13:51:32.805] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:51:33.528] INFO: Expecting 41600 events.
[13:51:36.796] INFO: 41600 events read in total (2741ms).
[13:51:36.797] INFO: Test took 3973ms.
[13:51:36.832] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:51:37.550] INFO: Expecting 41600 events.
[13:51:40.809] INFO: 41600 events read in total (2733ms).
[13:51:40.810] INFO: Test took 3958ms.
[13:51:40.847] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:51:41.569] INFO: Expecting 41600 events.
[13:51:44.838] INFO: 41600 events read in total (2743ms).
[13:51:44.839] INFO: Test took 3972ms.
[13:51:44.875] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:51:45.594] INFO: Expecting 41600 events.
[13:51:48.419] INFO: 41600 events read in total (2299ms).
[13:51:48.420] INFO: Test took 3525ms.
[13:51:48.458] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:51:49.179] INFO: Expecting 41600 events.
[13:51:52.221] INFO: 41600 events read in total (2515ms).
[13:51:52.222] INFO: Test took 3745ms.
[13:51:52.260] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:51:52.981] INFO: Expecting 41600 events.
[13:51:56.226] INFO: 41600 events read in total (2718ms).
[13:51:56.227] INFO: Test took 3947ms.
[13:51:56.264] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:51:56.982] INFO: Expecting 41600 events.
[13:52:00.063] INFO: 41600 events read in total (2554ms).
[13:52:00.064] INFO: Test took 3780ms.
[13:52:00.100] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:52:00.461] INFO: enter test to run
[13:53:50.663] INFO: test: HighRate no parameter change
[13:53:50.663] INFO: running: highrate
[13:53:50.664] INFO: ----------------------------------------------------------------------
[13:53:50.664] INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[13:53:50.664] INFO: ----------------------------------------------------------------------
[13:53:50.672] INFO: ROC 2 masking pixel 48/50
[13:53:50.672] INFO: ROC 9 masking pixel 42/75
[13:53:50.672] INFO: ROC 12 masking pixel 3/38
[13:53:50.672] INFO: ROC 14 masking pixel 51/56
[13:53:51.284] INFO: Expecting 208000 events.
[13:54:03.289] INFO: 208000 events read in total (11478ms).
[13:54:03.292] INFO: Test took 12619ms.
[13:54:03.446] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:54:03.702] INFO: number of dead pixels (per ROC): 0 0 1 0 1 0 1 0 0 79 0 0 1 0 1 0
[13:54:03.702] INFO: number of red-efficiency pixels: 80 59 103 162 198 181 189 98 121 265 130 106 112 55 23 24
[13:54:03.703] INFO: number of X-ray hits detected: 68544 48721 72834 116091 132216 137389 134343 88722 81149 104191 108552 88156 90678 54697 22439 25195
[13:54:03.703] INFO: number of triggers sent (total per ROC): 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000
[13:54:03.703] INFO: number of Vcal hits detected: 207916 207940 207847 207833 207748 207814 207759 207894 207877 203848 207868 207894 207838 207945 207928 207976
[13:54:03.703] INFO: Vcal hit fiducial efficiency (%): 100.0 100.0 100.0 99.9 99.9 99.9 99.9 100.0 99.9 99.9 99.9 100.0 99.9 100.0 100.0 100.0
[13:54:03.703] INFO: Vcal hit overall efficiency (%): 100.0 100.0 99.9 99.9 99.9 99.9 99.9 99.9 99.9 98.0 99.9 99.9 99.9 100.0 100.0 100.0
[13:54:03.703] INFO: X-ray hit rate [MHz/cm2]: 20.1 14.3 21.3 34.0 38.8 40.3 39.4 26.0 23.8 30.5 31.8 25.8 26.6 16.0 6.6 7.4
[13:54:03.703] INFO: PixTestHighRate::doXPixelAlive() done
[13:54:03.747] INFO: PixTest:: pg_setup set to default.
[13:54:03.763] INFO: enter test to run
[13:54:33.190] INFO: test: HighRate no parameter change
[13:54:33.190] INFO: running: highrate
[13:54:33.191] INFO: ----------------------------------------------------------------------
[13:54:33.191] INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[13:54:33.191] INFO: ----------------------------------------------------------------------
[13:54:33.198] INFO: ROC 2 masking pixel 48/50
[13:54:33.198] INFO: ROC 9 masking pixel 42/75
[13:54:33.198] INFO: ROC 12 masking pixel 3/38
[13:54:33.198] INFO: ROC 14 masking pixel 51/56
[13:54:33.798] INFO: Expecting 208000 events.
[13:54:47.871] INFO: 208000 events read in total (13546ms).
[13:54:47.877] INFO: Test took 14678ms.
[13:54:48.223] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:54:48.538] INFO: number of dead pixels (per ROC): 0 0 1 0 1 0 1 0 0 79 0 0 1 0 1 0
[13:54:48.538] INFO: number of red-efficiency pixels: 220 157 282 603 713 635 788 326 366 615 394 304 309 165 62 66
[13:54:48.538] INFO: number of X-ray hits detected: 146152 104434 155547 246663 282036 293464 285273 189790 173923 221458 231672 188741 193099 117584 47482 53263
[13:54:48.538] INFO: number of triggers sent (total per ROC): 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000
[13:54:48.538] INFO: number of Vcal hits detected: 207755 207832 207643 207328 207122 207291 207029 207636 207608 203467 207583 207681 207622 207826 207887 207932
[13:54:48.538] INFO: Vcal hit fiducial efficiency (%): 99.9 99.9 99.9 99.7 99.6 99.7 99.6 99.8 99.8 99.7 99.8 99.9 99.9 99.9 100.0 100.0
[13:54:48.538] INFO: Vcal hit overall efficiency (%): 99.9 99.9 99.8 99.7 99.6 99.7 99.5 99.8 99.8 97.8 99.8 99.8 99.8 99.9 99.9 100.0
[13:54:48.538] INFO: X-ray hit rate [MHz/cm2]: 42.8 30.6 45.6 72.3 82.7 86.0 83.6 55.6 51.0 64.9 67.9 55.3 56.6 34.5 13.9 15.6
[13:54:48.538] INFO: PixTestHighRate::doXPixelAlive() done
[13:54:48.585] INFO: PixTest:: pg_setup set to default.
[13:54:48.599] INFO: enter test to run
[13:55:33.910] INFO: test: HighRate no parameter change
[13:55:33.910] INFO: running: highrate
[13:55:33.911] INFO: ----------------------------------------------------------------------
[13:55:33.911] INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[13:55:33.911] INFO: ----------------------------------------------------------------------
[13:55:33.920] INFO: ROC 2 masking pixel 48/50
[13:55:33.920] INFO: ROC 9 masking pixel 42/75
[13:55:33.920] INFO: ROC 12 masking pixel 3/38
[13:55:33.920] INFO: ROC 14 masking pixel 51/56
[13:55:34.526] INFO: Expecting 208000 events.
[13:55:51.048] INFO: 208000 events read in total (15995ms).
[13:55:51.056] INFO: Test took 17136ms.
[13:55:51.592] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:55:51.964] INFO: number of dead pixels (per ROC): 0 0 1 0 1 0 1 0 0 79 0 0 1 0 1 0
[13:55:51.965] INFO: number of red-efficiency pixels: 455 416 658 1514 1709 1496 1839 827 819 1398 938 703 710 435 104 91
[13:55:51.965] INFO: number of X-ray hits detected: 228179 162101 243597 382913 439228 457643 442822 294446 271089 344671 361424 292505 301235 183548 74713 83359
[13:55:51.965] INFO: number of triggers sent (total per ROC): 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000
[13:55:51.965] INFO: number of Vcal hits detected: 207430 207531 207089 205908 205476 205891 205109 206878 207048 202307 206891 207184 207133 207491 207842 207906
[13:55:51.965] INFO: Vcal hit fiducial efficiency (%): 99.8 99.8 99.6 99.1 98.9 99.1 98.8 99.5 99.6 99.2 99.5 99.7 99.7 99.8 100.0 100.0
[13:55:51.965] INFO: Vcal hit overall efficiency (%): 99.7 99.8 99.6 99.0 98.8 99.0 98.6 99.5 99.5 97.3 99.5 99.6 99.6 99.8 99.9 100.0
[13:55:51.965] INFO: X-ray hit rate [MHz/cm2]: 66.9 47.5 71.4 112.2 128.7 134.1 129.8 86.3 79.5 101.0 105.9 85.7 88.3 53.8 21.9 24.4
[13:55:51.965] INFO: PixTestHighRate::doXPixelAlive() done
[13:55:52.012] INFO: PixTest:: pg_setup set to default.
[13:55:52.030] INFO: enter test to run
[13:55:59.110] INFO: test: exit no parameter change
[13:55:59.490] QUIET: Connection to board 33 closed.
[13:55:59.491] INFO: pXar: this is the end, my friend