[13:37:03.358] INFO: *** Welcome to pxar ***
[13:37:03.358] INFO: *** Today: 2016/06/07
[13:37:03.376] INFO: *** Version: v1.9.0-814-g7497
[13:37:03.376] INFO: readRocDacs: /home/uicpirepix2/ProductionTestResults/M-K-3-28_FPIXTest-17C-FNAL-160601-1533_2016-06-01_15h33m_1464813187/000_FPIXTest_p17//dacParameters35_C0.dat .. /home/uicpirepix2/ProductionTestResults/M-K-3-28_FPIXTest-17C-FNAL-160601-1533_2016-06-01_15h33m_1464813187/000_FPIXTest_p17//dacParameters35_C15.dat
[13:37:03.416] INFO: readTbmDacs: /home/uicpirepix2/ProductionTestResults/M-K-3-28_FPIXTest-17C-FNAL-160601-1533_2016-06-01_15h33m_1464813187/000_FPIXTest_p17//tbmParameters_C0a.dat .. /home/uicpirepix2/ProductionTestResults/M-K-3-28_FPIXTest-17C-FNAL-160601-1533_2016-06-01_15h33m_1464813187/000_FPIXTest_p17//tbmParameters_C0b.dat
[13:37:03.417] INFO: readMaskFile: /home/uicpirepix2/ProductionTestResults/M-K-3-28_FPIXTest-17C-FNAL-160601-1533_2016-06-01_15h33m_1464813187/000_FPIXTest_p17//defaultMaskFile.dat
[13:37:03.421] INFO: readTrimFile: /home/uicpirepix2/ProductionTestResults/M-K-3-28_FPIXTest-17C-FNAL-160601-1533_2016-06-01_15h33m_1464813187/000_FPIXTest_p17//trimParameters35_C0.dat .. /home/uicpirepix2/ProductionTestResults/M-K-3-28_FPIXTest-17C-FNAL-160601-1533_2016-06-01_15h33m_1464813187/000_FPIXTest_p17//trimParameters35_C15.dat
[13:37:03.522] INFO: clk: 4
[13:37:03.522] INFO: ctr: 4
[13:37:03.522] INFO: sda: 19
[13:37:03.522] INFO: tin: 9
[13:37:03.522] INFO: level: 15
[13:37:03.522] INFO: triggerdelay: 0
[13:37:03.522] QUIET: Instanciating API for pxar v1.9.0+814~g74979a6
[13:37:03.522] INFO: Log level: INFO
[13:37:03.539] QUIET: Connection to board DTB_WREK4U opened.
[13:37:03.543] INFO: DTB startup information
--- DTB info------------------------------------------
Board id: 32
HW version: DTB1.2
FW version: 4.2
SW version: 4.5
USB id: DTB_WREK4U
MAC address: 40D855118020
Hostname: pixelDTB032
Comment:
------------------------------------------------------
[13:37:03.546] INFO: RPC call hashes of host and DTB match: 398089610
[13:37:05.074] INFO: DUT info:
[13:37:05.074] INFO: The DUT currently contains the following objects:
[13:37:05.074] INFO: 2 TBM Cores tbm08c (2 ON)
[13:37:05.074] INFO: TBM Core alpha (0): 7 registers set
[13:37:05.074] INFO: TBM Core beta (1): 7 registers set
[13:37:05.074] INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[13:37:05.074] INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[13:37:05.074] INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[13:37:05.074] INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[13:37:05.074] INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[13:37:05.074] INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[13:37:05.074] INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[13:37:05.074] INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[13:37:05.075] INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[13:37:05.075] INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[13:37:05.075] INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[13:37:05.075] INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[13:37:05.075] INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[13:37:05.075] INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[13:37:05.075] INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[13:37:05.075] INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[13:37:05.075] INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[13:37:05.478] INFO: enter 'restricted' command line mode
[13:37:05.478] INFO: enter test to run
[13:37:14.964] INFO: test: PixelAlive no parameter change
[13:37:14.964] INFO: running: pixelalive
[13:37:14.973] INFO: ----------------------------------------------------------------------
[13:37:14.973] 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:37:14.973] INFO: ----------------------------------------------------------------------
[13:37:15.298] INFO: Expecting 41600 events.
[13:37:19.616] INFO: 41600 events read in total (3599ms).
[13:37:19.781] INFO: Test took 4805ms.
[13:37:19.790] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:37:20.053] INFO: PixTestAlive::aliveTest() done
[13:37:20.053] INFO: number of dead pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 1 0 0 0 0 0
[13:37:20.082] INFO: enter test to run
[13:38:17.643] INFO: test: HighRate setting parameters: ->runsecondshotpixels=5;trimhotpixelthr=200<-
[13:38:17.644] INFO: running: highrate
[13:38:17.644] INFO: readGainPedestalParameters /home/uicpirepix2/ProductionTestResults/M-K-3-28_FPIXTest-17C-FNAL-160601-1533_2016-06-01_15h33m_1464813187/000_FPIXTest_p17//phCalibrationFitErr35_C0.dat .. /home/uicpirepix2/ProductionTestResults/M-K-3-28_FPIXTest-17C-FNAL-160601-1533_2016-06-01_15h33m_1464813187/000_FPIXTest_p17//phCalibrationFitErr35_C15.dat
[13:38:17.866] INFO: ----------------------------------------------------------------------
[13:38:17.866] INFO: PixTest::trimHotPixels() running for 5 seconds with 100 kHz trigger rate
[13:38:17.866] INFO: ----------------------------------------------------------------------
[13:38:17.866] INFO: THR = 200, corresponding to ~ 106.667 MHz/cm2
[13:38:17.866] INFO: edge/corner pixel THR is adjusted
[13:38:17.866] INFO: PixTestHighRate::trimHotPixels: step 0...
[13:38:18.825] INFO: Collecting data for 5 seconds...
[13:38:23.842] INFO: Done with hot pixel readout
[13:38:35.851] INFO: PixTest:: pg_setup set to default.
[13:38:35.852] INFO: 45 hot pixels found in step 0
[13:38:36.867] INFO: Collecting data for 5 seconds...
[13:38:41.883] INFO: Done with hot pixel readout
[13:38:53.906] INFO: PixTest:: pg_setup set to default.
[13:38:53.907] INFO: 35 hot pixels found in step 1
[13:38:54.898] INFO: Collecting data for 5 seconds...
[13:38:59.915] INFO: Done with hot pixel readout
[13:39:11.920] INFO: PixTest:: pg_setup set to default.
[13:39:11.921] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[13:39:11.921] INFO: 41 hot pixels found in step 2
[13:39:12.912] INFO: Collecting data for 5 seconds...
[13:39:17.929] INFO: Done with hot pixel readout
[13:39:29.946] INFO: PixTest:: pg_setup set to default.
[13:39:29.946] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[13:39:29.947] INFO: 44 hot pixels found in step 3
[13:39:30.937] INFO: Collecting data for 5 seconds...
[13:39:35.954] INFO: Done with hot pixel readout
[13:39:47.956] INFO: PixTest:: pg_setup set to default.
[13:39:47.957] INFO: 43 hot pixels found in step 4
[13:39:48.947] INFO: Collecting data for 5 seconds...
[13:39:53.963] INFO: Done with hot pixel readout
[13:40:05.909] INFO: PixTest:: pg_setup set to default.
[13:40:05.909] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[13:40:05.909] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[13:40:05.910] INFO: 30 hot pixels found in step 5
[13:40:06.900] INFO: Collecting data for 5 seconds...
[13:40:11.917] INFO: Done with hot pixel readout
[13:40:23.898] INFO: PixTest:: pg_setup set to default.
[13:40:23.898] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[13:40:23.898] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[13:40:23.898] INFO: 36 hot pixels found in step 6
[13:40:24.888] INFO: Collecting data for 5 seconds...
[13:40:29.905] INFO: Done with hot pixel readout
[13:40:42.419] INFO: PixTest:: pg_setup set to default.
[13:40:42.419] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[13:40:42.419] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[13:40:42.420] INFO: 19 hot pixels found in step 7
[13:40:43.411] INFO: Collecting data for 5 seconds...
[13:40:48.429] INFO: Done with hot pixel readout
[13:41:00.014] INFO: PixTest:: pg_setup set to default.
[13:41:00.014] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[13:41:00.014] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[13:41:00.015] INFO: 20 hot pixels found in step 8
[13:41:01.005] INFO: Collecting data for 5 seconds...
[13:41:06.023] INFO: Done with hot pixel readout
[13:41:17.962] INFO: PixTest:: pg_setup set to default.
[13:41:17.962] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[13:41:17.963] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[13:41:17.963] INFO: 19 hot pixels found in step 9
[13:41:18.954] INFO: Collecting data for 5 seconds...
[13:41:23.971] INFO: Done with hot pixel readout
[13:41:35.830] INFO: PixTest:: pg_setup set to default.
[13:41:35.830] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[13:41:35.830] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[13:41:35.830] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[13:41:35.831] INFO: 22 hot pixels found in step 10
[13:41:36.822] INFO: Collecting data for 5 seconds...
[13:41:41.840] INFO: Done with hot pixel readout
[13:41:53.969] INFO: PixTest:: pg_setup set to default.
[13:41:53.970] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[13:41:53.970] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[13:41:53.970] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[13:41:53.970] INFO: 22 hot pixels found in step 11
[13:41:54.961] INFO: Collecting data for 5 seconds...
[13:41:59.979] INFO: Done with hot pixel readout
[13:42:11.218] INFO: PixTest:: pg_setup set to default.
[13:42:11.218] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[13:42:11.219] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[13:42:11.219] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[13:42:11.221] INFO: 23 hot pixels found in step 12
[13:42:12.214] INFO: Collecting data for 5 seconds...
[13:42:17.234] INFO: Done with hot pixel readout
[13:42:29.390] INFO: PixTest:: pg_setup set to default.
[13:42:29.390] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[13:42:29.390] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[13:42:29.390] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[13:42:29.390] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[13:42:29.390] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[13:42:29.390] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[13:42:29.390] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[13:42:29.391] INFO: 24 hot pixels found in step 13
[13:42:30.382] INFO: Collecting data for 5 seconds...
[13:42:35.401] INFO: Done with hot pixel readout
[13:42:47.686] INFO: PixTest:: pg_setup set to default.
[13:42:47.686] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[13:42:47.686] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[13:42:47.686] INFO: 12 hot pixels found in step 14
[13:42:47.722] INFO: 12 hot pixels could not be trimmed and have been masked.
[13:42:47.725] INFO: PixTest::trimHotPixels() done
[13:42:47.731] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-K-3-28_FPIXTest-17C-FNAL-160601-1533_2016-06-01_15h33m_1464813187/000_FPIXTest_p17//trimParameters35_C0.dat
[13:42:47.737] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-K-3-28_FPIXTest-17C-FNAL-160601-1533_2016-06-01_15h33m_1464813187/000_FPIXTest_p17//trimParameters35_C1.dat
[13:42:47.742] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-K-3-28_FPIXTest-17C-FNAL-160601-1533_2016-06-01_15h33m_1464813187/000_FPIXTest_p17//trimParameters35_C2.dat
[13:42:47.749] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-K-3-28_FPIXTest-17C-FNAL-160601-1533_2016-06-01_15h33m_1464813187/000_FPIXTest_p17//trimParameters35_C3.dat
[13:42:47.754] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-K-3-28_FPIXTest-17C-FNAL-160601-1533_2016-06-01_15h33m_1464813187/000_FPIXTest_p17//trimParameters35_C4.dat
[13:42:47.759] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-K-3-28_FPIXTest-17C-FNAL-160601-1533_2016-06-01_15h33m_1464813187/000_FPIXTest_p17//trimParameters35_C5.dat
[13:42:47.765] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-K-3-28_FPIXTest-17C-FNAL-160601-1533_2016-06-01_15h33m_1464813187/000_FPIXTest_p17//trimParameters35_C6.dat
[13:42:47.770] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-K-3-28_FPIXTest-17C-FNAL-160601-1533_2016-06-01_15h33m_1464813187/000_FPIXTest_p17//trimParameters35_C7.dat
[13:42:47.776] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-K-3-28_FPIXTest-17C-FNAL-160601-1533_2016-06-01_15h33m_1464813187/000_FPIXTest_p17//trimParameters35_C8.dat
[13:42:47.781] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-K-3-28_FPIXTest-17C-FNAL-160601-1533_2016-06-01_15h33m_1464813187/000_FPIXTest_p17//trimParameters35_C9.dat
[13:42:47.787] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-K-3-28_FPIXTest-17C-FNAL-160601-1533_2016-06-01_15h33m_1464813187/000_FPIXTest_p17//trimParameters35_C10.dat
[13:42:47.792] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-K-3-28_FPIXTest-17C-FNAL-160601-1533_2016-06-01_15h33m_1464813187/000_FPIXTest_p17//trimParameters35_C11.dat
[13:42:47.797] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-K-3-28_FPIXTest-17C-FNAL-160601-1533_2016-06-01_15h33m_1464813187/000_FPIXTest_p17//trimParameters35_C12.dat
[13:42:47.803] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-K-3-28_FPIXTest-17C-FNAL-160601-1533_2016-06-01_15h33m_1464813187/000_FPIXTest_p17//trimParameters35_C13.dat
[13:42:47.809] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-K-3-28_FPIXTest-17C-FNAL-160601-1533_2016-06-01_15h33m_1464813187/000_FPIXTest_p17//trimParameters35_C14.dat
[13:42:47.814] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-K-3-28_FPIXTest-17C-FNAL-160601-1533_2016-06-01_15h33m_1464813187/000_FPIXTest_p17//trimParameters35_C15.dat
[13:42:47.819] INFO: write masked pixels into /home/uicpirepix2/ProductionTestResults/M-K-3-28_FPIXTest-17C-FNAL-160601-1533_2016-06-01_15h33m_1464813187/000_FPIXTest_p17//defaultMaskFile.dat
[13:42:47.829] INFO: enter test to run
[13:45:25.616] INFO: test: HighRate setting parameters: ->runsecondshotpixels=1;trimhotpixelthr=200<-
[13:45:25.616] INFO: running: highrate
[13:45:25.621] INFO: ----------------------------------------------------------------------
[13:45:25.621] INFO: PixTest::trimHotPixels() running for 1 seconds with 100 kHz trigger rate
[13:45:25.621] INFO: ----------------------------------------------------------------------
[13:45:25.621] INFO: THR = 200, corresponding to ~ 533.333 MHz/cm2
[13:45:25.621] INFO: edge/corner pixel THR is adjusted
[13:45:25.621] INFO: PixTestHighRate::trimHotPixels: step 0...
[13:45:26.579] INFO: Collecting data for 1 seconds...
[13:45:27.583] INFO: Done with hot pixel readout
[13:45:31.767] INFO: PixTest:: pg_setup set to default.
[13:45:31.767] INFO: 0 hot pixels found in step 0
[13:45:31.772] INFO: 0 hot pixels could not be trimmed and have been masked.
[13:45:31.855] INFO: PixTest::trimHotPixels() done
[13:45:31.855] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-K-3-28_FPIXTest-17C-FNAL-160601-1533_2016-06-01_15h33m_1464813187/000_FPIXTest_p17//trimParameters35_C0.dat
[13:45:31.868] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-K-3-28_FPIXTest-17C-FNAL-160601-1533_2016-06-01_15h33m_1464813187/000_FPIXTest_p17//trimParameters35_C1.dat
[13:45:31.874] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-K-3-28_FPIXTest-17C-FNAL-160601-1533_2016-06-01_15h33m_1464813187/000_FPIXTest_p17//trimParameters35_C2.dat
[13:45:31.879] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-K-3-28_FPIXTest-17C-FNAL-160601-1533_2016-06-01_15h33m_1464813187/000_FPIXTest_p17//trimParameters35_C3.dat
[13:45:31.885] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-K-3-28_FPIXTest-17C-FNAL-160601-1533_2016-06-01_15h33m_1464813187/000_FPIXTest_p17//trimParameters35_C4.dat
[13:45:31.890] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-K-3-28_FPIXTest-17C-FNAL-160601-1533_2016-06-01_15h33m_1464813187/000_FPIXTest_p17//trimParameters35_C5.dat
[13:45:31.895] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-K-3-28_FPIXTest-17C-FNAL-160601-1533_2016-06-01_15h33m_1464813187/000_FPIXTest_p17//trimParameters35_C6.dat
[13:45:31.901] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-K-3-28_FPIXTest-17C-FNAL-160601-1533_2016-06-01_15h33m_1464813187/000_FPIXTest_p17//trimParameters35_C7.dat
[13:45:31.906] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-K-3-28_FPIXTest-17C-FNAL-160601-1533_2016-06-01_15h33m_1464813187/000_FPIXTest_p17//trimParameters35_C8.dat
[13:45:31.911] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-K-3-28_FPIXTest-17C-FNAL-160601-1533_2016-06-01_15h33m_1464813187/000_FPIXTest_p17//trimParameters35_C9.dat
[13:45:31.917] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-K-3-28_FPIXTest-17C-FNAL-160601-1533_2016-06-01_15h33m_1464813187/000_FPIXTest_p17//trimParameters35_C10.dat
[13:45:31.923] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-K-3-28_FPIXTest-17C-FNAL-160601-1533_2016-06-01_15h33m_1464813187/000_FPIXTest_p17//trimParameters35_C11.dat
[13:45:31.928] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-K-3-28_FPIXTest-17C-FNAL-160601-1533_2016-06-01_15h33m_1464813187/000_FPIXTest_p17//trimParameters35_C12.dat
[13:45:31.933] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-K-3-28_FPIXTest-17C-FNAL-160601-1533_2016-06-01_15h33m_1464813187/000_FPIXTest_p17//trimParameters35_C13.dat
[13:45:31.939] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-K-3-28_FPIXTest-17C-FNAL-160601-1533_2016-06-01_15h33m_1464813187/000_FPIXTest_p17//trimParameters35_C14.dat
[13:45:31.944] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-K-3-28_FPIXTest-17C-FNAL-160601-1533_2016-06-01_15h33m_1464813187/000_FPIXTest_p17//trimParameters35_C15.dat
[13:45:31.950] INFO: write masked pixels into /home/uicpirepix2/ProductionTestResults/M-K-3-28_FPIXTest-17C-FNAL-160601-1533_2016-06-01_15h33m_1464813187/000_FPIXTest_p17//defaultMaskFile.dat
[13:45:31.960] INFO: enter test to run
[13:46:09.408] INFO: test: Xray setting parameters: ->source=DCLowRate<-
[13:46:09.408] INFO: running: xray
[13:46:09.409] INFO: ----------------------------------------------------------------------
[13:46:09.409] INFO: PixTestXray::doPhRun() fParRunSeconds = 100
[13:46:09.409] INFO: ----------------------------------------------------------------------
[13:46:10.372] INFO: PixTestXray::doPhRun start TriggerLoop with trigger frequency 100 kHz, period 405 and duration 100 seconds, fEventsMax = 10000000
[13:46:21.593] INFO: run duration 11 seconds, buffer almost full (81%), pausing triggers.
[13:46:51.143] INFO: Resuming triggers.
[13:47:02.361] INFO: run duration 22 seconds, buffer almost full (81%), pausing triggers.
[13:47:31.786] INFO: Resuming triggers.
[13:47:43.007] INFO: run duration 33 seconds, buffer almost full (81%), pausing triggers.
[13:48:12.638] INFO: Resuming triggers.
[13:48:23.857] INFO: run duration 44 seconds, buffer almost full (81%), pausing triggers.
[13:48:53.320] INFO: Resuming triggers.
[13:49:04.542] INFO: run duration 55 seconds, buffer almost full (81%), pausing triggers.
[13:49:34.086] INFO: Resuming triggers.
[13:49:45.306] INFO: run duration 67 seconds, buffer almost full (81%), pausing triggers.
[13:50:14.451] INFO: Resuming triggers.
[13:50:25.670] INFO: run duration 78 seconds, buffer almost full (81%), pausing triggers.
[13:50:54.312] INFO: Resuming triggers.
[13:51:05.532] INFO: run duration 89 seconds, buffer almost full (81%), pausing triggers.
[13:51:34.996] INFO: Resuming triggers.
[13:51:45.559] INFO: data taking finished, elapsed time: 100 seconds.
[13:52:13.815] INFO: PixTest:: pg_setup set to default.
[13:52:13.818] INFO: PixTestXray::doPhRun() done
[13:52:14.006] INFO: enter test to run
[13:52:51.541] INFO: test: Xray setting parameters: ->source=DCHighRate<-
[13:52:51.541] INFO: running: xray
[13:52:51.542] INFO: ----------------------------------------------------------------------
[13:52:51.542] INFO: PixTestXray::doPhRun() fParRunSeconds = 100
[13:52:51.542] INFO: ----------------------------------------------------------------------
[13:52:52.506] INFO: PixTestXray::doPhRun start TriggerLoop with trigger frequency 100 kHz, period 405 and duration 100 seconds, fEventsMax = 10000000
[13:52:58.839] INFO: run duration 6 seconds, buffer almost full (81%), pausing triggers.
[13:53:29.725] INFO: Resuming triggers.
[13:53:36.060] INFO: run duration 12 seconds, buffer almost full (81%), pausing triggers.
[13:54:07.544] INFO: Resuming triggers.
[13:54:13.881] INFO: run duration 18 seconds, buffer almost full (81%), pausing triggers.
[13:54:45.047] INFO: Resuming triggers.
[13:54:51.385] INFO: run duration 25 seconds, buffer almost full (81%), pausing triggers.
[13:55:22.820] INFO: Resuming triggers.
[13:55:29.157] INFO: run duration 31 seconds, buffer almost full (81%), pausing triggers.
[13:55:59.203] INFO: Resuming triggers.
[13:56:05.539] INFO: run duration 37 seconds, buffer almost full (81%), pausing triggers.
[13:56:35.629] INFO: Resuming triggers.
[13:56:41.965] INFO: run duration 44 seconds, buffer almost full (81%), pausing triggers.
[13:57:11.894] INFO: Resuming triggers.
[13:57:18.231] INFO: run duration 50 seconds, buffer almost full (81%), pausing triggers.
[13:57:48.281] INFO: Resuming triggers.
[13:57:54.617] INFO: run duration 56 seconds, buffer almost full (81%), pausing triggers.
[13:58:24.709] INFO: Resuming triggers.
[13:58:31.046] INFO: run duration 63 seconds, buffer almost full (81%), pausing triggers.
[13:59:01.203] INFO: Resuming triggers.
[13:59:07.539] INFO: run duration 69 seconds, buffer almost full (81%), pausing triggers.
[13:59:37.568] INFO: Resuming triggers.
[13:59:43.904] INFO: run duration 75 seconds, buffer almost full (81%), pausing triggers.
[14:00:13.916] INFO: Resuming triggers.
[14:00:20.249] INFO: run duration 82 seconds, buffer almost full (81%), pausing triggers.
[14:00:50.283] INFO: Resuming triggers.
[14:00:56.617] INFO: run duration 88 seconds, buffer almost full (81%), pausing triggers.
[14:01:26.733] INFO: Resuming triggers.
[14:01:33.064] INFO: run duration 94 seconds, buffer almost full (81%), pausing triggers.
[14:02:03.054] INFO: Resuming triggers.
[14:02:08.360] INFO: data taking finished, elapsed time: 100 seconds.
[14:02:33.714] INFO: PixTest:: pg_setup set to default.
[14:02:33.717] INFO: PixTestXray::doPhRun() done
[14:02:33.865] INFO: enter test to run
[14:03:37.184] INFO: test: HighRate no parameter change
[14:03:37.184] INFO: running: highrate
[14:03:37.199] INFO: ----------------------------------------------------------------------
[14:03:37.199] INFO: PixTestHighRate::calDelScan() ntrig = 10, vcal = 200
[14:03:37.199] INFO: ----------------------------------------------------------------------
[14:03:37.441] INFO: Expecting 768 events.
[14:03:38.575] INFO: 768 events read in total (419ms).
[14:03:38.575] INFO: Test took 1288ms.
[14:03:39.378] INFO: Expecting 41600 events.
[14:03:42.463] INFO: 41600 events read in total (2559ms).
[14:03:42.464] INFO: Test took 3880ms.
[14:03:42.499] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:03:43.224] INFO: Expecting 41600 events.
[14:03:46.512] INFO: 41600 events read in total (2761ms).
[14:03:46.513] INFO: Test took 3996ms.
[14:03:46.549] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:03:47.270] INFO: Expecting 41600 events.
[14:03:50.527] INFO: 41600 events read in total (2730ms).
[14:03:50.528] INFO: Test took 3961ms.
[14:03:50.568] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:03:51.278] INFO: Expecting 41600 events.
[14:03:54.553] INFO: 41600 events read in total (2748ms).
[14:03:54.554] INFO: Test took 3962ms.
[14:03:54.591] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:03:55.304] INFO: Expecting 41600 events.
[14:03:58.527] INFO: 41600 events read in total (2696ms).
[14:03:58.528] INFO: Test took 3919ms.
[14:03:58.565] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:03:59.276] INFO: Expecting 41600 events.
[14:04:02.571] INFO: 41600 events read in total (2768ms).
[14:04:02.572] INFO: Test took 3988ms.
[14:04:02.609] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:04:03.323] INFO: Expecting 41600 events.
[14:04:06.680] INFO: 41600 events read in total (2830ms).
[14:04:06.681] INFO: Test took 4054ms.
[14:04:06.720] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:04:07.432] INFO: Expecting 41600 events.
[14:04:10.687] INFO: 41600 events read in total (2728ms).
[14:04:10.688] INFO: Test took 3950ms.
[14:04:10.725] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:04:11.437] INFO: Expecting 41600 events.
[14:04:14.816] INFO: 41600 events read in total (2852ms).
[14:04:14.817] INFO: Test took 4074ms.
[14:04:14.854] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:04:15.568] INFO: Expecting 41600 events.
[14:04:18.826] INFO: 41600 events read in total (2732ms).
[14:04:18.827] INFO: Test took 3954ms.
[14:04:18.865] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:04:19.581] INFO: Expecting 41600 events.
[14:04:22.737] INFO: 41600 events read in total (2629ms).
[14:04:22.738] INFO: Test took 3854ms.
[14:04:22.775] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:04:23.490] INFO: Expecting 41600 events.
[14:04:26.779] INFO: 41600 events read in total (2762ms).
[14:04:26.780] INFO: Test took 3986ms.
[14:04:26.826] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:04:27.530] INFO: Expecting 41600 events.
[14:04:30.821] INFO: 41600 events read in total (2765ms).
[14:04:30.823] INFO: Test took 3977ms.
[14:04:30.862] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:04:31.575] INFO: Expecting 41600 events.
[14:04:34.906] INFO: 41600 events read in total (2804ms).
[14:04:34.907] INFO: Test took 4026ms.
[14:04:34.945] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:04:35.661] INFO: Expecting 41600 events.
[14:04:38.857] INFO: 41600 events read in total (2670ms).
[14:04:38.858] INFO: Test took 3895ms.
[14:04:38.897] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:04:39.614] INFO: Expecting 41600 events.
[14:04:42.936] INFO: 41600 events read in total (2795ms).
[14:04:42.937] INFO: Test took 4021ms.
[14:04:42.974] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:04:43.676] INFO: Expecting 41600 events.
[14:04:46.933] INFO: 41600 events read in total (2730ms).
[14:04:46.934] INFO: Test took 3941ms.
[14:04:46.972] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:04:47.690] INFO: Expecting 41600 events.
[14:04:50.999] INFO: 41600 events read in total (2783ms).
[14:04:50.000] INFO: Test took 4008ms.
[14:04:51.037] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:04:51.751] INFO: Expecting 41600 events.
[14:04:54.000] INFO: 41600 events read in total (2722ms).
[14:04:54.001] INFO: Test took 3945ms.
[14:04:55.039] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:04:55.755] INFO: Expecting 41600 events.
[14:04:58.923] INFO: 41600 events read in total (2642ms).
[14:04:58.924] INFO: Test took 3867ms.
[14:04:58.963] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:04:59.314] INFO: enter test to run
[14:05:13.688] INFO: test: HighRate no parameter change
[14:05:13.688] INFO: running: highrate
[14:05:13.689] INFO: ----------------------------------------------------------------------
[14:05:13.689] INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[14:05:13.689] INFO: ----------------------------------------------------------------------
[14:05:14.320] INFO: Expecting 208000 events.
[14:05:26.371] INFO: 208000 events read in total (11525ms).
[14:05:26.375] INFO: Test took 12675ms.
[14:05:26.539] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:05:26.798] INFO: number of dead pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 1 0 0 0 0 0
[14:05:26.798] INFO: number of red-efficiency pixels: 99 57 94 163 157 169 198 111 92 125 108 104 123 71 25 21
[14:05:26.799] INFO: number of X-ray hits detected: 78325 49300 76571 123022 127721 130195 128031 88130 81978 98368 99176 88213 89555 53584 25949 26897
[14:05:26.799] INFO: number of triggers sent (total per ROC): 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000
[14:05:26.799] INFO: number of Vcal hits detected: 207896 207942 207904 207833 207840 207827 207792 207887 207907 207873 207840 207894 207875 207927 207974 207979
[14:05:26.799] INFO: Vcal hit fiducial efficiency (%): 100.0 100.0 100.0 99.9 99.9 99.9 99.9 99.9 100.0 99.9 100.0 100.0 99.9 100.0 100.0 100.0
[14:05:26.799] INFO: Vcal hit overall efficiency (%): 100.0 100.0 100.0 99.9 99.9 99.9 99.9 99.9 100.0 99.9 99.9 99.9 99.9 100.0 100.0 100.0
[14:05:26.799] INFO: X-ray hit rate [MHz/cm2]: 23.0 14.5 22.4 36.1 37.4 38.2 37.5 25.8 24.0 28.8 29.1 25.9 26.2 15.7 7.6 7.9
[14:05:26.799] INFO: PixTestHighRate::doXPixelAlive() done
[14:05:26.844] INFO: PixTest:: pg_setup set to default.
[14:05:26.857] INFO: enter test to run
[14:05:58.983] INFO: test: HighRate no parameter change
[14:05:58.983] INFO: running: highrate
[14:05:58.985] INFO: ----------------------------------------------------------------------
[14:05:58.985] INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[14:05:58.985] INFO: ----------------------------------------------------------------------
[14:05:59.609] INFO: Expecting 208000 events.
[14:06:13.831] INFO: 208000 events read in total (13695ms).
[14:06:13.836] INFO: Test took 14841ms.
[14:06:14.160] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:06:14.472] INFO: number of dead pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 1 0 0 0 0 0
[14:06:14.472] INFO: number of red-efficiency pixels: 241 155 263 579 540 495 679 311 227 385 292 261 313 191 58 56
[14:06:14.472] INFO: number of X-ray hits detected: 161464 101061 157258 251751 261556 267858 262396 180445 169341 202823 203741 182418 185463 111301 53164 56007
[14:06:14.472] INFO: number of triggers sent (total per ROC): 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000
[14:06:14.472] INFO: number of Vcal hits detected: 207748 207838 207706 207356 207398 207475 207233 207671 207760 207587 207640 207735 207673 207786 207940 207943
[14:06:14.472] INFO: Vcal hit fiducial efficiency (%): 99.9 99.9 99.9 99.7 99.7 99.8 99.7 99.9 99.9 99.8 99.9 99.9 99.9 99.9 100.0 100.0
[14:06:14.472] INFO: Vcal hit overall efficiency (%): 99.9 99.9 99.9 99.7 99.7 99.7 99.6 99.8 99.9 99.8 99.8 99.9 99.8 99.9 100.0 100.0
[14:06:14.472] INFO: X-ray hit rate [MHz/cm2]: 47.3 29.6 46.1 73.8 76.7 78.5 76.9 52.9 49.6 59.4 59.7 53.5 54.4 32.6 15.6 16.4
[14:06:14.472] INFO: PixTestHighRate::doXPixelAlive() done
[14:06:14.518] INFO: PixTest:: pg_setup set to default.
[14:06:14.538] INFO: enter test to run
[14:06:36.567] INFO: test: HighRate no parameter change
[14:06:36.567] INFO: running: highrate
[14:06:36.568] INFO: ----------------------------------------------------------------------
[14:06:36.568] INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[14:06:36.568] INFO: ----------------------------------------------------------------------
[14:06:37.192] INFO: Expecting 208000 events.
[14:06:53.589] INFO: 208000 events read in total (15870ms).
[14:06:53.598] INFO: Test took 17021ms.
[14:06:54.134] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:06:54.500] INFO: number of dead pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 1 0 0 0 0 0
[14:06:54.500] INFO: number of red-efficiency pixels: 530 360 629 1401 1361 1183 1589 673 501 983 683 660 799 493 102 91
[14:06:54.500] INFO: number of X-ray hits detected: 243676 153559 237657 379643 395535 404457 396043 274742 257136 307661 308554 275078 280858 168167 80788 84059
[14:06:54.500] INFO: number of triggers sent (total per ROC): 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000
[14:06:54.500] INFO: number of Vcal hits detected: 207368 207589 207187 206116 206213 206489 205804 207185 207445 206792 207174 207255 207064 207404 207891 207907
[14:06:54.500] INFO: Vcal hit fiducial efficiency (%): 99.7 99.8 99.6 99.2 99.2 99.3 99.0 99.6 99.7 99.5 99.7 99.7 99.6 99.7 100.0 100.0
[14:06:54.500] INFO: Vcal hit overall efficiency (%): 99.7 99.8 99.6 99.1 99.1 99.3 98.9 99.6 99.7 99.4 99.6 99.6 99.6 99.7 99.9 100.0
[14:06:54.500] INFO: X-ray hit rate [MHz/cm2]: 71.4 45.0 69.7 111.3 115.9 118.5 116.1 80.5 75.4 90.2 90.4 80.6 82.3 49.3 23.7 24.6
[14:06:54.500] INFO: PixTestHighRate::doXPixelAlive() done
[14:06:54.548] INFO: PixTest:: pg_setup set to default.
[14:06:54.565] INFO: enter test to run
[14:07:04.079] INFO: test: exit no parameter change
[14:07:04.426] QUIET: Connection to board 32 closed.
[14:07:04.427] INFO: pXar: this is the end, my friend