[15:13:59.171] INFO: *** Welcome to pxar ***
[15:13:59.171] INFO: *** Today: 2016/08/31
[15:14:00.019] INFO: *** Version: v1.9.0-818-g96727
[15:14:00.019] INFO: readRocDacs: /home/uicpirepix2/ProductionTestResults/M-Q-9-24_FPIXTest-17C-FNAL-160823-1252-150V_2016-08-23_12h52m_1471974731/000_FPIXTest_p17//dacParameters35_C0.dat .. /home/uicpirepix2/ProductionTestResults/M-Q-9-24_FPIXTest-17C-FNAL-160823-1252-150V_2016-08-23_12h52m_1471974731/000_FPIXTest_p17//dacParameters35_C15.dat
[15:14:00.053] INFO: readTbmDacs: /home/uicpirepix2/ProductionTestResults/M-Q-9-24_FPIXTest-17C-FNAL-160823-1252-150V_2016-08-23_12h52m_1471974731/000_FPIXTest_p17//tbmParameters_C0a.dat .. /home/uicpirepix2/ProductionTestResults/M-Q-9-24_FPIXTest-17C-FNAL-160823-1252-150V_2016-08-23_12h52m_1471974731/000_FPIXTest_p17//tbmParameters_C0b.dat
[15:14:00.053] INFO: readMaskFile: /home/uicpirepix2/ProductionTestResults/M-Q-9-24_FPIXTest-17C-FNAL-160823-1252-150V_2016-08-23_12h52m_1471974731/000_FPIXTest_p17//defaultMaskFile.dat
[15:14:00.056] INFO: readTrimFile: /home/uicpirepix2/ProductionTestResults/M-Q-9-24_FPIXTest-17C-FNAL-160823-1252-150V_2016-08-23_12h52m_1471974731/000_FPIXTest_p17//trimParameters35_C0.dat .. /home/uicpirepix2/ProductionTestResults/M-Q-9-24_FPIXTest-17C-FNAL-160823-1252-150V_2016-08-23_12h52m_1471974731/000_FPIXTest_p17//trimParameters35_C15.dat
[15:14:00.154] INFO: clk: 4
[15:14:00.154] INFO: ctr: 4
[15:14:00.154] INFO: sda: 19
[15:14:00.154] INFO: tin: 9
[15:14:00.154] INFO: level: 15
[15:14:00.154] INFO: triggerdelay: 0
[15:14:00.154] QUIET: Instanciating API for pxar v1.9.0+814~g74979a6
[15:14:00.154] INFO: Log level: INFO
[15:14:00.170] QUIET: Connection to board DTB_WREK4U opened.
[15:14:00.174] 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:
------------------------------------------------------
[15:14:00.176] INFO: RPC call hashes of host and DTB match: 398089610
[15:14:01.716] INFO: DUT info:
[15:14:01.716] INFO: The DUT currently contains the following objects:
[15:14:01.716] INFO: 2 TBM Cores tbm08c (2 ON)
[15:14:01.716] INFO: TBM Core alpha (0): 7 registers set
[15:14:01.716] INFO: TBM Core beta (1): 7 registers set
[15:14:01.716] INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[15:14:01.716] INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[15:14:01.716] INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[15:14:01.716] INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[15:14:01.716] INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[15:14:01.716] INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[15:14:01.716] INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[15:14:01.716] INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[15:14:01.716] INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[15:14:01.716] INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[15:14:01.717] INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[15:14:01.717] INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[15:14:01.717] INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[15:14:01.717] INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[15:14:01.717] INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[15:14:01.717] INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[15:14:01.717] INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[15:14:02.120] INFO: enter 'restricted' command line mode
[15:14:02.120] INFO: enter test to run
[15:14:12.666] INFO: test: PixelAlive no parameter change
[15:14:12.666] INFO: running: pixelalive
[15:14:12.713] INFO: ----------------------------------------------------------------------
[15:14:12.714] INFO: PixTestAlive::aliveTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[15:14:12.714] INFO: ----------------------------------------------------------------------
[15:14:13.038] INFO: Expecting 41600 events.
[15:14:17.377] INFO: 41600 events read in total (3621ms).
[15:14:17.540] INFO: Test took 4824ms.
[15:14:17.555] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:14:17.819] INFO: PixTestAlive::aliveTest() done
[15:14:17.819] INFO: number of dead pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 1 0 0 0
[15:14:17.875] INFO: enter test to run
[15:14:57.298] INFO: test: HighRate setting parameters: ->runsecondshotpixels=5;trimhotpixelthr=200<-
[15:14:57.298] INFO: running: highrate
[15:14:57.299] INFO: readGainPedestalParameters /home/uicpirepix2/ProductionTestResults/M-Q-9-24_FPIXTest-17C-FNAL-160823-1252-150V_2016-08-23_12h52m_1471974731/000_FPIXTest_p17//phCalibrationFitErr35_C0.dat .. /home/uicpirepix2/ProductionTestResults/M-Q-9-24_FPIXTest-17C-FNAL-160823-1252-150V_2016-08-23_12h52m_1471974731/000_FPIXTest_p17//phCalibrationFitErr35_C15.dat
[15:14:59.481] INFO: ----------------------------------------------------------------------
[15:14:59.481] INFO: PixTest::trimHotPixels() running for 5 seconds with 100 kHz trigger rate
[15:14:59.481] INFO: ----------------------------------------------------------------------
[15:14:59.481] INFO: THR = 200, corresponding to ~ 106.667 MHz/cm2
[15:14:59.481] INFO: edge/corner pixel THR is adjusted
[15:14:59.481] INFO: PixTestHighRate::trimHotPixels: step 0...
[15:15:00.439] INFO: Collecting data for 5 seconds...
[15:15:05.458] INFO: Done with hot pixel readout
[15:15:17.420] INFO: PixTest:: pg_setup set to default.
[15:15:17.421] INFO: 14 hot pixels found in step 0
[15:15:18.412] INFO: Collecting data for 5 seconds...
[15:15:23.430] INFO: Done with hot pixel readout
[15:15:35.778] INFO: PixTest:: pg_setup set to default.
[15:15:35.779] INFO: 15 hot pixels found in step 1
[15:15:36.773] INFO: Collecting data for 5 seconds...
[15:15:41.792] INFO: Done with hot pixel readout
[15:15:53.839] INFO: PixTest:: pg_setup set to default.
[15:15:53.840] INFO: 11 hot pixels found in step 2
[15:15:54.830] INFO: Collecting data for 5 seconds...
[15:15:59.850] INFO: Done with hot pixel readout
[15:16:11.567] INFO: PixTest:: pg_setup set to default.
[15:16:11.568] INFO: 16 hot pixels found in step 3
[15:16:12.559] INFO: Collecting data for 5 seconds...
[15:16:17.575] INFO: Done with hot pixel readout
[15:16:29.288] INFO: PixTest:: pg_setup set to default.
[15:16:29.288] INFO: 15 hot pixels found in step 4
[15:16:30.279] INFO: Collecting data for 5 seconds...
[15:16:35.297] INFO: Done with hot pixel readout
[15:16:47.025] INFO: PixTest:: pg_setup set to default.
[15:16:47.026] INFO: 6 hot pixels found in step 5
[15:16:48.017] INFO: Collecting data for 5 seconds...
[15:16:53.033] INFO: Done with hot pixel readout
[15:17:04.742] INFO: PixTest:: pg_setup set to default.

[15:17:04.743] INFO: 14 hot pixels found in step 6
[15:17:05.734] INFO: Collecting data for 5 seconds...
[15:17:10.751] INFO: Done with hot pixel readout
[15:17:22.460] INFO: PixTest:: pg_setup set to default.
[15:17:22.461] INFO: 9 hot pixels found in step 7
[15:17:23.453] INFO: Collecting data for 5 seconds...
[15:17:28.469] INFO: Done with hot pixel readout
[15:17:40.139] INFO: PixTest:: pg_setup set to default.
[15:17:40.140] INFO: 5 hot pixels found in step 8
[15:17:41.132] INFO: Collecting data for 5 seconds...
[15:17:46.148] INFO: Done with hot pixel readout
[15:17:57.763] INFO: PixTest:: pg_setup set to default.
[15:17:57.764] INFO: 6 hot pixels found in step 9
[15:17:58.755] INFO: Collecting data for 5 seconds...
[15:18:03.772] INFO: Done with hot pixel readout
[15:18:15.466] INFO: PixTest:: pg_setup set to default.
[15:18:15.466] INFO: 3 hot pixels found in step 10
[15:18:16.457] INFO: Collecting data for 5 seconds...
[15:18:21.473] INFO: Done with hot pixel readout
[15:18:33.176] INFO: PixTest:: pg_setup set to default.
[15:18:33.177] INFO: 1 hot pixels found in step 11
[15:18:34.167] INFO: Collecting data for 5 seconds...
[15:18:39.183] INFO: Done with hot pixel readout
[15:18:50.840] INFO: PixTest:: pg_setup set to default.

[15:18:50.840] INFO: 5 hot pixels found in step 12
[15:18:51.831] INFO: Collecting data for 5 seconds...
[15:18:56.847] INFO: Done with hot pixel readout
[15:19:08.570] INFO: PixTest:: pg_setup set to default.

[15:19:08.571] INFO: 7 hot pixels found in step 13
[15:19:09.561] INFO: Collecting data for 5 seconds...
[15:19:14.577] INFO: Done with hot pixel readout
[15:19:26.230] INFO: PixTest:: pg_setup set to default.
[15:19:26.231] INFO: 2 hot pixels found in step 14
[15:19:26.266] INFO: 2 hot pixels could not be trimmed and have been masked.
[15:19:26.269] INFO: PixTest::trimHotPixels() done
[15:19:26.269] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-9-24_FPIXTest-17C-FNAL-160823-1252-150V_2016-08-23_12h52m_1471974731/000_FPIXTest_p17//trimParameters35_C0.dat
[15:19:26.276] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-9-24_FPIXTest-17C-FNAL-160823-1252-150V_2016-08-23_12h52m_1471974731/000_FPIXTest_p17//trimParameters35_C1.dat
[15:19:26.283] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-9-24_FPIXTest-17C-FNAL-160823-1252-150V_2016-08-23_12h52m_1471974731/000_FPIXTest_p17//trimParameters35_C2.dat
[15:19:26.288] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-9-24_FPIXTest-17C-FNAL-160823-1252-150V_2016-08-23_12h52m_1471974731/000_FPIXTest_p17//trimParameters35_C3.dat
[15:19:26.294] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-9-24_FPIXTest-17C-FNAL-160823-1252-150V_2016-08-23_12h52m_1471974731/000_FPIXTest_p17//trimParameters35_C4.dat
[15:19:26.299] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-9-24_FPIXTest-17C-FNAL-160823-1252-150V_2016-08-23_12h52m_1471974731/000_FPIXTest_p17//trimParameters35_C5.dat
[15:19:26.305] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-9-24_FPIXTest-17C-FNAL-160823-1252-150V_2016-08-23_12h52m_1471974731/000_FPIXTest_p17//trimParameters35_C6.dat
[15:19:26.310] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-9-24_FPIXTest-17C-FNAL-160823-1252-150V_2016-08-23_12h52m_1471974731/000_FPIXTest_p17//trimParameters35_C7.dat
[15:19:26.316] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-9-24_FPIXTest-17C-FNAL-160823-1252-150V_2016-08-23_12h52m_1471974731/000_FPIXTest_p17//trimParameters35_C8.dat
[15:19:26.321] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-9-24_FPIXTest-17C-FNAL-160823-1252-150V_2016-08-23_12h52m_1471974731/000_FPIXTest_p17//trimParameters35_C9.dat
[15:19:26.327] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-9-24_FPIXTest-17C-FNAL-160823-1252-150V_2016-08-23_12h52m_1471974731/000_FPIXTest_p17//trimParameters35_C10.dat
[15:19:26.332] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-9-24_FPIXTest-17C-FNAL-160823-1252-150V_2016-08-23_12h52m_1471974731/000_FPIXTest_p17//trimParameters35_C11.dat
[15:19:26.338] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-9-24_FPIXTest-17C-FNAL-160823-1252-150V_2016-08-23_12h52m_1471974731/000_FPIXTest_p17//trimParameters35_C12.dat
[15:19:26.343] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-9-24_FPIXTest-17C-FNAL-160823-1252-150V_2016-08-23_12h52m_1471974731/000_FPIXTest_p17//trimParameters35_C13.dat
[15:19:26.349] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-9-24_FPIXTest-17C-FNAL-160823-1252-150V_2016-08-23_12h52m_1471974731/000_FPIXTest_p17//trimParameters35_C14.dat
[15:19:26.354] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-9-24_FPIXTest-17C-FNAL-160823-1252-150V_2016-08-23_12h52m_1471974731/000_FPIXTest_p17//trimParameters35_C15.dat
[15:19:26.360] INFO: write masked pixels into /home/uicpirepix2/ProductionTestResults/M-Q-9-24_FPIXTest-17C-FNAL-160823-1252-150V_2016-08-23_12h52m_1471974731/000_FPIXTest_p17//defaultMaskFile.dat
[15:19:26.370] INFO: enter test to run
[15:20:48.880] INFO: test: HighRate setting parameters: ->runsecondshotpixels=1;trimhotpixelthr=200<-
[15:20:48.880] INFO: running: highrate
[15:20:48.884] INFO: ----------------------------------------------------------------------
[15:20:48.884] INFO: PixTest::trimHotPixels() running for 1 seconds with 100 kHz trigger rate
[15:20:48.884] INFO: ----------------------------------------------------------------------
[15:20:48.884] INFO: THR = 200, corresponding to ~ 533.333 MHz/cm2
[15:20:48.884] INFO: edge/corner pixel THR is adjusted
[15:20:48.884] INFO: PixTestHighRate::trimHotPixels: step 0...
[15:20:49.843] INFO: Collecting data for 1 seconds...
[15:20:50.847] INFO: Done with hot pixel readout
[15:20:54.813] INFO: PixTest:: pg_setup set to default.
[15:20:54.814] INFO: 0 hot pixels found in step 0
[15:20:54.819] INFO: 0 hot pixels could not be trimmed and have been masked.
[15:20:54.912] INFO: PixTest::trimHotPixels() done
[15:20:54.912] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-9-24_FPIXTest-17C-FNAL-160823-1252-150V_2016-08-23_12h52m_1471974731/000_FPIXTest_p17//trimParameters35_C0.dat
[15:20:54.925] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-9-24_FPIXTest-17C-FNAL-160823-1252-150V_2016-08-23_12h52m_1471974731/000_FPIXTest_p17//trimParameters35_C1.dat
[15:20:54.931] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-9-24_FPIXTest-17C-FNAL-160823-1252-150V_2016-08-23_12h52m_1471974731/000_FPIXTest_p17//trimParameters35_C2.dat
[15:20:54.936] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-9-24_FPIXTest-17C-FNAL-160823-1252-150V_2016-08-23_12h52m_1471974731/000_FPIXTest_p17//trimParameters35_C3.dat
[15:20:54.942] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-9-24_FPIXTest-17C-FNAL-160823-1252-150V_2016-08-23_12h52m_1471974731/000_FPIXTest_p17//trimParameters35_C4.dat
[15:20:54.947] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-9-24_FPIXTest-17C-FNAL-160823-1252-150V_2016-08-23_12h52m_1471974731/000_FPIXTest_p17//trimParameters35_C5.dat
[15:20:54.953] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-9-24_FPIXTest-17C-FNAL-160823-1252-150V_2016-08-23_12h52m_1471974731/000_FPIXTest_p17//trimParameters35_C6.dat
[15:20:54.958] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-9-24_FPIXTest-17C-FNAL-160823-1252-150V_2016-08-23_12h52m_1471974731/000_FPIXTest_p17//trimParameters35_C7.dat
[15:20:54.964] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-9-24_FPIXTest-17C-FNAL-160823-1252-150V_2016-08-23_12h52m_1471974731/000_FPIXTest_p17//trimParameters35_C8.dat
[15:20:54.969] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-9-24_FPIXTest-17C-FNAL-160823-1252-150V_2016-08-23_12h52m_1471974731/000_FPIXTest_p17//trimParameters35_C9.dat
[15:20:54.975] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-9-24_FPIXTest-17C-FNAL-160823-1252-150V_2016-08-23_12h52m_1471974731/000_FPIXTest_p17//trimParameters35_C10.dat
[15:20:54.981] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-9-24_FPIXTest-17C-FNAL-160823-1252-150V_2016-08-23_12h52m_1471974731/000_FPIXTest_p17//trimParameters35_C11.dat
[15:20:54.986] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-9-24_FPIXTest-17C-FNAL-160823-1252-150V_2016-08-23_12h52m_1471974731/000_FPIXTest_p17//trimParameters35_C12.dat
[15:20:54.992] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-9-24_FPIXTest-17C-FNAL-160823-1252-150V_2016-08-23_12h52m_1471974731/000_FPIXTest_p17//trimParameters35_C13.dat
[15:20:54.997] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-9-24_FPIXTest-17C-FNAL-160823-1252-150V_2016-08-23_12h52m_1471974731/000_FPIXTest_p17//trimParameters35_C14.dat
[15:20:54.003] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-9-24_FPIXTest-17C-FNAL-160823-1252-150V_2016-08-23_12h52m_1471974731/000_FPIXTest_p17//trimParameters35_C15.dat
[15:20:55.008] INFO: write masked pixels into /home/uicpirepix2/ProductionTestResults/M-Q-9-24_FPIXTest-17C-FNAL-160823-1252-150V_2016-08-23_12h52m_1471974731/000_FPIXTest_p17//defaultMaskFile.dat
[15:20:55.018] INFO: enter test to run
[15:22:05.375] INFO: test: Xray setting parameters: ->source=DCLowRate<-
[15:22:05.375] INFO: running: xray
[15:22:05.376] INFO: ----------------------------------------------------------------------
[15:22:05.376] INFO: PixTestXray::doPhRun() fParRunSeconds = 100
[15:22:05.376] INFO: ----------------------------------------------------------------------
[15:22:06.340] INFO: PixTestXray::doPhRun start TriggerLoop with trigger frequency 100 kHz, period 405 and duration 100 seconds, fEventsMax = 10000000
[15:22:17.837] INFO: run duration 11 seconds, buffer almost full (81%), pausing triggers.
[15:22:47.175] INFO: Resuming triggers.
[15:22:58.675] INFO: run duration 22 seconds, buffer almost full (81%), pausing triggers.
[15:23:28.460] INFO: Resuming triggers.
[15:23:39.958] INFO: run duration 34 seconds, buffer almost full (81%), pausing triggers.
[15:24:09.722] INFO: Resuming triggers.
[15:24:21.225] INFO: run duration 45 seconds, buffer almost full (81%), pausing triggers.
[15:24:51.312] INFO: Resuming triggers.
[15:25:02.813] INFO: run duration 57 seconds, buffer almost full (81%), pausing triggers.
[15:25:32.816] INFO: Resuming triggers.
[15:25:44.319] INFO: run duration 68 seconds, buffer almost full (81%), pausing triggers.
[15:26:14.423] INFO: Resuming triggers.
[15:26:25.926] INFO: run duration 80 seconds, buffer almost full (81%), pausing triggers.
[15:26:56.309] INFO: Resuming triggers.
[15:27:07.812] INFO: run duration 91 seconds, buffer almost full (81%), pausing triggers.
[15:27:38.336] INFO: Resuming triggers.
[15:27:46.694] INFO: data taking finished, elapsed time: 100 seconds.
[15:28:08.005] INFO: PixTest:: pg_setup set to default.
[15:28:09.008] INFO: PixTestXray::doPhRun() done
[15:28:09.208] INFO: enter test to run
[15:28:54.060] INFO: test: Xray setting parameters: ->source=DCHighRate<-
[15:28:54.060] INFO: running: xray
[15:28:54.061] INFO: ----------------------------------------------------------------------
[15:28:54.061] INFO: PixTestXray::doPhRun() fParRunSeconds = 100
[15:28:54.061] INFO: ----------------------------------------------------------------------
[15:28:55.025] INFO: PixTestXray::doPhRun start TriggerLoop with trigger frequency 100 kHz, period 405 and duration 100 seconds, fEventsMax = 10000000
[15:29:01.924] INFO: run duration 6 seconds, buffer almost full (81%), pausing triggers.
[15:29:32.353] INFO: Resuming triggers.
[15:29:39.251] INFO: run duration 13 seconds, buffer almost full (81%), pausing triggers.
[15:30:09.588] INFO: Resuming triggers.
[15:30:16.491] INFO: run duration 20 seconds, buffer almost full (81%), pausing triggers.
[15:30:46.922] INFO: Resuming triggers.
[15:30:53.823] INFO: run duration 27 seconds, buffer almost full (81%), pausing triggers.
[15:31:24.256] INFO: Resuming triggers.
[15:31:31.155] INFO: run duration 34 seconds, buffer almost full (81%), pausing triggers.
[15:32:01.493] INFO: Resuming triggers.
[15:32:08.396] INFO: run duration 41 seconds, buffer almost full (81%), pausing triggers.
[15:32:38.818] INFO: Resuming triggers.
[15:32:45.716] INFO: run duration 48 seconds, buffer almost full (81%), pausing triggers.
[15:33:16.127] INFO: Resuming triggers.
[15:33:23.030] INFO: run duration 55 seconds, buffer almost full (81%), pausing triggers.
[15:33:53.419] INFO: Resuming triggers.
[15:34:00.323] INFO: run duration 61 seconds, buffer almost full (81%), pausing triggers.
[15:34:30.678] INFO: Resuming triggers.
[15:34:37.576] INFO: run duration 68 seconds, buffer almost full (81%), pausing triggers.
[15:35:07.998] INFO: Resuming triggers.
[15:35:14.902] INFO: run duration 75 seconds, buffer almost full (81%), pausing triggers.
[15:35:45.320] INFO: Resuming triggers.
[15:35:52.218] INFO: run duration 82 seconds, buffer almost full (81%), pausing triggers.
[15:36:23.336] INFO: Resuming triggers.
[15:36:30.235] INFO: run duration 89 seconds, buffer almost full (81%), pausing triggers.
[15:37:01.462] INFO: Resuming triggers.
[15:37:08.361] INFO: run duration 96 seconds, buffer almost full (81%), pausing triggers.
[15:37:39.176] INFO: Resuming triggers.
[15:37:42.905] INFO: data taking finished, elapsed time: 100 seconds.
[15:37:59.710] INFO: PixTest:: pg_setup set to default.
[15:37:59.713] INFO: PixTestXray::doPhRun() done
[15:37:59.865] INFO: enter test to run
[15:38:22.912] INFO: test: HighRate no parameter change
[15:38:22.913] INFO: running: highrate
[15:38:22.927] INFO: ----------------------------------------------------------------------
[15:38:22.927] INFO: PixTestHighRate::calDelScan() ntrig = 10, vcal = 200
[15:38:22.927] INFO: ----------------------------------------------------------------------
[15:38:23.082] INFO: Expecting 768 events.
[15:38:24.222] INFO: 768 events read in total (425ms).
[15:38:24.222] INFO: Test took 1274ms.
[15:38:25.026] INFO: Expecting 41600 events.
[15:38:28.220] INFO: 41600 events read in total (2667ms).
[15:38:28.221] INFO: Test took 3980ms.
[15:38:28.253] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:38:28.979] INFO: Expecting 41600 events.
[15:38:32.173] INFO: 41600 events read in total (2667ms).
[15:38:32.174] INFO: Test took 3904ms.
[15:38:32.208] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:38:32.935] INFO: Expecting 41600 events.
[15:38:36.184] INFO: 41600 events read in total (2722ms).
[15:38:36.184] INFO: Test took 3959ms.
[15:38:36.218] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:38:36.940] INFO: Expecting 41600 events.
[15:38:40.153] INFO: 41600 events read in total (2686ms).
[15:38:40.154] INFO: Test took 3918ms.
[15:38:40.187] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:38:40.914] INFO: Expecting 41600 events.
[15:38:44.147] INFO: 41600 events read in total (2706ms).
[15:38:44.148] INFO: Test took 3944ms.
[15:38:44.180] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:38:44.906] INFO: Expecting 41600 events.
[15:38:48.232] INFO: 41600 events read in total (2799ms).
[15:38:48.233] INFO: Test took 4037ms.
[15:38:48.267] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:38:48.984] INFO: Expecting 41600 events.
[15:38:52.354] INFO: 41600 events read in total (2844ms).
[15:38:52.354] INFO: Test took 4070ms.
[15:38:52.387] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:38:53.110] INFO: Expecting 41600 events.
[15:38:56.366] INFO: 41600 events read in total (2730ms).
[15:38:56.367] INFO: Test took 3963ms.
[15:38:56.400] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:38:57.120] INFO: Expecting 41600 events.
[15:39:00.420] INFO: 41600 events read in total (2774ms).
[15:39:00.421] INFO: Test took 4002ms.
[15:39:00.454] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:39:01.181] INFO: Expecting 41600 events.
[15:39:04.471] INFO: 41600 events read in total (2763ms).
[15:39:04.472] INFO: Test took 4002ms.
[15:39:04.505] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:39:05.219] INFO: Expecting 41600 events.
[15:39:08.556] INFO: 41600 events read in total (2810ms).
[15:39:08.557] INFO: Test took 4034ms.
[15:39:08.590] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:39:09.316] INFO: Expecting 41600 events.
[15:39:12.586] INFO: 41600 events read in total (2743ms).
[15:39:12.587] INFO: Test took 3980ms.
[15:39:12.619] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:39:13.343] INFO: Expecting 41600 events.
[15:39:16.664] INFO: 41600 events read in total (2794ms).
[15:39:16.665] INFO: Test took 4028ms.
[15:39:16.698] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:39:17.417] INFO: Expecting 41600 events.
[15:39:20.687] INFO: 41600 events read in total (2743ms).
[15:39:20.688] INFO: Test took 3972ms.
[15:39:20.720] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:39:21.446] INFO: Expecting 41600 events.
[15:39:24.707] INFO: 41600 events read in total (2734ms).
[15:39:24.708] INFO: Test took 3971ms.
[15:39:24.741] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:39:25.464] INFO: Expecting 41600 events.
[15:39:28.759] INFO: 41600 events read in total (2768ms).
[15:39:28.760] INFO: Test took 4001ms.
[15:39:28.793] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:39:29.512] INFO: Expecting 41600 events.
[15:39:32.767] INFO: 41600 events read in total (2728ms).
[15:39:32.768] INFO: Test took 3958ms.
[15:39:32.801] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:39:33.520] INFO: Expecting 41600 events.
[15:39:36.772] INFO: 41600 events read in total (2727ms).
[15:39:36.773] INFO: Test took 3954ms.
[15:39:36.808] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:39:37.526] INFO: Expecting 41600 events.
[15:39:40.761] INFO: 41600 events read in total (2708ms).
[15:39:40.762] INFO: Test took 3938ms.
[15:39:40.795] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:39:41.520] INFO: Expecting 41600 events.
[15:39:44.542] INFO: 41600 events read in total (2495ms).
[15:39:44.543] INFO: Test took 3731ms.
[15:39:44.575] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:39:44.947] INFO: enter test to run
[15:40:02.432] INFO: test: HighRate no parameter change
[15:40:02.432] INFO: running: highrate
[15:40:02.433] INFO: ----------------------------------------------------------------------
[15:40:02.433] INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[15:40:02.433] INFO: ----------------------------------------------------------------------
[15:40:03.053] INFO: Expecting 208000 events.
[15:40:14.886] INFO: 208000 events read in total (11306ms).
[15:40:14.889] INFO: Test took 12447ms.
[15:40:15.028] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:40:15.278] INFO: number of dead pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[15:40:15.278] INFO: number of red-efficiency pixels: 75 33 118 150 141 150 136 86 79 114 114 87 102 68 25 22
[15:40:15.278] INFO: number of X-ray hits detected: 69723 42738 64269 104376 102860 109904 104740 72292 64689 86575 89605 75677 81462 48606 21536 21929
[15:40:15.278] INFO: number of triggers sent (total per ROC): 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000
[15:40:15.278] INFO: number of Vcal hits detected: 207924 207967 207873 207845 207855 207848 207854 207911 207921 207884 207884 207912 207898 207931 207975 207978
[15:40:15.278] INFO: Vcal hit fiducial efficiency (%): 100.0 100.0 99.9 99.9 99.9 99.9 99.9 100.0 100.0 99.9 99.9 100.0 100.0 100.0 100.0 100.0
[15:40:15.278] INFO: Vcal hit overall efficiency (%): 100.0 100.0 99.9 99.9 99.9 99.9 99.9 100.0 100.0 99.9 99.9 100.0 100.0 100.0 100.0 100.0
[15:40:15.278] INFO: X-ray hit rate [MHz/cm2]: 20.4 12.5 18.8 30.6 30.1 32.2 30.7 21.2 19.0 25.4 26.3 22.2 23.9 14.2 6.3 6.4
[15:40:15.278] INFO: PixTestHighRate::doXPixelAlive() done
[15:40:15.327] INFO: PixTest:: pg_setup set to default.
[15:40:15.341] INFO: enter test to run
[15:40:33.383] INFO: test: HighRate no parameter change
[15:40:33.383] INFO: running: highrate
[15:40:33.385] INFO: ----------------------------------------------------------------------
[15:40:33.385] INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[15:40:33.385] INFO: ----------------------------------------------------------------------
[15:40:34.006] INFO: Expecting 208000 events.
[15:40:47.482] INFO: 208000 events read in total (12949ms).
[15:40:47.488] INFO: Test took 14094ms.
[15:40:47.764] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:40:48.063] INFO: number of dead pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[15:40:48.063] INFO: number of red-efficiency pixels: 239 147 306 447 520 447 388 329 207 347 358 226 256 158 40 56
[15:40:48.063] INFO: number of X-ray hits detected: 145814 88920 133878 219134 217515 229385 219773 152440 136557 182350 189141 159266 171501 101763 45497 45804
[15:40:48.063] INFO: number of triggers sent (total per ROC): 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000
[15:40:48.063] INFO: number of Vcal hits detected: 207751 207850 207652 207507 207434 207523 207556 207653 207785 207633 207625 207758 207738 207834 207959 207944
[15:40:48.063] INFO: Vcal hit fiducial efficiency (%): 99.9 99.9 99.9 99.8 99.8 99.8 99.8 99.8 99.9 99.8 99.8 99.9 99.9 99.9 100.0 100.0
[15:40:48.063] INFO: Vcal hit overall efficiency (%): 99.9 99.9 99.8 99.8 99.7 99.8 99.8 99.8 99.9 99.8 99.8 99.9 99.9 99.9 100.0 100.0
[15:40:48.063] INFO: X-ray hit rate [MHz/cm2]: 42.7 26.1 39.2 64.2 63.8 67.2 64.4 44.7 40.0 53.4 55.4 46.7 50.3 29.8 13.3 13.4
[15:40:48.063] INFO: PixTestHighRate::doXPixelAlive() done
[15:40:48.111] INFO: PixTest:: pg_setup set to default.
[15:40:48.126] INFO: enter test to run
[15:41:04.423] INFO: test: HighRate no parameter change
[15:41:04.423] INFO: running: highrate
[15:41:04.424] INFO: ----------------------------------------------------------------------
[15:41:04.424] INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[15:41:04.425] INFO: ----------------------------------------------------------------------
[15:41:05.046] INFO: Expecting 208000 events.
[15:41:20.370] INFO: 208000 events read in total (14797ms).
[15:41:20.377] INFO: Test took 15943ms.
[15:41:20.809] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:41:21.152] INFO: number of dead pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[15:41:21.152] INFO: number of red-efficiency pixels: 484 294 726 1212 1258 1037 917 727 450 800 893 543 548 320 97 72
[15:41:21.152] INFO: number of X-ray hits detected: 224788 136688 207431 335448 332299 352463 337181 232893 210657 280655 290034 246260 263574 158175 69851 71645
[15:41:21.152] INFO: number of triggers sent (total per ROC): 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000
[15:41:21.152] INFO: number of Vcal hits detected: 207419 207666 206981 206440 206364 206729 206846 207088 207504 207060 206920 207397 207382 207630 207900 207928
[15:41:21.152] INFO: Vcal hit fiducial efficiency (%): 99.7 99.9 99.6 99.3 99.3 99.4 99.5 99.6 99.8 99.6 99.5 99.7 99.7 99.8 100.0 100.0
[15:41:21.152] INFO: Vcal hit overall efficiency (%): 99.7 99.8 99.5 99.2 99.2 99.4 99.4 99.6 99.8 99.5 99.5 99.7 99.7 99.8 100.0 100.0
[15:41:21.152] INFO: X-ray hit rate [MHz/cm2]: 65.9 40.1 60.8 98.3 97.4 103.3 98.8 68.3 61.7 82.3 85.0 72.2 77.3 46.4 20.5 21.0
[15:41:21.152] INFO: PixTestHighRate::doXPixelAlive() done
[15:41:21.211] INFO: PixTest:: pg_setup set to default.
[15:41:21.231] INFO: enter test to run
[15:41:42.983] INFO: test: exit no parameter change
[15:41:43.367] QUIET: Connection to board 32 closed.
[15:41:43.381] INFO: pXar: this is the end, my friend