[12:43:40.177] INFO: *** Welcome to pxar ***
[12:43:40.178] INFO: *** Today: 2016/06/30
[12:43:40.405] INFO: *** Version: v1.9.0-814-g7497
[12:43:40.405] INFO: readRocDacs: /home/uicpirepix2/ProductionTestResults/M-P-1-44_FPIXTest-17C-FNAL-160622-1238_2016-06-22_12h38m_1466617096/000_FPIXTest_p17//dacParameters35_C0.dat .. /home/uicpirepix2/ProductionTestResults/M-P-1-44_FPIXTest-17C-FNAL-160622-1238_2016-06-22_12h38m_1466617096/000_FPIXTest_p17//dacParameters35_C15.dat
[12:43:40.434] INFO: readTbmDacs: /home/uicpirepix2/ProductionTestResults/M-P-1-44_FPIXTest-17C-FNAL-160622-1238_2016-06-22_12h38m_1466617096/000_FPIXTest_p17//tbmParameters_C0a.dat .. /home/uicpirepix2/ProductionTestResults/M-P-1-44_FPIXTest-17C-FNAL-160622-1238_2016-06-22_12h38m_1466617096/000_FPIXTest_p17//tbmParameters_C0b.dat
[12:43:40.434] INFO: readMaskFile: /home/uicpirepix2/ProductionTestResults/M-P-1-44_FPIXTest-17C-FNAL-160622-1238_2016-06-22_12h38m_1466617096/000_FPIXTest_p17//defaultMaskFile.dat
[12:43:40.434] INFO: readTrimFile: /home/uicpirepix2/ProductionTestResults/M-P-1-44_FPIXTest-17C-FNAL-160622-1238_2016-06-22_12h38m_1466617096/000_FPIXTest_p17//trimParameters35_C0.dat .. /home/uicpirepix2/ProductionTestResults/M-P-1-44_FPIXTest-17C-FNAL-160622-1238_2016-06-22_12h38m_1466617096/000_FPIXTest_p17//trimParameters35_C15.dat
[12:43:40.538] INFO: clk: 4
[12:43:40.538] INFO: ctr: 4
[12:43:40.538] INFO: sda: 19
[12:43:40.538] INFO: tin: 9
[12:43:40.538] INFO: level: 15
[12:43:40.538] INFO: triggerdelay: 0
[12:43:40.538] QUIET: Instanciating API for pxar v1.9.0+814~g74979a6
[12:43:40.538] INFO: Log level: INFO
[12:43:40.555] QUIET: Connection to board DTB_WREKRL opened.
[12:43:40.559] INFO: DTB startup information
--- DTB info------------------------------------------
Board id: 33
HW version: DTB1.2
FW version: 4.2
SW version: 4.5
USB id: DTB_WREKRL
MAC address: 40D855118021
Hostname: pixelDTB033
Comment:
------------------------------------------------------
[12:43:40.561] INFO: RPC call hashes of host and DTB match: 398089610
[12:43:42.091] INFO: DUT info:
[12:43:42.092] INFO: The DUT currently contains the following objects:
[12:43:42.092] INFO: 2 TBM Cores tbm08c (2 ON)
[12:43:42.092] INFO: TBM Core alpha (0): 7 registers set
[12:43:42.092] INFO: TBM Core beta (1): 7 registers set
[12:43:42.092] INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[12:43:42.092] INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[12:43:42.092] INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[12:43:42.092] INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[12:43:42.092] INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[12:43:42.092] INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[12:43:42.092] INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[12:43:42.092] INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[12:43:42.092] INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[12:43:42.092] INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[12:43:42.092] INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[12:43:42.092] INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[12:43:42.092] INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[12:43:42.092] INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[12:43:42.092] INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[12:43:42.092] INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[12:43:42.092] INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[12:43:42.495] INFO: enter 'restricted' command line mode
[12:43:42.495] INFO: enter test to run
[12:43:53.669] INFO: test: PixelAlive no parameter change
[12:43:53.670] INFO: running: pixelalive
[12:43:53.678] INFO: ----------------------------------------------------------------------
[12:43:53.679] INFO: PixTestAlive::aliveTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[12:43:53.679] INFO: ----------------------------------------------------------------------
[12:43:53.996] INFO: Expecting 41600 events.
[12:43:58.300] INFO: 41600 events read in total (3585ms).
[12:43:58.468] INFO: Test took 4787ms.
[12:43:58.481] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:43:58.753] INFO: PixTestAlive::aliveTest() done
[12:43:58.753] INFO: number of dead pixels (per ROC): 0 0 0 0 0 0 0 0 0 2 0 0 0 0 0 1
[12:43:58.785] INFO: enter test to run
[12:44:48.037] INFO: test: HighRate setting parameters: ->runsecondshotpixels=5;trimhotpixelthr=200<-
[12:44:48.037] INFO: running: highrate
[12:44:48.037] INFO: readGainPedestalParameters /home/uicpirepix2/ProductionTestResults/M-P-1-44_FPIXTest-17C-FNAL-160622-1238_2016-06-22_12h38m_1466617096/000_FPIXTest_p17//phCalibrationFitErr35_C0.dat .. /home/uicpirepix2/ProductionTestResults/M-P-1-44_FPIXTest-17C-FNAL-160622-1238_2016-06-22_12h38m_1466617096/000_FPIXTest_p17//phCalibrationFitErr35_C15.dat
[12:44:48.269] INFO: ----------------------------------------------------------------------
[12:44:48.269] INFO: PixTest::trimHotPixels() running for 5 seconds with 100 kHz trigger rate
[12:44:48.269] INFO: ----------------------------------------------------------------------
[12:44:48.269] INFO: THR = 200, corresponding to ~ 106.667 MHz/cm2
[12:44:48.269] INFO: edge/corner pixel THR is adjusted
[12:44:48.269] INFO: PixTestHighRate::trimHotPixels: step 0...
[12:44:49.228] INFO: Collecting data for 5 seconds...
[12:44:54.247] INFO: Done with hot pixel readout
[12:45:05.439] INFO: PixTest:: pg_setup set to default.
[12:45:05.439] INFO: 13 hot pixels found in step 0
[12:45:06.425] INFO: Collecting data for 5 seconds...
[12:45:11.443] INFO: Done with hot pixel readout
[12:45:22.686] INFO: PixTest:: pg_setup set to default.
[12:45:22.687] INFO: 14 hot pixels found in step 1
[12:45:23.678] INFO: Collecting data for 5 seconds...
[12:45:28.696] INFO: Done with hot pixel readout
[12:45:39.579] INFO: PixTest:: pg_setup set to default.
[12:45:39.580] INFO: 12 hot pixels found in step 2
[12:45:40.568] INFO: Collecting data for 5 seconds...
[12:45:45.586] INFO: Done with hot pixel readout
[12:45:56.463] INFO: PixTest:: pg_setup set to default.
[12:45:56.464] INFO: 14 hot pixels found in step 3
[12:45:57.452] INFO: Collecting data for 5 seconds...
[12:46:02.470] INFO: Done with hot pixel readout
[12:46:13.664] INFO: PixTest:: pg_setup set to default.
[12:46:13.665] INFO: 10 hot pixels found in step 4
[12:46:14.653] INFO: Collecting data for 5 seconds...
[12:46:19.672] INFO: Done with hot pixel readout
[12:46:30.220] INFO: PixTest:: pg_setup set to default.
[12:46:30.221] INFO: 14 hot pixels found in step 5
[12:46:31.210] INFO: Collecting data for 5 seconds...
[12:46:36.225] INFO: Done with hot pixel readout
[12:46:47.377] INFO: PixTest:: pg_setup set to default.
[12:46:47.378] INFO: 11 hot pixels found in step 6
[12:46:48.367] INFO: Collecting data for 5 seconds...
[12:46:53.386] INFO: Done with hot pixel readout
[12:47:04.816] INFO: PixTest:: pg_setup set to default.
[12:47:04.817] INFO: 2 hot pixels found in step 7
[12:47:05.803] INFO: Collecting data for 5 seconds...
[12:47:10.823] INFO: Done with hot pixel readout
[12:47:22.436] INFO: PixTest:: pg_setup set to default.
[12:47:22.437] INFO: 4 hot pixels found in step 8
[12:47:23.424] INFO: Collecting data for 5 seconds...
[12:47:28.442] INFO: Done with hot pixel readout
[12:47:39.957] INFO: PixTest:: pg_setup set to default.
[12:47:39.958] INFO: 2 hot pixels found in step 9
[12:47:40.947] INFO: Collecting data for 5 seconds...
[12:47:45.964] INFO: Done with hot pixel readout
[12:47:51.375] WARNING: Channel 0 ROC 5: Readback start marker after 6 readouts!
[12:47:51.466] ERROR: <datapipe.cc/CheckEventValidity:L480> Channel 0 has NoTokenPass but 6 ROCs were found
[12:47:51.466] ERROR: <datapipe.cc/CheckEventValidity:L491> Channel 1 Number of ROCs (6) != Token Chain Length (8)
[12:47:51.466] ERROR: <datapipe.cc/Read:L164> Dumping the flawed event +- 3 events:
[12:47:51.466] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a0a8 80b1 43c8 43c8 43c8 43c8 43c8 43c8 43c8 15d 222f 692 202c 43c8 24c 2845 e000 c000
[12:47:51.466] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a0a2 8000 4388 4388 4389 651 2849 4389 155 224c 310 244d 4388 4388 4389 4389 e000 c000
[12:47:51.466] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a0a3 8040 4388 4389 4388 4389 61c 2465 4388 4389 4388 4389 e000 c000
[12:47:51.466] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a0a4 80b1 4388 4388 4388 4388 a 2243 4388 8d 224f 413 2469 510 2049 4388 810 2044 4388 10 2045 15a 2845 4388 e000 c000
[12:47:51.466] ERROR: <datapipe.cc/Read:L166> ====== 2 ====== a0a5 80c0 4388 4388 53c8 43c8 43c8 43ca da9 256a ffe 2000 ff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff
[12:47:51.466] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a0a6 8000 43c8 43c8 43c8 43c8 1d 2441 43c8 69d 2465 43c8 40 2a29 43c8 43c8 e000 c000
[12:47:51.466] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a0a7 8040 43c8 43c8 43c8 43c8 43c8 43c8 43c8 43c8 e000 c000
[12:47:51.466] ERROR: <datapipe.cc/Read:L164> Dumping the flawed event +- 3 events:
[12:47:51.466] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a1a8 80b1 43c8 802 2045 43c8 43c8 43c8 313 244d 6d4 224d 43c8 43c8 35a 2840 43c8 43c8 e000 c000
[12:47:51.466] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a1a2 8000 4388 4388 4389 4389 4388 4388 4389 4389 e000 c000
[12:47:51.466] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a1a3 8040 4388 758 282d 4389 4388 4389 4388 4389 4388 4389 e000 c000
[12:47:51.466] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a1a4 80b1 4388 4388 855 222d 4388 850 2641 4388 4388 4388 254 2043 4388 4388 e000 c000
[12:47:51.466] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a1a5 80c0 4388 4ca 344f 43c8 608 262f 3 2c04 70 2a92 2a91 27 43c8 43c8 43c8 43c8 e000 c000
[12:47:51.466] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a1a6 8000 43c8 119 242f 43c8 d4 2a49 43c8 2d8 2a25 35a 2465 443 2668 43c8 43c8 2c2 2067 422 2065 43c8 461 2061 43c8 43c8 e000 c000
[12:47:51.466] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a1a7 8040 43c8 43c8 43c8 43c8 43c8 43c8 43c8 43c8 e000 c000
[12:47:57.617] INFO: PixTest:: pg_setup set to default.
[12:47:57.618] INFO: 5 hot pixels found in step 10
[12:47:58.607] INFO: Collecting data for 5 seconds...
[12:48:03.628] INFO: Done with hot pixel readout
[12:48:15.078] INFO: PixTest:: pg_setup set to default.
[12:48:15.078] INFO: 0 hot pixels found in step 11
[12:48:15.112] INFO: 0 hot pixels could not be trimmed and have been masked.
[12:48:15.115] INFO: PixTest::trimHotPixels() done
[12:48:15.116] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-1-44_FPIXTest-17C-FNAL-160622-1238_2016-06-22_12h38m_1466617096/000_FPIXTest_p17//trimParameters35_C0.dat
[12:48:15.121] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-1-44_FPIXTest-17C-FNAL-160622-1238_2016-06-22_12h38m_1466617096/000_FPIXTest_p17//trimParameters35_C1.dat
[12:48:15.128] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-1-44_FPIXTest-17C-FNAL-160622-1238_2016-06-22_12h38m_1466617096/000_FPIXTest_p17//trimParameters35_C2.dat
[12:48:15.135] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-1-44_FPIXTest-17C-FNAL-160622-1238_2016-06-22_12h38m_1466617096/000_FPIXTest_p17//trimParameters35_C3.dat
[12:48:15.140] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-1-44_FPIXTest-17C-FNAL-160622-1238_2016-06-22_12h38m_1466617096/000_FPIXTest_p17//trimParameters35_C4.dat
[12:48:15.146] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-1-44_FPIXTest-17C-FNAL-160622-1238_2016-06-22_12h38m_1466617096/000_FPIXTest_p17//trimParameters35_C5.dat
[12:48:15.151] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-1-44_FPIXTest-17C-FNAL-160622-1238_2016-06-22_12h38m_1466617096/000_FPIXTest_p17//trimParameters35_C6.dat
[12:48:15.157] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-1-44_FPIXTest-17C-FNAL-160622-1238_2016-06-22_12h38m_1466617096/000_FPIXTest_p17//trimParameters35_C7.dat
[12:48:15.163] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-1-44_FPIXTest-17C-FNAL-160622-1238_2016-06-22_12h38m_1466617096/000_FPIXTest_p17//trimParameters35_C8.dat
[12:48:15.168] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-1-44_FPIXTest-17C-FNAL-160622-1238_2016-06-22_12h38m_1466617096/000_FPIXTest_p17//trimParameters35_C9.dat
[12:48:15.174] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-1-44_FPIXTest-17C-FNAL-160622-1238_2016-06-22_12h38m_1466617096/000_FPIXTest_p17//trimParameters35_C10.dat
[12:48:15.179] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-1-44_FPIXTest-17C-FNAL-160622-1238_2016-06-22_12h38m_1466617096/000_FPIXTest_p17//trimParameters35_C11.dat
[12:48:15.185] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-1-44_FPIXTest-17C-FNAL-160622-1238_2016-06-22_12h38m_1466617096/000_FPIXTest_p17//trimParameters35_C12.dat
[12:48:15.190] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-1-44_FPIXTest-17C-FNAL-160622-1238_2016-06-22_12h38m_1466617096/000_FPIXTest_p17//trimParameters35_C13.dat
[12:48:15.196] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-1-44_FPIXTest-17C-FNAL-160622-1238_2016-06-22_12h38m_1466617096/000_FPIXTest_p17//trimParameters35_C14.dat
[12:48:15.201] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-1-44_FPIXTest-17C-FNAL-160622-1238_2016-06-22_12h38m_1466617096/000_FPIXTest_p17//trimParameters35_C15.dat
[12:48:15.207] INFO: write masked pixels into /home/uicpirepix2/ProductionTestResults/M-P-1-44_FPIXTest-17C-FNAL-160622-1238_2016-06-22_12h38m_1466617096/000_FPIXTest_p17//defaultMaskFile.dat
[12:48:15.217] INFO: enter test to run
[12:48:47.187] INFO: test: timing no parameter change
[12:48:47.188] INFO: running: timing
[12:48:47.205] INFO: ######################################################################
[12:48:47.213] INFO: PixTestTiming::doTest()
[12:48:47.213] INFO: ######################################################################
[12:48:47.213] INFO: ----------------------------------------------------------------------
[12:48:47.213] INFO: PixTestTiming::TBMPhaseScan()
[12:48:47.213] INFO: ----------------------------------------------------------------------
[12:58:44.951] INFO: TBM Phase Settings: 236
[12:58:44.951] INFO: 400MHz Phase: 3
[12:58:44.951] INFO: 160MHz Phase: 7
[12:58:44.951] INFO: Functional Phase Area: 4
[12:58:44.962] INFO: Test took 597749 ms.
[12:58:44.962] INFO: PixTestTiming::TBMPhaseScan() done.
[12:58:44.962] INFO: ----------------------------------------------------------------------
[12:58:44.962] INFO: PixTestTiming::ROCDelayScan()
[12:58:44.962] INFO: ----------------------------------------------------------------------
[13:00:49.338] INFO: ROC Delay Settings: 228
[13:00:49.338] INFO: ROC Header-Trailer/Token Delay: 11
[13:00:49.338] INFO: ROC Port 0 Delay: 4
[13:00:49.338] INFO: ROC Port 1 Delay: 4
[13:00:49.338] INFO: Functional ROC Area: 5
[13:00:49.341] INFO: Test took 124379 ms.
[13:00:49.342] INFO: PixTestTiming::ROCDelayScan() done.
[13:00:49.342] INFO: ----------------------------------------------------------------------
[13:00:49.342] INFO: PixTestTiming::TimingTest()
[13:00:49.342] INFO: ----------------------------------------------------------------------
[13:01:05.497] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:01:20.478] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:01:35.444] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:01:50.433] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:02:05.395] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:02:20.373] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:02:35.372] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:02:50.385] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:03:05.383] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:03:20.379] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:03:20.757] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:03:20.771] INFO: ----------------------------------------------------------------------
[13:03:20.771] INFO: The fraction of properly decoded events is 100.00%: 10000000/10000000
[13:03:20.771] INFO: ----------------------------------------------------------------------
[13:03:20.771] INFO: ----------------------------------------------------------------------
[13:03:20.771] INFO: Read back bit status: 1
[13:03:20.771] INFO: ----------------------------------------------------------------------
[13:03:20.771] INFO: ----------------------------------------------------------------------
[13:03:20.771] INFO: Timings are good!
[13:03:20.771] INFO: ----------------------------------------------------------------------
[13:03:20.771] INFO: Test took 151429 ms.
[13:03:20.771] INFO: PixTestTiming::TimingTest() done.
[13:03:20.783] INFO: write tbm parameters into /home/uicpirepix2/ProductionTestResults/M-P-1-44_FPIXTest-17C-FNAL-160622-1238_2016-06-22_12h38m_1466617096/000_FPIXTest_p17//tbmParameters_C0a.dat
[13:03:20.783] INFO: write tbm parameters into /home/uicpirepix2/ProductionTestResults/M-P-1-44_FPIXTest-17C-FNAL-160622-1238_2016-06-22_12h38m_1466617096/000_FPIXTest_p17//tbmParameters_C0b.dat
[13:03:20.784] INFO: PixTestTiming::doTest took 873582 ms.
[13:03:20.784] INFO: PixTestTiming::doTest() done
[13:03:20.784] INFO: Write out TBMPhaseScan_0_V0
[13:03:20.784] INFO: Write out TBMPhaseScan_1_V0
[13:03:20.784] INFO: Write out CombinedTBMPhaseScan_V0
[13:03:20.809] INFO: Write out ROCDelayScan3_V0
[13:03:20.810] INFO: enter test to run
[13:03:49.813] INFO: test: HighRate setting parameters: ->runsecondshotpixels=5;trimhotpixelthr=200<-
[13:03:49.813] INFO: running: highrate
[13:03:49.818] INFO: ----------------------------------------------------------------------
[13:03:49.819] INFO: PixTest::trimHotPixels() running for 5 seconds with 100 kHz trigger rate
[13:03:49.819] INFO: ----------------------------------------------------------------------
[13:03:49.819] INFO: THR = 200, corresponding to ~ 106.667 MHz/cm2
[13:03:49.819] INFO: edge/corner pixel THR is adjusted
[13:03:49.819] INFO: PixTestHighRate::trimHotPixels: step 0...
[13:03:50.777] INFO: Collecting data for 5 seconds...
[13:03:55.795] INFO: Done with hot pixel readout
[13:04:07.023] INFO: PixTest:: pg_setup set to default.
[13:04:07.024] INFO: 2 hot pixels found in step 0
[13:04:08.012] INFO: Collecting data for 5 seconds...
[13:04:13.030] INFO: Done with hot pixel readout
[13:04:24.318] INFO: PixTest:: pg_setup set to default.
[13:04:24.319] INFO: 1 hot pixels found in step 1
[13:04:25.307] INFO: Collecting data for 5 seconds...
[13:04:30.324] INFO: Done with hot pixel readout
[13:04:41.591] INFO: PixTest:: pg_setup set to default.
[13:04:41.592] INFO: 2 hot pixels found in step 2
[13:04:42.581] INFO: Collecting data for 5 seconds...
[13:04:47.597] INFO: Done with hot pixel readout
[13:04:58.891] INFO: PixTest:: pg_setup set to default.
[13:04:58.891] INFO: 2 hot pixels found in step 3
[13:04:59.880] INFO: Collecting data for 5 seconds...
[13:05:04.897] INFO: Done with hot pixel readout
[13:05:16.136] INFO: PixTest:: pg_setup set to default.
[13:05:16.137] INFO: 3 hot pixels found in step 4
[13:05:17.125] INFO: Collecting data for 5 seconds...
[13:05:22.142] INFO: Done with hot pixel readout
[13:05:33.407] INFO: PixTest:: pg_setup set to default.
[13:05:33.408] INFO: 3 hot pixels found in step 5
[13:05:34.396] INFO: Collecting data for 5 seconds...
[13:05:39.413] INFO: Done with hot pixel readout
[13:05:50.780] INFO: PixTest:: pg_setup set to default.
[13:05:50.781] INFO: 1 hot pixels found in step 6
[13:05:51.769] INFO: Collecting data for 5 seconds...
[13:05:56.786] INFO: Done with hot pixel readout
[13:06:08.084] INFO: PixTest:: pg_setup set to default.
[13:06:08.085] INFO: 3 hot pixels found in step 7
[13:06:09.074] INFO: Collecting data for 5 seconds...
[13:06:14.092] INFO: Done with hot pixel readout
[13:06:25.357] INFO: PixTest:: pg_setup set to default.
[13:06:25.358] INFO: 3 hot pixels found in step 8
[13:06:26.347] INFO: Collecting data for 5 seconds...
[13:06:31.364] INFO: Done with hot pixel readout
[13:06:42.215] INFO: PixTest:: pg_setup set to default.
[13:06:42.216] INFO: 0 hot pixels found in step 9
[13:06:42.248] INFO: 0 hot pixels could not be trimmed and have been masked.
[13:06:42.253] INFO: PixTest::trimHotPixels() done
[13:06:42.253] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-1-44_FPIXTest-17C-FNAL-160622-1238_2016-06-22_12h38m_1466617096/000_FPIXTest_p17//trimParameters35_C0.dat
[13:06:42.259] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-1-44_FPIXTest-17C-FNAL-160622-1238_2016-06-22_12h38m_1466617096/000_FPIXTest_p17//trimParameters35_C1.dat
[13:06:42.266] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-1-44_FPIXTest-17C-FNAL-160622-1238_2016-06-22_12h38m_1466617096/000_FPIXTest_p17//trimParameters35_C2.dat
[13:06:42.271] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-1-44_FPIXTest-17C-FNAL-160622-1238_2016-06-22_12h38m_1466617096/000_FPIXTest_p17//trimParameters35_C3.dat
[13:06:42.277] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-1-44_FPIXTest-17C-FNAL-160622-1238_2016-06-22_12h38m_1466617096/000_FPIXTest_p17//trimParameters35_C4.dat
[13:06:42.282] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-1-44_FPIXTest-17C-FNAL-160622-1238_2016-06-22_12h38m_1466617096/000_FPIXTest_p17//trimParameters35_C5.dat
[13:06:42.287] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-1-44_FPIXTest-17C-FNAL-160622-1238_2016-06-22_12h38m_1466617096/000_FPIXTest_p17//trimParameters35_C6.dat
[13:06:42.292] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-1-44_FPIXTest-17C-FNAL-160622-1238_2016-06-22_12h38m_1466617096/000_FPIXTest_p17//trimParameters35_C7.dat
[13:06:42.297] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-1-44_FPIXTest-17C-FNAL-160622-1238_2016-06-22_12h38m_1466617096/000_FPIXTest_p17//trimParameters35_C8.dat
[13:06:42.303] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-1-44_FPIXTest-17C-FNAL-160622-1238_2016-06-22_12h38m_1466617096/000_FPIXTest_p17//trimParameters35_C9.dat
[13:06:42.308] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-1-44_FPIXTest-17C-FNAL-160622-1238_2016-06-22_12h38m_1466617096/000_FPIXTest_p17//trimParameters35_C10.dat
[13:06:42.313] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-1-44_FPIXTest-17C-FNAL-160622-1238_2016-06-22_12h38m_1466617096/000_FPIXTest_p17//trimParameters35_C11.dat
[13:06:42.318] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-1-44_FPIXTest-17C-FNAL-160622-1238_2016-06-22_12h38m_1466617096/000_FPIXTest_p17//trimParameters35_C12.dat
[13:06:42.324] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-1-44_FPIXTest-17C-FNAL-160622-1238_2016-06-22_12h38m_1466617096/000_FPIXTest_p17//trimParameters35_C13.dat
[13:06:42.329] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-1-44_FPIXTest-17C-FNAL-160622-1238_2016-06-22_12h38m_1466617096/000_FPIXTest_p17//trimParameters35_C14.dat
[13:06:42.334] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-1-44_FPIXTest-17C-FNAL-160622-1238_2016-06-22_12h38m_1466617096/000_FPIXTest_p17//trimParameters35_C15.dat
[13:06:42.339] INFO: write masked pixels into /home/uicpirepix2/ProductionTestResults/M-P-1-44_FPIXTest-17C-FNAL-160622-1238_2016-06-22_12h38m_1466617096/000_FPIXTest_p17//defaultMaskFile.dat
[13:06:42.349] INFO: enter test to run
[13:07:12.932] INFO: test: HighRate setting parameters: ->runsecondshotpixels=1;trimhotpixelthr=200<-
[13:07:12.932] INFO: running: highrate
[13:07:12.938] INFO: ----------------------------------------------------------------------
[13:07:12.938] INFO: PixTest::trimHotPixels() running for 1 seconds with 100 kHz trigger rate
[13:07:12.938] INFO: ----------------------------------------------------------------------
[13:07:12.938] INFO: THR = 200, corresponding to ~ 533.333 MHz/cm2
[13:07:12.938] INFO: edge/corner pixel THR is adjusted
[13:07:12.938] INFO: PixTestHighRate::trimHotPixels: step 0...
[13:07:13.900] INFO: Collecting data for 1 seconds...
[13:07:14.904] INFO: Done with hot pixel readout
[13:07:18.565] INFO: PixTest:: pg_setup set to default.
[13:07:18.566] INFO: 0 hot pixels found in step 0
[13:07:18.572] INFO: 0 hot pixels could not be trimmed and have been masked.
[13:07:18.672] INFO: PixTest::trimHotPixels() done
[13:07:18.672] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-1-44_FPIXTest-17C-FNAL-160622-1238_2016-06-22_12h38m_1466617096/000_FPIXTest_p17//trimParameters35_C0.dat
[13:07:18.681] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-1-44_FPIXTest-17C-FNAL-160622-1238_2016-06-22_12h38m_1466617096/000_FPIXTest_p17//trimParameters35_C1.dat
[13:07:18.689] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-1-44_FPIXTest-17C-FNAL-160622-1238_2016-06-22_12h38m_1466617096/000_FPIXTest_p17//trimParameters35_C2.dat
[13:07:18.694] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-1-44_FPIXTest-17C-FNAL-160622-1238_2016-06-22_12h38m_1466617096/000_FPIXTest_p17//trimParameters35_C3.dat
[13:07:18.699] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-1-44_FPIXTest-17C-FNAL-160622-1238_2016-06-22_12h38m_1466617096/000_FPIXTest_p17//trimParameters35_C4.dat
[13:07:18.704] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-1-44_FPIXTest-17C-FNAL-160622-1238_2016-06-22_12h38m_1466617096/000_FPIXTest_p17//trimParameters35_C5.dat
[13:07:18.710] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-1-44_FPIXTest-17C-FNAL-160622-1238_2016-06-22_12h38m_1466617096/000_FPIXTest_p17//trimParameters35_C6.dat
[13:07:18.715] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-1-44_FPIXTest-17C-FNAL-160622-1238_2016-06-22_12h38m_1466617096/000_FPIXTest_p17//trimParameters35_C7.dat
[13:07:18.720] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-1-44_FPIXTest-17C-FNAL-160622-1238_2016-06-22_12h38m_1466617096/000_FPIXTest_p17//trimParameters35_C8.dat
[13:07:18.725] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-1-44_FPIXTest-17C-FNAL-160622-1238_2016-06-22_12h38m_1466617096/000_FPIXTest_p17//trimParameters35_C9.dat
[13:07:18.730] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-1-44_FPIXTest-17C-FNAL-160622-1238_2016-06-22_12h38m_1466617096/000_FPIXTest_p17//trimParameters35_C10.dat
[13:07:18.735] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-1-44_FPIXTest-17C-FNAL-160622-1238_2016-06-22_12h38m_1466617096/000_FPIXTest_p17//trimParameters35_C11.dat
[13:07:18.740] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-1-44_FPIXTest-17C-FNAL-160622-1238_2016-06-22_12h38m_1466617096/000_FPIXTest_p17//trimParameters35_C12.dat
[13:07:18.746] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-1-44_FPIXTest-17C-FNAL-160622-1238_2016-06-22_12h38m_1466617096/000_FPIXTest_p17//trimParameters35_C13.dat
[13:07:18.751] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-1-44_FPIXTest-17C-FNAL-160622-1238_2016-06-22_12h38m_1466617096/000_FPIXTest_p17//trimParameters35_C14.dat
[13:07:18.756] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-1-44_FPIXTest-17C-FNAL-160622-1238_2016-06-22_12h38m_1466617096/000_FPIXTest_p17//trimParameters35_C15.dat
[13:07:18.761] INFO: write masked pixels into /home/uicpirepix2/ProductionTestResults/M-P-1-44_FPIXTest-17C-FNAL-160622-1238_2016-06-22_12h38m_1466617096/000_FPIXTest_p17//defaultMaskFile.dat
[13:07:18.771] INFO: enter test to run
[13:07:48.772] INFO: test: Xray setting parameters: ->source=DCLowRate<-
[13:07:48.772] INFO: running: xray
[13:07:48.773] INFO: ----------------------------------------------------------------------
[13:07:48.773] INFO: PixTestXray::doPhRun() fParRunSeconds = 100
[13:07:48.773] INFO: ----------------------------------------------------------------------
[13:07:49.738] INFO: PixTestXray::doPhRun start TriggerLoop with trigger frequency 100 kHz, period 405 and duration 100 seconds, fEventsMax = 10000000
[13:08:01.754] INFO: run duration 11 seconds, buffer almost full (81%), pausing triggers.
[13:08:30.981] INFO: Resuming triggers.
[13:08:43.002] INFO: run duration 23 seconds, buffer almost full (81%), pausing triggers.
[13:09:12.690] INFO: Resuming triggers.
[13:09:24.710] INFO: run duration 35 seconds, buffer almost full (81%), pausing triggers.
[13:09:54.132] INFO: Resuming triggers.
[13:10:06.157] INFO: run duration 47 seconds, buffer almost full (81%), pausing triggers.
[13:10:35.128] INFO: Resuming triggers.
[13:10:47.150] INFO: run duration 59 seconds, buffer almost full (81%), pausing triggers.
[13:11:17.384] INFO: Resuming triggers.
[13:11:29.405] INFO: run duration 71 seconds, buffer almost full (81%), pausing triggers.
[13:11:59.399] INFO: Resuming triggers.
[13:12:11.420] INFO: run duration 83 seconds, buffer almost full (81%), pausing triggers.
[13:12:40.862] INFO: Resuming triggers.
[13:12:52.881] INFO: run duration 95 seconds, buffer almost full (81%), pausing triggers.
[13:13:22.092] INFO: Resuming triggers.
[13:13:26.284] INFO: data taking finished, elapsed time: 100 seconds.
[13:13:36.766] INFO: PixTest:: pg_setup set to default.
[13:13:36.769] INFO: PixTestXray::doPhRun() done
[13:13:36.905] INFO: enter test to run
[13:14:17.569] INFO: test: Xray setting parameters: ->source=DCHighRate<-
[13:14:17.569] INFO: running: xray
[13:14:17.570] INFO: ----------------------------------------------------------------------
[13:14:17.570] INFO: PixTestXray::doPhRun() fParRunSeconds = 100
[13:14:17.570] INFO: ----------------------------------------------------------------------
[13:14:18.534] INFO: PixTestXray::doPhRun start TriggerLoop with trigger frequency 100 kHz, period 405 and duration 100 seconds, fEventsMax = 10000000
[13:14:25.944] INFO: run duration 7 seconds, buffer almost full (81%), pausing triggers.
[13:14:56.047] INFO: Resuming triggers.
[13:15:03.455] INFO: run duration 14 seconds, buffer almost full (81%), pausing triggers.
[13:15:33.586] INFO: Resuming triggers.
[13:15:40.994] INFO: run duration 22 seconds, buffer almost full (81%), pausing triggers.
[13:16:11.077] INFO: Resuming triggers.
[13:16:18.483] INFO: run duration 29 seconds, buffer almost full (81%), pausing triggers.
[13:16:48.537] INFO: Resuming triggers.
[13:16:55.946] INFO: run duration 36 seconds, buffer almost full (81%), pausing triggers.
[13:17:25.994] INFO: Resuming triggers.
[13:17:33.402] INFO: run duration 44 seconds, buffer almost full (81%), pausing triggers.
[13:18:03.338] INFO: Resuming triggers.
[13:18:10.742] INFO: run duration 51 seconds, buffer almost full (81%), pausing triggers.
[13:18:40.985] INFO: Resuming triggers.
[13:18:48.393] INFO: run duration 59 seconds, buffer almost full (81%), pausing triggers.
[13:19:18.494] INFO: Resuming triggers.
[13:19:25.900] INFO: run duration 66 seconds, buffer almost full (81%), pausing triggers.
[13:19:55.981] INFO: Resuming triggers.
[13:20:03.386] INFO: run duration 73 seconds, buffer almost full (81%), pausing triggers.
[13:20:34.175] INFO: Resuming triggers.
[13:20:41.585] INFO: run duration 81 seconds, buffer almost full (81%), pausing triggers.
[13:21:12.415] INFO: Resuming triggers.
[13:21:19.822] INFO: run duration 88 seconds, buffer almost full (81%), pausing triggers.
[13:21:50.803] INFO: Resuming triggers.
[13:21:58.215] INFO: run duration 95 seconds, buffer almost full (81%), pausing triggers.
[13:22:29.094] INFO: Resuming triggers.
[13:22:33.131] INFO: data taking finished, elapsed time: 100 seconds.
[13:22:50.588] INFO: PixTest:: pg_setup set to default.
[13:22:50.591] INFO: PixTestXray::doPhRun() done
[13:22:50.738] INFO: enter test to run
[13:23:26.253] INFO: test: HighRate no parameter change
[13:23:26.254] INFO: running: highrate
[13:23:26.275] INFO: ----------------------------------------------------------------------
[13:23:26.275] INFO: PixTestHighRate::calDelScan() ntrig = 10, vcal = 200
[13:23:26.275] INFO: ----------------------------------------------------------------------
[13:23:26.430] INFO: Expecting 768 events.
[13:23:27.562] INFO: 768 events read in total (416ms).
[13:23:27.562] INFO: Test took 1266ms.
[13:23:28.365] INFO: Expecting 41600 events.
[13:23:31.377] INFO: 41600 events read in total (2485ms).
[13:23:31.378] INFO: Test took 3809ms.
[13:23:31.407] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:23:32.139] INFO: Expecting 41600 events.
[13:23:35.248] INFO: 41600 events read in total (2582ms).
[13:23:35.249] INFO: Test took 3826ms.
[13:23:35.280] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:23:36.005] INFO: Expecting 41600 events.
[13:23:39.126] INFO: 41600 events read in total (2594ms).
[13:23:39.127] INFO: Test took 3832ms.
[13:23:39.159] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:23:39.880] INFO: Expecting 41600 events.
[13:23:43.090] INFO: 41600 events read in total (2683ms).
[13:23:43.091] INFO: Test took 3915ms.
[13:23:43.122] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:23:43.851] INFO: Expecting 41600 events.
[13:23:47.072] INFO: 41600 events read in total (2694ms).
[13:23:47.073] INFO: Test took 3935ms.
[13:23:47.104] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:23:47.831] INFO: Expecting 41600 events.
[13:23:51.070] INFO: 41600 events read in total (2712ms).
[13:23:51.071] INFO: Test took 3951ms.
[13:23:51.102] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:23:51.832] INFO: Expecting 41600 events.
[13:23:55.051] INFO: 41600 events read in total (2692ms).
[13:23:55.052] INFO: Test took 3934ms.
[13:23:55.083] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:23:55.809] INFO: Expecting 41600 events.
[13:23:59.016] INFO: 41600 events read in total (2680ms).
[13:23:59.017] INFO: Test took 3916ms.
[13:23:59.047] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:23:59.777] INFO: Expecting 41600 events.
[13:24:02.994] INFO: 41600 events read in total (2690ms).
[13:24:02.995] INFO: Test took 3930ms.
[13:24:03.026] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:24:03.751] INFO: Expecting 41600 events.
[13:24:06.935] INFO: 41600 events read in total (2657ms).
[13:24:06.936] INFO: Test took 3892ms.
[13:24:06.966] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:24:07.699] INFO: Expecting 41600 events.
[13:24:10.907] INFO: 41600 events read in total (2681ms).
[13:24:10.908] INFO: Test took 3926ms.
[13:24:10.938] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:24:11.671] INFO: Expecting 41600 events.
[13:24:14.875] INFO: 41600 events read in total (2677ms).
[13:24:14.876] INFO: Test took 3922ms.
[13:24:14.906] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:24:15.635] INFO: Expecting 41600 events.
[13:24:18.813] INFO: 41600 events read in total (2651ms).
[13:24:18.813] INFO: Test took 3891ms.
[13:24:18.843] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:24:19.576] INFO: Expecting 41600 events.
[13:24:22.774] INFO: 41600 events read in total (2671ms).
[13:24:22.775] INFO: Test took 3915ms.
[13:24:22.804] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:24:23.537] INFO: Expecting 41600 events.
[13:24:26.743] INFO: 41600 events read in total (2679ms).
[13:24:26.744] INFO: Test took 3924ms.
[13:24:26.775] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:24:27.504] INFO: Expecting 41600 events.
[13:24:30.719] INFO: 41600 events read in total (2688ms).
[13:24:30.720] INFO: Test took 3928ms.
[13:24:30.750] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:24:31.482] INFO: Expecting 41600 events.
[13:24:34.680] INFO: 41600 events read in total (2671ms).
[13:24:34.681] INFO: Test took 3914ms.
[13:24:34.711] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:24:35.444] INFO: Expecting 41600 events.
[13:24:38.650] INFO: 41600 events read in total (2679ms).
[13:24:38.651] INFO: Test took 3923ms.
[13:24:38.681] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:24:39.414] INFO: Expecting 41600 events.
[13:24:42.630] INFO: 41600 events read in total (2689ms).
[13:24:42.632] INFO: Test took 3934ms.
[13:24:42.665] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:24:43.388] INFO: Expecting 41600 events.
[13:24:46.480] INFO: 41600 events read in total (2565ms).
[13:24:46.481] INFO: Test took 3798ms.
[13:24:46.511] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:24:46.881] INFO: enter test to run
[13:24:52.757] INFO: test: HighRate no parameter change
[13:24:52.757] INFO: running: highrate
[13:24:52.758] INFO: ----------------------------------------------------------------------
[13:24:52.758] INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[13:24:52.758] INFO: ----------------------------------------------------------------------
[13:24:53.371] INFO: Expecting 208000 events.
[13:25:05.008] INFO: 208000 events read in total (11110ms).
[13:25:05.011] INFO: Test took 12246ms.
[13:25:05.134] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:25:05.381] INFO: number of dead pixels (per ROC): 0 0 0 0 0 0 0 0 0 2 0 0 0 0 0 1
[13:25:05.381] INFO: number of red-efficiency pixels: 64 37 73 100 112 120 117 79 68 93 103 83 83 46 33 30
[13:25:05.381] INFO: number of X-ray hits detected: 53702 36015 59038 91313 95692 98227 102740 74627 75129 81650 82890 70893 69973 41185 17377 20080
[13:25:05.381] INFO: number of triggers sent (total per ROC): 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000
[13:25:05.381] INFO: number of Vcal hits detected: 207936 207962 207925 207899 207887 207878 207882 207921 207932 207805 207897 207916 207915 207954 207967 207921
[13:25:05.381] INFO: Vcal hit fiducial efficiency (%): 100.0 100.0 100.0 100.0 99.9 99.9 99.9 100.0 100.0 100.0 100.0 100.0 100.0 100.0 100.0 100.0
[13:25:05.382] INFO: Vcal hit overall efficiency (%): 100.0 100.0 100.0 100.0 99.9 99.9 99.9 100.0 100.0 99.9 100.0 100.0 100.0 100.0 100.0 100.0
[13:25:05.382] INFO: X-ray hit rate [MHz/cm2]: 15.7 10.6 17.3 26.8 28.0 28.8 30.1 21.9 22.0 23.9 24.3 20.8 20.5 12.1 5.1 5.9
[13:25:05.382] INFO: PixTestHighRate::doXPixelAlive() done
[13:25:05.432] INFO: PixTest:: pg_setup set to default.
[13:25:05.449] INFO: enter test to run
[13:25:30.309] INFO: test: HighRate no parameter change
[13:25:30.309] INFO: running: highrate
[13:25:30.310] INFO: ----------------------------------------------------------------------
[13:25:30.310] INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[13:25:30.310] INFO: ----------------------------------------------------------------------
[13:25:30.928] INFO: Expecting 208000 events.
[13:25:44.023] INFO: 208000 events read in total (12568ms).
[13:25:44.028] INFO: Test took 13708ms.
[13:25:44.273] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:25:44.557] INFO: number of dead pixels (per ROC): 0 0 0 0 0 0 0 0 0 2 0 0 0 0 0 1
[13:25:44.557] INFO: number of red-efficiency pixels: 148 107 200 286 315 424 345 224 246 298 306 248 195 137 53 46
[13:25:44.557] INFO: number of X-ray hits detected: 110481 73154 120085 185268 193429 199322 207520 152822 152733 164909 169033 145197 142659 83852 35516 40990
[13:25:44.557] INFO: number of triggers sent (total per ROC): 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000
[13:25:44.557] INFO: number of Vcal hits detected: 207843 207888 207784 207702 207666 207547 207629 207765 207739 207598 207682 207747 207805 207857 207944 207904
[13:25:44.557] INFO: Vcal hit fiducial efficiency (%): 99.9 99.9 99.9 99.9 99.8 99.8 99.8 99.9 99.9 99.9 99.9 99.9 99.9 99.9 100.0 100.0
[13:25:44.557] INFO: Vcal hit overall efficiency (%): 99.9 99.9 99.9 99.9 99.8 99.8 99.8 99.9 99.9 99.8 99.8 99.9 99.9 99.9 100.0 100.0
[13:25:44.557] INFO: X-ray hit rate [MHz/cm2]: 32.4 21.4 35.2 54.3 56.7 58.4 60.8 44.8 44.8 48.3 49.5 42.6 41.8 24.6 10.4 12.0
[13:25:44.557] INFO: PixTestHighRate::doXPixelAlive() done
[13:25:44.609] INFO: PixTest:: pg_setup set to default.
[13:25:44.627] INFO: enter test to run
[13:26:20.572] INFO: test: HighRate no parameter change
[13:26:20.572] INFO: running: highrate
[13:26:20.574] INFO: ----------------------------------------------------------------------
[13:26:20.574] INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[13:26:20.574] INFO: ----------------------------------------------------------------------
[13:26:21.189] INFO: Expecting 208000 events.
[13:26:35.788] INFO: 208000 events read in total (14072ms).
[13:26:35.795] INFO: Test took 15209ms.
[13:26:36.161] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:26:36.489] INFO: number of dead pixels (per ROC): 0 0 0 0 0 0 0 0 0 2 0 0 0 0 0 1
[13:26:36.489] INFO: number of red-efficiency pixels: 352 190 437 651 756 944 749 512 552 621 685 494 339 266 92 62
[13:26:36.489] INFO: number of X-ray hits detected: 166710 111112 181456 280854 292997 300870 313937 231766 232678 249238 253969 219220 217178 126624 54371 62143
[13:26:36.489] INFO: number of triggers sent (total per ROC): 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000
[13:26:36.489] INFO: number of Vcal hits detected: 207605 207793 207460 207262 207115 206859 207133 207407 207368 207204 207215 207459 207644 207710 207899 207888
[13:26:36.489] INFO: Vcal hit fiducial efficiency (%): 99.8 99.9 99.8 99.7 99.6 99.5 99.6 99.7 99.7 99.7 99.7 99.8 99.8 99.9 100.0 100.0
[13:26:36.489] INFO: Vcal hit overall efficiency (%): 99.8 99.9 99.7 99.6 99.6 99.5 99.6 99.7 99.7 99.6 99.6 99.7 99.8 99.9 100.0 99.9
[13:26:36.489] INFO: X-ray hit rate [MHz/cm2]: 48.9 32.6 53.2 82.3 85.9 88.2 92.0 67.9 68.2 73.1 74.4 64.3 63.7 37.1 15.9 18.2
[13:26:36.489] INFO: PixTestHighRate::doXPixelAlive() done
[13:26:36.539] INFO: PixTest:: pg_setup set to default.
[13:26:36.556] INFO: enter test to run
[13:26:56.204] INFO: test: exit no parameter change
[13:26:56.573] QUIET: Connection to board 33 closed.
[13:26:56.588] INFO: pXar: this is the end, my friend