[17:03:31.341] INFO: *** Welcome to pxar ***
[17:03:31.342] INFO: *** Today: 2016/05/06
[17:03:31.477] INFO: *** Version: v1.9.0-796-gef167-dirty
[17:03:31.477] INFO: readRocDacs: /home/uicpirepix2/ProductionTestResults/M-Y-B-NB_FPIXTest-17C-FNAL-160429-1212_2016-04-29_12h12m_1461949956/000_FPIXTest_p17//dacParameters35_C0.dat .. /home/uicpirepix2/ProductionTestResults/M-Y-B-NB_FPIXTest-17C-FNAL-160429-1212_2016-04-29_12h12m_1461949956/000_FPIXTest_p17//dacParameters35_C15.dat
[17:03:31.506] INFO: readTbmDacs: /home/uicpirepix2/ProductionTestResults/M-Y-B-NB_FPIXTest-17C-FNAL-160429-1212_2016-04-29_12h12m_1461949956/000_FPIXTest_p17//tbmParameters_C0a.dat .. /home/uicpirepix2/ProductionTestResults/M-Y-B-NB_FPIXTest-17C-FNAL-160429-1212_2016-04-29_12h12m_1461949956/000_FPIXTest_p17//tbmParameters_C0b.dat
[17:03:31.506] INFO: readMaskFile: /home/uicpirepix2/ProductionTestResults/M-Y-B-NB_FPIXTest-17C-FNAL-160429-1212_2016-04-29_12h12m_1461949956/000_FPIXTest_p17//defaultMaskFile.dat
[17:03:31.507] INFO: readTrimFile: /home/uicpirepix2/ProductionTestResults/M-Y-B-NB_FPIXTest-17C-FNAL-160429-1212_2016-04-29_12h12m_1461949956/000_FPIXTest_p17//trimParameters35_C0.dat .. /home/uicpirepix2/ProductionTestResults/M-Y-B-NB_FPIXTest-17C-FNAL-160429-1212_2016-04-29_12h12m_1461949956/000_FPIXTest_p17//trimParameters35_C15.dat
[17:03:31.612] INFO: clk: 4
[17:03:31.612] INFO: ctr: 4
[17:03:31.612] INFO: sda: 19
[17:03:31.612] INFO: tin: 9
[17:03:31.612] INFO: level: 15
[17:03:31.612] INFO: triggerdelay: 0
[17:03:31.612] QUIET: Instanciating API for pxar v1.9.0+796~gef16747
[17:03:31.612] INFO: Log level: INFO
[17:03:31.628] QUIET: Connection to board DTB_WREKRL opened.
[17:03:31.631] 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:03:31.634] INFO: RPC call hashes of host and DTB match: 398089610
[17:03:33.166] INFO: DUT info:
[17:03:33.166] INFO: The DUT currently contains the following objects:
[17:03:33.166] INFO: 2 TBM Cores tbm08c (2 ON)
[17:03:33.167] INFO: TBM Core alpha (0): 7 registers set
[17:03:33.167] INFO: TBM Core beta (1): 7 registers set
[17:03:33.167] INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[17:03:33.167] INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[17:03:33.167] INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[17:03:33.167] INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[17:03:33.167] INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[17:03:33.167] INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[17:03:33.167] INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[17:03:33.167] INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[17:03:33.167] INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[17:03:33.167] INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[17:03:33.167] INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[17:03:33.167] INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[17:03:33.167] INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[17:03:33.167] INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[17:03:33.167] INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[17:03:33.167] INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[17:03:33.167] INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[17:03:33.593] INFO: enter 'restricted' command line mode
[17:03:33.593] INFO: enter test to run
[17:03:42.430] INFO: test: PixelAlive no parameter change
[17:03:42.430] INFO: running: pixelalive
[17:03:42.439] INFO: ----------------------------------------------------------------------
[17:03:42.439] 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:03:42.440] INFO: ----------------------------------------------------------------------
[17:03:42.765] INFO: Expecting 41600 events.
[17:03:47.110] INFO: 41600 events read in total (3627ms).
[17:03:47.273] INFO: Test took 4831ms.
[17:03:47.283] INFO: Fetched DAQ statistics. Counters are being reset now.
[17:03:47.544] INFO: PixTestAlive::aliveTest() done
[17:03:47.544] INFO: number of dead pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[17:03:47.594] INFO: enter test to run
[17:05:14.781] INFO: test: HighRate setting parameters: ->runsecondshotpixels=5;trimhotpixelthr=200<-
[17:05:14.782] INFO: running: highrate
[17:05:14.782] INFO: readGainPedestalParameters /home/uicpirepix2/ProductionTestResults/M-Y-B-NB_FPIXTest-17C-FNAL-160429-1212_2016-04-29_12h12m_1461949956/000_FPIXTest_p17//phCalibrationFitErr35_C0.dat .. /home/uicpirepix2/ProductionTestResults/M-Y-B-NB_FPIXTest-17C-FNAL-160429-1212_2016-04-29_12h12m_1461949956/000_FPIXTest_p17//phCalibrationFitErr35_C15.dat
[17:05:15.065] INFO: ----------------------------------------------------------------------
[17:05:15.065] INFO: PixTest::trimHotPixels() running for 5 seconds with 100 kHz trigger rate
[17:05:15.065] INFO: ----------------------------------------------------------------------
[17:05:15.065] INFO: THR = 200, corresponding to ~ 106.667 MHz/cm2
[17:05:15.065] INFO: edge/corner pixel THR is adjusted
[17:05:15.065] INFO: PixTestHighRate::trimHotPixels: step 0...
[17:05:16.023] INFO: Collecting data for 5 seconds...
[17:05:21.040] INFO: Done with hot pixel readout
[17:05:34.555] INFO: PixTest:: pg_setup set to default.





























[17:05:34.590] INFO: 890 hot pixels found in step 0
[17:05:35.589] INFO: Collecting data for 5 seconds...
[17:05:40.606] INFO: Done with hot pixel readout
[17:05:53.884] INFO: PixTest:: pg_setup set to default.























































[17:05:53.887] INFO: 852 hot pixels found in step 1
[17:05:54.888] INFO: Collecting data for 5 seconds...
[17:05:59.904] INFO: Done with hot pixel readout
[17:06:13.191] INFO: PixTest:: pg_setup set to default.
















































































[17:06:13.195] INFO: 762 hot pixels found in step 2
[17:06:14.201] INFO: Collecting data for 5 seconds...
[17:06:19.217] INFO: Done with hot pixel readout
[17:06:32.504] INFO: PixTest:: pg_setup set to default.


































































































[17:06:32.508] INFO: 702 hot pixels found in step 3
[17:06:33.513] INFO: Collecting data for 5 seconds...
[17:06:38.530] INFO: Done with hot pixel readout
[17:06:51.824] INFO: PixTest:: pg_setup set to default.







































































































































[17:06:51.828] INFO: 627 hot pixels found in step 4
[17:06:52.832] INFO: Collecting data for 5 seconds...
[17:06:57.849] INFO: Done with hot pixel readout
[17:07:11.122] INFO: PixTest:: pg_setup set to default.






































































































































































[17:07:11.134] INFO: 593 hot pixels found in step 5
[17:07:12.146] INFO: Collecting data for 5 seconds...
[17:07:17.163] INFO: Done with hot pixel readout
[17:07:30.415] INFO: PixTest:: pg_setup set to default.




























































































































































































[17:07:30.429] INFO: 589 hot pixels found in step 6
[17:07:31.440] INFO: Collecting data for 5 seconds...
[17:07:36.458] INFO: Done with hot pixel readout
[17:07:49.727] INFO: PixTest:: pg_setup set to default.






































































































































































































[17:07:49.744] INFO: 525 hot pixels found in step 7
[17:07:50.754] INFO: Collecting data for 5 seconds...
[17:07:55.770] INFO: Done with hot pixel readout
[17:08:08.972] INFO: PixTest:: pg_setup set to default.










































































































































































































[17:08:08.987] INFO: 499 hot pixels found in step 8
[17:08:09.996] INFO: Collecting data for 5 seconds...
[17:08:15.012] INFO: Done with hot pixel readout
[17:08:28.313] INFO: PixTest:: pg_setup set to default.




















































































































































































































[17:08:28.327] INFO: 454 hot pixels found in step 9
[17:08:29.333] INFO: Collecting data for 5 seconds...
[17:08:34.349] INFO: Done with hot pixel readout
[17:08:47.631] INFO: PixTest:: pg_setup set to default.





































































































































































































































[17:08:47.648] INFO: 424 hot pixels found in step 10
[17:08:48.656] INFO: Collecting data for 5 seconds...
[17:08:53.673] INFO: Done with hot pixel readout
[17:09:06.907] INFO: PixTest:: pg_setup set to default.



































































































































































































































[17:09:06.925] INFO: 418 hot pixels found in step 11
[17:09:07.936] INFO: Collecting data for 5 seconds...
[17:09:12.953] INFO: Done with hot pixel readout
[17:09:26.242] INFO: PixTest:: pg_setup set to default.







































































































































































































































[17:09:26.257] INFO: 388 hot pixels found in step 12
[17:09:27.266] INFO: Collecting data for 5 seconds...
[17:09:32.283] INFO: Done with hot pixel readout
[17:09:45.529] INFO: PixTest:: pg_setup set to default.

































































































































































































































[17:09:45.547] INFO: 371 hot pixels found in step 13
[17:09:46.555] INFO: Collecting data for 5 seconds...
[17:09:51.572] INFO: Done with hot pixel readout
[17:10:04.822] INFO: PixTest:: pg_setup set to default.


































































































































































































































[17:10:04.838] INFO: 357 hot pixels found in step 14
[17:10:04.894] INFO: 357 hot pixels could not be trimmed and have been masked.
[17:10:04.898] INFO: PixTest::trimHotPixels() done
[17:10:04.907] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Y-B-NB_FPIXTest-17C-FNAL-160429-1212_2016-04-29_12h12m_1461949956/000_FPIXTest_p17//trimParameters35_C0.dat
[17:10:04.916] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Y-B-NB_FPIXTest-17C-FNAL-160429-1212_2016-04-29_12h12m_1461949956/000_FPIXTest_p17//trimParameters35_C1.dat
[17:10:04.924] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Y-B-NB_FPIXTest-17C-FNAL-160429-1212_2016-04-29_12h12m_1461949956/000_FPIXTest_p17//trimParameters35_C2.dat
[17:10:04.929] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Y-B-NB_FPIXTest-17C-FNAL-160429-1212_2016-04-29_12h12m_1461949956/000_FPIXTest_p17//trimParameters35_C3.dat
[17:10:04.935] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Y-B-NB_FPIXTest-17C-FNAL-160429-1212_2016-04-29_12h12m_1461949956/000_FPIXTest_p17//trimParameters35_C4.dat
[17:10:04.940] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Y-B-NB_FPIXTest-17C-FNAL-160429-1212_2016-04-29_12h12m_1461949956/000_FPIXTest_p17//trimParameters35_C5.dat
[17:10:04.946] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Y-B-NB_FPIXTest-17C-FNAL-160429-1212_2016-04-29_12h12m_1461949956/000_FPIXTest_p17//trimParameters35_C6.dat
[17:10:04.951] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Y-B-NB_FPIXTest-17C-FNAL-160429-1212_2016-04-29_12h12m_1461949956/000_FPIXTest_p17//trimParameters35_C7.dat
[17:10:04.956] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Y-B-NB_FPIXTest-17C-FNAL-160429-1212_2016-04-29_12h12m_1461949956/000_FPIXTest_p17//trimParameters35_C8.dat
[17:10:04.961] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Y-B-NB_FPIXTest-17C-FNAL-160429-1212_2016-04-29_12h12m_1461949956/000_FPIXTest_p17//trimParameters35_C9.dat
[17:10:04.967] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Y-B-NB_FPIXTest-17C-FNAL-160429-1212_2016-04-29_12h12m_1461949956/000_FPIXTest_p17//trimParameters35_C10.dat
[17:10:04.972] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Y-B-NB_FPIXTest-17C-FNAL-160429-1212_2016-04-29_12h12m_1461949956/000_FPIXTest_p17//trimParameters35_C11.dat
[17:10:04.977] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Y-B-NB_FPIXTest-17C-FNAL-160429-1212_2016-04-29_12h12m_1461949956/000_FPIXTest_p17//trimParameters35_C12.dat
[17:10:04.983] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Y-B-NB_FPIXTest-17C-FNAL-160429-1212_2016-04-29_12h12m_1461949956/000_FPIXTest_p17//trimParameters35_C13.dat
[17:10:04.988] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Y-B-NB_FPIXTest-17C-FNAL-160429-1212_2016-04-29_12h12m_1461949956/000_FPIXTest_p17//trimParameters35_C14.dat
[17:10:04.993] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Y-B-NB_FPIXTest-17C-FNAL-160429-1212_2016-04-29_12h12m_1461949956/000_FPIXTest_p17//trimParameters35_C15.dat
[17:10:04.999] INFO: write masked pixels into /home/uicpirepix2/ProductionTestResults/M-Y-B-NB_FPIXTest-17C-FNAL-160429-1212_2016-04-29_12h12m_1461949956/000_FPIXTest_p17//defaultMaskFile.dat
[17:10:05.017] INFO: enter test to run
[17:12:01.282] INFO: test: HighRate setting parameters: ->runsecondshotpixels=1;trimhotpixelthr=200<-
[17:12:01.282] INFO: running: highrate
[17:12:01.287] INFO: ----------------------------------------------------------------------
[17:12:01.287] INFO: PixTest::trimHotPixels() running for 1 seconds with 100 kHz trigger rate
[17:12:01.287] INFO: ----------------------------------------------------------------------
[17:12:01.287] INFO: THR = 200, corresponding to ~ 533.333 MHz/cm2
[17:12:01.287] INFO: edge/corner pixel THR is adjusted
[17:12:01.287] INFO: PixTestHighRate::trimHotPixels: step 0...
[17:12:02.244] INFO: Collecting data for 1 seconds...
[17:12:03.248] INFO: Done with hot pixel readout
[17:12:07.377] INFO: PixTest:: pg_setup set to default.
[17:12:07.378] INFO: 0 hot pixels found in step 0
[17:12:07.384] INFO: 0 hot pixels could not be trimmed and have been masked.
[17:12:07.434] INFO: PixTest::trimHotPixels() done
[17:12:07.434] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Y-B-NB_FPIXTest-17C-FNAL-160429-1212_2016-04-29_12h12m_1461949956/000_FPIXTest_p17//trimParameters35_C0.dat
[17:12:07.441] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Y-B-NB_FPIXTest-17C-FNAL-160429-1212_2016-04-29_12h12m_1461949956/000_FPIXTest_p17//trimParameters35_C1.dat
[17:12:07.447] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Y-B-NB_FPIXTest-17C-FNAL-160429-1212_2016-04-29_12h12m_1461949956/000_FPIXTest_p17//trimParameters35_C2.dat
[17:12:07.453] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Y-B-NB_FPIXTest-17C-FNAL-160429-1212_2016-04-29_12h12m_1461949956/000_FPIXTest_p17//trimParameters35_C3.dat
[17:12:07.459] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Y-B-NB_FPIXTest-17C-FNAL-160429-1212_2016-04-29_12h12m_1461949956/000_FPIXTest_p17//trimParameters35_C4.dat
[17:12:07.464] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Y-B-NB_FPIXTest-17C-FNAL-160429-1212_2016-04-29_12h12m_1461949956/000_FPIXTest_p17//trimParameters35_C5.dat
[17:12:07.470] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Y-B-NB_FPIXTest-17C-FNAL-160429-1212_2016-04-29_12h12m_1461949956/000_FPIXTest_p17//trimParameters35_C6.dat
[17:12:07.476] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Y-B-NB_FPIXTest-17C-FNAL-160429-1212_2016-04-29_12h12m_1461949956/000_FPIXTest_p17//trimParameters35_C7.dat
[17:12:07.482] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Y-B-NB_FPIXTest-17C-FNAL-160429-1212_2016-04-29_12h12m_1461949956/000_FPIXTest_p17//trimParameters35_C8.dat
[17:12:07.487] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Y-B-NB_FPIXTest-17C-FNAL-160429-1212_2016-04-29_12h12m_1461949956/000_FPIXTest_p17//trimParameters35_C9.dat
[17:12:07.493] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Y-B-NB_FPIXTest-17C-FNAL-160429-1212_2016-04-29_12h12m_1461949956/000_FPIXTest_p17//trimParameters35_C10.dat
[17:12:07.498] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Y-B-NB_FPIXTest-17C-FNAL-160429-1212_2016-04-29_12h12m_1461949956/000_FPIXTest_p17//trimParameters35_C11.dat
[17:12:07.504] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Y-B-NB_FPIXTest-17C-FNAL-160429-1212_2016-04-29_12h12m_1461949956/000_FPIXTest_p17//trimParameters35_C12.dat
[17:12:07.509] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Y-B-NB_FPIXTest-17C-FNAL-160429-1212_2016-04-29_12h12m_1461949956/000_FPIXTest_p17//trimParameters35_C13.dat
[17:12:07.515] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Y-B-NB_FPIXTest-17C-FNAL-160429-1212_2016-04-29_12h12m_1461949956/000_FPIXTest_p17//trimParameters35_C14.dat
[17:12:07.521] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Y-B-NB_FPIXTest-17C-FNAL-160429-1212_2016-04-29_12h12m_1461949956/000_FPIXTest_p17//trimParameters35_C15.dat
[17:12:07.526] INFO: write masked pixels into /home/uicpirepix2/ProductionTestResults/M-Y-B-NB_FPIXTest-17C-FNAL-160429-1212_2016-04-29_12h12m_1461949956/000_FPIXTest_p17//defaultMaskFile.dat
[17:12:07.536] INFO: enter test to run
[17:12:56.506] INFO: test: Xray setting parameters: ->source=DCLowRate<-
[17:12:56.506] INFO: running: xray
[17:12:56.507] INFO: ----------------------------------------------------------------------
[17:12:56.507] INFO: PixTestXray::doPhRun() fParRunSeconds = 100
[17:12:56.507] INFO: ----------------------------------------------------------------------
[17:12:57.471] INFO: PixTestXray::doPhRun start TriggerLoop with trigger frequency 100 kHz, period 405 and duration 100 seconds, fEventsMax = 10000000
[17:13:07.879] INFO: run duration 10 seconds, buffer almost full (81%), pausing triggers.
[17:13:37.336] INFO: Resuming triggers.
[17:13:47.746] INFO: run duration 20 seconds, buffer almost full (81%), pausing triggers.
[17:14:17.466] INFO: Resuming triggers.
[17:14:27.877] INFO: run duration 31 seconds, buffer almost full (81%), pausing triggers.
[17:14:57.979] INFO: Resuming triggers.
[17:15:08.393] INFO: run duration 41 seconds, buffer almost full (81%), pausing triggers.
[17:15:38.323] INFO: Resuming triggers.
[17:15:48.730] INFO: run duration 51 seconds, buffer almost full (81%), pausing triggers.
[17:16:19.183] INFO: Resuming triggers.
[17:16:29.594] INFO: run duration 62 seconds, buffer almost full (81%), pausing triggers.
[17:16:59.195] INFO: Resuming triggers.
[17:17:09.605] INFO: run duration 72 seconds, buffer almost full (81%), pausing triggers.
[17:17:39.179] INFO: Resuming triggers.
[17:17:49.586] INFO: run duration 82 seconds, buffer almost full (81%), pausing triggers.
[17:18:19.147] INFO: Resuming triggers.
[17:18:29.555] INFO: run duration 93 seconds, buffer almost full (81%), pausing triggers.
[17:18:59.093] INFO: Resuming triggers.
[17:19:05.754] INFO: data taking finished, elapsed time: 100 seconds.
[17:19:24.988] INFO: PixTest:: pg_setup set to default.
[17:19:24.991] INFO: PixTestXray::doPhRun() done
[17:19:25.132] INFO: enter test to run
[17:19:51.927] INFO: test: Xray setting parameters: ->source=DCHighRate<-
[17:19:51.927] INFO: running: xray
[17:19:51.928] INFO: ----------------------------------------------------------------------
[17:19:51.928] INFO: PixTestXray::doPhRun() fParRunSeconds = 100
[17:19:51.928] INFO: ----------------------------------------------------------------------
[17:19:52.900] INFO: PixTestXray::doPhRun start TriggerLoop with trigger frequency 100 kHz, period 405 and duration 100 seconds, fEventsMax = 10000000
[17:19:58.662] INFO: run duration 5 seconds, buffer almost full (81%), pausing triggers.
[17:20:28.821] INFO: Resuming triggers.
[17:20:34.585] INFO: run duration 11 seconds, buffer almost full (81%), pausing triggers.
[17:21:04.767] INFO: Resuming triggers.
[17:21:10.530] INFO: run duration 17 seconds, buffer almost full (81%), pausing triggers.
[17:21:40.738] INFO: Resuming triggers.
[17:21:46.499] INFO: run duration 22 seconds, buffer almost full (81%), pausing triggers.
[17:22:16.723] INFO: Resuming triggers.
[17:22:22.490] INFO: run duration 28 seconds, buffer almost full (81%), pausing triggers.
[17:22:53.336] INFO: Resuming triggers.
[17:22:59.101] INFO: run duration 34 seconds, buffer almost full (81%), pausing triggers.
[17:23:28.762] INFO: Resuming triggers.
[17:23:34.528] INFO: run duration 40 seconds, buffer almost full (81%), pausing triggers.
[17:24:04.738] INFO: Resuming triggers.
[17:24:10.506] INFO: run duration 45 seconds, buffer almost full (81%), pausing triggers.
[17:24:40.730] INFO: Resuming triggers.
[17:24:46.500] INFO: run duration 51 seconds, buffer almost full (81%), pausing triggers.
[17:25:16.776] INFO: Resuming triggers.
[17:25:22.539] INFO: run duration 57 seconds, buffer almost full (81%), pausing triggers.
[17:25:53.495] INFO: Resuming triggers.
[17:25:59.261] INFO: run duration 63 seconds, buffer almost full (81%), pausing triggers.
[17:26:30.456] INFO: Resuming triggers.
[17:26:36.223] INFO: run duration 68 seconds, buffer almost full (81%), pausing triggers.
[17:27:07.460] INFO: Resuming triggers.
[17:27:13.228] INFO: run duration 74 seconds, buffer almost full (81%), pausing triggers.
[17:27:43.456] INFO: Resuming triggers.
[17:27:49.220] INFO: run duration 80 seconds, buffer almost full (81%), pausing triggers.
[17:28:19.361] INFO: Resuming triggers.
[17:28:25.126] INFO: run duration 86 seconds, buffer almost full (81%), pausing triggers.
[17:28:55.333] INFO: Resuming triggers.
[17:29:01.096] INFO: run duration 91 seconds, buffer almost full (81%), pausing triggers.
[17:29:31.282] INFO: Resuming triggers.
[17:29:37.043] INFO: run duration 97 seconds, buffer almost full (81%), pausing triggers.
[17:30:07.446] INFO: Resuming triggers.
[17:30:09.789] INFO: data taking finished, elapsed time: 100 seconds.
[17:30:22.348] INFO: PixTest:: pg_setup set to default.
[17:30:22.352] INFO: PixTestXray::doPhRun() done
[17:30:22.505] INFO: enter test to run
[17:32:31.705] INFO: test: HighRate no parameter change
[17:32:31.705] INFO: running: highrate
[17:32:31.706] INFO: ----------------------------------------------------------------------
[17:32:31.706] INFO: PixTestHighRate::calDelScan() ntrig = 10, vcal = 200
[17:32:31.706] INFO: ----------------------------------------------------------------------
[17:32:31.847] INFO: Expecting 768 events.
[17:32:32.982] INFO: 768 events read in total (419ms).
[17:32:32.982] INFO: Test took 1269ms.
[17:32:33.786] INFO: Expecting 41600 events.
[17:32:36.974] INFO: 41600 events read in total (2661ms).
[17:32:36.975] INFO: Test took 3984ms.
[17:32:37.018] INFO: Fetched DAQ statistics. Counters are being reset now.
[17:32:37.728] INFO: Expecting 41600 events.
[17:32:41.009] INFO: 41600 events read in total (2754ms).
[17:32:41.010] INFO: Test took 3970ms.
[17:32:41.054] INFO: Fetched DAQ statistics. Counters are being reset now.
[17:32:41.760] INFO: Expecting 41600 events.
[17:32:45.090] INFO: 41600 events read in total (2803ms).
[17:32:45.091] INFO: Test took 4015ms.
[17:32:45.135] INFO: Fetched DAQ statistics. Counters are being reset now.
[17:32:45.840] INFO: Expecting 41600 events.
[17:32:49.184] INFO: 41600 events read in total (2817ms).
[17:32:49.185] INFO: Test took 4028ms.
[17:32:49.228] INFO: Fetched DAQ statistics. Counters are being reset now.
[17:32:49.935] INFO: Expecting 41600 events.
[17:32:53.276] INFO: 41600 events read in total (2814ms).
[17:32:53.277] INFO: Test took 4029ms.
[17:32:53.322] INFO: Fetched DAQ statistics. Counters are being reset now.
[17:32:54.025] INFO: Expecting 41600 events.
[17:32:57.366] INFO: 41600 events read in total (2814ms).
[17:32:57.367] INFO: Test took 4023ms.
[17:32:57.410] INFO: Fetched DAQ statistics. Counters are being reset now.
[17:32:58.115] INFO: Expecting 41600 events.
[17:33:01.462] INFO: 41600 events read in total (2821ms).
[17:33:01.464] INFO: Test took 4032ms.
[17:33:01.507] INFO: Fetched DAQ statistics. Counters are being reset now.
[17:33:02.214] INFO: Expecting 41600 events.
[17:33:05.553] INFO: 41600 events read in total (2812ms).
[17:33:05.555] INFO: Test took 4028ms.
[17:33:05.599] INFO: Fetched DAQ statistics. Counters are being reset now.
[17:33:06.303] INFO: Expecting 41600 events.
[17:33:09.643] INFO: 41600 events read in total (2813ms).
[17:33:09.644] INFO: Test took 4023ms.
[17:33:09.688] INFO: Fetched DAQ statistics. Counters are being reset now.
[17:33:10.391] INFO: Expecting 41600 events.
[17:33:13.735] INFO: 41600 events read in total (2817ms).
[17:33:13.736] INFO: Test took 4026ms.
[17:33:13.780] INFO: Fetched DAQ statistics. Counters are being reset now.
[17:33:14.484] INFO: Expecting 41600 events.
[17:33:17.827] INFO: 41600 events read in total (2816ms).
[17:33:17.828] INFO: Test took 4026ms.
[17:33:17.873] INFO: Fetched DAQ statistics. Counters are being reset now.
[17:33:18.578] INFO: Expecting 41600 events.
[17:33:21.941] INFO: 41600 events read in total (2836ms).
[17:33:21.943] INFO: Test took 4048ms.
[17:33:21.999] INFO: Fetched DAQ statistics. Counters are being reset now.
[17:33:22.668] INFO: Expecting 41600 events.
[17:33:26.029] INFO: 41600 events read in total (2834ms).
[17:33:26.031] INFO: Test took 4002ms.
[17:33:26.075] INFO: Fetched DAQ statistics. Counters are being reset now.
[17:33:26.773] INFO: Expecting 41600 events.
[17:33:30.116] INFO: 41600 events read in total (2816ms).
[17:33:30.117] INFO: Test took 4021ms.
[17:33:30.162] INFO: Fetched DAQ statistics. Counters are being reset now.
[17:33:30.863] INFO: Expecting 41600 events.
[17:33:34.207] INFO: 41600 events read in total (2817ms).
[17:33:34.208] INFO: Test took 4025ms.
[17:33:34.254] INFO: Fetched DAQ statistics. Counters are being reset now.
[17:33:34.954] INFO: Expecting 41600 events.
[17:33:38.294] INFO: 41600 events read in total (2813ms).
[17:33:38.296] INFO: Test took 4021ms.
[17:33:38.340] INFO: Fetched DAQ statistics. Counters are being reset now.
[17:33:39.034] INFO: Expecting 41600 events.
[17:33:42.412] INFO: 41600 events read in total (2851ms).
[17:33:42.413] INFO: Test took 4050ms.
[17:33:42.458] INFO: Fetched DAQ statistics. Counters are being reset now.
[17:33:43.155] INFO: Expecting 41600 events.
[17:33:46.467] INFO: 41600 events read in total (2785ms).
[17:33:46.468] INFO: Test took 3989ms.
[17:33:46.513] INFO: Fetched DAQ statistics. Counters are being reset now.
[17:33:47.206] INFO: Expecting 41600 events.
[17:33:50.531] INFO: 41600 events read in total (2798ms).
[17:33:50.532] INFO: Test took 3998ms.
[17:33:50.576] INFO: Fetched DAQ statistics. Counters are being reset now.
[17:33:51.281] INFO: Expecting 41600 events.
[17:33:54.456] INFO: 41600 events read in total (2648ms).
[17:33:54.458] INFO: Test took 3860ms.
[17:33:54.502] INFO: Fetched DAQ statistics. Counters are being reset now.
[17:33:54.847] INFO: enter test to run
[17:34:05.545] INFO: test: HighRate no parameter change
[17:34:05.545] INFO: running: highrate
[17:34:05.546] INFO: ----------------------------------------------------------------------
[17:34:05.546] INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[17:34:05.546] INFO: ----------------------------------------------------------------------
[17:34:06.166] INFO: Expecting 208000 events.
[17:34:18.709] INFO: 208000 events read in total (12017ms).
[17:34:18.713] INFO: Test took 13159ms.
[17:34:18.914] INFO: Fetched DAQ statistics. Counters are being reset now.
[17:34:19.185] INFO: number of dead pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[17:34:19.185] INFO: number of red-efficiency pixels: 139 51 134 233 215 230 250 150 135 182 177 140 122 72 28 36
[17:34:19.186] INFO: number of X-ray hits detected: 86547 59928 96800 147844 160894 161649 169443 110864 106131 126933 127134 108117 110547 69642 29548 33105
[17:34:19.186] INFO: number of triggers sent (total per ROC): 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000
[17:34:19.186] INFO: number of Vcal hits detected: 207854 207948 207860 207759 207779 207762 207742 207844 207865 207812 207817 207857 207876 207927 207972 207964
[17:34:19.186] 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
[17:34:19.186] 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
[17:34:19.186] INFO: X-ray hit rate [MHz/cm2]: 25.4 17.6 28.4 43.3 47.2 47.4 49.7 32.5 31.1 37.2 37.3 31.7 32.4 20.4 8.7 9.7
[17:34:19.186] INFO: PixTestHighRate::doXPixelAlive() done
[17:34:19.232] INFO: PixTest:: pg_setup set to default.
[17:34:19.250] INFO: enter test to run
[17:34:38.176] INFO: test: HighRate no parameter change
[17:34:38.176] INFO: running: highrate
[17:34:38.178] INFO: ----------------------------------------------------------------------
[17:34:38.178] INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[17:34:38.178] INFO: ----------------------------------------------------------------------
[17:34:38.792] INFO: Expecting 208000 events.
[17:34:53.572] INFO: 208000 events read in total (14253ms).
[17:34:53.578] INFO: Test took 15390ms.
[17:34:53.949] INFO: Fetched DAQ statistics. Counters are being reset now.
[17:34:54.278] INFO: number of dead pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[17:34:54.279] INFO: number of red-efficiency pixels: 367 172 426 698 827 883 864 486 327 656 506 384 436 263 89 77
[17:34:54.279] INFO: number of X-ray hits detected: 172657 119899 194186 295572 321522 321557 335353 222242 213199 254353 253966 216552 221514 139589 58727 65291
[17:34:54.279] INFO: number of triggers sent (total per ROC): 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000
[17:34:54.279] INFO: number of Vcal hits detected: 207590 207815 207495 207194 207038 206928 206965 207456 207651 207268 207442 207581 207537 207717 207908 207921
[17:34:54.279] INFO: Vcal hit fiducial efficiency (%): 99.8 99.9 99.8 99.7 99.6 99.5 99.6 99.8 99.8 99.7 99.8 99.8 99.8 99.9 100.0 100.0
[17:34:54.279] INFO: Vcal hit overall efficiency (%): 99.8 99.9 99.8 99.6 99.5 99.5 99.5 99.7 99.8 99.6 99.7 99.8 99.8 99.9 100.0 100.0
[17:34:54.279] INFO: X-ray hit rate [MHz/cm2]: 50.6 35.1 56.9 86.6 94.2 94.3 98.3 65.1 62.5 74.6 74.4 63.5 64.9 40.9 17.2 19.1
[17:34:54.279] INFO: PixTestHighRate::doXPixelAlive() done
[17:34:54.328] INFO: PixTest:: pg_setup set to default.
[17:34:54.342] INFO: enter test to run
[17:35:10.280] INFO: test: HighRate no parameter change
[17:35:10.280] INFO: running: highrate
[17:35:10.281] INFO: ----------------------------------------------------------------------
[17:35:10.281] INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[17:35:10.281] INFO: ----------------------------------------------------------------------
[17:35:10.903] INFO: Expecting 208000 events.
[17:35:27.912] INFO: 208000 events read in total (16483ms).
[17:35:27.920] INFO: Test took 17628ms.
[17:35:28.457] INFO: Fetched DAQ statistics. Counters are being reset now.
[17:35:28.842] INFO: number of dead pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[17:35:28.842] INFO: number of red-efficiency pixels: 714 320 839 1495 1716 1755 1780 895 630 1434 1062 789 803 442 143 124
[17:35:28.842] INFO: number of X-ray hits detected: 245344 171132 275796 419681 452784 455550 475733 315492 304024 360511 362424 307599 313783 198033 83813 93373
[17:35:28.842] INFO: number of triggers sent (total per ROC): 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000
[17:35:28.843] INFO: number of Vcal hits detected: 207071 207635 206760 205961 205510 205363 205253 206785 207273 206100 206707 207038 207024 207461 207847 207872
[17:35:28.843] INFO: Vcal hit fiducial efficiency (%): 99.6 99.8 99.5 99.1 98.9 98.9 98.8 99.5 99.7 99.2 99.4 99.6 99.6 99.8 99.9 99.9
[17:35:28.843] INFO: Vcal hit overall efficiency (%): 99.6 99.8 99.4 99.0 98.8 98.7 98.7 99.4 99.7 99.1 99.4 99.5 99.5 99.7 99.9 99.9
[17:35:28.843] INFO: X-ray hit rate [MHz/cm2]: 71.9 50.2 80.8 123.0 132.7 133.5 139.4 92.5 89.1 105.7 106.2 90.2 92.0 58.0 24.6 27.4
[17:35:28.843] INFO: PixTestHighRate::doXPixelAlive() done
[17:35:28.888] INFO: PixTest:: pg_setup set to default.
[17:35:28.903] INFO: enter test to run
[17:35:33.392] INFO: test: exit no parameter change
[17:35:33.681] QUIET: Connection to board 33 closed.
[17:35:33.682] INFO: pXar: this is the end, my friend