[15:31:44.873] INFO: *** Welcome to pxar ***
[15:31:44.873] INFO: *** Today: 2016/10/25
[15:31:44.886] INFO: *** Version: v1.9.0-818-g96727
[15:31:44.887] INFO: readRocDacs: /home/uicpirepix2/ProductionTestResults/M-Q-3-31_FPIXTest-17C-FNAL-161024-1220-150V_2016-10-24_12h20m_1477329620/000_FPIXTest_p17//dacParameters35_C0.dat .. /home/uicpirepix2/ProductionTestResults/M-Q-3-31_FPIXTest-17C-FNAL-161024-1220-150V_2016-10-24_12h20m_1477329620/000_FPIXTest_p17//dacParameters35_C15.dat
[15:31:44.887] INFO: readTbmDacs: /home/uicpirepix2/ProductionTestResults/M-Q-3-31_FPIXTest-17C-FNAL-161024-1220-150V_2016-10-24_12h20m_1477329620/000_FPIXTest_p17//tbmParameters_C0a.dat .. /home/uicpirepix2/ProductionTestResults/M-Q-3-31_FPIXTest-17C-FNAL-161024-1220-150V_2016-10-24_12h20m_1477329620/000_FPIXTest_p17//tbmParameters_C0b.dat
[15:31:44.887] INFO: readMaskFile: /home/uicpirepix2/ProductionTestResults/M-Q-3-31_FPIXTest-17C-FNAL-161024-1220-150V_2016-10-24_12h20m_1477329620/000_FPIXTest_p17//defaultMaskFile.dat
[15:31:44.887] INFO: readTrimFile: /home/uicpirepix2/ProductionTestResults/M-Q-3-31_FPIXTest-17C-FNAL-161024-1220-150V_2016-10-24_12h20m_1477329620/000_FPIXTest_p17//trimParameters35_C0.dat .. /home/uicpirepix2/ProductionTestResults/M-Q-3-31_FPIXTest-17C-FNAL-161024-1220-150V_2016-10-24_12h20m_1477329620/000_FPIXTest_p17//trimParameters35_C15.dat
[15:31:44.960] INFO: clk: 4
[15:31:44.961] INFO: ctr: 4
[15:31:44.961] INFO: sda: 19
[15:31:44.961] INFO: tin: 9
[15:31:44.961] INFO: level: 15
[15:31:44.961] INFO: triggerdelay: 0
[15:31:44.961] QUIET: Instanciating API for pxar v1.9.0+814~g74979a6
[15:31:44.961] INFO: Log level: INFO
[15:31:44.000] QUIET: Connection to board DTB_WREHUL opened.
[15:31:44.004] INFO: DTB startup information
--- DTB info------------------------------------------
Board id: 30
HW version: DTB1.2
FW version: 4.2
SW version: 4.5
USB id: DTB_WREHUL
MAC address: 40D85511801E
Hostname: pixelDTB030
Comment:
------------------------------------------------------
[15:31:45.007] INFO: RPC call hashes of host and DTB match: 398089610
[15:31:46.542] INFO: DUT info:
[15:31:46.542] INFO: The DUT currently contains the following objects:
[15:31:46.542] INFO: 2 TBM Cores tbm08c (2 ON)
[15:31:46.543] INFO: TBM Core alpha (0): 7 registers set
[15:31:46.543] INFO: TBM Core beta (1): 7 registers set
[15:31:46.543] INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[15:31:46.543] INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[15:31:46.543] INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[15:31:46.543] INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[15:31:46.543] INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[15:31:46.543] INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[15:31:46.543] INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[15:31:46.543] INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[15:31:46.543] INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[15:31:46.543] INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[15:31:46.543] INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[15:31:46.543] INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[15:31:46.544] INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[15:31:46.544] INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[15:31:46.544] INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[15:31:46.544] INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[15:31:46.544] INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[15:31:46.961] INFO: enter 'restricted' command line mode
[15:31:46.961] INFO: enter test to run
[15:31:56.968] INFO: test: PixelAlive no parameter change
[15:31:56.968] INFO: running: pixelalive
[15:31:56.978] INFO: ----------------------------------------------------------------------
[15:31:56.978] INFO: PixTestAlive::aliveTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[15:31:56.978] INFO: ----------------------------------------------------------------------
[15:31:57.300] INFO: Expecting 41600 events.
[15:32:01.522] INFO: 41600 events read in total (3504ms).
[15:32:01.683] INFO: Test took 4703ms.
[15:32:01.696] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:32:01.958] INFO: PixTestAlive::aliveTest() done
[15:32:01.958] INFO: number of dead pixels (per ROC): 0 0 0 1 0 0 11 5 0 0 0 0 0 0 0 0
[15:32:01.991] INFO: enter test to run
[15:32:31.184] INFO: test: timing no parameter change
[15:32:31.184] INFO: running: timing
[15:32:31.187] INFO: ######################################################################
[15:32:31.188] INFO: PixTestTiming::doTest()
[15:32:31.188] INFO: ######################################################################
[15:32:31.188] INFO: ----------------------------------------------------------------------
[15:32:31.188] INFO: PixTestTiming::TBMPhaseScan()
[15:32:31.188] INFO: ----------------------------------------------------------------------
[15:39:07.397] INFO: TBM Phase Settings: 232
[15:39:07.397] INFO: 400MHz Phase: 2
[15:39:07.397] INFO: 160MHz Phase: 7
[15:39:07.397] INFO: Functional Phase Area: 3
[15:39:07.400] INFO: Test took 396212 ms.
[15:39:07.400] INFO: PixTestTiming::TBMPhaseScan() done.
[15:39:07.400] INFO: ----------------------------------------------------------------------
[15:39:07.400] INFO: PixTestTiming::ROCDelayScan()
[15:39:07.400] INFO: ----------------------------------------------------------------------
[15:43:04.964] INFO: ROC Delay Settings: 228
[15:43:04.964] INFO: ROC Header-Trailer/Token Delay: 11
[15:43:04.964] INFO: ROC Port 0 Delay: 4
[15:43:04.964] INFO: ROC Port 1 Delay: 4
[15:43:04.964] INFO: Functional ROC Area: 5
[15:43:04.967] INFO: Test took 237567 ms.
[15:43:04.967] INFO: PixTestTiming::ROCDelayScan() done.
[15:43:04.967] INFO: ----------------------------------------------------------------------
[15:43:04.967] INFO: PixTestTiming::TimingTest()
[15:43:04.967] INFO: ----------------------------------------------------------------------
[15:43:21.257] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:43:36.401] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:43:51.568] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:44:06.571] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:44:21.610] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:44:36.632] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:44:51.750] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:45:06.763] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:45:21.812] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:45:36.934] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:45:37.316] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:45:37.334] INFO: ----------------------------------------------------------------------
[15:45:37.334] INFO: The fraction of properly decoded events is 100.00%: 10000000/10000000
[15:45:37.334] INFO: ----------------------------------------------------------------------
[15:45:37.334] INFO: ----------------------------------------------------------------------
[15:45:37.334] INFO: Read back bit status: 1
[15:45:37.334] INFO: ----------------------------------------------------------------------
[15:45:37.334] INFO: ----------------------------------------------------------------------
[15:45:37.334] INFO: Timings are good!
[15:45:37.334] INFO: ----------------------------------------------------------------------
[15:45:37.334] INFO: Test took 152367 ms.
[15:45:37.334] INFO: PixTestTiming::TimingTest() done.
[15:45:37.334] INFO: write tbm parameters into /home/uicpirepix2/ProductionTestResults/M-Q-3-31_FPIXTest-17C-FNAL-161024-1220-150V_2016-10-24_12h20m_1477329620/000_FPIXTest_p17//tbmParameters_C0a.dat
[15:45:37.334] INFO: write tbm parameters into /home/uicpirepix2/ProductionTestResults/M-Q-3-31_FPIXTest-17C-FNAL-161024-1220-150V_2016-10-24_12h20m_1477329620/000_FPIXTest_p17//tbmParameters_C0b.dat
[15:45:37.334] INFO: PixTestTiming::doTest took 786150 ms.
[15:45:37.334] INFO: PixTestTiming::doTest() done
[15:45:37.334] INFO: Write out TBMPhaseScan_0_V0
[15:45:37.334] INFO: Write out TBMPhaseScan_1_V0
[15:45:37.335] INFO: Write out CombinedTBMPhaseScan_V0
[15:45:37.364] INFO: Write out ROCDelayScan3_V0
[15:45:37.365] INFO: enter test to run
[15:46:36.498] INFO: test: HighRate setting parameters: ->runsecondshotpixels=5;trimhotpixelthr=200<-
[15:46:36.498] INFO: running: highrate
[15:46:36.836] INFO: readGainPedestalParameters /home/uicpirepix2/ProductionTestResults/M-Q-3-31_FPIXTest-17C-FNAL-161024-1220-150V_2016-10-24_12h20m_1477329620/000_FPIXTest_p17//phCalibrationFitErr35_C0.dat .. /home/uicpirepix2/ProductionTestResults/M-Q-3-31_FPIXTest-17C-FNAL-161024-1220-150V_2016-10-24_12h20m_1477329620/000_FPIXTest_p17//phCalibrationFitErr35_C15.dat
[15:46:37.137] INFO: ----------------------------------------------------------------------
[15:46:37.137] INFO: PixTest::trimHotPixels() running for 5 seconds with 100 kHz trigger rate
[15:46:37.137] INFO: ----------------------------------------------------------------------
[15:46:37.137] INFO: THR = 200, corresponding to ~ 106.667 MHz/cm2
[15:46:37.137] INFO: edge/corner pixel THR is adjusted
[15:46:37.137] INFO: PixTestHighRate::trimHotPixels: step 0...
[15:46:38.094] INFO: Collecting data for 5 seconds...
[15:46:43.113] INFO: Done with hot pixel readout
[15:46:55.966] INFO: PixTest:: pg_setup set to default.
[15:46:55.966] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[15:46:55.966] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[15:46:55.966] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[15:46:55.966] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[15:46:55.967] INFO: 140 hot pixels found in step 0
[15:46:56.966] INFO: Collecting data for 5 seconds...
[15:47:01.985] INFO: Done with hot pixel readout
[15:47:14.801] INFO: PixTest:: pg_setup set to default.
[15:47:14.801] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[15:47:14.801] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[15:47:14.802] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[15:47:14.802] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[15:47:14.802] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[15:47:14.802] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[15:47:14.802] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[15:47:14.802] INFO: 138 hot pixels found in step 1
[15:47:15.801] INFO: Collecting data for 5 seconds...
[15:47:20.820] INFO: Done with hot pixel readout
[15:47:34.085] INFO: PixTest:: pg_setup set to default.
[15:47:34.086] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[15:47:34.086] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[15:47:34.086] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[15:47:34.086] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[15:47:34.086] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[15:47:34.086] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[15:47:34.086] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[15:47:34.087] INFO: 137 hot pixels found in step 2
[15:47:35.096] INFO: Collecting data for 5 seconds...
[15:47:40.116] INFO: Done with hot pixel readout
[15:47:52.756] INFO: PixTest:: pg_setup set to default.
[15:47:52.756] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[15:47:52.756] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[15:47:52.756] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[15:47:52.756] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[15:47:52.756] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[15:47:52.756] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[15:47:52.756] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[15:47:52.757] INFO: 114 hot pixels found in step 3
[15:47:53.756] INFO: Collecting data for 5 seconds...
[15:47:58.775] INFO: Done with hot pixel readout
[15:48:11.584] INFO: PixTest:: pg_setup set to default.
[15:48:11.584] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[15:48:11.584] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[15:48:11.584] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[15:48:11.584] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[15:48:11.584] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[15:48:11.584] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[15:48:11.584] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[15:48:11.584] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[15:48:11.584] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[15:48:11.584] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[15:48:11.584] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[15:48:11.584] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[15:48:11.585] INFO: 115 hot pixels found in step 4
[15:48:12.583] INFO: Collecting data for 5 seconds...
[15:48:17.603] INFO: Done with hot pixel readout
[15:48:30.030] INFO: PixTest:: pg_setup set to default.
[15:48:30.030] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[15:48:30.030] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[15:48:30.030] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[15:48:30.030] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[15:48:30.030] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[15:48:30.030] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[15:48:30.030] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[15:48:30.030] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[15:48:30.030] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[15:48:30.031] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[15:48:30.031] INFO: 98 hot pixels found in step 5
[15:48:31.031] INFO: Collecting data for 5 seconds...
[15:48:36.049] INFO: Done with hot pixel readout
[15:48:48.581] INFO: PixTest:: pg_setup set to default.
[15:48:48.581] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[15:48:48.581] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[15:48:48.581] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[15:48:48.581] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[15:48:48.581] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[15:48:48.581] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[15:48:48.581] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[15:48:48.581] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[15:48:48.581] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[15:48:48.581] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[15:48:48.582] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[15:48:48.582] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[15:48:48.582] INFO: 98 hot pixels found in step 6
[15:48:49.581] INFO: Collecting data for 5 seconds...
[15:48:54.597] INFO: Done with hot pixel readout
[15:49:07.038] INFO: PixTest:: pg_setup set to default.
[15:49:07.038] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[15:49:07.038] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[15:49:07.038] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[15:49:07.038] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[15:49:07.038] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[15:49:07.038] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[15:49:07.038] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[15:49:07.038] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[15:49:07.038] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[15:49:07.038] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[15:49:07.038] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[15:49:07.038] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[15:49:07.038] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[15:49:07.038] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[15:49:07.039] INFO: 91 hot pixels found in step 7
[15:49:08.035] INFO: Collecting data for 5 seconds...
[15:49:13.051] INFO: Done with hot pixel readout
[15:49:25.571] INFO: PixTest:: pg_setup set to default.
[15:49:25.572] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[15:49:25.572] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[15:49:25.572] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[15:49:25.572] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[15:49:25.572] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[15:49:25.572] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[15:49:25.572] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[15:49:25.572] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[15:49:25.572] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[15:49:25.572] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[15:49:25.572] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[15:49:25.572] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[15:49:25.572] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[15:49:25.572] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[15:49:25.573] INFO: 81 hot pixels found in step 8
[15:49:26.572] INFO: Collecting data for 5 seconds...
[15:49:31.589] INFO: Done with hot pixel readout
[15:49:44.119] INFO: PixTest:: pg_setup set to default.
[15:49:44.119] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[15:49:44.119] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[15:49:44.119] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[15:49:44.119] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[15:49:44.119] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[15:49:44.119] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[15:49:44.119] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[15:49:44.119] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[15:49:44.119] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[15:49:44.119] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[15:49:44.119] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[15:49:44.119] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[15:49:44.119] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[15:49:44.119] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[15:49:44.120] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[15:49:44.120] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[15:49:44.120] INFO: 95 hot pixels found in step 9
[15:49:45.118] INFO: Collecting data for 5 seconds...
[15:49:50.135] INFO: Done with hot pixel readout
[15:50:02.430] INFO: PixTest:: pg_setup set to default.
[15:50:02.430] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[15:50:02.430] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[15:50:02.430] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[15:50:02.430] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[15:50:02.430] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[15:50:02.430] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[15:50:02.431] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[15:50:02.431] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[15:50:02.431] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[15:50:02.431] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[15:50:02.431] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[15:50:02.431] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[15:50:02.431] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[15:50:02.431] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[15:50:02.431] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[15:50:02.431] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[15:50:02.431] INFO: 97 hot pixels found in step 10
[15:50:03.431] INFO: Collecting data for 5 seconds...
[15:50:08.448] INFO: Done with hot pixel readout
[15:50:20.961] INFO: PixTest:: pg_setup set to default.
[15:50:20.961] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[15:50:20.961] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[15:50:20.961] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[15:50:20.961] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[15:50:20.961] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[15:50:20.961] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[15:50:20.961] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[15:50:20.961] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[15:50:20.961] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[15:50:20.961] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[15:50:20.962] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[15:50:20.962] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[15:50:20.962] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[15:50:20.962] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[15:50:20.962] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[15:50:20.962] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[15:50:20.962] INFO: 78 hot pixels found in step 11
[15:50:21.967] INFO: Collecting data for 5 seconds...
[15:50:26.985] INFO: Done with hot pixel readout
[15:50:39.502] INFO: PixTest:: pg_setup set to default.
[15:50:39.502] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[15:50:39.502] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[15:50:39.502] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[15:50:39.502] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[15:50:39.502] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[15:50:39.503] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[15:50:39.503] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[15:50:39.503] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[15:50:39.503] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[15:50:39.503] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[15:50:39.503] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[15:50:39.503] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[15:50:39.503] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[15:50:39.503] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[15:50:39.503] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[15:50:39.503] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[15:50:39.503] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[15:50:39.503] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[15:50:39.503] INFO: 65 hot pixels found in step 12
[15:50:40.501] INFO: Collecting data for 5 seconds...
[15:50:45.519] INFO: Done with hot pixel readout
[15:50:58.039] INFO: PixTest:: pg_setup set to default.
[15:50:58.039] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[15:50:58.040] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[15:50:58.040] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[15:50:58.040] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[15:50:58.040] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[15:50:58.040] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[15:50:58.040] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[15:50:58.040] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[15:50:58.040] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[15:50:58.040] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[15:50:58.040] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[15:50:58.041] INFO: 63 hot pixels found in step 13
[15:50:59.040] INFO: Collecting data for 5 seconds...
[15:51:04.057] INFO: Done with hot pixel readout
[15:51:16.534] INFO: PixTest:: pg_setup set to default.
[15:51:16.535] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[15:51:16.535] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[15:51:16.535] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[15:51:16.535] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[15:51:16.535] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[15:51:16.535] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[15:51:16.535] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[15:51:16.535] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[15:51:16.535] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[15:51:16.535] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[15:51:16.535] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[15:51:16.535] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[15:51:16.535] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[15:51:16.535] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[15:51:16.535] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[15:51:16.535] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[15:51:16.535] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[15:51:16.535] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[15:51:16.535] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[15:51:16.535] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[15:51:16.536] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[15:51:16.536] INFO: 75 hot pixels found in step 14
[15:51:16.578] INFO: 75 hot pixels could not be trimmed and have been masked.
[15:51:16.582] INFO: PixTest::trimHotPixels() done
[15:51:16.582] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-3-31_FPIXTest-17C-FNAL-161024-1220-150V_2016-10-24_12h20m_1477329620/000_FPIXTest_p17//trimParameters35_C0.dat
[15:51:16.590] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-3-31_FPIXTest-17C-FNAL-161024-1220-150V_2016-10-24_12h20m_1477329620/000_FPIXTest_p17//trimParameters35_C1.dat
[15:51:16.595] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-3-31_FPIXTest-17C-FNAL-161024-1220-150V_2016-10-24_12h20m_1477329620/000_FPIXTest_p17//trimParameters35_C2.dat
[15:51:16.600] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-3-31_FPIXTest-17C-FNAL-161024-1220-150V_2016-10-24_12h20m_1477329620/000_FPIXTest_p17//trimParameters35_C3.dat
[15:51:16.605] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-3-31_FPIXTest-17C-FNAL-161024-1220-150V_2016-10-24_12h20m_1477329620/000_FPIXTest_p17//trimParameters35_C4.dat
[15:51:16.611] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-3-31_FPIXTest-17C-FNAL-161024-1220-150V_2016-10-24_12h20m_1477329620/000_FPIXTest_p17//trimParameters35_C5.dat
[15:51:16.616] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-3-31_FPIXTest-17C-FNAL-161024-1220-150V_2016-10-24_12h20m_1477329620/000_FPIXTest_p17//trimParameters35_C6.dat
[15:51:16.621] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-3-31_FPIXTest-17C-FNAL-161024-1220-150V_2016-10-24_12h20m_1477329620/000_FPIXTest_p17//trimParameters35_C7.dat
[15:51:16.626] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-3-31_FPIXTest-17C-FNAL-161024-1220-150V_2016-10-24_12h20m_1477329620/000_FPIXTest_p17//trimParameters35_C8.dat
[15:51:16.632] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-3-31_FPIXTest-17C-FNAL-161024-1220-150V_2016-10-24_12h20m_1477329620/000_FPIXTest_p17//trimParameters35_C9.dat
[15:51:16.637] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-3-31_FPIXTest-17C-FNAL-161024-1220-150V_2016-10-24_12h20m_1477329620/000_FPIXTest_p17//trimParameters35_C10.dat
[15:51:16.642] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-3-31_FPIXTest-17C-FNAL-161024-1220-150V_2016-10-24_12h20m_1477329620/000_FPIXTest_p17//trimParameters35_C11.dat
[15:51:16.647] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-3-31_FPIXTest-17C-FNAL-161024-1220-150V_2016-10-24_12h20m_1477329620/000_FPIXTest_p17//trimParameters35_C12.dat
[15:51:16.653] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-3-31_FPIXTest-17C-FNAL-161024-1220-150V_2016-10-24_12h20m_1477329620/000_FPIXTest_p17//trimParameters35_C13.dat
[15:51:16.671] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-3-31_FPIXTest-17C-FNAL-161024-1220-150V_2016-10-24_12h20m_1477329620/000_FPIXTest_p17//trimParameters35_C14.dat
[15:51:16.683] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-3-31_FPIXTest-17C-FNAL-161024-1220-150V_2016-10-24_12h20m_1477329620/000_FPIXTest_p17//trimParameters35_C15.dat
[15:51:16.693] INFO: write masked pixels into /home/uicpirepix2/ProductionTestResults/M-Q-3-31_FPIXTest-17C-FNAL-161024-1220-150V_2016-10-24_12h20m_1477329620/000_FPIXTest_p17//defaultMaskFile.dat
[15:51:16.707] INFO: enter test to run
[15:51:47.720] INFO: test: Xray setting parameters: ->source=DCLowRate<-
[15:51:47.720] INFO: running: xray
[15:51:47.722] INFO: ----------------------------------------------------------------------
[15:51:47.722] INFO: PixTestXray::doPhRun() fParRunSeconds = 100
[15:51:47.722] INFO: ----------------------------------------------------------------------
[15:51:48.686] INFO: PixTestXray::doPhRun start TriggerLoop with trigger frequency 100 kHz, period 405 and duration 100 seconds, fEventsMax = 10000000
[15:51:59.280] INFO: run duration 10 seconds, buffer almost full (81%), pausing triggers.
[15:52:28.778] INFO: Resuming triggers.
[15:52:39.371] INFO: run duration 21 seconds, buffer almost full (81%), pausing triggers.
[15:53:09.071] INFO: Resuming triggers.
[15:53:19.667] INFO: run duration 31 seconds, buffer almost full (81%), pausing triggers.
[15:53:49.159] INFO: Resuming triggers.
[15:53:59.756] INFO: run duration 42 seconds, buffer almost full (81%), pausing triggers.
[15:54:29.361] INFO: Resuming triggers.
[15:54:39.958] INFO: run duration 52 seconds, buffer almost full (81%), pausing triggers.
[15:55:09.414] INFO: Resuming triggers.
[15:55:20.010] INFO: run duration 63 seconds, buffer almost full (81%), pausing triggers.
[15:55:49.508] INFO: Resuming triggers.
[15:56:00.105] INFO: run duration 73 seconds, buffer almost full (81%), pausing triggers.
[15:56:30.018] INFO: Resuming triggers.
[15:56:40.616] INFO: run duration 84 seconds, buffer almost full (81%), pausing triggers.
[15:57:10.609] INFO: Resuming triggers.
[15:57:21.204] INFO: run duration 95 seconds, buffer almost full (81%), pausing triggers.
[15:57:51.116] INFO: Resuming triggers.
[15:57:56.099] INFO: data taking finished, elapsed time: 100 seconds.
[15:58:10.475] INFO: PixTest:: pg_setup set to default.
[15:58:10.479] INFO: PixTestXray::doPhRun() done
[15:58:10.698] INFO: enter test to run
[15:58:50.933] INFO: test: Xray setting parameters: ->source=DCHighRate<-
[15:58:50.934] INFO: running: xray
[15:58:50.935] INFO: ----------------------------------------------------------------------
[15:58:50.935] INFO: PixTestXray::doPhRun() fParRunSeconds = 100
[15:58:50.935] INFO: ----------------------------------------------------------------------
[15:58:51.898] INFO: PixTestXray::doPhRun start TriggerLoop with trigger frequency 100 kHz, period 405 and duration 100 seconds, fEventsMax = 10000000
[15:58:57.937] INFO: run duration 6 seconds, buffer almost full (81%), pausing triggers.
[15:59:28.818] INFO: Resuming triggers.
[15:59:34.850] INFO: run duration 12 seconds, buffer almost full (81%), pausing triggers.
[16:00:05.470] INFO: Resuming triggers.
[16:00:11.505] INFO: run duration 18 seconds, buffer almost full (81%), pausing triggers.
[16:00:42.568] INFO: Resuming triggers.
[16:00:48.607] INFO: run duration 24 seconds, buffer almost full (81%), pausing triggers.
[16:01:19.741] INFO: Resuming triggers.
[16:01:25.775] INFO: run duration 30 seconds, buffer almost full (81%), pausing triggers.
[16:01:56.939] INFO: Resuming triggers.
[16:02:02.976] INFO: run duration 36 seconds, buffer almost full (81%), pausing triggers.
[16:02:34.435] INFO: Resuming triggers.
[16:02:40.472] INFO: run duration 42 seconds, buffer almost full (81%), pausing triggers.
[16:03:10.559] INFO: Resuming triggers.
[16:03:16.593] INFO: run duration 48 seconds, buffer almost full (81%), pausing triggers.
[16:03:46.852] INFO: Resuming triggers.
[16:03:52.888] INFO: run duration 54 seconds, buffer almost full (81%), pausing triggers.
[16:04:23.190] INFO: Resuming triggers.
[16:04:29.229] INFO: run duration 60 seconds, buffer almost full (81%), pausing triggers.
[16:04:59.360] INFO: Resuming triggers.
[16:05:05.395] INFO: run duration 66 seconds, buffer almost full (81%), pausing triggers.
[16:05:35.458] INFO: Resuming triggers.
[16:05:41.492] INFO: run duration 72 seconds, buffer almost full (81%), pausing triggers.
[16:06:11.423] INFO: Resuming triggers.
[16:06:17.460] INFO: run duration 78 seconds, buffer almost full (81%), pausing triggers.
[16:06:47.437] INFO: Resuming triggers.
[16:06:53.470] INFO: run duration 84 seconds, buffer almost full (81%), pausing triggers.
[16:07:23.534] INFO: Resuming triggers.
[16:07:29.570] INFO: run duration 90 seconds, buffer almost full (81%), pausing triggers.
[16:07:59.610] INFO: Resuming triggers.
[16:08:05.646] INFO: run duration 96 seconds, buffer almost full (81%), pausing triggers.
[16:08:35.953] INFO: Resuming triggers.
[16:08:39.729] INFO: data taking finished, elapsed time: 100 seconds.
[16:08:58.712] INFO: PixTest:: pg_setup set to default.
[16:08:58.715] INFO: PixTestXray::doPhRun() done
[16:08:58.862] INFO: enter test to run
[16:09:22.857] INFO: test: HighRate no parameter change
[16:09:22.857] INFO: running: highrate
[16:09:22.868] INFO: ----------------------------------------------------------------------
[16:09:22.868] INFO: PixTestHighRate::calDelScan() ntrig = 10, vcal = 200
[16:09:22.868] INFO: ----------------------------------------------------------------------
[16:09:23.027] INFO: Expecting 768 events.
[16:09:24.159] INFO: 768 events read in total (416ms).
[16:09:24.160] INFO: Test took 1268ms.
[16:09:24.963] INFO: Expecting 41600 events.
[16:09:28.071] INFO: 41600 events read in total (2582ms).
[16:09:28.073] INFO: Test took 3865ms.
[16:09:28.115] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:09:28.829] INFO: Expecting 41600 events.
[16:09:32.069] INFO: 41600 events read in total (2713ms).
[16:09:32.070] INFO: Test took 3933ms.
[16:09:32.111] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:09:32.822] INFO: Expecting 41600 events.
[16:09:36.115] INFO: 41600 events read in total (2766ms).
[16:09:36.116] INFO: Test took 3986ms.
[16:09:36.157] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:09:36.869] INFO: Expecting 41600 events.
[16:09:40.171] INFO: 41600 events read in total (2775ms).
[16:09:40.172] INFO: Test took 3995ms.
[16:09:40.213] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:09:40.926] INFO: Expecting 41600 events.
[16:09:44.164] INFO: 41600 events read in total (2711ms).
[16:09:44.165] INFO: Test took 3931ms.
[16:09:44.205] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:09:44.917] INFO: Expecting 41600 events.
[16:09:48.225] INFO: 41600 events read in total (2781ms).
[16:09:48.226] INFO: Test took 4001ms.
[16:09:48.267] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:09:48.980] INFO: Expecting 41600 events.
[16:09:52.289] INFO: 41600 events read in total (2782ms).
[16:09:52.290] INFO: Test took 4002ms.
[16:09:52.331] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:09:53.041] INFO: Expecting 41600 events.
[16:09:56.346] INFO: 41600 events read in total (2778ms).
[16:09:56.347] INFO: Test took 3995ms.
[16:09:56.389] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:09:57.100] INFO: Expecting 41600 events.
[16:10:00.403] INFO: 41600 events read in total (2776ms).
[16:10:00.404] INFO: Test took 3995ms.
[16:10:00.445] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:10:01.158] INFO: Expecting 41600 events.
[16:10:04.423] INFO: 41600 events read in total (2739ms).
[16:10:04.424] INFO: Test took 3959ms.
[16:10:04.466] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:10:05.176] INFO: Expecting 41600 events.
[16:10:08.518] INFO: 41600 events read in total (2815ms).
[16:10:08.519] INFO: Test took 4031ms.
[16:10:08.560] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:10:09.271] INFO: Expecting 41600 events.
[16:10:12.629] INFO: 41600 events read in total (2831ms).
[16:10:12.631] INFO: Test took 4051ms.
[16:10:12.672] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:10:13.378] INFO: Expecting 41600 events.
[16:10:16.723] INFO: 41600 events read in total (2818ms).
[16:10:16.724] INFO: Test took 4032ms.
[16:10:16.765] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:10:17.475] INFO: Expecting 41600 events.
[16:10:20.845] INFO: 41600 events read in total (2843ms).
[16:10:20.846] INFO: Test took 4061ms.
[16:10:20.894] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:10:21.582] INFO: Expecting 41600 events.
[16:10:24.898] INFO: 41600 events read in total (2790ms).
[16:10:24.899] INFO: Test took 3982ms.
[16:10:24.941] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:10:25.652] INFO: Expecting 41600 events.
[16:10:28.974] INFO: 41600 events read in total (2796ms).
[16:10:28.975] INFO: Test took 4013ms.
[16:10:29.016] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:10:29.725] INFO: Expecting 41600 events.
[16:10:33.043] INFO: 41600 events read in total (2791ms).
[16:10:33.044] INFO: Test took 4008ms.
[16:10:33.098] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:10:33.787] INFO: Expecting 41600 events.
[16:10:37.045] INFO: 41600 events read in total (2731ms).
[16:10:37.046] INFO: Test took 3927ms.
[16:10:37.088] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:10:37.793] INFO: Expecting 41600 events.
[16:10:41.109] INFO: 41600 events read in total (2789ms).
[16:10:41.110] INFO: Test took 4002ms.
[16:10:41.151] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:10:41.860] INFO: Expecting 41600 events.
[16:10:45.223] INFO: 41600 events read in total (2836ms).
[16:10:45.225] INFO: Test took 4053ms.
[16:10:45.277] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:10:45.610] INFO: enter test to run
[16:11:14.705] INFO: test: HighRate no parameter change
[16:11:14.705] INFO: running: highrate
[16:11:14.706] INFO: ----------------------------------------------------------------------
[16:11:14.706] INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[16:11:14.706] INFO: ----------------------------------------------------------------------
[16:11:15.326] INFO: Expecting 208000 events.
[16:11:26.530] INFO: 208000 events read in total (10677ms).
[16:11:26.534] INFO: Test took 11819ms.
[16:11:26.719] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:11:26.984] INFO: number of dead pixels (per ROC): 0 0 0 1 0 0 11 5 0 0 0 0 0 0 0 0
[16:11:27.010] INFO: number of red-efficiency pixels: 88 68 119 148 169 217 249 107 117 188 129 153 146 79 33 27
[16:11:27.010] INFO: number of X-ray hits detected: 75220 52672 83200 130422 133709 142235 145071 96494 89566 136104 127266 109825 115137 70767 28224 29018
[16:11:27.010] INFO: number of triggers sent (total per ROC): 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000
[16:11:27.010] INFO: number of Vcal hits detected: 207911 207932 207879 207795 207829 207774 207202 207647 207880 207805 207866 207844 207853 207921 207967 207972
[16:11:27.010] INFO: Vcal hit fiducial efficiency (%): 100.0 100.0 99.9 99.9 99.9 99.9 99.9 100.0 99.9 99.9 99.9 99.9 99.9 100.0 100.0 100.0
[16:11:27.010] INFO: Vcal hit overall efficiency (%): 100.0 100.0 99.9 99.9 99.9 99.9 99.6 99.8 99.9 99.9 99.9 99.9 99.9 100.0 100.0 100.0
[16:11:27.010] INFO: X-ray hit rate [MHz/cm2]: 22.0 15.4 24.4 38.2 39.2 41.7 42.5 28.3 26.3 39.9 37.3 32.2 33.7 20.7 8.3 8.5
[16:11:27.010] INFO: PixTestHighRate::doXPixelAlive() done
[16:11:27.055] INFO: PixTest:: pg_setup set to default.
[16:11:27.064] INFO: enter test to run
[16:12:17.608] INFO: test: HighRate no parameter change
[16:12:17.608] INFO: running: highrate
[16:12:17.609] INFO: ----------------------------------------------------------------------
[16:12:17.609] INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[16:12:17.609] INFO: ----------------------------------------------------------------------
[16:12:18.228] INFO: Expecting 208000 events.
[16:12:32.508] INFO: 208000 events read in total (13753ms).
[16:12:32.515] INFO: Test took 14896ms.
[16:12:32.878] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:12:33.202] INFO: number of dead pixels (per ROC): 0 0 0 1 0 0 9 5 0 0 0 0 0 0 0 0
[16:12:33.202] INFO: number of red-efficiency pixels: 204 187 415 454 476 843 832 331 347 695 456 470 416 261 83 59
[16:12:33.202] INFO: number of X-ray hits detected: 149978 105739 165662 258625 268014 286014 290524 193822 180586 271465 255940 219384 229204 142215 56437 57974
[16:12:33.202] INFO: number of triggers sent (total per ROC): 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000
[16:12:33.202] INFO: number of Vcal hits detected: 207781 207803 207537 207451 207475 207033 206499 207400 207628 207214 207508 207482 207553 207720 207914 207939
[16:12:33.202] INFO: Vcal hit fiducial efficiency (%): 99.9 99.9 99.8 99.8 99.8 99.6 99.6 99.8 99.8 99.7 99.8 99.8 99.8 99.9 100.0 100.0
[16:12:33.202] INFO: Vcal hit overall efficiency (%): 99.9 99.9 99.8 99.7 99.7 99.5 99.3 99.7 99.8 99.6 99.8 99.8 99.8 99.9 100.0 100.0
[16:12:33.202] INFO: X-ray hit rate [MHz/cm2]: 44.0 31.0 48.6 75.8 78.6 83.8 85.2 56.8 52.9 79.6 75.0 64.3 67.2 41.7 16.5 17.0
[16:12:33.202] INFO: PixTestHighRate::doXPixelAlive() done
[16:12:33.249] INFO: PixTest:: pg_setup set to default.
[16:12:33.262] INFO: enter test to run
[16:12:56.288] INFO: test: HighRate no parameter change
[16:12:56.288] INFO: running: highrate
[16:12:56.289] INFO: ----------------------------------------------------------------------
[16:12:56.289] INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[16:12:56.289] INFO: ----------------------------------------------------------------------
[16:12:56.910] INFO: Expecting 208000 events.
[16:13:13.402] INFO: 208000 events read in total (15966ms).
[16:13:13.410] INFO: Test took 17111ms.
[16:13:13.949] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:13:14.330] INFO: number of dead pixels (per ROC): 0 0 0 1 0 0 11 5 0 0 0 0 0 0 0 0
[16:13:14.330] INFO: number of red-efficiency pixels: 449 357 860 990 1143 1700 1841 718 680 1521 1008 1058 861 574 132 91
[16:13:14.330] INFO: number of X-ray hits detected: 221060 156307 244087 380951 394507 419464 423966 285377 266103 400658 374833 322774 336493 208652 83202 85802
[16:13:14.330] INFO: number of triggers sent (total per ROC): 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000
[16:13:14.330] INFO: number of Vcal hits detected: 207482 207597 206768 206726 206534 205502 204708 206826 207152 205949 206778 206673 206973 207272 207863 207907
[16:13:14.330] INFO: Vcal hit fiducial efficiency (%): 99.8 99.8 99.5 99.5 99.4 98.9 98.8 99.6 99.6 99.1 99.5 99.4 99.6 99.7 99.9 100.0
[16:13:14.330] INFO: Vcal hit overall efficiency (%): 99.8 99.8 99.4 99.4 99.3 98.8 98.4 99.4 99.6 99.0 99.4 99.4 99.5 99.7 99.9 100.0
[16:13:14.330] INFO: X-ray hit rate [MHz/cm2]: 64.8 45.8 71.5 111.7 115.6 122.9 124.3 83.6 78.0 117.4 109.9 94.6 98.6 61.2 24.4 25.1
[16:13:14.330] INFO: PixTestHighRate::doXPixelAlive() done
[16:13:14.376] INFO: PixTest:: pg_setup set to default.
[16:13:14.394] INFO: enter test to run
[16:13:22.824] INFO: test: exit no parameter change
[16:13:23.787] QUIET: Connection to board 30 closed.
[16:13:24.063] INFO: pXar: this is the end, my friend