[14:27:10.421] INFO: *** Welcome to pxar ***
[14:27:10.421] INFO: *** Today: 2016/05/19
[14:27:10.438] INFO: *** Version: v1.9.0-796-gef167-dirty
[14:27:10.438] INFO: readRocDacs: /home/uicpirepix2/ProductionTestResults/M-Y-T-NL_FPIXTest-17C-FNAL-160516-0917_2016-05-16_09h17m_1463408253/000_FPIXTest_p17//dacParameters35_C0.dat .. /home/uicpirepix2/ProductionTestResults/M-Y-T-NL_FPIXTest-17C-FNAL-160516-0917_2016-05-16_09h17m_1463408253/000_FPIXTest_p17//dacParameters35_C15.dat
[14:27:10.462] INFO: readTbmDacs: /home/uicpirepix2/ProductionTestResults/M-Y-T-NL_FPIXTest-17C-FNAL-160516-0917_2016-05-16_09h17m_1463408253/000_FPIXTest_p17//tbmParameters_C0a.dat .. /home/uicpirepix2/ProductionTestResults/M-Y-T-NL_FPIXTest-17C-FNAL-160516-0917_2016-05-16_09h17m_1463408253/000_FPIXTest_p17//tbmParameters_C0b.dat
[14:27:10.462] INFO: readMaskFile: /home/uicpirepix2/ProductionTestResults/M-Y-T-NL_FPIXTest-17C-FNAL-160516-0917_2016-05-16_09h17m_1463408253/000_FPIXTest_p17//defaultMaskFile.dat
[14:27:10.468] INFO: readTrimFile: /home/uicpirepix2/ProductionTestResults/M-Y-T-NL_FPIXTest-17C-FNAL-160516-0917_2016-05-16_09h17m_1463408253/000_FPIXTest_p17//trimParameters35_C0.dat .. /home/uicpirepix2/ProductionTestResults/M-Y-T-NL_FPIXTest-17C-FNAL-160516-0917_2016-05-16_09h17m_1463408253/000_FPIXTest_p17//trimParameters35_C15.dat
[14:27:10.550] INFO: clk: 4
[14:27:10.550] INFO: ctr: 4
[14:27:10.550] INFO: sda: 19
[14:27:10.550] INFO: tin: 9
[14:27:10.550] INFO: level: 15
[14:27:10.550] INFO: triggerdelay: 0
[14:27:10.550] QUIET: Instanciating API for pxar v1.9.0+796~gef16747
[14:27:10.550] INFO: Log level: INFO
[14:27:10.568] QUIET: Connection to board DTB_WREK4U opened.
[14:27:10.573] 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:
------------------------------------------------------
[14:27:10.576] INFO: RPC call hashes of host and DTB match: 398089610
[14:27:12.110] INFO: DUT info:
[14:27:12.110] INFO: The DUT currently contains the following objects:
[14:27:12.110] INFO: 2 TBM Cores tbm08c (2 ON)
[14:27:12.110] INFO: TBM Core alpha (0): 7 registers set
[14:27:12.110] INFO: TBM Core beta (1): 7 registers set
[14:27:12.110] INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[14:27:12.110] INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[14:27:12.110] INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[14:27:12.110] INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[14:27:12.110] INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[14:27:12.110] INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[14:27:12.110] INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[14:27:12.110] INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[14:27:12.110] INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[14:27:12.110] INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[14:27:12.110] INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[14:27:12.110] INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[14:27:12.110] INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[14:27:12.110] INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[14:27:12.111] INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[14:27:12.111] INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[14:27:12.111] INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[14:27:12.514] INFO: enter 'restricted' command line mode
[14:27:12.514] INFO: enter test to run
[14:27:26.282] INFO: test: PixelAlive no parameter change
[14:27:26.282] INFO: running: pixelalive
[14:27:26.291] INFO: ----------------------------------------------------------------------
[14:27:26.291] INFO: PixTestAlive::aliveTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[14:27:26.291] INFO: ----------------------------------------------------------------------
[14:27:26.610] INFO: Expecting 41600 events.
[14:27:30.692] INFO: 41600 events read in total (3363ms).
[14:27:30.863] INFO: Test took 4570ms.
[14:27:30.878] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:27:31.138] INFO: PixTestAlive::aliveTest() done
[14:27:31.138] INFO: number of dead pixels (per ROC): 0 0 0 0 0 0 7 0 2 0 0 0 0 0 0 1
[14:27:31.174] INFO: enter test to run
[14:28:27.369] INFO: test: HighRate setting parameters: ->runsecondshotpixels=5;trimhotpixelthr=200<-
[14:28:27.369] INFO: running: highrate
[14:28:27.370] INFO: readGainPedestalParameters /home/uicpirepix2/ProductionTestResults/M-Y-T-NL_FPIXTest-17C-FNAL-160516-0917_2016-05-16_09h17m_1463408253/000_FPIXTest_p17//phCalibrationFitErr35_C0.dat .. /home/uicpirepix2/ProductionTestResults/M-Y-T-NL_FPIXTest-17C-FNAL-160516-0917_2016-05-16_09h17m_1463408253/000_FPIXTest_p17//phCalibrationFitErr35_C15.dat
[14:28:29.468] INFO: ----------------------------------------------------------------------
[14:28:29.468] INFO: PixTest::trimHotPixels() running for 5 seconds with 100 kHz trigger rate
[14:28:29.468] INFO: ----------------------------------------------------------------------
[14:28:29.468] INFO: THR = 200, corresponding to ~ 106.667 MHz/cm2
[14:28:29.468] INFO: edge/corner pixel THR is adjusted
[14:28:29.468] INFO: PixTestHighRate::trimHotPixels: step 0...
[14:28:30.536] INFO: Collecting data for 5 seconds...
[14:28:35.552] INFO: Done with hot pixel readout
[14:28:47.834] INFO: PixTest:: pg_setup set to default.
[14:28:47.834] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[14:28:47.834] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[14:28:47.835] INFO: 95 hot pixels found in step 0
[14:28:48.828] INFO: Collecting data for 5 seconds...
[14:28:53.846] INFO: Done with hot pixel readout
[14:29:06.021] INFO: PixTest:: pg_setup set to default.
[14:29:06.022] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[14:29:06.023] INFO: 76 hot pixels found in step 1
[14:29:07.021] INFO: Collecting data for 5 seconds...
[14:29:12.038] INFO: Done with hot pixel readout
[14:29:24.292] INFO: PixTest:: pg_setup set to default.
[14:29:24.292] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[14:29:24.292] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[14:29:24.292] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[14:29:24.292] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[14:29:24.292] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[14:29:24.293] INFO: 75 hot pixels found in step 2
[14:29:25.293] INFO: Collecting data for 5 seconds...
[14:29:30.310] INFO: Done with hot pixel readout
[14:29:42.523] INFO: PixTest:: pg_setup set to default.
[14:29:42.523] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[14:29:42.523] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[14:29:42.523] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[14:29:42.524] INFO: 80 hot pixels found in step 3
[14:29:43.522] INFO: Collecting data for 5 seconds...
[14:29:48.538] INFO: Done with hot pixel readout
[14:30:00.661] INFO: PixTest:: pg_setup set to default.
[14:30:00.661] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[14:30:00.662] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[14:30:00.662] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[14:30:00.662] INFO: 66 hot pixels found in step 4
[14:30:01.670] INFO: Collecting data for 5 seconds...
[14:30:06.688] INFO: Done with hot pixel readout
[14:30:18.442] INFO: PixTest:: pg_setup set to default.
[14:30:18.443] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[14:30:18.443] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[14:30:18.443] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[14:30:18.443] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[14:30:18.443] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[14:30:18.443] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[14:30:18.444] INFO: 85 hot pixels found in step 5
[14:30:19.450] INFO: Collecting data for 5 seconds...
[14:30:24.468] INFO: Done with hot pixel readout
[14:30:36.104] INFO: PixTest:: pg_setup set to default.
[14:30:36.104] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[14:30:36.104] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[14:30:36.104] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[14:30:36.104] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[14:30:36.104] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[14:30:36.105] INFO: 68 hot pixels found in step 6
[14:30:37.103] INFO: Collecting data for 5 seconds...
[14:30:42.120] INFO: Done with hot pixel readout
[14:30:53.808] INFO: PixTest:: pg_setup set to default.
[14:30:53.808] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[14:30:53.808] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[14:30:53.808] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[14:30:53.808] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[14:30:53.808] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[14:30:53.808] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[14:30:53.808] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[14:30:53.808] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[14:30:53.808] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[14:30:53.808] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[14:30:53.809] INFO: 65 hot pixels found in step 7
[14:30:54.807] INFO: Collecting data for 5 seconds...
[14:30:59.826] INFO: Done with hot pixel readout
[14:31:11.702] INFO: PixTest:: pg_setup set to default.
[14:31:11.702] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[14:31:11.702] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[14:31:11.702] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[14:31:11.702] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[14:31:11.702] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[14:31:11.702] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[14:31:11.703] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[14:31:11.703] INFO: 59 hot pixels found in step 8
[14:31:12.700] INFO: Collecting data for 5 seconds...
[14:31:17.717] INFO: Done with hot pixel readout
[14:31:29.311] INFO: PixTest:: pg_setup set to default.
[14:31:29.311] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[14:31:29.312] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[14:31:29.312] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[14:31:29.312] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[14:31:29.312] INFO: 43 hot pixels found in step 9
[14:31:30.318] INFO: Collecting data for 5 seconds...
[14:31:35.335] INFO: Done with hot pixel readout
[14:31:47.574] INFO: PixTest:: pg_setup set to default.
[14:31:47.574] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[14:31:47.574] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[14:31:47.574] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[14:31:47.574] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[14:31:47.574] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[14:31:47.574] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[14:31:47.574] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[14:31:47.575] INFO: 52 hot pixels found in step 10
[14:31:48.572] INFO: Collecting data for 5 seconds...
[14:31:53.590] INFO: Done with hot pixel readout
[14:32:06.041] INFO: PixTest:: pg_setup set to default.
[14:32:06.041] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[14:32:06.041] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[14:32:06.041] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[14:32:06.041] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[14:32:06.041] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[14:32:06.041] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[14:32:06.041] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[14:32:06.041] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[14:32:06.041] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[14:32:06.042] INFO: 57 hot pixels found in step 11
[14:32:07.041] INFO: Collecting data for 5 seconds...
[14:32:12.059] INFO: Done with hot pixel readout
[14:32:24.097] INFO: PixTest:: pg_setup set to default.
[14:32:24.098] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[14:32:24.098] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[14:32:24.098] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[14:32:24.098] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[14:32:24.098] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[14:32:24.098] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[14:32:24.099] INFO: 53 hot pixels found in step 12
[14:32:25.098] INFO: Collecting data for 5 seconds...
[14:32:30.115] INFO: Done with hot pixel readout
[14:32:42.547] INFO: PixTest:: pg_setup set to default.
[14:32:42.548] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[14:32:42.548] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[14:32:42.548] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[14:32:42.548] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[14:32:42.548] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[14:32:42.549] INFO: 54 hot pixels found in step 13
[14:32:43.555] INFO: Collecting data for 5 seconds...
[14:32:48.575] INFO: Done with hot pixel readout
[14:33:00.512] INFO: PixTest:: pg_setup set to default.
[14:33:00.512] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[14:33:00.512] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[14:33:00.512] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[14:33:00.512] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[14:33:00.512] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[14:33:00.512] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[14:33:00.512] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[14:33:00.512] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[14:33:00.512] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[14:33:00.512] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[14:33:00.512] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[14:33:00.512] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[14:33:00.513] INFO: 58 hot pixels found in step 14
[14:33:00.555] INFO: 58 hot pixels could not be trimmed and have been masked.
[14:33:00.559] INFO: PixTest::trimHotPixels() done
[14:33:00.576] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Y-T-NL_FPIXTest-17C-FNAL-160516-0917_2016-05-16_09h17m_1463408253/000_FPIXTest_p17//trimParameters35_C0.dat
[14:33:00.582] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Y-T-NL_FPIXTest-17C-FNAL-160516-0917_2016-05-16_09h17m_1463408253/000_FPIXTest_p17//trimParameters35_C1.dat
[14:33:00.587] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Y-T-NL_FPIXTest-17C-FNAL-160516-0917_2016-05-16_09h17m_1463408253/000_FPIXTest_p17//trimParameters35_C2.dat
[14:33:00.593] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Y-T-NL_FPIXTest-17C-FNAL-160516-0917_2016-05-16_09h17m_1463408253/000_FPIXTest_p17//trimParameters35_C3.dat
[14:33:00.598] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Y-T-NL_FPIXTest-17C-FNAL-160516-0917_2016-05-16_09h17m_1463408253/000_FPIXTest_p17//trimParameters35_C4.dat
[14:33:00.604] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Y-T-NL_FPIXTest-17C-FNAL-160516-0917_2016-05-16_09h17m_1463408253/000_FPIXTest_p17//trimParameters35_C5.dat
[14:33:00.609] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Y-T-NL_FPIXTest-17C-FNAL-160516-0917_2016-05-16_09h17m_1463408253/000_FPIXTest_p17//trimParameters35_C6.dat
[14:33:00.614] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Y-T-NL_FPIXTest-17C-FNAL-160516-0917_2016-05-16_09h17m_1463408253/000_FPIXTest_p17//trimParameters35_C7.dat
[14:33:00.620] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Y-T-NL_FPIXTest-17C-FNAL-160516-0917_2016-05-16_09h17m_1463408253/000_FPIXTest_p17//trimParameters35_C8.dat
[14:33:00.625] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Y-T-NL_FPIXTest-17C-FNAL-160516-0917_2016-05-16_09h17m_1463408253/000_FPIXTest_p17//trimParameters35_C9.dat
[14:33:00.630] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Y-T-NL_FPIXTest-17C-FNAL-160516-0917_2016-05-16_09h17m_1463408253/000_FPIXTest_p17//trimParameters35_C10.dat
[14:33:00.636] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Y-T-NL_FPIXTest-17C-FNAL-160516-0917_2016-05-16_09h17m_1463408253/000_FPIXTest_p17//trimParameters35_C11.dat
[14:33:00.641] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Y-T-NL_FPIXTest-17C-FNAL-160516-0917_2016-05-16_09h17m_1463408253/000_FPIXTest_p17//trimParameters35_C12.dat
[14:33:00.647] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Y-T-NL_FPIXTest-17C-FNAL-160516-0917_2016-05-16_09h17m_1463408253/000_FPIXTest_p17//trimParameters35_C13.dat
[14:33:00.652] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Y-T-NL_FPIXTest-17C-FNAL-160516-0917_2016-05-16_09h17m_1463408253/000_FPIXTest_p17//trimParameters35_C14.dat
[14:33:00.657] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Y-T-NL_FPIXTest-17C-FNAL-160516-0917_2016-05-16_09h17m_1463408253/000_FPIXTest_p17//trimParameters35_C15.dat
[14:33:00.663] INFO: write masked pixels into /home/uicpirepix2/ProductionTestResults/M-Y-T-NL_FPIXTest-17C-FNAL-160516-0917_2016-05-16_09h17m_1463408253/000_FPIXTest_p17//defaultMaskFile.dat
[14:33:00.673] INFO: enter test to run
[14:33:36.719] INFO: test: HighRate setting parameters: ->runsecondshotpixels=1;trimhotpixelthr=200<-
[14:33:36.719] INFO: running: highrate
[14:33:36.724] INFO: ----------------------------------------------------------------------
[14:33:36.724] INFO: PixTest::trimHotPixels() running for 1 seconds with 100 kHz trigger rate
[14:33:36.724] INFO: ----------------------------------------------------------------------
[14:33:36.724] INFO: THR = 200, corresponding to ~ 533.333 MHz/cm2
[14:33:36.724] INFO: edge/corner pixel THR is adjusted
[14:33:36.724] INFO: PixTestHighRate::trimHotPixels: step 0...
[14:33:37.681] INFO: Collecting data for 1 seconds...
[14:33:38.684] INFO: Done with hot pixel readout
[14:33:43.223] INFO: PixTest:: pg_setup set to default.
[14:33:43.224] INFO: 0 hot pixels found in step 0
[14:33:43.229] INFO: 0 hot pixels could not be trimmed and have been masked.
[14:33:43.294] INFO: PixTest::trimHotPixels() done
[14:33:43.294] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Y-T-NL_FPIXTest-17C-FNAL-160516-0917_2016-05-16_09h17m_1463408253/000_FPIXTest_p17//trimParameters35_C0.dat
[14:33:43.306] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Y-T-NL_FPIXTest-17C-FNAL-160516-0917_2016-05-16_09h17m_1463408253/000_FPIXTest_p17//trimParameters35_C1.dat
[14:33:43.313] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Y-T-NL_FPIXTest-17C-FNAL-160516-0917_2016-05-16_09h17m_1463408253/000_FPIXTest_p17//trimParameters35_C2.dat
[14:33:43.318] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Y-T-NL_FPIXTest-17C-FNAL-160516-0917_2016-05-16_09h17m_1463408253/000_FPIXTest_p17//trimParameters35_C3.dat
[14:33:43.323] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Y-T-NL_FPIXTest-17C-FNAL-160516-0917_2016-05-16_09h17m_1463408253/000_FPIXTest_p17//trimParameters35_C4.dat
[14:33:43.329] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Y-T-NL_FPIXTest-17C-FNAL-160516-0917_2016-05-16_09h17m_1463408253/000_FPIXTest_p17//trimParameters35_C5.dat
[14:33:43.334] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Y-T-NL_FPIXTest-17C-FNAL-160516-0917_2016-05-16_09h17m_1463408253/000_FPIXTest_p17//trimParameters35_C6.dat
[14:33:43.339] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Y-T-NL_FPIXTest-17C-FNAL-160516-0917_2016-05-16_09h17m_1463408253/000_FPIXTest_p17//trimParameters35_C7.dat
[14:33:43.344] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Y-T-NL_FPIXTest-17C-FNAL-160516-0917_2016-05-16_09h17m_1463408253/000_FPIXTest_p17//trimParameters35_C8.dat
[14:33:43.349] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Y-T-NL_FPIXTest-17C-FNAL-160516-0917_2016-05-16_09h17m_1463408253/000_FPIXTest_p17//trimParameters35_C9.dat
[14:33:43.355] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Y-T-NL_FPIXTest-17C-FNAL-160516-0917_2016-05-16_09h17m_1463408253/000_FPIXTest_p17//trimParameters35_C10.dat
[14:33:43.360] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Y-T-NL_FPIXTest-17C-FNAL-160516-0917_2016-05-16_09h17m_1463408253/000_FPIXTest_p17//trimParameters35_C11.dat
[14:33:43.365] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Y-T-NL_FPIXTest-17C-FNAL-160516-0917_2016-05-16_09h17m_1463408253/000_FPIXTest_p17//trimParameters35_C12.dat
[14:33:43.370] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Y-T-NL_FPIXTest-17C-FNAL-160516-0917_2016-05-16_09h17m_1463408253/000_FPIXTest_p17//trimParameters35_C13.dat
[14:33:43.375] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Y-T-NL_FPIXTest-17C-FNAL-160516-0917_2016-05-16_09h17m_1463408253/000_FPIXTest_p17//trimParameters35_C14.dat
[14:33:43.380] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Y-T-NL_FPIXTest-17C-FNAL-160516-0917_2016-05-16_09h17m_1463408253/000_FPIXTest_p17//trimParameters35_C15.dat
[14:33:43.386] INFO: write masked pixels into /home/uicpirepix2/ProductionTestResults/M-Y-T-NL_FPIXTest-17C-FNAL-160516-0917_2016-05-16_09h17m_1463408253/000_FPIXTest_p17//defaultMaskFile.dat
[14:33:43.395] INFO: enter test to run
[14:34:03.791] INFO: test: Xray setting parameters: ->source=DCLowRate<-
[14:34:03.791] INFO: running: xray
[14:34:03.802] INFO: ----------------------------------------------------------------------
[14:34:03.802] INFO: PixTestXray::doPhRun() fParRunSeconds = 100
[14:34:03.802] INFO: ----------------------------------------------------------------------
[14:34:04.786] INFO: PixTestXray::doPhRun start TriggerLoop with trigger frequency 100 kHz, period 405 and duration 100 seconds, fEventsMax = 10000000
[14:34:15.232] INFO: run duration 10 seconds, buffer almost full (81%), pausing triggers.
[14:34:44.020] INFO: Resuming triggers.
[14:34:54.468] INFO: run duration 20 seconds, buffer almost full (81%), pausing triggers.
[14:35:24.020] INFO: Resuming triggers.
[14:35:34.468] INFO: run duration 31 seconds, buffer almost full (81%), pausing triggers.
[14:36:03.366] INFO: Resuming triggers.
[14:36:13.809] INFO: run duration 41 seconds, buffer almost full (81%), pausing triggers.
[14:36:42.185] INFO: Resuming triggers.
[14:36:52.631] INFO: run duration 52 seconds, buffer almost full (81%), pausing triggers.
[14:37:20.947] INFO: Resuming triggers.
[14:37:31.396] INFO: run duration 62 seconds, buffer almost full (81%), pausing triggers.
[14:38:00.294] INFO: Resuming triggers.
[14:38:10.744] INFO: run duration 72 seconds, buffer almost full (81%), pausing triggers.
[14:38:39.379] INFO: Resuming triggers.
[14:38:49.823] INFO: run duration 83 seconds, buffer almost full (81%), pausing triggers.
[14:39:18.979] INFO: Resuming triggers.
[14:39:29.423] INFO: run duration 93 seconds, buffer almost full (81%), pausing triggers.
[14:39:58.356] INFO: Resuming triggers.
[14:40:04.676] INFO: data taking finished, elapsed time: 100 seconds.
[14:40:22.619] INFO: PixTest:: pg_setup set to default.
[14:40:22.623] INFO: PixTestXray::doPhRun() done
[14:40:22.762] INFO: enter test to run
[14:41:25.020] INFO: test: Xray setting parameters: ->source=DCHighRate<-
[14:41:25.020] INFO: running: xray
[14:41:25.021] INFO: ----------------------------------------------------------------------
[14:41:25.021] INFO: PixTestXray::doPhRun() fParRunSeconds = 100
[14:41:25.021] INFO: ----------------------------------------------------------------------
[14:41:25.983] INFO: PixTestXray::doPhRun start TriggerLoop with trigger frequency 100 kHz, period 405 and duration 100 seconds, fEventsMax = 10000000
[14:41:31.516] INFO: run duration 5 seconds, buffer almost full (81%), pausing triggers.
[14:42:02.229] INFO: Resuming triggers.
[14:42:07.763] INFO: run duration 11 seconds, buffer almost full (81%), pausing triggers.
[14:42:37.474] INFO: Resuming triggers.
[14:42:43.011] INFO: run duration 16 seconds, buffer almost full (81%), pausing triggers.
[14:43:13.459] INFO: Resuming triggers.
[14:43:18.991] INFO: run duration 22 seconds, buffer almost full (81%), pausing triggers.
[14:43:50.058] INFO: Resuming triggers.
[14:43:55.592] INFO: run duration 27 seconds, buffer almost full (81%), pausing triggers.
[14:44:26.527] INFO: Resuming triggers.
[14:44:32.064] INFO: run duration 33 seconds, buffer almost full (81%), pausing triggers.
[14:45:01.799] INFO: Resuming triggers.
[14:45:07.338] INFO: run duration 38 seconds, buffer almost full (81%), pausing triggers.
[14:45:37.533] INFO: Resuming triggers.
[14:45:43.069] INFO: run duration 44 seconds, buffer almost full (81%), pausing triggers.
[14:46:13.559] INFO: Resuming triggers.
[14:46:19.093] INFO: run duration 49 seconds, buffer almost full (81%), pausing triggers.
[14:46:49.487] INFO: Resuming triggers.
[14:46:55.027] INFO: run duration 55 seconds, buffer almost full (81%), pausing triggers.
[14:47:25.444] INFO: Resuming triggers.
[14:47:30.979] INFO: run duration 60 seconds, buffer almost full (81%), pausing triggers.
[14:48:01.060] INFO: Resuming triggers.
[14:48:06.599] INFO: run duration 66 seconds, buffer almost full (81%), pausing triggers.
[14:48:36.300] INFO: Resuming triggers.
[14:48:41.835] INFO: run duration 71 seconds, buffer almost full (81%), pausing triggers.
[14:49:11.873] INFO: Resuming triggers.
[14:49:17.407] INFO: run duration 77 seconds, buffer almost full (81%), pausing triggers.
[14:49:47.044] INFO: Resuming triggers.
[14:49:52.581] INFO: run duration 82 seconds, buffer almost full (81%), pausing triggers.
[14:50:22.940] INFO: Resuming triggers.
[14:50:28.474] INFO: run duration 88 seconds, buffer almost full (81%), pausing triggers.
[14:50:58.806] INFO: Resuming triggers.
[14:51:04.339] INFO: run duration 93 seconds, buffer almost full (81%), pausing triggers.
[14:51:34.171] INFO: Resuming triggers.
[14:51:39.704] INFO: run duration 99 seconds, buffer almost full (81%), pausing triggers.
[14:52:09.793] INFO: Resuming triggers.
[14:52:10.501] INFO: data taking finished, elapsed time: 100 seconds.
[14:52:14.711] INFO: PixTest:: pg_setup set to default.
[14:52:14.714] INFO: PixTestXray::doPhRun() done
[14:52:14.880] INFO: enter test to run
[14:52:47.639] INFO: test: HighRate no parameter change
[14:52:47.639] INFO: running: highrate
[14:52:47.651] INFO: ----------------------------------------------------------------------
[14:52:47.651] INFO: PixTestHighRate::calDelScan() ntrig = 10, vcal = 200
[14:52:47.651] INFO: ----------------------------------------------------------------------
[14:52:47.815] INFO: Expecting 768 events.
[14:52:48.952] INFO: 768 events read in total (421ms).
[14:52:48.952] INFO: Test took 1272ms.
[14:52:49.755] INFO: Expecting 41600 events.
[14:52:52.982] INFO: 41600 events read in total (2700ms).
[14:52:52.984] INFO: Test took 4025ms.
[14:52:53.030] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:52:53.727] INFO: Expecting 41600 events.
[14:52:57.019] INFO: 41600 events read in total (2765ms).
[14:52:57.020] INFO: Test took 3968ms.
[14:52:57.064] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:52:57.764] INFO: Expecting 41600 events.
[14:53:01.018] INFO: 41600 events read in total (2727ms).
[14:53:01.020] INFO: Test took 3936ms.
[14:53:01.066] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:53:01.767] INFO: Expecting 41600 events.
[14:53:05.068] INFO: 41600 events read in total (2774ms).
[14:53:05.070] INFO: Test took 3982ms.
[14:53:05.115] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:53:05.818] INFO: Expecting 41600 events.
[14:53:09.101] INFO: 41600 events read in total (2756ms).
[14:53:09.102] INFO: Test took 3965ms.
[14:53:09.145] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:53:09.849] INFO: Expecting 41600 events.
[14:53:13.178] INFO: 41600 events read in total (2802ms).
[14:53:13.180] INFO: Test took 4015ms.
[14:53:13.224] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:53:13.932] INFO: Expecting 41600 events.
[14:53:17.238] INFO: 41600 events read in total (2780ms).
[14:53:17.239] INFO: Test took 3992ms.
[14:53:17.283] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:53:17.985] INFO: Expecting 41600 events.
[14:53:21.218] INFO: 41600 events read in total (2706ms).
[14:53:21.219] INFO: Test took 3915ms.
[14:53:21.264] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:53:21.959] INFO: Expecting 41600 events.
[14:53:25.325] INFO: 41600 events read in total (2839ms).
[14:53:25.327] INFO: Test took 4041ms.
[14:53:25.372] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:53:26.049] INFO: Expecting 41600 events.
[14:53:29.270] INFO: 41600 events read in total (2694ms).
[14:53:29.271] INFO: Test took 3878ms.
[14:53:29.315] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:53:30.017] INFO: Expecting 41600 events.
[14:53:33.246] INFO: 41600 events read in total (2702ms).
[14:53:33.247] INFO: Test took 3909ms.
[14:53:33.292] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:53:33.989] INFO: Expecting 41600 events.
[14:53:37.229] INFO: 41600 events read in total (2714ms).
[14:53:37.231] INFO: Test took 3918ms.
[14:53:37.276] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:53:37.977] INFO: Expecting 41600 events.
[14:53:41.305] INFO: 41600 events read in total (2801ms).
[14:53:41.306] INFO: Test took 4007ms.
[14:53:41.351] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:53:42.056] INFO: Expecting 41600 events.
[14:53:45.276] INFO: 41600 events read in total (2694ms).
[14:53:45.277] INFO: Test took 3904ms.
[14:53:45.323] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:53:46.024] INFO: Expecting 41600 events.
[14:53:49.328] INFO: 41600 events read in total (2777ms).
[14:53:49.329] INFO: Test took 3984ms.
[14:53:49.373] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:53:50.075] INFO: Expecting 41600 events.
[14:53:53.285] INFO: 41600 events read in total (2683ms).
[14:53:53.286] INFO: Test took 3891ms.
[14:53:53.332] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:53:54.032] INFO: Expecting 41600 events.
[14:53:57.105] INFO: 41600 events read in total (2546ms).
[14:53:57.106] INFO: Test took 3753ms.
[14:53:57.151] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:53:57.852] INFO: Expecting 41600 events.
[14:54:01.168] INFO: 41600 events read in total (2789ms).
[14:54:01.169] INFO: Test took 3995ms.
[14:54:01.214] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:54:01.912] INFO: Expecting 41600 events.
[14:54:04.969] INFO: 41600 events read in total (2530ms).
[14:54:04.971] INFO: Test took 3736ms.
[14:54:05.017] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:54:05.719] INFO: Expecting 41600 events.
[14:54:08.754] INFO: 41600 events read in total (2508ms).
[14:54:08.755] INFO: Test took 3715ms.
[14:54:08.799] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:54:09.143] INFO: enter test to run
[14:54:52.310] INFO: test: HighRate no parameter change
[14:54:52.310] INFO: running: highrate
[14:54:52.311] INFO: ----------------------------------------------------------------------
[14:54:52.311] INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[14:54:52.311] INFO: ----------------------------------------------------------------------
[14:54:52.932] INFO: Expecting 208000 events.
[14:55:05.575] INFO: 208000 events read in total (12116ms).
[14:55:05.579] INFO: Test took 13258ms.
[14:55:05.779] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:55:06.048] INFO: number of dead pixels (per ROC): 0 0 0 0 0 0 7 0 2 0 0 0 0 0 0 0
[14:55:06.048] INFO: number of red-efficiency pixels: 98 54 105 207 282 250 240 174 118 152 142 116 148 102 29 37
[14:55:06.048] INFO: number of X-ray hits detected: 87868 57006 96661 153000 159307 163859 160691 116814 99752 124251 123630 105639 110087 64214 28946 34573
[14:55:06.048] 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:55:06.048] INFO: number of Vcal hits detected: 207902 207945 207889 207786 207709 207744 207399 207824 207761 207846 207857 207883 207851 207895 207970 207962
[14:55:06.048] INFO: Vcal hit fiducial efficiency (%): 100.0 100.0 100.0 99.9 99.9 99.9 99.9 99.9 99.9 99.9 99.9 100.0 99.9 100.0 100.0 100.0
[14:55:06.048] INFO: Vcal hit overall efficiency (%): 100.0 100.0 99.9 99.9 99.9 99.9 99.7 99.9 99.9 99.9 99.9 99.9 99.9 99.9 100.0 100.0
[14:55:06.048] INFO: X-ray hit rate [MHz/cm2]: 25.8 16.7 28.3 44.8 46.7 48.0 47.1 34.2 29.2 36.4 36.2 31.0 32.3 18.8 8.5 10.1
[14:55:06.048] INFO: PixTestHighRate::doXPixelAlive() done
[14:55:06.092] INFO: PixTest:: pg_setup set to default.
[14:55:06.110] INFO: enter test to run
[14:55:24.797] INFO: test: HighRate no parameter change
[14:55:24.797] INFO: running: highrate
[14:55:24.799] INFO: ----------------------------------------------------------------------
[14:55:24.799] INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[14:55:24.799] INFO: ----------------------------------------------------------------------
[14:55:25.417] INFO: Expecting 208000 events.
[14:55:39.940] INFO: 208000 events read in total (13996ms).
[14:55:39.947] INFO: Test took 15140ms.
[14:55:40.344] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:55:40.682] INFO: number of dead pixels (per ROC): 0 0 0 0 0 0 7 0 2 0 0 0 0 0 0 0
[14:55:40.682] INFO: number of red-efficiency pixels: 309 146 462 682 965 991 910 594 327 488 466 426 503 303 75 90
[14:55:40.682] INFO: number of X-ray hits detected: 179766 117956 197689 314075 326802 336938 329814 241119 206430 256504 253540 217025 228131 132898 59474 71108
[14:55:40.682] 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:55:40.682] INFO: number of Vcal hits detected: 207655 207849 207438 207215 206824 206817 206607 207314 207526 207467 207491 207527 207444 207659 207922 207906
[14:55:40.682] INFO: Vcal hit fiducial efficiency (%): 99.8 99.9 99.8 99.7 99.5 99.5 99.5 99.7 99.8 99.8 99.8 99.8 99.8 99.9 100.0 100.0
[14:55:40.682] INFO: Vcal hit overall efficiency (%): 99.8 99.9 99.7 99.6 99.4 99.4 99.3 99.7 99.8 99.7 99.8 99.8 99.7 99.8 100.0 100.0
[14:55:40.683] INFO: X-ray hit rate [MHz/cm2]: 52.7 34.6 57.9 92.1 95.8 98.8 96.7 70.7 60.5 75.2 74.3 63.6 66.9 39.0 17.4 20.8
[14:55:40.683] INFO: PixTestHighRate::doXPixelAlive() done
[14:55:40.730] INFO: PixTest:: pg_setup set to default.
[14:55:40.743] INFO: enter test to run
[14:56:39.509] INFO: test: HighRate no parameter change
[14:56:39.509] INFO: running: highrate
[14:56:39.510] INFO: ----------------------------------------------------------------------
[14:56:39.510] INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[14:56:39.510] INFO: ----------------------------------------------------------------------
[14:56:40.124] INFO: Expecting 208000 events.
[14:56:57.361] INFO: 208000 events read in total (16710ms).
[14:56:57.370] INFO: Test took 17850ms.
[14:56:57.941] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:56:58.334] INFO: number of dead pixels (per ROC): 0 0 0 0 0 0 6 0 2 0 0 0 0 0 0 0
[14:56:58.334] INFO: number of red-efficiency pixels: 588 323 926 1552 2121 2007 1936 1203 669 1033 1053 862 1105 598 112 118
[14:56:58.334] INFO: number of X-ray hits detected: 264600 172667 290178 459652 475915 493631 480597 353908 301532 375024 372054 319214 333724 194158 87943 104822
[14:56:58.334] 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:56:58.334] INFO: number of Vcal hits detected: 207265 207639 206527 205864 204541 204763 204698 206227 207106 206739 206708 206960 206613 207273 207887 207877
[14:56:58.334] INFO: Vcal hit fiducial efficiency (%): 99.7 99.8 99.4 99.1 98.5 98.6 98.7 99.2 99.7 99.5 99.4 99.6 99.4 99.7 99.9 99.9
[14:56:58.334] INFO: Vcal hit overall efficiency (%): 99.6 99.8 99.3 99.0 98.3 98.4 98.4 99.1 99.6 99.4 99.4 99.5 99.3 99.7 99.9 99.9
[14:56:58.334] INFO: X-ray hit rate [MHz/cm2]: 77.6 50.6 85.1 134.7 139.5 144.7 140.9 103.7 88.4 109.9 109.1 93.6 97.8 56.9 25.8 30.7
[14:56:58.334] INFO: PixTestHighRate::doXPixelAlive() done
[14:56:58.379] INFO: PixTest:: pg_setup set to default.
[14:56:58.397] INFO: enter test to run
[14:57:01.756] INFO: test: exit no parameter change
[14:57:02.185] QUIET: Connection to board 32 closed.
[14:57:02.186] INFO: pXar: this is the end, my friend