[14:46:09.962] INFO: *** Welcome to pxar ***
[14:46:09.963] INFO: *** Today: 2016/06/06
[14:46:11.733] INFO: *** Version: v1.9.0-814-g7497
[14:46:11.733] INFO: readRocDacs: /home/uicpirepix2/ProductionTestResults/M-K-3-11_FPIXTest-17C-FNAL-160602-1223_2016-06-02_12h23m_1464888229/000_FPIXTest_p17//dacParameters35_C0.dat .. /home/uicpirepix2/ProductionTestResults/M-K-3-11_FPIXTest-17C-FNAL-160602-1223_2016-06-02_12h23m_1464888229/000_FPIXTest_p17//dacParameters35_C15.dat
[14:46:11.845] INFO: readTbmDacs: /home/uicpirepix2/ProductionTestResults/M-K-3-11_FPIXTest-17C-FNAL-160602-1223_2016-06-02_12h23m_1464888229/000_FPIXTest_p17//tbmParameters_C0a.dat .. /home/uicpirepix2/ProductionTestResults/M-K-3-11_FPIXTest-17C-FNAL-160602-1223_2016-06-02_12h23m_1464888229/000_FPIXTest_p17//tbmParameters_C0b.dat
[14:46:11.861] INFO: readMaskFile: /home/uicpirepix2/ProductionTestResults/M-K-3-11_FPIXTest-17C-FNAL-160602-1223_2016-06-02_12h23m_1464888229/000_FPIXTest_p17//defaultMaskFile.dat
[14:46:11.874] INFO: readTrimFile: /home/uicpirepix2/ProductionTestResults/M-K-3-11_FPIXTest-17C-FNAL-160602-1223_2016-06-02_12h23m_1464888229/000_FPIXTest_p17//trimParameters35_C0.dat .. /home/uicpirepix2/ProductionTestResults/M-K-3-11_FPIXTest-17C-FNAL-160602-1223_2016-06-02_12h23m_1464888229/000_FPIXTest_p17//trimParameters35_C15.dat
[14:46:12.038] INFO: clk: 4
[14:46:12.038] INFO: ctr: 4
[14:46:12.038] INFO: sda: 19
[14:46:12.038] INFO: tin: 9
[14:46:12.038] INFO: level: 15
[14:46:12.038] INFO: triggerdelay: 0
[14:46:12.038] QUIET: Instanciating API for pxar v1.9.0+814~g74979a6
[14:46:12.038] INFO: Log level: INFO
[14:46:12.067] QUIET: Connection to board DTB_WREKRL opened.
[14:46:12.070] 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:
------------------------------------------------------
[14:46:12.072] INFO: RPC call hashes of host and DTB match: 398089610
[14:46:13.607] INFO: DUT info:
[14:46:13.607] INFO: The DUT currently contains the following objects:
[14:46:13.607] INFO: 2 TBM Cores tbm08c (2 ON)
[14:46:13.607] INFO: TBM Core alpha (0): 7 registers set
[14:46:13.608] INFO: TBM Core beta (1): 7 registers set
[14:46:13.608] INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[14:46:13.608] INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[14:46:13.608] INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[14:46:13.608] INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[14:46:13.608] INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[14:46:13.608] INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[14:46:13.608] INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[14:46:13.608] INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[14:46:13.608] INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[14:46:13.608] INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[14:46:13.608] INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[14:46:13.608] INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[14:46:13.608] INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[14:46:13.608] INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[14:46:13.608] INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[14:46:13.608] INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[14:46:13.608] INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[14:46:14.073] INFO: enter 'restricted' command line mode
[14:46:14.073] INFO: enter test to run
[14:46:16.758] INFO: test: PixelAlive no parameter change
[14:46:16.758] INFO: running: pixelalive
[14:46:16.777] INFO: enter test to run
[14:46:20.254] INFO: test: PixelAlive no parameter change
[14:46:20.254] INFO: running: pixelalive
[14:46:20.272] INFO: ----------------------------------------------------------------------
[14:46:20.272] 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:46:20.272] INFO: ----------------------------------------------------------------------
[14:46:20.610] INFO: Expecting 41600 events.
[14:46:24.803] INFO: 41600 events read in total (3475ms).
[14:46:24.972] INFO: Test took 4680ms.
[14:46:25.004] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:46:25.267] INFO: PixTestAlive::aliveTest() done
[14:46:25.267] INFO: number of dead pixels (per ROC): 0 0 0 0 0 0 0 0 1 0 0 0 0 0 0 0
[14:46:25.308] INFO: enter test to run
[14:46:45.687] INFO: test: HighRate setting parameters: ->runsecondshotpixels=5;trimhotpixelthr=200<-
[14:46:45.687] INFO: running: highrate
[14:46:45.687] INFO: readGainPedestalParameters /home/uicpirepix2/ProductionTestResults/M-K-3-11_FPIXTest-17C-FNAL-160602-1223_2016-06-02_12h23m_1464888229/000_FPIXTest_p17//phCalibrationFitErr35_C0.dat .. /home/uicpirepix2/ProductionTestResults/M-K-3-11_FPIXTest-17C-FNAL-160602-1223_2016-06-02_12h23m_1464888229/000_FPIXTest_p17//phCalibrationFitErr35_C15.dat
[14:46:45.994] INFO: ----------------------------------------------------------------------
[14:46:45.994] INFO: PixTest::trimHotPixels() running for 5 seconds with 100 kHz trigger rate
[14:46:45.994] INFO: ----------------------------------------------------------------------
[14:46:45.994] INFO: THR = 200, corresponding to ~ 106.667 MHz/cm2
[14:46:45.994] INFO: edge/corner pixel THR is adjusted
[14:46:45.994] INFO: PixTestHighRate::trimHotPixels: step 0...
[14:46:46.952] INFO: Collecting data for 5 seconds...
[14:46:51.970] INFO: Done with hot pixel readout
[14:47:03.545] INFO: PixTest:: pg_setup set to default.
[14:47:03.545] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[14:47:03.545] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[14:47:03.545] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[14:47:03.545] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[14:47:03.545] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[14:47:03.545] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[14:47:03.546] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[14:47:03.546] INFO: 120 hot pixels found in step 0
[14:47:04.541] INFO: Collecting data for 5 seconds...
[14:47:09.559] INFO: Done with hot pixel readout
[14:47:20.801] INFO: PixTest:: pg_setup set to default.
[14:47:20.801] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[14:47:20.801] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[14:47:20.801] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[14:47:20.801] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[14:47:20.801] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[14:47:20.801] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[14:47:20.801] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[14:47:20.801] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[14:47:20.801] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[14:47:20.802] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[14:47:20.802] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[14:47:20.802] INFO: 150 hot pixels found in step 1
[14:47:21.799] INFO: Collecting data for 5 seconds...
[14:47:26.817] INFO: Done with hot pixel readout
[14:47:38.136] INFO: PixTest:: pg_setup set to default.
[14:47:38.136] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[14:47:38.136] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[14:47:38.136] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[14:47:38.136] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[14:47:38.136] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[14:47:38.136] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[14:47:38.136] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[14:47:38.136] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[14:47:38.136] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[14:47:38.137] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[14:47:38.137] INFO: 134 hot pixels found in step 2
[14:47:39.133] INFO: Collecting data for 5 seconds...
[14:47:44.150] INFO: Done with hot pixel readout
[14:47:54.890] INFO: PixTest:: pg_setup set to default.
[14:47:54.890] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[14:47:54.891] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[14:47:54.891] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[14:47:54.891] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[14:47:54.891] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[14:47:54.891] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[14:47:54.891] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[14:47:54.891] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[14:47:54.891] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[14:47:54.891] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[14:47:54.891] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[14:47:54.892] INFO: 109 hot pixels found in step 3
[14:47:55.888] INFO: Collecting data for 5 seconds...
[14:48:00.904] INFO: Done with hot pixel readout
[14:48:12.062] INFO: PixTest:: pg_setup set to default.
[14:48:12.062] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[14:48:12.063] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[14:48:12.063] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[14:48:12.063] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[14:48:12.063] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[14:48:12.063] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[14:48:12.063] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[14:48:12.063] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[14:48:12.063] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[14:48:12.063] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[14:48:12.063] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[14:48:12.063] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[14:48:12.063] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[14:48:12.063] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[14:48:12.064] INFO: 109 hot pixels found in step 4
[14:48:13.059] INFO: Collecting data for 5 seconds...
[14:48:18.076] INFO: Done with hot pixel readout
[14:48:29.784] INFO: PixTest:: pg_setup set to default.
[14:48:29.784] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[14:48:29.784] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[14:48:29.784] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[14:48:29.784] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[14:48:29.784] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[14:48:29.784] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[14:48:29.784] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[14:48:29.784] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[14:48:29.784] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[14:48:29.784] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[14:48:29.784] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[14:48:29.784] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[14:48:29.784] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[14:48:29.785] INFO: 103 hot pixels found in step 5
[14:48:30.779] INFO: Collecting data for 5 seconds...
[14:48:35.796] INFO: Done with hot pixel readout
[14:48:46.980] INFO: PixTest:: pg_setup set to default.
[14:48:46.980] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[14:48:46.980] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[14:48:46.980] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[14:48:46.980] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[14:48:46.980] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[14:48:46.980] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[14:48:46.980] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[14:48:46.981] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[14:48:46.981] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[14:48:46.981] INFO: 83 hot pixels found in step 6
[14:48:47.977] INFO: Collecting data for 5 seconds...
[14:48:52.993] INFO: Done with hot pixel readout
[14:49:04.460] INFO: PixTest:: pg_setup set to default.
[14:49:04.460] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[14:49:04.460] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[14:49:04.460] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[14:49:04.460] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[14:49:04.460] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[14:49:04.460] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[14:49:04.460] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[14:49:04.460] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[14:49:04.460] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[14:49:04.460] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[14:49:04.461] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[14:49:04.461] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[14:49:04.461] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[14:49:04.461] INFO: 89 hot pixels found in step 7
[14:49:05.465] INFO: Collecting data for 5 seconds...
[14:49:10.481] INFO: Done with hot pixel readout
[14:49:22.272] INFO: PixTest:: pg_setup set to default.
[14:49:22.272] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[14:49:22.272] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[14:49:22.272] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[14:49:22.272] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[14:49:22.272] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[14:49:22.272] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[14:49:22.272] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[14:49:22.272] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[14:49:22.272] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[14:49:22.272] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[14:49:22.272] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[14:49:22.273] INFO: 75 hot pixels found in step 8
[14:49:23.272] INFO: Collecting data for 5 seconds...
[14:49:28.288] INFO: Done with hot pixel readout
[14:49:39.784] INFO: PixTest:: pg_setup set to default.
[14:49:39.784] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[14:49:39.784] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[14:49:39.784] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[14:49:39.785] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[14:49:39.785] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[14:49:39.785] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[14:49:39.785] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[14:49:39.785] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[14:49:39.785] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[14:49:39.785] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[14:49:39.785] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[14:49:39.785] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[14:49:39.785] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[14:49:39.785] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[14:49:39.786] INFO: 71 hot pixels found in step 9
[14:49:40.781] INFO: Collecting data for 5 seconds...
[14:49:45.798] INFO: Done with hot pixel readout
[14:49:57.440] INFO: PixTest:: pg_setup set to default.
[14:49:57.440] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[14:49:57.440] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[14:49:57.440] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[14:49:57.441] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[14:49:57.441] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[14:49:57.441] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[14:49:57.441] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[14:49:57.441] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[14:49:57.441] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[14:49:57.441] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[14:49:57.441] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[14:49:57.441] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[14:49:57.441] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[14:49:57.441] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[14:49:57.441] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[14:49:57.441] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[14:49:57.441] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[14:49:57.441] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[14:49:57.441] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[14:49:57.442] INFO: 80 hot pixels found in step 10
[14:49:58.443] INFO: Collecting data for 5 seconds...
[14:50:03.460] INFO: Done with hot pixel readout
[14:50:14.892] INFO: PixTest:: pg_setup set to default.
[14:50:14.892] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[14:50:14.893] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[14:50:14.893] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[14:50:14.893] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[14:50:14.893] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[14:50:14.893] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[14:50:14.893] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[14:50:14.893] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[14:50:14.893] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[14:50:14.893] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[14:50:14.893] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[14:50:14.893] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[14:50:14.893] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[14:50:14.893] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[14:50:14.893] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[14:50:14.893] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[14:50:14.893] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[14:50:14.893] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[14:50:14.893] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[14:50:14.894] INFO: 87 hot pixels found in step 11
[14:50:15.889] INFO: Collecting data for 5 seconds...
[14:50:20.906] INFO: Done with hot pixel readout
[14:50:32.424] INFO: PixTest:: pg_setup set to default.
[14:50:32.425] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[14:50:32.425] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[14:50:32.425] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[14:50:32.425] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[14:50:32.425] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[14:50:32.425] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[14:50:32.425] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[14:50:32.425] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[14:50:32.425] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[14:50:32.425] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[14:50:32.425] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[14:50:32.425] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[14:50:32.425] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[14:50:32.425] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[14:50:32.426] INFO: 77 hot pixels found in step 12
[14:50:33.421] INFO: Collecting data for 5 seconds...
[14:50:38.438] INFO: Done with hot pixel readout
[14:50:49.893] INFO: PixTest:: pg_setup set to default.
[14:50:49.893] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[14:50:49.893] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[14:50:49.893] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[14:50:49.893] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[14:50:49.893] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[14:50:49.893] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[14:50:49.893] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[14:50:49.893] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[14:50:49.893] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[14:50:49.893] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[14:50:49.894] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[14:50:49.894] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[14:50:49.894] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[14:50:49.894] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[14:50:49.894] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[14:50:49.894] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[14:50:49.894] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[14:50:49.894] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[14:50:49.894] INFO: 77 hot pixels found in step 13
[14:50:50.900] INFO: Collecting data for 5 seconds...
[14:50:55.916] INFO: Done with hot pixel readout
[14:51:06.818] INFO: PixTest:: pg_setup set to default.
[14:51:06.818] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[14:51:06.818] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[14:51:06.819] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[14:51:06.819] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[14:51:06.819] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[14:51:06.819] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[14:51:06.819] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[14:51:06.819] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[14:51:06.819] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[14:51:06.819] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[14:51:06.819] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[14:51:06.819] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[14:51:06.819] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[14:51:06.819] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[14:51:06.819] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[14:51:06.820] INFO: 62 hot pixels found in step 14
[14:51:06.860] INFO: 62 hot pixels could not be trimmed and have been masked.
[14:51:06.864] INFO: PixTest::trimHotPixels() done
[14:51:06.864] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-K-3-11_FPIXTest-17C-FNAL-160602-1223_2016-06-02_12h23m_1464888229/000_FPIXTest_p17//trimParameters35_C0.dat
[14:51:06.871] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-K-3-11_FPIXTest-17C-FNAL-160602-1223_2016-06-02_12h23m_1464888229/000_FPIXTest_p17//trimParameters35_C1.dat
[14:51:06.876] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-K-3-11_FPIXTest-17C-FNAL-160602-1223_2016-06-02_12h23m_1464888229/000_FPIXTest_p17//trimParameters35_C2.dat
[14:51:06.882] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-K-3-11_FPIXTest-17C-FNAL-160602-1223_2016-06-02_12h23m_1464888229/000_FPIXTest_p17//trimParameters35_C3.dat
[14:51:06.887] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-K-3-11_FPIXTest-17C-FNAL-160602-1223_2016-06-02_12h23m_1464888229/000_FPIXTest_p17//trimParameters35_C4.dat
[14:51:06.893] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-K-3-11_FPIXTest-17C-FNAL-160602-1223_2016-06-02_12h23m_1464888229/000_FPIXTest_p17//trimParameters35_C5.dat
[14:51:06.898] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-K-3-11_FPIXTest-17C-FNAL-160602-1223_2016-06-02_12h23m_1464888229/000_FPIXTest_p17//trimParameters35_C6.dat
[14:51:06.904] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-K-3-11_FPIXTest-17C-FNAL-160602-1223_2016-06-02_12h23m_1464888229/000_FPIXTest_p17//trimParameters35_C7.dat
[14:51:06.909] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-K-3-11_FPIXTest-17C-FNAL-160602-1223_2016-06-02_12h23m_1464888229/000_FPIXTest_p17//trimParameters35_C8.dat
[14:51:06.915] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-K-3-11_FPIXTest-17C-FNAL-160602-1223_2016-06-02_12h23m_1464888229/000_FPIXTest_p17//trimParameters35_C9.dat
[14:51:06.920] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-K-3-11_FPIXTest-17C-FNAL-160602-1223_2016-06-02_12h23m_1464888229/000_FPIXTest_p17//trimParameters35_C10.dat
[14:51:06.925] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-K-3-11_FPIXTest-17C-FNAL-160602-1223_2016-06-02_12h23m_1464888229/000_FPIXTest_p17//trimParameters35_C11.dat
[14:51:06.931] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-K-3-11_FPIXTest-17C-FNAL-160602-1223_2016-06-02_12h23m_1464888229/000_FPIXTest_p17//trimParameters35_C12.dat
[14:51:06.936] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-K-3-11_FPIXTest-17C-FNAL-160602-1223_2016-06-02_12h23m_1464888229/000_FPIXTest_p17//trimParameters35_C13.dat
[14:51:06.942] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-K-3-11_FPIXTest-17C-FNAL-160602-1223_2016-06-02_12h23m_1464888229/000_FPIXTest_p17//trimParameters35_C14.dat
[14:51:06.947] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-K-3-11_FPIXTest-17C-FNAL-160602-1223_2016-06-02_12h23m_1464888229/000_FPIXTest_p17//trimParameters35_C15.dat
[14:51:06.953] INFO: write masked pixels into /home/uicpirepix2/ProductionTestResults/M-K-3-11_FPIXTest-17C-FNAL-160602-1223_2016-06-02_12h23m_1464888229/000_FPIXTest_p17//defaultMaskFile.dat
[14:51:06.964] INFO: enter test to run
[14:52:34.084] INFO: test: HighRate setting parameters: ->runsecondshotpixels=1;trimhotpixelthr=200<-
[14:52:34.084] INFO: running: highrate
[14:52:34.088] INFO: ----------------------------------------------------------------------
[14:52:34.088] INFO: PixTest::trimHotPixels() running for 1 seconds with 100 kHz trigger rate
[14:52:34.088] INFO: ----------------------------------------------------------------------
[14:52:34.088] INFO: THR = 200, corresponding to ~ 533.333 MHz/cm2
[14:52:34.089] INFO: edge/corner pixel THR is adjusted
[14:52:34.089] INFO: PixTestHighRate::trimHotPixels: step 0...
[14:52:35.046] INFO: Collecting data for 1 seconds...
[14:52:36.049] INFO: Done with hot pixel readout
[14:52:39.869] INFO: PixTest:: pg_setup set to default.
[14:52:39.870] INFO: 0 hot pixels found in step 0
[14:52:39.875] INFO: 0 hot pixels could not be trimmed and have been masked.
[14:52:39.963] INFO: PixTest::trimHotPixels() done
[14:52:39.963] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-K-3-11_FPIXTest-17C-FNAL-160602-1223_2016-06-02_12h23m_1464888229/000_FPIXTest_p17//trimParameters35_C0.dat
[14:52:39.972] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-K-3-11_FPIXTest-17C-FNAL-160602-1223_2016-06-02_12h23m_1464888229/000_FPIXTest_p17//trimParameters35_C1.dat
[14:52:39.977] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-K-3-11_FPIXTest-17C-FNAL-160602-1223_2016-06-02_12h23m_1464888229/000_FPIXTest_p17//trimParameters35_C2.dat
[14:52:39.983] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-K-3-11_FPIXTest-17C-FNAL-160602-1223_2016-06-02_12h23m_1464888229/000_FPIXTest_p17//trimParameters35_C3.dat
[14:52:39.988] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-K-3-11_FPIXTest-17C-FNAL-160602-1223_2016-06-02_12h23m_1464888229/000_FPIXTest_p17//trimParameters35_C4.dat
[14:52:39.994] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-K-3-11_FPIXTest-17C-FNAL-160602-1223_2016-06-02_12h23m_1464888229/000_FPIXTest_p17//trimParameters35_C5.dat
[14:52:39.999] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-K-3-11_FPIXTest-17C-FNAL-160602-1223_2016-06-02_12h23m_1464888229/000_FPIXTest_p17//trimParameters35_C6.dat
[14:52:40.004] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-K-3-11_FPIXTest-17C-FNAL-160602-1223_2016-06-02_12h23m_1464888229/000_FPIXTest_p17//trimParameters35_C7.dat
[14:52:40.010] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-K-3-11_FPIXTest-17C-FNAL-160602-1223_2016-06-02_12h23m_1464888229/000_FPIXTest_p17//trimParameters35_C8.dat
[14:52:40.015] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-K-3-11_FPIXTest-17C-FNAL-160602-1223_2016-06-02_12h23m_1464888229/000_FPIXTest_p17//trimParameters35_C9.dat
[14:52:40.021] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-K-3-11_FPIXTest-17C-FNAL-160602-1223_2016-06-02_12h23m_1464888229/000_FPIXTest_p17//trimParameters35_C10.dat
[14:52:40.026] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-K-3-11_FPIXTest-17C-FNAL-160602-1223_2016-06-02_12h23m_1464888229/000_FPIXTest_p17//trimParameters35_C11.dat
[14:52:40.032] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-K-3-11_FPIXTest-17C-FNAL-160602-1223_2016-06-02_12h23m_1464888229/000_FPIXTest_p17//trimParameters35_C12.dat
[14:52:40.037] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-K-3-11_FPIXTest-17C-FNAL-160602-1223_2016-06-02_12h23m_1464888229/000_FPIXTest_p17//trimParameters35_C13.dat
[14:52:40.042] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-K-3-11_FPIXTest-17C-FNAL-160602-1223_2016-06-02_12h23m_1464888229/000_FPIXTest_p17//trimParameters35_C14.dat
[14:52:40.048] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-K-3-11_FPIXTest-17C-FNAL-160602-1223_2016-06-02_12h23m_1464888229/000_FPIXTest_p17//trimParameters35_C15.dat
[14:52:40.053] INFO: write masked pixels into /home/uicpirepix2/ProductionTestResults/M-K-3-11_FPIXTest-17C-FNAL-160602-1223_2016-06-02_12h23m_1464888229/000_FPIXTest_p17//defaultMaskFile.dat
[14:52:40.067] INFO: enter test to run
[14:54:20.587] INFO: test: Xray setting parameters: ->source=DCLowRate<-
[14:54:20.587] INFO: running: xray
[14:54:20.588] INFO: ----------------------------------------------------------------------
[14:54:20.588] INFO: PixTestXray::doPhRun() fParRunSeconds = 100
[14:54:20.588] INFO: ----------------------------------------------------------------------
[14:54:21.552] INFO: PixTestXray::doPhRun start TriggerLoop with trigger frequency 100 kHz, period 405 and duration 100 seconds, fEventsMax = 10000000
[14:54:32.337] INFO: run duration 10 seconds, buffer almost full (81%), pausing triggers.
[14:55:00.249] INFO: Resuming triggers.
[14:55:11.035] INFO: run duration 21 seconds, buffer almost full (81%), pausing triggers.
[14:55:39.454] INFO: Resuming triggers.
[14:55:50.243] INFO: run duration 32 seconds, buffer almost full (81%), pausing triggers.
[14:56:18.735] INFO: Resuming triggers.
[14:56:29.525] INFO: run duration 42 seconds, buffer almost full (81%), pausing triggers.
[14:56:57.231] INFO: Resuming triggers.
[14:57:08.017] INFO: run duration 53 seconds, buffer almost full (81%), pausing triggers.
[14:57:35.619] INFO: Resuming triggers.
[14:57:46.405] INFO: run duration 64 seconds, buffer almost full (81%), pausing triggers.
[14:58:15.181] INFO: Resuming triggers.
[14:58:25.972] INFO: run duration 75 seconds, buffer almost full (81%), pausing triggers.
[14:58:55.294] INFO: Resuming triggers.
[14:59:06.082] INFO: run duration 85 seconds, buffer almost full (81%), pausing triggers.
[14:59:34.278] INFO: Resuming triggers.
[14:59:45.063] INFO: run duration 96 seconds, buffer almost full (81%), pausing triggers.
[15:00:12.939] INFO: Resuming triggers.
[15:00:16.203] INFO: data taking finished, elapsed time: 100 seconds.
[15:00:24.715] INFO: PixTest:: pg_setup set to default.
[15:00:24.719] INFO: PixTestXray::doPhRun() done
[15:00:24.874] INFO: enter test to run
[15:00:56.152] INFO: test: Xray setting parameters: ->source=DCHighRate<-
[15:00:56.152] INFO: running: xray
[15:00:56.153] INFO: ----------------------------------------------------------------------
[15:00:56.153] INFO: PixTestXray::doPhRun() fParRunSeconds = 100
[15:00:56.153] INFO: ----------------------------------------------------------------------
[15:00:57.122] INFO: PixTestXray::doPhRun start TriggerLoop with trigger frequency 100 kHz, period 405 and duration 100 seconds, fEventsMax = 10000000
[15:01:03.327] INFO: run duration 6 seconds, buffer almost full (81%), pausing triggers.
[15:01:32.718] INFO: Resuming triggers.
[15:01:38.923] INFO: run duration 12 seconds, buffer almost full (81%), pausing triggers.
[15:02:08.176] INFO: Resuming triggers.
[15:02:14.382] INFO: run duration 18 seconds, buffer almost full (81%), pausing triggers.
[15:02:43.453] INFO: Resuming triggers.
[15:02:49.657] INFO: run duration 24 seconds, buffer almost full (81%), pausing triggers.
[15:03:19.332] INFO: Resuming triggers.
[15:03:25.538] INFO: run duration 30 seconds, buffer almost full (81%), pausing triggers.
[15:03:54.140] INFO: Resuming triggers.
[15:04:00.343] INFO: run duration 37 seconds, buffer almost full (81%), pausing triggers.
[15:04:29.836] INFO: Resuming triggers.
[15:04:36.043] INFO: run duration 43 seconds, buffer almost full (81%), pausing triggers.
[15:05:05.976] INFO: Resuming triggers.
[15:05:12.183] INFO: run duration 49 seconds, buffer almost full (81%), pausing triggers.
[15:05:41.099] INFO: Resuming triggers.
[15:05:47.303] INFO: run duration 55 seconds, buffer almost full (81%), pausing triggers.
[15:06:17.561] INFO: Resuming triggers.
[15:06:23.770] INFO: run duration 61 seconds, buffer almost full (81%), pausing triggers.
[15:06:53.581] INFO: Resuming triggers.
[15:06:59.789] INFO: run duration 68 seconds, buffer almost full (81%), pausing triggers.
[15:07:29.506] INFO: Resuming triggers.
[15:07:35.711] INFO: run duration 74 seconds, buffer almost full (81%), pausing triggers.
[15:08:05.289] INFO: Resuming triggers.
[15:08:11.494] INFO: run duration 80 seconds, buffer almost full (81%), pausing triggers.
[15:08:42.436] INFO: Resuming triggers.
[15:08:48.642] INFO: run duration 86 seconds, buffer almost full (81%), pausing triggers.
[15:09:18.284] INFO: Resuming triggers.
[15:09:24.494] INFO: run duration 92 seconds, buffer almost full (81%), pausing triggers.
[15:09:54.508] INFO: Resuming triggers.
[15:10:00.715] INFO: run duration 98 seconds, buffer almost full (81%), pausing triggers.
[15:10:30.664] INFO: Resuming triggers.
[15:10:31.698] INFO: data taking finished, elapsed time: 100 seconds.
[15:10:37.231] INFO: PixTest:: pg_setup set to default.
[15:10:37.234] INFO: PixTestXray::doPhRun() done
[15:10:37.399] INFO: enter test to run
[15:10:56.107] INFO: test: HighRate no parameter change
[15:10:56.107] INFO: running: highrate
[15:10:56.128] INFO: ----------------------------------------------------------------------
[15:10:56.128] INFO: PixTestHighRate::calDelScan() ntrig = 10, vcal = 200
[15:10:56.128] INFO: ----------------------------------------------------------------------
[15:10:56.324] INFO: Expecting 768 events.
[15:10:57.454] INFO: 768 events read in total (415ms).
[15:10:57.454] INFO: Test took 1305ms.
[15:10:58.257] INFO: Expecting 41600 events.
[15:11:01.362] INFO: 41600 events read in total (2579ms).
[15:11:01.363] INFO: Test took 3901ms.
[15:11:01.401] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:11:02.117] INFO: Expecting 41600 events.
[15:11:05.294] INFO: 41600 events read in total (2650ms).
[15:11:05.295] INFO: Test took 3876ms.
[15:11:05.334] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:11:06.051] INFO: Expecting 41600 events.
[15:11:09.370] INFO: 41600 events read in total (2792ms).
[15:11:09.372] INFO: Test took 4019ms.
[15:11:09.411] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:11:10.118] INFO: Expecting 41600 events.
[15:11:13.261] INFO: 41600 events read in total (2616ms).
[15:11:13.262] INFO: Test took 3831ms.
[15:11:13.302] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:11:14.013] INFO: Expecting 41600 events.
[15:11:17.223] INFO: 41600 events read in total (2683ms).
[15:11:17.223] INFO: Test took 3901ms.
[15:11:17.262] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:11:17.979] INFO: Expecting 41600 events.
[15:11:21.216] INFO: 41600 events read in total (2710ms).
[15:11:21.217] INFO: Test took 3934ms.
[15:11:21.255] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:11:21.971] INFO: Expecting 41600 events.
[15:11:25.104] INFO: 41600 events read in total (2606ms).
[15:11:25.106] INFO: Test took 3831ms.
[15:11:25.145] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:11:25.858] INFO: Expecting 41600 events.
[15:11:29.140] INFO: 41600 events read in total (2755ms).
[15:11:29.141] INFO: Test took 3976ms.
[15:11:29.181] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:11:29.894] INFO: Expecting 41600 events.
[15:11:32.962] INFO: 41600 events read in total (2541ms).
[15:11:32.963] INFO: Test took 3761ms.
[15:11:33.002] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:11:33.717] INFO: Expecting 41600 events.
[15:11:36.959] INFO: 41600 events read in total (2715ms).
[15:11:36.960] INFO: Test took 3938ms.
[15:11:36.998] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:11:37.714] INFO: Expecting 41600 events.
[15:11:40.741] INFO: 41600 events read in total (2500ms).
[15:11:40.742] INFO: Test took 3725ms.
[15:11:40.781] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:11:41.498] INFO: Expecting 41600 events.
[15:11:44.778] INFO: 41600 events read in total (2753ms).
[15:11:44.779] INFO: Test took 3978ms.
[15:11:44.819] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:11:45.534] INFO: Expecting 41600 events.
[15:11:48.743] INFO: 41600 events read in total (2682ms).
[15:11:48.744] INFO: Test took 3906ms.
[15:11:48.782] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:11:49.494] INFO: Expecting 41600 events.
[15:11:52.605] INFO: 41600 events read in total (2585ms).
[15:11:52.607] INFO: Test took 3805ms.
[15:11:52.645] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:11:53.359] INFO: Expecting 41600 events.
[15:11:56.589] INFO: 41600 events read in total (2704ms).
[15:11:56.590] INFO: Test took 3925ms.
[15:11:56.630] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:11:57.342] INFO: Expecting 41600 events.
[15:12:00.549] INFO: 41600 events read in total (2680ms).
[15:12:00.550] INFO: Test took 3901ms.
[15:12:00.588] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:12:01.302] INFO: Expecting 41600 events.
[15:12:04.547] INFO: 41600 events read in total (2719ms).
[15:12:04.548] INFO: Test took 3939ms.
[15:12:04.586] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:12:05.297] INFO: Expecting 41600 events.
[15:12:08.488] INFO: 41600 events read in total (2664ms).
[15:12:08.490] INFO: Test took 3885ms.
[15:12:08.531] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:12:09.243] INFO: Expecting 41600 events.
[15:12:12.528] INFO: 41600 events read in total (2758ms).
[15:12:12.529] INFO: Test took 3978ms.
[15:12:12.568] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:12:13.283] INFO: Expecting 41600 events.
[15:12:16.317] INFO: 41600 events read in total (2507ms).
[15:12:16.318] INFO: Test took 3731ms.
[15:12:16.355] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:12:16.713] INFO: enter test to run
[15:13:47.514] INFO: test: HighRate no parameter change
[15:13:47.514] INFO: running: highrate
[15:13:47.515] INFO: ----------------------------------------------------------------------
[15:13:47.515] INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[15:13:47.515] INFO: ----------------------------------------------------------------------
[15:13:48.133] INFO: Expecting 208000 events.
[15:13:59.757] INFO: 208000 events read in total (11097ms).
[15:13:59.760] INFO: Test took 12236ms.
[15:13:59.927] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:14:00.186] INFO: number of dead pixels (per ROC): 0 0 0 0 0 0 0 0 1 0 0 0 0 0 0 0
[15:14:00.186] INFO: number of red-efficiency pixels: 102 65 133 172 207 185 178 121 95 146 137 117 99 64 30 24
[15:14:00.187] INFO: number of X-ray hits detected: 74148 48997 81375 126693 136252 141025 140660 104800 85169 108765 103451 88468 91674 61393 26948 29304
[15:14:00.187] INFO: number of triggers sent (total per ROC): 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000
[15:14:00.187] INFO: number of Vcal hits detected: 207891 207934 207861 207825 207785 207809 207816 207879 207854 207850 207860 207879 207900 207932 207970 207976
[15:14:00.187] INFO: Vcal hit fiducial efficiency (%): 100.0 100.0 99.9 99.9 99.9 99.9 99.9 99.9 100.0 99.9 99.9 99.9 100.0 100.0 100.0 100.0
[15:14:00.187] INFO: Vcal hit overall efficiency (%): 99.9 100.0 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 100.0
[15:14:00.187] INFO: X-ray hit rate [MHz/cm2]: 21.7 14.4 23.9 37.1 39.9 41.3 41.2 30.7 25.0 31.9 30.3 25.9 26.9 18.0 7.9 8.6
[15:14:00.187] INFO: PixTestHighRate::doXPixelAlive() done
[15:14:00.231] INFO: PixTest:: pg_setup set to default.
[15:14:00.242] INFO: enter test to run
[15:14:44.538] INFO: test: HighRate no parameter change
[15:14:44.538] INFO: running: highrate
[15:14:44.539] INFO: ----------------------------------------------------------------------
[15:14:44.539] INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[15:14:44.539] INFO: ----------------------------------------------------------------------
[15:14:45.158] INFO: Expecting 208000 events.
[15:14:58.507] INFO: 208000 events read in total (12823ms).
[15:14:58.514] INFO: Test took 13964ms.
[15:14:58.847] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:14:59.161] INFO: number of dead pixels (per ROC): 0 0 0 0 0 0 0 0 1 0 0 0 0 0 0 0
[15:14:59.162] INFO: number of red-efficiency pixels: 215 160 379 543 709 574 763 423 253 358 350 318 264 198 74 57
[15:14:59.162] INFO: number of X-ray hits detected: 151170 99279 164349 255801 275393 285466 284530 212385 172882 220907 209935 178497 186044 125295 54619 59629
[15:14:59.162] INFO: number of triggers sent (total per ROC): 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000
[15:14:59.162] INFO: number of Vcal hits detected: 207773 207834 207562 207392 207184 207355 207138 207538 207684 207605 207617 207667 207726 207792 207925 207942
[15:14:59.162] INFO: Vcal hit fiducial efficiency (%): 99.9 99.9 99.8 99.7 99.6 99.7 99.6 99.8 99.9 99.8 99.8 99.9 99.9 99.9 100.0 100.0
[15:14:59.162] INFO: Vcal hit overall efficiency (%): 99.9 99.9 99.8 99.7 99.6 99.7 99.6 99.8 99.8 99.8 99.8 99.8 99.9 99.9 100.0 100.0
[15:14:59.162] INFO: X-ray hit rate [MHz/cm2]: 44.3 29.1 48.2 75.0 80.7 83.7 83.4 62.3 50.7 64.7 61.5 52.3 54.5 36.7 16.0 17.5
[15:14:59.162] INFO: PixTestHighRate::doXPixelAlive() done
[15:14:59.211] INFO: PixTest:: pg_setup set to default.
[15:14:59.221] INFO: enter test to run
[15:15:27.146] INFO: test: HighRate no parameter change
[15:15:27.146] INFO: running: highrate
[15:15:27.147] INFO: ----------------------------------------------------------------------
[15:15:27.147] INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[15:15:27.147] INFO: ----------------------------------------------------------------------
[15:15:27.767] INFO: Expecting 208000 events.
[15:15:43.007] INFO: 208000 events read in total (14713ms).
[15:15:43.017] INFO: Test took 15861ms.
[15:15:43.529] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:15:43.907] INFO: number of dead pixels (per ROC): 0 0 0 0 0 0 0 0 1 0 0 0 0 0 0 0
[15:15:43.907] INFO: number of red-efficiency pixels: 459 323 874 1259 1685 1350 1674 825 544 796 826 705 564 386 115 87
[15:15:43.907] INFO: number of X-ray hits detected: 224947 148172 243555 381322 409562 423965 421769 315434 257078 331327 311366 267007 277185 186948 81213 89836
[15:15:43.907] INFO: number of triggers sent (total per ROC): 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000
[15:15:43.907] INFO: number of Vcal hits detected: 207456 207643 206738 206345 205598 206169 205564 206958 207329 207039 207001 207152 207380 207550 207880 207912
[15:15:43.907] INFO: Vcal hit fiducial efficiency (%): 99.8 99.8 99.5 99.3 99.0 99.2 99.0 99.6 99.7 99.6 99.6 99.6 99.7 99.8 99.9 100.0
[15:15:43.907] INFO: Vcal hit overall efficiency (%): 99.7 99.8 99.4 99.2 98.8 99.1 98.8 99.5 99.7 99.5 99.5 99.6 99.7 99.8 99.9 100.0
[15:15:43.907] INFO: X-ray hit rate [MHz/cm2]: 65.9 43.4 71.4 111.8 120.0 124.3 123.6 92.5 75.4 97.1 91.3 78.3 81.2 54.8 23.8 26.3
[15:15:43.907] INFO: PixTestHighRate::doXPixelAlive() done
[15:15:43.952] INFO: PixTest:: pg_setup set to default.
[15:15:43.962] INFO: enter test to run
[15:16:49.537] INFO: test: exit no parameter change
[15:16:49.981] QUIET: Connection to board 33 closed.
[15:16:49.989] INFO: pXar: this is the end, my friend