[17:47:34.701] INFO: *** Welcome to pxar ***
[17:47:34.701] INFO: *** Today: 2016/08/31
[17:47:34.723] INFO: *** Version: v1.9.0-818-g96727
[17:47:34.723] INFO: readRocDacs: /home/uicpirepix2/ProductionTestResults/M-Q-8-25_FPIXTest-17C-FNAL-160823-1252-150V_2016-08-23_12h52m_1471974731/000_FPIXTest_p17//dacParameters35_C0.dat .. /home/uicpirepix2/ProductionTestResults/M-Q-8-25_FPIXTest-17C-FNAL-160823-1252-150V_2016-08-23_12h52m_1471974731/000_FPIXTest_p17//dacParameters35_C15.dat
[17:47:35.036] INFO: readTbmDacs: /home/uicpirepix2/ProductionTestResults/M-Q-8-25_FPIXTest-17C-FNAL-160823-1252-150V_2016-08-23_12h52m_1471974731/000_FPIXTest_p17//tbmParameters_C0a.dat .. /home/uicpirepix2/ProductionTestResults/M-Q-8-25_FPIXTest-17C-FNAL-160823-1252-150V_2016-08-23_12h52m_1471974731/000_FPIXTest_p17//tbmParameters_C0b.dat
[17:47:35.036] INFO: readMaskFile: /home/uicpirepix2/ProductionTestResults/M-Q-8-25_FPIXTest-17C-FNAL-160823-1252-150V_2016-08-23_12h52m_1471974731/000_FPIXTest_p17//defaultMaskFile.dat
[17:47:35.054] INFO: readTrimFile: /home/uicpirepix2/ProductionTestResults/M-Q-8-25_FPIXTest-17C-FNAL-160823-1252-150V_2016-08-23_12h52m_1471974731/000_FPIXTest_p17//trimParameters35_C0.dat .. /home/uicpirepix2/ProductionTestResults/M-Q-8-25_FPIXTest-17C-FNAL-160823-1252-150V_2016-08-23_12h52m_1471974731/000_FPIXTest_p17//trimParameters35_C15.dat
[17:47:36.271] INFO: clk: 4
[17:47:36.271] INFO: ctr: 4
[17:47:36.271] INFO: sda: 19
[17:47:36.271] INFO: tin: 9
[17:47:36.271] INFO: level: 15
[17:47:36.271] INFO: triggerdelay: 0
[17:47:36.271] QUIET: Instanciating API for pxar v1.9.0+814~g74979a6
[17:47:36.271] INFO: Log level: INFO
[17:47:36.290] QUIET: Connection to board DTB_WREK4U opened.
[17:47:36.293] INFO: DTB startup information
--- DTB info------------------------------------------
Board id: 32
HW version: DTB1.2
FW version: 4.2
SW version: 4.5
USB id: DTB_WREK4U
MAC address: 40D855118020
Hostname: pixelDTB032
Comment:
------------------------------------------------------
[17:47:36.296] INFO: RPC call hashes of host and DTB match: 398089610
[17:47:37.828] INFO: DUT info:
[17:47:37.828] INFO: The DUT currently contains the following objects:
[17:47:37.828] INFO: 2 TBM Cores tbm08c (2 ON)
[17:47:37.828] INFO: TBM Core alpha (0): 7 registers set
[17:47:37.828] INFO: TBM Core beta (1): 7 registers set
[17:47:37.828] INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[17:47:37.828] INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[17:47:37.828] INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[17:47:37.828] INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[17:47:37.828] INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[17:47:37.829] INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[17:47:37.829] INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[17:47:37.829] INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[17:47:37.829] INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[17:47:37.829] INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[17:47:37.829] INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[17:47:37.829] INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[17:47:37.829] INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[17:47:37.829] INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[17:47:37.829] INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[17:47:37.829] INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[17:47:37.829] INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[17:47:38.272] INFO: enter 'restricted' command line mode
[17:47:38.272] INFO: enter test to run
[17:47:44.260] INFO: test: PixelAlive no parameter change
[17:47:44.260] INFO: running: pixelalive
[17:47:44.270] INFO: ----------------------------------------------------------------------
[17:47:44.270] 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:47:44.270] INFO: ----------------------------------------------------------------------
[17:47:44.589] INFO: Expecting 41600 events.
[17:47:48.917] INFO: 41600 events read in total (3610ms).
[17:47:49.088] INFO: Test took 4816ms.
[17:47:49.102] INFO: Fetched DAQ statistics. Counters are being reset now.
[17:47:49.368] INFO: PixTestAlive::aliveTest() done
[17:47:49.368] INFO: number of dead pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[17:47:49.401] INFO: enter test to run
[17:48:31.292] INFO: test: HighRate setting parameters: ->runsecondshotpixels=5;trimhotpixelthr=200<-
[17:48:31.292] INFO: running: highrate
[17:48:31.292] INFO: readGainPedestalParameters /home/uicpirepix2/ProductionTestResults/M-Q-8-25_FPIXTest-17C-FNAL-160823-1252-150V_2016-08-23_12h52m_1471974731/000_FPIXTest_p17//phCalibrationFitErr35_C0.dat .. /home/uicpirepix2/ProductionTestResults/M-Q-8-25_FPIXTest-17C-FNAL-160823-1252-150V_2016-08-23_12h52m_1471974731/000_FPIXTest_p17//phCalibrationFitErr35_C15.dat
[17:48:32.659] INFO: ----------------------------------------------------------------------
[17:48:32.659] INFO: PixTest::trimHotPixels() running for 5 seconds with 100 kHz trigger rate
[17:48:32.660] INFO: ----------------------------------------------------------------------
[17:48:32.660] INFO: THR = 200, corresponding to ~ 106.667 MHz/cm2
[17:48:32.660] INFO: edge/corner pixel THR is adjusted
[17:48:32.660] INFO: PixTestHighRate::trimHotPixels: step 0...
[17:48:33.619] INFO: Collecting data for 5 seconds...
[17:48:38.637] INFO: Done with hot pixel readout
[17:48:50.315] INFO: PixTest:: pg_setup set to default.
[17:48:50.316] INFO: 15 hot pixels found in step 0
[17:48:51.324] INFO: Collecting data for 5 seconds...
[17:48:56.340] INFO: Done with hot pixel readout
[17:49:08.090] INFO: PixTest:: pg_setup set to default.
[17:49:08.091] INFO: 17 hot pixels found in step 1
[17:49:09.078] INFO: Collecting data for 5 seconds...
[17:49:14.095] INFO: Done with hot pixel readout
[17:49:25.818] INFO: PixTest:: pg_setup set to default.
[17:49:25.819] INFO: 16 hot pixels found in step 2
[17:49:26.806] INFO: Collecting data for 5 seconds...
[17:49:31.824] INFO: Done with hot pixel readout
[17:49:43.544] INFO: PixTest:: pg_setup set to default.
[17:49:43.544] INFO: 15 hot pixels found in step 3
[17:49:44.532] INFO: Collecting data for 5 seconds...
[17:49:49.548] INFO: Done with hot pixel readout
[17:50:01.254] INFO: PixTest:: pg_setup set to default.
[17:50:01.255] INFO: 17 hot pixels found in step 4
[17:50:02.242] INFO: Collecting data for 5 seconds...
[17:50:07.259] INFO: Done with hot pixel readout
[17:50:18.859] INFO: PixTest:: pg_setup set to default.

[17:50:18.860] INFO: 10 hot pixels found in step 5
[17:50:19.849] INFO: Collecting data for 5 seconds...
[17:50:24.867] INFO: Done with hot pixel readout
[17:50:36.513] INFO: PixTest:: pg_setup set to default.

[17:50:36.514] INFO: 14 hot pixels found in step 6
[17:50:37.502] INFO: Collecting data for 5 seconds...
[17:50:42.519] INFO: Done with hot pixel readout
[17:50:53.683] INFO: PixTest:: pg_setup set to default.

[17:50:53.684] INFO: 17 hot pixels found in step 7
[17:50:54.672] INFO: Collecting data for 5 seconds...
[17:50:59.689] INFO: Done with hot pixel readout
[17:51:11.227] INFO: PixTest:: pg_setup set to default.



[17:51:11.228] INFO: 15 hot pixels found in step 8
[17:51:12.217] INFO: Collecting data for 5 seconds...
[17:51:17.233] INFO: Done with hot pixel readout
[17:51:28.953] INFO: PixTest:: pg_setup set to default.



[17:51:28.954] INFO: 6 hot pixels found in step 9
[17:51:29.941] INFO: Collecting data for 5 seconds...
[17:51:34.959] INFO: Done with hot pixel readout
[17:51:46.522] INFO: PixTest:: pg_setup set to default.


[17:51:46.523] INFO: 8 hot pixels found in step 10
[17:51:47.511] INFO: Collecting data for 5 seconds...
[17:51:52.529] INFO: Done with hot pixel readout
[17:52:03.984] INFO: PixTest:: pg_setup set to default.



[17:52:03.985] INFO: 10 hot pixels found in step 11
[17:52:04.972] INFO: Collecting data for 5 seconds...
[17:52:09.988] INFO: Done with hot pixel readout
[17:52:21.747] INFO: PixTest:: pg_setup set to default.



[17:52:21.748] INFO: 13 hot pixels found in step 12
[17:52:22.734] INFO: Collecting data for 5 seconds...
[17:52:27.750] INFO: Done with hot pixel readout
[17:52:39.699] INFO: PixTest:: pg_setup set to default.



[17:52:39.700] INFO: 5 hot pixels found in step 13
[17:52:40.687] INFO: Collecting data for 5 seconds...
[17:52:45.705] INFO: Done with hot pixel readout
[17:52:56.905] INFO: PixTest:: pg_setup set to default.

[17:52:56.906] INFO: 8 hot pixels found in step 14
[17:52:56.937] INFO: 8 hot pixels could not be trimmed and have been masked.
[17:52:56.985] INFO: PixTest::trimHotPixels() done
[17:52:56.992] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-8-25_FPIXTest-17C-FNAL-160823-1252-150V_2016-08-23_12h52m_1471974731/000_FPIXTest_p17//trimParameters35_C0.dat
[17:52:57.017] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-8-25_FPIXTest-17C-FNAL-160823-1252-150V_2016-08-23_12h52m_1471974731/000_FPIXTest_p17//trimParameters35_C1.dat
[17:52:57.025] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-8-25_FPIXTest-17C-FNAL-160823-1252-150V_2016-08-23_12h52m_1471974731/000_FPIXTest_p17//trimParameters35_C2.dat
[17:52:57.030] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-8-25_FPIXTest-17C-FNAL-160823-1252-150V_2016-08-23_12h52m_1471974731/000_FPIXTest_p17//trimParameters35_C3.dat
[17:52:57.036] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-8-25_FPIXTest-17C-FNAL-160823-1252-150V_2016-08-23_12h52m_1471974731/000_FPIXTest_p17//trimParameters35_C4.dat
[17:52:57.041] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-8-25_FPIXTest-17C-FNAL-160823-1252-150V_2016-08-23_12h52m_1471974731/000_FPIXTest_p17//trimParameters35_C5.dat
[17:52:57.046] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-8-25_FPIXTest-17C-FNAL-160823-1252-150V_2016-08-23_12h52m_1471974731/000_FPIXTest_p17//trimParameters35_C6.dat
[17:52:57.051] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-8-25_FPIXTest-17C-FNAL-160823-1252-150V_2016-08-23_12h52m_1471974731/000_FPIXTest_p17//trimParameters35_C7.dat
[17:52:57.057] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-8-25_FPIXTest-17C-FNAL-160823-1252-150V_2016-08-23_12h52m_1471974731/000_FPIXTest_p17//trimParameters35_C8.dat
[17:52:57.062] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-8-25_FPIXTest-17C-FNAL-160823-1252-150V_2016-08-23_12h52m_1471974731/000_FPIXTest_p17//trimParameters35_C9.dat
[17:52:57.067] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-8-25_FPIXTest-17C-FNAL-160823-1252-150V_2016-08-23_12h52m_1471974731/000_FPIXTest_p17//trimParameters35_C10.dat
[17:52:57.073] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-8-25_FPIXTest-17C-FNAL-160823-1252-150V_2016-08-23_12h52m_1471974731/000_FPIXTest_p17//trimParameters35_C11.dat
[17:52:57.078] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-8-25_FPIXTest-17C-FNAL-160823-1252-150V_2016-08-23_12h52m_1471974731/000_FPIXTest_p17//trimParameters35_C12.dat
[17:52:57.083] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-8-25_FPIXTest-17C-FNAL-160823-1252-150V_2016-08-23_12h52m_1471974731/000_FPIXTest_p17//trimParameters35_C13.dat
[17:52:57.089] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-8-25_FPIXTest-17C-FNAL-160823-1252-150V_2016-08-23_12h52m_1471974731/000_FPIXTest_p17//trimParameters35_C14.dat
[17:52:57.094] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-8-25_FPIXTest-17C-FNAL-160823-1252-150V_2016-08-23_12h52m_1471974731/000_FPIXTest_p17//trimParameters35_C15.dat
[17:52:57.099] INFO: write masked pixels into /home/uicpirepix2/ProductionTestResults/M-Q-8-25_FPIXTest-17C-FNAL-160823-1252-150V_2016-08-23_12h52m_1471974731/000_FPIXTest_p17//defaultMaskFile.dat
[17:52:57.113] INFO: enter test to run
[17:55:35.361] INFO: test: HighRate setting parameters: ->runsecondshotpixels=1;trimhotpixelthr=200<-
[17:55:35.361] INFO: running: highrate
[17:55:35.366] INFO: ----------------------------------------------------------------------
[17:55:35.366] INFO: PixTest::trimHotPixels() running for 1 seconds with 100 kHz trigger rate
[17:55:35.366] INFO: ----------------------------------------------------------------------
[17:55:35.366] INFO: THR = 200, corresponding to ~ 533.333 MHz/cm2
[17:55:35.366] INFO: edge/corner pixel THR is adjusted
[17:55:35.366] INFO: PixTestHighRate::trimHotPixels: step 0...
[17:55:36.324] INFO: Collecting data for 1 seconds...
[17:55:37.328] INFO: Done with hot pixel readout
[17:55:41.301] INFO: PixTest:: pg_setup set to default.
[17:55:41.302] INFO: 0 hot pixels found in step 0
[17:55:41.307] INFO: 0 hot pixels could not be trimmed and have been masked.
[17:55:41.395] INFO: PixTest::trimHotPixels() done
[17:55:41.395] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-8-25_FPIXTest-17C-FNAL-160823-1252-150V_2016-08-23_12h52m_1471974731/000_FPIXTest_p17//trimParameters35_C0.dat
[17:55:41.403] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-8-25_FPIXTest-17C-FNAL-160823-1252-150V_2016-08-23_12h52m_1471974731/000_FPIXTest_p17//trimParameters35_C1.dat
[17:55:41.408] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-8-25_FPIXTest-17C-FNAL-160823-1252-150V_2016-08-23_12h52m_1471974731/000_FPIXTest_p17//trimParameters35_C2.dat
[17:55:41.413] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-8-25_FPIXTest-17C-FNAL-160823-1252-150V_2016-08-23_12h52m_1471974731/000_FPIXTest_p17//trimParameters35_C3.dat
[17:55:41.418] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-8-25_FPIXTest-17C-FNAL-160823-1252-150V_2016-08-23_12h52m_1471974731/000_FPIXTest_p17//trimParameters35_C4.dat
[17:55:41.423] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-8-25_FPIXTest-17C-FNAL-160823-1252-150V_2016-08-23_12h52m_1471974731/000_FPIXTest_p17//trimParameters35_C5.dat
[17:55:41.428] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-8-25_FPIXTest-17C-FNAL-160823-1252-150V_2016-08-23_12h52m_1471974731/000_FPIXTest_p17//trimParameters35_C6.dat
[17:55:41.433] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-8-25_FPIXTest-17C-FNAL-160823-1252-150V_2016-08-23_12h52m_1471974731/000_FPIXTest_p17//trimParameters35_C7.dat
[17:55:41.438] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-8-25_FPIXTest-17C-FNAL-160823-1252-150V_2016-08-23_12h52m_1471974731/000_FPIXTest_p17//trimParameters35_C8.dat
[17:55:41.443] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-8-25_FPIXTest-17C-FNAL-160823-1252-150V_2016-08-23_12h52m_1471974731/000_FPIXTest_p17//trimParameters35_C9.dat
[17:55:41.448] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-8-25_FPIXTest-17C-FNAL-160823-1252-150V_2016-08-23_12h52m_1471974731/000_FPIXTest_p17//trimParameters35_C10.dat
[17:55:41.453] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-8-25_FPIXTest-17C-FNAL-160823-1252-150V_2016-08-23_12h52m_1471974731/000_FPIXTest_p17//trimParameters35_C11.dat
[17:55:41.458] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-8-25_FPIXTest-17C-FNAL-160823-1252-150V_2016-08-23_12h52m_1471974731/000_FPIXTest_p17//trimParameters35_C12.dat
[17:55:41.463] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-8-25_FPIXTest-17C-FNAL-160823-1252-150V_2016-08-23_12h52m_1471974731/000_FPIXTest_p17//trimParameters35_C13.dat
[17:55:41.468] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-8-25_FPIXTest-17C-FNAL-160823-1252-150V_2016-08-23_12h52m_1471974731/000_FPIXTest_p17//trimParameters35_C14.dat
[17:55:41.473] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-8-25_FPIXTest-17C-FNAL-160823-1252-150V_2016-08-23_12h52m_1471974731/000_FPIXTest_p17//trimParameters35_C15.dat
[17:55:41.478] INFO: write masked pixels into /home/uicpirepix2/ProductionTestResults/M-Q-8-25_FPIXTest-17C-FNAL-160823-1252-150V_2016-08-23_12h52m_1471974731/000_FPIXTest_p17//defaultMaskFile.dat
[17:55:41.487] INFO: enter test to run
[17:56:01.841] INFO: test: Xray setting parameters: ->source=DCLowRate<-
[17:56:01.841] INFO: running: xray
[17:56:01.862] INFO: ----------------------------------------------------------------------
[17:56:01.862] INFO: PixTestXray::doPhRun() fParRunSeconds = 100
[17:56:01.862] INFO: ----------------------------------------------------------------------
[17:56:02.840] INFO: PixTestXray::doPhRun start TriggerLoop with trigger frequency 100 kHz, period 405 and duration 100 seconds, fEventsMax = 10000000
[17:56:14.449] INFO: run duration 11 seconds, buffer almost full (81%), pausing triggers.
[17:56:42.124] INFO: Resuming triggers.
[17:56:53.739] INFO: run duration 23 seconds, buffer almost full (81%), pausing triggers.
[17:57:22.260] INFO: Resuming triggers.
[17:57:33.874] INFO: run duration 34 seconds, buffer almost full (81%), pausing triggers.
[17:58:01.049] INFO: Resuming triggers.
[17:58:12.667] INFO: run duration 46 seconds, buffer almost full (81%), pausing triggers.
[17:58:43.021] INFO: Resuming triggers.
[17:58:54.636] INFO: run duration 57 seconds, buffer almost full (81%), pausing triggers.
[17:59:24.929] INFO: Resuming triggers.
[17:59:36.544] INFO: run duration 69 seconds, buffer almost full (81%), pausing triggers.
[18:00:06.801] INFO: Resuming triggers.
[18:00:18.415] INFO: run duration 81 seconds, buffer almost full (81%), pausing triggers.
[18:00:48.355] INFO: Resuming triggers.
[18:00:59.966] INFO: run duration 92 seconds, buffer almost full (81%), pausing triggers.
[18:01:29.544] INFO: Resuming triggers.
[18:01:36.987] INFO: data taking finished, elapsed time: 100 seconds.
[18:01:55.984] INFO: PixTest:: pg_setup set to default.
[18:01:55.987] INFO: PixTestXray::doPhRun() done
[18:01:56.254] INFO: enter test to run
[18:03:24.598] INFO: test: Xray setting parameters: ->source=DCHighRate<-
[18:03:24.598] INFO: running: xray
[18:03:24.599] INFO: ----------------------------------------------------------------------
[18:03:24.599] INFO: PixTestXray::doPhRun() fParRunSeconds = 100
[18:03:24.599] INFO: ----------------------------------------------------------------------
[18:03:25.568] INFO: PixTestXray::doPhRun start TriggerLoop with trigger frequency 100 kHz, period 405 and duration 100 seconds, fEventsMax = 10000000
[18:03:32.400] INFO: run duration 6 seconds, buffer almost full (81%), pausing triggers.
[18:04:02.780] INFO: Resuming triggers.
[18:04:09.610] INFO: run duration 13 seconds, buffer almost full (81%), pausing triggers.
[18:04:39.960] INFO: Resuming triggers.
[18:04:46.794] INFO: run duration 20 seconds, buffer almost full (81%), pausing triggers.
[18:05:16.998] INFO: Resuming triggers.
[18:05:23.833] INFO: run duration 27 seconds, buffer almost full (81%), pausing triggers.
[18:05:54.203] INFO: Resuming triggers.
[18:06:01.041] INFO: run duration 34 seconds, buffer almost full (81%), pausing triggers.
[18:06:31.111] INFO: Resuming triggers.
[18:06:37.947] INFO: run duration 40 seconds, buffer almost full (81%), pausing triggers.
[18:07:08.622] INFO: Resuming triggers.
[18:07:15.459] INFO: run duration 47 seconds, buffer almost full (81%), pausing triggers.
[18:07:46.428] INFO: Resuming triggers.
[18:07:53.267] INFO: run duration 54 seconds, buffer almost full (81%), pausing triggers.
[18:08:24.197] INFO: Resuming triggers.
[18:08:31.039] INFO: run duration 61 seconds, buffer almost full (81%), pausing triggers.
[18:09:02.111] INFO: Resuming triggers.
[18:09:08.951] INFO: run duration 68 seconds, buffer almost full (81%), pausing triggers.
[18:09:39.956] INFO: Resuming triggers.
[18:09:46.796] INFO: run duration 74 seconds, buffer almost full (81%), pausing triggers.
[18:10:17.412] INFO: Resuming triggers.
[18:10:24.254] INFO: run duration 81 seconds, buffer almost full (81%), pausing triggers.
[18:10:55.411] INFO: Resuming triggers.
[18:11:02.253] INFO: run duration 88 seconds, buffer almost full (81%), pausing triggers.
[18:11:33.898] INFO: Resuming triggers.
[18:11:40.738] INFO: run duration 95 seconds, buffer almost full (81%), pausing triggers.
[18:12:11.920] INFO: Resuming triggers.
[18:12:16.542] INFO: data taking finished, elapsed time: 100 seconds.
[18:12:37.004] INFO: PixTest:: pg_setup set to default.
[18:12:38.007] INFO: PixTestXray::doPhRun() done
[18:12:38.157] INFO: enter test to run
[18:13:15.498] INFO: test: HighRate no parameter change
[18:13:15.498] INFO: running: highrate
[18:13:15.547] INFO: ----------------------------------------------------------------------
[18:13:15.547] INFO: PixTestHighRate::calDelScan() ntrig = 10, vcal = 200
[18:13:15.547] INFO: ----------------------------------------------------------------------
[18:13:15.735] INFO: Expecting 768 events.
[18:13:16.881] INFO: 768 events read in total (425ms).
[18:13:16.881] INFO: Test took 1281ms.
[18:13:17.684] INFO: Expecting 41600 events.
[18:13:20.746] INFO: 41600 events read in total (2535ms).
[18:13:20.747] INFO: Test took 3828ms.
[18:13:20.779] INFO: Fetched DAQ statistics. Counters are being reset now.
[18:13:21.510] INFO: Expecting 41600 events.
[18:13:24.738] INFO: 41600 events read in total (2702ms).
[18:13:24.739] INFO: Test took 3943ms.
[18:13:24.773] INFO: Fetched DAQ statistics. Counters are being reset now.
[18:13:25.498] INFO: Expecting 41600 events.
[18:13:28.738] INFO: 41600 events read in total (2713ms).
[18:13:28.739] INFO: Test took 3949ms.
[18:13:28.773] INFO: Fetched DAQ statistics. Counters are being reset now.
[18:13:29.494] INFO: Expecting 41600 events.
[18:13:32.735] INFO: 41600 events read in total (2714ms).
[18:13:32.736] INFO: Test took 3945ms.
[18:13:32.770] INFO: Fetched DAQ statistics. Counters are being reset now.
[18:13:33.494] INFO: Expecting 41600 events.
[18:13:36.745] INFO: 41600 events read in total (2724ms).
[18:13:36.746] INFO: Test took 3958ms.
[18:13:36.780] INFO: Fetched DAQ statistics. Counters are being reset now.
[18:13:37.506] INFO: Expecting 41600 events.
[18:13:40.742] INFO: 41600 events read in total (2710ms).
[18:13:40.743] INFO: Test took 3945ms.
[18:13:40.777] INFO: Fetched DAQ statistics. Counters are being reset now.
[18:13:41.504] INFO: Expecting 41600 events.
[18:13:44.737] INFO: 41600 events read in total (2706ms).
[18:13:44.738] INFO: Test took 3944ms.
[18:13:44.772] INFO: Fetched DAQ statistics. Counters are being reset now.
[18:13:45.497] INFO: Expecting 41600 events.
[18:13:48.742] INFO: 41600 events read in total (2719ms).
[18:13:48.743] INFO: Test took 3955ms.
[18:13:48.776] INFO: Fetched DAQ statistics. Counters are being reset now.
[18:13:49.499] INFO: Expecting 41600 events.
[18:13:52.739] INFO: 41600 events read in total (2713ms).
[18:13:52.740] INFO: Test took 3946ms.
[18:13:52.774] INFO: Fetched DAQ statistics. Counters are being reset now.
[18:13:53.498] INFO: Expecting 41600 events.
[18:13:56.736] INFO: 41600 events read in total (2711ms).
[18:13:56.737] INFO: Test took 3946ms.
[18:13:56.771] INFO: Fetched DAQ statistics. Counters are being reset now.
[18:13:57.497] INFO: Expecting 41600 events.
[18:14:00.743] INFO: 41600 events read in total (2719ms).
[18:14:00.744] INFO: Test took 3955ms.
[18:14:00.778] INFO: Fetched DAQ statistics. Counters are being reset now.
[18:14:01.502] INFO: Expecting 41600 events.
[18:14:04.755] INFO: 41600 events read in total (2726ms).
[18:14:04.756] INFO: Test took 3960ms.
[18:14:04.789] INFO: Fetched DAQ statistics. Counters are being reset now.
[18:14:05.516] INFO: Expecting 41600 events.
[18:14:08.739] INFO: 41600 events read in total (2696ms).
[18:14:08.740] INFO: Test took 3933ms.
[18:14:08.772] INFO: Fetched DAQ statistics. Counters are being reset now.
[18:14:09.501] INFO: Expecting 41600 events.
[18:14:12.758] INFO: 41600 events read in total (2731ms).
[18:14:12.759] INFO: Test took 3969ms.
[18:14:12.793] INFO: Fetched DAQ statistics. Counters are being reset now.
[18:14:13.519] INFO: Expecting 41600 events.
[18:14:16.765] INFO: 41600 events read in total (2719ms).
[18:14:16.766] INFO: Test took 3956ms.
[18:14:16.800] INFO: Fetched DAQ statistics. Counters are being reset now.
[18:14:17.525] INFO: Expecting 41600 events.
[18:14:20.760] INFO: 41600 events read in total (2708ms).
[18:14:20.761] INFO: Test took 3942ms.
[18:14:20.795] INFO: Fetched DAQ statistics. Counters are being reset now.
[18:14:21.520] INFO: Expecting 41600 events.
[18:14:24.766] INFO: 41600 events read in total (2719ms).
[18:14:24.767] INFO: Test took 3955ms.
[18:14:24.800] INFO: Fetched DAQ statistics. Counters are being reset now.
[18:14:25.523] INFO: Expecting 41600 events.
[18:14:28.763] INFO: 41600 events read in total (2713ms).
[18:14:28.764] INFO: Test took 3945ms.
[18:14:28.798] INFO: Fetched DAQ statistics. Counters are being reset now.
[18:14:29.521] INFO: Expecting 41600 events.
[18:14:32.749] INFO: 41600 events read in total (2701ms).
[18:14:32.750] INFO: Test took 3935ms.
[18:14:32.783] INFO: Fetched DAQ statistics. Counters are being reset now.
[18:14:33.511] INFO: Expecting 41600 events.
[18:14:36.622] INFO: 41600 events read in total (2584ms).
[18:14:36.623] INFO: Test took 3821ms.
[18:14:36.656] INFO: Fetched DAQ statistics. Counters are being reset now.
[18:14:37.028] INFO: enter test to run
[18:15:09.345] INFO: test: HighRate no parameter change
[18:15:09.345] INFO: running: highrate
[18:15:09.347] INFO: ----------------------------------------------------------------------
[18:15:09.347] INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[18:15:09.347] INFO: ----------------------------------------------------------------------
[18:15:09.957] INFO: Expecting 208000 events.
[18:15:21.808] INFO: 208000 events read in total (11325ms).
[18:15:21.811] INFO: Test took 12456ms.
[18:15:21.954] INFO: Fetched DAQ statistics. Counters are being reset now.
[18:15:22.206] INFO: number of dead pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[18:15:22.206] INFO: number of red-efficiency pixels: 93 48 77 134 144 145 167 95 68 108 108 102 121 63 22 28
[18:15:22.206] INFO: number of X-ray hits detected: 71938 43564 67527 105666 111467 111791 114495 77259 73777 91033 94204 80276 85353 51627 20616 24865
[18:15:22.206] INFO: number of triggers sent (total per ROC): 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000
[18:15:22.206] INFO: number of Vcal hits detected: 207906 207952 207922 207862 207850 207851 207791 207903 207932 207888 207890 207892 207879 207937 207978 207971
[18:15:22.206] INFO: Vcal hit fiducial efficiency (%): 100.0 100.0 100.0 99.9 99.9 99.9 99.9 100.0 100.0 99.9 99.9 100.0 99.9 100.0 100.0 100.0
[18:15:22.206] INFO: Vcal hit overall efficiency (%): 100.0 100.0 100.0 99.9 99.9 99.9 99.9 100.0 100.0 99.9 99.9 99.9 99.9 100.0 100.0 100.0
[18:15:22.206] INFO: X-ray hit rate [MHz/cm2]: 21.1 12.8 19.8 31.0 32.7 32.8 33.6 22.6 21.6 26.7 27.6 23.5 25.0 15.1 6.0 7.3
[18:15:22.206] INFO: PixTestHighRate::doXPixelAlive() done
[18:15:22.259] INFO: PixTest:: pg_setup set to default.
[18:15:22.275] INFO: enter test to run
[18:15:48.049] INFO: test: HighRate no parameter change
[18:15:48.049] INFO: running: highrate
[18:15:48.050] INFO: ----------------------------------------------------------------------
[18:15:48.050] INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[18:15:48.050] INFO: ----------------------------------------------------------------------
[18:15:48.667] INFO: Expecting 208000 events.
[18:16:02.186] INFO: 208000 events read in total (12992ms).
[18:16:02.191] INFO: Test took 14130ms.
[18:16:02.489] INFO: Fetched DAQ statistics. Counters are being reset now.
[18:16:02.788] INFO: number of dead pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[18:16:02.788] INFO: number of red-efficiency pixels: 298 144 297 481 513 464 454 321 225 368 283 244 336 194 49 61
[18:16:02.788] INFO: number of X-ray hits detected: 145717 89573 137070 214618 226215 227232 231262 156477 150231 187695 190920 163958 172917 104986 42415 50893
[18:16:02.788] INFO: number of triggers sent (total per ROC): 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000
[18:16:02.788] INFO: number of Vcal hits detected: 207669 207852 207677 207471 207420 207501 207434 207661 207766 207611 207704 207729 207650 207797 207950 207935
[18:16:02.788] INFO: Vcal hit fiducial efficiency (%): 99.9 99.9 99.9 99.8 99.7 99.8 99.7 99.8 99.9 99.8 99.9 99.9 99.8 99.9 100.0 100.0
[18:16:02.788] INFO: Vcal hit overall efficiency (%): 99.8 99.9 99.8 99.7 99.7 99.8 99.7 99.8 99.9 99.8 99.9 99.9 99.8 99.9 100.0 100.0
[18:16:02.788] INFO: X-ray hit rate [MHz/cm2]: 42.7 26.3 40.2 62.9 66.3 66.6 67.8 45.9 44.0 55.0 56.0 48.1 50.7 30.8 12.4 14.9
[18:16:02.788] INFO: PixTestHighRate::doXPixelAlive() done
[18:16:02.841] INFO: PixTest:: pg_setup set to default.
[18:16:02.852] INFO: enter test to run
[18:16:22.353] INFO: test: HighRate no parameter change
[18:16:22.353] INFO: running: highrate
[18:16:22.354] INFO: ----------------------------------------------------------------------
[18:16:22.354] INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[18:16:22.354] INFO: ----------------------------------------------------------------------
[18:16:22.968] INFO: Expecting 208000 events.
[18:16:38.324] INFO: 208000 events read in total (14829ms).
[18:16:38.331] INFO: Test took 15968ms.
[18:16:38.764] INFO: Fetched DAQ statistics. Counters are being reset now.
[18:16:39.109] INFO: number of dead pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[18:16:39.109] INFO: number of red-efficiency pixels: 645 291 662 1159 1316 1077 1047 741 413 841 628 540 734 442 108 98
[18:16:39.109] INFO: number of X-ray hits detected: 222307 135952 208482 327762 344350 345926 352798 241072 228960 283973 292188 250167 263833 160650 65079 78414
[18:16:39.109] INFO: number of triggers sent (total per ROC): 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000
[18:16:39.110] INFO: number of Vcal hits detected: 207158 207683 207112 206526 206259 206567 206516 207062 207536 207029 207288 207355 207169 207475 207882 207897
[18:16:39.110] INFO: Vcal hit fiducial efficiency (%): 99.7 99.9 99.6 99.4 99.2 99.4 99.3 99.6 99.8 99.6 99.7 99.7 99.6 99.8 99.9 100.0
[18:16:39.110] INFO: Vcal hit overall efficiency (%): 99.6 99.8 99.6 99.3 99.2 99.3 99.3 99.5 99.8 99.5 99.7 99.7 99.6 99.7 99.9 100.0
[18:16:39.110] INFO: X-ray hit rate [MHz/cm2]: 65.2 39.8 61.1 96.1 100.9 101.4 103.4 70.7 67.1 83.2 85.6 73.3 77.3 47.1 19.1 23.0
[18:16:39.110] INFO: PixTestHighRate::doXPixelAlive() done
[18:16:39.159] INFO: PixTest:: pg_setup set to default.
[18:16:39.175] INFO: enter test to run
[18:16:43.768] INFO: test: exit no parameter change
[18:16:44.202] QUIET: Connection to board 32 closed.
[18:16:44.292] INFO: pXar: this is the end, my friend