[14:15:38.543] INFO: *** Welcome to pxar ***
[14:15:38.543] INFO: *** Today: 2016/06/01
[14:15:38.561] INFO: *** Version: v1.9.0-796-gef167-dirty
[14:15:38.561] INFO: readRocDacs: /home/uicpirepix2/ProductionTestResults/M-Y-L-NV_FPIXTest-17C-FNAL-160524-0931_2016-05-24_09h31m_1464100312/000_FPIXTest_p17//dacParameters35_C0.dat .. /home/uicpirepix2/ProductionTestResults/M-Y-L-NV_FPIXTest-17C-FNAL-160524-0931_2016-05-24_09h31m_1464100312/000_FPIXTest_p17//dacParameters35_C15.dat
[14:15:38.605] INFO: readTbmDacs: /home/uicpirepix2/ProductionTestResults/M-Y-L-NV_FPIXTest-17C-FNAL-160524-0931_2016-05-24_09h31m_1464100312/000_FPIXTest_p17//tbmParameters_C0a.dat .. /home/uicpirepix2/ProductionTestResults/M-Y-L-NV_FPIXTest-17C-FNAL-160524-0931_2016-05-24_09h31m_1464100312/000_FPIXTest_p17//tbmParameters_C0b.dat
[14:15:38.605] INFO: readMaskFile: /home/uicpirepix2/ProductionTestResults/M-Y-L-NV_FPIXTest-17C-FNAL-160524-0931_2016-05-24_09h31m_1464100312/000_FPIXTest_p17//defaultMaskFile.dat
[14:15:38.611] INFO: readTrimFile: /home/uicpirepix2/ProductionTestResults/M-Y-L-NV_FPIXTest-17C-FNAL-160524-0931_2016-05-24_09h31m_1464100312/000_FPIXTest_p17//trimParameters35_C0.dat .. /home/uicpirepix2/ProductionTestResults/M-Y-L-NV_FPIXTest-17C-FNAL-160524-0931_2016-05-24_09h31m_1464100312/000_FPIXTest_p17//trimParameters35_C15.dat
[14:15:38.701] INFO: clk: 4
[14:15:38.701] INFO: ctr: 4
[14:15:38.701] INFO: sda: 19
[14:15:38.701] INFO: tin: 9
[14:15:38.701] INFO: level: 15
[14:15:38.701] INFO: triggerdelay: 0
[14:15:38.701] QUIET: Instanciating API for pxar v1.9.0+796~gef16747
[14:15:38.701] INFO: Log level: INFO
[14:15:38.718] QUIET: Connection to board DTB_WREKRL opened.
[14:15:38.721] INFO: DTB startup information
--- DTB info------------------------------------------
Board id: 33
HW version: DTB1.2
FW version: 4.2
SW version: 4.5
USB id: DTB_WREKRL
MAC address: 40D855118021
Hostname: pixelDTB033
Comment:
------------------------------------------------------
[14:15:38.724] INFO: RPC call hashes of host and DTB match: 398089610
[14:15:40.257] INFO: DUT info:
[14:15:40.257] INFO: The DUT currently contains the following objects:
[14:15:40.257] INFO: 2 TBM Cores tbm08c (2 ON)
[14:15:40.257] INFO: TBM Core alpha (0): 7 registers set
[14:15:40.257] INFO: TBM Core beta (1): 7 registers set
[14:15:40.257] INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[14:15:40.257] INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[14:15:40.257] INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[14:15:40.257] INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[14:15:40.257] INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[14:15:40.257] INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[14:15:40.257] INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[14:15:40.257] INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[14:15:40.257] INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[14:15:40.257] INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[14:15:40.257] INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[14:15:40.257] INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[14:15:40.257] INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[14:15:40.257] INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[14:15:40.257] INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[14:15:40.257] INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[14:15:40.257] INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[14:15:40.660] INFO: enter 'restricted' command line mode
[14:15:40.660] INFO: enter test to run
[14:15:46.879] INFO: test: PixelAlive no parameter change
[14:15:46.879] INFO: running: pixelalive
[14:15:46.888] INFO: ----------------------------------------------------------------------
[14:15:46.888] INFO: PixTestAlive::aliveTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[14:15:46.888] INFO: ----------------------------------------------------------------------
[14:15:47.210] INFO: Expecting 41600 events.
[14:15:51.565] INFO: 41600 events read in total (3637ms).
[14:15:51.734] INFO: Test took 4842ms.
[14:15:51.748] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:15:52.014] INFO: PixTestAlive::aliveTest() done
[14:15:52.014] INFO: number of dead pixels (per ROC): 0 1 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[14:15:52.049] INFO: enter test to run
[14:16:26.799] INFO: test: HighRate setting parameters: ->runsecondshotpixels=5;trimhotpixelthr=200<-
[14:16:26.799] INFO: running: highrate
[14:16:26.799] INFO: readGainPedestalParameters /home/uicpirepix2/ProductionTestResults/M-Y-L-NV_FPIXTest-17C-FNAL-160524-0931_2016-05-24_09h31m_1464100312/000_FPIXTest_p17//phCalibrationFitErr35_C0.dat .. /home/uicpirepix2/ProductionTestResults/M-Y-L-NV_FPIXTest-17C-FNAL-160524-0931_2016-05-24_09h31m_1464100312/000_FPIXTest_p17//phCalibrationFitErr35_C15.dat
[14:16:27.017] INFO: ----------------------------------------------------------------------
[14:16:27.017] INFO: PixTest::trimHotPixels() running for 5 seconds with 100 kHz trigger rate
[14:16:27.017] INFO: ----------------------------------------------------------------------
[14:16:27.017] INFO: THR = 200, corresponding to ~ 106.667 MHz/cm2
[14:16:27.017] INFO: edge/corner pixel THR is adjusted
[14:16:27.017] INFO: PixTestHighRate::trimHotPixels: step 0...
[14:16:27.976] INFO: Collecting data for 5 seconds...
[14:16:32.992] INFO: Done with hot pixel readout
[14:16:45.546] INFO: PixTest:: pg_setup set to default.














[14:16:45.548] INFO: 367 hot pixels found in step 0
[14:16:46.544] INFO: Collecting data for 5 seconds...
[14:16:51.560] INFO: Done with hot pixel readout
[14:17:04.456] INFO: PixTest:: pg_setup set to default.


















[14:17:04.460] INFO: 345 hot pixels found in step 1
[14:17:05.458] INFO: Collecting data for 5 seconds...
[14:17:10.472] INFO: Done with hot pixel readout
[14:17:23.586] INFO: PixTest:: pg_setup set to default.




















[14:17:23.588] INFO: 333 hot pixels found in step 2
[14:17:24.587] INFO: Collecting data for 5 seconds...
[14:17:29.603] INFO: Done with hot pixel readout
[14:17:42.535] INFO: PixTest:: pg_setup set to default.

























[14:17:42.536] INFO: 306 hot pixels found in step 3
[14:17:43.536] INFO: Collecting data for 5 seconds...
[14:17:48.551] INFO: Done with hot pixel readout
[14:18:01.639] INFO: PixTest:: pg_setup set to default.































[14:18:01.641] INFO: 274 hot pixels found in step 4
[14:18:02.642] INFO: Collecting data for 5 seconds...
[14:18:07.657] INFO: Done with hot pixel readout
[14:18:20.716] INFO: PixTest:: pg_setup set to default.






























[14:18:20.718] INFO: 247 hot pixels found in step 5
[14:18:21.717] INFO: Collecting data for 5 seconds...
[14:18:26.732] INFO: Done with hot pixel readout
[14:18:39.827] INFO: PixTest:: pg_setup set to default.





































[14:18:39.830] INFO: 226 hot pixels found in step 6
[14:18:40.833] INFO: Collecting data for 5 seconds...
[14:18:45.850] INFO: Done with hot pixel readout
[14:18:58.514] INFO: PixTest:: pg_setup set to default.


















































[14:18:58.516] INFO: 224 hot pixels found in step 7
[14:18:59.517] INFO: Collecting data for 5 seconds...
[14:19:04.532] INFO: Done with hot pixel readout
[14:19:17.420] INFO: PixTest:: pg_setup set to default.












































[14:19:17.422] INFO: 200 hot pixels found in step 8
[14:19:18.426] INFO: Collecting data for 5 seconds...
[14:19:23.443] INFO: Done with hot pixel readout
[14:19:36.343] INFO: PixTest:: pg_setup set to default.
























































[14:19:36.345] INFO: 216 hot pixels found in step 9
[14:19:37.346] INFO: Collecting data for 5 seconds...
[14:19:42.362] INFO: Done with hot pixel readout
[14:19:55.409] INFO: PixTest:: pg_setup set to default.



























































[14:19:55.411] INFO: 183 hot pixels found in step 10
[14:19:56.412] INFO: Collecting data for 5 seconds...
[14:20:01.428] INFO: Done with hot pixel readout
[14:20:14.473] INFO: PixTest:: pg_setup set to default.




























































[14:20:14.476] INFO: 183 hot pixels found in step 11
[14:20:15.477] INFO: Collecting data for 5 seconds...
[14:20:20.493] INFO: Done with hot pixel readout
[14:20:33.575] INFO: PixTest:: pg_setup set to default.






















































[14:20:33.577] INFO: 176 hot pixels found in step 12
[14:20:34.578] INFO: Collecting data for 5 seconds...
[14:20:39.593] INFO: Done with hot pixel readout
[14:20:52.604] INFO: PixTest:: pg_setup set to default.



































































[14:20:52.606] INFO: 188 hot pixels found in step 13
[14:20:53.606] INFO: Collecting data for 5 seconds...
[14:20:58.621] INFO: Done with hot pixel readout
[14:21:11.705] INFO: PixTest:: pg_setup set to default.



























































[14:21:11.708] INFO: 149 hot pixels found in step 14
[14:21:11.753] INFO: 149 hot pixels could not be trimmed and have been masked.
[14:21:11.757] INFO: PixTest::trimHotPixels() done
[14:21:11.757] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Y-L-NV_FPIXTest-17C-FNAL-160524-0931_2016-05-24_09h31m_1464100312/000_FPIXTest_p17//trimParameters35_C0.dat
[14:21:11.763] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Y-L-NV_FPIXTest-17C-FNAL-160524-0931_2016-05-24_09h31m_1464100312/000_FPIXTest_p17//trimParameters35_C1.dat
[14:21:11.769] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Y-L-NV_FPIXTest-17C-FNAL-160524-0931_2016-05-24_09h31m_1464100312/000_FPIXTest_p17//trimParameters35_C2.dat
[14:21:11.775] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Y-L-NV_FPIXTest-17C-FNAL-160524-0931_2016-05-24_09h31m_1464100312/000_FPIXTest_p17//trimParameters35_C3.dat
[14:21:11.780] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Y-L-NV_FPIXTest-17C-FNAL-160524-0931_2016-05-24_09h31m_1464100312/000_FPIXTest_p17//trimParameters35_C4.dat
[14:21:11.785] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Y-L-NV_FPIXTest-17C-FNAL-160524-0931_2016-05-24_09h31m_1464100312/000_FPIXTest_p17//trimParameters35_C5.dat
[14:21:11.790] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Y-L-NV_FPIXTest-17C-FNAL-160524-0931_2016-05-24_09h31m_1464100312/000_FPIXTest_p17//trimParameters35_C6.dat
[14:21:11.795] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Y-L-NV_FPIXTest-17C-FNAL-160524-0931_2016-05-24_09h31m_1464100312/000_FPIXTest_p17//trimParameters35_C7.dat
[14:21:11.800] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Y-L-NV_FPIXTest-17C-FNAL-160524-0931_2016-05-24_09h31m_1464100312/000_FPIXTest_p17//trimParameters35_C8.dat
[14:21:11.806] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Y-L-NV_FPIXTest-17C-FNAL-160524-0931_2016-05-24_09h31m_1464100312/000_FPIXTest_p17//trimParameters35_C9.dat
[14:21:11.811] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Y-L-NV_FPIXTest-17C-FNAL-160524-0931_2016-05-24_09h31m_1464100312/000_FPIXTest_p17//trimParameters35_C10.dat
[14:21:11.816] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Y-L-NV_FPIXTest-17C-FNAL-160524-0931_2016-05-24_09h31m_1464100312/000_FPIXTest_p17//trimParameters35_C11.dat
[14:21:11.821] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Y-L-NV_FPIXTest-17C-FNAL-160524-0931_2016-05-24_09h31m_1464100312/000_FPIXTest_p17//trimParameters35_C12.dat
[14:21:11.826] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Y-L-NV_FPIXTest-17C-FNAL-160524-0931_2016-05-24_09h31m_1464100312/000_FPIXTest_p17//trimParameters35_C13.dat
[14:21:11.831] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Y-L-NV_FPIXTest-17C-FNAL-160524-0931_2016-05-24_09h31m_1464100312/000_FPIXTest_p17//trimParameters35_C14.dat
[14:21:11.836] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Y-L-NV_FPIXTest-17C-FNAL-160524-0931_2016-05-24_09h31m_1464100312/000_FPIXTest_p17//trimParameters35_C15.dat
[14:21:11.842] INFO: write masked pixels into /home/uicpirepix2/ProductionTestResults/M-Y-L-NV_FPIXTest-17C-FNAL-160524-0931_2016-05-24_09h31m_1464100312/000_FPIXTest_p17//defaultMaskFile.dat
[14:21:11.852] INFO: enter test to run
[14:21:49.100] INFO: test: HighRate setting parameters: ->runsecondshotpixels=1;trimhotpixelthr=200<-
[14:21:49.100] INFO: running: highrate
[14:21:49.105] INFO: ----------------------------------------------------------------------
[14:21:49.105] INFO: PixTest::trimHotPixels() running for 1 seconds with 100 kHz trigger rate
[14:21:49.105] INFO: ----------------------------------------------------------------------
[14:21:49.105] INFO: THR = 200, corresponding to ~ 533.333 MHz/cm2
[14:21:49.105] INFO: edge/corner pixel THR is adjusted
[14:21:49.105] INFO: PixTestHighRate::trimHotPixels: step 0...
[14:21:50.063] INFO: Collecting data for 1 seconds...
[14:21:51.067] INFO: Done with hot pixel readout
[14:21:55.826] INFO: PixTest:: pg_setup set to default.
[14:21:55.827] INFO: 0 hot pixels found in step 0
[14:21:55.833] INFO: 0 hot pixels could not be trimmed and have been masked.
[14:21:55.892] INFO: PixTest::trimHotPixels() done
[14:21:55.893] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Y-L-NV_FPIXTest-17C-FNAL-160524-0931_2016-05-24_09h31m_1464100312/000_FPIXTest_p17//trimParameters35_C0.dat
[14:21:55.904] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Y-L-NV_FPIXTest-17C-FNAL-160524-0931_2016-05-24_09h31m_1464100312/000_FPIXTest_p17//trimParameters35_C1.dat
[14:21:55.910] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Y-L-NV_FPIXTest-17C-FNAL-160524-0931_2016-05-24_09h31m_1464100312/000_FPIXTest_p17//trimParameters35_C2.dat
[14:21:55.915] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Y-L-NV_FPIXTest-17C-FNAL-160524-0931_2016-05-24_09h31m_1464100312/000_FPIXTest_p17//trimParameters35_C3.dat
[14:21:55.920] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Y-L-NV_FPIXTest-17C-FNAL-160524-0931_2016-05-24_09h31m_1464100312/000_FPIXTest_p17//trimParameters35_C4.dat
[14:21:55.925] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Y-L-NV_FPIXTest-17C-FNAL-160524-0931_2016-05-24_09h31m_1464100312/000_FPIXTest_p17//trimParameters35_C5.dat
[14:21:55.930] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Y-L-NV_FPIXTest-17C-FNAL-160524-0931_2016-05-24_09h31m_1464100312/000_FPIXTest_p17//trimParameters35_C6.dat
[14:21:55.936] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Y-L-NV_FPIXTest-17C-FNAL-160524-0931_2016-05-24_09h31m_1464100312/000_FPIXTest_p17//trimParameters35_C7.dat
[14:21:55.941] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Y-L-NV_FPIXTest-17C-FNAL-160524-0931_2016-05-24_09h31m_1464100312/000_FPIXTest_p17//trimParameters35_C8.dat
[14:21:55.946] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Y-L-NV_FPIXTest-17C-FNAL-160524-0931_2016-05-24_09h31m_1464100312/000_FPIXTest_p17//trimParameters35_C9.dat
[14:21:55.951] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Y-L-NV_FPIXTest-17C-FNAL-160524-0931_2016-05-24_09h31m_1464100312/000_FPIXTest_p17//trimParameters35_C10.dat
[14:21:55.956] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Y-L-NV_FPIXTest-17C-FNAL-160524-0931_2016-05-24_09h31m_1464100312/000_FPIXTest_p17//trimParameters35_C11.dat
[14:21:55.962] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Y-L-NV_FPIXTest-17C-FNAL-160524-0931_2016-05-24_09h31m_1464100312/000_FPIXTest_p17//trimParameters35_C12.dat
[14:21:55.967] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Y-L-NV_FPIXTest-17C-FNAL-160524-0931_2016-05-24_09h31m_1464100312/000_FPIXTest_p17//trimParameters35_C13.dat
[14:21:55.972] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Y-L-NV_FPIXTest-17C-FNAL-160524-0931_2016-05-24_09h31m_1464100312/000_FPIXTest_p17//trimParameters35_C14.dat
[14:21:55.977] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Y-L-NV_FPIXTest-17C-FNAL-160524-0931_2016-05-24_09h31m_1464100312/000_FPIXTest_p17//trimParameters35_C15.dat
[14:21:55.982] INFO: write masked pixels into /home/uicpirepix2/ProductionTestResults/M-Y-L-NV_FPIXTest-17C-FNAL-160524-0931_2016-05-24_09h31m_1464100312/000_FPIXTest_p17//defaultMaskFile.dat
[14:21:55.992] INFO: enter test to run
[14:22:44.324] INFO: test: Xray setting parameters: ->source=DCLowRate<-
[14:22:44.324] INFO: running: xray
[14:22:44.325] INFO: ----------------------------------------------------------------------
[14:22:44.325] INFO: PixTestXray::doPhRun() fParRunSeconds = 100
[14:22:44.325] INFO: ----------------------------------------------------------------------
[14:22:45.288] INFO: PixTestXray::doPhRun start TriggerLoop with trigger frequency 100 kHz, period 405 and duration 100 seconds, fEventsMax = 10000000
[14:22:55.446] INFO: run duration 10 seconds, buffer almost full (81%), pausing triggers.
[14:23:24.913] INFO: Resuming triggers.
[14:23:35.074] INFO: run duration 20 seconds, buffer almost full (81%), pausing triggers.
[14:24:04.610] INFO: Resuming triggers.
[14:24:14.768] INFO: run duration 30 seconds, buffer almost full (81%), pausing triggers.
[14:24:44.294] INFO: Resuming triggers.
[14:24:54.455] INFO: run duration 40 seconds, buffer almost full (81%), pausing triggers.
[14:25:23.922] INFO: Resuming triggers.
[14:25:34.086] INFO: run duration 50 seconds, buffer almost full (81%), pausing triggers.
[14:26:03.679] INFO: Resuming triggers.
[14:26:13.839] INFO: run duration 60 seconds, buffer almost full (81%), pausing triggers.
[14:26:43.372] INFO: Resuming triggers.
[14:26:53.536] INFO: run duration 70 seconds, buffer almost full (81%), pausing triggers.
[14:27:22.950] INFO: Resuming triggers.
[14:27:33.110] INFO: run duration 81 seconds, buffer almost full (81%), pausing triggers.
[14:28:02.678] INFO: Resuming triggers.
[14:28:12.838] INFO: run duration 91 seconds, buffer almost full (81%), pausing triggers.
[14:28:42.352] INFO: Resuming triggers.
[14:28:51.216] INFO: data taking finished, elapsed time: 100 seconds.
[14:29:17.210] INFO: PixTest:: pg_setup set to default.
[14:29:17.214] INFO: PixTestXray::doPhRun() done
[14:29:17.354] INFO: enter test to run
[14:30:18.377] INFO: test: Xray setting parameters: ->source=DCHighRate<-
[14:30:18.377] INFO: running: xray
[14:30:18.378] INFO: ----------------------------------------------------------------------
[14:30:18.378] INFO: PixTestXray::doPhRun() fParRunSeconds = 100
[14:30:18.378] INFO: ----------------------------------------------------------------------
[14:30:19.341] INFO: PixTestXray::doPhRun start TriggerLoop with trigger frequency 100 kHz, period 405 and duration 100 seconds, fEventsMax = 10000000
[14:30:24.721] INFO: run duration 5 seconds, buffer almost full (81%), pausing triggers.
[14:30:54.903] INFO: Resuming triggers.
[14:31:00.284] INFO: run duration 10 seconds, buffer almost full (81%), pausing triggers.
[14:31:30.305] INFO: Resuming triggers.
[14:31:35.688] INFO: run duration 16 seconds, buffer almost full (81%), pausing triggers.
[14:32:05.694] INFO: Resuming triggers.
[14:32:11.077] INFO: run duration 21 seconds, buffer almost full (81%), pausing triggers.
[14:32:41.145] INFO: Resuming triggers.
[14:32:46.531] INFO: run duration 26 seconds, buffer almost full (81%), pausing triggers.
[14:33:16.597] INFO: Resuming triggers.
[14:33:21.983] INFO: run duration 32 seconds, buffer almost full (81%), pausing triggers.
[14:33:52.099] INFO: Resuming triggers.
[14:33:57.482] INFO: run duration 37 seconds, buffer almost full (81%), pausing triggers.
[14:34:27.597] INFO: Resuming triggers.
[14:34:32.984] INFO: run duration 42 seconds, buffer almost full (81%), pausing triggers.
[14:35:03.086] INFO: Resuming triggers.
[14:35:08.470] INFO: run duration 48 seconds, buffer almost full (81%), pausing triggers.
[14:35:38.529] INFO: Resuming triggers.
[14:35:43.914] INFO: run duration 53 seconds, buffer almost full (81%), pausing triggers.
[14:36:13.945] INFO: Resuming triggers.
[14:36:19.332] INFO: run duration 59 seconds, buffer almost full (81%), pausing triggers.
[14:36:49.437] INFO: Resuming triggers.
[14:36:54.821] INFO: run duration 64 seconds, buffer almost full (81%), pausing triggers.
[14:37:24.891] INFO: Resuming triggers.
[14:37:30.278] INFO: run duration 69 seconds, buffer almost full (81%), pausing triggers.
[14:38:00.336] INFO: Resuming triggers.
[14:38:05.727] INFO: run duration 75 seconds, buffer almost full (81%), pausing triggers.
[14:38:35.698] INFO: Resuming triggers.
[14:38:41.087] INFO: run duration 80 seconds, buffer almost full (81%), pausing triggers.
[14:39:11.149] INFO: Resuming triggers.
[14:39:16.537] INFO: run duration 85 seconds, buffer almost full (81%), pausing triggers.
[14:39:46.664] INFO: Resuming triggers.
[14:39:52.050] INFO: run duration 91 seconds, buffer almost full (81%), pausing triggers.
[14:40:22.156] INFO: Resuming triggers.
[14:40:27.544] INFO: run duration 96 seconds, buffer almost full (81%), pausing triggers.
[14:40:57.658] INFO: Resuming triggers.
[14:41:01.033] INFO: data taking finished, elapsed time: 100 seconds.
[14:41:20.153] INFO: PixTest:: pg_setup set to default.
[14:41:20.157] INFO: PixTestXray::doPhRun() done
[14:41:20.308] INFO: enter test to run
[14:42:24.011] INFO: test: HighRate no parameter change
[14:42:24.011] INFO: running: highrate
[14:42:24.012] INFO: ----------------------------------------------------------------------
[14:42:24.012] INFO: PixTestHighRate::calDelScan() ntrig = 10, vcal = 200
[14:42:24.012] INFO: ----------------------------------------------------------------------
[14:42:24.154] INFO: Expecting 768 events.
[14:42:25.288] INFO: 768 events read in total (419ms).
[14:42:25.288] INFO: Test took 1268ms.
[14:42:26.092] INFO: Expecting 41600 events.
[14:42:29.302] INFO: 41600 events read in total (2683ms).
[14:42:29.303] INFO: Test took 4009ms.
[14:42:29.348] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:42:30.052] INFO: Expecting 41600 events.
[14:42:33.362] INFO: 41600 events read in total (2784ms).
[14:42:33.363] INFO: Test took 3993ms.
[14:42:33.408] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:42:34.109] INFO: Expecting 41600 events.
[14:42:37.442] INFO: 41600 events read in total (2806ms).
[14:42:37.443] INFO: Test took 4013ms.
[14:42:37.489] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:42:38.189] INFO: Expecting 41600 events.
[14:42:41.524] INFO: 41600 events read in total (2808ms).
[14:42:41.525] INFO: Test took 4013ms.
[14:42:41.572] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:42:42.272] INFO: Expecting 41600 events.
[14:42:45.629] INFO: 41600 events read in total (2830ms).
[14:42:45.630] INFO: Test took 4037ms.
[14:42:45.676] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:42:46.380] INFO: Expecting 41600 events.
[14:42:49.743] INFO: 41600 events read in total (2837ms).
[14:42:49.745] INFO: Test took 4047ms.
[14:42:49.790] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:42:50.490] INFO: Expecting 41600 events.
[14:42:53.840] INFO: 41600 events read in total (2823ms).
[14:42:53.841] INFO: Test took 4030ms.
[14:42:53.886] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:42:54.591] INFO: Expecting 41600 events.
[14:42:57.941] INFO: 41600 events read in total (2824ms).
[14:42:57.942] INFO: Test took 4034ms.
[14:42:57.986] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:42:58.689] INFO: Expecting 41600 events.
[14:43:02.036] INFO: 41600 events read in total (2820ms).
[14:43:02.037] INFO: Test took 4030ms.
[14:43:02.083] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:43:02.786] INFO: Expecting 41600 events.
[14:43:06.140] INFO: 41600 events read in total (2827ms).
[14:43:06.141] INFO: Test took 4036ms.
[14:43:06.186] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:43:06.890] INFO: Expecting 41600 events.
[14:43:10.239] INFO: 41600 events read in total (2822ms).
[14:43:10.241] INFO: Test took 4032ms.
[14:43:10.286] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:43:10.985] INFO: Expecting 41600 events.
[14:43:14.356] INFO: 41600 events read in total (2844ms).
[14:43:14.357] INFO: Test took 4048ms.
[14:43:14.402] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:43:15.103] INFO: Expecting 41600 events.
[14:43:18.471] INFO: 41600 events read in total (2841ms).
[14:43:18.472] INFO: Test took 4047ms.
[14:43:18.517] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:43:19.217] INFO: Expecting 41600 events.
[14:43:22.589] INFO: 41600 events read in total (2846ms).
[14:43:22.590] INFO: Test took 4050ms.
[14:43:22.637] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:43:23.338] INFO: Expecting 41600 events.
[14:43:26.692] INFO: 41600 events read in total (2827ms).
[14:43:26.693] INFO: Test took 4035ms.
[14:43:26.739] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:43:27.440] INFO: Expecting 41600 events.
[14:43:30.809] INFO: 41600 events read in total (2842ms).
[14:43:30.810] INFO: Test took 4048ms.
[14:43:30.855] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:43:31.559] INFO: Expecting 41600 events.
[14:43:34.905] INFO: 41600 events read in total (2819ms).
[14:43:34.906] INFO: Test took 4029ms.
[14:43:34.951] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:43:35.656] INFO: Expecting 41600 events.
[14:43:39.027] INFO: 41600 events read in total (2844ms).
[14:43:39.028] INFO: Test took 4054ms.
[14:43:39.074] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:43:39.774] INFO: Expecting 41600 events.
[14:43:43.118] INFO: 41600 events read in total (2817ms).
[14:43:43.120] INFO: Test took 4024ms.
[14:43:43.164] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:43:43.865] INFO: Expecting 41600 events.
[14:43:47.114] INFO: 41600 events read in total (2722ms).
[14:43:47.115] INFO: Test took 3928ms.
[14:43:47.161] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:43:47.501] INFO: enter test to run
[14:44:00.299] INFO: test: HighRate no parameter change
[14:44:00.299] INFO: running: highrate
[14:44:00.300] INFO: ----------------------------------------------------------------------
[14:44:00.300] INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[14:44:00.300] INFO: ----------------------------------------------------------------------
[14:44:00.916] INFO: Expecting 208000 events.
[14:44:13.436] INFO: 208000 events read in total (11993ms).
[14:44:13.440] INFO: Test took 13131ms.
[14:44:13.638] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:44:13.910] INFO: number of dead pixels (per ROC): 0 1 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[14:44:13.910] INFO: number of red-efficiency pixels: 142 72 143 188 237 220 211 156 123 153 213 166 185 102 34 43
[14:44:13.910] INFO: number of X-ray hits detected: 94549 65429 95722 154169 162467 161275 157908 118842 113858 139133 139583 120363 126808 73901 31859 31817
[14:44:13.910] INFO: number of triggers sent (total per ROC): 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000
[14:44:13.910] INFO: number of Vcal hits detected: 207855 207876 207850 207804 207754 207760 207779 207842 207874 207843 207781 207831 207808 207896 207966 207956
[14:44:13.910] INFO: Vcal hit fiducial efficiency (%): 99.9 100.0 99.9 99.9 99.9 99.9 99.9 99.9 99.9 99.9 99.9 99.9 99.9 100.0 100.0 100.0
[14:44:13.910] INFO: Vcal hit overall efficiency (%): 99.9 99.9 99.9 99.9 99.9 99.9 99.9 99.9 99.9 99.9 99.9 99.9 99.9 100.0 100.0 100.0
[14:44:13.910] INFO: X-ray hit rate [MHz/cm2]: 27.7 19.2 28.1 45.2 47.6 47.3 46.3 34.8 33.4 40.8 40.9 35.3 37.2 21.7 9.3 9.3
[14:44:13.910] INFO: PixTestHighRate::doXPixelAlive() done
[14:44:13.954] INFO: PixTest:: pg_setup set to default.
[14:44:13.968] INFO: enter test to run
[14:45:11.290] INFO: test: HighRate no parameter change
[14:45:11.290] INFO: running: highrate
[14:45:11.291] INFO: ----------------------------------------------------------------------
[14:45:11.291] INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[14:45:11.291] INFO: ----------------------------------------------------------------------
[14:45:11.909] INFO: Expecting 208000 events.
[14:45:26.776] INFO: 208000 events read in total (14340ms).
[14:45:26.783] INFO: Test took 15483ms.
[14:45:27.171] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:45:27.504] INFO: number of dead pixels (per ROC): 0 1 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[14:45:27.504] INFO: number of red-efficiency pixels: 412 245 580 677 1006 749 726 498 434 518 730 433 636 270 70 74
[14:45:27.504] INFO: number of X-ray hits detected: 185561 130049 189559 302620 318059 317639 312804 235504 225069 274569 274951 237323 250637 145894 62676 62771
[14:45:27.504] INFO: number of triggers sent (total per ROC): 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000
[14:45:27.504] INFO: number of Vcal hits detected: 207533 207686 207316 207233 206736 207100 207187 207447 207530 207437 207174 207533 207296 207709 207930 207923
[14:45:27.504] INFO: Vcal hit fiducial efficiency (%): 99.8 99.9 99.7 99.7 99.5 99.6 99.6 99.8 99.8 99.7 99.6 99.8 99.7 99.9 100.0 100.0
[14:45:27.504] INFO: Vcal hit overall efficiency (%): 99.8 99.8 99.7 99.6 99.4 99.6 99.6 99.7 99.8 99.7 99.6 99.8 99.7 99.9 100.0 100.0
[14:45:27.504] INFO: X-ray hit rate [MHz/cm2]: 54.4 38.1 55.6 88.7 93.2 93.1 91.7 69.0 66.0 80.5 80.6 69.6 73.5 42.8 18.4 18.4
[14:45:27.504] INFO: PixTestHighRate::doXPixelAlive() done
[14:45:27.552] INFO: PixTest:: pg_setup set to default.
[14:45:27.566] INFO: enter test to run
[14:45:43.242] INFO: test: HighRate no parameter change
[14:45:43.242] INFO: running: highrate
[14:45:43.243] INFO: ----------------------------------------------------------------------
[14:45:43.243] INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[14:45:43.243] INFO: ----------------------------------------------------------------------
[14:45:43.866] INFO: Expecting 208000 events.
[14:46:01.881] INFO: 208000 events read in total (17488ms).
[14:46:01.891] INFO: Test took 18638ms.
[14:46:02.496] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:46:02.898] INFO: number of dead pixels (per ROC): 0 1 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[14:46:02.898] INFO: number of red-efficiency pixels: 1005 544 1252 1775 2481 1812 1795 1172 998 1381 1883 1134 1563 672 142 122
[14:46:02.898] INFO: number of X-ray hits detected: 289263 202502 293889 472209 494035 493697 485293 366912 350813 426827 428222 369057 391635 226591 98271 98856
[14:46:02.898] INFO: number of triggers sent (total per ROC): 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000
[14:46:02.898] INFO: number of Vcal hits detected: 206548 207280 205716 205377 203617 205090 205322 206315 206742 206191 205172 206535 205779 207120 207848 207871
[14:46:02.898] INFO: Vcal hit fiducial efficiency (%): 99.4 99.7 99.0 98.9 98.1 98.7 98.8 99.3 99.5 99.2 98.8 99.4 99.1 99.6 99.9 99.9
[14:46:02.898] INFO: Vcal hit overall efficiency (%): 99.3 99.7 98.9 98.7 97.9 98.6 98.7 99.2 99.4 99.1 98.6 99.3 98.9 99.6 99.9 99.9
[14:46:02.898] INFO: X-ray hit rate [MHz/cm2]: 84.8 59.4 86.1 138.4 144.8 144.7 142.2 107.5 102.8 125.1 125.5 108.2 114.8 66.4 28.8 29.0
[14:46:02.898] INFO: PixTestHighRate::doXPixelAlive() done
[14:46:02.948] INFO: PixTest:: pg_setup set to default.
[14:46:02.965] INFO: enter test to run
[14:46:07.138] INFO: test: exit no parameter change
[14:46:07.444] QUIET: Connection to board 33 closed.
[14:46:07.445] INFO: pXar: this is the end, my friend