[13:56:01.955] INFO: *** Welcome to pxar ***
[13:56:01.956] INFO: *** Today: 2016/05/25
[13:56:01.976] INFO: *** Version: v1.9.0-796-gef167-dirty
[13:56:01.976] INFO: readRocDacs: /home/uicpirepix2/ProductionTestResults/M-Y-F-NM_FPIXTest-17C-FNAL-160519-0937_2016-05-19_09h38m_1463668705/000_FPIXTest_p17//dacParameters35_C0.dat .. /home/uicpirepix2/ProductionTestResults/M-Y-F-NM_FPIXTest-17C-FNAL-160519-0937_2016-05-19_09h38m_1463668705/000_FPIXTest_p17//dacParameters35_C15.dat
[13:56:01.001] INFO: readTbmDacs: /home/uicpirepix2/ProductionTestResults/M-Y-F-NM_FPIXTest-17C-FNAL-160519-0937_2016-05-19_09h38m_1463668705/000_FPIXTest_p17//tbmParameters_C0a.dat .. /home/uicpirepix2/ProductionTestResults/M-Y-F-NM_FPIXTest-17C-FNAL-160519-0937_2016-05-19_09h38m_1463668705/000_FPIXTest_p17//tbmParameters_C0b.dat
[13:56:01.001] INFO: readMaskFile: /home/uicpirepix2/ProductionTestResults/M-Y-F-NM_FPIXTest-17C-FNAL-160519-0937_2016-05-19_09h38m_1463668705/000_FPIXTest_p17//defaultMaskFile.dat
[13:56:01.005] INFO: readTrimFile: /home/uicpirepix2/ProductionTestResults/M-Y-F-NM_FPIXTest-17C-FNAL-160519-0937_2016-05-19_09h38m_1463668705/000_FPIXTest_p17//trimParameters35_C0.dat .. /home/uicpirepix2/ProductionTestResults/M-Y-F-NM_FPIXTest-17C-FNAL-160519-0937_2016-05-19_09h38m_1463668705/000_FPIXTest_p17//trimParameters35_C15.dat
[13:56:02.104] INFO: clk: 4
[13:56:02.104] INFO: ctr: 4
[13:56:02.104] INFO: sda: 19
[13:56:02.104] INFO: tin: 9
[13:56:02.104] INFO: level: 15
[13:56:02.104] INFO: triggerdelay: 0
[13:56:02.104] QUIET: Instanciating API for pxar v1.9.0+796~gef16747
[13:56:02.104] INFO: Log level: INFO
[13:56:02.121] QUIET: Connection to board DTB_WREKRL opened.
[13:56:02.124] 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:
------------------------------------------------------
[13:56:02.127] INFO: RPC call hashes of host and DTB match: 398089610
[13:56:03.661] INFO: DUT info:
[13:56:03.661] INFO: The DUT currently contains the following objects:
[13:56:03.661] INFO: 2 TBM Cores tbm08c (2 ON)
[13:56:03.661] INFO: TBM Core alpha (0): 7 registers set
[13:56:03.661] INFO: TBM Core beta (1): 7 registers set
[13:56:03.661] INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[13:56:03.661] INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[13:56:03.661] INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[13:56:03.661] INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[13:56:03.661] INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[13:56:03.661] INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[13:56:03.661] INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[13:56:03.661] INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[13:56:03.661] INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[13:56:03.661] INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[13:56:03.661] INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[13:56:03.661] INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[13:56:03.661] INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[13:56:03.661] INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[13:56:03.661] INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[13:56:03.661] INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[13:56:03.661] INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[13:56:04.064] INFO: enter 'restricted' command line mode
[13:56:04.064] INFO: enter test to run
[13:56:06.507] INFO: test: PixelAlive no parameter change
[13:56:06.507] INFO: running: pixelalive
[13:56:06.517] INFO: ----------------------------------------------------------------------
[13:56:06.517] INFO: PixTestAlive::aliveTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[13:56:06.517] INFO: ----------------------------------------------------------------------
[13:56:06.840] INFO: Expecting 41600 events.
[13:56:11.220] INFO: 41600 events read in total (3662ms).
[13:56:11.386] INFO: Test took 4867ms.
[13:56:11.403] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:56:11.681] INFO: PixTestAlive::aliveTest() done
[13:56:11.681] INFO: number of dead pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[13:56:11.719] INFO: enter test to run
[13:56:45.851] INFO: test: HighRate setting parameters: ->runsecondshotpixels=5;trimhotpixelthr=200<-
[13:56:45.851] INFO: running: highrate
[13:56:45.851] INFO: readGainPedestalParameters /home/uicpirepix2/ProductionTestResults/M-Y-F-NM_FPIXTest-17C-FNAL-160519-0937_2016-05-19_09h38m_1463668705/000_FPIXTest_p17//phCalibrationFitErr35_C0.dat .. /home/uicpirepix2/ProductionTestResults/M-Y-F-NM_FPIXTest-17C-FNAL-160519-0937_2016-05-19_09h38m_1463668705/000_FPIXTest_p17//phCalibrationFitErr35_C15.dat
[13:56:46.076] INFO: ----------------------------------------------------------------------
[13:56:46.076] INFO: PixTest::trimHotPixels() running for 5 seconds with 100 kHz trigger rate
[13:56:46.076] INFO: ----------------------------------------------------------------------
[13:56:46.076] INFO: THR = 200, corresponding to ~ 106.667 MHz/cm2
[13:56:46.076] INFO: edge/corner pixel THR is adjusted
[13:56:46.076] INFO: PixTestHighRate::trimHotPixels: step 0...
[13:56:47.034] INFO: Collecting data for 5 seconds...
[13:56:52.051] INFO: Done with hot pixel readout
[13:57:05.078] INFO: PixTest:: pg_setup set to default.













[13:57:05.079] INFO: 315 hot pixels found in step 0
[13:57:06.075] INFO: Collecting data for 5 seconds...
[13:57:11.096] INFO: Done with hot pixel readout
[13:57:24.281] INFO: PixTest:: pg_setup set to default.
















[13:57:24.282] INFO: 286 hot pixels found in step 1
[13:57:25.280] INFO: Collecting data for 5 seconds...
[13:57:30.298] INFO: Done with hot pixel readout
[13:57:43.294] INFO: PixTest:: pg_setup set to default.






















[13:57:43.296] INFO: 260 hot pixels found in step 2
[13:57:44.296] INFO: Collecting data for 5 seconds...
[13:57:49.315] INFO: Done with hot pixel readout
[13:58:02.514] INFO: PixTest:: pg_setup set to default.


























[13:58:02.516] INFO: 282 hot pixels found in step 3
[13:58:03.516] INFO: Collecting data for 5 seconds...
[13:58:08.534] INFO: Done with hot pixel readout
[13:58:21.522] INFO: PixTest:: pg_setup set to default.

































[13:58:21.523] INFO: 249 hot pixels found in step 4
[13:58:22.523] INFO: Collecting data for 5 seconds...
[13:58:27.539] INFO: Done with hot pixel readout
[13:58:40.427] INFO: PixTest:: pg_setup set to default.

































[13:58:40.428] INFO: 199 hot pixels found in step 5
[13:58:41.428] INFO: Collecting data for 5 seconds...
[13:58:46.444] INFO: Done with hot pixel readout
[13:58:59.438] INFO: PixTest:: pg_setup set to default.










































[13:58:59.440] INFO: 217 hot pixels found in step 6
[13:59:00.440] INFO: Collecting data for 5 seconds...
[13:59:05.456] INFO: Done with hot pixel readout
[13:59:18.432] INFO: PixTest:: pg_setup set to default.




















































[13:59:18.434] INFO: 210 hot pixels found in step 7
[13:59:19.434] INFO: Collecting data for 5 seconds...
[13:59:24.450] INFO: Done with hot pixel readout
[13:59:37.382] INFO: PixTest:: pg_setup set to default.




















































[13:59:37.384] INFO: 187 hot pixels found in step 8
[13:59:38.383] INFO: Collecting data for 5 seconds...
[13:59:43.400] INFO: Done with hot pixel readout
[13:59:56.304] INFO: PixTest:: pg_setup set to default.





































































[13:59:56.306] INFO: 180 hot pixels found in step 9
[13:59:57.306] INFO: Collecting data for 5 seconds...
[14:00:02.322] INFO: Done with hot pixel readout
[14:00:14.991] INFO: PixTest:: pg_setup set to default.























































[14:00:14.993] INFO: 166 hot pixels found in step 10
[14:00:15.991] INFO: Collecting data for 5 seconds...
[14:00:21.008] INFO: Done with hot pixel readout
[14:00:33.843] INFO: PixTest:: pg_setup set to default.



























































[14:00:33.845] INFO: 159 hot pixels found in step 11
[14:00:34.845] INFO: Collecting data for 5 seconds...
[14:00:39.862] INFO: Done with hot pixel readout
[14:00:52.829] INFO: PixTest:: pg_setup set to default.



























































[14:00:52.831] INFO: 146 hot pixels found in step 12
[14:00:53.830] INFO: Collecting data for 5 seconds...
[14:00:58.846] INFO: Done with hot pixel readout
[14:01:11.662] INFO: PixTest:: pg_setup set to default.


































































[14:01:11.664] INFO: 151 hot pixels found in step 13
[14:01:12.664] INFO: Collecting data for 5 seconds...
[14:01:17.681] INFO: Done with hot pixel readout
[14:01:30.581] INFO: PixTest:: pg_setup set to default.






























































[14:01:30.583] INFO: 134 hot pixels found in step 14
[14:01:30.630] INFO: 134 hot pixels could not be trimmed and have been masked.
[14:01:30.634] INFO: PixTest::trimHotPixels() done
[14:01:30.634] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Y-F-NM_FPIXTest-17C-FNAL-160519-0937_2016-05-19_09h38m_1463668705/000_FPIXTest_p17//trimParameters35_C0.dat
[14:01:30.640] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Y-F-NM_FPIXTest-17C-FNAL-160519-0937_2016-05-19_09h38m_1463668705/000_FPIXTest_p17//trimParameters35_C1.dat
[14:01:30.648] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Y-F-NM_FPIXTest-17C-FNAL-160519-0937_2016-05-19_09h38m_1463668705/000_FPIXTest_p17//trimParameters35_C2.dat
[14:01:30.654] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Y-F-NM_FPIXTest-17C-FNAL-160519-0937_2016-05-19_09h38m_1463668705/000_FPIXTest_p17//trimParameters35_C3.dat
[14:01:30.660] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Y-F-NM_FPIXTest-17C-FNAL-160519-0937_2016-05-19_09h38m_1463668705/000_FPIXTest_p17//trimParameters35_C4.dat
[14:01:30.666] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Y-F-NM_FPIXTest-17C-FNAL-160519-0937_2016-05-19_09h38m_1463668705/000_FPIXTest_p17//trimParameters35_C5.dat
[14:01:30.672] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Y-F-NM_FPIXTest-17C-FNAL-160519-0937_2016-05-19_09h38m_1463668705/000_FPIXTest_p17//trimParameters35_C6.dat
[14:01:30.678] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Y-F-NM_FPIXTest-17C-FNAL-160519-0937_2016-05-19_09h38m_1463668705/000_FPIXTest_p17//trimParameters35_C7.dat
[14:01:30.684] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Y-F-NM_FPIXTest-17C-FNAL-160519-0937_2016-05-19_09h38m_1463668705/000_FPIXTest_p17//trimParameters35_C8.dat
[14:01:30.690] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Y-F-NM_FPIXTest-17C-FNAL-160519-0937_2016-05-19_09h38m_1463668705/000_FPIXTest_p17//trimParameters35_C9.dat
[14:01:30.696] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Y-F-NM_FPIXTest-17C-FNAL-160519-0937_2016-05-19_09h38m_1463668705/000_FPIXTest_p17//trimParameters35_C10.dat
[14:01:30.701] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Y-F-NM_FPIXTest-17C-FNAL-160519-0937_2016-05-19_09h38m_1463668705/000_FPIXTest_p17//trimParameters35_C11.dat
[14:01:30.707] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Y-F-NM_FPIXTest-17C-FNAL-160519-0937_2016-05-19_09h38m_1463668705/000_FPIXTest_p17//trimParameters35_C12.dat
[14:01:30.713] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Y-F-NM_FPIXTest-17C-FNAL-160519-0937_2016-05-19_09h38m_1463668705/000_FPIXTest_p17//trimParameters35_C13.dat
[14:01:30.719] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Y-F-NM_FPIXTest-17C-FNAL-160519-0937_2016-05-19_09h38m_1463668705/000_FPIXTest_p17//trimParameters35_C14.dat
[14:01:30.725] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Y-F-NM_FPIXTest-17C-FNAL-160519-0937_2016-05-19_09h38m_1463668705/000_FPIXTest_p17//trimParameters35_C15.dat
[14:01:30.731] INFO: write masked pixels into /home/uicpirepix2/ProductionTestResults/M-Y-F-NM_FPIXTest-17C-FNAL-160519-0937_2016-05-19_09h38m_1463668705/000_FPIXTest_p17//defaultMaskFile.dat
[14:01:30.742] INFO: enter test to run
[14:01:45.889] INFO: test: HighRate setting parameters: ->runsecondshotpixels=1;trimhotpixelthr=200<-
[14:01:45.889] INFO: running: highrate
[14:01:45.894] INFO: ----------------------------------------------------------------------
[14:01:45.894] INFO: PixTest::trimHotPixels() running for 1 seconds with 100 kHz trigger rate
[14:01:45.894] INFO: ----------------------------------------------------------------------
[14:01:45.894] INFO: THR = 200, corresponding to ~ 533.333 MHz/cm2
[14:01:45.894] INFO: edge/corner pixel THR is adjusted
[14:01:45.894] INFO: PixTestHighRate::trimHotPixels: step 0...
[14:01:46.851] INFO: Collecting data for 1 seconds...
[14:01:47.854] INFO: Done with hot pixel readout
[14:01:52.365] INFO: PixTest:: pg_setup set to default.
[14:01:52.366] INFO: 0 hot pixels found in step 0
[14:01:52.371] INFO: 0 hot pixels could not be trimmed and have been masked.
[14:01:52.436] INFO: PixTest::trimHotPixels() done
[14:01:52.437] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Y-F-NM_FPIXTest-17C-FNAL-160519-0937_2016-05-19_09h38m_1463668705/000_FPIXTest_p17//trimParameters35_C0.dat
[14:01:52.449] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Y-F-NM_FPIXTest-17C-FNAL-160519-0937_2016-05-19_09h38m_1463668705/000_FPIXTest_p17//trimParameters35_C1.dat
[14:01:52.457] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Y-F-NM_FPIXTest-17C-FNAL-160519-0937_2016-05-19_09h38m_1463668705/000_FPIXTest_p17//trimParameters35_C2.dat
[14:01:52.462] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Y-F-NM_FPIXTest-17C-FNAL-160519-0937_2016-05-19_09h38m_1463668705/000_FPIXTest_p17//trimParameters35_C3.dat
[14:01:52.468] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Y-F-NM_FPIXTest-17C-FNAL-160519-0937_2016-05-19_09h38m_1463668705/000_FPIXTest_p17//trimParameters35_C4.dat
[14:01:52.473] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Y-F-NM_FPIXTest-17C-FNAL-160519-0937_2016-05-19_09h38m_1463668705/000_FPIXTest_p17//trimParameters35_C5.dat
[14:01:52.479] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Y-F-NM_FPIXTest-17C-FNAL-160519-0937_2016-05-19_09h38m_1463668705/000_FPIXTest_p17//trimParameters35_C6.dat
[14:01:52.484] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Y-F-NM_FPIXTest-17C-FNAL-160519-0937_2016-05-19_09h38m_1463668705/000_FPIXTest_p17//trimParameters35_C7.dat
[14:01:52.489] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Y-F-NM_FPIXTest-17C-FNAL-160519-0937_2016-05-19_09h38m_1463668705/000_FPIXTest_p17//trimParameters35_C8.dat
[14:01:52.495] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Y-F-NM_FPIXTest-17C-FNAL-160519-0937_2016-05-19_09h38m_1463668705/000_FPIXTest_p17//trimParameters35_C9.dat
[14:01:52.500] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Y-F-NM_FPIXTest-17C-FNAL-160519-0937_2016-05-19_09h38m_1463668705/000_FPIXTest_p17//trimParameters35_C10.dat
[14:01:52.506] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Y-F-NM_FPIXTest-17C-FNAL-160519-0937_2016-05-19_09h38m_1463668705/000_FPIXTest_p17//trimParameters35_C11.dat
[14:01:52.511] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Y-F-NM_FPIXTest-17C-FNAL-160519-0937_2016-05-19_09h38m_1463668705/000_FPIXTest_p17//trimParameters35_C12.dat
[14:01:52.517] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Y-F-NM_FPIXTest-17C-FNAL-160519-0937_2016-05-19_09h38m_1463668705/000_FPIXTest_p17//trimParameters35_C13.dat
[14:01:52.522] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Y-F-NM_FPIXTest-17C-FNAL-160519-0937_2016-05-19_09h38m_1463668705/000_FPIXTest_p17//trimParameters35_C14.dat
[14:01:52.527] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Y-F-NM_FPIXTest-17C-FNAL-160519-0937_2016-05-19_09h38m_1463668705/000_FPIXTest_p17//trimParameters35_C15.dat
[14:01:52.533] INFO: write masked pixels into /home/uicpirepix2/ProductionTestResults/M-Y-F-NM_FPIXTest-17C-FNAL-160519-0937_2016-05-19_09h38m_1463668705/000_FPIXTest_p17//defaultMaskFile.dat
[14:01:52.543] INFO: enter test to run
[14:02:27.321] INFO: test: Xray setting parameters: ->source=DCLowRate<-
[14:02:27.321] INFO: running: xray
[14:02:27.322] INFO: ----------------------------------------------------------------------
[14:02:27.322] INFO: PixTestXray::doPhRun() fParRunSeconds = 100
[14:02:27.322] INFO: ----------------------------------------------------------------------
[14:02:28.285] INFO: PixTestXray::doPhRun start TriggerLoop with trigger frequency 100 kHz, period 405 and duration 100 seconds, fEventsMax = 10000000
[14:02:38.569] INFO: run duration 10 seconds, buffer almost full (81%), pausing triggers.
[14:03:07.786] INFO: Resuming triggers.
[14:03:18.072] INFO: run duration 20 seconds, buffer almost full (81%), pausing triggers.
[14:03:47.391] INFO: Resuming triggers.
[14:03:57.675] INFO: run duration 30 seconds, buffer almost full (81%), pausing triggers.
[14:04:27.366] INFO: Resuming triggers.
[14:04:37.652] INFO: run duration 41 seconds, buffer almost full (81%), pausing triggers.
[14:05:07.423] INFO: Resuming triggers.
[14:05:17.705] INFO: run duration 51 seconds, buffer almost full (81%), pausing triggers.
[14:05:47.550] INFO: Resuming triggers.
[14:05:57.834] INFO: run duration 61 seconds, buffer almost full (81%), pausing triggers.
[14:06:28.134] INFO: Resuming triggers.
[14:06:38.416] INFO: run duration 71 seconds, buffer almost full (81%), pausing triggers.
[14:07:07.863] INFO: Resuming triggers.
[14:07:18.148] INFO: run duration 81 seconds, buffer almost full (81%), pausing triggers.
[14:07:48.306] INFO: Resuming triggers.
[14:07:58.589] INFO: run duration 92 seconds, buffer almost full (81%), pausing triggers.
[14:08:28.836] INFO: Resuming triggers.
[14:08:36.627] INFO: data taking finished, elapsed time: 100 seconds.
[14:09:00.306] INFO: PixTest:: pg_setup set to default.
[14:09:00.310] INFO: PixTestXray::doPhRun() done
[14:09:00.472] INFO: enter test to run
[14:14:15.851] INFO: test: Xray setting parameters: ->source=DCHighRate<-
[14:14:15.852] INFO: running: xray
[14:14:15.853] INFO: ----------------------------------------------------------------------
[14:14:15.853] INFO: PixTestXray::doPhRun() fParRunSeconds = 100
[14:14:15.853] INFO: ----------------------------------------------------------------------
[14:14:16.825] INFO: PixTestXray::doPhRun start TriggerLoop with trigger frequency 100 kHz, period 405 and duration 100 seconds, fEventsMax = 10000000
[14:14:22.380] INFO: run duration 5 seconds, buffer almost full (81%), pausing triggers.
[14:14:51.766] INFO: Resuming triggers.
[14:14:57.319] INFO: run duration 11 seconds, buffer almost full (81%), pausing triggers.
[14:15:27.353] INFO: Resuming triggers.
[14:15:32.907] INFO: run duration 16 seconds, buffer almost full (81%), pausing triggers.
[14:16:02.905] INFO: Resuming triggers.
[14:16:08.456] INFO: run duration 22 seconds, buffer almost full (81%), pausing triggers.
[14:16:38.506] INFO: Resuming triggers.
[14:16:44.058] INFO: run duration 27 seconds, buffer almost full (81%), pausing triggers.
[14:17:14.080] INFO: Resuming triggers.
[14:17:19.632] INFO: run duration 33 seconds, buffer almost full (81%), pausing triggers.
[14:17:49.938] INFO: Resuming triggers.
[14:17:55.490] INFO: run duration 38 seconds, buffer almost full (81%), pausing triggers.
[14:18:24.790] INFO: Resuming triggers.
[14:18:30.343] INFO: run duration 44 seconds, buffer almost full (81%), pausing triggers.
[14:19:00.401] INFO: Resuming triggers.
[14:19:05.952] INFO: run duration 49 seconds, buffer almost full (81%), pausing triggers.
[14:19:36.014] INFO: Resuming triggers.
[14:19:41.566] INFO: run duration 55 seconds, buffer almost full (81%), pausing triggers.
[14:20:12.171] INFO: Resuming triggers.
[14:20:17.726] INFO: run duration 60 seconds, buffer almost full (81%), pausing triggers.
[14:20:47.476] INFO: Resuming triggers.
[14:20:53.034] INFO: run duration 66 seconds, buffer almost full (81%), pausing triggers.
[14:21:23.230] INFO: Resuming triggers.
[14:21:28.784] INFO: run duration 71 seconds, buffer almost full (81%), pausing triggers.
[14:21:58.680] INFO: Resuming triggers.
[14:22:04.235] INFO: run duration 77 seconds, buffer almost full (81%), pausing triggers.
[14:22:34.158] INFO: Resuming triggers.
[14:22:39.713] INFO: run duration 83 seconds, buffer almost full (81%), pausing triggers.
[14:23:09.685] INFO: Resuming triggers.
[14:23:15.238] INFO: run duration 88 seconds, buffer almost full (81%), pausing triggers.
[14:23:45.245] INFO: Resuming triggers.
[14:23:50.801] INFO: run duration 94 seconds, buffer almost full (81%), pausing triggers.
[14:24:20.756] INFO: Resuming triggers.
[14:24:26.313] INFO: run duration 99 seconds, buffer almost full (81%), pausing triggers.
[14:24:55.495] INFO: Resuming triggers.
[14:24:55.846] INFO: data taking finished, elapsed time: 100 seconds.
[14:24:58.149] INFO: PixTest:: pg_setup set to default.
[14:24:58.152] INFO: PixTestXray::doPhRun() done
[14:24:58.304] INFO: enter test to run
[14:25:54.374] INFO: test: HighRate no parameter change
[14:25:54.374] INFO: running: highrate
[14:25:54.387] INFO: ----------------------------------------------------------------------
[14:25:54.387] INFO: PixTestHighRate::calDelScan() ntrig = 10, vcal = 200
[14:25:54.387] INFO: ----------------------------------------------------------------------
[14:25:54.543] INFO: Expecting 768 events.
[14:25:55.677] INFO: 768 events read in total (419ms).
[14:25:55.677] INFO: Test took 1268ms.
[14:25:56.481] INFO: Expecting 41600 events.
[14:25:59.615] INFO: 41600 events read in total (2607ms).
[14:25:59.616] INFO: Test took 3931ms.
[14:25:59.658] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:26:00.369] INFO: Expecting 41600 events.
[14:26:03.646] INFO: 41600 events read in total (2750ms).
[14:26:03.647] INFO: Test took 3969ms.
[14:26:03.689] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:26:04.398] INFO: Expecting 41600 events.
[14:26:07.600] INFO: 41600 events read in total (2675ms).
[14:26:07.602] INFO: Test took 3892ms.
[14:26:07.645] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:26:08.353] INFO: Expecting 41600 events.
[14:26:11.530] INFO: 41600 events read in total (2650ms).
[14:26:11.531] INFO: Test took 3865ms.
[14:26:11.573] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:26:12.283] INFO: Expecting 41600 events.
[14:26:15.594] INFO: 41600 events read in total (2784ms).
[14:26:15.595] INFO: Test took 4000ms.
[14:26:15.637] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:26:16.345] INFO: Expecting 41600 events.
[14:26:19.654] INFO: 41600 events read in total (2782ms).
[14:26:19.655] INFO: Test took 3998ms.
[14:26:19.697] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:26:20.407] INFO: Expecting 41600 events.
[14:26:23.721] INFO: 41600 events read in total (2788ms).
[14:26:23.722] INFO: Test took 4003ms.
[14:26:23.765] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:26:24.474] INFO: Expecting 41600 events.
[14:26:27.762] INFO: 41600 events read in total (2761ms).
[14:26:27.763] INFO: Test took 3977ms.
[14:26:27.805] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:26:28.513] INFO: Expecting 41600 events.
[14:26:31.735] INFO: 41600 events read in total (2695ms).
[14:26:31.737] INFO: Test took 3910ms.
[14:26:31.779] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:26:32.489] INFO: Expecting 41600 events.
[14:26:35.693] INFO: 41600 events read in total (2677ms).
[14:26:35.694] INFO: Test took 3894ms.
[14:26:35.736] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:26:36.442] INFO: Expecting 41600 events.
[14:26:39.752] INFO: 41600 events read in total (2783ms).
[14:26:39.753] INFO: Test took 3996ms.
[14:26:39.796] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:26:40.504] INFO: Expecting 41600 events.
[14:26:43.846] INFO: 41600 events read in total (2815ms).
[14:26:43.847] INFO: Test took 4030ms.
[14:26:43.889] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:26:44.599] INFO: Expecting 41600 events.
[14:26:47.909] INFO: 41600 events read in total (2783ms).
[14:26:47.910] INFO: Test took 3999ms.
[14:26:47.953] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:26:48.661] INFO: Expecting 41600 events.
[14:26:51.979] INFO: 41600 events read in total (2791ms).
[14:26:51.980] INFO: Test took 4005ms.
[14:26:52.022] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:26:52.730] INFO: Expecting 41600 events.
[14:26:55.930] INFO: 41600 events read in total (2673ms).
[14:26:55.931] INFO: Test took 3887ms.
[14:26:55.973] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:26:56.683] INFO: Expecting 41600 events.
[14:26:59.997] INFO: 41600 events read in total (2787ms).
[14:26:59.998] INFO: Test took 4004ms.
[14:27:00.041] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:27:00.752] INFO: Expecting 41600 events.
[14:27:04.077] INFO: 41600 events read in total (2798ms).
[14:27:04.078] INFO: Test took 4015ms.
[14:27:04.121] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:27:04.830] INFO: Expecting 41600 events.
[14:27:08.143] INFO: 41600 events read in total (2787ms).
[14:27:08.145] INFO: Test took 4002ms.
[14:27:08.191] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:27:08.895] INFO: Expecting 41600 events.
[14:27:12.198] INFO: 41600 events read in total (2776ms).
[14:27:12.199] INFO: Test took 3987ms.
[14:27:12.242] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:27:12.949] INFO: Expecting 41600 events.
[14:27:16.156] INFO: 41600 events read in total (2680ms).
[14:27:16.157] INFO: Test took 3893ms.
[14:27:16.199] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:27:16.550] INFO: enter test to run
[14:27:26.942] INFO: test: HighRate no parameter change
[14:27:26.942] INFO: running: highrate
[14:27:26.943] INFO: ----------------------------------------------------------------------
[14:27:26.943] INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[14:27:26.943] INFO: ----------------------------------------------------------------------
[14:27:27.551] INFO: Expecting 208000 events.
[14:27:39.732] INFO: 208000 events read in total (11655ms).
[14:27:39.736] INFO: Test took 12787ms.
[14:27:39.922] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:27:40.188] INFO: number of dead pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[14:27:40.188] INFO: number of red-efficiency pixels: 119 80 132 177 191 162 182 123 136 172 163 114 162 66 32 42
[14:27:40.188] INFO: number of X-ray hits detected: 88755 60765 90583 144282 151033 153943 152215 108951 99496 125589 121395 104705 113825 65307 27455 31935
[14:27:40.188] 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:27:40.188] INFO: number of Vcal hits detected: 207876 207920 207864 207819 207803 207834 207812 207872 207862 207825 207832 207885 207834 207933 207968 207958
[14:27:40.188] 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:27:40.189] INFO: Vcal hit overall 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:27:40.189] INFO: X-ray hit rate [MHz/cm2]: 26.0 17.8 26.6 42.3 44.3 45.1 44.6 31.9 29.2 36.8 35.6 30.7 33.4 19.1 8.0 9.4
[14:27:40.189] INFO: PixTestHighRate::doXPixelAlive() done
[14:27:40.235] INFO: PixTest:: pg_setup set to default.
[14:27:40.251] INFO: enter test to run
[14:28:05.533] INFO: test: HighRate no parameter change
[14:28:05.533] INFO: running: highrate
[14:28:05.534] INFO: ----------------------------------------------------------------------
[14:28:05.534] INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[14:28:05.534] INFO: ----------------------------------------------------------------------
[14:28:06.152] INFO: Expecting 208000 events.
[14:28:20.766] INFO: 208000 events read in total (14087ms).
[14:28:20.773] INFO: Test took 15232ms.
[14:28:21.159] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:28:21.491] INFO: number of dead pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[14:28:21.491] INFO: number of red-efficiency pixels: 324 181 460 632 745 742 756 424 416 642 459 363 502 229 69 94
[14:28:21.491] INFO: number of X-ray hits detected: 186226 127035 190142 301435 314844 319595 317221 225528 208966 262758 254180 218628 237884 136511 57845 66490
[14:28:21.491] 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:28:21.491] INFO: number of Vcal hits detected: 207644 207810 207473 207294 207157 207123 207134 207540 207546 207272 207515 207607 207452 207744 207930 207904
[14:28:21.491] INFO: Vcal hit fiducial efficiency (%): 99.8 99.9 99.8 99.7 99.6 99.6 99.6 99.8 99.8 99.7 99.8 99.8 99.8 99.9 100.0 100.0
[14:28:21.491] INFO: Vcal hit overall efficiency (%): 99.8 99.9 99.7 99.7 99.6 99.6 99.6 99.8 99.8 99.7 99.8 99.8 99.7 99.9 100.0 100.0
[14:28:21.491] INFO: X-ray hit rate [MHz/cm2]: 54.6 37.2 55.7 88.4 92.3 93.7 93.0 66.1 61.2 77.0 74.5 64.1 69.7 40.0 17.0 19.5
[14:28:21.491] INFO: PixTestHighRate::doXPixelAlive() done
[14:28:21.539] INFO: PixTest:: pg_setup set to default.
[14:28:21.556] INFO: enter test to run
[14:29:27.117] INFO: test: HighRate no parameter change
[14:29:27.117] INFO: running: highrate
[14:29:27.118] INFO: ----------------------------------------------------------------------
[14:29:27.118] INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[14:29:27.118] INFO: ----------------------------------------------------------------------
[14:29:27.735] INFO: Expecting 208000 events.
[14:29:44.467] INFO: 208000 events read in total (16205ms).
[14:29:44.477] INFO: Test took 17350ms.
[14:29:45.107] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:29:45.506] INFO: number of dead pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[14:29:45.506] INFO: number of red-efficiency pixels: 688 394 941 1530 1764 1775 1718 938 987 1579 1141 797 1060 518 133 133
[14:29:45.506] INFO: number of X-ray hits detected: 279834 191069 287140 453125 473192 480031 477888 340987 314450 396228 382941 330122 357905 207215 87776 100452
[14:29:45.506] 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:29:45.506] INFO: number of Vcal hits detected: 207073 207554 206485 205939 205305 205321 205437 206725 206774 205862 206577 207040 206637 207378 207858 207865
[14:29:45.506] INFO: Vcal hit fiducial efficiency (%): 99.6 99.8 99.4 99.1 98.9 98.8 98.9 99.5 99.5 99.1 99.4 99.6 99.4 99.7 99.9 99.9
[14:29:45.506] INFO: Vcal hit overall efficiency (%): 99.6 99.8 99.3 99.0 98.7 98.7 98.8 99.4 99.4 99.0 99.3 99.5 99.3 99.7 99.9 99.9
[14:29:45.506] INFO: X-ray hit rate [MHz/cm2]: 82.0 56.0 84.2 132.8 138.7 140.7 140.1 99.9 92.2 116.1 112.2 96.8 104.9 60.7 25.7 29.4
[14:29:45.506] INFO: PixTestHighRate::doXPixelAlive() done
[14:29:45.554] INFO: PixTest:: pg_setup set to default.
[14:29:45.572] INFO: enter test to run
[14:29:52.044] INFO: test: exit no parameter change
[14:29:52.380] QUIET: Connection to board 33 closed.
[14:29:52.391] INFO: pXar: this is the end, my friend