[10:34:24.818] INFO: *** Welcome to pxar ***
[10:34:24.818] INFO: *** Today: 2016/08/11
[10:34:25.795] INFO: *** Version: v1.9.0-818-g96727
[10:34:25.795] INFO: readRocDacs: /home/uicpirepix2/ProductionTestResults/M-N-3-17_FPIXTest-17C-FNAL-160527-0934_2016-05-27_09h35m_1464359703/000_FPIXTest_p17//dacParameters35_C0.dat .. /home/uicpirepix2/ProductionTestResults/M-N-3-17_FPIXTest-17C-FNAL-160527-0934_2016-05-27_09h35m_1464359703/000_FPIXTest_p17//dacParameters35_C15.dat
[10:34:25.861] INFO: readTbmDacs: /home/uicpirepix2/ProductionTestResults/M-N-3-17_FPIXTest-17C-FNAL-160527-0934_2016-05-27_09h35m_1464359703/000_FPIXTest_p17//tbmParameters_C0a.dat .. /home/uicpirepix2/ProductionTestResults/M-N-3-17_FPIXTest-17C-FNAL-160527-0934_2016-05-27_09h35m_1464359703/000_FPIXTest_p17//tbmParameters_C0b.dat
[10:34:25.861] INFO: readMaskFile: /home/uicpirepix2/ProductionTestResults/M-N-3-17_FPIXTest-17C-FNAL-160527-0934_2016-05-27_09h35m_1464359703/000_FPIXTest_p17//defaultMaskFile.dat
[10:34:25.869] INFO: readTrimFile: /home/uicpirepix2/ProductionTestResults/M-N-3-17_FPIXTest-17C-FNAL-160527-0934_2016-05-27_09h35m_1464359703/000_FPIXTest_p17//trimParameters35_C0.dat .. /home/uicpirepix2/ProductionTestResults/M-N-3-17_FPIXTest-17C-FNAL-160527-0934_2016-05-27_09h35m_1464359703/000_FPIXTest_p17//trimParameters35_C15.dat
[10:34:26.004] INFO: clk: 4
[10:34:26.004] INFO: ctr: 4
[10:34:26.004] INFO: sda: 19
[10:34:26.004] INFO: tin: 9
[10:34:26.004] INFO: level: 15
[10:34:26.004] INFO: triggerdelay: 0
[10:34:26.004] QUIET: Instanciating API for pxar v1.9.0+814~g74979a6
[10:34:26.004] INFO: Log level: INFO
[10:34:26.023] QUIET: Connection to board DTB_WREKRL opened.
[10:34:26.026] 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:
------------------------------------------------------
[10:34:26.029] INFO: RPC call hashes of host and DTB match: 398089610
[10:34:27.569] INFO: DUT info:
[10:34:27.570] INFO: The DUT currently contains the following objects:
[10:34:27.570] INFO: 2 TBM Cores tbm08c (2 ON)
[10:34:27.582] INFO: TBM Core alpha (0): 7 registers set
[10:34:27.582] INFO: TBM Core beta (1): 7 registers set
[10:34:27.582] INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[10:34:27.582] INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[10:34:27.582] INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[10:34:27.582] INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[10:34:27.582] INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[10:34:27.582] INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[10:34:27.582] INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[10:34:27.582] INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[10:34:27.582] INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[10:34:27.582] INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[10:34:27.582] INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[10:34:27.582] INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[10:34:27.582] INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[10:34:27.583] INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[10:34:27.583] INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[10:34:27.583] INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[10:34:27.583] INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[10:34:28.026] INFO: enter 'restricted' command line mode
[10:34:28.026] INFO: enter test to run
[10:34:45.101] INFO: test: PixelAlive no parameter change
[10:34:45.101] INFO: running: pixelalive
[10:34:45.162] INFO: ----------------------------------------------------------------------
[10:34:45.162] INFO: PixTestAlive::aliveTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[10:34:45.162] INFO: ----------------------------------------------------------------------
[10:34:45.483] INFO: Expecting 41600 events.
[10:34:49.849] INFO: 41600 events read in total (3647ms).
[10:34:50.014] INFO: Test took 4848ms.
[10:34:50.022] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:34:50.543] INFO: PixTestAlive::aliveTest() done
[10:34:50.543] INFO: number of dead pixels (per ROC): 0 1 1 1 0 1 0 0 0 0 0 1 4 0 9 0
[10:34:50.691] INFO: enter test to run
[10:35:04.733] INFO: test: timing no parameter change
[10:35:04.733] INFO: running: timing
[10:35:04.737] INFO: ######################################################################
[10:35:04.737] INFO: PixTestTiming::doTest()
[10:35:04.737] INFO: ######################################################################
[10:35:04.737] INFO: ----------------------------------------------------------------------
[10:35:04.737] INFO: PixTestTiming::TBMPhaseScan()
[10:35:04.737] INFO: ----------------------------------------------------------------------
[10:41:26.267] INFO: TBM Phase Settings: 200
[10:41:26.267] INFO: 400MHz Phase: 2
[10:41:26.267] INFO: 160MHz Phase: 6
[10:41:26.267] INFO: Functional Phase Area: 3
[10:41:26.292] INFO: Test took 381555 ms.
[10:41:26.292] INFO: PixTestTiming::TBMPhaseScan() done.
[10:41:26.292] INFO: ----------------------------------------------------------------------
[10:41:26.292] INFO: PixTestTiming::ROCDelayScan()
[10:41:26.292] INFO: ----------------------------------------------------------------------
[10:43:22.719] INFO: ROC Delay Settings: 219
[10:43:22.719] INFO: ROC Header-Trailer/Token Delay: 11
[10:43:22.719] INFO: ROC Port 0 Delay: 3
[10:43:22.719] INFO: ROC Port 1 Delay: 3
[10:43:22.719] INFO: Functional ROC Area: 4
[10:43:22.722] INFO: Test took 116430 ms.
[10:43:22.723] INFO: PixTestTiming::ROCDelayScan() done.
[10:43:22.723] INFO: ----------------------------------------------------------------------
[10:43:22.723] INFO: PixTestTiming::TimingTest()
[10:43:22.723] INFO: ----------------------------------------------------------------------
[10:43:38.900] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:43:53.914] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:44:08.911] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:44:23.877] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:44:38.841] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:44:53.782] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:45:08.709] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:45:23.660] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:45:38.566] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:45:53.561] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:45:53.939] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:45:53.957] INFO: ----------------------------------------------------------------------
[10:45:53.957] INFO: The fraction of properly decoded events is 100.00%: 10000000/10000000
[10:45:53.957] INFO: ----------------------------------------------------------------------
[10:45:53.957] INFO: ----------------------------------------------------------------------
[10:45:53.957] INFO: Read back bit status: 1
[10:45:53.957] INFO: ----------------------------------------------------------------------
[10:45:53.957] INFO: ----------------------------------------------------------------------
[10:45:53.957] INFO: Timings are good!
[10:45:53.957] INFO: ----------------------------------------------------------------------
[10:45:53.957] INFO: Test took 151234 ms.
[10:45:53.957] INFO: PixTestTiming::TimingTest() done.
[10:45:53.957] INFO: write tbm parameters into /home/uicpirepix2/ProductionTestResults/M-N-3-17_FPIXTest-17C-FNAL-160527-0934_2016-05-27_09h35m_1464359703/000_FPIXTest_p17//tbmParameters_C0a.dat
[10:45:53.957] INFO: write tbm parameters into /home/uicpirepix2/ProductionTestResults/M-N-3-17_FPIXTest-17C-FNAL-160527-0934_2016-05-27_09h35m_1464359703/000_FPIXTest_p17//tbmParameters_C0b.dat
[10:45:53.957] INFO: PixTestTiming::doTest took 649224 ms.
[10:45:53.957] INFO: PixTestTiming::doTest() done
[10:45:53.957] INFO: Write out TBMPhaseScan_0_V0
[10:45:53.958] INFO: Write out TBMPhaseScan_1_V0
[10:45:53.958] INFO: Write out CombinedTBMPhaseScan_V0
[10:45:53.959] INFO: Write out ROCDelayScan3_V0
[10:45:53.959] INFO: enter test to run
[10:47:28.680] INFO: test: HighRate setting parameters: ->runsecondshotpixels=5;trimhotpixelthr=200<-
[10:47:28.680] INFO: running: highrate
[10:47:28.697] INFO: readGainPedestalParameters /home/uicpirepix2/ProductionTestResults/M-N-3-17_FPIXTest-17C-FNAL-160527-0934_2016-05-27_09h35m_1464359703/000_FPIXTest_p17//phCalibrationFitErr35_C0.dat .. /home/uicpirepix2/ProductionTestResults/M-N-3-17_FPIXTest-17C-FNAL-160527-0934_2016-05-27_09h35m_1464359703/000_FPIXTest_p17//phCalibrationFitErr35_C15.dat
[10:47:28.959] INFO: ----------------------------------------------------------------------
[10:47:28.959] INFO: PixTest::trimHotPixels() running for 5 seconds with 100 kHz trigger rate
[10:47:28.959] INFO: ----------------------------------------------------------------------
[10:47:28.959] INFO: THR = 200, corresponding to ~ 106.667 MHz/cm2
[10:47:28.959] INFO: edge/corner pixel THR is adjusted
[10:47:28.959] INFO: PixTestHighRate::trimHotPixels: step 0...
[10:47:29.918] INFO: Collecting data for 5 seconds...
[10:47:34.936] INFO: Done with hot pixel readout
[10:47:46.729] INFO: PixTest:: pg_setup set to default.
[10:47:46.730] INFO: 2 hot pixels found in step 0
[10:47:47.725] INFO: Collecting data for 5 seconds...
[10:47:52.742] INFO: Done with hot pixel readout
[10:48:01.847] WARNING: Channel 1 ROC 2: Readback start marker after 32 readouts!
[10:48:01.847] ERROR: <datapipe.cc/Read:L164> Dumping the flawed event +- 3 events:
[10:48:01.847] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a1aa 80c0 4388 4388 291 284f 4e0 264c 4389 418 202f 453 224f 4389 4389 4388 11 2a43 4389 4389 e000 c000
[10:48:01.847] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a1a4 8040 4388 162 284a 4388 74c 2241 4388 690 284c 4388 6d8 222f 4388 4388 4388 4388 e000 c000
[10:48:01.847] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a1a5 80b1 4388 438a 4388 4388 760 2040 4388 8d 2841 6cd 244c 4388 4b 2649 4388 4388 e000 c000
[10:48:01.847] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a1a6 80c0 4388 20d 2a4f 4389 4388 4388 4388 4388 4388 438a e000 c000
[10:48:01.847] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a1a7 8000 4388 4388 749 2a41 438a 438a 744 2a4a 4388 81a 224f 438a 4388 4389 e000 c000
[10:48:01.847] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a1a8 8040 438a 521 2048 4388 4389 311 2647 4389 438a 20d 284f 4389 438a 4389 e000 c000
[10:48:01.847] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a1a9 80b1 4389 4389 4388 2da 2645 4388 4389 4389 4389 4389 e000 c000
[10:48:04.525] INFO: PixTest:: pg_setup set to default.
[10:48:04.526] INFO: 2 hot pixels found in step 1
[10:48:05.520] INFO: Collecting data for 5 seconds...
[10:48:10.537] INFO: Done with hot pixel readout
[10:48:22.284] INFO: PixTest:: pg_setup set to default.
[10:48:22.285] INFO: 2 hot pixels found in step 2
[10:48:23.278] INFO: Collecting data for 5 seconds...
[10:48:28.295] INFO: Done with hot pixel readout
[10:48:40.132] INFO: PixTest:: pg_setup set to default.
[10:48:40.133] INFO: 4 hot pixels found in step 3
[10:48:41.126] INFO: Collecting data for 5 seconds...
[10:48:46.143] INFO: Done with hot pixel readout
[10:48:57.972] INFO: PixTest:: pg_setup set to default.
[10:48:57.973] INFO: 4 hot pixels found in step 4
[10:48:58.966] INFO: Collecting data for 5 seconds...
[10:49:03.982] INFO: Done with hot pixel readout
[10:49:15.805] INFO: PixTest:: pg_setup set to default.
[10:49:15.806] INFO: 3 hot pixels found in step 5
[10:49:16.800] INFO: Collecting data for 5 seconds...
[10:49:21.816] INFO: Done with hot pixel readout
[10:49:33.243] INFO: PixTest:: pg_setup set to default.
[10:49:33.244] INFO: 4 hot pixels found in step 6
[10:49:34.240] INFO: Collecting data for 5 seconds...
[10:49:39.258] INFO: Done with hot pixel readout
[10:49:50.924] INFO: PixTest:: pg_setup set to default.
[10:49:50.924] INFO: 2 hot pixels found in step 7
[10:49:51.918] INFO: Collecting data for 5 seconds...
[10:49:56.936] INFO: Done with hot pixel readout
[10:50:08.843] INFO: PixTest:: pg_setup set to default.
[10:50:08.844] INFO: 1 hot pixels found in step 8
[10:50:09.837] INFO: Collecting data for 5 seconds...
[10:50:14.856] INFO: Done with hot pixel readout
[10:50:26.588] INFO: PixTest:: pg_setup set to default.
[10:50:26.589] INFO: 3 hot pixels found in step 9
[10:50:27.583] INFO: Collecting data for 5 seconds...
[10:50:32.601] INFO: Done with hot pixel readout
[10:50:43.890] INFO: PixTest:: pg_setup set to default.
[10:50:43.891] INFO: 1 hot pixels found in step 10
[10:50:44.885] INFO: Collecting data for 5 seconds...
[10:50:49.902] INFO: Done with hot pixel readout
[10:51:01.402] INFO: PixTest:: pg_setup set to default.
[10:51:01.403] INFO: 0 hot pixels found in step 11
[10:51:01.441] INFO: 0 hot pixels could not be trimmed and have been masked.
[10:51:01.444] INFO: PixTest::trimHotPixels() done
[10:51:01.463] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-N-3-17_FPIXTest-17C-FNAL-160527-0934_2016-05-27_09h35m_1464359703/000_FPIXTest_p17//trimParameters35_C0.dat
[10:51:01.475] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-N-3-17_FPIXTest-17C-FNAL-160527-0934_2016-05-27_09h35m_1464359703/000_FPIXTest_p17//trimParameters35_C1.dat
[10:51:01.482] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-N-3-17_FPIXTest-17C-FNAL-160527-0934_2016-05-27_09h35m_1464359703/000_FPIXTest_p17//trimParameters35_C2.dat
[10:51:01.488] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-N-3-17_FPIXTest-17C-FNAL-160527-0934_2016-05-27_09h35m_1464359703/000_FPIXTest_p17//trimParameters35_C3.dat
[10:51:01.493] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-N-3-17_FPIXTest-17C-FNAL-160527-0934_2016-05-27_09h35m_1464359703/000_FPIXTest_p17//trimParameters35_C4.dat
[10:51:01.499] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-N-3-17_FPIXTest-17C-FNAL-160527-0934_2016-05-27_09h35m_1464359703/000_FPIXTest_p17//trimParameters35_C5.dat
[10:51:01.504] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-N-3-17_FPIXTest-17C-FNAL-160527-0934_2016-05-27_09h35m_1464359703/000_FPIXTest_p17//trimParameters35_C6.dat
[10:51:01.510] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-N-3-17_FPIXTest-17C-FNAL-160527-0934_2016-05-27_09h35m_1464359703/000_FPIXTest_p17//trimParameters35_C7.dat
[10:51:01.515] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-N-3-17_FPIXTest-17C-FNAL-160527-0934_2016-05-27_09h35m_1464359703/000_FPIXTest_p17//trimParameters35_C8.dat
[10:51:01.520] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-N-3-17_FPIXTest-17C-FNAL-160527-0934_2016-05-27_09h35m_1464359703/000_FPIXTest_p17//trimParameters35_C9.dat
[10:51:01.526] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-N-3-17_FPIXTest-17C-FNAL-160527-0934_2016-05-27_09h35m_1464359703/000_FPIXTest_p17//trimParameters35_C10.dat
[10:51:01.531] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-N-3-17_FPIXTest-17C-FNAL-160527-0934_2016-05-27_09h35m_1464359703/000_FPIXTest_p17//trimParameters35_C11.dat
[10:51:01.537] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-N-3-17_FPIXTest-17C-FNAL-160527-0934_2016-05-27_09h35m_1464359703/000_FPIXTest_p17//trimParameters35_C12.dat
[10:51:01.542] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-N-3-17_FPIXTest-17C-FNAL-160527-0934_2016-05-27_09h35m_1464359703/000_FPIXTest_p17//trimParameters35_C13.dat
[10:51:01.548] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-N-3-17_FPIXTest-17C-FNAL-160527-0934_2016-05-27_09h35m_1464359703/000_FPIXTest_p17//trimParameters35_C14.dat
[10:51:01.553] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-N-3-17_FPIXTest-17C-FNAL-160527-0934_2016-05-27_09h35m_1464359703/000_FPIXTest_p17//trimParameters35_C15.dat
[10:51:01.559] INFO: write masked pixels into /home/uicpirepix2/ProductionTestResults/M-N-3-17_FPIXTest-17C-FNAL-160527-0934_2016-05-27_09h35m_1464359703/000_FPIXTest_p17//defaultMaskFile.dat
[10:51:01.569] INFO: enter test to run
[10:51:32.639] INFO: test: HighRate setting parameters: ->runsecondshotpixels=1;trimhotpixelthr=200<-
[10:51:32.639] INFO: running: highrate
[10:51:32.643] INFO: ----------------------------------------------------------------------
[10:51:32.643] INFO: PixTest::trimHotPixels() running for 1 seconds with 100 kHz trigger rate
[10:51:32.644] INFO: ----------------------------------------------------------------------
[10:51:32.644] INFO: THR = 200, corresponding to ~ 533.333 MHz/cm2
[10:51:32.644] INFO: edge/corner pixel THR is adjusted
[10:51:32.644] INFO: PixTestHighRate::trimHotPixels: step 0...
[10:51:33.601] INFO: Collecting data for 1 seconds...
[10:51:34.605] INFO: Done with hot pixel readout
[10:51:38.741] INFO: PixTest:: pg_setup set to default.
[10:51:38.742] INFO: 0 hot pixels found in step 0
[10:51:38.748] INFO: 0 hot pixels could not be trimmed and have been masked.
[10:51:38.839] INFO: PixTest::trimHotPixels() done
[10:51:38.840] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-N-3-17_FPIXTest-17C-FNAL-160527-0934_2016-05-27_09h35m_1464359703/000_FPIXTest_p17//trimParameters35_C0.dat
[10:51:38.848] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-N-3-17_FPIXTest-17C-FNAL-160527-0934_2016-05-27_09h35m_1464359703/000_FPIXTest_p17//trimParameters35_C1.dat
[10:51:38.854] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-N-3-17_FPIXTest-17C-FNAL-160527-0934_2016-05-27_09h35m_1464359703/000_FPIXTest_p17//trimParameters35_C2.dat
[10:51:38.859] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-N-3-17_FPIXTest-17C-FNAL-160527-0934_2016-05-27_09h35m_1464359703/000_FPIXTest_p17//trimParameters35_C3.dat
[10:51:38.864] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-N-3-17_FPIXTest-17C-FNAL-160527-0934_2016-05-27_09h35m_1464359703/000_FPIXTest_p17//trimParameters35_C4.dat
[10:51:38.871] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-N-3-17_FPIXTest-17C-FNAL-160527-0934_2016-05-27_09h35m_1464359703/000_FPIXTest_p17//trimParameters35_C5.dat
[10:51:38.876] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-N-3-17_FPIXTest-17C-FNAL-160527-0934_2016-05-27_09h35m_1464359703/000_FPIXTest_p17//trimParameters35_C6.dat
[10:51:38.881] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-N-3-17_FPIXTest-17C-FNAL-160527-0934_2016-05-27_09h35m_1464359703/000_FPIXTest_p17//trimParameters35_C7.dat
[10:51:38.887] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-N-3-17_FPIXTest-17C-FNAL-160527-0934_2016-05-27_09h35m_1464359703/000_FPIXTest_p17//trimParameters35_C8.dat
[10:51:38.892] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-N-3-17_FPIXTest-17C-FNAL-160527-0934_2016-05-27_09h35m_1464359703/000_FPIXTest_p17//trimParameters35_C9.dat
[10:51:38.898] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-N-3-17_FPIXTest-17C-FNAL-160527-0934_2016-05-27_09h35m_1464359703/000_FPIXTest_p17//trimParameters35_C10.dat
[10:51:38.904] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-N-3-17_FPIXTest-17C-FNAL-160527-0934_2016-05-27_09h35m_1464359703/000_FPIXTest_p17//trimParameters35_C11.dat
[10:51:38.909] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-N-3-17_FPIXTest-17C-FNAL-160527-0934_2016-05-27_09h35m_1464359703/000_FPIXTest_p17//trimParameters35_C12.dat
[10:51:38.915] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-N-3-17_FPIXTest-17C-FNAL-160527-0934_2016-05-27_09h35m_1464359703/000_FPIXTest_p17//trimParameters35_C13.dat
[10:51:38.920] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-N-3-17_FPIXTest-17C-FNAL-160527-0934_2016-05-27_09h35m_1464359703/000_FPIXTest_p17//trimParameters35_C14.dat
[10:51:38.926] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-N-3-17_FPIXTest-17C-FNAL-160527-0934_2016-05-27_09h35m_1464359703/000_FPIXTest_p17//trimParameters35_C15.dat
[10:51:38.932] INFO: write masked pixels into /home/uicpirepix2/ProductionTestResults/M-N-3-17_FPIXTest-17C-FNAL-160527-0934_2016-05-27_09h35m_1464359703/000_FPIXTest_p17//defaultMaskFile.dat
[10:51:38.968] INFO: enter test to run
[10:52:23.758] INFO: test: Xray setting parameters: ->source=DCLowRate<-
[10:52:23.758] INFO: running: xray
[10:52:23.760] INFO: ----------------------------------------------------------------------
[10:52:23.760] INFO: PixTestXray::doPhRun() fParRunSeconds = 100
[10:52:23.760] INFO: ----------------------------------------------------------------------
[10:52:24.803] INFO: PixTestXray::doPhRun start TriggerLoop with trigger frequency 100 kHz, period 405 and duration 100 seconds, fEventsMax = 10000000
[10:52:36.238] INFO: run duration 11 seconds, buffer almost full (81%), pausing triggers.
[10:53:05.915] INFO: Resuming triggers.
[10:53:17.452] INFO: run duration 22 seconds, buffer almost full (81%), pausing triggers.
[10:53:47.058] INFO: Resuming triggers.
[10:53:58.494] INFO: run duration 34 seconds, buffer almost full (81%), pausing triggers.
[10:54:27.854] INFO: Resuming triggers.
[10:54:39.289] INFO: run duration 45 seconds, buffer almost full (81%), pausing triggers.
[10:55:08.708] INFO: Resuming triggers.
[10:55:20.148] INFO: run duration 56 seconds, buffer almost full (81%), pausing triggers.
[10:55:49.432] INFO: Resuming triggers.
[10:56:00.867] INFO: run duration 68 seconds, buffer almost full (81%), pausing triggers.
[10:56:16.024] WARNING: Channel 1 ROC 2: Readback start marker after 32 readouts!
[10:56:16.024] ERROR: <datapipe.cc/Read:L164> Dumping the flawed event +- 3 events:
[10:56:16.024] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a1aa 80c0 4788 4788 4789 4789 4789 4788 4789 6c1 242c 4789 e000 c000
[10:56:16.024] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a1a4 8040 4788 309 222d 4788 10a 2a46 722 264e 4788 4788 4788 4788 4788 4788 e000 c000
[10:56:16.024] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a1a5 80b1 4788 478a 4788 4788 4788 4788 4788 4788 e000 c000
[10:56:16.024] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a1a6 80c0 4788 4789 4788 4788 21c 2a43 4788 4788 4788 478a e000 c000
[10:56:16.024] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a1a7 8000 4788 4788 478a 1c 2069 ca 224f 478a 4788 449 282a 478a 88 2626 4e1 2249 4788 4789 299 2845 e000 c000
[10:56:16.025] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a1a8 8040 478a 421 264c 45a 2845 4788 4c0 242f 4789 99 2a45 4789 61d 204f 478a 93 264f 4789 478a 75a 2645 4789 e000 c000
[10:56:16.025] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a1a9 80b1 4789 4789 282 2a28 61d 224f 4788 4788 4789 262 204e 4789 4789 4789 c8 286d e000 c000
[10:56:30.230] INFO: Resuming triggers.
[10:56:41.668] INFO: run duration 79 seconds, buffer almost full (81%), pausing triggers.
[10:57:11.113] INFO: Resuming triggers.
[10:57:22.552] INFO: run duration 91 seconds, buffer almost full (81%), pausing triggers.
[10:57:51.931] INFO: Resuming triggers.
[10:58:00.778] INFO: data taking finished, elapsed time: 100 seconds.
[10:58:23.719] INFO: PixTest:: pg_setup set to default.
[10:58:23.722] INFO: PixTestXray::doPhRun() done
[10:58:23.857] INFO: enter test to run
[10:59:01.619] INFO: test: Xray setting parameters: ->source=DCHighRate<-
[10:59:01.620] INFO: running: xray
[10:59:01.621] INFO: ----------------------------------------------------------------------
[10:59:01.621] INFO: PixTestXray::doPhRun() fParRunSeconds = 100
[10:59:01.621] INFO: ----------------------------------------------------------------------
[10:59:02.584] INFO: PixTestXray::doPhRun start TriggerLoop with trigger frequency 100 kHz, period 405 and duration 100 seconds, fEventsMax = 10000000
[10:59:09.323] INFO: run duration 6 seconds, buffer almost full (81%), pausing triggers.
[10:59:39.487] INFO: Resuming triggers.
[10:59:46.225] INFO: run duration 13 seconds, buffer almost full (81%), pausing triggers.
[11:00:16.245] INFO: Resuming triggers.
[11:00:22.983] INFO: run duration 20 seconds, buffer almost full (81%), pausing triggers.
[11:00:53.014] INFO: Resuming triggers.
[11:00:59.755] INFO: run duration 26 seconds, buffer almost full (81%), pausing triggers.
[11:01:12.135] WARNING: Channel 1 ROC 2: Readback start marker after 32 readouts!
[11:01:12.135] ERROR: <datapipe.cc/Read:L164> Dumping the flawed event +- 3 events:
[11:01:12.135] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a1ca 80c0 4788 213 264e 2d9 284d 4788 110 2a2a 111 2246 4789 488 222d 4789 149 224e 4789 210 2a4d 45d 2841 450 264c 4788 4789 4789 e000 c000
[11:01:12.135] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a1c4 8040 4788 453 264c 511 2243 4788 3 2449 4788 4788 59 2a45 4788 4788 4788 4788 e000 c000
[11:01:12.135] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a1c5 80b1 4788 255 2a65 68b 2648 478a 9 204f 4788 b 2463 149 2243 35d 2262 4788 21 2245 53 2444 442 2461 4788 75b 2845 4788 6e2 2269 4788 4788 e000 c000
[11:01:12.135] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a1c6 80c0 4788 4789 748 264f 4788 4788 4788 250 2463 4788 109 2061 4788 478a e000 c000
[11:01:12.135] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a1c7 8000 4788 491 2844 544 264d 4788 21a 2042 249 2869 753 264e 478a 2dd 2645 458 2a4d 478a 4788 478a 4788 4789 e000 c000
[11:01:12.135] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a1c8 8040 478a 4788 111 2842 4789 482 284a 4789 2da 224a 478a 4789 478a 4d5 2448 4789 422 264d 6d0 2245 e000 c000
[11:01:12.135] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a1c9 80b1 4789 321 264a 4789 719 244f 70b 2461 750 2865 4788 4788 4789 99 2a47 c9 224e 4789 4789 71a 2a25 4789 85c 242f e000 c000
[11:01:30.081] INFO: Resuming triggers.
[11:01:36.817] INFO: run duration 33 seconds, buffer almost full (81%), pausing triggers.
[11:02:07.641] INFO: Resuming triggers.
[11:02:14.374] INFO: run duration 40 seconds, buffer almost full (81%), pausing triggers.
[11:02:45.339] INFO: Resuming triggers.
[11:02:52.078] INFO: run duration 47 seconds, buffer almost full (81%), pausing triggers.
[11:03:22.481] INFO: Resuming triggers.
[11:03:29.219] INFO: run duration 53 seconds, buffer almost full (81%), pausing triggers.
[11:04:00.143] INFO: Resuming triggers.
[11:04:06.876] INFO: run duration 60 seconds, buffer almost full (81%), pausing triggers.
[11:04:37.782] INFO: Resuming triggers.
[11:04:44.516] INFO: run duration 67 seconds, buffer almost full (81%), pausing triggers.
[11:05:14.843] INFO: Resuming triggers.
[11:05:21.578] INFO: run duration 73 seconds, buffer almost full (81%), pausing triggers.
[11:05:51.618] INFO: Resuming triggers.
[11:05:58.356] INFO: run duration 80 seconds, buffer almost full (81%), pausing triggers.
[11:06:28.372] INFO: Resuming triggers.
[11:06:35.110] INFO: run duration 87 seconds, buffer almost full (81%), pausing triggers.
[11:07:05.135] INFO: Resuming triggers.
[11:07:11.874] INFO: run duration 93 seconds, buffer almost full (81%), pausing triggers.
[11:07:41.900] INFO: Resuming triggers.
[11:07:47.931] INFO: data taking finished, elapsed time: 100 seconds.
[11:07:48.534] WARNING: Channel 1 ROC 2: Readback start marker after 32 readouts!
[11:07:48.534] ERROR: <datapipe.cc/Read:L164> Dumping the flawed event +- 3 events:
[11:07:48.534] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a18a 80c0 4788 9d 2628 54b 2048 4788 4789 10d 2a40 6e2 2649 4789 412 204a 4789 699 2a41 4788 4789 4789 e000 c000
[11:07:48.534] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a184 8040 4788 4788 14 244a 0 2446 410 2242 4788 4788 4788 85a 224c 4788 353 2a4e 4788 4788 e000 c000
[11:07:48.534] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a185 80b1 4788 478a 4788 452 204a 4dc 2a4e 4788 5d 2846 283 204c 6cd 202d 4788 db 224e 4788 158 2442 4788 402 264c 61d 284a 4788 420 2867 e000 c000
[11:07:48.534] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a186 80c0 4788 159 2244 653 2249 4789 203 2446 4788 4788 8 282d 48b 2649 4788 242 244f 290 2448 2c8 242c 4788 4788 6dc 262c 818 2829 478a e000 c000
[11:07:48.534] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a187 8000 4788 4788 50b 224f 478a 478a 412 224f 64b 266c 4788 243 2647 478a 2d0 2824 35d 2865 4788 4789 6e0 2a4c 754 2641 e000 c000
[11:07:48.534] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a188 8040 478a 4788 1c 284d 4789 220 284f 64c 2249 80d 2246 4789 101 2442 478a 4789 478a 4789 e000 c000
[11:07:48.534] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a189 80b1 4789 285 2468 4789 750 2847 4788 645 2048 6c4 2044 4788 4789 92 282d 4789 344 2649 4789 4789 294 242c e000 c000
[11:08:14.981] INFO: PixTest:: pg_setup set to default.
[11:08:14.984] INFO: PixTestXray::doPhRun() done
[11:08:15.135] INFO: enter test to run
[11:09:08.919] INFO: test: HighRate no parameter change
[11:09:08.919] INFO: running: highrate
[11:09:08.920] INFO: ----------------------------------------------------------------------
[11:09:08.921] INFO: PixTestHighRate::calDelScan() ntrig = 10, vcal = 200
[11:09:08.921] INFO: ----------------------------------------------------------------------
[11:09:09.061] INFO: Expecting 768 events.
[11:09:10.195] INFO: 768 events read in total (419ms).
[11:09:10.195] INFO: Test took 1268ms.
[11:09:10.998] INFO: Expecting 41600 events.
[11:09:14.111] INFO: 41600 events read in total (2586ms).
[11:09:14.112] INFO: Test took 3911ms.
[11:09:14.147] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:09:14.874] INFO: Expecting 41600 events.
[11:09:18.095] INFO: 41600 events read in total (2694ms).
[11:09:18.096] INFO: Test took 3931ms.
[11:09:18.130] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:09:18.853] INFO: Expecting 41600 events.
[11:09:22.070] INFO: 41600 events read in total (2690ms).
[11:09:22.071] INFO: Test took 3922ms.
[11:09:22.105] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:09:22.832] INFO: Expecting 41600 events.
[11:09:26.063] INFO: 41600 events read in total (2705ms).
[11:09:26.064] INFO: Test took 3941ms.
[11:09:26.099] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:09:26.820] INFO: Expecting 41600 events.
[11:09:30.064] INFO: 41600 events read in total (2717ms).
[11:09:30.065] INFO: Test took 3948ms.
[11:09:30.099] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:09:30.824] INFO: Expecting 41600 events.
[11:09:34.079] INFO: 41600 events read in total (2729ms).
[11:09:34.080] INFO: Test took 3963ms.
[11:09:34.115] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:09:34.836] INFO: Expecting 41600 events.
[11:09:38.077] INFO: 41600 events read in total (2714ms).
[11:09:38.078] INFO: Test took 3945ms.
[11:09:38.112] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:09:38.836] INFO: Expecting 41600 events.
[11:09:42.083] INFO: 41600 events read in total (2720ms).
[11:09:42.084] INFO: Test took 3955ms.
[11:09:42.119] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:09:42.840] INFO: Expecting 41600 events.
[11:09:46.092] INFO: 41600 events read in total (2725ms).
[11:09:46.093] INFO: Test took 3958ms.
[11:09:46.127] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:09:46.849] INFO: Expecting 41600 events.
[11:09:50.091] INFO: 41600 events read in total (2715ms).
[11:09:50.092] INFO: Test took 3947ms.
[11:09:50.127] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:09:50.853] INFO: Expecting 41600 events.
[11:09:54.088] INFO: 41600 events read in total (2708ms).
[11:09:54.089] INFO: Test took 3944ms.
[11:09:54.123] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:09:54.850] INFO: Expecting 41600 events.
[11:09:58.083] INFO: 41600 events read in total (2707ms).
[11:09:58.084] INFO: Test took 3943ms.
[11:09:58.118] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:09:58.845] INFO: Expecting 41600 events.
[11:10:02.039] INFO: 41600 events read in total (2667ms).
[11:10:02.040] INFO: Test took 3903ms.
[11:10:02.074] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:10:02.796] INFO: Expecting 41600 events.
[11:10:06.039] INFO: 41600 events read in total (2716ms).
[11:10:06.040] INFO: Test took 3948ms.
[11:10:06.074] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:10:06.800] INFO: Expecting 41600 events.
[11:10:10.042] INFO: 41600 events read in total (2715ms).
[11:10:10.043] INFO: Test took 3950ms.
[11:10:10.078] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:10:10.798] INFO: Expecting 41600 events.
[11:10:14.042] INFO: 41600 events read in total (2717ms).
[11:10:14.043] INFO: Test took 3947ms.
[11:10:14.078] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:10:14.804] INFO: Expecting 41600 events.
[11:10:18.038] INFO: 41600 events read in total (2707ms).
[11:10:18.039] INFO: Test took 3943ms.
[11:10:18.072] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:10:18.798] INFO: Expecting 41600 events.
[11:10:22.035] INFO: 41600 events read in total (2711ms).
[11:10:22.036] INFO: Test took 3945ms.
[11:10:22.071] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:10:22.796] INFO: Expecting 41600 events.
[11:10:26.015] INFO: 41600 events read in total (2692ms).
[11:10:26.016] INFO: Test took 3928ms.
[11:10:26.050] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:10:26.770] INFO: Expecting 41600 events.
[11:10:29.841] INFO: 41600 events read in total (2544ms).
[11:10:29.842] INFO: Test took 3772ms.
[11:10:29.875] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:10:30.240] INFO: enter test to run
[11:10:34.967] INFO: test: HighRate no parameter change
[11:10:34.967] INFO: running: highrate
[11:10:34.968] INFO: ----------------------------------------------------------------------
[11:10:34.968] INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[11:10:34.968] INFO: ----------------------------------------------------------------------
[11:10:35.578] INFO: Expecting 208000 events.
[11:10:47.429] INFO: 208000 events read in total (11324ms).
[11:10:47.432] INFO: Test took 12456ms.
[11:10:47.578] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:10:47.831] INFO: number of dead pixels (per ROC): 0 1 1 1 0 1 0 0 0 0 0 1 4 0 9 0
[11:10:47.831] INFO: number of red-efficiency pixels: 82 59 99 112 133 163 118 71 64 122 135 104 123 51 35 36
[11:10:47.831] INFO: number of X-ray hits detected: 67798 45734 68311 111895 120072 114280 112571 77699 71284 96159 95436 82446 88339 52928 23798 29790
[11:10:47.831] INFO: number of triggers sent (total per ROC): 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000
[11:10:47.831] INFO: number of Vcal hits detected: 207917 207891 207850 207836 207862 207783 207879 207929 207936 207876 207864 207845 207681 207949 207524 207964
[11:10:47.831] INFO: Vcal hit fiducial efficiency (%): 100.0 100.0 100.0 99.9 99.9 99.9 99.9 100.0 100.0 99.9 99.9 100.0 99.9 100.0 100.0 100.0
[11:10:47.831] INFO: Vcal hit overall efficiency (%): 100.0 99.9 99.9 99.9 99.9 99.9 99.9 100.0 100.0 99.9 99.9 99.9 99.8 100.0 99.8 100.0
[11:10:47.831] INFO: X-ray hit rate [MHz/cm2]: 19.9 13.4 20.0 32.8 35.2 33.5 33.0 22.8 20.9 28.2 28.0 24.2 25.9 15.5 7.0 8.7
[11:10:47.831] INFO: PixTestHighRate::doXPixelAlive() done
[11:10:47.881] INFO: PixTest:: pg_setup set to default.
[11:10:47.896] INFO: enter test to run
[11:11:19.422] INFO: test: HighRate no parameter change
[11:11:19.423] INFO: running: highrate
[11:11:19.424] INFO: ----------------------------------------------------------------------
[11:11:19.424] INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[11:11:19.424] INFO: ----------------------------------------------------------------------
[11:11:20.044] INFO: Expecting 208000 events.
[11:11:33.631] INFO: 208000 events read in total (13060ms).
[11:11:33.637] INFO: Test took 14203ms.
[11:11:33.922] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:11:34.222] INFO: number of dead pixels (per ROC): 0 1 1 1 0 1 0 0 0 0 0 1 4 0 9 0
[11:11:34.222] INFO: number of red-efficiency pixels: 198 123 259 402 491 555 453 237 203 437 377 291 331 190 58 69
[11:11:34.222] INFO: number of X-ray hits detected: 139256 93544 138113 228376 244830 231339 227279 157742 145902 194493 194588 167630 179178 107872 49469 61005
[11:11:34.222] INFO: number of triggers sent (total per ROC): 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000
[11:11:34.222] INFO: number of Vcal hits detected: 207795 207819 207670 207523 207470 207320 207510 207749 207788 207507 207599 207643 207452 207802 207501 207931
[11:11:34.222] INFO: Vcal hit fiducial efficiency (%): 99.9 99.9 99.9 99.8 99.8 99.7 99.8 99.9 99.9 99.8 99.8 99.9 99.8 99.9 100.0 100.0
[11:11:34.222] INFO: Vcal hit overall efficiency (%): 99.9 99.9 99.8 99.8 99.7 99.7 99.8 99.9 99.9 99.8 99.8 99.8 99.7 99.9 99.8 100.0
[11:11:34.222] INFO: X-ray hit rate [MHz/cm2]: 40.8 27.4 40.5 66.9 71.8 67.8 66.6 46.2 42.8 57.0 57.0 49.1 52.5 31.6 14.5 17.9
[11:11:34.222] INFO: PixTestHighRate::doXPixelAlive() done
[11:11:34.269] INFO: PixTest:: pg_setup set to default.
[11:11:34.287] INFO: enter test to run
[11:12:07.134] INFO: test: HighRate no parameter change
[11:12:07.134] INFO: running: highrate
[11:12:07.135] INFO: ----------------------------------------------------------------------
[11:12:07.135] INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[11:12:07.135] INFO: ----------------------------------------------------------------------
[11:12:07.749] INFO: Expecting 208000 events.
[11:12:23.180] INFO: 208000 events read in total (14904ms).
[11:12:23.187] INFO: Test took 16043ms.
[11:12:23.623] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:12:23.969] INFO: number of dead pixels (per ROC): 0 1 1 1 0 1 0 0 0 0 0 1 4 0 9 0
[11:12:23.969] INFO: number of red-efficiency pixels: 452 238 642 968 1092 1322 939 554 428 929 961 613 786 378 135 114
[11:12:23.969] INFO: number of X-ray hits detected: 208773 141186 208795 344511 369181 347705 343146 238149 221082 295598 293624 252794 270934 162751 74702 92270
[11:12:23.969] INFO: number of triggers sent (total per ROC): 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000
[11:12:23.969] INFO: number of Vcal hits detected: 207490 207692 207137 206783 206679 206196 206850 207372 207532 206314 206840 207247 206890 207563 207419 207884
[11:12:23.969] INFO: Vcal hit fiducial efficiency (%): 99.8 99.9 99.7 99.5 99.4 99.2 99.5 99.7 99.8 99.2 99.5 99.7 99.6 99.8 99.9 99.9
[11:12:23.969] INFO: Vcal hit overall efficiency (%): 99.8 99.9 99.6 99.4 99.4 99.1 99.4 99.7 99.8 99.2 99.4 99.6 99.5 99.8 99.7 99.9
[11:12:23.969] INFO: X-ray hit rate [MHz/cm2]: 61.2 41.4 61.2 101.0 108.2 101.9 100.6 69.8 64.8 86.6 86.1 74.1 79.4 47.7 21.9 27.0
[11:12:23.969] INFO: PixTestHighRate::doXPixelAlive() done
[11:12:24.014] INFO: PixTest:: pg_setup set to default.
[11:12:24.030] INFO: enter test to run
[11:12:43.886] INFO: test: exit no parameter change
[11:12:44.285] QUIET: Connection to board 33 closed.
[11:12:44.286] INFO: pXar: this is the end, my friend