[13:03:24.168] INFO: *** Welcome to pxar ***
[13:03:24.168] INFO: *** Today: 2016/05/20
[13:03:24.197] INFO: *** Version: v1.9.0-796-gef167-dirty
[13:03:24.197] INFO: readRocDacs: /home/uicpirepix2/ProductionTestResults/M-Y-S-ND_FPIXTest-17C-FNAL-160517-0929_2016-05-17_09h29m_1463495357/000_FPIXTest_p17//dacParameters35_C0.dat .. /home/uicpirepix2/ProductionTestResults/M-Y-S-ND_FPIXTest-17C-FNAL-160517-0929_2016-05-17_09h29m_1463495357/000_FPIXTest_p17//dacParameters35_C15.dat
[13:03:24.221] INFO: readTbmDacs: /home/uicpirepix2/ProductionTestResults/M-Y-S-ND_FPIXTest-17C-FNAL-160517-0929_2016-05-17_09h29m_1463495357/000_FPIXTest_p17//tbmParameters_C0a.dat .. /home/uicpirepix2/ProductionTestResults/M-Y-S-ND_FPIXTest-17C-FNAL-160517-0929_2016-05-17_09h29m_1463495357/000_FPIXTest_p17//tbmParameters_C0b.dat
[13:03:24.221] INFO: readMaskFile: /home/uicpirepix2/ProductionTestResults/M-Y-S-ND_FPIXTest-17C-FNAL-160517-0929_2016-05-17_09h29m_1463495357/000_FPIXTest_p17//defaultMaskFile.dat
[13:03:24.228] INFO: readTrimFile: /home/uicpirepix2/ProductionTestResults/M-Y-S-ND_FPIXTest-17C-FNAL-160517-0929_2016-05-17_09h29m_1463495357/000_FPIXTest_p17//trimParameters35_C0.dat .. /home/uicpirepix2/ProductionTestResults/M-Y-S-ND_FPIXTest-17C-FNAL-160517-0929_2016-05-17_09h29m_1463495357/000_FPIXTest_p17//trimParameters35_C15.dat
[13:03:24.317] INFO: clk: 4
[13:03:24.317] INFO: ctr: 4
[13:03:24.317] INFO: sda: 19
[13:03:24.317] INFO: tin: 9
[13:03:24.317] INFO: level: 15
[13:03:24.318] INFO: triggerdelay: 0
[13:03:24.318] QUIET: Instanciating API for pxar v1.9.0+796~gef16747
[13:03:24.318] INFO: Log level: INFO
[13:03:24.337] QUIET: Connection to board DTB_WREK4U opened.
[13:03:24.340] 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:03:24.343] INFO: RPC call hashes of host and DTB match: 398089610
[13:03:25.874] INFO: DUT info:
[13:03:25.875] INFO: The DUT currently contains the following objects:
[13:03:25.875] INFO: 2 TBM Cores tbm08c (2 ON)
[13:03:25.875] INFO: TBM Core alpha (0): 7 registers set
[13:03:25.875] INFO: TBM Core beta (1): 7 registers set
[13:03:25.875] INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[13:03:25.875] INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[13:03:25.875] INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[13:03:25.875] INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[13:03:25.875] INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[13:03:25.875] INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[13:03:25.875] INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[13:03:25.875] INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[13:03:25.875] INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[13:03:25.875] INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[13:03:25.875] INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[13:03:25.875] INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[13:03:25.875] INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[13:03:25.875] INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[13:03:25.875] INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[13:03:25.875] INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[13:03:25.875] INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[13:03:26.278] INFO: enter 'restricted' command line mode
[13:03:26.278] INFO: enter test to run
[13:03:28.390] INFO: test: PixelAlive no parameter change
[13:03:28.390] INFO: running: pixelalive
[13:03:28.399] INFO: ----------------------------------------------------------------------
[13:03:28.399] 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:03:28.399] INFO: ----------------------------------------------------------------------
[13:03:28.721] INFO: Expecting 41600 events.
[13:03:33.025] INFO: 41600 events read in total (3585ms).
[13:03:33.193] INFO: Test took 4791ms.
[13:03:33.207] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:03:33.471] INFO: PixTestAlive::aliveTest() done
[13:03:33.471] INFO: number of dead pixels (per ROC): 0 0 1 0 0 0 1 0 0 0 0 3 0 0 0 0
[13:03:33.504] INFO: enter test to run
[13:04:17.389] INFO: test: HighRate setting parameters: ->runsecondshotpixels=5;trimhotpixelthr=200<-
[13:04:17.390] INFO: running: highrate
[13:04:17.390] INFO: readGainPedestalParameters /home/uicpirepix2/ProductionTestResults/M-Y-S-ND_FPIXTest-17C-FNAL-160517-0929_2016-05-17_09h29m_1463495357/000_FPIXTest_p17//phCalibrationFitErr35_C0.dat .. /home/uicpirepix2/ProductionTestResults/M-Y-S-ND_FPIXTest-17C-FNAL-160517-0929_2016-05-17_09h29m_1463495357/000_FPIXTest_p17//phCalibrationFitErr35_C15.dat
[13:04:17.588] INFO: ----------------------------------------------------------------------
[13:04:17.588] INFO: PixTest::trimHotPixels() running for 5 seconds with 100 kHz trigger rate
[13:04:17.588] INFO: ----------------------------------------------------------------------
[13:04:17.588] INFO: THR = 200, corresponding to ~ 106.667 MHz/cm2
[13:04:17.588] INFO: edge/corner pixel THR is adjusted
[13:04:17.588] INFO: PixTestHighRate::trimHotPixels: step 0...
[13:04:18.548] INFO: Collecting data for 5 seconds...
[13:04:23.564] INFO: Done with hot pixel readout
[13:04:36.188] INFO: PixTest:: pg_setup set to default.
[13:04:36.188] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[13:04:36.188] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[13:04:36.189] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[13:04:36.189] INFO: 241 hot pixels found in step 0
[13:04:37.188] INFO: Collecting data for 5 seconds...
[13:04:42.205] INFO: Done with hot pixel readout
[13:04:54.743] INFO: PixTest:: pg_setup set to default.
[13:04:54.743] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[13:04:54.743] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[13:04:54.743] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[13:04:54.743] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[13:04:54.744] INFO: 223 hot pixels found in step 1
[13:04:55.743] INFO: Collecting data for 5 seconds...
[13:05:00.760] INFO: Done with hot pixel readout
[13:05:13.544] INFO: PixTest:: pg_setup set to default.
[13:05:13.544] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[13:05:13.544] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[13:05:13.544] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[13:05:13.544] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[13:05:13.544] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[13:05:13.544] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[13:05:13.544] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[13:05:13.545] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[13:05:13.545] INFO: 205 hot pixels found in step 2
[13:05:14.545] INFO: Collecting data for 5 seconds...
[13:05:19.561] INFO: Done with hot pixel readout
[13:05:32.423] INFO: PixTest:: pg_setup set to default.
[13:05:32.423] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[13:05:32.423] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[13:05:32.423] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[13:05:32.423] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[13:05:32.423] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[13:05:32.423] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[13:05:32.424] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[13:05:32.424] INFO: 203 hot pixels found in step 3
[13:05:33.425] INFO: Collecting data for 5 seconds...
[13:05:38.441] INFO: Done with hot pixel readout
[13:05:51.382] INFO: PixTest:: pg_setup set to default.
[13:05:51.382] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[13:05:51.382] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[13:05:51.383] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[13:05:51.383] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[13:05:51.383] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[13:05:51.383] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[13:05:51.383] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[13:05:51.383] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[13:05:51.383] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[13:05:51.383] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[13:05:51.383] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[13:05:51.383] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[13:05:51.383] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[13:05:51.383] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[13:05:51.383] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[13:05:51.383] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[13:05:51.383] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[13:05:51.384] INFO: 197 hot pixels found in step 4
[13:05:52.387] INFO: Collecting data for 5 seconds...
[13:05:57.404] INFO: Done with hot pixel readout
[13:06:10.273] INFO: PixTest:: pg_setup set to default.
[13:06:10.273] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[13:06:10.273] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[13:06:10.273] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[13:06:10.273] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[13:06:10.273] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[13:06:10.273] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[13:06:10.273] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[13:06:10.273] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[13:06:10.273] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[13:06:10.273] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[13:06:10.273] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[13:06:10.274] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[13:06:10.274] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[13:06:10.274] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[13:06:10.274] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[13:06:10.274] INFO: 170 hot pixels found in step 5
[13:06:11.275] INFO: Collecting data for 5 seconds...
[13:06:16.292] INFO: Done with hot pixel readout
[13:06:28.807] INFO: PixTest:: pg_setup set to default.
[13:06:28.807] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[13:06:28.807] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[13:06:28.807] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[13:06:28.807] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[13:06:28.807] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[13:06:28.807] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[13:06:28.807] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[13:06:28.808] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[13:06:28.808] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[13:06:28.808] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[13:06:28.808] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[13:06:28.808] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[13:06:28.808] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[13:06:28.808] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[13:06:28.808] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[13:06:28.808] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[13:06:28.808] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[13:06:28.808] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[13:06:28.808] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[13:06:28.808] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[13:06:28.808] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[13:06:28.808] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[13:06:28.808] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[13:06:28.808] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[13:06:28.808] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[13:06:28.808] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[13:06:28.808] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[13:06:28.809] INFO: 158 hot pixels found in step 6
[13:06:29.809] INFO: Collecting data for 5 seconds...
[13:06:34.826] INFO: Done with hot pixel readout
[13:06:47.596] INFO: PixTest:: pg_setup set to default.
[13:06:47.596] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[13:06:47.596] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[13:06:47.596] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[13:06:47.596] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[13:06:47.596] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[13:06:47.596] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[13:06:47.596] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[13:06:47.596] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[13:06:47.596] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[13:06:47.596] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[13:06:47.596] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[13:06:47.596] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[13:06:47.596] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[13:06:47.596] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[13:06:47.596] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[13:06:47.596] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[13:06:47.596] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[13:06:47.596] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[13:06:47.596] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[13:06:47.596] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[13:06:47.596] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[13:06:47.597] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[13:06:47.597] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[13:06:47.597] INFO: 154 hot pixels found in step 7
[13:06:48.598] INFO: Collecting data for 5 seconds...
[13:06:53.615] INFO: Done with hot pixel readout
[13:07:06.155] INFO: PixTest:: pg_setup set to default.
[13:07:06.155] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[13:07:06.155] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[13:07:06.155] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[13:07:06.155] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[13:07:06.156] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[13:07:06.156] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[13:07:06.156] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[13:07:06.156] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[13:07:06.156] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[13:07:06.156] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[13:07:06.156] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[13:07:06.156] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[13:07:06.156] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[13:07:06.156] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[13:07:06.156] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[13:07:06.156] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[13:07:06.156] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[13:07:06.156] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[13:07:06.156] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[13:07:06.156] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[13:07:06.156] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[13:07:06.156] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[13:07:06.156] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[13:07:06.156] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[13:07:06.157] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[13:07:06.157] INFO: 131 hot pixels found in step 8
[13:07:07.157] INFO: Collecting data for 5 seconds...
[13:07:12.170] INFO: Done with hot pixel readout
[13:07:24.461] INFO: PixTest:: pg_setup set to default.
[13:07:24.461] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[13:07:24.461] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[13:07:24.461] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[13:07:24.461] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[13:07:24.461] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[13:07:24.461] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[13:07:24.461] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[13:07:24.462] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[13:07:24.462] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[13:07:24.462] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[13:07:24.462] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[13:07:24.462] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[13:07:24.462] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[13:07:24.462] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[13:07:24.462] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[13:07:24.462] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[13:07:24.462] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[13:07:24.462] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[13:07:24.462] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[13:07:24.462] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[13:07:24.462] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[13:07:24.462] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[13:07:24.462] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[13:07:24.462] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[13:07:24.462] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[13:07:24.462] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[13:07:24.462] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[13:07:24.462] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[13:07:24.462] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[13:07:24.463] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[13:07:24.463] INFO: 127 hot pixels found in step 9
[13:07:25.474] INFO: Collecting data for 5 seconds...
[13:07:30.491] INFO: Done with hot pixel readout
[13:07:41.849] INFO: PixTest:: pg_setup set to default.
[13:07:41.850] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[13:07:41.850] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[13:07:41.850] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[13:07:41.850] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[13:07:41.850] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[13:07:41.850] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[13:07:41.850] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[13:07:41.850] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[13:07:41.850] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[13:07:41.850] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[13:07:41.850] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[13:07:41.850] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[13:07:41.850] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[13:07:41.850] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[13:07:41.850] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[13:07:41.850] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[13:07:41.850] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[13:07:41.850] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[13:07:41.850] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[13:07:41.850] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[13:07:41.850] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[13:07:41.850] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[13:07:41.850] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[13:07:41.850] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[13:07:41.850] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[13:07:41.850] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[13:07:41.850] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[13:07:41.850] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[13:07:41.850] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[13:07:41.850] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[13:07:41.850] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[13:07:41.850] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[13:07:41.850] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[13:07:41.850] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[13:07:41.851] INFO: 114 hot pixels found in step 10
[13:07:42.852] INFO: Collecting data for 5 seconds...
[13:07:47.871] INFO: Done with hot pixel readout
[13:08:00.162] INFO: PixTest:: pg_setup set to default.
[13:08:00.162] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[13:08:00.162] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[13:08:00.162] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[13:08:00.162] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[13:08:00.162] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[13:08:00.162] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[13:08:00.162] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[13:08:00.162] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[13:08:00.162] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[13:08:00.162] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[13:08:00.162] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[13:08:00.162] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[13:08:00.162] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[13:08:00.162] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[13:08:00.162] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[13:08:00.162] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[13:08:00.162] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[13:08:00.162] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[13:08:00.162] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[13:08:00.162] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[13:08:00.162] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[13:08:00.162] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[13:08:00.163] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[13:08:00.163] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[13:08:00.163] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[13:08:00.163] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[13:08:00.163] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[13:08:00.163] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[13:08:00.163] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[13:08:00.163] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[13:08:00.163] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[13:08:00.163] INFO: 119 hot pixels found in step 11
[13:08:01.165] INFO: Collecting data for 5 seconds...
[13:08:06.182] INFO: Done with hot pixel readout
[13:08:18.597] INFO: PixTest:: pg_setup set to default.
[13:08:18.597] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[13:08:18.597] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[13:08:18.597] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[13:08:18.597] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[13:08:18.597] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[13:08:18.597] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[13:08:18.597] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[13:08:18.597] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[13:08:18.597] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[13:08:18.597] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[13:08:18.597] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[13:08:18.597] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[13:08:18.597] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[13:08:18.597] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[13:08:18.597] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[13:08:18.597] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[13:08:18.597] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[13:08:18.598] INFO: 113 hot pixels found in step 12
[13:08:19.600] INFO: Collecting data for 5 seconds...
[13:08:24.617] INFO: Done with hot pixel readout
[13:08:37.007] INFO: PixTest:: pg_setup set to default.
[13:08:37.007] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[13:08:37.007] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[13:08:37.007] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[13:08:37.007] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[13:08:37.008] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[13:08:37.008] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[13:08:37.008] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[13:08:37.008] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[13:08:37.008] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[13:08:37.008] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[13:08:37.008] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[13:08:37.008] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[13:08:37.008] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[13:08:37.008] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[13:08:37.008] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[13:08:37.008] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[13:08:37.008] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[13:08:37.008] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[13:08:37.008] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[13:08:37.008] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[13:08:37.008] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[13:08:37.008] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[13:08:37.008] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[13:08:37.008] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[13:08:37.008] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[13:08:37.008] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[13:08:37.008] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[13:08:37.008] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[13:08:37.008] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[13:08:37.009] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[13:08:37.009] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[13:08:37.009] INFO: 101 hot pixels found in step 13
[13:08:38.010] INFO: Collecting data for 5 seconds...
[13:08:43.026] INFO: Done with hot pixel readout
[13:08:55.609] INFO: PixTest:: pg_setup set to default.
[13:08:55.609] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[13:08:55.609] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[13:08:55.609] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[13:08:55.609] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[13:08:55.610] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[13:08:55.610] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[13:08:55.610] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[13:08:55.610] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[13:08:55.610] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[13:08:55.610] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[13:08:55.610] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[13:08:55.610] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[13:08:55.610] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[13:08:55.610] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[13:08:55.610] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[13:08:55.610] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[13:08:55.610] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[13:08:55.610] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[13:08:55.610] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[13:08:55.610] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[13:08:55.610] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[13:08:55.610] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[13:08:55.610] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[13:08:55.610] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[13:08:55.610] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[13:08:55.610] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[13:08:55.610] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[13:08:55.610] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[13:08:55.610] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[13:08:55.610] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[13:08:55.610] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[13:08:55.610] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[13:08:55.610] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[13:08:55.610] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[13:08:55.610] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[13:08:55.611] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[13:08:55.611] INFO: 120 hot pixels found in step 14
[13:08:55.657] INFO: 120 hot pixels could not be trimmed and have been masked.
[13:08:55.660] INFO: PixTest::trimHotPixels() done
[13:08:55.661] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Y-S-ND_FPIXTest-17C-FNAL-160517-0929_2016-05-17_09h29m_1463495357/000_FPIXTest_p17//trimParameters35_C0.dat
[13:08:55.666] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Y-S-ND_FPIXTest-17C-FNAL-160517-0929_2016-05-17_09h29m_1463495357/000_FPIXTest_p17//trimParameters35_C1.dat
[13:08:55.672] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Y-S-ND_FPIXTest-17C-FNAL-160517-0929_2016-05-17_09h29m_1463495357/000_FPIXTest_p17//trimParameters35_C2.dat
[13:08:55.677] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Y-S-ND_FPIXTest-17C-FNAL-160517-0929_2016-05-17_09h29m_1463495357/000_FPIXTest_p17//trimParameters35_C3.dat
[13:08:55.683] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Y-S-ND_FPIXTest-17C-FNAL-160517-0929_2016-05-17_09h29m_1463495357/000_FPIXTest_p17//trimParameters35_C4.dat
[13:08:55.688] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Y-S-ND_FPIXTest-17C-FNAL-160517-0929_2016-05-17_09h29m_1463495357/000_FPIXTest_p17//trimParameters35_C5.dat
[13:08:55.693] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Y-S-ND_FPIXTest-17C-FNAL-160517-0929_2016-05-17_09h29m_1463495357/000_FPIXTest_p17//trimParameters35_C6.dat
[13:08:55.698] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Y-S-ND_FPIXTest-17C-FNAL-160517-0929_2016-05-17_09h29m_1463495357/000_FPIXTest_p17//trimParameters35_C7.dat
[13:08:55.704] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Y-S-ND_FPIXTest-17C-FNAL-160517-0929_2016-05-17_09h29m_1463495357/000_FPIXTest_p17//trimParameters35_C8.dat
[13:08:55.709] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Y-S-ND_FPIXTest-17C-FNAL-160517-0929_2016-05-17_09h29m_1463495357/000_FPIXTest_p17//trimParameters35_C9.dat
[13:08:55.715] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Y-S-ND_FPIXTest-17C-FNAL-160517-0929_2016-05-17_09h29m_1463495357/000_FPIXTest_p17//trimParameters35_C10.dat
[13:08:55.720] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Y-S-ND_FPIXTest-17C-FNAL-160517-0929_2016-05-17_09h29m_1463495357/000_FPIXTest_p17//trimParameters35_C11.dat
[13:08:55.725] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Y-S-ND_FPIXTest-17C-FNAL-160517-0929_2016-05-17_09h29m_1463495357/000_FPIXTest_p17//trimParameters35_C12.dat
[13:08:55.731] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Y-S-ND_FPIXTest-17C-FNAL-160517-0929_2016-05-17_09h29m_1463495357/000_FPIXTest_p17//trimParameters35_C13.dat
[13:08:55.736] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Y-S-ND_FPIXTest-17C-FNAL-160517-0929_2016-05-17_09h29m_1463495357/000_FPIXTest_p17//trimParameters35_C14.dat
[13:08:55.741] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Y-S-ND_FPIXTest-17C-FNAL-160517-0929_2016-05-17_09h29m_1463495357/000_FPIXTest_p17//trimParameters35_C15.dat
[13:08:55.747] INFO: write masked pixels into /home/uicpirepix2/ProductionTestResults/M-Y-S-ND_FPIXTest-17C-FNAL-160517-0929_2016-05-17_09h29m_1463495357/000_FPIXTest_p17//defaultMaskFile.dat
[13:08:55.757] INFO: enter test to run
[13:09:23.843] INFO: test: HighRate setting parameters: ->runsecondshotpixels=1;trimhotpixelthr=200<-
[13:09:23.861] INFO: running: highrate
[13:09:23.865] INFO: ----------------------------------------------------------------------
[13:09:23.865] INFO: PixTest::trimHotPixels() running for 1 seconds with 100 kHz trigger rate
[13:09:23.865] INFO: ----------------------------------------------------------------------
[13:09:23.865] INFO: THR = 200, corresponding to ~ 533.333 MHz/cm2
[13:09:23.865] INFO: edge/corner pixel THR is adjusted
[13:09:23.865] INFO: PixTestHighRate::trimHotPixels: step 0...
[13:09:24.823] INFO: Collecting data for 1 seconds...
[13:09:25.827] INFO: Done with hot pixel readout
[13:09:30.336] INFO: PixTest:: pg_setup set to default.
[13:09:30.337] INFO: 0 hot pixels found in step 0
[13:09:30.342] INFO: 0 hot pixels could not be trimmed and have been masked.
[13:09:30.398] INFO: PixTest::trimHotPixels() done
[13:09:30.399] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Y-S-ND_FPIXTest-17C-FNAL-160517-0929_2016-05-17_09h29m_1463495357/000_FPIXTest_p17//trimParameters35_C0.dat
[13:09:30.414] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Y-S-ND_FPIXTest-17C-FNAL-160517-0929_2016-05-17_09h29m_1463495357/000_FPIXTest_p17//trimParameters35_C1.dat
[13:09:30.423] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Y-S-ND_FPIXTest-17C-FNAL-160517-0929_2016-05-17_09h29m_1463495357/000_FPIXTest_p17//trimParameters35_C2.dat
[13:09:30.429] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Y-S-ND_FPIXTest-17C-FNAL-160517-0929_2016-05-17_09h29m_1463495357/000_FPIXTest_p17//trimParameters35_C3.dat
[13:09:30.434] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Y-S-ND_FPIXTest-17C-FNAL-160517-0929_2016-05-17_09h29m_1463495357/000_FPIXTest_p17//trimParameters35_C4.dat
[13:09:30.439] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Y-S-ND_FPIXTest-17C-FNAL-160517-0929_2016-05-17_09h29m_1463495357/000_FPIXTest_p17//trimParameters35_C5.dat
[13:09:30.445] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Y-S-ND_FPIXTest-17C-FNAL-160517-0929_2016-05-17_09h29m_1463495357/000_FPIXTest_p17//trimParameters35_C6.dat
[13:09:30.450] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Y-S-ND_FPIXTest-17C-FNAL-160517-0929_2016-05-17_09h29m_1463495357/000_FPIXTest_p17//trimParameters35_C7.dat
[13:09:30.455] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Y-S-ND_FPIXTest-17C-FNAL-160517-0929_2016-05-17_09h29m_1463495357/000_FPIXTest_p17//trimParameters35_C8.dat
[13:09:30.461] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Y-S-ND_FPIXTest-17C-FNAL-160517-0929_2016-05-17_09h29m_1463495357/000_FPIXTest_p17//trimParameters35_C9.dat
[13:09:30.466] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Y-S-ND_FPIXTest-17C-FNAL-160517-0929_2016-05-17_09h29m_1463495357/000_FPIXTest_p17//trimParameters35_C10.dat
[13:09:30.471] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Y-S-ND_FPIXTest-17C-FNAL-160517-0929_2016-05-17_09h29m_1463495357/000_FPIXTest_p17//trimParameters35_C11.dat
[13:09:30.477] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Y-S-ND_FPIXTest-17C-FNAL-160517-0929_2016-05-17_09h29m_1463495357/000_FPIXTest_p17//trimParameters35_C12.dat
[13:09:30.482] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Y-S-ND_FPIXTest-17C-FNAL-160517-0929_2016-05-17_09h29m_1463495357/000_FPIXTest_p17//trimParameters35_C13.dat
[13:09:30.487] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Y-S-ND_FPIXTest-17C-FNAL-160517-0929_2016-05-17_09h29m_1463495357/000_FPIXTest_p17//trimParameters35_C14.dat
[13:09:30.493] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Y-S-ND_FPIXTest-17C-FNAL-160517-0929_2016-05-17_09h29m_1463495357/000_FPIXTest_p17//trimParameters35_C15.dat
[13:09:30.498] INFO: write masked pixels into /home/uicpirepix2/ProductionTestResults/M-Y-S-ND_FPIXTest-17C-FNAL-160517-0929_2016-05-17_09h29m_1463495357/000_FPIXTest_p17//defaultMaskFile.dat
[13:09:30.508] INFO: enter test to run
[13:10:17.227] INFO: test: Xray setting parameters: ->source=DCLowRate<-
[13:10:17.227] INFO: running: xray
[13:10:17.228] INFO: ----------------------------------------------------------------------
[13:10:17.228] INFO: PixTestXray::doPhRun() fParRunSeconds = 100
[13:10:17.228] INFO: ----------------------------------------------------------------------
[13:10:18.192] INFO: PixTestXray::doPhRun start TriggerLoop with trigger frequency 100 kHz, period 405 and duration 100 seconds, fEventsMax = 10000000
[13:10:28.384] INFO: run duration 10 seconds, buffer almost full (81%), pausing triggers.
[13:10:56.660] INFO: Resuming triggers.
[13:11:06.849] INFO: run duration 20 seconds, buffer almost full (81%), pausing triggers.
[13:11:35.962] INFO: Resuming triggers.
[13:11:46.151] INFO: run duration 30 seconds, buffer almost full (81%), pausing triggers.
[13:12:14.988] INFO: Resuming triggers.
[13:12:25.174] INFO: run duration 40 seconds, buffer almost full (81%), pausing triggers.
[13:12:54.087] INFO: Resuming triggers.
[13:13:04.276] INFO: run duration 50 seconds, buffer almost full (81%), pausing triggers.
[13:13:33.448] INFO: Resuming triggers.
[13:13:43.634] INFO: run duration 60 seconds, buffer almost full (81%), pausing triggers.
[13:14:11.980] INFO: Resuming triggers.
[13:14:22.169] INFO: run duration 71 seconds, buffer almost full (81%), pausing triggers.
[13:14:51.130] INFO: Resuming triggers.
[13:15:01.313] INFO: run duration 81 seconds, buffer almost full (81%), pausing triggers.
[13:15:30.327] INFO: Resuming triggers.
[13:15:40.514] INFO: run duration 91 seconds, buffer almost full (81%), pausing triggers.
[13:16:09.493] INFO: Resuming triggers.
[13:16:18.131] INFO: data taking finished, elapsed time: 100 seconds.
[13:16:42.900] INFO: PixTest:: pg_setup set to default.
[13:16:42.903] INFO: PixTestXray::doPhRun() done
[13:16:43.045] INFO: enter test to run
[13:17:43.960] INFO: test: Xray setting parameters: ->source=DCHighRate<-
[13:17:43.960] INFO: running: xray
[13:17:43.961] INFO: ----------------------------------------------------------------------
[13:17:43.961] INFO: PixTestXray::doPhRun() fParRunSeconds = 100
[13:17:43.961] INFO: ----------------------------------------------------------------------
[13:17:44.936] INFO: PixTestXray::doPhRun start TriggerLoop with trigger frequency 100 kHz, period 405 and duration 100 seconds, fEventsMax = 10000000
[13:17:50.451] INFO: run duration 5 seconds, buffer almost full (81%), pausing triggers.
[13:18:20.643] INFO: Resuming triggers.
[13:18:26.160] INFO: run duration 10 seconds, buffer almost full (81%), pausing triggers.
[13:18:56.441] INFO: Resuming triggers.
[13:19:01.961] INFO: run duration 16 seconds, buffer almost full (81%), pausing triggers.
[13:19:31.916] INFO: Resuming triggers.
[13:19:37.434] INFO: run duration 21 seconds, buffer almost full (81%), pausing triggers.
[13:20:07.632] INFO: Resuming triggers.
[13:20:13.157] INFO: run duration 27 seconds, buffer almost full (81%), pausing triggers.
[13:20:44.145] INFO: Resuming triggers.
[13:20:49.668] INFO: run duration 33 seconds, buffer almost full (81%), pausing triggers.
[13:21:19.287] INFO: Resuming triggers.
[13:21:24.806] INFO: run duration 38 seconds, buffer almost full (81%), pausing triggers.
[13:21:54.521] INFO: Resuming triggers.
[13:22:00.038] INFO: run duration 44 seconds, buffer almost full (81%), pausing triggers.
[13:22:29.647] INFO: Resuming triggers.
[13:22:35.164] INFO: run duration 49 seconds, buffer almost full (81%), pausing triggers.
[13:23:04.885] INFO: Resuming triggers.
[13:23:10.407] INFO: run duration 55 seconds, buffer almost full (81%), pausing triggers.
[13:23:39.993] INFO: Resuming triggers.
[13:23:45.510] INFO: run duration 60 seconds, buffer almost full (81%), pausing triggers.
[13:24:14.710] INFO: Resuming triggers.
[13:24:20.226] INFO: run duration 66 seconds, buffer almost full (81%), pausing triggers.
[13:24:50.119] INFO: Resuming triggers.
[13:24:55.635] INFO: run duration 71 seconds, buffer almost full (81%), pausing triggers.
[13:25:25.362] INFO: Resuming triggers.
[13:25:30.876] INFO: run duration 76 seconds, buffer almost full (81%), pausing triggers.
[13:26:00.648] INFO: Resuming triggers.
[13:26:06.166] INFO: run duration 82 seconds, buffer almost full (81%), pausing triggers.
[13:26:35.834] INFO: Resuming triggers.
[13:26:41.351] INFO: run duration 87 seconds, buffer almost full (81%), pausing triggers.
[13:27:11.160] INFO: Resuming triggers.
[13:27:16.676] INFO: run duration 93 seconds, buffer almost full (81%), pausing triggers.
[13:27:45.852] INFO: Resuming triggers.
[13:27:51.369] INFO: run duration 98 seconds, buffer almost full (81%), pausing triggers.
[13:28:21.109] INFO: Resuming triggers.
[13:28:22.119] INFO: data taking finished, elapsed time: 100 seconds.
[13:28:27.984] INFO: PixTest:: pg_setup set to default.
[13:28:27.987] INFO: PixTestXray::doPhRun() done
[13:28:28.140] INFO: enter test to run
[13:29:05.891] INFO: test: HighRate no parameter change
[13:29:05.891] INFO: running: highrate
[13:29:05.905] INFO: ----------------------------------------------------------------------
[13:29:05.905] INFO: PixTestHighRate::calDelScan() ntrig = 10, vcal = 200
[13:29:05.905] INFO: ----------------------------------------------------------------------
[13:29:06.069] INFO: Expecting 768 events.
[13:29:07.198] INFO: 768 events read in total (414ms).
[13:29:07.198] INFO: Test took 1263ms.
[13:29:07.002] INFO: Expecting 41600 events.
[13:29:11.151] INFO: 41600 events read in total (2622ms).
[13:29:11.152] INFO: Test took 3930ms.
[13:29:11.195] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:29:11.905] INFO: Expecting 41600 events.
[13:29:14.985] INFO: 41600 events read in total (2553ms).
[13:29:14.986] INFO: Test took 3770ms.
[13:29:15.030] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:29:15.738] INFO: Expecting 41600 events.
[13:29:19.054] INFO: 41600 events read in total (2789ms).
[13:29:19.055] INFO: Test took 4003ms.
[13:29:19.099] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:29:19.806] INFO: Expecting 41600 events.
[13:29:23.086] INFO: 41600 events read in total (2753ms).
[13:29:23.087] INFO: Test took 3966ms.
[13:29:23.133] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:29:23.835] INFO: Expecting 41600 events.
[13:29:27.082] INFO: 41600 events read in total (2720ms).
[13:29:27.083] INFO: Test took 3929ms.
[13:29:27.129] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:29:27.830] INFO: Expecting 41600 events.
[13:29:31.127] INFO: 41600 events read in total (2770ms).
[13:29:31.128] INFO: Test took 3977ms.
[13:29:31.173] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:29:31.872] INFO: Expecting 41600 events.
[13:29:35.069] INFO: 41600 events read in total (2671ms).
[13:29:35.071] INFO: Test took 3875ms.
[13:29:35.119] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:29:35.812] INFO: Expecting 41600 events.
[13:29:39.175] INFO: 41600 events read in total (2836ms).
[13:29:39.177] INFO: Test took 4028ms.
[13:29:39.222] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:29:39.923] INFO: Expecting 41600 events.
[13:29:43.220] INFO: 41600 events read in total (2770ms).
[13:29:43.221] INFO: Test took 3977ms.
[13:29:43.266] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:29:43.965] INFO: Expecting 41600 events.
[13:29:47.279] INFO: 41600 events read in total (2787ms).
[13:29:47.280] INFO: Test took 3993ms.
[13:29:47.326] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:29:48.028] INFO: Expecting 41600 events.
[13:29:51.309] INFO: 41600 events read in total (2754ms).
[13:29:51.310] INFO: Test took 3962ms.
[13:29:51.356] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:29:52.057] INFO: Expecting 41600 events.
[13:29:55.353] INFO: 41600 events read in total (2769ms).
[13:29:55.354] INFO: Test took 3975ms.
[13:29:55.399] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:29:56.098] INFO: Expecting 41600 events.
[13:29:59.429] INFO: 41600 events read in total (2804ms).
[13:29:59.430] INFO: Test took 4008ms.
[13:29:59.476] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:30:00.182] INFO: Expecting 41600 events.
[13:30:03.542] INFO: 41600 events read in total (2833ms).
[13:30:03.543] INFO: Test took 4045ms.
[13:30:03.589] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:30:04.282] INFO: Expecting 41600 events.
[13:30:07.567] INFO: 41600 events read in total (2758ms).
[13:30:07.568] INFO: Test took 3958ms.
[13:30:07.614] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:30:08.311] INFO: Expecting 41600 events.
[13:30:11.533] INFO: 41600 events read in total (2695ms).
[13:30:11.534] INFO: Test took 3898ms.
[13:30:11.579] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:30:12.284] INFO: Expecting 41600 events.
[13:30:15.597] INFO: 41600 events read in total (2787ms).
[13:30:15.599] INFO: Test took 3998ms.
[13:30:15.645] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:30:16.344] INFO: Expecting 41600 events.
[13:30:19.630] INFO: 41600 events read in total (2759ms).
[13:30:19.631] INFO: Test took 3964ms.
[13:30:19.676] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:30:20.379] INFO: Expecting 41600 events.
[13:30:23.614] INFO: 41600 events read in total (2708ms).
[13:30:23.615] INFO: Test took 3917ms.
[13:30:23.660] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:30:24.364] INFO: Expecting 41600 events.
[13:30:27.472] INFO: 41600 events read in total (2581ms).
[13:30:27.473] INFO: Test took 3790ms.
[13:30:27.517] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:30:27.854] INFO: enter test to run
[13:31:26.570] INFO: test: HighRate no parameter change
[13:31:26.570] INFO: running: highrate
[13:31:26.571] INFO: ----------------------------------------------------------------------
[13:31:26.571] INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[13:31:26.571] INFO: ----------------------------------------------------------------------
[13:31:27.191] INFO: Expecting 208000 events.
[13:31:39.362] INFO: 208000 events read in total (11644ms).
[13:31:39.366] INFO: Test took 12787ms.
[13:31:39.568] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:31:39.840] INFO: number of dead pixels (per ROC): 0 0 1 0 0 0 1 0 0 0 0 4 0 0 0 0
[13:31:39.840] INFO: number of red-efficiency pixels: 95 69 124 198 241 172 237 141 115 171 148 160 143 86 39 32
[13:31:39.840] INFO: number of X-ray hits detected: 86914 65055 93256 149982 155063 156709 151046 107173 104448 144328 138788 125038 123384 75627 31066 33960
[13:31:39.840] 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:31:39.840] INFO: number of Vcal hits detected: 207903 207929 207826 207792 207750 207824 207703 207853 207884 207825 207849 207639 207852 207912 207961 207968
[13:31:39.840] INFO: Vcal hit fiducial efficiency (%): 100.0 100.0 99.9 99.9 99.9 99.9 99.9 99.9 99.9 99.9 99.9 99.9 99.9 100.0 100.0 100.0
[13:31:39.840] INFO: Vcal hit overall efficiency (%): 100.0 100.0 99.9 99.9 99.9 99.9 99.9 99.9 99.9 99.9 99.9 99.8 99.9 100.0 100.0 100.0
[13:31:39.840] INFO: X-ray hit rate [MHz/cm2]: 25.5 19.1 27.3 44.0 45.5 45.9 44.3 31.4 30.6 42.3 40.7 36.6 36.2 22.2 9.1 10.0
[13:31:39.840] INFO: PixTestHighRate::doXPixelAlive() done
[13:31:39.885] INFO: PixTest:: pg_setup set to default.
[13:31:39.899] INFO: enter test to run
[13:32:30.217] INFO: test: HighRate no parameter change
[13:32:30.217] INFO: running: highrate
[13:32:30.218] INFO: ----------------------------------------------------------------------
[13:32:30.218] INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[13:32:30.218] INFO: ----------------------------------------------------------------------
[13:32:30.838] INFO: Expecting 208000 events.
[13:32:45.352] INFO: 208000 events read in total (13987ms).
[13:32:45.359] INFO: Test took 15132ms.
[13:32:45.738] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:32:46.074] INFO: number of dead pixels (per ROC): 0 0 1 0 0 0 1 0 0 0 0 4 0 0 0 0
[13:32:46.074] INFO: number of red-efficiency pixels: 278 201 348 680 872 697 775 486 332 566 543 517 404 288 49 68
[13:32:46.074] INFO: number of X-ray hits detected: 169870 127530 181768 294507 304590 308783 298581 210497 204679 283469 273541 246441 242676 149710 60781 66953
[13:32:46.074] 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:32:46.074] INFO: number of Vcal hits detected: 207698 207789 207551 207229 206977 207227 207043 207444 207641 207367 207405 207238 207562 207686 207949 207932
[13:32:46.074] INFO: Vcal hit fiducial efficiency (%): 99.9 99.9 99.8 99.7 99.6 99.7 99.6 99.8 99.8 99.7 99.7 99.8 99.8 99.9 100.0 100.0
[13:32:46.074] INFO: Vcal hit overall efficiency (%): 99.9 99.9 99.8 99.6 99.5 99.6 99.5 99.7 99.8 99.7 99.7 99.6 99.8 99.8 100.0 100.0
[13:32:46.074] INFO: X-ray hit rate [MHz/cm2]: 49.8 37.4 53.3 86.3 89.3 90.5 87.5 61.7 60.0 83.1 80.2 72.2 71.1 43.9 17.8 19.6
[13:32:46.074] INFO: PixTestHighRate::doXPixelAlive() done
[13:32:46.122] INFO: PixTest:: pg_setup set to default.
[13:32:46.135] INFO: enter test to run
[13:33:11.577] INFO: test: HighRate no parameter change
[13:33:11.577] INFO: running: highrate
[13:33:11.578] INFO: ----------------------------------------------------------------------
[13:33:11.578] INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[13:33:11.578] INFO: ----------------------------------------------------------------------
[13:33:12.192] INFO: Expecting 208000 events.
[13:33:29.416] INFO: 208000 events read in total (16697ms).
[13:33:29.426] INFO: Test took 17837ms.
[13:33:30.027] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:33:30.423] INFO: number of dead pixels (per ROC): 0 0 1 0 0 0 1 0 0 0 0 3 0 0 0 0
[13:33:30.423] INFO: number of red-efficiency pixels: 623 415 836 1616 2163 1726 1902 1167 735 1490 1360 1126 1019 626 128 102
[13:33:30.423] INFO: number of X-ray hits detected: 259124 195226 277911 447536 462654 467338 450080 321645 311748 430305 415167 374145 369797 228312 93060 102194
[13:33:30.423] 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:33:30.423] INFO: number of Vcal hits detected: 207219 207529 206750 205670 204494 205496 205036 206292 207121 206004 206202 206355 206783 207167 207865 207896
[13:33:30.423] INFO: Vcal hit fiducial efficiency (%): 99.7 99.8 99.5 99.0 98.5 98.9 98.7 99.3 99.6 99.1 99.2 99.3 99.5 99.6 99.9 99.9
[13:33:30.423] INFO: Vcal hit overall efficiency (%): 99.6 99.8 99.4 98.9 98.3 98.8 98.6 99.2 99.6 99.0 99.1 99.2 99.4 99.6 99.9 100.0
[13:33:30.423] INFO: X-ray hit rate [MHz/cm2]: 76.0 57.2 81.5 131.2 135.6 137.0 131.9 94.3 91.4 126.1 121.7 109.7 108.4 66.9 27.3 30.0
[13:33:30.423] INFO: PixTestHighRate::doXPixelAlive() done
[13:33:30.474] INFO: PixTest:: pg_setup set to default.
[13:33:30.488] INFO: enter test to run
[13:33:32.577] INFO: test: exit no parameter change
[13:33:32.929] QUIET: Connection to board 32 closed.
[13:33:32.942] INFO: pXar: this is the end, my friend