[12:48:50.161]     INFO: *** Welcome to pxar ***
[12:48:50.161]     INFO: *** Today: 2016/05/31
[12:48:50.177]     INFO: *** Version: v1.9.0-796-gef167-dirty
[12:48:50.177]     INFO: readRocDacs: /home/uicpirepix2/ProductionTestResults/M-Y-E-NJ_FPIXTest-17C-FNAL-160524-1120_2016-05-24_11h20m_1464106816/000_FPIXTest_p17//dacParameters35_C0.dat .. /home/uicpirepix2/ProductionTestResults/M-Y-E-NJ_FPIXTest-17C-FNAL-160524-1120_2016-05-24_11h20m_1464106816/000_FPIXTest_p17//dacParameters35_C15.dat
[12:48:50.215]     INFO: readTbmDacs: /home/uicpirepix2/ProductionTestResults/M-Y-E-NJ_FPIXTest-17C-FNAL-160524-1120_2016-05-24_11h20m_1464106816/000_FPIXTest_p17//tbmParameters_C0a.dat .. /home/uicpirepix2/ProductionTestResults/M-Y-E-NJ_FPIXTest-17C-FNAL-160524-1120_2016-05-24_11h20m_1464106816/000_FPIXTest_p17//tbmParameters_C0b.dat
[12:48:50.215]     INFO: readMaskFile: /home/uicpirepix2/ProductionTestResults/M-Y-E-NJ_FPIXTest-17C-FNAL-160524-1120_2016-05-24_11h20m_1464106816/000_FPIXTest_p17//defaultMaskFile.dat
[12:48:50.216]     INFO: readTrimFile: /home/uicpirepix2/ProductionTestResults/M-Y-E-NJ_FPIXTest-17C-FNAL-160524-1120_2016-05-24_11h20m_1464106816/000_FPIXTest_p17//trimParameters35_C0.dat .. /home/uicpirepix2/ProductionTestResults/M-Y-E-NJ_FPIXTest-17C-FNAL-160524-1120_2016-05-24_11h20m_1464106816/000_FPIXTest_p17//trimParameters35_C15.dat
[12:48:50.301]     INFO:         clk: 4
[12:48:50.301]     INFO:         ctr: 4
[12:48:50.301]     INFO:         sda: 19
[12:48:50.301]     INFO:         tin: 9
[12:48:50.301]     INFO:         level: 15
[12:48:50.301]     INFO:         triggerdelay: 0
[12:48:50.301]    QUIET: Instanciating API for pxar v1.9.0+796~gef16747
[12:48:50.301]     INFO: Log level: INFO
[12:48:50.318]    QUIET: Connection to board DTB_WREKRL opened.
[12:48:50.322]     INFO: DTB startup information
--- DTB info------------------------------------------
Board id:    33
HW version:  DTB1.2
FW version:  4.2
SW version:  4.5
USB id:      DTB_WREKRL
MAC address: 40D855118021
Hostname:    pixelDTB033
Comment:     
------------------------------------------------------
[12:48:50.325]     INFO: RPC call hashes of host and DTB match: 398089610
[12:48:51.856]     INFO: DUT info: 
[12:48:51.856]     INFO: The DUT currently contains the following objects:
[12:48:51.856]     INFO:  2 TBM Cores tbm08c (2 ON)
[12:48:51.856]     INFO: 	TBM Core alpha (0): 7 registers set
[12:48:51.856]     INFO: 	TBM Core beta  (1): 7 registers set
[12:48:51.856]     INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[12:48:51.856]     INFO: 	ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[12:48:51.856]     INFO: 	ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[12:48:51.856]     INFO: 	ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[12:48:51.856]     INFO: 	ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[12:48:51.856]     INFO: 	ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[12:48:51.857]     INFO: 	ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[12:48:51.857]     INFO: 	ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[12:48:51.857]     INFO: 	ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[12:48:51.857]     INFO: 	ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[12:48:51.857]     INFO: 	ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[12:48:51.857]     INFO: 	ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[12:48:51.857]     INFO: 	ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[12:48:51.857]     INFO: 	ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[12:48:51.857]     INFO: 	ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[12:48:51.857]     INFO: 	ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[12:48:51.857]     INFO: 	ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[12:48:52.260]     INFO: enter 'restricted' command line mode
[12:48:52.261]     INFO: enter test to run
[12:49:02.062]     INFO:   test: PixelAlive no parameter change
[12:49:02.062]     INFO:   running: pixelalive
[12:49:02.071]     INFO:    ----------------------------------------------------------------------
[12:49:02.071]     INFO:    PixTestAlive::aliveTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[12:49:02.071]     INFO:    ----------------------------------------------------------------------
[12:49:02.390]     INFO: Expecting 41600 events.
[12:49:06.718]     INFO: 41600 events read in total (3609ms).
[12:49:06.887]     INFO: Test took 4813ms.
[12:49:06.899]     INFO: Fetched DAQ statistics. Counters are being reset now.
[12:49:07.160]     INFO: PixTestAlive::aliveTest() done
[12:49:07.160]     INFO: number of dead pixels (per ROC):     0    0    0    0    0    0    0    0    0    0    0    0    0    0    0    0
[12:49:07.193]     INFO: enter test to run
[12:49:39.886]     INFO:   test: HighRate setting parameters: ->runsecondshotpixels=5;trimhotpixelthr=200<-
[12:49:39.886]     INFO:   running: highrate
[12:49:39.886]     INFO: readGainPedestalParameters /home/uicpirepix2/ProductionTestResults/M-Y-E-NJ_FPIXTest-17C-FNAL-160524-1120_2016-05-24_11h20m_1464106816/000_FPIXTest_p17//phCalibrationFitErr35_C0.dat .. /home/uicpirepix2/ProductionTestResults/M-Y-E-NJ_FPIXTest-17C-FNAL-160524-1120_2016-05-24_11h20m_1464106816/000_FPIXTest_p17//phCalibrationFitErr35_C15.dat
[12:49:40.106]     INFO:    ----------------------------------------------------------------------
[12:49:40.106]     INFO:    PixTest::trimHotPixels() running for 5 seconds with 100 kHz trigger rate
[12:49:40.106]     INFO:    ----------------------------------------------------------------------
[12:49:40.106]     INFO: THR = 200, corresponding to ~ 106.667 MHz/cm2
[12:49:40.106]     INFO: edge/corner pixel THR is adjusted
[12:49:40.106]     INFO: PixTestHighRate::trimHotPixels: step 0...
[12:49:41.067]     INFO: Collecting data for 5 seconds...
[12:49:46.082]     INFO: Done with hot pixel readout
[12:49:58.800]     INFO: PixTest::       pg_setup set to default.
[12:49:58.801]  WARNING:   => trimBits already at highest possible threshold, 'real' hot pixel found
[12:49:58.801]  WARNING:   => trimBits already at highest possible threshold, 'real' hot pixel found
[12:49:58.801]  WARNING:   => trimBits already at highest possible threshold, 'real' hot pixel found
[12:49:58.828]     INFO: 90 hot pixels found in step 0
[12:49:59.824]     INFO: Collecting data for 5 seconds...
[12:50:04.839]     INFO: Done with hot pixel readout
[12:50:17.575]     INFO: PixTest::       pg_setup set to default.
[12:50:17.575]  WARNING:   => trimBits already at highest possible threshold, 'real' hot pixel found
[12:50:17.575]  WARNING:   => trimBits already at highest possible threshold, 'real' hot pixel found
[12:50:17.576]  WARNING:   => trimBits already at highest possible threshold, 'real' hot pixel found
[12:50:17.576]     INFO: 83 hot pixels found in step 1
[12:50:18.571]     INFO: Collecting data for 5 seconds...
[12:50:23.586]     INFO: Done with hot pixel readout
[12:50:36.346]     INFO: PixTest::       pg_setup set to default.
[12:50:36.346]  WARNING:   => trimBits already at highest possible threshold, 'real' hot pixel found
[12:50:36.346]  WARNING:   => trimBits already at highest possible threshold, 'real' hot pixel found
[12:50:36.346]  WARNING:   => trimBits already at highest possible threshold, 'real' hot pixel found
[12:50:36.346]  WARNING:   => trimBits already at highest possible threshold, 'real' hot pixel found
[12:50:36.346]  WARNING:   => trimBits already at highest possible threshold, 'real' hot pixel found
[12:50:36.346]  WARNING:   => trimBits already at highest possible threshold, 'real' hot pixel found
[12:50:36.347]     INFO: 81 hot pixels found in step 2
[12:50:37.343]     INFO: Collecting data for 5 seconds...
[12:50:42.358]     INFO: Done with hot pixel readout
[12:50:55.116]     INFO: PixTest::       pg_setup set to default.
[12:50:55.117]  WARNING:   => trimBits already at highest possible threshold, 'real' hot pixel found
[12:50:55.117]  WARNING:   => trimBits already at highest possible threshold, 'real' hot pixel found
[12:50:55.117]  WARNING:   => trimBits already at highest possible threshold, 'real' hot pixel found
[12:50:55.117]  WARNING:   => trimBits already at highest possible threshold, 'real' hot pixel found
[12:50:55.117]  WARNING:   => trimBits already at highest possible threshold, 'real' hot pixel found
[12:50:55.117]  WARNING:   => trimBits already at highest possible threshold, 'real' hot pixel found
[12:50:55.117]  WARNING:   => trimBits already at highest possible threshold, 'real' hot pixel found
[12:50:55.117]  WARNING:   => trimBits already at highest possible threshold, 'real' hot pixel found
[12:50:55.117]  WARNING:   => trimBits already at highest possible threshold, 'real' hot pixel found
[12:50:55.117]  WARNING:   => trimBits already at highest possible threshold, 'real' hot pixel found
[12:50:55.117]  WARNING:   => trimBits already at highest possible threshold, 'real' hot pixel found
[12:50:55.118]     INFO: 77 hot pixels found in step 3
[12:50:56.114]     INFO: Collecting data for 5 seconds...
[12:51:01.131]     INFO: Done with hot pixel readout
[12:51:13.881]     INFO: PixTest::       pg_setup set to default.
[12:51:13.881]  WARNING:   => trimBits already at highest possible threshold, 'real' hot pixel found
[12:51:13.881]  WARNING:   => trimBits already at highest possible threshold, 'real' hot pixel found
[12:51:13.881]  WARNING:   => trimBits already at highest possible threshold, 'real' hot pixel found
[12:51:13.881]  WARNING:   => trimBits already at highest possible threshold, 'real' hot pixel found
[12:51:13.881]  WARNING:   => trimBits already at highest possible threshold, 'real' hot pixel found
[12:51:13.881]  WARNING:   => trimBits already at highest possible threshold, 'real' hot pixel found
[12:51:13.881]  WARNING:   => trimBits already at highest possible threshold, 'real' hot pixel found
[12:51:13.881]  WARNING:   => trimBits already at highest possible threshold, 'real' hot pixel found
[12:51:13.881]  WARNING:   => trimBits already at highest possible threshold, 'real' hot pixel found
[12:51:13.882]     INFO: 76 hot pixels found in step 4
[12:51:14.878]     INFO: Collecting data for 5 seconds...
[12:51:19.894]     INFO: Done with hot pixel readout
[12:51:32.648]     INFO: PixTest::       pg_setup set to default.
[12:51:32.648]  WARNING:   => trimBits already at highest possible threshold, 'real' hot pixel found
[12:51:32.648]  WARNING:   => trimBits already at highest possible threshold, 'real' hot pixel found
[12:51:32.648]  WARNING:   => trimBits already at highest possible threshold, 'real' hot pixel found
[12:51:32.648]  WARNING:   => trimBits already at highest possible threshold, 'real' hot pixel found
[12:51:32.648]  WARNING:   => trimBits already at highest possible threshold, 'real' hot pixel found
[12:51:32.648]  WARNING:   => trimBits already at highest possible threshold, 'real' hot pixel found
[12:51:32.649]     INFO: 72 hot pixels found in step 5
[12:51:33.647]     INFO: Collecting data for 5 seconds...
[12:51:38.661]     INFO: Done with hot pixel readout
[12:51:51.406]     INFO: PixTest::       pg_setup set to default.
[12:51:51.406]  WARNING:   => trimBits already at highest possible threshold, 'real' hot pixel found
[12:51:51.406]  WARNING:   => trimBits already at highest possible threshold, 'real' hot pixel found
[12:51:51.406]  WARNING:   => trimBits already at highest possible threshold, 'real' hot pixel found
[12:51:51.407]  WARNING:   => trimBits already at highest possible threshold, 'real' hot pixel found
[12:51:51.407]  WARNING:   => trimBits already at highest possible threshold, 'real' hot pixel found
[12:51:51.407]  WARNING:   => trimBits already at highest possible threshold, 'real' hot pixel found
[12:51:51.407]  WARNING:   => trimBits already at highest possible threshold, 'real' hot pixel found
[12:51:51.407]  WARNING:   => trimBits already at highest possible threshold, 'real' hot pixel found
[12:51:51.407]  WARNING:   => trimBits already at highest possible threshold, 'real' hot pixel found
[12:51:51.407]     INFO: 54 hot pixels found in step 6
[12:51:52.404]     INFO: Collecting data for 5 seconds...
[12:51:57.419]     INFO: Done with hot pixel readout
[12:52:10.139]     INFO: PixTest::       pg_setup set to default.
[12:52:10.139]  WARNING:   => trimBits already at highest possible threshold, 'real' hot pixel found
[12:52:10.139]  WARNING:   => trimBits already at highest possible threshold, 'real' hot pixel found
[12:52:10.139]  WARNING:   => trimBits already at highest possible threshold, 'real' hot pixel found
[12:52:10.139]  WARNING:   => trimBits already at highest possible threshold, 'real' hot pixel found
[12:52:10.139]  WARNING:   => trimBits already at highest possible threshold, 'real' hot pixel found
[12:52:10.139]  WARNING:   => trimBits already at highest possible threshold, 'real' hot pixel found
[12:52:10.139]  WARNING:   => trimBits already at highest possible threshold, 'real' hot pixel found
[12:52:10.139]  WARNING:   => trimBits already at highest possible threshold, 'real' hot pixel found
[12:52:10.140]     INFO: 65 hot pixels found in step 7
[12:52:11.138]     INFO: Collecting data for 5 seconds...
[12:52:16.153]     INFO: Done with hot pixel readout
[12:52:28.883]     INFO: PixTest::       pg_setup set to default.
[12:52:28.883]  WARNING:   => trimBits already at highest possible threshold, 'real' hot pixel found
[12:52:28.883]  WARNING:   => trimBits already at highest possible threshold, 'real' hot pixel found
[12:52:28.883]  WARNING:   => trimBits already at highest possible threshold, 'real' hot pixel found
[12:52:28.883]  WARNING:   => trimBits already at highest possible threshold, 'real' hot pixel found
[12:52:28.883]  WARNING:   => trimBits already at highest possible threshold, 'real' hot pixel found
[12:52:28.883]  WARNING:   => trimBits already at highest possible threshold, 'real' hot pixel found
[12:52:28.883]  WARNING:   => trimBits already at highest possible threshold, 'real' hot pixel found
[12:52:28.883]  WARNING:   => trimBits already at highest possible threshold, 'real' hot pixel found
[12:52:28.884]     INFO: 61 hot pixels found in step 8
[12:52:29.881]     INFO: Collecting data for 5 seconds...
[12:52:34.896]     INFO: Done with hot pixel readout
[12:52:47.638]     INFO: PixTest::       pg_setup set to default.
[12:52:47.638]  WARNING:   => trimBits already at highest possible threshold, 'real' hot pixel found
[12:52:47.638]  WARNING:   => trimBits already at highest possible threshold, 'real' hot pixel found
[12:52:47.638]  WARNING:   => trimBits already at highest possible threshold, 'real' hot pixel found
[12:52:47.638]  WARNING:   => trimBits already at highest possible threshold, 'real' hot pixel found
[12:52:47.638]  WARNING:   => trimBits already at highest possible threshold, 'real' hot pixel found
[12:52:47.638]  WARNING:   => trimBits already at highest possible threshold, 'real' hot pixel found
[12:52:47.638]  WARNING:   => trimBits already at highest possible threshold, 'real' hot pixel found
[12:52:47.639]     INFO: 62 hot pixels found in step 9
[12:52:48.637]     INFO: Collecting data for 5 seconds...
[12:52:53.652]     INFO: Done with hot pixel readout
[12:53:06.455]     INFO: PixTest::       pg_setup set to default.
[12:53:06.455]  WARNING:   => trimBits already at highest possible threshold, 'real' hot pixel found
[12:53:06.455]  WARNING:   => trimBits already at highest possible threshold, 'real' hot pixel found
[12:53:06.455]  WARNING:   => trimBits already at highest possible threshold, 'real' hot pixel found
[12:53:06.455]  WARNING:   => trimBits already at highest possible threshold, 'real' hot pixel found
[12:53:06.455]  WARNING:   => trimBits already at highest possible threshold, 'real' hot pixel found
[12:53:06.455]  WARNING:   => trimBits already at highest possible threshold, 'real' hot pixel found
[12:53:06.455]  WARNING:   => trimBits already at highest possible threshold, 'real' hot pixel found
[12:53:06.455]  WARNING:   => trimBits already at highest possible threshold, 'real' hot pixel found
[12:53:06.455]  WARNING:   => trimBits already at highest possible threshold, 'real' hot pixel found
[12:53:06.455]  WARNING:   => trimBits already at highest possible threshold, 'real' hot pixel found
[12:53:06.455]  WARNING:   => trimBits already at highest possible threshold, 'real' hot pixel found
[12:53:06.456]     INFO: 48 hot pixels found in step 10
[12:53:07.454]     INFO: Collecting data for 5 seconds...
[12:53:12.469]     INFO: Done with hot pixel readout
[12:53:25.186]     INFO: PixTest::       pg_setup set to default.
[12:53:25.186]  WARNING:   => trimBits already at highest possible threshold, 'real' hot pixel found
[12:53:25.186]  WARNING:   => trimBits already at highest possible threshold, 'real' hot pixel found
[12:53:25.186]  WARNING:   => trimBits already at highest possible threshold, 'real' hot pixel found
[12:53:25.186]  WARNING:   => trimBits already at highest possible threshold, 'real' hot pixel found
[12:53:25.186]  WARNING:   => trimBits already at highest possible threshold, 'real' hot pixel found
[12:53:25.186]  WARNING:   => trimBits already at highest possible threshold, 'real' hot pixel found
[12:53:25.186]  WARNING:   => trimBits already at highest possible threshold, 'real' hot pixel found
[12:53:25.186]  WARNING:   => trimBits already at highest possible threshold, 'real' hot pixel found
[12:53:25.187]     INFO: 57 hot pixels found in step 11
[12:53:26.185]     INFO: Collecting data for 5 seconds...
[12:53:31.200]     INFO: Done with hot pixel readout
[12:53:43.935]     INFO: PixTest::       pg_setup set to default.
[12:53:43.935]  WARNING:   => trimBits already at highest possible threshold, 'real' hot pixel found
[12:53:43.935]  WARNING:   => trimBits already at highest possible threshold, 'real' hot pixel found
[12:53:43.935]  WARNING:   => trimBits already at highest possible threshold, 'real' hot pixel found
[12:53:43.935]  WARNING:   => trimBits already at highest possible threshold, 'real' hot pixel found
[12:53:43.935]  WARNING:   => trimBits already at highest possible threshold, 'real' hot pixel found
[12:53:43.935]  WARNING:   => trimBits already at highest possible threshold, 'real' hot pixel found
[12:53:43.935]  WARNING:   => trimBits already at highest possible threshold, 'real' hot pixel found
[12:53:43.935]  WARNING:   => trimBits already at highest possible threshold, 'real' hot pixel found
[12:53:43.935]  WARNING:   => trimBits already at highest possible threshold, 'real' hot pixel found
[12:53:43.936]  WARNING:   => trimBits already at highest possible threshold, 'real' hot pixel found
[12:53:43.936]     INFO: 55 hot pixels found in step 12
[12:53:44.933]     INFO: Collecting data for 5 seconds...
[12:53:49.948]     INFO: Done with hot pixel readout
[12:54:02.675]     INFO: PixTest::       pg_setup set to default.
[12:54:02.675]  WARNING:   => trimBits already at highest possible threshold, 'real' hot pixel found
[12:54:02.675]  WARNING:   => trimBits already at highest possible threshold, 'real' hot pixel found
[12:54:02.675]  WARNING:   => trimBits already at highest possible threshold, 'real' hot pixel found
[12:54:02.675]  WARNING:   => trimBits already at highest possible threshold, 'real' hot pixel found
[12:54:02.675]  WARNING:   => trimBits already at highest possible threshold, 'real' hot pixel found
[12:54:02.675]  WARNING:   => trimBits already at highest possible threshold, 'real' hot pixel found
[12:54:02.676]     INFO: 46 hot pixels found in step 13
[12:54:03.673]     INFO: Collecting data for 5 seconds...
[12:54:08.688]     INFO: Done with hot pixel readout
[12:54:21.432]     INFO: PixTest::       pg_setup set to default.
[12:54:21.432]  WARNING:   => trimBits already at highest possible threshold, 'real' hot pixel found
[12:54:21.432]  WARNING:   => trimBits already at highest possible threshold, 'real' hot pixel found
[12:54:21.432]  WARNING:   => trimBits already at highest possible threshold, 'real' hot pixel found
[12:54:21.432]  WARNING:   => trimBits already at highest possible threshold, 'real' hot pixel found
[12:54:21.432]  WARNING:   => trimBits already at highest possible threshold, 'real' hot pixel found
[12:54:21.432]  WARNING:   => trimBits already at highest possible threshold, 'real' hot pixel found
[12:54:21.432]  WARNING:   => trimBits already at highest possible threshold, 'real' hot pixel found
[12:54:21.433]     INFO: 47 hot pixels found in step 14
[12:54:21.474]     INFO: 47 hot pixels could not be trimmed and have been masked.
[12:54:21.477]     INFO: PixTest::trimHotPixels() done
[12:54:21.477]     INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Y-E-NJ_FPIXTest-17C-FNAL-160524-1120_2016-05-24_11h20m_1464106816/000_FPIXTest_p17//trimParameters35_C0.dat
[12:54:21.483]     INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Y-E-NJ_FPIXTest-17C-FNAL-160524-1120_2016-05-24_11h20m_1464106816/000_FPIXTest_p17//trimParameters35_C1.dat
[12:54:21.489]     INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Y-E-NJ_FPIXTest-17C-FNAL-160524-1120_2016-05-24_11h20m_1464106816/000_FPIXTest_p17//trimParameters35_C2.dat
[12:54:21.494]     INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Y-E-NJ_FPIXTest-17C-FNAL-160524-1120_2016-05-24_11h20m_1464106816/000_FPIXTest_p17//trimParameters35_C3.dat
[12:54:21.500]     INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Y-E-NJ_FPIXTest-17C-FNAL-160524-1120_2016-05-24_11h20m_1464106816/000_FPIXTest_p17//trimParameters35_C4.dat
[12:54:21.505]     INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Y-E-NJ_FPIXTest-17C-FNAL-160524-1120_2016-05-24_11h20m_1464106816/000_FPIXTest_p17//trimParameters35_C5.dat
[12:54:21.510]     INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Y-E-NJ_FPIXTest-17C-FNAL-160524-1120_2016-05-24_11h20m_1464106816/000_FPIXTest_p17//trimParameters35_C6.dat
[12:54:21.515]     INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Y-E-NJ_FPIXTest-17C-FNAL-160524-1120_2016-05-24_11h20m_1464106816/000_FPIXTest_p17//trimParameters35_C7.dat
[12:54:21.520]     INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Y-E-NJ_FPIXTest-17C-FNAL-160524-1120_2016-05-24_11h20m_1464106816/000_FPIXTest_p17//trimParameters35_C8.dat
[12:54:21.526]     INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Y-E-NJ_FPIXTest-17C-FNAL-160524-1120_2016-05-24_11h20m_1464106816/000_FPIXTest_p17//trimParameters35_C9.dat
[12:54:21.531]     INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Y-E-NJ_FPIXTest-17C-FNAL-160524-1120_2016-05-24_11h20m_1464106816/000_FPIXTest_p17//trimParameters35_C10.dat
[12:54:21.536]     INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Y-E-NJ_FPIXTest-17C-FNAL-160524-1120_2016-05-24_11h20m_1464106816/000_FPIXTest_p17//trimParameters35_C11.dat
[12:54:21.541]     INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Y-E-NJ_FPIXTest-17C-FNAL-160524-1120_2016-05-24_11h20m_1464106816/000_FPIXTest_p17//trimParameters35_C12.dat
[12:54:21.546]     INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Y-E-NJ_FPIXTest-17C-FNAL-160524-1120_2016-05-24_11h20m_1464106816/000_FPIXTest_p17//trimParameters35_C13.dat
[12:54:21.552]     INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Y-E-NJ_FPIXTest-17C-FNAL-160524-1120_2016-05-24_11h20m_1464106816/000_FPIXTest_p17//trimParameters35_C14.dat
[12:54:21.557]     INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Y-E-NJ_FPIXTest-17C-FNAL-160524-1120_2016-05-24_11h20m_1464106816/000_FPIXTest_p17//trimParameters35_C15.dat
[12:54:21.562]     INFO: write masked pixels into /home/uicpirepix2/ProductionTestResults/M-Y-E-NJ_FPIXTest-17C-FNAL-160524-1120_2016-05-24_11h20m_1464106816/000_FPIXTest_p17//defaultMaskFile.dat
[12:54:21.572]     INFO: enter test to run
[12:55:15.131]     INFO:   test: HighRate setting parameters: ->runsecondshotpixels=1;trimhotpixelthr=200<-
[12:55:15.131]     INFO:   running: highrate
[12:55:15.136]     INFO:    ----------------------------------------------------------------------
[12:55:15.136]     INFO:    PixTest::trimHotPixels() running for 1 seconds with 100 kHz trigger rate
[12:55:15.136]     INFO:    ----------------------------------------------------------------------
[12:55:15.136]     INFO: THR = 200, corresponding to ~ 533.333 MHz/cm2
[12:55:15.136]     INFO: edge/corner pixel THR is adjusted
[12:55:15.136]     INFO: PixTestHighRate::trimHotPixels: step 0...
[12:55:16.094]     INFO: Collecting data for 1 seconds...
[12:55:17.098]     INFO: Done with hot pixel readout
[12:55:21.728]     INFO: PixTest::       pg_setup set to default.
[12:55:21.728]     INFO: 0 hot pixels found in step 0
[12:55:21.734]     INFO: 0 hot pixels could not be trimmed and have been masked.
[12:55:21.801]     INFO: PixTest::trimHotPixels() done
[12:55:21.801]     INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Y-E-NJ_FPIXTest-17C-FNAL-160524-1120_2016-05-24_11h20m_1464106816/000_FPIXTest_p17//trimParameters35_C0.dat
[12:55:21.812]     INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Y-E-NJ_FPIXTest-17C-FNAL-160524-1120_2016-05-24_11h20m_1464106816/000_FPIXTest_p17//trimParameters35_C1.dat
[12:55:21.818]     INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Y-E-NJ_FPIXTest-17C-FNAL-160524-1120_2016-05-24_11h20m_1464106816/000_FPIXTest_p17//trimParameters35_C2.dat
[12:55:21.823]     INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Y-E-NJ_FPIXTest-17C-FNAL-160524-1120_2016-05-24_11h20m_1464106816/000_FPIXTest_p17//trimParameters35_C3.dat
[12:55:21.828]     INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Y-E-NJ_FPIXTest-17C-FNAL-160524-1120_2016-05-24_11h20m_1464106816/000_FPIXTest_p17//trimParameters35_C4.dat
[12:55:21.833]     INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Y-E-NJ_FPIXTest-17C-FNAL-160524-1120_2016-05-24_11h20m_1464106816/000_FPIXTest_p17//trimParameters35_C5.dat
[12:55:21.839]     INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Y-E-NJ_FPIXTest-17C-FNAL-160524-1120_2016-05-24_11h20m_1464106816/000_FPIXTest_p17//trimParameters35_C6.dat
[12:55:21.844]     INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Y-E-NJ_FPIXTest-17C-FNAL-160524-1120_2016-05-24_11h20m_1464106816/000_FPIXTest_p17//trimParameters35_C7.dat
[12:55:21.849]     INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Y-E-NJ_FPIXTest-17C-FNAL-160524-1120_2016-05-24_11h20m_1464106816/000_FPIXTest_p17//trimParameters35_C8.dat
[12:55:21.854]     INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Y-E-NJ_FPIXTest-17C-FNAL-160524-1120_2016-05-24_11h20m_1464106816/000_FPIXTest_p17//trimParameters35_C9.dat
[12:55:21.859]     INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Y-E-NJ_FPIXTest-17C-FNAL-160524-1120_2016-05-24_11h20m_1464106816/000_FPIXTest_p17//trimParameters35_C10.dat
[12:55:21.864]     INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Y-E-NJ_FPIXTest-17C-FNAL-160524-1120_2016-05-24_11h20m_1464106816/000_FPIXTest_p17//trimParameters35_C11.dat
[12:55:21.869]     INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Y-E-NJ_FPIXTest-17C-FNAL-160524-1120_2016-05-24_11h20m_1464106816/000_FPIXTest_p17//trimParameters35_C12.dat
[12:55:21.874]     INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Y-E-NJ_FPIXTest-17C-FNAL-160524-1120_2016-05-24_11h20m_1464106816/000_FPIXTest_p17//trimParameters35_C13.dat
[12:55:21.879]     INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Y-E-NJ_FPIXTest-17C-FNAL-160524-1120_2016-05-24_11h20m_1464106816/000_FPIXTest_p17//trimParameters35_C14.dat
[12:55:21.885]     INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Y-E-NJ_FPIXTest-17C-FNAL-160524-1120_2016-05-24_11h20m_1464106816/000_FPIXTest_p17//trimParameters35_C15.dat
[12:55:21.890]     INFO: write masked pixels into /home/uicpirepix2/ProductionTestResults/M-Y-E-NJ_FPIXTest-17C-FNAL-160524-1120_2016-05-24_11h20m_1464106816/000_FPIXTest_p17//defaultMaskFile.dat
[12:55:21.900]     INFO: enter test to run
[12:55:48.387]     INFO:   test: Xray setting parameters: ->source=DCLowRate<-
[12:55:48.387]     INFO:   running: xray
[12:55:48.388]     INFO:    ----------------------------------------------------------------------
[12:55:48.388]     INFO:    PixTestXray::doPhRun() fParRunSeconds = 100
[12:55:48.388]     INFO:    ----------------------------------------------------------------------
[12:55:49.352]     INFO: PixTestXray::doPhRun start TriggerLoop with trigger frequency 100 kHz, period 405 and duration 100 seconds,  fEventsMax = 10000000
[12:55:59.667]     INFO: run duration 10 seconds, buffer almost full (81%), pausing triggers.
[12:56:29.073]     INFO: Resuming triggers.
[12:56:39.391]     INFO: run duration 20 seconds, buffer almost full (81%), pausing triggers.
[12:57:08.885]     INFO: Resuming triggers.
[12:57:19.202]     INFO: run duration 30 seconds, buffer almost full (81%), pausing triggers.
[12:57:48.688]     INFO: Resuming triggers.
[12:57:58.004]     INFO: run duration 41 seconds, buffer almost full (81%), pausing triggers.
[12:58:28.559]     INFO: Resuming triggers.
[12:58:38.879]     INFO: run duration 51 seconds, buffer almost full (81%), pausing triggers.
[12:59:08.366]     INFO: Resuming triggers.
[12:59:18.689]     INFO: run duration 61 seconds, buffer almost full (81%), pausing triggers.
[12:59:48.179]     INFO: Resuming triggers.
[12:59:58.508]     INFO: run duration 72 seconds, buffer almost full (81%), pausing triggers.
[13:00:27.997]     INFO: Resuming triggers.
[13:00:38.328]     INFO: run duration 82 seconds, buffer almost full (81%), pausing triggers.
[13:01:07.743]     INFO: Resuming triggers.
[13:01:18.074]     INFO: run duration 92 seconds, buffer almost full (81%), pausing triggers.
[13:01:47.621]     INFO: Resuming triggers.
[13:01:55.021]     INFO: data taking finished, elapsed time: 100 seconds.
[13:02:16.453]     INFO: PixTest::       pg_setup set to default.
[13:02:16.456]     INFO: PixTestXray::doPhRun() done
[13:02:16.596]     INFO: enter test to run
[13:02:59.240]     INFO:   test: Xray setting parameters: ->source=DCHighRate<-
[13:02:59.240]     INFO:   running: xray
[13:02:59.241]     INFO:    ----------------------------------------------------------------------
[13:02:59.241]     INFO:    PixTestXray::doPhRun() fParRunSeconds = 100
[13:02:59.241]     INFO:    ----------------------------------------------------------------------
[13:03:00.206]     INFO: PixTestXray::doPhRun start TriggerLoop with trigger frequency 100 kHz, period 405 and duration 100 seconds,  fEventsMax = 10000000
[13:03:05.697]     INFO: run duration 5 seconds, buffer almost full (81%), pausing triggers.
[13:03:35.905]     INFO: Resuming triggers.
[13:03:41.397]     INFO: run duration 10 seconds, buffer almost full (81%), pausing triggers.
[13:04:11.510]     INFO: Resuming triggers.
[13:04:16.999]     INFO: run duration 16 seconds, buffer almost full (81%), pausing triggers.
[13:04:47.137]     INFO: Resuming triggers.
[13:04:52.630]     INFO: run duration 21 seconds, buffer almost full (81%), pausing triggers.
[13:05:22.815]     INFO: Resuming triggers.
[13:05:28.308]     INFO: run duration 27 seconds, buffer almost full (81%), pausing triggers.
[13:05:58.487]     INFO: Resuming triggers.
[13:06:03.977]     INFO: run duration 32 seconds, buffer almost full (81%), pausing triggers.
[13:06:34.094]     INFO: Resuming triggers.
[13:06:39.585]     INFO: run duration 38 seconds, buffer almost full (81%), pausing triggers.
[13:07:09.722]     INFO: Resuming triggers.
[13:07:15.217]     INFO: run duration 43 seconds, buffer almost full (81%), pausing triggers.
[13:07:45.408]     INFO: Resuming triggers.
[13:07:50.900]     INFO: run duration 49 seconds, buffer almost full (81%), pausing triggers.
[13:08:21.067]     INFO: Resuming triggers.
[13:08:26.563]     INFO: run duration 54 seconds, buffer almost full (81%), pausing triggers.
[13:08:56.718]     INFO: Resuming triggers.
[13:09:02.214]     INFO: run duration 60 seconds, buffer almost full (81%), pausing triggers.
[13:09:32.312]     INFO: Resuming triggers.
[13:09:37.808]     INFO: run duration 65 seconds, buffer almost full (81%), pausing triggers.
[13:10:08.041]     INFO: Resuming triggers.
[13:10:13.539]     INFO: run duration 71 seconds, buffer almost full (81%), pausing triggers.
[13:10:43.640]     INFO: Resuming triggers.
[13:10:49.139]     INFO: run duration 76 seconds, buffer almost full (81%), pausing triggers.
[13:11:19.279]     INFO: Resuming triggers.
[13:11:24.776]     INFO: run duration 82 seconds, buffer almost full (81%), pausing triggers.
[13:11:54.939]     INFO: Resuming triggers.
[13:12:00.439]     INFO: run duration 87 seconds, buffer almost full (81%), pausing triggers.
[13:12:30.565]     INFO: Resuming triggers.
[13:12:36.067]     INFO: run duration 93 seconds, buffer almost full (81%), pausing triggers.
[13:13:06.184]     INFO: Resuming triggers.
[13:13:11.686]     INFO: run duration 98 seconds, buffer almost full (81%), pausing triggers.
[13:13:41.839]     INFO: Resuming triggers.
[13:13:43.224]     INFO: data taking finished, elapsed time: 100 seconds.
[13:13:51.156]     INFO: PixTest::       pg_setup set to default.
[13:13:51.159]     INFO: PixTestXray::doPhRun() done
[13:13:51.311]     INFO: enter test to run
[13:14:27.019]     INFO:   test: HighRate no parameter change
[13:14:27.019]     INFO:   running: highrate
[13:14:27.020]     INFO:    ----------------------------------------------------------------------
[13:14:27.020]     INFO:    PixTestHighRate::calDelScan() ntrig = 10, vcal = 200
[13:14:27.020]     INFO:    ----------------------------------------------------------------------
[13:14:27.160]     INFO: Expecting 768 events.
[13:14:28.294]     INFO: 768 events read in total (419ms).
[13:14:28.294]     INFO: Test took 1268ms.
[13:14:29.097]     INFO: Expecting 41600 events.
[13:14:32.265]     INFO: 41600 events read in total (2641ms).
[13:14:32.266]     INFO: Test took 3964ms.
[13:14:32.308]     INFO: Fetched DAQ statistics. Counters are being reset now.
[13:14:33.019]     INFO: Expecting 41600 events.
[13:14:36.299]     INFO: 41600 events read in total (2753ms).
[13:14:36.300]     INFO: Test took 3971ms.
[13:14:36.341]     INFO: Fetched DAQ statistics. Counters are being reset now.
[13:14:37.049]     INFO: Expecting 41600 events.
[13:14:40.357]     INFO: 41600 events read in total (2781ms).
[13:14:40.358]     INFO: Test took 3996ms.
[13:14:40.401]     INFO: Fetched DAQ statistics. Counters are being reset now.
[13:14:41.109]     INFO: Expecting 41600 events.
[13:14:44.414]     INFO: 41600 events read in total (2778ms).
[13:14:44.415]     INFO: Test took 3992ms.
[13:14:44.458]     INFO: Fetched DAQ statistics. Counters are being reset now.
[13:14:45.165]     INFO: Expecting 41600 events.
[13:14:48.486]     INFO: 41600 events read in total (2794ms).
[13:14:48.487]     INFO: Test took 4009ms.
[13:14:48.530]     INFO: Fetched DAQ statistics. Counters are being reset now.
[13:14:49.239]     INFO: Expecting 41600 events.
[13:14:52.552]     INFO: 41600 events read in total (2786ms).
[13:14:52.553]     INFO: Test took 4002ms.
[13:14:52.595]     INFO: Fetched DAQ statistics. Counters are being reset now.
[13:14:53.306]     INFO: Expecting 41600 events.
[13:14:56.628]     INFO: 41600 events read in total (2795ms).
[13:14:56.629]     INFO: Test took 4014ms.
[13:14:56.672]     INFO: Fetched DAQ statistics. Counters are being reset now.
[13:14:57.377]     INFO: Expecting 41600 events.
[13:15:00.696]     INFO: 41600 events read in total (2793ms).
[13:15:00.698]     INFO: Test took 4005ms.
[13:15:00.740]     INFO: Fetched DAQ statistics. Counters are being reset now.
[13:15:01.445]     INFO: Expecting 41600 events.
[13:15:04.777]     INFO: 41600 events read in total (2805ms).
[13:15:04.778]     INFO: Test took 4017ms.
[13:15:04.820]     INFO: Fetched DAQ statistics. Counters are being reset now.
[13:15:05.527]     INFO: Expecting 41600 events.
[13:15:08.869]     INFO: 41600 events read in total (2815ms).
[13:15:08.870]     INFO: Test took 4028ms.
[13:15:08.912]     INFO: Fetched DAQ statistics. Counters are being reset now.
[13:15:09.620]     INFO: Expecting 41600 events.
[13:15:12.936]     INFO: 41600 events read in total (2789ms).
[13:15:12.937]     INFO: Test took 4003ms.
[13:15:12.979]     INFO: Fetched DAQ statistics. Counters are being reset now.
[13:15:13.685]     INFO: Expecting 41600 events.
[13:15:16.995]     INFO: 41600 events read in total (2783ms).
[13:15:16.996]     INFO: Test took 3997ms.
[13:15:17.038]     INFO: Fetched DAQ statistics. Counters are being reset now.
[13:15:17.748]     INFO: Expecting 41600 events.
[13:15:21.072]     INFO: 41600 events read in total (2797ms).
[13:15:21.073]     INFO: Test took 4014ms.
[13:15:21.117]     INFO: Fetched DAQ statistics. Counters are being reset now.
[13:15:21.821]     INFO: Expecting 41600 events.
[13:15:25.133]     INFO: 41600 events read in total (2785ms).
[13:15:25.134]     INFO: Test took 3996ms.
[13:15:25.178]     INFO: Fetched DAQ statistics. Counters are being reset now.
[13:15:25.885]     INFO: Expecting 41600 events.
[13:15:29.209]     INFO: 41600 events read in total (2797ms).
[13:15:29.210]     INFO: Test took 4009ms.
[13:15:29.252]     INFO: Fetched DAQ statistics. Counters are being reset now.
[13:15:29.959]     INFO: Expecting 41600 events.
[13:15:33.291]     INFO: 41600 events read in total (2806ms).
[13:15:33.292]     INFO: Test took 4020ms.
[13:15:33.335]     INFO: Fetched DAQ statistics. Counters are being reset now.
[13:15:34.042]     INFO: Expecting 41600 events.
[13:15:37.378]     INFO: 41600 events read in total (2809ms).
[13:15:37.379]     INFO: Test took 4023ms.
[13:15:37.421]     INFO: Fetched DAQ statistics. Counters are being reset now.
[13:15:38.124]     INFO: Expecting 41600 events.
[13:15:41.441]     INFO: 41600 events read in total (2790ms).
[13:15:41.442]     INFO: Test took 3999ms.
[13:15:41.485]     INFO: Fetched DAQ statistics. Counters are being reset now.
[13:15:42.196]     INFO: Expecting 41600 events.
[13:15:45.508]     INFO: 41600 events read in total (2785ms).
[13:15:45.509]     INFO: Test took 4003ms.
[13:15:45.552]     INFO: Fetched DAQ statistics. Counters are being reset now.
[13:15:46.262]     INFO: Expecting 41600 events.
[13:15:49.452]     INFO: 41600 events read in total (2663ms).
[13:15:49.453]     INFO: Test took 3879ms.
[13:15:49.495]     INFO: Fetched DAQ statistics. Counters are being reset now.
[13:15:49.840]     INFO: enter test to run
[13:16:16.850]     INFO:   test: HighRate no parameter change
[13:16:16.850]     INFO:   running: highrate
[13:16:16.851]     INFO:    ----------------------------------------------------------------------
[13:16:16.851]     INFO:    PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[13:16:16.851]     INFO:    ----------------------------------------------------------------------
[13:16:17.463]     INFO: Expecting 208000 events.
[13:16:29.772]     INFO: 208000 events read in total (11782ms).
[13:16:29.775]     INFO: Test took 12917ms.
[13:16:29.960]     INFO: Fetched DAQ statistics. Counters are being reset now.
[13:16:30.224]     INFO: number of dead pixels (per ROC):     0    0    0    0    0    0    0    0    0    0    0    0    0    0    0    0
[13:16:30.225]     INFO: number of red-efficiency pixels:    79   76  126  217  236  264  241  138  132  164  174  144  234   78   35   27
[13:16:30.225]     INFO: number of X-ray hits detected:    78964 55667 87437 141819 148554 153296 150172 104632 99081 128735 128019 110220 120055 70148 29290 31370
[13:16:30.225]     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:16:30.225]     INFO: number of Vcal hits detected:  207920 207922 207870 207776 207756 207723 207748 207858 207866 207834 207817 207853 207625 207921 207962 207972
[13:16:30.225]     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.8 100.0 100.0 100.0
[13:16:30.225]     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.9 99.8 100.0 100.0 100.0
[13:16:30.225]     INFO: X-ray hit rate [MHz/cm2]:  23.1 16.3 25.6 41.6 43.5 44.9 44.0 30.7 29.0 37.7 37.5 32.3 35.2 20.6 8.6 9.2
[13:16:30.225]     INFO: PixTestHighRate::doXPixelAlive() done
[13:16:30.278]     INFO: PixTest::       pg_setup set to default.
[13:16:30.290]     INFO: enter test to run
[13:16:55.882]     INFO:   test: HighRate no parameter change
[13:16:55.882]     INFO:   running: highrate
[13:16:55.883]     INFO:    ----------------------------------------------------------------------
[13:16:55.883]     INFO:    PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[13:16:55.883]     INFO:    ----------------------------------------------------------------------
[13:16:56.499]     INFO: Expecting 208000 events.
[13:17:11.268]     INFO: 208000 events read in total (14242ms).
[13:17:11.275]     INFO: Test took 15384ms.
[13:17:11.662]     INFO: Fetched DAQ statistics. Counters are being reset now.
[13:17:11.994]     INFO: number of dead pixels (per ROC):     0    0    0    0    0    0    0    0    0    0    0    0    0    0    0    0
[13:17:11.995]     INFO: number of red-efficiency pixels:   261  201  447  882  982 1097 1017  541  453  518  555  424  607  350   78   77
[13:17:11.995]     INFO: number of X-ray hits detected:    167101 117432 183846 299762 313713 323456 317683 221545 211105 270914 271994 232274 254186 148337 61889 67353
[13:17:11.995]     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:17:11.995]     INFO: number of Vcal hits detected:  207719 207777 207461 206964 206810 206640 206755 207360 207498 207429 207391 207543 207319 207617 207920 207923
[13:17:11.995]     INFO: Vcal hit fiducial efficiency (%):  99.9 99.9 99.8 99.6 99.5 99.4 99.5 99.7 99.8 99.8 99.7 99.8 99.7 99.8 100.0 100.0
[13:17:11.995]     INFO: Vcal hit overall efficiency (%):  99.9 99.9 99.7 99.5 99.4 99.3 99.4 99.7 99.8 99.7 99.7 99.8 99.7 99.8 100.0 100.0
[13:17:11.995]     INFO: X-ray hit rate [MHz/cm2]:  49.0 34.4 53.9 87.9 92.0 94.8 93.1 64.9 61.9 79.4 79.7 68.1 74.5 43.5 18.1 19.7
[13:17:11.995]     INFO: PixTestHighRate::doXPixelAlive() done
[13:17:12.043]     INFO: PixTest::       pg_setup set to default.
[13:17:12.060]     INFO: enter test to run
[13:17:33.145]     INFO:   test: HighRate no parameter change
[13:17:33.145]     INFO:   running: highrate
[13:17:33.146]     INFO:    ----------------------------------------------------------------------
[13:17:33.146]     INFO:    PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[13:17:33.146]     INFO:    ----------------------------------------------------------------------
[13:17:33.768]     INFO: Expecting 208000 events.
[13:17:51.491]     INFO: 208000 events read in total (17196ms).
[13:17:51.501]     INFO: Test took 18346ms.
[13:17:52.114]     INFO: Fetched DAQ statistics. Counters are being reset now.
[13:17:52.514]     INFO: number of dead pixels (per ROC):     0    0    0    0    0    0    0    0    0    0    0    0    0    0    0    0
[13:17:52.514]     INFO: number of red-efficiency pixels:   567  511  995 2057 2238 2453 2373 1253 1062 1264 1357  999 1422  783  117  121
[13:17:52.515]     INFO: number of X-ray hits detected:    255446 179868 280395 455978 479009 492862 482814 338086 322166 414596 415623 354735 386255 227055 95187 102485
[13:17:52.515]     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:17:52.515]     INFO: number of Vcal hits detected:  207297 207383 206333 204703 204210 203661 203866 206110 206605 206361 206171 206708 206115 206895 207881 207873
[13:17:52.515]     INFO: Vcal hit fiducial efficiency (%):  99.7 99.7 99.3 98.6 98.4 98.1 98.2 99.2 99.4 99.3 99.2 99.5 99.2 99.5 99.9 99.9
[13:17:52.515]     INFO: Vcal hit overall efficiency (%):  99.7 99.7 99.2 98.4 98.2 97.9 98.0 99.1 99.3 99.2 99.1 99.4 99.1 99.5 99.9 99.9
[13:17:52.515]     INFO: X-ray hit rate [MHz/cm2]:  74.9 52.7 82.2 133.7 140.4 144.5 141.5 99.1 94.4 121.5 121.8 104.0 113.2 66.6 27.9 30.0
[13:17:52.515]     INFO: PixTestHighRate::doXPixelAlive() done
[13:17:52.563]     INFO: PixTest::       pg_setup set to default.
[13:17:52.577]     INFO: enter test to run
[13:17:55.769]     INFO:   test: exit no parameter change
[13:17:56.111]    QUIET: Connection to board 33 closed.
[13:17:56.112]     INFO: pXar: this is the end, my friend