[14:51:53.511] INFO: *** Welcome to pxar ***
[14:51:53.511] INFO: *** Today: 2016/04/12
[14:51:53.531] INFO: *** Version: v1.9.0-796-gef167-dirty
[14:51:53.531] INFO: readRocDacs: /home/uicpirepix2/ProductionTestResults/M-K-2-46_FPIXTest-17C-FNAL-160408-0917_2016-04-08_09h17m_1460125040/000_FPIXTest_p17//dacParameters35_C0.dat .. /home/uicpirepix2/ProductionTestResults/M-K-2-46_FPIXTest-17C-FNAL-160408-0917_2016-04-08_09h17m_1460125040/000_FPIXTest_p17//dacParameters35_C15.dat
[14:51:53.533] INFO: readTbmDacs: /home/uicpirepix2/ProductionTestResults/M-K-2-46_FPIXTest-17C-FNAL-160408-0917_2016-04-08_09h17m_1460125040/000_FPIXTest_p17//tbmParameters_C0a.dat .. /home/uicpirepix2/ProductionTestResults/M-K-2-46_FPIXTest-17C-FNAL-160408-0917_2016-04-08_09h17m_1460125040/000_FPIXTest_p17//tbmParameters_C0b.dat
[14:51:53.533] INFO: readMaskFile: /home/uicpirepix2/ProductionTestResults/M-K-2-46_FPIXTest-17C-FNAL-160408-0917_2016-04-08_09h17m_1460125040/000_FPIXTest_p17//defaultMaskFile.dat
[14:51:53.533] INFO: readTrimFile: /home/uicpirepix2/ProductionTestResults/M-K-2-46_FPIXTest-17C-FNAL-160408-0917_2016-04-08_09h17m_1460125040/000_FPIXTest_p17//trimParameters35_C0.dat .. /home/uicpirepix2/ProductionTestResults/M-K-2-46_FPIXTest-17C-FNAL-160408-0917_2016-04-08_09h17m_1460125040/000_FPIXTest_p17//trimParameters35_C15.dat
[14:51:53.613] INFO: clk: 4
[14:51:53.613] INFO: ctr: 4
[14:51:53.613] INFO: sda: 19
[14:51:53.613] INFO: tin: 9
[14:51:53.613] INFO: level: 15
[14:51:53.613] INFO: triggerdelay: 0
[14:51:53.613] QUIET: Instanciating API for pxar v1.9.0+796~gef16747
[14:51:53.613] INFO: Log level: INFO
[14:51:53.630] QUIET: Connection to board DTB_WREKRL opened.
[14:51:53.634] 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:51:53.637] INFO: RPC call hashes of host and DTB match: 398089610
[14:51:55.168] INFO: DUT info:
[14:51:55.168] INFO: The DUT currently contains the following objects:
[14:51:55.168] INFO: 2 TBM Cores tbm08c (2 ON)
[14:51:55.168] INFO: TBM Core alpha (0): 7 registers set
[14:51:55.169] INFO: TBM Core beta (1): 7 registers set
[14:51:55.169] INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[14:51:55.169] INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[14:51:55.169] INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[14:51:55.169] INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[14:51:55.169] INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[14:51:55.169] INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[14:51:55.169] INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[14:51:55.169] INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[14:51:55.169] INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[14:51:55.169] INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[14:51:55.169] INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[14:51:55.169] INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[14:51:55.169] INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[14:51:55.169] INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[14:51:55.169] INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[14:51:55.169] INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[14:51:55.169] INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[14:51:55.572] INFO: enter 'restricted' command line mode
[14:51:55.572] INFO: enter test to run
[14:52:06.017] INFO: test: PixelAlive no parameter change
[14:52:06.017] INFO: running: pixelalive
[14:52:06.026] INFO: ----------------------------------------------------------------------
[14:52:06.026] 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:52:06.026] INFO: ----------------------------------------------------------------------
[14:52:06.346] INFO: Expecting 41600 events.
[14:52:10.553] INFO: 41600 events read in total (3489ms).
[14:52:10.718] INFO: Test took 4690ms.
[14:52:10.725] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:52:10.987] INFO: PixTestAlive::aliveTest() done
[14:52:10.987] INFO: number of dead pixels (per ROC): 0 0 0 0 0 2 0 0 2 0 0 0 0 0 0 0
[14:52:11.025] INFO: enter test to run
[14:53:13.632] INFO: test: HighRate setting parameters: ->runsecondshotpixels=5;trimhotpixelthr=200<-
[14:53:13.632] INFO: running: highrate
[14:53:13.632] INFO: readGainPedestalParameters /home/uicpirepix2/ProductionTestResults/M-K-2-46_FPIXTest-17C-FNAL-160408-0917_2016-04-08_09h17m_1460125040/000_FPIXTest_p17//phCalibrationFitErr35_C0.dat .. /home/uicpirepix2/ProductionTestResults/M-K-2-46_FPIXTest-17C-FNAL-160408-0917_2016-04-08_09h17m_1460125040/000_FPIXTest_p17//phCalibrationFitErr35_C15.dat
[14:53:13.782] INFO: ----------------------------------------------------------------------
[14:53:13.782] INFO: PixTest::trimHotPixels() running for 5 seconds with 100 kHz trigger rate
[14:53:13.782] INFO: ----------------------------------------------------------------------
[14:53:13.782] INFO: THR = 200, corresponding to ~ 106.667 MHz/cm2
[14:53:13.782] INFO: edge/corner pixel THR is adjusted
[14:53:13.782] INFO: PixTestHighRate::trimHotPixels: step 0...
[14:53:14.742] INFO: Collecting data for 5 seconds...
[14:53:19.759] INFO: Done with hot pixel readout
[14:53:28.708] INFO: PixTest:: pg_setup set to default.
[14:53:28.709] INFO: 1 hot pixels found in step 0
[14:53:29.699] INFO: Collecting data for 5 seconds...
[14:53:34.709] INFO: Done with hot pixel readout
[14:53:46.135] INFO: PixTest:: pg_setup set to default.
[14:53:46.136] INFO: 2 hot pixels found in step 1
[14:53:47.134] INFO: Collecting data for 5 seconds...
[14:53:52.150] INFO: Done with hot pixel readout
[14:54:03.828] INFO: PixTest:: pg_setup set to default.
[14:54:03.829] INFO: 3 hot pixels found in step 2
[14:54:04.825] INFO: Collecting data for 5 seconds...
[14:54:09.842] INFO: Done with hot pixel readout
[14:54:21.700] INFO: PixTest:: pg_setup set to default.
[14:54:21.701] INFO: 4 hot pixels found in step 3
[14:54:22.694] INFO: Collecting data for 5 seconds...
[14:54:27.712] INFO: Done with hot pixel readout
[14:54:27.988] ERROR: <datapipe.cc/CheckEventValidity:L491> Channel 0 Number of ROCs (4) != Token Chain Length (8)
[14:54:27.988] ERROR: <datapipe.cc/CheckEventValidity:L491> Channel 1 Number of ROCs (6) != Token Chain Length (8)
[14:54:27.988] ERROR: <datapipe.cc/Read:L164> Dumping the flawed event +- 3 events:
[14:54:27.988] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a0ff 8040 43ca 29a 282d 43ca 4d3 2645 43ca 65a 264b 43ca 43ca 6cc 2649 43ca 43ca 51a 2841 43ca e000 c000
[14:54:27.988] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a0f9 80c0 4388 4388 4388 409 2644 85b 2268 4388 4388 4388 4cd 2847 4388 301 244f 4388 284 2249 e000 c000
[14:54:27.988] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a0fa 8000 4388 213 264f 451 2247 4388 4388 4388 4388 758 2044 4388 658 242d 68a 2665 4388 4388 e000 c000
[14:54:27.988] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a0fb 8040 4388 4388 4388 61a 2441 4388 4388 d5 2a42 244 2a4a 411 2040 518 284a 4388 4b 2a4f 4388 221 2a4d 4388 88 224d e000 c000
[14:54:27.988] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a0fc 80b1 7fc 2ff8 7f8 43c8 290 264f 45c 2a46 43c8 2c9 2245 354 246d 43c8 818 242f 43c8 549 2441 43c8 e000 c000
[14:54:27.988] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a0fd 80c0 43c8 43c8 454 284f 43c8 43c8 20a 2241 43c8 43c8 43c8 43c8 e000 c000
[14:54:27.988] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a0fe 8000 43c8 43c8 43c8 43c8 215 2241 854 264f 43c8 74a 2847 43c8 43c8 305 2462 43c8 e000 c000
[14:54:27.988] ERROR: <datapipe.cc/Read:L164> Dumping the flawed event +- 3 events:
[14:54:27.988] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a1ff 8040 43ca 560 2a49 43ca 19 2442 43ca 43ca 43ca 6cc 284d 43ca 101 2429 43ca 43c9 e000 c000
[14:54:27.989] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a1f9 80c0 4388 4388 4388 4388 19 202f 4388 4388 4388 4388 e000 c000
[14:54:27.989] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a1fa 8000 4388 4388 2e0 2844 4388 4388 6d5 2848 4388 4388 4388 4388 e000 c000
[14:54:27.989] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a1fb 8040 4388 4388 4388 814 202f 4388 4388 4388 4388 4388 e000 c000
[14:54:27.989] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a1fc 80b1 43c8 2020 18 2485 4 43c8 db 2045 43c8 43c8 19 2849 43c8 43c8 e000 c000
[14:54:27.989] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a1fd 80c0 43c8 43c8 43c8 43c8 60a 2a2f 43c8 43c8 43c8 699 2a2f 43ca e000 c000
[14:54:27.989] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a1fe 8000 43c8 703 224c 43c8 614 2445 71d 2849 43c8 43c8 43c8 4c9 2840 43c8 43c8 43c9 e000 c000
[14:54:39.608] INFO: PixTest:: pg_setup set to default.
[14:54:39.609] INFO: 2 hot pixels found in step 4
[14:54:40.604] INFO: Collecting data for 5 seconds...
[14:54:45.625] INFO: Done with hot pixel readout
[14:54:57.056] INFO: PixTest:: pg_setup set to default.
[14:54:57.057] INFO: 3 hot pixels found in step 5
[14:54:58.051] INFO: Collecting data for 5 seconds...
[14:55:03.067] INFO: Done with hot pixel readout
[14:55:14.924] INFO: PixTest:: pg_setup set to default.
[14:55:14.925] INFO: 3 hot pixels found in step 6
[14:55:15.920] INFO: Collecting data for 5 seconds...
[14:55:20.935] INFO: Done with hot pixel readout
[14:55:32.808] INFO: PixTest:: pg_setup set to default.
[14:55:32.809] INFO: 3 hot pixels found in step 7
[14:55:33.803] INFO: Collecting data for 5 seconds...
[14:55:38.820] INFO: Done with hot pixel readout
[14:55:50.707] INFO: PixTest:: pg_setup set to default.
[14:55:50.708] INFO: 0 hot pixels found in step 8
[14:55:50.746] INFO: 0 hot pixels could not be trimmed and have been masked.
[14:55:50.750] INFO: PixTest::trimHotPixels() done
[14:55:50.750] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-K-2-46_FPIXTest-17C-FNAL-160408-0917_2016-04-08_09h17m_1460125040/000_FPIXTest_p17//trimParameters35_C0.dat
[14:55:50.762] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-K-2-46_FPIXTest-17C-FNAL-160408-0917_2016-04-08_09h17m_1460125040/000_FPIXTest_p17//trimParameters35_C1.dat
[14:55:50.769] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-K-2-46_FPIXTest-17C-FNAL-160408-0917_2016-04-08_09h17m_1460125040/000_FPIXTest_p17//trimParameters35_C2.dat
[14:55:50.775] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-K-2-46_FPIXTest-17C-FNAL-160408-0917_2016-04-08_09h17m_1460125040/000_FPIXTest_p17//trimParameters35_C3.dat
[14:55:50.780] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-K-2-46_FPIXTest-17C-FNAL-160408-0917_2016-04-08_09h17m_1460125040/000_FPIXTest_p17//trimParameters35_C4.dat
[14:55:50.785] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-K-2-46_FPIXTest-17C-FNAL-160408-0917_2016-04-08_09h17m_1460125040/000_FPIXTest_p17//trimParameters35_C5.dat
[14:55:50.791] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-K-2-46_FPIXTest-17C-FNAL-160408-0917_2016-04-08_09h17m_1460125040/000_FPIXTest_p17//trimParameters35_C6.dat
[14:55:50.796] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-K-2-46_FPIXTest-17C-FNAL-160408-0917_2016-04-08_09h17m_1460125040/000_FPIXTest_p17//trimParameters35_C7.dat
[14:55:50.801] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-K-2-46_FPIXTest-17C-FNAL-160408-0917_2016-04-08_09h17m_1460125040/000_FPIXTest_p17//trimParameters35_C8.dat
[14:55:50.806] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-K-2-46_FPIXTest-17C-FNAL-160408-0917_2016-04-08_09h17m_1460125040/000_FPIXTest_p17//trimParameters35_C9.dat
[14:55:50.812] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-K-2-46_FPIXTest-17C-FNAL-160408-0917_2016-04-08_09h17m_1460125040/000_FPIXTest_p17//trimParameters35_C10.dat
[14:55:50.817] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-K-2-46_FPIXTest-17C-FNAL-160408-0917_2016-04-08_09h17m_1460125040/000_FPIXTest_p17//trimParameters35_C11.dat
[14:55:50.822] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-K-2-46_FPIXTest-17C-FNAL-160408-0917_2016-04-08_09h17m_1460125040/000_FPIXTest_p17//trimParameters35_C12.dat
[14:55:50.827] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-K-2-46_FPIXTest-17C-FNAL-160408-0917_2016-04-08_09h17m_1460125040/000_FPIXTest_p17//trimParameters35_C13.dat
[14:55:50.833] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-K-2-46_FPIXTest-17C-FNAL-160408-0917_2016-04-08_09h17m_1460125040/000_FPIXTest_p17//trimParameters35_C14.dat
[14:55:50.838] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-K-2-46_FPIXTest-17C-FNAL-160408-0917_2016-04-08_09h17m_1460125040/000_FPIXTest_p17//trimParameters35_C15.dat
[14:55:50.843] INFO: write masked pixels into /home/uicpirepix2/ProductionTestResults/M-K-2-46_FPIXTest-17C-FNAL-160408-0917_2016-04-08_09h17m_1460125040/000_FPIXTest_p17//defaultMaskFile.dat
[14:55:50.853] INFO: enter test to run
[15:01:00.844] INFO: test: timing no parameter change
[15:01:00.845] INFO: running: timing
[15:01:00.868] INFO: ######################################################################
[15:01:00.868] INFO: PixTestTiming::doTest()
[15:01:00.877] INFO: ######################################################################
[15:01:00.877] INFO: ----------------------------------------------------------------------
[15:01:00.877] INFO: PixTestTiming::TBMPhaseScan()
[15:01:00.877] INFO: ----------------------------------------------------------------------
[15:06:46.795] INFO: TBM Phase Settings: 240
[15:06:46.795] INFO: 400MHz Phase: 4
[15:06:46.795] INFO: 160MHz Phase: 7
[15:06:46.795] INFO: Functional Phase Area: 4
[15:06:46.804] INFO: Test took 345927 ms.
[15:06:46.805] INFO: PixTestTiming::TBMPhaseScan() done.
[15:06:46.805] INFO: ----------------------------------------------------------------------
[15:06:46.805] INFO: PixTestTiming::ROCDelayScan()
[15:06:46.805] INFO: ----------------------------------------------------------------------
[15:08:53.957] INFO: ROC Delay Settings: 228
[15:08:53.957] INFO: ROC Header-Trailer/Token Delay: 11
[15:08:53.957] INFO: ROC Port 0 Delay: 4
[15:08:53.957] INFO: ROC Port 1 Delay: 4
[15:08:53.957] INFO: Functional ROC Area: 5
[15:08:53.960] INFO: Test took 127155 ms.
[15:08:53.960] INFO: PixTestTiming::ROCDelayScan() done.
[15:08:53.961] INFO: ----------------------------------------------------------------------
[15:08:53.961] INFO: PixTestTiming::TimingTest()
[15:08:53.961] INFO: ----------------------------------------------------------------------
[15:09:10.075] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:09:25.032] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:09:39.004] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:09:54.969] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:10:09.925] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:10:24.896] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:10:39.874] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:10:54.830] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:11:09.812] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:11:24.794] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:11:25.175] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:11:25.189] INFO: ----------------------------------------------------------------------
[15:11:25.189] INFO: The fraction of properly decoded events is 100.00%: 10000000/10000000
[15:11:25.189] INFO: ----------------------------------------------------------------------
[15:11:25.189] INFO: ----------------------------------------------------------------------
[15:11:25.189] INFO: Read back bit status: 1
[15:11:25.189] INFO: ----------------------------------------------------------------------
[15:11:25.189] INFO: ----------------------------------------------------------------------
[15:11:25.189] INFO: Timings are good!
[15:11:25.189] INFO: ----------------------------------------------------------------------
[15:11:25.189] INFO: Test took 151228 ms.
[15:11:25.189] INFO: PixTestTiming::TimingTest() done.
[15:11:25.270] INFO: write tbm parameters into /home/uicpirepix2/ProductionTestResults/M-K-2-46_FPIXTest-17C-FNAL-160408-0917_2016-04-08_09h17m_1460125040/000_FPIXTest_p17//tbmParameters_C0a.dat
[15:11:25.270] INFO: write tbm parameters into /home/uicpirepix2/ProductionTestResults/M-K-2-46_FPIXTest-17C-FNAL-160408-0917_2016-04-08_09h17m_1460125040/000_FPIXTest_p17//tbmParameters_C0b.dat
[15:11:25.270] INFO: PixTestTiming::doTest took 624405 ms.
[15:11:25.270] INFO: PixTestTiming::doTest() done
[15:11:25.271] INFO: Write out TBMPhaseScan_0_V0
[15:11:25.271] INFO: Write out TBMPhaseScan_1_V0
[15:11:25.271] INFO: Write out CombinedTBMPhaseScan_V0
[15:11:25.312] INFO: Write out ROCDelayScan3_V0
[15:11:25.313] INFO: enter test to run
[15:13:59.631] INFO: test: HighRate setting parameters: ->runsecondshotpixels=5;trimhotpixelthr=200<-
[15:13:59.631] INFO: running: highrate
[15:13:59.636] INFO: ----------------------------------------------------------------------
[15:13:59.636] INFO: PixTest::trimHotPixels() running for 5 seconds with 100 kHz trigger rate
[15:13:59.636] INFO: ----------------------------------------------------------------------
[15:13:59.636] INFO: THR = 200, corresponding to ~ 106.667 MHz/cm2
[15:13:59.636] INFO: edge/corner pixel THR is adjusted
[15:13:59.636] INFO: PixTestHighRate::trimHotPixels: step 0...
[15:14:00.593] INFO: Collecting data for 5 seconds...
[15:14:05.610] INFO: Done with hot pixel readout
[15:14:17.429] INFO: PixTest:: pg_setup set to default.
[15:14:17.430] INFO: 0 hot pixels found in step 0
[15:14:17.473] INFO: 0 hot pixels could not be trimmed and have been masked.
[15:14:17.477] INFO: PixTest::trimHotPixels() done
[15:14:17.477] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-K-2-46_FPIXTest-17C-FNAL-160408-0917_2016-04-08_09h17m_1460125040/000_FPIXTest_p17//trimParameters35_C0.dat
[15:14:17.483] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-K-2-46_FPIXTest-17C-FNAL-160408-0917_2016-04-08_09h17m_1460125040/000_FPIXTest_p17//trimParameters35_C1.dat
[15:14:17.489] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-K-2-46_FPIXTest-17C-FNAL-160408-0917_2016-04-08_09h17m_1460125040/000_FPIXTest_p17//trimParameters35_C2.dat
[15:14:17.494] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-K-2-46_FPIXTest-17C-FNAL-160408-0917_2016-04-08_09h17m_1460125040/000_FPIXTest_p17//trimParameters35_C3.dat
[15:14:17.499] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-K-2-46_FPIXTest-17C-FNAL-160408-0917_2016-04-08_09h17m_1460125040/000_FPIXTest_p17//trimParameters35_C4.dat
[15:14:17.504] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-K-2-46_FPIXTest-17C-FNAL-160408-0917_2016-04-08_09h17m_1460125040/000_FPIXTest_p17//trimParameters35_C5.dat
[15:14:17.510] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-K-2-46_FPIXTest-17C-FNAL-160408-0917_2016-04-08_09h17m_1460125040/000_FPIXTest_p17//trimParameters35_C6.dat
[15:14:17.515] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-K-2-46_FPIXTest-17C-FNAL-160408-0917_2016-04-08_09h17m_1460125040/000_FPIXTest_p17//trimParameters35_C7.dat
[15:14:17.520] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-K-2-46_FPIXTest-17C-FNAL-160408-0917_2016-04-08_09h17m_1460125040/000_FPIXTest_p17//trimParameters35_C8.dat
[15:14:17.525] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-K-2-46_FPIXTest-17C-FNAL-160408-0917_2016-04-08_09h17m_1460125040/000_FPIXTest_p17//trimParameters35_C9.dat
[15:14:17.530] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-K-2-46_FPIXTest-17C-FNAL-160408-0917_2016-04-08_09h17m_1460125040/000_FPIXTest_p17//trimParameters35_C10.dat
[15:14:17.536] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-K-2-46_FPIXTest-17C-FNAL-160408-0917_2016-04-08_09h17m_1460125040/000_FPIXTest_p17//trimParameters35_C11.dat
[15:14:17.541] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-K-2-46_FPIXTest-17C-FNAL-160408-0917_2016-04-08_09h17m_1460125040/000_FPIXTest_p17//trimParameters35_C12.dat
[15:14:17.546] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-K-2-46_FPIXTest-17C-FNAL-160408-0917_2016-04-08_09h17m_1460125040/000_FPIXTest_p17//trimParameters35_C13.dat
[15:14:17.551] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-K-2-46_FPIXTest-17C-FNAL-160408-0917_2016-04-08_09h17m_1460125040/000_FPIXTest_p17//trimParameters35_C14.dat
[15:14:17.556] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-K-2-46_FPIXTest-17C-FNAL-160408-0917_2016-04-08_09h17m_1460125040/000_FPIXTest_p17//trimParameters35_C15.dat
[15:14:17.562] INFO: write masked pixels into /home/uicpirepix2/ProductionTestResults/M-K-2-46_FPIXTest-17C-FNAL-160408-0917_2016-04-08_09h17m_1460125040/000_FPIXTest_p17//defaultMaskFile.dat
[15:14:17.572] INFO: enter test to run
[15:14:48.054] INFO: test: HighRate setting parameters: ->runsecondshotpixels=1;trimhotpixelthr=200<-
[15:14:48.054] INFO: running: highrate
[15:14:48.060] INFO: ----------------------------------------------------------------------
[15:14:48.060] INFO: PixTest::trimHotPixels() running for 1 seconds with 100 kHz trigger rate
[15:14:48.060] INFO: ----------------------------------------------------------------------
[15:14:48.060] INFO: THR = 200, corresponding to ~ 533.333 MHz/cm2
[15:14:48.060] INFO: edge/corner pixel THR is adjusted
[15:14:48.060] INFO: PixTestHighRate::trimHotPixels: step 0...
[15:14:49.018] INFO: Collecting data for 1 seconds...
[15:14:50.021] INFO: Done with hot pixel readout
[15:14:54.138] INFO: PixTest:: pg_setup set to default.
[15:14:54.139] INFO: 0 hot pixels found in step 0
[15:14:54.144] INFO: 0 hot pixels could not be trimmed and have been masked.
[15:14:54.230] INFO: PixTest::trimHotPixels() done
[15:14:54.230] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-K-2-46_FPIXTest-17C-FNAL-160408-0917_2016-04-08_09h17m_1460125040/000_FPIXTest_p17//trimParameters35_C0.dat
[15:14:54.237] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-K-2-46_FPIXTest-17C-FNAL-160408-0917_2016-04-08_09h17m_1460125040/000_FPIXTest_p17//trimParameters35_C1.dat
[15:14:54.243] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-K-2-46_FPIXTest-17C-FNAL-160408-0917_2016-04-08_09h17m_1460125040/000_FPIXTest_p17//trimParameters35_C2.dat
[15:14:54.248] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-K-2-46_FPIXTest-17C-FNAL-160408-0917_2016-04-08_09h17m_1460125040/000_FPIXTest_p17//trimParameters35_C3.dat
[15:14:54.253] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-K-2-46_FPIXTest-17C-FNAL-160408-0917_2016-04-08_09h17m_1460125040/000_FPIXTest_p17//trimParameters35_C4.dat
[15:14:54.258] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-K-2-46_FPIXTest-17C-FNAL-160408-0917_2016-04-08_09h17m_1460125040/000_FPIXTest_p17//trimParameters35_C5.dat
[15:14:54.264] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-K-2-46_FPIXTest-17C-FNAL-160408-0917_2016-04-08_09h17m_1460125040/000_FPIXTest_p17//trimParameters35_C6.dat
[15:14:54.269] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-K-2-46_FPIXTest-17C-FNAL-160408-0917_2016-04-08_09h17m_1460125040/000_FPIXTest_p17//trimParameters35_C7.dat
[15:14:54.274] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-K-2-46_FPIXTest-17C-FNAL-160408-0917_2016-04-08_09h17m_1460125040/000_FPIXTest_p17//trimParameters35_C8.dat
[15:14:54.279] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-K-2-46_FPIXTest-17C-FNAL-160408-0917_2016-04-08_09h17m_1460125040/000_FPIXTest_p17//trimParameters35_C9.dat
[15:14:54.284] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-K-2-46_FPIXTest-17C-FNAL-160408-0917_2016-04-08_09h17m_1460125040/000_FPIXTest_p17//trimParameters35_C10.dat
[15:14:54.290] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-K-2-46_FPIXTest-17C-FNAL-160408-0917_2016-04-08_09h17m_1460125040/000_FPIXTest_p17//trimParameters35_C11.dat
[15:14:54.295] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-K-2-46_FPIXTest-17C-FNAL-160408-0917_2016-04-08_09h17m_1460125040/000_FPIXTest_p17//trimParameters35_C12.dat
[15:14:54.300] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-K-2-46_FPIXTest-17C-FNAL-160408-0917_2016-04-08_09h17m_1460125040/000_FPIXTest_p17//trimParameters35_C13.dat
[15:14:54.305] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-K-2-46_FPIXTest-17C-FNAL-160408-0917_2016-04-08_09h17m_1460125040/000_FPIXTest_p17//trimParameters35_C14.dat
[15:14:54.311] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-K-2-46_FPIXTest-17C-FNAL-160408-0917_2016-04-08_09h17m_1460125040/000_FPIXTest_p17//trimParameters35_C15.dat
[15:14:54.316] INFO: write masked pixels into /home/uicpirepix2/ProductionTestResults/M-K-2-46_FPIXTest-17C-FNAL-160408-0917_2016-04-08_09h17m_1460125040/000_FPIXTest_p17//defaultMaskFile.dat
[15:14:54.332] INFO: enter test to run
[15:15:19.278] INFO: test: Xray setting parameters: ->source=DCLowRate<-
[15:15:19.278] INFO: running: xray
[15:15:19.279] INFO: ----------------------------------------------------------------------
[15:15:19.279] INFO: PixTestXray::doPhRun() fParRunSeconds = 100
[15:15:19.280] INFO: ----------------------------------------------------------------------
[15:15:20.243] INFO: PixTestXray::doPhRun start TriggerLoop with trigger frequency 100 kHz, period 405 and duration 100 seconds, fEventsMax = 10000000
[15:15:31.519] INFO: run duration 11 seconds, buffer almost full (81%), pausing triggers.
[15:16:01.186] INFO: Resuming triggers.
[15:16:12.459] INFO: run duration 22 seconds, buffer almost full (81%), pausing triggers.
[15:16:41.518] INFO: Resuming triggers.
[15:16:52.796] INFO: run duration 33 seconds, buffer almost full (81%), pausing triggers.
[15:17:22.217] INFO: Resuming triggers.
[15:17:33.496] INFO: run duration 44 seconds, buffer almost full (81%), pausing triggers.
[15:18:03.092] INFO: Resuming triggers.
[15:18:14.373] INFO: run duration 56 seconds, buffer almost full (81%), pausing triggers.
[15:18:43.789] INFO: Resuming triggers.
[15:18:55.069] INFO: run duration 67 seconds, buffer almost full (81%), pausing triggers.
[15:19:24.919] INFO: Resuming triggers.
[15:19:36.195] INFO: run duration 78 seconds, buffer almost full (81%), pausing triggers.
[15:20:04.941] INFO: Resuming triggers.
[15:20:16.219] INFO: run duration 89 seconds, buffer almost full (81%), pausing triggers.
[15:20:45.655] INFO: Resuming triggers.
[15:20:55.772] INFO: data taking finished, elapsed time: 100 seconds.
[15:21:22.231] INFO: PixTest:: pg_setup set to default.
[15:21:22.234] INFO: PixTestXray::doPhRun() done
[15:21:22.370] INFO: enter test to run
[15:21:47.899] INFO: test: Xray setting parameters: ->source=DCHighRate<-
[15:21:47.899] INFO: running: xray
[15:21:47.900] INFO: ----------------------------------------------------------------------
[15:21:47.900] INFO: PixTestXray::doPhRun() fParRunSeconds = 100
[15:21:47.900] INFO: ----------------------------------------------------------------------
[15:21:48.877] INFO: PixTestXray::doPhRun start TriggerLoop with trigger frequency 100 kHz, period 405 and duration 100 seconds, fEventsMax = 10000000
[15:21:55.286] INFO: run duration 6 seconds, buffer almost full (81%), pausing triggers.
[15:22:25.596] INFO: Resuming triggers.
[15:22:32.006] INFO: run duration 12 seconds, buffer almost full (81%), pausing triggers.
[15:23:02.030] INFO: Resuming triggers.
[15:23:08.441] INFO: run duration 19 seconds, buffer almost full (81%), pausing triggers.
[15:23:38.494] INFO: Resuming triggers.
[15:23:44.901] INFO: run duration 25 seconds, buffer almost full (81%), pausing triggers.
[15:24:10.883] INFO: Resuming triggers.
[15:24:17.294] INFO: run duration 31 seconds, buffer almost full (81%), pausing triggers.
[15:24:47.235] INFO: Resuming triggers.
[15:24:53.646] INFO: run duration 38 seconds, buffer almost full (81%), pausing triggers.
[15:25:23.653] INFO: Resuming triggers.
[15:25:30.063] INFO: run duration 44 seconds, buffer almost full (81%), pausing triggers.
[15:26:00.157] INFO: Resuming triggers.
[15:26:06.568] INFO: run duration 51 seconds, buffer almost full (81%), pausing triggers.
[15:26:36.700] INFO: Resuming triggers.
[15:26:43.112] INFO: run duration 57 seconds, buffer almost full (81%), pausing triggers.
[15:27:13.236] INFO: Resuming triggers.
[15:27:19.649] INFO: run duration 63 seconds, buffer almost full (81%), pausing triggers.
[15:27:49.699] INFO: Resuming triggers.
[15:27:56.112] INFO: run duration 70 seconds, buffer almost full (81%), pausing triggers.
[15:28:26.286] INFO: Resuming triggers.
[15:28:32.700] INFO: run duration 76 seconds, buffer almost full (81%), pausing triggers.
[15:29:02.859] INFO: Resuming triggers.
[15:29:09.270] INFO: run duration 83 seconds, buffer almost full (81%), pausing triggers.
[15:29:39.316] INFO: Resuming triggers.
[15:29:45.729] INFO: run duration 89 seconds, buffer almost full (81%), pausing triggers.
[15:30:15.770] INFO: Resuming triggers.
[15:30:22.183] INFO: run duration 95 seconds, buffer almost full (81%), pausing triggers.
[15:30:52.242] INFO: Resuming triggers.
[15:30:56.393] INFO: data taking finished, elapsed time: 100 seconds.
[15:31:16.151] INFO: PixTest:: pg_setup set to default.
[15:31:16.154] INFO: PixTestXray::doPhRun() done
[15:31:16.302] INFO: enter test to run
[15:31:41.919] INFO: test: HighRate no parameter change
[15:31:41.919] INFO: running: highrate
[15:31:41.920] INFO: ----------------------------------------------------------------------
[15:31:41.920] INFO: PixTestHighRate::calDelScan() ntrig = 10, vcal = 200
[15:31:41.920] INFO: ----------------------------------------------------------------------
[15:31:42.061] INFO: Expecting 768 events.
[15:31:43.195] INFO: 768 events read in total (419ms).
[15:31:43.195] INFO: Test took 1268ms.
[15:31:43.999] INFO: Expecting 41600 events.
[15:31:47.062] INFO: 41600 events read in total (2536ms).
[15:31:47.063] INFO: Test took 3862ms.
[15:31:47.097] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:31:47.822] INFO: Expecting 41600 events.
[15:31:51.026] INFO: 41600 events read in total (2677ms).
[15:31:51.027] INFO: Test took 3912ms.
[15:31:51.062] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:31:51.785] INFO: Expecting 41600 events.
[15:31:55.013] INFO: 41600 events read in total (2701ms).
[15:31:55.014] INFO: Test took 3934ms.
[15:31:55.049] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:31:55.774] INFO: Expecting 41600 events.
[15:31:59.019] INFO: 41600 events read in total (2718ms).
[15:31:59.020] INFO: Test took 3952ms.
[15:31:59.055] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:31:59.776] INFO: Expecting 41600 events.
[15:32:03.027] INFO: 41600 events read in total (2724ms).
[15:32:03.028] INFO: Test took 3955ms.
[15:32:03.062] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:32:03.783] INFO: Expecting 41600 events.
[15:32:07.040] INFO: 41600 events read in total (2730ms).
[15:32:07.041] INFO: Test took 3961ms.
[15:32:07.076] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:32:07.799] INFO: Expecting 41600 events.
[15:32:11.058] INFO: 41600 events read in total (2732ms).
[15:32:11.059] INFO: Test took 3963ms.
[15:32:11.094] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:32:11.820] INFO: Expecting 41600 events.
[15:32:15.066] INFO: 41600 events read in total (2719ms).
[15:32:15.067] INFO: Test took 3954ms.
[15:32:15.102] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:32:15.823] INFO: Expecting 41600 events.
[15:32:19.071] INFO: 41600 events read in total (2722ms).
[15:32:19.072] INFO: Test took 3952ms.
[15:32:19.107] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:32:19.829] INFO: Expecting 41600 events.
[15:32:23.079] INFO: 41600 events read in total (2723ms).
[15:32:23.080] INFO: Test took 3956ms.
[15:32:23.116] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:32:23.828] INFO: Expecting 41600 events.
[15:32:27.066] INFO: 41600 events read in total (2711ms).
[15:32:27.067] INFO: Test took 3932ms.
[15:32:27.101] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:32:27.826] INFO: Expecting 41600 events.
[15:32:31.083] INFO: 41600 events read in total (2730ms).
[15:32:31.084] INFO: Test took 3964ms.
[15:32:31.119] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:32:31.841] INFO: Expecting 41600 events.
[15:32:35.083] INFO: 41600 events read in total (2715ms).
[15:32:35.084] INFO: Test took 3947ms.
[15:32:35.119] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:32:35.845] INFO: Expecting 41600 events.
[15:32:39.090] INFO: 41600 events read in total (2718ms).
[15:32:39.091] INFO: Test took 3955ms.
[15:32:39.126] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:32:39.850] INFO: Expecting 41600 events.
[15:32:43.106] INFO: 41600 events read in total (2729ms).
[15:32:43.107] INFO: Test took 3962ms.
[15:32:43.141] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:32:43.862] INFO: Expecting 41600 events.
[15:32:47.118] INFO: 41600 events read in total (2729ms).
[15:32:47.119] INFO: Test took 3960ms.
[15:32:47.153] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:32:47.878] INFO: Expecting 41600 events.
[15:32:51.124] INFO: 41600 events read in total (2719ms).
[15:32:51.125] INFO: Test took 3955ms.
[15:32:51.160] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:32:51.884] INFO: Expecting 41600 events.
[15:32:55.132] INFO: 41600 events read in total (2722ms).
[15:32:55.133] INFO: Test took 3956ms.
[15:32:55.167] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:32:55.889] INFO: Expecting 41600 events.
[15:32:59.140] INFO: 41600 events read in total (2724ms).
[15:32:59.141] INFO: Test took 3954ms.
[15:32:59.176] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:32:59.900] INFO: Expecting 41600 events.
[15:33:03.051] INFO: 41600 events read in total (2625ms).
[15:33:03.052] INFO: Test took 3857ms.
[15:33:03.086] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:33:03.448] INFO: enter test to run
[15:33:25.902] INFO: test: HighRate no parameter change
[15:33:25.902] INFO: running: highrate
[15:33:25.903] INFO: ----------------------------------------------------------------------
[15:33:25.903] INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[15:33:25.903] INFO: ----------------------------------------------------------------------
[15:33:26.518] INFO: Expecting 208000 events.
[15:33:38.469] INFO: 208000 events read in total (11424ms).
[15:33:38.473] INFO: Test took 12560ms.
[15:33:38.623] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:33:38.876] INFO: number of dead pixels (per ROC): 0 0 0 0 0 2 0 0 2 0 0 0 0 0 0 0
[15:33:38.876] INFO: number of red-efficiency pixels: 59 47 77 153 158 177 148 126 113 110 103 92 104 71 27 32
[15:33:38.876] INFO: number of X-ray hits detected: 63471 42998 70894 106485 118243 117450 120263 84223 82993 99964 97393 82927 90450 51636 21152 27215
[15:33:38.876] 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:33:38.876] INFO: number of Vcal hits detected: 207939 207952 207920 207844 207841 207715 207849 207871 207785 207889 207895 207904 207894 207927 207973 207968
[15:33:38.876] INFO: Vcal hit fiducial efficiency (%): 100.0 100.0 100.0 99.9 99.9 99.9 99.9 99.9 99.9 99.9 100.0 100.0 100.0 100.0 100.0 100.0
[15:33:38.876] INFO: Vcal hit overall efficiency (%): 100.0 100.0 100.0 99.9 99.9 99.9 99.9 99.9 99.9 99.9 99.9 100.0 99.9 100.0 100.0 100.0
[15:33:38.876] INFO: X-ray hit rate [MHz/cm2]: 18.6 12.6 20.8 31.2 34.7 34.4 35.2 24.7 24.3 29.3 28.5 24.3 26.5 15.1 6.2 8.0
[15:33:38.876] INFO: PixTestHighRate::doXPixelAlive() done
[15:33:38.920] INFO: PixTest:: pg_setup set to default.
[15:33:38.935] INFO: enter test to run
[15:33:57.925] INFO: test: HighRate no parameter change
[15:33:57.926] INFO: running: highrate
[15:33:57.926] INFO: ----------------------------------------------------------------------
[15:33:57.926] INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[15:33:57.926] INFO: ----------------------------------------------------------------------
[15:33:58.537] INFO: Expecting 208000 events.
[15:34:11.599] INFO: 208000 events read in total (12535ms).
[15:34:11.605] INFO: Test took 13671ms.
[15:34:11.917] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:34:12.223] INFO: number of dead pixels (per ROC): 0 0 0 0 0 2 0 0 2 0 0 0 0 0 0 0
[15:34:12.223] INFO: number of red-efficiency pixels: 232 132 267 539 520 619 512 379 322 338 340 360 392 173 70 87
[15:34:12.223] INFO: number of X-ray hits detected: 137603 93171 153073 231655 253862 252562 260227 182782 182001 214580 209818 179769 194626 111570 46399 59204
[15:34:12.224] 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:34:12.224] INFO: number of Vcal hits detected: 207752 207864 207708 207405 207429 207184 207435 207589 207547 207645 207638 207614 207572 207805 207930 207912
[15:34:12.224] INFO: Vcal hit fiducial efficiency (%): 99.9 99.9 99.9 99.7 99.7 99.7 99.8 99.8 99.8 99.8 99.8 99.8 99.8 99.9 100.0 100.0
[15:34:12.224] INFO: Vcal hit overall efficiency (%): 99.9 99.9 99.9 99.7 99.7 99.6 99.7 99.8 99.8 99.8 99.8 99.8 99.8 99.9 100.0 100.0
[15:34:12.224] INFO: X-ray hit rate [MHz/cm2]: 40.3 27.3 44.9 67.9 74.4 74.0 76.3 53.6 53.3 62.9 61.5 52.7 57.0 32.7 13.6 17.4
[15:34:12.224] INFO: PixTestHighRate::doXPixelAlive() done
[15:34:12.273] INFO: PixTest:: pg_setup set to default.
[15:34:12.287] INFO: enter test to run
[15:34:34.358] INFO: test: HighRate no parameter change
[15:34:34.358] INFO: running: highrate
[15:34:34.359] INFO: ----------------------------------------------------------------------
[15:34:34.359] INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[15:34:34.359] INFO: ----------------------------------------------------------------------
[15:34:34.977] INFO: Expecting 208000 events.
[15:34:50.901] INFO: 208000 events read in total (15397ms).
[15:34:50.909] INFO: Test took 16541ms.
[15:34:51.406] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:34:51.766] INFO: number of dead pixels (per ROC): 0 0 0 0 0 2 0 0 2 0 0 0 0 0 0 0
[15:34:51.766] INFO: number of red-efficiency pixels: 525 281 603 1234 1073 1495 1150 925 718 782 817 744 863 465 100 121
[15:34:51.766] INFO: number of X-ray hits detected: 207411 141396 232784 351623 384989 383328 395623 277195 274728 324798 319567 272463 295967 169510 70202 90118
[15:34:51.766] 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:34:51.767] INFO: number of Vcal hits detected: 207366 207692 207222 206414 206646 205816 206494 206792 207023 207094 207064 207092 206972 207430 207896 207874
[15:34:51.767] INFO: Vcal hit fiducial efficiency (%): 99.7 99.9 99.7 99.3 99.4 99.1 99.4 99.5 99.6 99.6 99.6 99.6 99.5 99.7 100.0 99.9
[15:34:51.767] INFO: Vcal hit overall efficiency (%): 99.7 99.9 99.6 99.2 99.3 99.0 99.3 99.4 99.5 99.6 99.6 99.6 99.5 99.7 100.0 99.9
[15:34:51.767] INFO: X-ray hit rate [MHz/cm2]: 60.8 41.4 68.2 103.1 112.8 112.4 116.0 81.2 80.5 95.2 93.7 79.9 86.8 49.7 20.6 26.4
[15:34:51.767] INFO: PixTestHighRate::doXPixelAlive() done
[15:34:51.815] INFO: PixTest:: pg_setup set to default.
[15:34:51.835] INFO: enter test to run
[15:35:04.229] INFO: test: exit no parameter change
[15:35:04.630] QUIET: Connection to board 33 closed.
[15:35:04.632] INFO: pXar: this is the end, my friend