[13:51:02.712] INFO: *** Welcome to pxar ***
[13:51:02.712] INFO: *** Today: 2016/07/11
[13:51:03.605] INFO: *** Version: v1.9.0-814-g7497
[13:51:03.605] INFO: readRocDacs: /home/uicpirepix2/ProductionTestResults/M-P-6-34_FPIXTest-17C-FNAL-160707-1047_2016-07-07_10h47m_1467906454/000_FPIXTest_p17/dacParameters35_C0.dat .. /home/uicpirepix2/ProductionTestResults/M-P-6-34_FPIXTest-17C-FNAL-160707-1047_2016-07-07_10h47m_1467906454/000_FPIXTest_p17/dacParameters35_C15.dat
[13:51:03.676] INFO: readTbmDacs: /home/uicpirepix2/ProductionTestResults/M-P-6-34_FPIXTest-17C-FNAL-160707-1047_2016-07-07_10h47m_1467906454/000_FPIXTest_p17/tbmParameters_C0a.dat .. /home/uicpirepix2/ProductionTestResults/M-P-6-34_FPIXTest-17C-FNAL-160707-1047_2016-07-07_10h47m_1467906454/000_FPIXTest_p17/tbmParameters_C0b.dat
[13:51:03.676] INFO: readMaskFile: /home/uicpirepix2/ProductionTestResults/M-P-6-34_FPIXTest-17C-FNAL-160707-1047_2016-07-07_10h47m_1467906454/000_FPIXTest_p17/defaultMaskFile.dat
[13:51:03.683] INFO: readTrimFile: /home/uicpirepix2/ProductionTestResults/M-P-6-34_FPIXTest-17C-FNAL-160707-1047_2016-07-07_10h47m_1467906454/000_FPIXTest_p17/trimParameters35_C0.dat .. /home/uicpirepix2/ProductionTestResults/M-P-6-34_FPIXTest-17C-FNAL-160707-1047_2016-07-07_10h47m_1467906454/000_FPIXTest_p17/trimParameters35_C15.dat
[13:51:03.783] INFO: clk: 4
[13:51:03.783] INFO: ctr: 4
[13:51:03.783] INFO: sda: 19
[13:51:03.783] INFO: tin: 9
[13:51:03.783] INFO: level: 15
[13:51:03.783] INFO: triggerdelay: 0
[13:51:03.783] QUIET: Instanciating API for pxar v1.9.0+814~g74979a6
[13:51:03.783] INFO: Log level: INFO
[13:51:03.798] QUIET: Connection to board DTB_WREKRL opened.
[13:51:03.801] INFO: DTB startup information
--- DTB info------------------------------------------
Board id: 33
HW version: DTB1.2
FW version: 4.2
SW version: 4.5
USB id: DTB_WREKRL
MAC address: 40D855118021
Hostname: pixelDTB033
Comment:
------------------------------------------------------
[13:51:03.804] INFO: RPC call hashes of host and DTB match: 398089610
[13:51:05.335] INFO: DUT info:
[13:51:05.335] INFO: The DUT currently contains the following objects:
[13:51:05.335] INFO: 2 TBM Cores tbm08c (2 ON)
[13:51:05.335] INFO: TBM Core alpha (0): 7 registers set
[13:51:05.335] INFO: TBM Core beta (1): 7 registers set
[13:51:05.335] INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[13:51:05.335] INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[13:51:05.335] INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[13:51:05.335] INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[13:51:05.335] INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[13:51:05.335] INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[13:51:05.335] INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[13:51:05.336] INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[13:51:05.336] INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[13:51:05.336] INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[13:51:05.336] INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[13:51:05.336] INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[13:51:05.336] INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[13:51:05.336] INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[13:51:05.336] INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[13:51:05.336] INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[13:51:05.336] INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[13:51:05.769] INFO: enter 'restricted' command line mode
[13:51:05.769] INFO: enter test to run
[13:51:08.407] INFO: test: PixelAlive no parameter change
[13:51:08.407] INFO: running: pixelalive
[13:51:08.472] INFO: ----------------------------------------------------------------------
[13:51:08.472] INFO: PixTestAlive::aliveTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[13:51:08.472] INFO: ----------------------------------------------------------------------
[13:51:08.790] INFO: Expecting 41600 events.
[13:51:13.047] INFO: 41600 events read in total (3539ms).
[13:51:13.208] INFO: Test took 4733ms.
[13:51:13.217] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:51:13.858] INFO: PixTestAlive::aliveTest() done
[13:51:13.858] INFO: number of dead pixels (per ROC): 0 117 0 1 0 5 0 5 0 0 297 9 0 16 0 0
[13:51:14.039] INFO: enter test to run
[13:51:45.091] INFO: test: HighRate setting parameters: ->runsecondshotpixels=5;trimhotpixelthr=200<-
[13:51:45.091] INFO: running: highrate
[13:51:45.109] INFO: readGainPedestalParameters /home/uicpirepix2/ProductionTestResults/M-P-6-34_FPIXTest-17C-FNAL-160707-1047_2016-07-07_10h47m_1467906454/000_FPIXTest_p17/phCalibrationFitErr35_C0.dat .. /home/uicpirepix2/ProductionTestResults/M-P-6-34_FPIXTest-17C-FNAL-160707-1047_2016-07-07_10h47m_1467906454/000_FPIXTest_p17/phCalibrationFitErr35_C15.dat
[13:51:45.372] INFO: ----------------------------------------------------------------------
[13:51:45.372] INFO: PixTest::trimHotPixels() running for 5 seconds with 100 kHz trigger rate
[13:51:45.372] INFO: ----------------------------------------------------------------------
[13:51:45.372] INFO: THR = 200, corresponding to ~ 106.667 MHz/cm2
[13:51:45.372] INFO: edge/corner pixel THR is adjusted
[13:51:45.372] INFO: PixTestHighRate::trimHotPixels: step 0...
[13:51:46.332] INFO: Collecting data for 5 seconds...
[13:51:51.348] INFO: Done with hot pixel readout
[13:52:04.471] INFO: PixTest:: pg_setup set to default.
[13:52:04.502] INFO: 264 hot pixels found in step 0
[13:52:05.498] INFO: Collecting data for 5 seconds...
[13:52:10.514] INFO: Done with hot pixel readout
[13:52:21.798] INFO: PixTest:: pg_setup set to default.
[13:52:21.799] INFO: 177 hot pixels found in step 1
[13:52:22.794] INFO: Collecting data for 5 seconds...
[13:52:27.809] INFO: Done with hot pixel readout
[13:52:39.256] INFO: PixTest:: pg_setup set to default.
[13:52:39.257] INFO: 90 hot pixels found in step 2
[13:52:40.247] INFO: Collecting data for 5 seconds...
[13:52:45.264] INFO: Done with hot pixel readout
[13:52:56.570] INFO: PixTest:: pg_setup set to default.
[13:52:56.571] INFO: 37 hot pixels found in step 3
[13:52:57.559] INFO: Collecting data for 5 seconds...
[13:53:02.575] INFO: Done with hot pixel readout
[13:53:13.825] INFO: PixTest:: pg_setup set to default.
[13:53:13.826] INFO: 20 hot pixels found in step 4
[13:53:14.816] INFO: Collecting data for 5 seconds...
[13:53:19.828] INFO: Done with hot pixel readout
[13:53:31.031] INFO: PixTest:: pg_setup set to default.
[13:53:31.032] INFO: 14 hot pixels found in step 5
[13:53:32.020] INFO: Collecting data for 5 seconds...
[13:53:37.036] INFO: Done with hot pixel readout
[13:53:48.371] INFO: PixTest:: pg_setup set to default.
[13:53:48.372] INFO: 30 hot pixels found in step 6
[13:53:49.361] INFO: Collecting data for 5 seconds...
[13:53:54.378] INFO: Done with hot pixel readout
[13:54:05.630] INFO: PixTest:: pg_setup set to default.
[13:54:05.631] INFO: 22 hot pixels found in step 7
[13:54:06.619] INFO: Collecting data for 5 seconds...
[13:54:11.636] INFO: Done with hot pixel readout
[13:54:23.020] INFO: PixTest:: pg_setup set to default.
[13:54:23.021] INFO: 27 hot pixels found in step 8
[13:54:24.011] INFO: Collecting data for 5 seconds...
[13:54:29.027] INFO: Done with hot pixel readout
[13:54:40.345] INFO: PixTest:: pg_setup set to default.
[13:54:40.346] INFO: 9 hot pixels found in step 9
[13:54:41.335] INFO: Collecting data for 5 seconds...
[13:54:46.352] INFO: Done with hot pixel readout
[13:54:57.673] INFO: PixTest:: pg_setup set to default.
[13:54:57.674] INFO: 18 hot pixels found in step 10
[13:54:58.663] INFO: Collecting data for 5 seconds...
[13:55:03.679] INFO: Done with hot pixel readout
[13:55:15.034] INFO: PixTest:: pg_setup set to default.
[13:55:15.035] INFO: 12 hot pixels found in step 11
[13:55:16.023] INFO: Collecting data for 5 seconds...
[13:55:21.040] INFO: Done with hot pixel readout
[13:55:31.449] INFO: PixTest:: pg_setup set to default.

[13:55:31.450] INFO: 12 hot pixels found in step 12
[13:55:32.438] INFO: Collecting data for 5 seconds...
[13:55:37.455] INFO: Done with hot pixel readout
[13:55:48.505] INFO: PixTest:: pg_setup set to default.

[13:55:48.506] INFO: 11 hot pixels found in step 13
[13:55:49.495] INFO: Collecting data for 5 seconds...
[13:55:54.511] INFO: Done with hot pixel readout
[13:56:05.810] INFO: PixTest:: pg_setup set to default.
[13:56:05.811] INFO: 7 hot pixels found in step 14
[13:56:05.843] INFO: 7 hot pixels could not be trimmed and have been masked.
[13:56:05.856] INFO: PixTest::trimHotPixels() done
[13:56:05.856] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-6-34_FPIXTest-17C-FNAL-160707-1047_2016-07-07_10h47m_1467906454/000_FPIXTest_p17/trimParameters35_C0.dat
[13:56:05.868] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-6-34_FPIXTest-17C-FNAL-160707-1047_2016-07-07_10h47m_1467906454/000_FPIXTest_p17/trimParameters35_C1.dat
[13:56:05.875] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-6-34_FPIXTest-17C-FNAL-160707-1047_2016-07-07_10h47m_1467906454/000_FPIXTest_p17/trimParameters35_C2.dat
[13:56:05.881] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-6-34_FPIXTest-17C-FNAL-160707-1047_2016-07-07_10h47m_1467906454/000_FPIXTest_p17/trimParameters35_C3.dat
[13:56:05.886] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-6-34_FPIXTest-17C-FNAL-160707-1047_2016-07-07_10h47m_1467906454/000_FPIXTest_p17/trimParameters35_C4.dat
[13:56:05.892] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-6-34_FPIXTest-17C-FNAL-160707-1047_2016-07-07_10h47m_1467906454/000_FPIXTest_p17/trimParameters35_C5.dat
[13:56:05.897] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-6-34_FPIXTest-17C-FNAL-160707-1047_2016-07-07_10h47m_1467906454/000_FPIXTest_p17/trimParameters35_C6.dat
[13:56:05.903] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-6-34_FPIXTest-17C-FNAL-160707-1047_2016-07-07_10h47m_1467906454/000_FPIXTest_p17/trimParameters35_C7.dat
[13:56:05.909] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-6-34_FPIXTest-17C-FNAL-160707-1047_2016-07-07_10h47m_1467906454/000_FPIXTest_p17/trimParameters35_C8.dat
[13:56:05.914] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-6-34_FPIXTest-17C-FNAL-160707-1047_2016-07-07_10h47m_1467906454/000_FPIXTest_p17/trimParameters35_C9.dat
[13:56:05.919] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-6-34_FPIXTest-17C-FNAL-160707-1047_2016-07-07_10h47m_1467906454/000_FPIXTest_p17/trimParameters35_C10.dat
[13:56:05.925] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-6-34_FPIXTest-17C-FNAL-160707-1047_2016-07-07_10h47m_1467906454/000_FPIXTest_p17/trimParameters35_C11.dat
[13:56:05.931] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-6-34_FPIXTest-17C-FNAL-160707-1047_2016-07-07_10h47m_1467906454/000_FPIXTest_p17/trimParameters35_C12.dat
[13:56:05.936] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-6-34_FPIXTest-17C-FNAL-160707-1047_2016-07-07_10h47m_1467906454/000_FPIXTest_p17/trimParameters35_C13.dat
[13:56:05.942] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-6-34_FPIXTest-17C-FNAL-160707-1047_2016-07-07_10h47m_1467906454/000_FPIXTest_p17/trimParameters35_C14.dat
[13:56:05.947] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-6-34_FPIXTest-17C-FNAL-160707-1047_2016-07-07_10h47m_1467906454/000_FPIXTest_p17/trimParameters35_C15.dat
[13:56:05.953] INFO: write masked pixels into /home/uicpirepix2/ProductionTestResults/M-P-6-34_FPIXTest-17C-FNAL-160707-1047_2016-07-07_10h47m_1467906454/000_FPIXTest_p17/defaultMaskFile.dat
[13:56:05.963] INFO: enter test to run
[13:57:32.625] INFO: test: HighRate setting parameters: ->runsecondshotpixels=1;trimhotpixelthr=200<-
[13:57:32.625] INFO: running: highrate
[13:57:32.630] INFO: ----------------------------------------------------------------------
[13:57:32.630] INFO: PixTest::trimHotPixels() running for 1 seconds with 100 kHz trigger rate
[13:57:32.630] INFO: ----------------------------------------------------------------------
[13:57:32.630] INFO: THR = 200, corresponding to ~ 533.333 MHz/cm2
[13:57:32.630] INFO: edge/corner pixel THR is adjusted
[13:57:32.630] INFO: PixTestHighRate::trimHotPixels: step 0...
[13:57:33.587] INFO: Collecting data for 1 seconds...
[13:57:34.590] INFO: Done with hot pixel readout
[13:57:38.319] INFO: PixTest:: pg_setup set to default.
[13:57:38.320] INFO: 0 hot pixels found in step 0
[13:57:38.325] INFO: 0 hot pixels could not be trimmed and have been masked.
[13:57:38.425] INFO: PixTest::trimHotPixels() done
[13:57:38.425] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-6-34_FPIXTest-17C-FNAL-160707-1047_2016-07-07_10h47m_1467906454/000_FPIXTest_p17/trimParameters35_C0.dat
[13:57:38.437] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-6-34_FPIXTest-17C-FNAL-160707-1047_2016-07-07_10h47m_1467906454/000_FPIXTest_p17/trimParameters35_C1.dat
[13:57:38.443] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-6-34_FPIXTest-17C-FNAL-160707-1047_2016-07-07_10h47m_1467906454/000_FPIXTest_p17/trimParameters35_C2.dat
[13:57:38.448] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-6-34_FPIXTest-17C-FNAL-160707-1047_2016-07-07_10h47m_1467906454/000_FPIXTest_p17/trimParameters35_C3.dat
[13:57:38.454] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-6-34_FPIXTest-17C-FNAL-160707-1047_2016-07-07_10h47m_1467906454/000_FPIXTest_p17/trimParameters35_C4.dat
[13:57:38.459] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-6-34_FPIXTest-17C-FNAL-160707-1047_2016-07-07_10h47m_1467906454/000_FPIXTest_p17/trimParameters35_C5.dat
[13:57:38.465] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-6-34_FPIXTest-17C-FNAL-160707-1047_2016-07-07_10h47m_1467906454/000_FPIXTest_p17/trimParameters35_C6.dat
[13:57:38.470] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-6-34_FPIXTest-17C-FNAL-160707-1047_2016-07-07_10h47m_1467906454/000_FPIXTest_p17/trimParameters35_C7.dat
[13:57:38.475] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-6-34_FPIXTest-17C-FNAL-160707-1047_2016-07-07_10h47m_1467906454/000_FPIXTest_p17/trimParameters35_C8.dat
[13:57:38.481] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-6-34_FPIXTest-17C-FNAL-160707-1047_2016-07-07_10h47m_1467906454/000_FPIXTest_p17/trimParameters35_C9.dat
[13:57:38.486] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-6-34_FPIXTest-17C-FNAL-160707-1047_2016-07-07_10h47m_1467906454/000_FPIXTest_p17/trimParameters35_C10.dat
[13:57:38.492] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-6-34_FPIXTest-17C-FNAL-160707-1047_2016-07-07_10h47m_1467906454/000_FPIXTest_p17/trimParameters35_C11.dat
[13:57:38.497] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-6-34_FPIXTest-17C-FNAL-160707-1047_2016-07-07_10h47m_1467906454/000_FPIXTest_p17/trimParameters35_C12.dat
[13:57:38.502] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-6-34_FPIXTest-17C-FNAL-160707-1047_2016-07-07_10h47m_1467906454/000_FPIXTest_p17/trimParameters35_C13.dat
[13:57:38.508] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-6-34_FPIXTest-17C-FNAL-160707-1047_2016-07-07_10h47m_1467906454/000_FPIXTest_p17/trimParameters35_C14.dat
[13:57:38.513] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-6-34_FPIXTest-17C-FNAL-160707-1047_2016-07-07_10h47m_1467906454/000_FPIXTest_p17/trimParameters35_C15.dat
[13:57:38.519] INFO: write masked pixels into /home/uicpirepix2/ProductionTestResults/M-P-6-34_FPIXTest-17C-FNAL-160707-1047_2016-07-07_10h47m_1467906454/000_FPIXTest_p17/defaultMaskFile.dat
[13:57:38.529] INFO: enter test to run
[13:57:59.185] INFO: test: Xray setting parameters: ->source=DCLowRate<-
[13:57:59.185] INFO: running: xray
[13:57:59.186] INFO: ----------------------------------------------------------------------
[13:57:59.186] INFO: PixTestXray::doPhRun() fParRunSeconds = 100
[13:57:59.186] INFO: ----------------------------------------------------------------------
[13:58:00.164] INFO: PixTestXray::doPhRun start TriggerLoop with trigger frequency 100 kHz, period 405 and duration 100 seconds, fEventsMax = 10000000
[13:58:12.154] INFO: run duration 11 seconds, buffer almost full (81%), pausing triggers.
[13:58:42.400] INFO: Resuming triggers.
[13:58:54.390] INFO: run duration 23 seconds, buffer almost full (81%), pausing triggers.
[13:59:24.866] INFO: Resuming triggers.
[13:59:36.856] INFO: run duration 35 seconds, buffer almost full (81%), pausing triggers.
[14:00:06.004] INFO: Resuming triggers.
[14:00:17.997] INFO: run duration 47 seconds, buffer almost full (81%), pausing triggers.
[14:00:48.532] INFO: Resuming triggers.
[14:01:00.526] INFO: run duration 59 seconds, buffer almost full (81%), pausing triggers.
[14:01:31.398] INFO: Resuming triggers.
[14:01:43.387] INFO: run duration 71 seconds, buffer almost full (81%), pausing triggers.
[14:02:14.027] INFO: Resuming triggers.
[14:02:26.015] INFO: run duration 83 seconds, buffer almost full (81%), pausing triggers.
[14:02:55.998] INFO: Resuming triggers.
[14:03:07.982] INFO: run duration 95 seconds, buffer almost full (81%), pausing triggers.
[14:03:37.907] INFO: Resuming triggers.
[14:03:42.340] INFO: data taking finished, elapsed time: 100 seconds.
[14:03:53.801] INFO: PixTest:: pg_setup set to default.
[14:03:53.804] INFO: PixTestXray::doPhRun() done
[14:03:53.963] INFO: enter test to run
[14:07:13.293] INFO: test: Xray setting parameters: ->source=DCHighRate<-
[14:07:13.293] INFO: running: xray
[14:07:13.295] INFO: ----------------------------------------------------------------------
[14:07:13.295] INFO: PixTestXray::doPhRun() fParRunSeconds = 100
[14:07:13.295] INFO: ----------------------------------------------------------------------
[14:07:14.258] INFO: PixTestXray::doPhRun start TriggerLoop with trigger frequency 100 kHz, period 405 and duration 100 seconds, fEventsMax = 10000000
[14:07:21.608] INFO: run duration 7 seconds, buffer almost full (81%), pausing triggers.
[14:07:52.907] INFO: Resuming triggers.
[14:08:00.253] INFO: run duration 14 seconds, buffer almost full (81%), pausing triggers.
[14:08:30.556] INFO: Resuming triggers.
[14:08:37.907] INFO: run duration 21 seconds, buffer almost full (81%), pausing triggers.
[14:09:09.199] INFO: Resuming triggers.
[14:09:16.552] INFO: run duration 29 seconds, buffer almost full (81%), pausing triggers.
[14:09:47.789] INFO: Resuming triggers.
[14:09:55.141] INFO: run duration 36 seconds, buffer almost full (81%), pausing triggers.
[14:10:26.608] INFO: Resuming triggers.
[14:10:33.961] INFO: run duration 43 seconds, buffer almost full (81%), pausing triggers.
[14:11:05.784] INFO: Resuming triggers.
[14:11:13.138] INFO: run duration 51 seconds, buffer almost full (81%), pausing triggers.
[14:11:45.483] INFO: Resuming triggers.
[14:11:52.836] INFO: run duration 58 seconds, buffer almost full (81%), pausing triggers.
[14:12:24.671] INFO: Resuming triggers.
[14:12:32.023] INFO: run duration 65 seconds, buffer almost full (81%), pausing triggers.
[14:13:04.882] INFO: Resuming triggers.
[14:13:12.230] INFO: run duration 73 seconds, buffer almost full (81%), pausing triggers.
[14:13:44.654] INFO: Resuming triggers.
[14:13:51.998] INFO: run duration 80 seconds, buffer almost full (81%), pausing triggers.
[14:14:23.337] INFO: Resuming triggers.
[14:14:30.684] INFO: run duration 87 seconds, buffer almost full (81%), pausing triggers.
[14:15:01.925] INFO: Resuming triggers.
[14:15:09.267] INFO: run duration 95 seconds, buffer almost full (81%), pausing triggers.
[14:15:40.512] INFO: Resuming triggers.
[14:15:45.319] INFO: data taking finished, elapsed time: 100 seconds.
[14:16:05.983] INFO: PixTest:: pg_setup set to default.
[14:16:05.986] INFO: PixTestXray::doPhRun() done
[14:16:06.133] INFO: enter test to run
[14:20:43.031] INFO: test: HighRate no parameter change
[14:20:43.031] INFO: running: highrate
[14:20:43.058] INFO: ----------------------------------------------------------------------
[14:20:43.058] INFO: PixTestHighRate::calDelScan() ntrig = 10, vcal = 200
[14:20:43.058] INFO: ----------------------------------------------------------------------
[14:20:43.215] INFO: Expecting 768 events.
[14:20:44.349] INFO: 768 events read in total (419ms).
[14:20:44.349] INFO: Test took 1270ms.
[14:20:45.152] INFO: Expecting 41600 events.
[14:20:48.201] INFO: 41600 events read in total (2522ms).
[14:20:48.202] INFO: Test took 3828ms.
[14:20:48.233] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:20:48.968] INFO: Expecting 41600 events.
[14:20:52.123] INFO: 41600 events read in total (2628ms).
[14:20:52.124] INFO: Test took 3874ms.
[14:20:52.155] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:20:52.882] INFO: Expecting 41600 events.
[14:20:56.085] INFO: 41600 events read in total (2676ms).
[14:20:56.086] INFO: Test took 3914ms.
[14:20:56.118] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:20:56.847] INFO: Expecting 41600 events.
[14:21:00.048] INFO: 41600 events read in total (2674ms).
[14:21:00.049] INFO: Test took 3913ms.
[14:21:00.080] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:21:00.812] INFO: Expecting 41600 events.
[14:21:04.028] INFO: 41600 events read in total (2689ms).
[14:21:04.029] INFO: Test took 3931ms.
[14:21:04.061] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:21:04.786] INFO: Expecting 41600 events.
[14:21:07.999] INFO: 41600 events read in total (2686ms).
[14:21:07.000] INFO: Test took 3922ms.
[14:21:08.031] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:21:08.758] INFO: Expecting 41600 events.
[14:21:11.972] INFO: 41600 events read in total (2687ms).
[14:21:11.973] INFO: Test took 3925ms.
[14:21:12.004] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:21:12.733] INFO: Expecting 41600 events.
[14:21:15.936] INFO: 41600 events read in total (2676ms).
[14:21:15.937] INFO: Test took 3916ms.
[14:21:15.968] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:21:16.701] INFO: Expecting 41600 events.
[14:21:19.918] INFO: 41600 events read in total (2691ms).
[14:21:19.919] INFO: Test took 3934ms.
[14:21:19.950] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:21:20.681] INFO: Expecting 41600 events.
[14:21:23.913] INFO: 41600 events read in total (2705ms).
[14:21:23.913] INFO: Test took 3947ms.
[14:21:23.946] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:21:24.670] INFO: Expecting 41600 events.
[14:21:27.864] INFO: 41600 events read in total (2667ms).
[14:21:27.865] INFO: Test took 3902ms.
[14:21:27.897] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:21:28.628] INFO: Expecting 41600 events.
[14:21:31.844] INFO: 41600 events read in total (2689ms).
[14:21:31.845] INFO: Test took 3930ms.
[14:21:31.878] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:21:32.604] INFO: Expecting 41600 events.
[14:21:35.807] INFO: 41600 events read in total (2676ms).
[14:21:35.808] INFO: Test took 3913ms.
[14:21:35.839] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:21:36.570] INFO: Expecting 41600 events.
[14:21:39.788] INFO: 41600 events read in total (2691ms).
[14:21:39.789] INFO: Test took 3933ms.
[14:21:39.821] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:21:40.548] INFO: Expecting 41600 events.
[14:21:43.773] INFO: 41600 events read in total (2698ms).
[14:21:43.774] INFO: Test took 3936ms.
[14:21:43.804] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:21:44.531] INFO: Expecting 41600 events.
[14:21:47.746] INFO: 41600 events read in total (2689ms).
[14:21:47.747] INFO: Test took 3926ms.
[14:21:47.778] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:21:48.508] INFO: Expecting 41600 events.
[14:21:51.715] INFO: 41600 events read in total (2680ms).
[14:21:51.716] INFO: Test took 3919ms.
[14:21:51.748] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:21:52.474] INFO: Expecting 41600 events.
[14:21:55.667] INFO: 41600 events read in total (2666ms).
[14:21:55.668] INFO: Test took 3904ms.
[14:21:55.700] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:21:56.431] INFO: Expecting 41600 events.
[14:21:59.587] INFO: 41600 events read in total (2629ms).
[14:21:59.588] INFO: Test took 3872ms.
[14:21:59.618] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:22:00.350] INFO: Expecting 41600 events.
[14:22:03.334] INFO: 41600 events read in total (2457ms).
[14:22:03.335] INFO: Test took 3699ms.
[14:22:03.366] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:22:03.731] INFO: enter test to run
[14:23:12.334] INFO: test: HighRate no parameter change
[14:23:12.335] INFO: running: highrate
[14:23:12.336] INFO: ----------------------------------------------------------------------
[14:23:12.336] INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[14:23:12.336] INFO: ----------------------------------------------------------------------
[14:23:12.960] INFO: Expecting 208000 events.
[14:23:24.760] INFO: 208000 events read in total (11273ms).
[14:23:24.763] INFO: Test took 12416ms.
[14:23:24.897] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:23:25.148] INFO: number of dead pixels (per ROC): 0 0 0 1 0 4 0 5 0 160 0 1 0 9 0 0
[14:23:25.148] INFO: number of red-efficiency pixels: 66 10 72 184 102 227 133 89 90 216 128 160 102 552 28 17
[14:23:25.148] INFO: number of X-ray hits detected: 58107 12493 66507 111335 100366 126903 105078 73221 61975 57631 97883 83313 74337 56126 21231 19221
[14:23:25.148] INFO: number of triggers sent (total per ROC): 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000
[14:23:25.148] INFO: number of Vcal hits detected: 207933 207990 207924 207752 207896 207555 207865 207665 207909 199944 207866 207778 207895 206961 207972 207983
[14:23:25.148] INFO: Vcal hit fiducial efficiency (%): 100.0 100.0 100.0 99.9 100.0 99.9 99.9 100.0 100.0 100.0 99.9 99.9 100.0 99.7 100.0 100.0
[14:23:25.148] INFO: Vcal hit overall efficiency (%): 100.0 100.0 100.0 99.9 100.0 99.8 99.9 99.8 100.0 96.1 99.9 99.9 99.9 99.5 100.0 100.0
[14:23:25.148] INFO: X-ray hit rate [MHz/cm2]: 17.0 3.7 19.5 32.6 29.4 37.2 30.8 21.5 18.2 16.9 28.7 24.4 21.8 16.5 6.2 5.6
[14:23:25.148] INFO: PixTestHighRate::doXPixelAlive() done
[14:23:25.193] INFO: PixTest:: pg_setup set to default.
[14:23:25.204] INFO: enter test to run
[14:23:49.150] INFO: test: HighRate no parameter change
[14:23:49.150] INFO: running: highrate
[14:23:49.151] INFO: ----------------------------------------------------------------------
[14:23:49.151] INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[14:23:49.151] INFO: ----------------------------------------------------------------------
[14:23:49.774] INFO: Expecting 208000 events.
[14:24:02.797] INFO: 208000 events read in total (12496ms).
[14:24:02.802] INFO: Test took 13641ms.
[14:24:03.063] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:24:03.358] INFO: number of dead pixels (per ROC): 0 0 0 1 0 3 0 5 0 146 0 1 0 9 0 0
[14:24:03.358] INFO: number of red-efficiency pixels: 159 25 267 498 393 634 448 252 195 295 366 358 219 431 52 51
[14:24:03.358] INFO: number of X-ray hits detected: 116072 25442 132079 221054 199635 251054 210572 147051 122048 115000 194094 165068 147730 108677 42663 38534
[14:24:03.358] INFO: number of triggers sent (total per ROC): 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000
[14:24:03.358] INFO: number of Vcal hits detected: 207838 207975 207709 207400 207577 207047 207521 207482 207797 200536 207605 207551 207773 207044 207946 207948
[14:24:03.358] INFO: Vcal hit fiducial efficiency (%): 99.9 100.0 99.9 99.8 99.8 99.7 99.8 99.9 99.9 99.9 99.8 99.8 99.9 99.8 100.0 100.0
[14:24:03.358] INFO: Vcal hit overall efficiency (%): 99.9 100.0 99.9 99.7 99.8 99.5 99.8 99.8 99.9 96.4 99.8 99.8 99.9 99.5 100.0 100.0
[14:24:03.358] INFO: X-ray hit rate [MHz/cm2]: 34.0 7.5 38.7 64.8 58.5 73.6 61.7 43.1 35.8 33.7 56.9 48.4 43.3 31.9 12.5 11.3
[14:24:03.358] INFO: PixTestHighRate::doXPixelAlive() done
[14:24:03.406] INFO: PixTest:: pg_setup set to default.
[14:24:03.424] INFO: enter test to run
[14:26:46.493] INFO: test: HighRate no parameter change
[14:26:46.493] INFO: running: highrate
[14:26:46.494] INFO: ----------------------------------------------------------------------
[14:26:46.494] INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[14:26:46.494] INFO: ----------------------------------------------------------------------
[14:26:47.108] INFO: Expecting 208000 events.
[14:27:01.789] INFO: 208000 events read in total (14154ms).
[14:27:01.795] INFO: Test took 15291ms.
[14:27:02.178] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:27:02.507] INFO: number of dead pixels (per ROC): 0 0 0 1 0 4 0 5 0 155 0 1 0 9 0 0
[14:27:02.507] INFO: number of red-efficiency pixels: 337 36 603 1176 931 1420 1012 570 314 460 726 671 491 575 93 65
[14:27:02.507] INFO: number of X-ray hits detected: 175213 38057 198291 330835 300381 376498 316043 220973 185119 173122 291708 248912 222371 161464 64802 58573
[14:27:02.507] INFO: number of triggers sent (total per ROC): 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000
[14:27:02.507] INFO: number of Vcal hits detected: 207620 207964 207244 206450 206917 205801 206789 207094 207671 199905 207140 207135 207475 206825 207905 207935
[14:27:02.507] INFO: Vcal hit fiducial efficiency (%): 99.8 100.0 99.7 99.3 99.5 99.1 99.5 99.7 99.8 99.8 99.6 99.7 99.8 99.7 100.0 100.0
[14:27:02.507] INFO: Vcal hit overall efficiency (%): 99.8 100.0 99.6 99.3 99.5 98.9 99.4 99.6 99.8 96.1 99.6 99.6 99.7 99.4 100.0 100.0
[14:27:02.507] INFO: X-ray hit rate [MHz/cm2]: 51.4 11.2 58.1 97.0 88.0 110.4 92.6 64.8 54.3 50.7 85.5 73.0 65.2 47.3 19.0 17.2
[14:27:02.507] INFO: PixTestHighRate::doXPixelAlive() done
[14:27:02.556] INFO: PixTest:: pg_setup set to default.
[14:27:02.573] INFO: enter test to run
[14:27:07.061] INFO: test: exit no parameter change
[14:27:07.555] QUIET: Connection to board 33 closed.
[14:27:07.569] INFO: pXar: this is the end, my friend