[17:51:58.888] INFO: *** Welcome to pxar ***
[17:51:58.888] INFO: *** Today: 2016/09/22
[17:51:58.903] INFO: *** Version: v1.9.0-818-g96727
[17:51:58.903] INFO: readRocDacs: /home/uicpirepix2/ProductionTestResults/M-Q-4-46_FPIXTest-17C-FNAL-160912-1213-150V_2016-09-12_12h14m_1473700447/000_FPIXTest_p17//dacParameters35_C0.dat .. /home/uicpirepix2/ProductionTestResults/M-Q-4-46_FPIXTest-17C-FNAL-160912-1213-150V_2016-09-12_12h14m_1473700447/000_FPIXTest_p17//dacParameters35_C15.dat
[17:51:58.904] INFO: readTbmDacs: /home/uicpirepix2/ProductionTestResults/M-Q-4-46_FPIXTest-17C-FNAL-160912-1213-150V_2016-09-12_12h14m_1473700447/000_FPIXTest_p17//tbmParameters_C0a.dat .. /home/uicpirepix2/ProductionTestResults/M-Q-4-46_FPIXTest-17C-FNAL-160912-1213-150V_2016-09-12_12h14m_1473700447/000_FPIXTest_p17//tbmParameters_C0b.dat
[17:51:58.905] INFO: readMaskFile: /home/uicpirepix2/ProductionTestResults/M-Q-4-46_FPIXTest-17C-FNAL-160912-1213-150V_2016-09-12_12h14m_1473700447/000_FPIXTest_p17//defaultMaskFile.dat
[17:51:58.905] INFO: MASKED Roc 0 col/row: 1 1
[17:51:58.905] INFO: MASKED Roc 0 col/row: 1 2
[17:51:58.905] INFO: MASKED Roc 1 col/row: 2 16
[17:51:58.905] INFO: MASKED Roc 3 col/row: 30 57
[17:51:58.905] INFO: MASKED Roc 5 col/row: 26 48
[17:51:58.905] INFO: MASKED Roc 5 col/row: 33 50
[17:51:58.905] INFO: readTrimFile: /home/uicpirepix2/ProductionTestResults/M-Q-4-46_FPIXTest-17C-FNAL-160912-1213-150V_2016-09-12_12h14m_1473700447/000_FPIXTest_p17//trimParameters35_C0.dat .. /home/uicpirepix2/ProductionTestResults/M-Q-4-46_FPIXTest-17C-FNAL-160912-1213-150V_2016-09-12_12h14m_1473700447/000_FPIXTest_p17//trimParameters35_C15.dat
[17:51:58.905] INFO: masking Roc 0 col/row: 1 1
[17:51:58.905] INFO: masking Roc 0 col/row: 1 2
[17:51:58.912] INFO: masking Roc 1 col/row: 2 16
[17:51:58.923] INFO: masking Roc 3 col/row: 30 57
[17:51:58.932] INFO: masking Roc 5 col/row: 26 48
[17:51:58.932] INFO: masking Roc 5 col/row: 33 50
[17:51:58.979] INFO: clk: 4
[17:51:58.979] INFO: ctr: 4
[17:51:58.979] INFO: sda: 19
[17:51:58.979] INFO: tin: 9
[17:51:58.979] INFO: level: 15
[17:51:58.979] INFO: triggerdelay: 0
[17:51:58.979] QUIET: Instanciating API for pxar v1.9.0+814~g74979a6
[17:51:58.980] INFO: Log level: INFO
[17:51:58.996] QUIET: Connection to board DTB_WREKRL opened.
[17:51:58.999] 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:
------------------------------------------------------
[17:51:58.002] INFO: RPC call hashes of host and DTB match: 398089610
[17:52:00.535] INFO: DUT info:
[17:52:00.535] INFO: The DUT currently contains the following objects:
[17:52:00.535] INFO: 2 TBM Cores tbm08c (2 ON)
[17:52:00.535] INFO: TBM Core alpha (0): 7 registers set
[17:52:00.536] INFO: TBM Core beta (1): 7 registers set
[17:52:00.536] INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[17:52:00.536] INFO: ROC 0: 19 DACs set, Pixels: 2 masked, 0 active.
[17:52:00.536] INFO: ROC 1: 19 DACs set, Pixels: 1 masked, 0 active.
[17:52:00.536] INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[17:52:00.536] INFO: ROC 3: 19 DACs set, Pixels: 1 masked, 0 active.
[17:52:00.536] INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[17:52:00.536] INFO: ROC 5: 19 DACs set, Pixels: 2 masked, 0 active.
[17:52:00.536] INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[17:52:00.536] INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[17:52:00.536] INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[17:52:00.536] INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[17:52:00.536] INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[17:52:00.536] INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[17:52:00.536] INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[17:52:00.536] INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[17:52:00.536] INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[17:52:00.536] INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[17:52:00.939] INFO: enter 'restricted' command line mode
[17:52:00.939] INFO: enter test to run
[17:52:05.661] INFO: test: PixelAlive no parameter change
[17:52:05.661] INFO: running: pixelalive
[17:52:05.670] INFO: ----------------------------------------------------------------------
[17:52:05.670] INFO: PixTestAlive::aliveTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[17:52:05.670] INFO: ----------------------------------------------------------------------
[17:52:05.672] INFO: ROC 0 masking pixel 1/1
[17:52:05.672] INFO: ROC 0 masking pixel 1/2
[17:52:05.672] INFO: ROC 1 masking pixel 2/16
[17:52:05.672] INFO: ROC 3 masking pixel 30/57
[17:52:05.672] INFO: ROC 5 masking pixel 26/48
[17:52:05.672] INFO: ROC 5 masking pixel 33/50
[17:52:05.997] INFO: Expecting 41600 events.
[17:52:10.366] INFO: 41600 events read in total (3651ms).
[17:52:10.520] INFO: Test took 4848ms.
[17:52:10.530] INFO: Fetched DAQ statistics. Counters are being reset now.
[17:52:10.802] INFO: PixTestAlive::aliveTest() done
[17:52:10.802] INFO: number of dead pixels (per ROC): 2 1 0 1 0 2 0 1 0 0 0 0 1 0 0 0
[17:52:10.802] INFO: ROC 0 masking pixel 1/1
[17:52:10.802] INFO: ROC 0 masking pixel 1/2
[17:52:10.802] INFO: ROC 1 masking pixel 2/16
[17:52:10.802] INFO: ROC 3 masking pixel 30/57
[17:52:10.802] INFO: ROC 5 masking pixel 26/48
[17:52:10.802] INFO: ROC 5 masking pixel 33/50
[17:52:10.834] INFO: enter test to run
[17:52:56.292] INFO: test: timing no parameter change
[17:52:56.292] INFO: running: timing
[17:52:56.296] INFO: ######################################################################
[17:52:56.296] INFO: PixTestTiming::doTest()
[17:52:56.296] INFO: ######################################################################
[17:52:56.296] INFO: ----------------------------------------------------------------------
[17:52:56.296] INFO: PixTestTiming::TBMPhaseScan()
[17:52:56.296] INFO: ----------------------------------------------------------------------
[17:59:18.719] INFO: TBM Phase Settings: 248
[17:59:18.719] INFO: 400MHz Phase: 6
[17:59:18.719] INFO: 160MHz Phase: 7
[17:59:18.719] INFO: Functional Phase Area: 3
[17:59:18.739] INFO: Test took 382443 ms.
[17:59:18.739] INFO: PixTestTiming::TBMPhaseScan() done.
[17:59:18.739] INFO: ROC 0 masking pixel 1/1
[17:59:18.739] INFO: ROC 0 masking pixel 1/2
[17:59:18.739] INFO: ROC 1 masking pixel 2/16
[17:59:18.739] INFO: ROC 3 masking pixel 30/57
[17:59:18.739] INFO: ROC 5 masking pixel 26/48
[17:59:18.739] INFO: ROC 5 masking pixel 33/50
[17:59:18.740] INFO: ----------------------------------------------------------------------
[17:59:18.740] INFO: PixTestTiming::ROCDelayScan()
[17:59:18.740] INFO: ----------------------------------------------------------------------
[18:01:22.133] INFO: ROC Delay Settings: 228
[18:01:22.133] INFO: ROC Header-Trailer/Token Delay: 11
[18:01:22.133] INFO: ROC Port 0 Delay: 4
[18:01:22.133] INFO: ROC Port 1 Delay: 4
[18:01:22.133] INFO: Functional ROC Area: 4
[18:01:22.136] INFO: Test took 123397 ms.
[18:01:22.137] INFO: PixTestTiming::ROCDelayScan() done.
[18:01:22.137] INFO: ROC 0 masking pixel 1/1
[18:01:22.137] INFO: ROC 0 masking pixel 1/2
[18:01:22.137] INFO: ROC 1 masking pixel 2/16
[18:01:22.137] INFO: ROC 3 masking pixel 30/57
[18:01:22.137] INFO: ROC 5 masking pixel 26/48
[18:01:22.137] INFO: ROC 5 masking pixel 33/50
[18:01:22.137] INFO: ----------------------------------------------------------------------
[18:01:22.137] INFO: PixTestTiming::TimingTest()
[18:01:22.137] INFO: ----------------------------------------------------------------------
[18:01:38.295] INFO: Fetched DAQ statistics. Counters are being reset now.
[18:01:53.301] INFO: Fetched DAQ statistics. Counters are being reset now.
[18:02:08.174] INFO: Fetched DAQ statistics. Counters are being reset now.
[18:02:23.192] INFO: Fetched DAQ statistics. Counters are being reset now.
[18:02:38.229] INFO: Fetched DAQ statistics. Counters are being reset now.
[18:02:53.351] INFO: Fetched DAQ statistics. Counters are being reset now.
[18:03:08.411] INFO: Fetched DAQ statistics. Counters are being reset now.
[18:03:23.445] INFO: Fetched DAQ statistics. Counters are being reset now.
[18:03:38.448] INFO: Fetched DAQ statistics. Counters are being reset now.
[18:03:53.508] INFO: Fetched DAQ statistics. Counters are being reset now.
[18:03:53.886] INFO: Fetched DAQ statistics. Counters are being reset now.
[18:03:53.903] INFO: ----------------------------------------------------------------------
[18:03:53.903] INFO: The fraction of properly decoded events is 100.00%: 10000000/10000000
[18:03:53.903] INFO: ----------------------------------------------------------------------
[18:03:53.903] INFO: ----------------------------------------------------------------------
[18:03:53.903] INFO: Read back bit status: 1
[18:03:53.903] INFO: ----------------------------------------------------------------------
[18:03:53.903] INFO: ----------------------------------------------------------------------
[18:03:53.903] INFO: Timings are good!
[18:03:53.903] INFO: ----------------------------------------------------------------------
[18:03:53.903] INFO: Test took 151766 ms.
[18:03:53.903] INFO: PixTestTiming::TimingTest() done.
[18:03:53.903] INFO: ROC 0 masking pixel 1/1
[18:03:53.903] INFO: ROC 0 masking pixel 1/2
[18:03:53.903] INFO: ROC 1 masking pixel 2/16
[18:03:53.903] INFO: ROC 3 masking pixel 30/57
[18:03:53.903] INFO: ROC 5 masking pixel 26/48
[18:03:53.903] INFO: ROC 5 masking pixel 33/50
[18:03:53.904] INFO: write tbm parameters into /home/uicpirepix2/ProductionTestResults/M-Q-4-46_FPIXTest-17C-FNAL-160912-1213-150V_2016-09-12_12h14m_1473700447/000_FPIXTest_p17//tbmParameters_C0a.dat
[18:03:53.904] INFO: write tbm parameters into /home/uicpirepix2/ProductionTestResults/M-Q-4-46_FPIXTest-17C-FNAL-160912-1213-150V_2016-09-12_12h14m_1473700447/000_FPIXTest_p17//tbmParameters_C0b.dat
[18:03:53.904] INFO: PixTestTiming::doTest took 657611 ms.
[18:03:53.904] INFO: PixTestTiming::doTest() done
[18:03:53.904] INFO: ROC 0 masking pixel 1/1
[18:03:53.904] INFO: ROC 0 masking pixel 1/2
[18:03:53.904] INFO: ROC 1 masking pixel 2/16
[18:03:53.904] INFO: ROC 3 masking pixel 30/57
[18:03:53.904] INFO: ROC 5 masking pixel 26/48
[18:03:53.904] INFO: ROC 5 masking pixel 33/50
[18:03:53.904] INFO: Write out TBMPhaseScan_0_V0
[18:03:53.904] INFO: Write out TBMPhaseScan_1_V0
[18:03:53.904] INFO: Write out CombinedTBMPhaseScan_V0
[18:03:53.905] INFO: Write out ROCDelayScan3_V0
[18:03:53.905] INFO: enter test to run
[18:04:43.040] INFO: test: HighRate setting parameters: ->runsecondshotpixels=5;trimhotpixelthr=200<-
[18:04:43.040] INFO: running: highrate
[18:04:43.053] INFO: readGainPedestalParameters /home/uicpirepix2/ProductionTestResults/M-Q-4-46_FPIXTest-17C-FNAL-160912-1213-150V_2016-09-12_12h14m_1473700447/000_FPIXTest_p17//phCalibrationFitErr35_C0.dat .. /home/uicpirepix2/ProductionTestResults/M-Q-4-46_FPIXTest-17C-FNAL-160912-1213-150V_2016-09-12_12h14m_1473700447/000_FPIXTest_p17//phCalibrationFitErr35_C15.dat
[18:04:43.224] INFO: ----------------------------------------------------------------------
[18:04:43.224] INFO: PixTest::trimHotPixels() running for 5 seconds with 100 kHz trigger rate
[18:04:43.224] INFO: ----------------------------------------------------------------------
[18:04:43.224] INFO: THR = 200, corresponding to ~ 106.667 MHz/cm2
[18:04:43.224] INFO: edge/corner pixel THR is adjusted
[18:04:43.224] INFO: PixTestHighRate::trimHotPixels: step 0...
[18:04:44.181] INFO: Collecting data for 5 seconds...
[18:04:49.199] INFO: Done with hot pixel readout
[18:05:00.988] INFO: PixTest:: pg_setup set to default.
[18:05:00.988] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[18:05:00.988] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[18:05:00.989] INFO: 6 hot pixels found in step 0
[18:05:01.983] INFO: Collecting data for 5 seconds...
[18:05:06.002] INFO: Done with hot pixel readout
[18:05:19.068] INFO: PixTest:: pg_setup set to default.
[18:05:19.068] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[18:05:19.068] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[18:05:19.069] INFO: 5 hot pixels found in step 1
[18:05:20.070] INFO: Collecting data for 5 seconds...
[18:05:25.089] INFO: Done with hot pixel readout
[18:05:37.108] INFO: PixTest:: pg_setup set to default.
[18:05:37.108] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[18:05:37.109] INFO: 5 hot pixels found in step 2
[18:05:38.102] INFO: Collecting data for 5 seconds...
[18:05:43.124] INFO: Done with hot pixel readout
[18:05:55.036] INFO: PixTest:: pg_setup set to default.
[18:05:55.036] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[18:05:55.036] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[18:05:55.036] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[18:05:55.037] INFO: 11 hot pixels found in step 3
[18:05:56.030] INFO: Collecting data for 5 seconds...
[18:06:01.048] INFO: Done with hot pixel readout
[18:06:12.835] INFO: PixTest:: pg_setup set to default.
[18:06:12.835] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[18:06:12.835] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[18:06:12.836] INFO: 10 hot pixels found in step 4
[18:06:13.828] INFO: Collecting data for 5 seconds...
[18:06:18.848] INFO: Done with hot pixel readout
[18:06:30.787] INFO: PixTest:: pg_setup set to default.
[18:06:30.788] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[18:06:30.788] INFO: 5 hot pixels found in step 5
[18:06:31.782] INFO: Collecting data for 5 seconds...
[18:06:36.802] INFO: Done with hot pixel readout
[18:06:48.599] INFO: PixTest:: pg_setup set to default.
[18:06:48.599] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[18:06:48.599] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[18:06:48.600] INFO: 8 hot pixels found in step 6
[18:06:49.594] INFO: Collecting data for 5 seconds...
[18:06:54.611] INFO: Done with hot pixel readout
[18:07:06.351] INFO: PixTest:: pg_setup set to default.
[18:07:06.351] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[18:07:06.351] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[18:07:06.352] INFO: 6 hot pixels found in step 7
[18:07:07.345] INFO: Collecting data for 5 seconds...
[18:07:12.362] INFO: Done with hot pixel readout
[18:07:24.118] INFO: PixTest:: pg_setup set to default.
[18:07:24.118] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[18:07:24.118] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[18:07:24.118] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[18:07:24.118] INFO: 8 hot pixels found in step 8
[18:07:25.112] INFO: Collecting data for 5 seconds...
[18:07:30.129] INFO: Done with hot pixel readout
[18:07:41.849] INFO: PixTest:: pg_setup set to default.
[18:07:41.849] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[18:07:41.849] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[18:07:41.850] INFO: 6 hot pixels found in step 9
[18:07:42.843] INFO: Collecting data for 5 seconds...
[18:07:47.860] INFO: Done with hot pixel readout
[18:07:59.587] INFO: PixTest:: pg_setup set to default.
[18:07:59.587] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[18:07:59.587] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[18:07:59.587] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[18:07:59.588] INFO: 11 hot pixels found in step 10
[18:08:00.581] INFO: Collecting data for 5 seconds...
[18:08:05.597] INFO: Done with hot pixel readout
[18:08:17.377] INFO: PixTest:: pg_setup set to default.
[18:08:17.377] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[18:08:17.377] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[18:08:17.377] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[18:08:17.378] INFO: 6 hot pixels found in step 11
[18:08:18.371] INFO: Collecting data for 5 seconds...
[18:08:23.387] INFO: Done with hot pixel readout
[18:08:35.148] INFO: PixTest:: pg_setup set to default.
[18:08:35.148] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[18:08:35.149] INFO: 8 hot pixels found in step 12
[18:08:36.142] INFO: Collecting data for 5 seconds...
[18:08:41.158] INFO: Done with hot pixel readout
[18:08:52.928] INFO: PixTest:: pg_setup set to default.
[18:08:52.928] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[18:08:52.928] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[18:08:52.929] INFO: 4 hot pixels found in step 13
[18:08:53.922] INFO: Collecting data for 5 seconds...
[18:08:58.939] INFO: Done with hot pixel readout
[18:09:10.640] INFO: PixTest:: pg_setup set to default.
[18:09:10.640] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[18:09:10.640] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[18:09:10.641] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[18:09:10.641] INFO: 14 hot pixels found in step 14
[18:09:10.678] INFO: 14 hot pixels could not be trimmed and have been masked.
[18:09:10.682] INFO: PixTest::trimHotPixels() done
[18:09:10.682] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-4-46_FPIXTest-17C-FNAL-160912-1213-150V_2016-09-12_12h14m_1473700447/000_FPIXTest_p17//trimParameters35_C0.dat
[18:09:10.688] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-4-46_FPIXTest-17C-FNAL-160912-1213-150V_2016-09-12_12h14m_1473700447/000_FPIXTest_p17//trimParameters35_C1.dat
[18:09:10.694] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-4-46_FPIXTest-17C-FNAL-160912-1213-150V_2016-09-12_12h14m_1473700447/000_FPIXTest_p17//trimParameters35_C2.dat
[18:09:10.699] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-4-46_FPIXTest-17C-FNAL-160912-1213-150V_2016-09-12_12h14m_1473700447/000_FPIXTest_p17//trimParameters35_C3.dat
[18:09:10.705] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-4-46_FPIXTest-17C-FNAL-160912-1213-150V_2016-09-12_12h14m_1473700447/000_FPIXTest_p17//trimParameters35_C4.dat
[18:09:10.710] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-4-46_FPIXTest-17C-FNAL-160912-1213-150V_2016-09-12_12h14m_1473700447/000_FPIXTest_p17//trimParameters35_C5.dat
[18:09:10.715] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-4-46_FPIXTest-17C-FNAL-160912-1213-150V_2016-09-12_12h14m_1473700447/000_FPIXTest_p17//trimParameters35_C6.dat
[18:09:10.720] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-4-46_FPIXTest-17C-FNAL-160912-1213-150V_2016-09-12_12h14m_1473700447/000_FPIXTest_p17//trimParameters35_C7.dat
[18:09:10.726] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-4-46_FPIXTest-17C-FNAL-160912-1213-150V_2016-09-12_12h14m_1473700447/000_FPIXTest_p17//trimParameters35_C8.dat
[18:09:10.731] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-4-46_FPIXTest-17C-FNAL-160912-1213-150V_2016-09-12_12h14m_1473700447/000_FPIXTest_p17//trimParameters35_C9.dat
[18:09:10.736] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-4-46_FPIXTest-17C-FNAL-160912-1213-150V_2016-09-12_12h14m_1473700447/000_FPIXTest_p17//trimParameters35_C10.dat
[18:09:10.741] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-4-46_FPIXTest-17C-FNAL-160912-1213-150V_2016-09-12_12h14m_1473700447/000_FPIXTest_p17//trimParameters35_C11.dat
[18:09:10.747] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-4-46_FPIXTest-17C-FNAL-160912-1213-150V_2016-09-12_12h14m_1473700447/000_FPIXTest_p17//trimParameters35_C12.dat
[18:09:10.752] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-4-46_FPIXTest-17C-FNAL-160912-1213-150V_2016-09-12_12h14m_1473700447/000_FPIXTest_p17//trimParameters35_C13.dat
[18:09:10.757] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-4-46_FPIXTest-17C-FNAL-160912-1213-150V_2016-09-12_12h14m_1473700447/000_FPIXTest_p17//trimParameters35_C14.dat
[18:09:10.762] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-4-46_FPIXTest-17C-FNAL-160912-1213-150V_2016-09-12_12h14m_1473700447/000_FPIXTest_p17//trimParameters35_C15.dat
[18:09:10.768] INFO: write masked pixels into /home/uicpirepix2/ProductionTestResults/M-Q-4-46_FPIXTest-17C-FNAL-160912-1213-150V_2016-09-12_12h14m_1473700447/000_FPIXTest_p17//defaultMaskFile.dat
[18:09:10.778] INFO: enter test to run
[18:09:47.790] INFO: test: HighRate setting parameters: ->runsecondshotpixels=1;trimhotpixelthr=200<-
[18:09:47.790] INFO: running: highrate
[18:09:47.795] INFO: ----------------------------------------------------------------------
[18:09:47.795] INFO: PixTest::trimHotPixels() running for 1 seconds with 100 kHz trigger rate
[18:09:47.795] INFO: ----------------------------------------------------------------------
[18:09:47.795] INFO: THR = 200, corresponding to ~ 533.333 MHz/cm2
[18:09:47.795] INFO: edge/corner pixel THR is adjusted
[18:09:47.795] INFO: PixTestHighRate::trimHotPixels: step 0...
[18:09:48.752] INFO: Collecting data for 1 seconds...
[18:09:49.756] INFO: Done with hot pixel readout
[18:09:53.763] INFO: PixTest:: pg_setup set to default.
[18:09:53.764] INFO: 0 hot pixels found in step 0
[18:09:53.769] INFO: 0 hot pixels could not be trimmed and have been masked.
[18:09:53.859] INFO: PixTest::trimHotPixels() done
[18:09:53.859] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-4-46_FPIXTest-17C-FNAL-160912-1213-150V_2016-09-12_12h14m_1473700447/000_FPIXTest_p17//trimParameters35_C0.dat
[18:09:53.871] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-4-46_FPIXTest-17C-FNAL-160912-1213-150V_2016-09-12_12h14m_1473700447/000_FPIXTest_p17//trimParameters35_C1.dat
[18:09:53.876] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-4-46_FPIXTest-17C-FNAL-160912-1213-150V_2016-09-12_12h14m_1473700447/000_FPIXTest_p17//trimParameters35_C2.dat
[18:09:53.881] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-4-46_FPIXTest-17C-FNAL-160912-1213-150V_2016-09-12_12h14m_1473700447/000_FPIXTest_p17//trimParameters35_C3.dat
[18:09:53.886] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-4-46_FPIXTest-17C-FNAL-160912-1213-150V_2016-09-12_12h14m_1473700447/000_FPIXTest_p17//trimParameters35_C4.dat
[18:09:53.892] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-4-46_FPIXTest-17C-FNAL-160912-1213-150V_2016-09-12_12h14m_1473700447/000_FPIXTest_p17//trimParameters35_C5.dat
[18:09:53.897] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-4-46_FPIXTest-17C-FNAL-160912-1213-150V_2016-09-12_12h14m_1473700447/000_FPIXTest_p17//trimParameters35_C6.dat
[18:09:53.902] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-4-46_FPIXTest-17C-FNAL-160912-1213-150V_2016-09-12_12h14m_1473700447/000_FPIXTest_p17//trimParameters35_C7.dat
[18:09:53.908] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-4-46_FPIXTest-17C-FNAL-160912-1213-150V_2016-09-12_12h14m_1473700447/000_FPIXTest_p17//trimParameters35_C8.dat
[18:09:53.913] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-4-46_FPIXTest-17C-FNAL-160912-1213-150V_2016-09-12_12h14m_1473700447/000_FPIXTest_p17//trimParameters35_C9.dat
[18:09:53.918] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-4-46_FPIXTest-17C-FNAL-160912-1213-150V_2016-09-12_12h14m_1473700447/000_FPIXTest_p17//trimParameters35_C10.dat
[18:09:53.923] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-4-46_FPIXTest-17C-FNAL-160912-1213-150V_2016-09-12_12h14m_1473700447/000_FPIXTest_p17//trimParameters35_C11.dat
[18:09:53.929] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-4-46_FPIXTest-17C-FNAL-160912-1213-150V_2016-09-12_12h14m_1473700447/000_FPIXTest_p17//trimParameters35_C12.dat
[18:09:53.934] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-4-46_FPIXTest-17C-FNAL-160912-1213-150V_2016-09-12_12h14m_1473700447/000_FPIXTest_p17//trimParameters35_C13.dat
[18:09:53.939] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-4-46_FPIXTest-17C-FNAL-160912-1213-150V_2016-09-12_12h14m_1473700447/000_FPIXTest_p17//trimParameters35_C14.dat
[18:09:53.944] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-4-46_FPIXTest-17C-FNAL-160912-1213-150V_2016-09-12_12h14m_1473700447/000_FPIXTest_p17//trimParameters35_C15.dat
[18:09:53.950] INFO: write masked pixels into /home/uicpirepix2/ProductionTestResults/M-Q-4-46_FPIXTest-17C-FNAL-160912-1213-150V_2016-09-12_12h14m_1473700447/000_FPIXTest_p17//defaultMaskFile.dat
[18:09:53.960] INFO: enter test to run
[18:10:39.349] INFO: test: Xray setting parameters: ->source=DCLowRate<-
[18:10:39.349] INFO: running: xray
[18:10:39.351] INFO: ----------------------------------------------------------------------
[18:10:39.351] INFO: PixTestXray::doPhRun() fParRunSeconds = 100
[18:10:39.351] INFO: ----------------------------------------------------------------------
[18:10:39.354] INFO: ROC 0 masking hot pixel 1/1
[18:10:39.354] INFO: ROC 0 masking hot pixel 1/2
[18:10:39.354] INFO: ROC 1 masking hot pixel 2/16
[18:10:39.354] INFO: ROC 3 masking hot pixel 30/57
[18:10:39.354] INFO: ROC 5 masking hot pixel 26/48
[18:10:39.354] INFO: ROC 5 masking hot pixel 33/50
[18:10:39.354] INFO: ROC 0 masking pixel 1/1
[18:10:39.354] INFO: ROC 0 masking pixel 1/2
[18:10:39.354] INFO: ROC 1 masking pixel 2/16
[18:10:39.354] INFO: ROC 3 masking pixel 30/57
[18:10:39.354] INFO: ROC 5 masking pixel 26/48
[18:10:39.354] INFO: ROC 5 masking pixel 33/50
[18:10:40.335] INFO: PixTestXray::doPhRun start TriggerLoop with trigger frequency 100 kHz, period 405 and duration 100 seconds, fEventsMax = 10000000
[18:10:51.477] INFO: run duration 11 seconds, buffer almost full (81%), pausing triggers.
[18:11:21.032] INFO: Resuming triggers.
[18:11:32.179] INFO: run duration 22 seconds, buffer almost full (81%), pausing triggers.
[18:12:01.782] INFO: Resuming triggers.
[18:12:12.926] INFO: run duration 33 seconds, buffer almost full (81%), pausing triggers.
[18:12:42.400] INFO: Resuming triggers.
[18:12:53.548] INFO: run duration 44 seconds, buffer almost full (81%), pausing triggers.
[18:13:23.049] INFO: Resuming triggers.
[18:13:34.196] INFO: run duration 55 seconds, buffer almost full (81%), pausing triggers.
[18:14:03.753] INFO: Resuming triggers.
[18:14:14.900] INFO: run duration 66 seconds, buffer almost full (81%), pausing triggers.
[18:14:44.795] INFO: Resuming triggers.
[18:14:55.941] INFO: run duration 77 seconds, buffer almost full (81%), pausing triggers.
[18:15:24.970] INFO: Resuming triggers.
[18:15:36.114] INFO: run duration 88 seconds, buffer almost full (81%), pausing triggers.
[18:16:06.046] INFO: Resuming triggers.
[18:16:17.191] INFO: run duration 99 seconds, buffer almost full (81%), pausing triggers.
[18:16:46.780] INFO: Resuming triggers.
[18:16:46.816] INFO: data taking finished, elapsed time: 100 seconds.
[18:16:47.487] INFO: PixTest:: pg_setup set to default.
[18:16:47.491] INFO: PixTestXray::doPhRun() done
[18:16:47.670] INFO: enter test to run
[18:17:32.786] INFO: test: Xray setting parameters: ->source=DCHighRate<-
[18:17:32.786] INFO: running: xray
[18:17:32.788] INFO: ----------------------------------------------------------------------
[18:17:32.788] INFO: PixTestXray::doPhRun() fParRunSeconds = 100
[18:17:32.788] INFO: ----------------------------------------------------------------------
[18:17:32.800] INFO: ROC 0 masking hot pixel 1/1
[18:17:32.800] INFO: ROC 0 masking hot pixel 1/2
[18:17:32.800] INFO: ROC 1 masking hot pixel 2/16
[18:17:32.800] INFO: ROC 3 masking hot pixel 30/57
[18:17:32.800] INFO: ROC 5 masking hot pixel 26/48
[18:17:32.800] INFO: ROC 5 masking hot pixel 33/50
[18:17:32.800] INFO: ROC 0 masking pixel 1/1
[18:17:32.800] INFO: ROC 0 masking pixel 1/2
[18:17:32.800] INFO: ROC 1 masking pixel 2/16
[18:17:32.800] INFO: ROC 3 masking pixel 30/57
[18:17:32.800] INFO: ROC 5 masking pixel 26/48
[18:17:32.801] INFO: ROC 5 masking pixel 33/50
[18:17:33.760] INFO: PixTestXray::doPhRun start TriggerLoop with trigger frequency 100 kHz, period 405 and duration 100 seconds, fEventsMax = 10000000
[18:17:40.503] INFO: run duration 6 seconds, buffer almost full (81%), pausing triggers.
[18:18:11.858] INFO: Resuming triggers.
[18:18:18.604] INFO: run duration 13 seconds, buffer almost full (81%), pausing triggers.
[18:18:50.357] INFO: Resuming triggers.
[18:18:57.100] INFO: run duration 20 seconds, buffer almost full (81%), pausing triggers.
[18:19:27.281] INFO: Resuming triggers.
[18:19:34.025] INFO: run duration 26 seconds, buffer almost full (81%), pausing triggers.
[18:20:04.263] INFO: Resuming triggers.
[18:20:10.006] INFO: run duration 33 seconds, buffer almost full (81%), pausing triggers.
[18:20:41.147] INFO: Resuming triggers.
[18:20:47.890] INFO: run duration 40 seconds, buffer almost full (81%), pausing triggers.
[18:21:17.992] INFO: Resuming triggers.
[18:21:24.732] INFO: run duration 47 seconds, buffer almost full (81%), pausing triggers.
[18:21:54.829] INFO: Resuming triggers.
[18:22:01.571] INFO: run duration 53 seconds, buffer almost full (81%), pausing triggers.
[18:22:31.585] INFO: Resuming triggers.
[18:22:38.330] INFO: run duration 60 seconds, buffer almost full (81%), pausing triggers.
[18:23:08.409] INFO: Resuming triggers.
[18:23:15.155] INFO: run duration 67 seconds, buffer almost full (81%), pausing triggers.
[18:23:45.225] INFO: Resuming triggers.
[18:23:51.970] INFO: run duration 73 seconds, buffer almost full (81%), pausing triggers.
[18:24:22.036] INFO: Resuming triggers.
[18:24:28.781] INFO: run duration 80 seconds, buffer almost full (81%), pausing triggers.
[18:24:58.914] INFO: Resuming triggers.
[18:25:05.656] INFO: run duration 87 seconds, buffer almost full (81%), pausing triggers.
[18:25:35.789] INFO: Resuming triggers.
[18:25:42.532] INFO: run duration 94 seconds, buffer almost full (81%), pausing triggers.
[18:26:12.617] INFO: Resuming triggers.
[18:26:18.542] INFO: data taking finished, elapsed time: 100 seconds.
[18:26:45.214] INFO: PixTest:: pg_setup set to default.
[18:26:45.218] INFO: PixTestXray::doPhRun() done
[18:26:45.372] INFO: enter test to run
[18:27:11.167] INFO: test: HighRate no parameter change
[18:27:11.167] INFO: running: highrate
[18:27:11.183] INFO: ----------------------------------------------------------------------
[18:27:11.183] INFO: PixTestHighRate::calDelScan() ntrig = 10, vcal = 200
[18:27:11.183] INFO: ----------------------------------------------------------------------
[18:27:11.338] INFO: Expecting 768 events.
[18:27:12.468] INFO: 768 events read in total (415ms).
[18:27:12.468] INFO: Test took 1265ms.
[18:27:12.472] INFO: ROC 0 masking pixel 1/1
[18:27:12.472] INFO: ROC 0 masking pixel 1/2
[18:27:12.472] INFO: ROC 1 masking pixel 2/16
[18:27:12.472] INFO: ROC 3 masking pixel 30/57
[18:27:12.472] INFO: ROC 5 masking pixel 26/48
[18:27:12.472] INFO: ROC 5 masking pixel 33/50
[18:27:13.271] INFO: Expecting 41600 events.
[18:27:16.403] INFO: 41600 events read in total (2605ms).
[18:27:16.404] INFO: Test took 3930ms.
[18:27:16.442] INFO: Fetched DAQ statistics. Counters are being reset now.
[18:27:17.163] INFO: Expecting 41600 events.
[18:27:20.221] INFO: 41600 events read in total (2531ms).
[18:27:20.222] INFO: Test took 3761ms.
[18:27:20.259] INFO: Fetched DAQ statistics. Counters are being reset now.
[18:27:20.977] INFO: Expecting 41600 events.
[18:27:24.174] INFO: 41600 events read in total (2671ms).
[18:27:24.175] INFO: Test took 3898ms.
[18:27:24.213] INFO: Fetched DAQ statistics. Counters are being reset now.
[18:27:24.929] INFO: Expecting 41600 events.
[18:27:28.292] INFO: 41600 events read in total (2836ms).
[18:27:28.293] INFO: Test took 4062ms.
[18:27:28.332] INFO: Fetched DAQ statistics. Counters are being reset now.
[18:27:29.050] INFO: Expecting 41600 events.
[18:27:32.333] INFO: 41600 events read in total (2756ms).
[18:27:32.334] INFO: Test took 3982ms.
[18:27:32.373] INFO: Fetched DAQ statistics. Counters are being reset now.
[18:27:33.084] INFO: Expecting 41600 events.
[18:27:36.385] INFO: 41600 events read in total (2775ms).
[18:27:36.386] INFO: Test took 3993ms.
[18:27:36.424] INFO: Fetched DAQ statistics. Counters are being reset now.
[18:27:37.130] INFO: Expecting 41600 events.
[18:27:40.253] INFO: 41600 events read in total (2596ms).
[18:27:40.254] INFO: Test took 3811ms.
[18:27:40.293] INFO: Fetched DAQ statistics. Counters are being reset now.
[18:27:40.005] INFO: Expecting 41600 events.
[18:27:44.318] INFO: 41600 events read in total (2786ms).
[18:27:44.319] INFO: Test took 4007ms.
[18:27:44.357] INFO: Fetched DAQ statistics. Counters are being reset now.
[18:27:45.070] INFO: Expecting 41600 events.
[18:27:48.386] INFO: 41600 events read in total (2789ms).
[18:27:48.387] INFO: Test took 4010ms.
[18:27:48.425] INFO: Fetched DAQ statistics. Counters are being reset now.
[18:27:49.141] INFO: Expecting 41600 events.
[18:27:52.426] INFO: 41600 events read in total (2758ms).
[18:27:52.427] INFO: Test took 3984ms.
[18:27:52.465] INFO: Fetched DAQ statistics. Counters are being reset now.
[18:27:53.187] INFO: Expecting 41600 events.
[18:27:56.395] INFO: 41600 events read in total (2682ms).
[18:27:56.396] INFO: Test took 3912ms.
[18:27:56.434] INFO: Fetched DAQ statistics. Counters are being reset now.
[18:27:57.153] INFO: Expecting 41600 events.
[18:28:00.475] INFO: 41600 events read in total (2795ms).
[18:28:00.476] INFO: Test took 4023ms.
[18:28:00.514] INFO: Fetched DAQ statistics. Counters are being reset now.
[18:28:01.226] INFO: Expecting 41600 events.
[18:28:04.527] INFO: 41600 events read in total (2774ms).
[18:28:04.528] INFO: Test took 3996ms.
[18:28:04.566] INFO: Fetched DAQ statistics. Counters are being reset now.
[18:28:05.281] INFO: Expecting 41600 events.
[18:28:08.671] INFO: 41600 events read in total (2863ms).
[18:28:08.672] INFO: Test took 4087ms.
[18:28:08.709] INFO: Fetched DAQ statistics. Counters are being reset now.
[18:28:09.428] INFO: Expecting 41600 events.
[18:28:12.702] INFO: 41600 events read in total (2748ms).
[18:28:12.703] INFO: Test took 3975ms.
[18:28:12.741] INFO: Fetched DAQ statistics. Counters are being reset now.
[18:28:13.458] INFO: Expecting 41600 events.
[18:28:16.791] INFO: 41600 events read in total (2806ms).
[18:28:16.792] INFO: Test took 4033ms.
[18:28:16.830] INFO: Fetched DAQ statistics. Counters are being reset now.
[18:28:17.545] INFO: Expecting 41600 events.
[18:28:20.759] INFO: 41600 events read in total (2687ms).
[18:28:20.760] INFO: Test took 3912ms.
[18:28:20.798] INFO: Fetched DAQ statistics. Counters are being reset now.
[18:28:21.508] INFO: Expecting 41600 events.
[18:28:24.797] INFO: 41600 events read in total (2762ms).
[18:28:24.798] INFO: Test took 3981ms.
[18:28:24.836] INFO: Fetched DAQ statistics. Counters are being reset now.
[18:28:25.552] INFO: Expecting 41600 events.
[18:28:28.840] INFO: 41600 events read in total (2761ms).
[18:28:28.841] INFO: Test took 3986ms.
[18:28:28.879] INFO: Fetched DAQ statistics. Counters are being reset now.
[18:28:29.596] INFO: Expecting 41600 events.
[18:28:32.695] INFO: 41600 events read in total (2572ms).
[18:28:32.696] INFO: Test took 3798ms.
[18:28:32.733] INFO: Fetched DAQ statistics. Counters are being reset now.
[18:28:33.082] INFO: enter test to run
[18:30:18.437] INFO: test: HighRate no parameter change
[18:30:18.437] INFO: running: highrate
[18:30:18.438] INFO: ----------------------------------------------------------------------
[18:30:18.438] INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[18:30:18.439] INFO: ----------------------------------------------------------------------
[18:30:18.447] INFO: ROC 0 masking pixel 1/1
[18:30:18.447] INFO: ROC 0 masking pixel 1/2
[18:30:18.447] INFO: ROC 1 masking pixel 2/16
[18:30:18.447] INFO: ROC 3 masking pixel 30/57
[18:30:18.447] INFO: ROC 5 masking pixel 26/48
[18:30:18.447] INFO: ROC 5 masking pixel 33/50
[18:30:19.058] INFO: Expecting 208000 events.
[18:30:31.430] INFO: 208000 events read in total (11845ms).
[18:30:31.434] INFO: Test took 12987ms.
[18:30:31.599] INFO: Fetched DAQ statistics. Counters are being reset now.
[18:30:31.858] INFO: number of dead pixels (per ROC): 2 1 0 1 0 2 0 1 0 0 0 0 1 0 0 0
[18:30:31.858] INFO: number of red-efficiency pixels: 81 64 96 143 165 164 220 128 100 121 148 123 108 79 25 36
[18:30:31.858] INFO: number of X-ray hits detected: 69642 46574 76307 117778 122602 132692 132910 97646 83861 106640 107702 87829 89883 58054 23583 27722
[18:30:31.858] INFO: number of triggers sent (total per ROC): 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000
[18:30:31.858] INFO: number of Vcal hits detected: 207815 207886 207904 207805 207833 207732 207773 207820 207898 207875 207851 207876 207842 207920 207974 207964
[18:30:31.858] INFO: Vcal hit fiducial efficiency (%): 100.0 100.0 100.0 99.9 99.9 99.9 99.9 99.9 100.0 99.9 99.9 99.9 100.0 100.0 100.0 100.0
[18:30:31.858] INFO: Vcal hit overall efficiency (%): 99.9 99.9 100.0 99.9 99.9 99.9 99.9 99.9 100.0 99.9 99.9 99.9 99.9 100.0 100.0 100.0
[18:30:31.858] INFO: X-ray hit rate [MHz/cm2]: 20.4 13.7 22.4 34.5 35.9 38.9 39.0 28.6 24.6 31.3 31.6 25.7 26.3 17.0 6.9 8.1
[18:30:31.858] INFO: PixTestHighRate::doXPixelAlive() done
[18:30:31.903] INFO: PixTest:: pg_setup set to default.
[18:30:31.915] INFO: enter test to run
[18:30:46.893] INFO: test: HighRate no parameter change
[18:30:46.893] INFO: running: highrate
[18:30:46.894] INFO: ----------------------------------------------------------------------
[18:30:46.894] INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[18:30:46.894] INFO: ----------------------------------------------------------------------
[18:30:46.905] INFO: ROC 0 masking pixel 1/1
[18:30:46.905] INFO: ROC 0 masking pixel 1/2
[18:30:46.905] INFO: ROC 1 masking pixel 2/16
[18:30:46.905] INFO: ROC 3 masking pixel 30/57
[18:30:46.905] INFO: ROC 5 masking pixel 26/48
[18:30:46.905] INFO: ROC 5 masking pixel 33/50
[18:30:47.509] INFO: Expecting 208000 events.
[18:31:02.033] INFO: 208000 events read in total (13997ms).
[18:31:02.039] INFO: Test took 15133ms.
[18:31:02.342] INFO: Fetched DAQ statistics. Counters are being reset now.
[18:31:02.644] INFO: number of dead pixels (per ROC): 2 1 0 1 0 2 0 1 0 0 0 0 1 0 0 0
[18:31:02.644] INFO: number of red-efficiency pixels: 213 142 240 456 463 473 630 342 262 378 398 267 280 222 59 66
[18:31:02.645] INFO: number of X-ray hits detected: 129775 87242 141007 217942 227646 246366 247254 181064 156440 198290 199891 163735 168915 107914 44151 51423
[18:31:02.645] INFO: number of triggers sent (total per ROC): 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000
[18:31:02.645] INFO: number of Vcal hits detected: 207680 207804 207737 207458 207488 207377 207292 207576 207723 207596 207573 207720 207652 207767 207939 207934
[18:31:02.645] INFO: Vcal hit fiducial efficiency (%): 99.9 99.9 99.9 99.8 99.8 99.8 99.7 99.8 99.9 99.8 99.8 99.9 99.9 99.9 100.0 100.0
[18:31:02.645] INFO: Vcal hit overall efficiency (%): 99.8 99.9 99.9 99.7 99.8 99.7 99.7 99.8 99.9 99.8 99.8 99.9 99.8 99.9 100.0 100.0
[18:31:02.645] INFO: X-ray hit rate [MHz/cm2]: 38.0 25.6 41.3 63.9 66.7 72.2 72.5 53.1 45.9 58.1 58.6 48.0 49.5 31.6 12.9 15.1
[18:31:02.645] INFO: PixTestHighRate::doXPixelAlive() done
[18:31:02.694] INFO: PixTest:: pg_setup set to default.
[18:31:02.714] INFO: enter test to run
[18:31:15.413] INFO: test: HighRate no parameter change
[18:31:15.413] INFO: running: highrate
[18:31:15.414] INFO: ----------------------------------------------------------------------
[18:31:15.414] INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[18:31:15.414] INFO: ----------------------------------------------------------------------
[18:31:15.423] INFO: ROC 0 masking pixel 1/1
[18:31:15.423] INFO: ROC 0 masking pixel 1/2
[18:31:15.423] INFO: ROC 1 masking pixel 2/16
[18:31:15.423] INFO: ROC 3 masking pixel 30/57
[18:31:15.423] INFO: ROC 5 masking pixel 26/48
[18:31:15.423] INFO: ROC 5 masking pixel 33/50
[18:31:16.033] INFO: Expecting 208000 events.
[18:31:31.853] INFO: 208000 events read in total (15293ms).
[18:31:31.860] INFO: Test took 16437ms.
[18:31:32.295] INFO: Fetched DAQ statistics. Counters are being reset now.
[18:31:32.645] INFO: number of dead pixels (per ROC): 2 1 0 1 0 2 0 1 0 0 0 0 1 0 0 0
[18:31:32.645] INFO: number of red-efficiency pixels: 462 271 529 986 1106 1022 1338 802 587 846 883 638 601 460 78 81
[18:31:32.645] INFO: number of X-ray hits detected: 190197 127895 207501 319526 333544 361461 363154 267072 229629 289649 295151 240091 248191 158898 65138 76120
[18:31:32.645] INFO: number of triggers sent (total per ROC): 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000
[18:31:32.645] INFO: number of Vcal hits detected: 207358 207655 207342 206725 206612 206623 206248 206938 207354 207007 206968 207272 207254 207466 207919 207912
[18:31:32.645] INFO: Vcal hit fiducial efficiency (%): 99.8 99.9 99.7 99.5 99.4 99.5 99.2 99.6 99.7 99.6 99.5 99.7 99.7 99.8 100.0 100.0
[18:31:32.646] INFO: Vcal hit overall efficiency (%): 99.7 99.8 99.7 99.4 99.3 99.3 99.2 99.5 99.7 99.5 99.5 99.7 99.6 99.7 100.0 100.0
[18:31:32.646] INFO: X-ray hit rate [MHz/cm2]: 55.7 37.5 60.8 93.7 97.8 105.9 106.4 78.3 67.3 84.9 86.5 70.4 72.7 46.6 19.1 22.3
[18:31:32.646] INFO: PixTestHighRate::doXPixelAlive() done
[18:31:32.690] INFO: PixTest:: pg_setup set to default.
[18:31:32.706] INFO: enter test to run
[18:31:35.021] INFO: test: exit no parameter change
[18:31:35.360] QUIET: Connection to board 33 closed.
[18:31:35.361] INFO: pXar: this is the end, my friend